-- Logs begin at Fri 2024-08-23 00:45:01 JST, end at Fri 2024-08-23 01:23:49 JST. -- Aug 23 01:22:04 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:22:08 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:22:12 volumio volumio[945]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/armhf Aug 23 01:22:13 volumio volumio[945]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/armhf Aug 23 01:22:13 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:22:13 volumio volumio[945]: info: Check plugin dependencies Aug 23 01:22:13 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 01:22:13 volumio volumio[945]: info: Checking if plugin already exists Aug 23 01:22:13 volumio volumio[945]: info: Rename folder Aug 23 01:22:13 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:22:13 volumio volumio[945]: info: Move to category Aug 23 01:22:13 volumio volumio[945]: info: Checking if install.sh is present Aug 23 01:22:13 volumio volumio[945]: info: Executing install.sh Aug 23 01:22:14 volumio sudo[7779]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty= ruser=volumio rhost= user=volumio Aug 23 01:22:16 volumio sudo[7779]: pam_unix(sudo:auth): conversation failed Aug 23 01:22:16 volumio sudo[7779]: pam_unix(sudo:auth): auth could not identify password for [volumio] Aug 23 01:22:16 volumio sudo[7779]: volumio : 1 incorrect password attempt ; TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/autostart/install.sh Aug 23 01:22:16 volumio volumio[945]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/system_controller/autostart/install.sh > /tmp/installog Aug 23 01:22:16 volumio volumio[945]: [sudo] password for volumio: Sorry, try again. Aug 23 01:22:16 volumio volumio[945]: [sudo] password for volumio: Aug 23 01:22:16 volumio volumio[945]: sudo: no password was provided Aug 23 01:22:16 volumio volumio[945]: sudo: 1 incorrect password attempt Aug 23 01:22:16 volumio volumio[945]: info: An error occurred installing the plugin. Rolling back config Aug 23 01:22:16 volumio volumio[945]: info: Plugin folders cleanup Aug 23 01:22:16 volumio volumio[945]: info: Scanning into folder /volumio/app/plugins/ Aug 23 01:22:16 volumio volumio[945]: info: Scanning category audio_interface Aug 23 01:22:16 volumio volumio[945]: info: Scanning category miscellanea Aug 23 01:22:16 volumio volumio[945]: info: Scanning category music_service Aug 23 01:22:16 volumio volumio[945]: info: Scanning category plugins.json Aug 23 01:22:16 volumio volumio[945]: info: Scanning category system_controller Aug 23 01:22:16 volumio volumio[945]: info: Scanning category user_interface Aug 23 01:22:16 volumio volumio[945]: info: Scanning into folder /data/plugins/ Aug 23 01:22:16 volumio volumio[945]: info: Scanning category audio_interface Aug 23 01:22:16 volumio volumio[945]: info: Scanning category system_controller Aug 23 01:22:16 volumio volumio[945]: info: Scanning category user_interface Aug 23 01:22:16 volumio volumio[945]: info: Plugin folders cleanup completed Aug 23 01:22:16 volumio volumio[945]: info: Error: Error Aug 23 01:22:16 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:22:16 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:22:16 volumio volumio[945]: info: Folder /data/temp removed Aug 23 01:22:22 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 23 01:22:23 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 23 01:22:23 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:22:25 volumio volumio[945]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/autostart/3.0.4/volumio/buster/armhf Aug 23 01:22:27 volumio volumio[945]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/autostart/3.0.4/volumio/buster/armhf Aug 23 01:22:27 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:22:27 volumio volumio[945]: info: Check plugin dependencies Aug 23 01:22:27 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 01:22:27 volumio volumio[945]: info: Checking if plugin already exists Aug 23 01:22:27 volumio volumio[945]: info: Rename folder Aug 23 01:22:27 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:22:27 volumio volumio[945]: info: Move to category Aug 23 01:22:27 volumio volumio[945]: info: Checking if install.sh is present Aug 23 01:22:27 volumio volumio[945]: info: Executing install.sh Aug 23 01:22:27 volumio sudo[7843]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty= ruser=volumio rhost= user=volumio Aug 23 01:22:30 volumio sudo[7843]: pam_unix(sudo:auth): conversation failed Aug 23 01:22:30 volumio sudo[7843]: pam_unix(sudo:auth): auth could not identify password for [volumio] Aug 23 01:22:30 volumio sudo[7843]: volumio : 1 incorrect password attempt ; TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/autostart/install.sh Aug 23 01:22:30 volumio volumio[945]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/system_controller/autostart/install.sh > /tmp/installog Aug 23 01:22:30 volumio volumio[945]: [sudo] password for volumio: Sorry, try again. Aug 23 01:22:30 volumio volumio[945]: [sudo] password for volumio: Aug 23 01:22:30 volumio volumio[945]: sudo: no password was provided Aug 23 01:22:30 volumio volumio[945]: sudo: 1 incorrect password attempt Aug 23 01:22:30 volumio volumio[945]: info: An error occurred installing the plugin. Rolling back config Aug 23 01:22:30 volumio volumio[945]: info: Plugin folders cleanup Aug 23 01:22:30 volumio volumio[945]: info: Scanning into folder /volumio/app/plugins/ Aug 23 01:22:30 volumio volumio[945]: info: Scanning category audio_interface Aug 23 01:22:30 volumio volumio[945]: info: Scanning category miscellanea Aug 23 01:22:30 volumio volumio[945]: info: Scanning category music_service Aug 23 01:22:30 volumio volumio[945]: info: Scanning category plugins.json Aug 23 01:22:30 volumio volumio[945]: info: Scanning category system_controller Aug 23 01:22:30 volumio volumio[945]: info: Scanning category user_interface Aug 23 01:22:30 volumio volumio[945]: info: Scanning into folder /data/plugins/ Aug 23 01:22:30 volumio volumio[945]: info: Scanning category audio_interface Aug 23 01:22:30 volumio volumio[945]: info: Scanning category system_controller Aug 23 01:22:30 volumio volumio[945]: info: Scanning category user_interface Aug 23 01:22:30 volumio volumio[945]: info: Plugin folders cleanup completed Aug 23 01:22:30 volumio volumio[945]: info: Error: Error Aug 23 01:22:30 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:22:30 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:22:30 volumio volumio[945]: info: Folder /data/temp removed Aug 23 01:22:34 volumio volumio[945]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf Aug 23 01:22:36 volumio volumio[945]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf Aug 23 01:22:36 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:22:36 volumio volumio[945]: info: Check plugin dependencies Aug 23 01:22:36 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 01:22:36 volumio volumio[945]: info: Checking if plugin already exists Aug 23 01:22:36 volumio volumio[945]: info: Rename folder Aug 23 01:22:36 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:22:36 volumio volumio[945]: info: Move to category Aug 23 01:22:37 volumio volumio[945]: info: Checking if install.sh is present Aug 23 01:22:37 volumio volumio[945]: info: Executing install.sh Aug 23 01:22:37 volumio sudo[7877]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty= ruser=volumio rhost= user=volumio Aug 23 01:22:38 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:22:39 volumio sudo[7877]: pam_unix(sudo:auth): conversation failed Aug 23 01:22:39 volumio sudo[7877]: pam_unix(sudo:auth): auth could not identify password for [volumio] Aug 23 01:22:39 volumio sudo[7877]: volumio : 1 incorrect password attempt ; TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/backup_restore/install.sh Aug 23 01:22:39 volumio volumio[945]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/system_controller/backup_restore/install.sh > /tmp/installog Aug 23 01:22:39 volumio volumio[945]: [sudo] password for volumio: Sorry, try again. Aug 23 01:22:39 volumio volumio[945]: [sudo] password for volumio: Aug 23 01:22:39 volumio volumio[945]: sudo: no password was provided Aug 23 01:22:39 volumio volumio[945]: sudo: 1 incorrect password attempt Aug 23 01:22:39 volumio volumio[945]: info: An error occurred installing the plugin. Rolling back config Aug 23 01:22:39 volumio volumio[945]: info: Plugin folders cleanup Aug 23 01:22:39 volumio volumio[945]: info: Scanning into folder /volumio/app/plugins/ Aug 23 01:22:39 volumio volumio[945]: info: Scanning category audio_interface Aug 23 01:22:39 volumio volumio[945]: info: Scanning category miscellanea Aug 23 01:22:39 volumio volumio[945]: info: Scanning category music_service Aug 23 01:22:39 volumio volumio[945]: info: Scanning category plugins.json Aug 23 01:22:39 volumio volumio[945]: info: Scanning category system_controller Aug 23 01:22:39 volumio volumio[945]: info: Scanning category user_interface Aug 23 01:22:39 volumio volumio[945]: info: Scanning into folder /data/plugins/ Aug 23 01:22:39 volumio volumio[945]: info: Scanning category audio_interface Aug 23 01:22:39 volumio volumio[945]: info: Scanning category system_controller Aug 23 01:22:39 volumio volumio[945]: info: Scanning category user_interface Aug 23 01:22:39 volumio volumio[945]: info: Plugin folders cleanup completed Aug 23 01:22:39 volumio volumio[945]: info: Error: Error Aug 23 01:22:39 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:22:39 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:22:39 volumio volumio[945]: info: Folder /data/temp removed Aug 23 01:22:53 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:23:06 volumio sudo[8011]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/passwd volumio Aug 23 01:23:06 volumio sudo[8011]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Aug 23 01:23:08 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:23:08 volumio ntpd[794]: 129.250.35.251 local addr 192.168.1.120 -> Aug 23 01:23:21 volumio passwd[8013]: pam_unix(passwd:chauthtok): password changed for volumio Aug 23 01:23:21 volumio sudo[8011]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:23 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:23:25 volumio volumio[945]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf Aug 23 01:23:26 volumio volumio[945]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf Aug 23 01:23:26 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:23:26 volumio volumio[945]: info: Check plugin dependencies Aug 23 01:23:26 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 01:23:26 volumio volumio[945]: info: Checking if plugin already exists Aug 23 01:23:26 volumio volumio[945]: info: Rename folder Aug 23 01:23:26 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:23:26 volumio volumio[945]: info: Move to category Aug 23 01:23:27 volumio volumio[945]: info: Checking if install.sh is present Aug 23 01:23:27 volumio volumio[945]: info: Executing install.sh Aug 23 01:23:27 volumio sudo[8067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/backup_restore/install.sh Aug 23 01:23:27 volumio sudo[8067]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:27 volumio sudo[8067]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:27 volumio volumio[945]: info: Install script completed Aug 23 01:23:27 volumio volumio[945]: info: Adding reference to registry Aug 23 01:23:27 volumio volumio[945]: info: Done installing plugin. Aug 23 01:23:27 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 01:23:27 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Installing backup restore Dependencies Aug 23 01:23:27 volumio volumio[945]: Plugin install end detected on script Aug 23 01:23:27 volumio volumio[945]: info: Folder /tmp/plugins removed Aug 23 01:23:27 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Aug 23 01:23:27 volumio volumio[945]: info: Folder /data/temp removed Aug 23 01:23:29 volumio volumio[945]: info: Enabling plugin backup_restore Aug 23 01:23:29 volumio volumio[945]: info: Loading plugin "backup_restore"... Aug 23 01:23:29 volumio volumio[945]: info: PLUGIN START: backup_restore Aug 23 01:23:29 volumio volumio[945]: info: Done. Aug 23 01:23:29 volumio volumio[945]: info: Enabling plugin backup_restore Aug 23 01:23:29 volumio volumio[945]: info: Loading plugin "backup_restore"... Aug 23 01:23:29 volumio volumio[945]: info: Applying required configuration parameters for plugin backup_restore Aug 23 01:23:29 volumio volumio[945]: info: PLUGIN START: backup_restore Aug 23 01:23:29 volumio volumio[945]: info: Done. Aug 23 01:23:38 volumio dnsmasq-dhcp[936]: no address range available for DHCP request via wlan0 Aug 23 01:23:42 volumio volumio[945]: info: Enabling plugin lastfm Aug 23 01:23:42 volumio volumio[945]: info: Loading plugin "lastfm"... Aug 23 01:23:43 volumio volumio[945]: info: PLUGIN START: lastfm Aug 23 01:23:43 volumio volumio[945]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 01:23:43 volumio volumio[945]: info: [1724343823457] CoreMusicLibrary::Adding element LastFM Aug 23 01:23:43 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 01:23:43 volumio volumio[945]: Cannot find translation for source LastFM Aug 23 01:23:43 volumio volumio[945]: info: [LastFM] scrobbler initiated! Aug 23 01:23:43 volumio volumio[945]: info: [LastFM] extended logging: false Aug 23 01:23:43 volumio volumio[945]: info: [LastFM] try scrobble stream/radio plays: true Aug 23 01:23:43 volumio volumio[945]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Aug 23 01:23:43 volumio volumio[945]: info: [LastFM] Left init routine Aug 23 01:23:43 volumio volumio[945]: info: [LastFM] Socket already connected: false Aug 23 01:23:43 volumio volumio[945]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Aug 23 01:23:43 volumio volumio[945]: info: Done. Aug 23 01:23:43 volumio volumio[945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 23 01:23:43 volumio volumio[945]: info: Enabling plugin now_playing Aug 23 01:23:43 volumio volumio[945]: info: Loading plugin "now_playing"... Aug 23 01:23:43 volumio volumio[945]: error: !!!! WARNING !!!! Aug 23 01:23:43 volumio volumio[945]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Aug 23 01:23:43 volumio volumio[945]: Require stack: Aug 23 01:23:43 volumio volumio[945]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 23 01:23:43 volumio volumio[945]: - /volumio/app/pluginmanager.js Aug 23 01:23:43 volumio volumio[945]: - /volumio/app/index.js Aug 23 01:23:43 volumio volumio[945]: - /volumio/index.js Aug 23 01:23:43 volumio volumio[945]: error: Stack trace: Error: Cannot find module 'geo-tz' Aug 23 01:23:43 volumio volumio[945]: Require stack: Aug 23 01:23:43 volumio volumio[945]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 23 01:23:43 volumio volumio[945]: - /volumio/app/pluginmanager.js Aug 23 01:23:43 volumio volumio[945]: - /volumio/app/index.js Aug 23 01:23:43 volumio volumio[945]: - /volumio/index.js Aug 23 01:23:43 volumio volumio[945]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Aug 23 01:23:43 volumio volumio[945]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Aug 23 01:23:43 volumio volumio[945]: at Module.require (internal/modules/cjs/loader.js:952:19) Aug 23 01:23:43 volumio volumio[945]: at require (internal/modules/cjs/helpers.js:88:18) Aug 23 01:23:43 volumio volumio[945]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Aug 23 01:23:43 volumio volumio[945]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Aug 23 01:23:43 volumio volumio[945]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Aug 23 01:23:43 volumio volumio[945]: at Module.load (internal/modules/cjs/loader.js:928:32) Aug 23 01:23:43 volumio volumio[945]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Aug 23 01:23:43 volumio volumio[945]: at Module.require (internal/modules/cjs/loader.js:952:19) Aug 23 01:23:43 volumio volumio[945]: at require (internal/modules/cjs/helpers.js:88:18) Aug 23 01:23:43 volumio volumio[945]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 23 01:23:43 volumio volumio[945]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 23 01:23:43 volumio volumio[945]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 23 01:23:43 volumio volumio[945]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 23 01:23:43 volumio volumio[945]: error: !!!! WARNING !!!! Aug 23 01:23:43 volumio volumio[945]: info: Done. Aug 23 01:23:45 volumio volumio[945]: info: Enabling plugin peppyspectrum Aug 23 01:23:45 volumio volumio[945]: info: Loading plugin "peppyspectrum"... Aug 23 01:23:45 volumio volumio[945]: info: Preparing to generate the ALSA configuration file Aug 23 01:23:45 volumio volumio[945]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 23 01:23:45 volumio volumio[945]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 23 01:23:45 volumio volumio[945]: info: Reading ALSA contributions from plugins. Aug 23 01:23:45 volumio volumio[945]: info: Asound.conf file unchanged, so no further update is needed Aug 23 01:23:45 volumio volumio[945]: info: Output device has changed, restarting MPD Aug 23 01:23:45 volumio sudo[8110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 01:23:45 volumio sudo[8110]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:45 volumio volumio[945]: info: Output device has changed, restarting Shairport Sync Aug 23 01:23:45 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:45 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 01:23:45 volumio sudo[8110]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:45 volumio sudo[8112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 01:23:45 volumio sudo[8112]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:46 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 01:23:46 volumio volumio[945]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 01:23:46 volumio volumio[945]: info: PLUGIN START: peppyspectrum Aug 23 01:23:46 volumio systemd[1]: mpd.service: Succeeded. Aug 23 01:23:46 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 23 01:23:46 volumio volumio[945]: info: Preparing to generate the ALSA configuration file Aug 23 01:23:46 volumio systemd[1]: Starting Music Player Daemon... Aug 23 01:23:46 volumio volumio[945]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Aug 23 01:23:46 volumio volumio[945]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 23 01:23:46 volumio volumio[945]: info: MPD Permissions set Aug 23 01:23:46 volumio volumio[945]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 23 01:23:46 volumio volumio[945]: info: Reading ALSA contributions from plugins. Aug 23 01:23:46 volumio volumio[945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio sudo[8117]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 01:23:46 volumio sudo[8117]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:46 volumio volumio[945]: info: Starting Shairport Sync Aug 23 01:23:46 volumio sudo[8117]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:46 volumio sudo[8128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 01:23:46 volumio sudo[8128]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:46 volumio volumio[945]: info: Asound.conf file unchanged, so no further update is needed Aug 23 01:23:46 volumio volumio[945]: info: Output device has changed, restarting MPD Aug 23 01:23:46 volumio sudo[8131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 01:23:46 volumio sudo[8131]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:46 volumio sudo[8131]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 01:23:46 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 01:23:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 01:23:46 volumio volumio[945]: info: Output device has changed, restarting Shairport Sync Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 01:23:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 01:23:46 volumio sudo[8128]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:46 volumio sudo[8135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 01:23:46 volumio sudo[8135]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:46 volumio volumio[945]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 01:23:46 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 23 01:23:46 volumio systemd[1]: mpd.service: Succeeded. Aug 23 01:23:46 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 01:23:46 volumio systemd[1]: Starting Music Player Daemon... Aug 23 01:23:46 volumio volumio[945]: info: MPD Permissions set Aug 23 01:23:46 volumio volumio[945]: info: Shairport-Sync Started Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 01:23:46 volumio volumio[945]: info: Starting Shairport Sync Aug 23 01:23:46 volumio sudo[8151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 01:23:46 volumio sudo[8151]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:46 volumio sudo[8143]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 01:23:46 volumio sudo[8143]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 01:23:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 01:23:46 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 01:23:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 01:23:46 volumio sudo[8143]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 01:23:46 volumio sudo[8151]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:46 volumio volumio[945]: info: Shairport-Sync Started Aug 23 01:23:46 volumio volumio[945]: error: Upnp client error: Error: This socket has been ended by the other party Aug 23 01:23:46 volumio volumio[945]: info: Enabling plugin randomizer Aug 23 01:23:46 volumio volumio[945]: info: Loading plugin "randomizer"... Aug 23 01:23:47 volumio volumio[945]: info: PLUGIN START: randomizer Aug 23 01:23:47 volumio volumio[945]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 01:23:47 volumio volumio[945]: info: [1724343827409] CoreMusicLibrary::Adding element Randomizer Aug 23 01:23:47 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 01:23:47 volumio volumio[945]: Cannot find translation for source LastFM Aug 23 01:23:47 volumio volumio[945]: Cannot find translation for source Randomizer Aug 23 01:23:47 volumio volumio[945]: info: Error: TypeError: Cannot read property 'broadcastMessage' of null Aug 23 01:23:47 volumio volumio[945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 23 01:23:48 volumio mpd[8156]: Aug 23 01:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 01:23:48 volumio systemd[1]: Started Music Player Daemon. Aug 23 01:23:48 volumio sudo[8112]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:48 volumio sudo[8135]: pam_unix(sudo:session): session closed for user root Aug 23 01:23:48 volumio volumio[945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 01:23:48 volumio volumio[945]: TypeError: Cannot read property 'printToastMessage' of null Aug 23 01:23:48 volumio volumio[945]: at /volumio/app/index.js:1201:32 Aug 23 01:23:48 volumio volumio[945]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 23 01:23:48 volumio volumio[945]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 23 01:23:48 volumio volumio[945]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1199:13) Aug 23 01:23:48 volumio volumio[945]: at /volumio/app/plugins/music_service/mpd/index.js:797:30 Aug 23 01:23:48 volumio volumio[945]: at /volumio/app/plugins/music_service/mpd/index.js:941:9 Aug 23 01:23:48 volumio volumio[945]: at ChildProcess.exithandler (child_process.js:299:7) Aug 23 01:23:48 volumio volumio[945]: at ChildProcess.emit (events.js:315:20) Aug 23 01:23:48 volumio volumio[945]: at maybeClose (internal/child_process.js:1048:16) Aug 23 01:23:48 volumio volumio[945]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Aug 23 01:23:48 volumio volumio[945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 01:23:49 volumio sudo[8171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 01:22 Aug 23 01:23:49 volumio sudo[8171]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"