[MarMic]

Hi Jungs,

ich habe die 5.4 Testing auf den Schlafzimmer und Wohnzimmer VDR gebügelt und nun folgendes Problem.

Die Last der System geht irgendwann Hoch bis die System unbedienbar werden.
Die % Belegungen (idle usr....) sehen in top sehr gut aus aber die Load geht > 1.5 bis 2.0 und der VDR fängt an sich immer wieder neu zu starten.

Das Phänomen ist nicht reproduzierbar.

Am häufigsten fällt es auf, wenn der VDR zwei 1080i Aufnahmen auf zwei unterschiedlichen Transpondern ausführt. Das geht manchmal gut und ein anderes Mal fängt die Last wieder an zuzunehmen.

Ab und an geschieht es auch wenn nur eine Aufnahme läuft und ich KODI starte um einen Film 1080p/720p anzuschauen. Dann fängt KODI sogar an zu "buffern" was ich bisher noch nie erlebt hatte. Der Film kommt von einer USB2 Platte.

In meinem IMON Display sehe ich dann auch das der VDR sich andauernd neu startet.
TOP zeigt dann wieder eine Last von >1.5

Bevor ich die System auf 5.4 gehoben habe liefen diese über mehrere Stunden Stabil. Es kommen auch Tage vor an dennen die Last Konstant auf 0.5 bleibt trotz KODI, Aufnahmen und/oder Chromium browsen.

So nun meine Frage an die 5.x Profis.... wie debuggen wir diese Sch**** :-)

Ich will ja das ihr eine 5.4 stable bekommt

Gruß

MarMic

[MarMic]

nochmal beleben:

unter 5.3 stable bekomme ich wenigstens nen trace: kann jemand damit etwas anfangen:

Code: [Select]

Jun 20 13:53:39 (MLD) user.err kernel: [ 1525.622236] irq 16: nobody cared (try booting with the "irqpoll" option)
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622240] CPU: 0 PID: 0 Comm: swapper/0 Tainted: P           O    4.8.15.198.3 #1
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622241] Hardware name: To Be Filled By O.E.M. To Be Filled By O.E.M./B75 Pro3-M, BIOS P1.90 12/24/2013
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622243]  0000000000000000 ffffffff813010b7 ffff88011d35c600 ffff88011d35c69c
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622247]  ffffffff8106aa79 ffff88011d35c600 0000000000000000 0000000000000000
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622249]  ffff88011d35c600 ffffffff8106ad1d ffff88011d35c600 0000000000000000
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622252] Call Trace:
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622253]  <IRQ>  [<ffffffff813010b7>] ? dump_stack+0x46/0x59
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622262]  [<ffffffff8106aa79>] ? __report_bad_irq+0x36/0xb0
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622264]  [<ffffffff8106ad1d>] ? note_interrupt+0x19a/0x222
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622267]  [<ffffffff81068bba>] ? handle_irq_event_percpu+0x39/0x3f
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622270]  [<ffffffff81068be2>] ? handle_irq_event+0x22/0x43
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622272]  [<ffffffff8106b507>] ? handle_fasteoi_irq+0x75/0xcb
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622275]  [<ffffffff81013f98>] ? handle_irq+0x11/0x17
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622278]  [<ffffffff81013a9f>] ? do_IRQ+0x40/0xba
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622281]  [<ffffffff815c317f>] ? common_interrupt+0x7f/0x7f
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622285]  [<ffffffff81039780>] ? __do_softirq+0x6c/0x1bb
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622287]  [<ffffffff8102732f>] ? ioapic_ack_level+0x6b/0x103
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622289]  [<ffffffff810399f9>] ? irq_exit+0x33/0x3f
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622292]  [<ffffffff81013b03>] ? do_IRQ+0xa4/0xba
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622294]  [<ffffffff815c317f>] ? common_interrupt+0x7f/0x7f
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622295]  <EOI>  [<ffffffff814bf5b8>] ? cpuidle_enter_state+0xf1/0x143
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622302]  [<ffffffff814bf57e>] ? cpuidle_enter_state+0xb7/0x143
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622306]  [<ffffffff8105c58c>] ? cpu_startup_entry+0x13d/0x1a6
Jun 20 13:53:39 (MLD) user.warn kernel: [ 1525.622309]  [<ffffffff81aa8de6>] ? start_kernel+0x393/0x39b
Jun 20 13:53:39 (MLD) user.err kernel: [ 1525.622310] handlers:
Jun 20 13:53:39 (MLD) user.err kernel: [ 1525.622313] [<ffffffff8144f6a6>] usb_hcd_irq
Jun 20 13:53:39 (MLD) user.err kernel: [ 1525.622315] [<ffffffff8144f6a6>] usb_hcd_irq
Jun 20 13:53:39 (MLD) user.err kernel: [ 1525.622316] [<ffffffff8144f6a6>] usb_hcd_irq
Jun 20 13:53:39 (MLD) user.emerg kernel: [ 1525.622317] Disabling IRQ #16
Jun 20 13:53:39 (MLD) user.info vdr: Connection to LCDd at 127.0.0.1:13666 lost, trying to reestablish.
Jun 20 13:55:41 (MLD) user.info vdr: LCDd at 127.0.0.1:13666 does not respond.
Jun 20 13:57:07 (MLD) user.err vdr: codec/audio: error more than one frame data
Jun 20 13:57:07 (MLD) user.err vdr: codec/audio: no frame
edit:// ich werde mal das powersave addon entfernen, welches das cpu stepping erledigt. Bei 5.4 scheint es nicht mehr nötig weil vllt bereits im kernel integriert?

[MarMic]

Also ich antworte mir selbst (falls mal doch jemand auch mal das Problem hat)

mein Board scheint es irgendwie nicht zu mögen, wenn der Sound über HDMI ausgegeben wird. Das führt zu diesen IRQ errors und dann zu einer SEHR HOHEN LAST.

Sobald HDMI sound auf SPDIF umgestellt wird gibt es keine Lasten mehr >0.3 und das System läuft absolut stabil. Aufnahmen sind auch kein Problem. (heute Nacht 22 Test Aufnahmen mit mehrfachen hoch und runterfahren ohne Probleme erledigt)

gelöst sehe ich das Problem noch nicht.

next step: schauen ob es eine neue BIOS version gibt
grafikkate gegen eine andere tauschen und HDMI wieder aktivieren

Gruß

MarMic

[MegaX]

Hi,

mit welchem Treiber läuft deine Karte, und ist es die 610er?

[MarMic]

hidiho,

ja ist die 610er mit nvidia-legacy (der neue sollte auch gehen tut er aber nicht)

soviel ich weiß werden außerdem für die hdmi sound ausgabe snd-hda-intel verwendet (unabhängig von nvidida)

gruß

MarMic

[MegaX]

Installier mal das tools paket und poste mal die Ausgabe von lspci.

[MarMic]

sodelle:

Code: [Select]

WZVDR> lspci
00:00.0 Host bridge: Intel Corporation Xeon E3-1200 v2/3rd Gen Core processor DRAM Controller (rev 09)
00:01.0 PCI bridge: Intel Corporation Xeon E3-1200 v2/3rd Gen Core processor PCI Express Root Port (rev 09)
00:14.0 USB controller: Intel Corporation 7 Series/C210 Series Chipset Family USB xHCI Host Controller (rev 04)
00:16.0 Communication controller: Intel Corporation 7 Series/C216 Chipset Family MEI Controller #1 (rev 04)
00:1a.0 USB controller: Intel Corporation 7 Series/C216 Chipset Family USB Enhanced Host Controller #2 (rev 04)
00:1b.0 Audio device: Intel Corporation 7 Series/C216 Chipset Family High Definition Audio Controller (rev 04)
00:1c.0 PCI bridge: Intel Corporation 7 Series/C216 Chipset Family PCI Express Root Port 1 (rev c4)
00:1c.4 PCI bridge: Intel Corporation 7 Series/C210 Series Chipset Family PCI Express Root Port 5 (rev c4)
00:1c.5 PCI bridge: Intel Corporation 7 Series/C210 Series Chipset Family PCI Express Root Port 6 (rev c4)
00:1d.0 USB controller: Intel Corporation 7 Series/C216 Chipset Family USB Enhanced Host Controller #1 (rev 04)
00:1e.0 PCI bridge: Intel Corporation 82801 PCI Bridge (rev a4)
00:1f.0 ISA bridge: Intel Corporation B75 Express Chipset LPC Controller (rev 04)
00:1f.2 SATA controller: Intel Corporation 7 Series/C210 Series Chipset Family 6-port SATA Controller [AHCI mode] (rev 04)
00:1f.3 SMBus: Intel Corporation 7 Series/C216 Chipset Family SMBus Controller (rev 04)
01:00.0 VGA compatible controller: NVIDIA Corporation GF119 [GeForce GT 610] (rev a1)
01:00.1 Audio device: NVIDIA Corporation GF119 HDMI Audio Controller (rev a1)
02:00.0 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
02:00.1 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
02:00.2 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
02:00.3 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
02:00.4 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
02:00.5 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
02:00.6 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
02:00.7 USB controller: MosChip Semiconductor Technology Ltd. MCS9990 PCIe to 4‐Port USB 2.0 Host Controller
03:00.0 SATA controller: ASMedia Technology Inc. ASM1062 Serial ATA Controller (rev 01)
04:00.0 Ethernet controller: Realtek Semiconductor Co., Ltd. RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller (rev 06)

edit:// auch mal lsusb

Code: [Select]

WZVDR> lsusb
Bus 002 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 006 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 010 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 005 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 009 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 004 Device 003: ID 9022:d660 TeVii Technology Ltd. DVB-S2 S660
Bus 004 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 008 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 003 Device 003: ID 9022:d660 TeVii Technology Ltd. DVB-S2 S660
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 007 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 006: ID 09da:0006 A4Tech Co., Ltd. Optical Mouse WOP-35 / Trust 450L Optical Mouse
Bus 001 Device 005: ID 05e3:0608 Genesys Logic, Inc. Hub
Bus 001 Device 004: ID 05e3:070e Genesys Logic, Inc. USB 2.0 Card Reader
Bus 001 Device 003: ID 15c2:0036 SoundGraph Inc. LC16M VFD Display/IR Receiver
Bus 001 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 012 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 011 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
WZVDR>


[MegaX]

Und jetzt bitte ein:

Code: [Select]

lspci -v -s 0000:01:00.0

[MarMic]

bitte

Code: [Select]

lspci -v -s 0000:01:00.0
01:00.0 VGA compatible controller: NVIDIA Corporation GF119 [GeForce GT 610] (rev a1) (prog-if 00 [VGA controller])
        Subsystem: ASUSTeK Computer Inc. Device 840d
        Flags: bus master, fast devsel, latency 0, IRQ 29
        Memory at f6000000 (32-bit, non-prefetchable) [size=16M]
        Memory at e8000000 (64-bit, prefetchable) [size=128M]
        Memory at f0000000 (64-bit, prefetchable) [size=32M]
        I/O ports at e000 [size=128]
        [virtual] Expansion ROM at 000c0000 [disabled] [size=128K]
        Capabilities: [60] Power Management version 3
        Capabilities: [68] MSI: Enable+ Count=1/1 Maskable- 64bit+
        Capabilities: [78] Express Endpoint, MSI 00
        Capabilities: [b4] Vendor Specific Information: Len=14 <?>
        Kernel driver in use: nvidia
        Kernel modules: nvidia


[MegaX]

OK, jetzt mach mal bitte folgendes:

Code: [Select]

echo "options nvidia NVreg_EnableMSI=0" | tee /etc/modprobe.d/nvidia.conf
Danach HDMI wieder aktivieren und einmal rebooten.

Jetzt teste mal bitte ob das Problem damit behoben ist.

[MarMic]

ist gemacht!

sag mal sehen bei euch die interupt zahlen auch so hoch aus bei nvdidia?

Code: [Select]

cat /proc/interrupts
           CPU0       CPU1
  0:         20          0   IO-APIC   2-edge      timer
  1:          9          2   IO-APIC   1-edge      i8042
  8:          0          0   IO-APIC   8-edge      rtc0
  9:          0          0   IO-APIC   9-fasteoi   acpi
 14:          0          0   IO-APIC  14-edge      platform[pata_legacy.0]
 15:          0          0   IO-APIC  15-edge      platform[pata_legacy.1]
 16:    1540805     771127   IO-APIC  16-fasteoi   ehci_hcd:usb1, ehci_hcd:usb3, ohci_hcd:usb7, nvidia
 17:     810022      64201   IO-APIC  17-fasteoi   ehci_hcd:usb4, ohci_hcd:usb8, snd_hda_intel:card1
 18:          0          0   IO-APIC  18-fasteoi   ehci_hcd:usb5, ohci_hcd:usb9, i801_smbus
 19:          0          0   IO-APIC  19-fasteoi   ehci_hcd:usb6, ohci_hcd:usb10
 23:         26          3   IO-APIC  23-fasteoi   ehci_hcd:usb2
 24:       2813        749   PCI-MSI 512000-edge      ahci[0000:00:1f.2]
 25:          0          0   PCI-MSI 1572864-edge      ahci[0000:03:00.0]
 26:          0          0   PCI-MSI 327680-edge      xhci_hcd
 27:       1761        207   PCI-MSI 2097152-edge      eth0
 28:        331         96   PCI-MSI 442368-edge      snd_hda_intel:card0
NMI:          0          0   Non-maskable interrupts
LOC:     596953     596891   Local timer interrupts
SPU:          0          0   Spurious interrupts
PMI:          0          0   Performance monitoring interrupts
IWI:          0          0   IRQ work interrupts
RTR:          1          0   APIC ICR read retries
RES:       6198       6450   Rescheduling interrupts
CAL:        300        306   Function call interrupts
TLB:        236        202   TLB shootdowns
ERR:          0
MIS:          0
PIN:          0          0   Posted-interrupt notification event
PIW:          0          0   Posted-interrupt wakeup event

uptime 8minuten

[HagenS]

So siehts bei mir aus:

Code: [Select]

video> uptime
 17:08:03 up 6 min,  load average: 1.75, 1.50, 0.73
video> cat /proc/interrupts
           CPU0       CPU1       
  0:         35          0   IO-APIC   2-edge      timer
  1:          3          0   IO-APIC   1-edge      i8042
  8:          0          0   IO-APIC   8-edge      rtc0
  9:          0          0   IO-APIC   9-fasteoi   acpi
 12:          4          0   IO-APIC  12-edge      i8042
 14:          0          0   IO-APIC  14-fasteoi   platform[pata_legacy.0]
 15:          0          0   IO-APIC  15-edge      platform[pata_legacy.1]
 16:          0          0   IO-APIC  16-fasteoi   i801_smbus
 17:      13999       2729   IO-APIC  17-fasteoi   snd_hda_intel:card0
120:      98957      30720   PCI-MSI 376832-edge      ahci[0000:00:17.0]
121:     103795      21226   PCI-MSI 327680-edge      xhci_hcd
122:      87663      25351   PCI-MSI 1048576-edge      ddbridge
123:      10915       2549   PCI-MSI 520192-edge      eth0
124:      46798       9606   PCI-MSI 524288-edge      nvidia
NMI:          0          0   Non-maskable interrupts
LOC:     390287     390227   Local timer interrupts
SPU:          0          0   Spurious interrupts
PMI:          0          0   Performance monitoring interrupts
IWI:          0          0   IRQ work interrupts
RTR:          1          0   APIC ICR read retries
RES:      54160      70561   Rescheduling interrupts
CAL:        465        636   Function call interrupts
TLB:        340        506   TLB shootdowns
ERR:         23
MIS:          0
PIN:          0          0   Posted-interrupt notification event
PIW:          0          0   Posted-interrupt wakeup event
video>

...Hagen

[MarMic]

hmm das ist ohne  NVreg_EnableMSI=0. das sieht man daran das PCI-MSI bei nvidia aktiv ist.

aber meine interrupts sind schon enorm.

vielen dank für deinen post

[HagenS]

Ja - natürlich ohne. Ich dachte, Du wolltest einen Vergleich haben 

[MarMic]

Hi Andy,

hat nix gebracht! eben der vdr wieder in die Knie

Load average: 1.54 1.10 0.93 2/192 3457 UND STEIGEND

Code: [Select]

[ 4577.998232] irq 16: nobody cared (try booting with the "irqpoll" option)
[ 4577.998235] CPU: 0 PID: 3396 Comm: mount.mergerfs Tainted: P           O    4.8.15.198.3 #1
[ 4577.998236] Hardware name: To Be Filled By O.E.M. To Be Filled By O.E.M./B75 Pro3-M, BIOS P1.90 12/24/2013
[ 4577.998238]  0000000000000000 ffffffff813010b7 ffff88011d35c600 ffff88011d35c69c
[ 4577.998240]  ffffffff8106aa79 ffff88011d35c600 0000000000000000 0000000000000000
[ 4577.998242]  ffff88011d35c600 ffffffff8106ad1d ffff88011d35c600 0000000000000000
[ 4577.998243] Call Trace:
[ 4577.998244]  <IRQ>  [<ffffffff813010b7>] ? dump_stack+0x46/0x59
[ 4577.998251]  [<ffffffff8106aa79>] ? __report_bad_irq+0x36/0xb0
[ 4577.998252]  [<ffffffff8106ad1d>] ? note_interrupt+0x19a/0x222
[ 4577.998254]  [<ffffffff81068bba>] ? handle_irq_event_percpu+0x39/0x3f
[ 4577.998256]  [<ffffffff81068be2>] ? handle_irq_event+0x22/0x43
[ 4577.998258]  [<ffffffff8106b507>] ? handle_fasteoi_irq+0x75/0xcb
[ 4577.998260]  [<ffffffff81013f98>] ? handle_irq+0x11/0x17
[ 4577.998261]  [<ffffffff81013a9f>] ? do_IRQ+0x40/0xba
[ 4577.998264]  [<ffffffff815c317f>] ? common_interrupt+0x7f/0x7f
[ 4577.998427]  [<ffffffffa04210d0>] ? _nv008900rm+0x10b0/0x5c10 [nvidia]
[ 4577.998429]  [<ffffffff81039780>] ? __do_softirq+0x6c/0x1bb
[ 4577.998430]  [<ffffffff8102732f>] ? ioapic_ack_level+0x6b/0x103
[ 4577.998432]  [<ffffffff810399f9>] ? irq_exit+0x33/0x3f
[ 4577.998433]  [<ffffffff81013b03>] ? do_IRQ+0xa4/0xba
[ 4577.998435]  [<ffffffff815c317f>] ? common_interrupt+0x7f/0x7f
[ 4577.998436]  <EOI>  [<ffffffff810d2703>] ? kmem_cache_alloc+0x86/0x95
[ 4577.998443]  [<ffffffff8122cc75>] ? kmem_zone_alloc+0x6e/0xc6
[ 4577.998444]  [<ffffffff8122c669>] ? xfs_trans_alloc+0x7e/0xee
[ 4577.998446]  [<ffffffff81221713>] ? xfs_vn_update_time+0x35/0xca
[ 4577.998449]  [<ffffffff810ea4b3>] ? file_update_time+0xa6/0xc8
[ 4577.998451]  [<ffffffff8121aa15>] ? xfs_file_aio_write_checks+0x14a/0x160
[ 4577.998452]  [<ffffffff8121ace5>] ? xfs_file_buffered_aio_write+0x5c/0x18b
[ 4577.998455]  [<ffffffff811cfaac>] ? fuse_dev_read+0x53/0x58
[ 4577.998456]  [<ffffffff8121ae9b>] ? xfs_file_write_iter+0x87/0xfa
[ 4577.998458]  [<ffffffff810d5d99>] ? __vfs_write+0xc9/0xf1
[ 4577.998459]  [<ffffffff810d66fb>] ? vfs_write+0xc6/0x149
[ 4577.998461]  [<ffffffff810d73ad>] ? SyS_pwrite64+0x5b/0x78
[ 4577.998462]  [<ffffffff815c285b>] ? entry_SYSCALL_64_fastpath+0x13/0x8f
[ 4577.998463] handlers:
[ 4577.998465] [<ffffffff8144f6a6>] usb_hcd_irq
[ 4577.998466] [<ffffffff8144f6a6>] usb_hcd_irq
[ 4577.998467] [<ffffffff8144f6a6>] usb_hcd_irq
[ 4577.998557] [<ffffffffa06a4930>] nvidia_isr [nvidia]
[ 4577.998558] Disabling IRQ #16
[ 4577.999807] clocksource: timekeeping watchdog on CPU0: Marking clocksource 'tsc' as unstable because the skew is too large:
[ 4577.999816] clocksource:                       'acpi_pm' wd_now: 2be64e wd_last: a44918 mask: ffffff
[ 4577.999817] clocksource:                       'tsc' cs_now: ad3e88a5eac cs_last: ad268a26b9e mask: ffffffffffffffff
[ 4578.000273] clocksource: Switched to clocksource acpi_pm

so ich stelle mal zurück auf spdig und rm /etc/mod.../nvidia.conf