-- Logs begin at Sat 2025-04-19 10:44:58 +07, end at Sat 2025-04-19 10:47:25 +07. -- Apr 19 10:46:00 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 10:46:00 volumio volumio[1080]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 19 10:46:00 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 19 10:46:00 volumio volumio[1080]: info: Received Get System Version Apr 19 10:46:00 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 10:46:00 volumio volumio[1080]: info: Received Get System Info Apr 19 10:46:00 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 10:46:00 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 10:46:00 volumio volumio[1080]: info: Discovery: Getting this device information Apr 19 10:46:00 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:46:00 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:46:00 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 10:46:00 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:04 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 10:46:04 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 19 10:46:04 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:04 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:04 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:46:06 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 10:46:07 volumio volumio[1080]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Apr 19 10:46:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:46:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 10:46:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:46:07 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 10:46:07 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 19 10:46:07 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:46:07 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:46:08 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Fpt 3 Ngoi Sao" Apr 19 10:46:08 volumio wpa_supplicant[983]: wlan0: SME: Trying to authenticate with 7c:a1:07:85:4f:00 (SSID='Fpt 3 Ngoi Sao' freq=2412 MHz) Apr 19 10:46:08 volumio kernel: wlan0: authenticate with 7c:a1:07:85:4f:00 Apr 19 10:46:08 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 19 10:46:08 volumio kernel: wlan0: send auth to 7c:a1:07:85:4f:00 (try 1/3) Apr 19 10:46:08 volumio wpa_supplicant[983]: wlan0: Trying to associate with 7c:a1:07:85:4f:00 (SSID='Fpt 3 Ngoi Sao' freq=2412 MHz) Apr 19 10:46:08 volumio kernel: wlan0: authenticated Apr 19 10:46:08 volumio kernel: wlan0: associate with 7c:a1:07:85:4f:00 (try 1/3) Apr 19 10:46:08 volumio kernel: wlan0: RX AssocResp from 7c:a1:07:85:4f:00 (capab=0x1411 status=12 aid=3) Apr 19 10:46:08 volumio kernel: wlan0: 7c:a1:07:85:4f:00 denied association (code=12) Apr 19 10:46:08 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=7c:a1:07:85:4f:00 status_code=12 Apr 19 10:46:08 volumio wpa_supplicant[983]: wlan0: SME: Deauth request to the driver failed Apr 19 10:46:08 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Fpt 3 Ngoi Sao" auth_failures=2 duration=23 reason=CONN_FAILED Apr 19 10:46:11 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:46:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:46:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 19 10:46:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 19 10:46:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 19 10:46:12 volumio volumio[1080]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 10:46:12 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 10:46:13 volumio volumio[1080]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 19 10:46:13 volumio volumio[1080]: info: Completed starting MyVolumio Plugin Apr 19 10:46:13 volumio volumio[1080]: [Metrics] CommandRouter: 53s 43.53ms Apr 19 10:46:13 volumio volumio[1080]: info: CoreCommandRouter::volumiosetStartupVolume Apr 19 10:46:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:46:13 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:46:13 volumio volumio[1080]: info: CoreCommandRouter::Close All Modals sent Apr 19 10:46:13 volumio volumio[1080]: info: CoreCommandRouter::Close All Modals sent Apr 19 10:46:13 volumio volumio[1080]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 19 10:46:13 volumio volumio[1080]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 19 10:46:13 volumio volumio[1080]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 19 10:46:16 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 19 10:46:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 10:46:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 19 10:46:19 volumio volumio[1080]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 19 10:46:20 volumio volumio[1080]: info: BOOT COMPLETED Apr 19 10:46:20 volumio volumio[1080]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Apr 19 10:46:20 volumio volumio[1080]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 19 10:46:20 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:46:20 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:46:20 volumio volumio[1080]: info: CoreCommandRouter::volumioPause Apr 19 10:46:20 volumio volumio[1080]: info: CoreStateMachine::pause Apr 19 10:46:20 volumio volumio[1080]: info: CoreStateMachine::stPlaybackTimer Apr 19 10:46:20 volumio volumio[1080]: info: CoreStateMachine::servicePause Apr 19 10:46:20 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:46:20 volumio volumio[1080]: info: CoreCommandRouter::servicePause Apr 19 10:46:20 volumio volumio[1080]: info: ControllerMpd::pause Apr 19 10:46:20 volumio volumio[1080]: verbose: ControllerMpd::sendMpdCommand pause Apr 19 10:46:20 volumio volumio[1080]: info: mpdhttpout --- Volumio set on pause Apr 19 10:46:20 volumio volumio[1080]: info: Apr 19 10:46:20 volumio volumio[1080]: ---------------------------- MPD announces state update: player Apr 19 10:46:20 volumio volumio[1080]: info: sendMpdCommand pause took 3 milliseconds Apr 19 10:46:20 volumio volumio[1080]: info: ControllerMpd::getState Apr 19 10:46:20 volumio volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 19 10:46:20 volumio volumio[1080]: info: sendMpdCommand status took 2 milliseconds Apr 19 10:46:21 volumio volumio[1080]: verbose: ControllerMpd::parseState Apr 19 10:46:21 volumio volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 10:46:21 volumio volumio[1080]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 10:46:21 volumio volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 19 10:46:21 volumio volumio[1080]: info: ControllerMpd::pushState Apr 19 10:46:21 volumio volumio[1080]: info: CoreCommandRouter::servicePushState Apr 19 10:46:21 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:46:21 volumio volumio[1080]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24473,"duration":331,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Track.wav","artist":null,"album":null,"uri":"USB/VOLUMIO/Album CD09 Mưa Đêm Ngoại Ô/01 Track.wav","trackType":"wav"} Apr 19 10:46:21 volumio volumio[1080]: verbose: CURRENT POSITION 0 Apr 19 10:46:21 volumio volumio[1080]: info: CoreStateMachine::syncState stateService pause Apr 19 10:46:21 volumio volumio[1080]: info: CoreStateMachine::syncState currentStatus pause Apr 19 10:46:21 volumio volumio[1080]: info: CoreStateMachine::pushState Apr 19 10:46:21 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:46:21 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 10:46:21 volumio volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 19 10:46:21 volumio volumio[1080]: info: CoreStateMachine::stPlaybackTimer Apr 19 10:46:21 volumio volumio[1080]: info: ------------------------------ 24ms Apr 19 10:46:21 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:23 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 19 10:46:23 volumio sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 10:46:23 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:46:23 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 19 10:46:23 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 19 10:46:23 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 19 10:46:23 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 10:46:23 volumio systemd[1]: mpd.service: Succeeded. Apr 19 10:46:23 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 10:46:23 volumio systemd[1]: Starting Music Player Daemon... Apr 19 10:46:23 volumio sudo[2209]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 10:46:23 volumio sudo[2209]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:46:23 volumio sudo[2209]: pam_unix(sudo:session): session closed for user root Apr 19 10:46:23 volumio mpd[2211]: Apr 19 10:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 10:46:23 volumio systemd[1]: Started Music Player Daemon. Apr 19 10:46:23 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root Apr 19 10:46:23 volumio volumio[1080]: error: updateQueue error: null Apr 19 10:46:23 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 19 10:46:24 volumio volumio[2214]: cset: --> shielding system active with Apr 19 10:46:24 volumio volumio[2214]: cset: "system" cpuset of CPUSPEC(1-3) with 241 tasks running Apr 19 10:46:24 volumio volumio[2214]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Apr 19 10:46:24 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 19 10:46:24 volumio ntpd[1069]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 19 10:46:26 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:26 volumio ntpd[1069]: Soliciting pool server 2401:5f80:5001:1f::31f Apr 19 10:46:28 volumio ntpd[1069]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 19 10:46:29 volumio ntpd[1069]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 19 10:46:31 volumio volumio[1080]: info: Enabling plugin 80s80s Apr 19 10:46:31 volumio volumio[1080]: info: Loading plugin "80s80s"... Apr 19 10:46:31 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:31 volumio volumio[1080]: info: [1745034391702] [80s80s] API delay: 30 Apr 19 10:46:31 volumio volumio[1080]: info: PLUGIN START: 80s80s Apr 19 10:46:31 volumio volumio[1080]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 10:46:31 volumio volumio[1080]: info: [1745034391707] CoreMusicLibrary::Adding element 80s80s Radio Apr 19 10:46:31 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:46:31 volumio volumio[1080]: Cannot find translation for source Calm Radio Apr 19 10:46:31 volumio volumio[1080]: Cannot find translation for source Jellyfin Apr 19 10:46:31 volumio volumio[1080]: Cannot find translation for source 80s80s Radio Apr 19 10:46:31 volumio volumio[1080]: info: Done. Apr 19 10:46:31 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:31 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:31 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:46:32 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Fpt 3 Ngoi Sao" Apr 19 10:46:32 volumio wpa_supplicant[983]: wlan0: SME: Trying to authenticate with 7c:a1:07:85:4f:00 (SSID='Fpt 3 Ngoi Sao' freq=2412 MHz) Apr 19 10:46:32 volumio kernel: wlan0: authenticate with 7c:a1:07:85:4f:00 Apr 19 10:46:32 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 19 10:46:32 volumio kernel: wlan0: send auth to 7c:a1:07:85:4f:00 (try 1/3) Apr 19 10:46:32 volumio wpa_supplicant[983]: wlan0: Trying to associate with 7c:a1:07:85:4f:00 (SSID='Fpt 3 Ngoi Sao' freq=2412 MHz) Apr 19 10:46:32 volumio kernel: wlan0: authenticated Apr 19 10:46:32 volumio kernel: wlan0: associate with 7c:a1:07:85:4f:00 (try 1/3) Apr 19 10:46:32 volumio kernel: wlan0: RX AssocResp from 7c:a1:07:85:4f:00 (capab=0x1411 status=12 aid=3) Apr 19 10:46:32 volumio kernel: wlan0: 7c:a1:07:85:4f:00 denied association (code=12) Apr 19 10:46:32 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=7c:a1:07:85:4f:00 status_code=12 Apr 19 10:46:32 volumio wpa_supplicant[983]: wlan0: SME: Deauth request to the driver failed Apr 19 10:46:32 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Fpt 3 Ngoi Sao" auth_failures=3 duration=46 reason=CONN_FAILED Apr 19 10:46:34 volumio nmbd[857]: [2025/04/19 10:46:34.800898, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 19 10:46:34 volumio nmbd[857]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Apr 19 10:46:36 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:39 volumio volumio[1080]: info: Enabling plugin controradio Apr 19 10:46:39 volumio volumio[1080]: info: Loading plugin "controradio"... Apr 19 10:46:39 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:39 volumio volumio[1080]: error: The plugin music_service/controradio failed to load, setting it to stopped. Error: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:39 volumio volumio[1080]: Require stack: Apr 19 10:46:39 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:39 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:39 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:39 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:39 volumio volumio[1080]: error: Stack trace: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:39 volumio volumio[1080]: Require stack: Apr 19 10:46:39 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:39 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:39 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:39 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:39 volumio volumio[1080]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Apr 19 10:46:39 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Apr 19 10:46:39 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:39 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:39 volumio volumio[1080]: at Object. (/data/plugins/music_service/controradio/index.js:5:21) Apr 19 10:46:39 volumio volumio[1080]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Apr 19 10:46:39 volumio volumio[1080]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Apr 19 10:46:39 volumio volumio[1080]: at Module.load (internal/modules/cjs/loader.js:979:32) Apr 19 10:46:39 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Apr 19 10:46:39 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:39 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:39 volumio volumio[1080]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 19 10:46:39 volumio volumio[1080]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 19 10:46:39 volumio volumio[1080]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 10:46:39 volumio volumio[1080]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 10:46:39 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:39 volumio volumio[1080]: info: Done. Apr 19 10:46:39 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:39 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:39 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:46:40 volumio volumio[1080]: info: Enabling plugin controradio Apr 19 10:46:40 volumio volumio[1080]: info: Loading plugin "controradio"... Apr 19 10:46:40 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:40 volumio volumio[1080]: error: The plugin music_service/controradio failed to load, setting it to stopped. Error: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:40 volumio volumio[1080]: Require stack: Apr 19 10:46:40 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:40 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:40 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:40 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:40 volumio volumio[1080]: error: Stack trace: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:40 volumio volumio[1080]: Require stack: Apr 19 10:46:40 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:40 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:40 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:40 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:40 volumio volumio[1080]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Apr 19 10:46:40 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Apr 19 10:46:40 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:40 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:40 volumio volumio[1080]: at Object. (/data/plugins/music_service/controradio/index.js:5:21) Apr 19 10:46:40 volumio volumio[1080]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Apr 19 10:46:40 volumio volumio[1080]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Apr 19 10:46:40 volumio volumio[1080]: at Module.load (internal/modules/cjs/loader.js:979:32) Apr 19 10:46:40 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Apr 19 10:46:40 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:40 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:40 volumio volumio[1080]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 19 10:46:40 volumio volumio[1080]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 19 10:46:40 volumio volumio[1080]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 10:46:40 volumio volumio[1080]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 10:46:40 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:40 volumio volumio[1080]: info: Done. Apr 19 10:46:40 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:40 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:40 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:46:41 volumio volumio[1080]: info: Enabling plugin controradio Apr 19 10:46:41 volumio volumio[1080]: info: Loading plugin "controradio"... Apr 19 10:46:41 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:41 volumio volumio[1080]: error: The plugin music_service/controradio failed to load, setting it to stopped. Error: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:41 volumio volumio[1080]: Require stack: Apr 19 10:46:41 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:41 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:41 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:41 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:41 volumio volumio[1080]: error: Stack trace: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:41 volumio volumio[1080]: Require stack: Apr 19 10:46:41 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:41 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:41 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:41 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:41 volumio volumio[1080]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Apr 19 10:46:41 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Apr 19 10:46:41 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:41 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:41 volumio volumio[1080]: at Object. (/data/plugins/music_service/controradio/index.js:5:21) Apr 19 10:46:41 volumio volumio[1080]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Apr 19 10:46:41 volumio volumio[1080]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Apr 19 10:46:41 volumio volumio[1080]: at Module.load (internal/modules/cjs/loader.js:979:32) Apr 19 10:46:41 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Apr 19 10:46:41 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:41 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:41 volumio volumio[1080]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 19 10:46:41 volumio volumio[1080]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 19 10:46:41 volumio volumio[1080]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 10:46:41 volumio volumio[1080]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 10:46:41 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:41 volumio volumio[1080]: info: Done. Apr 19 10:46:41 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:41 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:41 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:41 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:46:42 volumio volumio[1080]: info: Enabling plugin controradio Apr 19 10:46:42 volumio volumio[1080]: info: Loading plugin "controradio"... Apr 19 10:46:42 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:42 volumio volumio[1080]: error: The plugin music_service/controradio failed to load, setting it to stopped. Error: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:42 volumio volumio[1080]: Require stack: Apr 19 10:46:42 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:42 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:42 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:42 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:42 volumio volumio[1080]: error: Stack trace: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:42 volumio volumio[1080]: Require stack: Apr 19 10:46:42 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:42 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:42 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:42 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:42 volumio volumio[1080]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Apr 19 10:46:42 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Apr 19 10:46:42 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:42 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:42 volumio volumio[1080]: at Object. (/data/plugins/music_service/controradio/index.js:5:21) Apr 19 10:46:42 volumio volumio[1080]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Apr 19 10:46:42 volumio volumio[1080]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Apr 19 10:46:42 volumio volumio[1080]: at Module.load (internal/modules/cjs/loader.js:979:32) Apr 19 10:46:42 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Apr 19 10:46:42 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:42 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:42 volumio volumio[1080]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 19 10:46:42 volumio volumio[1080]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 19 10:46:42 volumio volumio[1080]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 10:46:42 volumio volumio[1080]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 10:46:42 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:42 volumio volumio[1080]: info: Done. Apr 19 10:46:42 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:42 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:42 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:46:46 volumio volumio[1080]: info: Enabling plugin controradio Apr 19 10:46:46 volumio volumio[1080]: info: Loading plugin "controradio"... Apr 19 10:46:46 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:46 volumio volumio[1080]: error: The plugin music_service/controradio failed to load, setting it to stopped. Error: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:46 volumio volumio[1080]: Require stack: Apr 19 10:46:46 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:46 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:46 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:46 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:46 volumio volumio[1080]: error: Stack trace: Error: Cannot find module 'fast-xml-parser' Apr 19 10:46:46 volumio volumio[1080]: Require stack: Apr 19 10:46:46 volumio volumio[1080]: - /data/plugins/music_service/controradio/index.js Apr 19 10:46:46 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:46:46 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:46:46 volumio volumio[1080]: - /volumio/index.js Apr 19 10:46:46 volumio volumio[1080]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Apr 19 10:46:46 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Apr 19 10:46:46 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:46 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:46 volumio volumio[1080]: at Object. (/data/plugins/music_service/controradio/index.js:5:21) Apr 19 10:46:46 volumio volumio[1080]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Apr 19 10:46:46 volumio volumio[1080]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Apr 19 10:46:46 volumio volumio[1080]: at Module.load (internal/modules/cjs/loader.js:979:32) Apr 19 10:46:46 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Apr 19 10:46:46 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:46:46 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:46:46 volumio volumio[1080]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 19 10:46:46 volumio volumio[1080]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 19 10:46:46 volumio volumio[1080]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 10:46:46 volumio volumio[1080]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 10:46:46 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:46:46 volumio volumio[1080]: info: Done. Apr 19 10:46:46 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:46 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:46 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:46:46 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:52 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:56 volumio volumio[1080]: info: Enabling plugin lastfm Apr 19 10:46:56 volumio volumio[1080]: info: Loading plugin "lastfm"... Apr 19 10:46:57 volumio volumio[1080]: info: PLUGIN START: lastfm Apr 19 10:46:57 volumio volumio[1080]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 10:46:57 volumio volumio[1080]: info: [1745034417163] CoreMusicLibrary::Adding element LastFM Apr 19 10:46:57 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:46:57 volumio volumio[1080]: Cannot find translation for source Calm Radio Apr 19 10:46:57 volumio volumio[1080]: Cannot find translation for source Jellyfin Apr 19 10:46:57 volumio volumio[1080]: Cannot find translation for source 80s80s Radio Apr 19 10:46:57 volumio volumio[1080]: Cannot find translation for source LastFM Apr 19 10:46:57 volumio volumio[1080]: info: [LastFM] scrobbler initiated! Apr 19 10:46:57 volumio volumio[1080]: info: [LastFM] extended logging: false Apr 19 10:46:57 volumio volumio[1080]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 10:46:57 volumio volumio[1080]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Apr 19 10:46:57 volumio volumio[1080]: info: [LastFM] Left init routine Apr 19 10:46:57 volumio volumio[1080]: info: [LastFM] Socket already connected: false Apr 19 10:46:57 volumio volumio[1080]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Apr 19 10:46:57 volumio volumio[1080]: info: Done. Apr 19 10:46:57 volumio volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 19 10:46:57 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:46:57 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:46:57 volumio volumio[1080]: info: FusionDsp - Apr 19 10:46:57 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:47:00 volumio volumio[1080]: info: Enabling plugin now_playing Apr 19 10:47:00 volumio volumio[1080]: info: Loading plugin "now_playing"... Apr 19 10:47:00 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:47:00 volumio volumio[1080]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Apr 19 10:47:00 volumio volumio[1080]: Require stack: Apr 19 10:47:00 volumio volumio[1080]: - /data/plugins/user_interface/now_playing/dist/index.js Apr 19 10:47:00 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:47:00 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:47:00 volumio volumio[1080]: - /volumio/index.js Apr 19 10:47:00 volumio volumio[1080]: error: Stack trace: Error: Cannot find module 'geo-tz' Apr 19 10:47:00 volumio volumio[1080]: Require stack: Apr 19 10:47:00 volumio volumio[1080]: - /data/plugins/user_interface/now_playing/dist/index.js Apr 19 10:47:00 volumio volumio[1080]: - /volumio/app/pluginmanager.js Apr 19 10:47:00 volumio volumio[1080]: - /volumio/app/index.js Apr 19 10:47:00 volumio volumio[1080]: - /volumio/index.js Apr 19 10:47:00 volumio volumio[1080]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Apr 19 10:47:00 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Apr 19 10:47:00 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:47:00 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:47:00 volumio volumio[1080]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Apr 19 10:47:00 volumio volumio[1080]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Apr 19 10:47:00 volumio volumio[1080]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Apr 19 10:47:00 volumio volumio[1080]: at Module.load (internal/modules/cjs/loader.js:979:32) Apr 19 10:47:00 volumio volumio[1080]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Apr 19 10:47:00 volumio volumio[1080]: at Module.require (internal/modules/cjs/loader.js:1003:19) Apr 19 10:47:00 volumio volumio[1080]: at require (internal/modules/cjs/helpers.js:107:18) Apr 19 10:47:00 volumio volumio[1080]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 19 10:47:00 volumio volumio[1080]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 19 10:47:00 volumio volumio[1080]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 10:47:00 volumio volumio[1080]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 10:47:00 volumio volumio[1080]: error: !!!! WARNING !!!! Apr 19 10:47:00 volumio volumio[1080]: info: Done. Apr 19 10:47:00 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:47:00 volumio volumio[1080]: info: FusionDsp - Apr 19 10:47:00 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:47:02 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:47:04 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:47:07 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:47:08 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 10:47:08 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 19 10:47:12 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:47:17 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:47:19 volumio volumio[1080]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: Received Get System Info Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 10:47:19 volumio volumio[1080]: info: Discovery: Getting this device information Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 10:47:19 volumio volumio[1080]: info: Received Get System Info Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 10:47:19 volumio volumio[1080]: info: Discovery: Getting this device information Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: Listing playlists Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Fpt 3 Ngoi Sao" Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: SME: Trying to authenticate with 7c:a1:07:85:4f:00 (SSID='Fpt 3 Ngoi Sao' freq=2412 MHz) Apr 19 10:47:19 volumio kernel: wlan0: authenticate with 7c:a1:07:85:4f:00 Apr 19 10:47:19 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 19 10:47:19 volumio kernel: wlan0: send auth to 7c:a1:07:85:4f:00 (try 1/3) Apr 19 10:47:19 volumio kernel: wlan0: authenticated Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: Trying to associate with 7c:a1:07:85:4f:00 (SSID='Fpt 3 Ngoi Sao' freq=2412 MHz) Apr 19 10:47:19 volumio kernel: wlan0: associate with 7c:a1:07:85:4f:00 (try 1/3) Apr 19 10:47:19 volumio volumio[1080]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: Associated with 7c:a1:07:85:4f:00 Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 19 10:47:19 volumio kernel: wlan0: RX AssocResp from 7c:a1:07:85:4f:00 (capab=0x1411 status=0 aid=3) Apr 19 10:47:19 volumio kernel: wlan0: associated Apr 19 10:47:19 volumio kernel: ath: EEPROM regdomain: 0x8348 Apr 19 10:47:19 volumio kernel: ath: EEPROM indicates we should expect a country code Apr 19 10:47:19 volumio kernel: ath: doing EEPROM country->regdmn map search Apr 19 10:47:19 volumio kernel: ath: country maps to regdmn code: 0x3a Apr 19 10:47:19 volumio kernel: ath: Country alpha2 being used: US Apr 19 10:47:19 volumio kernel: ath: Regpair used: 0x3a Apr 19 10:47:19 volumio kernel: ath: regdomain 0x8348 dynamically updated by country element Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: WPA: Key negotiation completed with 7c:a1:07:85:4f:00 [PTK=CCMP GTK=TKIP] Apr 19 10:47:19 volumio wpa_supplicant[983]: wlan0: CTRL-EVENT-CONNECTED - Connection to 7c:a1:07:85:4f:00 completed [id=0 id_str=] Apr 19 10:47:19 volumio dhcpcd[809]: wlan0: carrier acquired Apr 19 10:47:19 volumio dhcpcd[809]: wlan0: IAID 00:87:7a:c8 Apr 19 10:47:19 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 7c:a1:07:85:4f:00 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: Received Get System Info Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 10:47:19 volumio volumio[1080]: info: Discovery: Getting this device information Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 10:47:19 volumio volumio[1080]: info: Received Get System Info Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 10:47:19 volumio volumio[1080]: info: Discovery: Getting this device information Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:19 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:19 volumio volumio[1080]: info: Listing playlists Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:47:19 volumio volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 10:47:19 volumio volumio[1080]: info: FusionDsp - Apr 19 10:47:19 volumio volumio[1080]: info: FusionDsp - Apr 19 10:47:19 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:47:19 volumio volumio[1080]: info: FusionDsp - undefined Apr 19 10:47:19 volumio dhcpcd[809]: wlan0: soliciting an IPv6 router Apr 19 10:47:20 volumio dhcpcd[809]: wlan0: rebinding lease of 192.168.1.77 Apr 19 10:47:20 volumio dhcpcd[809]: wlan0: probing address 192.168.1.77/24 Apr 19 10:47:21 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 10:47:21 volumio volumio[1080]: info: Received Get System Info Apr 19 10:47:21 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 10:47:21 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 10:47:21 volumio volumio[1080]: info: Discovery: Getting this device information Apr 19 10:47:21 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:21 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:21 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 10:47:22 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 19 10:47:24 volumio dhcpcd[809]: wlan0: leased 192.168.1.77 for 86400 seconds Apr 19 10:47:24 volumio dhcpcd[809]: wlan0: adding route to 192.168.1.0/24 Apr 19 10:47:24 volumio dhcpcd[809]: wlan0: adding default route via 192.168.1.1 Apr 19 10:47:24 volumio avahi-daemon[721]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.77. Apr 19 10:47:24 volumio avahi-daemon[721]: New relevant interface wlan0.IPv4 for mDNS. Apr 19 10:47:24 volumio avahi-daemon[721]: Registering new address record for 192.168.1.77 on wlan0.IPv4. Apr 19 10:47:24 volumio ntpd[1069]: ntpd exiting on signal 15 (Terminated) Apr 19 10:47:24 volumio systemd[1]: Stopping Network Time Service... Apr 19 10:47:24 volumio systemd[1]: ntp.service: Succeeded. Apr 19 10:47:24 volumio systemd[1]: Stopped Network Time Service. Apr 19 10:47:24 volumio systemd[1]: Starting Network Time Service... Apr 19 10:47:24 volumio ntpd[2422]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 19 10:47:24 volumio ntpd[2422]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 19 10:47:24 volumio systemd[1]: Started Network Time Service. Apr 19 10:47:24 volumio ntpd[2428]: proto: precision = 0.065 usec (-24) Apr 19 10:47:24 volumio ntpd[2428]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 19 10:47:24 volumio ntpd[2428]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 19 10:47:24 volumio ntpd[2428]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 844 days ago Apr 19 10:47:24 volumio ntpd[2428]: Listen and drop on 0 v6wildcard [::]:123 Apr 19 10:47:24 volumio ntpd[2428]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 19 10:47:24 volumio ntpd[2428]: Listen normally on 2 lo 127.0.0.1:123 Apr 19 10:47:24 volumio ntpd[2428]: Listen normally on 3 wlan0 192.168.1.77:123 Apr 19 10:47:24 volumio ntpd[2428]: Listening on routing socket on fd #20 for interface updates Apr 19 10:47:24 volumio ntpd[2428]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 19 10:47:24 volumio ntpd[2428]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 19 10:47:24 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 10:47:24 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 10:47:24 volumio volumio-remote-updater[720]: No test mode Apr 19 10:47:24 volumio volumio-remote-updater[720]: No alpha test mode Apr 19 10:47:25 volumio volumio[1080]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 19 10:47:25 volumio volumio[1080]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 10:47:25 volumio volumio[1080]: info: Discovery: adding 5a1b22ee-e55c-4865-a609-eda85a3188c4 Apr 19 10:47:25 volumio volumio[1080]: info: Discovery: Found device Volumio Apr 19 10:47:25 volumio volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 19 10:47:25 volumio volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 19 10:47:25 volumio volumio[1080]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 10:47:25 volumio volumio[1080]: TypeError: Cannot read property 'pushMultiroomDevices' of null Apr 19 10:47:25 volumio volumio[1080]: at /volumio/app/index.js:1261:32 Apr 19 10:47:25 volumio volumio[1080]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Apr 19 10:47:25 volumio volumio[1080]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Apr 19 10:47:25 volumio volumio[1080]: at CoreCommandRouter.pushMultiroomDevices (/volumio/app/index.js:1259:13) Apr 19 10:47:25 volumio volumio[1080]: at ControllerVolumioDiscovery.pushMultiRoomStatus (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:339:22) Apr 19 10:47:25 volumio volumio[1080]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:333:8) Apr 19 10:47:25 volumio volumio[1080]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 19 10:47:25 volumio volumio[1080]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 19 10:47:25 volumio volumio[1080]: at Browser.emit (events.js:400:28) Apr 19 10:47:25 volumio volumio[1080]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 19 10:47:25 volumio volumio[1080]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 19 10:47:25 volumio volumio[1080]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 19 10:47:25 volumio volumio[1080]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 19 10:47:25 volumio volumio[1080]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 19 10:47:25 volumio volumio[1080]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 19 10:47:25 volumio volumio[1080]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 19 10:47:25 volumio volumio[1080]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 10:47:25 volumio ntpd[2428]: Soliciting pool server 103.199.19.135 Apr 19 10:47:25 volumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 10:46 Apr 19 10:47:25 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"