[vdr] Upgrading from 1.4.7 to 1.7.0 : enabling #define

Pierre-Yves Paranthoen (PERSO) py.paranthoen at wanadoo.fr
Mon May 5 11:13:25 CEST 2008


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 at linuxtv.org [mailto:vdr-bounces at linuxtv.org] De la part de
Klaus Schmidinger
Envoyé : dimanche 4 mai 2008 17:59
À : vdr at 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 at linuxtv.org
http://www.linuxtv.org/cgi-bin/mailman/listinfo/vdr
-------------- next part --------------
A non-text attachment was scrubbed...
Name: vdr-1.7.0-debug.tar.bz2
Type: application/octet-stream
Size: 3704 bytes
Desc: not available
Url : http://www.linuxtv.org/pipermail/vdr/attachments/20080505/9183c6f8/attachment-0001.obj 


More information about the vdr mailing list