Update Volumio 2.361 : strange behaviour [solved]

Help and Support Help
1

Hi,

I just updated my Raspberry PI B with Volumio 2.361 (from the embedded command on the web UI)

Update happened well, but after reboot, my PI doesn’t stop to play the boot sound, about every 10 seconds

Moreover, i don’t have access to the Web UI : the connection ends on a blank screen, or a “failed connection” screen.

If I launch music play with MPDroid or Ario, it perfectly works, and boot sound is no more played, until I stop the music…

I tried to reboot (by disconnecting power), but nothing changed.

I eventually would like to go back to 2.348, but where can i download it ? Could someone give me the URL to download it ?

Thanks a lot,
Florian

2

updates.volumio.org/pi/volumio/2 … pi.img.zip

3

Many thanks !!

4

There is a better way than reflashing. System, factory reset

Also it would be useful for us to know what goes wrong in your system to fix the problem.
Can you send logs?

Or connect via ssh and type sudo journalctl -f and paste it here

Thanks

5

Not sure whether my problem with this update is linked to what Florian described, but also for me the update was not successful. I did the update via the Web UI. Download was ok, then I clicked restart. After a while nothing happened, neither a start up sound nor I had access to the Web UI. Then I checked with a network scanner whether IP address changed, which in fact was the case, so at least Volumio showed up. But however, also with this IP-address I couldn’t reach the Web UI. Instead of this ERR-CONNECTION_REFUSED was displayed. In difference to Florian I had no success with another player (I tried it with Hi-Fi Cast).

My system/hardware is Raspbi PI3 + Allo Kali + Piano 2.1

Would be glad to get a hint how to fix this. The output of sudo journalctl -f I have attached below
(remark: I’ve stopped the comand with CTRL+C because it seemed to me that it was running in loops)

Btw: How to make a factory reset in this state (I only have access via ssh)?

Thx in advance!

Axel

volumio@volumio:~$ sudo journalctl -f
– Logs begin at Sat 2018-02-10 22:53:35 UTC. –
Feb 11 21:40:00 volumio volumio[2367]: info: Scanning category user_interface
Feb 11 21:40:00 volumio volumio[2367]: info: Scanning into folder /data/plugins/
Feb 11 21:40:00 volumio volumio[2367]: info: Scanning category music_service
Feb 11 21:40:00 volumio volumio[2367]: info: Plugin folders cleanup completed
Feb 11 21:40:00 volumio volumio[2367]: info: Loading plugins from folder /volumi o/app/plugins/
Feb 11 21:40:00 volumio volumio[2367]: info: Loading plugins from folder /data/p lugins/
Feb 11 21:40:00 volumio volumio[2367]: info: Loading plugin “system”…
Feb 11 21:40:00 volumio volumio[2367]: info: Loading plugin “appearance”…
Feb 11 21:40:02 volumio sudo[2388]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US ER=root ; COMMAND=/bin/journalctl -f
Feb 11 21:40:02 volumio sudo[2388]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0)
Feb 11 21:40:02 volumio volumio[2367]: info: Loading plugin “network”…
Feb 11 21:40:02 volumio volumio[2367]: info: Loading plugin “services”…
Feb 11 21:40:02 volumio volumio[2367]: info: Loading plugin “alsa_controller”…
Feb 11 21:40:02 volumio volumio[2367]: info: Loading plugin “wizard”…
Feb 11 21:40:02 volumio volumio[2367]: info: Loading plugin “volumio_command_lin e_client”…
Feb 11 21:40:02 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:02 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:02 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:02 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:02 volumio volumio[2367]: info: Loading plugin “upnp”…
Feb 11 21:40:02 volumio volumio[2367]: info: [1518385202869] Starting Upmpd Daem on
Feb 11 21:40:02 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 11 21:40:02 volumio volumio[2367]: info: Loading plugin “mpd”…
Feb 11 21:40:03 volumio volumio[2367]: info: Loading plugin “upnp_browser”…
Feb 11 21:40:04 volumio volumio[2367]: info: Loading plugin “networkfs”…
Feb 11 21:40:04 volumio volumio[2367]: info: Cannot mount NAS Music at system bo ot, trial number 1 ,retrying in 5 seconds
Feb 11 21:40:04 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 11 21:40:04 volumio volumio[2367]: info: Loading plugin “alarm-clock”…
Feb 11 21:40:04 volumio volumio[2367]: info: Loading plugin “airplay_emulation”. …
Feb 11 21:40:04 volumio volumio[2367]: info: [1518385204840] Starting Shairport Sync
Feb 11 21:40:04 volumio volumio[2367]: info: Loading plugin “last_100”…
Feb 11 21:40:04 volumio volumio[2367]: info: Loading plugin “webradio”…
Feb 11 21:40:04 volumio volumio[2367]: info: Loading plugin “i2s_dacs”…
Feb 11 21:40:04 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 21:40:04 volumio volumio[2367]: info: Loading plugin “volumiodiscovery”… .
Feb 11 21:40:04 volumio volumio[2367]: *** WARNING *** The program ‘node’ uses t he Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:04 volumio node[2367]: *** WARNING *** The program ‘node’ uses the Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:04 volumio node[2367]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 11 21:40:04 volumio node[2367]: *** WARNING *** For more information see <ht tp://0pointer.de/avahi-compat?s=libdns_sd&e=node>
Feb 11 21:40:04 volumio node[2367]: *** WARNING *** The program ‘node’ called ‘D NSServiceRegister()’ which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:04 volumio node[2367]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 11 21:40:04 volumio node[2367]: *** WARNING *** For more information see <ht tp://0pointer.de/avahi-compat?s=libdns_ … ceRegister>
Feb 11 21:40:05 volumio volumio[2367]: *** WARNING *** Please fix your applicati on to use the native API of Avahi!
Feb 11 21:40:05 volumio volumio[2367]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node
Feb 11 21:40:05 volumio volumio[2367]: *** WARNING *** The program ‘node’ called ‘DNSServiceRegister()’ which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:05 volumio volumio[2367]: *** WARNING *** Please fix your applicati on to use the native API of Avahi!
Feb 11 21:40:05 volumio volumio[2367]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister
Feb 11 21:40:05 volumio volumio[2367]: info: Applying required configuration par ameters for plugin volumiodiscovery
Feb 11 21:40:05 volumio volumio[2367]: Discovery: StartAdv! undefined
Feb 11 21:40:05 volumio volumio[2367]: Discovery: Started advertising… Volumio - undefined
Feb 11 21:40:05 volumio volumio[2367]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 11 21:40:05 volumio volumio[2367]: info: Loading plugin “youtube”…
Feb 11 21:40:05 volumio volumio-remote-updater[681]: [2018-02-11 21:40:05] [conn ect] Successful connection
Feb 11 21:40:07 volumio volumio[2367]: module.js:590
Feb 11 21:40:07 volumio volumio[2367]: throw err;
Feb 11 21:40:07 volumio volumio[2367]: ^
Feb 11 21:40:07 volumio volumio[2367]: SyntaxError: /data/plugins/music_service/ youtube/authToken.json: Unexpected end of JSON input
Feb 11 21:40:07 volumio volumio[2367]: at Object.parse (native)
Feb 11 21:40:07 volumio volumio[2367]: at Object.Module._extensions…json (modul e.js:587:27)
Feb 11 21:40:07 volumio volumio[2367]: at Module.load (module.js:487:32)
Feb 11 21:40:07 volumio volumio[2367]: at tryModuleLoad (module.js:446:12)
Feb 11 21:40:07 volumio volumio[2367]: at Function.Module._load (module.js:438:3 )
Feb 11 21:40:07 volumio volumio[2367]: at Module.require (module.js:497:17)
Feb 11 21:40:07 volumio volumio[2367]: at require (internal/module.js:20:19)
Feb 11 21:40:07 volumio volumio[2367]: at Object. (/data/plugins/musi c_service/youtube/index.js:19:13)
Feb 11 21:40:07 volumio volumio[2367]: at Module._compile (module.js:570:32)
Feb 11 21:40:07 volumio volumio[2367]: at Object.Module._extensions…js (module. js:579:10)
Feb 11 21:40:07 volumio volumio[2367]: at Module.load (module.js:487:32)
Feb 11 21:40:07 volumio volumio[2367]: at tryModuleLoad (module.js:446:12)
Feb 11 21:40:07 volumio volumio[2367]: at Function.Module._load (module.js:438:3 )
Feb 11 21:40:07 volumio volumio[2367]: at Module.require (module.js:497:17)
Feb 11 21:40:07 volumio volumio[2367]: at require (internal/module.js:20:19)
Feb 11 21:40:07 volumio volumio[2367]: at PluginManager.loadPlugin (/volumio/app /pluginmanager.js:117:25)
Feb 11 21:40:07 volumio volumio-remote-updater[681]: [2018-02-11 21:40:07] [info ] asio async_read_at_least error: system:104 (Connection reset by peer)
Feb 11 21:40:07 volumio systemd[1]: volumio.service: main process exited, code=e xited, status=1/FAILURE
Feb 11 21:40:07 volumio systemd[1]: Unit volumio.service entered failed state.
Feb 11 21:40:07 volumio systemd[1]: Starting dynamicswap service…
Feb 11 21:40:07 volumio systemd[1]: Started dynamicswap service.
Feb 11 21:40:07 volumio volumio-remote-updater[681]: [2018-02-11 21:40:07] [erro r] handle_read_http_response error: websocketpp.transport:2 (Underlying Transpor t Error)
Feb 11 21:40:07 volumio volumio-remote-updater[681]: [2018-02-11 21:40:07] [info ] Error getting remote endpoint: system:107 (Transport endpoint is not connected )
Feb 11 21:40:07 volumio systemd[1]: volumio.service holdoff time over, schedulin g restart.
Feb 11 21:40:07 volumio systemd[1]: Starting dynamicswap service…
Feb 11 21:40:07 volumio systemd[1]: Started dynamicswap service.
Feb 11 21:40:07 volumio systemd[1]: Stopping Volumio Backend Module…
Feb 11 21:40:07 volumio systemd[1]: Starting Volumio Backend Module…
Feb 11 21:40:07 volumio systemd[1]: Started Volumio Backend Module.
Feb 11 21:40:11 volumio volumio[2415]: info: ----------------------------------- --------
Feb 11 21:40:11 volumio volumio[2415]: info: ----- Volumio2 ----
Feb 11 21:40:11 volumio volumio[2415]: info: ----------------------------------- --------
Feb 11 21:40:11 volumio volumio[2415]: info: ----- System startup ----
Feb 11 21:40:11 volumio volumio[2415]: info: ----------------------------------- --------
Feb 11 21:40:11 volumio volumio[2415]: info: Plugin folders cleanup
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning into folder /volumio/app/p lugins/
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning category audio_interface
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning category miscellanea
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning category music_service
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning category plugins.json
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning category system_controller
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning category user_interface
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning into folder /data/plugins/
Feb 11 21:40:11 volumio volumio[2415]: info: Scanning category music_service
Feb 11 21:40:11 volumio volumio[2415]: info: Plugin folders cleanup completed
Feb 11 21:40:11 volumio volumio[2415]: info: Loading plugins from folder /volumi o/app/plugins/
Feb 11 21:40:11 volumio volumio[2415]: info: Loading plugins from folder /data/p lugins/
Feb 11 21:40:11 volumio volumio[2415]: info: Loading plugin “system”…
Feb 11 21:40:12 volumio volumio[2415]: info: Loading plugin “appearance”…
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “network”…
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “services”…
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “alsa_controller”…
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “wizard”…
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “volumio_command_lin e_client”…
Feb 11 21:40:14 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:14 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:14 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:14 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “upnp”…
Feb 11 21:40:14 volumio volumio[2415]: info: [1518385214554] Starting Upmpd Daem on
Feb 11 21:40:14 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “mpd”…
Feb 11 21:40:14 volumio volumio[2415]: info: Loading plugin “upnp_browser”…
Feb 11 21:40:15 volumio volumio[2415]: info: Loading plugin “networkfs”…
Feb 11 21:40:15 volumio volumio[2415]: info: Cannot mount NAS Music at system bo ot, trial number 1 ,retrying in 5 seconds
Feb 11 21:40:15 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 11 21:40:15 volumio volumio[2415]: info: Loading plugin “alarm-clock”…
Feb 11 21:40:16 volumio volumio[2415]: info: Loading plugin “airplay_emulation”. …
Feb 11 21:40:16 volumio volumio[2415]: info: [1518385216356] Starting Shairport Sync
Feb 11 21:40:16 volumio volumio[2415]: info: Loading plugin “last_100”…
Feb 11 21:40:16 volumio volumio[2415]: info: Loading plugin “webradio”…
Feb 11 21:40:16 volumio volumio[2415]: info: Loading plugin “i2s_dacs”…
Feb 11 21:40:16 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 11 21:40:16 volumio volumio[2415]: info: Loading plugin “volumiodiscovery”…
Feb 11 21:40:16 volumio volumio[2415]: *** WARNING *** The program ‘node’ uses t he Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:16 volumio node[2415]: *** WARNING *** The program ‘node’ uses the Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:16 volumio node[2415]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 11 21:40:16 volumio node[2415]: *** WARNING *** For more information see <ht tp://0pointer.de/avahi-compat?s=libdns_sd&e=node>
Feb 11 21:40:16 volumio node[2415]: *** WARNING *** The program ‘node’ called ‘D NSServiceRegister()’ which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:16 volumio node[2415]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 11 21:40:16 volumio node[2415]: *** WARNING *** For more information see <ht tp://0pointer.de/avahi-compat?s=libdns_ … ceRegister>
Feb 11 21:40:16 volumio volumio[2415]: *** WARNING *** Please fix your applicati on to use the native API of Avahi!
Feb 11 21:40:16 volumio volumio[2415]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node
Feb 11 21:40:16 volumio volumio[2415]: *** WARNING *** The program ‘node’ called ‘DNSServiceRegister()’ which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 11 21:40:16 volumio volumio[2415]: *** WARNING *** Please fix your applicati on to use the native API of Avahi!
Feb 11 21:40:16 volumio volumio[2415]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister
Feb 11 21:40:16 volumio volumio[2415]: info: Applying required configuration par ameters for plugin volumiodiscovery
Feb 11 21:40:16 volumio volumio[2415]: Discovery: StartAdv! undefined
Feb 11 21:40:16 volumio volumio[2415]: Discovery: Started advertising… Volumio - undefined
Feb 11 21:40:16 volumio volumio[2415]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 11 21:40:16 volumio volumio[2415]: info: Loading plugin “youtube”…
Feb 11 21:40:17 volumio volumio-remote-updater[681]: [2018-02-11 21:40:17] [conn ect] Successful connection
Feb 11 21:40:19 volumio volumio[2415]: module.js:590
Feb 11 21:40:19 volumio volumio[2415]: throw err;
Feb 11 21:40:19 volumio volumio[2415]: ^

6

Hi Michelangelo,

I went too fast and already flashed rev 2.348, sorry.
But I promise to try getting the logs or journal end of next week.

Can you explain me how to make a factory reset without access to the web UI ?

I would like also to take the opportunity of this post to thank you so much for this masterful piece of software.
I use it since the first release of Raspifi. It’s a huge and great work you did here.
The last upgrade of my system with Kali & Piano 2.1 gave me chills !

Florian

7

Thank you for your kind words Florian!
I was able to get the logs, and this issue only affects Piano 2.1 users, I am fixing as we speak.
When you’ll see a new release out, you will know that’s fixed

8

Just to finish off the thread, it is possible to effect a factory reset from a ssh session by adding an empty file called factory_reset to /boot, for example by:

sudo touch /boot/factory_reset sync reboot

Note that factory resets return your system to the FIRST image that you flashed, rather than the last installed version.

9

New image released :wink:

10

Thanks a lot, guys !

When I see how things are working in my company, I’m still really impressed by such reactivity :smiley:

I will unfortunately not be able to test it before Wednesday, but whatever, thanks again !

11

Many thanks also from my side!

Will give it a try tonight

@Florian: looks like we are working for the same company :wink:

12

Update - after factory reset via ssh and update from v2.348 to v2.362 all works fine again.

Great work! Thanks!

:smiley: :smiley: :smiley:

13

Back from business trip.

And like Axel, update to 2.362 works perfectly.

Thanks !