Dec 20 10:03:01 volumio volumio[1299]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/bookworm/amd64 Dec 20 10:03:03 volumio volumio[1299]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/bookworm/amd64 Dec 20 10:03:03 volumio volumio[1299]: info: Folder /tmp/plugins removed Dec 20 10:03:03 volumio volumio[1299]: info: Check plugin dependencies Dec 20 10:03:03 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 10:03:03 volumio volumio[1299]: info: Checking if plugin already exists Dec 20 10:03:03 volumio volumio[1299]: info: Rename folder Dec 20 10:03:03 volumio volumio[1299]: info: Folder /tmp/downloaded_plugin.zip removed Dec 20 10:03:03 volumio volumio[1299]: info: Move to category Dec 20 10:03:03 volumio volumio[1299]: info: Checking if install.sh is present Dec 20 10:03:03 volumio volumio[1299]: info: Executing install.sh Dec 20 10:03:03 volumio sudo[22183]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_controller/backup_restore/install.sh Dec 20 10:03:03 volumio sudo[22183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 10:03:03 volumio sudo[22183]: pam_unix(sudo:session): session closed for user root Dec 20 10:03:03 volumio volumio[1299]: info: Installing backup restore Dependencies Dec 20 10:03:03 volumio volumio[1299]: Plugin install end detected on script Dec 20 10:03:03 volumio volumio[1299]: info: Install script completed Dec 20 10:03:03 volumio volumio[1299]: info: Adding reference to registry Dec 20 10:03:03 volumio volumio[1299]: info: Done installing plugin. Dec 20 10:03:03 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 10:03:03 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 10:03:03 volumio volumio[1299]: info: Folder /tmp/plugins removed Dec 20 10:03:03 volumio volumio[1299]: info: Folder /tmp/downloaded_plugin.zip removed Dec 20 10:03:03 volumio volumio[1299]: info: Folder /data/temp removed Dec 20 10:03:04 volumio volumio[1299]: info: Enabling plugin backup_restore Dec 20 10:03:04 volumio volumio[1299]: info: Loading plugin "backup_restore"... Dec 20 10:03:05 volumio volumio[1299]: info: PLUGIN START: backup_restore Dec 20 10:03:05 volumio volumio[1299]: info: Done. Dec 20 10:03:10 volumio volumio[1299]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 10:03:19 volumio go-librespot[1885]: time="2025-12-20T10:03:19+09:00" level=trace msg="sent dealer ping" Dec 20 10:03:19 volumio go-librespot[1885]: time="2025-12-20T10:03:19+09:00" level=trace msg="received dealer pong" Dec 20 10:03:26 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 10:03:26 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 10:03:44 volumio volumio[1299]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Dec 20 10:03:46 volumio volumio[1299]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Dec 20 10:03:46 volumio volumio[1299]: info: Folder /tmp/plugins removed Dec 20 10:03:46 volumio volumio[1299]: info: Check plugin dependencies Dec 20 10:03:46 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 10:03:46 volumio volumio[1299]: info: Checking if plugin already exists Dec 20 10:03:46 volumio volumio[1299]: info: Rename folder Dec 20 10:03:46 volumio volumio[1299]: info: Folder /tmp/downloaded_plugin.zip removed Dec 20 10:03:46 volumio volumio[1299]: info: Move to category Dec 20 10:03:46 volumio volumio[1299]: info: Checking if install.sh is present Dec 20 10:03:46 volumio volumio[1299]: info: Executing install.sh Dec 20 10:03:46 volumio sudo[22402]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/ferrum_streaming_control_technology/install.sh Dec 20 10:03:46 volumio sudo[22402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 10:03:46 volumio sudo[22402]: pam_unix(sudo:session): session closed for user root Dec 20 10:03:46 volumio volumio[1299]: info: Installing ferrum streaming control technology Dependencies Dec 20 10:03:46 volumio volumio[1299]: info: Install script completed Dec 20 10:03:46 volumio volumio[1299]: info: Adding reference to registry Dec 20 10:03:46 volumio volumio[1299]: info: Done installing plugin. Dec 20 10:03:46 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 10:03:46 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 10:03:46 volumio volumio[1299]: info: Folder /tmp/plugins removed Dec 20 10:03:46 volumio volumio[1299]: info: Folder /tmp/downloaded_plugin.zip removed Dec 20 10:03:46 volumio volumio[1299]: Plugin install end detected on script Dec 20 10:03:46 volumio volumio[1299]: info: Folder /data/temp removed Dec 20 10:03:47 volumio volumio[1299]: info: Enabling plugin ferrum_streaming_control_technology Dec 20 10:03:47 volumio volumio[1299]: info: Loading plugin "ferrum_streaming_control_technology"... Dec 20 10:03:48 volumio volumio[1299]: error: !!!! WARNING !!!! Dec 20 10:03:48 volumio volumio[1299]: error: The plugin user_interface/ferrum_streaming_control_technology failed to load, setting it to stopped. Error: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Dec 20 10:03:48 volumio volumio[1299]: Require stack: Dec 20 10:03:48 volumio volumio[1299]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Dec 20 10:03:48 volumio volumio[1299]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Dec 20 10:03:48 volumio volumio[1299]: - /volumio/app/pluginmanager.js Dec 20 10:03:48 volumio volumio[1299]: - /volumio/app/index.js Dec 20 10:03:48 volumio volumio[1299]: - /volumio/index.js Dec 20 10:03:48 volumio volumio[1299]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Dec 20 10:03:48 volumio volumio[1299]: Require stack: Dec 20 10:03:48 volumio volumio[1299]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Dec 20 10:03:48 volumio volumio[1299]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Dec 20 10:03:48 volumio volumio[1299]: - /volumio/app/pluginmanager.js Dec 20 10:03:48 volumio volumio[1299]: - /volumio/app/index.js Dec 20 10:03:48 volumio volumio[1299]: - /volumio/index.js Dec 20 10:03:48 volumio volumio[1299]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Dec 20 10:03:48 volumio volumio[1299]: at Module._load (node:internal/modules/cjs/loader:901:27) Dec 20 10:03:48 volumio volumio[1299]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 20 10:03:48 volumio volumio[1299]: at require (node:internal/modules/helpers:130:18) Dec 20 10:03:48 volumio volumio[1299]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Dec 20 10:03:48 volumio volumio[1299]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Dec 20 10:03:48 volumio volumio[1299]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Dec 20 10:03:48 volumio volumio[1299]: at Module.load (node:internal/modules/cjs/loader:1091:32) Dec 20 10:03:48 volumio volumio[1299]: at Module._load (node:internal/modules/cjs/loader:938:12) Dec 20 10:03:48 volumio volumio[1299]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 20 10:03:48 volumio volumio[1299]: at require (node:internal/modules/helpers:130:18) Dec 20 10:03:48 volumio volumio[1299]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Dec 20 10:03:48 volumio volumio[1299]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Dec 20 10:03:48 volumio volumio[1299]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Dec 20 10:03:48 volumio volumio[1299]: at Module.load (node:internal/modules/cjs/loader:1091:32) Dec 20 10:03:48 volumio volumio[1299]: at Module._load (node:internal/modules/cjs/loader:938:12) Dec 20 10:03:48 volumio volumio[1299]: error: !!!! WARNING !!!! Dec 20 10:03:48 volumio volumio[1299]: info: Done. Dec 20 10:03:49 volumio go-librespot[1885]: time="2025-12-20T10:03:49+09:00" level=trace msg="sent dealer ping" Dec 20 10:03:49 volumio go-librespot[1885]: time="2025-12-20T10:03:49+09:00" level=trace msg="received dealer pong" Dec 20 10:03:59 volumio volumio[1299]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 10:04:01 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 10:04:01 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 10:04:12 volumio volumio[1299]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 10:04:12 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 10:04:12 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 10:04:12 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 10:04:12 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 20 10:04:12 volumio volumio[1299]: info: Received Get System Version Dec 20 10:04:12 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 10:04:12 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Dec 20 10:04:19 volumio go-librespot[1885]: time="2025-12-20T10:04:19+09:00" level=trace msg="sent dealer ping" Dec 20 10:04:19 volumio go-librespot[1885]: time="2025-12-20T10:04:19+09:00" level=trace msg="received dealer pong" Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 20 10:04:34 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 20 10:04:48 volumio volumio[1299]: info: CorePlayQueue::getTrack 7 Dec 20 10:04:48 volumio volumio[1299]: info: CorePlayQueue::getTrack 8 Dec 20 10:04:48 volumio volumio[1299]: info: Prefetching next song Dec 20 10:04:48 volumio volumio[1299]: info: [ytmusic] Obtained PO token for video #QLNM39iLQr8: MlUxxagMI9-_UzH7worshE-9gMbFS6gZ8AmZTfKirp8hG19wneQI8RBgnml5IkPS2vSeZqhTbyh7pBf9snEV9WKSmADVQy3bQE3-HbQpM7iZ9uxe3sEw Dec 20 10:04:49 volumio volumio[1299]: info: [ytmusic] (卒業写真) validating stream URL "https://rr6---sn-ji0c0uxa-obvel.googlevideo.com/videoplayback?expire=1766214288&ei=MPZFacmQN7zppt8PprD2-A8&ip=123.48.24.122&id=o-AM4N6xWujXDWZwpgpExwMtm-Nvcz8AZ3iRdTaxcq6trf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1766192688%2C&mh=zi&mm=31%2C29&mn=sn-ji0c0uxa-obvel%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=6&pl=18&rms=au%2Cau&gcr=jp&initcwndbps=4202500&bui=AYUSA3BTf2AEd36sq6dqjEIFcpiFKL4n-aW2Na7y2qStOyq1Q-uHaZcIzxY6ObnsyiBOZsvrXbwg3LRK&spc=wH4Qq_Qk2_ijZ6SFvtSK543ihhdZi0GVlb7VhM1RSW2nK671dA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oVpq6_I_eOvX63UaPKWQYi8R&rqh=1&gir=yes&clen=4134361&dur=242.861&lmt=1714625991701775&mt=1766192217&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=1318224&n=p_jhP2VtdTaebA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgXPL9Sp4CfmKlO4vrh39BZeQl98AKO49oI9GWIvFX8AoCIDVxR7qamUf8C-M52Nxp9cxiN7bELczJxddRdrUNX1SN&sig=AJfQdSswRQIhAKfC3xReOEG29rzkBD0LcWRR_1Mms4rSi6RtYEKoz8cIAiA0Lpb_ORzol40Aoh_Pqr6VbHsWoYELmzhnze4jQRwQAw%3D%3D&pot=MlUxxagMI9-_UzH7worshE-9gMbFS6gZ8AmZTfKirp8hG19wneQI8RBgnml5IkPS2vSeZqhTbyh7pBf9snEV9WKSmADVQy3bQE3-HbQpM7iZ9uxe3sEw&cver=1.20250219.01.00"... Dec 20 10:04:49 volumio volumio[1299]: info: [ytmusic] (卒業写真) stream validated in 0.05s. Dec 20 10:04:49 volumio volumio[1299]: verbose: ControllerMpd::sendMpdCommand addid "https://rr6---sn-ji0c0uxa-obvel.googlevideo.com/videoplayback?expire=1766214288&ei=MPZFacmQN7zppt8PprD2-A8&ip=123.48.24.122&id=o-AM4N6xWujXDWZwpgpExwMtm-Nvcz8AZ3iRdTaxcq6trf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1766192688%2C&mh=zi&mm=31%2C29&mn=sn-ji0c0uxa-obvel%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=6&pl=18&rms=au%2Cau&gcr=jp&initcwndbps=4202500&bui=AYUSA3BTf2AEd36sq6dqjEIFcpiFKL4n-aW2Na7y2qStOyq1Q-uHaZcIzxY6ObnsyiBOZsvrXbwg3LRK&spc=wH4Qq_Qk2_ijZ6SFvtSK543ihhdZi0GVlb7VhM1RSW2nK671dA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oVpq6_I_eOvX63UaPKWQYi8R&rqh=1&gir=yes&clen=4134361&dur=242.861&lmt=1714625991701775&mt=1766192217&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=1318224&n=p_jhP2VtdTaebA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgXPL9Sp4CfmKlO4vrh39BZeQl98AKO49oI9GWIvFX8AoCIDVxR7qamUf8C-M52Nxp9cxiN7bELczJxddRdrUNX1SN&sig=AJfQdSswRQIhAKfC3xReOEG29rzkBD0LcWRR_1Mms4rSi6RtYEKoz8cIAiA0Lpb_ORzol40Aoh_Pqr6VbHsWoYELmzhnze4jQRwQAw%3D%3D&pot=MlUxxagMI9-_UzH7worshE-9gMbFS6gZ8AmZTfKirp8hG19wneQI8RBgnml5IkPS2vSeZqhTbyh7pBf9snEV9WKSmADVQy3bQE3-HbQpM7iZ9uxe3sEw&cver=1.20250219.01.00&t.YouTube" Dec 20 10:04:49 volumio volumio[1299]: info: Dec 20 10:04:49 volumio volumio[1299]: ---------------------------- MPD announces system playlist update Dec 20 10:04:49 volumio volumio[1299]: info: Ignoring MPD Status Update Dec 20 10:04:49 volumio volumio[1299]: info: sendMpdCommand addid "https://rr6---sn-ji0c0uxa-obvel.googlevideo.com/videoplayback?expire=1766214288&ei=MPZFacmQN7zppt8PprD2-A8&ip=123.48.24.122&id=o-AM4N6xWujXDWZwpgpExwMtm-Nvcz8AZ3iRdTaxcq6trf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=0&met=1766192688%2C&mh=zi&mm=31%2C29&mn=sn-ji0c0uxa-obvel%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=6&pl=18&rms=au%2Cau&gcr=jp&initcwndbps=4202500&bui=AYUSA3BTf2AEd36sq6dqjEIFcpiFKL4n-aW2Na7y2qStOyq1Q-uHaZcIzxY6ObnsyiBOZsvrXbwg3LRK&spc=wH4Qq_Qk2_ijZ6SFvtSK543ihhdZi0GVlb7VhM1RSW2nK671dA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oVpq6_I_eOvX63UaPKWQYi8R&rqh=1&gir=yes&clen=4134361&dur=242.861&lmt=1714625991701775&mt=1766192217&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=1318224&n=p_jhP2VtdTaebA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgXPL9Sp4CfmKlO4vrh39BZeQl98AKO49oI9GWIvFX8AoCIDVxR7qamUf8C-M52Nxp9cxiN7bELczJxddRdrUNX1SN&sig=AJfQdSswRQIhAKfC3xReOEG29rzkBD0LcWRR_1Mms4rSi6RtYEKoz8cIAiA0Lpb_ORzol40Aoh_Pqr6VbHsWoYELmzhnze4jQRwQAw%3D%3D&pot=MlUxxagMI9-_UzH7worshE-9gMbFS6gZ8AmZTfKirp8hG19wneQI8RBgnml5IkPS2vSeZqhTbyh7pBf9snEV9WKSmADVQy3bQE3-HbQpM7iZ9uxe3sEw&cver=1.20250219.01.00&t.YouTube" took 1 milliseconds Dec 20 10:04:49 volumio volumio[1299]: verbose: MPD COMMAND [object Object] Dec 20 10:04:49 volumio volumio[1299]: verbose: MPD COMMAND [object Object] Dec 20 10:04:49 volumio volumio[1299]: verbose: MPD COMMAND [object Object] Dec 20 10:04:49 volumio volumio[1299]: info: Dec 20 10:04:49 volumio volumio[1299]: ---------------------------- MPD announces system playlist update Dec 20 10:04:49 volumio volumio[1299]: info: Ignoring MPD Status Update Dec 20 10:04:49 volumio volumio[1299]: info: ------------------------------ 1ms Dec 20 10:04:49 volumio volumio[1299]: info: [ytmusic-play] Prefetched and added track to MPD queue: 卒業写真 Dec 20 10:04:49 volumio volumio[1299]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 20 10:04:49 volumio volumio[1299]: info: ------------------------------ 1ms Dec 20 10:04:49 volumio volumio[1299]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 20 10:04:49 volumio go-librespot[1885]: time="2025-12-20T10:04:49+09:00" level=trace msg="received accesspoint ping" Dec 20 10:04:49 volumio go-librespot[1885]: time="2025-12-20T10:04:49+09:00" level=trace msg="received accesspoint pong ack" Dec 20 10:04:49 volumio go-librespot[1885]: time="2025-12-20T10:04:49+09:00" level=trace msg="sent dealer ping" Dec 20 10:04:49 volumio go-librespot[1885]: time="2025-12-20T10:04:49+09:00" level=trace msg="received dealer pong" Dec 20 10:04:52 volumio volumio[1299]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Dec 20 10:04:52 volumio sudo[22737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 10:04:52 volumio sudo[22737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 10:04:52 volumio sudo[22737]: pam_unix(sudo:session): session closed for user root Dec 20 10:04:52 volumio sudo[22739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 10:04:52 volumio sudo[22739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 10:04:52 volumio volumio[1299]: error: Failed callmethod call: TypeError: Cannot read properties of null (reading 'printToastMessage') Dec 20 10:04:52 volumio volumio[1299]: info: MPD Permissions set Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 20 10:04:52 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 10:04:52 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 20 10:04:52 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 20 10:04:52 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 20 10:04:52 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 20 10:04:52 volumio systemd[1]: mpd.service: Consumed 32min 28.519s CPU time. Dec 20 10:04:52 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 20 10:04:52 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 20 10:04:52 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 20 10:04:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 10:04:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 10:04:52 volumio sudo[22763]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 10:04:52 volumio sudo[22763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 10:04:52 volumio sudo[22763]: pam_unix(sudo:session): session closed for user root Dec 20 10:04:52 volumio mpd[22768]: 2025-12-20T10:04:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 10:04:52 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 20 10:04:52 volumio sudo[22739]: pam_unix(sudo:session): session closed for user root Dec 20 10:04:52 volumio volumio[1299]: error: updateQueue error: null Dec 20 10:04:52 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 20 10:04:52 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 20 10:04:53 volumio volumio[1299]: info: CoreStateMachine::startPlaybackTimer Dec 20 10:04:53 volumio volumio[1299]: info: CorePlayQueue::getTrack 8 Dec 20 10:04:53 volumio volumio[1299]: info: CoreStateMachine::pushState Dec 20 10:04:53 volumio volumio[1299]: info: CorePlayQueue::getTrack 8 Dec 20 10:04:53 volumio volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 10:04:53 volumio volumio[1299]: info: CoreCommandRouter::volumioPushState Dec 20 10:04:53 volumio volumio[1299]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 10:04:53 volumio volumio[1299]: TypeError: Cannot read properties of null (reading 'pushState') Dec 20 10:04:53 volumio volumio[1299]: at /volumio/app/index.js:326:32 Dec 20 10:04:53 volumio volumio[1299]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 20 10:04:53 volumio volumio[1299]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 20 10:04:53 volumio volumio[1299]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Dec 20 10:04:53 volumio volumio[1299]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Dec 20 10:04:53 volumio volumio[1299]: at listOnTimeout (node:internal/timers:573:17) Dec 20 10:04:53 volumio volumio[1299]: at process.processTimers (node:internal/timers:514:7) Dec 20 10:04:53 volumio volumio[1299]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 10:04:53 volumio sudo[22797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 10:03' Dec 20 10:04:53 volumio sudo[22797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"