Opened 10 years ago

Closed 9 years ago

#3789 closed defect (invalid)

T1 Resync command error, trying to reactivate!

Reported by: javi Owned by:
Priority: major Component: Card support
Severity: high Keywords: nagra, t1 resync
Cc: Sensitive: no

Description

Revision

oscam-1.20-unstable_svn-r9778

Issue Description

T1 Resync command error, trying to reactivate! using phoenix usb 357mhz in mouse protocol

When the issue occurs

some times

How the issue is reproducable

2014/07/26 15:40:05 9E74128 c iplus (1810&004001/0000/74CF/89:E1B7B6E42714D1871543195B3BD23520): cache1 (0 ms) by blanquita - Canal+ Xtra HD
2014/07/26 15:40:14 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 7850 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:14 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 76C4 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:14 9DEF128 r {client cccam, caid 1810, srvid 7850} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:14 9E75F50 c cccam (1810&004001/0000/7850/8E:48F2E86061EA52E376C2B733DFBD3EAD): found (278 ms) by blanquita - AXN Spain HD (cwc LEARN)
2014/07/26 15:40:14 9DEF128 r {client cccam, caid 1810, srvid 76C4} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:14 9E75F50 c cccam (1810&004001/0000/76C4/8E:0A76CA790D3F823C5C2280825B3A4A24): found (522 ms) by blanquita - Sportmania (cwc LEARN)
2014/07/26 15:40:14 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 75FE rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:15 9DEF128 r {client cccam, caid 1810, srvid 75FE} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:15 9E75F50 c cccam (1810&004001/0000/75FE/8E:7BC112FBC684AE3425EA9B7FF250E0EE): found (280 ms) by blanquita - Antena Neox (cwc LEARN)
2014/07/26 15:40:17 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 0121 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:17 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:18 9DEF128 r blanquita [nagra] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2014/07/26 15:40:20 9DEF128 r blanquita [nagra] T1 Resync command error, trying to reactivate!
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 38 30 20 4D 65 72 4A 30 39 05
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Init card protocol T1, FI=9, F=512, D=16, N=255
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Setting baudrate to 111600 bps
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Calculated work ETU is 8.96 us reader mhz = 357
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Card responded ok for ifsd request of 251
2014/07/26 15:40:22 9E75F50 c {client cccam, caid 1810, srvid 0121} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 5
2014/07/26 15:40:22 9E75F50 c cccam (1810&004001/0000/0121/91:CFC0C939F5BBD0B8D380C7083958E471): timeout (5000 ms) by blanquita
2014/07/26 15:40:22 9E74128 c {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 5
2014/07/26 15:40:22 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): timeout (5000 ms) by blanquita - Canal+ Xtra HD
2014/07/26 15:40:22 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:23 9DEF128 r blanquita [nagra] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2014/07/26 15:40:24 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 75FE rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:24 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 78C1 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:24 9DEF128 r blanquita [nagra] T1 Resync command error, trying to reactivate!
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 38 30 20 4D 65 72 4A 30 39 05
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Init card protocol T1, FI=9, F=512, D=16, N=255
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Setting baudrate to 111600 bps
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Calculated work ETU is 8.96 us reader mhz = 357
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Card responded ok for ifsd request of 251
2014/07/26 15:40:27 9DEF128 r {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:27 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): found (4273 ms) by blanquita - Canal+ Xtra HD (cwc OK)
2014/07/26 15:40:27 9DEF128 r {client cccam, caid 1810, srvid 75FE} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:27 9E75F50 c cccam (1810&004001/0000/75FE/8E:AC1981482535AE0FE6D573C5D723EAAC): found (3061 ms) by blanquita - Antena Neox (cwc LEARN)
2014/07/26 15:40:27 9E74128 c cyclecheck [Bad CW Cycle] for: iplus 1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD from: cache -> ECM Answer is too OLD -> drop cw (ECM Answer)
2014/07/26 15:40:27 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:27 9DEF128 r {client cccam, caid 1810, srvid 78C1} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:27 9E75F50 c cccam (1810&004001/0000/78C1/8E:014C098174FA307442EA3207E7912210): found (3002 ms) by blanquita (cwc LEARN)
2014/07/26 15:40:27 9DEF128 r cyclecheck [Bad CW Cycle] for: iplus 1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD from: blanquita -> ECM Answer is too OLD -> drop cw (ECM Answer)
2014/07/26 15:40:27 9DEF128 r {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck failed! Reader: blanquita set rc: 4
2014/07/26 15:40:27 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): rejected (98 ms) by blanquita - Canal+ Xtra HD (cwc NOK(old))
2014/07/26 15:40:28 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:28 9DEF128 r cyclecheck [Bad CW Cycle] for: iplus 1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD from: blanquita -> ECM Answer is too OLD -> drop cw (ECM Answer)
2014/07/26 15:40:28 9DEF128 r {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck failed! Reader: blanquita set rc: 4
2014/07/26 15:40:28 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): rejected (273 ms) by blanquita - Canal+ Xtra HD (cwc NOK(old))

Change History (6)

comment:1 by javi, 10 years ago

new log

2014/07/26 16:08:01 9E74128 c iplus (1810&004001/0000/74CF/89:A3C285E52693A3B9743347383F12EFD7): found (275 ms) by blanquita - Canal+ Xtra HD (cwc OK)
2014/07/26 16:08:01 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 75FE} [get_cw] NEW REQUEST!
2014/07/26 16:08:01 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 75FE} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:01 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 75FE} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:01 9DEF128 r {client cccam, caid 1810, srvid 75FE} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 16:08:01 9DEF128 r {client cccam, caid 1810, prid 004001, srvid 75FE} [write_ecm_answer] reader blanquita rc 0, ecm time 271 ms (273 ms)
2014/07/26 16:08:01 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 75FE} [send_dcw] rc 0 from reader blanquita
2014/07/26 16:08:01 9E75F50 c cccam (1810&004001/0000/75FE/8E:C0B667D6B177DE4C0736D5BBCC5F0CC7): found (273 ms) by blanquita - Antena Neox (cwc LEARN)
2014/07/26 16:08:01 9E74128 c {client iplus, caid 1810, prid 000000, srvid 74CF} [get_cw] NEW REQUEST!
2014/07/26 16:08:01 9E74128 c {client iplus, caid 1810, srvid 74CF} [check_cache] cyclecheck passed ecm in INT. cache, ecm->rc -1
2014/07/26 16:08:01 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [get_cw] cw found immediately in cache!
2014/07/26 16:08:01 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [write_ecm_answer_fromcache] found cw in CACHE (count 1)!
2014/07/26 16:08:01 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [send_dcw] rc 1 from reader blanquita
2014/07/26 16:08:01 9E74128 c iplus (1810&004001/0000/74CF/89:A3C285E52693A3B9743347383F12EFD7): cache1 (1 ms) by blanquita - Canal+ Xtra HD
2014/07/26 16:08:06 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 779D} [get_cw] NEW REQUEST!
2014/07/26 16:08:06 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 779D} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:06 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 779D} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:08 9DEF128 r blanquita [nagra] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2014/07/26 16:08:08 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 77C7} [get_cw] NEW REQUEST!
2014/07/26 16:08:08 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 77C7} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:08 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 77C7} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:09 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 779D} fallback timeout! (stage: 2)
2014/07/26 16:08:09 9DEF128 r blanquita [nagra] T1 Resync command error, trying to reactivate!
2014/07/26 16:08:11 9DEF128 r blanquita [nagra] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 38 30 20 4D 65 72 4A 30 39 05
2014/07/26 16:08:11 9DEF128 r blanquita [nagra] Init card protocol T1, FI=9, F=512, D=16, N=255
2014/07/26 16:08:11 9DEF128 r blanquita [nagra] Setting baudrate to 111600 bps
2014/07/26 16:08:11 9DEF128 r blanquita [nagra] Calculated work ETU is 8.96 us reader mhz = 357
2014/07/26 16:08:11 9DEF128 r blanquita [nagra] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2014/07/26 16:08:11 9DEF128 r blanquita [nagra] Card responded ok for ifsd request of 251
2014/07/26 16:08:11 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 77C7} fallback timeout! (stage: 2)
2014/07/26 16:08:11 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 779D} client timeout!
2014/07/26 16:08:11 9E75F50 c {client cccam, caid 1810, srvid 779D} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 5
2014/07/26 16:08:11 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 779D} [write_ecm_answer] reader blanquita rc 5, ecm time 5000 ms (5000 ms)
2014/07/26 16:08:11 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 779D} [send_dcw] rc 5 from reader blanquita
2014/07/26 16:08:11 9E75F50 c cccam (1810&004001/0000/779D/8E:5416FA969180A4943354A6370D13560C): timeout (5000 ms) by blanquita - C+ DEPORTES
2014/07/26 16:08:12 9DEF128 r blanquita [nagra] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2014/07/26 16:08:13 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 77C7} client timeout!
2014/07/26 16:08:13 9E75F50 c {client cccam, caid 1810, srvid 77C7} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 5
2014/07/26 16:08:13 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 77C7} [write_ecm_answer] reader blanquita rc 5, ecm time 4999 ms (5000 ms)
2014/07/26 16:08:13 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 77C7} [send_dcw] rc 5 from reader blanquita
2014/07/26 16:08:13 9E75F50 c cccam (1810&004001/0000/77C7/8E:1DBC02426C18FCB3CEE4C4B1D7BDD9EF): timeout (5000 ms) by blanquita - Cuatro
2014/07/26 16:08:14 9DEF128 r blanquita [nagra] T1 Resync command error, trying to reactivate!
2014/07/26 16:08:15 9DEF128 r blanquita [nagra] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 38 30 20 4D 65 72 4A 30 39 05
2014/07/26 16:08:15 9DEF128 r blanquita [nagra] Init card protocol T1, FI=9, F=512, D=16, N=255
2014/07/26 16:08:15 9DEF128 r blanquita [nagra] Setting baudrate to 111600 bps
2014/07/26 16:08:15 9DEF128 r blanquita [nagra] Calculated work ETU is 8.96 us reader mhz = 357
2014/07/26 16:08:15 9DEF128 r blanquita [nagra] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2014/07/26 16:08:15 9DEF128 r blanquita [nagra] Card responded ok for ifsd request of 251
2014/07/26 16:08:17 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 74A4} [get_cw] NEW REQUEST!
2014/07/26 16:08:17 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 74A4} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:17 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 74A4} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:17 9DEF128 r {client cccam, caid 1810, srvid 74A4} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 4
2014/07/26 16:08:17 9DEF128 r {client cccam, caid 1810, prid 004001, srvid 74A4} [write_ecm_answer] reader blanquita rc 4, ecm time 204 ms (205 ms)
2014/07/26 16:08:17 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 74A4} [send_dcw] rc 4 from reader blanquita
2014/07/26 16:08:17 9E75F50 c cccam (1810&004001/0000/74A4/8E:E5F5042630BFCD0A9DAE56D580F70203): not found (205 ms) by blanquita - Motors TV
2014/07/26 16:08:20 9E74128 c {client iplus, caid 1810, prid 000000, srvid 74CF} [get_cw] NEW REQUEST!
2014/07/26 16:08:20 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:20 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:21 9DEF128 r {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 16:08:21 9DEF128 r {client iplus, caid 1810, prid 004001, srvid 74CF} [write_ecm_answer] reader blanquita rc 0, ecm time 292 ms (293 ms)
2014/07/26 16:08:21 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [send_dcw] rc 0 from reader blanquita
2014/07/26 16:08:21 9E74128 c iplus (1810&004001/0000/74CF/89:8FF824A2E3ED397769C51F638D6EFEC4): found (293 ms) by blanquita - Canal+ Xtra HD (cwc IGN)
2014/07/26 16:08:21 9E74128 c {client iplus, caid 1810, prid 000000, srvid 74CF} [get_cw] NEW REQUEST!
2014/07/26 16:08:21 9E74128 c {client iplus, caid 1810, srvid 74CF} [check_cache] cyclecheck passed ecm in INT. cache, ecm->rc -1
2014/07/26 16:08:21 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [get_cw] cw found immediately in cache!
2014/07/26 16:08:21 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [write_ecm_answer_fromcache] found cw in CACHE (count 1)!
2014/07/26 16:08:21 9E74128 c {client iplus, caid 1810, prid 004001, srvid 74CF} [send_dcw] rc 1 from reader blanquita
2014/07/26 16:08:21 9E74128 c iplus (1810&004001/0000/74CF/89:8FF824A2E3ED397769C51F638D6EFEC4): cache1 (1 ms) by blanquita - Canal+ Xtra HD (cwc LEARN)
2014/07/26 16:08:21 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 78B8} [get_cw] NEW REQUEST!
2014/07/26 16:08:21 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 78B8} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:21 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 78B8} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:21 9DEF128 r {client cccam, caid 1810, srvid 78B8} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 4
2014/07/26 16:08:21 9DEF128 r {client cccam, caid 1810, prid 004001, srvid 78B8} [write_ecm_answer] reader blanquita rc 4, ecm time 204 ms (205 ms)
2014/07/26 16:08:21 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 78B8} [send_dcw] rc 4 from reader blanquita
2014/07/26 16:08:21 9E75F50 c cccam (1810&004001/0000/78B8/8E:67DD79357FFE40EED7DD11BE5308D19D): not found (205 ms) by blanquita - Cosmopolitan HD
2014/07/26 16:08:25 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 76C4} [get_cw] NEW REQUEST!
2014/07/26 16:08:25 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 76C4} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:25 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 76C4} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:25 9DEF128 r {client cccam, caid 1810, srvid 76C4} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 16:08:25 9DEF128 r {client cccam, caid 1810, prid 004001, srvid 76C4} [write_ecm_answer] reader blanquita rc 0, ecm time 275 ms (276 ms)
2014/07/26 16:08:25 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 76C4} [send_dcw] rc 0 from reader blanquita
2014/07/26 16:08:25 9E75F50 c cccam (1810&004001/0000/76C4/8E:E779958B9C9DC1F26DA71A2D1DF30114): found (276 ms) by blanquita - Sportmania (cwc LEARN)
2014/07/26 16:08:27 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 778B} [get_cw] NEW REQUEST!
2014/07/26 16:08:27 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 778B} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:27 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 778B} [write_ecm_request] reader blanquita --> SENT 1
2014/07/26 16:08:27 9DEF128 r {client cccam, caid 1810, srvid 778B} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 4
2014/07/26 16:08:27 9DEF128 r {client cccam, caid 1810, prid 004001, srvid 778B} [write_ecm_answer] reader blanquita rc 4, ecm time 203 ms (204 ms)
2014/07/26 16:08:27 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 778B} [send_dcw] rc 4 from reader blanquita
2014/07/26 16:08:27 9E75F50 c cccam (1810&004001/0000/778B/8E:BA41AD67A428EC8273CE0AEB63FFEBF7): not found (204 ms) by blanquita - Playboy TV
2014/07/26 16:08:28 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 02C4} [get_cw] NEW REQUEST!
2014/07/26 16:08:28 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 02C4} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/07/26 16:08:28 9E75F50 c {client cccam, caid 1810, prid 004001, srvid 02C4} [write_ecm_request] reader blanquita --> SENT 1

in reply to:  description comment:2 by rickysnake, 10 years ago

Hi kivivaj, I had a similar problem and I could solve it with the following code:

Code:
dwc_otg.microframe_schedule=1 dwc_otg.speed=1

Please test it and give us feedback it solved.

Replying to kivivaj:

Revision

oscam-1.20-unstable_svn-r9778

Issue Description

T1 Resync command error, trying to reactivate! using phoenix usb 357mhz in mouse protocol

When the issue occurs

some times

How the issue is reproducable

2014/07/26 15:40:05 9E74128 c iplus (1810&004001/0000/74CF/89:E1B7B6E42714D1871543195B3BD23520): cache1 (0 ms) by blanquita - Canal+ Xtra HD
2014/07/26 15:40:14 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 7850 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:14 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 76C4 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:14 9DEF128 r {client cccam, caid 1810, srvid 7850} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:14 9E75F50 c cccam (1810&004001/0000/7850/8E:48F2E86061EA52E376C2B733DFBD3EAD): found (278 ms) by blanquita - AXN Spain HD (cwc LEARN)
2014/07/26 15:40:14 9DEF128 r {client cccam, caid 1810, srvid 76C4} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:14 9E75F50 c cccam (1810&004001/0000/76C4/8E:0A76CA790D3F823C5C2280825B3A4A24): found (522 ms) by blanquita - Sportmania (cwc LEARN)
2014/07/26 15:40:14 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 75FE rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:15 9DEF128 r {client cccam, caid 1810, srvid 75FE} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:15 9E75F50 c cccam (1810&004001/0000/75FE/8E:7BC112FBC684AE3425EA9B7FF250E0EE): found (280 ms) by blanquita - Antena Neox (cwc LEARN)
2014/07/26 15:40:17 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 0121 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:17 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:18 9DEF128 r blanquita [nagra] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2014/07/26 15:40:20 9DEF128 r blanquita [nagra] T1 Resync command error, trying to reactivate!
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 38 30 20 4D 65 72 4A 30 39 05
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Init card protocol T1, FI=9, F=512, D=16, N=255
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Setting baudrate to 111600 bps
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Calculated work ETU is 8.96 us reader mhz = 357
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2014/07/26 15:40:21 9DEF128 r blanquita [nagra] Card responded ok for ifsd request of 251
2014/07/26 15:40:22 9E75F50 c {client cccam, caid 1810, srvid 0121} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 5
2014/07/26 15:40:22 9E75F50 c cccam (1810&004001/0000/0121/91:CFC0C939F5BBD0B8D380C7083958E471): timeout (5000 ms) by blanquita
2014/07/26 15:40:22 9E74128 c {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 5
2014/07/26 15:40:22 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): timeout (5000 ms) by blanquita - Canal+ Xtra HD
2014/07/26 15:40:22 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:23 9DEF128 r blanquita [nagra] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2014/07/26 15:40:24 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 75FE rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:24 9E75F50 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 78C1 rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:24 9DEF128 r blanquita [nagra] T1 Resync command error, trying to reactivate!
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] ATR: 3F FF 95 00 FF 91 81 71 FE 47 00 44 4E 41 53 50 31 38 30 20 4D 65 72 4A 30 39 05
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Init card protocol T1, FI=9, F=512, D=16, N=255
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Setting baudrate to 111600 bps
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Calculated work ETU is 8.96 us reader mhz = 357
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2014/07/26 15:40:26 9DEF128 r blanquita [nagra] Card responded ok for ifsd request of 251
2014/07/26 15:40:27 9DEF128 r {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:27 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): found (4273 ms) by blanquita - Canal+ Xtra HD (cwc OK)
2014/07/26 15:40:27 9DEF128 r {client cccam, caid 1810, srvid 75FE} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:27 9E75F50 c cccam (1810&004001/0000/75FE/8E:AC1981482535AE0FE6D573C5D723EAAC): found (3061 ms) by blanquita - Antena Neox (cwc LEARN)
2014/07/26 15:40:27 9E74128 c cyclecheck [Bad CW Cycle] for: iplus 1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD from: cache -> ECM Answer is too OLD -> drop cw (ECM Answer)
2014/07/26 15:40:27 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:27 9DEF128 r {client cccam, caid 1810, srvid 78C1} [write_ecm_answer] cyclecheck passed! Reader: blanquita rc: 0
2014/07/26 15:40:27 9E75F50 c cccam (1810&004001/0000/78C1/8E:014C098174FA307442EA3207E7912210): found (3002 ms) by blanquita (cwc LEARN)
2014/07/26 15:40:27 9DEF128 r cyclecheck [Bad CW Cycle] for: iplus 1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD from: blanquita -> ECM Answer is too OLD -> drop cw (ECM Answer)
2014/07/26 15:40:27 9DEF128 r {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck failed! Reader: blanquita set rc: 4
2014/07/26 15:40:27 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): rejected (98 ms) by blanquita - Canal+ Xtra HD (cwc NOK(old))
2014/07/26 15:40:28 9E74128 c [GET_CW] wait_time 0 caid 1810 prov 004001 srvid 74CF rc 100 cacheex cl mode 0 ex1rdr 0
2014/07/26 15:40:28 9DEF128 r cyclecheck [Bad CW Cycle] for: iplus 1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD from: blanquita -> ECM Answer is too OLD -> drop cw (ECM Answer)
2014/07/26 15:40:28 9DEF128 r {client iplus, caid 1810, srvid 74CF} [write_ecm_answer] cyclecheck failed! Reader: blanquita set rc: 4
2014/07/26 15:40:28 9E74128 c iplus (1810&004001/0000/74CF/89:73A90642CD43B64DA09EB4B55E7E41AD): rejected (273 ms) by blanquita - Canal+ Xtra HD (cwc NOK(old))

comment:3 by javi, 10 years ago

where put the code?

in reply to:  3 comment:4 by rickysnake, 10 years ago

No code, just modify cmdline.txt with the mentioned line:
dwc_otg.microframe_schedule=1 dwc_otg.speed=1

Replying to kivivaj:

where put the code?

comment:5 by javi, 10 years ago

i not have this file

comment:6 by Deas, 9 years ago

Resolution: invalid
Status: newclosed
Note: See TracTickets for help on using tickets.