[mythtv-users] slow mythweb?

Sat Apr 26 15:56:29 UTC 2008

Udo van den Heuvel wrote:
> Udo van den Heuvel wrote:
>> How can I find out why I get no mpeg files on disk, although MythTV says
>> it does write mpeg to disk?
>> Where in the 0.21-fixes source can I place some debug prints?
> 
> Also: there must be something wrong.
> Either it is stupid me, or it is something by MytHTV or both of us.
> So why is there no error/warning of some sorts, somewhere?
> In the log, in mythweb or on the frontend?
> 
> The machine just runs and does as everything is OK but nothing is
> recording to the harddisk.

See some log:

2008-04-26 17:50:02.684 TVRec(4): StartRecording(Socutera: Wings of Hope)
2008-04-26 17:50:02.890 TVRec(4): Checking input group recorders - begin
2008-04-26 17:50:02.907 TVRec(4): Checking input group recorders - done
2008-04-26 17:50:03.014 TVRec(4): StartedRecording(0x8da0bd8)
fn(/Myth/LiveTV/1_20080426175000.mpg)
2008-04-26 17:50:03.020 TVRec(4): ClearFlags(CancelNextRecording,) ->
RunMainLoop,
2008-04-26 17:50:03.021 TVRec(4): Changing from None to RecordingOnly
2008-04-26 17:50:03.028 TVRec(4):
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
2008-04-26 17:50:03.029 TVRec(4): Request: Program(yes) channel()
input() flags(Recording,)
2008-04-26 17:50:03.033 TVRec(4): HW Tuner: 4->4
2008-04-26 17:50:03.034 TVRec(4): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,
2008-04-26 17:50:03.035 TVRec(4): No recorder yet, calling TuningFrequency
2008-04-26 17:50:03.068 TVRec(4): Starting Signal Monitor
2008-04-26 17:50:03.070 TVRec(4): SetupSignalMonitor(1, 0)
2008-04-26 17:50:03.074 TVRec(4): Signal monitor successfully created
2008-04-26 17:50:03.076 TVRec(4): Setting up table monitoring.
2008-04-26 17:50:03.086 Using profile 'Live TV' to record
2008-04-26 17:50:03.087 TVRec(4): DVB service_id 1101 on net_id 8720
tsid 2213
2008-04-26 17:50:03.088 TVRec(4): Successfully set up DVB table monitoring.
2008-04-26 17:50:03.090 TVRec(4): SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning,
2008-04-26 17:50:03.091 TVRec(4): ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning,
2008-04-26 17:50:03.092 TVRec(4): SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2008-04-26 17:50:03.093 DVBSH(0): AddListener(0x8e0cf90) -- begin
2008-04-26 17:50:03.101 DVBSH(0): AddListener(0x8e0cf90) -- locked
2008-04-26 17:50:03.102 DVBSH(0): AddListener(0x8e0cf90) -- end
2008-04-26 17:50:03.093 TVRec(4): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,
2008-04-26 17:50:03.110 TVRec(4): SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
2008-04-26 17:50:03.135 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 4 min
2008-04-26 17:50:03.142 Started recording: Socutera: Wings of Hope:
channel 1 on cardid 4, sourceid 1
2008-04-26 17:50:03.149 CreatePATSingleProgram()
2008-04-26 17:50:03.159 PAT in input stream
2008-04-26 17:50:03.160 Program Association Table
 PSIP tableID(0x0) length(65) extension(0x8a6)
      version(21) current(1) section(0) last_section(0)
         tsid: 2214
 programCount: 14
  program number     0 has PID 0x  10   data  0x 0 0x 0 0xe0 0x10
  program number  1101 has PID 0x1b62   data  0x 4 0x4d 0xfb 0x62
(...)
  program number  1119 has PID 0x1c16   data  0x 4 0x5f 0xfc 0x16

2008-04-26 17:50:03.161 desired_program(1101) pid(0x1b62)
2008-04-26 17:50:03.163 pmt_pid(0x1b62)
2008-04-26 17:50:03.164 PAT for output stream
2008-04-26 17:50:03.165 Program Association Table
 PSIP tableID(0x0) length(13) extension(0x8a6)
      version(21) current(1) section(0) last_section(0)
         tsid: 2214
 programCount: 1
  program number     1 has PID 0x1b62   data  0x 0 0x 1 0xfb 0x62

2008-04-26 17:50:03.221 CreatePMTSingleProgram()
2008-04-26 17:50:03.222 PMT in input stream
2008-04-26 17:50:03.223 Program Map Table ver(3) pid(0x1b62) pnum(1101)
len(35)

 Stream #0 pid(0x1b63) type(video-mpeg2  0x2)
 Stream #1 pid(0x1b64) type(audio-mp1-layer[1,2,3]  0x3)
 Stream #2 pid(0x1b65) type(private-data  0x6)
  Teletext Descriptor (0x56) length(5)

2008-04-26 17:50:03.226 PMT for output stream
2008-04-26 17:50:03.227 Program Map Table ver(3) pid(0x1b62) pnum(1) len(35)

 Stream #0 pid(0x1b63) type(video-mpeg2  0x2)
 Stream #1 pid(0x1b64) type(audio-mp1-layer[1,2,3]  0x3)
 Stream #2 pid(0x1b65) type(private-data  0x6)
  Teletext Descriptor (0x56) length(5)

2008-04-26 17:50:29.324 TVRec(5): Deleting stale pending recording 4
'Socutera: Wings of Hope'
2008-04-26 17:50:29.339 TVRec(6): Deleting stale pending recording 4
'Socutera: Wings of Hope'
2008-04-26 17:50:29.448 TVRec(7): Deleting stale pending recording 4
'Socutera: Wings of Hope'
2008-04-26 17:50:29.513 TVRec(2): Deleting stale pending recording 4
'Socutera: Wings of Hope'
2008-04-26 17:50:29.970 TVRec(3): Deleting stale pending recording 4
'Socutera: Wings of Hope'
2008-04-26 17:50:30.058 TVRec(1): Deleting stale pending recording 4
'Socutera: Wings of Hope'
2008-04-26 17:52:00.072 TVRec(1): ClearFlags(FinishRecording,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
2008-04-26 17:52:00.074 TVRec(1): Changing from RecordingOnly to None
2008-04-26 17:52:00.075 TVRec(1):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,
2008-04-26 17:52:00.076 TVRec(1): Request: Program(no) channel() input()
flags(CloseRec,KillRingBuffer,)
2008-04-26 17:52:00.077 TVRec(1): TeardownSignalMonitor() -- begin
2008-04-26 17:52:00.125 DVBSH(0): RemoveListener(0x8e63db0) -- begin
2008-04-26 17:52:00.130 DVBSH(0): RemoveListener(0x8e63db0) -- locked
2008-04-26 17:52:00.131 DVBSH(0): RemoveListener(0x8e63db0) -- end
2008-04-26 17:52:00.131 TVRec(1): TeardownSignalMonitor() -- end
2008-04-26 17:52:00.133 TVRec(1): ClearFlags(SignalMonitorRunning,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,
2008-04-26 17:52:00.144 TVRec(1): ClearFlags(WaitingForSignal,) ->
RunMainLoop,NeedToStartRecorder,
2008-04-26 17:52:00.154 TVRec(1): FinishedRecording(NOS: EK Voetbal) in
recgroup: Default
2008-04-26 17:52:00.159 Reschedule requested for id 0.
2008-04-26 17:52:00.161 Finished recording NOS: EK Voetbal "5": channel 1
2008-04-26 17:52:00.188 TVRec(1): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,
2008-04-26 17:52:01.956 Scheduled 65 items in 1.8 = 0.01 match + 1.78 place
2008-04-26 17:52:23.889 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 5 min
(cut)

Maybe an expert can see some strange stuff here. I can't.
I just see a program explaining that it is starting to record one
program and slightly later finishing another.
Yet some of these logged `statements` are not in line with the reality
of no files being written. I.e.: an issue with the software.
Maybe it's my fault. But how I find out?