2025/10/26 11:55:18 00000000 s (main) System name = Linux
2025/10/26 11:55:18 00000000 s (main) Host name = satellite
2025/10/26 11:55:18 00000000 s (main) Release = 6.12.47+rpt-rpi-v8
2025/10/26 11:55:18 00000000 s (main) Version = #1 SMP PREEMPT Debian 1:6.12.47-1+rpt1~bookworm (2025-09-16)
2025/10/26 11:55:18 00000000 s (main) Machine = aarch64
2025/10/26 11:55:18 00000000 s (main) creating pidfile /var/run/oscam.pid with pid 74179
2025/10/26 11:55:18 00000000 s (config) services reloaded: 0 services freed, 1 services loaded, rejected 0
2025/10/26 11:55:18 00000000 s (config) userdb reloaded: 3 accounts loaded, 0 expired, 1 disabled
2025/10/26 11:55:18 00000000 s (main) signal handling initialized
2025/10/26 11:55:18 00000000 s (config) 10 provid's loaded
2025/10/26 11:55:18 00000000 s (config) 31 service-id's loaded in 0 ms
2025/10/26 11:55:18 00000000 s (net) cccam: initialized (fd=4, port=13000, ip=10.0.0.21)
2025/10/26 11:55:18 00000000 s (reader) Serial-ORF [mouse] creating thread for device /dev/ttyUSB0
2025/10/26 11:55:18 00000000 s (main) waiting for local card init
2025/10/26 11:55:18 6833D73B h (webif) webif: decompressed 178754 bytes back into 482176 bytes
2025/10/26 11:55:18 6833D73B h (webif) HTTP Server running. ip=0.0.0.0 port=8889
2025/10/26 11:55:18 321FCFDA r (reader) Serial-ORF [mouse] Reader initialized (device=/dev/ttyUSB0, detect=cd, mhz=357, cardmhz=357)
2025/10/26 11:55:19 321FCFDA r (reader) Serial-ORF [mouse] card detected
2025/10/26 11:55:20 321FCFDA r (reader) Serial-ORF [mouse] ATR: 3B 9F 11 40 60 49 52 44 45 54 4F 20 41 43 53 20 56 37 2E 30
2025/10/26 11:55:20 321FCFDA r (reader) Serial-ORF [mouse] Setting baudrate to 9600 bps
2025/10/26 11:55:22 321FCFDA r (reader) Serial-ORF [mouse] Hist. Bytes: IRDETO ACS V7.0
2025/10/26 11:55:22 321FCFDA r (reader) Serial-ORF [mouse] detect irdeto card
2025/10/26 11:55:22 321FCFDA r (reader) Serial-ORF [mouse] Irdeto SC 70 version 2 revision 0, patch level 0
2025/10/26 11:55:22 321FCFDA r (reader) Serial-ORF [mouse] caid: 0650, acs: 0.06, country code: AUT
2025/10/26 11:55:22 321FCFDA r (reader) Serial-ORF [mouse] providers: 4, ascii serial: ##########, hex serial: ######, hex base: ##
2025/10/26 11:55:23 321FCFDA r (reader) Serial-ORF [mouse] active providers: 4 #############################
2025/10/26 11:55:23 321FCFDA r (reader) Serial-ORF [mouse] found card system irdeto
2025/10/26 11:55:23 321FCFDA r (reader) Serial-ORF [irdeto] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max allotted of 1
2025/10/26 11:55:23 321FCFDA r (reader) Serial-ORF [irdeto] entitlements for provider: 1, id: ######
2025/10/26 11:55:23 321FCFDA r (reader) Serial-ORF [irdeto] chid: 000D, date: 2025/06/22 - 2025/07/22
2025/10/26 11:55:23 321FCFDA r (reader) Serial-ORF [irdeto] entitlements for provider: 2, id: ######
2025/10/26 11:55:23 321FCFDA r (reader) Serial-ORF [irdeto] chid: 001D, date: 2025/06/22 - 2025/07/22
2025/10/26 11:55:24 321FCFDA r (reader) Serial-ORF [irdeto] entitlements for provider: 4, id: ######
2025/10/26 11:55:24 321FCFDA r (reader) Serial-ORF [irdeto] chid: 0031, date: 2025/06/22 - 2025/07/22
2025/10/26 11:55:24 321FCFDA r (reader) Serial-ORF [irdeto] ready for requests
2025/10/26 11:55:24 00000000 s (main) init for all local cards done
2025/10/26 11:55:24 00000000 s (emmcache) loaded 0 emmcache records from /usr/local/etc/oscam.emmcache in 0 ms
2025/10/26 11:55:24 00000000 s (emmcache) loaded 0 emmstat records from /usr/local/etc/oscam.emmstat in 0 ms
2025/10/26 11:55:24 471628BE c (client) plain dvbapi-client granted (dvbapi, au=auto (4 reader))
2025/10/26 11:55:24 471628BE c (dvbapi) dvbapi channelcache loaded from /usr/local/etc/oscam.ccache
2025/10/26 11:55:24 471628BE c (dvbapi) Using TCP listen socket, API forced to DVBAPIv3 (0), userconfig boxtype: 11
2025/10/26 11:55:25 471628BE c (dvbapi) Client connected: 'dvbapiNET v1.0 (#20985898)' (protocol version = 2)
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 0 CAID: 0648 ECM_PID: 0079 PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0650 ECM_PID: 007B PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 2 CAID: 0D95 ECM_PID: 010F PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 3 CAID: 0D98 ECM_PID: 0111 PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 4 CAID: 06E2 ECM_PID: 0141 PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 5 CAID: 0500 ECM_PID: 01CE PROVID: 051900
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 6 CAID: 098D ECM_PID: 01D7 PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 7 CAID: 09C4 ECM_PID: 01E1 PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 ecmpid 8 CAID: 098C ECM_PID: 01EB PROVID: 000000
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 found 9 ECM pids and 6 STREAM pids in CA PMT
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:54 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:2E12971FA05ECBFC7432CB1C09C672F4): not found (167 ms) by Serial-ORF - ORF2W HD (Serial-ORF Time Sync Global EMM needed)
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:54 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:54 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:2E12971FA05ECBFC7432CB1C09C672F4): not found (1 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:55:55 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:55 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:55 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:2E12971FA05ECBFC7432CB1C09C672F4): not found (1 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:55:55 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:55 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:56 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:2E12971FA05ECBFC7432CB1C09C672F4): not found (168 ms) by Serial-ORF - ORF2W HD (Serial-ORF Time Sync Global EMM needed)
2025/10/26 11:55:56 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:56 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:56 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:2E12971FA05ECBFC7432CB1C09C672F4): not found (0 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:55:57 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:57 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:57 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (155 ms) by Serial-ORF - ORF2W HD (Serial-ORF Time Sync Global EMM needed)
2025/10/26 11:55:57 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:57 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:58 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (0 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:55:58 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:58 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:58 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (0 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:55:59 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:59 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:55:59 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (169 ms) by Serial-ORF - ORF2W HD (Serial-ORF Time Sync Global EMM needed)
2025/10/26 11:55:59 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:55:59 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:56:00 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (0 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:56:00 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:56:00 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:56:00 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (1 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:56:01 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:56:01 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:56:01 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (155 ms) by Serial-ORF - ORF2W HD (Serial-ORF Time Sync Global EMM needed)
2025/10/26 11:56:01 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:56:01 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:56:01 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (0 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:56:02 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:56:02 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:56:02 6833D73B h (webif) all debug_level=64
2025/10/26 11:56:02 321FCFDA r (reader) Serial-ORF [irdeto] EMM: response 01 01 00 12 3F (OK)
2025/10/26 11:56:02 321FCFDA r (reader) Serial-ORF [irdeto] dvbapi emmtype=global, len=36 (hex: 0x24), cnt=0: written (119 ms)
2025/10/26 11:56:02 321FCFDA r (reader) Serial-ORF [irdeto] EMM: response 01 01 35 00 3F (ERROR)
2025/10/26 11:56:02 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (161 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:56:02 471628BE c (emm) emm:
2025/10/26 11:56:02 471628BE c (emm) 82 40 25 B0 00 00 00 20 DA 55 57 28 2C 9B D4 EE
2025/10/26 11:56:02 471628BE c (emm) B3 03 43 91 34 D5 75 C9 A4 77 40 28 1F C7 F1 98
2025/10/26 11:56:02 471628BE c (emm) F0 BA 91 37 9A E1 BC 15
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader 0650 match since emmpid has no provid -> SEND!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: Entered irdeto_get_emm_type ep->emm[3]=b0
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: GLOBAL base = 16
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: emmtype global. Reader serial ################.
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: emm UA/SA: ################.
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: emm is being sent to reader
2025/10/26 11:56:02 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:56:02 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:56:02 321FCFDA r (reader) Serial-ORF [irdeto] EMM: response 01 01 00 00 3F (OK)
2025/10/26 11:56:02 321FCFDA r (reader) Serial-ORF [irdeto] dvbapi emmtype=global, len=37 (hex: 0x25), cnt=0: written (118 ms)
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0648 -> SKIP!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader 0650 match since emmpid has no provid -> SEND!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 06E2 -> SKIP!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0D95 -> SKIP!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0D98 -> SKIP!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 098C -> SKIP!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 09C4 -> SKIP!
2025/10/26 11:56:02 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0500 -> SKIP!
2025/10/26 11:56:02 471628BE c (-) -- Skipped 3 duplicated log lines --
2025/10/26 11:56:02 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (0 ms) by Serial-ORF - ORF2W HD
2025/10/26 11:56:03 471628BE c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 8 disabled ecmpids!
2025/10/26 11:56:03 471628BE c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0650 PROVID 000000 ECMPID 007B ANY CHID PMTPID 006C VPID 0B68
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0648 -> SKIP!
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader 0650 match since emmpid has no provid -> SEND!
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 06E2 -> SKIP!
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0D95 -> SKIP!
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0D98 -> SKIP!
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 098C -> SKIP!
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 09C4 -> SKIP!
2025/10/26 11:56:03 471628BE c (reader) Serial-ORF [irdeto] EMM: reader_caid 0650 != emmpid caid 0500 -> SKIP!
2025/10/26 11:56:03 471628BE c (-) -- Skipped 3 duplicated log lines --
2025/10/26 11:56:03 471628BE c (ecm) dvbapi (0650@000000/0000/1330/4E:F3118EE7524654195646071C64525C47): not found (196 ms) by Serial-ORF - ORF2W HD (Serial-ORF reader_chk_cmd [11] 93 00)
2025/10/26 11:56:03 47