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
volumio
November 2, 2018, 10:43pm
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?
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.
volumio
December 6, 2018, 5:49pm
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.
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”
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
volumio
December 7, 2018, 2:53pm
27
Can you please try with the latest version (released today?)
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
Michelangelo, have you found a solution to the problem?
I have tested the new version but it does not work properly.
massi
December 18, 2018, 4:52pm
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: -------------------------------------------
klodespe:
Michelangelo, have you found a solution to the problem?
I have tested the new version but it does not work properly.
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…
volumio
December 26, 2018, 11:38am
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?
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.
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.
klodespe:
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.
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.
T1b
January 31, 2019, 3:48pm
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
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.
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
Wow, we thought to have solved this issue for good.
Can you update to latest version?
Which browser are you using?
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