May 24 15:58:24 jwb-volumio volumio[5372]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 24 15:58:24 jwb-volumio volumio[5372]: info: READ May 24 15:58:25 jwb-volumio volumio[5372]: Use XMLElement.setAttribute instead May 24 15:58:25 jwb-volumio volumio[5372]: info: JWB LOGGER LOG: checking web radio category 0 of 313 - name: undefined May 24 15:58:25 jwb-volumio volumio[5372]: Use XMLElement.setAttribute instead May 24 15:58:25 jwb-volumio volumio[5372]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:58:25 jwb-volumio volumio[5372]: TypeError: children[i].attr(...).value is not a function May 24 15:58:25 jwb-volumio volumio[5372]: at /volumio/app/plugins/music_service/webradio/index.js:292:47 May 24 15:58:25 jwb-volumio volumio[5372]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 24 15:58:25 jwb-volumio volumio[5372]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:58:26 jwb-volumio sudo[5567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-05-24 15:57' May 24 15:58:26 jwb-volumio sudo[5567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:26 jwb-volumio sudo[5567]: pam_unix(sudo:session): session closed for user root May 24 15:58:29 jwb-volumio volumio[5571]: info: ------------------------------------------- May 24 15:58:29 jwb-volumio volumio[5571]: info: ----- Volumio3 ---- May 24 15:58:29 jwb-volumio volumio[5571]: info: ------------------------------------------- May 24 15:58:29 jwb-volumio volumio[5571]: info: ----- System startup ---- May 24 15:58:29 jwb-volumio volumio[5571]: info: ------------------------------------------- May 24 15:58:30 jwb-volumio volumio[5571]: info: Plugin folders cleanup May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning into folder /volumio/app/plugins/ May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category audio_interface May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category miscellanea May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category music_service May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category plugins.json May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category system_controller May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category user_interface May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning into folder /data/plugins/ May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category audio_interface May 24 15:58:30 jwb-volumio volumio[5571]: info: Scanning category music_service May 24 15:58:30 jwb-volumio volumio[5571]: info: Plugin folders cleanup completed May 24 15:58:30 jwb-volumio volumio[5571]: info: ------------------------------------------- May 24 15:58:30 jwb-volumio volumio[5571]: info: ----- Core plugins startup ---- May 24 15:58:30 jwb-volumio volumio[5571]: info: ------------------------------------------- May 24 15:58:30 jwb-volumio volumio[5571]: info: Loading plugins from folder /volumio/app/plugins/ May 24 15:58:30 jwb-volumio volumio[5571]: info: Adding plugin upnp to MyMusic Plugins May 24 15:58:30 jwb-volumio volumio[5571]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 15:58:30 jwb-volumio volumio[5571]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 15:58:30 jwb-volumio volumio[5571]: info: Loading plugins from folder /data/plugins/ May 24 15:58:30 jwb-volumio volumio[5571]: info: Loading plugin "system"... May 24 15:58:30 jwb-volumio volumio[5571]: info: Loading plugin "appearance"... May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "network"... May 24 15:58:32 jwb-volumio volumio[5571]: info: Refreshing Cached IP Addresses May 24 15:58:32 jwb-volumio sudo[5592]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:58:32 jwb-volumio sudo[5592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:32 jwb-volumio sudo[5592]: pam_unix(sudo:session): session closed for user root May 24 15:58:32 jwb-volumio sudo[5594]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:58:32 jwb-volumio sudo[5594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:32 jwb-volumio sudo[5594]: pam_unix(sudo:session): session closed for user root May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "services"... May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "alsa_controller"... May 24 15:58:32 jwb-volumio sudo[5598]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 15:58:32 jwb-volumio sudo[5598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:32 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "wizard"... May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "networkfs"... May 24 15:58:32 jwb-volumio volumio[5571]: info: Starting Udev Watcher for removable devices May 24 15:58:32 jwb-volumio volumio[5571]: info: Ignoring mount for partition: boot May 24 15:58:32 jwb-volumio volumio[5571]: info: Ignoring mount for partition: root May 24 15:58:32 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "volumio_command_line_client"... May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "upnp"... May 24 15:58:32 jwb-volumio volumio[5571]: info: [1716584312709] Starting Upmpd Daemon May 24 15:58:32 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "my_music"... May 24 15:58:32 jwb-volumio volumio[5571]: info: Loading plugin "mpd"... May 24 15:58:33 jwb-volumio volumio[5571]: info: Loading plugin "upnp_browser"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "alarm-clock"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "airplay_emulation"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Starting Shairport Sync May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "last_100"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "webradio"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "i2s_dacs"... May 24 15:58:35 jwb-volumio volumio[5571]: info: I2S DAC not set, start Auto-detection May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "volumiodiscovery"... May 24 15:58:35 jwb-volumio volumio[5571]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:58:35 jwb-volumio volumio[5571]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:58:35 jwb-volumio volumio[5571]: *** WARNING *** For more information see May 24 15:58:35 jwb-volumio volumio[5571]: *** 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:58:35 jwb-volumio node[5571]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:58:35 jwb-volumio volumio[5571]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:58:35 jwb-volumio volumio[5571]: *** WARNING *** For more information see May 24 15:58:35 jwb-volumio node[5571]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:58:35 jwb-volumio node[5571]: *** WARNING *** For more information see May 24 15:58:35 jwb-volumio node[5571]: *** 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:58:35 jwb-volumio node[5571]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:58:35 jwb-volumio node[5571]: *** WARNING *** For more information see May 24 15:58:35 jwb-volumio volumio[5571]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 15:58:35 jwb-volumio volumio[5571]: info: Discovery: Started advertising with name: jwb-volumio May 24 15:58:35 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "outputs"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "albumart"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Plugin example_plugin is not enabled May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "inputs"... May 24 15:58:35 jwb-volumio volumio[5571]: info: Loading plugin "updater_comm"... May 24 15:58:36 jwb-volumio volumio[5571]: info: Plugin mpdemulation is not enabled May 24 15:58:36 jwb-volumio volumio[5571]: info: Loading plugin "rest_api"... May 24 15:58:36 jwb-volumio volumio[5571]: info: Loading plugin "websocket"... May 24 15:58:36 jwb-volumio volumio[5571]: info: Loading plugin "snapclient"... May 24 15:58:36 jwb-volumio volumio[5571]: info: Applying required configuration parameters for plugin snapclient May 24 15:58:36 jwb-volumio volumio[5571]: info: Plugin snapserver is not enabled May 24 15:58:36 jwb-volumio volumio[5571]: info: Plugin spop is not enabled May 24 15:58:36 jwb-volumio volumio[5571]: info: Loading plugin "volspotconnect2"... May 24 15:58:36 jwb-volumio sudo[5598]: pam_unix(sudo:session): session closed for user root May 24 15:58:36 jwb-volumio volumio[5609]: Forking 3 albumart workers May 24 15:58:36 jwb-volumio volumio[5571]: info: Loading i18n strings for locale en May 24 15:58:36 jwb-volumio volumio[5571]: Updating browse sources language May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::initPlayerControls May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: Express server listening on port 3000 May 24 15:58:37 jwb-volumio volumio[5571]: [Metrics] WebUI: 8s 590.03ms May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreStateMachine::resetVolumioState May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreStateMachine::getcurrentVolume May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:58:37 jwb-volumio volumio[5571]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 24 15:58:37 jwb-volumio volumio[5571]: info: Completed loading Core Plugins May 24 15:58:37 jwb-volumio volumio[5571]: info: Preparing to generate the ALSA configuration file May 24 15:58:37 jwb-volumio volumio[5571]: info: Cannot read play queue from file May 24 15:58:37 jwb-volumio volumio[5571]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 24 15:58:37 jwb-volumio volumio[5571]: info: The plugin snapclient has an ALSA contribution file nosound.finalizeMultiRoom.0.conf May 24 15:58:37 jwb-volumio volumio[5571]: info: Reading ALSA contributions from plugins. May 24 15:58:37 jwb-volumio volumio[5571]: 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:58:37 jwb-volumio volumio[5571]: 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:58:37 jwb-volumio volumio[5571]: info: VolumeController:: Volume=100 Mute =false May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreStateMachine::pushState May 24 15:58:37 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:58:37 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioPushState May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreStateMachine::updateTrackBlock May 24 15:58:38 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrackBlock May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreStateMachine::setRepeat null single undefined May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreStateMachine::pushState May 24 15:58:38 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioPushState May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreStateMachine::setRandom null May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreStateMachine::pushState May 24 15:58:38 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioPushState May 24 15:58:38 jwb-volumio volumio[5571]: info: VolumeController:: Volume=100 Mute =false May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreStateMachine::pushState May 24 15:58:38 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioPushState May 24 15:58:38 jwb-volumio volumio[5571]: info: Discovery: adding d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:58:38 jwb-volumio volumio[5571]: info: Discovery: Found device jwb-volumio May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioGetState May 24 15:58:38 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:38 jwb-volumio volumio[5571]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:58:38 jwb-volumio volumio[5571]: info: Asound.conf file unchanged, so no further update is needed May 24 15:58:38 jwb-volumio volumio[5571]: info: Output device has changed, restarting MPD May 24 15:58:38 jwb-volumio volumio[5571]: info: Output device has changed, restarting Shairport Sync May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:58:38 jwb-volumio sudo[5658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:58:38 jwb-volumio volumio[5571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:58:38 jwb-volumio volumio[5571]: info: ___________ START PLUGINS ___________ May 24 15:58:38 jwb-volumio sudo[5658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:38 jwb-volumio sudo[5660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:58:38 jwb-volumio sudo[5660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:38 jwb-volumio sudo[5658]: pam_unix(sudo:session): session closed for user root May 24 15:58:38 jwb-volumio volumio[5571]: info: ControllerMpd::onStart: Initializing MPD May 24 15:58:38 jwb-volumio volumio[5571]: info: Creating MPD Configuration file May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:58:38 jwb-volumio volumio[5571]: info: [1716584318476] CoreMusicLibrary::Adding element Media Servers May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:58:38 jwb-volumio sudo[5667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:58:38 jwb-volumio sudo[5667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:38 jwb-volumio sudo[5669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:58:38 jwb-volumio sudo[5669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:38 jwb-volumio sudo[5667]: pam_unix(sudo:session): session closed for user root May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:58:38 jwb-volumio volumio[5616]: Starting albumart workers May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:58:38 jwb-volumio volumio[5571]: info: [1716584318765] CoreMusicLibrary::Adding element Last_100 May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:58:38 jwb-volumio volumio[5617]: Starting albumart workers May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:58:38 jwb-volumio volumio[5571]: info: [1716584318787] CoreMusicLibrary::Adding element Webradio May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:58:38 jwb-volumio volumio[5618]: Starting albumart workers May 24 15:58:38 jwb-volumio volumio[5571]: [SpotifyConnect] Creating VLS config file May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:38 jwb-volumio volumio[5571]: [SpotifyConnect] Unimplemented debug mode!! May 24 15:58:38 jwb-volumio volumio[5571]: [SpotifyConnect] Starting metadata listener May 24 15:58:38 jwb-volumio sudo[5681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient May 24 15:58:38 jwb-volumio sudo[5681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:38 jwb-volumio volumio[5571]: info: Cannot retrieve data for calling home May 24 15:58:38 jwb-volumio sudo[5681]: pam_unix(sudo:session): session closed for user root May 24 15:58:39 jwb-volumio sudo[5684]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 24 15:58:39 jwb-volumio sudo[5684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:39 jwb-volumio sudo[5684]: pam_unix(sudo:session): session closed for user root May 24 15:58:40 jwb-volumio volumio[5571]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:58:40 jwb-volumio volumio[5571]: info: Discovery: Found device jwb-volumio May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioGetState May 24 15:58:40 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:40 jwb-volumio volumio[5571]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:58:40 jwb-volumio volumio[5571]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:58:40 jwb-volumio volumio[5571]: info: Discovery: Found device jwb-volumio May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioGetState May 24 15:58:40 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:40 jwb-volumio volumio[5571]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:58:40 jwb-volumio volumio[5571]: 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:58:40 jwb-volumio volumio[5571]: [SpotifyConnect] Vollibrespot Daemon service started! May 24 15:58:40 jwb-volumio volumio[5571]: [Metrics] SpotifyConnect: 1s 528.98ms May 24 15:58:40 jwb-volumio volumio[5571]: info: [SnapClient] The following error occurred while starting snapclient: Error: Command failed: /usr/bin/sudo /bin/systemctl restart snapclient May 24 15:58:40 jwb-volumio volumio[5571]: Failed to restart snapclient.service: Unit snapclient.service not found. May 24 15:58:40 jwb-volumio volumio[5571]: error: [SnapClient] Could not start the SnapCast plugin in a fashionable manner. Error: undefined May 24 15:58:40 jwb-volumio volumio[5571]: info: MPD Permissions set May 24 15:58:40 jwb-volumio volumio[5571]: info: MPD Permissions set May 24 15:58:40 jwb-volumio volumio[5571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:58:40 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:40 jwb-volumio volumio[5571]: info: Starting Shairport Sync May 24 15:58:40 jwb-volumio volumio[5571]: info: Starting Shairport Sync May 24 15:58:40 jwb-volumio volumio[5571]: info: Starting Shairport Sync May 24 15:58:40 jwb-volumio sudo[5723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:58:40 jwb-volumio sudo[5723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:40 jwb-volumio sudo[5725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:58:40 jwb-volumio sudo[5725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:40 jwb-volumio sudo[5728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:58:40 jwb-volumio sudo[5728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:40 jwb-volumio sudo[5723]: pam_unix(sudo:session): session closed for user root May 24 15:58:41 jwb-volumio sudo[5728]: pam_unix(sudo:session): session closed for user root May 24 15:58:41 jwb-volumio sudo[5725]: pam_unix(sudo:session): session closed for user root May 24 15:58:41 jwb-volumio volumio[5571]: info: Shairport-Sync Started May 24 15:58:41 jwb-volumio volumio[5571]: Error adding Membership: Error: addMembership EINVAL May 24 15:58:41 jwb-volumio volumio[5571]: info: Shairport-Sync Started May 24 15:58:41 jwb-volumio volumio[5571]: info: Shairport-Sync Started May 24 15:58:41 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioGetState May 24 15:58:41 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:42 jwb-volumio sudo[5660]: pam_unix(sudo:session): session closed for user root May 24 15:58:42 jwb-volumio sudo[5669]: pam_unix(sudo:session): session closed for user root May 24 15:58:42 jwb-volumio volumio[5571]: info: Completed starting Core Plugins May 24 15:58:42 jwb-volumio volumio[5571]: info: BOOT COMPLETED May 24 15:58:42 jwb-volumio volumio[5571]: [Metrics] CommandRouter: 12s 552.93ms May 24 15:58:42 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumiosetStartupVolume May 24 15:58:42 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:58:42 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:58:42 jwb-volumio volumio[5571]: info: CoreCommandRouter::Close All Modals sent May 24 15:58:42 jwb-volumio volumio[5571]: info: CoreCommandRouter::Close All Modals sent May 24 15:58:42 jwb-volumio volumio[5571]: info: MPD running with PID5676 May 24 15:58:42 jwb-volumio volumio[5571]: ,establishing connection May 24 15:58:42 jwb-volumio volumio[5571]: error: MPD error: The expression evaluated to a falsy value: May 24 15:58:42 jwb-volumio volumio[5571]: assert.ok(self.idling) May 24 15:58:42 jwb-volumio volumio[5571]: error: The expression evaluated to a falsy value: May 24 15:58:42 jwb-volumio volumio[5571]: assert.ok(self.idling) May 24 15:58:42 jwb-volumio volumio[5571]: error: MPD error: The expression evaluated to a falsy value: May 24 15:58:42 jwb-volumio volumio[5571]: assert.ok(self.idling) May 24 15:58:42 jwb-volumio volumio[5571]: error: The expression evaluated to a falsy value: May 24 15:58:42 jwb-volumio volumio[5571]: assert.ok(self.idling) May 24 15:58:42 jwb-volumio volumio[5571]: error: updateQueue error: null May 24 15:58:42 jwb-volumio sudo[5740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:58:42 jwb-volumio sudo[5740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:42 jwb-volumio sudo[5740]: pam_unix(sudo:session): session closed for user root May 24 15:58:42 jwb-volumio sudo[5742]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:58:42 jwb-volumio sudo[5742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:42 jwb-volumio sudo[5742]: pam_unix(sudo:session): session closed for user root May 24 15:58:42 jwb-volumio sudo[5746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 15:58:42 jwb-volumio sudo[5746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:42 jwb-volumio sudo[5746]: pam_unix(sudo:session): session closed for user root May 24 15:58:42 jwb-volumio volumio[5571]: info: Upmpdcli Daemon Started May 24 15:58:43 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 24 15:58:43 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 15:58:43 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 24 15:58:44 jwb-volumio volumio[5571]: 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:58:44 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioGetState May 24 15:58:44 jwb-volumio volumio[5571]: info: CorePlayQueue::getTrack 0 May 24 15:58:45 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 15:58:45 jwb-volumio volumio[5571]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:58:45 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioGetVisibleSources May 24 15:58:45 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:58:45 jwb-volumio volumio[5571]: info: Listing playlists May 24 15:58:45 jwb-volumio volumio[5571]: info: CoreCommandRouter::volumioGetQueue May 24 15:58:45 jwb-volumio volumio[5571]: info: CoreStateMachine::getQueue May 24 15:58:45 jwb-volumio volumio[5571]: info: CorePlayQueue::getQueue May 24 15:58:47 jwb-volumio volumio[5571]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 24 15:58:49 jwb-volumio volumio[5571]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 24 15:58:49 jwb-volumio volumio[5571]: info: READ May 24 15:58:49 jwb-volumio volumio[5571]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:58:49 jwb-volumio volumio[5571]: TypeError: Converting circular structure to JSON May 24 15:58:49 jwb-volumio volumio[5571]: --> starting at object with constructor '_exports__xmlDoc' May 24 15:58:49 jwb-volumio volumio[5571]: --- property 'doc' closes the circle May 24 15:58:49 jwb-volumio volumio[5571]: at JSON.stringify () May 24 15:58:49 jwb-volumio volumio[5571]: at /volumio/app/plugins/music_service/webradio/index.js:291:117 May 24 15:58:49 jwb-volumio volumio[5571]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 24 15:58:49 jwb-volumio volumio[5571]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:58:50 jwb-volumio sudo[5761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-05-24 15:57' May 24 15:58:50 jwb-volumio sudo[5761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:50 jwb-volumio sudo[5761]: pam_unix(sudo:session): session closed for user root May 24 15:58:54 jwb-volumio volumio[5768]: info: ------------------------------------------- May 24 15:58:54 jwb-volumio volumio[5768]: info: ----- Volumio3 ---- May 24 15:58:54 jwb-volumio volumio[5768]: info: ------------------------------------------- May 24 15:58:54 jwb-volumio volumio[5768]: info: ----- System startup ---- May 24 15:58:54 jwb-volumio volumio[5768]: info: ------------------------------------------- May 24 15:58:56 jwb-volumio volumio[5768]: info: Plugin folders cleanup May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning into folder /volumio/app/plugins/ May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category audio_interface May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category miscellanea May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category music_service May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category plugins.json May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category system_controller May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category user_interface May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning into folder /data/plugins/ May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category audio_interface May 24 15:58:56 jwb-volumio volumio[5768]: info: Scanning category music_service May 24 15:58:56 jwb-volumio volumio[5768]: info: Plugin folders cleanup completed May 24 15:58:56 jwb-volumio volumio[5768]: info: ------------------------------------------- May 24 15:58:56 jwb-volumio volumio[5768]: info: ----- Core plugins startup ---- May 24 15:58:56 jwb-volumio volumio[5768]: info: ------------------------------------------- May 24 15:58:56 jwb-volumio volumio[5768]: info: Loading plugins from folder /volumio/app/plugins/ May 24 15:58:56 jwb-volumio volumio[5768]: info: Adding plugin upnp to MyMusic Plugins May 24 15:58:56 jwb-volumio volumio[5768]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 15:58:56 jwb-volumio volumio[5768]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 15:58:56 jwb-volumio volumio[5768]: info: Loading plugins from folder /data/plugins/ May 24 15:58:56 jwb-volumio volumio[5768]: info: Loading plugin "system"... May 24 15:58:56 jwb-volumio volumio[5768]: info: Loading plugin "appearance"... May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "network"... May 24 15:58:57 jwb-volumio volumio[5768]: info: Refreshing Cached IP Addresses May 24 15:58:57 jwb-volumio sudo[5789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:58:57 jwb-volumio sudo[5789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:57 jwb-volumio sudo[5789]: pam_unix(sudo:session): session closed for user root May 24 15:58:57 jwb-volumio sudo[5791]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:58:57 jwb-volumio sudo[5791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "services"... May 24 15:58:57 jwb-volumio sudo[5791]: pam_unix(sudo:session): session closed for user root May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "alsa_controller"... May 24 15:58:57 jwb-volumio sudo[5795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 15:58:57 jwb-volumio sudo[5795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:58:57 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "wizard"... May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "networkfs"... May 24 15:58:57 jwb-volumio volumio[5768]: info: Starting Udev Watcher for removable devices May 24 15:58:57 jwb-volumio volumio[5768]: info: Ignoring mount for partition: boot May 24 15:58:57 jwb-volumio volumio[5768]: info: Ignoring mount for partition: root May 24 15:58:57 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "volumio_command_line_client"... May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "upnp"... May 24 15:58:57 jwb-volumio volumio[5768]: info: [1716584337927] Starting Upmpd Daemon May 24 15:58:57 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "my_music"... May 24 15:58:57 jwb-volumio volumio[5768]: info: Loading plugin "mpd"... May 24 15:58:58 jwb-volumio volumio[5768]: info: Loading plugin "upnp_browser"... May 24 15:59:00 jwb-volumio volumio[5768]: info: Loading plugin "alarm-clock"... May 24 15:59:00 jwb-volumio volumio[5768]: info: Loading plugin "airplay_emulation"... May 24 15:59:00 jwb-volumio volumio[5768]: info: Starting Shairport Sync May 24 15:59:00 jwb-volumio volumio[5768]: info: Loading plugin "last_100"... May 24 15:59:00 jwb-volumio volumio[5768]: info: Loading plugin "webradio"... May 24 15:59:00 jwb-volumio volumio[5768]: info: Loading plugin "i2s_dacs"... May 24 15:59:00 jwb-volumio volumio[5768]: info: I2S DAC not set, start Auto-detection May 24 15:59:00 jwb-volumio volumio[5768]: info: Loading plugin "volumiodiscovery"... May 24 15:59:00 jwb-volumio volumio[5768]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:59:00 jwb-volumio volumio[5768]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:00 jwb-volumio volumio[5768]: *** WARNING *** For more information see May 24 15:59:00 jwb-volumio node[5768]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:59:00 jwb-volumio volumio[5768]: *** 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:59:00 jwb-volumio volumio[5768]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:00 jwb-volumio volumio[5768]: *** WARNING *** For more information see May 24 15:59:00 jwb-volumio node[5768]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:00 jwb-volumio node[5768]: *** WARNING *** For more information see May 24 15:59:00 jwb-volumio node[5768]: *** 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:59:00 jwb-volumio node[5768]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:00 jwb-volumio node[5768]: *** WARNING *** For more information see May 24 15:59:00 jwb-volumio volumio[5768]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 15:59:00 jwb-volumio volumio[5768]: info: Discovery: Started advertising with name: jwb-volumio May 24 15:59:01 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "outputs"... May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "albumart"... May 24 15:59:01 jwb-volumio volumio[5768]: info: Plugin example_plugin is not enabled May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "inputs"... May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "updater_comm"... May 24 15:59:01 jwb-volumio volumio[5768]: info: Plugin mpdemulation is not enabled May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "rest_api"... May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "websocket"... May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "snapclient"... May 24 15:59:01 jwb-volumio volumio[5768]: info: Applying required configuration parameters for plugin snapclient May 24 15:59:01 jwb-volumio volumio[5768]: info: Plugin snapserver is not enabled May 24 15:59:01 jwb-volumio volumio[5768]: info: Plugin spop is not enabled May 24 15:59:01 jwb-volumio volumio[5768]: info: Loading plugin "volspotconnect2"... May 24 15:59:01 jwb-volumio sudo[5795]: pam_unix(sudo:session): session closed for user root May 24 15:59:01 jwb-volumio volumio[5806]: Forking 3 albumart workers May 24 15:59:02 jwb-volumio volumio[5768]: info: Loading i18n strings for locale en May 24 15:59:02 jwb-volumio volumio[5768]: Updating browse sources language May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::initPlayerControls May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: Express server listening on port 3000 May 24 15:59:02 jwb-volumio volumio[5768]: [Metrics] WebUI: 8s 586.80ms May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreStateMachine::resetVolumioState May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreStateMachine::getcurrentVolume May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:59:02 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:02 jwb-volumio volumio[5768]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 24 15:59:02 jwb-volumio volumio[5768]: info: Completed loading Core Plugins May 24 15:59:02 jwb-volumio volumio[5768]: info: Preparing to generate the ALSA configuration file May 24 15:59:02 jwb-volumio volumio[5768]: info: Cannot read play queue from file May 24 15:59:03 jwb-volumio volumio[5768]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 24 15:59:03 jwb-volumio volumio[5768]: info: The plugin snapclient has an ALSA contribution file nosound.finalizeMultiRoom.0.conf May 24 15:59:03 jwb-volumio volumio[5768]: info: Reading ALSA contributions from plugins. May 24 15:59:03 jwb-volumio volumio[5768]: 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:59:03 jwb-volumio sudo[5860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:59:03 jwb-volumio volumio[5768]: info: VolumeController:: Volume=100 Mute =false May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreStateMachine::pushState May 24 15:59:03 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioPushState May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreStateMachine::updateTrackBlock May 24 15:59:03 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrackBlock May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreStateMachine::setRepeat null single undefined May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreStateMachine::pushState May 24 15:59:03 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioPushState May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreStateMachine::setRandom null May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreStateMachine::pushState May 24 15:59:03 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioPushState May 24 15:59:03 jwb-volumio volumio[5768]: info: Discovery: adding d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:59:03 jwb-volumio volumio[5768]: info: Discovery: Found device jwb-volumio May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioGetState May 24 15:59:03 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:03 jwb-volumio volumio[5768]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:03 jwb-volumio volumio[5768]: info: VolumeController:: Volume=100 Mute =false May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreStateMachine::pushState May 24 15:59:03 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioPushState May 24 15:59:03 jwb-volumio volumio[5768]: info: Asound.conf file unchanged, so no further update is needed May 24 15:59:03 jwb-volumio volumio[5768]: info: Output device has changed, restarting MPD May 24 15:59:03 jwb-volumio volumio[5768]: info: Output device has changed, restarting Shairport Sync May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:03 jwb-volumio volumio[5768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:59:03 jwb-volumio volumio[5768]: info: ___________ START PLUGINS ___________ May 24 15:59:03 jwb-volumio volumio[5768]: info: ControllerMpd::onStart: Initializing MPD May 24 15:59:03 jwb-volumio volumio[5768]: info: Creating MPD Configuration file May 24 15:59:03 jwb-volumio sudo[5860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:03 jwb-volumio sudo[5859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:59:03 jwb-volumio sudo[5859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:03 jwb-volumio sudo[5859]: pam_unix(sudo:session): session closed for user root May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:59:03 jwb-volumio volumio[5768]: info: [1716584343870] CoreMusicLibrary::Adding element Media Servers May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:03 jwb-volumio sudo[5868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:59:03 jwb-volumio sudo[5868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:03 jwb-volumio sudo[5868]: pam_unix(sudo:session): session closed for user root May 24 15:59:03 jwb-volumio sudo[5870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:59:03 jwb-volumio sudo[5870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:03 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:04 jwb-volumio volumio[5815]: Starting albumart workers May 24 15:59:04 jwb-volumio volumio[5768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:04 jwb-volumio volumio[5768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:59:04 jwb-volumio volumio[5768]: info: [1716584344073] CoreMusicLibrary::Adding element Last_100 May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:59:04 jwb-volumio volumio[5814]: Starting albumart workers May 24 15:59:04 jwb-volumio volumio[5768]: info: [1716584344080] CoreMusicLibrary::Adding element Webradio May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:59:04 jwb-volumio volumio[5768]: [SpotifyConnect] Creating VLS config file May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:04 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:04 jwb-volumio volumio[5768]: [SpotifyConnect] Unimplemented debug mode!! May 24 15:59:04 jwb-volumio volumio[5768]: [SpotifyConnect] Starting metadata listener May 24 15:59:04 jwb-volumio volumio[5768]: info: Cannot retrieve data for calling home May 24 15:59:04 jwb-volumio sudo[5881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient May 24 15:59:04 jwb-volumio sudo[5881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:04 jwb-volumio sudo[5883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 24 15:59:04 jwb-volumio sudo[5883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:04 jwb-volumio volumio[5816]: Starting albumart workers May 24 15:59:04 jwb-volumio sudo[5881]: pam_unix(sudo:session): session closed for user root May 24 15:59:04 jwb-volumio sudo[5883]: pam_unix(sudo:session): session closed for user root May 24 15:59:05 jwb-volumio volumio[5768]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:59:05 jwb-volumio volumio[5768]: info: Discovery: Found device jwb-volumio May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioGetState May 24 15:59:05 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:05 jwb-volumio volumio[5768]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:05 jwb-volumio volumio[5768]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:59:05 jwb-volumio volumio[5768]: info: Discovery: Found device jwb-volumio May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioGetState May 24 15:59:05 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:05 jwb-volumio volumio[5768]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:05 jwb-volumio volumio[5768]: 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:59:05 jwb-volumio volumio[5768]: [SpotifyConnect] Vollibrespot Daemon service started! May 24 15:59:05 jwb-volumio volumio[5768]: [Metrics] SpotifyConnect: 1s 289.39ms May 24 15:59:05 jwb-volumio volumio[5768]: info: [SnapClient] The following error occurred while starting snapclient: Error: Command failed: /usr/bin/sudo /bin/systemctl restart snapclient May 24 15:59:05 jwb-volumio volumio[5768]: Failed to restart snapclient.service: Unit snapclient.service not found. May 24 15:59:05 jwb-volumio volumio[5768]: error: [SnapClient] Could not start the SnapCast plugin in a fashionable manner. Error: undefined May 24 15:59:05 jwb-volumio volumio[5768]: info: MPD Permissions set May 24 15:59:05 jwb-volumio volumio[5768]: info: MPD Permissions set May 24 15:59:05 jwb-volumio volumio[5768]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:59:05 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:06 jwb-volumio volumio[5768]: info: Starting Shairport Sync May 24 15:59:06 jwb-volumio volumio[5768]: info: Starting Shairport Sync May 24 15:59:06 jwb-volumio volumio[5768]: info: Starting Shairport Sync May 24 15:59:06 jwb-volumio sudo[5923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:59:06 jwb-volumio sudo[5923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:06 jwb-volumio sudo[5925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:59:06 jwb-volumio sudo[5925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:06 jwb-volumio sudo[5929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:59:06 jwb-volumio sudo[5929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:06 jwb-volumio sudo[5923]: pam_unix(sudo:session): session closed for user root May 24 15:59:06 jwb-volumio sudo[5929]: pam_unix(sudo:session): session closed for user root May 24 15:59:06 jwb-volumio sudo[5925]: pam_unix(sudo:session): session closed for user root May 24 15:59:06 jwb-volumio volumio[5768]: info: Shairport-Sync Started May 24 15:59:06 jwb-volumio volumio[5768]: Error adding Membership: Error: addMembership EINVAL May 24 15:59:06 jwb-volumio volumio[5768]: info: Shairport-Sync Started May 24 15:59:06 jwb-volumio volumio[5768]: info: Shairport-Sync Started May 24 15:59:06 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioGetState May 24 15:59:06 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:07 jwb-volumio sudo[5860]: pam_unix(sudo:session): session closed for user root May 24 15:59:07 jwb-volumio sudo[5870]: pam_unix(sudo:session): session closed for user root May 24 15:59:07 jwb-volumio volumio[5768]: info: Completed starting Core Plugins May 24 15:59:07 jwb-volumio volumio[5768]: info: BOOT COMPLETED May 24 15:59:07 jwb-volumio volumio[5768]: [Metrics] CommandRouter: 12s 762.59ms May 24 15:59:07 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumiosetStartupVolume May 24 15:59:07 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:07 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:07 jwb-volumio volumio[5768]: info: CoreCommandRouter::Close All Modals sent May 24 15:59:07 jwb-volumio volumio[5768]: info: CoreCommandRouter::Close All Modals sent May 24 15:59:07 jwb-volumio volumio[5768]: info: MPD running with PID5876 May 24 15:59:07 jwb-volumio volumio[5768]: ,establishing connection May 24 15:59:07 jwb-volumio volumio[5768]: error: MPD error: The expression evaluated to a falsy value: May 24 15:59:07 jwb-volumio volumio[5768]: assert.ok(self.idling) May 24 15:59:07 jwb-volumio volumio[5768]: error: The expression evaluated to a falsy value: May 24 15:59:07 jwb-volumio volumio[5768]: assert.ok(self.idling) May 24 15:59:07 jwb-volumio volumio[5768]: error: MPD error: The expression evaluated to a falsy value: May 24 15:59:07 jwb-volumio volumio[5768]: assert.ok(self.idling) May 24 15:59:07 jwb-volumio volumio[5768]: error: The expression evaluated to a falsy value: May 24 15:59:07 jwb-volumio volumio[5768]: assert.ok(self.idling) May 24 15:59:07 jwb-volumio volumio[5768]: error: updateQueue error: null May 24 15:59:07 jwb-volumio sudo[5940]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:59:07 jwb-volumio sudo[5940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:07 jwb-volumio sudo[5940]: pam_unix(sudo:session): session closed for user root May 24 15:59:07 jwb-volumio sudo[5942]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:59:07 jwb-volumio sudo[5942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:07 jwb-volumio sudo[5942]: pam_unix(sudo:session): session closed for user root May 24 15:59:08 jwb-volumio sudo[5946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 15:59:08 jwb-volumio sudo[5946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:08 jwb-volumio sudo[5946]: pam_unix(sudo:session): session closed for user root May 24 15:59:08 jwb-volumio volumio[5768]: info: Upmpdcli Daemon Started May 24 15:59:08 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 24 15:59:08 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 15:59:08 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 24 15:59:09 jwb-volumio volumio[5768]: 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: 4 May 24 15:59:09 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioGetState May 24 15:59:09 jwb-volumio volumio[5768]: info: CorePlayQueue::getTrack 0 May 24 15:59:09 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 15:59:09 jwb-volumio volumio[5768]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:09 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioGetVisibleSources May 24 15:59:09 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:09 jwb-volumio volumio[5768]: info: Listing playlists May 24 15:59:09 jwb-volumio volumio[5768]: info: CoreCommandRouter::volumioGetQueue May 24 15:59:09 jwb-volumio volumio[5768]: info: CoreStateMachine::getQueue May 24 15:59:09 jwb-volumio volumio[5768]: info: CorePlayQueue::getQueue May 24 15:59:12 jwb-volumio volumio[5768]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 24 15:59:18 jwb-volumio volumio[5768]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 24 15:59:18 jwb-volumio volumio[5768]: info: READ May 24 15:59:18 jwb-volumio volumio[5768]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:59:18 jwb-volumio volumio[5768]: TypeError: Converting circular structure to JSON May 24 15:59:18 jwb-volumio volumio[5768]: --> starting at object with constructor '_exports__xmlDoc' May 24 15:59:18 jwb-volumio volumio[5768]: --- property 'doc' closes the circle May 24 15:59:18 jwb-volumio volumio[5768]: at JSON.stringify () May 24 15:59:18 jwb-volumio volumio[5768]: at /volumio/app/plugins/music_service/webradio/index.js:291:117 May 24 15:59:18 jwb-volumio volumio[5768]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 24 15:59:18 jwb-volumio volumio[5768]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:59:19 jwb-volumio sudo[5960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-05-24 15:58' May 24 15:59:19 jwb-volumio sudo[5960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:19 jwb-volumio sudo[5960]: pam_unix(sudo:session): session closed for user root May 24 15:59:23 jwb-volumio volumio[5964]: info: ------------------------------------------- May 24 15:59:23 jwb-volumio volumio[5964]: info: ----- Volumio3 ---- May 24 15:59:23 jwb-volumio volumio[5964]: info: ------------------------------------------- May 24 15:59:23 jwb-volumio volumio[5964]: info: ----- System startup ---- May 24 15:59:23 jwb-volumio volumio[5964]: info: ------------------------------------------- May 24 15:59:24 jwb-volumio volumio[5964]: info: Plugin folders cleanup May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning into folder /volumio/app/plugins/ May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category audio_interface May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category miscellanea May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category music_service May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category plugins.json May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category system_controller May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category user_interface May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning into folder /data/plugins/ May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category audio_interface May 24 15:59:24 jwb-volumio volumio[5964]: info: Scanning category music_service May 24 15:59:24 jwb-volumio volumio[5964]: info: Plugin folders cleanup completed May 24 15:59:24 jwb-volumio volumio[5964]: info: ------------------------------------------- May 24 15:59:24 jwb-volumio volumio[5964]: info: ----- Core plugins startup ---- May 24 15:59:24 jwb-volumio volumio[5964]: info: ------------------------------------------- May 24 15:59:24 jwb-volumio volumio[5964]: info: Loading plugins from folder /volumio/app/plugins/ May 24 15:59:24 jwb-volumio volumio[5964]: info: Adding plugin upnp to MyMusic Plugins May 24 15:59:24 jwb-volumio volumio[5964]: info: Adding plugin airplay_emulation to MyMusic Plugins May 24 15:59:24 jwb-volumio volumio[5964]: info: Adding plugin upnp_browser to MyMusic Plugins May 24 15:59:24 jwb-volumio volumio[5964]: info: Loading plugins from folder /data/plugins/ May 24 15:59:24 jwb-volumio volumio[5964]: info: Loading plugin "system"... May 24 15:59:24 jwb-volumio volumio[5964]: info: Loading plugin "appearance"... May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "network"... May 24 15:59:26 jwb-volumio volumio[5964]: info: Refreshing Cached IP Addresses May 24 15:59:26 jwb-volumio sudo[5985]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:59:26 jwb-volumio sudo[5985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:26 jwb-volumio sudo[5985]: pam_unix(sudo:session): session closed for user root May 24 15:59:26 jwb-volumio sudo[5987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "services"... May 24 15:59:26 jwb-volumio sudo[5987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "alsa_controller"... May 24 15:59:26 jwb-volumio sudo[5987]: pam_unix(sudo:session): session closed for user root May 24 15:59:26 jwb-volumio sudo[5990]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 24 15:59:26 jwb-volumio sudo[5990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:26 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "wizard"... May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "networkfs"... May 24 15:59:26 jwb-volumio volumio[5964]: info: Starting Udev Watcher for removable devices May 24 15:59:26 jwb-volumio volumio[5964]: info: Ignoring mount for partition: boot May 24 15:59:26 jwb-volumio volumio[5964]: info: Ignoring mount for partition: root May 24 15:59:26 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "volumio_command_line_client"... May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "upnp"... May 24 15:59:26 jwb-volumio volumio[5964]: info: [1716584366413] Starting Upmpd Daemon May 24 15:59:26 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "my_music"... May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "mpd"... May 24 15:59:26 jwb-volumio volumio[5964]: info: Loading plugin "upnp_browser"... May 24 15:59:28 jwb-volumio volumio[5964]: info: Loading plugin "alarm-clock"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "airplay_emulation"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Starting Shairport Sync May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "last_100"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "webradio"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "i2s_dacs"... May 24 15:59:29 jwb-volumio volumio[5964]: info: I2S DAC not set, start Auto-detection May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "volumiodiscovery"... May 24 15:59:29 jwb-volumio volumio[5964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:59:29 jwb-volumio volumio[5964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:29 jwb-volumio volumio[5964]: *** WARNING *** For more information see May 24 15:59:29 jwb-volumio volumio[5964]: *** 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:59:29 jwb-volumio volumio[5964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:29 jwb-volumio volumio[5964]: *** WARNING *** For more information see May 24 15:59:29 jwb-volumio node[5964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 24 15:59:29 jwb-volumio node[5964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:29 jwb-volumio node[5964]: *** WARNING *** For more information see May 24 15:59:29 jwb-volumio node[5964]: *** 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:59:29 jwb-volumio node[5964]: *** WARNING *** Please fix your application to use the native API of Avahi! May 24 15:59:29 jwb-volumio node[5964]: *** WARNING *** For more information see May 24 15:59:29 jwb-volumio volumio[5964]: info: Applying required configuration parameters for plugin volumiodiscovery May 24 15:59:29 jwb-volumio volumio[5964]: info: Discovery: Started advertising with name: jwb-volumio May 24 15:59:29 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "outputs"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "albumart"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Plugin example_plugin is not enabled May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "inputs"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "updater_comm"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Plugin mpdemulation is not enabled May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "rest_api"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "websocket"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "snapclient"... May 24 15:59:29 jwb-volumio volumio[5964]: info: Applying required configuration parameters for plugin snapclient May 24 15:59:29 jwb-volumio volumio[5964]: info: Plugin snapserver is not enabled May 24 15:59:29 jwb-volumio volumio[5964]: info: Plugin spop is not enabled May 24 15:59:29 jwb-volumio volumio[5964]: info: Loading plugin "volspotconnect2"... May 24 15:59:30 jwb-volumio volumio[6002]: Forking 3 albumart workers May 24 15:59:30 jwb-volumio sudo[5990]: pam_unix(sudo:session): session closed for user root May 24 15:59:30 jwb-volumio volumio[5964]: info: Loading i18n strings for locale en May 24 15:59:30 jwb-volumio volumio[5964]: Updating browse sources language May 24 15:59:30 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::initPlayerControls May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: Express server listening on port 3000 May 24 15:59:31 jwb-volumio volumio[5964]: [Metrics] WebUI: 8s 583.20ms May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::resetVolumioState May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::getcurrentVolume May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:31 jwb-volumio volumio[5964]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 24 15:59:31 jwb-volumio volumio[5964]: info: Completed loading Core Plugins May 24 15:59:31 jwb-volumio volumio[5964]: info: Preparing to generate the ALSA configuration file May 24 15:59:31 jwb-volumio volumio[5964]: info: Cannot read play queue from file May 24 15:59:31 jwb-volumio volumio[5964]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 24 15:59:31 jwb-volumio volumio[5964]: info: The plugin snapclient has an ALSA contribution file nosound.finalizeMultiRoom.0.conf May 24 15:59:31 jwb-volumio volumio[5964]: info: Reading ALSA contributions from plugins. May 24 15:59:31 jwb-volumio volumio[5964]: 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:59:31 jwb-volumio volumio[5964]: 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:59:31 jwb-volumio volumio[5964]: info: VolumeController:: Volume=100 Mute =false May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::pushState May 24 15:59:31 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioPushState May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::updateTrackBlock May 24 15:59:31 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrackBlock May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioRetrievevolume May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::setRepeat null single undefined May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::pushState May 24 15:59:31 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioPushState May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::setRandom null May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::pushState May 24 15:59:31 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioPushState May 24 15:59:31 jwb-volumio volumio[5964]: info: VolumeController:: Volume=100 Mute =false May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreStateMachine::pushState May 24 15:59:31 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:31 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioPushState May 24 15:59:32 jwb-volumio volumio[5964]: info: Discovery: adding d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:59:32 jwb-volumio volumio[5964]: info: Discovery: Found device jwb-volumio May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioGetState May 24 15:59:32 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:32 jwb-volumio volumio[5964]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:32 jwb-volumio volumio[5964]: info: Asound.conf file unchanged, so no further update is needed May 24 15:59:32 jwb-volumio volumio[5964]: info: Output device has changed, restarting MPD May 24 15:59:32 jwb-volumio volumio[5964]: info: Output device has changed, restarting Shairport Sync May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:32 jwb-volumio sudo[6052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:59:32 jwb-volumio sudo[6052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:32 jwb-volumio sudo[6052]: pam_unix(sudo:session): session closed for user root May 24 15:59:32 jwb-volumio volumio[5964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:59:32 jwb-volumio sudo[6053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:59:32 jwb-volumio sudo[6053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:32 jwb-volumio volumio[5964]: info: ___________ START PLUGINS ___________ May 24 15:59:32 jwb-volumio volumio[5964]: info: ControllerMpd::onStart: Initializing MPD May 24 15:59:32 jwb-volumio volumio[5964]: info: Creating MPD Configuration file May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:59:32 jwb-volumio volumio[5964]: info: [1716584372245] CoreMusicLibrary::Adding element Media Servers May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:32 jwb-volumio sudo[6062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 15:59:32 jwb-volumio sudo[6062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:32 jwb-volumio sudo[6061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 15:59:32 jwb-volumio sudo[6061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:32 jwb-volumio sudo[6061]: pam_unix(sudo:session): session closed for user root May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:32 jwb-volumio volumio[6009]: Starting albumart workers May 24 15:59:32 jwb-volumio volumio[5964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:32 jwb-volumio volumio[6011]: Starting albumart workers May 24 15:59:32 jwb-volumio volumio[6010]: Starting albumart workers May 24 15:59:32 jwb-volumio volumio[5964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:59:32 jwb-volumio volumio[5964]: info: [1716584372516] CoreMusicLibrary::Adding element Last_100 May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 24 15:59:32 jwb-volumio volumio[5964]: info: [1716584372536] CoreMusicLibrary::Adding element Webradio May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 15:59:32 jwb-volumio volumio[5964]: [SpotifyConnect] Creating VLS config file May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:32 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:32 jwb-volumio volumio[5964]: [SpotifyConnect] Unimplemented debug mode!! May 24 15:59:32 jwb-volumio volumio[5964]: [SpotifyConnect] Starting metadata listener May 24 15:59:32 jwb-volumio sudo[6074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient May 24 15:59:32 jwb-volumio sudo[6074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:32 jwb-volumio volumio[5964]: info: Cannot retrieve data for calling home May 24 15:59:32 jwb-volumio sudo[6074]: pam_unix(sudo:session): session closed for user root May 24 15:59:32 jwb-volumio sudo[6077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 24 15:59:32 jwb-volumio sudo[6077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:32 jwb-volumio sudo[6077]: pam_unix(sudo:session): session closed for user root May 24 15:59:34 jwb-volumio volumio[5964]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:59:34 jwb-volumio volumio[5964]: info: Discovery: Found device jwb-volumio May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioGetState May 24 15:59:34 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:34 jwb-volumio volumio[5964]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:34 jwb-volumio volumio[5964]: info: Discovery: this is already registered, d62c9484-4171-4c56-af78-8e7931441f32 May 24 15:59:34 jwb-volumio volumio[5964]: info: Discovery: Found device jwb-volumio May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioGetState May 24 15:59:34 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:34 jwb-volumio volumio[5964]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:34 jwb-volumio volumio[5964]: 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:59:34 jwb-volumio volumio[5964]: [SpotifyConnect] Vollibrespot Daemon service started! May 24 15:59:34 jwb-volumio volumio[5964]: [Metrics] SpotifyConnect: 1s 614.79ms May 24 15:59:34 jwb-volumio volumio[5964]: info: [SnapClient] The following error occurred while starting snapclient: Error: Command failed: /usr/bin/sudo /bin/systemctl restart snapclient May 24 15:59:34 jwb-volumio volumio[5964]: Failed to restart snapclient.service: Unit snapclient.service not found. May 24 15:59:34 jwb-volumio volumio[5964]: error: [SnapClient] Could not start the SnapCast plugin in a fashionable manner. Error: undefined May 24 15:59:34 jwb-volumio volumio[5964]: info: MPD Permissions set May 24 15:59:34 jwb-volumio volumio[5964]: info: MPD Permissions set May 24 15:59:34 jwb-volumio volumio[5964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 24 15:59:34 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:34 jwb-volumio volumio[5964]: info: Starting Shairport Sync May 24 15:59:34 jwb-volumio volumio[5964]: info: Starting Shairport Sync May 24 15:59:34 jwb-volumio sudo[6116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:59:34 jwb-volumio sudo[6116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:34 jwb-volumio volumio[5964]: info: Starting Shairport Sync May 24 15:59:34 jwb-volumio sudo[6119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:59:34 jwb-volumio sudo[6119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:34 jwb-volumio sudo[6121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 15:59:34 jwb-volumio sudo[6121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:34 jwb-volumio sudo[6116]: pam_unix(sudo:session): session closed for user root May 24 15:59:34 jwb-volumio sudo[6119]: pam_unix(sudo:session): session closed for user root May 24 15:59:34 jwb-volumio volumio[5964]: info: Shairport-Sync Started May 24 15:59:34 jwb-volumio volumio[5964]: Error adding Membership: Error: addMembership EINVAL May 24 15:59:34 jwb-volumio sudo[6121]: pam_unix(sudo:session): session closed for user root May 24 15:59:35 jwb-volumio volumio[5964]: info: Shairport-Sync Started May 24 15:59:35 jwb-volumio volumio[5964]: info: Shairport-Sync Started May 24 15:59:35 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioGetState May 24 15:59:35 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:35 jwb-volumio sudo[6062]: pam_unix(sudo:session): session closed for user root May 24 15:59:35 jwb-volumio sudo[6053]: pam_unix(sudo:session): session closed for user root May 24 15:59:35 jwb-volumio volumio[5964]: info: Completed starting Core Plugins May 24 15:59:35 jwb-volumio volumio[5964]: info: BOOT COMPLETED May 24 15:59:35 jwb-volumio volumio[5964]: [Metrics] CommandRouter: 12s 586.07ms May 24 15:59:35 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumiosetStartupVolume May 24 15:59:35 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 15:59:35 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 15:59:35 jwb-volumio volumio[5964]: info: CoreCommandRouter::Close All Modals sent May 24 15:59:35 jwb-volumio volumio[5964]: info: CoreCommandRouter::Close All Modals sent May 24 15:59:35 jwb-volumio volumio[5964]: info: MPD running with PID6069 May 24 15:59:35 jwb-volumio volumio[5964]: ,establishing connection May 24 15:59:36 jwb-volumio volumio[5964]: error: MPD error: The expression evaluated to a falsy value: May 24 15:59:36 jwb-volumio volumio[5964]: assert.ok(self.idling) May 24 15:59:36 jwb-volumio volumio[5964]: error: The expression evaluated to a falsy value: May 24 15:59:36 jwb-volumio volumio[5964]: assert.ok(self.idling) May 24 15:59:36 jwb-volumio volumio[5964]: error: MPD error: The expression evaluated to a falsy value: May 24 15:59:36 jwb-volumio volumio[5964]: assert.ok(self.idling) May 24 15:59:36 jwb-volumio volumio[5964]: error: The expression evaluated to a falsy value: May 24 15:59:36 jwb-volumio volumio[5964]: assert.ok(self.idling) May 24 15:59:36 jwb-volumio volumio[5964]: error: updateQueue error: null May 24 15:59:36 jwb-volumio sudo[6134]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 15:59:36 jwb-volumio sudo[6134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:36 jwb-volumio sudo[6134]: pam_unix(sudo:session): session closed for user root May 24 15:59:36 jwb-volumio sudo[6136]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 15:59:36 jwb-volumio sudo[6136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:36 jwb-volumio sudo[6136]: pam_unix(sudo:session): session closed for user root May 24 15:59:36 jwb-volumio sudo[6139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 15:59:36 jwb-volumio sudo[6139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 24 15:59:36 jwb-volumio sudo[6139]: pam_unix(sudo:session): session closed for user root May 24 15:59:36 jwb-volumio volumio[5964]: info: Upmpdcli Daemon Started May 24 15:59:36 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 24 15:59:36 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 15:59:37 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 24 15:59:38 jwb-volumio volumio[5964]: 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:59:38 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioGetState May 24 15:59:38 jwb-volumio volumio[5964]: info: CorePlayQueue::getTrack 0 May 24 15:59:38 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 15:59:38 jwb-volumio volumio[5964]: error: JWB LOGGER LOG: Could not retrieve plugin - category: audio_interface - name: multiroom May 24 15:59:38 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioGetVisibleSources May 24 15:59:38 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:59:38 jwb-volumio volumio[5964]: info: Listing playlists May 24 15:59:38 jwb-volumio volumio[5964]: info: CoreCommandRouter::volumioGetQueue May 24 15:59:38 jwb-volumio volumio[5964]: info: CoreStateMachine::getQueue May 24 15:59:38 jwb-volumio volumio[5964]: info: CorePlayQueue::getQueue May 24 15:59:41 jwb-volumio volumio[5964]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 24 15:59:42 jwb-volumio volumio[5964]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 24 15:59:42 jwb-volumio volumio[5964]: info: READ May 24 15:59:42 jwb-volumio volumio[5964]: info: JWB LOGGER LOG: checking web radio category 0 of 313: May 24 15:59:42 jwb-volumio volumio[5964]: Use XMLElement.setAttribute instead May 24 15:59:42 jwb-volumio volumio[5964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:59:42 jwb-volumio volumio[5964]: TypeError: children[i].attr(...).value is not a function May 24 15:59:42 jwb-volumio volumio[5964]: at /volumio/app/plugins/music_service/webradio/index.js:292:47 May 24 15:59:42 jwb-volumio volumio[5964]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 24 15:59:42 jwb-volumio volumio[5964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:59:44 jwb-volumio sudo[6155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-05-24 15:58' May 24 15:59:44 jwb-volumio sudo[6155]: 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/"