hallo,
ich habe eine komplett neue G02 karte von sky, natürlich noch nicht freigeschaltet. bekomme ich leider aber mit oscam in einem vu+ uno nicht hell. ich erhalte schlichwet keine emms. das freischaltsignal habe ich schön mehrfach über das webinterface verschicken lassen. ich erhalte im logfile aber nicht eine einzige meldung bzgl. emm. auch im webinterface stehen alle interessanten zahlen auf 0. habe euch unten mal meine aktuelle konfiguration sowie das dazugehörige logfile angehängt.
wäre nett, wenn sich mal das jemand ansehen könnte und mir weiterhelfen könnte ...
danke!
oscam.conf
oscam.server
oscam.user
oscam.log
ich habe eine komplett neue G02 karte von sky, natürlich noch nicht freigeschaltet. bekomme ich leider aber mit oscam in einem vu+ uno nicht hell. ich erhalte schlichwet keine emms. das freischaltsignal habe ich schön mehrfach über das webinterface verschicken lassen. ich erhalte im logfile aber nicht eine einzige meldung bzgl. emm. auch im webinterface stehen alle interessanten zahlen auf 0. habe euch unten mal meine aktuelle konfiguration sowie das dazugehörige logfile angehängt.
wäre nett, wenn sich mal das jemand ansehen könnte und mir weiterhelfen könnte ...
danke!
oscam.conf
[global]
usrfile = /var/log/oscamuser.log
usrfileflag = 1
logfile = /var/log/oscam.log
maxlogsize = 256
nice = -1
waitforcards = 1
[cccam]
port = 40000
[dvbapi]
enabled = 1
user = test
boxtype = dreambox
au = 1
reopenonzap = 1
[newcamd]
port = 34000@09C7:000000
key = 0102030405060708091011121314
[webif]
httpport = 8888
httpuser = http
httppwd = http
httpallowed = 127.0.0.1,10.21.11.0-10.21.11.255
usrfile = /var/log/oscamuser.log
usrfileflag = 1
logfile = /var/log/oscam.log
maxlogsize = 256
nice = -1
waitforcards = 1
[cccam]
port = 40000
[dvbapi]
enabled = 1
user = test
boxtype = dreambox
au = 1
reopenonzap = 1
[newcamd]
port = 34000@09C7:000000
key = 0102030405060708091011121314
[webif]
httpport = 8888
httpuser = http
httppwd = http
httpallowed = 127.0.0.1,10.21.11.0-10.21.11.255
oscam.server
[reader]
label = sky
protocol = internal
device = /dev/sci1
caid = 09C7
boxid = 12345678
detect = cd
mhz = 500
cardmhz = 357
group = 1
emmcache = 1,3,15
label = sky
protocol = internal
device = /dev/sci1
caid = 09C7
boxid = 12345678
detect = cd
mhz = 500
cardmhz = 357
group = 1
emmcache = 1,3,15
oscam.user
[account]
user = test
pwd = test
au = 1
group = 1
user = test
pwd = test
au = 1
group = 1
oscam.log
-------------------------------------------------------------------------------
>> OSCam << cardserver started at Wed Jul 31 13:12:31 2013
-------------------------------------------------------------------------------
2013/07/31 13:12:31 0 s starting thread log_list_thread
2013/07/31 13:12:31 0 s log_list_thread thread started
2013/07/31 13:12:31 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build #8118 (mipsel-oe-linux)
2013/07/31 13:12:31 0 s version=1.20-unstable_svn, build #8118, system=mipsel-oe-linux, nice=-1
2013/07/31 13:12:31 0 s client max. idle=120 sec, debug level=0, filter_sensitive=1
2013/07/31 13:12:31 0 s max. logsize=256 Kb, loghistorysize=4096 bytes
2013/07/31 13:12:31 0 s client timeout=5000 ms, fallback timeout=2500 ms, cache delay=0 ms
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.services" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2013/07/31 13:12:31 0 s signal handling initialized (type=sysv)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.srvid" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.tiers" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.provid" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.guess" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.ird" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.whitelist" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.cacheex" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s monitor: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s camd35: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s cs378x: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s newcamd: initialized (fd=6, port=34000, crypted)
2013/07/31 13:12:31 0 s -> CAID: 09C7 PROVID: 000000
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s cccam: initialized (fd=7, port=40000, crypted)
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s pandora: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s csp: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s radegast: disabled
2013/07/31 13:12:31 0 s starting thread http
2013/07/31 13:12:31 0 s http thread started
2013/07/31 13:12:31 0 s starting thread reader check
2013/07/31 13:12:31 0 s reader check thread started
2013/07/31 13:12:31 0 s starting thread check
2013/07/31 13:12:31 58BEC8 h HTTP Server listening on port 8888
2013/07/31 13:12:31 0 s check thread started
2013/07/31 13:12:31 0 s sky [internal] creating thread for device /dev/sci1
2013/07/31 13:12:31 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2013/07/31 13:12:31 0 s waiting for local card init
2013/07/31 13:12:31 58D730 r sky [internal] Reader initialized (device=/dev/sci1, detect=cd, mhz=500, cardmhz=357)
2013/07/31 13:12:32 58D730 r sky [internal] card detected
2013/07/31 13:12:33 58D730 r sky [internal] ATR: 3F FD 11 25 02 50 00 03 33 B0 15 69 FF 4A 50 F0 80 03 4B 4C 03
2013/07/31 13:12:33 58D730 r sky [internal] Init card protocol T0, FI=1, F=372, D=1, N=2
2013/07/31 13:12:33 58D730 r sky [internal] Setting baudrate to 9600 bps
2013/07/31 13:12:33 58D730 r sky [internal] Calculated work ETU is 74.40 us
2013/07/31 13:12:35 58D730 r sky [internal] ATR Fsmax is: 5 Mhz, clocking card to 5.00
2013/07/31 13:12:37 58D730 r sky [internal] Card type: KLTV
2013/07/31 13:12:37 58D730 r sky [internal] Rom version: 10LM
2013/07/31 13:12:37 58D730 r sky [internal] parental lock setting: FF FF FF FF
2013/07/31 13:12:37 58D730 r sky [internal] type: VideoGuard Kabel Deutschland G02/G09 (09C7), caid: 09C7
2013/07/31 13:12:37 58D730 r sky [internal] serial: ########, BoxID: ########, baseyear: 2004
2013/07/31 13:12:37 58D730 r sky [internal] ready for requests
2013/07/31 13:12:37 58D730 r sky [internal] found card system videoguard2
2013/07/31 13:12:37 58D730 r sky [videoguard2] card detected
2013/07/31 13:12:37 58D730 r sky [videoguard2] type: VideoGuard Kabel Deutschland G02/G09 (09C7)
2013/07/31 13:12:39 58BEC8 h all debug_level=1
2013/07/31 13:12:39 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed
2013/07/31 13:12:40 0 share-updater mode=initfast t=1s i=27
2013/07/31 13:12:41 58BEC8 h all debug_level=65
2013/07/31 13:12:41 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed
2013/07/31 13:12:41 0 --- Skipped 1 duplicated log lines ---
2013/07/31 13:12:41 0 share-updater mode=initfast t=1s i=26
2013/07/31 13:12:42 0 share-updater mode=initfast t=1s i=25
2013/07/31 13:12:42 58BEC8 h all debug_level=193
2013/07/31 13:12:43 0 share-updater mode=initfast t=1s i=24
2013/07/31 13:12:44 0 share-updater mode=initfast t=1s i=23
2013/07/31 13:12:45 0 share-updater mode=initfast t=1s i=22
2013/07/31 13:12:46 0 share-updater mode=initfast t=1s i=21
2013/07/31 13:12:47 0 share-updater mode=initfast t=1s i=20
2013/07/31 13:12:48 0 share-update [1] 1054509025 1170474242
2013/07/31 13:12:48 0 s-card added: id 0 remoteid 10000 caid 9C7 hop 0 reshare 10 originid 0 cardtype 5
2013/07/31 13:12:48 0 --- Skipped 1 duplicated log lines ---
2013/07/31 13:12:48 0 reported/updated +2/-0/dup 0 of 2 cards to sharelist
2013/07/31 13:12:48 0 share-updater mode=initfast t=1s i=30
2013/07/31 13:12:48 0 s init for all local cards done
2013/07/31 13:12:48 0 s anti cascading disabled
2013/07/31 13:12:48 77100638 c plain dvbapi-client granted (test, au=auto (2 reader))
2013/07/31 13:12:48 77100638 c dvbapi: ERROR: Can't open priority file /etc/tuxbox/config/oscam.dvbapi
2013/07/31 13:12:48 77100638 c dvbapi: Detected /dev/dvb/adapter0/demux0 Api: 0
2013/07/31 13:12:49 0 share-updater mode=initfast t=1s i=29
2013/07/31 13:12:50 0 share-updater mode=initfast t=1s i=28
2013/07/31 13:12:51 0 share-updater mode=initfast t=1s i=27
2013/07/31 13:12:52 0 share-updater mode=initfast t=1s i=26
2013/07/31 13:12:53 0 share-updater mode=initfast t=1s i=25
2013/07/31 13:12:53 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:12:53 77100638 c capmt:
2013/07/31 13:12:53 77100638 03 00 17 05 00 2B 01 81 08 FF FF 00 00 00 04 00
2013/07/31 13:12:53 77100638 85 82 02 01 00 84 02 00 63 09 04 18 34 FC 40 09
2013/07/31 13:12:53 77100638 04 09 C7 FD 40 09 04 18 61 F9 40 09 04 17 22 F6
2013/07/31 13:12:53 77100638 40 06 00 20 00 00 02 06 FF 00 00 03 07 00 00 00
2013/07/31 13:12:53 77100638 c dvbapi: id: 0 demux_index: 0 ca_mask: 01 program_info_length: 43 ca_pmt_list_management 03
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 81 length: 8
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 82 length: 2
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 84 length: 2
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 0] CAID: 1834 ECM_PID: 1C40 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 1] CAID: 09C7 ECM_PID: 1D40 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 2] CAID: 1861 ECM_PID: 1940 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 3] CAID: 1722 ECM_PID: 1640 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0020 length: 0
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] stream_type: 02 pid: 06ff length: 0
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] stream_type: 03 pid: 0700 length: 0
2013/07/31 13:12:53 77100638 c dvbapi: Found 4 ECMpids and 3 STREAMpids in PMT
2013/07/31 13:12:53 77100638 c dvbapi: New program number: 0017 (1834:0017 unknown) [pmt_list_management 3]
2013/07/31 13:12:53 77100638 c caid 1834 not found in caidlist reader sky
2013/07/31 13:12:53 77100638 c dvbapi: [IGNORE PID 0] 1834:000000:1C40 (no matching reader)
2013/07/31 13:12:53 77100638 c matching_reader became slot attribute of 0
2013/07/31 13:12:53 77100638 c dvbapi: [PRIORITIZE PID 1] 09C7:000000:1D40 (rdr: sky weight: 1)
2013/07/31 13:12:53 77100638 c caid 1861 not found in caidlist reader sky
2013/07/31 13:12:53 77100638 c dvbapi: [IGNORE PID 2] 1861:000000:1940 (no matching reader)
2013/07/31 13:12:53 77100638 c caid 1722 not found in caidlist reader sky
2013/07/31 13:12:53 77100638 c dvbapi: [IGNORE PID 3] 1722:000000:1640 (no matching reader)
2013/07/31 13:12:53 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:12:53 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:12:53 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:53 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:12:53 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:53 77100638 c request_cw stage=3 to reader sky ecm hash=902C0CCF71BC7C74B6918507E8141F4F
2013/07/31 13:12:53 77100638 c start reader thread action 5
2013/07/31 13:12:53 58D730 r data from add_job action=5 client r sky
2013/07/31 13:12:54 58D730 r sky [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2013/07/31 13:12:54 58D730 r start client thread action 24
2013/07/31 13:12:54 77100638 c data from add_job action=24 client c test
2013/07/31 13:12:54 77100638 c sky [videoguard2] TRACE: ecm answer for ecm hash 902C0CCF71BC7C74B6918507E8141F4F rc=4
2013/07/31 13:12:54 77100638 c dvbapi: try pids again #1
2013/07/31 13:12:54 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:12:54 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:12:54 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:54 77100638 c test (09C7&000000/0000/0017/A9:902C0CCF71BC7C74B6918507E8141F4F): not found (327 ms) by sky
2013/07/31 13:12:54 0 share-updater mode=initfast t=1s i=24
2013/07/31 13:12:54 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:12:54 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:54 77100638 c dvbapi: try pids again #2
2013/07/31 13:12:54 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:12:54 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:12:54 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:54 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:12:54 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:54 77100638 c dvbapi: try pids again #3
2013/07/31 13:12:54 77100638 c dvbapi: ERROR: Can't decode channel
2013/07/31 13:12:54 77100638 c dvbapi: event 17 on fd 15
2013/07/31 13:12:54 77100638 c dvbapi: stop descrambling (demux_id: 0)
2013/07/31 13:12:55 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:12:55 77100638 c capmt 3f:
2013/07/31 13:12:55 77100638 9F 80 3F 04 83 02 00 00
2013/07/31 13:12:55 0 share-updater mode=initfast t=1s i=23
2013/07/31 13:12:55 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:12:55 77100638 c capmt:
2013/07/31 13:12:55 77100638 03 00 6B 17 00 25 01 81 08 FF FF 00 00 00 0C 00
2013/07/31 13:12:55 77100638 85 82 02 01 00 84 02 00 62 09 04 18 34 FC 0B 09
2013/07/31 13:12:55 77100638 04 09 C7 FD 0B 09 04 18 61 F9 0B 06 00 20 00 00
2013/07/31 13:12:55 77100638 1B 02 FF 00 00 06 03 03 00 00 06 03 04 00 00
2013/07/31 13:12:55 77100638 c dvbapi: id: 0 demux_index: 0 ca_mask: 01 program_info_length: 37 ca_pmt_list_management 03
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 81 length: 8
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 82 length: 2
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 84 length: 2
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:55 77100638 c dvbapi: [ADD PID 0] CAID: 1834 ECM_PID: 1C0B PROVID: 000000
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:55 77100638 c dvbapi: [ADD PID 1] CAID: 09C7 ECM_PID: 1D0B PROVID: 000000
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:55 77100638 c dvbapi: [ADD PID 2] CAID: 1861 ECM_PID: 190B PROVID: 000000
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0020 length: 0
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 1b pid: 02ff length: 0
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0303 length: 0
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0304 length: 0
2013/07/31 13:12:55 77100638 c dvbapi: Found 3 ECMpids and 4 STREAMpids in PMT
2013/07/31 13:12:55 77100638 c dvbapi: New program number: 006B (1834:006B unknown) [pmt_list_management 3]
2013/07/31 13:12:55 77100638 c caid 1834 not found in caidlist reader sky
2013/07/31 13:12:55 77100638 c dvbapi: [IGNORE PID 0] 1834:000000:1C0B (no matching reader)
2013/07/31 13:12:55 77100638 c matching_reader became slot attribute of 0
2013/07/31 13:12:55 77100638 c dvbapi: [PRIORITIZE PID 1] 09C7:000000:1D0B (rdr: sky weight: 1)
2013/07/31 13:12:55 77100638 c caid 1861 not found in caidlist reader sky
2013/07/31 13:12:55 77100638 c dvbapi: [IGNORE PID 2] 1861:000000:190B (no matching reader)
2013/07/31 13:12:55 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D0B
2013/07/31 13:12:55 77100638 c dvbapi: set filter pid: 1d0b
2013/07/31 13:12:55 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:55 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/006B/A9:00000000000000000000000000000000
2013/07/31 13:12:55 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:55 77100638 c request_cw stage=3 to reader sky ecm hash=B8FF0E86818C9D490D69D28135F3F5A3
2013/07/31 13:12:55 77100638 c start reader thread action 5
2013/07/31 13:12:55 58D730 r data from add_job action=5 client r sky
2013/07/31 13:12:56 58D730 r sky [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2013/07/31 13:12:56 58D730 r start client thread action 24
2013/07/31 13:12:56 77100638 c data from add_job action=24 client c test
2013/07/31 13:12:56 77100638 c sky [videoguard2] TRACE: ecm answer for ecm hash B8FF0E86818C9D490D69D28135F3F5A3 rc=4
2013/07/31 13:12:56 77100638 c dvbapi: try pids again #1
2013/07/31 13:12:56 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D0B
2013/07/31 13:12:56 77100638 c dvbapi: set filter pid: 1d0b
2013/07/31 13:12:56 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:56 77100638 c test (09C7&000000/0000/006B/A9:B8FF0E86818C9D490D69D28135F3F5A3): not found (315 ms) by sky
2013/07/31 13:12:56 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/006B/A9:00000000000000000000000000000000
2013/07/31 13:12:56 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:56 77100638 c dvbapi: try pids again #2
2013/07/31 13:12:56 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D0B
2013/07/31 13:12:56 77100638 c dvbapi: set filter pid: 1d0b
2013/07/31 13:12:56 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:56 0 share-updater mode=initfast t=1s i=22
2013/07/31 13:12:56 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/006B/A9:00000000000000000000000000000000
2013/07/31 13:12:56 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:56 77100638 c dvbapi: try pids again #3
2013/07/31 13:12:56 77100638 c dvbapi: ERROR: Can't decode channel
2013/07/31 13:12:57 0 share-updater mode=initfast t=1s i=21
2013/07/31 13:12:58 0 share-updater mode=initfast t=1s i=20
2013/07/31 13:12:59 0 share-updater mode=initfast t=1s i=19
2013/07/31 13:13:00 0 share-updater mode=initfast t=1s i=18
2013/07/31 13:13:01 0 share-updater mode=initfast t=1s i=17
2013/07/31 13:13:02 0 share-updater mode=initfast t=1s i=16
2013/07/31 13:13:03 0 share-updater mode=initfast t=1s i=15
2013/07/31 13:13:03 77100638 c dvbapi: event 17 on fd 15
2013/07/31 13:13:03 77100638 c dvbapi: stop descrambling (demux_id: 0)
2013/07/31 13:13:04 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:13:04 77100638 c capmt 3f:
2013/07/31 13:13:04 77100638 9F 80 3F 04 83 02 00 00
2013/07/31 13:13:04 0 share-updater mode=initfast t=1s i=14
2013/07/31 13:13:05 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:13:05 77100638 c capmt:
2013/07/31 13:13:05 77100638 03 00 17 05 00 2B 01 81 08 FF FF 00 00 00 04 00
2013/07/31 13:13:05 77100638 85 82 02 01 00 84 02 00 63 09 04 18 34 FC 40 09
2013/07/31 13:13:05 77100638 04 09 C7 FD 40 09 04 18 61 F9 40 09 04 17 22 F6
2013/07/31 13:13:05 77100638 40 06 00 20 00 00 02 06 FF 00 00 03 07 00 00 00
2013/07/31 13:13:05 77100638 c dvbapi: id: 0 demux_index: 0 ca_mask: 01 program_info_length: 43 ca_pmt_list_management 03
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 81 length: 8
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 82 length: 2
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 84 length: 2
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 0] CAID: 1834 ECM_PID: 1C40 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 1] CAID: 09C7 ECM_PID: 1D40 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 2] CAID: 1861 ECM_PID: 1940 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 3] CAID: 1722 ECM_PID: 1640 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0020 length: 0
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] stream_type: 02 pid: 06ff length: 0
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] stream_type: 03 pid: 0700 length: 0
2013/07/31 13:13:05 77100638 c dvbapi: Found 4 ECMpids and 3 STREAMpids in PMT
2013/07/31 13:13:05 77100638 c dvbapi: New program number: 0017 (1834:0017 unknown) [pmt_list_management 3]
2013/07/31 13:13:05 77100638 c caid 1834 not found in caidlist reader sky
2013/07/31 13:13:05 77100638 c dvbapi: [IGNORE PID 0] 1834:000000:1C40 (no matching reader)
2013/07/31 13:13:05 77100638 c matching_reader became slot attribute of 0
2013/07/31 13:13:05 77100638 c dvbapi: [PRIORITIZE PID 1] 09C7:000000:1D40 (rdr: sky weight: 1)
2013/07/31 13:13:05 77100638 c caid 1861 not found in caidlist reader sky
2013/07/31 13:13:05 77100638 c dvbapi: [IGNORE PID 2] 1861:000000:1940 (no matching reader)
2013/07/31 13:13:05 77100638 c caid 1722 not found in caidlist reader sky
2013/07/31 13:13:05 77100638 c dvbapi: [IGNORE PID 3] 1722:000000:1640 (no matching reader)
2013/07/31 13:13:05 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:13:05 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:13:05 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:13:05 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:13:05 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:13:05 77100638 c request_cw stage=3 to reader sky ecm hash=3258503D90DDF63D547D3985013A5514
2013/07/31 13:13:05 77100638 c start reader thread action 5
2013/07/31 13:13:05 58D730 r data from add_job action=5 client r sky
2013/07/31 13:13:05 0 share-updater mode=initfast t=1s i=13
2013/07/31 13:13:05 58D730 r sky [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2013/07/31 13:13:05 58D730 r start client thread action 24
2013/07/31 13:13:05 77100638 c data from add_job action=24 client c test
2013/07/31 13:13:05 77100638 c sky [videoguard2] TRACE: ecm answer for ecm hash 3258503D90DDF63D547D3985013A5514 rc=4
2013/07/31 13:13:05 77100638 c dvbapi: try pids again #1
2013/07/31 13:13:05 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:13:05 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:13:05 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:13:05 77100638 c test (09C7&000000/0000/0017/A9:3258503D90DDF63D547D3985013A5514): not found (315 ms) by sky
2013/07/31 13:13:06 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:13:06 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:13:06 77100638 c dvbapi: try pids again #2
2013/07/31 13:13:06 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:13:06 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:13:06 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:13:06 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:13:06 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:13:06 77100638 c dvbapi: try pids again #3
2013/07/31 13:13:06 77100638 c dvbapi: ERROR: Can't decode channel
2013/07/31 13:13:06 77100638 c test (09C7&000000/0000/0017/A9:3258503D90DDF63D547D3985013A5514): not found (1 ms) by sky
2013/07/31 13:13:06 0 share-updater mode=initfast t=1s i=12
2013/07/31 13:13:07 0 share-updater mode=initfast t=1s i=11
2013/07/31 13:13:08 0 share-updater mode=initfast t=1s i=10
2013/07/31 13:13:09 0 share-updater mode=initfast t=1s i=9
2013/07/31 13:13:10 0 share-updater mode=initfast t=1s i=8
2013/07/31 13:13:11 0 share-updater mode=initfast t=1s i=7
2013/07/31 13:13:12 0 share-updater mode=initfast t=1s i=6
2013/07/31 13:13:13 0 share-updater mode=initfast t=1s i=5
2013/07/31 13:13:14 0 share-updater mode=initfast t=1s i=4
2013/07/31 13:13:15 0 share-updater mode=initfast t=1s i=3
2013/07/31 13:13:16 0 share-updater mode=initfast t=1s i=2
2013/07/31 13:13:17 0 share-updater mode=initfast t=1s i=1
2013/07/31 13:13:18 0 share-updater mode=interval t=240s
2013/07/31 13:13:28 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed
2013/07/31 13:13:28 58BEC8 h WebIf: Received stale header from 10.21.11.21 (nonce=ccafcde5df300d387b43818a1aaef47d, expectednonce=e55d172f13d671ced6e40ee3b3238da1, opaque=2908ced3f3c3d0eac78a21b653c0ae22).
2013/07/31 13:13:28 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed
>> OSCam << cardserver started at Wed Jul 31 13:12:31 2013
-------------------------------------------------------------------------------
2013/07/31 13:12:31 0 s starting thread log_list_thread
2013/07/31 13:12:31 0 s log_list_thread thread started
2013/07/31 13:12:31 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build #8118 (mipsel-oe-linux)
2013/07/31 13:12:31 0 s version=1.20-unstable_svn, build #8118, system=mipsel-oe-linux, nice=-1
2013/07/31 13:12:31 0 s client max. idle=120 sec, debug level=0, filter_sensitive=1
2013/07/31 13:12:31 0 s max. logsize=256 Kb, loghistorysize=4096 bytes
2013/07/31 13:12:31 0 s client timeout=5000 ms, fallback timeout=2500 ms, cache delay=0 ms
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.services" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2013/07/31 13:12:31 0 s signal handling initialized (type=sysv)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.srvid" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.tiers" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.provid" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.guess" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.ird" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.whitelist" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s ERROR: Cannot open file "/etc/tuxbox/config/oscam.cacheex" (errno=2 No such file or directory)
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s monitor: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s camd35: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s cs378x: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s newcamd: initialized (fd=6, port=34000, crypted)
2013/07/31 13:12:31 0 s -> CAID: 09C7 PROVID: 000000
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s cccam: initialized (fd=7, port=40000, crypted)
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s pandora: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s csp: disabled
2013/07/31 13:12:31 0 s Starting listener 0
2013/07/31 13:12:31 0 s radegast: disabled
2013/07/31 13:12:31 0 s starting thread http
2013/07/31 13:12:31 0 s http thread started
2013/07/31 13:12:31 0 s starting thread reader check
2013/07/31 13:12:31 0 s reader check thread started
2013/07/31 13:12:31 0 s starting thread check
2013/07/31 13:12:31 58BEC8 h HTTP Server listening on port 8888
2013/07/31 13:12:31 0 s check thread started
2013/07/31 13:12:31 0 s sky [internal] creating thread for device /dev/sci1
2013/07/31 13:12:31 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2013/07/31 13:12:31 0 s waiting for local card init
2013/07/31 13:12:31 58D730 r sky [internal] Reader initialized (device=/dev/sci1, detect=cd, mhz=500, cardmhz=357)
2013/07/31 13:12:32 58D730 r sky [internal] card detected
2013/07/31 13:12:33 58D730 r sky [internal] ATR: 3F FD 11 25 02 50 00 03 33 B0 15 69 FF 4A 50 F0 80 03 4B 4C 03
2013/07/31 13:12:33 58D730 r sky [internal] Init card protocol T0, FI=1, F=372, D=1, N=2
2013/07/31 13:12:33 58D730 r sky [internal] Setting baudrate to 9600 bps
2013/07/31 13:12:33 58D730 r sky [internal] Calculated work ETU is 74.40 us
2013/07/31 13:12:35 58D730 r sky [internal] ATR Fsmax is: 5 Mhz, clocking card to 5.00
2013/07/31 13:12:37 58D730 r sky [internal] Card type: KLTV
2013/07/31 13:12:37 58D730 r sky [internal] Rom version: 10LM
2013/07/31 13:12:37 58D730 r sky [internal] parental lock setting: FF FF FF FF
2013/07/31 13:12:37 58D730 r sky [internal] type: VideoGuard Kabel Deutschland G02/G09 (09C7), caid: 09C7
2013/07/31 13:12:37 58D730 r sky [internal] serial: ########, BoxID: ########, baseyear: 2004
2013/07/31 13:12:37 58D730 r sky [internal] ready for requests
2013/07/31 13:12:37 58D730 r sky [internal] found card system videoguard2
2013/07/31 13:12:37 58D730 r sky [videoguard2] card detected
2013/07/31 13:12:37 58D730 r sky [videoguard2] type: VideoGuard Kabel Deutschland G02/G09 (09C7)
2013/07/31 13:12:39 58BEC8 h all debug_level=1
2013/07/31 13:12:39 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed
2013/07/31 13:12:40 0 share-updater mode=initfast t=1s i=27
2013/07/31 13:12:41 58BEC8 h all debug_level=65
2013/07/31 13:12:41 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed
2013/07/31 13:12:41 0 --- Skipped 1 duplicated log lines ---
2013/07/31 13:12:41 0 share-updater mode=initfast t=1s i=26
2013/07/31 13:12:42 0 share-updater mode=initfast t=1s i=25
2013/07/31 13:12:42 58BEC8 h all debug_level=193
2013/07/31 13:12:43 0 share-updater mode=initfast t=1s i=24
2013/07/31 13:12:44 0 share-updater mode=initfast t=1s i=23
2013/07/31 13:12:45 0 share-updater mode=initfast t=1s i=22
2013/07/31 13:12:46 0 share-updater mode=initfast t=1s i=21
2013/07/31 13:12:47 0 share-updater mode=initfast t=1s i=20
2013/07/31 13:12:48 0 share-update [1] 1054509025 1170474242
2013/07/31 13:12:48 0 s-card added: id 0 remoteid 10000 caid 9C7 hop 0 reshare 10 originid 0 cardtype 5
2013/07/31 13:12:48 0 --- Skipped 1 duplicated log lines ---
2013/07/31 13:12:48 0 reported/updated +2/-0/dup 0 of 2 cards to sharelist
2013/07/31 13:12:48 0 share-updater mode=initfast t=1s i=30
2013/07/31 13:12:48 0 s init for all local cards done
2013/07/31 13:12:48 0 s anti cascading disabled
2013/07/31 13:12:48 77100638 c plain dvbapi-client granted (test, au=auto (2 reader))
2013/07/31 13:12:48 77100638 c dvbapi: ERROR: Can't open priority file /etc/tuxbox/config/oscam.dvbapi
2013/07/31 13:12:48 77100638 c dvbapi: Detected /dev/dvb/adapter0/demux0 Api: 0
2013/07/31 13:12:49 0 share-updater mode=initfast t=1s i=29
2013/07/31 13:12:50 0 share-updater mode=initfast t=1s i=28
2013/07/31 13:12:51 0 share-updater mode=initfast t=1s i=27
2013/07/31 13:12:52 0 share-updater mode=initfast t=1s i=26
2013/07/31 13:12:53 0 share-updater mode=initfast t=1s i=25
2013/07/31 13:12:53 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:12:53 77100638 c capmt:
2013/07/31 13:12:53 77100638 03 00 17 05 00 2B 01 81 08 FF FF 00 00 00 04 00
2013/07/31 13:12:53 77100638 85 82 02 01 00 84 02 00 63 09 04 18 34 FC 40 09
2013/07/31 13:12:53 77100638 04 09 C7 FD 40 09 04 18 61 F9 40 09 04 17 22 F6
2013/07/31 13:12:53 77100638 40 06 00 20 00 00 02 06 FF 00 00 03 07 00 00 00
2013/07/31 13:12:53 77100638 c dvbapi: id: 0 demux_index: 0 ca_mask: 01 program_info_length: 43 ca_pmt_list_management 03
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 81 length: 8
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 82 length: 2
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 84 length: 2
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 0] CAID: 1834 ECM_PID: 1C40 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 1] CAID: 09C7 ECM_PID: 1D40 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 2] CAID: 1861 ECM_PID: 1940 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:53 77100638 c dvbapi: [ADD PID 3] CAID: 1722 ECM_PID: 1640 PROVID: 000000
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0020 length: 0
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] stream_type: 02 pid: 06ff length: 0
2013/07/31 13:12:53 77100638 c dvbapi: [pmt] stream_type: 03 pid: 0700 length: 0
2013/07/31 13:12:53 77100638 c dvbapi: Found 4 ECMpids and 3 STREAMpids in PMT
2013/07/31 13:12:53 77100638 c dvbapi: New program number: 0017 (1834:0017 unknown) [pmt_list_management 3]
2013/07/31 13:12:53 77100638 c caid 1834 not found in caidlist reader sky
2013/07/31 13:12:53 77100638 c dvbapi: [IGNORE PID 0] 1834:000000:1C40 (no matching reader)
2013/07/31 13:12:53 77100638 c matching_reader became slot attribute of 0
2013/07/31 13:12:53 77100638 c dvbapi: [PRIORITIZE PID 1] 09C7:000000:1D40 (rdr: sky weight: 1)
2013/07/31 13:12:53 77100638 c caid 1861 not found in caidlist reader sky
2013/07/31 13:12:53 77100638 c dvbapi: [IGNORE PID 2] 1861:000000:1940 (no matching reader)
2013/07/31 13:12:53 77100638 c caid 1722 not found in caidlist reader sky
2013/07/31 13:12:53 77100638 c dvbapi: [IGNORE PID 3] 1722:000000:1640 (no matching reader)
2013/07/31 13:12:53 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:12:53 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:12:53 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:53 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:12:53 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:53 77100638 c request_cw stage=3 to reader sky ecm hash=902C0CCF71BC7C74B6918507E8141F4F
2013/07/31 13:12:53 77100638 c start reader thread action 5
2013/07/31 13:12:53 58D730 r data from add_job action=5 client r sky
2013/07/31 13:12:54 58D730 r sky [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2013/07/31 13:12:54 58D730 r start client thread action 24
2013/07/31 13:12:54 77100638 c data from add_job action=24 client c test
2013/07/31 13:12:54 77100638 c sky [videoguard2] TRACE: ecm answer for ecm hash 902C0CCF71BC7C74B6918507E8141F4F rc=4
2013/07/31 13:12:54 77100638 c dvbapi: try pids again #1
2013/07/31 13:12:54 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:12:54 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:12:54 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:54 77100638 c test (09C7&000000/0000/0017/A9:902C0CCF71BC7C74B6918507E8141F4F): not found (327 ms) by sky
2013/07/31 13:12:54 0 share-updater mode=initfast t=1s i=24
2013/07/31 13:12:54 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:12:54 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:54 77100638 c dvbapi: try pids again #2
2013/07/31 13:12:54 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:12:54 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:12:54 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:54 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:12:54 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:54 77100638 c dvbapi: try pids again #3
2013/07/31 13:12:54 77100638 c dvbapi: ERROR: Can't decode channel
2013/07/31 13:12:54 77100638 c dvbapi: event 17 on fd 15
2013/07/31 13:12:54 77100638 c dvbapi: stop descrambling (demux_id: 0)
2013/07/31 13:12:55 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:12:55 77100638 c capmt 3f:
2013/07/31 13:12:55 77100638 9F 80 3F 04 83 02 00 00
2013/07/31 13:12:55 0 share-updater mode=initfast t=1s i=23
2013/07/31 13:12:55 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:12:55 77100638 c capmt:
2013/07/31 13:12:55 77100638 03 00 6B 17 00 25 01 81 08 FF FF 00 00 00 0C 00
2013/07/31 13:12:55 77100638 85 82 02 01 00 84 02 00 62 09 04 18 34 FC 0B 09
2013/07/31 13:12:55 77100638 04 09 C7 FD 0B 09 04 18 61 F9 0B 06 00 20 00 00
2013/07/31 13:12:55 77100638 1B 02 FF 00 00 06 03 03 00 00 06 03 04 00 00
2013/07/31 13:12:55 77100638 c dvbapi: id: 0 demux_index: 0 ca_mask: 01 program_info_length: 37 ca_pmt_list_management 03
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 81 length: 8
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 82 length: 2
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 84 length: 2
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:55 77100638 c dvbapi: [ADD PID 0] CAID: 1834 ECM_PID: 1C0B PROVID: 000000
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:55 77100638 c dvbapi: [ADD PID 1] CAID: 09C7 ECM_PID: 1D0B PROVID: 000000
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:12:55 77100638 c dvbapi: [ADD PID 2] CAID: 1861 ECM_PID: 190B PROVID: 000000
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0020 length: 0
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 1b pid: 02ff length: 0
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0303 length: 0
2013/07/31 13:12:55 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0304 length: 0
2013/07/31 13:12:55 77100638 c dvbapi: Found 3 ECMpids and 4 STREAMpids in PMT
2013/07/31 13:12:55 77100638 c dvbapi: New program number: 006B (1834:006B unknown) [pmt_list_management 3]
2013/07/31 13:12:55 77100638 c caid 1834 not found in caidlist reader sky
2013/07/31 13:12:55 77100638 c dvbapi: [IGNORE PID 0] 1834:000000:1C0B (no matching reader)
2013/07/31 13:12:55 77100638 c matching_reader became slot attribute of 0
2013/07/31 13:12:55 77100638 c dvbapi: [PRIORITIZE PID 1] 09C7:000000:1D0B (rdr: sky weight: 1)
2013/07/31 13:12:55 77100638 c caid 1861 not found in caidlist reader sky
2013/07/31 13:12:55 77100638 c dvbapi: [IGNORE PID 2] 1861:000000:190B (no matching reader)
2013/07/31 13:12:55 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D0B
2013/07/31 13:12:55 77100638 c dvbapi: set filter pid: 1d0b
2013/07/31 13:12:55 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:55 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/006B/A9:00000000000000000000000000000000
2013/07/31 13:12:55 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:55 77100638 c request_cw stage=3 to reader sky ecm hash=B8FF0E86818C9D490D69D28135F3F5A3
2013/07/31 13:12:55 77100638 c start reader thread action 5
2013/07/31 13:12:55 58D730 r data from add_job action=5 client r sky
2013/07/31 13:12:56 58D730 r sky [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2013/07/31 13:12:56 58D730 r start client thread action 24
2013/07/31 13:12:56 77100638 c data from add_job action=24 client c test
2013/07/31 13:12:56 77100638 c sky [videoguard2] TRACE: ecm answer for ecm hash B8FF0E86818C9D490D69D28135F3F5A3 rc=4
2013/07/31 13:12:56 77100638 c dvbapi: try pids again #1
2013/07/31 13:12:56 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D0B
2013/07/31 13:12:56 77100638 c dvbapi: set filter pid: 1d0b
2013/07/31 13:12:56 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:56 77100638 c test (09C7&000000/0000/006B/A9:B8FF0E86818C9D490D69D28135F3F5A3): not found (315 ms) by sky
2013/07/31 13:12:56 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/006B/A9:00000000000000000000000000000000
2013/07/31 13:12:56 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:56 77100638 c dvbapi: try pids again #2
2013/07/31 13:12:56 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D0B
2013/07/31 13:12:56 77100638 c dvbapi: set filter pid: 1d0b
2013/07/31 13:12:56 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:12:56 0 share-updater mode=initfast t=1s i=22
2013/07/31 13:12:56 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/006B/A9:00000000000000000000000000000000
2013/07/31 13:12:56 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:12:56 77100638 c dvbapi: try pids again #3
2013/07/31 13:12:56 77100638 c dvbapi: ERROR: Can't decode channel
2013/07/31 13:12:57 0 share-updater mode=initfast t=1s i=21
2013/07/31 13:12:58 0 share-updater mode=initfast t=1s i=20
2013/07/31 13:12:59 0 share-updater mode=initfast t=1s i=19
2013/07/31 13:13:00 0 share-updater mode=initfast t=1s i=18
2013/07/31 13:13:01 0 share-updater mode=initfast t=1s i=17
2013/07/31 13:13:02 0 share-updater mode=initfast t=1s i=16
2013/07/31 13:13:03 0 share-updater mode=initfast t=1s i=15
2013/07/31 13:13:03 77100638 c dvbapi: event 17 on fd 15
2013/07/31 13:13:03 77100638 c dvbapi: stop descrambling (demux_id: 0)
2013/07/31 13:13:04 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:13:04 77100638 c capmt 3f:
2013/07/31 13:13:04 77100638 9F 80 3F 04 83 02 00 00
2013/07/31 13:13:04 0 share-updater mode=initfast t=1s i=14
2013/07/31 13:13:05 77100638 c dvbapi: new socket connection fd: 15
2013/07/31 13:13:05 77100638 c capmt:
2013/07/31 13:13:05 77100638 03 00 17 05 00 2B 01 81 08 FF FF 00 00 00 04 00
2013/07/31 13:13:05 77100638 85 82 02 01 00 84 02 00 63 09 04 18 34 FC 40 09
2013/07/31 13:13:05 77100638 04 09 C7 FD 40 09 04 18 61 F9 40 09 04 17 22 F6
2013/07/31 13:13:05 77100638 40 06 00 20 00 00 02 06 FF 00 00 03 07 00 00 00
2013/07/31 13:13:05 77100638 c dvbapi: id: 0 demux_index: 0 ca_mask: 01 program_info_length: 43 ca_pmt_list_management 03
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 81 length: 8
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 82 length: 2
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 84 length: 2
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 0] CAID: 1834 ECM_PID: 1C40 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 1] CAID: 09C7 ECM_PID: 1D40 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 2] CAID: 1861 ECM_PID: 1940 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] type: 09 length: 4
2013/07/31 13:13:05 77100638 c dvbapi: [ADD PID 3] CAID: 1722 ECM_PID: 1640 PROVID: 000000
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] stream_type: 06 pid: 0020 length: 0
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] stream_type: 02 pid: 06ff length: 0
2013/07/31 13:13:05 77100638 c dvbapi: [pmt] stream_type: 03 pid: 0700 length: 0
2013/07/31 13:13:05 77100638 c dvbapi: Found 4 ECMpids and 3 STREAMpids in PMT
2013/07/31 13:13:05 77100638 c dvbapi: New program number: 0017 (1834:0017 unknown) [pmt_list_management 3]
2013/07/31 13:13:05 77100638 c caid 1834 not found in caidlist reader sky
2013/07/31 13:13:05 77100638 c dvbapi: [IGNORE PID 0] 1834:000000:1C40 (no matching reader)
2013/07/31 13:13:05 77100638 c matching_reader became slot attribute of 0
2013/07/31 13:13:05 77100638 c dvbapi: [PRIORITIZE PID 1] 09C7:000000:1D40 (rdr: sky weight: 1)
2013/07/31 13:13:05 77100638 c caid 1861 not found in caidlist reader sky
2013/07/31 13:13:05 77100638 c dvbapi: [IGNORE PID 2] 1861:000000:1940 (no matching reader)
2013/07/31 13:13:05 77100638 c caid 1722 not found in caidlist reader sky
2013/07/31 13:13:05 77100638 c dvbapi: [IGNORE PID 3] 1722:000000:1640 (no matching reader)
2013/07/31 13:13:05 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:13:05 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:13:05 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:13:05 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:13:05 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:13:05 77100638 c request_cw stage=3 to reader sky ecm hash=3258503D90DDF63D547D3985013A5514
2013/07/31 13:13:05 77100638 c start reader thread action 5
2013/07/31 13:13:05 58D730 r data from add_job action=5 client r sky
2013/07/31 13:13:05 0 share-updater mode=initfast t=1s i=13
2013/07/31 13:13:05 58D730 r sky [videoguard2] classD3 ins54: status 90 00 = ok but cw=00 -> channel not subscribed
2013/07/31 13:13:05 58D730 r start client thread action 24
2013/07/31 13:13:05 77100638 c data from add_job action=24 client c test
2013/07/31 13:13:05 77100638 c sky [videoguard2] TRACE: ecm answer for ecm hash 3258503D90DDF63D547D3985013A5514 rc=4
2013/07/31 13:13:05 77100638 c dvbapi: try pids again #1
2013/07/31 13:13:05 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:13:05 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:13:05 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:13:05 77100638 c test (09C7&000000/0000/0017/A9:3258503D90DDF63D547D3985013A5514): not found (315 ms) by sky
2013/07/31 13:13:06 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:13:06 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:13:06 77100638 c dvbapi: try pids again #2
2013/07/31 13:13:06 77100638 c dvbapi: [TRY PID 1] CAID: 09C7 PROVID: 000000 CA_PID: 1D40
2013/07/31 13:13:06 77100638 c dvbapi: set filter pid: 1d40
2013/07/31 13:13:06 77100638 c dvbapi: DEVICE open (/dev/dvb/adapter0/demux0) fd 16
2013/07/31 13:13:06 77100638 c dvbapi: dvbapi request cw for 09C7&000000/0000/0017/A9:00000000000000000000000000000000
2013/07/31 13:13:06 77100638 c matching_reader became slot attribute of 1
2013/07/31 13:13:06 77100638 c dvbapi: try pids again #3
2013/07/31 13:13:06 77100638 c dvbapi: ERROR: Can't decode channel
2013/07/31 13:13:06 77100638 c test (09C7&000000/0000/0017/A9:3258503D90DDF63D547D3985013A5514): not found (1 ms) by sky
2013/07/31 13:13:06 0 share-updater mode=initfast t=1s i=12
2013/07/31 13:13:07 0 share-updater mode=initfast t=1s i=11
2013/07/31 13:13:08 0 share-updater mode=initfast t=1s i=10
2013/07/31 13:13:09 0 share-updater mode=initfast t=1s i=9
2013/07/31 13:13:10 0 share-updater mode=initfast t=1s i=8
2013/07/31 13:13:11 0 share-updater mode=initfast t=1s i=7
2013/07/31 13:13:12 0 share-updater mode=initfast t=1s i=6
2013/07/31 13:13:13 0 share-updater mode=initfast t=1s i=5
2013/07/31 13:13:14 0 share-updater mode=initfast t=1s i=4
2013/07/31 13:13:15 0 share-updater mode=initfast t=1s i=3
2013/07/31 13:13:16 0 share-updater mode=initfast t=1s i=2
2013/07/31 13:13:17 0 share-updater mode=initfast t=1s i=1
2013/07/31 13:13:18 0 share-updater mode=interval t=240s
2013/07/31 13:13:28 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed
2013/07/31 13:13:28 58BEC8 h WebIf: Received stale header from 10.21.11.21 (nonce=ccafcde5df300d387b43818a1aaef47d, expectednonce=e55d172f13d671ced6e40ee3b3238da1, opaque=2908ced3f3c3d0eac78a21b653c0ae22).
2013/07/31 13:13:28 58BEC8 h WebIf: Origin checked. Result: access from 10.21.11.21 => allowed