Sun May 9 19:20:02 [1124] INFO: starting markad v3.0.1 (64bit) Sun May 9 19:20:02 [1124] INFO: using libavcodec.so.58.54.100 with -1 threads Sun May 9 19:20:02 [1124] INFO: on /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 19:20:12 [1124] DEBUG: parameter before is set, markad is called with a running recording Sun May 9 19:20:12 [1124] INFO: broadcast aspect ratio 4:3 (from vdr info) Sun May 9 19:20:12 [1124] DEBUG: cMarkAdStandalone::GetBroadcastStart(): mount option noatime is not set Sun May 9 19:20:12 [1124] DEBUG: cMarkAdStandalone::GetBroadcastStart(): getting recording start from VDR info file modification time Sun May 9 19:20:00 2021 Sun May 9 19:20:12 [1124] DEBUG: cMarkAdStandalone::LoadInfo(): recording start at Sun May 9 19:20:00 2021 Sun May 9 19:20:12 [1124] DEBUG: cMarkAdStandalone::LoadInfo(): timer start at Sun May 9 19:25:50 2021 Sun May 9 19:20:12 [1124] DEBUG: cMarkAdStandalone::LoadInfo(): broadcast start 350s after recording start Sun May 9 19:20:12 [1124] DEBUG: cMarkAdStandalone::CheckLogo(): using logo directory /tmp Sun May 9 19:20:12 [1124] DEBUG: cMarkAdStandalone::CheckLogo(): searching logo for ATV2 Sun May 9 19:20:12 [1124] INFO: no logo found in logo directory, trying to find logo in recording directory Sun May 9 19:20:12 [1124] INFO: no logo found in recording directory, trying to extract logo from recording Sun May 9 19:20:12 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 8750 with aspect ratio 4:3 Sun May 9 19:20:12 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (200) read frames (-1) minFrame (0) Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 8750 Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sun May 9 19:20:12 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 19:20:12 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 19:20:12 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 19:20:12 [1124] DEBUG: cDecoder::GetVideoType(): video coding format: H.262 Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::SearchLogo(): video resolution 720x576 Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::SearchLogo(): logo size 230x130 Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (211) read frames (0) minFrame (0) Sun May 9 19:20:12 [1124] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sun May 9 19:20:12 [1124] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 3 Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::SearchLogo(): seek to frame 8750 Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (211) read frames (3) minFrame (8750) Sun May 9 19:20:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 1688 bytes, system time 09-05-2021 19:20:12 index time 09-05-2021 19:20:12, wait 60s Sun May 9 19:21:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (1714) read frames (3) minFrame (8750) Sun May 9 19:21:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 13712 bytes, system time 09-05-2021 19:21:12 index time 09-05-2021 19:21:12, wait 60s Sun May 9 19:22:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (3209) read frames (3) minFrame (8750) Sun May 9 19:22:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 25672 bytes, system time 09-05-2021 19:22:12 index time 09-05-2021 19:22:12, wait 60s Sun May 9 19:23:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (4705) read frames (3) minFrame (8750) Sun May 9 19:23:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 37640 bytes, system time 09-05-2021 19:23:12 index time 09-05-2021 19:23:12, wait 60s Sun May 9 19:24:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (6210) read frames (3) minFrame (8750) Sun May 9 19:24:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 49680 bytes, system time 09-05-2021 19:24:12 index time 09-05-2021 19:24:12, wait 60s Sun May 9 19:25:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (7715) read frames (3) minFrame (8750) Sun May 9 19:25:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 61720 bytes, system time 09-05-2021 19:25:12 index time 09-05-2021 19:25:12, wait 60s Sun May 9 19:26:12 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (9213) read frames (3) minFrame (8750) Sun May 9 19:26:12 [1124] DEBUG: cDecoder::SeekToFrame(): (8750) Sun May 9 19:26:12 [1124] DEBUG: cIndex::GetIFrameBefore(): failed for frame (8750), index: first frame (0) last frame (0) Sun May 9 19:26:12 [1124] DEBUG: cDecoder::SeekFrame(): index does not yet contain frame ( 8750), decode from current frame (3) to build index Sun May 9 19:26:13 [1124] DEBUG: cDecoder::SeekToFrame(): successful Sun May 9 19:26:13 [1124] DEBUG: cExtractLogo::AudioInBroadcast(): got first time 2 channel at frame (8759) Sun May 9 19:26:13 [1124] DEBUG: cExtractLogo::SearchLogo(): recording is aktive, read frame (8759), now save dummy start mark at pre timer position 350s Sun May 9 19:26:13 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=1 Sun May 9 19:26:13 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (9235) read frames (9013) minFrame (0) Sun May 9 19:26:13 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (9235) read frames (9035) minFrame (0) Sun May 9 19:26:13 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 73880 bytes, system time 09-05-2021 19:26:13 index time 09-05-2021 19:26:13, wait 60s Sun May 9 19:27:13 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (10737) read frames (9035) minFrame (0) Sun May 9 19:27:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (10743) read frames (10537) minFrame (0) Sun May 9 19:27:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (10743) read frames (10543) minFrame (0) Sun May 9 19:27:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 85944 bytes, system time 09-05-2021 19:27:14 index time 09-05-2021 19:27:14, wait 60s Sun May 9 19:28:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12240) read frames (10543) minFrame (0) Sun May 9 19:28:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12243) read frames (12040) minFrame (0) Sun May 9 19:28:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12244) read frames (12043) minFrame (0) Sun May 9 19:28:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (12244) read frames (12044) minFrame (0) Sun May 9 19:28:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 97952 bytes, system time 09-05-2021 19:28:14 index time 09-05-2021 19:28:14, wait 60s Sun May 9 19:29:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (13748) read frames (12044) minFrame (0) Sun May 9 19:29:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (13756) read frames (13548) minFrame (0) Sun May 9 19:29:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (13756) read frames (13556) minFrame (0) Sun May 9 19:29:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 110048 bytes, system time 09-05-2021 19:29:14 index time 09-05-2021 19:29:14, wait 60s Sun May 9 19:30:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (15257) read frames (13556) minFrame (0) Sun May 9 19:30:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (15262) read frames (15057) minFrame (0) Sun May 9 19:30:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (15262) read frames (15062) minFrame (0) Sun May 9 19:30:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 122096 bytes, system time 09-05-2021 19:30:14 index time 09-05-2021 19:30:14, wait 60s Sun May 9 19:31:14 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (16757) read frames (15062) minFrame (0) Sun May 9 19:31:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (16764) read frames (16557) minFrame (0) Sun May 9 19:31:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (16764) read frames (16564) minFrame (0) Sun May 9 19:31:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 134112 bytes, system time 09-05-2021 19:31:15 index time 09-05-2021 19:31:14, wait 60s Sun May 9 19:32:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (18273) read frames (16564) minFrame (0) Sun May 9 19:32:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (18273) read frames (18073) minFrame (0) Sun May 9 19:32:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 146184 bytes, system time 09-05-2021 19:32:15 index time 09-05-2021 19:32:14, wait 60s Sun May 9 19:33:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (19772) read frames (18073) minFrame (0) Sun May 9 19:33:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (19776) read frames (19572) minFrame (0) Sun May 9 19:33:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (19776) read frames (19576) minFrame (0) Sun May 9 19:33:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 158208 bytes, system time 09-05-2021 19:33:15 index time 09-05-2021 19:33:15, wait 60s Sun May 9 19:34:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (21274) read frames (19576) minFrame (0) Sun May 9 19:34:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (21286) read frames (21074) minFrame (0) Sun May 9 19:34:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (21286) read frames (21086) minFrame (0) Sun May 9 19:34:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 170288 bytes, system time 09-05-2021 19:34:15 index time 09-05-2021 19:34:15, wait 60s Sun May 9 19:35:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (22786) read frames (21086) minFrame (0) Sun May 9 19:35:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (22792) read frames (22586) minFrame (0) Sun May 9 19:35:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (22792) read frames (22592) minFrame (0) Sun May 9 19:35:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 182336 bytes, system time 09-05-2021 19:35:15 index time 09-05-2021 19:35:15, wait 60s Sun May 9 19:36:15 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (24292) read frames (22592) minFrame (0) Sun May 9 19:36:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (24298) read frames (24092) minFrame (0) Sun May 9 19:36:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (24298) read frames (24098) minFrame (0) Sun May 9 19:36:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 194384 bytes, system time 09-05-2021 19:36:16 index time 09-05-2021 19:36:16, wait 60s Sun May 9 19:37:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (25796) read frames (24098) minFrame (0) Sun May 9 19:37:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (25806) read frames (25596) minFrame (0) Sun May 9 19:37:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (25806) read frames (25606) minFrame (0) Sun May 9 19:37:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 206448 bytes, system time 09-05-2021 19:37:16 index time 09-05-2021 19:37:16, wait 60s Sun May 9 19:38:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (27301) read frames (25606) minFrame (0) Sun May 9 19:38:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (27304) read frames (27101) minFrame (0) Sun May 9 19:38:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (27304) read frames (27104) minFrame (0) Sun May 9 19:38:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 218432 bytes, system time 09-05-2021 19:38:16 index time 09-05-2021 19:38:16, wait 60s Sun May 9 19:39:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (28807) read frames (27104) minFrame (0) Sun May 9 19:39:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (28810) read frames (28607) minFrame (0) Sun May 9 19:39:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (28810) read frames (28610) minFrame (0) Sun May 9 19:39:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 230480 bytes, system time 09-05-2021 19:39:16 index time 09-05-2021 19:39:16, wait 60s Sun May 9 19:40:16 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (30315) read frames (28610) minFrame (0) Sun May 9 19:40:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (30317) read frames (30115) minFrame (0) Sun May 9 19:40:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (30317) read frames (30117) minFrame (0) Sun May 9 19:40:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 242536 bytes, system time 09-05-2021 19:40:17 index time 09-05-2021 19:40:16, wait 60s Sun May 9 19:41:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (31814) read frames (30117) minFrame (0) Sun May 9 19:41:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (31818) read frames (31614) minFrame (0) Sun May 9 19:41:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (31818) read frames (31618) minFrame (0) Sun May 9 19:41:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 254544 bytes, system time 09-05-2021 19:41:17 index time 09-05-2021 19:41:17, wait 60s Sun May 9 19:42:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (33319) read frames (31618) minFrame (0) Sun May 9 19:42:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (33326) read frames (33119) minFrame (0) Sun May 9 19:42:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (33326) read frames (33126) minFrame (0) Sun May 9 19:42:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 266608 bytes, system time 09-05-2021 19:42:17 index time 09-05-2021 19:42:17, wait 60s Sun May 9 19:43:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (34832) read frames (33126) minFrame (0) Sun May 9 19:43:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (34835) read frames (34632) minFrame (0) Sun May 9 19:43:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (34835) read frames (34635) minFrame (0) Sun May 9 19:43:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 278680 bytes, system time 09-05-2021 19:43:17 index time 09-05-2021 19:43:17, wait 60s Sun May 9 19:44:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (36332) read frames (34635) minFrame (0) Sun May 9 19:44:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (36340) read frames (36132) minFrame (0) Sun May 9 19:44:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (36340) read frames (36140) minFrame (0) Sun May 9 19:44:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 290720 bytes, system time 09-05-2021 19:44:17 index time 09-05-2021 19:44:17, wait 60s Sun May 9 19:45:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (37841) read frames (36140) minFrame (0) Sun May 9 19:45:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (37843) read frames (37641) minFrame (0) Sun May 9 19:45:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (37843) read frames (37643) minFrame (0) Sun May 9 19:45:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 302744 bytes, system time 09-05-2021 19:45:17 index time 09-05-2021 19:45:17, wait 60s Sun May 9 19:46:17 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (39343) read frames (37643) minFrame (0) Sun May 9 19:46:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (39347) read frames (39143) minFrame (0) Sun May 9 19:46:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (39347) read frames (39147) minFrame (0) Sun May 9 19:46:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 314776 bytes, system time 09-05-2021 19:46:18 index time 09-05-2021 19:46:18, wait 60s Sun May 9 19:47:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (40850) read frames (39147) minFrame (0) Sun May 9 19:47:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (40851) read frames (40650) minFrame (0) Sun May 9 19:47:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (40851) read frames (40651) minFrame (0) Sun May 9 19:47:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 326808 bytes, system time 09-05-2021 19:47:18 index time 09-05-2021 19:47:18, wait 60s Sun May 9 19:48:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (42351) read frames (40651) minFrame (0) Sun May 9 19:48:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (42356) read frames (42151) minFrame (0) Sun May 9 19:48:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (42356) read frames (42156) minFrame (0) Sun May 9 19:48:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 338848 bytes, system time 09-05-2021 19:48:18 index time 09-05-2021 19:48:18, wait 60s Sun May 9 19:49:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (43860) read frames (42156) minFrame (0) Sun May 9 19:49:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (43860) read frames (43660) minFrame (0) Sun May 9 19:49:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 350880 bytes, system time 09-05-2021 19:49:18 index time 09-05-2021 19:49:18, wait 60s Sun May 9 19:50:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (45357) read frames (43660) minFrame (0) Sun May 9 19:50:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (45366) read frames (45157) minFrame (0) Sun May 9 19:50:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (45366) read frames (45166) minFrame (0) Sun May 9 19:50:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 362928 bytes, system time 09-05-2021 19:50:18 index time 09-05-2021 19:50:18, wait 60s Sun May 9 19:51:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (46865) read frames (45166) minFrame (0) Sun May 9 19:51:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (46869) read frames (46665) minFrame (0) Sun May 9 19:51:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (46869) read frames (46669) minFrame (0) Sun May 9 19:51:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 374952 bytes, system time 09-05-2021 19:51:18 index time 09-05-2021 19:51:18, wait 60s Sun May 9 19:52:18 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (48371) read frames (46669) minFrame (0) Sun May 9 19:52:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (48374) read frames (48171) minFrame (0) Sun May 9 19:52:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (48374) read frames (48174) minFrame (0) Sun May 9 19:52:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 386992 bytes, system time 09-05-2021 19:52:19 index time 09-05-2021 19:52:19, wait 60s Sun May 9 19:53:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (49872) read frames (48174) minFrame (0) Sun May 9 19:53:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (49875) read frames (49672) minFrame (0) Sun May 9 19:53:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (49875) read frames (49675) minFrame (0) Sun May 9 19:53:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 399000 bytes, system time 09-05-2021 19:53:19 index time 09-05-2021 19:53:19, wait 60s Sun May 9 19:54:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (51377) read frames (49675) minFrame (0) Sun May 9 19:54:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (51380) read frames (51177) minFrame (0) Sun May 9 19:54:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (51380) read frames (51180) minFrame (0) Sun May 9 19:54:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 411040 bytes, system time 09-05-2021 19:54:19 index time 09-05-2021 19:54:19, wait 60s Sun May 9 19:55:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (52882) read frames (51180) minFrame (0) Sun May 9 19:55:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (52888) read frames (52682) minFrame (0) Sun May 9 19:55:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (52888) read frames (52688) minFrame (0) Sun May 9 19:55:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 423104 bytes, system time 09-05-2021 19:55:19 index time 09-05-2021 19:55:19, wait 60s Sun May 9 19:56:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (54385) read frames (52688) minFrame (0) Sun May 9 19:56:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (54392) read frames (54185) minFrame (0) Sun May 9 19:56:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (54392) read frames (54192) minFrame (0) Sun May 9 19:56:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 435136 bytes, system time 09-05-2021 19:56:19 index time 09-05-2021 19:56:19, wait 60s Sun May 9 19:57:19 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (55893) read frames (54192) minFrame (0) Sun May 9 19:57:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (55897) read frames (55693) minFrame (0) Sun May 9 19:57:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (55897) read frames (55697) minFrame (0) Sun May 9 19:57:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 447176 bytes, system time 09-05-2021 19:57:20 index time 09-05-2021 19:57:19, wait 60s Sun May 9 19:58:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (57399) read frames (55697) minFrame (0) Sun May 9 19:58:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (57402) read frames (57199) minFrame (0) Sun May 9 19:58:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (57402) read frames (57202) minFrame (0) Sun May 9 19:58:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 459216 bytes, system time 09-05-2021 19:58:20 index time 09-05-2021 19:58:20, wait 60s Sun May 9 19:59:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (58898) read frames (57202) minFrame (0) Sun May 9 19:59:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (58904) read frames (58698) minFrame (0) Sun May 9 19:59:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (58904) read frames (58704) minFrame (0) Sun May 9 19:59:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 471232 bytes, system time 09-05-2021 19:59:20 index time 09-05-2021 19:59:20, wait 60s Sun May 9 20:00:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (60410) read frames (58704) minFrame (0) Sun May 9 20:00:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (60417) read frames (60210) minFrame (0) Sun May 9 20:00:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (60417) read frames (60217) minFrame (0) Sun May 9 20:00:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 483336 bytes, system time 09-05-2021 20:00:20 index time 09-05-2021 20:00:20, wait 60s Sun May 9 20:01:20 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (61911) read frames (60217) minFrame (0) Sun May 9 20:01:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (61918) read frames (61711) minFrame (0) Sun May 9 20:01:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (61918) read frames (61718) minFrame (0) Sun May 9 20:01:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 495344 bytes, system time 09-05-2021 20:01:21 index time 09-05-2021 20:01:21, wait 60s Sun May 9 20:02:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (63422) read frames (61718) minFrame (0) Sun May 9 20:02:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (63430) read frames (63222) minFrame (0) Sun May 9 20:02:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (63430) read frames (63230) minFrame (0) Sun May 9 20:02:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 507440 bytes, system time 09-05-2021 20:02:21 index time 09-05-2021 20:02:21, wait 60s Sun May 9 20:03:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (64928) read frames (63230) minFrame (0) Sun May 9 20:03:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (64931) read frames (64728) minFrame (0) Sun May 9 20:03:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (64931) read frames (64731) minFrame (0) Sun May 9 20:03:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 519448 bytes, system time 09-05-2021 20:03:21 index time 09-05-2021 20:03:21, wait 60s Sun May 9 20:04:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (66433) read frames (64731) minFrame (0) Sun May 9 20:04:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (66433) read frames (66233) minFrame (0) Sun May 9 20:04:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 531464 bytes, system time 09-05-2021 20:04:21 index time 09-05-2021 20:04:21, wait 60s Sun May 9 20:05:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (67935) read frames (66233) minFrame (0) Sun May 9 20:05:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (67940) read frames (67735) minFrame (0) Sun May 9 20:05:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (67940) read frames (67740) minFrame (0) Sun May 9 20:05:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 543520 bytes, system time 09-05-2021 20:05:21 index time 09-05-2021 20:05:21, wait 60s Sun May 9 20:06:21 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (69442) read frames (67740) minFrame (0) Sun May 9 20:06:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (69446) read frames (69242) minFrame (0) Sun May 9 20:06:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (69446) read frames (69246) minFrame (0) Sun May 9 20:06:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 555568 bytes, system time 09-05-2021 20:06:22 index time 09-05-2021 20:06:22, wait 60s Sun May 9 20:07:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (70946) read frames (69246) minFrame (0) Sun May 9 20:07:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (70953) read frames (70746) minFrame (0) Sun May 9 20:07:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (70953) read frames (70753) minFrame (0) Sun May 9 20:07:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 567624 bytes, system time 09-05-2021 20:07:22 index time 09-05-2021 20:07:22, wait 60s Sun May 9 20:08:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (72450) read frames (70753) minFrame (0) Sun May 9 20:08:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (72459) read frames (72250) minFrame (0) Sun May 9 20:08:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (72459) read frames (72259) minFrame (0) Sun May 9 20:08:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 579672 bytes, system time 09-05-2021 20:08:22 index time 09-05-2021 20:08:22, wait 60s Sun May 9 20:09:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (73962) read frames (72259) minFrame (0) Sun May 9 20:09:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (73965) read frames (73762) minFrame (0) Sun May 9 20:09:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (73965) read frames (73765) minFrame (0) Sun May 9 20:09:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 591720 bytes, system time 09-05-2021 20:09:22 index time 09-05-2021 20:09:22, wait 60s Sun May 9 20:10:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (75459) read frames (73765) minFrame (0) Sun May 9 20:10:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (75464) read frames (75259) minFrame (0) Sun May 9 20:10:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (75464) read frames (75264) minFrame (0) Sun May 9 20:10:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 603712 bytes, system time 09-05-2021 20:10:22 index time 09-05-2021 20:10:22, wait 60s Sun May 9 20:11:22 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (76971) read frames (75264) minFrame (0) Sun May 9 20:11:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (76974) read frames (76771) minFrame (0) Sun May 9 20:11:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (76974) read frames (76774) minFrame (0) Sun May 9 20:11:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 615792 bytes, system time 09-05-2021 20:11:23 index time 09-05-2021 20:11:23, wait 60s Sun May 9 20:12:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (78469) read frames (76774) minFrame (0) Sun May 9 20:12:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (78477) read frames (78269) minFrame (0) Sun May 9 20:12:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (78477) read frames (78277) minFrame (0) Sun May 9 20:12:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 627816 bytes, system time 09-05-2021 20:12:23 index time 09-05-2021 20:12:23, wait 60s Sun May 9 20:13:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (79970) read frames (78277) minFrame (0) Sun May 9 20:13:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (79978) read frames (79770) minFrame (0) Sun May 9 20:13:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (79978) read frames (79778) minFrame (0) Sun May 9 20:13:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 639824 bytes, system time 09-05-2021 20:13:23 index time 09-05-2021 20:13:23, wait 60s Sun May 9 20:14:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (81477) read frames (79778) minFrame (0) Sun May 9 20:14:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (81483) read frames (81277) minFrame (0) Sun May 9 20:14:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (81483) read frames (81283) minFrame (0) Sun May 9 20:14:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 651864 bytes, system time 09-05-2021 20:14:23 index time 09-05-2021 20:14:23, wait 60s Sun May 9 20:15:23 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (82990) read frames (81283) minFrame (0) Sun May 9 20:15:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (82995) read frames (82790) minFrame (0) Sun May 9 20:15:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (82995) read frames (82795) minFrame (0) Sun May 9 20:15:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 663960 bytes, system time 09-05-2021 20:15:24 index time 09-05-2021 20:15:23, wait 60s Sun May 9 20:16:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (84493) read frames (82795) minFrame (0) Sun May 9 20:16:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (84500) read frames (84293) minFrame (0) Sun May 9 20:16:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (84500) read frames (84300) minFrame (0) Sun May 9 20:16:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 676000 bytes, system time 09-05-2021 20:16:24 index time 09-05-2021 20:16:24, wait 60s Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (86003) read frames (84300) minFrame (0) Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::SearchLogo(): read (3000) frames and could not get enough valid frames (0) Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 85413 Sun May 9 20:17:24 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:17:24 [1124] INFO: no logo found in recording, retry in 2nd recording part Sun May 9 20:17:24 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 16250 with aspect ratio 4:3 Sun May 9 20:17:24 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 16250 Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sun May 9 20:17:24 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:17:24 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 20:17:24 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 20:17:24 [1124] DEBUG: cDecoder::GetVideoType(): video coding format: H.262 Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::SearchLogo(): video resolution 720x576 Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::SearchLogo(): logo size 230x130 Sun May 9 20:17:24 [1124] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sun May 9 20:17:24 [1124] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 3 Sun May 9 20:17:24 [1124] DEBUG: cExtractLogo::SearchLogo(): seek to frame 16250 Sun May 9 20:17:24 [1124] DEBUG: cDecoder::SeekToFrame(): (16250) Sun May 9 20:17:26 [1124] DEBUG: cDecoder::SeekToFrame(): successful Sun May 9 20:17:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (86271) read frames (85803) minFrame (0) Sun May 9 20:17:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (86273) read frames (86071) minFrame (0) Sun May 9 20:17:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (86273) read frames (86073) minFrame (0) Sun May 9 20:17:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 690184 bytes, system time 09-05-2021 20:17:35 index time 09-05-2021 20:17:35, wait 60s Sun May 9 20:18:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (87772) read frames (86073) minFrame (0) Sun May 9 20:18:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (87779) read frames (87572) minFrame (0) Sun May 9 20:18:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (87779) read frames (87579) minFrame (0) Sun May 9 20:18:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 702232 bytes, system time 09-05-2021 20:18:35 index time 09-05-2021 20:18:35, wait 60s Sun May 9 20:19:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (89279) read frames (87579) minFrame (0) Sun May 9 20:19:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (89284) read frames (89079) minFrame (0) Sun May 9 20:19:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (89284) read frames (89084) minFrame (0) Sun May 9 20:19:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 714272 bytes, system time 09-05-2021 20:19:35 index time 09-05-2021 20:19:35, wait 60s Sun May 9 20:20:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (90789) read frames (89084) minFrame (0) Sun May 9 20:20:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (90793) read frames (90589) minFrame (0) Sun May 9 20:20:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (90793) read frames (90593) minFrame (0) Sun May 9 20:20:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 726344 bytes, system time 09-05-2021 20:20:35 index time 09-05-2021 20:20:35, wait 60s Sun May 9 20:21:35 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (92291) read frames (90593) minFrame (0) Sun May 9 20:21:36 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (92295) read frames (92091) minFrame (0) Sun May 9 20:21:36 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (92295) read frames (92095) minFrame (0) Sun May 9 20:21:36 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 738360 bytes, system time 09-05-2021 20:21:36 index time 09-05-2021 20:21:35, wait 60s Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (93795) read frames (92095) minFrame (0) Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::SearchLogo(): read (3000) frames and could not get enough valid frames (0) Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 93219 Sun May 9 20:22:36 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:22:36 [1124] INFO: no logo found in recording, retry in 3nd recording part Sun May 9 20:22:36 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 23750 with aspect ratio 4:3 Sun May 9 20:22:36 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 23750 Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sun May 9 20:22:36 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:22:36 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 20:22:36 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 20:22:36 [1124] DEBUG: cDecoder::GetVideoType(): video coding format: H.262 Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::SearchLogo(): video resolution 720x576 Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::SearchLogo(): logo size 230x130 Sun May 9 20:22:36 [1124] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sun May 9 20:22:36 [1124] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 3 Sun May 9 20:22:36 [1124] DEBUG: cExtractLogo::SearchLogo(): seek to frame 23750 Sun May 9 20:22:36 [1124] DEBUG: cDecoder::SeekToFrame(): (23750) Sun May 9 20:22:36 [1124] DEBUG: cDecoder::SeekToFrame(): successful Sun May 9 20:22:41 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (93940) read frames (93595) minFrame (0) Sun May 9 20:22:41 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (93940) read frames (93740) minFrame (0) Sun May 9 20:22:41 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 751520 bytes, system time 09-05-2021 20:22:41 index time 09-05-2021 20:22:41, wait 60s Sun May 9 20:23:41 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (95440) read frames (93740) minFrame (0) Sun May 9 20:23:41 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (95440) read frames (95240) minFrame (0) Sun May 9 20:23:41 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 763520 bytes, system time 09-05-2021 20:23:41 index time 09-05-2021 20:23:41, wait 60s Sun May 9 20:24:41 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (96942) read frames (95240) minFrame (0) Sun May 9 20:24:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (96949) read frames (96742) minFrame (0) Sun May 9 20:24:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (96949) read frames (96749) minFrame (0) Sun May 9 20:24:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 775592 bytes, system time 09-05-2021 20:24:42 index time 09-05-2021 20:24:42, wait 60s Sun May 9 20:25:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (96749) minFrame (0) Sun May 9 20:25:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (97201) minFrame (0) Sun May 9 20:25:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 779208 bytes, system time 09-05-2021 20:25:42 index time 09-05-2021 20:25:00, wait 60s Sun May 9 20:26:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (97201) minFrame (0) Sun May 9 20:26:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 779208 bytes, system time 09-05-2021 20:26:42 index time 09-05-2021 20:25:00, wait 60s Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (97201) minFrame (0) Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 779208 bytes, system time 09-05-2021 20:27:42 index time 09-05-2021 20:25:00, wait 60s Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (97201), old or interrupted recording Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (97201), got 0 valid frames of 2883 frames read Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): end of recording reached at frame (97192), read (2883) iFrames and got (0) valid iFrames, try anyway Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): 0 valid frames of 2883 frames read, got enough iFrames at frame (97201), start analyze Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at TOP_LEFT Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at TOP_RIGHT Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at BOTTOM_LEFT Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at BOTTOM_RIGHT Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): no logo found Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 97192 Sun May 9 20:27:42 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:27:42 [1124] INFO: no logo found in recording, retry in 4nd recording part Sun May 9 20:27:42 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 31250 with aspect ratio 4:3 Sun May 9 20:27:42 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 31250 Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sun May 9 20:27:42 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:27:42 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 20:27:42 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 20:27:42 [1124] DEBUG: cDecoder::GetVideoType(): video coding format: H.262 Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): video resolution 720x576 Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): logo size 230x130 Sun May 9 20:27:42 [1124] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sun May 9 20:27:42 [1124] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 3 Sun May 9 20:27:42 [1124] DEBUG: cExtractLogo::SearchLogo(): seek to frame 31250 Sun May 9 20:27:42 [1124] DEBUG: cDecoder::SeekToFrame(): (31250) Sun May 9 20:27:45 [1124] DEBUG: cDecoder::SeekToFrame(): successful Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (97201) minFrame (0) Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 779208 bytes, system time 09-05-2021 20:27:53 index time 09-05-2021 20:25:00, wait 60s Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (97201), old or interrupted recording Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (97201), got 0 valid frames of 2571 frames read Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): end of recording reached at frame (97192), read (2571) iFrames and got (0) valid iFrames, try anyway Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): 0 valid frames of 2571 frames read, got enough iFrames at frame (97201), start analyze Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at TOP_LEFT Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at TOP_RIGHT Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at BOTTOM_LEFT Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 0 valid frames at BOTTOM_RIGHT Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): no logo found Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 97192 Sun May 9 20:27:53 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CheckLogo(): logo search failed Sun May 9 20:27:53 [1124] INFO: pre-timer 350s Sun May 9 20:27:53 [1124] INFO: broadcast length 49min Sun May 9 20:27:53 [1124] INFO: channel ATV2 Sun May 9 20:27:53 [1124] INFO: parameter --loglevel is set to 3 Sun May 9 20:27:53 [1124] DEBUG: markad process nice level 19 Sun May 9 20:27:53 [1124] DEBUG: markad IO priority class 3 Sun May 9 20:27:53 [1124] DEBUG: parameter --logocachedir is set to /tmp Sun May 9 20:27:53 [1124] DEBUG: parameter --threads is set to -1 Sun May 9 20:27:53 [1124] DEBUG: parameter --astopoffs is set to 0 Sun May 9 20:27:53 [1124] DEBUG: parameter --log2rec is set Sun May 9 20:27:53 [1124] DEBUG: parameter --autologo is set to 2 Sun May 9 20:27:53 [1124] DEBUG: parameter --fulldeode is set Sun May 9 20:27:53 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::ProcessFiles(): start processing files Sun May 9 20:27:53 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 20:27:53 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:27:53 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 20:27:53 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 20:27:53 [1124] DEBUG: cDecoder::GetVideoType(): video coding format: H.262 Sun May 9 20:27:53 [1124] INFO: video hight: 576 Sun May 9 20:27:53 [1124] INFO: video width: 720 Sun May 9 20:27:53 [1124] INFO: average frame rate 25 frames per second Sun May 9 20:27:53 [1124] INFO: real frame rate 25 frames per second Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 8750 (5min 50s) Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 25 Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 2955s ( 49min 15s) Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 8750 ( 5min 50s) Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 82625 ( 55min 5s) Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 20750 ( 13min 50s) Sun May 9 20:27:53 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 97625 ( 65min 5s) Sun May 9 20:27:53 [1124] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sun May 9 20:27:53 [1124] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 3 Sun May 9 20:27:53 [1124] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 16:9, reload logo Sun May 9 20:27:53 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 0 in /tmp Sun May 9 20:27:53 [1124] DEBUG: cMarkAdLogo::Load(): file not found for logo ATV2-A16_9 plane 0 in /tmp Sun May 9 20:27:53 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:27:53 [1124] DEBUG: cMarkAdLogo::Load(): file not found for logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:27:53 [1124] INFO: no valid logo for ATV2-A16_9 in logo cache and recording directory, extract logo from recording Sun May 9 20:27:53 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 0 with aspect ratio 16:9 Sun May 9 20:27:53 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (-1) minFrame (0) Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sun May 9 20:27:53 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:27:53 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 20:27:53 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 20:27:53 [1124] DEBUG: cDecoder::GetVideoType(): video coding format: H.262 Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): video resolution 720x576 Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::SearchLogo(): logo size 230x130 Sun May 9 20:27:53 [1124] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sun May 9 20:27:53 [1124] DEBUG: cExtractLogo::AudioInBroadcast(): got first time 2 channel at frame (3) Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): 1001 valid frames of 1001 frames read, got enough iFrames at frame (25930), start analyze Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 9450 with 106 similars out of 142 valid frames at TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 14255 with 126 similars out of 147 valid frames at TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 1178 with 4 similars out of 20 valid frames at BOTTOM_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 10 valid frames at BOTTOM_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): best corner is TOP_RIGHT at frame 14255 with 126 similars Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): logo size before resize: 230 width 130 height on corner TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): cut out 66p lines horizontal and 0p column vertical Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 230 width 64 height on corner TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 96p column vertical Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 134 width 64 height on corner TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): logo size after 1. resize: 134 width 64 height on corner TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 134 width 64 height on corner TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 134 width 64 height on corner TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): logo size after 2. resize: 134 width 64 height on corner TOP_RIGHT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): video 720x576 with logo size 134 width 64 height on corner TOP_RIGHT is valid Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): try with second best corner 0 at frame 9450 with 106 similars Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): logo size before resize: 230 width 130 height on corner TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): cut out 62p lines horizontal and 0p column vertical Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 230 width 68 height on corner TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 120p column vertical Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 110 width 68 height on corner TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): logo size after 1. resize: 110 width 68 height on corner TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 110 width 68 height on corner TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 110 width 68 height on corner TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): logo size after 2. resize: 110 width 68 height on corner TOP_LEFT Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::Resize(): video 720x576 with logo size 110 width 68 height on corner TOP_LEFT is valid Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): resize logo from second best corner is valid, still no clear result Sun May 9 20:27:58 [1124] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 25930 Sun May 9 20:27:58 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:27:58 [1124] DEBUG: cMarkAdLogo::Process(): no logo found in recording Sun May 9 20:27:58 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:27:58 [1124] DEBUG: cMarkAdLogo::Load(): file not found for logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:27:58 [1124] INFO: still no valid logo for ATV2-A16_9 in recording directory Sun May 9 20:27:58 [1124] DEBUG: cMarkAdLogo::Process(): no valid logo found for aspect ratio 16:9, disable logo detection Sun May 9 20:27:58 [1124] DEBUG: detected end of black screen (2)* at 0:00:00.03 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected start of black screen (9173) at 0:06:06.24 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected end of black screen (9175)* at 0:06:07.01 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected start of black screen (9410) at 0:06:16.11 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected end of black screen (9412)* at 0:06:16.13 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected start of black screen (9652) at 0:06:26.03 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected end of black screen (9652)* at 0:06:26.03 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: duplicate mark on position 9652 type 0x21 and type 0x22 Sun May 9 20:28:05 [1124] DEBUG: duplicate mark on position 9652 type 0x21 and type 0x22 Sun May 9 20:28:05 [1124] DEBUG: detected start of black screen (9660) at 0:06:26.11 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected end of black screen (9660)* at 0:06:26.11 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: duplicate mark on position 9660 type 0x21 and type 0x22 Sun May 9 20:28:05 [1124] DEBUG: duplicate mark on position 9660 type 0x21 and type 0x22 Sun May 9 20:28:05 [1124] DEBUG: detected start of black screen (9669) at 0:06:26.20 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected end of black screen (9669)* at 0:06:26.20 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: duplicate mark on position 9669 type 0x21 and type 0x22 Sun May 9 20:28:05 [1124] DEBUG: duplicate mark on position 9669 type 0x21 and type 0x22 Sun May 9 20:28:05 [1124] DEBUG: detected start of black screen (9950) at 0:06:38.01 inBroadCast: 0 Sun May 9 20:28:05 [1124] DEBUG: detected end of black screen (9952)* at 0:06:38.03 inBroadCast: 0 Sun May 9 20:28:06 [1124] DEBUG: detected start of black screen (10228) at 0:06:49.04 inBroadCast: 0 Sun May 9 20:28:06 [1124] DEBUG: detected end of black screen (10258)* at 0:06:50.09 inBroadCast: 0 Sun May 9 20:28:06 [1124] DEBUG: detected start of black screen (10327) at 0:06:53.03 inBroadCast: 0 Sun May 9 20:28:06 [1124] DEBUG: detected end of black screen (10349)* at 0:06:53.25 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11714) at 0:07:48.15 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11715)* at 0:07:48.16 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11729) at 0:07:49.05 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11733)* at 0:07:49.09 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11750) at 0:07:50.01 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11750)* at 0:07:50.01 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: duplicate mark on position 11750 type 0x21 and type 0x22 Sun May 9 20:28:07 [1124] DEBUG: duplicate mark on position 11750 type 0x21 and type 0x22 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11760) at 0:07:50.11 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11760)* at 0:07:50.11 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: duplicate mark on position 11760 type 0x21 and type 0x22 Sun May 9 20:28:07 [1124] DEBUG: duplicate mark on position 11760 type 0x21 and type 0x22 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11767) at 0:07:50.18 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11769)* at 0:07:50.20 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11778) at 0:07:51.04 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11779)* at 0:07:51.05 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11786) at 0:07:51.12 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11787)* at 0:07:51.13 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11794) at 0:07:51.20 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11795)* at 0:07:51.21 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11832) at 0:07:53.08 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11833)* at 0:07:53.09 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11841) at 0:07:53.17 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11842)* at 0:07:53.18 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (11860) at 0:07:54.11 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (11860)* at 0:07:54.11 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: duplicate mark on position 11860 type 0x21 and type 0x22 Sun May 9 20:28:07 [1124] DEBUG: duplicate mark on position 11860 type 0x21 and type 0x22 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (12125) at 0:08:05.01 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (12127)* at 0:08:05.03 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (12153) at 0:08:06.04 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (12154)* at 0:08:06.05 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected start of black screen (12188) at 0:08:07.14 inBroadCast: 0 Sun May 9 20:28:07 [1124] DEBUG: detected end of black screen (12192)* at 0:08:07.18 inBroadCast: 0 Sun May 9 20:28:19 [1124] DEBUG: detected start of black screen (27396) at 0:18:15.22 inBroadCast: 0 Sun May 9 20:28:19 [1124] DEBUG: detected end of black screen (27419)* at 0:18:16.20 inBroadCast: 0 Sun May 9 20:28:19 [1124] DEBUG: detected start of black screen (27495) at 0:18:19.21 inBroadCast: 0 Sun May 9 20:28:19 [1124] DEBUG: detected end of black screen (27495)* at 0:18:19.21 inBroadCast: 0 Sun May 9 20:28:19 [1124] DEBUG: duplicate mark on position 27495 type 0x21 and type 0x22 Sun May 9 20:28:19 [1124] DEBUG: duplicate mark on position 27495 type 0x21 and type 0x22 Sun May 9 20:28:20 [1124] DEBUG: detected start of black screen (27937) at 0:18:37.13 inBroadCast: 0 Sun May 9 20:28:20 [1124] DEBUG: detected end of black screen (27994)* at 0:18:39.20 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected start of black screen (32475) at 0:21:39.01 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected end of black screen (32476)* at 0:21:39.02 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected start of black screen (32485) at 0:21:39.11 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected end of black screen (32494)* at 0:21:39.20 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected start of black screen (32627) at 0:21:45.03 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected end of black screen (32631)* at 0:21:45.07 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected start of black screen (32742) at 0:21:49.18 inBroadCast: 0 Sun May 9 20:28:23 [1124] DEBUG: detected end of black screen (32743)* at 0:21:49.19 inBroadCast: 0 Sun May 9 20:28:24 [1124] DEBUG: detected start of black screen (33695) at 0:22:27.21 inBroadCast: 0 Sun May 9 20:28:24 [1124] DEBUG: detected end of black screen (33695)* at 0:22:27.21 inBroadCast: 0 Sun May 9 20:28:24 [1124] DEBUG: duplicate mark on position 33695 type 0x21 and type 0x22 Sun May 9 20:28:24 [1124] DEBUG: duplicate mark on position 33695 type 0x21 and type 0x22 Sun May 9 20:28:24 [1124] DEBUG: detected start of black screen (33763) at 0:22:30.14 inBroadCast: 0 Sun May 9 20:28:24 [1124] DEBUG: detected end of black screen (33782)* at 0:22:31.08 inBroadCast: 0 Sun May 9 20:28:35 [1124] DEBUG: detected start of black screen (49538) at 0:33:01.14 inBroadCast: 0 Sun May 9 20:28:35 [1124] DEBUG: detected end of black screen (49562)* at 0:33:02.13 inBroadCast: 0 Sun May 9 20:28:35 [1124] DEBUG: detected start of black screen (49630) at 0:33:05.06 inBroadCast: 0 Sun May 9 20:28:35 [1124] DEBUG: detected end of black screen (49652)* at 0:33:06.03 inBroadCast: 0 Sun May 9 20:28:44 [1124] DEBUG: detected start of black screen (59988) at 0:39:59.14 inBroadCast: 0 Sun May 9 20:28:44 [1124] DEBUG: detected end of black screen (60015)* at 0:40:00.16 inBroadCast: 0 Sun May 9 20:28:44 [1124] DEBUG: detected start of black screen (60079) at 0:40:03.05 inBroadCast: 0 Sun May 9 20:28:44 [1124] DEBUG: detected end of black screen (60105)* at 0:40:04.06 inBroadCast: 0 Sun May 9 20:28:50 [1124] DEBUG: detected start of black screen (67307) at 0:44:52.08 inBroadCast: 0 Sun May 9 20:28:50 [1124] DEBUG: detected end of black screen (67329)* at 0:44:53.05 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69330) at 0:46:13.06 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69331)* at 0:46:13.07 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69333) at 0:46:13.09 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69334)* at 0:46:13.10 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69337) at 0:46:13.13 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69337)* at 0:46:13.13 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69337 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69337 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69340) at 0:46:13.16 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69344)* at 0:46:13.20 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69354) at 0:46:14.05 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69354)* at 0:46:14.05 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69354 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69354 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69358) at 0:46:14.09 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69359)* at 0:46:14.10 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69361) at 0:46:14.12 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69361)* at 0:46:14.12 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69361 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69361 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69367) at 0:46:14.18 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69367)* at 0:46:14.18 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69367 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69367 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69386) at 0:46:15.12 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69387)* at 0:46:15.13 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69389) at 0:46:15.15 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69389)* at 0:46:15.15 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69389 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69389 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69392) at 0:46:15.18 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69395)* at 0:46:15.21 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69398) at 0:46:15.24 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69398)* at 0:46:15.24 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69398 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69398 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69409) at 0:46:16.10 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69409)* at 0:46:16.10 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69409 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69409 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69415) at 0:46:16.16 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69415)* at 0:46:16.16 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69415 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: duplicate mark on position 69415 type 0x21 and type 0x22 Sun May 9 20:28:51 [1124] DEBUG: detected start of black screen (69417) at 0:46:16.18 inBroadCast: 0 Sun May 9 20:28:51 [1124] DEBUG: detected end of black screen (69418)* at 0:46:16.19 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71372) at 0:47:34.23 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71373)* at 0:47:34.24 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71379) at 0:47:35.05 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71381)* at 0:47:35.07 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71383) at 0:47:35.09 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71383)* at 0:47:35.09 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: duplicate mark on position 71383 type 0x21 and type 0x22 Sun May 9 20:28:53 [1124] DEBUG: duplicate mark on position 71383 type 0x21 and type 0x22 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71385) at 0:47:35.11 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71386)* at 0:47:35.12 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71603) at 0:47:44.04 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71627)* at 0:47:45.03 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71659) at 0:47:46.10 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71660)* at 0:47:46.11 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71666) at 0:47:46.17 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71668)* at 0:47:46.19 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71670) at 0:47:46.21 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71670)* at 0:47:46.21 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: duplicate mark on position 71670 type 0x21 and type 0x22 Sun May 9 20:28:53 [1124] DEBUG: duplicate mark on position 71670 type 0x21 and type 0x22 Sun May 9 20:28:53 [1124] DEBUG: detected start of black screen (71672) at 0:47:46.23 inBroadCast: 0 Sun May 9 20:28:53 [1124] DEBUG: detected end of black screen (71673)* at 0:47:46.24 inBroadCast: 0 Sun May 9 20:28:55 [1124] DEBUG: detected start of black screen (74330) at 0:49:33.06 inBroadCast: 0 Sun May 9 20:28:55 [1124] DEBUG: detected end of black screen (74331)* at 0:49:33.07 inBroadCast: 0 Sun May 9 20:28:55 [1124] DEBUG: detected start of black screen (74397) at 0:49:35.23 inBroadCast: 0 Sun May 9 20:28:55 [1124] DEBUG: detected end of black screen (74421)* at 0:49:36.22 inBroadCast: 0 Sun May 9 20:28:56 [1124] INFO: restart logo and black screen detection at frame (76626) Sun May 9 20:28:56 [1124] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 16:9, reload logo Sun May 9 20:28:56 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 0 in /tmp Sun May 9 20:28:56 [1124] DEBUG: cMarkAdLogo::Load(): file not found for logo ATV2-A16_9 plane 0 in /tmp Sun May 9 20:28:56 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:28:56 [1124] DEBUG: cMarkAdLogo::Load(): file not found for logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:28:56 [1124] INFO: no valid logo for ATV2-A16_9 in logo cache and recording directory, extract logo from recording Sun May 9 20:28:56 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:28:56 [1124] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 76607 with aspect ratio 16:9 Sun May 9 20:28:56 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 20:28:56 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (-1) minFrame (0) Sun May 9 20:28:56 [1124] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 76607 Sun May 9 20:28:56 [1124] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sun May 9 20:28:56 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:28:56 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 20:28:56 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 20:28:56 [1124] DEBUG: cDecoder::GetVideoType(): video coding format: H.262 Sun May 9 20:28:56 [1124] DEBUG: cExtractLogo::SearchLogo(): video resolution 720x576 Sun May 9 20:28:56 [1124] DEBUG: cExtractLogo::SearchLogo(): logo size 230x130 Sun May 9 20:28:56 [1124] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sun May 9 20:28:56 [1124] DEBUG: cExtractLogo::SearchLogo(): seek to frame 76607 Sun May 9 20:28:56 [1124] DEBUG: cDecoder::SeekToFrame(): (76607) Sun May 9 20:28:58 [1124] DEBUG: cDecoder::SeekToFrame(): successful Sun May 9 20:28:58 [1124] DEBUG: cExtractLogo::AudioInBroadcast(): got first time 2 channel at frame (76635) Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (97401) read frames (97201) minFrame (0) Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::WaitForFrames(): index file size 779208 bytes, system time 09-05-2021 20:29:02 index time 09-05-2021 20:25:00, wait 60s Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::isRunningRecording(): index not growing at frame (97201), old or interrupted recording Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): WaitForFrames() failed at frame (97201), got 820 valid frames of 820 frames read Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): end of recording reached at frame (97192), read (820) iFrames and got (820) valid iFrames, try anyway Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): 820 valid frames of 820 frames read, got enough iFrames at frame (97201), start analyze Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 76766 with 193 similars out of 228 valid frames at TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 77841 with 45 similars out of 56 valid frames at TOP_RIGHT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 5 valid frames at BOTTOM_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 2 valid frames at BOTTOM_RIGHT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): best corner is TOP_LEFT at frame 76766 with 193 similars Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Resize(): logo size before resize: 230 width 130 height on corner TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::CutOut(): cut out 62p lines horizontal and 0p column vertical Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 230 width 68 height on corner TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 112p column vertical Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 118 width 68 height on corner TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Resize(): logo size after 1. resize: 118 width 68 height on corner TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 118 width 68 height on corner TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 118 width 68 height on corner TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Resize(): logo size after 2. resize: 118 width 68 height on corner TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Resize(): video 720x576 with logo size 118 width 68 height on corner TOP_LEFT is valid Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::isLogoColourChange(): 228 valid frames in corner 0, plane 1: 215 are white, ratio 94% Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::isLogoColourChange(): plane 2: 0 are white, ratio 0% Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Save(): 628 pixel in plane 0 Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Save(): store logo in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/ATV2-A16_9-P0.pgm Sun May 9 20:29:02 [1124] INFO: Logo size for Channel: ATV2 16:9 720W 576H: 118W 68H TOP_LEFT Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Save(): not enough pixel (20) in plane 1 Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::Save(): not enough pixel (105) in plane 2 Sun May 9 20:29:02 [1124] DEBUG: cExtractLogo::SearchLogo(): finished successfully, last frame 97192 Sun May 9 20:29:02 [1124] DEBUG: ---------------------------------------------------------------------------- Sun May 9 20:29:02 [1124] DEBUG: cMarkAdLogo::Process(): new logo for ATV2-A16_9 found in recording Sun May 9 20:29:02 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:29:02 [1124] DEBUG: cMarkAdLogo::Load(): file found for logo ATV2-A16_9 plane 0 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:29:02 [1124] INFO: logo ATV2-A16_9 found in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:29:02 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 1 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:29:02 [1124] DEBUG: cMarkAdLogo::Load(): file not found for logo ATV2-A16_9 plane 1 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:29:02 [1124] DEBUG: cMarkAdLogo::Load(): try to find logo ATV2-A16_9 plane 2 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:29:02 [1124] DEBUG: cMarkAdLogo::Load(): file not found for logo ATV2-A16_9 plane 2 in /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec Sun May 9 20:29:02 [1124] DEBUG: detected end of black screen (76625)* at 0:51:05.01 inBroadCast: 0 Sun May 9 20:29:02 [1124] INFO: actual mark (76626) type 0x31 stronger then previous mark, deleting 76625 type 0x21 Sun May 9 20:29:02 [1124] DEBUG: duplicate mark on position 76625 type 0x21 and type 0x21 Sun May 9 20:29:02 [1124] INFO: detected logo start (76626)* at 0:51:05.02 inBroadCast: 1 Sun May 9 20:29:02 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): checking start at frame (76629) check start planed at (20750) Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): assumed start frame 8750 Sun May 9 20:29:02 [1124] DEBUG: ************************************************************* Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:02 [1124] DEBUG: mark at position 2 type 0x21 at 0:00:00.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9173 type 0x22 at 0:06:06.24 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9175 type 0x21 at 0:06:07.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9410 type 0x22 at 0:06:16.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9412 type 0x21 at 0:06:16.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9950 type 0x22 at 0:06:38.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9952 type 0x21 at 0:06:38.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10228 type 0x22 at 0:06:49.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10258 type 0x21 at 0:06:50.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10327 type 0x22 at 0:06:53.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10349 type 0x21 at 0:06:53.25 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11714 type 0x22 at 0:07:48.15 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11715 type 0x21 at 0:07:48.16 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11729 type 0x22 at 0:07:49.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11733 type 0x21 at 0:07:49.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11767 type 0x22 at 0:07:50.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11769 type 0x21 at 0:07:50.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11778 type 0x22 at 0:07:51.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11779 type 0x21 at 0:07:51.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11786 type 0x22 at 0:07:51.12 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11787 type 0x21 at 0:07:51.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11794 type 0x22 at 0:07:51.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11795 type 0x21 at 0:07:51.21 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11832 type 0x22 at 0:07:53.08 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11833 type 0x21 at 0:07:53.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11841 type 0x22 at 0:07:53.17 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11842 type 0x21 at 0:07:53.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12125 type 0x22 at 0:08:05.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12127 type 0x21 at 0:08:05.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12153 type 0x22 at 0:08:06.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12154 type 0x21 at 0:08:06.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12188 type 0x22 at 0:08:07.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12192 type 0x21 at 0:08:07.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27396 type 0x22 at 0:18:15.22 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27419 type 0x21 at 0:18:16.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27937 type 0x22 at 0:18:37.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27994 type 0x21 at 0:18:39.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32475 type 0x22 at 0:21:39.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32476 type 0x21 at 0:21:39.02 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32485 type 0x22 at 0:21:39.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32494 type 0x21 at 0:21:39.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32627 type 0x22 at 0:21:45.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32631 type 0x21 at 0:21:45.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32742 type 0x22 at 0:21:49.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32743 type 0x21 at 0:21:49.19 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 33763 type 0x22 at 0:22:30.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 33782 type 0x21 at 0:22:31.08 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49538 type 0x22 at 0:33:01.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49562 type 0x21 at 0:33:02.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49630 type 0x22 at 0:33:05.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49652 type 0x21 at 0:33:06.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 59988 type 0x22 at 0:39:59.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 60015 type 0x21 at 0:40:00.16 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 60079 type 0x22 at 0:40:03.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 60105 type 0x21 at 0:40:04.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 67307 type 0x22 at 0:44:52.08 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 67329 type 0x21 at 0:44:53.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69330 type 0x22 at 0:46:13.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69331 type 0x21 at 0:46:13.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69333 type 0x22 at 0:46:13.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69334 type 0x21 at 0:46:13.10 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69340 type 0x22 at 0:46:13.16 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69344 type 0x21 at 0:46:13.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69358 type 0x22 at 0:46:14.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69359 type 0x21 at 0:46:14.10 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69386 type 0x22 at 0:46:15.12 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69387 type 0x21 at 0:46:15.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69392 type 0x22 at 0:46:15.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69395 type 0x21 at 0:46:15.21 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69417 type 0x22 at 0:46:16.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69418 type 0x21 at 0:46:16.19 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71372 type 0x22 at 0:47:34.23 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71373 type 0x21 at 0:47:34.24 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71379 type 0x22 at 0:47:35.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71381 type 0x21 at 0:47:35.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71385 type 0x22 at 0:47:35.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71386 type 0x21 at 0:47:35.12 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71603 type 0x22 at 0:47:44.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71627 type 0x21 at 0:47:45.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71659 type 0x22 at 0:47:46.10 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71660 type 0x21 at 0:47:46.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71666 type 0x22 at 0:47:46.17 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71668 type 0x21 at 0:47:46.19 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71672 type 0x22 at 0:47:46.23 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71673 type 0x21 at 0:47:46.24 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74330 type 0x22 at 0:49:33.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74331 type 0x21 at 0:49:33.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74397 type 0x22 at 0:49:35.23 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74421 type 0x21 at 0:49:36.22 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:02 [1124] DEBUG: ************************************************************* Sun May 9 20:29:02 [1124] INFO: video aspect description in info (4:3) wrong, correct to (16:9) Sun May 9 20:29:02 [1124] INFO: SD Video with aspectratio of 16:9 detected Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): no horizontal border at start found, ignore horizontal border detection Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): no vertical border at start found, ignore vertical border detection Sun May 9 20:29:02 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): only 0 logo start mark and 0 logo stop marks found, do not delete any Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Sun May 9 20:29:02 [1124] DEBUG: ************************************************************* Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:02 [1124] DEBUG: mark at position 2 type 0x21 at 0:00:00.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9173 type 0x22 at 0:06:06.24 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9175 type 0x21 at 0:06:07.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9410 type 0x22 at 0:06:16.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9412 type 0x21 at 0:06:16.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9950 type 0x22 at 0:06:38.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 9952 type 0x21 at 0:06:38.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10228 type 0x22 at 0:06:49.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10258 type 0x21 at 0:06:50.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10327 type 0x22 at 0:06:53.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 10349 type 0x21 at 0:06:53.25 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11714 type 0x22 at 0:07:48.15 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11715 type 0x21 at 0:07:48.16 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11729 type 0x22 at 0:07:49.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11733 type 0x21 at 0:07:49.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11767 type 0x22 at 0:07:50.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11769 type 0x21 at 0:07:50.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11778 type 0x22 at 0:07:51.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11779 type 0x21 at 0:07:51.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11786 type 0x22 at 0:07:51.12 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11787 type 0x21 at 0:07:51.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11794 type 0x22 at 0:07:51.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11795 type 0x21 at 0:07:51.21 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11832 type 0x22 at 0:07:53.08 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11833 type 0x21 at 0:07:53.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11841 type 0x22 at 0:07:53.17 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 11842 type 0x21 at 0:07:53.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12125 type 0x22 at 0:08:05.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12127 type 0x21 at 0:08:05.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12153 type 0x22 at 0:08:06.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12154 type 0x21 at 0:08:06.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12188 type 0x22 at 0:08:07.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 12192 type 0x21 at 0:08:07.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27396 type 0x22 at 0:18:15.22 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27419 type 0x21 at 0:18:16.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27937 type 0x22 at 0:18:37.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 27994 type 0x21 at 0:18:39.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32475 type 0x22 at 0:21:39.01 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32476 type 0x21 at 0:21:39.02 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32485 type 0x22 at 0:21:39.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32494 type 0x21 at 0:21:39.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32627 type 0x22 at 0:21:45.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32631 type 0x21 at 0:21:45.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32742 type 0x22 at 0:21:49.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 32743 type 0x21 at 0:21:49.19 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 33763 type 0x22 at 0:22:30.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 33782 type 0x21 at 0:22:31.08 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49538 type 0x22 at 0:33:01.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49562 type 0x21 at 0:33:02.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49630 type 0x22 at 0:33:05.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 49652 type 0x21 at 0:33:06.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 59988 type 0x22 at 0:39:59.14 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 60015 type 0x21 at 0:40:00.16 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 60079 type 0x22 at 0:40:03.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 60105 type 0x21 at 0:40:04.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 67307 type 0x22 at 0:44:52.08 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 67329 type 0x21 at 0:44:53.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69330 type 0x22 at 0:46:13.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69331 type 0x21 at 0:46:13.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69333 type 0x22 at 0:46:13.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69334 type 0x21 at 0:46:13.10 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69340 type 0x22 at 0:46:13.16 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69344 type 0x21 at 0:46:13.20 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69358 type 0x22 at 0:46:14.09 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69359 type 0x21 at 0:46:14.10 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69386 type 0x22 at 0:46:15.12 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69387 type 0x21 at 0:46:15.13 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69392 type 0x22 at 0:46:15.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69395 type 0x21 at 0:46:15.21 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69417 type 0x22 at 0:46:16.18 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 69418 type 0x21 at 0:46:16.19 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71372 type 0x22 at 0:47:34.23 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71373 type 0x21 at 0:47:34.24 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71379 type 0x22 at 0:47:35.05 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71381 type 0x21 at 0:47:35.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71385 type 0x22 at 0:47:35.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71386 type 0x21 at 0:47:35.12 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71603 type 0x22 at 0:47:44.04 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71627 type 0x21 at 0:47:45.03 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71659 type 0x22 at 0:47:46.10 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71660 type 0x21 at 0:47:46.11 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71666 type 0x22 at 0:47:46.17 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71668 type 0x21 at 0:47:46.19 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71672 type 0x22 at 0:47:46.23 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 71673 type 0x21 at 0:47:46.24 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74330 type 0x22 at 0:49:33.06 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74331 type 0x21 at 0:49:33.07 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74397 type 0x22 at 0:49:35.23 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 74421 type 0x21 at 0:49:36.22 inBroadCast 0 Sun May 9 20:29:02 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:02 [1124] DEBUG: ************************************************************* Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Sun May 9 20:29:02 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): no logo start mark found Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): found start mark (9175) type 0x21 after search for any type Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): start mark found but not inBroadCast (9175) type 0x21 at 0:06:07.01 inBroadCast 0, ignoring Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CheckStart(): no valid start mark found, assume start time at pre recording time Sun May 9 20:29:02 [1124] INFO: assuming start (8751)* at 0:05:50.02 inBroadCast: 1 Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 8751 (5min 50s) Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 25 Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 2955s ( 49min 15s) Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 8751 ( 5min 50s) Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 82626 ( 55min 5s) Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 20751 ( 13min 50s) Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 97626 ( 65min 5s) Sun May 9 20:29:02 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:02 [1124] DEBUG: ************************************************************* Sun May 9 20:29:02 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:02 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:02 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:02 [1124] DEBUG: ************************************************************* Sun May 9 20:29:02 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:07 [1124] DEBUG: detected start of black screen (82960) at 0:55:18.11 inBroadCast: 1 Sun May 9 20:29:07 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:07 [1124] DEBUG: cMarkAdLogo::Detect(): black screen without logo detected at frame (83037) Sun May 9 20:29:07 [1124] INFO: actual mark (83035) type 0x32 stronger then previous mark, deleting 82960 type 0x22 Sun May 9 20:29:07 [1124] DEBUG: duplicate mark on position 82960 type 0x22 and type 0x22 Sun May 9 20:29:07 [1124] INFO: detected logo stop (83035) at 0:55:21.11 inBroadCast: 0 Sun May 9 20:29:07 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:07 [1124] DEBUG: detected end of black screen (83054)* at 0:55:22.05 inBroadCast: 0 Sun May 9 20:29:07 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:08 [1124] DEBUG: detected start of black screen (83305) at 0:55:32.06 inBroadCast: 0 Sun May 9 20:29:08 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:08 [1124] DEBUG: detected end of black screen (83307)* at 0:55:32.08 inBroadCast: 0 Sun May 9 20:29:08 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:08 [1124] INFO: actual mark (83334) type 0x31 stronger then previous mark, deleting 83307 type 0x21 Sun May 9 20:29:08 [1124] DEBUG: duplicate mark on position 83307 type 0x21 and type 0x21 Sun May 9 20:29:08 [1124] INFO: detected logo start (83334)* at 0:55:33.10 inBroadCast: 1 Sun May 9 20:29:08 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:08 [1124] DEBUG: detected start of black screen (83353) at 0:55:34.04 inBroadCast: 1 Sun May 9 20:29:08 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:08 [1124] DEBUG: detected end of black screen (83367)* at 0:55:34.18 inBroadCast: 1 Sun May 9 20:29:08 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:10 [1124] DEBUG: detected start of black screen (85754) at 0:57:10.05 inBroadCast: 1 Sun May 9 20:29:10 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:10 [1124] DEBUG: detected end of black screen (85766)* at 0:57:10.17 inBroadCast: 1 Sun May 9 20:29:10 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:15 [1124] DEBUG: detected start of black screen (91849) at 1:01:13.25 inBroadCast: 1 Sun May 9 20:29:15 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:15 [1124] DEBUG: detected end of black screen (91852)* at 1:01:14.03 inBroadCast: 1 Sun May 9 20:29:15 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:15 [1124] DEBUG: detected start of black screen (91898) at 1:01:15.24 inBroadCast: 1 Sun May 9 20:29:15 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:15 [1124] DEBUG: detected end of black screen (91901)* at 1:01:16.02 inBroadCast: 1 Sun May 9 20:29:15 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:19 [1124] DEBUG: AVlog(): ac-tex damaged at 43 21 Sun May 9 20:29:19 [1124] DEBUG: AVlog(): Warning MVs not available Sun May 9 20:29:19 [1124] DEBUG: cDecoder::GetNextFrame(): last frame of filenumber 1 is (97400), end time 3896000ms ( 64:56min) Sun May 9 20:29:19 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): start check stop (97400) Sun May 9 20:29:19 [1124] DEBUG: assumed stop position (82626) at 0:55:05.02 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83054 type 0x21 at 0:55:22.05 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83305 type 0x22 at 0:55:32.06 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83334 type 0x31 at 0:55:33.10 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83353 type 0x22 at 0:55:34.04 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83367 type 0x21 at 0:55:34.18 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 85754 type 0x22 at 0:57:10.05 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 85766 type 0x21 at 0:57:10.17 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91849 type 0x22 at 1:01:13.25 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91852 type 0x21 at 1:01:14.03 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91898 type 0x22 at 1:01:15.24 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91901 type 0x21 at 1:01:16.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): start with iStart 0, chkSTART 20751, iStopA 82626 Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::isInfoLogo(): ????? stop (83035) start (83334) pair: blacksceen before (82960) and (76625) length -253400ms, diff 256400ms Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::isInfoLogo(): ????? stop (83035) start (83334) pair: in between blacksceen (83305) and (83307) length 80ms Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::isInfoLogo(): ????? stop (83035) start (83334) pair: blacksceen around stop (82960) and (83054) length 3760ms, diff -11200ms Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::isInfoLogo(): ----- stop (83035) start (83334) pair: blacksceen pair long and near, no info logo part Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (83035) start (83334) pair: Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange 0 Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 0 Sun May 9 20:29:19 [1124] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sun May 9 20:29:19 [1124] DEBUG: cDecoder::cDecoder(): init with 1 threads Sun May 9 20:29:19 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:29:19 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 2 -> MPEG-2 video Sun May 9 20:29:19 [1124] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): check logo stop (83035) at 0:55:21.11 and logo start (83334) at 0:55:33.10, isInfoLogo -1 Sun May 9 20:29:19 [1124] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83054 type 0x21 at 0:55:22.05 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83305 type 0x22 at 0:55:32.06 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83334 type 0x31 at 0:55:33.10 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83353 type 0x22 at 0:55:34.04 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83367 type 0x21 at 0:55:34.18 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 85754 type 0x22 at 0:57:10.05 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 85766 type 0x21 at 0:57:10.17 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91849 type 0x22 at 1:01:13.25 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91852 type 0x21 at 1:01:14.03 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91898 type 0x22 at 1:01:15.24 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 91901 type 0x21 at 1:01:16.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): no MT_CHANNELSTOP mark found Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): no MT_ASPECTSTOP mark found Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): no MT_HBORDERSTOP mark found Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): no MT_VBORDERSTOP mark found Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): MT_LOGOSTOP found at frame 83035 Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): logo stop mark (83035) is valid, logo start mark (76626) is 256s before Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): found end mark at (83035) Sun May 9 20:29:19 [1124] INFO: using mark on position (83035) type 0x32 at 0:55:21.11 as stop mark Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): delete all marks after final stop mark at (83035) Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): move all black screen marks except start and end mark to black screen list Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckStop(): end check stop Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): delete logo marks if we have channel or border marks Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): delete invalid black sceen marks Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): delete very short logo stop/start pairs Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): detect previews in advertisement Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): remove logo and hborder detection failure marks Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): mark distance between logo START and STOP 256.4s, keep (76626,83035) Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): remove invalid marks Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 76626 type 0x31 at 0:51:05.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): start mark (8751) followed by start mark (76626) delete second Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::CheckMarks(): final marks: Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sun May 9 20:29:19 [1124] DEBUG: mark at position 8751 type 0x11 at 0:05:50.02 inBroadCast 1 Sun May 9 20:29:19 [1124] DEBUG: mark at position 83035 type 0x32 at 0:55:21.11 inBroadCast 0 Sun May 9 20:29:19 [1124] DEBUG: ************************************************************* Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::ProcessFiles(): end processing files Sun May 9 20:29:19 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:29:19 [1124] INFO: start 2nd pass (detect overlaps) Sun May 9 20:29:19 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:29:19 [1124] DEBUG: end 2ndPass Sun May 9 20:29:19 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:29:19 [1124] INFO: start 3nd pass (optimze logo marks) Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): check last logo stop mark if closing credits follows Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): search for closing credits Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::MoveLastStopAfterClosingCredits(): check closing credits without logo after position (83035) Sun May 9 20:29:19 [1124] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::MoveLastStopAfterClosingCredits(): no closing credits found Sun May 9 20:29:19 [1124] DEBUG: ======================================================================================================================= Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): check for advertising in frame with logo after logo start and before logo stop mark and check for introduction logo Sun May 9 20:29:19 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:29:19 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): search for audio silence around logo marks Sun May 9 20:29:19 [1124] DEBUG: cDecoder::DecodeFile(): start decode file /srv/vdr/video/Serien/Navy_CIS#3A_New_Orleans/07x05_-_144._Gefahr_aus_der_Tiefe_(2)/2021-05-09.19.20.54-0.rec/00001.ts Sun May 9 20:29:19 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo stop mark at frame ( 83035) type 0x32 at 0:55:21.11 range 5 Sun May 9 20:29:19 [1124] DEBUG: cDecoder::SeekToFrame(): (82910) Sun May 9 20:29:21 [1124] DEBUG: cDecoder::SeekToFrame(): successful Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (82910) to frame (83035) Sun May 9 20:29:21 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence after logo stop mark at frame ( 83035) type 0x32 at 0:55:21.11 range 5 Sun May 9 20:29:21 [1124] DEBUG: cDecoder::SeekToFrame(): (83035) Sun May 9 20:29:21 [1124] DEBUG: cDecoder::SeekToFrame(): successful Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (83035) to frame (83135) Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (83057) level 20 silenceCount 1, pts 4300429271 Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (83057) level 16 silenceCount 2, pts 4300431431 Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (83057) level 23 silenceCount 3, pts 4300433591 Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (83061) level 19 silenceCount 4, pts 4300435751 Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (83061) level 18 silenceCount 5, pts 4300437911 Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (83061) level 15 silenceCount 6, pts 4300440071 Sun May 9 20:29:21 [1124] DEBUG: cDecoder::GetNextSilence(): found silence part in stream 1 between audio frame (83057) and (83061), video frame (83045) PTS 4300427469, return frame (83045) Sun May 9 20:29:21 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence after logo stop mark (83035) at iFrame (83045) Sun May 9 20:29:21 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence for mark at frame ( 83035) type 0x32 at 0:55:21.11 range 5 Sun May 9 20:29:21 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): use audio silence after logo stop at iFrame (83045) Sun May 9 20:29:21 [1124] INFO: moved stop mark (83045) after logo stop mark (83035) at 0:55:21.11, silence detected Sun May 9 20:29:21 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:21 [1124] DEBUG: cMarkAdStandalone::Process3ndPass(): start search for blackscreen near logo marks Sun May 9 20:29:21 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=0 Sun May 9 20:29:21 [1124] DEBUG: clMarks::Save(): save marks, isRunningRecording=1 force=1 Sun May 9 20:29:21 [1124] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sun May 9 20:29:21 [1124] DEBUG: time for decoding: 81s 237ms Sun May 9 20:29:21 [1124] DEBUG: time to find logo in recording: 4071s 689ms Sun May 9 20:29:21 [1124] DEBUG: time to find logo changes: 0s 1ms Sun May 9 20:29:21 [1124] INFO: pass 1: time 86s 052ms, frames 97400, fps 1132 Sun May 9 20:29:21 [1124] INFO: pass 3: time 1s 648ms, frames 200, fps 200 Sun May 9 20:29:21 [1124] INFO: processed time 69:19 min with 23.5 fps