After running cam_test for a while I get "ERROR: CAM: Read failed: slot 0, tcid 1". The error seems to occur pretty randomly (after half a minute, a couple of minutes or even after an hour or so).
The actual error message is printed from line 407 in libdvbci/ci.cc:
$ nl libdvb-0.5.4/libdvbci/ci.cc | grep "Read failed"
407 esyslog("ERROR: CAM: Read failed: slot %d, tcid %d\n", slot, tcid);
I'd appreciate it if someone a bit more knowledgeable could take a look at the logs below and tell me what's going on. I don't know how this stuff works but to me it looks like there are two interrupts at the same time, and somehow the driver thread gets confused by this?
$ ./cam_set 2>&1 | awk '{print strftime("%H:%M:%S - ", systime()) $0}'
20:28:06 - Found 1CAM(s)
20:28:06 - Using /dev/dvb/adapter0/ca0
20:28:06 - Found 1 slots
20:28:09 - CAM: Conax 4.00e, 01, 0B00, 04B1
20:28:16 - New connection established.
20:28:16 - CI HANDLER: connection established
20:28:16 - CI HANDLER: 0x642 0x200 0x280 0x0 0xffff 0x6 | 0x9 0x4 0xb 0x0 0xe0 0x40 ok
20:28:16 - SysID b00
20:28:16 - Slot 0
20:28:16 - checking b00
20:28:16 - state: CAM
20:28:16 - CI HANDLER: client hung up
20:28:16 - state: NONE
20:37:14 - ERROR: CAM: Read failed: slot 0, tcid 1
20:37:14 -
20:37:20 - CAM: Conax 4.00e, 01, 0B00, 04B1
$ ./cam_test -q 11216000 -l V -r 24500000 -i 1602 2>&1 | awk '{print strftime("%H:%M:%S - ", systime()) $0}'
20:28:13 - Warning: TP already defined:
20:28:13 - ID: 1 SATID: 1
20:28:16 - Channel: TV4 apid: 0x280 vpid: 0x200 ttpid: 0x240
20:28:16 - CAM Test: 0x642 0x200 0x280 0x0 0xffff 0x6
Syslog:
May 12 20:37:14 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:14 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:14 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:14 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:14 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:14 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:14 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:14 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:14 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:14 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:14 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:14 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:14 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:14 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:14 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_do_ioctl
May 12 20:37:14 htpc kernel: dvb_ca_en50221_slot_shutdown
May 12 20:37:14 htpc kernel: Slot 0 shutdown
May 12 20:37:14 htpc kernel: CAMCHANGE IRQ slot:0 change_type:1
May 12 20:37:14 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:14 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_do_ioctl
May 12 20:37:14 htpc last message repeated 243 times
May 12 20:37:14 htpc kernel: CAMREADY IRQ slot:0
May 12 20:37:14 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:14 htpc kernel: TUPLE type:0x1d length:4
May 12 20:37:14 htpc kernel: 0x00: 0x00 .
May 12 20:37:14 htpc kernel: 0x01: 0xdb .
May 12 20:37:14 htpc kernel: 0x02: 0x08 .
May 12 20:37:14 htpc kernel: 0x03: 0xff .
May 12 20:37:14 htpc kernel: TUPLE type:0x1c length:3
May 12 20:37:14 htpc kernel: 0x00: 0x00 .
May 12 20:37:14 htpc kernel: 0x01: 0x08 .
May 12 20:37:14 htpc kernel: 0x02: 0xff .
May 12 20:37:14 htpc kernel: TUPLE type:0x15 length:21
May 12 20:37:14 htpc kernel: 0x00: 0x05 .
May 12 20:37:14 htpc kernel: 0x01: 0x00 .
May 12 20:37:14 htpc kernel: 0x02: 0x53 S
May 12 20:37:14 htpc kernel: 0x03: 0x43 C
May 12 20:37:14 htpc kernel: 0x04: 0x4d M
May 12 20:37:14 htpc kernel: 0x05: 0x00 .
May 12 20:37:14 htpc kernel: 0x06: 0x44 D
May 12 20:37:14 htpc kernel: 0x07: 0x56 V
May 12 20:37:14 htpc kernel: 0x08: 0x42 B
May 12 20:37:14 htpc kernel: 0x09: 0x20 May 12 20:37:14 htpc kernel: 0x0a: 0x43 C
May 12 20:37:14 htpc kernel: 0x0b: 0x41 A
May 12 20:37:14 htpc kernel: 0x0c: 0x20 May 12 20:37:14 htpc kernel: 0x0d: 0x4d M
May 12 20:37:14 htpc kernel: 0x0e: 0x6f o
May 12 20:37:14 htpc kernel: 0x0f: 0x64 d
May 12 20:37:14 htpc kernel: 0x10: 0x75 u
May 12 20:37:14 htpc kernel: 0x11: 0x6c l
May 12 20:37:14 htpc kernel: 0x12: 0x65 e
May 12 20:37:14 htpc kernel: 0x13: 0x00 .
May 12 20:37:14 htpc kernel: 0x14: 0xff .
May 12 20:37:14 htpc kernel: TUPLE type:0x20 length:4
May 12 20:37:14 htpc kernel: 0x00: 0xff .
May 12 20:37:14 htpc kernel: 0x01: 0xff .
May 12 20:37:14 htpc kernel: 0x02: 0x01 .
May 12 20:37:14 htpc kernel: 0x03: 0x00 .
May 12 20:37:14 htpc kernel: TUPLE type:0x1a length:21
May 12 20:37:14 htpc kernel: 0x00: 0x01 .
May 12 20:37:14 htpc kernel: 0x01: 0x0f .
May 12 20:37:14 htpc kernel: 0x02: 0xfe .
May 12 20:37:14 htpc kernel: 0x03: 0x01 .
May 12 20:37:14 htpc kernel: 0x04: 0x01 .
May 12 20:37:14 htpc kernel: 0x05: 0xc0 .
May 12 20:37:14 htpc kernel: 0x06: 0x0e .
May 12 20:37:14 htpc kernel: 0x07: 0x41 A
May 12 20:37:14 htpc kernel: 0x08: 0x02 .
May 12 20:37:14 htpc kernel: 0x09: 0x44 D
May 12 20:37:14 htpc kernel: 0x0a: 0x56 V
May 12 20:37:14 htpc kernel: 0x0b: 0x42 B
May 12 20:37:14 htpc kernel: 0x0c: 0x5f _
May 12 20:37:14 htpc kernel: 0x0d: 0x43 C
May 12 20:37:14 htpc kernel: 0x0e: 0x49 I
May 12 20:37:14 htpc kernel: 0x0f: 0x5f _
May 12 20:37:14 htpc kernel: 0x10: 0x56 V
May 12 20:37:14 htpc kernel: 0x11: 0x31 1
May 12 20:37:14 htpc kernel: 0x12: 0x2e .
May 12 20:37:14 htpc kernel: 0x13: 0x30 0
May 12 20:37:14 htpc kernel: 0x14: 0x30 0
May 12 20:37:14 htpc kernel: TUPLE type:0x1b length:17
May 12 20:37:14 htpc kernel: 0x00: 0xc9 .
May 12 20:37:14 htpc kernel: 0x01: 0x41 A
May 12 20:37:14 htpc kernel: 0x02: 0x19 .
May 12 20:37:14 htpc kernel: 0x03: 0x37 7
May 12 20:37:14 htpc kernel: 0x04: 0x55 U
May 12 20:37:14 htpc kernel: 0x05: 0x4e N
May 12 20:37:14 htpc kernel: 0x06: 0x5e ^
May 12 20:37:14 htpc kernel: 0x07: 0x1d .
May 12 20:37:14 htpc kernel: 0x08: 0x56 V
May 12 20:37:14 htpc kernel: 0x09: 0xaa .
May 12 20:37:14 htpc kernel: 0x0a: 0x60 `
May 12 20:37:14 htpc kernel: 0x0b: 0x20 May 12 20:37:14 htpc kernel: 0x0c: 0x03 .
May 12 20:37:14 htpc kernel: 0x0d: 0x03 .
May 12 20:37:14 htpc kernel: 0x0e: 0x50 P
May 12 20:37:14 htpc kernel: 0x0f: 0xff .
May 12 20:37:14 htpc kernel: 0x10: 0xff .
May 12 20:37:14 htpc kernel: TUPLE type:0x1b length:37
May 12 20:37:14 htpc kernel: 0x00: 0xcf .
May 12 20:37:14 htpc kernel: 0x01: 0x04 .
May 12 20:37:14 htpc kernel: 0x02: 0x09 .
May 12 20:37:14 htpc kernel: 0x03: 0x37 7
May 12 20:37:14 htpc kernel: 0x04: 0x55 U
May 12 20:37:14 htpc kernel: 0x05: 0x4d M
May 12 20:37:14 htpc kernel: 0x06: 0x5d ]
May 12 20:37:14 htpc kernel: 0x07: 0x1d .
May 12 20:37:14 htpc kernel: 0x08: 0x56 V
May 12 20:37:14 htpc kernel: 0x09: 0x22 "
May 12 20:37:14 htpc kernel: 0x0a: 0xc0 .
May 12 20:37:14 htpc kernel: 0x0b: 0x09 .
May 12 20:37:14 htpc kernel: 0x0c: 0x44 D
May 12 20:37:14 htpc kernel: 0x0d: 0x56 V
May 12 20:37:14 htpc kernel: 0x0e: 0x42 B
May 12 20:37:14 htpc kernel: 0x0f: 0x5f _
May 12 20:37:14 htpc kernel: 0x10: 0x48 H
May 12 20:37:14 htpc kernel: 0x11: 0x4f O
May 12 20:37:14 htpc kernel: 0x12: 0x53 S
May 12 20:37:14 htpc kernel: 0x13: 0x54 T
May 12 20:37:14 htpc kernel: 0x14: 0x00 .
May 12 20:37:14 htpc kernel: 0x15: 0xc1 .
May 12 20:37:14 htpc kernel: 0x16: 0x0e .
May 12 20:37:14 htpc kernel: 0x17: 0x44 D
May 12 20:37:14 htpc kernel: 0x18: 0x56 V
May 12 20:37:14 htpc kernel: 0x19: 0x42 B
May 12 20:37:14 htpc kernel: 0x1a: 0x5f _
May 12 20:37:14 htpc kernel: 0x1b: 0x43 C
May 12 20:37:14 htpc kernel: 0x1c: 0x49 I
May 12 20:37:14 htpc kernel: 0x1d: 0x5f _
May 12 20:37:14 htpc kernel: 0x1e: 0x4d M
May 12 20:37:14 htpc kernel: 0x1f: 0x4f O
May 12 20:37:14 htpc kernel: 0x20: 0x44 D
May 12 20:37:14 htpc kernel: 0x21: 0x55 U
May 12 20:37:14 htpc kernel: 0x22: 0x4c L
May 12 20:37:14 htpc kernel: 0x23: 0x45 E
May 12 20:37:14 htpc kernel: 0x24: 0x00 .
May 12 20:37:14 htpc kernel: TUPLE type:0x14 length:0
May 12 20:37:14 htpc kernel: TUPLE type:0xff length:0
May 12 20:37:14 htpc kernel: Valid DVB CAM detected MANID:ffff DEVID:1 CONFIGBASE:0x1fe CONFIGOPTION:0xf
May 12 20:37:14 htpc kernel: dvb_ca_en50221_set_configoption
May 12 20:37:14 htpc kernel: Set configoption 0xf, read configoption 0xf
May 12 20:37:14 htpc kernel: DVB CAM validated successfully
May 12 20:37:14 htpc kernel: dvb_ca_en50221_io_do_ioctl
May 12 20:37:16 htpc last message repeated 1798 times
May 12 20:37:16 htpc kernel: dvb_ca_en50221_link_init
May 12 20:37:16 htpc kernel: dvb_ca_en50221_wait_if_status
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_do_ioctl
May 12 20:37:16 htpc kernel: dvb_ca_en50221_wait_if_status succeeded timeout:1
May 12 20:37:16 htpc kernel: dvb_ca_en50221_wait_if_status
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_do_ioctl
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: CAM supports DA IRQ
May 12 20:37:16 htpc kernel: dvb_ca_en50221_wait_if_status succeeded timeout:1
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: Chosen link buffer size of 128
May 12 20:37:16 htpc kernel: dvb_ca_en50221_wait_if_status
May 12 20:37:16 htpc kernel: dvb_ca_en50221_wait_if_status succeeded timeout:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_do_ioctl
May 12 20:37:16 htpc kernel: dvb_ca: DVB CAM detected and initialised successfully
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_do_ioctl
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
May 12 20:37:16 htpc kernel: dvb_ca_en50221_thread_wakeup
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_read_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_read
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_write
May 12 20:37:16 htpc kernel: dvb_ca_en50221_write_data
May 12 20:37:16 htpc kernel: dvb_ca_en50221_io_poll
May 12 20:37:16 htpc kernel: FR/DA IRQ slot:0
-- Info: To unsubscribe send a mail to [EMAIL PROTECTED] with "unsubscribe linux-dvb" as subject.
