Web interface is constantly rebooting since 2.452

Volumio Development Bug reports
21

Hello, I just did.

Formatted my SD card (16GB). And re-imaged it with Win32DiskImager.
Unfortunetely, I even didn’t got the time to finish the first setup wizard and the browser crashes again.

I did manage to send the logs via the dev page.
URL of the log: logs.volumio.org/volumio/C8w1Mt4.html

22

Thanks for the log. Unfortunately this is the first time I see this error.l,and have no clue on what happens. On which device are you using it? Can you close all your browser window when launching volumio?

23

Hello Michelangelo,

I use it on a Rpi 3. Connected to the official 7" touch display. If I install the touchscreen plugin, I see the same behaviour on that screen.

24

Those are 2 distinct errors. Quite hard to troubleshoot.

One is related to socket.io, and I really have no clue on what triggers it.

The second one is a failed parsing on an albumart from a server which probably sends incorrect info…
Will try a tentative fix for the latter.

25

Hello,
I have the same problem.

I have installed Volumio 2.502 on Raspberry 3 whith Etcher, then I have open UI by Chrome and every 30 second the UI restart.
I haven’t install Plugins…

You can see the same problem by console if you send a command when the UI restart
Example…
I have sent this command: “volumio volume 10”
and the sentence are: “curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused”

26

This is my log (it also happens in the initial wizzard):

Dec 07 14:04:32 volumio sudo[14115]: pam_unix(sudo:session): session closed for user root Dec 07 14:04:32 volumio sudo[14122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 07 14:04:32 volumio sudo[14122]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:04:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 07 14:04:32 volumio sudo[14122]: pam_unix(sudo:session): session closed for user root Dec 07 14:04:32 volumio volumio[13930]: info: Upmpdcli Daemon Started Dec 07 14:04:36 volumio volumio-remote-updater[681]: [2018-12-07 14:04:36] [connect] Successful connection Dec 07 14:04:36 volumio volumio-remote-updater[681]: [2018-12-07 14:04:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1544191476 101 Dec 07 14:04:48 volumio sudo[14238]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Dec 07 14:04:48 volumio sudo[14238]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 07 14:04:54 volumio volumio[13930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 14:04:54 volumio volumio[13930]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 14:04:54 volumio volumio[13930]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 14:04:54 volumio volumio[13930]: TypeError: Cannot create property 'type' on string 'invalid server key' Dec 07 14:04:54 volumio volumio[13930]: at WebSocket.onError (/volumio/node_modules/ws/lib/WebSocket.js:450:16) Dec 07 14:04:54 volumio volumio[13930]: at emitOne (events.js:116:13) Dec 07 14:04:54 volumio volumio[13930]: at WebSocket.emit (events.js:211:7) Dec 07 14:04:54 volumio volumio[13930]: at ClientRequest.upgrade (/volumio/node_modules/ws/lib/WebSocket.js:738:12) Dec 07 14:04:54 volumio volumio[13930]: at Object.onceWrapper (events.js:319:30) Dec 07 14:04:54 volumio volumio[13930]: at emitThree (events.js:136:13) Dec 07 14:04:54 volumio volumio[13930]: at ClientRequest.emit (events.js:217:7) Dec 07 14:04:54 volumio volumio[13930]: at Socket.socketOnData (_http_client.js:472:11) Dec 07 14:04:54 volumio volumio[13930]: at emitOne (events.js:116:13) Dec 07 14:04:54 volumio volumio[13930]: at Socket.emit (events.js:211:7) Dec 07 14:04:54 volumio volumio[13930]: at addChunk (_stream_readable.js:263:12) Dec 07 14:04:54 volumio volumio[13930]: at readableAddChunk (_stream_readable.js:250:11) Dec 07 14:04:54 volumio volumio[13930]: at Socket.Readable.push (_stream_readable.js:208:10) Dec 07 14:04:54 volumio volumio[13930]: at TCP.onread (net.js:607:20) Dec 07 14:04:54 volumio volumio[13930]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 14:04:55 volumio sudo[14298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2018-12-07 14:03 Dec 07 14:04:55 volumio sudo[14298]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:04:55 volumio sudo[14298]: pam_unix(sudo:session): session closed for user root Dec 07 14:04:55 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Dec 07 14:04:55 volumio volumio-remote-updater[681]: [2018-12-07 14:04:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 07 14:04:55 volumio volumio-remote-updater[681]: [2018-12-07 14:04:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 07 14:04:55 volumio systemd[1]: Unit volumio.service entered failed state. Dec 07 14:04:55 volumio systemd[1]: Starting dynamicswap service... Dec 07 14:04:55 volumio systemd[1]: Started dynamicswap service. Dec 07 14:04:55 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Dec 07 14:04:55 volumio systemd[1]: Starting dynamicswap service... Dec 07 14:04:55 volumio systemd[1]: Started dynamicswap service. Dec 07 14:04:55 volumio systemd[1]: Stopping Volumio Backend Module... Dec 07 14:04:55 volumio systemd[1]: Starting Volumio Backend Module... Dec 07 14:04:55 volumio systemd[1]: Started Volumio Backend Module. Dec 07 14:04:57 volumio volumio[14320]: info: ------------------------------------------- Dec 07 14:04:57 volumio volumio[14320]: info: ----- Volumio2 ---- Dec 07 14:04:57 volumio volumio[14320]: info: ------------------------------------------- Dec 07 14:04:57 volumio volumio[14320]: info: ----- System startup ---- Dec 07 14:04:57 volumio volumio[14320]: info: ------------------------------------------- Dec 07 14:04:57 volumio volumio[14320]: info: Plugin folders cleanup Dec 07 14:04:57 volumio volumio[14320]: info: Scanning into folder /volumio/app/plugins/ Dec 07 14:04:57 volumio volumio[14320]: info: Scanning category audio_interface Dec 07 14:04:57 volumio volumio[14320]: info: Scanning category miscellanea Dec 07 14:04:57 volumio volumio[14320]: info: Scanning category music_service Dec 07 14:04:57 volumio volumio[14320]: info: Scanning category plugins.json Dec 07 14:04:57 volumio volumio[14320]: info: Scanning category system_controller Dec 07 14:04:57 volumio volumio[14320]: info: Scanning category user_interface Dec 07 14:04:57 volumio volumio[14320]: info: Scanning into folder /data/plugins/ Dec 07 14:04:57 volumio volumio[14320]: info: Plugin folders cleanup completed Dec 07 14:04:57 volumio volumio[14320]: info: ------------------------------------------- Dec 07 14:04:57 volumio volumio[14320]: info: ----- Core plugins startup ---- Dec 07 14:04:57 volumio volumio[14320]: info: ------------------------------------------- Dec 07 14:04:57 volumio volumio[14320]: info: Loading plugins from folder /volumio/app/plugins/ Dec 07 14:04:57 volumio volumio[14320]: info: Adding plugin upnp to MyMusic Plugins Dec 07 14:04:57 volumio volumio[14320]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 07 14:04:57 volumio volumio[14320]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 07 14:04:57 volumio volumio[14320]: info: Loading plugins from folder /data/plugins/ Dec 07 14:04:57 volumio volumio[14320]: info: Loading plugin "system"... Dec 07 14:04:57 volumio volumio[14320]: info: Loading plugin "appearance"... Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "network"... Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "services"... Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "alsa_controller"... Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "wizard"... Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "volumio_command_line_client"... Dec 07 14:04:58 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:04:58 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:04:58 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:04:58 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "upnp"... Dec 07 14:04:58 volumio volumio[14320]: info: [1544191498949] Starting Upmpd Daemon Dec 07 14:04:58 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "my_music"... Dec 07 14:04:58 volumio volumio[14320]: info: Loading plugin "mpd"... Dec 07 14:04:59 volumio volumio[14320]: info: Loading plugin "upnp_browser"... Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "networkfs"... Dec 07 14:05:00 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "alarm-clock"... Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "airplay_emulation"... Dec 07 14:05:00 volumio volumio[14320]: info: Starting Shairport Sync Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "last_100"... Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "webradio"... Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "i2s_dacs"... Dec 07 14:05:00 volumio volumio[14320]: info: I2S DAC not set, start Auto-detection Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "volumiodiscovery"... Dec 07 14:05:00 volumio volumio[14320]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 14:05:00 volumio node[14320]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 14:05:00 volumio node[14320]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 14:05:00 volumio node[14320]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node> Dec 07 14:05:00 volumio node[14320]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 14:05:00 volumio node[14320]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 14:05:00 volumio node[14320]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister> Dec 07 14:05:00 volumio volumio[14320]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 14:05:00 volumio volumio[14320]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node> Dec 07 14:05:00 volumio volumio[14320]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 14:05:00 volumio volumio[14320]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 14:05:00 volumio volumio[14320]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister> Dec 07 14:05:00 volumio volumio[14320]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 07 14:05:00 volumio volumio[14320]: Discovery: StartAdv! undefined Dec 07 14:05:00 volumio volumio[14320]: Discovery: Started advertising... Volumio - undefined Dec 07 14:05:00 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "albumart"... Dec 07 14:05:00 volumio volumio[14320]: info: Plugin example_plugin is not enabled Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "updater_comm"... Dec 07 14:05:00 volumio volumio-remote-updater[681]: [2018-12-07 14:05:00] [connect] Successful connection Dec 07 14:05:00 volumio volumio[14320]: info: Plugin mpdemulation is not enabled Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "rest_api"... Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "websocket"... Dec 07 14:05:00 volumio volumio[14320]: info: ___________ START PLUGINS ___________ Dec 07 14:05:00 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 14:05:00 volumio volumio[14320]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 14:05:00 volumio volumio[14320]: info: [1544191500636] CoreMusicLibrary::Adding element Media Servers Dec 07 14:05:00 volumio volumio[14320]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 14:05:00 volumio volumio[14320]: info: [1544191500668] CoreMusicLibrary::Adding element Last_100 Dec 07 14:05:00 volumio volumio[14320]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 14:05:00 volumio volumio[14320]: info: [1544191500671] CoreMusicLibrary::Adding element Webradio Dec 07 14:05:00 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 14:05:00 volumio volumio[14320]: info: ------------------------------------------- Dec 07 14:05:00 volumio volumio[14320]: info: ----- MyVolumio plugins startup ---- Dec 07 14:05:00 volumio volumio[14320]: info: ------------------------------------------- Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugins from folder /myvolumio/plugins Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugins from folder /data/myvolumio/plugins Dec 07 14:05:00 volumio volumio[14320]: info: Loading plugin "my_volumio"... Dec 07 14:05:00 volumio volumio[14320]: Forking 3 albumart workers Dec 07 14:05:01 volumio volumio[14320]: info: Loading plugin "streaming_services"... Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: Streaming services startup Dec 07 14:05:01 volumio volumio[14320]: info: Loading i18n strings for locale it Dec 07 14:05:01 volumio volumio[14320]: Updating browse sources language Dec 07 14:05:01 volumio volumio[14320]: Cannot find translation for sourceMedia Servers Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::initPlayerControls Dec 07 14:05:01 volumio sudo[14377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 07 14:05:01 volumio sudo[14377]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:01 volumio systemd[1]: Stopping Volumio Streaming Daemon... Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 14:05:01 volumio systemd[1]: Starting Volumio Streaming Daemon... Dec 07 14:05:01 volumio systemd[1]: Started Volumio Streaming Daemon. Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 14:05:01 volumio sudo[14377]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:01 volumio volumio[14320]: info: BOOT COMPLETED Dec 07 14:05:01 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 14:05:01 volumio volumio-streaming-daemon[14385]: ############################ Dec 07 14:05:01 volumio volumio-streaming-daemon[14385]: # Volumio Streaming Daemon # Dec 07 14:05:01 volumio volumio-streaming-daemon[14385]: # Running on port 7777 # Dec 07 14:05:01 volumio volumio-streaming-daemon[14385]: ############################ Dec 07 14:05:01 volumio volumio[14320]: Starting albumart workers Dec 07 14:05:01 volumio volumio[14320]: Starting albumart workers Dec 07 14:05:01 volumio volumio[14320]: Starting albumart workers Dec 07 14:05:06 volumio volumio[14320]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 07 14:05:06 volumio volumio[14320]: info: CoreCommandRouter::Close All Modals sent Dec 07 14:05:06 volumio volumio[14320]: info: CoreCommandRouter::Close All Modals sent Dec 07 14:05:06 volumio volumio[14320]: Express server listening on port 3000 Dec 07 14:05:06 volumio volumio[14320]: Volumio Calling Home Dec 07 14:05:07 volumio volumio[14320]: info: MyVolumio not started Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::resetVolumioState Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::getcurrentVolume Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::updateTrackBlock Dec 07 14:05:07 volumio volumio[14320]: info: CorePlayQueue::getTrackBlock Dec 07 14:05:07 volumio volumio[14320]: info: Volumio called home Dec 07 14:05:07 volumio volumio[14320]: info: Setting Device type: Raspberry PI Dec 07 14:05:07 volumio volumio[14320]: info: MPD running with PID709 ,establishing connection Dec 07 14:05:07 volumio sudo[14408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Dec 07 14:05:07 volumio sudo[14408]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:07 volumio sudo[14408]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:07 volumio volumio[14320]: info: VolumeController:: Volume=29 Mute =false Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::pushState Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::getState Dec 07 14:05:07 volumio volumio[14320]: info: CorePlayQueue::getTrack 0 Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::volumioPushState Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 14:05:07 volumio volumio[14320]: info: Reloading queue from file Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::setRepeat null single undefined Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::pushState Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::getState Dec 07 14:05:07 volumio volumio[14320]: info: CorePlayQueue::getTrack 0 Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::volumioPushState Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::setRandom null Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::pushState Dec 07 14:05:07 volumio volumio[14320]: info: CoreStateMachine::getState Dec 07 14:05:07 volumio volumio[14320]: info: CorePlayQueue::getTrack 0 Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::volumioPushState Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 14:05:07 volumio sudo[14419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Dec 07 14:05:07 volumio volumio[14320]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 14:05:07 volumio sudo[14422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Dec 07 14:05:07 volumio sudo[14419]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:08 volumio sudo[14422]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:08 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Dec 07 14:05:08 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Dec 07 14:05:08 volumio volumio[14320]: info: CoreCommandRouter::volumioGetState Dec 07 14:05:08 volumio volumio[14320]: info: CoreStateMachine::getState Dec 07 14:05:08 volumio systemd[1]: Started ShairportSync AirTunes receiver. Dec 07 14:05:08 volumio volumio[14320]: info: CorePlayQueue::getTrack 0 Dec 07 14:05:08 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 14:05:08 volumio volumio[14320]: info: Listing playlists Dec 07 14:05:08 volumio volumio[14320]: info: CoreCommandRouter::volumioGetQueue Dec 07 14:05:08 volumio volumio[14320]: info: CoreStateMachine::getQueue Dec 07 14:05:08 volumio shairport-sync[14433]: Successful Startup Dec 07 14:05:08 volumio volumio[14320]: info: CorePlayQueue::getQueue Dec 07 14:05:08 volumio sudo[14419]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:08 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Dec 07 14:05:08 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Dec 07 14:05:08 volumio systemd[1]: Started ShairportSync AirTunes receiver. Dec 07 14:05:08 volumio sudo[14422]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:08 volumio shairport-sync[14440]: Successful Startup Dec 07 14:05:08 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 07 14:05:08 volumio volumio[14320]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 07 14:05:08 volumio volumio[14320]: info: Shairport-Sync Started Dec 07 14:05:08 volumio volumio[14320]: Error adding Membership: Error: addMembership EINVAL Dec 07 14:05:08 volumio volumio[14320]: info: Shairport-Sync Started Dec 07 14:05:08 volumio volumio[14320]: Discovery: adding 4c6aa139-b923-4211-b3d7-b2de43c4bc3d Dec 07 14:05:08 volumio volumio[14320]: info: mDNS: Found device Volumio Dec 07 14:05:08 volumio sudo[14449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 14:05:08 volumio sudo[14449]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:08 volumio volumio[14320]: Discovery: this is already registered, 4c6aa139-b923-4211-b3d7-b2de43c4bc3d Dec 07 14:05:08 volumio volumio[14320]: info: mDNS: Found device Volumio Dec 07 14:05:08 volumio sudo[14449]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:08 volumio sudo[14458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 14:05:08 volumio sudo[14458]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:08 volumio sudo[14458]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:08 volumio volumio[14320]: info: CoreCommandRouter::volumioGetState Dec 07 14:05:08 volumio volumio[14320]: info: CoreStateMachine::getState Dec 07 14:05:08 volumio volumio[14320]: info: CorePlayQueue::getTrack 0 Dec 07 14:05:08 volumio sudo[14467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 14:05:08 volumio sudo[14467]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:08 volumio sudo[14467]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:08 volumio sudo[14484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 14:05:08 volumio sudo[14484]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:09 volumio sudo[14484]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:09 volumio sudo[14494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 07 14:05:09 volumio sudo[14494]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 14:05:09 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 07 14:05:09 volumio sudo[14494]: pam_unix(sudo:session): session closed for user root Dec 07 14:05:09 volumio volumio[14320]: info: Upmpdcli Daemon Started Dec 07 14:05:13 volumio volumio-remote-updater[681]: [2018-12-07 14:05:13] [connect] Successful connection Dec 07 14:05:13 volumio volumio-remote-updater[681]: [2018-12-07 14:05:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocke

27

Can you please try with the latest version (released today?)

28

I have try the latest version, but the problem is the same.

Here you are a new log:

Dec 07 16:27:00 volumio volumio[5782]: info: Upmpdcli Daemon Started Dec 07 16:27:03 volumio volumio-remote-updater[661]: [2018-12-07 16:27:03] [connect] Successful connection Dec 07 16:27:03 volumio volumio-remote-updater[661]: [2018-12-07 16:27:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1544200023 101 Dec 07 16:27:21 volumio volumio[5782]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 16:27:21 volumio volumio[5782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:27:21 volumio volumio[5782]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 16:27:21 volumio volumio[5782]: TypeError: Cannot create property 'type' on string 'invalid server key' Dec 07 16:27:21 volumio volumio[5782]: at WebSocket.onError (/volumio/node_modules/ws/lib/WebSocket.js:450:16) Dec 07 16:27:21 volumio volumio[5782]: at emitOne (events.js:116:13) Dec 07 16:27:21 volumio volumio[5782]: at WebSocket.emit (events.js:211:7) Dec 07 16:27:21 volumio volumio[5782]: at ClientRequest.upgrade (/volumio/node_modules/ws/lib/WebSocket.js:738:12) Dec 07 16:27:21 volumio volumio[5782]: at Object.onceWrapper (events.js:319:30) Dec 07 16:27:21 volumio volumio[5782]: at emitThree (events.js:136:13) Dec 07 16:27:21 volumio volumio[5782]: at ClientRequest.emit (events.js:217:7) Dec 07 16:27:21 volumio volumio[5782]: at Socket.socketOnData (_http_client.js:472:11) Dec 07 16:27:21 volumio volumio[5782]: at emitOne (events.js:116:13) Dec 07 16:27:21 volumio volumio[5782]: at Socket.emit (events.js:211:7) Dec 07 16:27:21 volumio volumio[5782]: at addChunk (_stream_readable.js:263:12) Dec 07 16:27:21 volumio volumio[5782]: at readableAddChunk (_stream_readable.js:250:11) Dec 07 16:27:21 volumio volumio[5782]: at Socket.Readable.push (_stream_readable.js:208:10) Dec 07 16:27:21 volumio volumio[5782]: at TCP.onread (net.js:607:20) Dec 07 16:27:21 volumio volumio[5782]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 16:27:22 volumio sudo[6130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2018-12-07 16:26 Dec 07 16:27:22 volumio sudo[6130]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:22 volumio sudo[6130]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:22 volumio volumio-remote-updater[661]: [2018-12-07 16:27:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 07 16:27:22 volumio volumio-remote-updater[661]: [2018-12-07 16:27:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 07 16:27:22 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Dec 07 16:27:22 volumio systemd[1]: Unit volumio.service entered failed state. Dec 07 16:27:22 volumio systemd[1]: Starting dynamicswap service... Dec 07 16:27:22 volumio systemd[1]: Started dynamicswap service. Dec 07 16:27:22 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Dec 07 16:27:22 volumio systemd[1]: Starting dynamicswap service... Dec 07 16:27:22 volumio systemd[1]: Started dynamicswap service. Dec 07 16:27:22 volumio systemd[1]: Stopping Volumio Backend Module... Dec 07 16:27:22 volumio systemd[1]: Starting Volumio Backend Module... Dec 07 16:27:22 volumio systemd[1]: Started Volumio Backend Module. Dec 07 16:27:24 volumio volumio[6151]: info: ------------------------------------------- Dec 07 16:27:24 volumio volumio[6151]: info: ----- Volumio2 ---- Dec 07 16:27:24 volumio volumio[6151]: info: ------------------------------------------- Dec 07 16:27:24 volumio volumio[6151]: info: ----- System startup ---- Dec 07 16:27:24 volumio volumio[6151]: info: ------------------------------------------- Dec 07 16:27:24 volumio volumio[6151]: info: Plugin folders cleanup Dec 07 16:27:24 volumio volumio[6151]: info: Scanning into folder /volumio/app/plugins/ Dec 07 16:27:24 volumio volumio[6151]: info: Scanning category audio_interface Dec 07 16:27:24 volumio volumio[6151]: info: Scanning category miscellanea Dec 07 16:27:24 volumio volumio[6151]: info: Scanning category music_service Dec 07 16:27:24 volumio volumio[6151]: info: Scanning category plugins.json Dec 07 16:27:24 volumio volumio[6151]: info: Scanning category system_controller Dec 07 16:27:24 volumio volumio[6151]: info: Scanning category user_interface Dec 07 16:27:24 volumio volumio[6151]: info: Scanning into folder /data/plugins/ Dec 07 16:27:24 volumio volumio[6151]: info: Plugin folders cleanup completed Dec 07 16:27:24 volumio volumio[6151]: info: ------------------------------------------- Dec 07 16:27:24 volumio volumio[6151]: info: ----- Core plugins startup ---- Dec 07 16:27:24 volumio volumio[6151]: info: ------------------------------------------- Dec 07 16:27:24 volumio volumio[6151]: info: Loading plugins from folder /volumio/app/plugins/ Dec 07 16:27:24 volumio volumio[6151]: info: Adding plugin upnp to MyMusic Plugins Dec 07 16:27:24 volumio volumio[6151]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 07 16:27:24 volumio volumio[6151]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 07 16:27:24 volumio volumio[6151]: info: Loading plugins from folder /data/plugins/ Dec 07 16:27:24 volumio volumio[6151]: info: Loading plugin "system"... Dec 07 16:27:24 volumio volumio[6151]: info: Loading plugin "appearance"... Dec 07 16:27:25 volumio volumio[6151]: info: Loading plugin "network"... Dec 07 16:27:25 volumio volumio[6151]: info: Loading plugin "services"... Dec 07 16:27:25 volumio volumio[6151]: info: Loading plugin "alsa_controller"... Dec 07 16:27:26 volumio volumio[6151]: info: Loading plugin "wizard"... Dec 07 16:27:26 volumio volumio[6151]: info: Loading plugin "volumio_command_line_client"... Dec 07 16:27:26 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:26 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:26 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:26 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:26 volumio volumio[6151]: info: Loading plugin "upnp"... Dec 07 16:27:26 volumio volumio[6151]: info: [1544200046125] Starting Upmpd Daemon Dec 07 16:27:26 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 16:27:26 volumio volumio[6151]: info: Loading plugin "my_music"... Dec 07 16:27:26 volumio volumio[6151]: info: Loading plugin "mpd"... Dec 07 16:27:26 volumio volumio[6151]: info: Loading plugin "upnp_browser"... Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "networkfs"... Dec 07 16:27:27 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "alarm-clock"... Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "airplay_emulation"... Dec 07 16:27:27 volumio volumio[6151]: info: Starting Shairport Sync Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "last_100"... Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "webradio"... Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "i2s_dacs"... Dec 07 16:27:27 volumio volumio[6151]: info: I2S DAC not set, start Auto-detection Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "volumiodiscovery"... Dec 07 16:27:27 volumio volumio[6151]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 16:27:27 volumio node[6151]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 07 16:27:27 volumio node[6151]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 16:27:27 volumio node[6151]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node> Dec 07 16:27:27 volumio node[6151]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 16:27:27 volumio node[6151]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 16:27:27 volumio node[6151]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister> Dec 07 16:27:27 volumio volumio[6151]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 16:27:27 volumio volumio[6151]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node> Dec 07 16:27:27 volumio volumio[6151]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 07 16:27:27 volumio volumio[6151]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 07 16:27:27 volumio volumio[6151]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister> Dec 07 16:27:27 volumio volumio[6151]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 07 16:27:27 volumio volumio[6151]: Discovery: StartAdv! undefined Dec 07 16:27:27 volumio volumio[6151]: Discovery: Started advertising... Volumio - undefined Dec 07 16:27:27 volumio volumio-remote-updater[661]: [2018-12-07 16:27:27] [connect] Successful connection Dec 07 16:27:27 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "albumart"... Dec 07 16:27:27 volumio volumio[6151]: info: Plugin example_plugin is not enabled Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "updater_comm"... Dec 07 16:27:27 volumio volumio[6151]: info: Plugin mpdemulation is not enabled Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "rest_api"... Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "websocket"... Dec 07 16:27:27 volumio volumio[6151]: info: ___________ START PLUGINS ___________ Dec 07 16:27:27 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 16:27:27 volumio volumio[6151]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:27:27 volumio volumio[6151]: info: [1544200047877] CoreMusicLibrary::Adding element Media Servers Dec 07 16:27:27 volumio volumio[6151]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:27:27 volumio volumio[6151]: info: [1544200047911] CoreMusicLibrary::Adding element Last_100 Dec 07 16:27:27 volumio volumio[6151]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 07 16:27:27 volumio volumio[6151]: info: [1544200047915] CoreMusicLibrary::Adding element Webradio Dec 07 16:27:27 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 16:27:27 volumio volumio[6151]: info: ------------------------------------------- Dec 07 16:27:27 volumio volumio[6151]: info: ----- MyVolumio plugins startup ---- Dec 07 16:27:27 volumio volumio[6151]: info: ------------------------------------------- Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugins from folder /myvolumio/plugins Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugins from folder /data/myvolumio/plugins Dec 07 16:27:27 volumio volumio[6151]: info: Loading plugin "my_volumio"... Dec 07 16:27:28 volumio volumio[6151]: Forking 3 albumart workers Dec 07 16:27:28 volumio volumio[6151]: info: Loading plugin "streaming_services"... Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: Streaming services startup Dec 07 16:27:28 volumio volumio[6151]: info: Loading i18n strings for locale it Dec 07 16:27:28 volumio volumio[6151]: Updating browse sources language Dec 07 16:27:28 volumio volumio[6151]: Cannot find translation for sourceMedia Servers Dec 07 16:27:28 volumio sudo[6210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::initPlayerControls Dec 07 16:27:28 volumio sudo[6210]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:28 volumio systemd[1]: Stopping Volumio Streaming Daemon... Dec 07 16:27:28 volumio systemd[1]: Starting Volumio Streaming Daemon... Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:28 volumio systemd[1]: Started Volumio Streaming Daemon. Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 07 16:27:28 volumio sudo[6210]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:28 volumio volumio-streaming-daemon[6216]: ############################ Dec 07 16:27:28 volumio volumio-streaming-daemon[6216]: # Volumio Streaming Daemon # Dec 07 16:27:28 volumio volumio-streaming-daemon[6216]: # Running on port 7777 # Dec 07 16:27:28 volumio volumio-streaming-daemon[6216]: ############################ Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 16:27:28 volumio volumio[6151]: info: BOOT COMPLETED Dec 07 16:27:28 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:27:29 volumio volumio[6151]: Starting albumart workers Dec 07 16:27:29 volumio volumio[6151]: Starting albumart workers Dec 07 16:27:29 volumio volumio[6151]: Starting albumart workers Dec 07 16:27:34 volumio volumio[6151]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 07 16:27:34 volumio volumio[6151]: info: CoreCommandRouter::Close All Modals sent Dec 07 16:27:34 volumio volumio[6151]: info: CoreCommandRouter::Close All Modals sent Dec 07 16:27:34 volumio volumio[6151]: Express server listening on port 3000 Dec 07 16:27:34 volumio volumio[6151]: Volumio Calling Home Dec 07 16:27:34 volumio volumio[6151]: info: MyVolumio not started Dec 07 16:27:34 volumio volumio[6151]: info: CoreStateMachine::resetVolumioState Dec 07 16:27:34 volumio volumio[6151]: info: CoreStateMachine::getcurrentVolume Dec 07 16:27:34 volumio volumio[6151]: info: CoreCommandRouter::volumioRetrievevolume Dec 07 16:27:34 volumio volumio[6151]: info: CoreStateMachine::updateTrackBlock Dec 07 16:27:34 volumio volumio[6151]: info: CorePlayQueue::getTrackBlock Dec 07 16:27:34 volumio volumio[6151]: info: Cannot read play queue from file Dec 07 16:27:34 volumio volumio[6151]: info: Volumio called home Dec 07 16:27:34 volumio volumio[6151]: info: Setting Device type: Raspberry PI Dec 07 16:27:34 volumio volumio[6151]: info: MPD running with PID1896 ,establishing connection Dec 07 16:27:34 volumio sudo[6242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Dec 07 16:27:34 volumio sudo[6242]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:34 volumio sudo[6242]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:34 volumio volumio[6151]: info: VolumeController:: Volume=40 Mute =false Dec 07 16:27:34 volumio volumio[6151]: info: CoreStateMachine::pushState Dec 07 16:27:34 volumio volumio[6151]: info: CoreStateMachine::getState Dec 07 16:27:34 volumio volumio[6151]: info: CorePlayQueue::getTrack 0 Dec 07 16:27:34 volumio volumio[6151]: info: CoreCommandRouter::volumioPushState Dec 07 16:27:34 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 16:27:35 volumio volumio[6151]: info: Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::setRepeat null single undefined Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::pushState Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::getState Dec 07 16:27:35 volumio volumio[6151]: info: CorePlayQueue::getTrack 0 Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::volumioPushState Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::setRandom null Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::pushState Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::getState Dec 07 16:27:35 volumio volumio[6151]: info: CorePlayQueue::getTrack 0 Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::volumioPushState Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 16:27:35 volumio sudo[6253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 16:27:35 volumio sudo[6253]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:35 volumio sudo[6255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Dec 07 16:27:35 volumio sudo[6255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:35 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::volumioGetState Dec 07 16:27:35 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::getState Dec 07 16:27:35 volumio volumio[6151]: info: CorePlayQueue::getTrack 0 Dec 07 16:27:35 volumio systemd[1]: Started ShairportSync AirTunes receiver. Dec 07 16:27:35 volumio sudo[6253]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:35 volumio sudo[6255]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 07 16:27:35 volumio volumio[6151]: info: Listing playlists Dec 07 16:27:35 volumio shairport-sync[6265]: Successful Startup Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 07 16:27:35 volumio volumio[6151]: info: Shairport-Sync Started Dec 07 16:27:35 volumio volumio[6151]: Error adding Membership: Error: addMembership EINVAL Dec 07 16:27:35 volumio volumio[6151]: info: Shairport-Sync Started Dec 07 16:27:35 volumio volumio[6151]: Discovery: adding 57ec9d30-5880-45e6-84d3-f30d06dee663 Dec 07 16:27:35 volumio volumio[6151]: info: mDNS: Found device Volumio Dec 07 16:27:35 volumio sudo[6278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 16:27:35 volumio sudo[6278]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:35 volumio sudo[6278]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:35 volumio volumio[6151]: Discovery: this is already registered, 57ec9d30-5880-45e6-84d3-f30d06dee663 Dec 07 16:27:35 volumio volumio[6151]: info: mDNS: Found device Volumio Dec 07 16:27:35 volumio sudo[6288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 16:27:35 volumio sudo[6288]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:35 volumio sudo[6288]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:35 volumio volumio[6151]: info: CoreCommandRouter::volumioGetState Dec 07 16:27:35 volumio volumio[6151]: info: CoreStateMachine::getState Dec 07 16:27:35 volumio volumio[6151]: info: CorePlayQueue::getTrack 0 Dec 07 16:27:36 volumio sudo[6297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 16:27:36 volumio sudo[6297]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:36 volumio sudo[6297]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:36 volumio sudo[6307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 07 16:27:36 volumio sudo[6307]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:36 volumio sudo[6307]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:36 volumio sudo[6323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 07 16:27:36 volumio sudo[6323]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 16:27:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 07 16:27:36 volumio sudo[6323]: pam_unix(sudo:session): session closed for user root Dec 07 16:27:36 volumio volumio[6151]: info: Upmpdcli Daemon Started Dec 07 16:27:40 volumio volumio-remote-updater[661]: [2018-12-07 16:27:40] [connect] Successful connection Dec 07 16:27:40 volumio volumio-remote-updater[661]: [2018-12-07 16:27:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1544200060 101

29

Michelangelo, have you found a solution to the problem?

I have tested the new version but it does not work properly.

30

Hi I have a similar problem. I turn off volumio hotspot and then the web interface stop working with these logs. Volumio crashes and start again and again.
I solved changing ssh password with “sudo passwd” back to default “volumio” and restarting. I do the same for plugin updates. Maybe this could help don’t know because logs are quite different

dic 18 16:34:14 bagno volumio[6863]: info: -------------------------------------------
dic 18 16:34:14 bagno volumio[6863]: info: -----            Volumio2              ----
dic 18 16:34:14 bagno volumio[6863]: info: -------------------------------------------
dic 18 16:34:14 bagno volumio[6863]: info: -----          System startup          ----
dic 18 16:34:14 bagno volumio[6863]: info: -------------------------------------------
dic 18 16:34:14 bagno volumio[6863]: info: Plugin folders cleanup
dic 18 16:34:14 bagno volumio[6863]: info: Scanning into folder /volumio/app/plugins/
dic 18 16:34:14 bagno volumio[6863]: info: Scanning category audio_interface
dic 18 16:34:14 bagno volumio[6863]: info: Scanning category miscellanea
dic 18 16:34:14 bagno volumio[6863]: info: Scanning category music_service
dic 18 16:34:15 bagno volumio[6863]: info: Scanning category plugins.json
dic 18 16:34:15 bagno volumio[6863]: info: Scanning category system_controller
dic 18 16:34:15 bagno volumio[6863]: info: Scanning category user_interface
dic 18 16:34:15 bagno volumio[6863]: info: Scanning into folder /data/plugins/
dic 18 16:34:15 bagno volumio[6863]: info: Scanning category miscellanea
dic 18 16:34:15 bagno volumio[6863]: info: Scanning category music_service
dic 18 16:34:15 bagno volumio[6863]: info: Plugin folders cleanup completed
dic 18 16:34:15 bagno volumio[6863]: info: -------------------------------------------
dic 18 16:34:15 bagno volumio[6863]: info: -----      Core plugins startup        ----
dic 18 16:34:15 bagno volumio[6863]: info: -------------------------------------------
dic 18 16:34:15 bagno volumio[6863]: info: Loading plugins from folder /volumio/app/plugins/
dic 18 16:34:15 bagno volumio[6863]: info: Adding plugin upnp to MyMusic Plugins
dic 18 16:34:15 bagno volumio[6863]: info: Adding plugin airplay_emulation to MyMusic Plugins
dic 18 16:34:15 bagno volumio[6863]: info: Adding plugin upnp_browser to MyMusic Plugins
dic 18 16:34:15 bagno volumio[6863]: info: Loading plugins from folder /data/plugins/
dic 18 16:34:15 bagno volumio[6863]: info: Loading plugin "system"...
dic 18 16:34:15 bagno volumio[6863]: info: Loading plugin "appearance"...
dic 18 16:34:15 bagno volumio[6863]: info: Loading plugin "network"...
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "services"...
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "alsa_controller"...
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "wizard"...
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "volumio_command_line_client"...
dic 18 16:34:16 bagno volumio[6863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
dic 18 16:34:16 bagno volumio[6863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
dic 18 16:34:16 bagno volumio[6863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
dic 18 16:34:16 bagno volumio[6863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "upnp"...
dic 18 16:34:16 bagno volumio[6863]: info: [1545150856201] Starting Upmpd Daemon
dic 18 16:34:16 bagno volumio[6863]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "my_music"...
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "mpd"...
dic 18 16:34:16 bagno volumio[6863]: info: Loading plugin "upnp_browser"...
dic 18 16:34:17 bagno volumio[6863]: info: Loading plugin "networkfs"...
dic 18 16:34:23 bagno volumio-remote-updater[532]: [2018-12-18 16:34:23] [connect] Successful connection
dic 18 16:34:38 bagno volumio-remote-updater[532]: [2018-12-18 16:34:38] [connect] Successful connection
dic 18 16:34:47 bagno volumio[6863]: fs.js:885
dic 18 16:34:47 bagno volumio[6863]: return binding.mkdir(pathModule._makeLong(path),
dic 18 16:34:47 bagno volumio[6863]: ^
dic 18 16:34:47 bagno volumio[6863]: Error: EEXIST: file already exists, mkdir '/mnt/NAS/Musica'
dic 18 16:34:47 bagno volumio[6863]: at Object.fs.mkdirSync (fs.js:885:18)
dic 18 16:34:47 bagno volumio[6863]: at Object.exports.mount (/volumio/node_modules/linux-mountutils/mountutils.js:108:10)
dic 18 16:34:47 bagno volumio[6863]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:234:12)
dic 18 16:34:47 bagno volumio[6863]: at ControllerNetworkfs.initShares (/volumio/app/plugins/system_controller/networkfs/index.js:165:8)
dic 18 16:34:47 bagno volumio[6863]: at ControllerNetworkfs.onVolumioStart (/volumio/app/plugins/system_controller/networkfs/index.js:39:7)
dic 18 16:34:47 bagno volumio[6863]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:163:35)
dic 18 16:34:47 bagno volumio[6863]: at /volumio/app/pluginmanager.js:255:30
dic 18 16:34:47 bagno volumio[6863]: at Array.forEach (<anonymous>)
dic 18 16:34:47 bagno volumio[6863]: at HashMap.<anonymous> (/volumio/app/pluginmanager.js:254:17)
dic 18 16:34:47 bagno volumio[6863]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:172:10)
dic 18 16:34:47 bagno volumio[6863]: at HashMap.proto.(anonymous function) [as forEach] (/volumio/node_modules/hashmap/hashmap.js:184:7)
dic 18 16:34:47 bagno volumio[6863]: at PluginManager.loadCorePlugins (/volumio/app/pluginmanager.js:252:17)
dic 18 16:34:47 bagno volumio[6863]: at PluginManager.startPlugins (/volumio/app/pluginmanager.js:80:10)
dic 18 16:34:47 bagno volumio[6863]: at new CoreCommandRouter (/volumio/app/index.js:62:24)
dic 18 16:34:47 bagno volumio[6863]: at Object.<anonymous> (/volumio/index.js:32:21)
dic 18 16:34:47 bagno volumio[6863]: at Module._compile (module.js:652:30)
dic 18 16:34:47 bagno systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE
dic 18 16:34:47 bagno systemd[1]: Unit volumio.service entered failed state.
dic 18 16:34:47 bagno systemd[1]: Starting dynamicswap service...
dic 18 16:34:47 bagno systemd[1]: Started dynamicswap service.
dic 18 16:34:48 bagno systemd[1]: volumio.service holdoff time over, scheduling restart.
dic 18 16:34:48 bagno systemd[1]: Starting dynamicswap service...
dic 18 16:34:48 bagno systemd[1]: Started dynamicswap service.
dic 18 16:34:48 bagno systemd[1]: Stopping Volumio Backend Module...
dic 18 16:34:48 bagno systemd[1]: Starting Volumio Backend Module...
dic 18 16:34:48 bagno systemd[1]: Started Volumio Backend Module.
dic 18 16:34:49 bagno volumio[6903]: info: -------------------------------------------
dic 18 16:34:49 bagno volumio[6903]: info: -----            Volumio2              ----
dic 18 16:34:49 bagno volumio[6903]: info: -------------------------------------------
dic 18 16:34:49 bagno volumio[6903]: info: -----          System startup          ----
dic 18 16:34:49 bagno volumio[6903]: info: -------------------------------------------
31

I can confirm that also with version 2.513 the problem remains the same.
I updated a new log here: logs.volumio.org/volumio/qNxm62y.html

I was just going through the initial wizard setup. (after clean install of 2.513)

PS: Where can I download previous versions? My VolumioPlayer is gathering too many dust now…

32

Very nasty issue, I can’t understand why it does that. Which browser are you using? If you use a different browser does it have the same issue?

33

I use most of the time FF, but tried now with Chrome. It doesn’t matter. I have also the official 7" touchscreen connected. And when I could make it to enable the touchscreen plugin. I would see the same behavior on the touchscreen.

34

I think that the problem is not in the software, because I solved when I changed the office.
The first tests I did in the office and I had the problem, but when I connected it at home the problem disappeared.

35

I can assume that the Volumio office did not have access to the Internet.
If so, I have a similar problem.

ЗЫ. Sorry for my english.

36

Hi,

after updating 2.526 I see this crashing issue as well.
I’ve already deactivated all the plugins, and removed the mounted NAS drive, but the problem keeps coming back. What I see in the log( http://logs.volumio.org/volumio/iTX5wNK.html):

Jan 31 15:37:52 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 31 15:37:52 volumio volumio[1096]: info: CURURI: music-library Jan 31 15:37:52 volumio volumio[1096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 31 15:37:52 volumio volumio[1096]: TypeError: Cannot read property 'then' of undefined Jan 31 15:37:52 volumio volumio[1096]: at ControllerMpd.lsInfo (/volumio/app/plugins/music_service/mpd/index.js:1205:16) Jan 31 15:37:52 volumio volumio[1096]: at ControllerMpd.handleBrowseUri (/volumio/app/plugins/music_service/mpd/index.js:2893:25) Jan 31 15:37:52 volumio volumio[1096]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1018:29) Jan 31 15:37:52 volumio volumio[1096]: at CoreMusicLibrary.executeBrowseSource (/volumio/app/musiclibrary.js:329:47) Jan 31 15:37:52 volumio volumio[1096]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:363:32) Jan 31 15:37:52 volumio volumio[1096]: at emitTwo (events.js:126:13) Jan 31 15:37:52 volumio volumio[1096]: at Socket.emit (events.js:214:7) Jan 31 15:37:52 volumio volumio[1096]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 31 15:37:52 volumio volumio[1096]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jan 31 15:37:52 volumio volumio[1096]: at process._tickCallback (internal/process/next_tick.js:180:9) Jan 31 15:37:52 volumio volumio[1096]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||

What might be causing this problem, is there a known workaround?

Thanks

37

Hello,

I tried the latest release 2.555, and it is still the same.

I got by accident hand of another RPi3. I don’t know if there are more HW versions of the RPi3. But both here are the same. “RPi3 Model B V1.2”

Both RPi’s are showing the same behaviour. I swapped also the SD cards, but no difference.

38

I’m still struggling with this issue on two RPI3’s.

Managed to send a log file. Here is the link.

logs.volumio.org/volumio/Js8pzqS.html

currently on 2.609

39

Wow, we thought to have solved this issue for good.
Can you update to latest version?
Which browser are you using?

40

I update to the latest version. Installed on the same SD card also first NOOBS to check if that works well. But that’s ok.

I’m using latest version of Chrome.

New log: logs.volumio.org/volumio/toVQDgy.html