[mythtv] mythplayer.cpp query

Wed Mar 14 10:10:21 UTC 2012

-----Original Message-----
On 13. mars 2012 6:41 Russell Gower wrote:

On 13 Mar 2012, at 16:01, Stuart Morgan wrote:

> On Tuesday 13 Mar 2012 15:25:02 Russell Gower wrote:
>> Just to be clear, I'm not talking about channel changes, I'm talking 
>> about the delay when one program ends and the next one starts  - i.e 
>> the transition from 8441_20120312180956.mpg to 
>> 8441_20120312183001.mpg
> 
> I'm unable to reproduce a delay on programme transitions ... and 
> historically no-one has ever reported a delay there.
> 
> Still if you are seeing a delay then something is going wrong.

I'm really hoping this is a setup issue, it's persisted over 3 hardware "upgrades" and I've experienced it ever since the switch to creating individual recording files for live tv, so i'm doubt full.

Bellow is an typical example from the frontend log - on this occasion SwitchToProgram took 7.6 seconds, during which the screen was frozen.

2012-03-13 09:15:01.343816 I  Player(0): SwitchToProgram - start

<snip>

2012-03-13 09:15:01.525242 N  SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
2012-03-13 09:15:01.530305 I  ProgramInfo(8441_20120313091500.mpg): GetPlaybackURL: File is local: '/mythtv/kvm02/Recordings/8441_20120313091500.mpg'
2012-03-13 09:15:01.530322 I  FileRingBuf(/mythtv/kvm02/Recordings/8441_20120313083045.mpg): OpenFile(/mythtv/kvm02/Recordings/8441_20120313091500.mpg, 10000 ms)
2012-03-13 09:15:01.535417 I  AO: OutputAudioLoop: audio paused
2012-03-13 09:15:05.544482 I  FileRingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): OpenFile() made 346 attempts in 4014 ms

<snip>

2012-03-13 09:15:05.610378 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 0, 1048576) -> 349116, took 66 ms (42.3171Mbps)

<snip>

2012-03-13 09:15:05.675579 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 60 ms (0Mbps)

<snip>

2012-03-13 09:15:05.981138 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 301 ms (0Mbps)
2012-03-13 09:15:06.237066 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 0.2 seconds for data
2012-03-13 09:15:06.286617 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 300 ms (0Mbps)
2012-03-13 09:15:06.286690 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 0.2 seconds for data
2012-03-13 09:15:06.536811 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 0.5 seconds for data
2012-03-13 09:15:06.592141 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 301 ms (0Mbps)
2012-03-13 09:15:06.592192 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 0.5 seconds for data
2012-03-13 09:15:06.897687 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 300 ms (0Mbps)
2012-03-13 09:15:07.147847 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 1.0 seconds for data
2012-03-13 09:15:07.203214 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 301 ms (0Mbps)
2012-03-13 09:15:07.203266 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 1.0 seconds for data
2012-03-13 09:15:07.508765 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 300 ms (0Mbps)
2012-03-13 09:15:07.814295 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 301 ms (0Mbps)
2012-03-13 09:15:08.064446 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 2.0 seconds for data
2012-03-13 09:15:08.119849 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 300 ms (0Mbps)
2012-03-13 09:15:08.119901 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): Waited 2.0 seconds for data
2012-03-13 09:15:08.425382 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 0, took 301 ms (0Mbps)
2012-03-13 09:15:08.564542 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 349116, 1048576) -> 1048576, took 134 ms (62.6016Mbps)
2012-03-13 09:15:08.580782 I  RingBuf(/mythtv/kvm02/Recordings/8441_20120313091500.mpg): safe_read(... at 1397692, 1048576) -> 1048576, took 11 ms (762.601Mbps)

<snip>

-----Original Message-----

I am not a developer of MythTV but have some experience of reading log-files.

To me it looks like you have a severe latency in accessing the recording (which live-TV is) from your filesystem.

Jens