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

rwood03 · January 8, 2022, 3:30pm

[aid] network, outgoing  to: http://192.168.1.73: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.72: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.70: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.89:80/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.89:80/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.89:80/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: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: The expression evaluated to a falsy value:

  assert.ok(self.idling)

[aid] network, outgoing  to: http://192.168.1.89:80/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.89:80/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.89:80/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 Timeout._onTimeout (/volumio/app/plugins/music_service/mpd/index.js:687:14)
    at listOnTimeout (internal/timers.js:554:17)
    at processTimers (internal/timers.js:497:7)
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: The expression evaluated to a falsy value:

  assert.ok(self.idling)

[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 Timeout._onTimeout (/volumio/app/plugins/music_service/mpd/index.js:687:14)
    at listOnTimeout (internal/timers.js:554:17)
    at processTimers (internal/timers.js:497:7)
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: The expression evaluated to a falsy value:

  assert.ok(self.idling)

[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 Timeout._onTimeout (/volumio/app/plugins/music_service/mpd/index.js:687:14)
    at listOnTimeout (internal/timers.js:554:17)
    at processTimers (internal/timers.js:497:7)
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: The expression evaluated to a falsy value:

  assert.ok(self.idling)

[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 Timeout._onTimeout (/volumio/app/plugins/music_service/mpd/index.js:687:14)
    at listOnTimeout (internal/timers.js:554:17)
    at processTimers (internal/timers.js:497:7)
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: The expression evaluated to a falsy value:

  assert.ok(self.idling)

[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 Timeout._onTimeout (/volumio/app/plugins/music_service/mpd/index.js:687:14)
    at listOnTimeout (internal/timers.js:554:17)
    at processTimers (internal/timers.js:497:7)
[aid] network, outgoing  to: https://ubesolwbwzdytipsq85sbr4lrax1-ee73f489126ef9e0bd0fb1fb1d6ea476.as1.myvolumio.org/api/v1/ping
 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: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: The expression evaluated to a falsy value:

  assert.ok(self.idling)

[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 Timeout._onTimeout (/volumio/app/plugins/music_service/mpd/index.js:687:14)
    at listOnTimeout (internal/timers.js:554:17)
    at processTimers (internal/timers.js:497:7)
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: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
info: [1641654430583] 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: 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.55: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)
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
}
|||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||

Wheaten · January 8, 2022, 3:33pm

@volumio

Please have a look. A lot of MPD errors,

rwood03 · January 12, 2022, 1:11pm

Having moved the same 2 * Pi4B Volumio hosts that are failing above over to a separate / isolated network with only one other (IOS) device to manage and control, I can confirm that the Pi’s do in fact activate and are able to stream seemingly as normal … when isolated !
The indication is therefore that something on the home network is affecting Volumio v3 operation on a Pi.
But what ? Back to basics and turn everything off and then back on again until I find the culprit ?

mikand · January 13, 2022, 12:01pm

If you find the source to the problem, please repost.

mgafner · January 16, 2022, 7:56pm

I have found a workaround. Since some said the problems come from the network, I started a firewall to allow only the most important accesses to the Volumio PI.

I installed a fresh Volumio 3 PI and configurated everything, but not logged into the premium account.

With the following commands the firewall is started:

sudo /sbin/iptables -A INPUT -i lo -j ACCEPT
sudo /sbin/iptables -A INPUT -p tcp --dport 22 -j ACCEPT
sudo /sbin/iptables -A INPUT -p tcp --dport 80 -j ACCEPT
sudo /sbin/iptables -A INPUT -p tcp --dport 3000 -j ACCEPT
sudo /sbin/iptables -A INPUT -p udp --sport 53 -j ACCEPT
sudo /sbin/iptables -A INPUT -p udp --dport 53 -j ACCEPT
sudo /sbin/iptables -A INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
sudo /sbin/iptables -A INPUT -j DROP

after that, i logged into the account.

about 1-2 minutes later i stopped the firewall

sudo /sbin/iptables -F

everything still works as expected.
maybe this will help to narrow down the causes further?

mgafner · January 16, 2022, 8:02pm

for the experienced networkers among you, maybe someone who knows the protocols used by Volumio 3, here is another variant that allows to log and evaluate the blocked packets: (the bold line)

sudo /sbin/iptables -A INPUT -i lo -j ACCEPT
sudo /sbin/iptables -A INPUT -p tcp --dport 22 -j ACCEPT
sudo /sbin/iptables -A INPUT -p tcp --dport 80 -j ACCEPT
sudo /sbin/iptables -A INPUT -p tcp --dport 3000 -j ACCEPT
sudo /sbin/iptables -A INPUT -p udp --sport 53 -j ACCEPT
sudo /sbin/iptables -A INPUT -p udp --dport 53 -j ACCEPT
sudo /sbin/iptables -A INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
sudo /sbin/iptables -A INPUT -j LOG --log-prefix "iptables dropped INPUT "
sudo /sbin/iptables -A INPUT -j DROP

now watch the log of dropped packets:

sudo journalctl -f | grep iptables

mikand · January 18, 2022, 1:19pm

Hi
I tested this and for me it´s stable too , but I could not activate any premium sources like Tidal, so it appears to me that it doesn’t activate the plugins. I haven’t looked in to it further due to the f…king covid. Thanks for the tip it maybe helps to find the source.
/Mikael

rwood03 · January 18, 2022, 2:22pm

Volumio 3 activated Pi + Ikea Tradfri gateway on same network = constant “configuration updates”
At least that’s the case on my own network.

Whenever I restart a Volumio 3 activated Pi with the Tradfri already running, Volumio becomes basically useless.

Powering up Tradfri only AFTER the Pi’s are up and running does not seem to affect Volumio operations. Will observe whether this remains the case over a longer period.

mikand · January 18, 2022, 4:10pm

Thanks it working, It was IKEA gateway, I had never problem with Volumio 2 so I´ll hope they can find a fix. Very nice to be able to evaluate Volumio 3.

volumio · January 18, 2022, 6:07pm

Good news gentlemen, we might have been able to track down the issue (and hopefully fix it).

The issue is due to devices on the network which advertise specific services and then cannot receive connections from other devices.
Those devices triggered a bug in a library we use.

Hopefully this is fixed now. We will release a new build shortly.

Thanks so far for your patience

mikand · January 18, 2022, 6:11pm

Good News indeed

mikand · January 19, 2022, 10:54am

Tried the Beta, works perfect

rwood03 · January 19, 2022, 2:12pm

Where / how can I get the latest beta version that resolves this problem ?

gkkpch · January 19, 2022, 6:02pm

By putting your device into test mode and update.

Now a big BUT: test mode is not recommended for all users, better wait for the released version.
With test mode you risk updating to unreleased versions which could potentially break your system. Only do this when asked by devs to verify fixes.

hulsey314 · January 20, 2023, 4:33pm

What version was this issue resolved in? I’m currently having this issue on a Pi 3+ and a Wyze 3040. I was excited to see a fix was coming soon until I realized this thread is a year old.

Josh2000 · January 20, 2023, 8:46pm

Others seem not to have this issue…

Wheaten · January 20, 2023, 9:13pm

Just upgrade to the latest version V3.396