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@xxxxxxxxxxx [mailto:vdr-bounces@xxxxxxxxxxx] De la part de Klaus Schmidinger Envoyé : dimanche 4 mai 2008 16:02 À : vdr@xxxxxxxxxxx Objet : Re: 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@xxxxxxxxxxx http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr _______________________________________________ vdr mailing list vdr@xxxxxxxxxxx http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
