Noob Problem "please try again later"

[mattbatt]

I'm running LinHES 7.4 and ICS I can see the list of TV series that I have recorded when I click on any of them I get a "please try again later" I only have the one MythTV BE/FE system on my network. This is my first attempt at a remote front end.

[dmfrey]

@mattbatt

Just a couple of troubleshooting items...

1. Can you open in your browser: http://:6544/

If you can't then your backend is not enabled to all access to the Mythtv Services API.

If it is, then you should be all set.

Is it still trying to load the data now? How long has it been doing this?

Also, are you running the app from the market or amazon app store? from source?

What device are you attempting to run it on?

[billmeek]

All the questions Dan asked, plus:

Can you look at the log files on your MythTV backend? When trying to play a recording, the backend 1st needs to transcode the file. Depending on where LinHES stores logs, you should see activity similar to this

CoreContext transcode.cpp:1449 (TranscodeFile) - mythtranscode: xxx% Completed @ 56.3596 fps.

Logs are frequently stored in /var/log/syslog, or /var/log/mythtv/mythtranscode...log. If LinHES uses the rsyslog system, they may get directed elsewhere, as defined in /etc/rsyslog.d/mythtv (that's an example of the configuration filename.) If you can't find the logs, with the backend running, please post the output of: ps ax | grep myth | grep -v grep here.

[billmeek]

Also, note that a new FAQ page was added this morning (after you posted this.) You might it useful:

https://github.com/MythTV-Clients/MythTV-Android-Frontend/wiki/Connection-Troubleshooting-Guidelines

[mattbatt]

1) Yes I can see the page from my phone and my other computer

2) I installed the app yesterday morning I didn't start playing with it till about 10 pm and fell asleep at 1 am, I just turned my WiFi back on and tried the app to no avail.

3) Google play store.

4) phone is an HTC EVO 3D, Router is a TP link TL-841N with UPnP on, MythBackend is running on a AMD Athalon 64 3500+, 2 gB of ram, Nvdia GT430, I'm running linux 2.6.39. It's possible that the PC isn't fast enough. It's made from spare parts

5) LOGS

mythbackend.30300.log I found this repeating string

2012-11-22T00:01:48.404334-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:01:48.404436-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor 2012-11-22T00:01:48.404525-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:01:48.404661-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor 2012-11-22T00:01:48.404751-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:02:20.712671-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor 2012-11-22T00:02:20.712711-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:02:21.013022-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor 2012-11-22T00:02:21.013050-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:02:21.013058-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor 2012-11-22T00:02:21.013064-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)

File: gimli_messages.log

2012-11-22T00:09:33.223649-05:00 gimli kernel: [88606.043703] fuppes[15939]: segfault at 0 ip b7738d41 sp bfcfbbe0 error 4 in libfuppes.so.0.0.0[b7711000+107000] 2012-11-22T00:09:34.253653-05:00 gimli kernel: [88607.073513] fuppes[15944]: segfault at 0 ip b76eed41 sp bfc040c0 error 4 in libfuppes.so.0.0.0[b76c7000+107000] 2012-11-22T00:09:35.283649-05:00 gimli kernel: [88608.103473] fuppes[15947]: segfault at 0 ip b777cd41 sp bf96b620 error 4 in libfuppes.so.0.0.0[b7755000+107000] 2012-11-22T00:09:36.316652-05:00 gimli kernel: [88609.136354] fuppes[15952]: segfault at 0 ip b76a0d41 sp bfd65de0 error 4 in libfuppes.so.0.0.0[b7679000+107000] 2012-11-22T00:09:37.349649-05:00 gimli kernel: [88610.169595] fuppes[15955]: segfault at 0 ip b7609d41 sp bffc48f0 error 4 in libfuppes.so.0.0.0[b75e2000+107000]

File: gimli_mythpreviewgen.13775.log

2012-11-22T00:01:09.715887-05:00 gimli mythpreviewgen[13775]: C thread_unknown mythcommandlineparser.cpp:2534 (ConfigureLogging) mythpreviewgen version: fixes/0.25 [v0.25.3-7-gfe9f6fa] www.mythtv.org 2012-11-22T00:01:09.716138-05:00 gimli mythpreviewgen[13775]: C thread_unknown mythcommandlineparser.cpp:2536 (ConfigureLogging) Qt version: compile: 4.7.3, runtime: 4.7.3 2012-11-22T00:01:09.716231-05:00 gimli mythpreviewgen[13775]: N thread_unknown mythcommandlineparser.cpp:2538 (ConfigureLogging) Enabled verbose msgs: general 2012-11-22T00:01:09.716320-05:00 gimli mythpreviewgen[13775]: N thread_unknown logging.cpp:1176 (logStart) Setting Log Level to LOG_INFO 2012-11-22T00:01:09.716409-05:00 gimli mythpreviewgen[13775]: I thread_unknown logging.cpp:229 (FileLogger) Added logging to the console 2012-11-22T00:01:09.716496-05:00 gimli mythpreviewgen[13775]: I thread_unknown logging.cpp:369 (SyslogLogger) Added syslogging to facility local6 2012-11-22T00:01:09.716585-05:00 gimli mythpreviewgen[13775]: I thread_unknown logging.cpp:425 (DatabaseLogger) Added database logging to table logging 2012-11-22T00:01:09.716696-05:00 gimli mythpreviewgen[13775]: N thread_unknown logging.cpp:1215 (logStart) Setting up SIGHUP handler 2012-11-22T00:01:09.716785-05:00 gimli mythpreviewgen[13775]: N thread_unknown mythdirs.cpp:51 (InitializeMythDirs) Using runtime prefix = /usr 2012-11-22T00:01:09.716872-05:00 gimli mythpreviewgen[13775]: N thread_unknown mythdirs.cpp:64 (InitializeMythDirs) Using configuration directory = /home/mythtv/.mythtv 2012-11-22T00:01:09.716967-05:00 gimli mythpreviewgen[13775]: I CoreContext mythcorecontext.cpp:227 (Init) Assumed character encoding: en_US.utf-8 2012-11-22T00:01:09.717056-05:00 gimli mythpreviewgen[13775]: N CoreContext mythcontext.cpp:477 (LoadDatabaseSettings) Empty LocalHostName. 2012-11-22T00:01:09.717146-05:00 gimli mythpreviewgen[13775]: I CoreContext mythcontext.cpp:481 (LoadDatabaseSettings) Using localhost value of gimli 2012-11-22T00:01:10.117638-05:00 gimli mythpreviewgen[13775]: E CoreContext configuration.cpp:112 (Save) Could not open settings file /home/mythtv/.mythtv/config.xml for writing 2012-11-22T00:01:10.117856-05:00 gimli mythpreviewgen[13775]: N CoreContext mythcorecontext.cpp:1354 (InitLocale) Setting QT default locale to EN_US 2012-11-22T00:01:10.117974-05:00 gimli mythpreviewgen[13775]: I CoreContext mythcorecontext.cpp:1387 (SaveLocaleDefaults) Current locale EN_US 2012-11-22T00:01:10.118628-05:00 gimli mythpreviewgen[13775]: N CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) Reading locale defaults from /usr/share/mythtv//locales/en_us.xml 2012-11-22T00:01:10.218894-05:00 gimli mythpreviewgen[13775]: I CoreContext previewgenerator.cpp:656 (LocalPreviewRun) Preview at calculated offset (1200 seconds) 2012-11-22T00:01:10.419071-05:00 gimli mythpreviewgen[13775]: I ProgramInfoUpdater mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.0.164:6543 (try 1 of $ 2012-11-22T00:01:10.419268-05:00 gimli mythpreviewgen[13775]: I ProgramInfoUpdater mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72 2012-11-22T00:01:11.321526-05:00 gimli mythpreviewgen[13775]: I CoreContext avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x96c7680, id(MPEG2VIDEO) type(Video) 2012-11-22T00:01:11.321753-05:00 gimli mythpreviewgen[13775]: I CoreContext avformatdecoder.cpp:1960 (ScanStreams) AFD: codec AC3 has 6 channels 2012-11-22T00:01:11.321848-05:00 gimli mythpreviewgen[13775]: I CoreContext avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x96c7e10, id(AC3) type(Audio) 2012-11-22T00:01:11.321940-05:00 gimli mythpreviewgen[13775]: I CoreContext avformatdecoder.cpp:1960 (ScanStreams) AFD: codec AC3 has 1 channels 2012-11-22T00:01:11.322029-05:00 gimli mythpreviewgen[13775]: I CoreContext avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x96c8610, id(AC3) type(Audio) 2012-11-22T00:01:15.028574-05:00 gimli mythpreviewgen[13775]: I CoreContext previewgenerator.cpp:823 (GetScreenGrab) Preview: Grabbed preview '/myth0/tv/10021_20121121220000.mpg' 1920x1088@1200s 2012-11-22T00:01:15.830169-05:00 gimli mythpreviewgen[13775]: I CoreContext mythcontext.cpp:1115 (~MythContext) Waiting for threads to exit.

6)] $ ps ax | grep myth | grep -v grep 1042 ? Ss 0:00 runsv mythexport 1045 ? Ss 0:00 runsv mythbackend 1081 ? S 0:00 /bin/su - mythtv -l -c /usr/LH/bin/LinHES-start 1117 ? S 0:00 /usr/bin/logger -p local6.info -t mythfrontend 1950 ? Sl 26:22 /usr/bin/mythfrontend -d --syslog local6 14108 ? Ss 0:00 sshd: mythtv [priv] 14131 ? S 0:00 sshd: mythtv@pts/2 30259 ? S 0:00 su mythtv -c /usr/bin/mythbackend -q --syslog local6 30300 ? Sl 21:38 /usr/bin/mythbackend -q --syslog local6

7) Aw crap just my luck. I was looking all over for a user guide or something to make sure I had everything set up correctly on my backend. Just my luck the FAQ gets released this morning :)

8) I am not anywhere near the point of playing videos. I can see the list of overall TV shows for example it shows Ask this old house Austin City Limits The Big Bang Theory

If I click on any of them I get the "Please try again later" message, I don't see any episode titles or have the opportunity to select a video player.

[mattbatt]

going through the FAQ 1) http://192.168.0.164:6544/stuff gives me 404 on my computer and my phone 2) logging is on part of the mythbackend log is listed above

tailf /var/log/2012-11-22/gimli_mythbackend.30300.log

mythtv@gimli 2012-11-22]$ tailf /var/log/2012-11-22/gimli_mythbackend.30300.log 2012-11-22T00:37:57.474564-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:37:57.474570-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor 2012-11-22T00:37:57.475040-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:37:57.475054-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor 2012-11-22T00:37:57.475060-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) 2012-11-22T00:39:49.464581-05:00 gimli mythbackend[30300]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min 2012-11-22T00:40:31.498517-05:00 gimli mythbackend[30300]: E HttpServer94 programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121121150000.mpg): GetPlaybackURL: '10021_20121121150000.mpg' should be local, but it can not be found. 2012-11-22T00:40:47.410808-05:00 gimli mythbackend[30300]: E HttpServer85 programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121121150000.mpg): GetPlaybackURL: '10021_20121121150000.mpg' should be local, but it can not be found. 2012-11-22T00:40:55.017363-05:00 gimli mythbackend[30300]: E HttpServer85 programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121121150000.mpg): GetPlaybackURL: '10021_20121121150000.mpg' should be local, but it can not be found. 2012-11-22T00:41:02.723714-05:00 gimli mythbackend[30300]: E HttpServer116 programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121121150000.mpg): GetPlaybackURL: '10021_20121121150000.mpg' should be local, but it can not be found. 2012-11-22T00:42:43.706186-05:00 gimli mythbackend[30300]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread

I saw that it was looking for a file so I did a touch (filename) and the log says that mythbackend is now looking for another one 2012-11-22T00:50:02.964729-05:00 gimli mythbackend[30300]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3091_20110613173152.mpg): GetPlaybackURL: '3091_20110613173152.mpg' should be local, but it can not be found. 2012-11-22T00:50:02.965092-05:00 gimli mythbackend[30300]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3061_20110613172936.mpg): GetPlaybackURL: '3061_20110613172936.mpg' should be local, but it can not be found.

Hope this helps.

[billmeek]

On 11/21/2012 11:23 PM, mattbatt wrote:

Thanks for providing the detail.

...

mythbackend.30300.log I found this repeating string

2012-11-22T00:01:48.404334-05:00 gimli mythbackend[30300]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0) ...

These are normal information messages.

File: gimli_messages.log

2012-11-22T00:09:33.223649-05:00 gimli kernel: [88606.043703] fuppes[15939]: segfault at 0 ip b7738d41 sp bfcfbbe0 error 4 in libfuppes.so.0.0.0[b7711000+107000] ...

I"m running ubuntu, so won't be of much help here, however segfaults are never good and this appears to be UPnP related. If these match up with each time a recording is trying to be played, then I'd seek help on the distribution's list/site. This message just tells you the segfault happened, I normally use a tool called gdb to create a backtrace (which has the actual data used to fix the problem). Hopefully you don't need to go there yet.

File: gimli_mythpreviewgen.13775.log ...

The preview generator is what makes the little preview boxes for MythTV's frontend Watch Recordings display. No problem here

...

8) I am not anywhere near the point of playing videos. I can see the list of overall TV shows for example it shows Ask this old house Austin City Limits The Big Bang Theory

If I click on any of them I get the "Please try again later" message, I don't see any episode titles or have the opportunity to select a video player.

This seems consistent with the messages you posted in the next response, so I'll comment there.

Bill

[billmeek]

On 11/21/2012 11:52 PM, mattbatt wrote:

going through the FAQ 1) http://192.168.0.164:6544/stuff gives me 404 on my computer and my phone

Sorry, the "stuff" was supposed to remind me to test something. It's been removed from the FAQ. Thanks for posting the result.

mythtv@gimli 2012-11-22]$ tailf /var/log/2012-11-22/gimli_mythbackend.30300.log ... 2012-11-22T00:40:31.498517-05:00 gimli mythbackend[30300]: E HttpServer94 programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121121150000.mpg): GetPlaybackURL: '10021_20121121150000.mpg' should be local, but it can not be found. ...

I believe you've spotted the problem. Your Android device was asking for a file named 10021_20121121150000.mpg but MythTV says it couldn't find it. You mentioned doing a touch to create the file and that message went away, on the backend.

1. That suggests that MythTV couldn't play that file either, is that true?
2. Were you running the backend with: mythbackend --setverbose upnp ?

Bill

[dmfrey]

Do you have write permissions to the Streaming storage group?

Bill, we should add this to the FAQ. In some instances, when upgrading to .24 to .25, the streaming storage group either doesn't get created or the user doesn't get write access to that storage group.

On Thu, Nov 22, 2012 at 3:57 AM, billmeek notifications@github.com wrote:

On 11/21/2012 11:52 PM, mattbatt wrote:

going through the FAQ 1) http://192.168.0.164:6544/stuff gives me 404 on my computer and my phone

Sorry, the "stuff" was supposed to remind me to test something. It's been removed from the FAQ. Thanks for posting the result.

mythtv@gimli 2012-11-22]$ tailf /var/log/2012-11-22/gimli_mythbackend.30300.log ...

2012-11-22T00:40:31.498517-05:00 gimli mythbackend[30300]: E HttpServer94 programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121121150000.mpg): GetPlaybackURL: '10021_20121121150000.mpg' should be local, but it can not be found. ...

I believe you've spotted the problem. Your Android device was asking for a file named 10021_20121121150000.mpg but MythTV says it couldn't find it. You mentioned doing a touch to create the file and that message went away, on the backend.

1. That suggests that MythTV couldn't play that file either, is that true?
2. Were you running the backend with: mythbackend --setverbose upnp ?

Bill

— Reply to this email directly or view it on GitHubhttps://github.com/MythTV-Clients/MythTV-Android-Frontend/issues/84#issuecomment-10627460.

My Google Profile http://www.google.com/profiles/dmfrey

[mattbatt]

What streaming storage group? That might be my problem.

[billmeek]

Hi;

Take this out for a test drive. Streaming is the 2nd issue from the bottom.

https://github.com/MythTV-Clients/MythTV-Android-Frontend/wiki/MythTV-Configuration-Suggestions

You can see a sample of the MythTV error message that you would likely get.

Were those two files that "could not be found" playable on your frontend?

[dmfrey]

Matt,

I was just looking at the reviews on the Google Play Store. Was your review the 1 star from Matt with the HTC One X? If not, please disregard this. If so, and if you were able to resolve the issue, can you adjust that review?

Thank you, Dan

On Fri, Nov 23, 2012 at 12:18 AM, billmeek notifications@github.com wrote:

Hi;

Take this out for a test drive. Streaming is the 2nd issue from the bottom.

https://github.com/MythTV-Clients/MythTV-Android-Frontend/wiki/MythTV-Configuration-Suggestions

You can see a sample of the MythTV error message that you would likely get.

Were those two files that "could not be found" playable on your frontend?

— Reply to this email directly or view it on GitHubhttps://github.com/MythTV-Clients/MythTV-Android-Frontend/issues/84#issuecomment-10650033.

My Google Profile http://www.google.com/profiles/dmfrey

[mattbatt]

@dan. No way. I haven't reviewed yet. And I am using the EVO 3d.

[dmfrey]

Matt,

Sorry about that.

Is everything working for you now? Fyi, I pushed a new version of the app to the market tonight.

Dan On Nov 23, 2012 9:21 PM, "mattbatt" notifications@github.com wrote:

@dan https://github.com/dan. No way. I haven't reviewed yet. And I am using the EVO 3d.

— Reply to this email directly or view it on GitHubhttps://github.com/MythTV-Clients/MythTV-Android-Frontend/issues/84#issuecomment-10673102.

[mattbatt]

Well the new version is giving me a listing of episodes now. Vplayer gives me a complaint that it "can't open this file" I will go through the steps listed above again now that I'm a little further. But now I've killed my battery!

Is there some test I could use to judge if my Backend hardware is too slow?

[mattbatt]

Mythtranscode.log

2012-11-23T22:44:27.106908-05:00 gimli mythtranscode[387]: C thread_unknown mythcommandlineparser.cpp:2534 (ConfigureLogging) mythtranscode version: fixes/0.25 [v0.25.3-7-gfe9f6fa] www.mythtv.org 2012-11-23T22:44:27.107205-05:00 gimli mythtranscode[387]: C thread_unknown mythcommandlineparser.cpp:2536 (ConfigureLogging) Qt version: compile: 4.7.3, runtime: 4.7.3 2012-11-23T22:44:27.107300-05:00 gimli mythtranscode[387]: N thread_unknown mythcommandlineparser.cpp:2538 (ConfigureLogging) Enabled verbose msgs: general 2012-11-23T22:44:27.107388-05:00 gimli mythtranscode[387]: N thread_unknown logging.cpp:1176 (logStart) Setting Log Level to LOG_INFO 2012-11-23T22:44:27.107477-05:00 gimli mythtranscode[387]: I thread_unknown logging.cpp:229 (FileLogger) Added logging to the console 2012-11-23T22:44:27.107564-05:00 gimli mythtranscode[387]: I thread_unknown logging.cpp:369 (SyslogLogger) Added syslogging to facility local6 2012-11-23T22:44:27.107677-05:00 gimli mythtranscode[387]: I thread_unknown logging.cpp:425 (DatabaseLogger) Added database logging to table logging 2012-11-23T22:44:27.107765-05:00 gimli mythtranscode[387]: N thread_unknown logging.cpp:1215 (logStart) Setting up SIGHUP handler 2012-11-23T22:44:27.107853-05:00 gimli mythtranscode[387]: N thread_unknown mythdirs.cpp:51 (InitializeMythDirs) Using runtime prefix = /usr 2012-11-23T22:44:27.107940-05:00 gimli mythtranscode[387]: N thread_unknown mythdirs.cpp:64 (InitializeMythDirs) Using configuration directory = /home/mythtv/.mythtv 2012-11-23T22:44:27.108071-05:00 gimli mythtranscode[387]: I CoreContext mythcorecontext.cpp:227 (Init) Assumed character encoding: en_US.utf-8 2012-11-23T22:44:27.108161-05:00 gimli mythtranscode[387]: N CoreContext mythcontext.cpp:477 (LoadDatabaseSettings) Empty LocalHostName. 2012-11-23T22:44:27.108250-05:00 gimli mythtranscode[387]: I CoreContext mythcontext.cpp:481 (LoadDatabaseSettings) Using localhost value of gimli 2012-11-23T22:44:27.209140-05:00 gimli mythtranscode[387]: E CoreContext configuration.cpp:112 (Save) Could not open settings file /home/mythtv/.mythtv/config.xml for writing 2012-11-23T22:44:27.209344-05:00 gimli mythtranscode[387]: N CoreContext mythcorecontext.cpp:1354 (InitLocale) Setting QT default locale to EN_US 2012-11-23T22:44:27.209438-05:00 gimli mythtranscode[387]: I CoreContext mythcorecontext.cpp:1387 (SaveLocaleDefaults) Current locale EN_US 2012-11-23T22:44:27.209529-05:00 gimli mythtranscode[387]: N CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) Reading locale defaults from /usr/share/mythtv//locales/en_us.xml 2012-11-23T22:44:27.209660-05:00 gimli mythtranscode[387]: I CoreContext mythtranslation.cpp:66 (load) Loading en_us translation for module mythfrontend 2012-11-23T22:44:27.209752-05:00 gimli mythtranscode[387]: N CoreContext main.cpp:537 (main) Transcoding HTTP Live Stream ID 4 2012-11-23T22:44:27.309973-05:00 gimli mythtranscode[387]: I CoreContext programinfo.cpp:2198 (QueryBasename) QueryBasename found no entry for 0 @ 2012-11-23T22:44:27 2012-11-23T22:44:27.410308-05:00 gimli mythtranscode[387]: I ProgramInfoUpdater mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.0.164:6543 (try 1 of 1) 2012-11-23T22:44:27.410496-05:00 gimli mythtranscode[387]: I ProgramInfoUpdater mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72 2012-11-23T22:44:27.410596-05:00 gimli mythtranscode[387]: I CoreContext avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x870a330, id(MPEG2VIDEO) type(Video) 2012-11-23T22:44:27.410713-05:00 gimli mythtranscode[387]: I CoreContext avformatdecoder.cpp:1960 (ScanStreams) AFD: codec AC3 has 6 channels 2012-11-23T22:44:27.410801-05:00 gimli mythtranscode[387]: I CoreContext avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x8733820, id(AC3) type(Audio) 2012-11-23T22:44:27.410889-05:00 gimli mythtranscode[387]: I CoreContext avformatdecoder.cpp:1960 (ScanStreams) AFD: codec AC3 has 2 channels 2012-11-23T22:44:27.410977-05:00 gimli mythtranscode[387]: I CoreContext avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x8735670, id(AC3) type(Audio) 2012-11-23T22:44:27.411079-05:00 gimli mythtranscode[387]: N CoreContext audioplayer.cpp:167 (ReinitAudio) AudioPlayer: Enabling Audio 2012-11-23T22:44:27.411208-05:00 gimli mythtranscode[387]: N CoreContext transcode.cpp:989 (TranscodeFile) Found video height of 1088. This is unusual and more than likely the video is actually 1080 so mythtranscode will treat it as such. 2012-11-23T22:44:27.611653-05:00 gimli mythtranscode[387]: I CoreContext transcode.cpp:1584 (TranscodeFile) Transcoding for HTTP Live Streaming 2012-11-23T22:44:28.512339-05:00 gimli mythtranscode[387]: I TranscodeFrameQueue avformatdecoder.cpp:4204 (ProcessAudioPacket) AFD: Audio stream changed 2012-11-23T22:44:28.512561-05:00 gimli mythtranscode[387]: I CoreContext transcode.cpp:2068 (TranscodeFile) mythtranscode: 0% Completed @ 0 fps. 2012-11-23T22:44:28.612825-05:00 gimli mythtranscode[387]: N CoreContext main.cpp:657 (main) Transcoding done 2012-11-23T22:44:28.613043-05:00 gimli mythtranscode[387]: I CoreContext mythcontext.cpp:1115 (~MythContext) Waiting for threads to exit.

[mattbatt]

Mythbackend log

2012-11-23T09:45:10.884907-05:00 gimli mythbackend[31679]: C thread_unknown mythcommandlineparser.cpp:2534 (ConfigureLogging) mythbackend version: fixes/0.25 [v0.25.3-7-gfe9f6fa] www.mythtv.org
2012-11-23T09:45:10.885726-05:00 gimli mythbackend[31679]: C thread_unknown mythcommandlineparser.cpp:2536 (ConfigureLogging) Qt version: compile: 4.7.3, runtime: 4.7.3
2012-11-23T09:45:10.885844-05:00 gimli mythbackend[31679]: N thread_unknown mythcommandlineparser.cpp:2538 (ConfigureLogging) Enabled verbose msgs:  general
2012-11-23T09:45:10.885934-05:00 gimli mythbackend[31679]: N thread_unknown logging.cpp:1176 (logStart) Setting Log Level to LOG_INFO
2012-11-23T09:45:10.886025-05:00 gimli mythbackend[31679]: I thread_unknown logging.cpp:229 (FileLogger) Added logging to the console
2012-11-23T09:45:10.886113-05:00 gimli mythbackend[31679]: I thread_unknown logging.cpp:369 (SyslogLogger) Added syslogging to facility local6
2012-11-23T09:45:10.886201-05:00 gimli mythbackend[31679]: I thread_unknown logging.cpp:425 (DatabaseLogger) Added database logging to table logging
2012-11-23T09:45:10.886288-05:00 gimli mythbackend[31679]: N thread_unknown logging.cpp:1215 (logStart) Setting up SIGHUP handler
2012-11-23T09:45:10.886376-05:00 gimli mythbackend[31679]: N thread_unknown mythdirs.cpp:51 (InitializeMythDirs) Using runtime prefix = /usr
2012-11-23T09:45:10.886463-05:00 gimli mythbackend[31679]: N thread_unknown mythdirs.cpp:64 (InitializeMythDirs) Using configuration directory = /home/mythtv/.mythtv
2012-11-23T09:45:10.886561-05:00 gimli mythbackend[31679]: I CoreContext mythcorecontext.cpp:227 (Init) Assumed character encoding: en_US.utf-8
2012-11-23T09:45:10.886686-05:00 gimli mythbackend[31679]: N CoreContext mythcontext.cpp:477 (LoadDatabaseSettings) Empty LocalHostName.
2012-11-23T09:45:10.886775-05:00 gimli mythbackend[31679]: I CoreContext mythcontext.cpp:481 (LoadDatabaseSettings) Using localhost value of gimli
2012-11-23T09:45:10.987015-05:00 gimli mythbackend[31679]: E CoreContext configuration.cpp:112 (Save) Could not open settings file /home/mythtv/.mythtv/config.xml for writing
2012-11-23T09:45:10.987205-05:00 gimli mythbackend[31679]: N CoreContext mythcorecontext.cpp:1354 (InitLocale) Setting QT default locale to EN_US
2012-11-23T09:45:10.987298-05:00 gimli mythbackend[31679]: I CoreContext mythcorecontext.cpp:1387 (SaveLocaleDefaults) Current locale EN_US
2012-11-23T09:45:10.987387-05:00 gimli mythbackend[31679]: N CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2012-11-23T09:45:10.987481-05:00 gimli mythbackend[31679]: I CoreContext schemawizard.cpp:117 (Compare) Current MythTV Schema Version (DBSchemaVer): 1299
2012-11-23T09:45:10.987568-05:00 gimli mythbackend[31679]: I CoreContext mythtranslation.cpp:66 (load) Loading en_us translation for module mythfrontend
2012-11-23T09:45:10.987738-05:00 gimli mythbackend[31679]: N CoreContext main_helpers.cpp:556 (run_backend) MythBackend: Starting up as the master server.
2012-11-23T09:45:11.990297-05:00 gimli mythbackend[31679]: I CoreContext programinfo.cpp:2052 (CheckProgramIDAuthorities) Found 1 distinct programid authorities
2012-11-23T09:45:11.990497-05:00 gimli mythbackend[31679]: I Scheduler mythdbcon.cpp:422 (getStaticCon) New static DB connectionSchedCon
2012-11-23T09:45:11.990597-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 127.0.0.1:6544
2012-11-23T09:45:11.990704-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 192.168.0.164:6544
2012-11-23T09:45:11.990791-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [0:0:0:0:0:0:0:1]:6544
2012-11-23T09:45:11.990878-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [fe80:0:0:0:208:54ff:fe6c:97c7%eth0]:6544
2012-11-23T09:45:12.994926-05:00 gimli mythbackend[31679]: I CoreContext main_helpers.cpp:626 (run_backend) Main::Registering HttpStatus Extension
2012-11-23T09:45:12.995446-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 127.0.0.1:6543
2012-11-23T09:45:12.995577-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 192.168.0.164:6543
2012-11-23T09:45:12.995696-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [0:0:0:0:0:0:0:1]:6543
2012-11-23T09:45:12.995784-05:00 gimli mythbackend[31679]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [fe80:0:0:0:208:54ff:fe6c:97c7%eth0]:6543
2012-11-23T09:45:12.995878-05:00 gimli mythbackend[31679]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T09:45:13.997062-05:00 gimli mythbackend[31679]: I CoreContext bonjourregister.cpp:103 (BonjourCallback) Bonjour: Service registration complete: name 'Mythbackend on gimli' type '_mythbackend-master._tcp.' domain: 'local.'
2012-11-23T09:45:15.097923-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id -1.
2012-11-23T09:45:17.902862-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 213 items in 2.7 = 0.82 match + 1.93 place
2012-11-23T09:45:18.003039-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2152 (HandleRunSchedulerStartup) Scheduler: AUTO-Startup assumed
2012-11-23T09:45:19.604455-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T09:45:19.604484-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T09:45:19.604492-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T09:45:19.604498-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 1)
2012-11-23T09:45:19.811078-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3061_20110613172936.mpg): GetPlaybackURL: '3061_20110613172936.mpg' should be local, but it can not be found.
2012-11-23T09:45:19.811291-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3091_20110613173152.mpg): GetPlaybackURL: '3091_20110613173152.mpg' should be local, but it can not be found.
2012-11-23T09:45:22.013311-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T09:45:23.014434-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
2012-11-23T09:45:23.014461-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T09:45:23.014468-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
2012-11-23T09:45:23.014475-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: gimli as a remote file transfer
2012-11-23T09:45:24.816703-05:00 gimli mythbackend[31679]: I SystemIOHandlerW system-unix.cpp:90 (run) Starting IO manager (write)
2012-11-23T09:45:24.816902-05:00 gimli mythbackend[31679]: I SystemIOHandlerR system-unix.cpp:90 (run) Starting IO manager (read)
2012-11-23T09:45:24.816997-05:00 gimli mythbackend[31679]: I SystemSignalManager system-unix.cpp:485 (run) Starting process signal handler
2012-11-23T09:45:24.817087-05:00 gimli mythbackend[31679]: I SystemManager system-unix.cpp:263 (run) Starting process manager
2012-11-23T09:45:25.318051-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T09:45:25.318333-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T09:45:25.318450-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T09:45:25.318557-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 1)
2012-11-23T09:46:31.038289-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T09:46:31.238597-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 155 MB for 10021 at 2012-11-01T15:49:00 => "The Ellen DeGeneres Show"
2012-11-23T09:46:31.238820-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 614 MB for 3022 at 2012-10-01T19:30:00 => M*A*S*H:"Heal Thyself"
2012-11-23T09:46:31.238913-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 645 MB for 3022 at 2012-09-30T19:30:00 => M*A*S*H:"The Foresight Saga"
2012-11-23T09:46:31.239003-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 595 MB for 3022 at 2012-09-30T19:00:00 => M*A*S*H:"Blood Brothers"
2012-11-23T09:46:31.239090-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 11858 MB for 3021 at 2012-09-26T21:00:00 => "Law & Order: Special Victims Unit":"Lost Reputation; Above Suspicion"
2012-11-23T09:46:31.239178-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 666 MB for 3022 at 2012-09-26T19:30:00 => M*A*S*H:"Life Time"
2012-11-23T09:46:31.239281-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 635 MB for 3022 at 2012-09-26T19:00:00 => M*A*S*H:"The Yalu Brick Road"
2012-11-23T09:46:31.239368-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 6210 MB for 3021 at 2012-09-25T20:00:00 => "The Voice":"Blind Auditions Continued"
2012-11-23T09:46:31.239455-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 668 MB for 3022 at 2012-09-25T19:30:00 => M*A*S*H:"Mr. & Mrs. Who"
2012-11-23T09:46:31.239543-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 642 MB for 3022 at 2012-09-25T19:00:00 => M*A*S*H:"Private Finance"
2012-11-23T09:46:31.239643-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 12305 MB for 3021 at 2012-09-24T20:00:00 => "The Voice":"Blind Auditions Continued"
2012-11-23T09:46:31.239731-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 618 MB for 3022 at 2012-09-21T19:00:00 => M*A*S*H:"Goodbye Radar"
2012-11-23T09:46:31.239818-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 5595 MB for 3021 at 2012-10-01T20:00:00 => "The Voice":"Blind Auditions Continued"
2012-11-23T09:46:31.239905-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 1636 MB for 3091 at 2012-10-01T22:26:00 => Castle:"Cloudy With a Chance of Murder"
2012-11-23T09:46:31.239992-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 122 MB for 10021 at 2012-11-01T15:46:00 => "The Ellen DeGeneres Show"
2012-11-23T09:46:31.240079-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 164 MB for 10021 at 2012-11-01T15:52:00 => "The Ellen DeGeneres Show"
2012-11-23T09:46:31.240166-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 2933 MB for 3351 at 2012-10-07T21:01:00 => "Family Guy":"All in the Nielsen Family"
2012-11-23T09:46:31.240253-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 601 MB for 3683 at 2012-10-07T11:00:00 => "This Old House"
2012-11-23T09:46:31.240353-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 5519 MB for 3021 at 2012-10-05T15:00:00 => "The Ellen DeGeneres Show"
2012-11-23T09:46:31.240440-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 1291 MB for 3061 at 2012-10-04T20:00:00 => "The Big Bang Theory":"The Decoupling Fluctuation"
2012-11-23T09:46:31.241026-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 632 MB for 3022 at 2012-10-04T19:00:00 => M*A*S*H:Dreams
2012-11-23T09:46:31.241154-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 9020 MB for 3681 at 2012-10-03T21:00:00 => "Presidential Debate"
2012-11-23T09:46:31.241242-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 625 MB for 3022 at 2012-10-03T19:00:00 => M*A*S*H:"Lend a Hand"
2012-11-23T09:46:31.241345-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 5431 MB for 3681 at 2012-10-03T10:00:00 => "Sesame Street":"Letter 'R' Mystery"
2012-11-23T09:46:31.241900-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 1198 MB for 3091 at 2012-10-01T22:01:00 => Castle:"Cloudy With a Chance of Murder"
2012-11-23T09:46:31.242004-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 647 MB for 3022 at 2012-09-20T19:30:00 => M*A*S*H:"Guerrilla My Dreams"
2012-11-23T09:46:31.242093-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 630 MB for 3022 at 2012-09-20T19:00:00 => M*A*S*H:"Are You Now, Margaret?"
2012-11-23T09:46:31.242180-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 3475 MB for 3651 at 2012-09-17T19:00:00 => "The Big Bang Theory":"The Middle Earth Paradigm"
2012-11-23T09:46:31.242295-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 618 MB for 3022 at 2012-08-12T19:30:00 => M*A*S*H:"Death Takes a Holiday"
2012-11-23T09:46:31.242383-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 641 MB for 3022 at 2012-08-12T19:00:00 => M*A*S*H:"Father's Day"
2012-11-23T09:46:31.242470-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 642 MB for 3022 at 2012-08-07T19:00:00 => M*A*S*H:"Hawk's Nightmare"
2012-11-23T09:46:31.242557-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 661 MB for 3022 at 2012-08-06T19:00:00 => M*A*S*H:"The Colonel's Horse"
2012-11-23T09:46:31.242667-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 650 MB for 3022 at 2012-08-05T19:30:00 => M*A*S*H:"Cementing Relationships"
2012-11-23T09:46:31.242755-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 653 MB for 3022 at 2012-08-05T19:00:00 => M*A*S*H:Letters
2012-11-23T09:46:31.242857-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 666 MB for 3022 at 2012-08-01T19:30:00 => M*A*S*H:"The Abduction of Margaret Houlihan"
2012-11-23T09:46:31.242945-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 630 MB for 3022 at 2012-08-01T19:00:00 => M*A*S*H:"The Nurses"
2012-11-23T09:46:31.243032-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 651 MB for 3022 at 2012-07-31T19:30:00 => M*A*S*H:"Lt. Radar O'Reilly"
2012-11-23T09:46:31.243144-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 646 MB for 3022 at 2012-07-31T19:00:00 => M*A*S*H:"Out of Sight, Out of Mind"
2012-11-23T09:46:31.243306-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 655 MB for 3022 at 2012-07-30T19:00:00 => M*A*S*H:"Bug Out"
2012-11-23T09:46:31.243319-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 648 MB for 3022 at 2012-08-15T19:00:00 => M*A*S*H:"Fade Out, Fade In"
2012-11-23T09:46:31.243326-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 632 MB for 3022 at 2012-08-20T19:00:00 => M*A*S*H:"The Light That Failed"
2012-11-23T09:46:31.243332-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 655 MB for 3022 at 2012-08-20T19:30:00 => M*A*S*H:"In Love and War"
2012-11-23T09:46:31.243338-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 3475 MB for 3651 at 2012-09-14T19:30:00 => "The Big Bang Theory":"The Cornhusker Vortex"
2012-11-23T09:46:31.243345-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 2361 MB for 3681 at 2012-09-13T12:00:00 => WordWorld:"Kite Flight; Hide and Seek"
2012-11-23T09:46:31.243351-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 3476 MB for 3651 at 2012-09-12T19:00:00 => "The Big Bang Theory":"The Fuzzy Boots Corollary"
2012-11-23T09:46:31.243358-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 3475 MB for 3651 at 2012-09-11T19:00:00 => "The Big Bang Theory":"The Big Bran Hypothesis"
2012-11-23T09:46:31.243364-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 647 MB for 3022 at 2012-08-31T19:30:00 => M*A*S*H:"Commander Pierce"
2012-11-23T09:46:31.243370-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 609 MB for 3022 at 2012-08-31T19:00:00 => M*A*S*H:"Major Topper"
2012-11-23T09:46:31.243377-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 629 MB for 3022 at 2012-08-30T19:00:00 => M*A*S*H:"Potter's Retirement"
2012-11-23T09:46:31.243782-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 651 MB for 3022 at 2012-08-27T19:30:00 => M*A*S*H:"Tea and Empathy"
2012-11-23T09:46:31.243797-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 680 MB for 3022 at 2012-08-24T19:30:00 => M*A*S*H:"The Smell of Music"
2012-11-23T09:46:31.243803-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 4709 MB for 3681 at 2012-08-22T21:00:00 => NOVA:"What Are Dreams?"
2012-11-23T09:46:31.243810-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 635 MB for 3022 at 2012-08-21T19:30:00 => M*A*S*H:Images
2012-11-23T09:46:31.243815-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 2843 MB for 10151 at 2012-11-18T23:30:00 => "The Red Green Show":"Gun Powder Shakers"
2012-11-23T09:46:31.243822-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 2794 MB for 10151 at 2012-11-15T23:30:00 => "The Red Green Show":"The Marine Show Project"
2012-11-23T09:46:31.243828-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 2931 MB for 10151 at 2012-11-11T23:30:00 => "The Red Green Show":"The Storm Damage Project"
2012-11-23T09:46:31.243834-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 2808 MB for 10151 at 2012-11-09T03:30:00 => "The Red Green Show"
2012-11-23T09:46:31.243840-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 2802 MB for 10151 at 2012-11-08T23:30:00 => "The Red Green Show":"The Electrical Project"
2012-11-23T09:46:31.344407-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121101154900.mpg): GetPlaybackURL: '10021_20121101154900.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.344693-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/10021_20121101154900.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.344820-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20121001193000.mpg): GetPlaybackURL: '3022_20121001193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.344912-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20121001193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.445250-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120930193000.mpg): GetPlaybackURL: '3022_20120930193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.445279-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120930193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.445286-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120930190000.mpg): GetPlaybackURL: '3022_20120930190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.445293-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120930190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.445300-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3021_20120926210000.mpg): GetPlaybackURL: '3021_20120926210000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.445307-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3021_20120926210000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.445314-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120926193000.mpg): GetPlaybackURL: '3022_20120926193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.445320-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120926193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.445327-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120926190000.mpg): GetPlaybackURL: '3022_20120926190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.445333-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120926190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.445340-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3021_20120925200000.mpg): GetPlaybackURL: '3021_20120925200000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.445827-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3021_20120925200000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.445842-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120925193000.mpg): GetPlaybackURL: '3022_20120925193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.445849-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120925193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.546916-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120925190000.mpg): GetPlaybackURL: '3022_20120925190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.547142-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120925190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.547237-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3021_20120924200000.mpg): GetPlaybackURL: '3021_20120924200000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.547327-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3021_20120924200000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.547465-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120921190000.mpg): GetPlaybackURL: '3022_20120921190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.547569-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120921190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.547680-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3021_20121001200000.mpg): GetPlaybackURL: '3021_20121001200000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.547768-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3021_20121001200000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.547855-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3091_20121001222600.mpg): GetPlaybackURL: '3091_20121001222600.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.547943-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3091_20121001222600.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.548030-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121101154600.mpg): GetPlaybackURL: '10021_20121101154600.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.548117-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/10021_20121101154600.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.548217-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(10021_20121101155200.mpg): GetPlaybackURL: '10021_20121101155200.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.548306-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/10021_20121101155200.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.548481-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3351_20121007210100.mpg): GetPlaybackURL: '3351_20121007210100.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.548577-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3351_20121007210100.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.548695-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3683_20121007110000.mpg): GetPlaybackURL: '3683_20121007110000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.548795-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3683_20121007110000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.548883-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3021_20121005150000.mpg): GetPlaybackURL: '3021_20121005150000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.548970-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3021_20121005150000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.549075-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3061_20121004200000.mpg): GetPlaybackURL: '3061_20121004200000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.549767-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3061_20121004200000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.549910-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20121004190000.mpg): GetPlaybackURL: '3022_20121004190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.550159-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20121004190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.550255-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3681_20121003210000.mpg): GetPlaybackURL: '3681_20121003210000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.550344-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3681_20121003210000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.550478-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20121003190000.mpg): GetPlaybackURL: '3022_20121003190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.550565-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20121003190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.550707-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3681_20121003100000.mpg): GetPlaybackURL: '3681_20121003100000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.550868-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3681_20121003100000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.651312-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3091_20121001220100.mpg): GetPlaybackURL: '3091_20121001220100.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.651715-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3091_20121001220100.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.651818-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120920193000.mpg): GetPlaybackURL: '3022_20120920193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.651919-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120920193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.652009-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120920190000.mpg): GetPlaybackURL: '3022_20120920190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.652133-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120920190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.652223-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3651_20120917190000.mpg): GetPlaybackURL: '3651_20120917190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.652348-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3651_20120917190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.652485-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120812193000.mpg): GetPlaybackURL: '3022_20120812193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.652649-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120812193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.652741-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120812190000.mpg): GetPlaybackURL: '3022_20120812190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.652850-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120812190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.652938-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120807190000.mpg): GetPlaybackURL: '3022_20120807190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.653184-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120807190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.653279-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120806190000.mpg): GetPlaybackURL: '3022_20120806190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.653368-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120806190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.653529-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120805193000.mpg): GetPlaybackURL: '3022_20120805193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.653638-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120805193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.653743-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120805190000.mpg): GetPlaybackURL: '3022_20120805190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.653973-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120805190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.654535-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120801193000.mpg): GetPlaybackURL: '3022_20120801193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.654645-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120801193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.654736-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120801190000.mpg): GetPlaybackURL: '3022_20120801190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.654824-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120801190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.755116-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120731193000.mpg): GetPlaybackURL: '3022_20120731193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.755313-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120731193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.755407-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120731190000.mpg): GetPlaybackURL: '3022_20120731190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.755564-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120731190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.755825-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120730190000.mpg): GetPlaybackURL: '3022_20120730190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.755920-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120730190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.756008-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120815190000.mpg): GetPlaybackURL: '3022_20120815190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.756558-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120815190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.756973-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120820190000.mpg): GetPlaybackURL: '3022_20120820190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.757144-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120820190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.758176-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120820193000.mpg): GetPlaybackURL: '3022_20120820193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.758354-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120820193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.758446-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3651_20120914193000.mpg): GetPlaybackURL: '3651_20120914193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.758535-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3651_20120914193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.758645-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3681_20120913120000.mpg): GetPlaybackURL: '3681_20120913120000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.758735-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3681_20120913120000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859034-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3651_20120912190000.mpg): GetPlaybackURL: '3651_20120912190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859061-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3651_20120912190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859068-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3651_20120911190000.mpg): GetPlaybackURL: '3651_20120911190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859075-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3651_20120911190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859082-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120831193000.mpg): GetPlaybackURL: '3022_20120831193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859089-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120831193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859096-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120831190000.mpg): GetPlaybackURL: '3022_20120831190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859102-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120831190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859109-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120830190000.mpg): GetPlaybackURL: '3022_20120830190000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859115-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120830190000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859122-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120827193000.mpg): GetPlaybackURL: '3022_20120827193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859589-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120827193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859629-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120824193000.mpg): GetPlaybackURL: '3022_20120824193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859635-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120824193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859642-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3681_20120822210000.mpg): GetPlaybackURL: '3681_20120822210000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859648-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3681_20120822210000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:31.859654-05:00 gimli mythbackend[31679]: E CoreContext programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3022_20120821193000.mpg): GetPlaybackURL: '3022_20120821193000.mpg' should be local, but it can not be found.
2012-11-23T09:46:31.859660-05:00 gimli mythbackend[31679]: E CoreContext mainserver.cpp:2582 (DoHandleDeleteRecording) ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/gimli/3022_20120821193000.mpg. File doesn't exist.  Database metadata will not be removed.
2012-11-23T09:46:39.266971-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T09:46:41.470363-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 213 items in 2.3 = 0.00 match + 2.26 place
2012-11-23T09:46:43.473174-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T09:46:45.378347-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 213 items in 1.9 = 0.00 match + 1.93 place
2012-11-23T09:46:47.681324-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T09:46:49.686566-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 213 items in 1.9 = 0.00 match + 1.93 place
2012-11-23T09:46:52.089117-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T09:46:53.997769-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 213 items in 1.9 = 0.00 match + 1.92 place
2012-11-23T09:46:56.501049-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T09:46:59.205340-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 213 items in 2.8 = 0.00 match + 2.76 place
2012-11-23T09:50:04.024122-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T09:50:04.024362-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T09:50:04.225317-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3091_20110613173152.mpg): GetPlaybackURL: '3091_20110613173152.mpg' should be local, but it can not be found.
2012-11-23T09:50:04.225346-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3061_20110613172936.mpg): GetPlaybackURL: '3061_20110613172936.mpg' should be local, but it can not be found.
2012-11-23T09:50:28.058422-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T09:55:35.019699-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T09:59:54.423214-05:00 gimli mythbackend[31679]: I TVRecEvent tv_rec.cpp:1544 (HandlePendingRecordings) TVRec(68): ASK_RECORDING 68 4 0 0
2012-11-23T09:59:54.423243-05:00 gimli mythbackend[31679]: I TVRecEvent tv_rec.cpp:1544 (HandlePendingRecordings) TVRec(67): ASK_RECORDING 67 4 0 0
2012-11-23T10:00:00.333043-05:00 gimli mythbackend[31679]: I TVRecEvent tv_rec.cpp:1030 (HandleStateChange) TVRec(67): Changing from None to RecordingOnly
2012-11-23T10:00:00.333244-05:00 gimli mythbackend[31679]: I TVRecEvent tv_rec.cpp:3503 (TuningCheckForHWChange) TVRec(67): HW Tuner: 67->67
2012-11-23T10:00:00.434675-05:00 gimli mythbackend[31679]: N Scheduler autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2012-11-23T10:00:00.434955-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2514 (HandleRecordingStatusChange) Tuning recording: "Sesame Street":"Practice Makes Proud": channel 10241 on cardid 67, sourceid 10
2012-11-23T10:00:00.935960-05:00 gimli mythbackend[31679]: I CoreContext scheduler.cpp:638 (UpdateRecStatus) Updating status for "Sesame Street":"Practice Makes Proud" on cardid 67 (Tuning => Recording)
2012-11-23T10:00:01.036314-05:00 gimli mythbackend[31679]: I TVRecEvent tv_rec.cpp:3997 (TuningNewRecorder) TVRec(67): rec->GetPathname(): '/myth/tv/10241_20121123100000.mpg'
2012-11-23T10:00:01.036520-05:00 gimli mythbackend[31679]: E TVRecEvent recorderbase.cpp:164 (SetStrOption) RecBase(67:192.168.0.163-0): SetStrOption(...recordingtype): Option not in profile.
2012-11-23T10:00:01.440844-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T10:00:06.075995-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 213 items in 4.6 = 0.00 match + 4.57 place
2012-11-23T10:00:41.019570-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:02:30.473643-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2012-11-23T10:05:42.039779-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:10:44.069690-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:15:45.077048-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:16:30.447108-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2012-11-23T10:20:49.101161-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:25:49.022088-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:30:30.422989-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2012-11-23T10:30:52.048302-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:35:58.100947-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:41:02.125154-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:44:30.485778-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2012-11-23T10:46:06.103451-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:50:03.409161-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T10:50:03.409194-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T10:50:03.811141-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3091_20110613173152.mpg): GetPlaybackURL: '3091_20110613173152.mpg' should be local, but it can not be found.
2012-11-23T10:50:03.811342-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3061_20110613172936.mpg): GetPlaybackURL: '3061_20110613172936.mpg' should be local, but it can not be found.
2012-11-23T10:51:10.109520-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:56:14.106063-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T10:58:30.481765-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2012-11-23T11:00:00.095582-05:00 gimli mythbackend[31679]: I TVRecEvent tv_rec.cpp:1030 (HandleStateChange) TVRec(67): Changing from RecordingOnly to None
2012-11-23T11:00:00.396137-05:00 gimli mythbackend[31679]: I PreviewGeneratorQueue mythdbcon.cpp:395 (PurgeIdleConnections) New DB connection, total: 14
2012-11-23T11:00:00.396793-05:00 gimli mythbackend[31679]: I CoreContext scheduler.cpp:638 (UpdateRecStatus) Updating status for "Sesame Street":"Practice Makes Proud" on cardid 67 (Recording => Recorded)
2012-11-23T11:00:00.497255-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T11:00:00.680891-05:00 gimli mythbackend[31679]: I TVRecEvent recordinginfo.cpp:1113 (FinishedRecording) Finished recording Sesame Street "Practice Makes Proud": channel 10241
2012-11-23T11:00:06.192226-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 211 items in 5.7 = 0.00 match + 5.66 place
2012-11-23T11:00:07.694945-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T11:00:07.695140-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T11:00:07.695239-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T11:00:07.695330-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 1)
2012-11-23T11:00:22.313035-05:00 gimli mythbackend[31679]: I Commflag_384 jobqueue.cpp:2276 (DoFlagCommercialsThread) JobQueue: Commercial Detection Starting for "Sesame Street":"Practice Makes Proud" recorded from channel 10241 at 2012-11-23T10:00:00
2012-11-23T11:00:22.716719-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T11:00:22.716906-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T11:00:22.717007-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T11:00:22.717114-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 1)
2012-11-23T11:01:19.139719-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:06:23.097579-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:11:26.119915-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:12:30.490685-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T11:12:30.591046-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:739 (ExpireEpisodesOverMax) Expiring 6706 MBytes for 10681 at Mon Oct 29 10:00:00 2012 => Sesame Street.  Too many episodes, we only want to keep 14.
2012-11-23T11:12:31.892508-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T11:12:37.718294-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 211 items in 5.8 = 0.00 match + 5.83 place
2012-11-23T11:12:39.523710-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T11:12:42.038227-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 211 items in 2.5 = 0.00 match + 2.48 place
2012-11-23T11:16:32.153231-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:17:09.732110-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T11:17:09.732334-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T11:17:09.732459-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T11:17:09.732552-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 1)
2012-11-23T11:21:32.121783-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:26:32.151133-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:27:30.515003-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T11:29:40.365843-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T11:29:42.471916-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 211 items in 2.1 = 0.00 match + 2.12 place
2012-11-23T11:31:37.163507-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:36:43.116951-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:41:43.149313-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:42:30.485565-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T11:42:30.485787-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 18483 MB for 10021 at 2012-11-22T09:00:00 => "Macy's Thanksgiving Day Parade"
2012-11-23T11:42:37.891560-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2034 (HandleReschedule) Reschedule requested for id 0.
2012-11-23T11:42:39.192703-05:00 gimli mythbackend[31679]: I Scheduler scheduler.cpp:2094 (HandleReschedule) Scheduled 210 items in 1.3 = 0.00 match + 1.27 place
2012-11-23T11:46:44.086311-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:50:03.039523-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
2012-11-23T11:50:03.039551-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T11:50:03.343692-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3091_20110613173152.mpg): GetPlaybackURL: '3091_20110613173152.mpg' should be local, but it can not be found.
2012-11-23T11:50:03.343722-05:00 gimli mythbackend[31679]: E ProcessRequest programinfo.cpp:2278 (GetPlaybackURL) ProgramInfo(3061_20110613172936.mpg): GetPlaybackURL: '3061_20110613172936.mpg' should be local, but it can not be found.
2012-11-23T11:51:45.131127-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:56:47.176224-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T11:57:30.514680-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T12:01:51.120883-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:06:58.160899-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:09:57.503231-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
2012-11-23T12:09:57.503434-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: gimli as a client (events: 0)
2012-11-23T12:09:57.503544-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
2012-11-23T12:09:57.503662-05:00 gimli mythbackend[31679]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: gimli as a remote file transfer
2012-11-23T12:12:03.139110-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:12:30.467625-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T12:17:04.128664-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:22:07.123765-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:27:08.159759-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:27:30.483409-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T12:32:10.155999-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:37:10.177701-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:42:16.113352-05:00 gimli mythbackend[31679]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
2012-11-23T12:42:30.528858-05:00 gimli mythbackend[31679]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
2012-11-23T12:47:21.143700-05:00 gimli mythbacken

[billmeek]

Looking at the mythbackend.log, I doesn't look like upnp logging is on. That's the one where you type: mythbackend --setverbose upnp (on a running backend.)

But next time your get a chance, please use some more options:

 mythbackend --setverbose upnp,system,file

Then try to play a recording. When it fails (and we expect it will,) then turn the extra logging off, otherwise you'll get a lot of stuff filling up your logs. Here's the message:

mythbackend --setverbose general

You may find it easier to pastebinit your logs and just put the address that pastebinit returns here. You can see an example near the bottom of the 1st post in this issue: https://github.com/MythTV-Clients/MythTV-Android-Frontend/issues/82

If you don't have pastebinit, ubuntu uses: sudo apt-get install pastebinit. I don't know what LinHES uses. Then to use it, just type pastebinit filename. It will return a URL.

As to your system's capability, I'm not aware of any tools for testing it. I've got this: AMD Athlon(tm) 64 X2 Dual Core Processor 6000+. If you'd like to see what your closest 8,737 friends are using, take a look at this:

http://smolt.mythtv.org/static/stats/stats.html

Click on the CPU tab.

[mattbatt]

here is more info than you wanted. Using drop box since I already have that. https://www.dropbox.com/sh/ltx15fu6iv5w74z/yBZw6RJ1QK

[billmeek]

I think its fair to say the problem is now cut in half. The problem is that mythtranscode barely gets started and aborts. On your system, the mythtranscode log lines are like this:

mythtranscode: 4% Completed @ 2.96814 fps.

Here's what mine look like. Much faster (frames per second) and it actually completes:

...
mythtranscode: 1% Completed @ 67.139 fps
...
mythtranscode: 98% Completed @ 67.139 fps
...

I just made a lot of notes, trying to put things together, suggestions at the bottom of the post.

Transcode gets started with stream ID 11: 2012-11-24T00:12:57.... has started! * command=/usr/bin/mythtranscode --hls --hlsstreamid 11 --verbose general,upnp,system,file --loglevel info --quiet --syslog local6, timeout=0

There should have been another message in the backend log when mythtranscode finished. It would likely have a non zero return code.

Then, for 4 minutes Android makes 47 attempts to get that stream (and fails): ProcessRequest: GetLiveStream : GET /Content/GetLiveStream?Id=11

mythtranscode plods along, the threaded file writer is saving the stream to TFW(/myth0/streaming/10241_20121117140000.mpg.960x544_1000kV_128kA.ao_32kA.000008.ts

No idea if this is a problem, and I commented on this before, fuppes SEGVs every second: fuppes[23286]: segfault at 0 ip b7724d41 sp bfc7bb10 error 4 in libfuppes.so.0.0.0[b76fd000+107000]

Suggestions:

I think its OK, but double check the permissions on: /myth0/streaming. It should be writable by user mythtv.

You may be right about the system's capabilities, run the top program, look for line(s) like: Cpu0 : 73.6%us, 2.4%sy, 0.0%ni, 22.4%id, 1.4%wa, 0.0%hi, 0.3%si, 0.0%st Cpu1 : 73.2%us, 2.3%sy, 0.0%ni, 24.5%id, 0.0%wa, 0.0%hi, 0.0%si, 0.0%st

The above is what mine looks like while streaming (and transcoding.) So, I've got about 23% idle. I found this with Google: http://www.linhes.org/projects/linhes/wiki/Required_Hardware

I'd look into the fuppes issue with the LinHES group/form... That's got to be eating up some realtime.

Not likely a cause of this problem, but I'd make this file writable, unless you have some reason for not doing it:

Error: Could not open settings file /home/mythtv/.mythtv/config.xml for writing

Don't forget to restore logging to general!

Good luck, please report back.

[mattbatt]

Thanks for your help.

[billmeek]

FYI, I was just looking at the gimli_su.log and much like the fuppes SEGVs, the following are printing every second. That begs the question, what is mythtv doing once per second?

012-11-24T00:23:41.126627-05:00 gimli su: pam_unix(su:session): session closed for user mythtv
2012-11-24T00:23:42.132226-05:00 gimli su: pam_unix(su:session): session opened for user mythtv by (uid=0)

With 0.25, MythTV has a built-in UPnP server, I don't know if that conflicts with fuppes.

If you haven't already, I'd boot that backend to see if it stops. Or maybe 1st, stop mythbackend and look see if the SEGVs and su_logs stop. Or, is fuppes required for your system to run (it isn't running on my machine,) so perhaps you can temporarily shut it off.

Here's what my CPU's look like:

  grep "cpu MHz" /proc/cpuinfo 
   cpu MHz      : 1800.000
   cpu MHz      : 1800.000