Opened 13 years ago

Closed 13 years ago

#1239 closed defect (wontfix)

Protocol_T0_ExchangeTPDU: Received unexpected character C0 rotocol_T0_ExchangeTPDU: Received unexpected character C0

Reported by: a3639 Owned by: a3639
Priority: critical Component: Reader
Severity: high Keywords: NDS 0988
Cc: Sensitive: no

Description

2010/11/12 22:49:20 30016200 s >> OSCam << cardserver started version 1.00-unstable_svn, build #3698 (powerpc-tuxbox-linux)
2010/11/12 22:49:20 30016200 s version=1.00-unstable_svn, build #3698, system=powerpc-tuxbox-linux, nice=-1
2010/11/12 22:49:20 30016200 s client max. idle=120 sec, debug level=255
2010/11/12 22:49:20 30016200 s max. logsize=unlimited
2010/11/12 22:49:20 30016200 s client timeout=5000 ms, fallback timeout=2500 ms, cache delay=0 ms
2010/11/12 22:49:20 30016200 s auth size=4800
2010/11/12 22:49:20 30016200 s Cannot open file "/var/tuxbox/config/oscam.services" (errno=2)
2010/11/12 22:49:20 30016200 s userdb reloaded: 0 accounts freed, 1 accounts loaded, 0 expired, 0 disabled
2010/11/12 22:49:20 30016200 s signal handling initialized (type=sysv)
2010/11/12 22:49:20 30016200 s can't open file "/var/tuxbox/config/oscam.srvid" (err=2), no service-id's loaded
2010/11/12 22:49:20 30016200 s can't open file "/var/tuxbox/config/oscam.tiers" (err=2), no tier-id's loaded
2010/11/12 22:49:20 30016200 s can't open file "/var/tuxbox/config/oscam.provid" (err=2), no provids's loaded
2010/11/12 22:49:20 30016200 s can't open file "/var/tuxbox/config/oscam.ird" (errno=2) irdeto guessing not loaded
2010/11/12 22:49:20 30016200 s monitor: disabled
2010/11/12 22:49:20 30016200 s camd33: disabled
2010/11/12 22:49:20 30016200 s camd35: disabled
2010/11/12 22:49
:20 30016200 s cs378x: disabled

2010/11/12 22:49:20 30016200 s newcamd: initialized (fd=6, port=10001, crypted)
2010/11/12 22:49:20 30016200 s CAID: 0988
2010/11/12 22:49:20 30016200 s provid #0: 000000
2010/11/12 22:49:20 30016200 s cccam: disabled
2010/11/12 22:49:20 30016200 s radegast: disabled
2010/11/12 22:49:20 30016200 s http thread started
2010/11/12 22:49:20 30859B50 h HTTP Server listening on port 16988
2010/11/12 22:49:20 30016200 s creating thread for device /dev/sci0 slot 0
2010/11/12 22:49:20 30016200 s waiting for local card init
2010/11/12 22:49:20 31059B50 r reader thread started (thread=31059B50, device=/dev/sci0, detect=cd, mhz=357, cardmhz=357)
2010/11/12 22:49:20 31059B50 r IFD: Opening device /dev/sci0

2010/11/12 22:49:20 31059B50 r IFD: Device /dev/sci0 succesfully opened

2010/11/12 22:49:20 31059B50 r ct_init on /dev/sci0: 1
2010/11/12 22:49:20 31059B50 r card detected
2010/11/12 22:49:20 31059B50 r IFD: Activating card in reader dream

2010/11/12 22:49:20 31059B50 r IFD: Activating card

2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 3F
2010/11/12 22:49:21 31059B50 r IO: Receiving:

2010/11/12 22:49:21 7F
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 13
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 25
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 04
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 33
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 B0
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 02
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 69
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 FF
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 4A
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 50
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 E0
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 00
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 00
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 54
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 42
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 00
2010/11/12 22:49:21 31059B50 r IO: Receiving:
2010/11/12 22:49:21 0
0
2010/11/12 22:49:21 31059B50 r IO: Receiving:

2010/11/12 22:49:21 00
2010/11/12 22:49:21 31059B50 r IO: Receiving:

2010/11/12 22:49:22 TIMEOUT
2010/11/12 22:49:22 31059B50 r TA1 = 13
2010/11/12 22:49:22 31059B50 r FI = 01

2010/11/12 22:49:22 31059B50 r ATR: 3F 7F 13 25 04 33 B0 02 69 FF 4A 50 E0 00 00 54 42 00 00 00
2010/11/12 22:49:22 31059B50 r TA1=13 TB1=25 TC1=04 no TD1 means T0
2010/11/12 22:49:22 31059B50 r 1 protocol types detected. Historical bytes: 33 B0 02 69 FF 4A 50 E0 00 00 54 42 00 00 00
2010/11/12 22:49:22 31059B50 r Only T0 available, selected protocol: T0, F=372, D=4.000000, N=4

2010/11/12 22:49:22 31059B50 r Setting baudrate to 38400
2010/11/12 22:49:22 31059B50 r Setting timings: timeout=178 ms, block_delay=0 ms, char_delay=0 ms
2010/11/12 22:49:22 31059B50 r Protocol: T=0: WWT=9600, Clockrate=3571200

2010/11/12 22:49:22 31059B50 r IO: Receiving:

TIMEOUT
2010/11/12 22:49:23 31059B50 r Setting T=0 fs=5 mhz ETU=93 WWT=9600 CWT=0 BWT=0 EGT=4 clock=0 check=0 P=5 I=50 U=0
2010/11/12 22:49:23 31059B50 r Maximum frequency for this card is formally 5 Mhz, clocking it to 5.00 Mhz
2010/11/12 22:49:23 31059B50 r IFD: Card in reader dream succesfully activated

2010/11/12 22:49:24 31059B50 r [videoguard2-reader] passed history check
2010/11/12 22:49:24 31059B50 r [videoguard12-reader] passed history check
2010/11/12 22:49:24 31059B50 r [videoguard12-reader] type: VideoGuard China (0988), baseyear: 1997
2010/11/12 22:49:24 31059B50 r [videoguard12-reader] forced to NDS12
2010/11/12 22:49:24 31059B50 r [videoguard12-reader] oscam.server BoxID: 16XXXXB2
2010/11/12 22:49:24 31059B50 r write to cardreader dream:
2010/11/12 22:49:24 49 52 00 00 14
2010/11/12 22:49:24 31059B50 r IFD Transmit:
2010/11/12 22:49:24 49 52 00 00 14
2010/11/12 22:49:24 31059B50 r IO: Sending:
2010/11/12 22:49:24 49 52 00 00 14
2010/11/12 22:49:24 31059B50 r IFD Transmit succesful
2010/11/12 22:49:24 31059B50 r IO: Receiving:

52
2010/11/12 22:49:24 31059B50 r IFD Received:
2010/11/12 22:49:24 52
2010/11/12 22:49:24 31059B50 r IO: Receiving:
2010/11/12 22:49:24 00 01 IR IR 01 21 19 11 00 0C 09 00 3F 02 03 04
2010/11/12 22:49:24 11 01 00 00
2010/11/12 22:49:24 31059B50 r IFD Received:
2010/11/12 22:49:24 00 01 IR IR 01 21 19 11 00 0C 09 00 3F 02 03 04
2010/11/12 22:49:24 11 01 00 00
2010/11/12 22:49:24 31059B50 r IO: Receiving:
2010/11/12 22:49:24 90
2010/11/12 22:49:24 31059B50 r IFD Received:
2010/11/12 22:49:24 90
2010/11/12 22:49:24 31059B50 r IO: Receiving:
2010/11/12 22:49:24 00
2010/11/12 22:49:24 31059B50 r IFD Received:
2010/11/12 22:49:24 00
2010/11/12 22:49:24 31059B50 r answer from cardreader dream:
2010/11/12 22:49:24 00 01 IR IR 01 21 19 11 00 0C 09 00 3F 02 03 04
2010/11/12 22:49:24 11 01 00 00 90 00
2010/11/12 22:49:24 31059B50 r write to cardreader dream:
2010/11/12 22:49:24 49 58 00 00 35
2010/11/12 22:49:24 31059B50 r IFD Transmit:
2010/11/12 22:49:24 49 58 00 00 35
2010/11/12 22:49:24 31059B50 r IO: Sending:
2010/11/12 22:49:24 49 58 00 00 35
2010/11/12 22:49:24 31059B50 r IFD Transmit succesful
2010/11/12 22:49:24 31059B50 r IO: Receiving:
2010/11/12 22:49:24 C0
2010/11/12 22:49:24 31059B50 r IFD Received:
2010/11/12 22:4
9:24 C0
2010/11/12 22:49:24 31059B50 r ERROR Protocol_T0_ExchangeTPDU: Received unexpected character C0
2010/11/12 22:49:24 31059B50 r ERROR, function call Protocol_T0_Command (reader, command, command_len, rsp, lr) returns error.
2010/11/12 22:49:24 31059B50 r answer from cardreader dream:
2010/11/12 22:49:24 31059B50 r [videoguard12-reader] class49 ins58: failed

Something strange happened!
As you can see from the log, sending "49 52 00 00 14" to the card, the returned status bytes of '90 00' was correct, but the following command "49 58 00 00 35" sent to the card, it showed the "IO: Receiving: C0", but it is supposed to be 52. And of course all other error messages show up.

Pls help!!

Change History (3)

comment:1 by a3639, 13 years ago

I did some more testing with win explorer
RX ATR : 3F 7F 13 25 04 33 B0 02 69 FF 4A 50 E0 00 00 54 42 00 00 00
TX Data : 48 52 00 00 14
RX Data : 52 00 01 IR IR 01 21 19 11 00 0C 09 00 3F 02 03

04 11 01 00 00

RX Data : 90 00 <<<< The result is OK

Trying to reset card...
Reset Successful

RX ATR : 3F 7F 13 25 04 33 B0 02 69 FF 4A 50 E0 00 00 54 42 00 00 00
TX Data : 52 00 00 14 <<<< CLA 48 was omitted
RX Data : C0

Script Error on Line 35
Sc.Read: Timeout Reading Data From Card - 21 Bytes Requested, 1 Bytes Read

RX ATR : 3F 7F 13 25 04 33 B0 02 69 FF 4A 50 E0 00 00 54 42 00 00 00
TX Data : 48 00 00 14 << INS58 was omitted
RX Data : C0

Script Error on Line 35
Sc.Read: Timeout Reading Data From Card - 21 Bytes Requested, 1 Bytes Read

As we can see, if the command data sent to the card are less than the supposed 5 bytes, returned data will be C0 and timeout is the result.

And further more, if the card received more that what it supposed to recieve, returned data will show FF (but sending more that what it supposed to is rare).

comment:2 by pooyair, 13 years ago

no update for 5 months in this ticket ,also there are many change since 5 month ago , please test with latest revs and reopen it if problem still exist with latest debug log

thanks

comment:3 by docmarius, 13 years ago

Resolution: wontfix
Status: newclosed

This issue is old.
We have a new stable version and a new trunk.
Check on current versions and create new ticket if needed.

Note: See TracTickets for help on using tickets.