Project

General

Profile

Bug #4012

Feature Request #3993 add Chapter to MKV for EIT p/f not working

Added by Laurie Davis over 3 years ago. Updated about 3 years ago.

Status:
Fixed
Priority:
Normal
Assignee:
Category:
PVR / DVR
Target version:
-
Start date:
2016-10-05
Due date:
% Done:

0%

Estimated time:
Found in version:
v4.1-2249-g747ed01
Affected Versions:

Description

My feature request #3993 to add a chapter mark when using Accurate Recording to an MKV has been marked as fixed and implemented, but unfortunately it does not work.

No chapter mark is added to the MKV, however, Mediainfo shows a lone "Menu" item in the MKV - maybe the wrong thing is getting added to the MKV? See attached output from Mediainfo of the recorded MKV

Apologies for opening a new Issue but I have not received a response on the original request, presumably because it is marked as fixed.


Files

filedetails.txt (3.56 KB) filedetails.txt Laurie Davis, 2016-10-05 11:39
ffprobe-mkv.txt (3.36 KB) ffprobe-mkv.txt Laurie Davis, 2016-10-12 10:31
TVHlog-131016.zip (76.3 KB) TVHlog-131016.zip Laurie Davis, 2016-10-13 15:41
tvh.zip (143 KB) tvh.zip DVR trace Laurie Davis, 2017-05-18 17:15

History

#1

Updated by Jaroslav Kysela over 3 years ago

I don't know mediainfo. What says ffprobe ?

#2

Updated by Laurie Davis over 3 years ago

I've deleted this MKV but have recorded another and this one shows no sign of the "menu" item. Attached is the output of ffprobe (windows version as Libreelec doesn't seem to have it). No sign of any chapters in this file.

#3

Updated by Jaroslav Kysela over 3 years ago

-show_chapters argument is missing like:

$ ffprobe -v error -show_format -show_streams -show_chapters /mnt/dvb/perex/Amerika.mkv
[STREAM]
index=0
codec_name=h264
codec_long_name=H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
profile=High
codec_type=video
codec_time_base=1/50
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
width=1920
height=1080
coded_width=1920
coded_height=1088
has_b_frames=2
sample_aspect_ratio=1:1
display_aspect_ratio=16:9
pix_fmt=yuv420p
level=40
color_range=tv
color_space=bt709
color_transfer=bt709
color_primaries=bt709
chroma_location=left
timecode=N/A
refs=4
is_avc=1
nal_length_size=4
id=N/A
r_frame_rate=25/1
avg_frame_rate=25/1
time_base=1/1000
start_pts=16
start_time=0.016000
duration_ts=5850477
duration=5850.477000
bit_rate=N/A
max_bit_rate=N/A
bits_per_raw_sample=8
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
TAG:language=eng
[/STREAM]
[STREAM]
index=1
codec_name=ac3
codec_long_name=ATSC A/52A (AC-3)
profile=unknown
codec_type=audio
codec_time_base=1/48000
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
sample_fmt=fltp
sample_rate=48000
channels=2
channel_layout=stereo
bits_per_sample=0
dmix_mode=-1
ltrt_cmixlev=-1.000000
ltrt_surmixlev=-1.000000
loro_cmixlev=-1.000000
loro_surmixlev=-1.000000
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=16
start_time=0.016000
duration_ts=N/A
duration=N/A
bit_rate=384000
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
TAG:language=cze
[/STREAM]
[STREAM]
index=2
codec_name=ac3
codec_long_name=ATSC A/52A (AC-3)
profile=unknown
codec_type=audio
codec_time_base=1/48000
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
sample_fmt=fltp
sample_rate=48000
channels=2
channel_layout=stereo
bits_per_sample=0
dmix_mode=-1
ltrt_cmixlev=-1.000000
ltrt_surmixlev=-1.000000
loro_cmixlev=-1.000000
loro_surmixlev=-1.000000
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=26
start_time=0.026000
duration_ts=N/A
duration=N/A
bit_rate=384000
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
TAG:language=qaa
[/STREAM]
[STREAM]
index=3
codec_name=dvbsub
codec_long_name=DVB subtitles
profile=unknown
codec_type=subtitle
codec_time_base=1/1000
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
width=N/A
height=N/A
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=16
start_time=0.016000
duration_ts=5850477
duration=5850.477000
bit_rate=N/A
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
TAG:language=cze
[/STREAM]
[CHAPTER]
id=1
time_base=1/1000000000
start=57057000000
start_time=57.057000
end=4091457000000
end_time=4091.457000
[/CHAPTER]
[CHAPTER]
id=2
time_base=1/1000000000
start=4091457000000
start_time=4091.457000
end=4553677000000
end_time=4553.677000
[/CHAPTER]
[CHAPTER]
id=3
time_base=1/1000000000
start=4553677000000
start_time=4553.677000
end=5850493000000
end_time=5850.493000
[/CHAPTER]
[FORMAT]
filename=/mnt/dvb/perex/Amerika.mkv
nb_streams=4
nb_programs=0
format_name=matroska,webm
format_long_name=Matroska / WebM
start_time=0.016000
duration=5850.477000
size=5272931547
bit_rate=7210258
probe_score=100
TAG:title=Amerika
TAG:encoder=Tvheadend Matroska muxer
TAG:DATE_BROADCASTED=2015-11-19 21:35:00
TAG:ORIGINAL_MEDIA_TYPE=TV
TAG:CONTENT_TYPE=News / Current affairs
TAG:TVCHANNEL=HBO HD
TAG:SUMMARY=Čechokanaďanka Bára a muzikant Honza se vydávají na společnou toulku českou krajinou. Na své cestě, která vede stinnými lesy, přes prosluněné louky i pod jasnou noční oblohou, se pozvolna proplétají tuzemskou trampskou komunitou. Bich míst trampů, starých bezmála sto let. Film sleduje jejich cestu do země svobody, kde kulisou je jen les, oheň a širé nebe. Sami na sobě zakoušejí únik do imaginární Ameriky, s každým dalším setkáním či zážitkem se jejich cesta proměňuje v univerzální příběh přátelství, hudby a snů o svobodě.
Režie: Jan Foukal
(2015)
TAG:SUMMARY-cze=Čechokanaďanka Bára a muzikant Honza se vydávají na společnou toulku českou krajinou. Na své cestě, která vede stinnými lesy, přes prosluněné louky i pod jasnou noční oblohou, se pozvolna proplétají tuzemskou trampskou komunitou. Bich míst trampů, starých bezmála sto let. Film sleduje jejich cestu do země svobody, kde kulisou je jen les, oheň a širé nebe. Sami na sobě zakoušejí únik do imaginární Ameriky, s každým dalším setkáním či zážitkem se jejich cesta proměňuje v univerzální příběh přátelství, hudby a snů o svobodě.
Režie: Jan Foukal
(2015)
[/FORMAT]
#4

Updated by Laurie Davis over 3 years ago

Still not showing any chapters

C:\Video\ffmpeg-20161011-8063978-win64-static\bin>ffprobe -v error -show_format -show_streams -show_chapters "\\192.168.1.114\sda1-usb-WD_Elements_10A8\Recordings\US Presidential Debate Highlights\US Presidential Debate Highlights (BBC TWOHD) 2016-10-10 11-00 .mkv" 
[h264 @ 000000000035c160] mmco: unref short failure
[STREAM] message repeated 1 times
index=0
codec_name=h264
codec_long_name=H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
profile=High
codec_type=video
codec_time_base=1/50
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
width=1920
height=1080
coded_width=1920
coded_height=1080
has_b_frames=3
sample_aspect_ratio=1:1
display_aspect_ratio=16:9
pix_fmt=yuv420p
level=40
color_range=tv
color_space=bt709
color_transfer=bt709
color_primaries=bt709
chroma_location=left
timecode=N/A
refs=1
is_avc=true
nal_length_size=4
id=N/A
r_frame_rate=25/1
avg_frame_rate=25/1
time_base=1/1000
start_pts=1313
start_time=1.313000
duration_ts=N/A
duration=N/A
bit_rate=N/A
max_bit_rate=N/A
bits_per_raw_sample=8
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
TAG:language=eng
[/STREAM]
[STREAM]
index=1
codec_name=aac
codec_long_name=AAC (Advanced Audio Coding)
profile=LC
codec_type=audio
codec_time_base=1/48000
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
sample_fmt=fltp
sample_rate=48000
channels=2
channel_layout=stereo
bits_per_sample=0
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=0
start_time=0.000000
duration_ts=N/A
duration=N/A
bit_rate=N/A
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
TAG:language=eng
[/STREAM]
[STREAM]
index=2
codec_name=dvb_subtitle
codec_long_name=DVB subtitles
profile=unknown
codec_type=subtitle
codec_time_base=0/1
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
width=N/A
height=N/A
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=2816
start_time=2.816000
duration_ts=N/A
duration=N/A
bit_rate=N/A
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
TAG:language=eng
[/STREAM]
[FORMAT]
filename=\\192.168.1.114\sda1-usb-WD_Elements_10A8\Recordings\US Presidential Debate Highlights\US Presidential Debate H
ighlights (BBC TWO HD) 2016-10-10 11-00 .mkv
nb_streams=3
nb_programs=0
format_name=matroska,webm
format_long_name=Matroska / WebM
start_time=0.000000
duration=4380.553000
size=1982885249
bit_rate=3621251
probe_score=100
TAG:title=US Presidential Debate Highlights
TAG:encoder=Tvheadend Matroska muxer
TAG:DATE_BROADCASTED=2016-10-10 11:00:00
TAG:ORIGINAL_MEDIA_TYPE=TV
TAG:CONTENT_TYPE=News / Current affairs
TAG:TVCHANNEL=BBC TWO HD
TAG:SUMMARY=Highlights from the presidential debate between Hillary Clinton and Donald Trump. [HD] [S]
TAG:SUMMARY-eng=Highlights from the presidential debate between Hillary Clinton and Donald Trump. [HD] [S]
[/FORMAT]
#5

Updated by Jaroslav Kysela over 3 years ago

It works here. Upgrade to v4.1-2275-gea1f43f and provide log from tvh for the problematic recordings. There should be dvr: EPG lines.

$ ffprobe -show_chapters ~/git/tvheadend/dvr/2016-10-12/Scott\ i\ Bailey\ 1\ \(odc.\ 5\).mkv
[CHAPTER]
id=1
time_base=1/1000000000
start=2023600000000
start_time=2023.600000
end=2083280000000
end_time=2083.280000
[/CHAPTER]
[CHAPTER]
id=2
time_base=1/1000000000
start=2083280000000
start_time=2083.280000
end=2139760000000
end_time=2139.760000
[/CHAPTER]
[CHAPTER]
id=3
time_base=1/1000000000
start=2139760000000
start_time=2139.760000
end=2148253000000
end_time=2148.253000
[/CHAPTER]
#6

Updated by Laurie Davis over 3 years ago

I've upgraded to 2275 but still not working.

Attached is a log with a DVR trace (if that helps) and ffprobe of the mkv file. There are no "DVR: EPG" entries in the log. Also attached is a screenshot of the recording profile - is there anything that needs to be set to make this work that I'm missing? I have pre-record padding at 3 mins, post padding at 10 mins and "Use EPG Running State" unticked. So recording should start 3 mins before the EIT change. It seems odd that it works for you but not for me.

Let me know if you need any more logs.

Thanks

#7

Updated by Jaroslav Kysela over 3 years ago

I don't see 'BBC ONE HD' in the list of channels providing EITp/f. Try another channel.

2016-10-13 11:41:31.664 [  TRACE]:dvr: dvr event running check for 5 News At Lunchtime on Channel 5 HD running 0
2016-10-13 11:41:31.730 [  TRACE]:dvr: dvr event running check for The Dog Ate My Homework on CBBC HD running 0
2016-10-13 11:41:32.086 [  TRACE]:dvr: dvr event running check for Anzio on Film4+1 running 0
2016-10-13 11:41:32.676 [  TRACE]:dvr: dvr event running check for The Wright Stuff on Channel 5 HD running 0
2016-10-13 11:41:32.676 [  TRACE]:dvr: dvr event running check for New: House Doctor on Channel 5 HD running 2
2016-10-13 11:41:32.733 [  TRACE]:dvr: dvr event running check for Naomi's Nightmares of Nature on CBBC HD running 0
2016-10-13 11:41:32.733 [  TRACE]:dvr: dvr event running check for Junior Bake Off on CBBC HD running 2
2016-10-13 11:41:33.097 [  TRACE]:dvr: dvr event running check for Teleshopping on Film4+1 running 2
#8

Updated by Laurie Davis over 3 years ago

So I tried a recording on CBBC HD - and it worked! Two chapters, one at the start and one at the end point. However, this is now really weird. There are three channels on that same mux that are not appearing in the TVH debug log with the "dvr event running check" - BBC ONE HD, BBC TWO HD and ITV HD. These channels definitely, definitely use Accurate Recording as I use it all the time on my PVR. SO I'm not sure why TVH isn't checking them. Any ideas or any suggestions on what further traces I can do to check this?

#9

Updated by Laurie Davis over 3 years ago

So I think this is now fixed :)

I removed all channels from that mux, then all services and then the mux itself. I then added it back and re-scanned the channels, and now the trace shows it checking the EPG running status of those missing channels. A few test recordings and the chapter mark is being added exactly as the broadcaster sends the start signal - excellent stuff!

One question - when does the chapter mark get added to the file? Is it when the EPG change occurs or does TVH save them till the end of recording and then add them? I ask because I tried playing one of the test recordings back while it was still in progress and there were no chapter marks. They only appeared when the recording finished.

Thanks again for all your help

#10

Updated by Jaroslav Kysela over 3 years ago

The chapter blocks are created immediately, but the index table is written as last (on close) to the file (for matroska).

The question remains why tvh didn't detect EITp/f for your three channels before new scan. Do you have a config backup for the old mux to analyze this? The backups should be created automatically in the <tvh_config_dir>/backup directory.

#11

Updated by Laurie Davis over 3 years ago

I can't find a backup directory for TVH - this is running on Libreelec, and I've looked under the addons folder and the userdata one as well. Doing a Find from root for anything called 'backup' doesn't find anything either.

The only thing odd about those channels was that those were the ones I initially mapped when I was setting up TVH for the first time on the RPI3. The others were mapped later. I think I was using 2267 when I mapped them, but today I redid them using 2275.

#12

Updated by Mark Clarkstone over 3 years ago

Jaroslav Kysela wrote:

The chapter blocks are created immediately, but the index table is written as last (on close) to the file (for matroska).

The question remains why tvh didn't detect EITp/f for your three channels before new scan. Do you have a config backup for the old mux to analyze this? The backups should be created automatically in the <tvh_config_dir>/backup directory.

Just thought I'd pop in and post what I'd experienced with these changes.

A recording on BBC One HD and BBC One E Mids (at the same time but diff mux) had no bookmarks according to Kodi. These were both added at 21:51, 21:53 and started at 22:00, maybe I added them too late?

I also recorded two back to back episodes of an old game show on Challenge, both recorded almost as expected and had bookmarks, but and I'm not sure this was intended, both had a bookmark for the ending of the previous programme.

Basically it went..

  • Tvheadend starts recording (near end of current programme).
  • Current programme ends, bookmark created (on end production info/ident).
  • Programme (that we want to record) starts, bookmark created.
  • Adbreak starts (half way into the programme), bookmark created.
  • Adbreak ends, programme resumes, bookmark created.
  • Programme is near end, adbreak starts, bookmark created.
  • Adbreak ends, programme resumes, bookmark created.
  • Programme finishes (credits roll), bookmark created (on end production info/ident).

This to me looks as intended, (I'm a little surprised that Challenge (for a Sky owned channel) actually sent flags on adbreaks) :o, Tvheadends EITp/f is certainly a better than it was (not that it was bad to begin with it was just a bit buggy on some channels).

I'm wondering whether or not the reason the flags on BBC One weren't detected was because of an epg conflict (I have three ota grabbers enabled), but then again if that was the case Challenge would have the same problem? shrug.

If I was able I would upload 2 hours worth of a mux (for two muxes) but I can't as I have no fixed line broadband at the moment and having to use a mobile dongle.

Anyway, (and I know I keep saying this) I really appreciate everything you put into Tvheadend Jaroslav (and yes I'm still working on the docs while being 'offline' :))

------------------
@Laurie: I'm pretty sure that libreelec (just like openelec) uses the -B argument to prevent backups.

#13

Updated by Jaroslav Kysela over 3 years ago

Could you try upgrade to v4.1-2278-g398cf2c and do "--trace tbl-eit,dvr" ? Look for the channel which does not work (there should be lines with ch='<name>' and running= strings).

#14

Updated by Mark Clarkstone over 3 years ago

Jaroslav Kysela wrote:

Could you try upgrade to v4.1-2278-g398cf2c and do "--trace tbl-eit,dvr" ? Look for the channel which does not work (there should be lines with ch='<name>' and running= strings).

BBC One E Mid (channel that doesn't create bookmarks) : http://pastebin.com/raw/7ZXN9NmS
CBS Reality (It should work) : http://pastebin.com/raw/kvEuQ99i
Challenge (It does work on this one): http://pastebin.com/raw/wQBpFkSV

Hope these are what you are after.

Actually, scrap that, I think you may want to see the dvr lines too, not just the eit table (should have realised this as you asked for dvr trace too, damn lack of sleep!), will record and post another ASAP.

#15

Updated by Laurie Davis over 3 years ago

Now that I have this working on my setup, I've turned my attention to how I want to play these files back, and have hit another small problem. My aim is to watch the recorded programmes in Kodi using the TVH PVR client. I want to be able to start playback, press the "skip forward" button which should skip to the next chapter, so that I am immediately at the chapter marker for the start of the program. However, this doesn't quite work. What happens is that Kodi just jumps forward 20 secs at each press of the skip forward - until it is past the first chapter marker. The next press then takes me to the next chapter (ie, the end of the programme, or the first commercial break, depending on how the broadcaster has flagged it).

I believe the problem is that there is no initial chapter right at the start of the recording. It seems that before it has reached any chapters, Kodi is unaware of them and so only does a 20 second skip.

So Jaroslav, would it be possible for TVH to add a chapter right at the start of the recording (ie 00:00:00)? I think this will then fix it.

Thanks

#16

Updated by Mark Clarkstone over 3 years ago

I did a little investigating and this is the result (took me a while to sift through the 800mb log file):


Programme: James Martin: Home Comforts
Start/End: 11:30am/12:00pm
Padding Pre/Post: 10mins/10mins
Duration: 50m (inc padding + 30s warmup)
Eid: 65455 (based om the start/stop unix stamps which are UTC, but tvheadend correctly adds an hour to the value)


Status (and this is just a guess):

  • 0 = Not running?
  • 1 = Next?
  • 4 = Running?

Adding the entry and Recording starting..

2016-10-15 10:09:14.674 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 - assign broadcast 2525
2016-10-15 10:09:14.674 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 start 2016-10-15;11:19:30(+0100) stop 2016-10-15;12:10:00(+0100) - set timer
2016-10-15 10:09:14.674 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 start 2016-10-15;11:19:30(+0100) - set timer - schedule
2016-10-15 10:09:14.674 [   INFO]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 "James Martin: Home Comforts" on "BBC One E Mid" starting at 2016-10-15 11:19:30, scheduled for recording by "192.168.1.104" 

2016-10-15 11:20:00.037 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 - set state - state RECORDING rec_state RUNNING error 'OK'
2016-10-15 11:20:00.038 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 - set state - state RECORDING rec_state WAIT error 'OK'
2016-10-15 11:20:00.038 [  DEBUG]:dvr: File "/md0/tvheadend/dvr/BBC One E Mid/James Martin_ Home Comforts2016-10-1511-30.mkv" -- No such file or directory -- Using for recording
2016-10-15 11:20:00.068 [   INFO]:dvr: /md0/tvheadend/dvr/BBC One E Mid/James Martin_ Home Comforts2016-10-1511-30.mkv from adapter: "Sony CXD2820R : DVB-T #0", network: "DVB-T - Waltham", mux: "698MHz", provider: "<N/A>", service: "BBC ONE E Mid" 
2016-10-15 11:20:00.068 [   INFO]:dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
2016-10-15 11:20:00.068 [   INFO]:dvr:  1  MPEG2VIDEO              704x576     16:9                                 
2016-10-15 11:20:00.068 [   INFO]:dvr:  2  MPEG2AUDIO        eng                             48000        2         
2016-10-15 11:20:00.068 [   INFO]:dvr:  3  MPEG2AUDIO        eng                             ?            ?         <disabled, no valid input>
2016-10-15 11:20:00.068 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 - set state - state RECORDING rec_state RUNNING error 'OK'

Recording end log - first thing I noticed was the rstart/stop values, is that correct?

2016-10-15 12:10:00.001 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 rstart 1970-01-01;01:00:00(+0100) rstop 1970-01-01;01:00:00(+0100) - stop recording timer called
2016-10-15 12:10:00.086 [   INFO]:subscription: 0073: "DVR: James Martin: Home Comforts" unsubscribing from "BBC One E Mid" 
2016-10-15 12:10:00.154 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 - set state - state COMPLETED rec_state FINISHED error 'OK'
2016-10-15 12:10:00.154 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 - retention timer - disarm
2016-10-15 12:10:00.154 [   INFO]:dvr: "James Martin: Home Comforts" on "BBC One E Mid": End of program: Completed OK
2016-10-15 12:10:00.154 [  TRACE]:dvr: entry 1ef387f9e54dbd7e3a05af993d73b6e6 - retention timer - disarm

tbl-eit log lines (using shitty regex to filter):

2016-10-15 10:11:27.234 [  TRACE]:tbl-eit: svc='BBC One E Mid', ch='BBC One E Mid', eid=65455, tbl=60, running=0, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:32.004 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:34.028 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:36.019 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:36.164 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=50, running=0, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:37.995 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:40.036 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:41.558 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4f, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:42.032 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:44.056 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:46.032 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:48.001 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:50.024 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:52.024 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:54.039 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:56.016 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:19:58.007 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:00.069 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:02.038 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:04.000 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:06.053 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:07.998 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:10.008 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:12.013 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:14.029 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:16.052 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:18.004 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:20.060 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:22.028 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:24.012 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:26.013 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:28.011 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:30.026 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:32.026 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:34.044 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:36.029 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:38.033 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:40.025 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:41.993 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:44.008 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:46.024 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:48.008 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:50.027 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:52.008 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:54.015 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:55.999 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:20:58.030 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:00.054 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:02.014 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:04.007 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:06.013 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:07.998 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:10.045 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:12.006 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:14.020 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:16.044 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:18.020 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:20.027 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:22.020 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:24.035 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:26.003 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:28.026 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:30.066 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:32.034 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:34.018 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:36.011 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:38.041 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:40.034 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:42.025 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:44.040 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:46.000 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:48.008 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:50.031 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:52.031 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:54.001 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:56.015 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:21:58.008 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:00.022 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:02.015 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:04.015 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:06.030 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:08.021 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:10.037 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:12.005 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:14.052 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:16.028 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:18.022 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:20.028 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:22.019 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:24.043 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:26.028 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:28.050 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:30.050 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:32.034 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:34.014 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:36.081 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:38.041 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:40.014 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:42.057 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:44.064 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:46.675 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:48.009 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:50.033 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:52.023 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:54.023 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:56.132 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:22:58.038 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:00.031 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:02.046 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:04.018 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:06.037 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:08.029 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:10.037 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:12.053 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:14.068 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:16.044 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:18.036 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:20.059 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:22.020 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:24.043 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:26.005 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:28.287 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:30.013 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:32.050 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:34.015 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:36.012 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:36.596 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4f, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:38.026 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:40.032 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:42.009 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:44.016 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:46.025 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:48.039 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:50.024 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:52.026 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:54.093 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:56.015 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:23:58.047 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:00.015 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:02.024 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:04.078 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:06.014 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:08.053 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:10.053 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:12.021 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:14.044 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:16.014 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:18.013 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:20.042 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:22.028 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:24.022 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:26.043 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:28.029 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:24:30.059 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:25:54.047 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:28:26.038 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:28:36.050 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:28:38.026 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:28:48.025 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:28:48.267 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=60, running=0, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:28:50.064 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:29:50.051 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:30:44.072 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:30:46.033 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:32:56.040 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:32:58.070 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:24.052 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:26.075 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:28.059 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:30.059 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:40.065 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=1, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:41.042 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:43.058 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:45.049 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:45.622 [  TRACE]:tbl-eit: svc='BBC One E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:33:56.614 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4f, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:34:47.041 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:35:05.070 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:35:23.075 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:35:25.084 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:35:39.074 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:36:16.625 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4f, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:38:51.530 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=60, running=0, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:41:01.079 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:41:25.123 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:43:25.076 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:43:33.107 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:47:57.103 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:48:27.155 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:49:57.112 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:49:59.095 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:50:11.661 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4f, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:52:51.096 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:52:57.112 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:53:25.116 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:54:48.439 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=60, running=0, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:55:31.123 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:55:33.147 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:55:35.135 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:55:37.138 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:57:49.113 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:58:27.116 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:58:47.154 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 11:59:41.130 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 12:01:49.137 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 12:02:37.115 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
2016-10-15 12:02:57.136 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=65455, tbl=4e, running=4, start=1476527400, stop=1476529200,ebc=(nil)
Found 207 matches for ".*BBC ONE E Mid.*eid=65455.*".

The next flag is this:

2016-10-15 12:02:58.136 [  TRACE]:tbl-eit: svc='BBC ONE E Mid', ch='BBC One E Mid', eid=59691, tbl=4e, running=1, start=1476529200, stop=1476532800, ebc=(nil)

Hope this helps :)

#17

Updated by Jaroslav Kysela over 3 years ago

Mark, do you have multiple EPG sources for this channel ? If ebc = (nil) then something blocks to create the EIT event in the database (probably other EPG grabber has greater priority). In this case EITp/f does not work (I don't see a simple way to correlate the event from two and more different sources).

#18

Updated by Mark Clarkstone over 3 years ago

Jaroslav Kysela wrote:

Mark, do you have multiple EPG sources for this channel ? If ebc = (nil) then something blocks to create the EIT event in the database (probably other EPG grabber has greater priority). In this case EITp/f does not work (I don't see a simple way to correlate the event from two and more different sources).

Bold 1:


Yes. I have the following grabbers enabled across all channels (services from dvb-t and dvb-s are combined - dvb-t services have higher priority).

Grabber               | Priority    | Will change to
----------------------|-------------|--------
OpenTV (Sky UK)       | 10          | 1
UK: Freeview          | 5           | 8
UK: Freesat           | 1           | 6
EIT: DVB Grabber      | 1           | 10

I'll rejig the priority as in the table, delete the epgdb and attempt another recording then see it that makes any difference. See below..

Bold 2:


Yep, I see where you're coming from, the event info tvh has needs to match whatever is supplied in the stream during the recording, if the event info is from a different network then it won't match.

It is a bit of a pickle.

You could allow the user to pick the epg data/grabber that will be used for the recording. You could do this by adding a grabber drop down (with an "all" choice as default) to the EPG grid, then when the user chooses an entry to record (and only when a single grabber is selected) add a warning to the event info dialog, something along the lines of "Note that this event information is from a single EPG grabber/source. Only networks that supplied the data (or linked) to the selected grabber will be used for recording.".

It's not exactly a great solution and it wouldn't be much good for htsp clients but it's something I suppose. :p


Heh, it's taken me that long to write this I'd forgotten that I'd set a recording after changing the grabber priority!

Chapters are now being created and appear to be correct (or at least as far as I can tell)! The programme "Asian Provocateur: Mum's American Dream" is 30 mins long, the scheduled duration is shown as 50 mins (+ padding) and the actual length of the recording is (according to VLC) 31:28.

The recording starts exactly on the BBC One ident (00:00) which means Tvheadend has detected the flag correctly, this is also where the first chapter is, the second chapter is at 00:22 right after the ident ends and exactly where the programme starts.

The BBC don't appear to indicate that the programme has ended where you'd expect (right at the end credits) so all the bumpers for other programmes are included, the recording however ends exactly on the start of the ident for the next programme!

So it does work! =D

#19

Updated by Mark Clarkstone over 3 years ago

Heh, I've only just noticed that changing the grabber priority (to the above) caused loss of EPG on some channels, mostly those on satellite.

#20

Updated by Laurie Davis over 3 years ago

Laurie Davis wrote:

Now that I have this working on my setup, I've turned my attention to how I want to play these files back, and have hit another small problem. My aim is to watch the recorded programmes in Kodi using the TVH PVR client. I want to be able to start playback, press the "skip forward" button which should skip to the next chapter, so that I am immediately at the chapter marker for the start of the program. However, this doesn't quite work. What happens is that Kodi just jumps forward 20 secs at each press of the skip forward - until it is past the first chapter marker. The next press then takes me to the next chapter (ie, the end of the programme, or the first commercial break, depending on how the broadcaster has flagged it).

I believe the problem is that there is no initial chapter right at the start of the recording. It seems that before it has reached any chapters, Kodi is unaware of them and so only does a 20 second skip.

So Jaroslav, would it be possible for TVH to add a chapter right at the start of the recording (ie 00:00:00)? I think this will then fix it.

Thanks

Hi Jaroslav, did you have a chance to consider implementing this please?

Thanks

#21

Updated by Jaroslav Kysela about 3 years ago

Try v4.3-104-g5f04907 ..

#22

Updated by Jaroslav Kysela about 3 years ago

  • Status changed from New to Fixed
#23

Updated by Laurie Davis about 3 years ago

I'd forgotten all about this :) Thanks for trying to implement it, but it doesn't seem to be working for me. I've done a few test recordings to MKV and there is no chapter mark right at the start - the first still seems to be at the first EIT change.

I've attached a log with a DVR trace, and ffprobe on the file shows this:

[STREAM]
index=0
codec_name=mpeg2video
codec_long_name=MPEG-2 video
profile=Main
codec_type=video
codec_time_base=1/25
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
width=720
height=576
coded_width=0
coded_height=0
has_b_frames=1
sample_aspect_ratio=64:45
display_aspect_ratio=16:9
pix_fmt=yuv420p
level=8
color_range=tv
color_space=unknown
color_transfer=unknown
color_primaries=unknown
chroma_location=left
field_order=tt
timecode=N/A
refs=1
id=N/A
r_frame_rate=25/1
avg_frame_rate=25/1
time_base=1/1000
start_pts=120
start_time=0.120000
duration_ts=N/A
duration=N/A
bit_rate=N/A
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
DISPOSITION:timed_thumbnails=0
TAG:language=eng
[/STREAM]
[STREAM]
index=1
codec_name=mp2
codec_long_name=MP2 (MPEG audio layer 2)
profile=unknown
codec_type=audio
codec_time_base=1/48000
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
sample_fmt=s16p
sample_rate=48000
channels=2
channel_layout=stereo
bits_per_sample=0
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=20
start_time=0.020000
duration_ts=N/A
duration=N/A
bit_rate=192000
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
DISPOSITION:timed_thumbnails=0
TAG:language=eng
[/STREAM]
[STREAM]
index=2
codec_name=mp2
codec_long_name=MP2 (MPEG audio layer 2)
profile=unknown
codec_type=audio
codec_time_base=1/48000
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
sample_fmt=s16p
sample_rate=48000
channels=1
channel_layout=mono
bits_per_sample=0
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=20
start_time=0.020000
duration_ts=N/A
duration=N/A
bit_rate=64000
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
DISPOSITION:timed_thumbnails=0
TAG:language=eng
[/STREAM]
[STREAM]
index=3
codec_name=dvb_subtitle
codec_long_name=DVB subtitles
profile=unknown
codec_type=subtitle
codec_time_base=0/1
codec_tag_string=[0][0][0][0]
codec_tag=0x0000
width=N/A
height=N/A
id=N/A
r_frame_rate=0/0
avg_frame_rate=0/0
time_base=1/1000
start_pts=20
start_time=0.020000
duration_ts=2580440
duration=2580.440000
bit_rate=N/A
max_bit_rate=N/A
bits_per_raw_sample=N/A
nb_frames=N/A
nb_read_frames=N/A
nb_read_packets=N/A
DISPOSITION:default=1
DISPOSITION:dub=0
DISPOSITION:original=0
DISPOSITION:comment=0
DISPOSITION:lyrics=0
DISPOSITION:karaoke=0
DISPOSITION:forced=0
DISPOSITION:hearing_impaired=0
DISPOSITION:visual_impaired=0
DISPOSITION:clean_effects=0
DISPOSITION:attached_pic=0
DISPOSITION:timed_thumbnails=0
TAG:language=eng
[/STREAM]
[CHAPTER]
id=1
time_base=1/1000000000
start=293400000000
start_time=293.400000
end=1917560000000
end_time=1917.560000
[/CHAPTER]
[CHAPTER]
id=3
time_base=1/1000000000
start=1917560000000
start_time=1917.560000
end=2580460000000
end_time=2580.460000
[/CHAPTER]
[FORMAT]
filename=D:\Recordings-TVH\How I Met Your Mother\How I Met Your Mother (E4) 2017-05-18 15-30 .mkv
nb_streams=4
nb_programs=0
format_name=matroska,webm
format_long_name=Matroska / WebM
start_time=0.020000
duration=2580.440000
size=683834100
bit_rate=2120054
probe_score=100
TAG:title=How I Met Your Mother
TAG:encoder=Tvheadend Matroska muxer
TAG:DATE_BROADCASTED=2017-05-18 15:30:00
TAG:ORIGINAL_MEDIA_TYPE=TV
TAG:CONTENT_TYPE=Show / Game show
TAG:TVCHANNEL=E4
TAG:SUMMARY=Twin Beds: After Don asks Robin to move in with him, she decides it's time for Ted and Barney to meet him. They both react badly when they realise Robin has moved on.  [AD,S]
TAG:SUMMARY-eng=Twin Beds: After Don asks Robin to move in with him, she decides it's time for Ted and Barney to meet him. They both react badly when they realise Robin has moved on.  [AD,S]
[/FORMAT]

#24

Updated by Jaroslav Kysela about 3 years ago

You're right fixed in v4.3-110-ge3a5cb1 .

#25

Updated by Laurie Davis about 3 years ago

That seems to have fixed it - thanks!

Also available in: Atom PDF