Boot fail: hangs at LibreELEC beta splash screen [LE 7.95.2]

LE23 · February 10, 2017 at 7:03 PM

Kodi splash screen never appears
gdb terminates & restarts every few seconds in endless loop
issue was seen on Pi v3 and Play v1

some possibly relevant dmesg excerpts...

Code

[    7.281063@0] wetek-dvb dvb.8: Checking for Panasonic MN88436 ATSC demod ...
[    7.288212@0] i2c i2c-1: MN88436 ATSC successfully attached
[    7.293821@0] i2c i2c-1: MxL603 detected id(02)
[    7.298185@0] i2c i2c-1: Attaching MxL603
[    7.302136@0] wetek-dvb dvb.8: Total Wetek NIM(s) found: 1
[    7.308583@0] UMP: UMP device driver -7.90.001-2112-g47c4ea6 loaded
[    7.314876@0] dummy_codec_platform_probe
[    7.318248@0] enter aml_dai_probe
[    7.318688@0] enter dummy_codec_audio_probe
[    7.322117@0] soc-audio soc-audio: ASoC: machine AML-DUMMY-CODEC should use snd_soc_register_card()
[    7.331368@0] rtd ee91a010
[    7.334605@0] cpu_dai eeb62500
[    7.337857@0] codec_dai eeb62700
[    7.341084@0] enter aml_platform_pcm_new (codec_dai: dummy_codec 1 cpu_dai: aml-dai0 0)
[    7.341680@0] aml-pcm 0:playback preallocate_dma_buffer: area=eff22000, addr=ad700000, size=131072
[    7.350570@0] iec958 0: preallocate dma buffer start=eff44000, size=80000
[    7.356632@0] aml-pcm 1:capture preallocate_dma_buffer: area=effc5000, addr=adc80000, size=65536
[    7.364739@0] soc-audio soc-audio:  dummy_codec <-> aml-dai0 mapping ok
[    7.372335@0] =dummy_codec_device_init==,dummy_codec_audio init done


[    7.720334@0]   #0: AML-DUMMY-CODEC
[    7.727689@0] Freeing unused kernel memory: 4376K (c07a1000 - c0be7000)


[    7.727695@1] ------------[ cut here ]------------
[    7.727721@1] WARNING: at kernel/smp.c:382 smp_call_function_many+0xec/0x2d0()
[    7.727738@1] Modules linked in:
[    7.727752@1] CPU: 1 PID: 647 Comm: cfinteractive Not tainted 3.10.99 #1
[    7.727804@1] [<c0013c60>] (unwind_backtrace+0x0/0xe8) from [<c0011598>] (show_stack+0x10/0x14)
[    7.727823@1] [<c0011598>] (show_stack+0x10/0x14) from [<c002a030>] (warn_slowpath_common+0x54/0x6c)
[    7.727831@1] [<c002a030>] (warn_slowpath_common+0x54/0x6c) from [<c002a0e4>] (warn_slowpath_null+0x1c/0x24)
[    7.727840@1] [<c002a0e4>] (warn_slowpath_null+0x1c/0x24) from [<c006fd04>] (smp_call_function_many+0xec/0x2d0)
[    7.727849@1] [<c006fd04>] (smp_call_function_many+0xec/0x2d0) from [<c006ff0c>] (smp_call_function+0x24/0x2c)
[    7.727856@1] [<c006ff0c>] (smp_call_function+0x24/0x2c) from [<c001a798>] (clk_set_rate_a9+0xb8/0xe0)
[    7.727863@1] [<c001a798>] (clk_set_rate_a9+0xb8/0xe0) from [<c001ac88>] (meson_clk_set_rate+0xb0/0x190)
[    7.727869@1] [<c001ac88>] (meson_clk_set_rate+0xb0/0x190) from [<c001ae08>] (clk_set_rate+0xa0/0xc8)
[    7.727879@1] [<c001ae08>] (clk_set_rate+0xa0/0xc8) from [<c042ac38>] (meson_cpufreq_target_locked.constprop.2+0x114/0x1c4)
[    7.727888@1] [<c042ac38>] (meson_cpufreq_target_locked.constprop.2+0x114/0x1c4) from [<c042ad0c>] (meson_cpufreq_target+0x24/0x3c)
[    7.727898@1] [<c042ad0c>] (meson_cpufreq_target+0x24/0x3c) from [<c038cb4c>] (cpufreq_interactive_speedchange_task+0x174/0x190)
[    7.727918@1] [<c038cb4c>] (cpufreq_interactive_speedchange_task+0x174/0x190) from [<c0049794>] (kthread+0xb4/0xb8)
[    7.727927@1] [<c0049794>] (kthread+0xb4/0xb8) from [<c000df00>] (ret_from_fork+0x14/0x34)
[    7.727931@1] ---[ end trace b248d5dd858a4b40 ]---


[    7.727936@1] ------------[ cut here ]------------
[    7.727942@1] WARNING: at kernel/smp.c:244 smp_call_function_single+0x154/0x19c()
[    7.727944@1] Modules linked in:
[    7.727948@1] CPU: 1 PID: 647 Comm: cfinteractive Tainted: G        W    3.10.99 #1
[    7.727957@1] [<c0013c60>] (unwind_backtrace+0x0/0xe8) from [<c0011598>] (show_stack+0x10/0x14)
[    7.727965@1] [<c0011598>] (show_stack+0x10/0x14) from [<c002a030>] (warn_slowpath_common+0x54/0x6c)
[    7.727973@1] [<c002a030>] (warn_slowpath_common+0x54/0x6c) from [<c002a0e4>] (warn_slowpath_null+0x1c/0x24)
[    7.727980@1] [<c002a0e4>] (warn_slowpath_null+0x1c/0x24) from [<c006facc>] (smp_call_function_single+0x154/0x19c)
[    7.727986@1] [<c006facc>] (smp_call_function_single+0x154/0x19c) from [<c006ff0c>] (smp_call_function+0x24/0x2c)
[    7.727992@1] [<c006ff0c>] (smp_call_function+0x24/0x2c) from [<c001a798>] (clk_set_rate_a9+0xb8/0xe0)
[    7.727998@1] [<c001a798>] (clk_set_rate_a9+0xb8/0xe0) from [<c001ac88>] (meson_clk_set_rate+0xb0/0x190)
[    7.728003@1] [<c001ac88>] (meson_clk_set_rate+0xb0/0x190) from [<c001ae08>] (clk_set_rate+0xa0/0xc8)
[    7.728010@1] [<c001ae08>] (clk_set_rate+0xa0/0xc8) from [<c042ac38>] (meson_cpufreq_target_locked.constprop.2+0x114/0x1c4)
[    7.728016@1] [<c042ac38>] (meson_cpufreq_target_locked.constprop.2+0x114/0x1c4) from [<c042ad0c>] (meson_cpufreq_target+0x24/0x3c)
[    7.728023@1] [<c042ad0c>] (meson_cpufreq_target+0x24/0x3c) from [<c038cb4c>] (cpufreq_interactive_speedchange_task+0x174/0x190)
[    7.728030@1] [<c038cb4c>] (cpufreq_interactive_speedchange_task+0x174/0x190) from [<c0049794>] (kthread+0xb4/0xb8)
[    7.728037@1] [<c0049794>] (kthread+0xb4/0xb8) from [<c000df00>] (ret_from_fork+0x14/0x34)
[    7.728039@1] ---[ end trace b248d5dd858a4b41 ]---


[   23.793258@1] changed the filter setting to :4
[   26.304838@1] enter aml_platform_open (codec_dai: dummy_codec 1 cpu_dai: aml-dai0 0)
[   26.304889@1] substream#0 ptr: ee961c00 type: playback name: subdevice #0 interface: I2S
[   26.305912@1] enter aml_platform_close (codec_dai: dummy_codec 1 cpu_dai: aml-dai0 0)
[   30.877753@1] i2c i2c-1: Uploading demod firmware (dvb-fe-mn88436-atsc.fw)...
[   30.949648@1] i2c i2c-1: Uploading demod pseq (dvb-fe-mn88436-pseq.fw)...
[   31.101359@0] wetek-dvb dvb.8: DVB: adapter 0 frontend 0 frequency 0 out of range (51000000..858000000)


****


[   36.665268@0] enter aml_platform_open (codec_dai: dummy_codec 1 cpu_dai: aml-dai0 0)
[   36.665318@0] substream#0 ptr: ee961c00 type: playback name: subdevice #0 interface: I2S
[   36.666252@0] enter aml_platform_close (codec_dai: dummy_codec 1 cpu_dai: aml-dai0 0)

Display More

note: last 3 lines in code box above repeat continuously

**lrusak** · February 10, 2017 at 7:18 PM Official Post

Do a clean install?

LE23 · February 10, 2017 at 9:47 PM

Quote from lrusak

Do a clean install?

oh I see, its an in-Kodi issue then, not a shell script error

kodi.log says

Code

21:51:08.843 T:3036082176  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
21:51:08.844 T:3036082176  NOTICE: Loaded playercorefactory configuration
21:51:08.844 T:3036082176  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
21:51:08.844 T:3036082176  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
21:51:08.878 T:3036082176   ERROR: SQL: [Addons27.db] SQL error or missing database
                                            Query: SELECT idVersion FROM version

so here is the problem:
/storage/.kodi/userdata/Database/Addons27.db was zero length

deleted it via SCP and Kodi recreated it immediately by copying Addons26.db to Addons27.db like it should

then Kodi finished loading right away
so... database migration error then. whys it only fail on some systems and not others though?

in any case you can trap this, right?

If Addons27.db = 0 bytes at launch -and- Addons26.db = nonzero, delete/recreate Addons27.db

**Klojum** · February 10, 2017 at 9:55 PM Official Post

Quote from LE23

by copying Addons26.db to Addons27.db like it should

Like it should? I'm guessing Addons27.db will have changes compared to its previous version.
If Addons26.db is not corrupted, just delete Addons27.db so Kodi can upgrade via the normal upgrade procedure.

LE23 · February 10, 2017 at 10:13 PM

Quote from Klojum

Like it should? I'm guessing Addons27.db will have changes compared to its previous version.

yeah, I meant to say "migrate" like it should rather than copy, cause YES, it's not necessarily gonna be an exact copy -- I got that, okay?

Quote

just delete Addons27.db so Kodi can upgrade via the normal upgrade procedure.

That is exactly what happened. I do believe I said that clearly enough:

"deleted it via SCP and Kodi recreated it"

If Kodi recreated it, and that can only occur through auto-migration, it should be obvious the upgrade took place as designed. My question is why did it fail in the first place. If you don't know, consider it a rhetorical question - just something for devs to be aware of. I really did not need to post my solution at all, just trying to help the other guys with the same problem.

LE23 · February 11, 2017 at 11:04 AM

Posting Kodi crash log regarding original update failure

Code

############## kodi CRASH LOG ###############


################ SYSTEM INFO ################
 Date: Fri Feb 10 13:17:02 CST 2017
 kodi Options: --standalone -fs --lircdev /run/lirc/lircd
 Arch: armv7l
 Kernel: Linux 3.10.99 #1 SMP Sun Jan 29 15:14:42 GMT 2017
 Release: LibreELEC 7.95.2
############## END SYSTEM INFO ##############


############### STACK TRACE #################
=====>  Core file: /storage/.cache/cores/core.!usr!lib!kodi!kodi.bin.1486750620.4426
        =========================================
[New LWP 4426]
[New LWP 4428]
[New LWP 4427]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/usr/lib/libthread_db.so.1".
Core was generated by `/usr/lib/kodi/kodi.bin --standalone -fs --lircdev /run/lirc/lircd'.
Program terminated with signal SIGABRT, Aborted.
#0  __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:58
[Current thread is 1 (Thread 0xb4ea8000 (LWP 4426))]


Thread 3 (Thread 0xb4ea73a0 (LWP 4427)):
#0  0xb6eb77a4 in __pthread_cond_wait (cond=0x25da3b0, mutex=<optimized out>;) at pthread_cond_wait.c:186
#1  0x008bdf50 in ANNOUNCEMENT::CAnnouncementManager::Process() ()
#2  0x005b5aac in CThread::Action() ()
#3  0x005b61a4 in CThread::staticThread(void*) ()
#4  0xb6eb0f5c in start_thread (arg=0xb4ea73a0) at pthread_create.c:335
#5  0xb52cfbc8 in ?? () at ../sysdeps/unix/sysv/linux/arm/clone.S:86 from /usr/lib/libc.so.6
Backtrace stopped: previous frame identical to this frame (corrupt stack?)


Thread 2 (Thread 0xb44ff3a0 (LWP 4428)):
#0  0xb52c7154 in poll () at ../sysdeps/unix/syscall-template.S:84
#1  0x0045b518 in CFDEventMonitor::Process() ()
#2  0x005b5aac in CThread::Action() ()
#3  0x005b61a4 in CThread::staticThread(void*) ()
#4  0xb6eb0f5c in start_thread (arg=0xb44ff3a0) at pthread_create.c:335
#5  0xb52cfbc8 in ?? () at ../sysdeps/unix/sysv/linux/arm/clone.S:86 from /usr/lib/libc.so.6
Backtrace stopped: previous frame identical to this frame (corrupt stack?)


Thread 1 (Thread 0xb4ea8000 (LWP 4426)):
#0  __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:58
#1  0xb522d590 in __GI_abort () at abort.c:89
#2  0xb54561f0 in __gnu_cxx::__verbose_terminate_handler() () from /usr/lib/libstdc++.so.6
#3  0xb5453ff0 in ?? () from /usr/lib/libstdc++.so.6
#4  0xb5454064 in std::terminate() () from /usr/lib/libstdc++.so.6
#5  0xb5454394 in __cxa_throw () from /usr/lib/libstdc++.so.6
#6  0x006900b4 in dbiplus::SqliteDataset::query(std::string const&;) ()
#7  0x00686b80 in CDatabase::GetDBVersion() ()
#8  0x006fe3a4 in CDatabaseManager::UpdateVersion(CDatabase&, std::string const&;) ()
#9  0x006fe70c in CDatabaseManager::Update(CDatabase&, DatabaseSettings const&;) ()
#10 0x006fec0c in CDatabaseManager::UpdateDatabase(CDatabase&, DatabaseSettings*) ()
#11 0x006fec94 in CDatabaseManager::Initialize(bool) ()
#12 0x006e9a5c in CApplication::Create() ()
#13 0x005cb584 in XBMC_Run ()
#14 0x00361be0 in main ()
############# END STACK TRACE ###############


################# LOG FILE ##################


ï»¿13:16:59.896 T:3035267072  NOTICE: special://profile/ is mapped to: special://masterprofile/
13:16:59.896 T:3035267072  NOTICE: Previous line repeats 1 times.
13:16:59.896 T:3035267072  NOTICE: -----------------------------------------------------------------------
13:16:59.896 T:3035267072  NOTICE: Starting Kodi (17.0 Git:a10c504). Platform: Linux ARM 32-bit
13:16:59.896 T:3035267072  NOTICE: Using Release Kodi x32 build
13:16:59.896 T:3035267072  NOTICE: Kodi compiled Feb  1 2017 by GCC 6.2.0 for Linux ARM 32-bit version 3.10.99 (199267)
13:16:59.897 T:3035267072  NOTICE: Running on LibreELEC (official) - Version: 7.95.2 8.0, kernel: Linux ARM 32-bit version 3.10.99
13:16:59.897 T:3035267072  NOTICE: FFmpeg version/source: ffmpeg-3.1-kodi
13:16:59.897 T:3035267072  NOTICE: Host CPU: ARMv7 Processor rev 0 (v7l), 2 cores available
13:16:59.897 T:3035267072  NOTICE: ARM Features: Neon enabled
13:16:59.897 T:3035267072  NOTICE: special://xbmc/ is mapped to: /usr/share/kodi/
13:16:59.897 T:3035267072  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
13:16:59.897 T:3035267072  NOTICE: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons
13:16:59.897 T:3035267072  NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata
13:16:59.897 T:3035267072  NOTICE: special://envhome/ is mapped to: /storage
13:16:59.897 T:3035267072  NOTICE: special://home/ is mapped to: /storage/.kodi
13:16:59.897 T:3035267072  NOTICE: special://temp/ is mapped to: /storage/.kodi/temp
13:16:59.897 T:3035267072  NOTICE: special://logpath/ is mapped to: /storage/.kodi/temp
13:16:59.897 T:3035267072  NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
13:16:59.897 T:3035267072  NOTICE: Log File is located: /storage/.kodi/temp//kodi.log
13:16:59.897 T:3035267072  NOTICE: -----------------------------------------------------------------------
13:16:59.915 T:3035267072   ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
13:16:59.925 T:3035267072  NOTICE: load settings...
13:16:59.926 T:3035267072  NOTICE: AML device detected
13:17:00.041 T:3035267072  NOTICE: Found 2 Lists of Devices
13:17:00.041 T:3035267072  NOTICE: Enumerated ALSA devices:
13:17:00.041 T:3035267072  NOTICE:     Device 1
13:17:00.042 T:3035267072  NOTICE:         m_deviceName      : default
13:17:00.042 T:3035267072  NOTICE:         m_displayName     : Default (AML-DUMMY-CODEC S/PDIF)
13:17:00.042 T:3035267072  NOTICE:         m_displayNameExtra:
13:17:00.042 T:3035267072  NOTICE:         m_deviceType      : AE_DEVTYPE_IEC958
13:17:00.042 T:3035267072  NOTICE:         m_channels        : FL,FR,BL,BR,FC,LFE,SL,SR
13:17:00.042 T:3035267072  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000
13:17:00.042 T:3035267072  NOTICE:         m_dataFormats     : AE_FMT_RAW,AE_FMT_S16NE,AE_FMT_S16LE
13:17:00.042 T:3035267072  NOTICE:         m_streamTypes     : STREAM_TYPE_AC3,STREAM_TYPE_DTSHD_CORE,STREAM_TYPE_DTS_1024,STREAM_TYPE_DTS_2048,STREAM_TYPE_DTS_512
13:17:00.042 T:3035267072  NOTICE: Enumerated PULSE devices:
13:17:00.042 T:3035267072  NOTICE:     Device 1
13:17:00.042 T:3035267072  NOTICE:         m_deviceName      : Default
13:17:00.042 T:3035267072  NOTICE:         m_displayName     : Default
13:17:00.042 T:3035267072  NOTICE:         m_displayNameExtra: Bluetooth Audio (PULSEAUDIO)
13:17:00.042 T:3035267072  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
13:17:00.042 T:3035267072  NOTICE:         m_channels        : FL,FR
13:17:00.042 T:3035267072  NOTICE:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
13:17:00.042 T:3035267072  NOTICE:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
13:17:00.042 T:3035267072  NOTICE:         m_streamTypes     : No passthrough capabilities
13:17:00.060 T:3035267072  NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
13:17:00.060 T:3035267072  NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
                                            <?xml version="1.0" encoding="UTF-8" ?>
                                            <advancedsettings>
                                              <showexitbutton>false</showexitbutton>
                                              <samba>
                                                <clienttimeout>30</clienttimeout>
                                              </samba>
                                            </advancedsettings>
13:17:00.062 T:3035267072  NOTICE: Loaded settings file from special://profile/advancedsettings.xml
13:17:00.062 T:3035267072  NOTICE: Contents of special://profile/advancedsettings.xml are...
                                            <?xml version="1.0" encoding="UTF-8" ?>
                                            <advancedsettings />
13:17:00.063 T:3035267072  NOTICE: Default Video Player: VideoPlayer
13:17:00.063 T:3035267072  NOTICE: Default Audio Player: paplayer
13:17:00.063 T:3035267072  NOTICE: Disabled debug logging due to GUI setting. Level 0.
13:17:00.063 T:3035267072  NOTICE: Log level changed to "LOG_LEVEL_NORMAL"
13:17:00.063 T:3035267072  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
13:17:00.065 T:3035267072  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
13:17:00.066 T:3035267072  NOTICE: Loaded playercorefactory configuration
13:17:00.066 T:3035267072  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
13:17:00.067 T:3035267072  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
13:17:00.103 T:3035267072   ERROR: SQL: [Addons27.db] SQL error or missing database
                                            Query: SELECT idVersion FROM version


############### END LOG FILE ################


############ END kodi CRASH LOG #############

Display More