Volumio 3 PI 3B+ restar every time, its a loop!

dglonsdale · December 24, 2021, 2:10pm

A Sony blu-ray player BDP-S7200 (last device I tried!)

mikand · December 26, 2021, 2:11pm

New log from today
Decide to test ver 3.182 same problem can’t fin any chromecast gear in my network that start the problems. If I log in to MyVolumio on the internet I can se when the the player try to activate, the play button next to trash icon blinks. also tried to change plan from premium to superstar but that didn’t help either.
/Mikael

Starting Live Log...

info: Setting Geolocation for MyVolumio to eu1
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
Starting Live Log...
Environment: production
Enabled services:
1) tidal
2) qobuz
info: MYVOLUMIO SUCCESSFULLY LOGGED IN
info: MYVOLUMIO: Adding device
info: MYVOLUMIO: Evaluating Server
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
info: [MyVolumio PluginManager] Subscribed plan changed to superstar
info: Adding audio output:
info: MYVOLUMIO: Adding device
info: MYVOLUMIO: Evaluating Server
info: Remote config written successfully
info: Starting Tunnel 1
info: Starting Tunnel Connection Checker
info: Completed starting MyVolumio Plugin
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
pam_unix(sudo:session): session opened for user root by (uid=0)
received signal to exit (15)
Stopping MyVolumio SSH Tunnel...
sshtunnel.service: Main process exited, code=killed, status=15/TERM
sshtunnel.service: Succeeded.
Stopped MyVolumio SSH Tunnel.
Started MyVolumio SSH Tunnel.
pam_unix(sudo:session): session closed for user root
info: Remote SSH Started
port set to 0, monitoring disabled
starting ssh (count 1)
ssh child pid is 4170
info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Starting Live Log...
info: CorePlayQueue::getQueue
info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
info: VolumeController:: Volume=86 Mute =false
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::volumioPushState
Starting albumart workers
Starting albumart workers
Starting albumart workers
info: Asound.conf file unchanged, so no further update is needed
info: Output device has changed, restarting MPD
info: Output device has changed, restarting Shairport Sync
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
pam_unix(sudo:session): session opened for user root by (uid=0)
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
info: ___________ START PLUGINS ___________
Stopping Music Player Daemon...
info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
info: [1640527365862] CoreMusicLibrary::Adding element Media Servers
info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Cannot find translation for sourceMedia Servers
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
mpd.service: Succeeded.
Stopped Music Player Daemon.
Starting Music Player Daemon...
info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
info: [1640527366067] CoreMusicLibrary::Adding element Last_100
info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Cannot find translation for sourceMedia Servers
info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
info: [1640527366071] CoreMusicLibrary::Adding element Webradio
info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Cannot find translation for sourceMedia Servers
info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
info: Volumio Calling Home
info: Completed starting Core Plugins
info: -------------------------------------------
info: -----    MyVolumio plugins startup     ----
info: -------------------------------------------
info: [MyVolumio PluginManager] Fetching plans data....
[2021-12-26 14:02:46] [connect] Successful connection
info: MPD Permissions set

[2021-12-26 14:02:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1640527366 101

error: error
error: error
info: Discovery: adding 6775b697-7866-4b35-b16a-e8df7016f232
info: mDNS: Found device Volumio
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: Discovery: this is already registered,  6775b697-7866-4b35-b16a-e8df7016f232
info: mDNS: Found device Volumio
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
output: No 'audio_output' defined in config file
output: Attempt to detect audio output device
output: Attempting to detect a alsa audio device
output: Successfully detected a alsa audio device
exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
zeroconf: No global port, disabling zeroconf
Started Music Player Daemon.
pam_unix(sudo:session): session closed for user root
info: Volumio called home
client: [0] opened from local
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: Starting Shairport Sync
info: Starting Shairport Sync
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
pam_unix(sudo:session): session opened for user root by (uid=0)
info: Starting Shairport Sync
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
pam_unix(sudo:session): session opened for user root by (uid=0)
Stopping Shairport Sync - AirPlay Audio Receiver...
shairport-sync.service: Succeeded.
Stopped Shairport Sync - AirPlay Audio Receiver.
Started Shairport Sync - AirPlay Audio Receiver.
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
pam_unix(sudo:session): session opened for user root by (uid=0)
Stopping Shairport Sync - AirPlay Audio Receiver...
shairport-sync.service: Main process exited, code=killed, status=15/TERM
shairport-sync.service: Succeeded.
pam_unix(sudo:session): session closed for user root
Stopped Shairport Sync - AirPlay Audio Receiver.
Started Shairport Sync - AirPlay Audio Receiver.
pam_unix(sudo:session): session closed for user root
Stopping Shairport Sync - AirPlay Audio Receiver...
shairport-sync.service: Main process exited, code=killed, status=15/TERM
shairport-sync.service: Succeeded.
Stopped Shairport Sync - AirPlay Audio Receiver.
error: updateQueue error: null
Started Shairport Sync - AirPlay Audio Receiver.
pam_unix(sudo:session): session closed for user root
info: Shairport-Sync Started
Error adding Membership: Error: addMembership EINVAL
info: Shairport-Sync Started
info: Shairport-Sync Started
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
pam_unix(sudo:session): session closed for user root
Starting Live Log...
process exited with code nullinfo: Shairport-Sync Started
info: Shairport-Sync Started
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
pam_unix(sudo:session): session closed for user root
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
info: Killing previous LiveLog session
info: Live Log process terminated: null
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
info: Upmpdcli Daemon Started
info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
info: Adding plugin bluetooth to MyMusic Plugins
info: Adding plugin multiroom to MyMusic Plugins
info: Adding plugin cd_controller to MyMusic Plugins
info: Adding plugin smart_inputs to MyMusic Plugins
info: Adding plugin tidalconnect to MyMusic Plugins
info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
info: Starting MyVolumio Remote Streaming Endpoints
info: MyVolumio login type: Token
info: [MyVolumio PluginManager] MyVolumio plugin successfully started
info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
info: Streaming services startup
info: Starting Streaming Daemon
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
pam_unix(sudo:session): session opened for user root by (uid=0)
Stopping Volumio Streaming Daemon...
volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM
volumio-streaming-daemon.service: Succeeded.
Stopped Volumio Streaming Daemon.
Started Volumio Streaming Daemon.
pam_unix(sudo:session): session closed for user root
############################
# Volumio Streaming Daemon #
# Running on port 7777     #
############################
Environment: production
Enabled services:
1) tidal
2) qobuz
info: MYVOLUMIO SUCCESSFULLY LOGGED IN
info: MYVOLUMIO: Adding device
info: MYVOLUMIO: Evaluating Server
info: [MyVolumio PluginManager] Subscribed plan changed to superstar
info: Adding audio output:
info: MYVOLUMIO: Adding device
info: MYVOLUMIO: Evaluating Server
info: Remote config written successfully
info: Starting Tunnel 1
info: Starting Tunnel Connection Checker
info: Completed starting MyVolumio Plugin
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
pam_unix(sudo:session): session opened for user root by (uid=0)
received signal to exit (15)
Stopping MyVolumio SSH Tunnel...
sshtunnel.service: Main process exited, code=killed, status=15/TERM
sshtunnel.service: Succeeded.
Stopped MyVolumio SSH Tunnel.
Started MyVolumio SSH Tunnel.
pam_unix(sudo:session): session closed for user root
info: Remote SSH Started
port set to 0, monitoring disabled
starting ssh (count 1)
ssh child pid is 5540
info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...

volumio · December 27, 2021, 12:18pm

Guys thanks for your patience.
We are trying to figure out why this issue happens (it’s affecting only a small numbers of users and we could still not figure out the root cause).

We are on it

volumio · December 27, 2021, 1:00pm

This issue is particularly hard to debug, as we are unable to reproduce it.

Our initial understanding is that it manifests under particular circumstances: either a firewall or a non-compliant device in the network.

For those of you who are familiar with SSH, it would be fantastic if you could help us troubleshoot it.

Mind that it’s advisable, after you’ve done the procedure and gathered the data, to do a factory reset.

If you can SSH and experience the issue, please do this:

Enable SSH. See: SSH - Volumio Documentation
Connect via SSH and execute those commands:

cd /home/volumio

npm i -D debugging-aid

systemctl stop volumio

node --require debugging-aid/network /volumio/index.js

This will launch Volumio in a network debugging mode.

Now, we need the logs of the system, just before it crashes . You will notice this:


|||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||

If you could copy the 100 lines before this and then paste it here , that would be a good help.
Any further info you can provide about your network: firewall, number of devices, powerlines etc will help us

Thanks for your cooperation

mikand · December 27, 2021, 11:28pm

Hi
It said that debugging-aid module is missing so I can’t
execute the command. But I have a log from earlier today if it could help it’s a fatal error in the end and a cycle between errors

ec 27 19:17:40 volumio3 volumio[4449]: info: -----            Volumio3              ----
Dec 27 19:17:40 volumio3 volumio[4449]: info: -------------------------------------------
Dec 27 19:17:40 volumio3 volumio[4449]: info: -----          System startup          ----
Dec 27 19:17:40 volumio3 volumio[4449]: info: -------------------------------------------
Dec 27 19:17:41 volumio3 volumio[4449]: info: MYVOLUMIO Environment detected
Dec 27 19:17:41 volumio3 volumio[4449]: info: Plugin folders cleanup
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning into folder /volumio/app/plugins/
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning category audio_interface
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning category miscellanea
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning category music_service
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning category plugins.json
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning category system_controller
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning category user_interface
Dec 27 19:17:41 volumio3 volumio[4449]: info: Scanning into folder /data/plugins/
Dec 27 19:17:41 volumio3 volumio[4449]: info: Plugin folders cleanup completed
Dec 27 19:17:41 volumio3 volumio[4449]: info: -------------------------------------------
Dec 27 19:17:41 volumio3 volumio[4449]: info: -----      Core plugins startup        ----
Dec 27 19:17:41 volumio3 volumio[4449]: info: -------------------------------------------
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugins from folder /volumio/app/plugins/
Dec 27 19:17:41 volumio3 volumio[4449]: info: Adding plugin upnp to MyMusic Plugins
Dec 27 19:17:41 volumio3 volumio[4449]: info: Adding plugin airplay_emulation to MyMusic Plugins
Dec 27 19:17:41 volumio3 volumio[4449]: info: Adding plugin upnp_browser to MyMusic Plugins
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugins from folder /data/plugins/
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "system"...
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "appearance"...
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "network"...
Dec 27 19:17:41 volumio3 volumio[4449]: info: Refreshing Cached IP Addresses
Dec 27 19:17:41 volumio3 sudo[4473]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 27 19:17:41 volumio3 sudo[4473]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:41 volumio3 sudo[4473]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:41 volumio3 sudo[4475]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "services"...
Dec 27 19:17:41 volumio3 sudo[4475]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "alsa_controller"...
Dec 27 19:17:41 volumio3 sudo[4475]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:41 volumio3 sudo[4479]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Dec 27 19:17:41 volumio3 sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "wizard"...
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "volumio_command_line_client"...
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "upnp"...
Dec 27 19:17:41 volumio3 volumio[4449]: info: [1640632661947] Starting Upmpd Daemon
Dec 27 19:17:41 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "my_music"...
Dec 27 19:17:41 volumio3 volumio[4449]: info: Loading plugin "mpd"...
Dec 27 19:17:42 volumio3 volumio[4449]: info: Creating MPD Configuration file
Dec 27 19:17:42 volumio3 sudo[4486]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "upnp_browser"...
Dec 27 19:17:42 volumio3 sudo[4486]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:42 volumio3 sudo[4486]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:42 volumio3 sudo[4488]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 27 19:17:42 volumio3 sudo[4488]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:42 volumio3 systemd[1]: Stopping Music Player Daemon...
Dec 27 19:17:42 volumio3 systemd[1]: mpd.service: Succeeded.
Dec 27 19:17:42 volumio3 systemd[1]: Stopped Music Player Daemon.
Dec 27 19:17:42 volumio3 systemd[1]: Starting Music Player Daemon...
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "networkfs"...
Dec 27 19:17:42 volumio3 volumio[4449]: info: Starting Udev Watcher for removable devices
Dec 27 19:17:42 volumio3 volumio[4449]: info: Ignoring mount for partition: boot
Dec 27 19:17:42 volumio3 volumio[4449]: info: Ignoring mount for partition: volumio
Dec 27 19:17:42 volumio3 volumio[4449]: info: Ignoring mount for partition: volumio_data
Dec 27 19:17:42 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "alarm-clock"...
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "airplay_emulation"...
Dec 27 19:17:42 volumio3 volumio[4449]: info: Starting Shairport Sync
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "last_100"...
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "webradio"...
Dec 27 19:17:42 volumio3 mpd[4493]: Dec 27 19:17 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 27 19:17:42 volumio3 mpd[4493]: Dec 27 19:17 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Dec 27 19:17:42 volumio3 mpd[4493]: Dec 27 19:17 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Dec 27 19:17:42 volumio3 systemd[1]: Started Music Player Daemon.
Dec 27 19:17:42 volumio3 sudo[4488]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "i2s_dacs"...
Dec 27 19:17:42 volumio3 volumio[4449]: info: I2S DAC not set, start Auto-detection
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "volumiodiscovery"...
Dec 27 19:17:42 volumio3 volumio[4449]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 27 19:17:42 volumio3 volumio[4449]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 27 19:17:42 volumio3 volumio[4449]: *** WARNING *** For more information see 
Dec 27 19:17:42 volumio3 node[4449]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 27 19:17:42 volumio3 volumio[4449]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 27 19:17:42 volumio3 volumio[4449]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 27 19:17:42 volumio3 volumio[4449]: *** WARNING *** For more information see 
Dec 27 19:17:42 volumio3 node[4449]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 27 19:17:42 volumio3 node[4449]: *** WARNING *** For more information see 
Dec 27 19:17:42 volumio3 node[4449]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 27 19:17:42 volumio3 node[4449]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 27 19:17:42 volumio3 node[4449]: *** WARNING *** For more information see 
Dec 27 19:17:42 volumio3 volumio[4449]: info: Applying required configuration parameters for plugin volumiodiscovery
Dec 27 19:17:42 volumio3 volumio[4449]: info: Discovery: Started advertising with name: Volumio3
Dec 27 19:17:42 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "outputs"...
Dec 27 19:17:42 volumio3 volumio[4449]: info: Loading plugin "albumart"...
Dec 27 19:17:43 volumio3 volumio[4449]: info: Plugin example_plugin is not enabled
Dec 27 19:17:43 volumio3 volumio[4449]: info: Loading plugin "inputs"...
Dec 27 19:17:43 volumio3 volumio[4449]: info: Loading plugin "updater_comm"...
Dec 27 19:17:43 volumio3 volumio[4449]: info: Plugin mpdemulation is not enabled
Dec 27 19:17:43 volumio3 volumio[4449]: info: Loading plugin "rest_api"...
Dec 27 19:17:43 volumio3 volumio[4449]: info: Loading plugin "websocket"...
Dec 27 19:17:43 volumio3 volumio[4449]: info: Loading i18n strings for locale sv
Dec 27 19:17:43 volumio3 volumio[4449]: Updating browse sources language
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::initPlayerControls
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: Express server listening on port 3000
Dec 27 19:17:43 volumio3 volumio[4449]:  [Metrics]  WebUI:  2s 744.65ms 
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::resetVolumioState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::getcurrentVolume
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioRetrievevolume
Dec 27 19:17:43 volumio3 volumio[4449]: Forking 3 albumart workers
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: Cannot read play queue from file
Dec 27 19:17:43 volumio3 volumio[4449]: info: MPD Permissions set
Dec 27 19:17:43 volumio3 volumio[4449]: verbose: New Socket.io Connection to 192.168.1.175 from 192.168.1.202 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.2 Safari/605.1.15 Total Clients: 1
Dec 27 19:17:43 volumio3 volumio[4449]: verbose: New Socket.io Connection to 192.168.1.169:3000 from 192.168.1.177 UA: node-XMLHttpRequest Total Clients: 2
Dec 27 19:17:43 volumio3 volumio[4449]: info: MPD running with PID4493
Dec 27 19:17:43 volumio3 volumio[4449]:  ,establishing connection
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioGetState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:43 volumio3 volumio[4449]: error: updateQueue error: null
Dec 27 19:17:43 volumio3 volumio[4449]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Dec 27 19:17:43 volumio3 volumio[4449]: info: VolumeController:: Volume=86 Mute =false
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::pushState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioPushState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::updateTrackBlock
Dec 27 19:17:43 volumio3 volumio[4449]: info: CorePlayQueue::getTrackBlock
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioRetrievevolume
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::setRepeat null single undefined
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::pushState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioPushState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::setRandom null
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::pushState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioPushState
Dec 27 19:17:43 volumio3 volumio[4449]: info: Setting Device type: Raspberry PI
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioGetQueue
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::getQueue
Dec 27 19:17:43 volumio3 volumio[4449]: info: CorePlayQueue::getQueue
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Dec 27 19:17:43 volumio3 volumio[4449]: error: updateQueue error: null
Dec 27 19:17:43 volumio3 volumio[4449]: Starting albumart workers
Dec 27 19:17:43 volumio3 volumio[4449]: Starting albumart workers
Dec 27 19:17:43 volumio3 volumio[4449]: info: Completed loading Core Plugins
Dec 27 19:17:43 volumio3 volumio[4449]: info: Preparing to generate the ALSA configuration file
Dec 27 19:17:43 volumio3 volumio[4449]: info: VolumeController:: Volume=86 Mute =false
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreStateMachine::pushState
Dec 27 19:17:43 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::volumioPushState
Dec 27 19:17:43 volumio3 volumio[4449]: Starting albumart workers
Dec 27 19:17:43 volumio3 volumio[4449]: info: Asound.conf file unchanged, so no further update is needed
Dec 27 19:17:43 volumio3 volumio[4449]: info: Output device has changed, restarting MPD
Dec 27 19:17:43 volumio3 volumio[4449]: info: Output device has changed, restarting Shairport Sync
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:43 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:43 volumio3 sudo[4548]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 27 19:17:43 volumio3 sudo[4548]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:44 volumio3 sudo[4548]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:44 volumio3 sudo[4550]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 27 19:17:44 volumio3 sudo[4550]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:44 volumio3 volumio[4449]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 27 19:17:44 volumio3 volumio[4449]: info: ___________ START PLUGINS ___________
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 27 19:17:44 volumio3 volumio[4449]: info: [1640632664057] CoreMusicLibrary::Adding element Media Servers
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 27 19:17:44 volumio3 volumio[4449]: Cannot find translation for sourceMedia Servers
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:44 volumio3 systemd[1]: Stopping Music Player Daemon...
Dec 27 19:17:44 volumio3 systemd[1]: mpd.service: Succeeded.
Dec 27 19:17:44 volumio3 systemd[1]: Stopped Music Player Daemon.
Dec 27 19:17:44 volumio3 systemd[1]: Starting Music Player Daemon...
Dec 27 19:17:44 volumio3 volumio[4449]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 27 19:17:44 volumio3 volumio[4449]: info: [1640632664339] CoreMusicLibrary::Adding element Last_100
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 27 19:17:44 volumio3 volumio[4449]: Cannot find translation for sourceMedia Servers
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 27 19:17:44 volumio3 volumio[4449]: info: [1640632664342] CoreMusicLibrary::Adding element Webradio
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 27 19:17:44 volumio3 volumio[4449]: Cannot find translation for sourceMedia Servers
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 27 19:17:44 volumio3 volumio[4449]: info: Volumio Calling Home
Dec 27 19:17:44 volumio3 volumio[4449]: info: Completed starting Core Plugins
Dec 27 19:17:44 volumio3 volumio[4449]: info: -------------------------------------------
Dec 27 19:17:44 volumio3 volumio[4449]: info: -----    MyVolumio plugins startup     ----
Dec 27 19:17:44 volumio3 volumio[4449]: info: -------------------------------------------
Dec 27 19:17:44 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 27 19:17:44 volumio3 volumio[4449]: info: Discovery: adding 83b53c60-ae27-4bf8-bf3d-0bd07cbc243c
Dec 27 19:17:44 volumio3 volumio[4449]: info: mDNS: Found device Volumio2
Dec 27 19:17:44 volumio3 volumio-remote-updater[458]: [2021-12-27 19:17:44] [connect] Successful connection
Dec 27 19:17:44 volumio3 volumio[4449]: info: Discovery: this is already registered,  83b53c60-ae27-4bf8-bf3d-0bd07cbc243c
Dec 27 19:17:44 volumio3 volumio[4449]: info: mDNS: Found device Volumio2
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::volumioGetState
Dec 27 19:17:44 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:44 volumio3 volumio[4449]: info: MPD Permissions set
Dec 27 19:17:44 volumio3 volumio-remote-updater[458]: [2021-12-27 19:17:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1640632664 101
Dec 27 19:17:44 volumio3 volumio[4449]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3
Dec 27 19:17:44 volumio3 volumio[4449]: error: error
Dec 27 19:17:44 volumio3 volumio[4449]: error: error
Dec 27 19:17:44 volumio3 volumio[4449]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 27 19:17:44 volumio3 volumio[4449]: info: Discovery: adding 6775b697-7866-4b35-b16a-e8df7016f232
Dec 27 19:17:44 volumio3 volumio[4449]: info: mDNS: Found device Volumio3
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::volumioGetState
Dec 27 19:17:44 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:44 volumio3 volumio[4449]: verbose: New Socket.io Connection to 192.168.1.169:3000 from 192.168.1.177 UA: node-XMLHttpRequest Total Clients: 5
Dec 27 19:17:44 volumio3 volumio[4449]: info: Volumio called home
Dec 27 19:17:44 volumio3 volumio[4449]: info: Discovery: this is already registered,  6775b697-7866-4b35-b16a-e8df7016f232
Dec 27 19:17:44 volumio3 volumio[4449]: info: mDNS: Found device Volumio3
Dec 27 19:17:44 volumio3 volumio[4449]: info: CoreCommandRouter::volumioGetState
Dec 27 19:17:44 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:44 volumio3 volumio[4449]: info: Starting Shairport Sync
Dec 27 19:17:44 volumio3 volumio[4449]: info: Starting Shairport Sync
Dec 27 19:17:44 volumio3 sudo[4598]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 27 19:17:44 volumio3 sudo[4598]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:44 volumio3 volumio[4449]: info: Starting Shairport Sync
Dec 27 19:17:44 volumio3 mpd[4575]: Dec 27 19:17 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 27 19:17:44 volumio3 mpd[4575]: Dec 27 19:17 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Dec 27 19:17:44 volumio3 mpd[4575]: Dec 27 19:17 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Dec 27 19:17:44 volumio3 systemd[1]: Started Music Player Daemon.
Dec 27 19:17:44 volumio3 sudo[4550]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:44 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 27 19:17:44 volumio3 systemd[1]: shairport-sync.service: Succeeded.
Dec 27 19:17:44 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 27 19:17:44 volumio3 sudo[4605]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 27 19:17:44 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 27 19:17:44 volumio3 sudo[4605]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:44 volumio3 sudo[4598]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:44 volumio3 sudo[4601]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 27 19:17:44 volumio3 sudo[4601]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:45 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 27 19:17:45 volumio3 systemd[1]: shairport-sync.service: Succeeded.
Dec 27 19:17:45 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 27 19:17:45 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 27 19:17:45 volumio3 sudo[4605]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:45 volumio3 sudo[4601]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:45 volumio3 volumio[4449]: info: Shairport-Sync Started
Dec 27 19:17:45 volumio3 volumio[4449]: Error adding Membership: Error: addMembership EINVAL
Dec 27 19:17:45 volumio3 volumio[4449]: info: Shairport-Sync Started
Dec 27 19:17:45 volumio3 volumio[4449]: info: Shairport-Sync Started
Dec 27 19:17:45 volumio3 volumio[4449]: error: updateQueue error: null
Dec 27 19:17:45 volumio3 volumio[4449]: info: CoreCommandRouter::volumioGetState
Dec 27 19:17:45 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:45 volumio3 volumio[4449]: info: CoreCommandRouter::volumioGetState
Dec 27 19:17:45 volumio3 volumio[4449]: info: CorePlayQueue::getTrack 0
Dec 27 19:17:45 volumio3 sudo[4479]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:51 volumio3 sudo[4615]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 27 19:17:51 volumio3 sudo[4615]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:51 volumio3 sudo[4615]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:51 volumio3 sudo[4617]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 27 19:17:51 volumio3 sudo[4617]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:51 volumio3 sudo[4617]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:52 volumio3 sudo[4621]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 27 19:17:52 volumio3 sudo[4621]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:52 volumio3 sudo[4621]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:52 volumio3 volumio[4449]: info: Upmpdcli Daemon Started
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 27 19:17:52 volumio3 volumio[4449]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 27 19:17:52 volumio3 volumio[4449]: info: Adding plugin multiroom to MyMusic Plugins
Dec 27 19:17:52 volumio3 volumio[4449]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 27 19:17:52 volumio3 volumio[4449]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 27 19:17:52 volumio3 volumio[4449]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 27 19:17:52 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 27 19:17:53 volumio3 volumio[4449]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 27 19:17:53 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 27 19:17:53 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:53 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:53 volumio3 volumio[4449]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 27 19:17:53 volumio3 volumio[4449]: info: MyVolumio login type: Token
Dec 27 19:17:53 volumio3 volumio[4449]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 27 19:17:53 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 27 19:17:53 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 27 19:17:53 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 27 19:17:53 volumio3 volumio[4449]: info: Streaming services startup
Dec 27 19:17:53 volumio3 volumio[4449]: info: Starting Streaming Daemon
Dec 27 19:17:53 volumio3 sudo[4624]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 27 19:17:53 volumio3 sudo[4624]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:53 volumio3 systemd[1]: Stopping Volumio Streaming Daemon...
Dec 27 19:17:53 volumio3 systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM
Dec 27 19:17:53 volumio3 systemd[1]: volumio-streaming-daemon.service: Succeeded.
Dec 27 19:17:53 volumio3 systemd[1]: Stopped Volumio Streaming Daemon.
Dec 27 19:17:53 volumio3 systemd[1]: Started Volumio Streaming Daemon.
Dec 27 19:17:53 volumio3 sudo[4624]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: ############################
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: # Volumio Streaming Daemon #
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: # Running on port 7777     #
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: ############################
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: Environment: production
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: Enabled services:
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: 1) tidal
Dec 27 19:17:53 volumio3 volumio-streaming-daemon[4630]: 2) qobuz
Dec 27 19:17:53 volumio3 volumio[4449]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 27 19:17:54 volumio3 volumio[4449]: info: MYVOLUMIO: Adding device
Dec 27 19:17:54 volumio3 volumio[4449]: info: MYVOLUMIO: Evaluating Server
Dec 27 19:17:54 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar
Dec 27 19:17:54 volumio3 volumio[4449]: info: Adding audio output:
Dec 27 19:17:54 volumio3 volumio[4449]: info: MYVOLUMIO: Adding device
Dec 27 19:17:54 volumio3 volumio[4449]: info: MYVOLUMIO: Evaluating Server
Dec 27 19:17:54 volumio3 volumio[4449]: info: Remote config written successfully
Dec 27 19:17:54 volumio3 volumio[4449]: info: Starting Tunnel 1
Dec 27 19:17:54 volumio3 volumio[4449]: info: Starting Tunnel Connection Checker
Dec 27 19:17:54 volumio3 volumio[4449]: info: Completed starting MyVolumio Plugin
Dec 27 19:17:54 volumio3 sudo[4652]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Dec 27 19:17:54 volumio3 sudo[4652]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:54 volumio3 autossh[4395]: received signal to exit (15)
Dec 27 19:17:54 volumio3 systemd[1]: Stopping MyVolumio SSH Tunnel...
Dec 27 19:17:54 volumio3 systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM
Dec 27 19:17:54 volumio3 systemd[1]: sshtunnel.service: Succeeded.
Dec 27 19:17:54 volumio3 systemd[1]: Stopped MyVolumio SSH Tunnel.
Dec 27 19:17:54 volumio3 systemd[1]: Started MyVolumio SSH Tunnel.
Dec 27 19:17:54 volumio3 sudo[4652]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:54 volumio3 volumio[4449]: info: Remote SSH Started
Dec 27 19:17:54 volumio3 autossh[4655]: port set to 0, monitoring disabled
Dec 27 19:17:54 volumio3 autossh[4655]: starting ssh (count 1)
Dec 27 19:17:54 volumio3 autossh[4655]: ssh child pid is 4658
Dec 27 19:17:55 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Dec 27 19:17:55 volumio3 volumio[4449]: info: MyVolumio status changed
Dec 27 19:17:55 volumio3 volumio[4449]: info: Streaming services startup
Dec 27 19:17:55 volumio3 volumio[4449]: info: Re-Starting Streaming Daemon
Dec 27 19:17:55 volumio3 volumio-streaming-daemon[4630]: Restarting daemon
Dec 27 19:17:55 volumio3 volumio-streaming-daemon[4630]: Environment: production
Dec 27 19:17:55 volumio3 volumio[4449]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6
Dec 27 19:17:55 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Dec 27 19:17:55 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Dec 27 19:17:55 volumio3 volumio[4449]: info: [1640632675298] Starting BluetoothController
Dec 27 19:17:55 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Dec 27 19:17:55 volumio3 volumio-streaming-daemon[4630]: Enabled services:
Dec 27 19:17:55 volumio3 volumio-streaming-daemon[4630]: 1) tidal
Dec 27 19:17:55 volumio3 volumio-streaming-daemon[4630]: 2) qobuz
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Dec 27 19:17:56 volumio3 sudo[4669]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast
Dec 27 19:17:56 volumio3 sudo[4669]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:56 volumio3 sudo[4669]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:56 volumio3 sudo[4672]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Dec 27 19:17:56 volumio3 sudo[4672]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:56 volumio3 sudo[4672]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:56 volumio3 volumio[4449]: info: MultiRoom plugin initialized
Dec 27 19:17:56 volumio3 volumio[4449]: STOPPING SNAPCLIENT
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Dec 27 19:17:56 volumio3 sudo[4688]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Dec 27 19:17:56 volumio3 sudo[4688]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Dec 27 19:17:56 volumio3 sudo[4688]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Dec 27 19:17:56 volumio3 volumio[4449]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Dec 27 19:17:56 volumio3 volumio[4449]: info: Preparing to generate the ALSA configuration file
Dec 27 19:17:56 volumio3 volumio[4449]: info: Setting Geolocation for MyVolumio to eu1
Dec 27 19:17:56 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:56 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:56 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:56 volumio3 volumio[4449]: info: Setting Geolocation for MyVolumio to eu1
Dec 27 19:17:56 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:56 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:56 volumio3 volumio[4449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 27 19:17:56 volumio3 volumio[4449]: info: MRS: SNAPCLIENT STOPPED
Dec 27 19:17:56 volumio3 volumio[4449]: info: Snap server and client installed
Dec 27 19:17:56 volumio3 volumio[4449]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 27 19:17:56 volumio3 volumio[4449]: Error: connect ECONNREFUSED 0.0.0.0:1901
Dec 27 19:17:56 volumio3 volumio[4449]:     at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
Dec 27 19:17:56 volumio3 volumio[4449]:   errno: -111,
Dec 27 19:17:56 volumio3 volumio[4449]:   code: 'ECONNREFUSED',
Dec 27 19:17:56 volumio3 volumio[4449]:   syscall: 'connect',
Dec 27 19:17:56 volumio3 volumio[4449]:   address: '0.0.0.0',
Dec 27 19:17:56 volumio3 volumio[4449]:   port: 1901
Dec 27 19:17:56 volumio3 volumio[4449]: }
Dec 27 19:17:56 volumio3 volumio[4449]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 27 19:17:56 volumio3 sudo[4699]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-12-27 19:16
Dec 27 19:17:56 volumio3 sudo[4699]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 27 19:17:57 volumio3 sudo[4699]: pam_unix(sudo:session): session closed for user root
Dec 27 19:17:57 volumio3 volumio-remote-updater[458]: [2021-12-27 19:17:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Dec 27 19:17:57 volumio3 volumio-remote-updater[458]: [2021-12-27 19:17:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Dec 27 19:17:57 volumio3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Dec 27 19:17:57 volumio3 systemd[1]: volumio.service: Failed with result 'exit-code'.
Dec 27 19:17:57 volumio3 systemd[1]: Started dynamicswap service.
Dec 27 19:17:57 volumio3 systemd[1]: dynamicswap.service: Succeeded.
Dec 27 19:17:57 volumio3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Dec 27 19:17:57 volumio3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14.
Dec 27 19:17:57 volumio3 systemd[1]: Stopped Volumio Backend Module.
Dec 27 19:17:57 volumio3 systemd[1]: Started Volumio Backend Module.
Dec 27 19:17:57 volumio3 systemd[1]: Started dynamicswap service.
Dec 27 19:17:57 volumio3 systemd[1]: dynamicswap.service: Succeeded.
Dec 27 19:17:58 volumio3 volumio[4709]: info: -------------------------------------------
Dec 27 19:17:58 volumio3 volumio[4709]: info: -----            Volumio3              ----
Dec 27 19:17:58 volumio3 volumio[4709]: info: -------------------------------------------
Dec 27 19:17:58 volumio3 volumio[4709]: info: -----          System startup          ----
Dec 27 19:17:58 volumio3 volumio[4709]: info: -------------------------------------------

mikand · December 28, 2021, 2:30pm

Hi can’t execute the command see post

Wheaten · December 28, 2021, 3:22pm

To be more detailed as mike says:

0 info it worked if it ends with ok
1 verbose cli [ '/usr/bin/node', '/usr/bin/npm', 'i', '-D', 'debugging-aid' ]
2 info using npm@6.14.10
3 info using node@v14.15.4
4 verbose npm-session 348b2c7088a76085
5 silly install loadCurrentTree
6 silly install readLocalPackageData
7 http fetch GET 200 https://registry.npmjs.org/debugging-aid 1148ms
8 http fetch GET 200 https://registry.npmjs.org/debugging-aid/-/debugging-aid-0.5.3.tgz 131ms
9 silly pacote tag manifest for debugging-aid@latest fetched in 1339ms
10 verbose stack RangeError: Maximum call stack size exceeded
10 verbose stack     at validate (/usr/lib/node_modules/npm/node_modules/aproba/index.js:25:19)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:37:3)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
10 verbose stack     at flatNameFromTree (/usr/lib/node_modules/npm/lib/install/flatten-tree.js:39:14)
11 verbose cwd /volumio
12 verbose Linux 5.4.83-v7l+
13 verbose argv "/usr/bin/node" "/usr/bin/npm" "i" "-D" "debugging-aid"
14 verbose node v14.15.4
15 verbose npm  v6.14.10
16 error Maximum call stack size exceeded
17 verbose exit [ 1, true ]

juanj2099 · December 28, 2021, 4:47pm

http://logs.volumio.org/volumio/0rFUwiK.html

gkkpch · December 28, 2021, 9:58pm

Thanks, but unfortunately in vane considering the requests from devs during the last 3 weeks.
There is specific debugging to be done, see of few posts back.
Please continue to check this thread in case you are still willing to help.
We need all the help we can get.

dglonsdale · December 29, 2021, 12:31pm

I also appear to be missing the debugging module:

volumio@volumio-w:/volumio$ npm i -D degugging-aid
npm ERR! code E404
npm ERR! 404 Not Found - GET https://registry.npmjs.org/degugging-aid - Not found
npm ERR! 404
npm ERR! 404 ‘degugging-aid@latest’ is not in the npm registry.
npm ERR! 404 You should bug the author to publish it (or use the name yourself!)
npm ERR! 404
npm ERR! 404 Note that you can also install from a
npm ERR! 404 tarball, folder, http url, or git url.
npm ERR! A complete log of this run can be found in:
npm ERR! /home/volumio/.npm/_logs/2021-12-29T12_22_35_029Z-debug.log

volumio · December 29, 2021, 3:45pm

I’ve redacted the required commands (this will solve Wheaten’s issue).

But for dglonsdale, I think that your network is the issue. It seems that it does quite an heavy filtering and github is and npm are not reachable.

mikand · January 3, 2022, 9:51pm


\ \ \_/ |/\ \L\ \\_\ \_\ \ \_\ \/\ \/\ \/\ \ \ \/\ \L\ \

\ \___/ \ \____//\____\\ \____/\ \_\ \_\ \_\ \_\ \____/

\/__/ \/___/ \/____/ \/___/ \/_/\/_/\/_/\/_/\/___/

Free Audiophile Linux Music Player - Version 3.0

© 2015-2021 Michelangelo Guarise - Volumio Team - [Volumio.org](http://volumio.org/)

Volumio Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent

permitted by applicable law.

Welcome to Volumio for Raspberry Pi (5.4.83-v7l+ armv7l)

**volumio@volumio3**:**~**$ npm install -D debugging-aid

npm WARN saveError ENOENT: no such file or directory, open '/home/volumio/package.json'

npm notice created a lockfile as package-lock.json. You should commit this file.

npm WARN enoent ENOENT: no such file or directory, open '/home/volumio/package.json'

npm WARN volumio No description

npm WARN volumio No repository field.

npm WARN volumio No README data

npm WARN volumio No license field.

+ debugging-aid@0.5.3

added 7 packages from 9 contributors and audited 7 packages in 4.991s

found 0 vulnerabilities

**volumio@volumio3**:**~**$ systemctl stop volumio

**volumio@volumio3**:**~**$ node --require debugging-aid/network /volumio/index.js

info: -------------------------------------------

info: ----- Volumio3 ----

info: -------------------------------------------

info: ----- System startup ----

info: -------------------------------------------

info: MYVOLUMIO Environment detected

info: Plugin folders cleanup

info: Scanning into folder /volumio/app/plugins/

info: Scanning category audio_interface

info: Scanning category miscellanea

info: Scanning category music_service

info: Scanning category plugins.json

info: Scanning category system_controller

info: Scanning category user_interface

info: Scanning into folder /data/plugins/

info: Plugin folders cleanup completed

info: -------------------------------------------

info: ----- Core plugins startup ----

info: -------------------------------------------

info: Loading plugins from folder /volumio/app/plugins/

info: Adding plugin upnp to MyMusic Plugins

info: Adding plugin airplay_emulation to MyMusic Plugins

info: Adding plugin upnp_browser to MyMusic Plugins

info: Loading plugins from folder /data/plugins/

info: Loading plugin "system"...

info: Loading plugin "appearance"...

info: Loading plugin "network"...

info: Refreshing Cached IP Addresses

info: Loading plugin "services"...

info: Loading plugin "alsa_controller"...

info: Loading plugin "wizard"...

info: Loading plugin "volumio_command_line_client"...

info: Loading plugin "upnp"...

info: [1640791284780] Starting Upmpd Daemon

info: CoreCommandRouter::executeOnPlugin: system , registerCallback

info: Loading plugin "my_music"...

info: Loading plugin "mpd"...

info: Creating MPD Configuration file

info: Loading plugin "upnp_browser"...

info: Loading plugin "networkfs"...

info: Starting Udev Watcher for removable devices

info: Ignoring mount for partition: boot

info: Ignoring mount for partition: volumio

info: Ignoring mount for partition: volumio_data

info: CoreCommandRouter::executeOnPlugin: system , registerCallback

info: Loading plugin "alarm-clock"...

info: Loading plugin "airplay_emulation"...

info: Starting Shairport Sync

info: Loading plugin "last_100"...

info: Loading plugin "webradio"...

info: Loading plugin "i2s_dacs"...

info: I2S DAC not set, start Auto-detection

info: Loading plugin "volumiodiscovery"...

*** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.

*** WARNING *** Please fix your application to use the native API of Avahi!

*** WARNING *** For more information see <http://0pointer.de/blog/projects/avahi-compat.html>

*** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.

*** WARNING *** Please fix your application to use the native API of Avahi!

*** WARNING *** For more information see <http://0pointer.de/blog/projects/avahi-compat.html>

info: Applying required configuration parameters for plugin volumiodiscovery

info: Discovery: Started advertising with name: Volumio3

info: CoreCommandRouter::executeOnPlugin: system , registerCallback

info: Loading plugin "outputs"...

info: Loading plugin "albumart"...

info: Plugin example_plugin is not enabled

info: Loading plugin "inputs"...

info: Loading plugin "updater_comm"...

info: Plugin mpdemulation is not enabled

info: Loading plugin "rest_api"...

info: Loading plugin "websocket"...

info: Loading i18n strings for locale sv

Updating browse sources language

info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources

info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam

info: CoreCommandRouter::initPlayerControls

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam

Express server listening on port 3000

[Metrics] WebUI: 2s 888.80ms

info: CoreStateMachine::resetVolumioState

info: CoreStateMachine::getcurrentVolume

info: CoreCommandRouter::volumioRetrievevolume

info: CoreStateMachine::pushState

info: CorePlayQueue::getTrack 0

info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

info: CoreCommandRouter::volumioPushState

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: Cannot read play queue from file

[aid] network, outgoing to: undefined//undefined:undefinedundefined

stack: at Function.MpdClient.connect (/volumio/app/plugins/music_service/mpd/lib/mpd.js:38:23)

at ControllerMpd.mpdConnect (/volumio/app/plugins/music_service/mpd/index.js:762:27)

at ControllerMpd.mpdEstablish (/volumio/app/plugins/music_service/mpd/index.js:668:8)

at /volumio/app/plugins/music_service/mpd/index.js:925:14

at ChildProcess.exithandler (child_process.js:299:7)

at ChildProcess.emit (events.js:315:20)

at maybeClose (internal/child_process.js:1048:16)

at Socket.<anonymous> (internal/child_process.js:439:11)

at Socket.emit (events.js:315:20)

at Pipe.<anonymous> (net.js:673:12)

info: MPD Permissions set

verbose: New [Socket.io](http://socket.io/) Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1

Forking 3 albumart workers

verbose: New [Socket.io](http://socket.io/) Connection to 192.168.1.169:3000 from 192.168.1.177 UA: node-XMLHttpRequest Total Clients: 2

info: MPD running with PID24402

,establishing connection

[aid] network, outgoing to: undefined//undefined:undefinedundefined

stack: at Function.MpdClient.connect (/volumio/app/plugins/music_service/mpd/lib/mpd.js:38:23)

at ControllerMpd.mpdConnect (/volumio/app/plugins/music_service/mpd/index.js:762:27)

at ControllerMpd.mpdEstablish (/volumio/app/plugins/music_service/mpd/index.js:668:8)

at /volumio/app/plugins/music_service/mpd/index.js:647:14

at ChildProcess.exithandler (child_process.js:299:7)

at ChildProcess.emit (events.js:315:20)

at maybeClose (internal/child_process.js:1048:16)

at Socket.<anonymous> (internal/child_process.js:439:11)

at Socket.emit (events.js:315:20)

at Pipe.<anonymous> (net.js:673:12)

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

error: updateQueue error: null

error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory

info: CoreStateMachine::setRepeat null single undefined

info: CoreStateMachine::pushState

info: CorePlayQueue::getTrack 0

info: CoreCommandRouter::volumioPushState

info: CoreStateMachine::setRandom null

info: CoreStateMachine::pushState

info: CorePlayQueue::getTrack 0

info: CoreCommandRouter::volumioPushState

info: Setting Device type: Raspberry PI

error: updateQueue error: null

info: Completed loading Core Plugins

info: Preparing to generate the ALSA configuration file

info: CoreCommandRouter::volumioGetState

info: CorePlayQueue::getTrack 0

info: Discovery: adding 83b53c60-ae27-4bf8-bf3d-0bd07cbc243c

info: mDNS: Found device Volumio2

[aid] network, outgoing to: undefined//192.168.1.177:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.doOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:63:8)

at XHR.Transport.open (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transport.js:80:10)

info: Discovery: this is already registered, 83b53c60-ae27-4bf8-bf3d-0bd07cbc243c

info: mDNS: Found device Volumio2

info: CoreCommandRouter::volumioGetState

info: CorePlayQueue::getTrack 0

info: Asound.conf file unchanged, so no further update is needed

info: Output device has changed, restarting MPD

info: Output device has changed, restarting Shairport Sync

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0

info: ___________ START PLUGINS ___________

info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam

info: CoreCommandRouter::volumioAddToBrowseSources[object Object]

info: [1640791286762] CoreMusicLibrary::Adding element Media Servers

info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources

Cannot find translation for sourceMedia Servers

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0

info: CoreCommandRouter::volumioAddToBrowseSources[object Object]

info: [1640791286929] CoreMusicLibrary::Adding element Last_100

info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources

Cannot find translation for sourceMedia Servers

info: CoreCommandRouter::volumioAddToBrowseSources[object Object]

info: [1640791286932] CoreMusicLibrary::Adding element Webradio

info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources

Cannot find translation for sourceMedia Servers

info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion

info: Volumio Calling Home

Starting albumart workers

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.doOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:63:8)

at XHR.Transport.open (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transport.js:80:10)

Starting albumart workers

Starting albumart workers

info: Completed starting Core Plugins

info: -------------------------------------------

info: ----- MyVolumio plugins startup ----

info: -------------------------------------------

info: [MyVolumio PluginManager] Fetching plans data....

[aid] network, outgoing to: [https://opml.radiotime.com:443/Browse.ashx](https://opml.radiotime.com/Browse.ashx)

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at RedirectableRequest._performRequest (/volumio/node_modules/follow-redirects/index.js:169:24)

at new RedirectableRequest (/volumio/node_modules/follow-redirects/index.js:66:8)

at Object.wrappedProtocol.request (/volumio/node_modules/follow-redirects/index.js:307:14)

at dispatchHttpRequest (/volumio/node_modules/axios/lib/adapters/http.js:141:25)

at new Promise (<anonymous>)

at httpAdapter (/volumio/node_modules/axios/lib/adapters/http.js:18:10)

at Object.<anonymous> (/volumio/node_modules/axios-extensions/lib/cacheAdapterEnhancer.js:36:54)

[aid] network, outgoing to: https://radio-directory.firebaseapp.com/volumio/selection.json?token=b5d113cd1f3465d39ede63b7cc51d9c0

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://0.0.0.0:1901/index.html

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.2:50001/desc/device.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.190:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.232:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.184:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.129:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.71:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://0.0.0.0:1901/index.html

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.2:50001/desc/device.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.184:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.196:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.213:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

info: MPD Permissions set

error: error

error: error

[aid] network, outgoing to: http://192.168.1.190:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

verbose: New [Socket.io](http://socket.io/) Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3

[aid] network, outgoing to: http://192.168.1.196:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.237:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.219:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.213:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.205:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

info: Volumio called home

info: Discovery: adding 6775b697-7866-4b35-b16a-e8df7016f232

info: mDNS: Found device Volumio3

info: CoreCommandRouter::volumioGetState

info: CorePlayQueue::getTrack 0

info: Discovery: this is already registered, 6775b697-7866-4b35-b16a-e8df7016f232

info: mDNS: Found device Volumio3

info: CoreCommandRouter::volumioGetState

info: CorePlayQueue::getTrack 0

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at WebSocket.initAsClient (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/ws/lib/WebSocket.js:708:21)

at new WebSocket (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/ws/lib/WebSocket.js:79:18)

at WS.doOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/websocket.js:111:65)

at WS.Transport.open (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transport.js:80:10)

at Socket.probe (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:392:13)

at Socket.onOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:413:12)

at Socket.onHandshake (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:474:8)

at Socket.onPacket (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:436:14)

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.onData (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:157:12)

at Request.<anonymous> (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:126:10)

[aid] network, outgoing to: http://192.168.1.237:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.219:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.232:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.205:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.71:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: http://192.168.1.129:1400/xml/device_description.xml

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: undefined//undefined:undefinedundefined

stack: at Function.MpdClient.connect (/volumio/app/plugins/music_service/mpd/lib/mpd.js:38:23)

at ControllerMpd.mpdConnect (/volumio/app/plugins/music_service/mpd/index.js:762:27)

at ControllerMpd.mpdEstablish (/volumio/app/plugins/music_service/mpd/index.js:668:8)

at /volumio/app/plugins/music_service/mpd/index.js:925:14

at ChildProcess.exithandler (child_process.js:299:7)

at ChildProcess.emit (events.js:315:20)

at maybeClose (internal/child_process.js:1048:16)

at Socket.<anonymous> (internal/child_process.js:439:11)

at Socket.emit (events.js:315:20)

at Pipe.<anonymous> (net.js:673:12)

info: Starting Shairport Sync

info: Starting Shairport Sync

info: Starting Shairport Sync

error: updateQueue error: null

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doWrite (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:106:18)

at /volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:206:10

at /volumio/node_modules/[engine.io](http://engine.io/)-parser/lib/index.js:235:12

at proxy (/volumio/node_modules/after/index.js:23:13)

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.onData (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:157:12)

at Request.<anonymous> (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:126:10)

info: Shairport-Sync Started

Error adding Membership: Error: addMembership EINVAL

info: Shairport-Sync Started

info: Shairport-Sync Started

info: CoreCommandRouter::volumioGetState

info: CorePlayQueue::getTrack 0

[aid] network, outgoing to: undefined//192.168.1.177:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at WebSocket.initAsClient (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/ws/lib/WebSocket.js:708:21)

at new WebSocket (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/ws/lib/WebSocket.js:79:18)

at WS.doOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/websocket.js:111:65)

at WS.Transport.open (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transport.js:80:10)

at Socket.probe (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:392:13)

at Socket.onOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:413:12)

at Socket.onHandshake (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:474:8)

at Socket.onPacket (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:436:14)

[aid] network, outgoing to: undefined//192.168.1.177:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.onData (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:157:12)

at Request.<anonymous> (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:126:10)

[aid] network, outgoing to: undefined//192.168.1.177:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doWrite (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:106:18)

at /volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:206:10

at /volumio/node_modules/[engine.io](http://engine.io/)-parser/lib/index.js:235:12

at proxy (/volumio/node_modules/after/index.js:23:13)

[aid] network, outgoing to: undefined//192.168.1.177:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.onData (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:157:12)

at Request.<anonymous> (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:126:10)

verbose: New [Socket.io](http://socket.io/) Connection to 192.168.1.169:3000 from 192.168.1.177 UA: node-XMLHttpRequest Total Clients: 4

info: CoreCommandRouter::volumioGetState

info: CorePlayQueue::getTrack 0

info: Upmpdcli Daemon Started

[aid] network, outgoing to: https://database.volumio.cloud/products/002001.json

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium

info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium

info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium

info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium

info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium

info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium

info: [MyVolumio PluginManager] Adding plugin tidal to plan premium

info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium

info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium

info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar

info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar

info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar

info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar

info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar

info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar

info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar

info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar

info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar

info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso

info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso

info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso

info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso

info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso

info: Adding plugin bluetooth to MyMusic Plugins

info: Adding plugin multiroom to MyMusic Plugins

info: Adding plugin cd_controller to MyMusic Plugins

info: Adding plugin smart_inputs to MyMusic Plugins

info: Adding plugin tidalconnect to MyMusic Plugins

info: [MyVolumio PluginManager] Loading plugin "my_volumio"...

info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded

info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: Starting MyVolumio Remote Streaming Endpoints

info: MyVolumio login type: Token

info: [MyVolumio PluginManager] MyVolumio plugin successfully started

info: [MyVolumio PluginManager] Loading plugin "streaming_services"...

info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded

info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services

info: Streaming services startup

info: Starting Streaming Daemon

[aid] network, outgoing to: undefined//[www.googleapis.com](http://www.googleapis.com/):443undefined

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (https.js:313:10)

at exports.XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest/lib/XMLHttpRequest.js:463:17)

at ai (/volumio/node_modules/@firebase/auth/dist/auth.js:204:471)

at Ei.u (/volumio/node_modules/@firebase/auth/dist/auth.js:226:175)

at Oi (/volumio/node_modules/@firebase/auth/dist/auth.js:224:102)

at /volumio/node_modules/@firebase/auth/dist/auth.js:230:180

at new C (/volumio/node_modules/@firebase/auth/dist/auth.js:69:937)

at Ui (/volumio/node_modules/@firebase/auth/dist/auth.js:230:160)

[aid] network, outgoing to: undefined//[www.googleapis.com](http://www.googleapis.com/):443undefined

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (https.js:313:10)

at exports.XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest/lib/XMLHttpRequest.js:463:17)

at ai (/volumio/node_modules/@firebase/auth/dist/auth.js:204:471)

at Ei.u (/volumio/node_modules/@firebase/auth/dist/auth.js:226:175)

at Oi (/volumio/node_modules/@firebase/auth/dist/auth.js:224:102)

at /volumio/node_modules/@firebase/auth/dist/auth.js:230:180

at new C (/volumio/node_modules/@firebase/auth/dist/auth.js:69:937)

at Ui (/volumio/node_modules/@firebase/auth/dist/auth.js:230:160)

info: MYVOLUMIO SUCCESSFULLY LOGGED IN

info: MYVOLUMIO: Adding device

info: MYVOLUMIO: Evaluating Server

[aid] network, outgoing to: https://securetoken.googleapis.com/v1/token?key=AIzaSyDzEZmwJZS4KZtG9pEXOxlm1XcZikP0KbA

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.write (/volumio/node_modules/request/request.js:1491:10)

at end (/volumio/node_modules/request/request.js:549:18)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: https://functions.volumio.cloud/api/v1/getCustomToken?idToken=eyJhbGciOiJSUzI1NiIsImtpZCI6IjcxMTQzNzFiMmU4NmY4MGM1YzYxNThmNDUzYzk0NTEyNmZlNzM5Y2MiLCJ0eXAiOiJKV1QifQ.eyJpc3MiOiJodHRwczovL3NlY3VyZXRva2VuLmdvb2dsZS5jb20vbXl2b2x1bWlvIiwiYXVkIjoibXl2b2x1bWlvIiwiYXV0aF90aW1lIjoxNjQwNzkxMjk2LCJ1c2VyX2lkIjoiNThTM05RNk1lWlRrbExUTXpVY3R1Q0oyUXlBMiIsInN1YiI6IjU4UzNOUTZNZVpUa2xMVE16VWN0dUNKMlF5QTIiLCJpYXQiOjE2NDA3OTEyOTYsImV4cCI6MTY0MDc5NDg5NiwiZW1haWwiOiJtaWthZWwuYW5kZXJzc29uQHRlbGlhLmNvbSIsImVtYWlsX3ZlcmlmaWVkIjp0cnVlLCJmaXJlYmFzZSI6eyJpZGVudGl0aWVzIjp7ImVtYWlsIjpbIm1pa2FlbC5hbmRlcnNzb25AdGVsaWEuY29tIl19LCJzaWduX2luX3Byb3ZpZGVyIjoiY3VzdG9tIn19.HJ-DJ9gvWoNQJtMrE-A092NYebP4bDA6ltnZMxdVC6H5jbJyYS3bq_EP1TjUlnC3N_S07bbZ30TUyoBP3HA3FYIinH8ixsQbNFdSBDIGIy8MBgk24y1C4_-KpjADsSO6zYwtceqH0PW6Z19qJoaHreOmyfH0NQtDKU2VYX5vNcM6A5D5cey3_s5RJ81YPq5teL_-geqAK8vk82SdRzWNUV50AmEC_m8fHU2lpQsurKdhcIea9k7GTsQzvV-5UYkbmHc5enAkrTPXn5MyiKIdlnhlfWOkfFE9X7F3ZRDkpZa5hBRM1aSeLKapAQlLkiBDHINqDJdNDs8WYhdNHQrrjA

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: undefined//[myvolumio.firebaseio.com](http://myvolumio.firebaseio.com/):443undefined

stack: at new Client (/volumio/node_modules/faye-websocket/lib/faye/websocket/client.js:44:22)

at WebSocketConnection.open (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:10644:31)

at Timeout._onTimeout (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:11083:40)

at listOnTimeout (internal/timers.js:554:17)

at processTimers (internal/timers.js:497:7)

[aid] network, outgoing to: https://functions.volumio.cloud/api/v1/getCustomToken?idToken=eyJhbGciOiJSUzI1NiIsImtpZCI6IjcxMTQzNzFiMmU4NmY4MGM1YzYxNThmNDUzYzk0NTEyNmZlNzM5Y2MiLCJ0eXAiOiJKV1QifQ.eyJpc3MiOiJodHRwczovL3NlY3VyZXRva2VuLmdvb2dsZS5jb20vbXl2b2x1bWlvIiwiYXVkIjoibXl2b2x1bWlvIiwiYXV0aF90aW1lIjoxNjQwNzkxMjk2LCJ1c2VyX2lkIjoiNThTM05RNk1lWlRrbExUTXpVY3R1Q0oyUXlBMiIsInN1YiI6IjU4UzNOUTZNZVpUa2xMVE16VWN0dUNKMlF5QTIiLCJpYXQiOjE2NDA3OTEyOTYsImV4cCI6MTY0MDc5NDg5NiwiZW1haWwiOiJtaWthZWwuYW5kZXJzc29uQHRlbGlhLmNvbSIsImVtYWlsX3ZlcmlmaWVkIjp0cnVlLCJmaXJlYmFzZSI6eyJpZGVudGl0aWVzIjp7ImVtYWlsIjpbIm1pa2FlbC5hbmRlcnNzb25AdGVsaWEuY29tIl19LCJzaWduX2luX3Byb3ZpZGVyIjoiY3VzdG9tIn19.HJ-DJ9gvWoNQJtMrE-A092NYebP4bDA6ltnZMxdVC6H5jbJyYS3bq_EP1TjUlnC3N_S07bbZ30TUyoBP3HA3FYIinH8ixsQbNFdSBDIGIy8MBgk24y1C4_-KpjADsSO6zYwtceqH0PW6Z19qJoaHreOmyfH0NQtDKU2VYX5vNcM6A5D5cey3_s5RJ81YPq5teL_-geqAK8vk82SdRzWNUV50AmEC_m8fHU2lpQsurKdhcIea9k7GTsQzvV-5UYkbmHc5enAkrTPXn5MyiKIdlnhlfWOkfFE9X7F3ZRDkpZa5hBRM1aSeLKapAQlLkiBDHINqDJdNDs8WYhdNHQrrjA

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

info: [MyVolumio PluginManager] Subscribed plan changed to superstar

info: Adding audio output:

info: MYVOLUMIO: Adding device

info: MYVOLUMIO: Evaluating Server

info: Remote config written successfully

info: Starting Tunnel 1

info: Starting Tunnel Connection Checker

info: Completed starting MyVolumio Plugin

info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...

info: MyVolumio status changed

info: Streaming services startup

info: Re-Starting Streaming Daemon

[aid] network, outgoing to: http://127.0.0.1:7777/restart

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.end (/volumio/node_modules/request/request.js:1505:10)

at end (/volumio/node_modules/request/request.js:564:14)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

info: Remote SSH Started

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.doOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:63:8)

at XHR.Transport.open (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transport.js:80:10)

[aid] network, outgoing to: undefined//[mqtt.volumio.org](http://mqtt.volumio.org/):80undefined

stack: at Object.buildBuilder (/volumio/node_modules/mqtt/lib/connect/tcp.js:16:14)

at MqttClient.wrapper [as streamBuilder] (/volumio/node_modules/mqtt/lib/connect/index.js:148:36)

at MqttClient._setupStream (/volumio/node_modules/mqtt/lib/client.js:263:22)

at new MqttClient (/volumio/node_modules/mqtt/lib/client.js:244:8)

at Object.connect (/volumio/node_modules/mqtt/lib/connect/index.js:151:10)

at myVolumio.deviceStateUpdater (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:14332)

at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18988

at ChildProcess.exithandler (child_process.js:299:7)

at ChildProcess.emit (events.js:315:20)

at maybeClose (internal/child_process.js:1048:16)

at Socket.<anonymous> (internal/child_process.js:439:11)

at Socket.emit (events.js:315:20)

info: Setting Geolocation for MyVolumio to eu1

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

[aid] network, outgoing to: https://functions.volumio.cloud/api/v1/addMyVolumioDevice?uid=58S3NQ6MeZTklLTMzUctuCJ2QyA2&token=eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJodHRwczovL2lkZW50aXR5dG9vbGtpdC5nb29nbGVhcGlzLmNvbS9nb29nbGUuaWRlbnRpdHkuaWRlbnRpdHl0b29sa2l0LnYxLklkZW50aXR5VG9vbGtpdCIsImlhdCI6MTY0MDc5MTI5NywiZXhwIjoxNjQwNzk0ODk3LCJpc3MiOiJteXZvbHVtaW9AYXBwc3BvdC5nc2VydmljZWFjY291bnQuY29tIiwic3ViIjoibXl2b2x1bWlvQGFwcHNwb3QuZ3NlcnZpY2VhY2NvdW50LmNvbSIsInVpZCI6IjU4UzNOUTZNZVpUa2xMVE16VWN0dUNKMlF5QTIifQ.Mn4G7CDtZisfr6igJbqeDaQNNZl769C76s1CeA_Turf6AzuxHR5qsVo-x4ZImIyzEl6rmw_O-it3yK169SDdlmm33Yyd_dyJ1-sgNioFxLh4449ycrHSUTrxLb9yvZ4G3RO0PE3SNFcIIGIPyw1j0Uo4cuntrGj_3-O1k9dTFT1alJYIZ8S7pcBTDvTtLX37iFB0e7QZjm2xatjKvRtje0HTxph8_agC6NQwjL_XvrYoyxh_wK65fl-udowip5FedxCSD4Mc4c59z3s1NOXFwSC7Q7ORTeD4km8YY9_EWjdiDjjpVaKdX0Vflxhho3zSUIH824fA4NbDW74klIXGzw

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.write (/volumio/node_modules/request/request.js:1491:10)

at end (/volumio/node_modules/request/request.js:549:18)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

verbose: New [Socket.io](http://socket.io/) Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5

info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid

info: [MyVolumio PluginManager] Loading plugin "bluetooth"...

info: [1640791298133] Starting BluetoothController

info: [MyVolumio PluginManager] Loading plugin "multiroom"...

info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom

info: MultiRoom plugin initialized

STOPPING SNAPCLIENT

info: [MyVolumio PluginManager] Loading plugin "metavolumio"...

info: [MyVolumio PluginManager] Loading plugin "cd_controller"...

info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...

info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...

info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio

info: [MyVolumio PluginManager] Loading plugin "tidal"...

info: [MyVolumio PluginManager] Loading plugin "qobuz"...

info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...

info: Preparing to generate the ALSA configuration file

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (http.js:50:10)

at WebSocket.initAsClient (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/ws/lib/WebSocket.js:708:21)

at new WebSocket (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/ws/lib/WebSocket.js:79:18)

at WS.doOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/websocket.js:111:65)

at WS.Transport.open (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transport.js:80:10)

at Socket.probe (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:392:13)

at Socket.onOpen (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:413:12)

at Socket.onHandshake (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:474:8)

at Socket.onPacket (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:436:14)

at XHR.<anonymous> (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/socket.js:268:10)

at XHR.Emitter.emit (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/component-emitter/index.js:133:20)

at XHR.Transport.onPacket (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transport.js:145:8)

at callback (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:144:10)

at Object.exports.decodePayload (/volumio/node_modules/[engine.io](http://engine.io/)-parser/lib/index.js:312:19)

at XHR.Polling.onData (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:148:10)

[aid] network, outgoing to: undefined//localhost:3000undefined

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at XMLHttpRequest.send (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:494:17)

at Request.create (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:261:9)

at new Request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:166:8)

at XHR.request (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:93:10)

at XHR.doPoll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:123:18)

at XHR.Polling.poll (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:118:8)

at XHR.Polling.onData (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling.js:157:12)

at Request.<anonymous> (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:126:10)

at Request.Emitter.emit (/volumio/node_modules/[engine.io](http://engine.io/)-client/node_modules/component-emitter/index.js:133:20)

at Request.onData (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:296:8)

at Request.onLoad (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:377:10)

at XMLHttpRequest.xhr.onreadystatechange (/volumio/node_modules/[engine.io](http://engine.io/)-client/lib/transports/polling-xhr.js:249:16)

at XMLHttpRequest.dispatchEvent (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:621:25)

at setState (/volumio/node_modules/xmlhttprequest-ssl/lib/XMLHttpRequest.js:640:14)

info: Updating MyVolumio device info

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: Setting Geolocation for MyVolumio to eu1

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

[aid] network, outgoing to: http://0.0.0.0:1901/index.html

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at Object.get (http.js:54:15)

at SsdpClient.<anonymous> (/myvolumio/plugins/audio_interface/multiroom/node_modules/chromecast-api/lib/client.js:120:12)

at SsdpClient.emit (events.js:315:20)

at SsdpClient.parseResponse [as _parseResponse] (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:457:8)

at SsdpClient.SSDP._parseMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:298:10)

at Socket.onSocketMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:244:12)

at Socket.emit (events.js:315:20)

at UDP.onMessage [as onmessage] (dgram.js:919:8)

[aid] network, outgoing to: http://192.168.1.89:7678/nservice/

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at Object.get (http.js:54:15)

at SsdpClient.<anonymous> (/myvolumio/plugins/audio_interface/multiroom/node_modules/chromecast-api/lib/client.js:120:12)

at SsdpClient.emit (events.js:315:20)

at SsdpClient.parseResponse [as _parseResponse] (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:457:8)

at SsdpClient.SSDP._parseMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:298:10)

at Socket.onSocketMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:244:12)

at Socket.emit (events.js:315:20)

at UDP.onMessage [as onmessage] (dgram.js:919:8)

[aid] network, outgoing to: http://0.0.0.0:1901/index.html

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at Object.get (http.js:54:15)

at SsdpClient.<anonymous> (/myvolumio/plugins/audio_interface/multiroom/node_modules/chromecast-api/lib/client.js:120:12)

at SsdpClient.emit (events.js:315:20)

at SsdpClient.parseResponse [as _parseResponse] (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:457:8)

at SsdpClient.SSDP._parseMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:298:10)

at Socket.onSocketMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:244:12)

at Socket.emit (events.js:315:20)

at UDP.onMessage [as onmessage] (dgram.js:919:8)

[aid] network, outgoing to: http://192.168.1.89:7678/nservice/

stack: at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at request (http.js:50:10)

at Object.get (http.js:54:15)

at SsdpClient.<anonymous> (/myvolumio/plugins/audio_interface/multiroom/node_modules/chromecast-api/lib/client.js:120:12)

at SsdpClient.emit (events.js:315:20)

at SsdpClient.parseResponse [as _parseResponse] (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:457:8)

at SsdpClient.SSDP._parseMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:298:10)

at Socket.onSocketMessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/node-ssdp/lib/index.js:244:12)

at Socket.emit (events.js:315:20)

at UDP.onMessage [as onmessage] (dgram.js:919:8)

[aid] network, outgoing to: https://functions.volumio.cloud/api/v1/updateMyVolumioDevice?uid=58S3NQ6MeZTklLTMzUctuCJ2QyA2&token=eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJodHRwczovL2lkZW50aXR5dG9vbGtpdC5nb29nbGVhcGlzLmNvbS9nb29nbGUuaWRlbnRpdHkuaWRlbnRpdHl0b29sa2l0LnYxLklkZW50aXR5VG9vbGtpdCIsImlhdCI6MTY0MDc5MTI5NywiZXhwIjoxNjQwNzk0ODk3LCJpc3MiOiJteXZvbHVtaW9AYXBwc3BvdC5nc2VydmljZWFjY291bnQuY29tIiwic3ViIjoibXl2b2x1bWlvQGFwcHNwb3QuZ3NlcnZpY2VhY2NvdW50LmNvbSIsInVpZCI6IjU4UzNOUTZNZVpUa2xMVE16VWN0dUNKMlF5QTIifQ.Mn4G7CDtZisfr6igJbqeDaQNNZl769C76s1CeA_Turf6AzuxHR5qsVo-x4ZImIyzEl6rmw_O-it3yK169SDdlmm33Yyd_dyJ1-sgNioFxLh4449ycrHSUTrxLb9yvZ4G3RO0PE3SNFcIIGIPyw1j0Uo4cuntrGj_3-O1k9dTFT1alJYIZ8S7pcBTDvTtLX37iFB0e7QZjm2xatjKvRtje0HTxph8_agC6NQwjL_XvrYoyxh_wK65fl-udowip5FedxCSD4Mc4c59z3s1NOXFwSC7Q7ORTeD4km8YY9_EWjdiDjjpVaKdX0Vflxhho3zSUIH824fA4NbDW74klIXGzw

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.write (/volumio/node_modules/request/request.js:1491:10)

at end (/volumio/node_modules/request/request.js:549:18)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

[aid] network, outgoing to: https://functions.volumio.cloud/api/v1/addMyVolumioDevice?uid=58S3NQ6MeZTklLTMzUctuCJ2QyA2&token=eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOiJodHRwczovL2lkZW50aXR5dG9vbGtpdC5nb29nbGVhcGlzLmNvbS9nb29nbGUuaWRlbnRpdHkuaWRlbnRpdHl0b29sa2l0LnYxLklkZW50aXR5VG9vbGtpdCIsImlhdCI6MTY0MDc5MTI5NywiZXhwIjoxNjQwNzk0ODk3LCJpc3MiOiJteXZvbHVtaW9AYXBwc3BvdC5nc2VydmljZWFjY291bnQuY29tIiwic3ViIjoibXl2b2x1bWlvQGFwcHNwb3QuZ3NlcnZpY2VhY2NvdW50LmNvbSIsInVpZCI6IjU4UzNOUTZNZVpUa2xMVE16VWN0dUNKMlF5QTIifQ.Mn4G7CDtZisfr6igJbqeDaQNNZl769C76s1CeA_Turf6AzuxHR5qsVo-x4ZImIyzEl6rmw_O-it3yK169SDdlmm33Yyd_dyJ1-sgNioFxLh4449ycrHSUTrxLb9yvZ4G3RO0PE3SNFcIIGIPyw1j0Uo4cuntrGj_3-O1k9dTFT1alJYIZ8S7pcBTDvTtLX37iFB0e7QZjm2xatjKvRtje0HTxph8_agC6NQwjL_XvrYoyxh_wK65fl-udowip5FedxCSD4Mc4c59z3s1NOXFwSC7Q7ORTeD4km8YY9_EWjdiDjjpVaKdX0Vflxhho3zSUIH824fA4NbDW74klIXGzw

stack: at Agent.createConnection (https.js:129:22)

at Agent.createSocket (_http_agent.js:323:26)

at Agent.addRequest (_http_agent.js:274:10)

at new ClientRequest (_http_client.js:306:16)

at Object.request (https.js:313:10)

at Request.start (/volumio/node_modules/request/request.js:751:32)

at Request.write (/volumio/node_modules/request/request.js:1491:10)

at end (/volumio/node_modules/request/request.js:549:18)

at Immediate._onImmediate (/volumio/node_modules/request/request.js:578:7)

at processImmediate (internal/timers.js:461:21)

info: MRS: SNAPCLIENT STOPPED

info: Snap server and client installed

|||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||

Error: connect ECONNREFUSED 0.0.0.0:1901

at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {

errno: -111,

code: 'ECONNREFUSED',

syscall: 'connect',

address: '0.0.0.0',

port: 1901

}

|||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||

**volumio@volumio3**:**~**$

rwood03 · January 8, 2022, 1:46pm

Today applied fresh v3.179 IMG to a brand new Pi4B, at the end of “first config wizard” login with all default config … the exact same problem occurs. Would appear that this problem is not specific to 3B+

SimonE · January 8, 2022, 2:32pm

Did you use the same SD card? They can have bad sectors and cause intermittent problems. Other than that, it could be a network issue. I assume you have a different, suitable power supply for your pi4.

rwood03 · January 8, 2022, 2:39pm

have tested with 2 brand new Pi4’s, 2 old Pi3B+, with 4 different SD cards and, 2 known good power sources … same results throughout.

Wheaten · January 8, 2022, 2:43pm

Those SD’s are all class 10 or better? As I have been able to reproduce this with SD < class10

SimonE · January 8, 2022, 2:47pm

Probably not hardware then, unless it’s your dac. Same on wifi and wired network?
Does it still happen if you try a very basic configuration with no plugins and play a radio stream?

rwood03 · January 8, 2022, 2:48pm

double checked, all are C10

Wheaten · January 8, 2022, 2:51pm

Then please execute the instructions as requested by @Volumio. So they have enough data to debug this one.

volumio:

If you can SSH and experience the issue, please do this:

Enable SSH. See: SSH - Volumio Documentation

Connect via SSH and execute those commands:
cd /home/volumio

npm i -D debugging-aid

systemctl stop volumio

node --require debugging-aid/network /volumio/index.js
This will launch Volumio in a network debugging mode.

Now, we need the logs of the system, just before it crashes . You will notice this:
|||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
If you could copy the 100 lines before this and then paste it here , that would be a good help.
Any further info you can provide about your network: firewall, number of devices, powerlines etc will help us

Thanks for your cooperation

rwood03 · January 8, 2022, 3:28pm

Network summary:
USG firewall, Netgear ORBI WiFi. No powerlines.
Multiple GB switches. Multiple Sonos devices.
Multiple gateways - Smartthings, Philips Hue, Somfy Tahoma.
Multiple Pis, PCs, Macs, IOS & Android devices, Apple TVs, smart TVs and AV components +++ etc.