-------------------------------------------------------------------------------
>> OSCam << cardserver log switched at Fri Nov 23 19:04:34 2018
-------------------------------------------------------------------------------
2018/11/23 19:04:34 00000000 s >> OSCam << cardserver log switched, version 1.20_svn, build r11438 (arm-newnigma2-linux-gnueabihf-libusb)
2018/11/23 19:04:34 00000000 s (main) System name = Linux
2018/11/23 19:04:34 00000000 s (main) Host name = dm920
2018/11/23 19:04:34 00000000 s (main) Release = 3.14-1.17-dm920
2018/11/23 19:04:34 00000000 s (main) Version = #1 SMP Wed Nov 15 13:02:43 CET 2017
2018/11/23 19:04:34 00000000 s (main) Machine = armv7l
2018/11/23 19:04:34 00000000 s (main) Stb model = dm920
2018/11/23 19:04:34 00000000 s (main) creating pidfile /tmp/.oscam/oscam.pid with pid 652
2018/11/23 19:04:34 00000000 s (config) services reloaded: 0 services freed, 5 services loaded, rejected 0
2018/11/23 19:04:34 00000000 s (config) userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2018/11/23 19:04:34 00000000 s (main) signal handling initialized
2018/11/23 19:04:34 00000000 s (config) 206 service-id's loaded in 1 ms
2018/11/23 19:04:34 00000000 s (config) 95 tier-id's loaded
2018/11/23 19:04:34 00000000 s (reader) HD03 [mouse] creating thread for device /dev/ttyUSB0
2018/11/23 19:04:34 00000000 s (main) waiting for local card init
2018/11/23 19:04:34 640D88FE h (webif) webif: decompressed 177720 bytes back into 438064 bytes
2018/11/23 19:04:34 640D88FE h (webif) HTTP Server running. ip=0.0.0.0 port=83
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Buffers readed 0 bytes total time_us 75997
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 1 value 03 time_us 3029
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 2 value 00 time_us 38871
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 3 value 56 time_us 22919
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 4 value ff time_us 36971
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 5 value 00 time_us 22829
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 6 value 76 time_us 22896
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 7 value 7e time_us 22940
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 8 value 71 time_us 22924
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 9 value 80 time_us 22862
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 10 value 15 time_us 22948
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 11 value ff time_us 22915
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 12 value dd time_us 22922
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 13 value 8d time_us 28893
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 14 value 7d time_us 18902
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 15 value 35 time_us 2887
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 16 value f5 time_us 1944
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 17 value d3 time_us 1948
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 18 value 73 time_us 2950
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 19 value f3 time_us 1941
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 20 value fb time_us 3195
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 21 value b5 time_us 1639
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 22 value 59 time_us 1934
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 23 value 91 time_us 2947
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 24 value 75 time_us 1885
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 25 value b3 time_us 2941
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 26 value 53 time_us 1961
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Flush readed byte Nr 27 value 17 time_us 1922
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Buffers readed 27 bytes total time_us 440156
2018/11/23 19:04:34 3F355FA5 r (reader) HD03 [mouse] Reader initialized (device=/dev/ttyUSB0, detect=cts, mhz=357, cardmhz=357)
2018/11/23 19:04:35 3F355FA5 r (reader) HD03 [mouse] card detected
2018/11/23 19:04:35 3F355FA5 r (reader) HD03 [mouse] Buffers readed 0 bytes total time_us 75111
2018/11/23 19:04:36 3F355FA5 r (reader) HD03 [mouse] ATR: 3F FF 95 00 FF 91 81 71 A0 47 00 44 4E 41 53 50 31 39 30 20 4D 65 72 51 32 35 4F
2018/11/23 19:04:36 3F355FA5 r (reader) HD03 [mouse] HD-Plus 03 recognized
2018/11/23 19:04:36 3F355FA5 r (reader) HD03 [mouse] Setting baudrate to 111600 bps
2018/11/23 19:04:36 3F355FA5 r (reader) HD03 [mouse] Buffers readed 0 bytes total time_us 75115
2018/11/23 19:04:36 3F355FA5 r (reader) HD03 [mouse] try to init nagra layer
2018/11/23 19:04:36 3F355FA5 r (reader) HD03 [mouse] switch to nagra layer OK
2018/11/23 19:04:36 3F355FA5 r (reader) HD03 [mouse] Buffers readed 0 bytes total time_us 75116
2018/11/23 19:04:38 3F355FA5 r (reader) HD03 [mouse] Nagra layer ATR: 3F FF 95 00 FF 91 81 71 FE 57 00 44 4E 41 53 50 34 31 30 20 52 65 76 51 32 35 17
2018/11/23 19:04:38 3F355FA5 r (reader) HD03 [mouse] Setting baudrate to 111600 bps
2018/11/23 19:04:38 3F355FA5 r (reader) HD03 [mouse] Buffers readed 0 bytes total time_us 75137
2018/11/23 19:04:39 3F355FA5 r (reader) HD03 [mouse] Rom revision: DNASP410 RevQ25
2018/11/23 19:04:40 3F355FA5 r (reader) HD03 [mouse] Card expire date: 2019/01/17
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [mouse] found card system Nagra_Merlin
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] ROM: D N A S P 4 1 0
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] REV: R e v Q 2 5
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] SER: ######## ##########
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] CAID: 1860
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] Prv.ID: 00 00 34 11 (sysid)
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] IRD ID: FF FF FF FF
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] -----------------------------------------
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] |id |tier |valid from |valid to |
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] +----+--------+------------+------------+
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] |8011|0068 |2014/09/30 |2015/09/30 |
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] |8011|0069 |2011/01/20 |2011/01/22 |
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] |8011|0BB8 |2018/10/25 |2019/01/05 |
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] -----------------------------------------
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] Prv.ID: 00 00 00 00
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] Prv.ID: 00 00 80 11
2018/11/23 19:04:41 3F355FA5 r (reader) HD03 [Nagra_Merlin] ready for requests
2018/11/23 19:04:41 00000000 s (main) init for all local cards done
2018/11/23 19:04:41 00000000 s (emmcache) loaded 0 emmcache records from /tmp/.oscam/oscam.emmcache in 0 ms
2018/11/23 19:04:41 00000000 s (emmcache) loaded 0 emmstat records from /tmp/.oscam/oscam.emmstat in 0 ms
2018/11/23 19:04:41 1CC19407 c (client) plain dvbapi-client granted (Doc24, au=on (2 reader))
2018/11/23 19:04:41 1CC19407 c (dvbapi) dvbapi channelcache loaded from /etc/tuxbox/config/oscam.ccache
2018/11/23 19:04:41 1CC19407 c (dvbapi) Detected /dev/dvb/adapter0/demux0 Api: 0, userconfig boxtype: 1 maximum amount of possible filters is 32 (oscam limit is 32)
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1830 ECM_PID: 1CCC PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 1843 ECM_PID: 19CC PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 1860 ECM_PID: 1ECC PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 098C ECM_PID: 1ACC PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 09C4 ECM_PID: 1BCC PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 5 CAID: 0500 ECM_PID: 0109 PROVID: 051900
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 6 CAID: 06CB ECM_PID: 0078 PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 7 CAID: 186A ECM_PID: 1FCC PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 8 CAID: 186D ECM_PID: 1FCD PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 ecmpid 9 CAID: 098D ECM_PID: 0108 PROVID: 000000
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 found 10 ECMpids and 7 STREAMpids in caPMT
2018/11/23 19:04:41 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:04:42 1CC19407 c (dvbapi) Demuxer 0 found 10 ECMpids and 7 STREAMpids in PMT
2018/11/23 19:04:47 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:86E60A3FE0D6720A86E692135BD805CE): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:04:48 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:9D3C7FAE138107125B9348CEADC37AE8): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:04:48 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:04:48 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:04:52 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:9D3C7FAE138107125B9348CEADC37AE8): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:04:54 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:9D3C7FAE138107125B9348CEADC37AE8): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:04:54 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 2 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:04:54 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:04:54 3F355FA5 r (reader) HD03 [Nagra_Merlin] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2018/11/23 19:04:57 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:9D3C7FAE138107125B9348CEADC37AE8): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:04:58 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:82D7F70EC9EEC6357F02FC1A88BF1363): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:04:58 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:04:58 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:00 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:82D7F70EC9EEC6357F02FC1A88BF1363): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:02 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:02 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:02 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:04 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:82D7F70EC9EEC6357F02FC1A88BF1363): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:05 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:82D7F70EC9EEC6357F02FC1A88BF1363): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:05 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:05 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:07 3F355FA5 r (reader) HD03 [Nagra_Merlin] T1 Resync command error, trying to reactivate!
2018/11/23 19:05:07 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75116
2018/11/23 19:05:08 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:82D7F70EC9EEC6357F02FC1A88BF1363): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:08 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:05E17DA418437D51911784C4B02750DC): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:08 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:08 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:09 3F355FA5 r (reader) HD03 [Nagra_Merlin] ATR: 3F FF 95 00 FF 91 81 71 FE 57 00 44 4E 41 53 50 34 31 30 20 52 65 76 51 32 35 17
2018/11/23 19:05:09 3F355FA5 r (reader) HD03 [Nagra_Merlin] HD-Plus 3A recognized
2018/11/23 19:05:09 3F355FA5 r (reader) HD03 [Nagra_Merlin] Bad Card -> quick restart
2018/11/23 19:05:09 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:05E17DA418437D51911784C4B02750DC): not found (895 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:09 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:05E17DA418437D51911784C4B02750DC): not found (4657 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:09 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:05E17DA418437D51911784C4B02750DC): not found (3153 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:09 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:09 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:14 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:14 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:05E17DA418437D51911784C4B02750DC): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:18 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:7C42A2A8A1B3B561543CC3B93B2B04E7): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:18 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 2 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:18 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:20 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:7C42A2A8A1B3B561543CC3B93B2B04E7): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:22 3F355FA5 r (reader) HD03 [Nagra_Merlin] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2018/11/23 19:05:24 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:7C42A2A8A1B3B561543CC3B93B2B04E7): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:24 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:24 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:25 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:7C42A2A8A1B3B561543CC3B93B2B04E7): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:28 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:74DD40FE235FB488C717C8217B5AE5B4): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:28 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:28 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:30 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:74DD40FE235FB488C717C8217B5AE5B4): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:30 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:30 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:30 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:34 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:74DD40FE235FB488C717C8217B5AE5B4): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:35 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:35 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:35 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:35 3F355FA5 r (reader) HD03 [Nagra_Merlin] T1 Resync command error, trying to reactivate!
2018/11/23 19:05:35 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75139
2018/11/23 19:05:36 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:74DD40FE235FB488C717C8217B5AE5B4): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:37 3F355FA5 r (reader) HD03 [Nagra_Merlin] ATR: 3F FF 95 00 FF 91 81 71 A0 47 00 44 4E 41 53 50 31 39 30 20 4D 65 72 51 32 35 4F
2018/11/23 19:05:37 3F355FA5 r (reader) HD03 [Nagra_Merlin] HD-Plus 03 recognized
2018/11/23 19:05:37 3F355FA5 r (reader) HD03 [Nagra_Merlin] Setting baudrate to 111600 bps
2018/11/23 19:05:37 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75129
2018/11/23 19:05:37 3F355FA5 r (reader) HD03 [Nagra_Merlin] try to init nagra layer
2018/11/23 19:05:37 3F355FA5 r (reader) HD03 [Nagra_Merlin] switch to nagra layer OK
2018/11/23 19:05:37 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75104
2018/11/23 19:05:38 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:2AEEDB15C9F0C6B97CE31A32835B5AFC): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:38 3F355FA5 r (reader) HD03 [Nagra_Merlin] Nagra layer ATR: 3F FF 95 00 FF 91 81 71 FE 57 00 44 4E 41 53 50 34 31 30 20 52 65 76 51 32 35 17
2018/11/23 19:05:38 3F355FA5 r (reader) HD03 [Nagra_Merlin] Setting baudrate to 111600 bps
2018/11/23 19:05:38 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:38 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:38 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75116
2018/11/23 19:05:38 3F355FA5 r (reader) HD03 [Nagra_Merlin] Doc24 emmtype=unique, len=139 (hex: 0x8B), cnt=1: written (29672 ms)
2018/11/23 19:05:39 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:2AEEDB15C9F0C6B97CE31A32835B5AFC): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:41 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:41 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:41 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:41 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:2AEEDB15C9F0C6B97CE31A32835B5AFC): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:44 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:2AEEDB15C9F0C6B97CE31A32835B5AFC): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:44 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:44 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:44 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:2AEEDB15C9F0C6B97CE31A32835B5AFC): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:47 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:47 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 2 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:47 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:48 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:C78B59405585CEBF753490A7F3724DA6): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:50 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:50 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:50 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:51 3F355FA5 r (reader) HD03 [Nagra_Merlin] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2018/11/23 19:05:52 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:C78B59405585CEBF753490A7F3724DA6): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:53 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:C78B59405585CEBF753490A7F3724DA6): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:54 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 2 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:54 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:55 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:C78B59405585CEBF753490A7F3724DA6): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:57 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:57 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 2 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:57 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:05:58 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:B1995752ED3A45AB3770DD75A9190028): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:05:59 1CC19407 c (-) -- Skipped 1 duplicated log lines --
2018/11/23 19:05:59 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:05:59 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:06:00 150DF9DE p (camd35) OScamServer is stopped - requested by server (invalid)
2018/11/23 19:06:00 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:B1995752ED3A45AB3770DD75A9190028): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:06:03 1CC19407 c (-) -- Skipped 2 duplicated log lines --
2018/11/23 19:06:03 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 1 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:06:03 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:06:04 150DF9DE p (camd35) OScamServer is stopped - requested by server (invalid)
2018/11/23 19:06:04 3F355FA5 r (reader) HD03 [Nagra_Merlin] T1 Resync command error, trying to reactivate!
2018/11/23 19:06:04 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75111
2018/11/23 19:06:05 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:B1995752ED3A45AB3770DD75A9190028): timeout (5001 ms) by HD03 (F/2/2/2)
2018/11/23 19:06:06 3F355FA5 r (reader) HD03 [Nagra_Merlin] ATR: 3F FF 95 00 FF 91 81 71 A0 47 00 44 4E 41 53 50 31 39 30 20 4D 65 72 51 32 35 4F
2018/11/23 19:06:06 3F355FA5 r (reader) HD03 [Nagra_Merlin] HD-Plus 03 recognized
2018/11/23 19:06:06 3F355FA5 r (reader) HD03 [Nagra_Merlin] Setting baudrate to 111600 bps
2018/11/23 19:06:06 1CC19407 c (ecm) Doc24 (1860@000000/0000/EF10/8F:B1995752ED3A45AB3770DD75A9190028): timeout (5000 ms) by HD03 (F/2/2/2)
2018/11/23 19:06:06 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75111
2018/11/23 19:06:06 3F355FA5 r (reader) HD03 [Nagra_Merlin] try to init nagra layer
2018/11/23 19:06:06 3F355FA5 r (reader) HD03 [Nagra_Merlin] switch to nagra layer OK
2018/11/23 19:06:06 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75112
2018/11/23 19:06:06 1CC19407 c (dvbapi) Demuxer 0 restarting decodingrequests after 2 ms with 1 enabled and 9 disabled ecmpids!
2018/11/23 19:06:06 1CC19407 c (dvbapi) Demuxer 0 trying to descramble PID 2 CAID 1860 PROVID 000000 ECMPID 1ECC ANY CHID PMTPID 0060 VPID 00FF
2018/11/23 19:06:07 3F355FA5 r (reader) HD03 [Nagra_Merlin] Nagra layer ATR: 3F FF 95 00 FF 91 81 71 FE 57 00 44 4E 41 53 50 34 31 30 20 52 65 76 51 32 35 17
2018/11/23 19:06:07 3F355FA5 r (reader) HD03 [Nagra_Merlin] Setting baudrate to 111600 bps
2018/11/23 19:06:07 3F355FA5 r (reader) HD03 [Nagra_Merlin] Buffers readed 0 bytes total time_us 75117