[mythtv-users] 0.19 Live-TV problems with DVB backend

Thu Feb 16 23:06:27 UTC 2006

Dear all.

I am hoping to have a config error...

This is on my test system.

PVR500: master backend has 1 PVR500 and 1 PVR250.
satje2: DVB slave backend has 1 DVB-S tuner. This system also runs the 
MySQL database.
storage is on a fileserver accessed over nfs. master and slave backend 
use the same mount point in their trees and nfs storage.

What i observe is that live-TV on the master backend works OK, but on 
the master backend mostly works not at all. Attached all information i 
have gathered so far.
My main observation is that the frontend is requesting a different 
filename, than the slave backend is actually using. This can be seen in 
the frontend log, where file "32016_20060216182605.mpg" is requested. 
This file exists on the nfs storage, but is not really used. A different 
file, "32016_20060216182606.mpg" is actually growing in size (and thus 
most likely used by the backend).

I am running ntpd on all systems, both production Myth and test myth, in 
order to have correct time.

Any suggestions ideas?

Cheers,

Rudy

DVB slave backend log

2006-02-16 18:25:14.985 EITScanner: Now looking for EIT data on channel 514
2006-02-16 18:25:15.537 DVB#0 DVB SI Table Parser Started
2006-02-16 18:26:05.193 MainServer::HandleAnnounce Playback
2006-02-16 18:26:05.293 adding: poledra as a client (events: 0)
2006-02-16 18:26:05.341 TVRec(6): Changing from None to WatchingLiveTV
2006-02-16 18:26:06.500 DVB#0 DVB SI Table Parser Started
2006-02-16 18:26:06.615 MainServer::HandleAnnounce Playback
2006-02-16 18:26:06.730 adding: poledra as a client (events: 0)
2006-02-16 18:26:06.781 MainServer::HandleAnnounce FileTransfer
2006-02-16 18:26:06.836 Finished recording Die heilige Johanna der 
Schlachthöfe am Berliner Ensemble: channel 32016
2006-02-16 18:26:06.856 adding: poledra as a remote file transfer
2006-02-16 18:26:07.115 Finished recording Die heilige Johanna der 
Schlachthöfe am Berliner Ensemble: channel 32016
0: start_time: 857.475 duration: -9223372036854.775
stream: start_time: 9527.500 duration: -9223372036854.775 bitrate=0 kb/s
2006-02-16 18:26:13.887 AFD: Opened codec 0x82a51d0, id(MPEG1VIDEO) 
type(Video)
2006-02-16 18:26:13.972 NVP: Disabling Audio, params(-1,-1,-1)
2006-02-16 18:26:14.004 NVP: Disabling Audio, params(0,-1,-1)
2006-02-16 18:26:27.553 TVRec(6): Changing from WatchingLiveTV to None
2006-02-16 18:26:28.137 Finished recording Die heilige Johanna der 
Schlachthöfe am Berliner Ensemble: channel 32016
2006-02-16 18:27:58.897 EITScanner: Now looking for EIT data on channel 180

ls info from storage directory

rudy at satje2:~$ ls -l /data/werk_data/data/myth/32016*
-rw-r--r--  1 rudy users 1990048244 2005-12-31 20:45 
/data/werk_data/data/myth/32016_20051231194000.mpg
-rw-r--r--  1 rudy users      23482 2006-02-12 15:07 
/data/werk_data/data/myth/32016_20051231194000.mpg.png
-rw-r--r--  1 rudy users    1272196 2006-02-16 18:23 
/data/werk_data/data/myth/32016_20060216182339.mpg
-rw-r--r--  1 rudy users        184 2006-02-16 18:23 
/data/werk_data/data/myth/32016_20060216182339.mpg.png
-rw-r--r--  1 rudy users   17137516 2006-02-16 18:24 
/data/werk_data/data/myth/32016_20060216182340.mpg
-rw-r--r--  1 rudy users    1646316 2006-02-16 18:26 
/data/werk_data/data/myth/32016_20060216182605.mpg
-rw-r--r--  1 rudy users        184 2006-02-16 18:26 
/data/werk_data/data/myth/32016_20060216182605.mpg.png
-rw-r--r--  1 rudy users    6424148 2006-02-16 18:26 
/data/werk_data/data/myth/32016_20060216182606.mpg
rudy at satje2:~$ ls -l /data/werk_data/data/myth/32016*
-rw-r--r--  1 rudy users 1990048244 2005-12-31 20:45 
/data/werk_data/data/myth/32016_20051231194000.mpg
-rw-r--r--  1 rudy users      23482 2006-02-12 15:07 
/data/werk_data/data/myth/32016_20051231194000.mpg.png
-rw-r--r--  1 rudy users    1272196 2006-02-16 18:23 
/data/werk_data/data/myth/32016_20060216182339.mpg
-rw-r--r--  1 rudy users        184 2006-02-16 18:23 
/data/werk_data/data/myth/32016_20060216182339.mpg.png
-rw-r--r--  1 rudy users   17137516 2006-02-16 18:24 
/data/werk_data/data/myth/32016_20060216182340.mpg
-rw-r--r--  1 rudy users    1646316 2006-02-16 18:26 
/data/werk_data/data/myth/32016_20060216182605.mpg
-rw-r--r--  1 rudy users        184 2006-02-16 18:26 
/data/werk_data/data/myth/32016_20060216182605.mpg.png
-rw-r--r--  1 rudy users   11456512 2006-02-16 18:26 
/data/werk_data/data/myth/32016_20060216182606.mpg

frontend log

2006-02-16 18:24:00.795 AFD: Opened codec 0x82dd560, id(MPEG1VIDEO) 
type(Video)
2006-02-16 18:24:00.796 NVP: Disabling Audio, params(-1,-1,-1)
2006-02-16 18:24:00.796 NVP: Disabling Audio, params(0,-1,-1)
2006-02-16 18:24:00.928 TV Error: StartPlayer(): NVP is not playing 
after 20000 msec
2006-02-16 18:24:01.410 Using realtime priority.
2006-02-16 18:24:01.521 Video timing method: USleep with busy wait
QObject::disconnect: Unexpected null parameter
2006-02-16 18:24:03.424 TV Error: LiveTV not successfully started
2006-02-16 18:26:05.174 TV: Attempting to change from None to 
WatchingLiveTV
2006-02-16 18:26:05.183 Using protocol version 26
2006-02-16 18:26:07.482 Could not bind to UDP notify port: 6948
2006-02-16 18:26:10.243 
RingBuf(myth://192.168.1.68:6543/32016_20060216182605.mpg): Waited 2 
seconds for data to become available...
2006-02-16 18:26:12.291 
RingBuf(myth://192.168.1.68:6543/32016_20060216182605.mpg): Waited 4 
seconds for data to become available...
2006-02-16 18:26:16.388 
RingBuf(myth://192.168.1.68:6543/32016_20060216182605.mpg): Waited 8 
seconds for data to become available...
2006-02-16 18:26:24.326 
RingBuf(myth://192.168.1.68:6543/32016_20060216182605.mpg) Error: Waited 
16 seconds for data, aborting.
0: start_time: -9223372036854.775 duration: -9223372036854.775
stream: start_time: -9223372036854.775 duration: -9223372036854.775 
bitrate=0 kb/s
2006-02-16 18:26:27.480 AFD: Opened codec 0x82dd560, id(MPEG1VIDEO) 
type(Video)
2006-02-16 18:26:27.481 NVP: Disabling Audio, params(-1,-1,-1)
2006-02-16 18:26:27.481 NVP: Disabling Audio, params(0,-1,-1)
2006-02-16 18:26:27.487 TV Error: StartPlayer(): NVP is not playing 
after 20000 msec
2006-02-16 18:26:28.082 Using realtime priority.
2006-02-16 18:26:28.192 Video timing method: USleep with busy wait
QObject::disconnect: Unexpected null parameter
2006-02-16 18:26:28.287 TV Error: LiveTV not successfully started