Here are the results with the device.c patch you gave me :
May 4 15:27:15 localhost vdr: [7031] video directory scanner thread started (pid=7030, tid=7031) May 4 15:27:15 localhost vdr: [7030] reading EPG data from /video/epg.data May 4 15:27:15 localhost vdr: [7032] video directory scanner thread started (pid=7030, tid=7032) May 4 15:27:15 localhost vdr: [7032] video directory scanner thread ended (pid=7030, tid=7032) May 4 15:27:15 localhost vdr: [7030] probing /dev/dvb/adapter0/frontend0 May 4 15:27:15 localhost vdr: [7031] video directory scanner thread ended (pid=7030, tid=7031) May 4 15:27:15 localhost vdr: [7034] CI adapter on device 0 thread started (pid=7030, tid=7034) May 4 15:27:15 localhost vdr: [7030] device 1 provides: DVBS May 4 15:27:15 localhost vdr: [7030] found 1 video device May 4 15:27:15 localhost vdr: [7030] initializing plugin: remote (0.3.9): Remote control May 4 15:27:15 localhost vdr: [7030] setting primary device to 1 May 4 15:27:15 localhost vdr: [7035] tuner on device 1 thread started (pid=7030, tid=7035) May 4 15:27:15 localhost vdr: [7036] section handler thread started (pid=7030, tid=7036) May 4 15:27:15 localhost vdr: [7030] assuming manual start of VDR May 4 15:27:15 localhost vdr: [7030] SVDRP listening on port 2001 May 4 15:27:15 localhost vdr: [7030] setting current skin to "sttng" May 4 15:27:15 localhost vdr: [7030] loading /etc/vdr/themes/sttng-default.theme May 4 15:27:15 localhost vdr: [7030] starting plugin: remote May 4 15:27:15 localhost vdr: [7030] plugin 'remote' called obsolete function RegisterI18n() May 4 15:27:15 localhost vdr: [7030] remote: using '/dev/input/event3' May 4 15:27:15 localhost vdr: [7030] remote-event3: autorepeat supported May 4 15:27:15 localhost vdr: [7030] remote-event3: exclusive access granted May 4 15:27:15 localhost vdr: [7030] remote-event3: keymap loaded '/proc/av7110_ir' flags 001e4000 May 4 15:27:15 localhost vdr: [7030] remote: using 'tcp:3333' May 4 15:27:15 localhost vdr: [7030] remote control remote-event3 - keys known May 4 15:27:15 localhost vdr: [7030] remote control remote-tcp:3333 - keys known May 4 15:27:15 localhost vdr: [7030] remote control KBD - keys known May 4 15:27:15 localhost vdr: [7039] KBD remote control thread started (pid=7030, tid=7039) May 4 15:27:16 localhost vdr: [7034] CAM 2: module present May 4 15:27:17 localhost vdr: [7034] CAM 1: no module present May 4 15:27:17 localhost vdr: [7034] CAM 2: module ready May 4 15:27:28 localhost vdr: [7038] connect from 192.168.3.245, port 51122 - accepted May 4 15:27:45 localhost vdr: [7030] not all devices ready after 30 seconds May 4 15:27:45 localhost vdr: [7030] switching to channel 2 May 4 15:27:45 localhost vdr: [7030] GetDevice 2 0 1 -1 0500 May 4 15:27:45 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:45 localhost vdr: [7030] CAM 0 not ready May 4 15:27:45 localhost vdr: [7030] CAM 1 ready May 4 15:27:45 localhost vdr: [7030] no usable CAM slots! May 4 15:27:45 localhost vdr: [7030] info: Channel not available! May 4 15:27:45 localhost vdr: [7030] switching to channel 1 May 4 15:27:45 localhost vdr: [7030] GetDevice 1 0 1 -1 0500 May 4 15:27:45 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:45 localhost vdr: [7030] CAM 0 not ready May 4 15:27:45 localhost vdr: [7030] CAM 1 ready May 4 15:27:45 localhost vdr: [7030] no usable CAM slots! May 4 15:27:45 localhost vdr: [7030] info: Channel not available! May 4 15:27:45 localhost vdr: [7030] timer 1 (46 2255-0000 'STARGATE ATLANTIS') set to event Tue 06.05.2008 23:05-23:50 'STARGATE ATLANTIS' May 4 15:27:45 localhost vdr: [7030] timer 2 (46 2340-0050 'STARGATE ATLANTIS') set to event Tue 06.05.2008 23:50-00:35 'STARGATE ATLANTIS' May 4 15:27:45 localhost vdr: [7030] timer 3 (23 1750-2010 'SERENITY') set to event Sun 04.05.2008 18:00-20:00 'SERENITY' May 4 15:27:53 localhost vdr: [7030] GetDevice 2 0 1 -1 0500 May 4 15:27:53 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:53 localhost vdr: [7030] CAM 0 not ready May 4 15:27:53 localhost vdr: [7030] CAM 1 ready May 4 15:27:53 localhost vdr: [7030] no usable CAM slots! May 4 15:27:53 localhost vdr: [7030] GetDevice 3 0 1 -1 0500 May 4 15:27:53 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:53 localhost vdr: [7030] CAM 0 not ready May 4 15:27:53 localhost vdr: [7030] CAM 1 ready May 4 15:27:53 localhost vdr: [7030] no usable CAM slots! May 4 15:27:53 localhost vdr: [7030] GetDevice 4 0 1 -1 0500 May 4 15:27:53 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:53 localhost vdr: [7030] CAM 0 not ready May 4 15:27:53 localhost vdr: [7030] CAM 1 ready May 4 15:27:53 localhost vdr: [7030] no usable CAM slots! May 4 15:27:53 localhost vdr: [7030] GetDevice 5 0 1 -1 0000 May 4 15:27:53 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:53 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:27:53 localhost vdr: [7030] device 0 May 4 15:27:53 localhost vdr: [7030] switching to channel 5 May 4 15:27:53 localhost vdr: [7030] GetDevice 5 0 1 -1 0000 May 4 15:27:53 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:53 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:27:53 localhost vdr: [7030] device 0 May 4 15:27:53 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 15:27:53 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 15:27:56 localhost vdr: [7030] switching to channel 2 May 4 15:27:56 localhost vdr: [7030] GetDevice 2 0 1 -1 0500 May 4 15:27:56 localhost vdr: [7030] NumCamSlots = 2 May 4 15:27:56 localhost vdr: [7030] CAM 0 not ready May 4 15:27:56 localhost vdr: [7030] CAM 1 ready May 4 15:27:56 localhost vdr: [7030] no usable CAM slots! May 4 15:27:56 localhost vdr: [7030] info: Channel not available! May 4 15:28:08 localhost vdr: [7030] edited channel 1 TF1;CSAT:11895:vC34O0S0:S19.2E:27500:171:124=fra,125=eng:53:0:8371:1:1074:0 May 4 15:28:11 localhost vdr: [7030] edited channel 2 FRANCE 2;CSAT:12012:vC34O0S0:S19.2E:27500:160:80=fra:32:0:8801:1:1080:0 May 4 15:28:15 localhost vdr: [7030] edited channel 3 FRANCE 3;CSAT:12012:vC34O0S0:S19.2E:27500:161:84=fra:35:0:8802:1:1080:0 May 4 15:28:20 localhost vdr: [7030] edited channel 6 FRANCE 4;CSAT:12168:vC34O0S0:S19.2E:27500:168:112=fra:0:0:9529:1:1088:0 May 4 15:28:26 localhost vdr: [7030] edited channel 8 CANAL+;CSAT:11856:vC34O0S0:S19.2E:27500:160:80=fra,81=eng:32:0:8201:1:1072:0 May 4 15:28:29 localhost vdr: [7030] switching to channel 1 May 4 15:28:29 localhost vdr: [7030] GetDevice 1 0 1 -1 0000 May 4 15:28:29 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:29 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:29 localhost vdr: [7030] device 0 May 4 15:28:30 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 15:28:30 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 15:28:32 localhost vdr: [7036] changing caids of channel 1 from 0 to 500,100 May 4 15:28:32 localhost vdr: [7030] retuning due to modification of channel 1 May 4 15:28:32 localhost vdr: [7030] switching to channel 1 May 4 15:28:32 localhost vdr: [7030] GetDevice 1 0 1 -1 0500 May 4 15:28:32 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:32 localhost vdr: [7030] CAM 0 not ready May 4 15:28:32 localhost vdr: [7030] CAM 1 ready May 4 15:28:32 localhost vdr: [7030] no usable CAM slots! May 4 15:28:32 localhost vdr: [7030] info: Channel not available! May 4 15:28:40 localhost vdr: [7030] GetDevice 2 0 1 -1 0000 May 4 15:28:40 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:40 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:40 localhost vdr: [7030] device 0 May 4 15:28:40 localhost vdr: [7030] switching to channel 2 May 4 15:28:40 localhost vdr: [7030] GetDevice 2 0 1 -1 0000 May 4 15:28:40 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:40 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:40 localhost vdr: [7030] device 0 May 4 15:28:41 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 15:28:41 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 15:28:41 localhost vdr: [7036] changing caids of channel 2 from 0 to 500,100 May 4 15:28:41 localhost vdr: [7030] retuning due to modification of channel 2 May 4 15:28:41 localhost vdr: [7030] switching to channel 2 May 4 15:28:41 localhost vdr: [7030] GetDevice 2 0 1 -1 0500 May 4 15:28:41 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:41 localhost vdr: [7030] CAM 0 not ready May 4 15:28:41 localhost vdr: [7030] CAM 1 ready May 4 15:28:41 localhost vdr: [7030] no usable CAM slots! May 4 15:28:41 localhost vdr: [7030] info: Channel not available! May 4 15:28:44 localhost vdr: [7036] changing caids of channel 3 from 0 to 500,100 May 4 15:28:48 localhost vdr: [7030] GetDevice 3 0 1 -1 0500 May 4 15:28:48 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:48 localhost vdr: [7030] CAM 0 not ready May 4 15:28:48 localhost vdr: [7030] CAM 1 ready May 4 15:28:48 localhost vdr: [7030] no usable CAM slots! May 4 15:28:48 localhost vdr: [7030] GetDevice 4 0 1 -1 0500 May 4 15:28:48 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:48 localhost vdr: [7030] CAM 0 not ready May 4 15:28:48 localhost vdr: [7030] CAM 1 ready May 4 15:28:48 localhost vdr: [7030] no usable CAM slots! May 4 15:28:48 localhost vdr: [7030] GetDevice 5 0 1 -1 0000 May 4 15:28:48 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:48 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:48 localhost vdr: [7030] device 0 May 4 15:28:48 localhost vdr: [7030] switching to channel 5 May 4 15:28:48 localhost vdr: [7030] GetDevice 5 0 1 -1 0000 May 4 15:28:48 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:48 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:48 localhost vdr: [7030] device 0 May 4 15:28:48 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 15:28:48 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 15:28:54 localhost vdr: [7030] GetDevice 6 0 1 -1 0000 May 4 15:28:54 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:54 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:54 localhost vdr: [7030] device 0 May 4 15:28:54 localhost vdr: [7030] switching to channel 6 May 4 15:28:54 localhost vdr: [7030] GetDevice 6 0 1 -1 0000 May 4 15:28:54 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:54 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:54 localhost vdr: [7030] device 0 May 4 15:28:54 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 15:28:54 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 15:28:56 localhost vdr: [7036] changing caids of channel 6 from 0 to 500,100 May 4 15:28:56 localhost vdr: [7030] retuning due to modification of channel 6 May 4 15:28:56 localhost vdr: [7030] switching to channel 6 May 4 15:28:56 localhost vdr: [7030] GetDevice 6 0 1 -1 0500 May 4 15:28:56 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:56 localhost vdr: [7030] CAM 0 not ready May 4 15:28:56 localhost vdr: [7030] CAM 1 ready May 4 15:28:56 localhost vdr: [7030] no usable CAM slots! May 4 15:28:56 localhost vdr: [7030] info: Channel not available! May 4 15:28:59 localhost vdr: [7030] GetDevice 7 0 1 -1 0500 May 4 15:28:59 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:59 localhost vdr: [7030] CAM 0 not ready May 4 15:28:59 localhost vdr: [7030] CAM 1 ready May 4 15:28:59 localhost vdr: [7030] no usable CAM slots! May 4 15:28:59 localhost vdr: [7030] GetDevice 8 0 1 -1 0000 May 4 15:28:59 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:59 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:59 localhost vdr: [7030] device 0 May 4 15:28:59 localhost vdr: [7030] switching to channel 8 May 4 15:28:59 localhost vdr: [7030] GetDevice 8 0 1 -1 0000 May 4 15:28:59 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:59 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:59 localhost vdr: [7030] device 0 May 4 15:28:59 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 15:28:59 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 15:29:00 localhost vdr: [7036] changing caids of channel 8 from 0 to 500,100 May 4 15:29:01 localhost vdr: [7030] retuning due to modification of channel 8 May 4 15:29:01 localhost vdr: [7030] switching to channel 8 May 4 15:29:01 localhost vdr: [7030] GetDevice 8 0 1 -1 0500 May 4 15:29:01 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:01 localhost vdr: [7030] CAM 0 not ready May 4 15:29:01 localhost vdr: [7030] CAM 1 ready May 4 15:29:01 localhost vdr: [7030] no usable CAM slots! May 4 15:29:01 localhost vdr: [7030] info: Channel not available! May 4 15:29:01 localhost vdr: [7036] channel 23 (C CINEMA PREMIER) event Sun 04.05.2008 15:15-16:50 'SENTIMENTS PARTAGES' status 4 May 4 15:29:38 localhost vdr: [7030] GetDevice 9 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 10 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 11 0 1 -1 0D03 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 12 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 13 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 14 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 15 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 16 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 17 0 1 -1 0500 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] CAM 0 not ready May 4 15:29:38 localhost vdr: [7030] CAM 1 ready May 4 15:29:38 localhost vdr: [7030] no usable CAM slots! May 4 15:29:38 localhost vdr: [7030] GetDevice 18 0 1 -1 0000 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:29:38 localhost vdr: [7030] device 0 May 4 15:29:38 localhost vdr: [7030] switching to channel 18 May 4 15:29:38 localhost vdr: [7030] GetDevice 18 0 1 -1 0000 May 4 15:29:38 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:38 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:29:38 localhost vdr: [7030] device 0 May 4 15:29:38 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 15:29:38 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 15:29:42 localhost vdr: [7030] switching to channel 20 May 4 15:29:42 localhost vdr: [7030] GetDevice 20 0 1 -1 0500 May 4 15:29:42 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:42 localhost vdr: [7030] CAM 0 not ready May 4 15:29:42 localhost vdr: [7030] CAM 1 ready May 4 15:29:42 localhost vdr: [7030] no usable CAM slots! May 4 15:29:42 localhost vdr: [7030] info: Channel not available! May 4 15:29:48 localhost vdr: [7030] switching to channel 1 May 4 15:29:48 localhost vdr: [7030] GetDevice 1 0 1 -1 0500 May 4 15:29:48 localhost vdr: [7030] NumCamSlots = 2 May 4 15:29:48 localhost vdr: [7030] CAM 0 not ready May 4 15:29:48 localhost vdr: [7030] CAM 1 ready May 4 15:29:48 localhost vdr: [7030] no usable CAM slots! May 4 15:29:48 localhost vdr: [7030] info: Channel not available!
Pierre
On 05/04/08 15:31, Pierre-Yves Paranthoen (PERSO) wrote:
Here are the results with the device.c patch you gave me :
... May 4 15:28:08 localhost vdr: [7030] edited channel 1 TF1;CSAT:11895:vC34O0S0:S19.2E:27500:171:124=fra,125=eng:53:0:8371:1:1074:0 ... May 4 15:28:29 localhost vdr: [7030] switching to channel 1 May 4 15:28:29 localhost vdr: [7030] GetDevice 1 0 1 -1 0000 May 4 15:28:29 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:29 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:29 localhost vdr: [7030] device 0 ... May 4 15:28:32 localhost vdr: [7036] changing caids of channel 1 from 0 to 500,100 May 4 15:28:32 localhost vdr: [7030] retuning due to modification of channel 1 May 4 15:28:32 localhost vdr: [7030] switching to channel 1 May 4 15:28:32 localhost vdr: [7030] GetDevice 1 0 1 -1 0500 May 4 15:28:32 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:32 localhost vdr: [7030] CAM 0 not ready May 4 15:28:32 localhost vdr: [7030] CAM 1 ready May 4 15:28:32 localhost vdr: [7030] no usable CAM slots! May 4 15:28:32 localhost vdr: [7030] info: Channel not available!
After changing the CA id of channel 1 to '0', the first device is chosen. Then the CA id is automatically updated to 500,100 and the channel is retuned. CAM 2 (index 1) is ready, but apparently doesn't provide CA id 500 or 100.
What I'm missing in your log is the actual "application information" message of your CAM - something like
CAM 2: AlphaCrypt, 01, 4A20, 4A20
Please change the 'dbgprotocol' to 'dsyslog' in the following lines of ci.c:
515: dbgprotocol("Slot %d: <== Application Info (%d)\n", Tc()->CamSlot()->SlotNumber(), SessionId());
721: dbgprotocol("Slot %d: <== Ca Pmt Reply (%d)", Tc()->CamSlot()->SlotNumber(), SessionId());
731: dbgprotocol(" %d", pnr);
735: dbgprotocol(" %02X", *d);
752: dbgprotocol(" %02X", caepl);
761: dbgprotocol(" %d=%02X", pid, caees);
772: dbgprotocol("\n");
and repeat the test. Make sure the "Application Info" and "Ca Pmt Reply" messages are in your log excerpt.
Klaus
One part of the pb is that my cam module is ramdomly identified under 1.7.0 that might be the reason why the info "Application Info" and "Ca Pmt Reply" is not in the log. VDR-1.7.0 most gives CAM 2: module present & CAM 2: module ready instead of giving Aston Module 1.0300, 01, 0100,0100 (info taken from vdr-1.4.7). When it's correctly being identified and trying to access CAM informations under OSD, VDR-1.7 responds ERROR: Can't open CAM menu! A CAM reset gives then a basic information : 2 CAM ready and nothing else. Of course no decryption.
Here is the log of matching my explainations :
May 4 16:07:49 localhost vdr: [8251] CAM 2: module present May 4 16:07:50 localhost vdr: [8251] CAM 1: no module present May 4 16:07:50 localhost vdr: [8251] CAM 2: module ready May 4 16:07:54 localhost vdr: [8251] Slot 2: <== Application Info (2) May 4 16:07:54 localhost vdr: [8251] CAM 2: Aston Module 1.0300, 01, 0100, 0100 May 4 16:07:58 localhost vdr: [8251] CAM 2: doesn't reply to QUERY - only a single channel can be decrypted May 4 16:07:58 localhost vdr: [8247] switching to channel 2 May 4 16:07:58 localhost vdr: [8247] GetDevice 2 0 1 -1 0500 May 4 16:07:58 localhost vdr: [8247] NumCamSlots = 2 May 4 16:07:58 localhost vdr: [8247] CAM 0 not ready May 4 16:07:58 localhost vdr: [8247] CAM 1 ready May 4 16:07:58 localhost vdr: [8247] CAM 1 provides CA May 4 16:07:58 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:07:58 localhost vdr: [8247] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 4 16:07:58 localhost vdr: [8247] device 0 May 4 16:07:58 localhost vdr: [8247] CAM 2: assigned to device 1 May 4 16:07:58 localhost vdr: [8265] transfer thread started (pid=8247, tid=8265) May 4 16:07:58 localhost vdr: [8266] receiver on device 1 thread started (pid=8247, tid=8266) May 4 16:07:58 localhost vdr: [8267] TS buffer on device 1 thread started (pid=8247, tid=8267) May 4 16:07:58 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 16:07:58 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 16:08:34 localhost vdr: [8247] switching to channel 1 May 4 16:08:34 localhost vdr: [8265] transfer thread ended (pid=8247, tid=8265) May 4 16:08:35 localhost vdr: [8247] buffer stats: 5640 (0%) used May 4 16:08:35 localhost vdr: [8247] GetDevice 1 0 1 -1 0500 May 4 16:08:35 localhost vdr: [8247] NumCamSlots = 2 May 4 16:08:35 localhost vdr: [8247] CAM 0 not ready May 4 16:08:35 localhost vdr: [8247] CAM 1 ready May 4 16:08:35 localhost vdr: [8247] CAM 1 provides CA May 4 16:08:35 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:08:35 localhost vdr: [8247] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 4 16:08:35 localhost vdr: [8247] device 0 May 4 16:08:35 localhost vdr: [8303] transfer thread started (pid=8247, tid=8303) May 4 16:08:35 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 16:08:35 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 16:08:35 localhost vdr: [8267] TS buffer on device 1 thread ended (pid=8247, tid=8267) May 4 16:08:35 localhost vdr: [8266] buffer stats: 25568 (1%) used May 4 16:08:35 localhost vdr: [8266] receiver on device 1 thread ended (pid=8247, tid=8266) May 4 16:08:35 localhost vdr: [8304] receiver on device 1 thread started (pid=8247, tid=8304) May 4 16:08:35 localhost vdr: [8305] TS buffer on device 1 thread started (pid=8247, tid=8305) May 4 16:08:39 localhost vdr: [8303] transfer thread ended (pid=8247, tid=8303) May 4 16:08:39 localhost vdr: [8305] TS buffer on device 1 thread ended (pid=8247, tid=8305) May 4 16:08:39 localhost vdr: [8304] buffer stats: 83096 (3%) used May 4 16:08:39 localhost vdr: [8304] receiver on device 1 thread ended (pid=8247, tid=8304) May 4 16:08:39 localhost vdr: [8247] switching to channel 1 May 4 16:08:39 localhost vdr: [8247] buffer stats: 47564 (2%) used May 4 16:08:39 localhost vdr: [8247] GetDevice 1 0 1 -1 0500 May 4 16:08:39 localhost vdr: [8247] NumCamSlots = 2 May 4 16:08:39 localhost vdr: [8247] CAM 0 not ready May 4 16:08:39 localhost vdr: [8247] CAM 1 ready May 4 16:08:39 localhost vdr: [8247] CAM 1 provides CA May 4 16:08:39 localhost vdr: [8247] ChannelCamRelations.CamChecked(S19.2E-1-1074-8371, 2) = 0 May 4 16:08:39 localhost vdr: [8247] no usable CAM slots! May 4 16:08:39 localhost vdr: [8247] info: Channel not available! May 4 16:08:50 localhost vdr: [8247] switching to channel 1 May 4 16:08:50 localhost vdr: [8247] GetDevice 1 0 1 -1 0500 May 4 16:08:50 localhost vdr: [8247] NumCamSlots = 2 May 4 16:08:50 localhost vdr: [8247] CAM 0 not ready May 4 16:08:50 localhost vdr: [8247] CAM 1 ready May 4 16:08:50 localhost vdr: [8247] CAM 1 provides CA May 4 16:08:50 localhost vdr: [8247] ChannelCamRelations.CamChecked(S19.2E-1-1074-8371, 2) = 0 May 4 16:08:50 localhost vdr: [8247] no usable CAM slots! May 4 16:08:50 localhost vdr: [8247] info: Channel not available! May 4 16:09:00 localhost vdr: [8247] GetDevice 2 0 1 -1 0500 May 4 16:09:00 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:00 localhost vdr: [8247] CAM 0 not ready May 4 16:09:00 localhost vdr: [8247] CAM 1 ready May 4 16:09:00 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:00 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:09:00 localhost vdr: [8247] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 4 16:09:00 localhost vdr: [8247] device 0 May 4 16:09:00 localhost vdr: [8247] switching to channel 2 May 4 16:09:00 localhost vdr: [8247] GetDevice 2 0 1 -1 0500 May 4 16:09:00 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:00 localhost vdr: [8247] CAM 0 not ready May 4 16:09:00 localhost vdr: [8247] CAM 1 ready May 4 16:09:00 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:00 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:09:00 localhost vdr: [8247] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 4 16:09:00 localhost vdr: [8247] device 0 May 4 16:09:00 localhost vdr: [8323] transfer thread started (pid=8247, tid=8323) May 4 16:09:00 localhost vdr: [8324] receiver on device 1 thread started (pid=8247, tid=8324) May 4 16:09:00 localhost vdr: [8325] TS buffer on device 1 thread started (pid=8247, tid=8325) May 4 16:09:00 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 16:09:00 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 16:09:17 localhost vdr: [8247] GetDevice 3 0 1 -1 0500 May 4 16:09:17 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:17 localhost vdr: [8247] CAM 0 not ready May 4 16:09:17 localhost vdr: [8247] CAM 1 ready May 4 16:09:17 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:17 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:09:17 localhost vdr: [8247] j = 1, i = 0, imp = 062C4C5B, Impact = FFFFFFFF May 4 16:09:17 localhost vdr: [8247] device 0 May 4 16:09:17 localhost vdr: [8323] transfer thread ended (pid=8247, tid=8323) May 4 16:09:17 localhost vdr: [8247] switching to channel 3 May 4 16:09:17 localhost vdr: [8247] buffer stats: 0 (0%) used May 4 16:09:17 localhost vdr: [8247] GetDevice 3 0 1 -1 0500 May 4 16:09:17 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:17 localhost vdr: [8247] CAM 0 not ready May 4 16:09:17 localhost vdr: [8247] CAM 1 ready May 4 16:09:17 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:17 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:09:17 localhost vdr: [8247] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 4 16:09:17 localhost vdr: [8247] device 0 May 4 16:09:17 localhost vdr: [8325] TS buffer on device 1 thread ended (pid=8247, tid=8325) May 4 16:09:17 localhost vdr: [8344] transfer thread started (pid=8247, tid=8344) May 4 16:09:17 localhost vdr: [8324] buffer stats: 7520 (0%) used May 4 16:09:17 localhost vdr: [8324] receiver on device 1 thread ended (pid=8247, tid=8324) May 4 16:09:18 localhost vdr: [8345] receiver on device 1 thread started (pid=8247, tid=8345) May 4 16:09:18 localhost vdr: [8346] TS buffer on device 1 thread started (pid=8247, tid=8346) May 4 16:09:21 localhost vdr: [8344] transfer thread ended (pid=8247, tid=8344) May 4 16:09:21 localhost vdr: [8346] TS buffer on device 1 thread ended (pid=8247, tid=8346) May 4 16:09:21 localhost vdr: [8345] buffer stats: 95316 (4%) used May 4 16:09:21 localhost vdr: [8345] receiver on device 1 thread ended (pid=8247, tid=8345) May 4 16:09:22 localhost vdr: [8247] switching to channel 3 May 4 16:09:22 localhost vdr: [8247] buffer stats: 37600 (1%) used May 4 16:09:22 localhost vdr: [8247] GetDevice 3 0 1 -1 0500 May 4 16:09:22 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:22 localhost vdr: [8247] CAM 0 not ready May 4 16:09:22 localhost vdr: [8247] CAM 1 ready May 4 16:09:22 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:22 localhost vdr: [8247] ChannelCamRelations.CamChecked(S19.2E-1-1080-8802, 2) = 0 May 4 16:09:22 localhost vdr: [8247] no usable CAM slots! May 4 16:09:22 localhost vdr: [8247] info: Channel not available! May 4 16:09:31 localhost vdr: [8247] switching to channel 5 May 4 16:09:31 localhost vdr: [8247] GetDevice 5 0 1 -1 0000 May 4 16:09:31 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:31 localhost vdr: [8247] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 16:09:31 localhost vdr: [8247] device 0 May 4 16:09:31 localhost vdr: [8247] CAM 2: unassigned May 4 16:09:31 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 16:09:31 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 16:09:33 localhost vdr: [8253] changing pids of channel 5 from 167+167:136=fra,137=deu:0:71 to 167+167:136=fra,137=deu:0:0 May 4 16:09:33 localhost vdr: [8247] retuning due to modification of channel 5 May 4 16:09:33 localhost vdr: [8247] switching to channel 5 May 4 16:09:33 localhost vdr: [8247] GetDevice 5 0 1 -1 0000 May 4 16:09:33 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:33 localhost vdr: [8247] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 16:09:33 localhost vdr: [8247] device 0 May 4 16:09:37 localhost vdr: [8247] switching to channel 6 May 4 16:09:37 localhost vdr: [8247] GetDevice 6 0 1 -1 0500 May 4 16:09:37 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:37 localhost vdr: [8247] CAM 0 not ready May 4 16:09:37 localhost vdr: [8247] CAM 1 ready May 4 16:09:37 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:37 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:09:37 localhost vdr: [8247] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 4 16:09:37 localhost vdr: [8247] device 0 May 4 16:09:37 localhost vdr: [8247] CAM 2: assigned to device 1 May 4 16:09:37 localhost vdr: [8364] transfer thread started (pid=8247, tid=8364) May 4 16:09:37 localhost vdr: [8365] receiver on device 1 thread started (pid=8247, tid=8365) May 4 16:09:37 localhost vdr: [8366] TS buffer on device 1 thread started (pid=8247, tid=8366) May 4 16:09:37 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 16:09:37 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 16:09:41 localhost vdr: [8364] transfer thread ended (pid=8247, tid=8364) May 4 16:09:41 localhost vdr: [8366] TS buffer on device 1 thread ended (pid=8247, tid=8366) May 4 16:09:41 localhost vdr: [8365] buffer stats: 128404 (6%) used May 4 16:09:41 localhost vdr: [8365] receiver on device 1 thread ended (pid=8247, tid=8365) May 4 16:09:42 localhost vdr: [8247] switching to channel 6 May 4 16:09:42 localhost vdr: [8247] buffer stats: 45308 (2%) used May 4 16:09:42 localhost vdr: [8247] GetDevice 6 0 1 -1 0500 May 4 16:09:42 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:42 localhost vdr: [8247] CAM 0 not ready May 4 16:09:42 localhost vdr: [8247] CAM 1 ready May 4 16:09:42 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:42 localhost vdr: [8247] ChannelCamRelations.CamChecked(S19.2E-1-1088-9529, 2) = 0 May 4 16:09:42 localhost vdr: [8247] no usable CAM slots! May 4 16:09:42 localhost vdr: [8247] info: Channel not available! May 4 16:09:48 localhost vdr: [8247] switching to channel 7 May 4 16:09:48 localhost vdr: [8247] GetDevice 7 0 1 -1 0500 May 4 16:09:48 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:48 localhost vdr: [8247] CAM 0 not ready May 4 16:09:48 localhost vdr: [8247] CAM 1 ready May 4 16:09:48 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:48 localhost vdr: [8247] NumUsableSlots = 1 May 4 16:09:48 localhost vdr: [8247] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 4 16:09:48 localhost vdr: [8247] device 0 May 4 16:09:48 localhost vdr: [8376] transfer thread started (pid=8247, tid=8376) May 4 16:09:48 localhost vdr: [8377] receiver on device 1 thread started (pid=8247, tid=8377) May 4 16:09:48 localhost vdr: [8378] TS buffer on device 1 thread started (pid=8247, tid=8378) May 4 16:09:48 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 16:09:48 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 16:09:52 localhost vdr: [8376] transfer thread ended (pid=8247, tid=8376) May 4 16:09:52 localhost vdr: [8378] TS buffer on device 1 thread ended (pid=8247, tid=8378) May 4 16:09:52 localhost vdr: [8377] buffer stats: 109604 (5%) used May 4 16:09:52 localhost vdr: [8377] receiver on device 1 thread ended (pid=8247, tid=8377) May 4 16:09:54 localhost vdr: [8247] switching to channel 7 May 4 16:09:54 localhost vdr: [8247] buffer stats: 43428 (2%) used May 4 16:09:54 localhost vdr: [8247] GetDevice 7 0 1 -1 0500 May 4 16:09:54 localhost vdr: [8247] NumCamSlots = 2 May 4 16:09:54 localhost vdr: [8247] CAM 0 not ready May 4 16:09:54 localhost vdr: [8247] CAM 1 ready May 4 16:09:54 localhost vdr: [8247] CAM 1 provides CA May 4 16:09:54 localhost vdr: [8247] ChannelCamRelations.CamChecked(S19.2E-1-1090-8591, 2) = 0 May 4 16:09:54 localhost vdr: [8247] no usable CAM slots! May 4 16:09:54 localhost vdr: [8247] info: Channel not available! May 4 16:10:06 localhost vdr: [8247] CAM 2: retrying to enter CAM menu... May 4 16:10:07 localhost vdr: [8247] CAM 2: Menu ------------------ May 4 16:10:07 localhost vdr: [8247] CAM 2: 'Aston Module' May 4 16:10:07 localhost vdr: [8247] CAM 2: 'Carte numéro xxx.xxx.xxx' May 4 16:10:07 localhost vdr: [8247] CAM 2: 'Consultation des droits' May 4 16:10:07 localhost vdr: [8247] CAM 2: 'Réglages' May 4 16:10:07 localhost vdr: [8247] CAM 2: 'Information' May 4 16:10:07 localhost vdr: [8247] CAM 2: 'Votre choix, svp' May 4 16:10:20 localhost vdr: [8247] CAM 2: select 2 May 4 16:10:25 localhost vdr: [8247] ERROR: CAM not responding! May 4 16:10:31 localhost vdr: [8247] CAM 2: Menu ------------------ May 4 16:10:31 localhost vdr: [8247] CAM 2: 'Aston Module' May 4 16:10:31 localhost vdr: [8247] CAM 2: 'Carte numéro xxx.xxx.xxx' May 4 16:10:31 localhost vdr: [8247] CAM 2: 'Consultation des droits' May 4 16:10:31 localhost vdr: [8247] CAM 2: 'Réglages' May 4 16:10:31 localhost vdr: [8247] CAM 2: 'Information' May 4 16:10:31 localhost vdr: [8247] CAM 2: 'Votre choix, svp' May 4 16:10:33 localhost vdr: [8247] CAM 2: select 0 May 4 16:10:35 localhost vdr: [8247] CAM 2: Menu ------------------ May 4 16:10:35 localhost vdr: [8247] CAM 2: 'Aston Module' May 4 16:10:35 localhost vdr: [8247] CAM 2: 'Interrogation de la carte: attendez' May 4 16:10:36 localhost vdr: [8247] CAM 2: Menu ------------------ May 4 16:10:36 localhost vdr: [8247] CAM 2: 'Aston Module/Consultation des droits' May 4 16:10:36 localhost vdr: [8247] CAM 2: 'Liste des services pour la carte N°xxx.xxx.xxx' May 4 16:10:36 localhost vdr: [8247] CAM 2: 'CANALSAT' May 4 16:10:36 localhost vdr: [8247] CAM 2: 'CANAL+' May 4 16:10:36 localhost vdr: [8247] CAM 2: 'CANALPRO' May 4 16:10:36 localhost vdr: [8247] CAM 2: 'CSAT 1' May 4 16:10:36 localhost vdr: [8247] CAM 2: 'CSAT 2' May 4 16:10:36 localhost vdr: [8247] CAM 2: 'Votre choix, svp' May 4 16:10:39 localhost vdr: [8247] CAM 2: select 0 May 4 16:10:44 localhost vdr: [8247] ERROR: CAM not responding! May 4 16:10:48 localhost vdr: [8247] confirm: CAM is in use - really reset? May 4 16:10:48 localhost vdr: [8247] warning: CAM is in use - really reset? May 4 16:10:51 localhost vdr: [8247] confirmed May 4 16:10:52 localhost vdr: [8251] CAM 2: module present May 4 16:10:53 localhost vdr: [8251] CAM 2: module ready
I can change the 'dbgprotocol' to 'dsyslog' under VDR-1.4.7 & give you the results if it might helps.
Pierre
-----Message d'origine----- De : vdr-bounces@linuxtv.org [mailto:vdr-bounces@linuxtv.org] De la part de Klaus Schmidinger Envoyé : dimanche 4 mai 2008 16:02 À : vdr@linuxtv.org Objet : Re: [vdr] Upgrading from 1.4.7 to 1.7.0 : enabling #define
On 05/04/08 15:31, Pierre-Yves Paranthoen (PERSO) wrote:
Here are the results with the device.c patch you gave me :
... May 4 15:28:08 localhost vdr: [7030] edited channel 1 TF1;CSAT:11895:vC34O0S0:S19.2E:27500:171:124=fra,125=eng:53:0:8371:1:1 074:0 ... May 4 15:28:29 localhost vdr: [7030] switching to channel 1 May 4 15:28:29 localhost vdr: [7030] GetDevice 1 0 1 -1 0000 May 4 15:28:29 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:29 localhost vdr: [7030] j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF May 4 15:28:29 localhost vdr: [7030] device 0 ... May 4 15:28:32 localhost vdr: [7036] changing caids of channel 1 from 0 to 500,100 May 4 15:28:32 localhost vdr: [7030] retuning due to modification of channel 1 May 4 15:28:32 localhost vdr: [7030] switching to channel 1 May 4 15:28:32 localhost vdr: [7030] GetDevice 1 0 1 -1 0500 May 4 15:28:32 localhost vdr: [7030] NumCamSlots = 2 May 4 15:28:32 localhost vdr: [7030] CAM 0 not ready May 4 15:28:32 localhost vdr: [7030] CAM 1 ready May 4 15:28:32 localhost vdr: [7030] no usable CAM slots! May 4 15:28:32 localhost vdr: [7030] info: Channel not available!
After changing the CA id of channel 1 to '0', the first device is chosen. Then the CA id is automatically updated to 500,100 and the channel is retuned. CAM 2 (index 1) is ready, but apparently doesn't provide CA id 500 or 100.
What I'm missing in your log is the actual "application information" message of your CAM - something like
CAM 2: AlphaCrypt, 01, 4A20, 4A20
Please change the 'dbgprotocol' to 'dsyslog' in the following lines of ci.c:
515: dbgprotocol("Slot %d: <== Application Info (%d)\n", Tc()->CamSlot()->SlotNumber(), SessionId());
721: dbgprotocol("Slot %d: <== Ca Pmt Reply (%d)", Tc()->CamSlot()->SlotNumber(), SessionId());
731: dbgprotocol(" %d", pnr);
735: dbgprotocol(" %02X", *d);
752: dbgprotocol(" %02X", caepl);
761: dbgprotocol(" %d=%02X", pid, caees);
772: dbgprotocol("\n");
and repeat the test. Make sure the "Application Info" and "Ca Pmt Reply" messages are in your log excerpt.
Klaus
_______________________________________________ vdr mailing list vdr@linuxtv.org http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
On 05/04/08 16:40, Pierre-Yves Paranthoen (PERSO) wrote:
One part of the pb is that my cam module is ramdomly identified under 1.7.0 that might be the reason why the info "Application Info" and "Ca Pmt Reply" is not in the log. VDR-1.7.0 most gives CAM 2: module present & CAM 2: module ready instead of giving Aston Module 1.0300, 01, 0100,0100 (info taken from vdr-1.4.7). When it's correctly being identified and trying to access CAM informations under OSD, VDR-1.7 responds ERROR: Can't open CAM menu! A CAM reset gives then a basic information : 2 CAM ready and nothing else. Of course no decryption.
Here is the log of matching my explainations :
May 4 16:07:49 localhost vdr: [8251] CAM 2: module present May 4 16:07:50 localhost vdr: [8251] CAM 1: no module present May 4 16:07:50 localhost vdr: [8251] CAM 2: module ready May 4 16:07:54 localhost vdr: [8251] Slot 2: <== Application Info (2) May 4 16:07:54 localhost vdr: [8251] CAM 2: Aston Module 1.0300, 01, 0100, 0100
So the "application information" is being received. I'm afraid I was looking at the wrong lines when telling you which 'dbgprotocol's to change. Please also change the ones in lines
696: dbgprotocol("Slot %d: <== Ca Info (%d)", Tc()->CamSlot()->SlotNumber(), SessionId());
702: dbgprotocol(" %04X", id);
713: dbgprotocol("\n");
Klaus
Ok, I modified those lines but the no more informations. I've extended the replacement of dbgprotocol to other parts of ci.c. Here are the logs while getting for once a successfull detection of the module. For info i'm running vdr-1.7.0 like this : ./vdr -l 3 -c /etc/vdr -L /srv/vdr-1.7.0/lib > log 2>&1 and giving you back both syslog and the pipe of the core.
May 5 10:43:06 localhost vdr: [17188] switching to channel 2 May 5 10:43:06 localhost vdr: [17188] GetDevice 2 0 1 -1 0500 May 5 10:43:06 localhost vdr: [17188] NumCamSlots = 2 May 5 10:43:06 localhost vdr: [17188] CAM 0 not ready May 5 10:43:06 localhost vdr: [17188] CAM 1 ready May 5 10:43:06 localhost vdr: [17188] CAM 1 provides CA May 5 10:43:06 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1080-8801, 2) = 0 May 5 10:43:06 localhost vdr: [17188] no usable CAM slots! May 5 10:43:06 localhost vdr: [17188] info: Channel not available! May 5 10:43:17 localhost vdr: [17188] switching to channel 2 May 5 10:43:17 localhost vdr: [17188] GetDevice 2 0 1 -1 0500 May 5 10:43:17 localhost vdr: [17188] NumCamSlots = 2 May 5 10:43:17 localhost vdr: [17188] CAM 0 not ready May 5 10:43:17 localhost vdr: [17188] CAM 1 ready May 5 10:43:17 localhost vdr: [17188] CAM 1 provides CA May 5 10:43:17 localhost vdr: [17188] NumUsableSlots = 1 May 5 10:43:17 localhost vdr: [17188] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 5 10:43:17 localhost vdr: [17188] device 0 May 5 10:43:17 localhost vdr: [17235] transfer thread started (pid=17188, tid=17235) May 5 10:43:17 localhost vdr: [17236] receiver on device 1 thread started (pid=17188, tid=17236) May 5 10:43:18 localhost vdr: [17237] TS buffer on device 1 thread started (pid=17188, tid=17237) May 5 10:43:20 localhost vdr: [17235] setting audio track to 1 (0) May 5 10:52:04 localhost vdr: [17188] switching to channel 7 May 5 10:52:04 localhost vdr: [17188] GetDevice 7 0 1 -1 0500 May 5 10:52:04 localhost vdr: [17188] NumCamSlots = 2 May 5 10:52:04 localhost vdr: [17188] CAM 0 not ready May 5 10:52:04 localhost vdr: [17188] CAM 1 ready May 5 10:52:04 localhost vdr: [17188] CAM 1 provides CA May 5 10:52:04 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1090-8591, 2) = 0 May 5 10:52:04 localhost vdr: [17188] no usable CAM slots! May 5 10:52:04 localhost vdr: [17188] info: Channel not available! May 5 10:52:15 localhost vdr: [17188] switching to channel 7 May 5 10:52:15 localhost vdr: [17188] GetDevice 7 0 1 -1 0500 May 5 10:52:15 localhost vdr: [17188] NumCamSlots = 2 May 5 10:52:15 localhost vdr: [17188] CAM 0 not ready May 5 10:52:15 localhost vdr: [17188] CAM 1 ready May 5 10:52:15 localhost vdr: [17188] CAM 1 provides CA May 5 10:52:15 localhost vdr: [17188] NumUsableSlots = 1 May 5 10:52:15 localhost vdr: [17188] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 5 10:52:15 localhost vdr: [17188] device 0 May 5 10:52:15 localhost vdr: [17722] transfer thread started (pid=17188, tid=17722) May 5 10:52:15 localhost vdr: [17723] receiver on device 1 thread started (pid=17188, tid=17723) May 5 10:52:16 localhost vdr: [17724] TS buffer on device 1 thread started (pid=17188, tid=17724) May 5 10:52:18 localhost vdr: [17722] setting audio track to 1 (0) May 5 10:54:40 localhost vdr: [17188] switching to channel 10 May 5 10:54:40 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:54:40 localhost vdr: [17188] NumCamSlots = 2 May 5 10:54:40 localhost vdr: [17188] CAM 0 not ready May 5 10:54:40 localhost vdr: [17188] CAM 1 ready May 5 10:54:40 localhost vdr: [17188] CAM 1 provides CA May 5 10:54:40 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1084-9403, 2) = 0 May 5 10:54:40 localhost vdr: [17188] no usable CAM slots! May 5 10:54:40 localhost vdr: [17188] info: Channel not available! May 5 10:54:49 localhost vdr: [17188] switching to channel 10 May 5 10:54:49 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:54:49 localhost vdr: [17188] NumCamSlots = 2 May 5 10:54:49 localhost vdr: [17188] CAM 0 not ready May 5 10:54:49 localhost vdr: [17188] CAM 1 ready May 5 10:54:49 localhost vdr: [17188] CAM 1 provides CA May 5 10:54:49 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1084-9403, 2) = 0 May 5 10:54:49 localhost vdr: [17188] no usable CAM slots! May 5 10:54:49 localhost vdr: [17188] info: Channel not available! May 5 10:55:00 localhost vdr: [17188] switching to channel 10 May 5 10:55:00 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:55:00 localhost vdr: [17188] NumCamSlots = 2 May 5 10:55:00 localhost vdr: [17188] CAM 0 not ready May 5 10:55:00 localhost vdr: [17188] CAM 1 ready May 5 10:55:00 localhost vdr: [17188] CAM 1 provides CA May 5 10:55:00 localhost vdr: [17188] NumUsableSlots = 1 May 5 10:55:00 localhost vdr: [17188] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 5 10:55:00 localhost vdr: [17188] device 0 May 5 10:55:00 localhost vdr: [17879] transfer thread started (pid=17188, tid=17879) May 5 10:55:00 localhost vdr: [17880] receiver on device 1 thread started (pid=17188, tid=17880) May 5 10:55:01 localhost vdr: [17881] TS buffer on device 1 thread started (pid=17188, tid=17881) May 5 10:55:03 localhost vdr: [17192] CAM 2: module reset May 5 10:55:03 localhost vdr: [17192] CAM 2: module present May 5 10:55:04 localhost vdr: [17192] CAM 2: module ready May 5 10:55:04 localhost vdr: [17879] transfer thread ended (pid=17188, tid=17879) May 5 10:55:05 localhost vdr: [17881] TS buffer on device 1 thread ended (pid=17188, tid=17881) May 5 10:55:05 localhost vdr: [17880] buffer stats: 77832 (3%) used May 5 10:55:05 localhost vdr: [17880] receiver on device 1 thread ended (pid=17188, tid=17880) May 5 10:55:11 localhost vdr: [17188] switching to channel 10 May 5 10:55:11 localhost vdr: [17188] cTS2PES got 0 TS errors, 1 TS continuity errors May 5 10:55:11 localhost last message repeated 2 times May 5 10:55:11 localhost vdr: [17188] buffer stats: 49632 (2%) used May 5 10:55:11 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:55:11 localhost vdr: [17188] NumCamSlots = 2 May 5 10:55:11 localhost vdr: [17188] CAM 0 not ready May 5 10:55:11 localhost vdr: [17188] CAM 1 ready May 5 10:55:11 localhost vdr: [17188] no usable CAM slots! May 5 10:55:11 localhost vdr: [17188] info: Channel not available! May 5 10:55:22 localhost vdr: [17188] switching to channel 10 May 5 10:55:22 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:55:22 localhost vdr: [17188] NumCamSlots = 2 May 5 10:55:22 localhost vdr: [17188] CAM 0 not ready May 5 10:55:22 localhost vdr: [17188] CAM 1 ready May 5 10:55:22 localhost vdr: [17188] no usable CAM slots! May 5 10:55:22 localhost vdr: [17188] info: Channel not available!
--> a few channels work afer staying 5 or 6 s on each one. Then CAM module what was marked ASTON came back to generic and no more decryption. I tried then to manually reset it. Unsuccessful.
May 5 11:04:40 localhost vdr: [17192] CAM 2: module present May 5 11:04:41 localhost vdr: [17192] CAM 2: module ready May 5 11:04:42 localhost vdr: [17192] CAM 2: module reset May 5 11:04:43 localhost vdr: [17192] CAM 2: module present May 5 11:04:44 localhost vdr: [17192] CAM 2: module ready May 5 11:04:49 localhost vdr: [17192] CAM 2: module reset May 5 11:04:49 localhost vdr: [17192] CAM 2: module present May 5 11:04:50 localhost vdr: [17192] CAM 2: module ready May 5 11:04:53 localhost vdr: [17192] CAM 2: module present May 5 11:04:55 localhost vdr: [17192] CAM 2: module ready May 5 11:05:00 localhost vdr: [17192] CAM 2: module reset May 5 11:05:01 localhost vdr: [17192] CAM 2: module present May 5 11:05:02 localhost vdr: [17192] CAM 2: module ready May 5 11:05:06 localhost vdr: [17192] CAM 2: module reset May 5 11:05:07 localhost vdr: [17192] CAM 2: module present May 5 11:05:08 localhost vdr: [17192] CAM 2: module ready May 5 11:05:09 localhost vdr: [17192] CAM 2: module reset May 5 11:05:10 localhost vdr: [17192] CAM 2: module present May 5 11:05:11 localhost vdr: [17192] CAM 2: module ready May 5 11:05:12 localhost vdr: [17192] CAM 2: module reset May 5 11:05:12 localhost vdr: [17192] CAM 2: module present May 5 11:05:13 localhost vdr: [17192] CAM 2: module ready
Pipe log :
... Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . ...
Slot 2: reset...ok. Slot 2: module present Slot 2: module ready Slot 2: creating connection 1/1 Slot 2: create connection 1/1 2: --> 01 01 82 01 01 2: <-- 01 01 83 01 01 80 02 01 00 . . . . . . . . . Slot 2: connection created 1/1 Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 80 . . . . . . Slot 2: receive data 1/1 2: --> 01 01 81 01 01 2: <-- 01 01 A0 07 01 91 04 00 01 00 41 80 02 01 00 . . . . . . . . . A . . . . Slot 2: open session 00010041 Slot 2: new Resource Manager (session id 1) 2: --> 01 01 A0 0A 01 92 07 00 00 01 00 41 00 01 Slot 2: ==> Profile Enq (1) 2: --> 01 01 A0 09 01 90 02 00 01 9F 80 10 00 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 Slot 2: ==> Ca Pmt (3) 3 4 2: --> 01 01 A0 10 01 90 02 00 03 9F 80 32 07 03 00 00 01 00 01 04 Slot 2: ==> Ca Pmt (3) 3 1 2: --> 01 01 A0 1F 01 90 02 00 03 9F 80 32 16 03 24 BB 01 00 01 01 02 00 A2 00 00 04 00 58 00 00 04 00 59 00 00 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: receive data 1/1 2: --> 01 01 81 01 01 2: <-- 01 01 A0 07 01 91 04 00 40 00 41 80 02 01 80 . . . . . . . @ . A . . . . Slot 2: open session 00400041 ERROR: CAM 2: session for resource identifier 00400041 already exists (1/1)Slot 2: receive data 1/1 2: --> 01 01 81 01 01 2: <-- 01 01 A0 0A 01 90 02 00 05 9F 88 00 01 00 80 02 01 00 . . . . . . . . . . . . . . . . . Slot 2: <== Close MMI (5) id = 00 delay = -1 Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 Slot 2: ==> Ca Pmt (3) 3 4 2: --> 01 01 A0 10 01 90 02 00 03 9F 80 32 07 03 00 00 01 00 01 04 2: <-- 01 01 80 02 01 00 . . . . . .
This log is really to heavy to post it. Pse find attached a tarball of it.
Pierre
-----Message d'origine----- De : vdr-bounces@linuxtv.org [mailto:vdr-bounces@linuxtv.org] De la part de Klaus Schmidinger Envoyé : dimanche 4 mai 2008 17:59 À : vdr@linuxtv.org Objet : Re: [vdr] Upgrading from 1.4.7 to 1.7.0 : enabling #define
On 05/04/08 16:40, Pierre-Yves Paranthoen (PERSO) wrote:
One part of the pb is that my cam module is ramdomly identified under 1.7.0 that might be the reason why the info "Application Info" and "Ca Pmt
Reply"
is not in the log. VDR-1.7.0 most gives CAM 2: module present & CAM 2: module ready instead of giving Aston Module 1.0300, 01, 0100,0100 (info taken from
vdr-1.4.7).
When it's correctly being identified and trying to access CAM informations under OSD, VDR-1.7 responds ERROR: Can't open CAM menu! A CAM reset gives then a basic information : 2 CAM ready and nothing else. Of course no decryption.
Here is the log of matching my explainations :
May 4 16:07:49 localhost vdr: [8251] CAM 2: module present May 4 16:07:50 localhost vdr: [8251] CAM 1: no module present May 4 16:07:50 localhost vdr: [8251] CAM 2: module ready May 4 16:07:54 localhost vdr: [8251] Slot 2: <== Application Info (2) May 4 16:07:54 localhost vdr: [8251] CAM 2: Aston Module 1.0300, 01, 0100, 0100
So the "application information" is being received. I'm afraid I was looking at the wrong lines when telling you which 'dbgprotocol's to change. Please also change the ones in lines
696: dbgprotocol("Slot %d: <== Ca Info (%d)", Tc()->CamSlot()->SlotNumber(), SessionId());
702: dbgprotocol(" %04X", id);
713: dbgprotocol("\n");
Klaus
_______________________________________________ vdr mailing list vdr@linuxtv.org http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
Hi!
Did you get this working, got a similar problem here with a AstonCrypt CAM too, but with vdr-1.6.0.
When switching to the channels:
CANAL+ SPORT;CYFRA +:10892:HC34:S13.0E:27500:168:112=pol,113=ORY:500:100,500:4809:318:11900:0 CANAL+ Polska;CYFRA +:10892:hC34:S13.0E:27500:160:80=pol,81=ORY:500:100,500:4801:318:11900:0
VDR encrypts some pictures and after 2 seconds screen goes black and "channel not available" is shown. I assume that there is a problem with the second Audio PID because the channels:
TVP 1;CYFRA +:10892:hC34:S13.0E:27500:166:104=pol:503:100,500,B00,B01:4807:318:11900:0 TVP 2;CYFRA +:10892:hC34:S13.0E:27500:167:108=pol:508:100,500,B00,B01:4808:318:11900:0
are working without any problem.
Regards
Marco
Pierre-Yves Paranthoen (PERSO) schrieb:
Ok, I modified those lines but the no more informations. I've extended the replacement of dbgprotocol to other parts of ci.c. Here are the logs while getting for once a successfull detection of the module. For info i'm running vdr-1.7.0 like this : ./vdr -l 3 -c /etc/vdr -L /srv/vdr-1.7.0/lib > log 2>&1 and giving you back both syslog and the pipe of the core.
May 5 10:43:06 localhost vdr: [17188] switching to channel 2 May 5 10:43:06 localhost vdr: [17188] GetDevice 2 0 1 -1 0500 May 5 10:43:06 localhost vdr: [17188] NumCamSlots = 2 May 5 10:43:06 localhost vdr: [17188] CAM 0 not ready May 5 10:43:06 localhost vdr: [17188] CAM 1 ready May 5 10:43:06 localhost vdr: [17188] CAM 1 provides CA May 5 10:43:06 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1080-8801, 2) = 0 May 5 10:43:06 localhost vdr: [17188] no usable CAM slots! May 5 10:43:06 localhost vdr: [17188] info: Channel not available! May 5 10:43:17 localhost vdr: [17188] switching to channel 2 May 5 10:43:17 localhost vdr: [17188] GetDevice 2 0 1 -1 0500 May 5 10:43:17 localhost vdr: [17188] NumCamSlots = 2 May 5 10:43:17 localhost vdr: [17188] CAM 0 not ready May 5 10:43:17 localhost vdr: [17188] CAM 1 ready May 5 10:43:17 localhost vdr: [17188] CAM 1 provides CA May 5 10:43:17 localhost vdr: [17188] NumUsableSlots = 1 May 5 10:43:17 localhost vdr: [17188] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 5 10:43:17 localhost vdr: [17188] device 0 May 5 10:43:17 localhost vdr: [17235] transfer thread started (pid=17188, tid=17235) May 5 10:43:17 localhost vdr: [17236] receiver on device 1 thread started (pid=17188, tid=17236) May 5 10:43:18 localhost vdr: [17237] TS buffer on device 1 thread started (pid=17188, tid=17237) May 5 10:43:20 localhost vdr: [17235] setting audio track to 1 (0) May 5 10:52:04 localhost vdr: [17188] switching to channel 7 May 5 10:52:04 localhost vdr: [17188] GetDevice 7 0 1 -1 0500 May 5 10:52:04 localhost vdr: [17188] NumCamSlots = 2 May 5 10:52:04 localhost vdr: [17188] CAM 0 not ready May 5 10:52:04 localhost vdr: [17188] CAM 1 ready May 5 10:52:04 localhost vdr: [17188] CAM 1 provides CA May 5 10:52:04 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1090-8591, 2) = 0 May 5 10:52:04 localhost vdr: [17188] no usable CAM slots! May 5 10:52:04 localhost vdr: [17188] info: Channel not available! May 5 10:52:15 localhost vdr: [17188] switching to channel 7 May 5 10:52:15 localhost vdr: [17188] GetDevice 7 0 1 -1 0500 May 5 10:52:15 localhost vdr: [17188] NumCamSlots = 2 May 5 10:52:15 localhost vdr: [17188] CAM 0 not ready May 5 10:52:15 localhost vdr: [17188] CAM 1 ready May 5 10:52:15 localhost vdr: [17188] CAM 1 provides CA May 5 10:52:15 localhost vdr: [17188] NumUsableSlots = 1 May 5 10:52:15 localhost vdr: [17188] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 5 10:52:15 localhost vdr: [17188] device 0 May 5 10:52:15 localhost vdr: [17722] transfer thread started (pid=17188, tid=17722) May 5 10:52:15 localhost vdr: [17723] receiver on device 1 thread started (pid=17188, tid=17723) May 5 10:52:16 localhost vdr: [17724] TS buffer on device 1 thread started (pid=17188, tid=17724) May 5 10:52:18 localhost vdr: [17722] setting audio track to 1 (0) May 5 10:54:40 localhost vdr: [17188] switching to channel 10 May 5 10:54:40 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:54:40 localhost vdr: [17188] NumCamSlots = 2 May 5 10:54:40 localhost vdr: [17188] CAM 0 not ready May 5 10:54:40 localhost vdr: [17188] CAM 1 ready May 5 10:54:40 localhost vdr: [17188] CAM 1 provides CA May 5 10:54:40 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1084-9403, 2) = 0 May 5 10:54:40 localhost vdr: [17188] no usable CAM slots! May 5 10:54:40 localhost vdr: [17188] info: Channel not available! May 5 10:54:49 localhost vdr: [17188] switching to channel 10 May 5 10:54:49 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:54:49 localhost vdr: [17188] NumCamSlots = 2 May 5 10:54:49 localhost vdr: [17188] CAM 0 not ready May 5 10:54:49 localhost vdr: [17188] CAM 1 ready May 5 10:54:49 localhost vdr: [17188] CAM 1 provides CA May 5 10:54:49 localhost vdr: [17188] ChannelCamRelations.CamChecked(S19.2E-1-1084-9403, 2) = 0 May 5 10:54:49 localhost vdr: [17188] no usable CAM slots! May 5 10:54:49 localhost vdr: [17188] info: Channel not available! May 5 10:55:00 localhost vdr: [17188] switching to channel 10 May 5 10:55:00 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:55:00 localhost vdr: [17188] NumCamSlots = 2 May 5 10:55:00 localhost vdr: [17188] CAM 0 not ready May 5 10:55:00 localhost vdr: [17188] CAM 1 ready May 5 10:55:00 localhost vdr: [17188] CAM 1 provides CA May 5 10:55:00 localhost vdr: [17188] NumUsableSlots = 1 May 5 10:55:00 localhost vdr: [17188] j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF May 5 10:55:00 localhost vdr: [17188] device 0 May 5 10:55:00 localhost vdr: [17879] transfer thread started (pid=17188, tid=17879) May 5 10:55:00 localhost vdr: [17880] receiver on device 1 thread started (pid=17188, tid=17880) May 5 10:55:01 localhost vdr: [17881] TS buffer on device 1 thread started (pid=17188, tid=17881) May 5 10:55:03 localhost vdr: [17192] CAM 2: module reset May 5 10:55:03 localhost vdr: [17192] CAM 2: module present May 5 10:55:04 localhost vdr: [17192] CAM 2: module ready May 5 10:55:04 localhost vdr: [17879] transfer thread ended (pid=17188, tid=17879) May 5 10:55:05 localhost vdr: [17881] TS buffer on device 1 thread ended (pid=17188, tid=17881) May 5 10:55:05 localhost vdr: [17880] buffer stats: 77832 (3%) used May 5 10:55:05 localhost vdr: [17880] receiver on device 1 thread ended (pid=17188, tid=17880) May 5 10:55:11 localhost vdr: [17188] switching to channel 10 May 5 10:55:11 localhost vdr: [17188] cTS2PES got 0 TS errors, 1 TS continuity errors May 5 10:55:11 localhost last message repeated 2 times May 5 10:55:11 localhost vdr: [17188] buffer stats: 49632 (2%) used May 5 10:55:11 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:55:11 localhost vdr: [17188] NumCamSlots = 2 May 5 10:55:11 localhost vdr: [17188] CAM 0 not ready May 5 10:55:11 localhost vdr: [17188] CAM 1 ready May 5 10:55:11 localhost vdr: [17188] no usable CAM slots! May 5 10:55:11 localhost vdr: [17188] info: Channel not available! May 5 10:55:22 localhost vdr: [17188] switching to channel 10 May 5 10:55:22 localhost vdr: [17188] GetDevice 10 0 1 -1 0500 May 5 10:55:22 localhost vdr: [17188] NumCamSlots = 2 May 5 10:55:22 localhost vdr: [17188] CAM 0 not ready May 5 10:55:22 localhost vdr: [17188] CAM 1 ready May 5 10:55:22 localhost vdr: [17188] no usable CAM slots! May 5 10:55:22 localhost vdr: [17188] info: Channel not available!
--> a few channels work afer staying 5 or 6 s on each one. Then CAM module what was marked ASTON came back to generic and no more decryption. I tried then to manually reset it. Unsuccessful.
May 5 11:04:40 localhost vdr: [17192] CAM 2: module present May 5 11:04:41 localhost vdr: [17192] CAM 2: module ready May 5 11:04:42 localhost vdr: [17192] CAM 2: module reset May 5 11:04:43 localhost vdr: [17192] CAM 2: module present May 5 11:04:44 localhost vdr: [17192] CAM 2: module ready May 5 11:04:49 localhost vdr: [17192] CAM 2: module reset May 5 11:04:49 localhost vdr: [17192] CAM 2: module present May 5 11:04:50 localhost vdr: [17192] CAM 2: module ready May 5 11:04:53 localhost vdr: [17192] CAM 2: module present May 5 11:04:55 localhost vdr: [17192] CAM 2: module ready May 5 11:05:00 localhost vdr: [17192] CAM 2: module reset May 5 11:05:01 localhost vdr: [17192] CAM 2: module present May 5 11:05:02 localhost vdr: [17192] CAM 2: module ready May 5 11:05:06 localhost vdr: [17192] CAM 2: module reset May 5 11:05:07 localhost vdr: [17192] CAM 2: module present May 5 11:05:08 localhost vdr: [17192] CAM 2: module ready May 5 11:05:09 localhost vdr: [17192] CAM 2: module reset May 5 11:05:10 localhost vdr: [17192] CAM 2: module present May 5 11:05:11 localhost vdr: [17192] CAM 2: module ready May 5 11:05:12 localhost vdr: [17192] CAM 2: module reset May 5 11:05:12 localhost vdr: [17192] CAM 2: module present May 5 11:05:13 localhost vdr: [17192] CAM 2: module ready
Pipe log :
... Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . ...
Slot 2: reset...ok. Slot 2: module present Slot 2: module ready Slot 2: creating connection 1/1 Slot 2: create connection 1/1 2: --> 01 01 82 01 01 2: <-- 01 01 83 01 01 80 02 01 00 . . . . . . . . . Slot 2: connection created 1/1 Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 80 . . . . . . Slot 2: receive data 1/1 2: --> 01 01 81 01 01 2: <-- 01 01 A0 07 01 91 04 00 01 00 41 80 02 01 00 . . . . . . . . . A . . . . Slot 2: open session 00010041 Slot 2: new Resource Manager (session id 1) 2: --> 01 01 A0 0A 01 92 07 00 00 01 00 41 00 01 Slot 2: ==> Profile Enq (1) 2: --> 01 01 A0 09 01 90 02 00 01 9F 80 10 00 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 Slot 2: ==> Ca Pmt (3) 3 4 2: --> 01 01 A0 10 01 90 02 00 03 9F 80 32 07 03 00 00 01 00 01 04 Slot 2: ==> Ca Pmt (3) 3 1 2: --> 01 01 A0 1F 01 90 02 00 03 9F 80 32 16 03 24 BB 01 00 01 01 02 00 A2 00 00 04 00 58 00 00 04 00 59 00 00 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: receive data 1/1 2: --> 01 01 81 01 01 2: <-- 01 01 A0 07 01 91 04 00 40 00 41 80 02 01 80 . . . . . . . @ . A . . . . Slot 2: open session 00400041 ERROR: CAM 2: session for resource identifier 00400041 already exists (1/1)Slot 2: receive data 1/1 2: --> 01 01 81 01 01 2: <-- 01 01 A0 0A 01 90 02 00 05 9F 88 00 01 00 80 02 01 00 . . . . . . . . . . . . . . . . . Slot 2: <== Close MMI (5) id = 00 delay = -1 Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 2: <-- 01 01 80 02 01 00 . . . . . . Slot 2: ==> Poll 2: --> 01 01 A0 01 01 Slot 2: ==> Ca Pmt (3) 3 4 2: --> 01 01 A0 10 01 90 02 00 03 9F 80 32 07 03 00 00 01 00 01 04 2: <-- 01 01 80 02 01 00 . . . . . .
This log is really to heavy to post it. Pse find attached a tarball of it.
Pierre
-----Message d'origine----- De : vdr-bounces@linuxtv.org [mailto:vdr-bounces@linuxtv.org] De la part de Klaus Schmidinger Envoyé : dimanche 4 mai 2008 17:59 À : vdr@linuxtv.org Objet : Re: [vdr] Upgrading from 1.4.7 to 1.7.0 : enabling #define
On 05/04/08 16:40, Pierre-Yves Paranthoen (PERSO) wrote:
One part of the pb is that my cam module is ramdomly identified under 1.7.0 that might be the reason why the info "Application Info" and "Ca Pmt
Reply"
is not in the log. VDR-1.7.0 most gives CAM 2: module present & CAM 2: module ready instead of giving Aston Module 1.0300, 01, 0100,0100 (info taken from
vdr-1.4.7).
When it's correctly being identified and trying to access CAM informations under OSD, VDR-1.7 responds ERROR: Can't open CAM menu! A CAM reset gives then a basic information : 2 CAM ready and nothing else. Of course no decryption.
Here is the log of matching my explainations :
May 4 16:07:49 localhost vdr: [8251] CAM 2: module present May 4 16:07:50 localhost vdr: [8251] CAM 1: no module present May 4 16:07:50 localhost vdr: [8251] CAM 2: module ready May 4 16:07:54 localhost vdr: [8251] Slot 2: <== Application Info (2) May 4 16:07:54 localhost vdr: [8251] CAM 2: Aston Module 1.0300, 01, 0100, 0100
So the "application information" is being received. I'm afraid I was looking at the wrong lines when telling you which 'dbgprotocol's to change. Please also change the ones in lines
696: dbgprotocol("Slot %d: <== Ca Info (%d)", Tc()->CamSlot()->SlotNumber(), SessionId());
702: dbgprotocol(" %04X", id);
713: dbgprotocol("\n");
Klaus
vdr mailing list vdr@linuxtv.org http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
vdr mailing list vdr@linuxtv.org http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
-
Klaus Schmidinger -
Marco Göbenich -
Pierre-Yves Paranthoen (PERSO)