Opened 8 years ago

Closed 6 years ago

Last modified 6 years ago

#11051 closed Bug Report - General (fixed)

Asking for recording details takes ~5 minutes

Reported by: numkem@… Owned by: Rob Smith
Priority: minor Milestone: 0.28
Component: Plugin - MythWeb Version: Master Head
Severity: medium Keywords:
Cc: Ticket locked: no

Description

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)

Change History (18)

comment:1 Changed 8 years ago by numkem@…

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

comment:2 Changed 8 years ago by numkem@…

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

comment:3 Changed 8 years ago by Kenni Lund [kenni a kelu dot dk]

Milestone: 0.26unknown
Version: UnspecifiedMaster Head

comment:4 Changed 7 years ago by Rob Smith

Status: newinfoneeded_new

Is this still an issue?

comment:5 Changed 7 years ago by paulh

Resolution: Unverified
Status: infoneeded_newclosed

No reply in 4 months

comment:6 Changed 7 years ago by JYA

Resolution: Unverified
Status: closednew

seeing the same thing here

comment:7 Changed 7 years ago by 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.

comment:8 Changed 7 years ago by JYA

how do you enable or disable the flash player?

comment:9 Changed 7 years ago by Raymond Wagner

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

comment:10 Changed 7 years ago by 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.

comment:11 Changed 7 years ago by 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.

comment:12 Changed 6 years ago by 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.

comment:13 Changed 6 years ago by JYA

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

comment:14 Changed 6 years ago by 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.

comment:15 Changed 6 years ago by Jean-Yves Avenard <jyavenard@…>

Resolution: fixed
Status: newclosed

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)

comment:16 Changed 6 years ago by 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

comment:17 Changed 6 years ago by JYA

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

comment:18 Changed 6 years ago by Raymond Wagner

Milestone: unknown0.28
Note: See TracTickets for help on using tickets.