20:36:35.860 [e2-python] ProcessRepeated
20:36:35.861 [e2-python] [#----] VTiCore [TIMER]activating state 3
20:36:35.861 [e2-python] [#----] VTiCore [TIMER]stop recording
20:36:35.862 [e2-python] [#----] VTiCore [VTiEvent] PVRDESCRAMBLE_STOP Der Vorname, Sky Cinema/media/hdd/Aufnahmen/Der Vorname.ts, 1579635395, 1579635395
20:36:35.862 [e2-core] stop recording!
20:36:35.862 [e2-core] (was not recording)
20:36:35.862 [e2-python] [#----] VTiCore [TIMER]record event 5
20:36:35.864 [e2-python] [#----] VTiCore [TIMER]record event 1
20:36:35.867 [e2-python] [#----] VTiCore [remove callback]
20:36:35.868 [e2-core] decoder state: play, vpid=-1, apid=-1
20:36:35.869 [e2-core] DEMUX_STOP - video - ok
20:36:35.869 [e2-core] VIDEO_STOP - ok
20:36:35.873 [e2-core] AUDIO_STOP - ok
20:36:35.873 [e2-core] DEMUX_STOP - audio - ok
20:36:35.874 [e2-core] AUDIO_CONTINUE - ok
20:36:35.876 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:83:6:85:C00000:0:0:0::Sky Cinema/media/hdd/Aufnahmen/Der Vorname.ts
20:36:35.876 [e2-core] [eDVBCAService] free service 1:0:19:83:6:85:C00000:0:0:0::Sky Cinema/media/hdd/Aufnahmen/Der Vorname.ts
20:36:35.877 [e2-core] [eDVBCIInterfaces] remove last pmt handler for service 1:0:19:83:6:85:C00000:0:0:0::Sky Cinema/media/hdd/Aufnahmen/Der Vorname.ts send empty capmt
20:36:35.878 [e2-core] stop ecm
20:36:35.878 [e2-core] stop ecm
20:36:35.879 [e2-core] stop ecm
20:36:35.879 [e2-core] (3) slot 0 usecount is now 0
20:36:35.880 [e2-core] start release channel timer
20:36:35.881 [e2-python] [#----] VTiCore [SILENT_REC] we are in auto mode
20:36:35.881 [e2-python] next real activation isWed Jan 22 02:29:40 2020
20:36:35.882 [e2-python] [#----] VTiCore state: 3
20:36:35.882 [e2-python] [#----] VTiCore in processed: False
20:36:35.882 [e2-python] [#----] VTiCore in running: False
20:36:35.884 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts...
20:36:35.884 [e2-core] before: 1
20:36:35.884 [e2-core] after: 1
20:36:35.884 [e2-core] old thread joined 0
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.meta...
20:36:35.885 [e2-core] setIoPrio best-effort level 7 ok
20:36:35.885 [e2-core] before: 1
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.ap...
20:36:35.885 [e2-core] file /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.del erased
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.sc...
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.cuts...
20:36:35.885 [e2-core] file /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.meta.del erased
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.info...
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.txt...
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.jpg...
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.png...
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.jpeg...
20:36:35.885 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.rec...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.ts.del...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.eit...
20:36:35.886 [e2-core] before: 1
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.info...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.txt...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.jpg...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.png...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.jpeg...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.rec...
20:36:35.886 [e2-core] Removing /media/hdd/Aufnahmen/Der Vorname_pvrdesc.del...
20:36:35.888 [e2-core] file /media/hdd/Aufnahmen/Der Vorname_pvrdesc.eit.del erased
20:36:38.870 [e2-python] [#----] VTiCore [PVRDescramble] get unscrambled recordings
20:36:38.871 [e2-python] [#----] VTiCore getRecordings : []
20:36:38.872 [e2-python] playing service..
20:36:38.886 [e2-python] [#----] VTiCore not pauseable.
20:36:38.887 [e2-python] RemovePopup, id =ZapError
20:36:38.887 [e2-core] use cached_channel
20:36:38.887 [e2-core] stop release channel timer
20:36:38.887 [e2-core] allocate Channel: res 0
20:36:38.887 [e2-core] '1:0:19:83:6:85:C00000:0:0:0::Sky Cinema' is in service list of slot 0... so use it
20:36:38.887 [e2-core] (1)CISlot 0, usecount now 1
20:36:38.888 [e2-core] no version filtering
20:36:38.888 [e2-core] 0012: 4e 00 83 00 00 00
20:36:38.888 [e2-core] mask: ff ff ff 00 00 00
20:36:38.888 [e2-core] mode: 00 00 00 00 00 00
20:36:38.889 [e2-core] ok ... now we start!!
20:36:38.889 [e2-core] no version filtering
20:36:38.889 [e2-core] 0000: 00 00 00 00 00 00
20:36:38.889 [e2-core] mask: ff 00 00 00 00 00
20:36:38.889 [e2-core] mode: 00 00 00 00 00 00
20:36:38.890 [e2-core] eventNewProgramInfo 0 0
20:36:38.890 [e2-core] have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is ffffffff
20:36:38.894 [e2-core] disable teletext subtitles
20:36:38.902 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
20:36:38.902 [e2-core] decoder state: play, vpid=1279, apid=1283
20:36:38.902 [e2-core] DMX_SET_PES_FILTER(0x4ff) - pcr - ok
20:36:38.903 [e2-core] DEMUX_START - pcr - ok
20:36:38.903 [e2-core] DMX_SET_PES_FILTER(0x503) - audio - ok
20:36:38.903 [e2-core] DEMUX_START - audio - ok
20:36:38.903 [e2-core] AUDIO_SET_BYPASS(0) - ok
20:36:38.903 [e2-core] AUDIO_PAUSE - ok
20:36:38.905 [e2-core] AUDIO_PLAY - ok
20:36:38.905 [e2-core] Video Device: /dev/dvb/adapter0/video0
20:36:38.905 [e2-core] demux device: /dev/dvb/adapter0/demux0
20:36:38.905 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
20:36:38.905 [e2-core] DMX_SET_PES_FILTER(0x4ff) - video - ok
20:36:38.905 [e2-core] DEMUX_START - video - ok
20:36:38.905 [e2-core] VIDEO_FREEZE - ok
20:36:38.911 [e2-core] VIDEO_PLAY - ok
20:36:38.911 [e2-core] VIDEO_SLOWMOTION(0) - ok
20:36:38.911 [e2-core] VIDEO_FAST_FORWARD(0) - ok
20:36:38.911 [e2-core] VIDEO_CONTINUE - ok
20:36:38.912 [e2-core] AUDIO_CONTINUE - ok
20:36:38.913 [e2-python] [#----] VTiCore not pauseable.
20:36:38.924 [e2-python] [AutoRes] service changed
20:36:38.926 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
20:36:38.927 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
20:36:38.927 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:83:6:85:C00000:0:0:0::Sky Cinema, Sky Cinema
20:36:38.939 [e2-core] + 1/1 TID 00
20:36:38.940 [e2-core] done!
20:36:38.940 [e2-core] PATready
20:36:38.940 [e2-core] use pmtpid 0064 for service_id 0083
20:36:38.940 [e2-core] no version filtering
20:36:38.940 [e2-core] 0064: 02 00 83 00 00 00
20:36:38.940 [e2-core] mask: ff ff ff 00 00 00
20:36:38.941 [e2-core] mode: 00 00 00 00 00 00
20:36:38.941 [e2-core] doing version filtering
20:36:38.941 [e2-core] 0000: 00 00 00 1f 00 00
20:36:38.941 [e2-core] mask: ff 00 00 3f 00 00
20:36:38.941 [e2-core] mode: 00 00 00 3e 00 00
20:36:39.019 [e2-core] + 1/1 TID 02
20:36:39.020 [e2-core] done!
20:36:39.020 [e2-core] eventNewProgramInfo 0 0
20:36:39.021 [e2-core] [autoAudioTrack] use audio track: 0
20:36:39.021 [e2-core] have 1 video stream(s) (04ff), and 2 audio stream(s) (0503, 0504), and the pcr pid is 04ff, and the text pid is ffffffff
20:36:39.028 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
20:36:39.028 [e2-core] decoder state: play, vpid=1279, apid=1283
20:36:39.028 [e2-core] VIDEO_SLOWMOTION(0) - ok
20:36:39.028 [e2-core] VIDEO_FAST_FORWARD(0) - ok
20:36:39.029 [e2-core] VIDEO_CONTINUE - ok
20:36:39.030 [e2-core] AUDIO_CONTINUE - ok
20:36:39.034 [e2-core] [autoAudioTrack] use audio track: 0
20:36:39.043 [e2-core] [eDVBCAService] new service 1:0:19:83:6:85:C00000:0:0:0::Sky Cinema
20:36:39.043 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:83:6:85:C00000:0:0:0::Sky Cinema
20:36:39.044 [e2-core] '1:0:19:83:6:85:C00000:0:0:0::Sky Cinema' is in service list of slot 0... so use it
20:36:39.044 [e2-core] PES Start CAPID = 6838 Caid = 2444
20:36:39.044 [e2-core] PES Start CAPID = 7094 Caid = 2500
20:36:39.044 [e2-core] PES Start CAPID = 7350 Caid = 2445
20:36:39.045 [e2-core] demux 0 mask 01 prevhash 00000000
20:36:39.045 [e2-core] [eDVBCAService] send 67 bytes
20:36:39.045 [e2-core] doing version filtering
20:36:39.045 [e2-core] 0064: 02 00 83 07 00 00
20:36:39.045 [e2-core] mask: ff ff ff 3f 00 00
20:36:39.046 [e2-core] mode: 00 00 00 3e 00 00
20:36:39.061 [e2-core] +- 1/2 TID 4e
20:36:39.940 [e2-python] [AutoRes] determineContent
20:36:40.040 [e2-core] ++ 2/2 TID 4e
20:36:40.040 [e2-core] done!
20:36:40.053 [e2-core] doing version filtering
20:36:40.053 [e2-core] 0012: 4e 00 83 03 00 00
20:36:40.053 [e2-core] mask: ff ff ff 3f 00 00
20:36:40.054 [e2-core] mode: 00 00 00 3e 00 00