[mythtvnz] Myth gives up on a recording
Noel & Di
noel at igrin.co.nz
Sun Jun 6 20:46:58 BST 2010
Steve Hodge wrote:
> On Sun, Jun 6, 2010 at 6:09 PM, Noel & Di <noel at igrin.co.nz
> <mailto:noel at igrin.co.nz>> wrote:
>
> /var/log/mythtv/mythbackend.log.1 shows alot of this sort of thing
>
> /2010-06-05 14:00:47.056 Connected to database 'mythconverg' at
> host: localhost
> 2010-06-05 14:00:47.059 Current Schema Version: 1214
> 2010-06-05 14:00:47.090 ProgramInfo, Error: GetPlaybackURL:
> '2928_20100605135000.mpg' should be local, but it can not be found.
> 2010-06-05 14:00:47.304 Preview: Grabbed preview
> '/media/400disk/Children/2904_20100601090000.nuv' 720x576 at 500s
> 2010-06-05 14:00:47.589 ProgramInfo, Error: GetPlaybackURL:
> '2928_20100605135000.mpg' should be local, but it can not be found.
> 2010-06-05 14:00:47.631 Using runtime prefix = /usr
> 2010-06-05 14:00:47.635 Empty LocalHostName.
> 2010-06-05 14:00:47.635 Using localhost value of G3Myth
> 2010-06-05 14:00:47.648 New DB connection, total: 1/
>
> There are a couple of HUGE sections of the "should be local but it
> can't be found" (sounds like me when the wife has been in the
> shed!) in the current log file, but nothing I understand that will
> stop a program recording.
> The logs used to be around 130k or so, but the last few have been
> 20 + meg each, so something has happened. There's been no
> fiddling for months - so it's not something I've done.
>
> >From looking at the record history it seems to just happen on
> DVB0, not DVB1
>
>
> Anything in the system logs?
>
> Cheers,
> Steve
> ------------------------------------------------------------------------
>
> _______________________________________________
> mythtvnz mailing list
> mythtvnz at lists.linuxnut.co.nz
> http://lists.ourshack.com/mailman/listinfo/mythtvnz
> Archives http://www.gossamer-threads.com/lists/mythtv/mythtvnz/
>
From daemon.log
Jun 6 12:34:04 G3Myth dhclient: DHCPREQUEST of <null address> on eth0
to 192.168.0.1 port 67
Jun 6 12:34:04 G3Myth dhclient: DHCPACK of 192.168.0.2 from 192.168.0.1
Jun 6 12:34:04 G3Myth NetworkManager: <info> DHCP daemon state is now
3 (renew) for interface eth0
Jun 6 12:34:04 G3Myth dhclient: bound to 192.168.0.2 -- renewal in
32763 seconds.
Jun 6 15:46:54 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 15:46:54 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 15:56:13 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 15:56:36 G3Myth last message repeated 3 times
Jun 6 16:19:31 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 16:19:31 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 16:24:34 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 16:24:34 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 16:36:19 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 16:36:19 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 16:43:33 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 16:43:33 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 17:04:59 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 17:04:59 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 17:07:15 G3Myth last message repeated 4 times
Jun 6 17:07:26 G3Myth last message repeated 2 times
Jun 6 17:12:04 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 17:12:04 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 17:27:16 G3Myth lircd-0.8.3pre1[4072]: accepted new client on
/dev/lircd
Jun 6 17:36:40 G3Myth lircd-0.8.3pre1[4072]: removed client
Jun 6 17:38:15 G3Myth lircd-0.8.3pre1[4072]: accepted new client on
/dev/lircd
Jun 6 17:38:25 G3Myth lircd-0.8.3pre1[4072]: removed client
Jun 6 17:39:13 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 17:39:43 G3Myth last message repeated 5 times
Jun 6 17:52:29 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 17:52:29 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 18:14:17 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 18:14:17 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 18:40:48 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 18:40:48 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:02:52 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:02:52 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:34:37 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:34:37 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:53:06 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:53:06 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:58:34 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 19:58:34 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 20:14:21 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 20:14:21 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 20:21:03 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 20:22:01 G3Myth last message repeated 3 times
Jun 6 20:25:37 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 20:25:37 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 20:52:47 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 20:52:47 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 21:40:07 G3Myth dhclient: DHCPREQUEST of <null address> on eth0
to 192.168.0.1 port 67
Jun 6 21:40:07 G3Myth dhclient: DHCPACK of 192.168.0.2 from 192.168.0.1
Jun 6 21:40:07 G3Myth NetworkManager: <info> DHCP daemon state is now
3 (renew) for interface eth0
Jun 6 21:40:07 G3Myth dhclient: bound to 192.168.0.2 -- renewal in
38869 seconds.
Jun 6 21:56:23 G3Myth modprobe: WARNING: Error running install command
for nvidia
Jun 6 21:56:40 G3Myth last message repeated 3 times
tail of mythbackend.log
2010-06-07 07:05:00.251 Finished recording Campbell Live: channel 2920
2010-06-07 07:05:01.915 Using runtime prefix = /usr
2010-06-07 07:05:01.989 Empty LocalHostName.
2010-06-07 07:05:01.990 Using localhost value of G3Myth
2010-06-07 07:05:02.119 New DB connection, total: 1
2010-06-07 07:05:02.149 Connected to database 'mythconverg' at host:
localhost
2010-06-07 07:05:02.154 Closing DB connection named 'DBManager0'
2010-06-07 07:05:02.156 Connected to database 'mythconverg' at host:
localhost
2010-06-07 07:05:02.159 New DB connection, total: 2
2010-06-07 07:05:02.161 Connected to database 'mythconverg' at host:
localhost
2010-06-07 07:05:02.169 Current Schema Version: 1214
2010-06-07 07:05:04.900 AFD: Opened codec 0x82b1700, id(MPEG2VIDEO)
type(Video)
2010-06-07 07:05:04.902 AFD: codec MP3 has 2 channels
2010-06-07 07:05:04.903 AFD: Opened codec 0x82b1cf0, id(MP3) type(Audio)
2010-06-07 07:05:05.335 Preview: Grabbed preview
'/media/400disk/recordings/2920_20100607062500.mpg' 720x576 at 500s
2010-06-07 07:05:05.531 Scheduled 195 items in 5.4 = 0.01 match + 5.36 place
2010-06-07 07:05:27.415 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2010-06-07 07:05:27.526 Expiring 1943 MBytes for 2920 @ Fri Jun 4
18:55:00 2010 => Campbell Live. Too many episodes, we only want to keep 1.
2010-06-07 07:05:27.560 Reschedule requested for id 0.
2010-06-07 07:05:33.744 Scheduled 195 items in 6.1 = 0.01 match + 6.13 place
2010-06-07 07:05:34.758 Reschedule requested for id 0.
2010-06-07 07:05:39.127 Scheduled 195 items in 4.3 = 0.00 match + 4.32 place
2010-06-07 07:06:39.170 Program #1909 not found in PAT!
Program Association Table
PSIP tableID(0x0) length(73) extension(0x15)
version(25) current(1) section(0) last_section(0)
tsid: 21
programCount: 16
program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10
program number 1920 has PID 0x 10a data 0x 7 0x80 0xe1 0x a
program number 1921 has PID 0x 10b data 0x 7 0x81 0xe1 0x b
program number 1926 has PID 0x 110 data 0x 7 0x86 0xe1 0x10
program number 1940 has PID 0x 122 data 0x 7 0x94 0xe1 0x22
program number 1928 has PID 0x 112 data 0x 7 0x88 0xe1 0x12
program number 1923 has PID 0x 10d data 0x 7 0x83 0xe1 0x d
program number 1924 has PID 0x 10e data 0x 7 0x84 0xe1 0x e
program number 1925 has PID 0x 10f data 0x 7 0x85 0xe1 0x f
program number 1927 has PID 0x 121 data 0x 7 0x87 0xe1 0x21
program number 1922 has PID 0x 10c data 0x 7 0x82 0xe1 0x c
program number 1929 has PID 0x 113 data 0x 7 0x89 0xe1 0x13
program number 1930 has PID 0x 114 data 0x 7 0x8a 0xe1 0x14
program number 1931 has PID 0x 115 data 0x 7 0x8b 0xe1 0x15
program number 1935 has PID 0x 119 data 0x 7 0x8f 0xe1 0x19
program number 1936 has PID 0x 11a data 0x 7 0x90 0xe1 0x1a
2010-06-07 07:07:57.292 Reschedule requested for id -1.
2010-06-07 07:08:01.860 Scheduled 195 items in 4.5 = 0.31 match + 4.21 place
2010-06-07 07:10:23.900 UPnpMedia: BuildMediaMap VIDEO scan starting in
:/media/400disk/Videos/:
2010-06-07 07:10:24.088 UPnpMedia: BuildMediaMap Done. Found 92 objects
snip from mythfrontend.log
2010-06-06 16:36:19.054 AFD: Opened codec 0x834b990, id(MPEG2VIDEO)
type(Video)
2010-06-06 16:36:19.054 AFD: codec MP3 has 2 channels
2010-06-06 16:36:19.054 AFD: Opened codec 0x8694e80, id(MP3) type(Audio)
2010-06-06 16:36:19.057 Opening audio device 'default'. ch 2(2) sr 48000
2010-06-06 16:36:19.057 Opening ALSA audio device 'default'.
2010-06-06 16:36:19.119 VideoOutputXv: Desired video renderer
'xvmc-blit' not available.
codec 'MPEG2' makes 'xv-blit,xshm,xlib,' available, using
'xv-blit' instead.
2010-06-06 16:36:19.125 VideoOutputXv Error: Could not find suitable
XVideo surface.
2010-06-06 16:36:19.125 VideoOutputXv: Falling back to X11 video output
over a network socket.
*** May be very slow ***
2010-06-06 16:36:19.125 VideoOutputXv Error: XCreateImage failed:
XJ_disp(0x86642c0) visual(0x84f7ec8)
XJ_depth(24) WxH(800x600) bpl(2400)
2010-06-06 16:36:19.126 VideoOutputXv Error: Failed to create X buffers.
2010-06-06 16:36:19.130 VideoOutputXv Error: Could not find suitable
XVideo surface.
2010-06-06 16:36:19.130 VideoOutputXv: Falling back to X shared memory
video output.
*** May be slow ***
2010-06-06 16:36:19.159 OSD Theme Dimensions W: 640 H: 480
2010-06-06 16:36:19.545 TV: Changing from None to WatchingPreRecorded
2010-06-06 16:36:19.546 VideoOutputXv Error: GetRefreshRate(): X11
ModeLine query returned zeroes
2010-06-06 16:36:19.563 The realtime priority setting is not enabled.
2010-06-06 16:36:19.625 VideoOutputXv Error: GetRefreshRate(): X11
ModeLine query returned zeroes
2010-06-06 16:36:19.625 Video timing method: USleep with busy wait
2010-06-06 16:36:24.017 VideoOutputXv Error:
***
* Your system is not capable of displaying the
* full framerate at 800x600 resolution. Frames
* will be skipped in order to keep the audio and
* video in sync.
and then
2010-06-06 16:37:36.516 [mpeg2video @ 0xb7390dc8]ac-tex damaged at 28 35
2010-06-06 16:37:36.516 [mpeg2video @ 0xb7390dc8]Warning MVs not available
2010-06-06 16:37:36.594 NVP::AddAudioData():p1: Audio buffer overflow,
audio data lost!
2010-06-06 16:37:36.595 NVP::AddAudioData():p1: Audio buffer overflow,
audio data lost!
2010-06-06 16:37:36.596 NVP::AddAudioData():p1: Audio buffer overflow,
audio data lost!
2010-06-06 16:37:36.596 NVP::AddAudioData():p1: Audio buffer overflow,
audio data lost!
2010-06-06 16:37:36.597 NVP::AddAudioData():p1: Audio buffer overflow,
audio data lost!
2010-06-06 16:37:36.714 NVP: prebuffering pause
tail of syslog.0
Jun 7 05:03:24 G3Myth -- MARK --
Jun 7 05:09:01 G3Myth /USR/SBIN/CRON[24855]: (root) CMD ( [ -x
/usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/ -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 |
xargs -r -0 rm)
Jun 7 05:17:01 G3Myth /USR/SBIN/CRON[24877]: (root) CMD ( cd / &&
run-parts --report /etc/cron.hourly)
Jun 7 05:39:01 G3Myth /USR/SBIN/CRON[24904]: (root) CMD ( [ -x
/usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/ -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 |
xargs -r -0 rm)
Jun 7 06:03:24 G3Myth -- MARK --
Jun 7 06:09:01 G3Myth /USR/SBIN/CRON[24945]: (root) CMD ( [ -x
/usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/ -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 |
xargs -r -0 rm)
Jun 7 06:17:02 G3Myth /USR/SBIN/CRON[24962]: (root) CMD ( cd / &&
run-parts --report /etc/cron.hourly)
Jun 7 06:25:01 G3Myth /USR/SBIN/CRON[24975]: (root) CMD (test -x
/usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily ))
Jun 7 06:39:01 G3Myth /USR/SBIN/CRON[25025]: (root) CMD ( [ -x
/usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find
/var/lib/php5/ -type f -cmin +$(/usr/lib/php5/maxlifetime) -print0 |
xargs -r -0 rm)
Jun 7 06:49:40 G3Myth fsr[25163]: no rw xfs file systems in mtab:
/etc/mtab
all of user.log
May 30 06:48:21 G3Myth fsr[2138]: no rw xfs file systems in mtab: /etc/mtab
May 31 06:32:30 G3Myth fsr[5185]: no rw xfs file systems in mtab: /etc/mtab
Jun 1 06:28:03 G3Myth fsr[7742]: no rw xfs file systems in mtab: /etc/mtab
Jun 2 06:40:42 G3Myth fsr[11397]: no rw xfs file systems in mtab:
/etc/mtab
Jun 2 18:03:36 G3Myth dhcdbd: Started up.
Jun 2 18:03:40 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.reason
Jun 2 18:03:46 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.host_name
Jun 2 18:03:46 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.domain_name
Jun 2 18:03:46 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_domain
Jun 2 18:03:46 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_servers
Jun 2 18:03:46 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.interface_mtu
Jun 3 06:38:23 G3Myth fsr[8117]: no rw xfs file systems in mtab: /etc/mtab
Jun 4 06:35:00 G3Myth fsr[10702]: no rw xfs file systems in mtab:
/etc/mtab
Jun 5 06:40:54 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.host_name
Jun 5 06:40:54 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.domain_name
Jun 5 06:40:54 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_domain
Jun 5 06:40:54 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_servers
Jun 5 06:40:54 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.interface_mtu
Jun 5 06:44:40 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.host_name
Jun 5 06:44:40 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.domain_name
Jun 5 06:44:40 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_domain
Jun 5 06:44:40 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_servers
Jun 5 06:44:40 G3Myth dhcdbd: message_handler: message handler not
found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.interface_mtu
Jun 5 06:52:26 G3Myth fsr[16234]: no rw xfs file systems in mtab:
/etc/mtab
Jun 6 06:36:24 G3Myth fsr[20190]: no rw xfs file systems in mtab:
/etc/mtab
the other logs don't have any warnings or messages that I can tell.
More information about the mythtvnz
mailing list