Sat Jul 24 22:51:06 [31788] INFO: starting markad v3.0.8 (64bit) Sat Jul 24 22:51:06 [31788] INFO: using libavcodec.so.58.54.100 with -1 threads Sat Jul 24 22:51:06 [31788] INFO: on /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 22:51:06 [31788] INFO: no broadcast aspect ratio found in vdr info Sat Jul 24 22:51:06 [31788] DEBUG: cMarkAdStandalone::GetRecordingStart(): mount option noatime is not set Sat Jul 24 22:51:06 [31788] DEBUG: cMarkAdStandalone::GetRecordingStart(): getting recording start from VDR info file modification time Thu Jul 22 19:58:00 2021 Sat Jul 24 22:51:06 [31788] DEBUG: cMarkAdStandalone::LoadInfo(): recording start at Thu Jul 22 19:58:00 2021 Sat Jul 24 22:51:06 [31788] DEBUG: cMarkAdStandalone::LoadInfo(): timer start at Thu Jul 22 20:00:00 2021 Sat Jul 24 22:51:06 [31788] DEBUG: cMarkAdStandalone::LoadInfo(): broadcast start 120s after recording start Sat Jul 24 22:51:06 [31788] DEBUG: cMarkAdStandalone::CheckLogo(): using logo directory /tmp Sat Jul 24 22:51:06 [31788] DEBUG: cMarkAdStandalone::CheckLogo(): searching logo for Kutonen_HD Sat Jul 24 22:51:06 [31788] INFO: no logo found in logo directory, trying to find logo in recording directory Sat Jul 24 22:51:06 [31788] INFO: no logo found in recording directory, trying to extract logo from recording Sat Jul 24 22:51:06 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:51:06 [31788] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 3000 with aspect ratio 0:0 Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:51:06 [31788] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (107942) read frames (-1) minFrame (0) Sat Jul 24 22:51:06 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 3000 Sat Jul 24 22:51:06 [31788] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:51:06 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:51:06 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 5 Sat Jul 24 22:51:06 [31788] DEBUG: cExtractLogo::SearchLogo(): seek to frame 3000 Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::SeekToFrame(): (3000) Sat Jul 24 22:51:06 [31788] DEBUG: cIndex::GetIFrameBefore(): failed for frame (3000), index: first frame (0) last frame (0) Sat Jul 24 22:51:06 [31788] DEBUG: cDecoder::SeekFrame(): index does not yet contain frame ( 3000), decode from current frame (5) to build index Sat Jul 24 22:51:10 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 22:51:10 [31788] DEBUG: cExtractLogo::AudioInBroadcast(): got first time 2 channel at frame (3020) Sat Jul 24 22:51:10 [31788] DEBUG: cExtractLogo::SearchLogo(): aspect ratio set to 16:9 Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): 1001 valid frames of 1001 frames read, got enough iFrames at frame (35192), start analyze Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 5525 with 4 similars out of 32 valid frames at TOP_LEFT Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 9695 with 17 similars out of 66 valid frames at TOP_RIGHT Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 24533 with 9 similars out of 53 valid frames at BOTTOM_LEFT Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 5973 with 5 similars out of 24 valid frames at BOTTOM_RIGHT Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): no valid logo found, best logo at frame 9695 with 17 similars at corner TOP_RIGHT Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 35192 Sat Jul 24 22:52:25 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:52:25 [31788] INFO: no logo found in recording, retry in 2nd recording part Sat Jul 24 22:52:25 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 10500 with aspect ratio 16:9 Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 10500 Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): already have 38 frames from (10527) to frame (34104) Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 5 Sat Jul 24 22:52:25 [31788] DEBUG: cExtractLogo::SearchLogo(): seek to frame 34104 Sat Jul 24 22:52:25 [31788] DEBUG: cDecoder::SeekToFrame(): (34104) Sat Jul 24 22:52:41 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:53:14 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:53:14 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:53:14 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): 1001 valid frames of 963 frames read, got enough iFrames at frame (65019), start analyze Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 22342 with 2 similars out of 36 valid frames at TOP_LEFT Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 14751 with 24 similars out of 88 valid frames at TOP_RIGHT Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 24533 with 20 similars out of 102 valid frames at BOTTOM_LEFT Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 49616 with 5 similars out of 50 valid frames at BOTTOM_RIGHT Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): no valid logo found, best logo at frame 14751 with 24 similars at corner TOP_RIGHT Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 65019 Sat Jul 24 22:53:58 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:53:58 [31788] INFO: no logo found in recording, retry in 3nd recording part Sat Jul 24 22:53:58 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 18000 with aspect ratio 16:9 Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 18000 Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): already have 96 frames from (18687) to frame (64923) Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 5 Sat Jul 24 22:53:58 [31788] DEBUG: cExtractLogo::SearchLogo(): seek to frame 64923 Sat Jul 24 22:53:58 [31788] DEBUG: cDecoder::SeekToFrame(): (64923) Sat Jul 24 22:54:20 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 22:54:20 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:54:20 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:54:21 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:54:21 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:54:21 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:54:21 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:54:31 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): 1001 valid frames of 905 frames read, got enough iFrames at frame (94080), start analyze Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 71496 with 5 similars out of 53 valid frames at TOP_LEFT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 19551 with 61 similars out of 226 valid frames at TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 24533 with 20 similars out of 148 valid frames at BOTTOM_LEFT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 49616 with 5 similars out of 71 valid frames at BOTTOM_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): best corner is TOP_RIGHT at frame 19551 with 61 similars Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::Resize(): logo size before resize: 400 width 210 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): cut out 6p lines horizontal and 0p column vertical Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 400 width 204 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::Resize(): found text under logo, cut at line 156, size 72Wx48H, pixel before: left 146 right 218, qoute 76, width is valid Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): cut out 48p lines horizontal and 0p column vertical Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 400 width 156 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 164p column vertical Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 236 width 156 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::Resize(): logo size after 1. resize: 236 width 156 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): cut out 36p lines horizontal and 0p column vertical Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 236 width 120 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 236p column vertical Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 0 width 120 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::Resize(): logo size after 2. resize: 0 width 120 height on corner TOP_RIGHT Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::Resize(): video 1920x1080 with logo size 0 width 120 height on corner TOP_RIGHT is not valid Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): resize logo from best corner failed Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 94080 Sat Jul 24 22:55:58 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:55:58 [31788] INFO: no logo found in recording, retry in 4nd recording part Sat Jul 24 22:55:58 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 25500 with aspect ratio 16:9 Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 25500 Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): already have 219 frames from (25525) to frame (94080) Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:55:58 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:55:58 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:55:59 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:55:59 [31788] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 5 Sat Jul 24 22:55:59 [31788] DEBUG: cExtractLogo::SearchLogo(): seek to frame 94080 Sat Jul 24 22:55:59 [31788] DEBUG: cDecoder::SeekToFrame(): (94080) Sat Jul 24 22:56:34 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 22:56:34 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:56:34 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:56:35 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:56:35 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:56:35 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:56:35 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:57:10 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 2 is (94882), end time 3775600ms ( 62:55min) Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:57:12 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:57:12 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:57:12 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): detect new horizontal border from frame (94691) to frame (96195) Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 94691 to 96195 Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): border frame detected, abort logo search Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): read (66) frames and could not get enough valid frames (276) Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 96195 Sat Jul 24 22:57:16 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:57:16 [31788] INFO: no logo found in recording, retry in 5nd recording part Sat Jul 24 22:57:16 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 33000 with aspect ratio 16:9 Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 33000 Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): already have 216 frames from (33048) to frame (94274) Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:57:16 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:57:16 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:57:17 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:57:17 [31788] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 5 Sat Jul 24 22:57:17 [31788] DEBUG: cExtractLogo::SearchLogo(): seek to frame 94274 Sat Jul 24 22:57:17 [31788] DEBUG: cDecoder::SeekToFrame(): (94274) Sat Jul 24 22:57:40 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 22:57:40 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:57:40 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:57:40 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:57:40 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:57:40 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:57:40 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:58:08 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 22:58:08 [31788] DEBUG: AVlog(): Missing reference picture, default is 6 Sat Jul 24 22:58:08 [31788] DEBUG: AVlog(): decode_slice_header error Sat Jul 24 22:58:08 [31788] DEBUG: AVlog(): Missing reference picture, default is 6 Sat Jul 24 22:58:08 [31788] DEBUG: AVlog(): decode_slice_header error Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 2 is (94882), end time 3775600ms ( 62:55min) Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:58:10 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:58:10 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:58:10 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::SearchLogo(): detect new horizontal border from frame (94691) to frame (96195) Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 94691 to 96195 Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::SearchLogo(): border frame detected, abort logo search Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::SearchLogo(): read (61) frames and could not get enough valid frames (268) Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 96195 Sat Jul 24 22:58:14 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:58:14 [31788] INFO: no logo found in recording, retry in 6nd recording part Sat Jul 24 22:58:14 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 40500 with aspect ratio 16:9 Sat Jul 24 22:58:14 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 40500 Sat Jul 24 22:58:14 [31788] DEBUG: cExtractLogo::SearchLogo(): already have 213 frames from (40793) to frame (94274) Sat Jul 24 22:58:14 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:58:14 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:58:15 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:58:15 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 5 Sat Jul 24 22:58:15 [31788] DEBUG: cExtractLogo::SearchLogo(): seek to frame 94274 Sat Jul 24 22:58:15 [31788] DEBUG: cDecoder::SeekToFrame(): (94274) Sat Jul 24 22:58:38 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 22:58:38 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:58:38 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 22:58:38 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:58:38 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:58:38 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:58:38 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:59:03 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 22:59:03 [31788] DEBUG: AVlog(): Missing reference picture, default is 6 Sat Jul 24 22:59:03 [31788] DEBUG: AVlog(): decode_slice_header error Sat Jul 24 22:59:03 [31788] DEBUG: AVlog(): Missing reference picture, default is 6 Sat Jul 24 22:59:03 [31788] DEBUG: AVlog(): decode_slice_header error Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 2 is (94882), end time 3775600ms ( 62:55min) Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:59:05 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:59:05 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:59:05 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): detect new horizontal border from frame (94691) to frame (96195) Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 94691 to 96195 Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): border frame detected, abort logo search Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): read (61) frames and could not get enough valid frames (265) Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): failed, last frame 96195 Sat Jul 24 22:59:09 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CheckLogo(): logo search failed Sat Jul 24 22:59:09 [31788] INFO: pre-timer 120s Sat Jul 24 22:59:09 [31788] INFO: broadcast length 60min Sat Jul 24 22:59:09 [31788] INFO: channel Kutonen_HD Sat Jul 24 22:59:09 [31788] INFO: parameter --loglevel is set to 3 Sat Jul 24 22:59:09 [31788] DEBUG: markad process nice level 0 Sat Jul 24 22:59:09 [31788] DEBUG: markad IO priority class 0 Sat Jul 24 22:59:09 [31788] DEBUG: parameter --logocachedir is set to /tmp Sat Jul 24 22:59:09 [31788] DEBUG: parameter --threads is set to -1 Sat Jul 24 22:59:09 [31788] DEBUG: parameter --astopoffs is set to 0 Sat Jul 24 22:59:09 [31788] DEBUG: parameter --log2rec is set Sat Jul 24 22:59:09 [31788] DEBUG: parameter --cut is set Sat Jul 24 22:59:09 [31788] DEBUG: parameter --autologo is set to 2 Sat Jul 24 22:59:09 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::ProcessFiles(): start processing files Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:59:09 [31788] INFO: video hight: 1080 Sat Jul 24 22:59:09 [31788] INFO: video width: 1920 Sat Jul 24 22:59:09 [31788] INFO: average frame rate 25 frames per second Sat Jul 24 22:59:09 [31788] INFO: real frame rate 25 frames per second Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 3000 (2min 0s) Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 25 Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 3600s ( 60min 0s) Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 3000 ( 2min 0s) Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 93000 ( 62min 0s) Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 15000 ( 10min 0s) Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 108000 ( 72min 0s) Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 5 Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 16:9, reload logo Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdLogo::Load(): try to find logo Kutonen_HD-A16_9 plane 0 in /tmp Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdLogo::Load(): file not found for logo Kutonen_HD-A16_9 plane 0 in /tmp Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdLogo::Load(): try to find logo Kutonen_HD-A16_9 plane 0 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 22:59:09 [31788] DEBUG: cMarkAdLogo::Load(): file not found for logo Kutonen_HD-A16_9 plane 0 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 22:59:09 [31788] INFO: no valid logo for Kutonen_HD-A16_9 in logo cache and recording directory, extract logo from recording Sat Jul 24 22:59:09 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 0 with aspect ratio 16:9 Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (107942) read frames (-1) minFrame (0) Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 22:59:09 [31788] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Sat Jul 24 22:59:09 [31788] DEBUG: cExtractLogo::SearchLogo(): logo size 400x210 Sat Jul 24 22:59:10 [31788] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Sat Jul 24 22:59:10 [31788] DEBUG: cExtractLogo::AudioInBroadcast(): got first time 2 channel at frame (5) Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::SearchLogo(): 1001 valid frames of 1001 frames read, got enough iFrames at frame (32184), start analyze Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 5525 with 4 similars out of 32 valid frames at TOP_LEFT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 9695 with 17 similars out of 77 valid frames at TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 24533 with 9 similars out of 56 valid frames at BOTTOM_LEFT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 5973 with 5 similars out of 26 valid frames at BOTTOM_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::SearchLogo(): best corner is TOP_RIGHT at frame 9695 with 17 similars Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Resize(): logo size before resize: 400 width 210 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): cut out 6p lines horizontal and 0p column vertical Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 400 width 204 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Resize(): found text under logo, cut at line 156, size 73Wx48H, pixel before: left 145 right 218, qoute 76, width is valid Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): cut out 48p lines horizontal and 0p column vertical Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 400 width 156 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 164p column vertical Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 236 width 156 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Resize(): logo size after 1. resize: 236 width 156 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): cut out 14p lines horizontal and 0p column vertical Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 236 width 142 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 236 width 142 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Resize(): logo size after 2. resize: 236 width 142 height on corner TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Resize(): video 1920x1080 with logo size 236 width 142 height on corner TOP_RIGHT is valid Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Save(): 447 pixel in plane 0 Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Save(): store logo in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/Kutonen_HD-A16_9-P0.pgm Sat Jul 24 23:00:25 [31788] INFO: Logo size for Channel: Kutonen_HD 16:9 1920W 1080H: 236W 142H TOP_RIGHT Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::isLogoColourChange(): 77 valid frames in corner 1, plane 1: 59 are white, ratio 76% Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Save(): logo is transparent or changed color, do not save plane 1 Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::isLogoColourChange(): 77 valid frames in corner 1, plane 2: 62 are white, ratio 80% Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::Save(): logo is transparent or changed color, do not save plane 2 Sat Jul 24 23:00:25 [31788] DEBUG: cExtractLogo::SearchLogo(): finished successfully, last frame 32184 Sat Jul 24 23:00:25 [31788] DEBUG: ---------------------------------------------------------------------------- Sat Jul 24 23:00:25 [31788] DEBUG: cMarkAdLogo::Process(): new logo for Kutonen_HD-A16_9 found in recording Sat Jul 24 23:00:25 [31788] DEBUG: cMarkAdLogo::Load(): try to find logo Kutonen_HD-A16_9 plane 0 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 23:00:25 [31788] DEBUG: cMarkAdLogo::Load(): file found for logo Kutonen_HD-A16_9 plane 0 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 23:00:25 [31788] INFO: logo Kutonen_HD-A16_9 found in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 23:00:25 [31788] DEBUG: cMarkAdLogo::Load(): try to find logo Kutonen_HD-A16_9 plane 1 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 23:00:25 [31788] DEBUG: cMarkAdLogo::Load(): file not found for logo Kutonen_HD-A16_9 plane 1 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 23:00:25 [31788] DEBUG: cMarkAdLogo::Load(): try to find logo Kutonen_HD-A16_9 plane 2 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 23:00:25 [31788] DEBUG: cMarkAdLogo::Load(): file not found for logo Kutonen_HD-A16_9 plane 2 in /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec Sat Jul 24 23:00:25 [31788] DEBUG: detected end of black screen (0)* at 0:00:00.00 inBroadCast: 0 Sat Jul 24 23:00:25 [31788] INFO: detected logo start (0)* at 0:00:00.00 inBroadCast: 1 Sat Jul 24 23:00:25 [31788] DEBUG: cMarks::Add(): duplicate mark on position 0 type 0x31 and type 0x21 Sat Jul 24 23:00:30 [31788] INFO: detected logo stop (2309) at 0:01:32.16 inBroadCast: 0 Sat Jul 24 23:00:38 [31788] INFO: detected logo start (7383)* at 0:04:50.55 inBroadCast: 1 Sat Jul 24 23:00:41 [31788] INFO: detected logo stop (9279) at 0:06:06.07 inBroadCast: 0 Sat Jul 24 23:00:42 [31788] INFO: detected logo start (9375)* at 0:06:09.92 inBroadCast: 1 Sat Jul 24 23:00:52 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): checking start at frame (15007) check start planed at (15000) Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): assumed start frame 3000 Sat Jul 24 23:00:52 [31788] DEBUG: ************************************************************* Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 0 type 0x31 at 0:00:00.00 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 2309 type 0x32 at 0:01:32.16 inBroadCast 0 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: ************************************************************* Sat Jul 24 23:00:52 [31788] INFO: no video aspect ratio found in vdr info file Sat Jul 24 23:00:52 [31788] INFO: HD video with aspectratio of 16:9 detected Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): no horizontal border at start found, ignore horizontal border detection Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): no vertical border at start found, ignore vertical border detection Sat Jul 24 23:00:52 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): start with iStart 3020, chkSTART 15000, iStopA 93000 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----------------------------------------------------------------------------------------- Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): stop (2309) start (7383) pair Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----------------------------------------------------------------------------------------- Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): stop (9279) start (9375) pair Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (2309) start (7383) pair: Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange -1 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 0 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isClosingCredits -1 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (9279) start (9375) pair: Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange -1 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 0 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isClosingCredits -1 Sat Jul 24 23:00:52 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 23:00:52 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:00:52 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:00:52 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:00:52 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:00:52 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:00:52 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Sat Jul 24 23:00:52 [31788] DEBUG: ************************************************************* Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 0 type 0x31 at 0:00:00.00 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 2309 type 0x32 at 0:01:32.16 inBroadCast 0 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: ************************************************************* Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Sat Jul 24 23:00:52 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): logo start mark found on position (0) at 0:00:00.00 Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): later logo start mark found on position (7383) at 0:04:50.55 Sat Jul 24 23:00:52 [31788] DEBUG: cEvaluateLogoStopStartPair::GetIsClosingCredits(): isClosingCredits for start (7383) mark: -1 Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): next logo stop mark (9279) but too far away 75s Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): disable border detection Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 7383 (4min 55s) Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 25 Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 3600s ( 60min 0s) Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 7383 ( 4min 55s) Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 97383 ( 64min 55s) Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 19383 ( 12min 55s) Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 112383 ( 74min 55s) Sat Jul 24 23:00:52 [31788] INFO: using mark on position (7383) type 0x31 at 0:04:50.55 as start mark Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::CheckStart(): delete all black screen marks except start mark Sat Jul 24 23:00:52 [31788] DEBUG: ************************************************************* Sat Jul 24 23:00:52 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:00:52 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:00:52 [31788] DEBUG: ************************************************************* Sat Jul 24 23:00:52 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:01:05 [31788] INFO: detected logo stop (21410) at 0:14:11.20 inBroadCast: 0 Sat Jul 24 23:01:14 [31788] INFO: detected logo start (26104)* at 0:17:16.80 inBroadCast: 1 Sat Jul 24 23:01:56 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 23:01:56 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:01:56 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:01:56 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:01:56 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:01:56 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:01:56 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:01:58 [31788] INFO: detected logo stop (47908) at 0:31:48.28 inBroadCast: 0 Sat Jul 24 23:02:09 [31788] INFO: detected logo start (54139)* at 0:35:55.32 inBroadCast: 1 Sat Jul 24 23:02:40 [31788] INFO: detected logo stop (69731) at 0:46:17.39 inBroadCast: 0 Sat Jul 24 23:02:52 [31788] INFO: detected logo start (76122)* at 0:50:29.56 inBroadCast: 1 Sat Jul 24 23:03:18 [31788] DEBUG: cMarkAdLogo::Detect(): black screen without logo detected at frame (89630) Sat Jul 24 23:03:18 [31788] INFO: detected logo stop (89598) at 0:59:28.43 inBroadCast: 0 Sat Jul 24 23:03:22 [31788] DEBUG: cMarkAdStandalone::ProcessFrame(): enter end part at frame (91393) Sat Jul 24 23:03:28 [31788] INFO: detected logo start (94691)* at 1:02:48.12 inBroadCast: 1 Sat Jul 24 23:03:28 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 2 is (94882), end time 3775600ms ( 62:55min) Sat Jul 24 23:03:28 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 23:03:28 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 23:03:28 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:03:28 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:03:28 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:03:28 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:03:29 [31788] DEBUG: detected start of black screen (95235) at 1:03:09.67 inBroadCast: 1 Sat Jul 24 23:03:29 [31788] DEBUG: detected end of black screen (95267)* at 1:03:10.95 inBroadCast: 1 Sat Jul 24 23:03:30 [31788] DEBUG: detected start of black screen (95683) at 1:03:27.60 inBroadCast: 1 Sat Jul 24 23:03:30 [31788] DEBUG: detected end of black screen (96067)* at 1:03:42.95 inBroadCast: 1 Sat Jul 24 23:03:44 [31788] DEBUG: detected start of black screen (102371) at 1:07:55.12 inBroadCast: 1 Sat Jul 24 23:03:44 [31788] DEBUG: detected end of black screen (102403)* at 1:07:56.39 inBroadCast: 1 Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 3 is (108424), end time 4317120ms ( 71:57min) Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00004.ts Sat Jul 24 23:03:56 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): start check stop (108424) Sat Jul 24 23:03:56 [31788] DEBUG: assumed stop position (97383) at 1:04:35.43 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 94691 type 0x31 at 1:02:48.12 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 95235 type 0x22 at 1:03:09.67 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 95267 type 0x21 at 1:03:10.95 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 95683 type 0x22 at 1:03:27.60 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 96067 type 0x21 at 1:03:42.95 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 102371 type 0x22 at 1:07:55.12 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 102403 type 0x21 at 1:07:56.39 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): start with iStart 0, chkSTART 19383, iStopA 97383 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): stop (9279) start (9375) pair Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----- stop (9279) start (9375) pair: next stop mark after stop/start pair in 481s (expect >=240s, start mark is in braoscast) Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): stop (21410) start (26104) pair Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----- stop (21410) start (26104) pair: next stop mark after stop/start pair in 872s (expect >=240s, start mark is in braoscast) Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): stop (47908) start (54139) pair Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----- stop (47908) start (54139) pair: next stop mark after stop/start pair in 623s (expect >=240s, start mark is in braoscast) Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): stop (69731) start (76122) pair Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----- stop (69731) start (76122) pair: next stop mark after stop/start pair in 539s (expect >=240s, start mark is in braoscast) Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): ----------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): stop (89598) start (94691) pair Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (9279) start (9375) pair: Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isClosingCredits -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (21410) start (26104) pair: Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isClosingCredits -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (47908) start (54139) pair: Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isClosingCredits -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (69731) start (76122) pair: Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isClosingCredits -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): add stop (89598) start (94691) pair: Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isLogoChange -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isAdvertising 0 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isStartMarkInBroadcast 0 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isInfoLogo -1 Sat Jul 24 23:03:56 [31788] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): isClosingCredits -1 Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::cDecoder(): init with 1 threads Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 94691 type 0x31 at 1:02:48.12 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 95235 type 0x22 at 1:03:09.67 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 95267 type 0x21 at 1:03:10.95 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 95683 type 0x22 at 1:03:27.60 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 96067 type 0x21 at 1:03:42.95 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 102371 type 0x22 at 1:07:55.12 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 102403 type 0x21 at 1:07:56.39 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): no MT_CHANNELSTOP mark found Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): no MT_ASPECTSTOP mark found Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): no MT_HBORDERSTOP mark found Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): no MT_VBORDERSTOP mark found Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): MT_LOGOSTOP found at frame 89598 Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): logo stop mark (89598) is valid, logo start mark (76122) is 539s before Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): logo start (76122) stop (69731) pair before assumed end mark is far 539s (expect >13s) or long 255s (expect >4s), end mark is valid Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): logo stop before at (69731) too far away 794s (expect <14s), no alternative Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): end mark (89598) 311s before assumed stop (97383) Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): advertising before from (76122) to (69731) 255s Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): advertising after from (89598) to (94691) 203s Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): broadcast before from (76122) to (89598) 539s Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): found end mark at (89598) Sat Jul 24 23:03:56 [31788] INFO: using mark on position (89598) type 0x32 at 0:59:28.43 as stop mark Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): delete all marks after final stop mark at (89598) Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): move all black screen marks except start and end mark to black screen list Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckStop(): end check stop Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): remove invalid marks Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): stop (9279) start (9375) length 3 Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): stop (21410) start (26104) length 187 Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): stop (47908) start (54139) length 249 Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): stop (69731) start (76122) length 255 Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): delete logo marks if we have channel or border marks Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): delete invalid black sceen marks Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): delete very short logo stop/start pairs Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): logo stop (9279) and logo start (9375) pair, diff 3840ms long enough Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): logo stop (21410) and logo start (26104) pair, diff 187760ms long enough Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): logo stop (47908) and logo start (54139) pair, diff 249240ms long enough Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): logo stop (69731) and logo start (76122) pair, diff 255640ms long enough Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): detect previews in advertisement Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): start (9375) stop (21410): length 481s, length ad before 3840ms, length ad after 187760ms Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): no preview between (9375) and (21410), length 481s not valid Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): start (26104) stop (47908): length 872s, length ad before 187760ms, length ad after 249240ms Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): no preview between (26104) and (47908), length 872s not valid Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): start (54139) stop (69731): length 623s, length ad before 249240ms, length ad after 255640ms Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): no preview between (54139) and (69731), length 623s not valid Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): check border marks Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): check for short start/stop pairs at start Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): first broadcast length 75s from (7383) to (9279) Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): check for near better end mark in case of recording length is too big Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): last broadcast length 539s from (76122) to (89598) Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): last advertising length 255s from (69731) to (76122) Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): remove logo and hborder detection failure marks Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9279 type 0x32 at 0:06:06.07 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 9375 type 0x31 at 0:06:09.92 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] INFO: mark distance between logo STOP and START too short (3.8s), deleting 9279,9375 Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): mark distance between logo START and STOP 872.2s, keep (26104,47908) Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): mark distance between logo START and STOP 623.7s, keep (54139,69731) Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): mark distance between logo START and STOP 539.0s, keep (76122,89598) Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::CheckMarks(): final marks: Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 7383 type 0x31 at 0:04:50.55 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 21410 type 0x32 at 0:14:11.20 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 47908 type 0x32 at 0:31:48.28 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 54139 type 0x31 at 0:35:55.32 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 69731 type 0x32 at 0:46:17.39 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:03:56 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:03:56 [31788] DEBUG: ************************************************************* Sat Jul 24 23:03:56 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:03:56 [31788] DEBUG: cMarkAdStandalone::ProcessFiles(): end processing files Sat Jul 24 23:03:56 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:03:56 [31788] INFO: start 2nd pass (detect overlaps) Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:03:56 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:03:57 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:03:57 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:03:57 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:03:57 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:03:57 [31788] DEBUG: cMarkAdStandalone::Process2ndPass(): ->->->->-> check overlap before stop frame (21410) and after start frame (26104) Sat Jul 24 23:03:57 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): preload from frame (18399) to (21410) Sat Jul 24 23:03:57 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): compare with frames from (26104) to (33604) Sat Jul 24 23:03:57 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): start check 120s before at frame (18399) and start overlap check at 0:12:10.88 Sat Jul 24 23:03:57 [31788] DEBUG: cDecoder::SeekToFrame(): (18399) Sat Jul 24 23:04:05 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:04:05 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): 95 i-frames to preload between start of check (18399) and stop mark (21410) Sat Jul 24 23:04:12 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): seek forward to iFrame (26072) at 0:17:15.52 before start mark (26104) and start overlap check Sat Jul 24 23:04:12 [31788] DEBUG: cDecoder::SeekToFrame(): (26072) Sat Jul 24 23:04:14 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:04:14 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): process overlap detection between frame (26072) at 0:17:15.52 and frame (33604) at 0:22:16.31 Sat Jul 24 23:04:29 [31788] DEBUG: cMarkAdStandalone::Process2ndPass(): no overlap found for marks before frames (21410) and after (26104) Sat Jul 24 23:04:29 [31788] DEBUG: cMarkAdStandalone::Process2ndPass(): ->->->->-> check overlap before stop frame (47908) and after start frame (54139) Sat Jul 24 23:04:29 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): next stop mark at (69731) to near, reduce check end position Sat Jul 24 23:04:29 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): preload from frame (44889) to (47908) Sat Jul 24 23:04:29 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): compare with frames from (54139) to (66706) Sat Jul 24 23:04:29 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): start check 120s before at frame (44889) and start overlap check at 0:29:48.16 Sat Jul 24 23:04:29 [31788] DEBUG: cDecoder::SeekToFrame(): (44889) Sat Jul 24 23:04:35 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:04:35 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): 95 i-frames to preload between start of check (44889) and stop mark (47908) Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 23:04:41 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): GetNextPacket failed at frame (47395) Sat Jul 24 23:04:41 [31788] DEBUG: cMarkAdStandalone::Process2ndPass(): no overlap found for marks before frames (47908) and after (54139) Sat Jul 24 23:04:41 [31788] DEBUG: cMarkAdStandalone::Process2ndPass(): ->->->->-> check overlap before stop frame (69731) and after start frame (76122) Sat Jul 24 23:04:41 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): preload from frame (66715) to (69731) Sat Jul 24 23:04:41 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): compare with frames from (76122) to (83622) Sat Jul 24 23:04:41 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): start check 120s before at frame (66715) and start overlap check at 0:44:17.08 Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::SeekToFrame(): (66715) Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:04:41 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:04:52 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:04:52 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): 95 i-frames to preload between start of check (66715) and stop mark (69731) Sat Jul 24 23:04:58 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): seek forward to iFrame (76090) at 0:50:28.28 before start mark (76122) and start overlap check Sat Jul 24 23:04:58 [31788] DEBUG: cDecoder::SeekToFrame(): (76090) Sat Jul 24 23:05:01 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:01 [31788] DEBUG: cMarkAdStandalone::ProcessMark2ndPass(): process overlap detection between frame (76090) at 0:50:28.28 and frame (83622) at 0:55:29.08 Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process2ndPass(): no overlap found for marks before frames (69731) and after (76122) Sat Jul 24 23:05:17 [31788] DEBUG: end 2ndPass Sat Jul 24 23:05:17 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:05:17 [31788] INFO: start 3nd pass (optimze logo marks) Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): check last logo stop mark if closing credits follows Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search for closing credits Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::MoveLastStopAfterClosingCredits(): check closing credits without logo after position (89598) Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::MoveLastStopAfterClosingCredits(): no closing credits found Sat Jul 24 23:05:17 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): check for advertising in frame with logo after logo start and before logo stop mark and check for introduction logo Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search introduction logo from position (6633) at 0:04:21.11 to logo start mark (7383) at 0:04:50.55 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search advertising in frame with logo after logo start mark (7383) at 0:04:50.55 to position (8258) at 0:05:25.11 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search advertising in frame with logo from frame (20285) at 0:13:26.40 to logo stop mark (21410) at 0:14:11.20 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search introduction logo from position (25354) at 0:16:47.35 to logo start mark (26104) at 0:17:16.80 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search advertising in frame with logo after logo start mark (26104) at 0:17:16.80 to position (26979) at 0:17:51.35 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search advertising in frame with logo from frame (46783) at 0:31:03.68 to logo stop mark (47908) at 0:31:48.28 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search introduction logo from position (53389) at 0:35:25.87 to logo start mark (54139) at 0:35:55.32 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search advertising in frame with logo after logo start mark (54139) at 0:35:55.32 to position (55014) at 0:36:29.87 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search advertising in frame with logo from frame (68606) at 0:45:32.60 to logo stop mark (69731) at 0:46:17.39 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search introduction logo from position (75372) at 0:50:00.12 to logo start mark (76122) at 0:50:29.56 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search advertising in frame with logo after logo start mark (76122) at 0:50:29.56 to position (76997) at 0:51:04.12 Sat Jul 24 23:05:17 [31788] DEBUG: cDetectLogoStopStart::Detect(): channel not in list for special logo detection Sat Jul 24 23:05:17 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): search for audio silence around logo marks Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:05:17 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo mark at frame ( 7383) type 0x31 at 0:04:50.55 range 5s Sat Jul 24 23:05:17 [31788] DEBUG: cDecoder::SeekToFrame(): (7258) Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (7258) to frame (7383) Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7260) level 0 silenceCount 2, pts 3547938615 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7260) level 0 silenceCount 4, pts 3547940775 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7260) level 0 silenceCount 6, pts 3547942935 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7260) level 0 silenceCount 8, pts 3547945095 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7260) level 0 silenceCount 10, pts 3547947255 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 12, pts 3547949415 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 14, pts 3547951575 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 16, pts 3547953735 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 18, pts 3547955895 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 20, pts 3547958055 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 22, pts 3547960215 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 24, pts 3547962375 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 26, pts 3547964535 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 28, pts 3547966695 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 30, pts 3547968855 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7267) level 0 silenceCount 32, pts 3547971015 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7274) level 0 silenceCount 34, pts 3547973175 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame ( 7274) level 0 silenceCount 36, pts 3547975335 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): found silence part in stream 1 between audio frame (7260) and (7274) Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::GetNextSilence(): use audio frame (7274) PTS 3547975335, video frame (7261) PTS 3548026181, return frame (7255) Sat Jul 24 23:05:20 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence before logo start at frame (7255) Sat Jul 24 23:05:20 [31788] INFO: moved start mark (7255) before logo start mark (7383) at 0:04:50.55, silence detected* Sat Jul 24 23:05:20 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo stop mark at frame ( 21410) type 0x32 at 0:14:11.20 range 5 Sat Jul 24 23:05:20 [31788] DEBUG: cDecoder::SeekToFrame(): (21285) Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (21285) to frame (21410) Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 19 silenceCount 1, pts 3598607895 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 19 silenceCount 2, pts 3598610055 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 16 silenceCount 3, pts 3598612215 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 17 silenceCount 4, pts 3598614375 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 14 silenceCount 5, pts 3598616535 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 9 silenceCount 6, pts 3598618695 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 8 silenceCount 7, pts 3598620855 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 5 silenceCount 9, pts 3598623015 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 5 silenceCount 11, pts 3598625175 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21349) level 4 silenceCount 13, pts 3598627335 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21357) level 3 silenceCount 15, pts 3598629495 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21357) level 2 silenceCount 17, pts 3598631655 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21357) level 1 silenceCount 19, pts 3598633815 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21357) level 1 silenceCount 21, pts 3598635975 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21357) level 1 silenceCount 23, pts 3598638135 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21357) level 1 silenceCount 25, pts 3598640295 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (21357) level 0 silenceCount 27, pts 3598642455 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): found silence part in stream 1 between audio frame (21349) and (21357) Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): use audio frame (21349) PTS 3598607895, video frame (21321) PTS 3598606181, return frame (21314) Sat Jul 24 23:05:27 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence before logo stop mark (21410) at frame (21314) Sat Jul 24 23:05:27 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence after logo stop mark at frame ( 21410) type 0x32 at 0:14:11.20 range 5 Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::SeekToFrame(): (21410) Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (21410) to frame (21510) Sat Jul 24 23:05:27 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence for mark at frame ( 21410) type 0x32 at 0:14:11.20 range 5 Sat Jul 24 23:05:27 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): use audio silence before logo stop at iFrame (21314) Sat Jul 24 23:05:27 [31788] INFO: moved stop mark (21314) before logo stop mark (21410) at 0:14:11.20, silence detected Sat Jul 24 23:05:27 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo mark at frame ( 26104) type 0x31 at 0:17:16.80 range 5s Sat Jul 24 23:05:27 [31788] DEBUG: cDecoder::SeekToFrame(): (25979) Sat Jul 24 23:05:30 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:30 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (25979) to frame (26104) Sat Jul 24 23:05:30 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (26064) level 0 silenceCount 2, pts 3615384615 Sat Jul 24 23:05:30 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (26064) level 9 silenceCount 3, pts 3615386775 Sat Jul 24 23:05:30 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): no audio silence before logo mark at frame ( 26104) type 0x31 at 0:17:16.80 found Sat Jul 24 23:05:30 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo stop mark at frame ( 47908) type 0x32 at 0:31:48.28 range 5 Sat Jul 24 23:05:30 [31788] DEBUG: cDecoder::SeekToFrame(): (47783) Sat Jul 24 23:05:40 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 23:05:40 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:05:40 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (47783) to frame (47908) Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47862) level 24 silenceCount 1, pts 3693831495 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47862) level 21 silenceCount 2, pts 3693833655 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 22 silenceCount 3, pts 3693835815 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 19 silenceCount 4, pts 3693837975 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 13 silenceCount 5, pts 3693840135 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 10 silenceCount 6, pts 3693842295 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 8 silenceCount 7, pts 3693844455 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 7 silenceCount 9, pts 3693846615 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 6 silenceCount 11, pts 3693848775 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 4 silenceCount 13, pts 3693850935 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 3 silenceCount 15, pts 3693853095 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 2 silenceCount 17, pts 3693855255 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47869) level 2 silenceCount 19, pts 3693857415 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47874) level 1 silenceCount 21, pts 3693859575 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47874) level 1 silenceCount 23, pts 3693861735 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (47874) level 4 silenceCount 25, pts 3693863895 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): found silence part in stream 1 between audio frame (47862) and (47874) Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): use audio frame (47862) PTS 3693831495, video frame (47837) PTS 3693829781, return frame (47812) Sat Jul 24 23:05:41 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence before logo stop mark (47908) at frame (47812) Sat Jul 24 23:05:41 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence after logo stop mark at frame ( 47908) type 0x32 at 0:31:48.28 range 5 Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::SeekToFrame(): (47908) Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (47908) to frame (48008) Sat Jul 24 23:05:41 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence for mark at frame ( 47908) type 0x32 at 0:31:48.28 range 5 Sat Jul 24 23:05:41 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): use audio silence before logo stop at iFrame (47812) Sat Jul 24 23:05:41 [31788] INFO: moved stop mark (47812) before logo stop mark (47908) at 0:31:48.28, silence detected Sat Jul 24 23:05:41 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo mark at frame ( 54139) type 0x31 at 0:35:55.32 range 5s Sat Jul 24 23:05:41 [31788] DEBUG: cDecoder::SeekToFrame(): (54014) Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (54014) to frame (54139) Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 2, pts 3715982295 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 4, pts 3715984455 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 6, pts 3715986615 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 8, pts 3715988775 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 10, pts 3715990935 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 12, pts 3715993095 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 14, pts 3715995255 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 16, pts 3715997415 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 18, pts 3715999575 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54073) level 0 silenceCount 20, pts 3716001735 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54081) level 0 silenceCount 22, pts 3716003895 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54081) level 0 silenceCount 24, pts 3716006055 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (54081) level 1 silenceCount 26, pts 3716008215 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): found silence part in stream 1 between audio frame (54073) and (54081) Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::GetNextSilence(): use audio frame (54081) PTS 3716008215, video frame (54054) PTS 3716009381, return frame (54043) Sat Jul 24 23:05:45 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence before logo start at frame (54043) Sat Jul 24 23:05:45 [31788] INFO: moved start mark (54043) before logo start mark (54139) at 0:35:55.32, silence detected* Sat Jul 24 23:05:45 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo stop mark at frame ( 69731) type 0x32 at 0:46:17.39 range 5 Sat Jul 24 23:05:45 [31788] DEBUG: cDecoder::SeekToFrame(): (69606) Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (69606) to frame (69731) Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 21 silenceCount 1, pts 3772034295 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 19 silenceCount 2, pts 3772036455 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 16 silenceCount 3, pts 3772038615 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 18 silenceCount 4, pts 3772040775 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 11 silenceCount 5, pts 3772042935 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 8 silenceCount 6, pts 3772045095 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 7 silenceCount 8, pts 3772047255 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 5 silenceCount 10, pts 3772049415 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69684) level 5 silenceCount 12, pts 3772051575 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 3 silenceCount 14, pts 3772053735 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 2 silenceCount 16, pts 3772055895 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 1 silenceCount 18, pts 3772058055 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 1 silenceCount 20, pts 3772060215 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 1 silenceCount 22, pts 3772062375 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 0 silenceCount 24, pts 3772064535 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 1 silenceCount 26, pts 3772066695 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 1 silenceCount 28, pts 3772068855 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 0 silenceCount 30, pts 3772071015 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (69691) level 0 silenceCount 32, pts 3772073175 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): found silence part in stream 1 between audio frame (69684) and (69691) Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): use audio frame (69684) PTS 3772034295, video frame (69657) PTS 3772032581, return frame (69635) Sat Jul 24 23:05:53 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence before logo stop mark (69731) at frame (69635) Sat Jul 24 23:05:53 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence after logo stop mark at frame ( 69731) type 0x32 at 0:46:17.39 range 5 Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::SeekToFrame(): (69731) Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (69731) to frame (69831) Sat Jul 24 23:05:53 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): found audio silence for mark at frame ( 69731) type 0x32 at 0:46:17.39 range 5 Sat Jul 24 23:05:53 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): use audio silence before logo stop at iFrame (69635) Sat Jul 24 23:05:53 [31788] INFO: moved stop mark (69635) before logo stop mark (69731) at 0:46:17.39, silence detected Sat Jul 24 23:05:53 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo mark at frame ( 76122) type 0x31 at 0:50:29.56 range 5s Sat Jul 24 23:05:53 [31788] DEBUG: cDecoder::SeekToFrame(): (75997) Sat Jul 24 23:05:57 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:05:57 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (75997) to frame (76122) Sat Jul 24 23:05:57 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (76055) level 12 silenceCount 1, pts 3794662455 Sat Jul 24 23:05:57 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (76055) level 2 silenceCount 3, pts 3794664615 Sat Jul 24 23:05:57 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (76055) level 11 silenceCount 4, pts 3794666775 Sat Jul 24 23:05:57 [31788] DEBUG: cDecoder::GetNextSilence(): stream 1 frame (76120) level 24 silenceCount 1, pts 3794908695 Sat Jul 24 23:05:57 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): no audio silence before logo mark at frame ( 76122) type 0x31 at 0:50:29.56 found Sat Jul 24 23:05:57 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence before logo stop mark at frame ( 89598) type 0x32 at 0:59:28.43 range 5 Sat Jul 24 23:05:57 [31788] DEBUG: cDecoder::SeekToFrame(): (89473) Sat Jul 24 23:06:05 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:06:05 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (89473) to frame (89598) Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): detect audio silence after logo stop mark at frame ( 89598) type 0x32 at 0:59:28.43 range 5 Sat Jul 24 23:06:05 [31788] DEBUG: cDecoder::SeekToFrame(): (89598) Sat Jul 24 23:06:05 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:06:05 [31788] DEBUG: cDecoder::GetNextSilence(): using stream index 1 from frame (89598) to frame (89698) Sat Jul 24 23:06:05 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): start search for blackscreen near logo marks Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): no black screen mark found before logo start mark (26104) Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): no black screen mark found before logo start mark (76122) Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::Process3ndPass(): blackscreen (95267) 226760ms (expect <=4270s) after logo stop mark (89598) found Sat Jul 24 23:06:05 [31788] DEBUG: ======================================================================================================================= Sat Jul 24 23:06:05 [31788] INFO: start cut video based on marks Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::MarkadCut(): final marks are: Sat Jul 24 23:06:05 [31788] DEBUG: ************************************************************* Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 7255 type 0xE1 at 0:04:45.44 inBroadCast 0 Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 21314 type 0xE2 at 0:14:07.35 inBroadCast 0 Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 26104 type 0x31 at 0:17:16.80 inBroadCast 1 Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 47812 type 0xE2 at 0:31:44.43 inBroadCast 0 Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 54043 type 0xE1 at 0:35:51.47 inBroadCast 0 Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 69635 type 0xE2 at 0:46:13.56 inBroadCast 0 Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 76122 type 0x31 at 0:50:29.56 inBroadCast 1 Sat Jul 24 23:06:05 [31788] DEBUG: mark at position 89598 type 0x32 at 0:59:28.43 inBroadCast 0 Sat Jul 24 23:06:05 [31788] DEBUG: ************************************************************* Sat Jul 24 23:06:05 [31788] DEBUG: cEncoder::cEncoder(): init with 1 threads Sat Jul 24 23:06:05 [31788] DEBUG: cMarkAdStandalone::MarkadCut(): start pass 0 Sat Jul 24 23:06:05 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:06:05 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:06:06 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:06:06 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:06:06 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:06:06 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:06:06 [31788] DEBUG: cDecoder::SeekToFrame(): (7255) Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::OpenFile(): cutName '2021.07.22-20#3A00-to' Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::OpenFile(): write to '/mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/2021.07.22-20#3A00-to.ts' Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::OpenFile(): output format MPEG-TS (MPEG-2 Transport Stream) Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::OpenFile(): best video: stream 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::OpenFile(): best audio: stream 1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::OpenFile(): source stream 0 -----> target stream 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): using encoder id 27 'libx264 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10' for output stream 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 avg framerate 25/1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 real framerate 25/1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 keyint_min 25 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input format stream 0 bit_rate 8886570 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 bit_rate 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 rc_max_rate 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 bit_rate_tolerance 4000000 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 global_quality 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 sample_rate 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 gop_size 12 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 level 40 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 aspect ratio 1:1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): target video bit rate 8502570 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 pix_fmt 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 keyint_min 1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 max_b_frames 7 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 bit_rate 8502570 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 rc_max_rate 0 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 bit_rate_tolerance 4000000 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 level 40 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 framerate 25/1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 gop_size 32 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 level 40 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 aspect ratio 1:1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): output stream 0 timebase 1/25 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): avcodec_open2 for stream 0 successful Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::OpenFile(): source stream 1 -----> target stream 1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): using encoder id 86016 'MP2 (MPEG audio layer 2)' for output stream 1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): input codec sample rate 48000, timebase 1/48000 for stream 1 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): audio output codec parameter for stream 1: bit_rate 384000 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): output stream 1 timebase 1/48000 Sat Jul 24 23:06:09 [31788] DEBUG: cEncoder::InitEncoderCodec(): avcodec_open2 for stream 1 successful Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00001.ts Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:06:09 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:06:09 [31788] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (7255) for start mark (7255) to frame (21314) in pass 0 Sat Jul 24 23:06:09 [31788] DEBUG: cDecoder::SeekToFrame(): (7255) Sat Jul 24 23:06:10 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:06:10 [31788] DEBUG: cEncoder::WritePacket(): start cut at frame ( 7255) PTS 3548022581 Sat Jul 24 23:06:10 [31788] DEBUG: cEncoder::WritePacket(): new dts/pts offset 0 Sat Jul 24 23:06:27 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:06:27 [31788] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (26104) for start mark (26104) to frame (47812) in pass 0 Sat Jul 24 23:06:27 [31788] DEBUG: cDecoder::SeekToFrame(): (26104) Sat Jul 24 23:06:30 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:06:30 [31788] DEBUG: cEncoder::WritePacket(): start cut at frame ( 26104) PTS 3615644981 Sat Jul 24 23:06:30 [31788] DEBUG: cEncoder::WritePacket(): new dts/pts offset 17046000 Sat Jul 24 23:06:54 [31788] DEBUG: cDecoder::GetNextPacket(): last frame of filenumber 1 is (47395), end time 1887800ms ( 31:27min) Sat Jul 24 23:06:54 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:06:54 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00002.ts Sat Jul 24 23:06:55 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:06:55 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:06:55 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:06:55 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:06:55 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:06:55 [31788] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (54043) for start mark (54043) to frame (69635) in pass 0 Sat Jul 24 23:06:55 [31788] DEBUG: cDecoder::SeekToFrame(): (54043) Sat Jul 24 23:06:59 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:06:59 [31788] DEBUG: cEncoder::WritePacket(): start cut at frame ( 54043) PTS 3715984181 Sat Jul 24 23:06:59 [31788] DEBUG: cEncoder::WritePacket(): new dts/pts offset 39276000 Sat Jul 24 23:07:18 [31788] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Sat Jul 24 23:07:18 [31788] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (76122) for start mark (76122) to frame (89598) in pass 0 Sat Jul 24 23:07:18 [31788] DEBUG: cDecoder::SeekToFrame(): (76122) Sat Jul 24 23:07:22 [31788] DEBUG: cDecoder::SeekToFrame(): successful Sat Jul 24 23:07:22 [31788] DEBUG: cEncoder::WritePacket(): start cut at frame ( 76122) PTS 3795011381 Sat Jul 24 23:07:22 [31788] DEBUG: cEncoder::WritePacket(): new dts/pts offset 62312400 Sat Jul 24 23:07:38 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 23:07:38 [31788] DEBUG: cDecoder::DecodeFile(): opened file /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00003.ts Sat Jul 24 23:07:38 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Sat Jul 24 23:07:38 [31788] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Sat Jul 24 23:07:38 [31788] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Sat Jul 24 23:07:38 [31788] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86016 -> MP2 (MPEG audio layer 2) Sat Jul 24 23:07:38 [31788] DEBUG: cDecoder::DecodeFile(): filename: /mnt/Videot/VDR/Wheeler_Dealers/2021.07.22-20#3A00-to/2021-07-22.19.58.6-0.rec/00004.ts Sat Jul 24 23:07:38 [31788] DEBUG: cEncoder::EncodeFrame(): avcodec_receive_packet() encode of frame (89600) failed with return code -541478725 Sat Jul 24 23:07:38 [31788] DEBUG: cEncoder::EncodeFrame(): avcodec_receive_packet() encode of frame (89600) failed with return code -541478725 Sat Jul 24 23:07:39 [31788] ERROR: segmentation fault Sat Jul 24 23:07:39 [31788] ERROR: [bt] Execution path: Sat Jul 24 23:07:39 [31788] ERROR: [bt] markad(+0xc4b8) [0x555bd72034b8] Sat Jul 24 23:07:39 [31788] ERROR: [bt] /lib/x86_64-linux-gnu/libc.so.6(+0x46210) [0x7f269238d210] Sat Jul 24 23:07:39 [31788] ERROR: [bt] /lib/x86_64-linux-gnu/libavcodec.so.58(avcodec_flush_buffers+0x1f) [0x7f269324b31f] Sat Jul 24 23:07:39 [31788] ERROR: [bt] markad(_ZN8cEncoder9CloseFileEP8cDecoder+0x11c) [0x555bd721dcdc] Sat Jul 24 23:07:39 [31788] ERROR: [bt] markad(_ZN17cMarkAdStandalone9MarkadCutEv+0x50f) [0x555bd720aecf] Sat Jul 24 23:07:39 [31788] ERROR: [bt] markad(main+0x131e) [0x555bd7202c0e] Sat Jul 24 23:07:39 [31788] ERROR: [bt] /lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xf3) [0x7f269236e0b3] Sat Jul 24 23:07:39 [31788] ERROR: [bt] markad(_start+0x2e) [0x555bd7202efe]