Opened 15 years ago
Closed 14 years ago
#6559 closed defect (invalid)
Doesn't record even though it says so
Reported by: | Owned by: | danielk | |
---|---|---|---|
Priority: | major | Milestone: | unknown |
Component: | MythTV - Recording | Version: | unknown |
Severity: | medium | Keywords: | |
Cc: | Ticket locked: | no |
Description
Sometimes the recording is supposed to start, and the logs say that this indeed happens, but nothing is recorded actually. This is how the backend log with --verbose record,file looks like:
2009-05-17 03:27:01.644 TVRec(3): StartRecording?(Das Appartement) 2009-05-17 03:27:01.877 ProgramInfo?: StartedRecording?: Recording to '/var/lib/my thtv/recordings/2116_20090517032700.mpg' 2009-05-17 03:27:01.970 TVRec(3): StartedRecording?(0x8d3b138) fn(/var/lib/mythtv /recordings/2116_20090517032700.mpg) 2009-05-17 03:27:01.971 TVRec(3): ClearFlags?(CancelNextRecording?,) -> RunMainLoo? p,RingBufferReady?, 2009-05-17 03:27:01.971 TVRec(3): Changing from None to RecordingOnly? 2009-05-17 03:27:01.978 TVRec(3): ClearFlags?(FrontendReady?,CancelNextRecording?,)
2009-05-17 03:27:01.978 TVRec(3): Request: Program(yes) channel() input() flags( Recording,) 2009-05-17 03:27:01.979 TVRec(3): HW Tuner: 3->3 2009-05-17 03:27:01.980 TVRec(3): ClearFlags?(PENDINGACTIONS,) -> RunMainLoop?,Rin gBufferReady, 2009-05-17 03:27:01.980 TVRec(3): No recorder yet, calling TuningFrequency? 2009-05-17 03:27:02.043 TVRec(3): Starting Signal Monitor 2009-05-17 03:27:02.044 TVRec(3): SetupSignalMonitor?(1, 0) 2009-05-17 03:27:02.084 TVRec(3): Signal monitor successfully created 2009-05-17 03:27:02.085 TVRec(3): Setting up table monitoring. 2009-05-17 03:27:02.087 Using profile 'Live TV' to record 2009-05-17 03:27:02.094 TVRec(3): DVB service_id 28108 on net_id 41985 tsid 1101 2009-05-17 03:27:02.094 TVRec(3): Successfully set up DVB table monitoring. 2009-05-17 03:27:02.095 TVRec(3): SetFlags?(SignalMonitorRunning?,) -> RunMainLoop? ,SignalMonitorRunning?,RingBufferReady?, 2009-05-17 03:27:02.095 TVRec(3): ClearFlags?(WaitingForSignal?,) -> RunMainLoop?,S ignalMonitorRunning,RingBufferReady?, 2009-05-17 03:27:02.096 TVRec(3): SetFlags?(WaitingForSignal?,) -> RunMainLoop?,Wai tingForSignal,SignalMonitorRunning?,RingBufferReady?, 2009-05-17 03:27:02.105 TVRec(3): ClearFlags?(NeedToStartRecorder?,) -> RunMainLoo? p,WaitingForSignal?,SignalMonitorRunning?,RingBufferReady?, 2009-05-17 03:27:02.114 TVRec(3): SetFlags?(NeedToStartRecorder?,) -> RunMainLoop?, WaitingForSignal?,NeedToStartRecorder?,SignalMonitorRunning?,RingBufferReady?, 2009-05-17 03:27:02.178 AutoExpire?: Cardid 3: is starting a recording on an unkn own fsID soon. 2009-05-17 03:27:02.182 AutoExpire?: CalcParams?() 2009-05-17 03:27:02.217 Cardid 3: max bitrate 142089 KB/min 2009-05-17 03:27:02.218 Maximal bitrate of busy encoders is 142089 KB/min --- GetFilesystemInfos? directory list start --- Dir: htpc:/var/lib/mythtv/recordings
Location: Local fsID : 1 dirID : 6 TotalKB : 475537480 UsedKB : 178052764 FreeKB : 297484716
--- GetFilesystemInfos? directory list end --- 2009-05-17 03:27:02.349 fsID #1: Total: 453.5 GB Used: 169.8 GB Free:283.7 GB 2009-05-17 03:27:02.350 Cardid 3: max bitrate 18945 Kb/sec, fsID 1 max is now 142089 KB/min 2009-05-17 03:27:02.360 Max of 142089 KB/min for fsID 1 is higher than the existing Max of 0 so we'll use this Max instead 2009-05-17 03:27:02.372 AutoExpire?: CalcParams?(): Max required Free Space: 3.0 GB w/freq: 15 min 2009-05-17 03:27:02.379 Started recording: Das Appartement: channel 2116 on cardid 3, sourceid 2 2009-05-17 03:27:33.278 AutoExpire?: Adding Programs to 'Do Not Expire' List 2009-05-17 03:27:33.283 2116 @ 2009-05-17T03:27:00 in use by recorder on htpc 2009-05-17 03:28:33.284 AutoExpire?: Adding Programs to 'Do Not Expire' List 2009-05-17 03:28:33.284 2116 @ 2009-05-17T03:27:00 in use by recorder on htpc 2009-05-17 03:28:33.285 AutoExpire?: ExpireLiveTV(10000) 2009-05-17 03:28:33.285 AutoExpire?: FillDBOrdered: Adding Short LiveTV programsin starttime order 2009-05-17 03:28:33.287 AutoExpire?: SendDeleteMessages?. Nothing to expire. 2009-05-17 03:29:33.322 AutoExpire?: Adding Programs to 'Do Not Expire' List 2009-05-17 03:29:33.323 2116 @ 2009-05-17T03:27:00 in use by recorder on htpc
etc.
Change History (16)
comment:1 Changed 15 years ago by
comment:2 Changed 15 years ago by
Status: | new → infoneeded_new |
---|
This is a livetv recording. It will not appear in the recorded programs screen unless you specifically select the filter there.
If that is not the issue please submit logs with -v record,channel.
comment:3 Changed 15 years ago by
It's not a LiveTV recording, I rarely watch TV at 3am. ;-) The recording *does* show up in the recording screen, but the recording is empty, i.e. the file doesn't exist.
comment:4 Changed 15 years ago by
Oh, and it looks like I didn't set the version when creating the ticket, this is 0.21-fixes, weekly builds.
comment:5 Changed 15 years ago by
These are the logs with -v record,channel,file:
2009-05-19 00:27:01.077 TVRec(3): StartRecording(60 x Deutschland - Die Jahresschau) 2009-05-19 00:27:01.104 TVRec(3): Checking input group recorders - begin 2009-05-19 00:27:01.104 TVRec(3): Checking input group recorders - done 2009-05-19 00:27:01.171 ProgramInfo: StartedRecording: Recording to '/var/lib/mythtv/recordings/2101_20090519002700.mpg' 2009-05-19 00:27:01.208 TVRec(3): StartedRecording(0xa3161c8) fn(/var/lib/mythtv/recordings/2101_20090519002700.mpg) 2009-05-19 00:27:01.209 TVRec(3): ClearFlags(CancelNextRecording,) -> RunMainLoop, 2009-05-19 00:27:01.210 TVRec(3): Changing from None to RecordingOnly 2009-05-19 00:27:01.218 TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2009-05-19 00:27:01.259 TVRec(3): Request: Program(yes) channel() input() flags(Recording,) 2009-05-19 00:27:01.260 TVRec(3): HW Tuner: 3->3 2009-05-19 00:27:01.270 TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 2009-05-19 00:27:01.278 TVRec(3): No recorder yet, calling TuningFrequency 2009-05-19 00:27:01.304 DVBChan(3:1): Opening DVB channel 2009-05-19 00:27:01.304 DVBChan(3:1): SetChannelByString(1): 2009-05-19 00:27:01.318 DVBChan(3:1): 410000000 qam_256 a auto auto a a auto a v 2009-05-19 00:27:01.322 DVBChan(3:1): Old Params: 113000000 qam_256 a auto autoa a auto a v DVBChan(3:1): New Params: 410000000 qam_256 a auto autoa a auto a v 2009-05-19 00:27:01.348 DVBChan(3:1): Tune(): Tuning to 410000000Hz 2009-05-19 00:27:01.348 dvbchannel.cpp:wait_for_backend: Status: 2009-05-19 00:27:01.359 DVBChan(3:1): Tune(): Frequency tuning successful. 2009-05-19 00:27:01.366 DVBChan(3:1): SetChannelByString(1): Tuned to frequency. 2009-05-19 00:27:01.392 TVRec(3): Starting Signal Monitor 2009-05-19 00:27:01.392 TVRec(3): SetupSignalMonitor(1, 0) 2009-05-19 00:27:01.404 DVBChan(3:1): Opening DVB channel 2009-05-19 00:27:01.411 DVBSM(1): Can measure Signal Strength 2009-05-19 00:27:01.419 DVBSM(1): Can measure S/N 2009-05-19 00:27:01.427 DVBSM(1): Can measure Bit Error Rate 2009-05-19 00:27:01.450 DVBSM(1): Can count Uncorrected Blocks 2009-05-19 00:27:01.451 SM(1)::RemoveFlags: Seen() Match() Wait() 2009-05-19 00:27:01.459 DVBSM(1): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2009-05-19 00:27:01.466 TVRec(3): Signal monitor successfully created 2009-05-19 00:27:01.474 TVRec(3): Setting up table monitoring. 2009-05-19 00:27:01.530 Using profile 'Live TV' to record 2009-05-19 00:27:01.531 TVRec(3): DVB service_id 28106 on net_id 41985 tsid 1101 2009-05-19 00:27:01.532 DTVSM(1)::SetDVBService(transport_id: 1101, network_id:41985, service_id: 28106): 2009-05-19 00:27:01.537 SM(1)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2009-05-19 00:27:01.559 SM(1)::AddFlags: Seen() Match() Wait(PMT,SDT,) 2009-05-19 00:27:01.560 SM(1)::AddFlags: Seen() Match() Wait(PMT,SDT,Pos,) 2009-05-19 00:27:01.566 TVRec(3): Successfully set up DVB table monitoring. 2009-05-19 00:27:01.574 SM(1)::Start: begin 2009-05-19 00:27:01.592 SM(1)::Start: end 2009-05-19 00:27:01.592 TVRec(3): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, 2009-05-19 00:27:01.592 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:01.603 TVRec(3): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, 2009-05-19 00:27:01.610 SM(1)::RemoveFlags: Seen() Match() Wait(Pos,) 2009-05-19 00:27:01.618 TVRec(3): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, 2009-05-19 00:27:01.637 TVRec(3): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, 2009-05-19 00:27:01.648 TVRec(3): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, 2009-05-19 00:27:01.692 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:01.715 AutoExpire: Cardid 3: is starting a recording on an unknown fsID soon. 2009-05-19 00:27:01.720 AutoExpire: CalcParams() 2009-05-19 00:27:01.723 Cardid 3: max bitrate 142089 KB/min 2009-05-19 00:27:01.748 Maximal bitrate of busy encoders is 142089 KB/min --- GetFilesystemInfos directory list start --- Dir: htpc:/var/lib/mythtv/recordings Location: Local fsID : 1 dirID : 6 TotalKB : 475537480 UsedKB : 158686868 FreeKB : 316850612 --- GetFilesystemInfos directory list end --- 2009-05-19 00:27:01.755 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:01.836 fsID #1: Total: 453.5 GB Used: 151.3 GB Free:302.2 GB 2009-05-19 00:27:01.858 Cardid 3: max bitrate 18945 Kb/sec, fsID 1 max is now 142089 KB/min 2009-05-19 00:27:01.859 Max of 142089 KB/min for fsID 1 is higher than the existing Max of 0 so we'll use this Max instead 2009-05-19 00:27:01.870 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min 2009-05-19 00:27:01.878 Started recording: 60 x Deutschland - Die Jahresschau "Das Jahr 2004": channel 2101 on cardid 3, sourceid 2 2009-05-19 00:27:01.898 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:01.963 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.020 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.076 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.132 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.189 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.245 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.301 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.358 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.414 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.470 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.532 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.589 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.645 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.701 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.757 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.815 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.871 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.927 SM(1)::AddFlags: Seen() Match() Wait(Sig,) 2009-05-19 00:27:02.984 SM(1)::AddFlags: Seen() Match() Wait(Sig,)
and so on
comment:7 Changed 15 years ago by
dup of #1850 ? (based on comments, I understand that the reported bug is that Myth attempts to record, but gets no data at all from the capture device, so it creates a valid entry in recorded and a 0-byte file)
comment:8 Changed 15 years ago by
The idea of re-recording failed recordings is a dupe. But not the reason for the failed recording.
comment:9 Changed 15 years ago by
Status: | infoneeded_new → new |
---|
comment:10 Changed 14 years ago by
I have a nagging feeling that you don't actually have a directory named:
"/var/lib/my thtv/recordings/"
There's a space in mythtv. Check your config.
comment:11 Changed 14 years ago by
There is no space.
Also, the problem no longer appears after I replaced my TT C-something with another Satelco card. I already had a 2nd Satelco running all the time. The TT used different multiplex frequencies though, so that might be what MythTV fails to handle correctly.
comment:12 Changed 14 years ago by
hi,
i have a litle more vorbose messages, i try to catch a gdb dump. at many time the records stop then start a second record at same time.
the mytbackend process ist running all times.
i have 2 usb devices(bus 001 Device 003: ID 07ca:a815 AVerMedia Technologies, Inc.) mythbuntu 9.10 core2duo 2gb ram nvidia 3 disks one for recordings only
mythbuntu 9.04 has not this problems, runs a year with same hardware.
44.392 MainServer::ANN Monitor 44.466 adding: tv as a client (events: 1) 44.497 Reschedule requested for id 43. 44.636 Scheduled 28 items in 0.1 = 0.04 match + 0.09 place 44.664 TVRec(2): RecordPending on inputid 2 44.680 TVRec(2): StartRecording(Tatort: Die Liebe und ihr Preis) 44.697 TVRec(2): ASK_RECORDING 2 0 0 0 44.756 TVRec(2): StartedRecording(0x1161d20) fn(/var/lib/mythtv/recordings/1011_20100104230500.mpg) 44.809 TVRec(2): ClearFlags(CancelNextRecording,) -> RunMainLoop, 44.820 MainServer::ANN Monitor 44.824 TVRec(2): Changing from None to Watching RecordingOnly 44.874 adding: tv as a client (events: 1) 44.933 TVRec(2): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 45.033 TVRec(2): Request: Program(yes) channel() input() flags(Recording,) 45.051 TVRec(2): HW Tuner: 2->2 45.077 TVRec(2): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 45.126 TVRec(2): No recorder yet, calling TuningFrequency 45.146 TVRec(2): Starting Signal Monitor 45.201 TVRec(2): SetupSignalMonitor(1, 0) 45.480 TVRec(2): Signal monitor successfully created 45.496 TVRec(2): Setting up table monitoring. 45.515 Using profile 'Live TV' to record 45.562 TVRec(2): DVB service_id 11 on net_id 8468 tsid 258 45.579 TVRec(2): Successfully set up DVB table monitoring. 45.597 TVRec(2): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, 45.613 TVRec(2): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, 45.630 TVRec(2): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, 45.647 TVRec(2): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, 45.664 TVRec(2): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, 45.686 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 8 min 45.694 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 45.730 Started recording: Tatort: Die Liebe und ihr Preis: channel 1011 on cardid 2, sourceid 1 45.779 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x130 discontinuity detected 53.589 MainServer::ANN Monitor 53.620 adding: tv as a client (events: 1) 28.218 MainServer::ANN Monitor 28.243 adding: tv as a client (events: 1) 28.286 MainServer::ANN Monitor 28.327 adding: tv as a client (events: 1) 28.349 ProgramInfo, Error: GetPlaybackURL: '1011_20100104230500.mpg' should be local, but it can not be found. 28.526 MainServer::ANN Monitor 28.556 adding: tv as a client (events: 1) 29.183 ProgramInfo, Error: GetPlaybackURL: '1011_20100104230500.mpg' should be local, but it can not be found. 29.203 ProgramInfo, Error: GetPlaybackURL: '1011_20100104230500.mpg' should be local, but it can not be found. 29.219 Preview Error: Run() file not local: '1011_20100104230500.mpg' 35.143 MainServer::ANN Monitor 35.157 adding: tv as a client (events: 1) 35.199 MainServer::ANN Monitor 35.240 adding: tv as a client (events: 1) 35.262 ProgramInfo, Error: GetPlaybackURL: '1011_20100104230500.mpg' should be local, but it can not be found. 35.408 MainServer::ANN Monitor 35.456 adding: tv as a client (events: 1) 36.042 ProgramInfo, Error: GetPlaybackURL: '1011_20100104230500.mpg' should be local, but it can not be found. 36.058 ProgramInfo, Error: GetPlaybackURL: '1011_20100104230500.mpg' should be local, but it can not be found. 36.074 Preview Error: Run() file not local: '1011_20100104230500.mpg' 39.713 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 39.728 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x130 discontinuity detected 51.094 Reschedule requested for id -1. 51.373 Scheduled 28 items in 0.3 = 0.19 match + 0.09 place 18.757 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 18.769 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12e discontinuity detected 23.431 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 23.458 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12e discontinuity detected 02.837 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 02.866 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x130 discontinuity detected 14.876 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 14.902 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x130 discontinuity detected 52.805 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 52.833 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 5 (expected 4). 52.850 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x13d2 discontinuity detected 53.537 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 53.549 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x130 discontinuity detected 23.253 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 23.278 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12e discontinuity detected 23.295 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 7 (expected 6). 23.332 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 23.362 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x130 discontinuity detected 25.989 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x12d discontinuity detected 26.018 DVBRec(1:/dev/dvb/adapter0/frontend0): PID 0x130 discontinuity detected 26.035 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x12, continuity counter: 2 (expected 1).
comment:14 Changed 14 years ago by
I had this problem today.
New tuner card, some channels wouldn't record properly...
Same discontinutity message from above.
It turns out the new card is much more sensitive to minor differences in frequencies.
I grabbed the latest TZAP file (for my area) and updated the dtv_multiplex table in the mythtv database with the correct frequencies.
All sorted now :)
comment:15 Changed 14 years ago by
Component: | MythTV - General → MythTV - Recording |
---|---|
Owner: | changed from Isaac Richards to danielk |
comment:16 Changed 14 years ago by
Resolution: | → invalid |
---|---|
Status: | new → closed |
All the log snippets do appear to indicate there is something seriously wrong with the recording. If this is a request to forward that information from the error log to the GUI I'm afraid we'll need a patch for that.
The log formatted better: