Thu May 6 16:20:02 [27887] INFO: starting markad v3.0.1-BM2LTS (0cc41a0) (64bit) Thu May 6 16:20:02 [27887] INFO: using libavcodec.so.58.91.100 with -1 threads Thu May 6 16:20:02 [27887] INFO: on /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 16:20:12 [27887] DEBUG: parameter before is set, markad is called with a running recording Thu May 6 16:20:12 [27887] INFO: broadcast aspect ratio 16:9 (from vdr info) Thu May 6 16:20:12 [27887] DEBUG: cMarkAdStandalone::GetBroadcastStart(): mount option noatime is set, use atime from directory /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec to get creation time Thu May 6 16:20:12 [27887] DEBUG: cMarkAdStandalone::GetBroadcastStart(): got recording start from directory creation time Thu May 6 16:20:12 [27887] DEBUG: cMarkAdStandalone::LoadInfo(): recording start at Thu May 6 16:19:55 2021 Thu May 6 16:20:12 [27887] DEBUG: cMarkAdStandalone::LoadInfo(): timer start at Thu May 6 16:25:00 2021 Thu May 6 16:20:12 [27887] DEBUG: cMarkAdStandalone::LoadInfo(): broadcast start 305s after recording start Thu May 6 16:20:12 [27887] DEBUG: cMarkAdStandalone::CheckLogo(): using logo directory /opt/vdr/plugins/markad Thu May 6 16:20:12 [27887] DEBUG: cMarkAdStandalone::CheckLogo(): searching logo for TLC_HD Thu May 6 16:20:12 [27887] INFO: no logo found in logo directory, trying to find logo in recording directory Thu May 6 16:20:12 [27887] INFO: no logo found in recording directory, trying to extract logo from recording Thu May 6 16:20:12 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 7625 with aspect ratio 16:9 Thu May 6 16:20:12 [27887] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (229) read frames (-1) minFrame (0) Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 7625 Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Thu May 6 16:20:12 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 16:20:12 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu May 6 16:20:12 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu May 6 16:20:12 [27887] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::SearchLogo(): video resolution 1440x1080 Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::SearchLogo(): logo size 340x220 Thu May 6 16:20:12 [27887] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 2 at frame (6) PTS 8176394867 Thu May 6 16:20:12 [27887] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu May 6 16:20:12 [27887] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (4:3) at frame 11 Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::SearchLogo(): seek to frame 7625 Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (233) read frames (11) minFrame (7625) Thu May 6 16:20:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 1864 bytes, system time 06-05-2021 16:20:12 index time 06-05-2021 16:20:04, wait 60s Thu May 6 16:21:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (1733) read frames (11) minFrame (7625) Thu May 6 16:21:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 13864 bytes, system time 06-05-2021 16:21:12 index time 06-05-2021 16:21:04, wait 60s Thu May 6 16:22:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (3228) read frames (11) minFrame (7625) Thu May 6 16:22:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 25824 bytes, system time 06-05-2021 16:22:12 index time 06-05-2021 16:22:04, wait 60s Thu May 6 16:23:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (4731) read frames (11) minFrame (7625) Thu May 6 16:23:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 37848 bytes, system time 06-05-2021 16:23:12 index time 06-05-2021 16:23:04, wait 60s Thu May 6 16:24:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (6232) read frames (11) minFrame (7625) Thu May 6 16:24:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 49856 bytes, system time 06-05-2021 16:24:12 index time 06-05-2021 16:24:04, wait 60s Thu May 6 16:25:12 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (7737) read frames (11) minFrame (7625) Thu May 6 16:25:12 [27887] DEBUG: cDecoder::SeekToFrame(): (7625) Thu May 6 16:25:12 [27887] DEBUG: cIndex::GetIFrameBefore(): failed for frame (7625), index: first frame (0) last frame (0) Thu May 6 16:25:12 [27887] DEBUG: cDecoder::SeekFrame(): index does not yet contain frame ( 7625), decode from current frame (11) to build index Thu May 6 16:25:15 [27887] DEBUG: cDecoder::SeekToFrame(): successful Thu May 6 16:25:15 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (7815) read frames (7626) minFrame (0) Thu May 6 16:25:15 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 62520 bytes, system time 06-05-2021 16:25:15 index time 06-05-2021 16:25:07, wait 60s Thu May 6 16:26:15 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (9314) read frames (7626) minFrame (0) Thu May 6 16:26:15 [27887] DEBUG: cExtractLogo::AudioInBroadcast(): got first time 2 channel at frame (7653) Thu May 6 16:26:15 [27887] DEBUG: cExtractLogo::SearchLogo(): recording is aktive, read frame (7653), now save dummy start mark at pre timer position 305s Thu May 6 16:26:15 [27887] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=1 Thu May 6 16:26:16 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (9335) read frames (9114) minFrame (0) Thu May 6 16:26:16 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (9335) read frames (9135) minFrame (0) Thu May 6 16:26:16 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 74680 bytes, system time 06-05-2021 16:26:16 index time 06-05-2021 16:26:08, wait 60s Thu May 6 16:27:16 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (10833) read frames (9135) minFrame (0) Thu May 6 16:27:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (10848) read frames (10633) minFrame (0) Thu May 6 16:27:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (10848) read frames (10648) minFrame (0) Thu May 6 16:27:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 86784 bytes, system time 06-05-2021 16:27:17 index time 06-05-2021 16:27:09, wait 60s Thu May 6 16:28:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12349) read frames (10648) minFrame (0) Thu May 6 16:28:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12365) read frames (12149) minFrame (0) Thu May 6 16:28:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12369) read frames (12165) minFrame (0) Thu May 6 16:28:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12369) read frames (12169) minFrame (0) Thu May 6 16:28:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 98952 bytes, system time 06-05-2021 16:28:17 index time 06-05-2021 16:28:09, wait 60s Thu May 6 16:29:17 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (13867) read frames (12169) minFrame (0) Thu May 6 16:29:18 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (13881) read frames (13667) minFrame (0) Thu May 6 16:29:18 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (13881) read frames (13681) minFrame (0) Thu May 6 16:29:18 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 111048 bytes, system time 06-05-2021 16:29:18 index time 06-05-2021 16:29:10, wait 60s Thu May 6 16:30:18 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (15381) read frames (13681) minFrame (0) Thu May 6 16:30:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (15399) read frames (15181) minFrame (0) Thu May 6 16:30:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (15399) read frames (15199) minFrame (0) Thu May 6 16:30:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 123192 bytes, system time 06-05-2021 16:30:19 index time 06-05-2021 16:30:11, wait 60s Thu May 6 16:31:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (16897) read frames (15199) minFrame (0) Thu May 6 16:31:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (16913) read frames (16697) minFrame (0) Thu May 6 16:31:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (16913) read frames (16713) minFrame (0) Thu May 6 16:31:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 135304 bytes, system time 06-05-2021 16:31:19 index time 06-05-2021 16:31:11, wait 60s Thu May 6 16:32:19 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (18415) read frames (16713) minFrame (0) Thu May 6 16:32:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (18427) read frames (18215) minFrame (0) Thu May 6 16:32:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (18427) read frames (18227) minFrame (0) Thu May 6 16:32:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 147416 bytes, system time 06-05-2021 16:32:20 index time 06-05-2021 16:32:12, wait 60s Thu May 6 16:33:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (19929) read frames (18227) minFrame (0) Thu May 6 16:33:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (19941) read frames (19729) minFrame (0) Thu May 6 16:33:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (19941) read frames (19741) minFrame (0) Thu May 6 16:33:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 159528 bytes, system time 06-05-2021 16:33:20 index time 06-05-2021 16:33:12, wait 60s Thu May 6 16:34:20 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (21441) read frames (19741) minFrame (0) Thu May 6 16:34:21 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (21463) read frames (21241) minFrame (0) Thu May 6 16:34:21 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (21463) read frames (21263) minFrame (0) Thu May 6 16:34:21 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 171704 bytes, system time 06-05-2021 16:34:21 index time 06-05-2021 16:34:13, wait 60s Thu May 6 16:35:21 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (22963) read frames (21263) minFrame (0) Thu May 6 16:35:22 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (22983) read frames (22763) minFrame (0) Thu May 6 16:35:22 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (22983) read frames (22783) minFrame (0) Thu May 6 16:35:22 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 183864 bytes, system time 06-05-2021 16:35:22 index time 06-05-2021 16:35:14, wait 60s Thu May 6 16:36:22 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (24481) read frames (22783) minFrame (0) Thu May 6 16:36:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (24499) read frames (24281) minFrame (0) Thu May 6 16:36:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (24499) read frames (24299) minFrame (0) Thu May 6 16:36:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 195992 bytes, system time 06-05-2021 16:36:23 index time 06-05-2021 16:36:15, wait 60s Thu May 6 16:37:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (26001) read frames (24299) minFrame (0) Thu May 6 16:37:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (26017) read frames (25801) minFrame (0) Thu May 6 16:37:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (26017) read frames (25817) minFrame (0) Thu May 6 16:37:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 208136 bytes, system time 06-05-2021 16:37:23 index time 06-05-2021 16:37:15, wait 60s Thu May 6 16:38:23 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (27516) read frames (25817) minFrame (0) Thu May 6 16:38:24 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (27533) read frames (27316) minFrame (0) Thu May 6 16:38:24 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (27533) read frames (27333) minFrame (0) Thu May 6 16:38:24 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 220264 bytes, system time 06-05-2021 16:38:24 index time 06-05-2021 16:38:16, wait 60s Thu May 6 16:39:24 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (29035) read frames (27333) minFrame (0) Thu May 6 16:39:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (29053) read frames (28835) minFrame (0) Thu May 6 16:39:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (29053) read frames (28853) minFrame (0) Thu May 6 16:39:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 232424 bytes, system time 06-05-2021 16:39:25 index time 06-05-2021 16:39:17, wait 60s Thu May 6 16:40:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (30549) read frames (28853) minFrame (0) Thu May 6 16:40:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (30565) read frames (30349) minFrame (0) Thu May 6 16:40:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (30565) read frames (30365) minFrame (0) Thu May 6 16:40:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 244520 bytes, system time 06-05-2021 16:40:25 index time 06-05-2021 16:40:17, wait 60s Thu May 6 16:41:25 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (32067) read frames (30365) minFrame (0) Thu May 6 16:41:26 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (32083) read frames (31867) minFrame (0) Thu May 6 16:41:26 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (32083) read frames (31883) minFrame (0) Thu May 6 16:41:26 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 256664 bytes, system time 06-05-2021 16:41:26 index time 06-05-2021 16:41:18, wait 60s Thu May 6 16:42:26 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (33579) read frames (31883) minFrame (0) Thu May 6 16:42:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (33599) read frames (33379) minFrame (0) Thu May 6 16:42:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (33599) read frames (33399) minFrame (0) Thu May 6 16:42:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 268792 bytes, system time 06-05-2021 16:42:27 index time 06-05-2021 16:42:19, wait 60s Thu May 6 16:43:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (35097) read frames (33399) minFrame (0) Thu May 6 16:43:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (35115) read frames (34897) minFrame (0) Thu May 6 16:43:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (35115) read frames (34915) minFrame (0) Thu May 6 16:43:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 280920 bytes, system time 06-05-2021 16:43:27 index time 06-05-2021 16:43:19, wait 60s Thu May 6 16:44:27 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (36615) read frames (34915) minFrame (0) Thu May 6 16:44:28 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (36631) read frames (36415) minFrame (0) Thu May 6 16:44:28 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (36631) read frames (36431) minFrame (0) Thu May 6 16:44:28 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 293048 bytes, system time 06-05-2021 16:44:28 index time 06-05-2021 16:44:20, wait 60s Thu May 6 16:45:28 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (38131) read frames (36431) minFrame (0) Thu May 6 16:45:29 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (38152) read frames (37931) minFrame (0) Thu May 6 16:45:29 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (38152) read frames (37952) minFrame (0) Thu May 6 16:45:29 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 305216 bytes, system time 06-05-2021 16:45:29 index time 06-05-2021 16:45:21, wait 60s Thu May 6 16:46:29 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (39653) read frames (37952) minFrame (0) Thu May 6 16:46:29 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (39670) read frames (39453) minFrame (0) Thu May 6 16:46:29 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (39670) read frames (39470) minFrame (0) Thu May 6 16:46:29 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 317360 bytes, system time 06-05-2021 16:46:29 index time 06-05-2021 16:46:22, wait 60s Thu May 6 16:47:30 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (41174) read frames (39470) minFrame (0) Thu May 6 16:47:30 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (41187) read frames (40974) minFrame (0) Thu May 6 16:47:30 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (41190) read frames (40987) minFrame (0) Thu May 6 16:47:30 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (41190) read frames (40990) minFrame (0) Thu May 6 16:47:30 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 329520 bytes, system time 06-05-2021 16:47:30 index time 06-05-2021 16:47:22, wait 60s Thu May 6 16:48:30 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (42690) read frames (40990) minFrame (0) Thu May 6 16:48:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (42701) read frames (42490) minFrame (0) Thu May 6 16:48:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (42701) read frames (42501) minFrame (0) Thu May 6 16:48:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 341608 bytes, system time 06-05-2021 16:48:31 index time 06-05-2021 16:48:23, wait 60s Thu May 6 16:49:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (44204) read frames (42501) minFrame (0) Thu May 6 16:49:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (44220) read frames (44004) minFrame (0) Thu May 6 16:49:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (44220) read frames (44020) minFrame (0) Thu May 6 16:49:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 353760 bytes, system time 06-05-2021 16:49:31 index time 06-05-2021 16:49:23, wait 60s Thu May 6 16:50:31 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (45716) read frames (44020) minFrame (0) Thu May 6 16:50:32 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (45732) read frames (45516) minFrame (0) Thu May 6 16:50:32 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (45732) read frames (45532) minFrame (0) Thu May 6 16:50:32 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 365856 bytes, system time 06-05-2021 16:50:32 index time 06-05-2021 16:50:24, wait 60s Thu May 6 16:51:32 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (47236) read frames (45532) minFrame (0) Thu May 6 16:51:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (47254) read frames (47036) minFrame (0) Thu May 6 16:51:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (47254) read frames (47054) minFrame (0) Thu May 6 16:51:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 378032 bytes, system time 06-05-2021 16:51:33 index time 06-05-2021 16:51:25, wait 60s Thu May 6 16:52:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (48753) read frames (47054) minFrame (0) Thu May 6 16:52:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (48769) read frames (48553) minFrame (0) Thu May 6 16:52:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (48769) read frames (48569) minFrame (0) Thu May 6 16:52:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 390152 bytes, system time 06-05-2021 16:52:33 index time 06-05-2021 16:52:26, wait 60s Thu May 6 16:53:33 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (50269) read frames (48569) minFrame (0) Thu May 6 16:53:34 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (50286) read frames (50069) minFrame (0) Thu May 6 16:53:34 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (50286) read frames (50086) minFrame (0) Thu May 6 16:53:34 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 402288 bytes, system time 06-05-2021 16:53:34 index time 06-05-2021 16:53:26, wait 60s Thu May 6 16:54:34 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (51785) read frames (50086) minFrame (0) Thu May 6 16:54:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (51802) read frames (51585) minFrame (0) Thu May 6 16:54:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (51802) read frames (51602) minFrame (0) Thu May 6 16:54:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 414416 bytes, system time 06-05-2021 16:54:35 index time 06-05-2021 16:54:27, wait 60s Thu May 6 16:55:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (53302) read frames (51602) minFrame (0) Thu May 6 16:55:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (53319) read frames (53102) minFrame (0) Thu May 6 16:55:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (53319) read frames (53119) minFrame (0) Thu May 6 16:55:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 426552 bytes, system time 06-05-2021 16:55:35 index time 06-05-2021 16:55:28, wait 60s Thu May 6 16:56:35 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (54822) read frames (53119) minFrame (0) Thu May 6 16:56:36 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (54837) read frames (54622) minFrame (0) Thu May 6 16:56:36 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (54837) read frames (54637) minFrame (0) Thu May 6 16:56:36 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 438696 bytes, system time 06-05-2021 16:56:36 index time 06-05-2021 16:56:28, wait 60s Thu May 6 16:57:36 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (56333) read frames (54637) minFrame (0) Thu May 6 16:57:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (56349) read frames (56133) minFrame (0) Thu May 6 16:57:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (56349) read frames (56149) minFrame (0) Thu May 6 16:57:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 450792 bytes, system time 06-05-2021 16:57:37 index time 06-05-2021 16:57:29, wait 60s Thu May 6 16:58:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (57853) read frames (56149) minFrame (0) Thu May 6 16:58:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (57865) read frames (57653) minFrame (0) Thu May 6 16:58:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (57865) read frames (57665) minFrame (0) Thu May 6 16:58:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 462920 bytes, system time 06-05-2021 16:58:37 index time 06-05-2021 16:58:29, wait 60s Thu May 6 16:59:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (59367) read frames (57665) minFrame (0) Thu May 6 16:59:38 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (59383) read frames (59167) minFrame (0) Thu May 6 16:59:38 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (59383) read frames (59183) minFrame (0) Thu May 6 16:59:38 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 475064 bytes, system time 06-05-2021 16:59:38 index time 06-05-2021 16:59:30, wait 60s Thu May 6 17:00:38 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (60885) read frames (59183) minFrame (0) Thu May 6 17:00:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (60900) read frames (60685) minFrame (0) Thu May 6 17:00:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (60900) read frames (60700) minFrame (0) Thu May 6 17:00:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 487200 bytes, system time 06-05-2021 17:00:39 index time 06-05-2021 17:00:31, wait 60s Thu May 6 17:01:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (62403) read frames (60700) minFrame (0) Thu May 6 17:01:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (62419) read frames (62203) minFrame (0) Thu May 6 17:01:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (62419) read frames (62219) minFrame (0) Thu May 6 17:01:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 499352 bytes, system time 06-05-2021 17:01:39 index time 06-05-2021 17:01:32, wait 60s Thu May 6 17:02:39 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (63921) read frames (62219) minFrame (0) Thu May 6 17:02:40 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (63939) read frames (63721) minFrame (0) Thu May 6 17:02:40 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (63939) read frames (63739) minFrame (0) Thu May 6 17:02:40 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 511512 bytes, system time 06-05-2021 17:02:40 index time 06-05-2021 17:02:32, wait 60s Thu May 6 17:03:40 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (65440) read frames (63739) minFrame (0) Thu May 6 17:03:41 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (65465) read frames (65240) minFrame (0) Thu May 6 17:03:41 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (65465) read frames (65265) minFrame (0) Thu May 6 17:03:41 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 523720 bytes, system time 06-05-2021 17:03:41 index time 06-05-2021 17:03:33, wait 60s Thu May 6 17:04:41 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (66969) read frames (65265) minFrame (0) Thu May 6 17:04:42 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (66985) read frames (66769) minFrame (0) Thu May 6 17:04:42 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (66985) read frames (66785) minFrame (0) Thu May 6 17:04:42 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 535880 bytes, system time 06-05-2021 17:04:42 index time 06-05-2021 17:04:34, wait 60s Thu May 6 17:05:42 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (68484) read frames (66785) minFrame (0) Thu May 6 17:05:43 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (68501) read frames (68284) minFrame (0) Thu May 6 17:05:43 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (68501) read frames (68301) minFrame (0) Thu May 6 17:05:43 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 548008 bytes, system time 06-05-2021 17:05:43 index time 06-05-2021 17:05:35, wait 60s Thu May 6 17:06:43 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (70001) read frames (68301) minFrame (0) Thu May 6 17:06:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (70019) read frames (69801) minFrame (0) Thu May 6 17:06:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (70019) read frames (69819) minFrame (0) Thu May 6 17:06:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 560152 bytes, system time 06-05-2021 17:06:44 index time 06-05-2021 17:06:36, wait 60s Thu May 6 17:07:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (71522) read frames (69819) minFrame (0) Thu May 6 17:07:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (71542) read frames (71322) minFrame (0) Thu May 6 17:07:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (71542) read frames (71342) minFrame (0) Thu May 6 17:07:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 572336 bytes, system time 06-05-2021 17:07:44 index time 06-05-2021 17:07:36, wait 60s Thu May 6 17:08:44 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (73041) read frames (71342) minFrame (0) Thu May 6 17:08:45 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (73060) read frames (72841) minFrame (0) Thu May 6 17:08:45 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (73060) read frames (72860) minFrame (0) Thu May 6 17:08:45 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 584480 bytes, system time 06-05-2021 17:08:45 index time 06-05-2021 17:08:37, wait 60s Thu May 6 17:09:45 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (74561) read frames (72860) minFrame (0) Thu May 6 17:09:46 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (74578) read frames (74361) minFrame (0) Thu May 6 17:09:46 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (74578) read frames (74378) minFrame (0) Thu May 6 17:09:46 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 596624 bytes, system time 06-05-2021 17:09:46 index time 06-05-2021 17:09:38, wait 60s Thu May 6 17:10:46 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (76080) read frames (74378) minFrame (0) Thu May 6 17:10:46 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (76096) read frames (75880) minFrame (0) Thu May 6 17:10:46 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (76096) read frames (75896) minFrame (0) Thu May 6 17:10:46 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 608768 bytes, system time 06-05-2021 17:10:46 index time 06-05-2021 17:10:39, wait 60s Thu May 6 17:11:47 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (77593) read frames (75896) minFrame (0) Thu May 6 17:11:47 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (77610) read frames (77393) minFrame (0) Thu May 6 17:11:47 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (77613) read frames (77410) minFrame (0) Thu May 6 17:11:47 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (77613) read frames (77413) minFrame (0) Thu May 6 17:11:47 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 620904 bytes, system time 06-05-2021 17:11:47 index time 06-05-2021 17:11:39, wait 60s Thu May 6 17:12:47 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (79112) read frames (77413) minFrame (0) Thu May 6 17:12:48 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (79129) read frames (78912) minFrame (0) Thu May 6 17:12:48 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (79129) read frames (78929) minFrame (0) Thu May 6 17:12:48 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 633032 bytes, system time 06-05-2021 17:12:48 index time 06-05-2021 17:12:40, wait 60s Thu May 6 17:13:48 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (80627) read frames (78929) minFrame (0) Thu May 6 17:13:49 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (80660) read frames (80427) minFrame (0) Thu May 6 17:13:49 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (80660) read frames (80460) minFrame (0) Thu May 6 17:13:49 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 645280 bytes, system time 06-05-2021 17:13:49 index time 06-05-2021 17:13:41, wait 60s Thu May 6 17:14:49 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (82164) read frames (80460) minFrame (0) Thu May 6 17:14:50 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (82184) read frames (81964) minFrame (0) Thu May 6 17:14:50 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (82187) read frames (81984) minFrame (0) Thu May 6 17:14:50 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (82187) read frames (81987) minFrame (0) Thu May 6 17:14:50 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 657496 bytes, system time 06-05-2021 17:14:50 index time 06-05-2021 17:14:42, wait 60s Thu May 6 17:15:50 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (83684) read frames (81987) minFrame (0) Thu May 6 17:15:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (83701) read frames (83484) minFrame (0) Thu May 6 17:15:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (83701) read frames (83501) minFrame (0) Thu May 6 17:15:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 669608 bytes, system time 06-05-2021 17:15:51 index time 06-05-2021 17:15:43, wait 60s Thu May 6 17:16:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (85200) read frames (83501) minFrame (0) Thu May 6 17:16:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (85220) read frames (85000) minFrame (0) Thu May 6 17:16:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (85220) read frames (85020) minFrame (0) Thu May 6 17:16:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 681760 bytes, system time 06-05-2021 17:16:51 index time 06-05-2021 17:16:43, wait 60s Thu May 6 17:17:51 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (86718) read frames (85020) minFrame (0) Thu May 6 17:17:52 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (86739) read frames (86518) minFrame (0) Thu May 6 17:17:52 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (86739) read frames (86539) minFrame (0) Thu May 6 17:17:52 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 693912 bytes, system time 06-05-2021 17:17:52 index time 06-05-2021 17:17:44, wait 60s Thu May 6 17:18:52 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (88238) read frames (86539) minFrame (0) Thu May 6 17:18:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (88271) read frames (88038) minFrame (0) Thu May 6 17:18:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (88271) read frames (88071) minFrame (0) Thu May 6 17:18:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 706168 bytes, system time 06-05-2021 17:18:54 index time 06-05-2021 17:18:46, wait 60s Thu May 6 17:19:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (89774) read frames (88071) minFrame (0) Thu May 6 17:19:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (89789) read frames (89574) minFrame (0) Thu May 6 17:19:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (89789) read frames (89589) minFrame (0) Thu May 6 17:19:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 718312 bytes, system time 06-05-2021 17:19:54 index time 06-05-2021 17:19:46, wait 60s Thu May 6 17:20:54 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (91287) read frames (89589) minFrame (0) Thu May 6 17:20:55 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (91302) read frames (91087) minFrame (0) Thu May 6 17:20:55 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (91302) read frames (91102) minFrame (0) Thu May 6 17:20:55 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 730416 bytes, system time 06-05-2021 17:20:55 index time 06-05-2021 17:20:47, wait 60s Thu May 6 17:21:55 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (92802) read frames (91102) minFrame (0) Thu May 6 17:21:56 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (92835) read frames (92602) minFrame (0) Thu May 6 17:21:56 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (92838) read frames (92635) minFrame (0) Thu May 6 17:21:56 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (92838) read frames (92638) minFrame (0) Thu May 6 17:21:56 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 742704 bytes, system time 06-05-2021 17:21:56 index time 06-05-2021 17:21:48, wait 60s Thu May 6 17:22:56 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (94338) read frames (92638) minFrame (0) Thu May 6 17:22:57 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (94361) read frames (94138) minFrame (0) Thu May 6 17:22:57 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (94361) read frames (94161) minFrame (0) Thu May 6 17:22:57 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 754888 bytes, system time 06-05-2021 17:22:57 index time 06-05-2021 17:22:49, wait 60s Thu May 6 17:23:57 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (95862) read frames (94161) minFrame (0) Thu May 6 17:23:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (95879) read frames (95662) minFrame (0) Thu May 6 17:23:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (95879) read frames (95679) minFrame (0) Thu May 6 17:23:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 767032 bytes, system time 06-05-2021 17:23:58 index time 06-05-2021 17:23:50, wait 60s Thu May 6 17:24:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97376) read frames (95679) minFrame (0) Thu May 6 17:24:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97390) read frames (97176) minFrame (0) Thu May 6 17:24:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97390) read frames (97190) minFrame (0) Thu May 6 17:24:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 779120 bytes, system time 06-05-2021 17:24:58 index time 06-05-2021 17:24:50, wait 60s Thu May 6 17:25:58 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (98891) read frames (97190) minFrame (0) Thu May 6 17:25:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (98904) read frames (98691) minFrame (0) Thu May 6 17:25:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (98904) read frames (98704) minFrame (0) Thu May 6 17:25:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 791232 bytes, system time 06-05-2021 17:25:59 index time 06-05-2021 17:25:51, wait 60s Thu May 6 17:26:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100406) read frames (98704) minFrame (0) Thu May 6 17:26:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100418) read frames (100206) minFrame (0) Thu May 6 17:26:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100418) read frames (100218) minFrame (0) Thu May 6 17:26:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803344 bytes, system time 06-05-2021 17:26:59 index time 06-05-2021 17:26:52, wait 60s Thu May 6 17:27:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (100218) minFrame (0) Thu May 6 17:27:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (100242) minFrame (0) Thu May 6 17:27:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803536 bytes, system time 06-05-2021 17:27:59 index time 06-05-2021 17:26:53, wait 60s Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (100242) minFrame (0) Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803536 bytes, system time 06-05-2021 17:28:59 index time 06-05-2021 17:26:53, wait 60s Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (100242), old or interrupted recording Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (100242), got 0 valid frames of 1569 frames read Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): end of recording reached at frame (100241), read (1569) iFrames and got (0) valid iFrames, try anyway Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): 0 valid frames of 1569 frames read, got enough iFrames at frame (100242), start analyze Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at TOP_LEFT Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at TOP_RIGHT Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at BOTTOM_LEFT Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at BOTTOM_RIGHT Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): no logo found Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 100241 Thu May 6 17:28:59 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:28:59 [27887] INFO: no logo found in recording, retry in 2nd recording part Thu May 6 17:28:59 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 15125 with aspect ratio 16:9 Thu May 6 17:28:59 [27887] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 15125 Thu May 6 17:28:59 [27887] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Thu May 6 17:28:59 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:29:00 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu May 6 17:29:00 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu May 6 17:29:00 [27887] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu May 6 17:29:00 [27887] DEBUG: cExtractLogo::SearchLogo(): video resolution 1440x1080 Thu May 6 17:29:00 [27887] DEBUG: cExtractLogo::SearchLogo(): logo size 340x220 Thu May 6 17:29:00 [27887] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 2 at frame (6) PTS 8176394867 Thu May 6 17:29:00 [27887] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu May 6 17:29:00 [27887] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (4:3) at frame 11 Thu May 6 17:29:00 [27887] DEBUG: cExtractLogo::SearchLogo(): seek to frame 15125 Thu May 6 17:29:00 [27887] DEBUG: cDecoder::SeekToFrame(): (15125) Thu May 6 17:29:03 [27887] DEBUG: cDecoder::SeekToFrame(): successful Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (100242) minFrame (0) Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803536 bytes, system time 06-05-2021 17:29:37 index time 06-05-2021 17:26:53, wait 60s Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (100242), old or interrupted recording Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (100242), got 0 valid frames of 1446 frames read Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): read (1446) frames and could not get enough valid frames (0) Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 100241 Thu May 6 17:29:37 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:29:37 [27887] INFO: no logo found in recording, retry in 3nd recording part Thu May 6 17:29:37 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 22625 with aspect ratio 16:9 Thu May 6 17:29:37 [27887] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 22625 Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Thu May 6 17:29:37 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:29:37 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu May 6 17:29:37 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu May 6 17:29:37 [27887] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): video resolution 1440x1080 Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): logo size 340x220 Thu May 6 17:29:37 [27887] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 2 at frame (6) PTS 8176394867 Thu May 6 17:29:37 [27887] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu May 6 17:29:37 [27887] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (4:3) at frame 11 Thu May 6 17:29:37 [27887] DEBUG: cExtractLogo::SearchLogo(): seek to frame 22625 Thu May 6 17:29:37 [27887] DEBUG: cDecoder::SeekToFrame(): (22625) Thu May 6 17:29:37 [27887] DEBUG: cDecoder::SeekToFrame(): successful Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (100242) minFrame (0) Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803536 bytes, system time 06-05-2021 17:29:55 index time 06-05-2021 17:26:53, wait 60s Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (100242), old or interrupted recording Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (100242), got 0 valid frames of 1328 frames read Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): read (1328) frames and could not get enough valid frames (0) Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 100241 Thu May 6 17:29:55 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:29:55 [27887] INFO: no logo found in recording, retry in 4nd recording part Thu May 6 17:29:55 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 30125 with aspect ratio 16:9 Thu May 6 17:29:55 [27887] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 30125 Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Thu May 6 17:29:55 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:29:55 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu May 6 17:29:55 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu May 6 17:29:55 [27887] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): video resolution 1440x1080 Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): logo size 340x220 Thu May 6 17:29:55 [27887] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 2 at frame (6) PTS 8176394867 Thu May 6 17:29:55 [27887] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu May 6 17:29:55 [27887] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (4:3) at frame 11 Thu May 6 17:29:55 [27887] DEBUG: cExtractLogo::SearchLogo(): seek to frame 30125 Thu May 6 17:29:55 [27887] DEBUG: cDecoder::SeekToFrame(): (30125) Thu May 6 17:29:55 [27887] DEBUG: cDecoder::SeekToFrame(): successful Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (100242) minFrame (0) Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803536 bytes, system time 06-05-2021 17:30:11 index time 06-05-2021 17:26:53, wait 60s Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (100242), old or interrupted recording Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (100242), got 0 valid frames of 1202 frames read Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::SearchLogo(): read (1202) frames and could not get enough valid frames (0) Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 100241 Thu May 6 17:30:11 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CheckLogo(): logo search failed Thu May 6 17:30:11 [27887] INFO: pre-timer 305s Thu May 6 17:30:11 [27887] INFO: broadcast length 55min Thu May 6 17:30:11 [27887] INFO: channel TLC_HD Thu May 6 17:30:11 [27887] INFO: parameter --loglevel is set to 3 Thu May 6 17:30:11 [27887] DEBUG: markad process nice level 19 Thu May 6 17:30:11 [27887] DEBUG: markad IO priority class 3 Thu May 6 17:30:11 [27887] DEBUG: parameter --logocachedir is set to /opt/vdr/plugins/markad Thu May 6 17:30:11 [27887] DEBUG: parameter --threads is set to -1 Thu May 6 17:30:11 [27887] DEBUG: parameter --astopoffs is set to 0 Thu May 6 17:30:11 [27887] DEBUG: parameter --log2rec is set Thu May 6 17:30:11 [27887] DEBUG: parameter --vps is set Thu May 6 17:30:11 [27887] DEBUG: parameter --autologo is set to 2 Thu May 6 17:30:11 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::ProcessFiles(): start processing files Thu May 6 17:30:11 [27887] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu May 6 17:30:11 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:30:11 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu May 6 17:30:11 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu May 6 17:30:11 [27887] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu May 6 17:30:11 [27887] INFO: video hight: 1080 Thu May 6 17:30:11 [27887] INFO: video width: 1440 Thu May 6 17:30:11 [27887] INFO: average frame rate 25 frames per second Thu May 6 17:30:11 [27887] INFO: real frame rate 50 frames per second Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 7625 (5min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 25 Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 3300s ( 55min 0s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 7625 ( 5min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 90125 ( 60min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 19625 ( 13min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 105125 ( 70min 5s) Thu May 6 17:30:11 [27887] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 2 at frame (6) PTS 8176394867 Thu May 6 17:30:11 [27887] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu May 6 17:30:11 [27887] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (4:3) at frame 11 Thu May 6 17:30:11 [27887] DEBUG: change internal frame rate to handle H.264 interlaced video Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 15250 (5min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 50 Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 3300s ( 55min 0s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 15250 ( 5min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 180250 ( 60min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 39250 ( 13min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 210250 ( 70min 5s) Thu May 6 17:30:11 [27887] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 4:3, reload logo Thu May 6 17:30:11 [27887] DEBUG: cMarkAdLogo::Load(): try to find logo TLC_HD-A4_3 plane 0 in /opt/vdr/plugins/markad Thu May 6 17:30:11 [27887] DEBUG: cMarkAdLogo::Load(): file not found for logo TLC_HD-A4_3 plane 0 in /opt/vdr/plugins/markad Thu May 6 17:30:11 [27887] DEBUG: cMarkAdLogo::Load(): try to find logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:30:11 [27887] DEBUG: cMarkAdLogo::Load(): file not found for logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:30:11 [27887] INFO: no valid logo for TLC_HD-A4_3 in logo cache and recording directory, extract logo from recording Thu May 6 17:30:11 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 0 with aspect ratio 4:3 Thu May 6 17:30:11 [27887] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (-1) minFrame (0) Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Thu May 6 17:30:11 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:30:11 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu May 6 17:30:11 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu May 6 17:30:11 [27887] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::SearchLogo(): video resolution 1440x1080 Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::SearchLogo(): logo size 340x220 Thu May 6 17:30:11 [27887] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu May 6 17:30:11 [27887] DEBUG: cExtractLogo::AudioInBroadcast(): got first time 2 channel at frame (11) Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): 1001 valid frames of 1001 frames read, got enough iFrames at frame (58969), start analyze Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 29945 with 242 similars out of 369 valid frames at TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 7 valid frames at TOP_RIGHT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 3457 with 17 similars out of 50 valid frames at BOTTOM_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 9 valid frames at BOTTOM_RIGHT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): best corner is TOP_LEFT at frame 29945 with 242 similars Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::Resize(): logo size before resize: 340 width 220 height on corner TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::CutOut(): cut out 108p lines horizontal and 0p column vertical Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 340 width 112 height on corner TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 156p column vertical Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 184 width 112 height on corner TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::Resize(): logo size after 1. resize: 184 width 112 height on corner TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 184 width 112 height on corner TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 184 width 112 height on corner TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::Resize(): logo size after 2. resize: 184 width 112 height on corner TOP_LEFT Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::CheckLogoSize(): no logo size rules for 1440x1080 Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::Resize(): video 1440x1080 with logo size 184 width 112 height on corner TOP_LEFT is not valid Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): resize logo from best corner failed Thu May 6 17:30:35 [27887] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 58969 Thu May 6 17:30:35 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:30:35 [27887] DEBUG: cMarkAdLogo::Process(): no logo found in recording Thu May 6 17:30:35 [27887] DEBUG: cMarkAdLogo::Load(): try to find logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:30:35 [27887] DEBUG: cMarkAdLogo::Load(): file not found for logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:30:35 [27887] INFO: still no valid logo for TLC_HD-A4_3 in recording directory Thu May 6 17:30:35 [27887] DEBUG: cMarkAdLogo::Process(): no valid logo found for aspect ratio 4:3, disable logo detection Thu May 6 17:30:35 [27887] DEBUG: detected end of black screen (0)* at 0:00:00.00 inBroadCast: 0 Thu May 6 17:30:36 [27887] DEBUG: detected start of black screen (3976) at 0:01:19.51 inBroadCast: 0 Thu May 6 17:30:36 [27887] DEBUG: detected end of black screen (4016)* at 0:01:20.31 inBroadCast: 0 Thu May 6 17:30:39 [27887] DEBUG: detected start of black screen (18208) at 0:06:04.16 inBroadCast: 0 Thu May 6 17:30:39 [27887] DEBUG: detected end of black screen (18264)* at 0:06:05.27 inBroadCast: 0 Thu May 6 17:30:39 [27887] DEBUG: detected start of black screen (18808) at 0:06:16.16 inBroadCast: 0 Thu May 6 17:30:39 [27887] DEBUG: detected end of black screen (18896)* at 0:06:17.92 inBroadCast: 0 Thu May 6 17:30:39 [27887] DEBUG: detected start of black screen (19176) at 0:06:23.51 inBroadCast: 0 Thu May 6 17:30:39 [27887] DEBUG: detected end of black screen (19440)* at 0:06:28.79 inBroadCast: 0 Thu May 6 17:30:40 [27887] DEBUG: detected start of black screen (20352) at 0:06:47.04 inBroadCast: 0 Thu May 6 17:30:40 [27887] DEBUG: detected end of black screen (20584)* at 0:06:51.67 inBroadCast: 0 Thu May 6 17:30:40 [27887] DEBUG: detected start of black screen (20912) at 0:06:58.23 inBroadCast: 0 Thu May 6 17:30:40 [27887] DEBUG: detected end of black screen (20968)* at 0:06:59.35 inBroadCast: 0 Thu May 6 17:30:40 [27887] DEBUG: detected start of black screen (21976) at 0:07:19.51 inBroadCast: 0 Thu May 6 17:30:40 [27887] DEBUG: detected end of black screen (22144)* at 0:07:22.88 inBroadCast: 0 Thu May 6 17:30:50 [27887] DEBUG: detected start of black screen (66340) at 0:22:06.80 inBroadCast: 0 Thu May 6 17:30:51 [27887] DEBUG: detected end of black screen (66932)* at 0:22:18.64 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected start of black screen (81370) at 0:27:07.40 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected end of black screen (81426)* at 0:27:08.52 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected start of black screen (82330) at 0:27:26.59 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected end of black screen (82386)* at 0:27:27.71 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected start of black screen (83378) at 0:27:47.56 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected end of black screen (83442)* at 0:27:48.83 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected start of black screen (84114) at 0:28:02.28 inBroadCast: 0 Thu May 6 17:30:54 [27887] DEBUG: detected end of black screen (84170)* at 0:28:03.40 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected start of black screen (84602) at 0:28:12.04 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected end of black screen (84666)* at 0:28:13.31 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected start of black screen (85626) at 0:28:32.52 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected end of black screen (85682)* at 0:28:33.64 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected start of black screen (86106) at 0:28:42.11 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected end of black screen (86194)* at 0:28:43.88 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected start of black screen (86578) at 0:28:51.56 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected end of black screen (86618)* at 0:28:52.35 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected start of black screen (87162) at 0:29:03.23 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected end of black screen (87218)* at 0:29:04.35 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected start of black screen (88542) at 0:29:30.83 inBroadCast: 0 Thu May 6 17:30:55 [27887] DEBUG: detected end of black screen (88718)* at 0:29:34.35 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected start of black screen (89158) at 0:29:43.16 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected end of black screen (89230)* at 0:29:44.59 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected start of black screen (89902) at 0:29:58.04 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected end of black screen (89958)* at 0:29:59.16 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected start of black screen (90670) at 0:30:13.40 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected end of black screen (90726)* at 0:30:14.52 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected start of black screen (92178) at 0:30:43.56 inBroadCast: 0 Thu May 6 17:30:56 [27887] DEBUG: detected end of black screen (92242)* at 0:30:44.83 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected start of black screen (94202) at 0:31:24.04 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected end of black screen (94258)* at 0:31:25.16 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected start of black screen (94730) at 0:31:34.59 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected end of black screen (94790)* at 0:31:35.71 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected start of black screen (95678) at 0:31:53.56 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected end of black screen (95734)* at 0:31:54.68 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected start of black screen (96430) at 0:32:08.59 inBroadCast: 0 Thu May 6 17:30:57 [27887] DEBUG: detected end of black screen (96486)* at 0:32:09.71 inBroadCast: 0 Thu May 6 17:30:58 [27887] DEBUG: detected start of black screen (98912) at 0:32:58.23 inBroadCast: 0 Thu May 6 17:30:58 [27887] DEBUG: detected end of black screen (98952)* at 0:32:59.04 inBroadCast: 0 Thu May 6 17:31:13 [27887] DEBUG: detected start of black screen (134108) at 0:44:42.04 inBroadCast: 0 Thu May 6 17:31:13 [27887] DEBUG: detected end of black screen (134122)* at 0:44:42.43 inBroadCast: 0 Thu May 6 17:31:13 [27887] DEBUG: detected start of black screen (135370) at 0:45:07.39 inBroadCast: 0 Thu May 6 17:31:13 [27887] DEBUG: detected end of black screen (135426)* at 0:45:08.52 inBroadCast: 0 Thu May 6 17:31:14 [27887] DEBUG: detected start of black screen (136602) at 0:45:32.04 inBroadCast: 0 Thu May 6 17:31:14 [27887] DEBUG: detected end of black screen (136674)* at 0:45:33.47 inBroadCast: 0 Thu May 6 17:31:14 [27887] DEBUG: detected start of black screen (138114) at 0:46:02.28 inBroadCast: 0 Thu May 6 17:31:14 [27887] DEBUG: detected end of black screen (138162)* at 0:46:03.23 inBroadCast: 0 Thu May 6 17:31:14 [27887] DEBUG: detected start of black screen (138642) at 0:46:12.84 inBroadCast: 0 Thu May 6 17:31:14 [27887] DEBUG: detected end of black screen (138674)* at 0:46:13.47 inBroadCast: 0 Thu May 6 17:31:14 [27887] DEBUG: detected start of black screen (139154) at 0:46:23.08 inBroadCast: 0 Thu May 6 17:31:15 [27887] DEBUG: detected end of black screen (139210)* at 0:46:24.19 inBroadCast: 0 Thu May 6 17:31:15 [27887] DEBUG: detected start of black screen (140650) at 0:46:53.00 inBroadCast: 0 Thu May 6 17:31:15 [27887] DEBUG: detected end of black screen (140706)* at 0:46:54.12 inBroadCast: 0 Thu May 6 17:31:15 [27887] DEBUG: detected start of black screen (141130) at 0:47:02.60 inBroadCast: 0 Thu May 6 17:31:15 [27887] DEBUG: detected end of black screen (141170)* at 0:47:03.39 inBroadCast: 0 Thu May 6 17:31:16 [27887] DEBUG: detected start of black screen (141914) at 0:47:18.28 inBroadCast: 0 Thu May 6 17:31:16 [27887] DEBUG: detected end of black screen (141970)* at 0:47:19.39 inBroadCast: 0 Thu May 6 17:31:16 [27887] DEBUG: detected start of black screen (143170) at 0:47:43.39 inBroadCast: 0 Thu May 6 17:31:16 [27887] DEBUG: detected end of black screen (143226)* at 0:47:44.52 inBroadCast: 0 Thu May 6 17:31:16 [27887] DEBUG: detected start of black screen (143634) at 0:47:52.67 inBroadCast: 0 Thu May 6 17:31:16 [27887] DEBUG: detected end of black screen (143674)* at 0:47:53.47 inBroadCast: 0 Thu May 6 17:31:17 [27887] DEBUG: detected start of black screen (144594) at 0:48:11.87 inBroadCast: 0 Thu May 6 17:31:17 [27887] DEBUG: detected end of black screen (144646)* at 0:48:12.84 inBroadCast: 0 Thu May 6 17:31:17 [27887] DEBUG: detected start of black screen (144894) at 0:48:17.87 inBroadCast: 0 Thu May 6 17:31:17 [27887] DEBUG: detected end of black screen (144910)* at 0:48:18.19 inBroadCast: 0 Thu May 6 17:31:17 [27887] DEBUG: detected start of black screen (145934) at 0:48:38.67 inBroadCast: 0 Thu May 6 17:31:17 [27887] DEBUG: detected end of black screen (146022)* at 0:48:40.43 inBroadCast: 0 Thu May 6 17:31:18 [27887] DEBUG: detected start of black screen (146446) at 0:48:48.91 inBroadCast: 0 Thu May 6 17:31:18 [27887] DEBUG: detected end of black screen (146518)* at 0:48:50.36 inBroadCast: 0 Thu May 6 17:31:18 [27887] DEBUG: detected start of black screen (147238) at 0:49:04.76 inBroadCast: 0 Thu May 6 17:31:18 [27887] DEBUG: detected end of black screen (147294)* at 0:49:05.87 inBroadCast: 0 Thu May 6 17:31:18 [27887] DEBUG: detected start of black screen (148170) at 0:49:23.39 inBroadCast: 0 Thu May 6 17:31:18 [27887] DEBUG: detected end of black screen (148226)* at 0:49:24.52 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected start of black screen (148986) at 0:49:39.71 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected end of black screen (149034)* at 0:49:40.67 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected start of black screen (150002) at 0:50:00.04 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected end of black screen (150058)* at 0:50:01.15 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected start of black screen (150722) at 0:50:14.43 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected end of black screen (150754)* at 0:50:15.08 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected start of black screen (151058) at 0:50:21.15 inBroadCast: 0 Thu May 6 17:31:19 [27887] DEBUG: detected end of black screen (151114)* at 0:50:22.28 inBroadCast: 0 Thu May 6 17:31:20 [27887] DEBUG: detected start of black screen (152574) at 0:50:51.47 inBroadCast: 0 Thu May 6 17:31:20 [27887] DEBUG: detected end of black screen (152614)* at 0:50:52.28 inBroadCast: 0 Thu May 6 17:31:24 [27887] DEBUG: detected start of black screen (162542) at 0:54:10.84 inBroadCast: 0 Thu May 6 17:31:24 [27887] DEBUG: detected end of black screen (162670)* at 0:54:13.39 inBroadCast: 0 Thu May 6 17:31:26 [27887] INFO: restart logo and black screen detection at frame (168255) Thu May 6 17:31:26 [27887] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 4:3, reload logo Thu May 6 17:31:26 [27887] DEBUG: cMarkAdLogo::Load(): try to find logo TLC_HD-A4_3 plane 0 in /opt/vdr/plugins/markad Thu May 6 17:31:26 [27887] DEBUG: cMarkAdLogo::Load(): file not found for logo TLC_HD-A4_3 plane 0 in /opt/vdr/plugins/markad Thu May 6 17:31:26 [27887] DEBUG: cMarkAdLogo::Load(): try to find logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:31:26 [27887] DEBUG: cMarkAdLogo::Load(): file not found for logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:31:26 [27887] INFO: no valid logo for TLC_HD-A4_3 in logo cache and recording directory, extract logo from recording Thu May 6 17:31:26 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 168254 with aspect ratio 4:3 Thu May 6 17:31:26 [27887] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (-1) minFrame (0) Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 168254 Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Thu May 6 17:31:26 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:31:26 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu May 6 17:31:26 [27887] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu May 6 17:31:26 [27887] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::SearchLogo(): video resolution 1440x1080 Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::SearchLogo(): logo size 340x220 Thu May 6 17:31:26 [27887] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::SearchLogo(): seek to frame 168254 Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (11) minFrame (168254) Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803536 bytes, system time 06-05-2021 17:31:26 index time 06-05-2021 17:26:53, wait 60s Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (11), old or interrupted recording Thu May 6 17:31:26 [27887] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() for startFrame 168254 failed Thu May 6 17:31:26 [27887] DEBUG: cDecoder::SeekToFrame(): (168254) Thu May 6 17:31:43 [27887] DEBUG: cDecoder::SeekToFrame(): successful Thu May 6 17:31:43 [27887] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (100442) read frames (168255) minFrame (0) Thu May 6 17:31:43 [27887] DEBUG: cExtractLogo::WaitForFrames(): index file size 803536 bytes, system time 06-05-2021 17:31:43 index time 06-05-2021 17:26:53, wait 60s Thu May 6 17:31:43 [27887] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (168255), old or interrupted recording Thu May 6 17:31:43 [27887] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (168255), got 0 valid frames of 0 frames read Thu May 6 17:31:43 [27887] DEBUG: cExtractLogo::SearchLogo(): read (0) frames and could not get enough valid frames (0) Thu May 6 17:31:43 [27887] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 11 Thu May 6 17:31:43 [27887] DEBUG: ---------------------------------------------------------------------------- Thu May 6 17:31:43 [27887] DEBUG: cMarkAdLogo::Process(): no logo found in recording Thu May 6 17:31:43 [27887] DEBUG: cMarkAdLogo::Load(): try to find logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:31:43 [27887] DEBUG: cMarkAdLogo::Load(): file not found for logo TLC_HD-A4_3 plane 0 in /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec Thu May 6 17:31:43 [27887] INFO: still no valid logo for TLC_HD-A4_3 in recording directory Thu May 6 17:31:43 [27887] DEBUG: cMarkAdLogo::Process(): no valid logo found for aspect ratio 4:3, disable logo detection Thu May 6 17:31:43 [27887] DEBUG: detected end of black screen (168254)* at 0:56:05.08 inBroadCast: 0 Thu May 6 17:31:50 [27887] DEBUG: detected start of black screen (182122) at 1:00:42.43 inBroadCast: 0 Thu May 6 17:31:50 [27887] DEBUG: detected end of black screen (182306)* at 1:00:46.12 inBroadCast: 0 Thu May 6 17:31:54 [27887] DEBUG: detected start of black screen (191626) at 1:03:52.52 inBroadCast: 0 Thu May 6 17:31:54 [27887] DEBUG: detected end of black screen (191682)* at 1:03:53.63 inBroadCast: 0 Thu May 6 17:31:54 [27887] DEBUG: detected start of black screen (192234) at 1:04:04.67 inBroadCast: 0 Thu May 6 17:31:54 [27887] DEBUG: detected end of black screen (192322)* at 1:04:06.43 inBroadCast: 0 Thu May 6 17:31:55 [27887] DEBUG: detected start of black screen (192602) at 1:04:12.04 inBroadCast: 0 Thu May 6 17:31:55 [27887] DEBUG: detected end of black screen (192866)* at 1:04:17.32 inBroadCast: 0 Thu May 6 17:31:55 [27887] DEBUG: detected start of black screen (193778) at 1:04:35.56 inBroadCast: 0 Thu May 6 17:31:55 [27887] DEBUG: detected end of black screen (194010)* at 1:04:40.19 inBroadCast: 0 Thu May 6 17:31:56 [27887] DEBUG: detected start of black screen (194338) at 1:04:46.76 inBroadCast: 0 Thu May 6 17:31:56 [27887] DEBUG: detected end of black screen (194394)* at 1:04:47.87 inBroadCast: 0 Thu May 6 17:32:00 [27887] DEBUG: AVlog(): error while decoding MB 89 32, bytestream -9 Thu May 6 17:32:00 [27887] DEBUG: cDecoder::GetNextFrame(): last frame of filenumber 1 is (200882), end time 4017640ms ( 66:57min) Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::ProcessFiles(): recording ends unexpected before chkSTART (39250) at frame 200882 Thu May 6 17:32:00 [27887] INFO: got end of recording before recording length from info file reached Thu May 6 17:32:00 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): checking start at frame (200882) check start planed at (39250) Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): assumed start frame 15250 Thu May 6 17:32:00 [27887] DEBUG: ************************************************************* Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:00 [27887] DEBUG: mark at position 0 type 0x21 at 0:00:00.00 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 3976 type 0x22 at 0:01:19.51 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 4016 type 0x21 at 0:01:20.31 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18208 type 0x22 at 0:06:04.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18808 type 0x22 at 0:06:16.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18896 type 0x21 at 0:06:17.92 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 19176 type 0x22 at 0:06:23.51 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 19440 type 0x21 at 0:06:28.79 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20352 type 0x22 at 0:06:47.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20584 type 0x21 at 0:06:51.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20912 type 0x22 at 0:06:58.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20968 type 0x21 at 0:06:59.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 21976 type 0x22 at 0:07:19.51 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 22144 type 0x21 at 0:07:22.88 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 66340 type 0x22 at 0:22:06.80 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 66932 type 0x21 at 0:22:18.64 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 81370 type 0x22 at 0:27:07.40 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 81426 type 0x21 at 0:27:08.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 82330 type 0x22 at 0:27:26.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 82386 type 0x21 at 0:27:27.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 83378 type 0x22 at 0:27:47.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 83442 type 0x21 at 0:27:48.83 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84114 type 0x22 at 0:28:02.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84170 type 0x21 at 0:28:03.40 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84602 type 0x22 at 0:28:12.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84666 type 0x21 at 0:28:13.31 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 85626 type 0x22 at 0:28:32.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 85682 type 0x21 at 0:28:33.64 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86106 type 0x22 at 0:28:42.11 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86194 type 0x21 at 0:28:43.88 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86578 type 0x22 at 0:28:51.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86618 type 0x21 at 0:28:52.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 87162 type 0x22 at 0:29:03.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 87218 type 0x21 at 0:29:04.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 88542 type 0x22 at 0:29:30.83 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 88718 type 0x21 at 0:29:34.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89158 type 0x22 at 0:29:43.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89230 type 0x21 at 0:29:44.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89902 type 0x22 at 0:29:58.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89958 type 0x21 at 0:29:59.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 90670 type 0x22 at 0:30:13.40 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 90726 type 0x21 at 0:30:14.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 92178 type 0x22 at 0:30:43.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 92242 type 0x21 at 0:30:44.83 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94202 type 0x22 at 0:31:24.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94258 type 0x21 at 0:31:25.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94730 type 0x22 at 0:31:34.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94790 type 0x21 at 0:31:35.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 95678 type 0x22 at 0:31:53.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 95734 type 0x21 at 0:31:54.68 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 96430 type 0x22 at 0:32:08.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 96486 type 0x21 at 0:32:09.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 98912 type 0x22 at 0:32:58.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 98952 type 0x21 at 0:32:59.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 134108 type 0x22 at 0:44:42.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 134122 type 0x21 at 0:44:42.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 135370 type 0x22 at 0:45:07.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 135426 type 0x21 at 0:45:08.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 136602 type 0x22 at 0:45:32.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 136674 type 0x21 at 0:45:33.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138114 type 0x22 at 0:46:02.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138162 type 0x21 at 0:46:03.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138642 type 0x22 at 0:46:12.84 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138674 type 0x21 at 0:46:13.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 139154 type 0x22 at 0:46:23.08 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 139210 type 0x21 at 0:46:24.19 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 140650 type 0x22 at 0:46:53.00 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 140706 type 0x21 at 0:46:54.12 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141130 type 0x22 at 0:47:02.60 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141170 type 0x21 at 0:47:03.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141914 type 0x22 at 0:47:18.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141970 type 0x21 at 0:47:19.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143170 type 0x22 at 0:47:43.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143226 type 0x21 at 0:47:44.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143634 type 0x22 at 0:47:52.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143674 type 0x21 at 0:47:53.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144594 type 0x22 at 0:48:11.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144646 type 0x21 at 0:48:12.84 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144894 type 0x22 at 0:48:17.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144910 type 0x21 at 0:48:18.19 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 145934 type 0x22 at 0:48:38.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 146022 type 0x21 at 0:48:40.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 146446 type 0x22 at 0:48:48.91 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 146518 type 0x21 at 0:48:50.36 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 147238 type 0x22 at 0:49:04.76 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 147294 type 0x21 at 0:49:05.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 148170 type 0x22 at 0:49:23.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 148226 type 0x21 at 0:49:24.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 148986 type 0x22 at 0:49:39.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 149034 type 0x21 at 0:49:40.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150002 type 0x22 at 0:50:00.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150058 type 0x21 at 0:50:01.15 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150722 type 0x22 at 0:50:14.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150754 type 0x21 at 0:50:15.08 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 151058 type 0x22 at 0:50:21.15 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 151114 type 0x21 at 0:50:22.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 152574 type 0x22 at 0:50:51.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 152614 type 0x21 at 0:50:52.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 162542 type 0x22 at 0:54:10.84 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 162670 type 0x21 at 0:54:13.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 168254 type 0x21 at 0:56:05.08 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 182122 type 0x22 at 1:00:42.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 182306 type 0x21 at 1:00:46.12 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 191626 type 0x22 at 1:03:52.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 191682 type 0x21 at 1:03:53.63 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192234 type 0x22 at 1:04:04.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192322 type 0x21 at 1:04:06.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192602 type 0x22 at 1:04:12.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192866 type 0x21 at 1:04:17.32 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 193778 type 0x22 at 1:04:35.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 194010 type 0x21 at 1:04:40.19 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 194338 type 0x22 at 1:04:46.76 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 194394 type 0x21 at 1:04:47.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: ************************************************************* Thu May 6 17:32:00 [27887] INFO: broadcast with 2 audio channels of stream 1 Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): we are not in broadcast at frame (200882), trying to find channel start mark anyway Thu May 6 17:32:00 [27887] INFO: video aspect description in info (16:9) wrong, correct to (4:3) Thu May 6 17:32:00 [27887] INFO: HD Video with aspectratio of 4:3 detected Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): no horizontal border at start found, ignore horizontal border detection Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): no vertical border at start found, ignore vertical border detection Thu May 6 17:32:00 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): only 0 logo start mark and 0 logo stop marks found, do not delete any Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Thu May 6 17:32:00 [27887] DEBUG: ************************************************************* Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:00 [27887] DEBUG: mark at position 0 type 0x21 at 0:00:00.00 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 3976 type 0x22 at 0:01:19.51 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 4016 type 0x21 at 0:01:20.31 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18208 type 0x22 at 0:06:04.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18808 type 0x22 at 0:06:16.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 18896 type 0x21 at 0:06:17.92 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 19176 type 0x22 at 0:06:23.51 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 19440 type 0x21 at 0:06:28.79 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20352 type 0x22 at 0:06:47.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20584 type 0x21 at 0:06:51.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20912 type 0x22 at 0:06:58.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 20968 type 0x21 at 0:06:59.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 21976 type 0x22 at 0:07:19.51 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 22144 type 0x21 at 0:07:22.88 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 66340 type 0x22 at 0:22:06.80 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 66932 type 0x21 at 0:22:18.64 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 81370 type 0x22 at 0:27:07.40 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 81426 type 0x21 at 0:27:08.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 82330 type 0x22 at 0:27:26.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 82386 type 0x21 at 0:27:27.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 83378 type 0x22 at 0:27:47.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 83442 type 0x21 at 0:27:48.83 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84114 type 0x22 at 0:28:02.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84170 type 0x21 at 0:28:03.40 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84602 type 0x22 at 0:28:12.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 84666 type 0x21 at 0:28:13.31 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 85626 type 0x22 at 0:28:32.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 85682 type 0x21 at 0:28:33.64 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86106 type 0x22 at 0:28:42.11 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86194 type 0x21 at 0:28:43.88 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86578 type 0x22 at 0:28:51.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 86618 type 0x21 at 0:28:52.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 87162 type 0x22 at 0:29:03.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 87218 type 0x21 at 0:29:04.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 88542 type 0x22 at 0:29:30.83 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 88718 type 0x21 at 0:29:34.35 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89158 type 0x22 at 0:29:43.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89230 type 0x21 at 0:29:44.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89902 type 0x22 at 0:29:58.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 89958 type 0x21 at 0:29:59.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 90670 type 0x22 at 0:30:13.40 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 90726 type 0x21 at 0:30:14.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 92178 type 0x22 at 0:30:43.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 92242 type 0x21 at 0:30:44.83 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94202 type 0x22 at 0:31:24.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94258 type 0x21 at 0:31:25.16 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94730 type 0x22 at 0:31:34.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 94790 type 0x21 at 0:31:35.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 95678 type 0x22 at 0:31:53.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 95734 type 0x21 at 0:31:54.68 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 96430 type 0x22 at 0:32:08.59 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 96486 type 0x21 at 0:32:09.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 98912 type 0x22 at 0:32:58.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 98952 type 0x21 at 0:32:59.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 134108 type 0x22 at 0:44:42.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 134122 type 0x21 at 0:44:42.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 135370 type 0x22 at 0:45:07.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 135426 type 0x21 at 0:45:08.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 136602 type 0x22 at 0:45:32.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 136674 type 0x21 at 0:45:33.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138114 type 0x22 at 0:46:02.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138162 type 0x21 at 0:46:03.23 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138642 type 0x22 at 0:46:12.84 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 138674 type 0x21 at 0:46:13.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 139154 type 0x22 at 0:46:23.08 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 139210 type 0x21 at 0:46:24.19 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 140650 type 0x22 at 0:46:53.00 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 140706 type 0x21 at 0:46:54.12 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141130 type 0x22 at 0:47:02.60 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141170 type 0x21 at 0:47:03.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141914 type 0x22 at 0:47:18.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 141970 type 0x21 at 0:47:19.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143170 type 0x22 at 0:47:43.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143226 type 0x21 at 0:47:44.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143634 type 0x22 at 0:47:52.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 143674 type 0x21 at 0:47:53.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144594 type 0x22 at 0:48:11.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144646 type 0x21 at 0:48:12.84 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144894 type 0x22 at 0:48:17.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 144910 type 0x21 at 0:48:18.19 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 145934 type 0x22 at 0:48:38.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 146022 type 0x21 at 0:48:40.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 146446 type 0x22 at 0:48:48.91 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 146518 type 0x21 at 0:48:50.36 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 147238 type 0x22 at 0:49:04.76 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 147294 type 0x21 at 0:49:05.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 148170 type 0x22 at 0:49:23.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 148226 type 0x21 at 0:49:24.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 148986 type 0x22 at 0:49:39.71 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 149034 type 0x21 at 0:49:40.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150002 type 0x22 at 0:50:00.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150058 type 0x21 at 0:50:01.15 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150722 type 0x22 at 0:50:14.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 150754 type 0x21 at 0:50:15.08 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 151058 type 0x22 at 0:50:21.15 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 151114 type 0x21 at 0:50:22.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 152574 type 0x22 at 0:50:51.47 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 152614 type 0x21 at 0:50:52.28 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 162542 type 0x22 at 0:54:10.84 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 162670 type 0x21 at 0:54:13.39 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 168254 type 0x21 at 0:56:05.08 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 182122 type 0x22 at 1:00:42.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 182306 type 0x21 at 1:00:46.12 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 191626 type 0x22 at 1:03:52.52 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 191682 type 0x21 at 1:03:53.63 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192234 type 0x22 at 1:04:04.67 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192322 type 0x21 at 1:04:06.43 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192602 type 0x22 at 1:04:12.04 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 192866 type 0x21 at 1:04:17.32 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 193778 type 0x22 at 1:04:35.56 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 194010 type 0x21 at 1:04:40.19 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 194338 type 0x22 at 1:04:46.76 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: mark at position 194394 type 0x21 at 1:04:47.87 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: ************************************************************* Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Thu May 6 17:32:00 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): no logo start mark found Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): found start mark (18264) type 0x21 after search for any type Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): found start mark (18264) type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 18264 (6min 5s) Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 50 Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 3300s ( 55min 0s) Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 18264 ( 6min 5s) Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 183264 ( 61min 5s) Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 42264 ( 14min 5s) Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 213264 ( 71min 5s) Thu May 6 17:32:00 [27887] INFO: using mark on position (18264) type 0x21 at 0:06:05.27 as start mark Thu May 6 17:32:00 [27887] DEBUG: cMarkAdStandalone::CheckStart(): delete all black screen marks except start mark Thu May 6 17:32:00 [27887] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::ProcessFiles(): recording ends unexpected before chkSTOP (213264) at frame 200882 Thu May 6 17:32:01 [27887] INFO: got end of recording before recording length from info file reached Thu May 6 17:32:01 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): start check stop (200882) Thu May 6 17:32:01 [27887] DEBUG: assumed stop position (183264) at 1:01:05.63 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): only 0 logo start mark and 0 logo stop marks found, do not delete any Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no MT_CHANNELSTOP mark found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no MT_ASPECTSTOP mark found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no MT_HBORDERSTOP mark found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no MT_VBORDERSTOP mark found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no logo stop mark found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no MT_LOGOSTOP mark found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no end mark found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): no stop mark found, add stop mark at the last frame (200882) Thu May 6 17:32:01 [27887] INFO: assuming stop (200882) at 1:06:57.63 inBroadCast: 0 Thu May 6 17:32:01 [27887] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): move all black screen marks except start and end mark to black screen list Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckStop(): end check stop Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): delete logo marks if we have channel or border marks Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): delete invalid black sceen marks Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): delete very short logo stop/start pairs Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): detect previews in advertisement Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): remove logo and hborder detection failure marks Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): remove invalid marks Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): apply VPS events Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): no VPS start event found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): no VPS pause start event found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): no VPS pause stop event found Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): no VPS stop event found Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::CheckMarks(): final marks: Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu May 6 17:32:01 [27887] DEBUG: mark at position 18264 type 0x21 at 0:06:05.27 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: mark at position 200882 type 0x12 at 1:06:57.63 inBroadCast 0 Thu May 6 17:32:01 [27887] DEBUG: ************************************************************* Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::ProcessFiles(): end processing files Thu May 6 17:32:01 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:32:01 [27887] INFO: start 2nd pass (detect overlaps) Thu May 6 17:32:01 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:32:01 [27887] DEBUG: end 2ndPass Thu May 6 17:32:01 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:32:01 [27887] INFO: start 3nd pass (optimze logo marks) Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::Process3ndPass(): check last logo stop mark if closing credits follows Thu May 6 17:32:01 [27887] DEBUG: ======================================================================================================================= Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::Process3ndPass(): check for advertising in frame with logo after logo start and before logo stop mark and check for introduction logo Thu May 6 17:32:01 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:32:01 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:01 [27887] DEBUG: cMarkAdStandalone::Process3ndPass(): search for audio silence around logo marks Thu May 6 17:32:01 [27887] DEBUG: cDecoder::DecodeFile(): start decode file /media/hd/recordings/Mein_Leben_mit_300_kg/Tamy_Lyn_-_Teil_1/2021-05-06.16.20.35-0.rec/00001.ts Thu May 6 17:32:02 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:02 [27887] DEBUG: cMarkAdStandalone::Process3ndPass(): start search for blackscreen near logo marks Thu May 6 17:32:02 [27887] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=1 Thu May 6 17:32:02 [27887] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu May 6 17:32:02 [27887] DEBUG: time for decoding: 126s 885ms Thu May 6 17:32:02 [27887] DEBUG: time to find logo in recording: 4239s 972ms Thu May 6 17:32:02 [27887] INFO: pass 1: time 110s 081ms, frames 200882, fps 1826 Thu May 6 17:32:02 [27887] INFO: processed time 71:59 min with 46.5 fps