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