[mythtv-users] LiveTV locks up and crashes

Ma Begaj derliebegott at gmail.com
Wed Jan 28 13:10:09 UTC 2009


2009/1/28 Marc Chamberlin <marc at marcchamberlin.com>:
> Well I didn't get any takers to answer this so will repost with some
> additional info -
>
> I believe these crashes are most likely to happen when I am trying to watch
> a show while it is being recorded. As least that seems to be an empirical
> observation..  When this last happened I also had an additional problem, I
> tried to shut off the recording process by going to the menu and telling
> MythTV to stop recording. I tried several different methods to shut off the
> recording but MythTV totally ignored my requests and continued to record the
> show! I even exited MythTV, restarted the backend and then restarted the
> front end only to find it was still trying to record the show. And if I said
> to go ahead and watch it, it would freeze up on me in a few seconds. So
> something is amiss with trying to watch the show while recording it...  What
> is the proper way to shut off a recording anywise, it does not appear to be
> obvious to me now?  Hope this will shed some light on the problem..
>
>   Marc...
>
>
> Marc Chamberlin wrote:
>>
>> Hi -  I am experiencing an occasional problem with watching live TV in
>> that it will suddenly freeze up and stop. I have a Hauppauge 350 PVR card,
>> running under SuSE11.1 linux. This is a single computer so both the backend
>> and the frontend are running on the same computer. Often (but not always) I
>> can hit the escape key and after a minute or so MythTV will return to the
>> main menu. From there sometimes (but again not always) I can click on Watch
>> TV and it will start back up. Sometimes it will just exit completely and
>> sometimes when I try that it fails to restart and comes back to the main
>> screen. When that happens I can often just restart the backend and it will
>> work for awhile....
>>
>> Below is a copy of the MythTV log file for the backend taken around the
>> time when a few of these freezes occurred. Could some kind guru take a look
>> at it and decipher it for me? I could make some guesses but probably better
>> if an expert takes a look instead... Dunno what other info is needed but
>> will be glad to supply... Thanks in advance for any and all help offered!
>>
>>  Marc...
>>
>> more crashlog.txt
>> Starting up as the master server.
>> 2009-01-22 21:05:01.554 New DB connection, total: 3
>> 2009-01-22 21:05:01.590 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:05:01.670 New DB scheduler connection
>> 2009-01-22 21:05:01.707 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:05:02.975 Main::Registering HttpStatus Extension
>> 2009-01-22 21:05:03.006 mythbackend version: 0.21.20080304-1
>> www.mythtv.org
>> 2009-01-22 21:05:03.039 Enabled verbose msgs:  important general
>> 2009-01-22 21:05:03.074 AutoExpire: CalcParams(): Max required Free Space:
>> 1.0 GB w/freq: 15
>> min
>> 2009-01-22 21:05:04.745 Reschedule requested for id -1.
>> 2009-01-22 21:05:04.819 Scheduled 17 items in 0.1 = 0.04 match + 0.03
>> place
>> 2009-01-22 21:05:04.869 Seem to be woken up by USER
>> 2009-01-22 21:05:11.775 UPnpMedia: BuildMediaMap VIDEO scan starting in
>> :/share/Movies/dvd:
>> 2009-01-22 21:05:11.808 UPnpMedia: BuildMediaMap Done. Found 0 objects
>> 2009-01-22 21:05:17.566 MainServer::HandleAnnounce Monitor
>> 2009-01-22 21:05:17.599 adding: nova as a client (events: 0)
>> 2009-01-22 21:05:17.633 MainServer::HandleAnnounce Monitor
>> 2009-01-22 21:05:17.666 adding: nova as a client (events: 1)
>> 2009-01-22 21:05:17.705 MainServer::HandleAnnounce Playback
>> 2009-01-22 21:05:17.733 adding: nova as a client (events: 0)
>> 2009-01-22 21:05:17.768 TVRec(1): Changing from None to WatchingLiveTV
>> 2009-01-22 21:05:17.804 TVRec(1): HW Tuner: 1->1
>> 2009-01-22 21:05:18.962 AutoExpire: CalcParams(): Max required Free Space:
>> 2.0 GB w/freq: 15
>> min
>> 2009-01-22 21:05:18.974 MPEGRec(/dev/video0) Warning: Audio sample rate
>> 32000 Hz
>>                       is not supported by ivtv driver, using 48000 Hz
>> instead.
>> 2009-01-22 21:05:30.119 TFW, Error: Write() -- IOBOUND begin cnt(2048)
>> free(2047)
>> 2009-01-22 21:05:38.669 TVRec(1): Changing from WatchingLiveTV to None
>> 2009-01-22 21:06:07.936 TFW, Error: Write() -- IOBOUND end
>> 2009-01-22 21:06:08.171 Finished recording Supernatural "Criss Angel Is a
>> Douche Bag": chann
>> el 1005
>> 2009-01-22 21:06:08.252 MythSocket(b1f0c070:-1): writeStringList: Error,
>> socket went unconne
>> cted.
>> Mutex destroy failure: Device or resource busy
>> 2009-01-22 21:06:28.517 DB Error (Error in JobQueue::GetJobCmd()):
>> Query was:
>> SELECT cmds FROM jobqueue WHERE id = 13;
>> Driver error was [2/2006]:
>> QMYSQL3: Unable to execute query
>> Database error was:
>> MySQL server has gone away
>>
>> 2009-01-22 21:06:28.568 DB Error (Error in JobQueue::ChangeJobStatus()):
>> Query was:
>> UPDATE jobqueue SET status = 4, comment = '15% Completed @ 56.4388 fps.'
>> WHERE id = 13;
>> Driver error was [2/2006]:
>> QMYSQL3: Unable to execute query
>> Database error was:
>> MySQL server has gone away
>>
>> 2009-01-22 21:07:35.492 Using runtime prefix = /usr
>> 2009-01-22 21:07:36.727 Empty LocalHostName.
>> 2009-01-22 21:07:37.217 Using localhost value of nova
>> 2009-01-22 21:07:37.647 New DB connection, total: 1
>> 2009-01-22 21:07:37.911 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:07:38.246 Closing DB connection named 'DBManager0'
>> 2009-01-22 21:07:38.315 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:07:38.410 New DB connection, total: 2
>> 2009-01-22 21:07:38.508 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:07:38.753 Current Schema Version: 1214
>> Starting up as the master server.
>> 2009-01-22 21:07:39.951 New DB connection, total: 3
>> 2009-01-22 21:07:40.194 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:07:40.689 New DB scheduler connection
>> 2009-01-22 21:07:40.726 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:07:42.097 Main::Registering HttpStatus Extension
>> 2009-01-22 21:07:42.318 mythbackend version: 0.21.20080304-1
>> www.mythtv.org
>> 2009-01-22 21:07:42.369 Enabled verbose msgs:  important general
>> 2009-01-22 21:07:42.445 AutoExpire: CalcParams(): Max required Free Space:
>> 1.0 GB w/freq: 15
>> min
>> 2009-01-22 21:07:43.837 Reschedule requested for id -1.
>> 2009-01-22 21:07:44.361 Scheduled 17 items in 0.5 = 0.37 match + 0.15
>> place
>> 2009-01-22 21:07:44.543 Seem to be woken up by USER
>> 2009-01-22 21:07:50.968 UPnpMedia: BuildMediaMap VIDEO scan starting in
>> :/share/Movies/dvd:
>> 2009-01-22 21:07:51.086 UPnpMedia: BuildMediaMap Done. Found 0 objects
>> 2009-01-22 21:08:00.823 Expiring 6 MBytes for 1005 @ Thu Jan 22 21:00:00
>> 2009 => Supernatura
>> l "Criss Angel Is a Douche Bag"
>> 2009-01-22 21:08:18.340 MainServer::HandleAnnounce Monitor
>> 2009-01-22 21:08:18.408 adding: nova as a client (events: 0)
>> 2009-01-22 21:08:18.434 MainServer::HandleAnnounce Monitor
>> 2009-01-22 21:08:18.459 adding: nova as a client (events: 1)
>> 2009-01-22 21:08:18.487 MainServer::HandleAnnounce Playback
>> 2009-01-22 21:08:18.542 adding: nova as a client (events: 0)
>> 2009-01-22 21:08:18.591 TVRec(1): Changing from None to WatchingLiveTV
>> 2009-01-22 21:08:18.623 TVRec(1): HW Tuner: 1->1
>> 2009-01-22 21:08:20.112 AutoExpire: CalcParams(): Max required Free Space:
>> 2.0 GB w/freq: 15
>> min
>> 2009-01-22 21:08:20.126 MPEGRec(/dev/video0) Warning: Audio sample rate
>> 32000 Hz
>>                       is not supported by ivtv driver, using 48000 Hz
>> instead.
>> 2009-01-22 21:08:50.872 JobQueue: Commercial Flagging Starting for
>> Smallville "Bulletproof"
>> recorded from channel 1005 at Thu Jan 22 20:00:00 2009
>> 2009-01-22 21:08:51.090 Using runtime prefix = /usr
>> 2009-01-22 21:08:51.206 Empty LocalHostName.
>> 2009-01-22 21:08:51.284 Using localhost value of nova
>> 2009-01-22 21:08:51.340 New DB connection, total: 1
>> 2009-01-22 21:08:51.375 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:08:51.404 Closing DB connection named 'DBManager0'
>> 2009-01-22 21:08:51.438 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:08:51.475 New DB connection, total: 2
>> 2009-01-22 21:08:51.505 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:08:51.646 Connecting to backend server: 192.168.2.20:6543
>> (try 1 of 5)
>> 2009-01-22 21:08:51.689 Using protocol version 40
>> 2009-01-22 21:08:51.723 MainServer::HandleAnnounce Monitor
>> 2009-01-22 21:08:51.756 adding: nova as a client (events: 0)
>> 2009-01-22 21:08:51.791 MainServer::HandleAnnounce Monitor
>> 2009-01-22 21:08:51.823 adding: nova as a client (events: 1)
>> 2009-01-22 21:08:54.361 AFD: Opened codec 0x821b4e0, id(MPEG2VIDEO)
>> type(Video)
>> 2009-01-22 21:08:54.413 AFD: codec MP2 has 2 channels
>> 2009-01-22 21:08:54.438 AFD: Opened codec 0x821b960, id(MP2) type(Audio)
>> 2009-01-22 21:09:01.157 AutoExpire: CalcParams(): Max required Free Space:
>> 2.0 GB w/freq: 15
>> min
>> 2009-01-22 21:24:01.231 AutoExpire: CalcParams(): Max required Free Space:
>> 2.0 GB w/freq: 15
>> min
>> 2009-01-22 21:24:01.274 Expiring 2081 MBytes for 1010 @ Wed Jan 21
>> 20:00:00 2009 => Make 'em
>> Laugh: The Funny Business of America "The Knockabouts: Slip on a Banana
>> Peel; The Groundbre
>> akers: When I'm Bad, I'm Better"
>> 2009-01-22 21:37:30.123 Using runtime prefix = /usr
>> 2009-01-22 21:37:30.229 Empty LocalHostName.
>> 2009-01-22 21:37:30.257 Using localhost value of nova
>> 2009-01-22 21:37:30.397 New DB connection, total: 1
>> 2009-01-22 21:37:30.523 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:37:30.558 Closing DB connection named 'DBManager0'
>> 2009-01-22 21:37:30.592 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:37:30.632 New DB connection, total: 2
>> 2009-01-22 21:37:30.675 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:37:30.724 Current Schema Version: 1214
>> 2009-01-22 21:37:31.045 AFD: Opened codec 0x82aeae0, id(MPEG2VIDEO)
>> type(Video)
>> 2009-01-22 21:37:31.085 AFD: codec MP2 has 2 channels
>> 2009-01-22 21:37:31.110 AFD: Opened codec 0x82aef60, id(MP2) type(Audio)
>> 2009-01-22 21:37:31.330 Preview: Grabbed preview
>> '/mediacenter/tv/1005_20090122200000.mpg' 7
>> 20x480 at 64s
>> 2009-01-22 21:37:54.237 UPnpMedia: BuildMediaMap VIDEO scan starting in
>> :/share/Movies/dvd:
>> 2009-01-22 21:37:54.338 UPnpMedia: BuildMediaMap Done. Found 0 objects
>> 2009-01-22 21:39:01.362 AutoExpire: CalcParams(): Max required Free Space:
>> 2.0 GB w/freq: 15
>> min
>> 2009-01-22 21:54:01.430 AutoExpire: CalcParams(): Max required Free Space:
>> 2.0 GB w/freq: 15
>> min
>> 2009-01-22 21:56:08.080 TFW, Error: Write() -- IOBOUND begin cnt(2048)
>> free(2047)
>> 2009-01-22 21:56:47.796 TVRec(1): Changing from WatchingLiveTV to None
>> 2009-01-22 21:56:56.785 MainServer::HandleAnnounce Playback
>> 2009-01-22 21:56:56.816 adding: nova as a client (events: 0)
>> 2009-01-22 21:57:31.966 Using runtime prefix = /usr
>> 2009-01-22 21:57:32.023 Empty LocalHostName.
>> 2009-01-22 21:57:32.064 Using localhost value of nova
>> 2009-01-22 21:57:32.125 New DB connection, total: 1
>> 2009-01-22 21:57:32.203 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:32.267 Closing DB connection named 'DBManager0'
>> 2009-01-22 21:57:32.317 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:32.351 New DB connection, total: 2
>> 2009-01-22 21:57:32.384 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:32.454 Current Schema Version: 1214
>> Starting up as the master server.
>> 2009-01-22 21:57:32.637 New DB connection, total: 3
>> 2009-01-22 21:57:32.687 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:32.860 New DB scheduler connection
>> 2009-01-22 21:57:32.879 Connected to database 'mythconverg' at host:
>> localhost
>> QServerSocket: failed to bind or listen to the socket
>> 2009-01-22 21:57:32.951 MediaServer::HttpServer Create Error
>> 2009-01-22 21:57:32.972 mythbackend version: 0.21.20080304-1
>> www.mythtv.org
>> 2009-01-22 21:57:33.055 Enabled verbose msgs:  important general
>> 2009-01-22 21:57:33.099 AutoExpire: CalcParams(): Max required Free Space:
>> 1.0 GB w/freq: 15
>> min
>> QServerSocket: failed to bind or listen to the socket
>> 2009-01-22 21:57:33.148 Failed to bind port 6543. Exiting.
>> 2009-01-22 21:57:36.436 Using runtime prefix = /usr
>> 2009-01-22 21:57:36.467 Empty LocalHostName.
>> 2009-01-22 21:57:36.541 Using localhost value of nova
>> 2009-01-22 21:57:36.592 New DB connection, total: 1
>> 2009-01-22 21:57:36.622 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:36.643 Closing DB connection named 'DBManager0'
>> 2009-01-22 21:57:36.668 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:36.694 New DB connection, total: 2
>> 2009-01-22 21:57:36.719 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:36.754 Current Schema Version: 1214
>> Starting up as the master server.
>> 2009-01-22 21:57:36.825 New DB connection, total: 3
>> 2009-01-22 21:57:36.853 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:57:37.012 New DB scheduler connection
>> 2009-01-22 21:57:37.063 Connected to database 'mythconverg' at host:
>> localhost
>> QServerSocket: failed to bind or listen to the socket
>> 2009-01-22 21:57:37.134 MediaServer::HttpServer Create Error
>> 2009-01-22 21:57:37.156 mythbackend version: 0.21.20080304-1
>> www.mythtv.org
>> 2009-01-22 21:57:37.181 Enabled verbose msgs:  important general
>> 2009-01-22 21:57:37.208 AutoExpire: CalcParams(): Max required Free Space:
>> 1.0 GB w/freq: 15
>> min
>> QServerSocket: failed to bind or listen to the socket
>> 2009-01-22 21:57:37.257 Failed to bind port 6543. Exiting.
>> 2009-01-22 21:58:14.707 Using runtime prefix = /usr
>> 2009-01-22 21:58:14.747 Empty LocalHostName.
>> 2009-01-22 21:58:14.780 Using localhost value of nova
>> 2009-01-22 21:58:14.821 New DB connection, total: 1
>> 2009-01-22 21:58:14.861 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:58:14.891 Closing DB connection named 'DBManager0'
>> 2009-01-22 21:58:14.924 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:58:14.958 New DB connection, total: 2
>> 2009-01-22 21:58:14.991 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:58:15.027 Current Schema Version: 1214
>> Starting up as the master server.
>> 2009-01-22 21:58:15.133 New DB connection, total: 3
>> 2009-01-22 21:58:15.159 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 21:58:15.337 New DB scheduler connection
>> 2009-01-22 21:58:15.360 Connected to database 'mythconverg' at host:
>> localhost
>> QServerSocket: failed to bind or listen to the socket
>> 2009-01-22 21:58:15.415 MediaServer::HttpServer Create Error
>> 2009-01-22 21:58:15.445 mythbackend version: 0.21.20080304-1
>> www.mythtv.org
>> 2009-01-22 21:58:15.478 Enabled verbose msgs:  important general
>> 2009-01-22 21:58:15.520 AutoExpire: CalcParams(): Max required Free Space:
>> 1.0 GB w/freq: 15
>> min
>> QServerSocket: failed to bind or listen to the socket
>> 2009-01-22 21:58:15.654 Failed to bind port 6543. Exiting.
>> 2009-01-22 22:01:26.402 Using runtime prefix = /usr
>> 2009-01-22 22:01:26.887 Empty LocalHostName.
>> 2009-01-22 22:01:27.075 Using localhost value of nova
>> 2009-01-22 22:01:27.509 New DB connection, total: 1
>> 2009-01-22 22:01:27.814 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 22:01:28.157 Closing DB connection named 'DBManager0'
>> 2009-01-22 22:01:28.257 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 22:01:28.494 New DB connection, total: 2
>> 2009-01-22 22:01:28.768 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 22:01:28.948 Current Schema Version: 1214
>> Starting up as the master server.
>> 2009-01-22 22:01:29.738 New DB connection, total: 3
>> 2009-01-22 22:01:29.839 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 22:01:30.170 New DB scheduler connection
>> 2009-01-22 22:01:30.792 Connected to database 'mythconverg' at host:
>> localhost
>> 2009-01-22 22:01:32.685 Main::Registering HttpStatus Extension
>> 2009-01-22 22:01:33.091 mythbackend version: 0.21.20080304-1
>> www.mythtv.org
>> 2009-01-22 22:01:33.234 Enabled verbose msgs:  important general
>> 2009-01-22 22:01:33.494 AutoExpire: CalcParams(): Max required Free Space:
>> 1.0 GB w/freq: 15
>> min
>> 2009-01-22 22:01:34.239 Reschedule requested for id -1.
>> 2009-01-22 22:01:35.203 Scheduled 17 items in 1.0 = 0.60 match + 0.36
>> place
>> 2009-01-22 22:01:35.354 Seem to be woken up by USER
>> 2009-01-22 22:01:41.520 UPnpMedia: BuildMediaMap VIDEO scan starting in
>> :/share/Movies/dvd:
>> 2009-01-22 22:01:43.806 UPnpMedia: BuildMediaMap Done. Found 0 objects
>> 2009-01-22 22:02:51.212 AutoExpire: CalcParams(): Max required Free Space:
>> 1.0 GB w/freq: 15
>> min
>>
>>
>> _______________________________________________
>> mythtv-users mailing list
>> mythtv-users at mythtv.org
>> http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users
>>
>
>
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users
>




this error  "TFW, Error: Write() -- IOBOUND" is talking about some
problems while trying to write on the disk. It happens usually when
your disk is too slow.

that is caused by too much I/O at that moment. you maybe have mysql
database and recordings on the same disk? or maybe slow delete is
turned on (it can cause problems for some people)... or disk is just
slow.

try moving mysql or recordings to an another disk.  Or try maybe
optimizing your disk settings:
http://www.mythtv.org/pipermail/mythtv-users/2007-October/199434.html

google: http://www.google.com/search?q=TFW%2C+Error%3A+Write()+--+IOBOUND&btnG=Suche&meta=


More information about the mythtv-users mailing list