Thu Dec 23 13:52:49 [1932] INFO: starting markad v3.0.19_V03 (726e0a5) (64bit) Thu Dec 23 13:52:49 [1932] INFO: using libavcodec.so.59.12.100 with 1 threads Thu Dec 23 13:52:49 [1932] INFO: on /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:52:49 [1932] INFO: broadcast with DolbyDigital5.1 (from vdr info) Thu Dec 23 13:52:49 [1932] INFO: aspect ratio 16:9 (from vdr info) Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::GetRecordingStart(): mount option noatime is set, use atime from directory /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec to get creation time Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::GetRecordingStart(): got no valid directory creation time, maybe recording was copied Thu Dec 23 13:39:03 2021 Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::GetRecordingStart(): broadcast start time from vdr info file Sun Dec 19 22:00:00 2021 Thu Dec 23 13:52:49 [1932] INFO: getting recording start from directory (can be wrong!) Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::LoadInfo(): recording start at Sun Dec 19 21:55:00 2021 Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::LoadInfo(): timer start at Sun Dec 19 22:00:00 2021 Thu Dec 23 13:52:49 [1932] INFO: VPS controlled recording start Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::LoadInfo(): no VPS start event found Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::LoadInfo(): broadcast start 45s after recording start Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::CheckLogo(): using logo directory /tmp Thu Dec 23 13:52:49 [1932] DEBUG: cMarkAdStandalone::CheckLogo(): searching logo for RTL_HD Thu Dec 23 13:52:49 [1932] INFO: no logo for RTL_HD 16:9 found in logo cache directory /tmp, trying to find logo in recording directory Thu Dec 23 13:52:49 [1932] INFO: no logo for RTL_HD 16:9 found in recording directory /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec, trying to extract logo from recording Thu Dec 23 13:52:49 [1932] DEBUG: ---------------------------------------------------------------------------- Thu Dec 23 13:52:49 [1932] DEBUG: cExtractLogo::SearchLogo(): start extract logo from frame 1125 with aspect ratio 16:9 Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::cDecoder(): create new decoder instance Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu Dec 23 13:52:49 [1932] DEBUG: cExtractLogo::WaitForFrames(): frames recorded (272624) read frames (-1) minFrame (0) Thu Dec 23 13:52:49 [1932] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 0 to 1125 Thu Dec 23 13:52:49 [1932] DEBUG: cExtractLogo::SearchLogo(): we have no frames already stored Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu Dec 23 13:52:49 [1932] DEBUG: cExtractLogo::SearchLogo(): video resolution 1920x1080 Thu Dec 23 13:52:49 [1932] DEBUG: cExtractLogo::SearchLogo(): logo size 400x220 Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 6 at frame (-1) PTS 2601559680 Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 1 Thu Dec 23 13:52:49 [1932] DEBUG: cExtractLogo::SearchLogo(): seek to frame 1125 Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::SeekToFrame(): (1125) Thu Dec 23 13:52:49 [1932] DEBUG: cIndex::GetIFrameBefore(): failed for frame (1125), index: first frame (0) last frame (0) Thu Dec 23 13:52:49 [1932] DEBUG: cDecoder::SeekFrame(): index does not yet contain frame ( 1125), decode from current frame (1) to build index Thu Dec 23 13:52:50 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): detect new horizontal border from frame (11970) to frame (13725) Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::DeleteFrames(): delete frames from 11970 to 13725 Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): border frame detected, abort logo search Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): end of recording reached at frame (13725), read (840) iFrames and got (824) valid iFrames, try anyway Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): 824 valid frames of 840 frames read, got enough iFrames at frame (13725), start analyze Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 2010 with 464 similars out of 531 valid frames at TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 5340 with 331 similars out of 508 valid frames at TOP_RIGHT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame 11865 with 6 similars out of 20 valid frames at BOTTOM_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): best guess found at frame -1 with 0 similars out of 9 valid frames at BOTTOM_RIGHT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): best corner is TOP_LEFT at frame 2010 with 464 similars Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Resize(): logo size before resize: 400 width 220 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): cut out 98p lines horizontal and 0p column vertical Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 400 width 122 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 24p column vertical Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 376 width 122 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Resize(): found text after logo, cut at column 331, pixel of text: top 96 bottom 114, text height 18 is valid Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): cut out 0p lines horizontal and 44p column vertical Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 332 width 122 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Resize(): logo size after 1. resize: 332 width 122 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 332 width 122 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::CutOut(): logo size after cut out: 332 width 122 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Resize(): found text after logo, cut at column 273, pixel test: top 89 bottom 121, text height 32 is not valid Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Resize(): logo size after 2. resize: 332 width 122 height on corner TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Resize(): video 1920x1080 with logo size 332 width 122 height on corner TOP_LEFT is valid Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Save(): 1960 pixel in plane 0 Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Save(): store logo plane 0 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/RTL_HD-A16_9-P0.pgm Thu Dec 23 13:53:33 [1932] INFO: logo found for channel: RTL_HD 16:9 1920W 1080H: 332W 122H TOP_LEFT Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::isLogoColourChange(): 531 valid frames in corner 0, plane 1: 416 are white, ratio 78% Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Save(): logo is transparent or changed color, do not save plane 1 Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::isLogoColourChange(): 531 valid frames in corner 0, plane 2: 276 are white, ratio 51% Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::Save(): logo is transparent or changed color, do not save plane 2 Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::~cDecoder(): decoder instance deleted Thu Dec 23 13:53:33 [1932] DEBUG: cExtractLogo::SearchLogo(): finished successfully, last frame 13725 Thu Dec 23 13:53:33 [1932] DEBUG: ---------------------------------------------------------------------------- Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CheckLogo(): found logo in recording Thu Dec 23 13:53:33 [1932] INFO: pre-timer: 0:00:45h Thu Dec 23 13:53:33 [1932] INFO: broadcast length: 2:50:00h Thu Dec 23 13:53:33 [1932] INFO: channel: RTL_HD Thu Dec 23 13:53:33 [1932] DEBUG: parameter --loglevel is set to 3 Thu Dec 23 13:53:33 [1932] DEBUG: markad process nice level 0 Thu Dec 23 13:53:33 [1932] DEBUG: markad IO priority class 0 Thu Dec 23 13:53:33 [1932] DEBUG: parameter --logocachedir is set to /tmp Thu Dec 23 13:53:33 [1932] DEBUG: parameter --threads is set to 1 Thu Dec 23 13:53:33 [1932] DEBUG: parameter --astopoffs is set to 0 Thu Dec 23 13:53:33 [1932] DEBUG: parameter --log2rec is set Thu Dec 23 13:53:33 [1932] DEBUG: parameter --cut is set Thu Dec 23 13:53:33 [1932] DEBUG: parameter --autologo is set to 2 Thu Dec 23 13:53:33 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::ProcessFiles(): start processing files Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::cDecoder(): create new decoder instance Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::GetVideoType(): video coding format: H.264 Thu Dec 23 13:53:33 [1932] INFO: video hight: 1080 Thu Dec 23 13:53:33 [1932] INFO: video width: 1920 Thu Dec 23 13:53:33 [1932] INFO: average frame rate: 25 frames per second Thu Dec 23 13:53:33 [1932] INFO: real frame rate: 25 frames per second Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 1125 (0min 45s) Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 25 Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 10200s (170min 0s) Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 1125 ( 0min 45s) Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 256125 (170min 45s) Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 13125 ( 8min 45s) Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 271125 (180min 45s) Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 6 at frame (-1) PTS 2601559680 Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdAudio::ChannelChange(): new audio stream 1 start at frame (-1) Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::GetFrameInfo(): interlaced video format Thu Dec 23 13:53:33 [1932] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 1 Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 16:9, reload logo Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 0 in /tmp Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): file not found for logo RTL_HD-A16_9 plane 0 in /tmp Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 0 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): file found for logo RTL_HD-A16_9 plane 0 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): logo plane 0 has 1960 pixel Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): logo pixel ratio of plane 0 is: 48 per mille Thu Dec 23 13:53:33 [1932] INFO: logo for RTL_HD 16:9 found in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 1 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): file not found for logo RTL_HD-A16_9 plane 1 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 2 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdLogo::Load(): file not found for logo RTL_HD-A16_9 plane 2 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:53:33 [1932] DEBUG: detected end of black screen ( 0)* at 0:00:00.00 inBroadCast: 0 Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:53:33 [1932] INFO: detected logo start ( 0)* at 0:00:00.00 Thu Dec 23 13:53:33 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:53:33 [1932] DEBUG: cMarks::Add(): duplicate mark on position 0 type 0x31 and type 0x21 Thu Dec 23 13:53:34 [1932] DEBUG: detected start of black screen ( 1905) at 0:01:16.19 inBroadCast: 1 Thu Dec 23 13:53:34 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:53:34 [1932] DEBUG: detected end of black screen ( 1920)* at 0:01:16.80 inBroadCast: 1 Thu Dec 23 13:53:34 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:53:43 [1932] DEBUG: cMarkAdLogo::ReduceBrightness(): logo area: xstart 159 xend 332, ystart 89 yend 122 Thu Dec 23 13:53:43 [1932] DEBUG: cMarkAdLogo::Detect(): black screen without logo detected at frame (11970) Thu Dec 23 13:53:43 [1932] INFO: detected logo stop ( 11940) at 0:07:57.60 Thu Dec 23 13:53:43 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:53:44 [1932] DEBUG: detected start of black screen ( 12585) at 0:08:23.39 inBroadCast: 0 Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:53:44 [1932] DEBUG: detected end of black screen ( 12735)* at 0:08:29.39 inBroadCast: 0 Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:53:44 [1932] INFO: detected start of horiz. borders ( 11970)* at 0:07:58.79 Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:53:44 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): checking start at frame (13725) check start planed at (13125) Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): assumed start frame 1125 Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 0 type 0x31 at 0:00:00.00 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 1905 type 0x22 at 0:01:16.19 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11940 type 0x32 at 0:07:57.60 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11970 type 0x51 at 0:07:58.79 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 12585 type 0x22 at 0:08:23.39 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 12735 type 0x21 at 0:08:29.39 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] INFO: DolbyDigital5.1 audio whith 6 Channels in stream 1 detected Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): no audio channel change found till now, do not disable logo/border/aspect detection Thu Dec 23 13:53:44 [1932] INFO: HD video with aspect ratio of 16:9 detected Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): no horizontal border at start found, ignore horizontal border detection Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): no vertical border at start found, ignore vertical border detection Thu Dec 23 13:53:44 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Thu Dec 23 13:53:44 [1932] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): start with iStart 1140, chkSTART 13125, iStopA 256125 Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::cDecoder(): create new decoder instance Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:53:44 [1932] DEBUG: cDecoder::~cDecoder(): decoder instance deleted Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 0 type 0x31 at 0:00:00.00 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 1905 type 0x22 at 0:01:16.19 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11940 type 0x32 at 0:07:57.60 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11970 type 0x51 at 0:07:58.79 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 12585 type 0x22 at 0:08:23.39 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 12735 type 0x21 at 0:08:29.39 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Thu Dec 23 13:53:44 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): logo start mark found on position (0) at 0:00:00.00 Thu Dec 23 13:53:44 [1932] DEBUG: cEvaluateLogoStopStartPair::GetIsClosingCredits(): start (0) mark not found Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): next logo stop mark (11940) but too far away 477s Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): logo start mark (0) too early, ignoring Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): found start mark (1920) type 0x21 after search for any type Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): found black screen from (1905) to (1920), length 600ms Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): next black screen from (12585) to (12735) in 426s, length 6s Thu Dec 23 13:53:44 [1932] INFO: using black screen start mark on position (1920) at 0:01:16.80 as first start mark Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStart(): delete all black screen marks except start mark Thu Dec 23 13:53:44 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStartMark(): check for short start/stop pairs at start Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11940 type 0x32 at 0:07:57.60 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11970 type 0x51 at 0:07:58.79 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStartMark(): first broadcast length 400s from (1920) to (11940) (expect <=96s) Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CheckStartMark(): first advertising length 1200ms from (11940) to (11970) Thu Dec 23 13:53:44 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 1920 (1min 16s) Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 25 Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording: 10200s (170min 0s) Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame: 1920 ( 1min 16s) Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame: 256920 (171min 16s) Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to: 13920 ( 9min 16s) Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to: 271920 (181min 16s) Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11940 type 0x32 at 0:07:57.60 inBroadCast 0 Thu Dec 23 13:53:44 [1932] DEBUG: mark at position 11970 type 0x51 at 0:07:58.79 inBroadCast 1 Thu Dec 23 13:53:44 [1932] DEBUG: ************************************************************* Thu Dec 23 13:53:44 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:53:55 [1932] DEBUG: detected start of black screen ( 23250) at 0:15:30.00 inBroadCast: 1 Thu Dec 23 13:53:55 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:53:55 [1932] DEBUG: detected end of black screen ( 23265)* at 0:15:30.59 inBroadCast: 1 Thu Dec 23 13:53:55 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:53:56 [1932] DEBUG: AVlog(): left block unavailable for requested intra mode Thu Dec 23 13:53:56 [1932] DEBUG: AVlog(): error while decoding MB 0 20, bytestream 3897 Thu Dec 23 13:53:56 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (24061), difference 1440ms should be 40ms, decoding errors 1 Thu Dec 23 13:53:56 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (24070) from stream 0 failed: decode_error_flags 12, decoding errors 2 Thu Dec 23 13:54:00 [1932] DEBUG: detected start of black screen ( 29530) at 0:19:42.59 inBroadCast: 1 Thu Dec 23 13:54:00 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:54:00 [1932] DEBUG: detected end of black screen ( 29680)* at 0:19:48.59 inBroadCast: 1 Thu Dec 23 13:54:00 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:54:15 [1932] DEBUG: detected start of black screen ( 42730) at 0:28:30.59 inBroadCast: 1 Thu Dec 23 13:54:15 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:54:15 [1932] DEBUG: detected end of black screen ( 43060)* at 0:28:43.80 inBroadCast: 1 Thu Dec 23 13:54:15 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:54:15 [1932] DEBUG: detected start of black screen ( 43255) at 0:28:51.59 inBroadCast: 1 Thu Dec 23 13:54:15 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:54:15 [1932] DEBUG: detected end of black screen ( 43300)* at 0:28:53.40 inBroadCast: 1 Thu Dec 23 13:54:15 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:54:18 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (45060) PTS 2763922560 Thu Dec 23 13:54:18 [1932] DEBUG: *cMarkAdAudio::Process(): previous i-frame (45055) Thu Dec 23 13:54:18 [1932] DEBUG: cMarkAdStandalone::AddMark(): first audio channel change is after chkSTART, disable logo/border/aspect detection now Thu Dec 23 13:54:18 [1932] INFO: audio channel change from 6 to 2 ( 45055) at 0:30:03.59 Thu Dec 23 13:54:18 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:54:23 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (48590), difference 80ms should be 40ms, decoding errors 3 Thu Dec 23 13:54:23 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (48593), difference 240ms should be 40ms, decoding errors 4 Thu Dec 23 13:54:31 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (55074), difference 480ms should be 40ms, decoding errors 5 Thu Dec 23 13:54:31 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (55088) from stream 0 failed: decode_error_flags 4, decoding errors 6 Thu Dec 23 13:54:35 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (57652) PTS 2809299839 Thu Dec 23 13:54:35 [1932] DEBUG: *cMarkAdAudio::Process(): next i-frame (57653) Thu Dec 23 13:54:35 [1932] INFO: audio channel change from 2 to 6 ( 57653)* at 0:38:28.19 Thu Dec 23 13:54:35 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:54:38 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (60960), difference 200ms should be 40ms, decoding errors 7 Thu Dec 23 13:54:40 [1932] DEBUG: AVlog(): cabac decode of qscale diff failed at 98 45 Thu Dec 23 13:54:40 [1932] DEBUG: AVlog(): error while decoding MB 98 44, bytestream 19213 Thu Dec 23 13:54:40 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (63019) from stream 0 failed: decode_error_flags 12, decoding errors 8 Thu Dec 23 13:54:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (73481), difference 400ms should be 40ms, decoding errors 9 Thu Dec 23 13:54:59 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (84881) PTS 2907384000 Thu Dec 23 13:54:59 [1932] DEBUG: cIndex::GetIFrameBefore(): failed for frame (84881), index: first frame (0) last frame (84880) Thu Dec 23 13:54:59 [1932] DEBUG: *cMarkAdAudio::Process(): previous i-frame (-2) Thu Dec 23 13:54:59 [1932] INFO: audio channel change from 6 to 2 ( 84881) at 0:56:37.80 Thu Dec 23 13:54:59 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:55:19 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (101505) PTS 2967215999 Thu Dec 23 13:55:19 [1932] DEBUG: *cMarkAdAudio::Process(): next i-frame (101515) Thu Dec 23 13:55:19 [1932] INFO: audio channel change from 2 to 6 (101515)* at 1:07:43.19 Thu Dec 23 13:55:19 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:55:23 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (106157), difference 440ms should be 40ms, decoding errors 10 Thu Dec 23 13:55:23 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (106389), difference 120ms should be 40ms, decoding errors 11 Thu Dec 23 13:55:52 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (135264) PTS 3088792320 Thu Dec 23 13:55:52 [1932] DEBUG: *cMarkAdAudio::Process(): previous i-frame (135253) Thu Dec 23 13:55:52 [1932] INFO: audio channel change from 6 to 2 (135253) at 1:30:13.20 Thu Dec 23 13:55:52 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:56:07 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (146060) PTS 3127657919 Thu Dec 23 13:56:07 [1932] DEBUG: *cMarkAdAudio::Process(): next i-frame (146068) Thu Dec 23 13:56:07 [1932] INFO: audio channel change from 2 to 6 (146068)* at 1:37:25.79 Thu Dec 23 13:56:07 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:56:22 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (162391), difference 1000ms should be 40ms, decoding errors 12 Thu Dec 23 13:56:37 [1932] DEBUG: AVlog(): left block unavailable for requested intra4x4 mode -1 Thu Dec 23 13:56:37 [1932] DEBUG: AVlog(): error while decoding MB 0 60, bytestream 8316 Thu Dec 23 13:56:37 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (177140), difference 80ms should be 40ms, decoding errors 13 Thu Dec 23 13:56:37 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (177153) from stream 0 failed: decode_error_flags 12, decoding errors 14 Thu Dec 23 13:56:38 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (177599), difference 160ms should be 40ms, decoding errors 15 Thu Dec 23 13:56:42 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (182057) PTS 3257370240 Thu Dec 23 13:56:42 [1932] DEBUG: *cMarkAdAudio::Process(): previous i-frame (182055) Thu Dec 23 13:56:42 [1932] INFO: audio channel change from 6 to 2 (182055) at 2:01:26.39 Thu Dec 23 13:56:42 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (187994), difference 80ms should be 40ms, decoding errors 16 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188009), difference 400ms should be 40ms, decoding errors 17 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188031), difference 320ms should be 40ms, decoding errors 18 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188032), difference 120ms should be 40ms, decoding errors 19 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (188036) from stream 0 failed: decode_error_flags 4, decoding errors 20 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188042), difference 80ms should be 40ms, decoding errors 21 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (188051) from stream 0 failed: decode_error_flags 4, decoding errors 22 Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): cabac decode of qscale diff failed at 55 36 Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): error while decoding MB 55 36, bytestream 5595 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188126), difference 320ms should be 40ms, decoding errors 23 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (188133) from stream 0 failed: decode_error_flags 12, decoding errors 24 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188392), difference 400ms should be 40ms, decoding errors 25 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188395), difference 280ms should be 40ms, decoding errors 26 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (188403) from stream 0 failed: decode_error_flags 4, decoding errors 27 Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): cabac decode of qscale diff failed at 118 25 Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): error while decoding MB 118 24, bytestream 143467 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188434), difference 1640ms should be 40ms, decoding errors 28 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (188438) from stream 0 failed: decode_error_flags 12, decoding errors 29 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188450), difference 960ms should be 40ms, decoding errors 30 Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): left block unavailable for requested intra mode Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): error while decoding MB 0 24, bytestream 3147 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188521), difference 80ms should be 40ms, decoding errors 31 Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): left block unavailable for requested intra4x4 mode -1 Thu Dec 23 13:56:49 [1932] DEBUG: AVlog(): error while decoding MB 0 60, bytestream 172519 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (188534) from stream 0 failed: decode_error_flags 12, decoding errors 32 Thu Dec 23 13:56:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188535), difference 1840ms should be 40ms, decoding errors 33 Thu Dec 23 13:56:56 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (193632) PTS 3299559359 Thu Dec 23 13:56:56 [1932] DEBUG: *cMarkAdAudio::Process(): next i-frame (193634) Thu Dec 23 13:56:56 [1932] INFO: audio channel change from 2 to 6 (193634)* at 2:09:15.60 Thu Dec 23 13:56:56 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:57:19 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (219781), difference 1240ms should be 40ms, decoding errors 34 Thu Dec 23 13:57:22 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (221960) PTS 3401678400 Thu Dec 23 13:57:22 [1932] DEBUG: *cMarkAdAudio::Process(): previous i-frame (221954) Thu Dec 23 13:57:22 [1932] INFO: audio channel change from 6 to 2 (221954) at 2:28:09.59 Thu Dec 23 13:57:22 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:57:28 [1932] DEBUG: AVlog(): left block unavailable for requested intra mode Thu Dec 23 13:57:28 [1932] DEBUG: AVlog(): error while decoding MB 0 12, bytestream 43403 Thu Dec 23 13:57:28 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (227300), difference 280ms should be 40ms, decoding errors 35 Thu Dec 23 13:57:28 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (227303) from stream 0 failed: decode_error_flags 12, decoding errors 36 Thu Dec 23 13:57:37 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (233807) PTS 3444319679 Thu Dec 23 13:57:37 [1932] DEBUG: *cMarkAdAudio::Process(): next i-frame (233813) Thu Dec 23 13:57:37 [1932] INFO: audio channel change from 2 to 6 (233813)* at 2:36:04.20 Thu Dec 23 13:57:37 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:57:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (237245), difference 720ms should be 40ms, decoding errors 37 Thu Dec 23 13:57:45 [1932] DEBUG: AVlog(): left block unavailable for requested intra mode Thu Dec 23 13:57:45 [1932] DEBUG: AVlog(): error while decoding MB 0 20, bytestream 104984 Thu Dec 23 13:57:45 [1932] DEBUG: cDecoder::DecodePacket(): decoding of frame (240561) from stream 0 failed: decode_error_flags 12, decoding errors 38 Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdStandalone::ProcessFrame(): enter end part at frame (250926) Thu Dec 23 13:57:58 [1932] INFO: restart logo and black screen detection at frame (250926) Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 16:9, reload logo Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 0 in /tmp Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): file not found for logo RTL_HD-A16_9 plane 0 in /tmp Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 0 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): file found for logo RTL_HD-A16_9 plane 0 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): logo plane 0 has 1960 pixel Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): logo pixel ratio of plane 0 is: 48 per mille Thu Dec 23 13:57:58 [1932] INFO: logo for RTL_HD 16:9 found in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 1 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): file not found for logo RTL_HD-A16_9 plane 1 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): try to find logo RTL_HD-A16_9 plane 2 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Load(): file not found for logo RTL_HD-A16_9 plane 2 in /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdLogo::Detect(): black screen without logo detected at frame (250926) Thu Dec 23 13:57:58 [1932] DEBUG: detected end of black screen (250926)* at 2:47:29.40 inBroadCast: 1 Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:57:58 [1932] INFO: detected logo stop (250911) at 2:47:28.79 Thu Dec 23 13:57:58 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:58:02 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (254700), difference 120ms should be 40ms, decoding errors 39 Thu Dec 23 13:58:14 [1932] DEBUG: detected start of black screen (263824) at 2:56:05.40 inBroadCast: 1 Thu Dec 23 13:58:14 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:58:14 [1932] DEBUG: detected end of black screen (264094)* at 2:56:16.20 inBroadCast: 1 Thu Dec 23 13:58:14 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:58:14 [1932] DEBUG: detected start of black screen (264409) at 2:56:28.79 inBroadCast: 1 Thu Dec 23 13:58:14 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 1 Thu Dec 23 13:58:14 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (264418) PTS 3554583360 Thu Dec 23 13:58:14 [1932] DEBUG: *cMarkAdAudio::Process(): previous i-frame (264409) Thu Dec 23 13:58:14 [1932] INFO: audio channel change from 6 to 2 (264409) at 2:56:28.79 Thu Dec 23 13:58:14 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:58:14 [1932] DEBUG: cMarks::Add(): duplicate mark on position 264409 type 0x72 and type 0x22 Thu Dec 23 13:58:14 [1932] INFO: detected logo start (264409)* at 2:56:28.79 Thu Dec 23 13:58:14 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:58:14 [1932] DEBUG: cMarks::Add(): duplicate mark on position 264409 type 0x31 and type 0x72 Thu Dec 23 13:58:14 [1932] DEBUG: detected end of black screen (264499)* at 2:56:32.40 inBroadCast: 0 Thu Dec 23 13:58:14 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:58:16 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (265923), difference 120ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:16 [1932] DEBUG: detected start of black screen (266207) at 2:57:40.79 inBroadCast: 0 Thu Dec 23 13:58:16 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:58:16 [1932] DEBUG: detected end of black screen (266237)* at 2:57:42.00 inBroadCast: 0 Thu Dec 23 13:58:16 [1932] DEBUG: cMarkAdStandalone::AddMark(): inBroadCast now: 0 Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStop(): start check stop (271922) Thu Dec 23 13:58:24 [1932] DEBUG: assumed stop position (256920) at 2:51:29.40 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 250911 type 0x32 at 2:47:28.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 250926 type 0x21 at 2:47:29.40 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 263824 type 0x22 at 2:56:05.40 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264094 type 0x21 at 2:56:16.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264499 type 0x21 at 2:56:32.40 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 266207 type 0x22 at 2:57:40.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 266237 type 0x21 at 2:57:42.00 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): start detect and remove logo stop/start mark pairs with special logo Thu Dec 23 13:58:24 [1932] DEBUG: cEvaluateLogoStopStartPair::cEvaluateLogoStopStartPair(): start with iStart 0, chkSTART 13920, iStopA 256920 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::cDecoder(): create new decoder instance Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::cDecoder(): init with 1 threads Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::~cDecoder(): decoder instance deleted Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): marks after detect and remove logo stop/start mark pairs with special logo Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 250911 type 0x32 at 2:47:28.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 250926 type 0x21 at 2:47:29.40 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 263824 type 0x22 at 2:56:05.40 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264094 type 0x21 at 2:56:16.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264499 type 0x21 at 2:56:32.40 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 266207 type 0x22 at 2:57:40.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 266237 type 0x21 at 2:57:42.00 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::RemoveLogoChangeMarks(): end detect and remove logo stop/start mark pairs with special logo Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStop(): start end end mark selection Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStop(): MT_CHANNELSTOP found at frame 264409 Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStop(): found end mark at (264409) Thu Dec 23 13:58:24 [1932] INFO: using channel stop mark on position (264409) at 2:56:28.79 as end mark Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStop(): delete all marks after final stop mark at (264409) Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStop(): move all black screen marks except start and end mark to black screen list Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStop(): end check stop Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00002.ts Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): remove invalid marks Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): check advertising from stop ( 45055) to start ( 57653), length 503s Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): check advertising from stop ( 84881) to start (101515), length 665s Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): check advertising from stop (135253) to start (146068), length 432s Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): check advertising from stop (182055) to start (193634), length 463s Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): check advertising from stop (221954) to start (233813), length 474s Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): delete logo marks if we have channel or border marks Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): delete invalid black sceen marks Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): delete very short logo stop/start pairs Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): detect previews in advertisement Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): check border marks Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStartMark(): check for short start/stop pairs at start Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStartMark(): first broadcast length 1725s from (1920) to (45055) (expect <=96s) Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckStartMark(): first advertising length 503920ms from (45055) to (57653) Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): check for near better end mark in case of recording length is too big Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): remove logo and hborder detection failure marks Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::CheckMarks(): final marks: Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::ProcessFiles(): end processing files Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::LogoMarkOptimization(): start logo mark optimization Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::LogoMarkOptimization(): check for advertising in frame with logo after logo start and before logo stop mark and check for introduction logo Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::LogoMarkOptimization(): search for audio silence around logo marks Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::LogoMarkOptimization(): start search for black screen near logo marks Thu Dec 23 13:58:24 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:24 [1932] DEBUG: ProcessOverlap(): start overlap detection Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:24 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:24 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:58:24 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): check overlap before stop mark (45055) and after start mark (57653) Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): start check 90s before start mark (45055) from frame (42790) at 0:28:33.00 Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): preload from frame (42790) to (45055) Thu Dec 23 13:58:24 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): compare with frames from (57653) to (59903) Thu Dec 23 13:58:24 [1932] DEBUG: cDecoder::SeekToFrame(): (42790) Thu Dec 23 13:58:25 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (24061), difference 1440ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:25 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 6 at frame (42775) PTS 2755680000 Thu Dec 23 13:58:25 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:25 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 152 frames to preload between start of check (42790) and stop mark (45055) Thu Dec 23 13:58:28 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): seek forward to frame (57638) at 0:38:27.60 before start mark (57653) and start overlap check Thu Dec 23 13:58:28 [1932] DEBUG: cDecoder::SeekToFrame(): (57638) Thu Dec 23 13:58:28 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (48590), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:28 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (48593), difference 240ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:28 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (55074), difference 480ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:28 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (57625) PTS 2809204799 Thu Dec 23 13:58:28 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:28 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 150 frames to preload between start mark (57653) and end of check (59903) Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): no overlap found before stop mark (45055) and after start (57653) Thu Dec 23 13:58:30 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): check overlap before stop mark (84881) and after start mark (101515) Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): start check 90s before start mark (84881) from frame (82630) at 0:55:07.80 Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): preload from frame (82630) to (84881) Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): compare with frames from (101515) to (103765) Thu Dec 23 13:58:30 [1932] DEBUG: cDecoder::SeekToFrame(): (82630) Thu Dec 23 13:58:30 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (60960), difference 200ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:30 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (73481), difference 400ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:30 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 6 at frame (82616) PTS 2899216320 Thu Dec 23 13:58:30 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:30 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 152 frames to preload between start of check (82630) and stop mark (84881) Thu Dec 23 13:58:32 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): seek forward to frame (101500) at 1:07:42.60 before start mark (101515) and start overlap check Thu Dec 23 13:58:32 [1932] DEBUG: cDecoder::SeekToFrame(): (101500) Thu Dec 23 13:58:32 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (101485) PTS 2967161279 Thu Dec 23 13:58:32 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:32 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 150 frames to preload between start mark (101515) and end of check (103765) Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): no overlap found before stop mark (84881) and after start (101515) Thu Dec 23 13:58:34 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): check overlap before stop mark (135253) and after start mark (146068) Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): start check 90s before start mark (135253) from frame (132988) at 1:28:42.60 Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): preload from frame (132988) to (135253) Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): compare with frames from (146068) to (148318) Thu Dec 23 13:58:34 [1932] DEBUG: cDecoder::SeekToFrame(): (132988) Thu Dec 23 13:58:34 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (106157), difference 440ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:34 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (106389), difference 120ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:34 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 6 at frame (132974) PTS 3080544000 Thu Dec 23 13:58:34 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:34 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 152 frames to preload between start of check (132988) and stop mark (135253) Thu Dec 23 13:58:36 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): seek forward to frame (146053) at 1:37:25.20 before start mark (146068) and start overlap check Thu Dec 23 13:58:36 [1932] DEBUG: cDecoder::SeekToFrame(): (146053) Thu Dec 23 13:58:36 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (146040) PTS 3127591679 Thu Dec 23 13:58:36 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:36 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 150 frames to preload between start mark (146068) and end of check (148318) Thu Dec 23 13:58:38 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:38 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:38 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): no overlap found before stop mark (135253) and after start (146068) Thu Dec 23 13:58:38 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:38 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): check overlap before stop mark (182055) and after start mark (193634) Thu Dec 23 13:58:38 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): start check 90s before start mark (182055) from frame (179790) at 1:59:55.79 Thu Dec 23 13:58:38 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): preload from frame (179790) to (182055) Thu Dec 23 13:58:38 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): compare with frames from (193634) to (195884) Thu Dec 23 13:58:38 [1932] DEBUG: cDecoder::SeekToFrame(): (179790) Thu Dec 23 13:58:38 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (162391), difference 1000ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:39 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (177140), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:39 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (177599), difference 160ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:39 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 6 at frame (179776) PTS 3249133440 Thu Dec 23 13:58:39 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:39 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 152 frames to preload between start of check (179790) and stop mark (182055) Thu Dec 23 13:58:40 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): seek forward to frame (193619) at 2:09:15.00 before start mark (193634) and start overlap check Thu Dec 23 13:58:40 [1932] DEBUG: cDecoder::SeekToFrame(): (193619) Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (187994), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188009), difference 400ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188031), difference 320ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188032), difference 120ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188042), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188126), difference 320ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188392), difference 400ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188395), difference 280ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188434), difference 1640ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188450), difference 960ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188521), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188535), difference 1840ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (193606) PTS 3299472959 Thu Dec 23 13:58:41 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:41 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 150 frames to preload between start mark (193634) and end of check (195884) Thu Dec 23 13:58:42 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:42 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:42 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): no overlap found before stop mark (182055) and after start (193634) Thu Dec 23 13:58:42 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:42 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): check overlap before stop mark (221954) and after start mark (233813) Thu Dec 23 13:58:42 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): start check 90s before start mark (221954) from frame (219689) at 2:26:37.79 Thu Dec 23 13:58:42 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): preload from frame (219689) to (221954) Thu Dec 23 13:58:42 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): compare with frames from (233813) to (236063) Thu Dec 23 13:58:42 [1932] DEBUG: cDecoder::SeekToFrame(): (219689) Thu Dec 23 13:58:43 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 0 to 6 at frame (219674) PTS 3393312000 Thu Dec 23 13:58:43 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:43 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 152 frames to preload between start of check (219689) and stop mark (221954) Thu Dec 23 13:58:43 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (219781), difference 1240ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:45 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): seek forward to frame (233798) at 2:36:03.59 before start mark (233813) and start overlap check Thu Dec 23 13:58:45 [1932] DEBUG: cDecoder::SeekToFrame(): (233798) Thu Dec 23 13:58:45 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (227300), difference 280ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:45 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (233783) PTS 3444236159 Thu Dec 23 13:58:45 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:45 [1932] DEBUG: cMarkAdStandalone::ProcessMarkOverlap(): 150 frames to preload between start mark (233813) and end of check (236063) Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdOverlap::Process(): start compare frames Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdStandalone::ProcessOverlap(): no overlap found before stop mark (221954) and after start (233813) Thu Dec 23 13:58:47 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdStandalone::LogoMarkOptimization(): check last logo stop mark if closing credits follows Thu Dec 23 13:58:47 [1932] DEBUG: end Overlap Thu Dec 23 13:58:47 [1932] DEBUG: ======================================================================================================================= Thu Dec 23 13:58:47 [1932] INFO: start cut video based on marks Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): final marks are: Thu Dec 23 13:58:47 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdStandalone::DebugMarks(): current marks: Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 1920 type 0x21 at 0:01:16.80 inBroadCast 1 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 45055 type 0x72 at 0:30:03.59 inBroadCast 0 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 57653 type 0x71 at 0:38:28.19 inBroadCast 1 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 84881 type 0x72 at 0:56:37.80 inBroadCast 0 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 101515 type 0x71 at 1:07:43.19 inBroadCast 1 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 135253 type 0x72 at 1:30:13.20 inBroadCast 0 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 146068 type 0x71 at 1:37:25.79 inBroadCast 1 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 182055 type 0x72 at 2:01:26.39 inBroadCast 0 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 193634 type 0x71 at 2:09:15.60 inBroadCast 1 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 221954 type 0x72 at 2:28:09.59 inBroadCast 0 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 233813 type 0x71 at 2:36:04.20 inBroadCast 1 Thu Dec 23 13:58:47 [1932] DEBUG: mark at position 264409 type 0x72 at 2:56:28.79 inBroadCast 0 Thu Dec 23 13:58:47 [1932] DEBUG: ************************************************************* Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::cEncoder(): init with 1 threads Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): start pass 0 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::SeekToFrame(): (1920) Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (1905) PTS 2608457280 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): cutName 'Avengers:_Age_of_Ultron' Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): write to '/media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/Avengers:_Age_of_Ultron.ts' Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): output format MPEG-TS (MPEG-2 Transport Stream) Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): best video: stream 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): best audio: stream 1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): source stream 0 -----> target stream 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): using encoder id 27 'libx264 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10' for output stream 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 avg framerate 25/1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 real framerate 25/1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 keyint_min 25 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input format stream 0 bit_rate 6344777 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 bit_rate 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 rc_max_rate 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 bit_rate_tolerance 4000000 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 global_quality 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 sample_rate 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 gop_size 12 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 level 40 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video input codec stream 0 aspect ratio 1:1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): target video bit rate 5960777 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 pix_fmt 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 keyint_min 1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 max_b_frames 7 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 bit_rate 5960777 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 rc_max_rate 0 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 bit_rate_tolerance 4000000 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 level 40 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 framerate 25/1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 gop_size 32 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 level 40 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): video output stream 0 aspect ratio 1:1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): output stream 0 timebase 1/25 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): avcodec_open2 for stream 0 successful Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): source stream 1 -----> target stream 1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): using encoder id 86019 'ATSC A/52A (AC-3)' for output stream 1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): input codec sample rate 48000, timebase 1/48000 for stream 1 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): audio output codec parameter for stream 1: bit_rate 384000 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): output stream 1 timebase 1/48000 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): avcodec_open2 for stream 1 successful Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::InitEncoderCodec(): AC3 input found at stream 1, initialize volume filter for output stream 1 Thu Dec 23 13:58:47 [1932] DEBUG: cAC3VolumeFilter::Init(): successful with channel layout 5.1(side) Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): stream 2 is no audio and no video, ignoring Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::OpenFile(): source stream 2 -----> target stream -1 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): filename: /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): opened file /media/hd/plexmedia/markad_work/Avengers:_Age_of_Ultron/2021-12-19.21.55.27-0.rec/00001.ts Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: codec id 27 -> H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): average framerate 25/1 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): real framerate 25/1 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: codec id 86019 -> ATSC A/52A (AC-3) Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: codec id 94209 -> DVB subtitles Thu Dec 23 13:58:47 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:47 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (1920) for start mark (1920) to frame (45055) in pass 0 Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::SeekToFrame(): (1920) Thu Dec 23 13:58:47 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::WritePacket(): start cut at frame ( 1920) PTS 2608603696 Thu Dec 23 13:58:47 [1932] DEBUG: cEncoder::WritePacket(): new dts/pts offset 0 Thu Dec 23 13:58:48 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (24061), difference 1440ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:48 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:48 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (57653) for start mark (57653) to frame (84881) in pass 0 Thu Dec 23 13:58:48 [1932] DEBUG: cDecoder::SeekToFrame(): (57653) Thu Dec 23 13:58:48 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (48590), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:48 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (48593), difference 240ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (55074), difference 480ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:49 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (57638) PTS 2809250879 Thu Dec 23 13:58:49 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (57652) PTS 2809299839 Thu Dec 23 13:58:49 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:49 [1932] DEBUG: cEncoder::WritePacket(): start cut at frame ( 57653) PTS 2809429696 Thu Dec 23 13:58:49 [1932] DEBUG: cEncoder::WritePacket(): new dts/pts offset 45410400 Thu Dec 23 13:58:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (60960), difference 200ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:49 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (73481), difference 400ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:49 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:49 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (101515) for start mark (101515) to frame (135253) in pass 0 Thu Dec 23 13:58:49 [1932] DEBUG: cDecoder::SeekToFrame(): (101515) Thu Dec 23 13:58:50 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (101500) PTS 2967198719 Thu Dec 23 13:58:50 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (101505) PTS 2967215999 Thu Dec 23 13:58:50 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:50 [1932] DEBUG: cEncoder::WritePacket(): start cut at frame (101515) PTS 2967379696 Thu Dec 23 13:58:50 [1932] DEBUG: cEncoder::WritePacket(): new dts/pts offset 105289200 Thu Dec 23 13:58:50 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (106157), difference 440ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:50 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (106389), difference 120ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:51 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:51 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (146068) for start mark (146068) to frame (182055) in pass 0 Thu Dec 23 13:58:51 [1932] DEBUG: cDecoder::SeekToFrame(): (146068) Thu Dec 23 13:58:51 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (146054) PTS 3127643519 Thu Dec 23 13:58:51 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (146060) PTS 3127657919 Thu Dec 23 13:58:51 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:51 [1932] DEBUG: cEncoder::WritePacket(): start cut at frame (146068) PTS 3127813696 Thu Dec 23 13:58:51 [1932] DEBUG: cEncoder::WritePacket(): new dts/pts offset 144219600 Thu Dec 23 13:58:51 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (162391), difference 1000ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:52 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (177140), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:52 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (177599), difference 160ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:52 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:58:52 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (193634) for start mark (193634) to frame (221954) in pass 0 Thu Dec 23 13:58:52 [1932] DEBUG: cDecoder::SeekToFrame(): (193634) Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (187994), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188009), difference 400ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188031), difference 320ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188032), difference 120ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188042), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188126), difference 320ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188392), difference 400ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188395), difference 280ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188434), difference 1640ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188450), difference 960ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188521), difference 80ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (188535), difference 1840ms should be 40ms, decoding errors 40 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (193620) PTS 3299530559 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (193632) PTS 3299559359 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:58:53 [1932] DEBUG: cEncoder::WritePacket(): start cut at frame (193634) PTS 3299695696 Thu Dec 23 13:58:53 [1932] DEBUG: cEncoder::WritePacket(): new dts/pts offset 186444000 Thu Dec 23 13:58:53 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (219781), difference 1240ms should be 40ms, decoding errors 40 Thu Dec 23 13:59:00 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:59:00 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): decoding from frame (233813) for start mark (233813) to frame (264409) in pass 0 Thu Dec 23 13:59:00 [1932] DEBUG: cDecoder::SeekToFrame(): (233813) Thu Dec 23 13:59:00 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (227300), difference 280ms should be 40ms, decoding errors 40 Thu Dec 23 13:59:00 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 6 to 2 at frame (233798) PTS 3444287999 Thu Dec 23 13:59:00 [1932] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 1 changed from 2 to 6 at frame (233807) PTS 3444319679 Thu Dec 23 13:59:00 [1932] DEBUG: cDecoder::SeekToFrame(): successful Thu Dec 23 13:59:00 [1932] DEBUG: cEncoder::WritePacket(): start cut at frame (233813) PTS 3444469696 Thu Dec 23 13:59:00 [1932] DEBUG: cEncoder::WritePacket(): new dts/pts offset 229154400 Thu Dec 23 13:59:01 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (237245), difference 720ms should be 40ms, decoding errors 40 Thu Dec 23 13:59:01 [1932] DEBUG: cDecoder::GetNextPacket(): DTS continuity error at frame (254700), difference 120ms should be 40ms, decoding errors 40 Thu Dec 23 13:59:02 [1932] DEBUG: cEncoder::EncodeFrame(): avcodec_receive_packet() encode of frame (264410) failed with return code -541478725 Thu Dec 23 13:59:02 [1932] DEBUG: cEncoder::EncodeFrame(): avcodec_receive_packet() encode of frame (264410) failed with return code -541478725 Thu Dec 23 13:59:50 [1932] DEBUG: cMarkAdStandalone::MarkadCut(): end at frame 264410 Thu Dec 23 13:59:50 [1932] DEBUG: cMarks::Save(): save marks, isRunningRecording=0 force=1 Thu Dec 23 13:59:50 [1932] DEBUG: ----------------------------------------------------------------------------------------------------------------------- Thu Dec 23 13:59:50 [1932] DEBUG: time for decoding: 239s 957ms Thu Dec 23 13:59:50 [1932] DEBUG: time to find logo in recording: 44s 264ms Thu Dec 23 13:59:50 [1932] DEBUG: time to find logo changes: 0s 30ms Thu Dec 23 13:59:50 [1932] DEBUG: pass 1: time 291s 128ms, frames 271921, fps 934 Thu Dec 23 13:59:50 [1932] DEBUG: overlap: time 22s 642ms, frames 236064, fps 10730 Thu Dec 23 13:59:50 [1932] DEBUG: pass 4: time 62s 807ms, frames 264410, fps 4264 Thu Dec 23 13:59:50 [1932] INFO: processed time 7:01 min with 1207 fps Thu Dec 23 13:59:50 [1932] ERROR: decoding errors: 40 Thu Dec 23 13:59:50 [1932] DEBUG: cDecoder::~cDecoder(): decoder instance deleted