[mythtv-users] Mythfrontend Crashes on Guide entry and input change

Thu Feb 22 02:49:32 UTC 2007

I am experiencing a crash of mythfrontend and sometimes X (kde)
randomly on one of my frontend machines.  Things will work fine some
of the time but then I will press guide and the guide screen will not
appear.  LiveTV will continue to play.  I will then press exit and the
screen goes blank and Mythfrontend and sometimes X freezes.  To remedy
the issue I press ctl-alt-backspace to recycle X.

This happens randomly - (usually when my in-laws or wife are using the Tv)

I see nothing "interesting" in any of the logs.  I do see a  /dev/dsp
in use error .. but this is because artsd is running and it takes a
couple of minutes on mythfrontend startup for it to be released by
artsd to where myth can play the sound... eventually the device is
freed and myth plays the sound normally.  I just havn't gotten around
to disabling artsd yet.

My system is setup with the following:
---------------------
Master Backend/Frontend  Mythtv .20
Ubuntu Edgy 6.10 as master backend
ivtv with PVR350, PVR150 using 350 output for X  (working fine)
lirc for remote

Remote Frontend / slave backend Mythtv .20 (Freezing problem)
FC6
HDHomerun for dual HD input.
lirc for remote
Nvidia 7600 running component output to HDTV 1080i (overscanned)
-----------------------
I have attached my logfile with messages, mythbackend and
mythfrontend logs from both machines.  The logtime for the issue shows
around 2/21 @9:29:30am

If there is anything else I can provide let me know.  I am new to the
list so I don't know if attachments are supported or welcomed but
couldn't find any documentation leaning one way or other...

Your help is greatly appreciated... from a frustrated first time user..

Logs:
------------------------------------------------------------------------------------------------------------------
Mythfrontend -v logging on Slave Frontend/Backend

2007-02-21 09:29:06.235 Using runtime prefix = /usr
2007-02-21 09:29:06.238 DPMS is disabled.
2007-02-21 09:29:06.244 New DB connection, total: 1
2007-02-21 09:29:06.362 Connected to database 'mythconverg' at host: TvMaster
2007-02-21 09:29:06.365 Total desktop dim: 1920x1080, with 1 screen[s].
2007-02-21 09:29:06.392 Using screen 0, 1920x1080 at 0,0
2007-02-21 09:29:06.411 Current Schema Version: 1160
2007-02-21 09:29:06.411 mythfrontend version: 0.20.20060828-3 www.mythtv.org
2007-02-21 09:29:06.412 Enabled verbose msgs:  important general
2007-02-21 09:29:07.458 Total desktop dim: 1920x1080, with 1 screen[s].
2007-02-21 09:29:07.459 Using screen 0, 1920x1080 at 0,0
2007-02-21 09:29:07.460 Switching to square mode (Iulius)
2007-02-21 09:29:07.505 Using the OpenGL painter
2007-02-21 09:29:07.810 New DB connection, total: 2
2007-02-21 09:29:07.811 Joystick disabled.
2007-02-21 09:29:07.812 Connected to database 'mythconverg' at host: TvMaster
2007-02-21 09:29:07.885 Loading from: /usr/share/mythtv/themes/default/base.xml
2007-02-21 09:29:08.205 Registering Internal as a media playback plugin.
2007-02-21 09:29:08.408 Registering MythDVD DVD Media Handler as a media handler
 ext()

2007-02-21 09:29:08.569 Registering MythDVD VCD Media Handler as a media handler
 ext()
2007-02-21 09:29:08.616 Registering MythGallery Media Handler 1/2 as a media han
dler ext()
2007-02-21 09:29:08.616 Registering MythGallery Media Handler 2/2 as a media han
dler ext(gif,jpg,png)
2007-02-21 09:29:08.616 MonitorRegisterExtensions(0x100, gif,jpg,png)
2007-02-21 09:29:08.823 Using NV NPOT texture extension
adding: ATA:2,0,0 -- CD/DVDW SH-S182M
adding: ATAPI:0,0,0 -- CD/DVDW SH-S182M
2007-02-21 09:29:09.025 Registering MythMusic Media Handler 1/2 as a media handl
er ext()
2007-02-21 09:29:09.025 Registering MythMusic Media Handler 2/2 as a media handl
er ext(ogg,mp3,aac,flac)
2007-02-21 09:29:09.025 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
SIP listening on IP Address 192.168.1.44:5060 NAT address 192.168.1.44
SIP: Cannot register; proxy, username or password not set
2007-02-21 09:29:09.402 Starting media monitor.
2007-02-21 09:29:09.406 Executing '/bin/mount /dev/sdb'
2007-02-21 09:29:09.475 Failed to mount /dev/sdb.
2007-02-21 09:29:09.477 Media status changed...  New status is: MEDIASTAT_NOTMOU
NTED old status was MEDIASTAT_UNPLUGGED
2007-02-21 09:29:09.478 Executing '/bin/mount /dev/sdc'
2007-02-21 09:29:09.483 Failed to mount /dev/sdc.
2007-02-21 09:29:09.495 Media status changed...  New status is: MEDIASTAT_NOTMOU
NTED old status was MEDIASTAT_UNPLUGGED
2007-02-21 09:29:09.496 Executing '/bin/mount /dev/sdd'
2007-02-21 09:29:09.502 Failed to mount /dev/sdd.
2007-02-21 09:29:09.504 Media status changed...  New status is: MEDIASTAT_NOTMOU
NTED old status was MEDIASTAT_UNPLUGGED
2007-02-21 09:29:09.505 Executing '/bin/mount /dev/sde'
2007-02-21 09:29:09.509 Failed to mount /dev/sde.
2007-02-21 09:29:09.511 Media status changed...  New status is: MEDIASTAT_NOTMOU
NTED old status was MEDIASTAT_UNPLUGGED
2007-02-21 09:29:13.543 Connecting to backend server: 192.168.1.40:6543 (try 1 o
f 5)
2007-02-21 09:29:13.544 Using protocol version 31
2007-02-21 09:29:13.592 TV: Attempting to change from None to WatchingLiveTV
2007-02-21 09:29:13.592 Using protocol version 31
2007-02-21 09:29:13.767 NVP: Disabling Audio, params(-1,2,44100)
2007-02-21 09:29:13.801 VideoOutputXv: XvMCTex: Init failed
2007-02-21 09:29:13.801 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2007-02-21 09:29:14.983 TV: Changing from None to WatchingLiveTV
2007-02-21 09:29:14.984 New DB connection, total: 3
2007-02-21 09:29:14.985 New DB connection, total: 4
2007-02-21 09:29:15.008 New DB connection, total: 5
2007-02-21 09:29:15.014 Connected to database 'mythconverg' at host: TvMaster
2007-02-21 09:29:15.014 Connected to database 'mythconverg' at host: TvMaster
2007-02-21 09:29:15.016 The realtime priority setting is not enabled.
2007-02-21 09:29:15.016 Connected to database 'mythconverg' at host: TvMaster
2007-02-21 09:29:15.113 Video timing method: USleep with busy wait
2007-02-21 09:29:15.547 VideoOutputXv: XvMCTex: Init failed
2007-02-21 09:29:15.547 VideoOutputXv: XVideo Adaptor Name: 'NV05 Video Blitter'
2007-02-21 09:29:16.131 AFD: Opened codec 0x8d46540, id(MPEG2VIDEO) type(Video)
2007-02-21 09:29:16.131 AFD: Opened codec 0x8da2f90, id(AC3) type(Audio)
2007-02-21 09:29:16.200 Opening OSS audio device '/dev/dsp'.
2007-02-21 09:29:16.200 AudioOutput Error: WARNING: something is currently using
: /dev/dsp, retrying.
2007-02-21 09:29:16.200 NVP: Disabling Audio, reason is: WARNING: something is c
urrently using: /dev/dsp, retrying.
2007-02-21 09:29:21.031 XMLParse::LoadTheme using /usr/share/mythtv/themes/Iuliu
s/ui.xml
2007-02-21 09:29:30.613 NVP: prebuffering pause

------------------------------------------------------------------------------------------------------
/var/log/messages on Slave Frontend/Backend

Feb 21 09:25:09 TvHD lircd-0.8.1-CVS[2721]: removed client
Feb 21 09:26:51 TvHD kernel: hde: cdrom_pc_intr: The drive appears confused (ire
ason = 0x01). Trying to recover by ending request.
Feb 21 09:27:33 TvHD last message repeated 2 times
Feb 21 09:28:37 TvHD kernel: hde: cdrom_pc_intr: The drive appears confused (ire
ason = 0x01). Trying to recover by ending request.
Feb 21 09:28:43 TvHD lircd-0.8.1-CVS[2721]: removed client
Feb 21 09:28:43 TvHD lircd-0.8.1-CVS[2721]: removed client
Feb 21 09:29:05 TvHD lircd-0.8.1-CVS[2721]: accepted new client on /dev/lircd
Feb 21 09:29:07 TvHD last message repeated 2 times
Feb 21 09:30:10 TvHD lircd-0.8.1-CVS[2721]: removed client
Feb 21 09:30:10 TvHD last message repeated 2 times
Feb 21 09:30:21 TvHD lircd-0.8.1-CVS[2721]: accepted new client on /dev/lircd
Feb 21 09:30:22 TvHD last message repeated 2 times
Feb 21 09:30:27 TvHD kernel: hde: cdrom_pc_intr: The drive appears confused (ire
ason = 0x01). Trying to recover by ending request.

--------------------------------------------------------------------------------------------------------
---------------------------------------------------------------------------------------------------------------

/var/log/syslog on Frontend/Slave Backend

2007-02-21 09:26:18.354 Expiring U.S. Senate from Wed Feb 21 09:00:00 2007, 0 MB
ytes, forced expire (LiveTV recording)
2007-02-21 09:28:43.718 TVRec(4): Changing from WatchingLiveTV to None
2007-02-21 09:28:44.229 Finished recording The Morning Show With Mike & Juliet:
channel 6920
2007-02-21 09:29:13.592 MainServer::HandleAnnounce Playback
2007-02-21 09:29:13.594 adding: TvHD as a client (events: 0)
2007-02-21 09:29:13.615 TVRec(3): Changing from None to WatchingLiveTV
2007-02-21 09:29:13.618 TVRec(3): HW Tuner: 3->3
2007-02-21 09:29:13.619 HDHRChan(10100586/0): device found at address 192.168.1.
97
2007-02-21 09:29:13.775 MainServer::HandleAnnounce Playback
2007-02-21 09:29:13.779 adding: TvHD as a client (events: 0)
2007-02-21 09:29:13.780 TVRec(4): Changing from None to WatchingLiveTV
2007-02-21 09:29:13.782 TVRec(4): HW Tuner: 4->4
2007-02-21 09:29:13.783 HDHRChan(10100586/1): device found at address 192.168.1.
97
2007-02-21 09:29:15.280 Finished recording The Morning Show With Mike & Juliet:
channel 6920
2007-02-21 09:29:15.318 Finished recording The Morning Show With Mike & Juliet:
channel 6920
2007-02-21 09:29:15.322 Preview Error: Previewer file '/video/6920_2007022109291
3.mpg' is not valid.
2007-02-21 09:29:16.366 Finished recording U.S. Senate: channel 7045
2007-02-21 09:29:16.546 Finished recording The Morning Show With Mike & Juliet:
channel 6920
0: start_time: 1478.157 duration: 0.087
1: start_time: 1478.098 duration: 0.089
stream: start_time: 16423.309 duration: 1.625 bitrate=10632 kb/s
2007-02-21 09:29:16.610 AFD: Opened codec 0x94d32d0, id(MPEG2VIDEO) type(Video)
2007-02-21 09:29:16.615 AFD: Opened codec 0x94d3620, id(AC3) type(Audio)
[mpeg2video @ 0x3466264]ac-tex damaged at 1 29
[mpeg2video @ 0x3466264]ac-tex damaged at 8 30
[mpeg2video @ 0x3466264]ac-tex damaged at 27 31
[mpeg2video @ 0x3466264]mb incr damaged
[mpeg2video @ 0x3466264]slice mismatch
[mpeg2video @ 0x3466264]ac-tex damaged at 2 34
[mpeg2video @ 0x3466264]invalid mb type in P Frame at 2 35
[mpeg2video @ 0x3466264]invalid mb type in P Frame at 53 36
[mpeg2video @ 0x3466264]mb incr damaged
[mpeg2video @ 0x3466264]ac-tex damaged at 3 38
[mpeg2video @ 0x3466264]mb incr damaged
[mpeg2video @ 0x3466264]invalid cbp at 22 40
[mpeg2video @ 0x3466264]ac-tex damaged at 1 41
[mpeg2video @ 0x3466264]ac-tex damaged at 8 42
[mpeg2video @ 0x3466264]ac-tex damaged at 3 43
[mpeg2video @ 0x3466264]ac-tex damaged at 0 44
2007-02-21 09:30:12.462 TVRec(3): Changing from WatchingLiveTV to None
2007-02-21 09:30:12.661 Finished recording U.S. Senate: channel 7045
2007-02-21 09:30:12.666 TVRec(4): Changing from WatchingLiveTV to None
2007-02-21 09:30:12.813 Finished recording The Morning Show With Mike & Juliet:
channel 6920
2007-02-21 09:30:18.478 Expiring U.S. Senate from Wed Feb 21 09:00:00 2007, 0 MB
ytes, forced expire (LiveTV recording)
2007-02-21 09:30:18.491 Expiring U.S. Senate from Wed Feb 21 09:00:00 2007, 0 MB
ytes, forced expire (LiveTV recording)

--------------------------------------------------------------------------------------------------------------------

Master Backend - Ubuntu Linux Edgy 6.10

/var/log/myth/mythbackend...

2007-02-21 09:23:12.333 Expiring  from Tue Feb 20 08:22:58 2007, 2240 MBytes, fo
rced expire (LiveTV recording)
2007-02-21 09:29:13.562 MainServer::HandleAnnounce Monitor
2007-02-21 09:29:13.599 adding: TvHD as a client (events: 0)
2007-02-21 09:29:13.601 MainServer::HandleAnnounce Monitor
2007-02-21 09:29:13.602 adding: TvHD as a client (events: 1)
2007-02-21 09:29:13.617 MainServer::HandleAnnounce Monitor
2007-02-21 09:29:13.632 adding: TvHD as a client (events: 0)
2007-02-21 09:29:13.633 MainServer::HandleAnnounce Monitor
2007-02-21 09:29:13.634 adding: TvHD as a client (events: 1)
2007-02-21 09:30:00.990 TVRec(1): Changing from RecordingOnly to None
2007-02-21 09:30:01.021 Finished recording Dora the Explorer "Boots to the Rescu
e": channel 1299
2007-02-21 09:30:01.240 Finished recording Dora the Explorer "Boots to the Rescu
e": channel 1299
[mpeg @ 0xb739a500]Parser not found for Codec Id: 94210 !
0: start_time: 0.036 duration: 161.201
1: start_time: 412.573 duration: 161.260
2: start_time: 0.026 duration: 161.179
stream: start_time: 0.289 duration: 6375.638 bitrate=1465 kb/s
2007-02-21 09:30:01.329 AFD: Opened codec 0x82a0f90, id(MPEG2VIDEO) type(Video)
2007-02-21 09:30:01.330 AFD: Opened codec 0x8425840, id(MP2) type(Audio)
2007-02-21 09:30:03.439 TVRec(1): Changing from None to RecordingOnly
2007-02-21 09:30:03.495 TVRec(1): HW Tuner: 1->1
2007-02-21 09:30:04.642 ret_pid(0) child(2847) status(0x0)
2007-02-21 09:30:05.658 ret_pid(0) child(2847) status(0x0)
2007-02-21 09:30:06.669 ret_pid(0) child(2847) status(0x0)
2007-02-21 09:30:07.690 ret_pid(2847) child(2847) status(0x0)
2007-02-21 09:30:07.724 External Tuning program exited with no error
2007-02-21 09:30:07.878 Started recording: Go, Diego, Go! "Little Kinkajou Is in
 Beehive Trouble": channel 1299 on cardid 1, sourceid 1
2007-02-21 09:30:09.019 Reschedule requested for id 0.
2007-02-21 09:30:13.327 Scheduled 307 items in 4.3 = 0.09 match + 4.21 place
[mpeg2video @ 0xb72b5a68]ac-tex damaged at 43 10
[mpeg2video @ 0xb72b5a68]Warning MVs not available
2007-02-21 09:30:23.918 JobQueue: Commercial Flagging Finished, 2 break(s) found
.
[mpeg @ 0xb739a500]Parser not found for Codec Id: 94210 !
0: start_time: 0.036 duration: 161.201
1: start_time: 412.573 duration: 161.260
2: start_time: 0.026 duration: 161.179
stream: start_time: 0.289 duration: 6375.638 bitrate=1465 kb/s
2007-02-21 09:30:23.969 AFD: Opened codec 0x832e5d0, id(MPEG2VIDEO) type(Video)
2007-02-21 09:30:23.969 AFD: Opened codec 0x83df140, id(MP2) type(Audio)
2007-02-21 09:30:31.841 JobQueue: Commercial Flagging Starting for Go, Diego, Go
! "Little Kinkajou Is in Beehive Trouble" recorded from channel 1299 at Wed Feb
21 09:30:00 2007
2007-02-21 09:30:32.875 Using runtime prefix = /usr
2007-02-21 09:30:33.118 New DB connection, total: 1
2007-02-21 09:30:33.128 Connected to database 'mythconverg' at host: TvMaster
2007-02-21 09:30:33.151 New DB connection, total: 2
2007-02-21 09:30:33.153 Connected to database 'mythconverg' at host: TvMaster
2007-02-21 09:30:33.198 Connecting to backend server: 192.168.1.40:6543 (try 1 o
f 5)
2007-02-21 09:30:33.210 Using protocol version 31
2007-02-21 09:30:33.211 MainServer::HandleAnnounce Monitor
2007-02-21 09:30:33.214 adding: TvMaster as a client (events: 0)
2007-02-21 09:30:33.215 MainServer::HandleAnnounce Monitor
2007-02-21 09:30:33.216 adding: TvMaster as a client (events: 1)

--------------------------------------------------------------------------------------------------------------------
/var/log/messages on Master Backend
Feb 21 08:31:00 TvMaster syslogd 1.4.1#18ubuntu6: restart.
Feb 21 08:51:01 TvMaster -- MARK --
Feb 21 09:11:01 TvMaster -- MARK --
Feb 21 09:31:01 TvMaster -- MARK --
Feb 21 09:51:02 TvMaster -- MARK --

--------------------------------------------------------------------------------------------------------------------