[SOLVED] Xbian-config addon "check for system updates" stuck

[Roy Terhaar]

Software
XBian version: 20161221-0
XBMC/Kodi version:17.3-Git:2017-05-26-1521bf9
Overclock settings:default

Hardware
Device type and model (e.g. Raspberry Pi Model A/B 256/512 MB, CuBox-i i4Pro, ...):RPI 3
Power supply rating:Original power supply adapter
SD card size and make/type:16Gb
Network (Ethernet or wireless):Kodi using Ethernet; wireless adapter active too on different network
Connected devices (TV, USB, network storage, ...):Philips TV, External USB harddisks Seagate and Hitachi

Log files
Will post any if needed...

Problem description:
When I try running "Check for system updates" via kodi gui xbian-config addon in menu "Update", the addon will freeze in the splash screen "Please wait... Retrieving list of upgradeable packages...".

How to reproduce:
However if I stop dnsmasq (sudo service dnsmasq stop) that is also running on the same raspberry pi, the update check seems to continue and ends as you would expect and returns back to the menu.
Dnsmasq is used by all my network devices effectively configured in my router.
However I have excluded the raspberry pi from using dnsmasq by setting a static network ip address and configuring use of google dns servers locally. This seems to be ok; if I check /etc/resolv.conf it displays the google dns servers.
Also, on command-line sudo apt-get update and apt-get upgrade works just fine.
So it seems that the addon is doing something extra or different than the command line commands.

I have checked several log files, but I have not found any info that can solve the freeze problem of the addon; the only workaround I have now is stopping the dnsmasq service and once update check is done starting the service again.

Can anybody help me or give some pointers to solve my problem?

Thanks.

[Nachteule]

Hmmm, that's strange

I know some users using Pi-Hole (that uses dnsmasq too) without such issues.

Did you already try that this Pi using dnsmasq as DNS resolver too?

Without logs it is very difficult to give any tips. Please enable debug messages (set DEBUG variable in file /etc/default/xbmc to DEBUG="--debug" and restart Kodi) and try again

Interesting part are three lines:

Terminal

root@kmxbilr2 ~ # cat /home/xbian/.kodi/temp/kodi.log | grep xbian-config\ updates

Aug 7 10:51:46 kmxbilr2 843 496.572235 T:1825567488 DEBUG: XBian-config : xbian-config updates updatedb : ['0']
Aug 7 10:51:46 kmxbilr2 970 496.699066 T:1825567488 DEBUG: XBian-config : xbian-config updates distupgrade query : ['0']
Aug 7 10:51:47 kmxbilr2 133 496.861481 T:1825567488 DEBUG: XBian-config : xbian-config updates list packages : ['-2']

Those three commands are called when processing "Check for system updates"

You can run them from cmdline as well (note: if you're not root you have to use sudo):

Terminal

root@kmxbilr2 ~ # xbian-config updates updatedb
0
root@kmxbilr2 ~ # xbian-config updates distupgrade query
0
root@kmxbilr2 ~ # xbian-config updates list packages
-2
root@kmxbilr2 ~ #

[Roy Terhaar]

Nachteule, thanks for your response.

Actually I am running pi-hole (which runs just fine) on the raspberry pi.
I have set the DEBUG option and generated the kodi.log file; please find below the interesting part of it.
It looks like the three commands that you mentioned are being executed and completed.
Last log line until freeze is at 20:37:24.272.
After approx. 10 minutes, I stopped dnsmasq and the log continues starting at 20:47:22.529 and onwards...
I don't understand from the log file what happened and why the software was waiting on something that had anything to do with dnsmasq or maybe it is something completely different?

20:32:59.795 109225.757812 T:1953832960 DEBUG: ------ Window Init (Pointer.xml) ------
20:33:02.262 109228.226562 T:1953832960 DEBUG: ProcessMouse: trying mouse action leftclick
20:33:02.262 109228.226562 T:1953832960 ERROR: Keymapping error: no such action '-' defined
20:33:02.262 109228.226562 T:1953832960 DEBUG: ExecuteXBMCAction: Tried translating, but failed to understand -
20:33:02.264 109228.226562 T:1706554288 DEBUG: XBian-config : Loading value for Update
20:33:02.265 109228.226562 T:1714942896 DEBUG: XBian-config : inthread : Loading value for Update
20:33:02.265 109228.226562 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> XBian
20:33:02.265 109228.226562 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> XBian
20:33:02.283 109228.250000 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> Updates
20:33:02.284 109228.250000 T:1706554288 DEBUG: XBian-config : Loading value thread started for Update
20:33:02.400 109228.367188 T:1714942896 DEBUG: XBian-config : xbian-config updates distupgrade query : ['0']
20:33:03.910 109229.875000 T:1714942896 DEBUG: XBian-config : xbian-config updates list packages : ['-2']
20:33:03.931 109229.898438 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> Updates
20:33:03.932 109229.898438 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> XBian
20:33:03.932 109229.898438 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> XBian
20:33:03.932 109229.898438 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> Staging repository
20:33:04.067 109230.031250 T:1714942896 DEBUG: XBian-config : xbian-config updates enablerepo staging : ['1']
20:33:04.071 109230.039062 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> Staging repository
20:33:04.076 109230.039062 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> Devel repository
20:33:04.214 109230.179688 T:1714942896 DEBUG: XBian-config : xbian-config updates enablerepo devel : ['0']
20:33:04.246 109230.210938 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> Devel repository
20:33:04.288 109230.250000 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> XBian
20:33:04.288 109230.250000 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> XBian
20:33:04.288 109230.250000 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> Auto update package inventory
20:33:04.412 109230.375000 T:1714942896 DEBUG: XBian-config : xbian-config updates autoinventory : ['1 1']
20:33:04.447 109230.414062 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> Auto update package inventory
20:33:04.447 109230.414062 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> Automatic updates
20:33:04.572 109230.539062 T:1714942896 DEBUG: XBian-config : xbian-config updates enableauto : ['0']
20:33:04.575 109230.539062 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> Automatic updates
20:33:04.616 109230.578125 T:1714942896 DEBUG: XBian-config : inthread : Start Loading setting value for Update -> Update
20:33:04.738 109230.703125 T:1714942896 DEBUG: XBian-config : xbian-config updates snapapt : ['1']
20:33:04.743 109230.710938 T:1714942896 DEBUG: XBian-config : inthread : Stop Loading setting value for Update -> Update
20:33:04.746 109230.710938 T:1714942896 DEBUG: XBian-config : End inthread : Loading value for Update
20:33:07.570 109233.531250 T:1953832960 DEBUG: ProcessMouse: trying mouse action leftclick
20:33:07.610 109233.578125 T:1953832960 DEBUG: ------ Window Init (/usr/local/share/kodi/addons/plugin.xbianconfig/resources/skins/skin.estuary/xml/DialogWait.xml) ------
20:33:07.610 109233.578125 T:1953832960 INFO: Loading skin file: /usr/local/share/kodi/addons/plugin.xbianconfig/resources/skins/skin.estuary/xml/DialogWait.xml, load type: LOAD_ON_GUI_INIT
20:33:07.617 109233.578125 T:1953832960 DEBUG: CGUIMediaWindow::GetDirectory ()
20:33:07.617 109233.578125 T:1953832960 DEBUG: ParentPath = []
20:33:12.619 109238.585938 T:1953832960 DEBUG: ------ Window Deinit (Pointer.xml) ------
20:33:17.330 109243.296875 T:1904210864 DEBUG: CAESinkPi:Drain delay:100ms now:0ms
20:33:27.330 109253.296875 T:1904210864 DEBUG: CAESinkPi:Deinitialize
20:33:27.331 109253.296875 T:1904210864 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
20:33:27.340 109253.304688 T:1904210864 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x71929b28
20:37:22.259 109488.226562 T:1742873520 NOTICE: script.tv.show.next.aired: ### no prior data found
20:37:24.259 109490.226562 T:1742873520 NOTICE: script.tv.show.next.aired: ### starting data update
20:37:24.259 109490.226562 T:1742873520 DEBUG: script.tv.show.next.aired: ### search language = "en"
20:37:24.272 109490.234375 T:1742873520 DEBUG: RunQuery took 2 ms for 0 items query: SELECT * FROM tvshow_view
20:47:22.529 110088.492188 T:1742873520 NOTICE: script.tv.show.next.aired: ### no prior data found
20:47:24.529 110090.492188 T:1742873520 NOTICE: script.tv.show.next.aired: ### starting data update
20:47:24.529 110090.492188 T:1742873520 DEBUG: script.tv.show.next.aired: ### search language = "en"
20:47:25.015 110090.976562 T:1742873520 DEBUG: RunQuery took 2 ms for 0 items query: SELECT * FROM tvshow_view
20:47:33.032 110099.000000 T:1706554288 DEBUG: XBian-config : xbian-config updates updatedb : ['0']
20:47:33.158 110099.125000 T:1706554288 DEBUG: XBian-config : xbian-config updates distupgrade query : ['0']
20:47:33.307 110099.273438 T:1527509936 DEBUG: <Event dir=False mask=0x2 maskname=IN_MODIFY name=cache.db path=/home/xbian/.kodi/userdata/addon_data/plugin.xbianconfig pathname=/home/xbian/.kodi/userdata/addon_data/plugin.xbianconfig/cache.db wd=3 >
20:47:33.496 110099.460938 T:1706554288 DEBUG: XBian-config : xbian-config updates list packages : ['-2']
20:47:33.767 110099.734375 T:1953832960 DEBUG: ------ Window Init (DialogNotification.xml) ------
20:47:34.264 110100.226562 T:1953832960 DEBUG: ------ Window Deinit (/usr/local/share/kodi/addons/plugin.xbianconfig/resources/skins/skin.estuary/xml/DialogWait.xml) ------
20:47:44.987 110110.953125 T:1953832960 DEBUG: ------ Window Deinit (DialogNotification.xml) ------

[Nachteule]

Thank you for your response.

System stucks in first command (xbian-config updates updatedb) - that's what I assumed

Already found place where the bug sits in need some time to fix it probably tomorrow evening

[Roy Terhaar]

(8th Aug, 2017 06:44 AM)Nachteule Wrote:  Thank you for your response.

System stucks in first command (xbian-config updates updatedb) - that's what I assumed

Already found place where the bug sits in need some time to fix it probably tomorrow evening

That's great news. Looking forward to your update! Thanks in advance.

[Nachteule]

Should be fixed now. New package xbian-package-config-shell released

[Roy Terhaar]

(9th Aug, 2017 04:30 AM)Nachteule Wrote:  Should be fixed now. New package xbian-package-config-shell released

I have just installed your fix.
After installing I ran "Check for system updates again" (with service dnsmasq running) and now it returns as per normal back to the update menu.
So it seems to be fixed.
Once another new update can be loaded I will test again just to have that scenario too...
Thanks for your help! Can be updated to [solved].

[Nachteule]

Done. Thanks for confirming