Fri Aug 28 10:41:14 [15897] INFO:  starting v2.3.5 (38de95c) (64bit)
Fri Aug 28 10:41:14 [15897] INFO:  using libavcodec.so.58.35.100 with -1 threads
Fri Aug 28 10:41:14 [15897] INFO:  on /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec
Fri Aug 28 10:41:14 [15897] INFO:  broadcast aspectratio 16:9 (from info)
Fri Aug 28 10:41:14 [15897] INFO:  broadcast with DolbyDigital2.0 (from info)
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::GetBroadcastStart(): mount option noatime is not set
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::GetBroadcastStart(): getting recording start from VDR info file modification time     Fri Aug 28 08:30:01 2020
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::LoadInfo(): recording start at Fri Aug 28 08:30:01 2020
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::LoadInfo(): broadcast start at Fri Aug 28 08:40:00 2020
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::LoadInfo(): broadcast start 599s after recording start
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::CheckLogo(): using logo directory /tmp
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::CheckLogo(): searching logo for SWR_BW_HD
Fri Aug 28 10:41:14 [15897] INFO:  no logo found in logo directory, trying to find logo in recording directory
Fri Aug 28 10:41:14 [15897] INFO:  logo found in recording directory
Fri Aug 28 10:41:14 [15897] INFO:  pre-timer 599s
Fri Aug 28 10:41:14 [15897] INFO:  broadcast length 50min
Fri Aug 28 10:41:14 [15897] INFO:  channel SWR_BW_HD
Fri Aug 28 10:41:14 [15897] INFO:  parameter --loglevel is set to 3
Fri Aug 28 10:41:14 [15897] DEBUG: markad process nice level 0
Fri Aug 28 10:41:14 [15897] DEBUG: makrad IO priority class 0
Fri Aug 28 10:41:14 [15897] DEBUG: parameter --logocachedir is set to /tmp
Fri Aug 28 10:41:14 [15897] DEBUG: parameter --threads is set to -1
Fri Aug 28 10:41:14 [15897] DEBUG: parameter --astopoffs is set to 0
Fri Aug 28 10:41:14 [15897] DEBUG: parameter --log2rec is set
Fri Aug 28 10:41:14 [15897] DEBUG: force parameter --cDecoder to set because this markad is compiled without classic decoder
Fri Aug 28 10:41:14 [15897] DEBUG: parameter --autologo is set to 2
Fri Aug 28 10:41:14 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:41:14 [15897] DEBUG: cMarkAdStandalone::ProcessFile_cDecoder(): start processing files
Fri Aug 28 10:41:14 [15897] DEBUG: cDecoder::cDecoder(): init with 1 threads
Fri Aug 28 10:41:14 [15897] DEBUG: cDecoder::DecodeFile(): start decode file /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec/00001.ts
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::DecodeFile(): using decoder for stream 0: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::DecodeFile(): using decoder for stream 1: MP2 (MPEG audio layer 2)
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::DecodeFile(): using decoder for stream 2: MP2 (MPEG audio layer 2)
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::DecodeFile(): using decoder for stream 3: ATSC A/52A (AC-3)
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::DecodeFile(): using decoder for stream 4: DVB subtitles
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::GetVideoType(): found H.264 Video
Fri Aug 28 10:41:15 [15897] INFO:  video hight: 720
Fri Aug 28 10:41:15 [15897] INFO:  video width: 1280
Fri Aug 28 10:41:15 [15897] INFO:  average frame rate 50 frames per second
Fri Aug 28 10:41:15 [15897] INFO:  real frame rate    50 frames per second
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 29950
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 50
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording 3000s
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame 29950
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame 178450
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to 53950
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to 209950
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::GetFrameInfo(): audio channels of stream 3 changed from 0 to 2 at frame (6)
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::GetFrameInfo(): found non interlaced video format
Fri Aug 28 10:41:15 [15897] DEBUG: cDecoder::GetFrameInfo(): aspect ratio changed from (0:0) to (16:9) at frame 9
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 16:9, reload logo
Fri Aug 28 10:41:15 [15897] INFO:  logo SWR_BW_HD-A16_9 found in /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdLogo::Load(): file not found for logo SWR_BW_HD-A16_9 plane 1 in /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec
Fri Aug 28 10:41:15 [15897] DEBUG: cMarkAdLogo::Load(): file not found for logo SWR_BW_HD-A16_9 plane 2 in /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec
Fri Aug 28 10:41:15 [15897] INFO:  detected end of black screen (0)* at 0:00:00.00 inBroadCast: 0
Fri Aug 28 10:41:15 [15897] INFO:  actual mark (0) type 0x31 stronger then previous mark, deleting 0 type 0x21
Fri Aug 28 10:41:15 [15897] INFO:  detected logo start (0)* at 0:00:00.00 inBroadCast: 1
Fri Aug 28 10:41:37 [15897] INFO:  detected logo stop (22902) at 0:07:38.04 inBroadCast: 0
Fri Aug 28 10:41:45 [15897] INFO:  detected start of vert. borders (28599)* at 0:09:31.85 inBroadCast: 1
Fri Aug 28 10:42:00 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CheckStart(); checking start at frame (53967)
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CheckStart(): assumed start frame 29950
Fri Aug 28 10:42:00 [15897] DEBUG: mark at position      0 type 0x31 at 0:00:00.00 inBroadCast 1
Fri Aug 28 10:42:00 [15897] DEBUG: mark at position  22902 type 0x32 at 0:07:38.04 inBroadCast 0
Fri Aug 28 10:42:00 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:42:00 [15897] INFO:  broadcast with 2 audio channels of stream 3
Fri Aug 28 10:42:00 [15897] INFO:  HD Video with aspectratio of 16:9 detected
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CheckStart(): no horizontal border at start found, ignore horizontal border detection
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CheckStart(): vertical border start found at (28599)
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CheckStart(): delete HBORDER marks if any
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): startframe 28599
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): use frame rate 50
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): length of recording 3000s
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed start frame 28599
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): assumed stop frame 177099
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTART set to 52599
Fri Aug 28 10:42:00 [15897] DEBUG: cMarkAdStandalone::CalculateCheckPositions(): chkSTOP set to 208599
Fri Aug 28 10:42:00 [15897] INFO:  using mark on position (28599) type 0x51 at 0:09:31.85 as start mark
Fri Aug 28 10:42:00 [15897] INFO:  found vertical borders, logo detection disabled
Fri Aug 28 10:42:00 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:42:00 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:42:11 [15897] DEBUG: cDecoder::GetNextFrame(): start time next file 128350
Fri Aug 28 10:42:11 [15897] DEBUG: cDecoder::DecodeFile(): start decode file /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec/00002.ts
Fri Aug 28 10:43:30 [15897] DEBUG: cDecoder::GetNextFrame(): start time next file 382468
Fri Aug 28 10:43:30 [15897] DEBUG: cDecoder::DecodeFile(): start decode file /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec/00003.ts
Fri Aug 28 10:44:21 [15897] INFO:  restart logo and black screen detection at frame (171163)
Fri Aug 28 10:44:21 [15897] DEBUG: cMarkAdLogo::Process(): aspect ratio changed from 0:0 to 16:9, reload logo
Fri Aug 28 10:44:21 [15897] INFO:  logo SWR_BW_HD-A16_9 found in /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec
Fri Aug 28 10:44:21 [15897] DEBUG: cMarkAdLogo::Load(): file not found for logo SWR_BW_HD-A16_9 plane 1 in /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec
Fri Aug 28 10:44:21 [15897] DEBUG: cMarkAdLogo::Load(): file not found for logo SWR_BW_HD-A16_9 plane 2 in /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec
Fri Aug 28 10:44:21 [15897] INFO:  detected end of black screen (171131)* at 1:18:24.83 inBroadCast: 1
Fri Aug 28 10:44:26 [15897] INFO:  detected stop of vert. borders (177169) at 1:20:25.43 inBroadCast: 0
Fri Aug 28 10:44:35 [15897] DEBUG: cDecoder::GetNextFrame(): start time next file 888368
Fri Aug 28 10:44:35 [15897] DEBUG: cDecoder::DecodeFile(): start decode file /daten/Video/Auf_Achse_(4)/Tommy's_Trip/2020-08-28.08.30.8-0.rec/00004.ts
Fri Aug 28 10:44:48 [15897] INFO:  detected logo start (199139)* at 2:31:29.04 inBroadCast: 1
Fri Aug 28 10:44:49 [15897] INFO:  detected logo stop (200671) at 2:31:59.40 inBroadCast: 0
Fri Aug 28 10:44:57 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckStop(): checking stop (208619)
Fri Aug 28 10:44:57 [15897] DEBUG: assumed stop position (177099) at 1:20:24.20
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 171131 type 0x21 at 1:18:24.83 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 177169 type 0x52 at 1:20:25.43 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 199139 type 0x31 at 2:31:29.04 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 200671 type 0x32 at 2:31:59.40 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckStop(): no MT_CHANNELSTOP mark found
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckStop(): no MT_ASPECTSTOP mark found
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckStop(): no MT_HBORDERSTOP mark found
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckStop(): MT_VBORDERSTOP found at frame 177169
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckStop(): found end mark at (177169)
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckStop(): found stronger end mark delete mark (171131)
Fri Aug 28 10:44:57 [15897] INFO:  using mark on position (177169) type 0x52 at 1:20:25.43 as stop mark
Fri Aug 28 10:44:57 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckMarks(): check marks first pass (delete logo marks if we have channel or vborder marks)
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 177169 type 0x52 at 1:20:25.43 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckMarks(): check marks 2nd pass (delete invalid black sceen marks)
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 177169 type 0x52 at 1:20:25.43 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckMarks(): check marks 3nd pass (detect previews in advertisement)
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 177169 type 0x52 at 1:20:25.43 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckMarks(): check marks 4nd pass (remove logo and hborder detection failure marks)
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 177169 type 0x52 at 1:20:25.43 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckMarks(): check marks 5nd pass (remove invalid marks)
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 177169 type 0x52 at 1:20:25.43 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::CheckMarks(): final marks:
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position  28599 type 0x51 at 0:09:31.85 inBroadCast 1
Fri Aug 28 10:44:57 [15897] DEBUG: mark at position 177169 type 0x52 at 1:20:25.43 inBroadCast 0
Fri Aug 28 10:44:57 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:44:57 [15897] DEBUG: cMarkAdStandalone::ProcessFile_cDecoder(): end processing files
Fri Aug 28 10:44:57 [15897] DEBUG: clMarks::Save(): save marks, force=1
Fri Aug 28 10:44:57 [15897] INFO:  writing info file
Fri Aug 28 10:44:57 [15897] DEBUG: stream 0 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 1 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 2 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 3 has 2 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 4 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 5 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 6 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 7 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 8 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: stream 9 has 0 channels
Fri Aug 28 10:44:57 [15897] DEBUG: info file line: C S19.2E-1-1019-10303 SWR BW HD

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: E 20943 1598596800 3000 4E 1B

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: T Auf Achse (4)

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: S Tommy's Trip

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: D Tommy ist einer der vielen Tramps, die nach Indien wollen. Obwohl Meersdonk grundsätzlich keine Anhalter mitnimmt - Tommy schafft es. Indem er ihm auf den Wecker fällt, entsteht so etwas wie Sympathie, bis Tommy versucht, Meersdonk zu beklauen. Der schmeißt ihn hinaus - aber wenig später gerät er selbst in Schwierigkeiten mit der Zollfahndung.

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: G 15

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: X 5 0B deu 16:9

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: X 2 03 deu stereo

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: X 2 03 deu ohne Audiodeskription

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: X 3 20 deu DVB-Untertitel

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: X 3 03 deu 

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: V 1598596800

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: F 50

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: P 50

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: L 99

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: X 2 05 deu Dolby Digital 2.0

Fri Aug 28 10:44:57 [15897] DEBUG: info file line: @ <epgsearch><channel>8 - SWR BW HD</channel><searchtimer>Auf Achse</searchtimer><start>1598596200</start><stop>1598601000</stop><s-id>42</s-id><eventid>20943</eventid></epgsearch>

Fri Aug 28 10:44:57 [15897] DEBUG: ------------------------------------------------------------------------------------------------
Fri Aug 28 10:44:57 [15897] INFO:  start 2nd pass
Fri Aug 28 10:44:57 [15897] DEBUG: only 2 marks, abort 2nd pass
Fri Aug 28 10:44:57 [15897] INFO:  processed time 3:42min, 935.8 fps
Fri Aug 28 10:44:57 [15897] INFO:  frames processed 208655 first pass
Fri Aug 28 10:44:57 [15897] INFO:  frames processed      0 2nd pass
Fri Aug 28 10:44:57 [15897] INFO:  frames processed      0 3nd pass
Fri Aug 28 10:44:57 [15897] DEBUG: cDecoder::~cDecoder(): close avformat context