[SOLVED] All Recordings are Failing as Damaged at Between 1 and 5 Mins in Length

[crum]

I've completed the initial setup of my MythTV and have been experimenting with recording. All of the recordings I have tried over the last week are failing between 1 and 5 mins in length w/ file sizes varying between 82 mb and 440 mb.

BACK-END SETUP:
Operating System - Ubuntu 18.04.01 LTS (Server)
Kernel - 4.15 (tried both the generic and the hauppauge media tree version) -- current output of uname -a is: Linux mythtv-server 4.15.0-29201807270420-generic #0+mediatree+hauppauge-Ubuntu SMP Fri Jul 27 18:09:45 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux
System - SUPERMICRO SYS-5018A-FTN4 1U Rackmount Server / 16 GB RAM / 2 TB HD WD Purple @ 5400 rpm - https://www.newegg.com/Product/Product. ... 6816101837
Capture card - Hauppauge 1609 WinTV-quadHD Four Tuner Digital TV Receiver
Software - "mythtv-backend-master” pulled from ubuntu bionic. e.g. - .29

ERRORS and LOGS:
I am getting following error to standard output from every few seconds to few minutes... sometimes as [0] and sometimes as [1].

Code: Select all

[kernel time...] cx23885: cx23885[0]: mpeg risc op code error
[kernel time...] cx23885: cx23885[1]: mpeg risc op code error

I am getting this error with both the standard 4.15 kernel and the Hauppauge media tree version

This is the dump of /var/log/mythtv/backend.log from when an example recording starts and fails. In this case the recording failed 4:36 after starting.

Code: Select all

Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[1]: Changing from None to RecordingOnly
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[1]: TuningFrequency
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2923 (HandleRecordingStatusChange) Tuning recording: "Gomer Pyle, U.S.M.C.": chan
nel 1582 on cardid [1], sourceid 1
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 0 0 0 2018-08-28T11:30:00Z
 EITScanner
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 1 items in 0.0 = 0.01 match + 0.00 check + 0.01
 place
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "Gomer Pyle, U.S.M.C." on cardid [1] 
(Tuning => Recording)
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:4192 (TuningNewRecorder) TVRec[1]: rec->GetPathname(): '/home/mythtv/recordings/oth
er/1582_20180828010000.ts'
Aug 27 20:00:00 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:4224 (TuningNewRecorder) TVRec[1]: TuningNewRecorder - CreateRecorder()
Aug 27 20:01:08 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 0 1 13 2018-08-28T11:30:00
Z EITScanner
Aug 27 20:01:08 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 1 items in 0.0 = 0.01 match + 0.00 check + 0.01
 place
Aug 27 20:02:06 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 0 1 10 2018-08-28T23:30:00
Z EITScanner
Aug 27 20:02:06 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 1 items in 0.0 = 0.01 match + 0.00 check + 0.01
 place
Aug 27 20:02:06 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[3]: TuningFrequency
Aug 27 20:03:08 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 0 1 9 2018-08-28T23:30:00Z
 EITScanner
Aug 27 20:03:08 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 1 items in 0.0 = 0.01 match + 0.00 check + 0.01
 place
Aug 27 20:03:08 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[2]: TuningFrequency
Aug 27 20:03:21 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 0 1 2 2018-08-28T23:30:00Z
 EITScanner
Aug 27 20:03:21 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 1 items in 0.0 = 0.01 match + 0.00 check + 0.01
 place
Aug 27 20:03:21 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[4]: TuningFrequency
Aug 27 20:07:13 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[3]: TuningFrequency

Here is the 'end' of the time slot:

Code: Select all

Aug 27 20:27:43 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[4]: TuningFrequency
Aug 27 20:29:23 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 0 1 1 2018-09-10T21:00:00Z EITScanner
Aug 27 20:29:24 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 1 items in 0.0 = 0.01 match + 0.00 check + 0.01 place
Aug 27 20:29:24 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[2]: TuningFrequency
Aug 27 20:29:27 mythtv-server mythbackend: mythbackend[1668]: E EIT programdata.cpp:544 (GetMatch) Unexpected result: shows don't overlap#012#011Body Electric: 2018-08-28T12:00:00Z - 2018-08-28T12:30:00Z#012#011       Milwaukee PBS 36 Programming: 2018-08-28T12:00:00Z - 2018-08-28T12:00:00Z
Aug 27 20:29:33 mythtv-server mythbackend: mythbackend[1668]: E EIT programdata.cpp:544 (GetMatch) Unexpected result: shows don't overlap#012#011Priscilla's Yoga Stretches: 2018-08-28T11:30:00Z - 2018-08-28T11:44:45Z#012#011       Milwaukee PBS 36 Programming: 2018-08-28T11:44:45Z - 2018-08-28T11:44:45Z
Aug 27 20:30:00 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[1]: Changing from RecordingOnly to None
Aug 27 20:30:00 mythtv-server mythbackend: mythbackend[1668]: W DeviceReadBuffer recorders/DeviceReadBuffer.cpp:557 (Poll) DevRdB(/dev/dvb/adapter0/frontend0): Poll took an unusually long time 1520301 ms
Aug 27 20:30:00 mythtv-server mythbackend: mythbackend[1668]: N RecThread recorders/recorderbase.cpp:501 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (704x480 A/R: 3 29.97fps) Audio Codec: ac3
Aug 27 20:30:00 mythtv-server mythbackend: mythbackend[1668]: I TVRecEvent tv_rec.cpp:863 (FinishedRecording) TVRec[1]: FinishedRecording(1582_2018-08-28T01:00:00Z) damaged recq:<RecordingQuality overall_score="0" key="1582_2018-08-28T01:00:00Z" countinuity_error_count="0" packet_count="450964">#012    <Gap start="2018-08-28T01:04:36Z" end="2018-08-28T01:30:00Z" duration="1523" />#012</RecordingQuality>
Aug 27 20:30:00 mythtv-server mythbackend: mythbackend[1668]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "Gomer Pyle, U.S.M.C." on cardid [1] (Recording => Recorder Failed)
Aug 27 20:30:00 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -9 13 0 UpdateRecStatus2 | Gomer Pyle, U.S.M.C. |  | Gomer learns that Carter is about to take the exam for the officer's training program, and he thinks that Carter would leave the platoon if he is successful. |
Aug 27 20:30:00 mythtv-server mythbackend: mythbackend[1668]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 0 items in 0.0 = 0.00 match + 0.00 check + 0.01 place
Aug 27 20:30:01 mythtv-server mythbackend: mythbackend[1668]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Aug 27 20:30:01 mythtv-server mythbackend: mythbackend[1668]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv-server(556896a06b10) as a client (events: 0)
Aug 27 20:30:02 mythtv-server mythbackend: mythbackend[1668]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Aug 27 20:30:02 mythtv-server mythbackend: mythbackend[1668]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv-server(556896a028b0) as a client (events: 1)

EFFORTS SO FAR / TROUBLESHOOTING:
(1) I'm limiting the back-end to 1 task at a time.

(2) I've disabled auto-encoding after recording.

(3) As I've alluded to - I tried loading the Hauppauge built kernel per the instructions here: http://www.hauppauge.com/pages/support/ ... linux.html NOTE: I'm getting the cx23885 error with both kernel versions.

(4) Walking through the logs didn't give me much to work with...but I'm not a computer scientist.

(5) I found this other Mythtv-forum string that might apply - viewtopic.php?t=2249
It suggests updated V4L-DVB Device Drivers - which is why I tried the Hauppauge ppa.

(6) I've tried searching the error code and get a few threads, but I don't know what to do with them. Examples:
- https://github.com/b-rad-NDi/Ubuntu-med ... /issues/17
- https://www.linuxtv.org/pipermail/linux ... 24103.html

Not sure where to go from here. Any suggestions would be much appreciated. Happy to provide logs, etc.

[crum]

All -- More digging

Logs

/var/log/kern.log
I noticed that kern.log is getting really really big. Here is less than 1 second of logging... Lots of the cx23885 error.

Code: Select all

Aug 28 19:23:59 mythtv-server kernel: [85544.699969] cx23885: cx23885[0]: mpeg risc op code error
Aug 28 19:23:59 mythtv-server kernel: [85544.699974] cx23885: cx23885[0]: TS1 B - dma channel status dump
Aug 28 19:23:59 mythtv-server kernel: [85544.699979] cx23885: cx23885[0]:   cmds: init risc lo   : 0x34128000
Aug 28 19:23:59 mythtv-server kernel: [85544.699985] cx23885: cx23885[0]:   cmds: init risc hi   : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.699990] cx23885: cx23885[0]:   cmds: cdt base       : 0x00010870
Aug 28 19:23:59 mythtv-server kernel: [85544.699994] cx23885: cx23885[0]:   cmds: cdt size       : 0x0000000a
Aug 28 19:23:59 mythtv-server kernel: [85544.699998] cx23885: cx23885[0]:   cmds: iq base        : 0x00010630
Aug 28 19:23:59 mythtv-server kernel: [85544.700002] cx23885: cx23885[0]:   cmds: iq size        : 0x00000010
Aug 28 19:23:59 mythtv-server kernel: [85544.700006] cx23885: cx23885[0]:   cmds: risc pc lo     : 0x346d9138
Aug 28 19:23:59 mythtv-server kernel: [85544.700010] cx23885: cx23885[0]:   cmds: risc pc hi     : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.700014] cx23885: cx23885[0]:   cmds: iq wr ptr      : 0x00004195
Aug 28 19:23:59 mythtv-server kernel: [85544.700018] cx23885: cx23885[0]:   cmds: iq rd ptr      : 0x00004192
Aug 28 19:23:59 mythtv-server kernel: [85544.700022] cx23885: cx23885[0]:   cmds: cdt current    : 0x00010888
Aug 28 19:23:59 mythtv-server kernel: [85544.700026] cx23885: cx23885[0]:   cmds: pci target lo  : 0x34582390
Aug 28 19:23:59 mythtv-server kernel: [85544.700030] cx23885: cx23885[0]:   cmds: pci target hi  : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.700034] cx23885: cx23885[0]:   cmds: line / byte    : 0x03370000
Aug 28 19:23:59 mythtv-server kernel: [85544.700039] cx23885: cx23885[0]:   risc0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700041] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700049] cx23885: cx23885[0]:   risc1: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700050] 0x345820a0 [ INVALID eol 22 20 19 13 count=160 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700060] cx23885: cx23885[0]:   risc2: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700061] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700066] cx23885: cx23885[0]:   risc3: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700068] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700075] cx23885: cx23885[0]:   (0x00010630) iq 0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700077] 0x140000a0 [ write eol count=160 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700084] cx23885: cx23885[0]:   iq 1: 0x34582000 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700088] cx23885: cx23885[0]:   iq 2: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700092] cx23885: cx23885[0]:   (0x0001063c) iq 3: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700094] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700101] cx23885: cx23885[0]:   iq 4: 0x345820a0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700106] cx23885: cx23885[0]:   iq 5: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700110] cx23885: cx23885[0]:   (0x00010648) iq 6: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700112] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700119] cx23885: cx23885[0]:   iq 7: 0x34582390 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700123] cx23885: cx23885[0]:   iq 8: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700127] cx23885: cx23885[0]:   (0x00010654) iq 9: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700129] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700134] cx23885: cx23885[0]:   (0x00010658) iq a: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700136] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700144] cx23885: cx23885[0]:   iq b: 0x342afac0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700148] cx23885: cx23885[0]:   iq c: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700152] cx23885: cx23885[0]:   (0x00010664) iq d: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700153] 0x18000250 [ write sol count=592 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700160] cx23885: cx23885[0]:   iq e: 0x342afdb0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700164] cx23885: cx23885[0]:   iq f: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700166] cx23885: cx23885[0]: fifo: 0x00005000 -> 0x6000
Aug 28 19:23:59 mythtv-server kernel: [85544.700169] cx23885: cx23885[0]: ctrl: 0x00010630 -> 0x10690
Aug 28 19:23:59 mythtv-server kernel: [85544.700172] cx23885: cx23885[0]:   ptr1_reg: 0x00005750
Aug 28 19:23:59 mythtv-server kernel: [85544.700176] cx23885: cx23885[0]:   ptr2_reg: 0x00010898
Aug 28 19:23:59 mythtv-server kernel: [85544.700180] cx23885: cx23885[0]:   cnt1_reg: 0x00000017
Aug 28 19:23:59 mythtv-server kernel: [85544.700184] cx23885: cx23885[0]:   cnt2_reg: 0x00000005
Aug 28 19:23:59 mythtv-server kernel: [85544.700185] cx23885: cx23885[0]: mpeg risc op code error
Aug 28 19:23:59 mythtv-server kernel: [85544.700189] cx23885: cx23885[0]: TS2 C - dma channel status dump
Aug 28 19:23:59 mythtv-server kernel: [85544.700193] cx23885: cx23885[0]:   cmds: init risc lo   : 0x34486000
Aug 28 19:23:59 mythtv-server kernel: [85544.700197] cx23885: cx23885[0]:   cmds: init risc hi   : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.700201] cx23885: cx23885[0]:   cmds: cdt base       : 0x000108d0
Aug 28 19:23:59 mythtv-server kernel: [85544.700205] cx23885: cx23885[0]:   cmds: cdt size       : 0x0000000a
Aug 28 19:23:59 mythtv-server kernel: [85544.700209] cx23885: cx23885[0]:   cmds: iq base        : 0x00010670
Aug 28 19:23:59 mythtv-server kernel: [85544.700214] cx23885: cx23885[0]:   cmds: iq size        : 0x00000010
Aug 28 19:23:59 mythtv-server kernel: [85544.700218] cx23885: cx23885[0]:   cmds: risc pc lo     : 0x344be168
Aug 28 19:23:59 mythtv-server kernel: [85544.700222] cx23885: cx23885[0]:   cmds: risc pc hi     : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.700226] cx23885: cx23885[0]:   cmds: iq wr ptr      : 0x000041a8
Aug 28 19:23:59 mythtv-server kernel: [85544.700230] cx23885: cx23885[0]:   cmds: iq rd ptr      : 0x0000419c
Aug 28 19:23:59 mythtv-server kernel: [85544.700234] cx23885: cx23885[0]:   cmds: cdt current    : 0x000108f8
Aug 28 19:23:59 mythtv-server kernel: [85544.700238] cx23885: cx23885[0]:   cmds: pci target lo  : 0x34852390
Aug 28 19:23:59 mythtv-server kernel: [85544.700242] cx23885: cx23885[0]:   cmds: pci target hi  : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.700246] cx23885: cx23885[0]:   cmds: line / byte    : 0x02970000
Aug 28 19:23:59 mythtv-server kernel: [85544.700250] cx23885: cx23885[0]:   risc0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700251] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700258] cx23885: cx23885[0]:   risc1: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700260] 0x34852390 [ INVALID eol 23 18 cnt0 13 count=912 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700269] cx23885: cx23885[0]:   risc2: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700270] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700275] cx23885: cx23885[0]:   risc3: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700277] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700284] cx23885: cx23885[0]:   (0x00010670) iq 0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700286] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700293] cx23885: cx23885[0]:   iq 1: 0x34852680 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700298] cx23885: cx23885[0]:   iq 2: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700302] cx23885: cx23885[0]:   (0x0001067c) iq 3: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700303] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700311] cx23885: cx23885[0]:   iq 4: 0x34852970 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700315] cx23885: cx23885[0]:   iq 5: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700319] cx23885: cx23885[0]:   (0x00010688) iq 6: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700321] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700328] cx23885: cx23885[0]:   iq 7: 0x34852c60 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700332] cx23885: cx23885[0]:   iq 8: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700336] cx23885: cx23885[0]:   (0x00010694) iq 9: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700338] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700346] cx23885: cx23885[0]:   iq a: 0x34852f50 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700350] cx23885: cx23885[0]:   iq b: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700354] cx23885: cx23885[0]:   (0x000106a0) iq c: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700355] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700361] cx23885: cx23885[0]:   (0x000106a4) iq d: 
Aug 28 19:23:59 mythtv-server kernel: [85544.700362] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700370] cx23885: cx23885[0]:   iq e: 0x34852390 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700374] cx23885: cx23885[0]:   iq f: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.700376] cx23885: cx23885[0]: fifo: 0x00006000 -> 0x7000
Aug 28 19:23:59 mythtv-server kernel: [85544.700379] cx23885: cx23885[0]: ctrl: 0x00010670 -> 0x106d0
Aug 28 19:23:59 mythtv-server kernel: [85544.700382] cx23885: cx23885[0]:   ptr1_reg: 0x00006d90
Aug 28 19:23:59 mythtv-server kernel: [85544.700386] cx23885: cx23885[0]:   ptr2_reg: 0x00010918
Aug 28 19:23:59 mythtv-server kernel: [85544.700390] cx23885: cx23885[0]:   cnt1_reg: 0x0000001d
Aug 28 19:23:59 mythtv-server kernel: [85544.700393] cx23885: cx23885[0]:   cnt2_reg: 0x00000001
Aug 28 19:23:59 mythtv-server kernel: [85544.705974] cx23885: cx23885[1]: TS2 C - dma channel status dump
Aug 28 19:23:59 mythtv-server kernel: [85544.705981] cx23885: cx23885[1]:   cmds: init risc lo   : 0x346f0000
Aug 28 19:23:59 mythtv-server kernel: [85544.705985] cx23885: cx23885[1]:   cmds: init risc hi   : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.705990] cx23885: cx23885[1]:   cmds: cdt base       : 0x000108d0
Aug 28 19:23:59 mythtv-server kernel: [85544.705995] cx23885: cx23885[1]:   cmds: cdt size       : 0x0000000a
Aug 28 19:23:59 mythtv-server kernel: [85544.705999] cx23885: cx23885[1]:   cmds: iq base        : 0x00010670
Aug 28 19:23:59 mythtv-server kernel: [85544.706004] cx23885: cx23885[1]:   cmds: iq size        : 0x00000010
Aug 28 19:23:59 mythtv-server kernel: [85544.706008] cx23885: cx23885[1]:   cmds: risc pc lo     : 0x346f2054
Aug 28 19:23:59 mythtv-server kernel: [85544.706012] cx23885: cx23885[1]:   cmds: risc pc hi     : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.706017] cx23885: cx23885[1]:   cmds: iq wr ptr      : 0x000041a9
Aug 28 19:23:59 mythtv-server kernel: [85544.706021] cx23885: cx23885[1]:   cmds: iq rd ptr      : 0x0000419d
Aug 28 19:23:59 mythtv-server kernel: [85544.706026] cx23885: cx23885[1]:   cmds: cdt current    : 0x000108e8
Aug 28 19:23:59 mythtv-server kernel: [85544.706030] cx23885: cx23885[1]:   cmds: pci target lo  : 0x346102f0
Aug 28 19:23:59 mythtv-server kernel: [85544.706035] cx23885: cx23885[1]:   cmds: pci target hi  : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.706039] cx23885: cx23885[1]:   cmds: line / byte    : 0x00410000
Aug 28 19:23:59 mythtv-server kernel: [85544.706044] cx23885: cx23885[1]:   risc0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706047] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706056] cx23885: cx23885[1]:   risc1: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706058] 0x346102f0 [ INVALID eol 22 21 cnt0 count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706069] cx23885: cx23885[1]:   risc2: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706071] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706077] cx23885: cx23885[1]:   risc3: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706079] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706088] cx23885: cx23885[1]:   (0x00010670) iq 0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706090] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706096] cx23885: cx23885[1]:   (0x00010674) iq 1: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706098] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706108] cx23885: cx23885[1]:   iq 2: 0x346105e0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706112] cx23885: cx23885[1]:   iq 3: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706117] cx23885: cx23885[1]:   (0x00010680) iq 4: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706119] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706128] cx23885: cx23885[1]:   iq 5: 0x346108d0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706133] cx23885: cx23885[1]:   iq 6: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706137] cx23885: cx23885[1]:   (0x0001068c) iq 7: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706139] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706148] cx23885: cx23885[1]:   iq 8: 0x34610bc0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706153] cx23885: cx23885[1]:   iq 9: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706157] cx23885: cx23885[1]:   (0x00010698) iq a: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706159] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706168] cx23885: cx23885[1]:   iq b: 0x34610eb0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706173] cx23885: cx23885[1]:   iq c: 0x00000000 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706177] cx23885: cx23885[1]:   (0x000106a4) iq d: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706179] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706185] cx23885: cx23885[1]:   (0x000106a8) iq e: 
Aug 28 19:23:59 mythtv-server kernel: [85544.706187] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706197] cx23885: cx23885[1]:   iq f: 0x346102f0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706202] cx23885: cx23885[1]:   iq 10: 0xb5d14b10 [ arg #2 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.706204] cx23885: cx23885[1]: fifo: 0x00006000 -> 0x7000
Aug 28 19:23:59 mythtv-server kernel: [85544.706207] cx23885: cx23885[1]: ctrl: 0x00010670 -> 0x106d0
Aug 28 19:23:59 mythtv-server kernel: [85544.706211] cx23885: cx23885[1]:   ptr1_reg: 0x000063a0
Aug 28 19:23:59 mythtv-server kernel: [85544.706215] cx23885: cx23885[1]:   ptr2_reg: 0x000108e8
Aug 28 19:23:59 mythtv-server kernel: [85544.706219] cx23885: cx23885[1]:   cnt1_reg: 0x0000000b
Aug 28 19:23:59 mythtv-server kernel: [85544.706223] cx23885: cx23885[1]:   cnt2_reg: 0x00000007
Aug 28 19:23:59 mythtv-server kernel: [85544.706334] cx23885: cx23885[1]: mpeg risc op code error
Aug 28 19:23:59 mythtv-server kernel: [85544.708249] cx23885: cx23885[1]: TS2 C - dma channel status dump
Aug 28 19:23:59 mythtv-server kernel: [85544.708255] cx23885: cx23885[1]:   cmds: init risc lo   : 0x346f0000
Aug 28 19:23:59 mythtv-server kernel: [85544.708260] cx23885: cx23885[1]:   cmds: init risc hi   : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.708264] cx23885: cx23885[1]:   cmds: cdt base       : 0x000108d0
Aug 28 19:23:59 mythtv-server kernel: [85544.708268] cx23885: cx23885[1]:   cmds: cdt size       : 0x0000000a
Aug 28 19:23:59 mythtv-server kernel: [85544.708273] cx23885: cx23885[1]:   cmds: iq base        : 0x00010670
Aug 28 19:23:59 mythtv-server kernel: [85544.708277] cx23885: cx23885[1]:   cmds: iq size        : 0x00000010
Aug 28 19:23:59 mythtv-server kernel: [85544.708282] cx23885: cx23885[1]:   cmds: risc pc lo     : 0x346f2054
Aug 28 19:23:59 mythtv-server kernel: [85544.708286] cx23885: cx23885[1]:   cmds: risc pc hi     : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.708291] cx23885: cx23885[1]:   cmds: iq wr ptr      : 0x000041a9
Aug 28 19:23:59 mythtv-server kernel: [85544.708295] cx23885: cx23885[1]:   cmds: iq rd ptr      : 0x0000419d
Aug 28 19:23:59 mythtv-server kernel: [85544.708300] cx23885: cx23885[1]:   cmds: cdt current    : 0x000108e8
Aug 28 19:23:59 mythtv-server kernel: [85544.708305] cx23885: cx23885[1]:   cmds: pci target lo  : 0x346102f0
Aug 28 19:23:59 mythtv-server kernel: [85544.708310] cx23885: cx23885[1]:   cmds: pci target hi  : 0x00000000
Aug 28 19:23:59 mythtv-server kernel: [85544.708314] cx23885: cx23885[1]:   cmds: line / byte    : 0x00410000
Aug 28 19:23:59 mythtv-server kernel: [85544.708319] cx23885: cx23885[1]:   risc0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.708326] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.708334] cx23885: cx23885[1]:   risc1: 
Aug 28 19:23:59 mythtv-server kernel: [85544.708336] 0x346102f0 [ INVALID eol 22 21 cnt0 count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.708347] cx23885: cx23885[1]:   risc2: 
Aug 28 19:23:59 mythtv-server kernel: [85544.708349] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.708355] cx23885: cx23885[1]:   risc3: 
Aug 28 19:23:59 mythtv-server kernel: [85544.708357] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.708366] cx23885: cx23885[1]:   (0x00010670) iq 0: 
Aug 28 19:23:59 mythtv-server kernel: [85544.708368] 0x00000000 [ INVALID count=0 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.708374] cx23885: cx23885[1]:   (0x00010674) iq 1: 
Aug 28 19:23:59 mythtv-server kernel: [85544.708376] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.708386] cx23885: cx23885[1]:   iq 2: 0x346105e0 [ arg #1 ]
Aug 28 19:23:59 mythtv-server kernel: [85544.708391] cx23885: cx23885[1]:   iq 3: 0x00000000 [ arg #2 ]

/var/log/syslog
syslog is the same story. Again - less then 1 second of logs. Lots of the cx23885 error.

Code: Select all

Aug 28 19:29:15 mythtv-server kernel: [85860.947931] si2157 6-0060: found a 'Silicon Labs Si2157-A30'
Aug 28 19:29:15 mythtv-server kernel: [85860.970358] si2157 6-0060: firmware version: 3.0.5
Aug 28 19:29:51 mythtv-server kernel: [85896.843707] si2157 6-0062: found a 'Silicon Labs Si2157-A30'
Aug 28 19:29:51 mythtv-server kernel: [85896.866259] si2157 6-0062: firmware version: 3.0.5
Aug 28 19:29:51 mythtv-server kernel: [85896.996458] cx23885: cx23885[1]: mpeg risc op code error
Aug 28 19:29:51 mythtv-server kernel: [85896.996469] cx23885: cx23885[0]: mpeg risc op code error
Aug 28 19:29:51 mythtv-server kernel: [85896.996474] cx23885: cx23885[0]: TS1 B - dma channel status dump
Aug 28 19:29:51 mythtv-server kernel: [85896.996482] cx23885: cx23885[0]:   cmds: init risc lo   : 0x34372000
Aug 28 19:29:51 mythtv-server kernel: [85896.996486] cx23885: cx23885[0]:   cmds: init risc hi   : 0x00000000
Aug 28 19:29:51 mythtv-server kernel: [85896.996491] cx23885: cx23885[0]:   cmds: cdt base       : 0x00010870
Aug 28 19:29:51 mythtv-server kernel: [85896.996495] cx23885: cx23885[0]:   cmds: cdt size       : 0x0000000a
Aug 28 19:29:51 mythtv-server kernel: [85896.996499] cx23885: cx23885[0]:   cmds: iq base        : 0x00010630
Aug 28 19:29:51 mythtv-server kernel: [85896.996503] cx23885: cx23885[0]:   cmds: iq size        : 0x00000010
Aug 28 19:29:51 mythtv-server kernel: [85896.996508] cx23885: cx23885[0]:   cmds: risc pc lo     : 0x3425018c
Aug 28 19:29:51 mythtv-server kernel: [85896.996512] cx23885: cx23885[0]:   cmds: risc pc hi     : 0x00000000
Aug 28 19:29:51 mythtv-server kernel: [85896.996518] cx23885: cx23885[0]:   cmds: iq wr ptr      : 0x00004195
Aug 28 19:29:51 mythtv-server kernel: [85896.996522] cx23885: cx23885[0]:   cmds: iq rd ptr      : 0x00004192
Aug 28 19:29:51 mythtv-server kernel: [85896.996526] cx23885: cx23885[0]:   cmds: cdt current    : 0x00010898
Aug 28 19:29:51 mythtv-server kernel: [85896.996530] cx23885: cx23885[0]:   cmds: pci target lo  : 0x342fd820
Aug 28 19:29:51 mythtv-server kernel: [85896.996534] cx23885: cx23885[0]:   cmds: pci target hi  : 0x00000000
Aug 28 19:29:51 mythtv-server kernel: [85896.996538] cx23885: cx23885[0]:   cmds: line / byte    : 0x02de0000
Aug 28 19:29:51 mythtv-server kernel: [85896.996542] cx23885: cx23885[0]:   risc0: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996545] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996552] cx23885: cx23885[0]:   risc1: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996554] 0x342fd530 [ INVALID eol 21 19 18 cnt1 cnt0 resync 14 12 count=1328 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996567] cx23885: cx23885[0]:   risc2: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996569] 0x00000000 [ INVALID count=0 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996573] cx23885: cx23885[0]:   risc3: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996575] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996582] cx23885: cx23885[0]:   (0x00010630) iq 0: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996584] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996592] cx23885: cx23885[0]:   iq 1: 0x342fd240 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996596] cx23885: cx23885[0]:   iq 2: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996600] cx23885: cx23885[0]:   (0x0001063c) iq 3: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996602] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996609] cx23885: cx23885[0]:   iq 4: 0x342fd530 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996614] cx23885: cx23885[0]:   iq 5: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996618] cx23885: cx23885[0]:   (0x00010648) iq 6: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996619] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996627] cx23885: cx23885[0]:   iq 7: 0x342fd820 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996631] cx23885: cx23885[0]:   iq 8: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996635] cx23885: cx23885[0]:   (0x00010654) iq 9: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996637] 0x00000000 [ INVALID count=0 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996642] cx23885: cx23885[0]:   (0x00010658) iq a: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996644] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996651] cx23885: cx23885[0]:   iq b: 0x342fcc60 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996656] cx23885: cx23885[0]:   iq c: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996659] cx23885: cx23885[0]:   (0x00010664) iq d: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996661] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996669] cx23885: cx23885[0]:   iq e: 0x342fcf50 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996673] cx23885: cx23885[0]:   iq f: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996675] cx23885: cx23885[0]: fifo: 0x00005000 -> 0x6000
Aug 28 19:29:51 mythtv-server kernel: [85896.996677] cx23885: cx23885[0]: ctrl: 0x00010630 -> 0x10690
Aug 28 19:29:51 mythtv-server kernel: [85896.996681] cx23885: cx23885[0]:   ptr1_reg: 0x000050b0
Aug 28 19:29:51 mythtv-server kernel: [85896.996685] cx23885: cx23885[0]:   ptr2_reg: 0x00010878
Aug 28 19:29:51 mythtv-server kernel: [85896.996689] cx23885: cx23885[0]:   cnt1_reg: 0x0000000b
Aug 28 19:29:51 mythtv-server kernel: [85896.996692] cx23885: cx23885[0]:   cnt2_reg: 0x00000009
Aug 28 19:29:51 mythtv-server kernel: [85896.996694] cx23885: cx23885[0]: mpeg risc op code error
Aug 28 19:29:51 mythtv-server kernel: [85896.996698] cx23885: cx23885[0]: TS2 C - dma channel status dump
Aug 28 19:29:51 mythtv-server kernel: [85896.996702] cx23885: cx23885[0]:   cmds: init risc lo   : 0x344e1000
Aug 28 19:29:51 mythtv-server kernel: [85896.996706] cx23885: cx23885[0]:   cmds: init risc hi   : 0x00000000
Aug 28 19:29:51 mythtv-server kernel: [85896.996710] cx23885: cx23885[0]:   cmds: cdt base       : 0x000108d0
Aug 28 19:29:51 mythtv-server kernel: [85896.996714] cx23885: cx23885[0]:   cmds: cdt size       : 0x0000000a
Aug 28 19:29:51 mythtv-server kernel: [85896.996718] cx23885: cx23885[0]:   cmds: iq base        : 0x00010670
Aug 28 19:29:51 mythtv-server kernel: [85896.996722] cx23885: cx23885[0]:   cmds: iq size        : 0x00000010
Aug 28 19:29:51 mythtv-server kernel: [85896.996726] cx23885: cx23885[0]:   cmds: risc pc lo     : 0x34641054
Aug 28 19:29:51 mythtv-server kernel: [85896.996730] cx23885: cx23885[0]:   cmds: risc pc hi     : 0x00000000
Aug 28 19:29:51 mythtv-server kernel: [85896.996734] cx23885: cx23885[0]:   cmds: iq wr ptr      : 0x0000419f
Aug 28 19:29:51 mythtv-server kernel: [85896.996738] cx23885: cx23885[0]:   cmds: iq rd ptr      : 0x000041a3
Aug 28 19:29:51 mythtv-server kernel: [85896.996742] cx23885: cx23885[0]:   cmds: cdt current    : 0x000108d8
Aug 28 19:29:51 mythtv-server kernel: [85896.996747] cx23885: cx23885[0]:   cmds: pci target lo  : 0x340dc2f0
Aug 28 19:29:51 mythtv-server kernel: [85896.996751] cx23885: cx23885[0]:   cmds: pci target hi  : 0x00000000
Aug 28 19:29:51 mythtv-server kernel: [85896.996755] cx23885: cx23885[0]:   cmds: line / byte    : 0x01c10000
Aug 28 19:29:51 mythtv-server kernel: [85896.996758] cx23885: cx23885[0]:   risc0: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996760] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996766] cx23885: cx23885[0]:   risc1: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996768] 0x340dc2f0 [ INVALID eol 19 18 cnt0 resync 14 count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996778] cx23885: cx23885[0]:   risc2: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996780] 0x00000000 [ INVALID count=0 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996784] cx23885: cx23885[0]:   risc3: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996786] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996793] cx23885: cx23885[0]:   (0x00010670) iq 0: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996795] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996802] cx23885: cx23885[0]:   iq 1: 0x340dceb0 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996807] cx23885: cx23885[0]:   iq 2: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996811] cx23885: cx23885[0]:   (0x0001067c) iq 3: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996812] 0x00000000 [ INVALID count=0 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996818] cx23885: cx23885[0]:   (0x00010680) iq 4: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996819] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996827] cx23885: cx23885[0]:   iq 5: 0x340dc2f0 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996831] cx23885: cx23885[0]:   iq 6: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996835] cx23885: cx23885[0]:   (0x0001068c) iq 7: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996837] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996844] cx23885: cx23885[0]:   iq 8: 0x340dc5e0 [ arg #1 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996849] cx23885: cx23885[0]:   iq 9: 0x00000000 [ arg #2 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996852] cx23885: cx23885[0]:   (0x00010698) iq a: 
Aug 28 19:29:51 mythtv-server kernel: [85896.996854] 0x1c0002f0 [ write sol eol count=752 ]
Aug 28 19:29:51 mythtv-server kernel: [85896.996862] cx23885: cx23885[0]:   iq b: 0x340dc8d0 [ arg #1 ]
...

Searching for the Bug

The more I read - the more I think I have a new version of the bug discussed here.
https://github.com/b-rad-NDi/Ubuntu-med ... /issues/17

It seems that they tried to address this 6 months ago. I'm using the Hauppauge kernel. Any ideas about reaching out? I could do it directly, but I would love some help to make sure I'm communicating the right data. ALSO - I don't have a github account...

[MikeB2013]

There are a few things to check/update, which may narrow down the issue.

1. The mythtv-backend-master version in Bionic repository is old, so it would be worthwhile to update to the latest version of mythtv 29 by doing the following in a terminal session:

Code: Select all

sudo add-apt-repository ppa:mythbuntu/0.29
sudo apt-get update
sudo apt-get upgrade

2. Using mythtv-setup change some settings for each of your Capture Cards, but first stop mythbackend and then go into mythtv-setup using a terminal session:

Code: Select all

sudo systemctl stop mythtv-backend
mythtv-setup

For each of your Capture Cards (there should be 4)

untick "Open xxx card on demand"
untick "Use xxx card for active EIT scan" on all but one of the Capture Cards, suggest this is only ticked on the 4th Capture Card. It is possible to have this ticked on more than one Capture Card, but it is not useful and really only serves to increase processing load.

Exit from mythtv-setup (say no to any prompts relating to starting mythbackend and mythfilldatabase)

EDIT:
Now start mythtv-backend, by rebooting the system, the mpeg risc errors you are seeing are not always cleared by restarting mythbackend.

Normally you should restart mythbackend using:

Code: Select all

sudo systemctl start mythtv-backend

Note: I am running the same Hauppauge kernel on Xubuntu Desktop 18.04.1 on motherboard Asus STRIX B250F GAMING, BIOS 1205 05/11/2018 with mythtv master (pre 30 built from source) with the QuadHD DVB version, with no issues.

[MikeB2013]

Regarding logs, snippets can be misleading, it is best to post the whole log to somewhere like pastebin and post the link pastebin responds with to the file on this forum.

As an example, to put the output of dmesg command to on to pastebin, just use the following in a terminal session:

Code: Select all

dmesg | pastebinit 
http://paste.ubuntu.com/p/9RtydDZkYN/

For the mythbackend log use:

Code: Select all

pastebinit /var/log/mythtv/mythbackend.log
http://paste.ubuntu.com/p/8CckFZGryx/

[crum]

MikeB2013 - Thank you. I didn't know about the pastebinit - very helpful.

Actions:
I added the mythbuntu PPA, upgraded and rebooted.
I also adjusted my backend settings per your comments.

Results:
- The frequency of the cx23885 error slowed down at first, but started again after the first recording.
- My recordings are still failing - but one made it almost 17 mins this time (835 MB). The others failed between 5 and 10 mins.

Logs:
dmesg log is here:
http://paste.ubuntu.com/p/zGgVPGBfvk/

mythbackend.log is here:
http://paste.ubuntu.com/p/qRTkjvpMDM/

[MikeB2013]

Thanks for the logs, the full dmesg output is very useful and I have raised a new issue at:

https://github.com/b-rad-NDi/Ubuntu-med ... /issues/51

Mike

[crum]

MikeB2013 - Wow! Thank you. Very kind of you to take the time.

This is a sole-purpose machine so I'm willing to experiment as needed (e.g., happy to break stuff). Note that I'm a junior hobbyist so I might need a little hand holding - or a point to a good reference - for advanced tinkering.

[MikeB2013]

@crum

Brad Love at Hauppauge has responded, due to my time zone difference , I suggest you monitor the issue thread at https://github.com/b-rad-NDi/Ubuntu-med ... /issues/51

There is a utility w-scan that scans for channels which may provoke the mpeg risc errors (you are seeing a lot of these errors) so I think it is worth a try.

You need to do the following in a terminal session:

Install w-scan (sudo apt install w-scan)

stop mythtv-backend (sudo systemctl stop mythtv-backend)
disable mythtv-backend from auto starting at boot (sudo systemctl disable mythtv-backend)
reboot (sudo reboot)
then run, again in a terminal session
w_scan -fa -A1 -c US -a 0

w_scan will likely take a few minutes to run (on my system it takes around 15 minutes).

Then have a look a dmesg output for the mpeg risc errors, if present send complete dmesg output to pastebin
And finally re-enable mythtv-backend (sudo systemctl enable mythtv-backend) and start it (sudo systemctl start mythtv-backend)

Mike

[crum]

@mike

I'll follow the github thread. Gonna need to buy both of you a beer after this.

TESTS:
- I installed w_scan, disabled mythtv and rebooted.
- Ran "w_scan -fa -A1 -c US -a 0" and checked for the event in dmesg three times. No dice.
- If it's important - my system did each search in about 4 mins.

LOGS:
http://paste.ubuntu.com/p/xWw7CtSF5F/

On another note:
I did see some lines indicating denied access of some type. I assume not related to this issue.

Code: Select all

[   49.213856] audit: type=1400 audit(1535675106.201:25): apparmor="DENIED" operation="open" profile="/usr/sbin/mysqld" name="/sys/devices/system/node/" pid=1349 comm="mysqld" requested_mask="r" denied_mask="r" fsuid=111 ouid=0

[MikeB2013]

Running w_scan was a long shot, Brad Love thought it probably would not show the problem, he was correct. The problem is how to easily trigger the mpeg risc errors on demand. I get these errors (non-fatal) maybe one every few weeks from my QuadHD DVB card.

The w_scan run time of 4 minutes is fine, in UK we have a different standard (DVB rather than ATSC) so it takes w_scan a lot longer as there are more frequencies and transmission modes to scan.

You are correct the "audit.... " messages are not related and can be ignored.

Can you paste the output of "cat /proc/interrupts", no need to use pastebin for this - the output will be relatively small.

Mike

[crum]

@Mike - here you go:

LOGS: cat /proc/interrupts
NOTE: The machine was off overnight so this is a pull from a fresh boot.

Code: Select all

crumka@mythtv-server:~$ cat /proc/interrupts
           CPU0       CPU1       CPU2       CPU3       CPU4       CPU5       CPU6       CPU7       
  0:         11          0          0          0          0          0          0          0   IO-APIC   2-edge      timer
  8:          1          0          0          0          0          0          0          0   IO-APIC   8-edge      rtc0
  9:          0          0          0          0          0          0          0          0   IO-APIC   9-fasteoi   acpi
 20:          0          0          0          0       1376          0          0          0   IO-APIC  20-fasteoi   cx23885[1]
 23:          0          0          0          0          0          0          0        141   IO-APIC  23-fasteoi   ehci_hcd:usb1, cx23885[0]
 24:          0          0          0          0          0          0          0          0   PCI-MSI 16384-edge      aerdrv, PCIe PME
 25:          0          0          0          0          0          0          0          0   PCI-MSI 32768-edge      aerdrv, PCIe PME
 26:          0          0          0          0          0          0          0          0   PCI-MSI 49152-edge      aerdrv, PCIe PME
 29:         25          0          0          0          0          0          0          0   PCI-MSI 1572864-edge      xhci_hcd
 30:          0          0          0          0          0          0          0          0   PCI-MSI 1572865-edge      xhci_hcd
 31:          0          0          0          0          0          0          0          0   PCI-MSI 1572866-edge      xhci_hcd
 32:          0          0          0          0          0          0          0          0   PCI-MSI 1572867-edge      xhci_hcd
 33:          0          0          0          0          0          0          0          0   PCI-MSI 1572868-edge      xhci_hcd
 34:          0          0          0          0          0          0          0          0   PCI-MSI 1572869-edge      xhci_hcd
 35:          0          0          0          0          0          0          0          0   PCI-MSI 1572870-edge      xhci_hcd
 36:          0          0          0          0          0          0          0          0   PCI-MSI 1572871-edge      xhci_hcd
 37:          0          0          0          0          0          0          0          0   PCI-MSI 311296-edge      ismt-msi
 38:          0          0          0          0          0          0          0          0   PCI-MSI 376832-edge      ahci[0000:00:17.0]
 39:          0          0       2628       6656          0          0          0          0   PCI-MSI 393216-edge      ahci[0000:00:18.0]
 40:          1          0          0          0          0          0          0          0   PCI-MSI 327680-edge      enp0s20f0
 41:          0         30          0          0          0          0          0        103   PCI-MSI 327681-edge      enp0s20f0-TxRx-0
 42:          0          0         20          0          0          0        110          0   PCI-MSI 327682-edge      enp0s20f0-TxRx-1
 43:          0          0          0         13          0        101          0          0   PCI-MSI 327683-edge      enp0s20f0-TxRx-2
 44:          0          0          0          0        107          0          0          0   PCI-MSI 327684-edge      enp0s20f0-TxRx-3
 45:          0          0          0        102          0         25          0          0   PCI-MSI 327685-edge      enp0s20f0-TxRx-4
 46:          0          0        103          0          0          0         15          0   PCI-MSI 327686-edge      enp0s20f0-TxRx-5
 47:          0          0          0         96          0          0          0         13   PCI-MSI 327687-edge      enp0s20f0-TxRx-6
 48:        126          0          0          0          0          0          0          0   PCI-MSI 327688-edge      enp0s20f0-TxRx-7
 49:          0          0          0          0          0          0          0          0   PCI-MSI 329728-edge      enp0s20f1
 50:         11          0          0         90          0          0          0          0   PCI-MSI 329729-edge      enp0s20f1-TxRx-0
 51:          0         11         90          0          0          0          0          0   PCI-MSI 329730-edge      enp0s20f1-TxRx-1
 52:          0         90         11          0          0          0          0          0   PCI-MSI 329731-edge      enp0s20f1-TxRx-2
 53:         90          0          0         11          0          0          0          0   PCI-MSI 329732-edge      enp0s20f1-TxRx-3
 54:          0          0          0          0         11          0         90          0   PCI-MSI 329733-edge      enp0s20f1-TxRx-4
 55:          0          0          0          0          0         11          0         90   PCI-MSI 329734-edge      enp0s20f1-TxRx-5
 56:          0          0          0          0         90          0         11          0   PCI-MSI 329735-edge      enp0s20f1-TxRx-6
 57:          0          0          0          0          0          0          0        101   PCI-MSI 329736-edge      enp0s20f1-TxRx-7
 58:          0          0          0          0          0          0          1          0   PCI-MSI 331776-edge      enp0s20f2
 59:          0          0          0          0          0         51        182         59   PCI-MSI 331777-edge      enp0s20f2-TxRx-0
 60:         31          0          0          0        134          0          0          0   PCI-MSI 331778-edge      enp0s20f2-TxRx-1
 61:          0         23          0        279          0          0          0          0   PCI-MSI 331779-edge      enp0s20f2-TxRx-2
 62:          0          0        139          0          0          0          0          0   PCI-MSI 331780-edge      enp0s20f2-TxRx-3
 63:          0        107          0         40          0          0          0          0   PCI-MSI 331781-edge      enp0s20f2-TxRx-4
 64:        105          0          0          0         24          0          0          0   PCI-MSI 331782-edge      enp0s20f2-TxRx-5
 65:          0        142          0          0          0         39          0          0   PCI-MSI 331783-edge      enp0s20f2-TxRx-6
 66:          0          0          0          0          0          0         47        110   PCI-MSI 331784-edge      enp0s20f2-TxRx-7
 67:          0          0          0          0          0          0          0          0   PCI-MSI 333824-edge      enp0s20f3
 68:          0          0          0          0          0         90         11          0   PCI-MSI 333825-edge      enp0s20f3-TxRx-0
 69:          0          0          0          0         90          0          0         11   PCI-MSI 333826-edge      enp0s20f3-TxRx-1
 70:         11          0         90          0          0          0          0          0   PCI-MSI 333827-edge      enp0s20f3-TxRx-2
 71:          0         11          0         90          0          0          0          0   PCI-MSI 333828-edge      enp0s20f3-TxRx-3
 72:         90          0         11          0          0          0          0          0   PCI-MSI 333829-edge      enp0s20f3-TxRx-4
 73:          0         90          0         11          0          0          0          0   PCI-MSI 333830-edge      enp0s20f3-TxRx-5
 74:          0          0          0          0         11          0          0         90   PCI-MSI 333831-edge      enp0s20f3-TxRx-6
 75:          0          0          0          0          0        101          0          0   PCI-MSI 333832-edge      enp0s20f3-TxRx-7
NMI:          0          1          0          0          0          0          0          0   Non-maskable interrupts
LOC:       7490       9625       6928       6053       9394      10777       5101       5567   Local timer interrupts
SPU:          0          0          0          0          0          0          0          0   Spurious interrupts
PMI:          0          1          0          0          0          0          0          0   Performance monitoring interrupts
IWI:       2347       2486       2085       1808       3154       5039       1370       1551   IRQ work interrupts
RTR:          0          0          0          0          0          0          0          0   APIC ICR read retries
RES:        607        232        350        320        280        273        272        248   Rescheduling interrupts
CAL:       2439       2366       1762       1701       3488       4328       2504       4050   Function call interrupts
TLB:         11         24         14         24         21         27          8         12   TLB shootdowns
TRM:          0          0          0          0          0          0          0          0   Thermal event interrupts
THR:          0          0          0          0          0          0          0          0   Threshold APIC interrupts
DFR:          0          0          0          0          0          0          0          0   Deferred Error APIC interrupts
MCE:          0          0          0          0          0          0          0          0   Machine check exceptions
MCP:          1          1          1          1          1          1          1          1   Machine check polls
HYP:          0          0          0          0          0          0          0          0   Hypervisor callback interrupts
ERR:          0
MIS:          0
PIN:          0          0          0          0          0          0          0          0   Posted-interrupt notification event
NPI:          0          0          0          0          0          0          0          0   Nested posted-interrupt event
PIW:          0          0          0          0          0          0          0          0   Posted-interrupt wakeup event

[crum]

Kernel changes rolled into 4.20