T1 Resync command error, trying to reactivate!

= 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))