Hi there! I’m having an issue where I cannot see any sources in the “browse” tab of the web UI and cannot connect to volumio using MPD clients. mpd.log shows client: Max connections reached a large number of times.
From my understanding, that means that volumio is not correctly terminating connections when a session is ended by a client.
Has anyone had any experience with this issue?
Thanks!
A
EDIT: Sorry, I came here from google, which showed 28 May 2020 as the date and I didn’t notice that the previous post was actually from 2015.
System Version: 2.834 Released: Thu Sep 24 22:42:43 CEST 2020
(WTH I cannot just select and copy that string in System Settings?!?!?)
I think it happens to me when I upload a new music and mpd starts its update process. Here are the logs, when at 14:52 I uploaded some new music and then at 15:09 I restarted volumio with volumio vstop and volumio vstart:
/var/log/mpd.log
Mar 20 01:06 : player: played "USB/1TB/flac/0_classical/Rachel Podger/2014 - Mozart- Complete Sonatas for Keyboard and Violin (Gary Cooper)/cd4/03. Sonata in G, K.9- I. Allegro spiritoso.flac"
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/08 Rodeo On The Ridge.flac
Mar 23 14:52 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/08 Rodeo On The Ridge.flac
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/09 Summer Wind.flac
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/07 The Riddle Song.flac
Mar 23 14:52 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/09 Summer Wind.flac
Mar 23 14:52 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/07 The Riddle Song.flac
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/10 One More Savior.flac
Mar 23 14:52 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/10 One More Savior.flac
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/12 Vision Of Her.flac
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/06 Take Me Down The Road.flac
Mar 23 14:52 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/12 Vision Of Her.flac
Mar 23 14:52 : exception: error on client 2: Output buffer is full
Mar 23 14:52 : client: [2] closed
Mar 23 14:52 : client: [8] opened from local
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/03 Above The Creek.flac
Mar 23 14:52 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/06 Take Me Down The Road.flac
Mar 23 14:52 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/03 Above The Creek.flac
Mar 23 14:52 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/14 Morning Light Western Town.flac
Mar 23 14:53 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/11 Good Old Days.flac
Mar 23 14:53 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/14 Morning Light Western Town.flac
Mar 23 14:53 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/04 Crossing.flac
Mar 23 14:53 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/11 Good Old Days.flac
Mar 23 14:53 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/04 Crossing.flac
Mar 23 14:53 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/05 Changing Down.flac
Mar 23 14:53 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/05 Changing Down.flac
Mar 23 14:53 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/13 Transcendental Deprivation Part II Straw Dream.flac
Mar 23 14:53 : exception: error on client 8: Output buffer is full
Mar 23 14:53 : client: [8] closed
Mar 23 14:53 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/02 Ohlone Dream.flac
Mar 23 14:53 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/13 Transcendental Deprivation Part II Straw Dream.flac
Mar 23 14:53 : client: [9] opened from local
Mar 23 14:53 : client: [10] opened from local
Mar 23 14:53 : client: [11] opened from local
Mar 23 14:53 : client: [12] opened from local
Mar 23 14:53 : client: [13] opened from local
Mar 23 14:53 : client: [14] opened from local
Mar 23 14:53 : client: [15] opened from local
Mar 23 14:53 : client: [16] opened from local
Mar 23 14:53 : client: [17] opened from local
Mar 23 14:53 : client: [18] opened from local
Mar 23 14:53 : client: [19] opened from local
Mar 23 14:53 : client: [20] opened from local
Mar 23 14:53 : client: [21] opened from local
Mar 23 14:53 : client: [22] opened from local
Mar 23 14:53 : client: [23] opened from local
Mar 23 14:53 : client: [24] opened from local
Mar 23 14:53 : client: [25] opened from local
Mar 23 14:53 : client: Max connections reached
(repeated 18 times)
Mar 23 14:53 : update: added USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/01 If I Had My Way.flac
Mar 23 14:53 : update: updating USB/1TB/flac/0_nativedsd/196-SSP110413D64-acoustic-trio-dsd-sessions-flac96-2ch/02 Ohlone Dream.flac
Mar 23 14:54 : client: Max connections reached
Mar 23 14:54 : client: Max connections reached
(repeated many times)
Mar 23 14:59 : client: Max connections reached
Mar 23 15:09 : client: [25] closed
Mar 23 15:09 : client: [24] closed
Mar 23 15:09 : client: [23] closed
Mar 23 15:09 : client: [22] closed
Mar 23 15:09 : client: [21] closed
Mar 23 15:09 : client: [20] closed
Mar 23 15:09 : client: [19] closed
Mar 23 15:09 : client: [18] closed
Mar 23 15:09 : client: [17] closed
Mar 23 15:09 : client: [16] closed
Mar 23 15:09 : client: [15] closed
Mar 23 15:09 : client: [14] closed
Mar 23 15:09 : client: [13] closed
Mar 23 15:09 : client: [12] closed
Mar 23 15:09 : client: [11] closed
Mar 23 15:09 : client: [10] closed
Mar 23 15:09 : client: [7] closed
Mar 23 15:09 : client: [9] closed
Mar 23 15:09 : client: [1] closed
Mar 23 15:09 : client: [0] closed
Mar 23 15:10 : client: [26] opened from 127.0.0.1:32928
Mar 23 15:10 : client: [27] opened from local
journalctl
Mar 23 14:50:48 volumio systemd[28161]: Startup finished in 60ms.
Mar 23 14:50:48 volumio systemd[1]: Started User Manager for UID 1000.
Mar 23 14:52:46 volumio volumio[1192]: error: MPD error: Error: read ECONNRESET
Mar 23 14:53:50 volumio volumio[1192]: error: MPD error: Error: This socket has been ended by the other party
(repeats 34 times)
Mar 23 14:53:51 volumio volumio[1192]: error: MPD error: Error: This socket has been ended by the other party
Mar 23 14:53:55 volumio volumio[1192]: error: MPD error: false == true
Mar 23 14:53:55 volumio volumio[1192]: error: MPD error: false == true
(repeats many times)
Mar 23 14:59:19 volumio volumio[1192]: error: MPD error: false == true
...
Mar 23 15:09:36 volumio sudo[30779]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl stop volumio.service
Mar 23 15:09:36 volumio sudo[30779]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Mar 23 15:09:36 volumio systemd[1]: Stopping Volumio Backend Module...
Mar 23 15:09:36 volumio systemd[1]: Stopped Volumio Backend Module.
Mar 23 15:09:36 volumio sudo[30779]: pam_unix(sudo:session): session closed for user root
Mar 23 15:09:36 volumio volumio-remote-updater[613]: [2021-03-23 15:09:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Mar 23 15:09:36 volumio volumio-remote-updater[613]: [2021-03-23 15:09:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Mar 23 15:09:41 volumio volumio-remote-updater[613]: [2021-03-23 15:09:41] [info] asio async_connect error: system:111 (Connection refused)
Mar 23 15:09:41 volumio volumio-remote-updater[613]: [2021-03-23 15:09:41] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Mar 23 15:09:41 volumio volumio-remote-updater[613]: [2021-03-23 15:09:41] [error] handle_connect error: Underlying Transport Error
Mar 23 15:09:48 volumio volumio-remote-updater[613]: [2021-03-23 15:09:48] [info] asio async_connect error: system:111 (Connection refused)
Mar 23 15:09:48 volumio volumio-remote-updater[613]: [2021-03-23 15:09:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Mar 23 15:09:48 volumio volumio-remote-updater[613]: [2021-03-23 15:09:48] [error] handle_connect error: Underlying Transport Error
Mar 23 15:09:58 volumio volumio-remote-updater[613]: [2021-03-23 15:09:58] [info] asio async_connect error: system:111 (Connection refused)
Mar 23 15:09:58 volumio volumio-remote-updater[613]: [2021-03-23 15:09:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Mar 23 15:09:58 volumio volumio-remote-updater[613]: [2021-03-23 15:09:58] [error] handle_connect error: Underlying Transport Error
Mar 23 15:10:01 volumio sudo[30792]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl start volumio.service