-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Wed 2025-12-17 14:30:32 CST. -- Dec 17 14:29:00 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:02 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:03 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:07 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:09 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:12 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:13 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:14 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:15 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:16 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:17 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:20 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:30 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:31 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:33 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:35 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:36 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:36 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:39 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:44 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:45 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:47 so1905-bfc4 volumio[8404]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 17 14:29:55 so1905-bfc4 sshd[9507]: rexec line 6: Deprecated option UsePrivilegeSeparation Dec 17 14:29:55 so1905-bfc4 sshd[9507]: rexec line 7: Deprecated option KeyRegenerationInterval Dec 17 14:29:55 so1905-bfc4 sshd[9507]: rexec line 8: Deprecated option ServerKeyBits Dec 17 14:29:55 so1905-bfc4 sshd[9507]: rexec line 14: Deprecated option RSAAuthentication Dec 17 14:29:55 so1905-bfc4 sshd[9507]: rexec line 17: Deprecated option RhostsRSAAuthentication Dec 17 14:29:55 so1905-bfc4 sshd[9507]: reprocess config line 14: Deprecated option RSAAuthentication Dec 17 14:29:55 so1905-bfc4 sshd[9507]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Dec 17 14:29:55 so1905-bfc4 sshd[9507]: Accepted password for volumio from 192.168.2.237 port 49990 ssh2 Dec 17 14:29:55 so1905-bfc4 sshd[9507]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Dec 17 14:29:55 so1905-bfc4 systemd-logind[2223]: New session c10 of user volumio. Dec 17 14:29:55 so1905-bfc4 systemd[1]: Started Session c10 of user volumio. Dec 17 14:29:59 so1905-bfc4 sshd[9534]: Received disconnect from 192.168.2.237 port 49990:11: Dec 17 14:29:59 so1905-bfc4 sshd[9534]: Disconnected from user volumio 192.168.2.237 port 49990 Dec 17 14:29:59 so1905-bfc4 sshd[9507]: pam_unix(sshd:session): session closed for user volumio Dec 17 14:29:59 so1905-bfc4 systemd[1]: session-c10.scope: Succeeded. Dec 17 14:29:59 so1905-bfc4 systemd-logind[2223]: Session c10 logged out. Waiting for processes to exit. Dec 17 14:29:59 so1905-bfc4 systemd-logind[2223]: Removed session c10. Dec 17 14:30:03 so1905-bfc4 sudo[9548]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl stop volumio.service Dec 17 14:30:03 so1905-bfc4 sudo[9548]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 17 14:30:03 so1905-bfc4 systemd[1]: Stopping Volumio Backend Module... Dec 17 14:30:03 so1905-bfc4 go-librespot[8696]: time="2025-12-17T14:30:03+08:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 17 14:30:03 so1905-bfc4 systemd[1]: volumio.service: Succeeded. Dec 17 14:30:03 so1905-bfc4 systemd[1]: Stopped Volumio Backend Module. Dec 17 14:30:03 so1905-bfc4 sudo[9548]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:03 so1905-bfc4 sudo[9558]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl stop display.service Dec 17 14:30:03 so1905-bfc4 sudo[9558]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 17 14:30:03 so1905-bfc4 systemd[1]: Stopping display Daemon... Dec 17 14:30:03 so1905-bfc4 systemd[1]: display.service: Succeeded. Dec 17 14:30:03 so1905-bfc4 systemd[1]: Stopped display Daemon. Dec 17 14:30:03 so1905-bfc4 sudo[9558]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:03 so1905-bfc4 sudo[9569]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl start display.service Dec 17 14:30:03 so1905-bfc4 sudo[9569]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 17 14:30:03 so1905-bfc4 systemd[1]: Started display Daemon. Dec 17 14:30:03 so1905-bfc4 display[9577]: lvgl_display daemon starting... Dec 17 14:30:03 so1905-bfc4 sudo[9569]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:03 so1905-bfc4 sudo[9582]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl start volumio.service Dec 17 14:30:03 so1905-bfc4 sudo[9582]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 17 14:30:03 so1905-bfc4 systemd[1]: Started dynamicswap service. Dec 17 14:30:04 so1905-bfc4 systemd[1]: Started Volumio Backend Module. Dec 17 14:30:04 so1905-bfc4 sudo[9582]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:04 so1905-bfc4 systemd[1]: dynamicswap.service: Succeeded. Dec 17 14:30:04 so1905-bfc4 display[9583]: 初始化FreeType... Dec 17 14:30:04 so1905-bfc4 display[9583]: FreeType初始化失败 Dec 17 14:30:04 so1905-bfc4 display[9583]: [HeartbeatMonitor] 初始化完成 Dec 17 14:30:04 so1905-bfc4 node[2221]: Failed to retrieve remote version or perform update: { Error: write EPROTO 3069117024:error:14094410:SSL routines:ssl3_read_bytes:sslv3 alert handshake failure:../deps/openssl/openssl/ssl/record/rec_layer_s3.c:1544:SSL alert number 40 Dec 17 14:30:04 so1905-bfc4 node[2221]: at WriteWrap.afterWrite [as oncomplete] (net.js:789:14) errno: 'EPROTO', code: 'EPROTO', syscall: 'write' } Dec 17 14:30:04 so1905-bfc4 display[9583]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 17 14:30:04 so1905-bfc4 display[9583]: 文件大小: 11364916 字节 Dec 17 14:30:04 so1905-bfc4 display[9583]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 50px) Dec 17 14:30:04 so1905-bfc4 display[9583]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 17 14:30:04 so1905-bfc4 display[9583]: 文件大小: 11364916 字节 Dec 17 14:30:04 so1905-bfc4 display[9583]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 36px) Dec 17 14:30:04 so1905-bfc4 display[9583]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 17 14:30:04 so1905-bfc4 display[9583]: 文件大小: 11364916 字节 Dec 17 14:30:04 so1905-bfc4 display[9583]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 40px) Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a45278, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a49810, str=/volumio/display/assets/source-icon/bt.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a49940, str=/volumio/display/assets/source-icon/aux.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/aux.png, size=10326, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a49df0, str=/volumio/display/assets/source-icon/opt.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a4a098, str=/volumio/display/assets/source-icon/opt.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a4a3b0, str=/volumio/display/assets/source-icon/hdmi-arc.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/hdmi-arc.png, size=7908, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a4ac80, str=/volumio/display/assets/source-icon/usb.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/usb.png, size=7652, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a45d98, str=/volumio/display/assets/source-icon/music-library.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/music-library.png, size=22848, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a46280, str=/volumio/display/assets/source-icon/bt.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a46850, str=/volumio/display/assets/albumart.jpg Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/albumart.jpg, size=77672, buffer[0]=ff Dec 17 14:30:05 so1905-bfc4 display[9583]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 17 14:30:05 so1905-bfc4 display[9583]: Attempting to process with stb_image... Dec 17 14:30:05 so1905-bfc4 display[9583]: stb_image successfully loaded: 855x855, channels=3 Dec 17 14:30:05 so1905-bfc4 display[9583]: Successfully processed with stb_image Dec 17 14:30:05 so1905-bfc4 display[9583]: Loading default image: /volumio/display/assets/albumart.jpg Dec 17 14:30:05 so1905-bfc4 display[9583]: Loaded default image from file: /volumio/display/assets/albumart.jpg, size=77672 Dec 17 14:30:05 so1905-bfc4 display[9583]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 17 14:30:05 so1905-bfc4 display[9583]: Attempting to process with stb_image... Dec 17 14:30:05 so1905-bfc4 display[9583]: stb_image successfully loaded: 855x855, channels=3 Dec 17 14:30:05 so1905-bfc4 display[9583]: Successfully processed with stb_image Dec 17 14:30:05 so1905-bfc4 display[9583]: Default image loaded successfully Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a46a20, str=/volumio/display/assets/status-icon/wifi-error.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/status-icon/wifi-error.png, size=1494, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a47860, str=/volumio/display/assets/status-icon/eth-error.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/status-icon/eth-error.png, size=936, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 17 14:30:05 so1905-bfc4 display[9583]: 文件大小: 6344 字节 Dec 17 14:30:05 so1905-bfc4 display[9583]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 40px) Dec 17 14:30:05 so1905-bfc4 display[9583]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf Dec 17 14:30:05 so1905-bfc4 display[9583]: 文件大小: 11495876 字节 Dec 17 14:30:05 so1905-bfc4 display[9583]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf (大小: 40px) Dec 17 14:30:05 so1905-bfc4 display[9583]: setInnerAlign: image_desc is null Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a4bec8, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: setImageSrc: this=0x2a5e4c8, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:30:05 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:30:05 so1905-bfc4 display[9583]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 17 14:30:05 so1905-bfc4 display[9583]: 文件大小: 6344 字节 Dec 17 14:30:05 so1905-bfc4 display[9583]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 100px) Dec 17 14:30:06 so1905-bfc4 display[9583]: setImageSrc: this=0x2a5ebf0, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:30:06 so1905-bfc4 display[9583]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:30:08 so1905-bfc4 volumio[9591]: info: ------------------------------------------- Dec 17 14:30:08 so1905-bfc4 volumio[9591]: info: ----- Synaudio ---- Dec 17 14:30:08 so1905-bfc4 volumio[9591]: info: ------------------------------------------- Dec 17 14:30:08 so1905-bfc4 volumio[9591]: info: ----- System startup ---- Dec 17 14:30:08 so1905-bfc4 volumio[9591]: info: ------------------------------------------- Dec 17 14:30:09 so1905-bfc4 volumio[9591]: info: Found new core plugin music_service/cdplayer. Adding it Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Plugin folders cleanup Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning into folder /volumio/app/plugins/ Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning category audio_interface Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning category miscellanea Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning category music_service Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning category plugins.json Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning category system_controller Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning category user_interface Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Scanning into folder /data/plugins/ Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Plugin folders cleanup completed Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: ------------------------------------------- Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: ----- Core plugins startup ---- Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: ------------------------------------------- Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Loading plugins from folder /volumio/app/plugins/ Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Adding plugin upnp to MyMusic Plugins Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Loading plugins from folder /data/plugins/ Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Loading plugin "system"... Dec 17 14:30:10 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/system/index.js Dec 17 14:30:10 so1905-bfc4 volumio[9591]: info: Loading plugin "appearance"... Dec 17 14:30:10 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "network"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/network/index.js Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [network] Refreshing Cached IP Addresses Dec 17 14:30:11 so1905-bfc4 volumio[9591]: ControllerNetwork autoStartNetworkConfig true Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "services"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/services/index.js Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "alsa_controller"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Dec 17 14:30:11 so1905-bfc4 sudo[9634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:30:11 so1905-bfc4 sudo[9634]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:11 so1905-bfc4 sudo[9636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Applying required configuration parameters for plugin alsa_controller Dec 17 14:30:11 so1905-bfc4 sudo[9636]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:11 so1905-bfc4 sudo[9634]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:11 so1905-bfc4 sudo[9636]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [alsa_controller] Creating shared var alsa.outputdevice='2' Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "wizard"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "volumio_command_line_client"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [volumio_command_line_client] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "upnp"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [upnp] [1765953011796] Starting Upmpd Daemon Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [upnp] CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "my_music"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Dec 17 14:30:11 so1905-bfc4 volumio[9591]: info: Loading plugin "mpd"... Dec 17 14:30:11 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Dec 17 14:30:12 so1905-bfc4 volumio[9591]: [mpd] MPD: starting udev watcher for USB partitions Dec 17 14:30:12 so1905-bfc4 volumio[9591]: [mpd] Creating MPD Configuration file Dec 17 14:30:12 so1905-bfc4 volumio[9591]: info: Loading plugin "upnp_browser"... Dec 17 14:30:12 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Dec 17 14:30:12 so1905-bfc4 sudo[9662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 14:30:12 so1905-bfc4 sudo[9662]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:12 so1905-bfc4 sudo[9662]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:12 so1905-bfc4 sudo[9666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 14:30:12 so1905-bfc4 sudo[9666]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:12 so1905-bfc4 systemd[1]: Stopping Music Player Daemon... Dec 17 14:30:13 so1905-bfc4 systemd[1]: mpd.service: Succeeded. Dec 17 14:30:13 so1905-bfc4 systemd[1]: Stopped Music Player Daemon. Dec 17 14:30:13 so1905-bfc4 systemd[1]: Starting Music Player Daemon... Dec 17 14:30:13 so1905-bfc4 volumio[9591]: info: Loading plugin "networkfs"... Dec 17 14:30:13 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Dec 17 14:30:13 so1905-bfc4 volumio[9591]: [networkfs] Starting IP address watcher Dec 17 14:30:13 so1905-bfc4 volumio[9591]: [networkfs] Started network interface ip monitor watcher Dec 17 14:30:13 so1905-bfc4 volumio[9591]: [networkfs] CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 14:30:13 so1905-bfc4 volumio[9591]: info: Loading plugin "alarm-clock"... Dec 17 14:30:13 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Dec 17 14:30:13 so1905-bfc4 volumio[9591]: info: Loading plugin "airplay_emulation"... Dec 17 14:30:13 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Dec 17 14:30:14 so1905-bfc4 volumio[9591]: Shairport Sync D-Bus 服务已注册。 Dec 17 14:30:14 so1905-bfc4 volumio[9591]: [airplay_emulation] Starting Shairport Sync Dec 17 14:30:14 so1905-bfc4 volumio[9591]: [airplay_emulation] [AirPlay] Started coverart directory watcher Dec 17 14:30:14 so1905-bfc4 volumio[9591]: info: Loading plugin "last_100"... Dec 17 14:30:14 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Dec 17 14:30:14 so1905-bfc4 volumio[9591]: info: Loading plugin "spop"... Dec 17 14:30:14 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/spop/index.js Dec 17 14:30:14 so1905-bfc4 volumio[9591]: info: Loading plugin "webradio"... Dec 17 14:30:14 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Dec 17 14:30:16 so1905-bfc4 mpd[9678]: Dec 17 14:30 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 17 14:30:16 so1905-bfc4 mpd[9678]: Dec 17 14:30 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 17 14:30:16 so1905-bfc4 mpd[9678]: Dec 17 14:30 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 17 14:30:16 so1905-bfc4 systemd[1]: Started Music Player Daemon. Dec 17 14:30:16 so1905-bfc4 sudo[9666]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:17 so1905-bfc4 volumio[9591]: info: Loading plugin "yandex_browser"... Dec 17 14:30:17 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Dec 17 14:30:17 so1905-bfc4 volumio[9591]: [yandex_browser] Yandex plugin started Dec 17 14:30:17 so1905-bfc4 volumio[9591]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Dec 17 14:30:17 so1905-bfc4 volumio[9591]: info: Loading plugin "i2s_dacs"... Dec 17 14:30:17 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Dec 17 14:30:17 so1905-bfc4 volumio[9591]: [i2s_dacs] I2S DAC not set, start Auto-detection Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Loading plugin "volumiodiscovery"... Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Dec 17 14:30:18 so1905-bfc4 volumio[9591]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 17 14:30:18 so1905-bfc4 node[9591]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 17 14:30:18 so1905-bfc4 volumio[9591]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:30:18 so1905-bfc4 node[9591]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:30:18 so1905-bfc4 volumio[9591]: *** WARNING *** For more information see Dec 17 14:30:18 so1905-bfc4 node[9591]: *** WARNING *** For more information see Dec 17 14:30:18 so1905-bfc4 volumio[9591]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 17 14:30:18 so1905-bfc4 volumio[9591]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:30:18 so1905-bfc4 volumio[9591]: *** WARNING *** For more information see Dec 17 14:30:18 so1905-bfc4 node[9591]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 17 14:30:18 so1905-bfc4 node[9591]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:30:18 so1905-bfc4 node[9591]: *** WARNING *** For more information see Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [volumiodiscovery] Discovery: Started advertising with name: SO1905-bfc4 Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [volumiodiscovery] CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Loading plugin "outputs"... Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Loading plugin "albumart"... Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Plugin example_plugin is not enabled Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Loading plugin "inputs"... Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Loading plugin "pandora"... Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Applying required configuration parameters for plugin pandora Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Loading plugin "podcast"... Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [podcast] ControllerPodcast::constructor Dec 17 14:30:18 so1905-bfc4 volumio[9591]: info: Loading plugin "qobuz"... Dec 17 14:30:18 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Dec 17 14:30:19 so1905-bfc4 volumio[9591]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: Loading plugin "tidal"... Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [tidal] [1765953019102] ControllerTidalPlugin::constructor Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [tidal] [1765953019104] ControllerTidalPlugin::onVolumioStart Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: Plugin volspotconnect2 is not enabled Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: Loading plugin "updater_comm"... Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: Plugin mpdemulation is not enabled Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: Loading plugin "rest_api"... Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: Loading plugin "websocket"... Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: ___________ START PLUGINS ___________ Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [upnp_browser] CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [upnp_browser] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019417] CoreMusicLibrary::Adding element Media Servers Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: SSDP Client initialized. Dec 17 14:30:19 so1905-bfc4 volumio[9591]: Starting initial SSDP search... Dec 17 14:30:19 so1905-bfc4 volumio[9591]: Discovering on interface: lo Dec 17 14:30:19 so1905-bfc4 volumio[9591]: Discovering on interface: wlan0 Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [airplay_emulation] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [airplay_emulation] Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [last_100] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019584] CoreMusicLibrary::Adding element Last_100 Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [spop] Creating Spotify config file Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [spop] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [webradio] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019646] CoreMusicLibrary::Adding element Webradio Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [webradio] CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [yandex_browser] Yandex plugin started Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [yandex_browser] [1765953019668] ControllerYandexPlugin::addToBrowseSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [yandex_browser] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019669] CoreMusicLibrary::Adding element Yandex Music Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:printToastMessage Dec 17 14:30:19 so1905-bfc4 volumio[9591]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [pandora] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019687] CoreMusicLibrary::Adding element Pandora Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [pandora] [1765953019694] ControllerPandora::initializeMQTT Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [pandora] [1765953019696] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [podcast] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019712] CoreMusicLibrary::Adding element Podcast Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [qobuz] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019722] CoreMusicLibrary::Adding element Qobuz Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [tidal] [1765953019743] ControllerTidalPlugin::onStart Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [tidal] [1765953019754] ControllerTidalPlugin::addToBrowseSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [tidal] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: [1765953019755] CoreMusicLibrary::Adding element Tidal Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: Loading i18n strings for locale en Dec 17 14:30:19 so1905-bfc4 volumio[9591]: Updating browse sources language Dec 17 14:30:19 so1905-bfc4 volumio[9591]: Cannot find translation for sourceMedia Servers Dec 17 14:30:19 so1905-bfc4 volumio[9591]: Cannot find translation for sourceYandex Music Dec 17 14:30:19 so1905-bfc4 volumio[9591]: Cannot find translation for sourcePodcast Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:30:19 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::initPlayerControls Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:19 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:20 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 14:30:20 so1905-bfc4 volumio[9591]: info: BOOT COMPLETED Dec 17 14:30:20 so1905-bfc4 volumio[9591]: [Metrics] CommandRouter: 11s 284.73ms Dec 17 14:30:20 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 14:30:20 so1905-bfc4 kernel: sunxi-spdif 2036000.spdif: active: 1 Dec 17 14:30:25 so1905-bfc4 volumio[9591]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 17 14:30:25 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::Close All Modals sent Dec 17 14:30:25 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:25 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::Close All Modals sent Dec 17 14:30:25 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:25 so1905-bfc4 volumio[9591]: Express server listening on port 3000 Dec 17 14:30:25 so1905-bfc4 volumio[9591]: [Metrics] WebUI: 17s 417.03ms Dec 17 14:30:25 so1905-bfc4 volumio[9591]: (node:9591) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 17 14:30:25 so1905-bfc4 volumio[9591]: [pandora] [1765953025537] ControllerPandora::validateAndSetAccountOptions Dec 17 14:30:25 so1905-bfc4 volumio[9591]: [pandora] [1765953025539] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Dec 17 14:30:25 so1905-bfc4 volumio[9591]: info: CoreStateMachine::resetVolumioState Dec 17 14:30:25 so1905-bfc4 volumio[9591]: info: CoreStateMachine::getcurrentVolume Dec 17 14:30:25 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 14:30:25 so1905-bfc4 volumio[9591]: [i2s_dacs] CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 14:30:25 so1905-bfc4 sudo[9725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:30:25 so1905-bfc4 volumio[9591]: [i2s_dacs] Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 17 14:30:25 so1905-bfc4 sudo[9725]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:26 so1905-bfc4 sudo[9725]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:26 so1905-bfc4 sudo[9729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:30:26 so1905-bfc4 sudo[9729]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:26 so1905-bfc4 volumio[9591]: [networkfs] IP address changed from "" to "192.168.2.118" Dec 17 14:30:26 so1905-bfc4 volumio[9591]: [networkfs] CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 17 14:30:26 so1905-bfc4 sudo[9729]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:26 so1905-bfc4 sudo[9759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:30:26 so1905-bfc4 sudo[9759]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:26 so1905-bfc4 sudo[9765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:30:26 so1905-bfc4 sudo[9759]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:26 so1905-bfc4 sudo[9765]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:26 so1905-bfc4 sudo[9775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:30:26 so1905-bfc4 sudo[9765]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:26 so1905-bfc4 sudo[9775]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:26 so1905-bfc4 sudo[9794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:30:26 so1905-bfc4 sudo[9775]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:26 so1905-bfc4 volumio[9591]: [networkfs] Connecting to wireless device: undefined Dec 17 14:30:26 so1905-bfc4 sudo[9794]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:26 so1905-bfc4 volumio[9591]: info: this.stateMachine.currentVolume: null, vol: 0 Dec 17 14:30:26 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:26 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:26 so1905-bfc4 sudo[9794]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:26 so1905-bfc4 volumio[9591]: [airplay_emulation] [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Dec 17 14:30:26 so1905-bfc4 volumio[9591]: [airplay_emulation] Sending DBus command: /usr/bin/dbus-send --system --print-reply --dest=org.gnome.ShairportSync "/org/gnome/ShairportSync" org.gnome.ShairportSync.RemoteControl.SetAirplayVolume double:-30.00 Dec 17 14:30:26 so1905-bfc4 sudo[9803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:30:26 so1905-bfc4 sudo[9803]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:26 so1905-bfc4 sudo[9803]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:26 so1905-bfc4 volumio[9591]: info: CoreStateMachine::pushState Dec 17 14:30:26 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 14:30:26 so1905-bfc4 volumio[9591]: [rest_api] API:pushState Dec 17 14:30:27 so1905-bfc4 volumio[9591]: info: CoreStateMachine::updateTrackBlock Dec 17 14:30:27 so1905-bfc4 volumio[9591]: info: CorePlayQueue::getTrackBlock Dec 17 14:30:27 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 14:30:27 so1905-bfc4 volumio[9591]: [mpd] MPD Permissions set Dec 17 14:30:27 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 17 14:30:27 so1905-bfc4 volumio[9591]: [alsa_controller] CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 14:30:27 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 17 14:30:27 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:27 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [mpd] MPD running with PID9678 Dec 17 14:30:28 so1905-bfc4 volumio[9591]: ,establishing connection Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [i2s_dacs] Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Dec 17 14:30:28 so1905-bfc4 volumio[9591]: sudo: /usr/sbin/i2cdetect: command not found Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [airplay_emulation] DBus command succeeded: SetAirplayVolume Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [rest_api] API:emitFavourites Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [spop] Spotify config file written Dec 17 14:30:28 so1905-bfc4 sudo[9853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Dec 17 14:30:28 so1905-bfc4 sudo[9853]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:30:28 so1905-bfc4 volumio[9826]: Forking 1 albumart workers Dec 17 14:30:28 so1905-bfc4 systemd[1]: Stopping go-librespot Daemon... Dec 17 14:30:28 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Killing process 8702 (go-librespot) with signal SIGKILL. Dec 17 14:30:28 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Succeeded. Dec 17 14:30:28 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 17 14:30:28 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 17 14:30:28 so1905-bfc4 volumio[9591]: sendCommand: Command { name: 'playlistinfo', args: [] } Dec 17 14:30:28 so1905-bfc4 sudo[9853]: pam_unix(sudo:session): session closed for user root Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [mpd] MPD error: false == true Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [mpd] false == true Dec 17 14:30:28 so1905-bfc4 volumio[9591]: sendCommand: Command { name: 'search album ""', args: [] } Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [mpd] MPD error: false == true Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [mpd] false == true Dec 17 14:30:28 so1905-bfc4 go-librespot[9860]: go-librespot daemon starting... Dec 17 14:30:28 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:30:29 so1905-bfc4 volumio[9591]: [spop] No need to fix Spotify hosts Dec 17 14:30:29 so1905-bfc4 volumio[9591]: sendCommand: Command { name: 'playlistinfo', args: [] } Dec 17 14:30:30 so1905-bfc4 volumio[9591]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Dec 17 14:30:30 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:30+08:00" level=info msg="running go-librespot 0.0.0" Dec 17 14:30:30 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:30+08:00" level=debug msg="app state loaded" Dec 17 14:30:30 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:30+08:00" level=debug msg="stored credentials not found" Dec 17 14:30:30 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:30+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 17 14:30:30 so1905-bfc4 display[9583]: setImageSrc: this=0x2a46a20, str=/volumio/display/assets/status-icon/wifi_ok.png Dec 17 14:30:30 so1905-bfc4 display[9583]: read file /volumio/display/assets/status-icon/wifi_ok.png, size=861, buffer[0]=89 Dec 17 14:30:30 so1905-bfc4 volumio[9591]: info: CoreStateMachine::setRepeat null single undefined Dec 17 14:30:30 so1905-bfc4 volumio[9591]: info: CoreStateMachine::pushState Dec 17 14:30:30 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 14:30:30 so1905-bfc4 volumio[9591]: [rest_api] API:pushState Dec 17 14:30:30 so1905-bfc4 volumio[9591]: info: CoreStateMachine::setRandom null Dec 17 14:30:30 so1905-bfc4 volumio[9591]: info: CoreStateMachine::pushState Dec 17 14:30:30 so1905-bfc4 volumio[9591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 14:30:30 so1905-bfc4 volumio[9591]: [rest_api] API:pushState Dec 17 14:30:30 so1905-bfc4 volumio[9591]: Discovery: adding 1262c1f4-267b-4e25-a601-bdb1d988c032 Dec 17 14:30:30 so1905-bfc4 volumio[9591]: [volumiodiscovery] mDNS: Found device FT1905-c7dc Dec 17 14:30:30 so1905-bfc4 volumio[9591]: error: Could not retrieve plugin audio_interface multiroom Dec 17 14:30:30 so1905-bfc4 volumio[9591]: [rest_api] API:pushMultiroomDevices Dec 17 14:30:30 so1905-bfc4 display[9583]: setImageSrc: this=0x2a46850, str=http://127.0.0.1:3000/albumart Dec 17 14:30:30 so1905-bfc4 volumio[9591]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 14:30:30 so1905-bfc4 volumio[9591]: TypeError: Cannot read property 'getState' of undefined Dec 17 14:30:30 so1905-bfc4 volumio[9591]: at CoreCommandRouter.volumioGetState (webpack://Volumio2/./app/index.js?:99:28) Dec 17 14:30:30 so1905-bfc4 volumio[9591]: at Socket.eval (webpack://Volumio2/./app/plugins/user_interface/websocket/index.js?:53:38) Dec 17 14:30:30 so1905-bfc4 volumio[9591]: at Socket.emit (events.js:198:13) Dec 17 14:30:30 so1905-bfc4 volumio[9591]: at eval (webpack://Volumio2/./node_modules/socket.io/lib/socket.js?:503:12) Dec 17 14:30:30 so1905-bfc4 volumio[9591]: at process._tickCallback (internal/process/next_tick.js:61:11) Dec 17 14:30:30 so1905-bfc4 volumio[9591]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 14:30:31 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:31+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 17 14:30:31 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:31+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 17 14:30:31 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:31+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 17 14:30:31 so1905-bfc4 go-librespot[9883]: time="2025-12-17T14:30:31+08:00" level=info msg="zeroconf server listening on port 46151" Dec 17 14:30:31 so1905-bfc4 go-librespot[9883]: 2025/12/17 14:30:31 [zeroconf] no suitable IPv6 interface: listen udp6 [ff02::]:5353: socket: address family not supported by protocol Dec 17 14:30:31 so1905-bfc4 volumio[9859]: Starting albumart workers Dec 17 14:30:32 so1905-bfc4 sudo[9905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-17 14:29 Dec 17 14:30:32 so1905-bfc4 sudo[9905]: 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 BRAND_NAME="PREMIERA" MODEL_NAME="FT1905" IMG_VERSION="1.100.006" VOLUMIO_BUILD_VERSION="" VOLUMIO_ARCH="armv7" VOLUMIO_VERSION=1.5.4.20 VOLUMIO_BUILD_DATE=2025-12-05 14:21:17 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system