3:22:07.2299 [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
13:22:07.2299 [eDVBPESReader] Created. Opening demux
13:22:07.2300 [eDVBTeletextParser] created teletext subtitle PES reader!
13:22:07.2300 [eDVBPESReader] Created. Opening demux
13:22:07.2301 [eDVBTeletextParser] starting PES reader on pid=ffffffff
13:22:07.2466 [eDVBCAService] new service 1:0:19:8C:4:85:C00000:0:0:0:, serviceId 10
13:22:07.2467 [eDVBCAService] add demux 0 to slot 0 service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:07.2726 [eDVBChannel] getDemux cap=00
13:22:07.2727 [eDVBServicePlay] Encrypted channel, creating speculative CSA session
13:22:07.2728 [CSASession] Created for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:07.2728 [CSAEngine] init: batch_size=128
13:22:07.2728 [CSASession] Initialized and connected to eDVBCAHandler signals
13:22:07.2729 [CSASession] Startup buffering enabled, max size: 2097152 bytes
13:22:07.2730 [eDVBServicePlay] Startup buffering enabled: 2 MB
13:22:07.2730 [SoftDecoder] Created for decoder 0
13:22:07.2731 [eDVBServicePlay] Speculative CSA session created, waiting for algo=3
13:22:07.2784 [eRCDeviceInputDev] 0 160 1
13:22:07.2786 [InfoBarGenerics] Key 'KEY_OK' (0x160 - 352) Break.
13:22:07.4053 [eDVBServicePMTHandler] PATready
13:22:07.4054 [eDVBServicePMTHandler] PAT TSID: 0x0004 (4)
13:22:07.4054 [eDVBServicePMTHandler] use pmtpid 006a for service_id 008c
13:22:07.4549 [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
13:22:07.4550 [eDVBServicePlay] have 1 video stream(s) (0aff), and 2 audio stream(s) (0b03, 0b04), and the pcr pid is 0aff, and the text pid is ffffffff
13:22:07.4552 [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 0
13:22:07.4553 [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0
13:22:07.4554 [eTSMPEGDecoder] decoder state: play, vpid=0aff, apid=0b03
13:22:07.4554 [eDVBVideo0] VIDEO_SLOWMOTION 0 ok13:22:07.4596 [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok13:22:07.4642 [eDVBVideo0] VIDEO_CONTINUE ok13:22:07.4681 [eDVBAudio0] AUDIO_CONTINUE ok13:22:07.4815 [eDVBCIInterfaces] gotPMT
13:22:07.4815 [eDVBCAService] don't build/send the same CA PMT twice
13:22:07.5094 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:07.5095 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:07.5095 [CSASession] ACTIVATED - algo=3 received, SW-Descrambling active
13:22:07.5095 [SoftDecoder] Session activated: 1
13:22:07.5095 [eDVBServicePlay] Session activated callback: active=1
13:22:07.5096 [eDVBServicePlay] SW-Descrambling activated, SoftDecoder takes over
13:22:07.5096 [eDVBServicePlay] Quick-releasing HW decoder (PIDs only, no pause)
13:22:07.5097 [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
13:22:07.5097 [eDVBPCR0] DEMUX_STOP ok13:22:07.5097 [eDVBPCR0] destroy
13:22:07.5098 [eDVBVideo0] DEMUX_STOP ok13:22:07.5105 [eDVBVideo0] VIDEO_STOP ok13:22:07.5140 [eDVBVideo0] destroy
13:22:07.5141 [eDVBAudio0] AUDIO_STOP ok13:22:07.5438 [eDVBAudio0] DEMUX_STOP ok13:22:07.5439 [eDVBAudio0] AUDIO_CONTINUE ok13:22:07.5526 [eDVBAudio0] destroy
13:22:07.5526 [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
13:22:07.5537 [eDVBServicePlay] HW decoder released (fast path)
13:22:07.5538 [eDVBServicePlay] Starting SoftDecoder
13:22:07.5538 [SoftDecoder] Starting
13:22:07.5539 [SoftDecoder] setupRecorder
13:22:07.5539 [SoftDecoder] Data demux ID: 0 (reads from tuner)
13:22:07.5540 [SoftDecoder] Using synchronous mode (config=0)
13:22:07.5541 [eDVBRecordStreamThread] allocated 40 buffers of 47 kB
13:22:07.5541 [eDVBRecordScrambledThread] sync allocated 40 buffers of 47 kB (streaming=0)
13:22:07.5542 [eDVBChannel] getDemux cap=00
13:22:07.5542 [eDVBResourceManager] allocate demux cap=00
13:22:07.5542 [eDVBResourceManager] allocating demux adapter=0, demux=20, source=-1 fesource=-1
13:22:07.5543 [SoftDecoder] PVR channel allocated
13:22:07.5543 [SoftDecoder] Decode demux ID: 20 (from PVR handler)
13:22:07.5544 [SoftDecoder] Set demux 20 source to PVR (DVR20)
13:22:07.5544 [eDVBDemux] open dvr /dev/dvb/adapter0/dvr20
13:22:07.5545 [SoftDecoder] DVR opened for writing (fd=54)
13:22:07.5545 [SoftDecoder] Attaching session as descrambler (active=1)
13:22:07.5546 [SoftDecoder] have 1 video stream(s) (0aff), and 2 audio stream(s) (0b03, 0b04), and the pcr pid is 0aff, and the text pid is ffffffff
13:22:07.5553 [setIoPrio] realtime level 7 ok
13:22:07.5554 [eFilePushThreadRecorder] THREAD START
13:22:07.5561 [SoftDecoder] Waiting for first data (timeout=800ms)
13:22:08.0171 [eDVBRecordScrambledThread] First data written (48128 bytes), signaling
13:22:08.0172 [eDVBRecordScrambledThread] waitForFirstData: data ready!
13:22:08.0173 [SoftDecoder] have 1 video stream(s) (0aff), and 2 audio stream(s) (0b03, 0b04), and the pcr pid is 0aff, and the text pid is ffffffff
13:22:08.0178 [SoftDecoder] Getting decoder from demux 20
13:22:08.0203 [SoftDecoder] Decoder created on demux 20
13:22:08.0205 [SoftDecoder] Setting decoder: vpid=0aff vpidtype=1 pcrpid=0aff
13:22:08.0205 [SoftDecoder] Using cached audio: apid=0b03 atype=1 (stream 0)
13:22:08.0206 [eTSMPEGDecoder] decoder state: play, vpid=0aff, apid=0b03
13:22:08.0247 [eDVBAudio0] DMX_SET_PES_FILTER pid=0x0b03 ok13:22:08.0248 [eDVBAudio0] DEMUX_START ok13:22:08.0249 [eDVBAudio0] AUDIO_SET_BYPASS bypass=0 ok13:22:08.0333 [eDVBAudio0] AUDIO_PAUSE ok13:22:08.0367 [eDVBAudio0] AUDIO_PLAY ok13:22:08.0433 [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
13:22:08.0433 [eDVBVideo] demux device: /dev/dvb/adapter0/demux20
13:22:08.0476 [eDVBVideo0] VIDEO_SET_STREAMTYPE 1 - ok13:22:08.0518 [eDVBVideo0] DMX_SET_PES_FILTER pid=0x0aff ok13:22:08.0518 [eDVBVideo0] DEMUX_START ok13:22:08.0519 [eDVBVideo0] VIDEO_FREEZE ok13:22:08.0556 [eDVBVideo0] VIDEO_PLAY ok13:22:08.0643 [eDVBVideo0] VIDEO_SLOWMOTION 0 ok13:22:08.0685 [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok13:22:08.0730 [eDVBVideo0] VIDEO_CONTINUE ok13:22:08.0770 [eDVBAudio0] AUDIO_CONTINUE ok13:22:08.0855 [SoftDecoder] Decoder PLAY with vpid=0aff vpidtype=1
13:22:08.0856 [SoftDecoder] Creating teletext/subtitle parsers
13:22:08.0856 [eDVBTeletextParser] Starting!
13:22:08.0856 [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
13:22:08.0856 [eDVBPESReader] Created. Opening demux
13:22:08.0857 [eDVBTeletextParser] created teletext subtitle PES reader!
13:22:08.0858 [eDVBPESReader] Created. Opening demux
13:22:08.0858 [eDVBServicePlay] Got parsers from SoftDecoder
13:22:08.0859 [eDVBServicePlay] Connected video events from SoftDecoder
13:22:08.0859 [eDVBServicePlay] SoftDecoder takeover complete
13:22:08.1012 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:08.1012 [CSASession] onCwReceived: parity=0 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:08.1013 [CSASession] CW set: parity=0, hasEven=1, hasOdd=0
13:22:08.1013 [CSASession] CW ready, startup buffer (2,0 MB) 18,4% filled, processing 385024 buffered bytes
13:22:08.1049 [CSASession] Buffered data processed (discarded, decoder will catch up)
13:22:14.5165 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:14.5167 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:14.5167 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:14.5167 [CSASession] onCwReceived: parity=1 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:14.5168 [CSASession] CW set: parity=1, hasEven=1, hasOdd=1
13:22:21.5052 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:21.5054 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:21.5054 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:21.5055 [CSASession] onCwReceived: parity=0 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:21.5055 [CSASession] CW set: parity=0, hasEven=1, hasOdd=1
13:22:28.5232 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:28.5234 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:28.5234 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:28.5234 [CSASession] onCwReceived: parity=1 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:28.5235 [CSASession] CW set: parity=1, hasEven=1, hasOdd=1
13:22:35.5224 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:35.5225 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:35.5226 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:35.5227 [CSASession] onCwReceived: parity=0 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:35.5227 [CSASession] CW set: parity=0, hasEven=1, hasOdd=1
13:22:42.5206 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:42.5207 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:42.5208 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:42.5208 [CSASession] onCwReceived: parity=1 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:42.5209 [CSASession] CW set: parity=1, hasEven=1, hasOdd=1
13:22:49.5183 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:49.5184 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:49.5185 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:49.5185 [CSASession] onCwReceived: parity=0 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:49.5186 [CSASession] CW set: parity=0, hasEven=1, hasOdd=1
13:22:56.5363 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:22:56.5365 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:56.5365 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:56.5365 [CSASession] onCwReceived: parity=1 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:22:56.5366 [CSASession] CW set: parity=1, hasEven=1, hasOdd=1
13:23:03.5253 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:23:03.5254 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:03.5256 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:03.5256 [CSASession] onCwReceived: parity=0 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:03.5256 [CSASession] CW set: parity=0, hasEven=1, hasOdd=1
13:23:10.5047 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:23:10.5048 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:10.5049 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:10.5049 [CSASession] onCwReceived: parity=1 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:10.5049 [CSASession] CW set: parity=1, hasEven=1, hasOdd=1
13:23:17.5329 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:23:17.5330 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:17.5330 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:17.5331 [CSASession] onCwReceived: parity=0 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:17.5331 [CSASession] CW set: parity=0, hasEven=1, hasOdd=1
13:23:24.5332 [ePMTClient] CaSetDescrMode: Service 1:0:19:8C:4:85:C00000:0:0:0:, Algo 3
13:23:24.5333 [CSASession] onAlgoReceived: algo=3 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:24.5334 [ePMTClient] CaSetDescr: Service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:24.5334 [CSASession] onCwReceived: parity=1 for service 1:0:19:8C:4:85:C00000:0:0:0:
13:23:24.5335 [CSASession] CW set: parity=1, hasEven=1, hasOdd=1