May 24 15:55:31 jwb-volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 24 15:55:31 jwb-volumio volumio[4395]: info: READ May 24 15:55:31 jwb-volumio volumio[4395]: Use XMLElement.setAttribute instead May 24 15:55:31 jwb-volumio volumio[4395]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:55:31 jwb-volumio volumio[4395]: TypeError: children[i].attr(...).value is not a function May 24 15:55:31 jwb-volumio volumio[4395]: at /volumio/app/plugins/music_service/webradio/index.js:291:47 May 24 15:55:31 jwb-volumio volumio[4395]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 24 15:55:31 jwb-volumio volumio[4395]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:55:32 jwb-volumio sudo[4769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-05-24 15:54' May 24 15:55:32 jwb-volumio sudo[4769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:33 jwb-volumio sudo[4769]: pam_unix(sudo:session): session closed for user root May 24 15:55:36 jwb-volumio volumio[4776]: info: ------------------------------------------- May 24 15:55:36 jwb-volumio volumio[4776]: info: ----- Volumio3 ---- May 24 15:55:36 jwb-volumio volumio[4776]: info: ------------------------------------------- May 24 15:55:36 jwb-volumio volumio[4776]: info: ----- System startup ---- May 24 15:55:36 jwb-volumio volumio[4776]: info: ------------------------------------------- May 24 15:55:37 jwb-volumio volumio[4776]: info: Plugin folders cleanup May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning into folder /volumio/app/plugins/ May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category audio_interface May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category miscellanea May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category music_service May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category plugins.json May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category system_controller May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category user_interface May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning into folder /data/plugins/ May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category audio_interface May 24 15:55:37 jwb-volumio volumio[4776]: info: Scanning category music_service May 24 15:55:37 jwb-volumio volumio[4776]: info: Plugin folders cleanup completed May 24 15:55:37 jwb-volumio volumio[4776]: info: ------------------------------------------- May 24 15:55:37 jwb-volumio volumio[4776]: info: ----- Core plugins startup ---- May 24 15:55:37 jwb-volumio volumio[4776]: info: ------------------------------------------- May 24 15:55:37 jwb-volumio volumio[4776]: info: Loading plugins from folder /volumio/app/plugins/ May 24 15:55:37 jwb-volumio volumio[4776]: info: Adding plugin upnp to MyMusic Plugins May 24 15:55:37 jwb-volumio volumio[4776]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 15:55:37 jwb-volumio volumio[4776]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 15:55:37 jwb-volumio volumio[4776]: info: Loading plugins from folder /data/plugins/ May 24 15:55:37 jwb-volumio volumio[4776]: info: Loading plugin "system"... May 24 15:55:37 jwb-volumio volumio[4776]: info: Loading plugin "appearance"... May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "network"... May 24 15:55:39 jwb-volumio volumio[4776]: info: Refreshing Cached IP Addresses May 24 15:55:39 jwb-volumio sudo[4798]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:55:39 jwb-volumio sudo[4798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:39 jwb-volumio sudo[4798]: pam_unix(sudo:session): session closed for user root May 24 15:55:39 jwb-volumio sudo[4800]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:55:39 jwb-volumio sudo[4800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:39 jwb-volumio sudo[4800]: pam_unix(sudo:session): session closed for user root May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "services"... May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "alsa_controller"... May 24 15:55:39 jwb-volumio sudo[4804]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 15:55:39 jwb-volumio sudo[4804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:39 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "wizard"... May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "networkfs"... May 24 15:55:39 jwb-volumio volumio[4776]: info: Starting Udev Watcher for removable devices May 24 15:55:39 jwb-volumio volumio[4776]: info: Ignoring mount for partition: boot May 24 15:55:39 jwb-volumio volumio[4776]: info: Ignoring mount for partition: root May 24 15:55:39 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "volumio_command_line_client"... May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "upnp"... May 24 15:55:39 jwb-volumio volumio[4776]: info: [1716584139479] Starting Upmpd Daemon May 24 15:55:39 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "my_music"... May 24 15:55:39 jwb-volumio volumio[4776]: info: Loading plugin "mpd"... May 24 15:55:40 jwb-volumio volumio[4776]: info: Loading plugin "upnp_browser"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "alarm-clock"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "airplay_emulation"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Starting Shairport Sync May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "last_100"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "webradio"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "i2s_dacs"... May 24 15:55:42 jwb-volumio volumio[4776]: info: I2S DAC not set, start Auto-detection May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "volumiodiscovery"... May 24 15:55:42 jwb-volumio volumio[4776]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:55:42 jwb-volumio volumio[4776]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:55:42 jwb-volumio volumio[4776]: *** WARNING *** For more information see May 24 15:55:42 jwb-volumio volumio[4776]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 15:55:42 jwb-volumio volumio[4776]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:55:42 jwb-volumio volumio[4776]: *** WARNING *** For more information see May 24 15:55:42 jwb-volumio node[4776]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:55:42 jwb-volumio node[4776]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:55:42 jwb-volumio node[4776]: *** WARNING *** For more information see May 24 15:55:42 jwb-volumio node[4776]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 24 15:55:42 jwb-volumio node[4776]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:55:42 jwb-volumio node[4776]: *** WARNING *** For more information see May 24 15:55:42 jwb-volumio volumio[4776]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 15:55:42 jwb-volumio volumio[4776]: info: Discovery: Started advertising with name: jwb-volumio May 24 15:55:42 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "outputs"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "albumart"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Plugin example_plugin is not enabled May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "inputs"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "updater_comm"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Plugin mpdemulation is not enabled May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "rest_api"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "websocket"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "snapclient"... May 24 15:55:42 jwb-volumio volumio[4776]: info: Applying required configuration parameters for plugin snapclient May 24 15:55:42 jwb-volumio volumio[4776]: info: Plugin snapserver is not enabled May 24 15:55:42 jwb-volumio volumio[4776]: info: Plugin spop is not enabled May 24 15:55:42 jwb-volumio volumio[4776]: info: Loading plugin "volspotconnect2"... May 24 15:55:43 jwb-volumio sudo[4804]: pam_unix(sudo:session): session closed for user root May 24 15:55:43 jwb-volumio volumio[4815]: Forking 3 albumart workers May 24 15:55:43 jwb-volumio volumio[4776]: info: Loading i18n strings for locale en May 24 15:55:43 jwb-volumio volumio[4776]: Updating browse sources language May 24 15:55:43 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::initPlayerControls May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: Express server listening on port 3000 May 24 15:55:44 jwb-volumio volumio[4776]: [Metrics] WebUI: 8s 668.35ms May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::resetVolumioState May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::getcurrentVolume May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:55:44 jwb-volumio volumio[4776]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 24 15:55:44 jwb-volumio volumio[4776]: info: Completed loading Core Plugins May 24 15:55:44 jwb-volumio volumio[4776]: info: Preparing to generate the ALSA configuration file May 24 15:55:44 jwb-volumio volumio[4776]: info: Cannot read play queue from file May 24 15:55:44 jwb-volumio volumio[4776]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 24 15:55:44 jwb-volumio volumio[4776]: info: The plugin snapclient has an ALSA contribution file nosound.finalizeMultiRoom.0.conf May 24 15:55:44 jwb-volumio volumio[4776]: info: Reading ALSA contributions from plugins. May 24 15:55:44 jwb-volumio volumio[4776]: verbose: New Socket.io Connection to 192.168.1.195 from 192.168.1.13 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 1 May 24 15:55:44 jwb-volumio volumio[4776]: verbose: New Socket.io Connection to 192.168.1.195 from 192.168.1.13 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 2 May 24 15:55:44 jwb-volumio volumio[4776]: info: VolumeController:: Volume=100 Mute =false May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::pushState May 24 15:55:44 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioPushState May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::updateTrackBlock May 24 15:55:44 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrackBlock May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::setRepeat null single undefined May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::pushState May 24 15:55:44 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioPushState May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::setRandom null May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreStateMachine::pushState May 24 15:55:44 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:44 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioPushState May 24 15:55:45 jwb-volumio volumio[4776]: info: VolumeController:: Volume=100 Mute =false May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreStateMachine::pushState May 24 15:55:45 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioPushState May 24 15:55:45 jwb-volumio volumio[4776]: info: Discovery: adding d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:55:45 jwb-volumio volumio[4776]: info: Discovery: Found device jwb-volumio May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioGetState May 24 15:55:45 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:45 jwb-volumio volumio[4776]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:55:45 jwb-volumio volumio[4776]: info: Asound.conf file unchanged, so no further update is needed May 24 15:55:45 jwb-volumio volumio[4776]: info: Output device has changed, restarting MPD May 24 15:55:45 jwb-volumio volumio[4776]: info: Output device has changed, restarting Shairport Sync May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:55:45 jwb-volumio volumio[4776]: info: ___________ START PLUGINS ___________ May 24 15:55:45 jwb-volumio sudo[4865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:55:45 jwb-volumio sudo[4865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:45 jwb-volumio sudo[4866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:55:45 jwb-volumio sudo[4866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:45 jwb-volumio sudo[4865]: pam_unix(sudo:session): session closed for user root May 24 15:55:45 jwb-volumio volumio[4776]: info: ControllerMpd::onStart: Initializing MPD May 24 15:55:45 jwb-volumio volumio[4776]: info: Creating MPD Configuration file May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:55:45 jwb-volumio volumio[4776]: info: [1716584145324] CoreMusicLibrary::Adding element Media Servers May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:55:45 jwb-volumio sudo[4875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:55:45 jwb-volumio sudo[4875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:45 jwb-volumio sudo[4874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:55:45 jwb-volumio sudo[4874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:45 jwb-volumio sudo[4874]: pam_unix(sudo:session): session closed for user root May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:55:45 jwb-volumio volumio[4823]: Starting albumart workers May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:55:45 jwb-volumio volumio[4822]: Starting albumart workers May 24 15:55:45 jwb-volumio volumio[4776]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:55:45 jwb-volumio volumio[4776]: info: [1716584145549] CoreMusicLibrary::Adding element Last_100 May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:55:45 jwb-volumio volumio[4776]: info: [1716584145583] CoreMusicLibrary::Adding element Webradio May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:55:45 jwb-volumio volumio[4824]: Starting albumart workers May 24 15:55:45 jwb-volumio volumio[4776]: [SpotifyConnect] Creating VLS config file May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:45 jwb-volumio volumio[4776]: [SpotifyConnect] Unimplemented debug mode!! May 24 15:55:45 jwb-volumio volumio[4776]: [SpotifyConnect] Starting metadata listener May 24 15:55:45 jwb-volumio sudo[4889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient May 24 15:55:45 jwb-volumio sudo[4889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:45 jwb-volumio volumio[4776]: info: Cannot retrieve data for calling home May 24 15:55:45 jwb-volumio sudo[4889]: pam_unix(sudo:session): session closed for user root May 24 15:55:45 jwb-volumio sudo[4892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 24 15:55:45 jwb-volumio sudo[4892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:45 jwb-volumio sudo[4892]: pam_unix(sudo:session): session closed for user root May 24 15:55:47 jwb-volumio volumio[4776]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:55:47 jwb-volumio volumio[4776]: info: Discovery: Found device jwb-volumio May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioGetState May 24 15:55:47 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:47 jwb-volumio volumio[4776]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:55:47 jwb-volumio volumio[4776]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:55:47 jwb-volumio volumio[4776]: info: Discovery: Found device jwb-volumio May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioGetState May 24 15:55:47 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:47 jwb-volumio volumio[4776]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:55:47 jwb-volumio volumio[4776]: verbose: New Socket.io Connection to 192.168.1.195 from 192.168.1.13 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 3 May 24 15:55:47 jwb-volumio volumio[4776]: [SpotifyConnect] Vollibrespot Daemon service started! May 24 15:55:47 jwb-volumio volumio[4776]: [Metrics] SpotifyConnect: 1s 537.46ms May 24 15:55:47 jwb-volumio volumio[4776]: info: [SnapClient] The following error occurred while starting snapclient: Error: Command failed: /usr/bin/sudo /bin/systemctl restart snapclient May 24 15:55:47 jwb-volumio volumio[4776]: Failed to restart snapclient.service: Unit snapclient.service not found. May 24 15:55:47 jwb-volumio volumio[4776]: error: [SnapClient] Could not start the SnapCast plugin in a fashionable manner. Error: undefined May 24 15:55:47 jwb-volumio volumio[4776]: info: MPD Permissions set May 24 15:55:47 jwb-volumio volumio[4776]: info: MPD Permissions set May 24 15:55:47 jwb-volumio volumio[4776]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:47 jwb-volumio volumio[4776]: info: Starting Shairport Sync May 24 15:55:47 jwb-volumio volumio[4776]: info: Starting Shairport Sync May 24 15:55:47 jwb-volumio volumio[4776]: info: Starting Shairport Sync May 24 15:55:47 jwb-volumio sudo[4931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:55:47 jwb-volumio sudo[4931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:47 jwb-volumio sudo[4933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:55:47 jwb-volumio sudo[4933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:47 jwb-volumio sudo[4936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:55:47 jwb-volumio sudo[4936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:47 jwb-volumio sudo[4931]: pam_unix(sudo:session): session closed for user root May 24 15:55:47 jwb-volumio sudo[4936]: pam_unix(sudo:session): session closed for user root May 24 15:55:47 jwb-volumio sudo[4933]: pam_unix(sudo:session): session closed for user root May 24 15:55:47 jwb-volumio volumio[4776]: info: Shairport-Sync Started May 24 15:55:47 jwb-volumio volumio[4776]: Error adding Membership: Error: addMembership EINVAL May 24 15:55:47 jwb-volumio volumio[4776]: info: Shairport-Sync Started May 24 15:55:47 jwb-volumio volumio[4776]: info: Shairport-Sync Started May 24 15:55:47 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioGetState May 24 15:55:47 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:48 jwb-volumio sudo[4866]: pam_unix(sudo:session): session closed for user root May 24 15:55:48 jwb-volumio sudo[4875]: pam_unix(sudo:session): session closed for user root May 24 15:55:48 jwb-volumio volumio[4776]: info: Completed starting Core Plugins May 24 15:55:48 jwb-volumio volumio[4776]: info: BOOT COMPLETED May 24 15:55:48 jwb-volumio volumio[4776]: [Metrics] CommandRouter: 12s 596.06ms May 24 15:55:48 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumiosetStartupVolume May 24 15:55:48 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:55:48 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:55:49 jwb-volumio volumio[4776]: info: CoreCommandRouter::Close All Modals sent May 24 15:55:49 jwb-volumio volumio[4776]: info: CoreCommandRouter::Close All Modals sent May 24 15:55:49 jwb-volumio volumio[4776]: info: MPD running with PID4887 May 24 15:55:49 jwb-volumio volumio[4776]: ,establishing connection May 24 15:55:49 jwb-volumio volumio[4776]: error: MPD error: The expression evaluated to a falsy value: May 24 15:55:49 jwb-volumio volumio[4776]: assert.ok(self.idling) May 24 15:55:49 jwb-volumio volumio[4776]: error: The expression evaluated to a falsy value: May 24 15:55:49 jwb-volumio volumio[4776]: assert.ok(self.idling) May 24 15:55:49 jwb-volumio volumio[4776]: error: MPD error: The expression evaluated to a falsy value: May 24 15:55:49 jwb-volumio volumio[4776]: assert.ok(self.idling) May 24 15:55:49 jwb-volumio volumio[4776]: error: The expression evaluated to a falsy value: May 24 15:55:49 jwb-volumio volumio[4776]: assert.ok(self.idling) May 24 15:55:49 jwb-volumio volumio[4776]: error: updateQueue error: null May 24 15:55:49 jwb-volumio sudo[4948]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:55:49 jwb-volumio sudo[4948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:49 jwb-volumio sudo[4948]: pam_unix(sudo:session): session closed for user root May 24 15:55:49 jwb-volumio sudo[4950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:55:49 jwb-volumio sudo[4950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:49 jwb-volumio sudo[4950]: pam_unix(sudo:session): session closed for user root May 24 15:55:49 jwb-volumio sudo[4953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 15:55:49 jwb-volumio sudo[4953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:55:49 jwb-volumio sudo[4953]: pam_unix(sudo:session): session closed for user root May 24 15:55:49 jwb-volumio volumio[4776]: info: Upmpdcli Daemon Started May 24 15:55:50 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 24 15:55:50 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 15:55:50 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 24 15:55:51 jwb-volumio volumio[4776]: verbose: New Socket.io Connection to 192.168.1.195 from 192.168.1.13 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 5 May 24 15:55:51 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioGetState May 24 15:55:51 jwb-volumio volumio[4776]: info: CorePlayQueue::getTrack 0 May 24 15:55:51 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 15:55:51 jwb-volumio volumio[4776]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:55:51 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioGetVisibleSources May 24 15:55:51 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:55:51 jwb-volumio volumio[4776]: info: Listing playlists May 24 15:55:51 jwb-volumio volumio[4776]: info: CoreCommandRouter::volumioGetQueue May 24 15:55:51 jwb-volumio volumio[4776]: info: CoreStateMachine::getQueue May 24 15:55:51 jwb-volumio volumio[4776]: info: CorePlayQueue::getQueue May 24 15:55:54 jwb-volumio volumio[4776]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 24 15:56:02 jwb-volumio volumio[4776]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 24 15:56:02 jwb-volumio volumio[4776]: info: READ May 24 15:56:02 jwb-volumio volumio[4776]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:56:02 jwb-volumio volumio[4776]: TypeError: children.length is not a function May 24 15:56:02 jwb-volumio volumio[4776]: at /volumio/app/plugins/music_service/webradio/index.js:291:85 May 24 15:56:02 jwb-volumio volumio[4776]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 24 15:56:02 jwb-volumio volumio[4776]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:56:03 jwb-volumio sudo[4969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-05-24 15:55' May 24 15:56:03 jwb-volumio sudo[4969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) -- Boot 29bfb13c224443d8bb55942fecb86902 -- Aug 30 00:00:00 jwb-volumio systemd-resolved[494]: Clock change detected. Flushing caches. Aug 30 00:00:00 jwb-volumio systemd-journald[347]: Oldest entry in /var/log/journal/4d015ee4d04746d9bca51a727eee2b27/system.journal is older than the configured file retention duration (1month), suggesting rotation. Aug 30 00:00:00 jwb-volumio systemd-journald[347]: /var/log/journal/4d015ee4d04746d9bca51a727eee2b27/system.journal: Journal header limits reached or header out-of-date, rotating. Aug 30 00:00:00 jwb-volumio systemd-timedated[30502]: Changed local time to Sat 2025-08-30 00:00:00 EST Aug 30 00:00:00 jwb-volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Aug 30 00:00:00 jwb-volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 30 00:00:00 jwb-volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Aug 30 00:00:00 jwb-volumio systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Aug 30 00:00:00 jwb-volumio systemd[1]: Starting logrotate.service - Rotate log files... Aug 30 00:00:00 jwb-volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Aug 30 00:00:00 jwb-volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Aug 30 00:00:00 jwb-volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 30 00:00:00 jwb-volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 30 00:00:01 jwb-volumio fstrim[30509]: /boot/efi: 246.4 MiB (258334720 bytes) trimmed on /dev/mmcblk1p1 Aug 30 00:00:01 jwb-volumio fstrim[30509]: /: 10.4 GiB (11149463552 bytes) trimmed on /dev/mmcblk1p2 Aug 30 00:00:01 jwb-volumio systemd[1]: fstrim.service: Deactivated successfully. Aug 30 00:00:01 jwb-volumio systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Aug 30 00:00:01 jwb-volumio systemd[1]: logrotate.service: Deactivated successfully. Aug 30 00:00:01 jwb-volumio systemd[1]: Finished logrotate.service - Rotate log files. Aug 30 19:34:00 jwb-volumio systemd-timedated[30502]: Changed local time to Sat 2025-08-30 19:34:00 EST Aug 30 19:34:00 jwb-volumio systemd-resolved[494]: Clock change detected. Flushing caches. Aug 30 19:34:17 jwb-volumio systemd-networkd-wait-online[30514]: Timeout occurred while waiting for network connectivity. Aug 30 19:34:17 jwb-volumio apt-helper[30503]: E: Sub-process /lib/systemd/systemd-networkd-wait-online returned an error code (1) Aug 30 19:34:19 jwb-volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Aug 30 19:34:19 jwb-volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Aug 30 19:34:19 jwb-volumio systemd[1]: apt-daily-upgrade.service: Consumed 1.976s CPU time. Aug 30 19:34:19 jwb-volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Aug 30 19:34:31 jwb-volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 30 19:34:48 jwb-volumio sudo[30672]: root : PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/usr/bin/tar -xvf volspotconnect2.service.tar -C / Aug 30 19:34:49 jwb-volumio sudo[30672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 19:34:49 jwb-volumio sudo[30672]: pam_unix(sudo:session): session closed for user root Aug 30 19:34:49 jwb-volumio sudo[30674]: root : PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/music_service/volspotconnect2/onstart1.sh Aug 30 19:34:49 jwb-volumio sudo[30674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 19:34:49 jwb-volumio sudo[30674]: pam_unix(sudo:session): session closed for user root Aug 30 19:34:49 jwb-volumio systemd-networkd-wait-online[30597]: Timeout occurred while waiting for network connectivity. Aug 30 19:34:49 jwb-volumio apt-helper[30594]: E: Sub-process /lib/systemd/systemd-networkd-wait-online returned an error code (1) Aug 30 19:34:51 jwb-volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Aug 30 19:34:51 jwb-volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Aug 30 19:34:51 jwb-volumio systemd[1]: apt-daily-upgrade.service: Consumed 1.963s CPU time. Aug 30 19:35:19 jwb-volumio systemd[1]: /etc/systemd/system/volspotconnect2.service:11: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 19:35:19 jwb-volumio systemd[1]: /etc/systemd/system/volspotconnect2.service:12: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 19:35:19 jwb-volumio systemd[1]: Started volspotconnect2.service - Volspotconnect2 Daemon. 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/"