Asking for recording details takes ~5 minutes

[numkem@…]

After an upgrade to 0.26 as of today (mythbuntu build from master 3b81805), going to a recording details in mythweb takes more than 5 minutes.

Based on what I can see, did something changed in the preview generation?

Here is what I see in the logs :

Aug 27 10:36:33 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) MainServer::ANN Monitor
Aug 27 10:36:33 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1364 (HandleAnnounce) adding: mythbox as a client (events: 2)
Aug 27 10:36:33 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) MainServer::ANN Monitor
Aug 27 10:36:33 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1364 (HandleAnnounce) adding: mythbox as a client (events: 2)
Aug 27 10:38:34 mythbox mythlogserver: mythbackend[11466]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
Aug 27 10:42:34 mythbox mythlogserver: mythbackend[11466]: I MetadataDownload metadatadownload.cpp:222 (runGrabber) Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -D 111051 2 32
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: C thread_unknown mythcommandlineparser.cpp:2545 (ConfigureLogging) mythpreviewgen version: master [v0.26-rc-8-g3b81805] www.mythtv.org
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: C thread_unknown mythcommandlineparser.cpp:2547 (ConfigureLogging) Qt version: compile: 4.8.1, runtime: 4.8.1
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: N thread_unknown mythcommandlineparser.cpp:2549 (ConfigureLogging) Enabled verbose msgs:  general
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: N thread_unknown logging.cpp:837 (logStart) Setting Log Level to LOG_INFO
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I thread_unknown signalhandling.cpp:175 (SetHandlerPrivate) Setup Interrupt handler
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I thread_unknown signalhandling.cpp:175 (SetHandlerPrivate) Setup Terminated handler
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I thread_unknown signalhandling.cpp:175 (SetHandlerPrivate) Setup Segmentation fault handler
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I thread_unknown signalhandling.cpp:175 (SetHandlerPrivate) Setup Aborted handler
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I thread_unknown signalhandling.cpp:175 (SetHandlerPrivate) Setup Bus error handler
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I thread_unknown signalhandling.cpp:175 (SetHandlerPrivate) Setup Floating point exception handler
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I thread_unknown signalhandling.cpp:175 (SetHandlerPrivate) Setup Illegal instruction handler
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: N thread_unknown mythdirs.cpp:55 (InitializeMythDirs) Using runtime prefix = /usr
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: N thread_unknown mythdirs.cpp:68 (InitializeMythDirs) Using configuration directory = /home/mythtv/.mythtv
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext mythcorecontext.cpp:231 (Init) Assumed character encoding: en_CA.UTF-8
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: N CoreContext mythcontext.cpp:493 (LoadDatabaseSettings) Empty LocalHostName.
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext mythcontext.cpp:501 (LoadDatabaseSettings) Using localhost value of mythbox
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I Logger logging.cpp:306 (run) Added logging to the console
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: N CoreContext mythcorecontext.cpp:1283 (InitLocale) Setting QT default locale to en_CA
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext mythcorecontext.cpp:1316 (SaveLocaleDefaults) Current locale en_CA
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: N CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) Reading locale defaults from /usr/share/mythtv//locales/en_ca.xml
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext previewgenerator.cpp:657 (LocalPreviewRun) Preview at calculated offset (605 seconds)
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I ProgramInfoUpdater mythcorecontext.cpp:375 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 10.0.1.11:6543 (try 1 of 1)
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I ProgramInfoUpdater mythcorecontext.cpp:1191 (CheckProtoVersion) Using protocol version 75
Aug 27 10:42:34 mythbox mythlogserver: mythlogserver[9829]: I LogForward loggingserver.cpp:1310 (forwardMessage) New Client: 00a75adbe9f15b47ee943172290c0d5863 (#3)
Aug 27 10:42:34 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) MainServer::ANN Monitor
Aug 27 10:42:34 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1364 (HandleAnnounce) adding: mythbox as a client (events: 0)
Aug 27 10:42:34 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) MainServer::ANN Monitor
Aug 27 10:42:34 mythbox mythlogserver: mythbackend[11466]: I ProcessRequest mainserver.cpp:1364 (HandleAnnounce) adding: mythbox as a client (events: 1)
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I Logger logging.cpp:447 (initialTimeout) Added logging to mythlogserver at TCP:35327
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext avformatdecoder.cpp:2141 (ScanStreams) AFD: Opened codec 0x1167500, id(H264) type(Video)
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext avformatdecoder.cpp:1999 (ScanStreams) AFD: codec AC3 has 2 channels
Aug 27 10:42:34 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext avformatdecoder.cpp:2141 (ScanStreams) AFD: Opened codec 0x1167e60, id(AC3) type(Audio)
Aug 27 10:42:35 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext previewgenerator.cpp:824 (GetScreenGrab) Preview: Grabbed preview '/mnt/video0/4074_20120827000000.mpg' 1920x1088@605s
Aug 27 10:42:35 mythbox mythlogserver: mythpreviewgen[12346]: I CoreContext mythcontext.cpp:1169 (~MythContext) Waiting for threads to exit.
Aug 27 10:42:37 mythbox mythlogserver: mythbackend[11466]: I MetadataDownload metadatadownload.cpp:144 (run) Returning Metadata Results: Pawn Stars 2 32
Aug 27 10:42:43 mythbox mythlogserver: mythlogserver[9829]: I LogForward loggingserver.cpp:1204 (expireClients) Expiring client 00a75adbe9f15b47ee943172290c0d5863 (#2)

[numkem@…]

Problem was fixed by just restarting the backend. Can't explain why it worked that time and not the other times I tried.

[numkem@…]

This problem is still present on revision 9ed48cb, restarting the backend doesn't fix the problem anymore.

[Rob Smith]

Is this still an issue?

[paulh]

No reply in 4 months

[JYA]

seeing the same thing here

[Raymond Wagner]

I recall there being some kind of timeout in there if you have the flash player enabled, but the web server cannot directly access your recordings.

[JYA]

how do you enable or disable the flash player?

[Raymond Wagner]

Settings --> MythWeb --> Video Playback --> Enable Video Playback

[JYA]

enable video playback here isn't enabled. (didn't even know about that option)

when going into the recorded programs option, it shows all the listings and create the preview images pretty quickly (like today, I had about 20 missing, in about 5s you could see them all loading)

however, clicking on a recording to see the details: and you're on for a 2-3 minutes wait.

can't explain why. no permission problem either.

[JYA]

note that this includes not just for listing past recordings.

Going into the listing tab, selecting an entry and creating a recording: So you check Record only this showing Click on Update Recording Settings

the page refresh will take about 2 minutes. If I interrupt the load, go back into listing , re-select the listing I just setup to record: it now opens immediately.

[lists@…]

I'm now using 0.27 and finding the same problem with loading the Recorded Program Details page. I tried disabling the flash player, as suggested earlier, but that doesn't seem to have made any noticeable difference.

I'm also experiencing slow page refreshes with Update Recording Settings.

[JYA]

I've identified the culprit on my system. it's in the PHP bindings, in MythFrontend.php in the function findFrontends();

[lists@…]

I can confirm that removing the findFrontends() function from my 0.27 system also resolves the problem with the Recorded Program Details page.

Thanks Jean-Yves for finding that.

[Jean-Yves Avenard <jyavenard@…>]

In ae96db7c5de40cc28b6b333066b2b111b2a6859d/mythweb:

Flush content so page display quicker

MythFrontend::findFrontends() may take a very long time to return. So flush any content.

Partially Fixes #11051

(cherry picked from commit a5684e79f2e1b042e0948d691969a55a2997401e)

[Jean-Yves Avenard <jyavenard@…>]

In a5684e79f2e1b042e0948d691969a55a2997401e/mythweb:

Flush content so page display quicker

MythFrontend::findFrontends() may take a very long time to return. So flush any content.

Partially Fixes #11051

[JYA]

#11853 marked as dup (same as comment #11 here)