[mythtv] to gigem: is multirec on dvb-s supposed to work in current master?

Piotr Oniszczuk piotr.oniszczuk at gmail.com
Tue Jun 28 17:16:50 UTC 2016


@gigem

After jun25 commits in master I have problems with recording more that 1 rec in multirec on given phy. tuner.
Is multirec support an work-in-progress now or rather it should work as expected?

If it should work as expected - then any another multirec attempt on already recording tuner gives:
„Requested channel is not available because the tuner is currently in use on another transport”

Below is be log with channel,recording

Look around 2016-06-28 19:10:01.351289 - be says channel not available despite channel is on the same mplex on which there is ongoing recording.

If You need I can bisect - but maybe log below will be enough to narrow issue?
This is from current master.
  

2016-06-28 19:09:00.001092 I  Reschedule requested for PLACE PrepareToRecord
2016-06-28 19:09:00.001100 I  Reschedule requested for PLACE PrepareToRecord
2016-06-28 19:09:00.063122 I  Scheduled 2 items in 0.0 = 0.00 match + 0.00 check + 0.01 place
2016-06-28 19:09:30.000538 I  TVRec[13]: RecordPending on inputid 13
2016-06-28 19:09:30.000843 I  CardUtil:   Group ID 4
2016-06-28 19:09:30.001020 I  CardUtil:   Group ID 7
2016-06-28 19:09:30.001170 I  CardUtil:   Input ID 14
2016-06-28 19:09:30.001176 I  CardUtil:   Input ID 15
2016-06-28 19:09:30.001179 I  CardUtil:   Input ID 16
2016-06-28 19:09:30.001181 I  CardUtil:   Input ID 17
2016-06-28 19:09:30.001183 I  CardUtil:   Input ID 18
2016-06-28 19:09:30.001185 I  CardUtil:   Input ID 19
2016-06-28 19:09:30.001187 I  CardUtil:   Input ID 20
2016-06-28 19:09:30.001190 I  CardUtil:   Input ID 21
2016-06-28 19:09:30.001192 I  CardUtil:   Input ID 22
2016-06-28 19:09:30.001211 I  TVRec[14]: RecordPending on inputid 13
2016-06-28 19:09:30.001223 I  TVRec[15]: RecordPending on inputid 13
2016-06-28 19:09:30.001230 I  TVRec[16]: RecordPending on inputid 13
2016-06-28 19:09:30.001237 I  TVRec[17]: RecordPending on inputid 13
2016-06-28 19:09:30.001244 I  TVRec[18]: RecordPending on inputid 13
2016-06-28 19:09:30.001251 I  TVRec[19]: RecordPending on inputid 13
2016-06-28 19:09:30.001258 I  TVRec[20]: RecordPending on inputid 13
2016-06-28 19:09:30.001264 I  TVRec[21]: RecordPending on inputid 13
2016-06-28 19:09:30.001271 I  TVRec[22]: RecordPending on inputid 13
2016-06-28 19:09:30.001316 I  TVRec[14]: RecordPending on inputid 14
2016-06-28 19:09:30.001466 I  CardUtil:   Group ID 4
2016-06-28 19:09:30.001624 I  CardUtil:   Group ID 7
2016-06-28 19:09:30.001772 I  CardUtil:   Input ID 13
2016-06-28 19:09:30.001777 I  CardUtil:   Input ID 15
2016-06-28 19:09:30.001780 I  CardUtil:   Input ID 16
2016-06-28 19:09:30.001782 I  CardUtil:   Input ID 17
2016-06-28 19:09:30.001784 I  CardUtil:   Input ID 18
2016-06-28 19:09:30.001787 I  CardUtil:   Input ID 19
2016-06-28 19:09:30.001789 I  CardUtil:   Input ID 20
2016-06-28 19:09:30.001791 I  CardUtil:   Input ID 21
2016-06-28 19:09:30.001793 I  CardUtil:   Input ID 22
2016-06-28 19:09:30.001800 I  TVRec[13]: RecordPending on inputid 14
2016-06-28 19:09:30.001810 I  TVRec[15]: RecordPending on inputid 14
2016-06-28 19:09:30.001817 I  TVRec[16]: RecordPending on inputid 14
2016-06-28 19:09:30.001824 I  TVRec[17]: RecordPending on inputid 14
2016-06-28 19:09:30.001830 I  TVRec[18]: RecordPending on inputid 14
2016-06-28 19:09:30.001837 I  TVRec[19]: RecordPending on inputid 14
2016-06-28 19:09:30.001843 I  TVRec[20]: RecordPending on inputid 14
2016-06-28 19:09:30.001850 I  TVRec[21]: RecordPending on inputid 14
2016-06-28 19:09:30.001856 I  TVRec[22]: RecordPending on inputid 14
2016-06-28 19:09:30.223571 I  TVRec[21]: ASK_RECORDING 21 29 0 0
2016-06-28 19:09:30.223615 I  TVRec[21]: ASK_RECORDING 21 28 0 0
2016-06-28 19:09:30.293532 I  TVRec[19]: ASK_RECORDING 19 29 0 0
2016-06-28 19:09:30.293571 I  TVRec[19]: ASK_RECORDING 19 28 0 0
2016-06-28 19:09:30.359963 I  TVRec[17]: ASK_RECORDING 17 29 0 0
2016-06-28 19:09:30.359998 I  TVRec[17]: ASK_RECORDING 17 28 0 0
2016-06-28 19:09:30.438335 I  TVRec[15]: ASK_RECORDING 15 29 0 0
2016-06-28 19:09:30.438371 I  TVRec[15]: ASK_RECORDING 15 28 0 0
2016-06-28 19:09:30.480695 I  TVRec[13]: ASK_RECORDING 13 29 0 0
2016-06-28 19:09:30.480729 I  TVRec[13]: ASK_RECORDING 13 28 0 0
2016-06-28 19:09:30.693628 I  TVRec[22]: ASK_RECORDING 22 29 0 0
2016-06-28 19:09:30.693661 I  TVRec[22]: ASK_RECORDING 22 28 0 0
2016-06-28 19:09:30.758466 I  TVRec[20]: ASK_RECORDING 20 29 0 0
2016-06-28 19:09:30.758500 I  TVRec[20]: ASK_RECORDING 20 28 0 0
2016-06-28 19:09:30.826738 I  TVRec[18]: ASK_RECORDING 18 29 0 0
2016-06-28 19:09:30.826770 I  TVRec[18]: ASK_RECORDING 18 28 0 0
2016-06-28 19:09:30.897728 I  TVRec[16]: ASK_RECORDING 16 29 0 0
2016-06-28 19:09:30.897763 I  TVRec[16]: ASK_RECORDING 16 28 0 0
2016-06-28 19:09:30.940435 I  TVRec[14]: ASK_RECORDING 14 29 0 0
2016-06-28 19:09:30.940467 I  TVRec[14]: ASK_RECORDING 14 28 0 0
2016-06-28 19:10:00.001112 I  TVRec[13]: StartRecording("54 (TVN Fabula HD)":"2016-06-28 19:10:00")
2016-06-28 19:10:00.001128 I  TVRec[13]: SetRecordingStatus(Nieznany->Zaniechane) on line 433
2016-06-28 19:10:00.001231 I  TVRec[13]: Checking input group recorders - begin
2016-06-28 19:10:00.001973 I  TVRec[13]: Checking input group recorders - done
2016-06-28 19:10:00.025080 I  TVRec[13]: StartedRecording(18520_2016-06-28T17:10:00Z) fn(/myth/tv/18520_20160628171000.ts)
2016-06-28 19:10:00.026455 I  TVRec[13]: Using profile 'Default' to record
2016-06-28 19:10:00.026940 I  TVRec[13]: ClearFlags(CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:622
2016-06-28 19:10:00.026969 I  TVRec[13]: SetRecordingStatus(Zaniechane->Strojenie) on line 625
2016-06-28 19:10:00.027027 I  TVRec[13]: Changing from None to RecordingOnly
2016-06-28 19:10:00.027060 I  TVRec[13]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:1431
2016-06-28 19:10:00.027106 I  TVRec[13]: HandleTuning Request: Program(ProgramInfo(18520_20160628171000.ts): channame(TVN Fabula HD) startts(Tue Jun 28 17:10:00 2016 GMT) endts(Tue Jun 28 17:15:00 2016 GMT)
             recstartts(Tue Jun 28 17:10:00 2016 GMT) recendts(Tue Jun 28 17:15:00 2016 GMT)
             title(54 (TVN Fabula HD))) channel() input() flags(Recording,)
2016-06-28 19:10:00.027397 I  TVRec[13]: TuningShutdowns(Program(ProgramInfo(18520_20160628171000.ts): channame(TVN Fabula HD) startts(Tue Jun 28 17:10:00 2016 GMT) endts(Tue Jun 28 17:15:00 2016 GMT)
             recstartts(Tue Jun 28 17:10:00 2016 GMT) recendts(Tue Jun 28 17:15:00 2016 GMT)
             title(54 (TVN Fabula HD))) channel(54) input(DVBInput) flags(Recording,))
2016-06-28 19:10:00.027529 I  TVRec[13]: HW Tuner: 13->13
2016-06-28 19:10:00.027544 I  TVRec[13]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop, @ tv_rec.cpp:3810
2016-06-28 19:10:00.027552 I  TVRec[13]: No recorder yet, calling TuningFrequency
2016-06-28 19:10:00.027556 I  TVRec[13]: TuningFrequency
2016-06-28 19:10:00.027688 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Opening DVB channel
2016-06-28 19:10:00.043798 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Using DVB card /dev/dvb/adapter2/frontend0, with frontend 'Montage Technology M88DS3103'.
2016-06-28 19:10:00.043810 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Requested DVB channel is on non-SCR system
2016-06-28 19:10:00.043817 I  DiSEqCDevTree: Changing LNB voltage to 18V
2016-06-28 19:10:00.203598 C  ProgramInfo(): Failed to find recorded entry for 0.
2016-06-28 19:10:00.328016 E  ProgramInfo(18520_20160628170101.ts): GetPlaybackURL: '18520_20160628170101.ts' should be local, but it can not be found.
2016-06-28 19:10:01.044846 I  DiSEqCDevTree: Resetting DiSEqC Bus
2016-06-28 19:10:01.044864 I  DiSEqCDevTree: Sending DiSEqC Command: e0  0  0 
2016-06-28 19:10:01.349807 I  ChannelBase[13]: Input #13: 'DVBInput' schan(55) sourceid(8)
2016-06-28 19:10:01.349835 I  DTVChan[13](/dev/dvb/adapter2/frontend0): SetChannelByString(54): 
2016-06-28 19:10:01.350042 I  CardUtil:   Group ID 4
2016-06-28 19:10:01.350186 I  CardUtil:   Group ID 7
2016-06-28 19:10:01.350303 I  CardUtil:   Input ID 14
2016-06-28 19:10:01.350307 I  CardUtil:   Input ID 15
2016-06-28 19:10:01.350311 I  CardUtil:   Input ID 16
2016-06-28 19:10:01.350313 I  CardUtil:   Input ID 17
2016-06-28 19:10:01.350316 I  CardUtil:   Input ID 18
2016-06-28 19:10:01.350319 I  CardUtil:   Input ID 19
2016-06-28 19:10:01.350323 I  CardUtil:   Input ID 20
2016-06-28 19:10:01.350325 I  CardUtil:   Input ID 21
2016-06-28 19:10:01.350329 I  CardUtil:   Input ID 22
2016-06-28 19:10:01.350890 I  ChannelBase[13]: Input is free on 16/23806
2016-06-28 19:10:01.351289 E  DTVChan[13](/dev/dvb/adapter2/frontend0): SetChannelByString(54): Requested channel '54' is not available because the tuner is currently in use on another transport.
2016-06-28 19:10:01.351297 E  TVRec[13]: Failed to set channel to 54. Reverting to kState_None
2016-06-28 19:10:01.351358 I  TVRec[13]: Changing from RecordingOnly to None
2016-06-28 19:10:01.351477 I  TVRec[13]: EIT active scan is in 10min. window. If enabled, will (re)start after 267 sec.
2016-06-28 19:10:01.351485 I  TVRec[13]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:1431
2016-06-28 19:10:01.351495 I  TVRec[13]: HandleTuning Request: Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,)
2016-06-28 19:10:01.351503 I  TVRec[13]: TuningShutdowns(Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,))
2016-06-28 19:10:01.351517 I  TVRec[13]: TeardownRecorder()
2016-06-28 19:10:01.351523 I  TVRec[13]: ClearFlags(NeedToStartRecorder,RecorderRunning,) -> RunMainLoop, @ tv_rec.cpp:1223
2016-06-28 19:10:01.351747 I  TVRec[13]: FinishedRecording(18520_2016-06-28T17:10:00Z) Bad quality
			title: 54 (TVN Fabula HD)
			in recgroup: 1 - Goska status: Recorder Failed:Recorder Failed not_dummy finished_now
2016-06-28 19:10:01.352368 I  SaveVideoProperties(0x3a, 0x20)
2016-06-28 19:10:01.352989 I  TVRec[13]: FinishedRecording -- UPDATE_RECORDING_STATUS: Recorder Failed
2016-06-28 19:10:01.353167 I  Updating status for "54 (TVN Fabula HD)":"2016-06-28 19:10:00" on cardid 13 (Będzie nagrane => Recorder Failed)
2016-06-28 19:10:01.353338 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Closing DVB channel
2016-06-28 19:10:01.353559 I  TVRec[13]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop, @ tv_rec.cpp:3810
2016-06-28 19:10:01.354184 I  Tuning recording: "54 (TVN Fabula HD)":"2016-06-28 19:10:00": channel 18520 on cardid 13, sourceid 8
2016-06-28 19:10:01.354742 I  TVRec[14]: StartRecording("55 (TVN24 HD)":"2016-06-28 19:10:00")
2016-06-28 19:10:01.354755 I  TVRec[14]: SetRecordingStatus(Nieznany->Zaniechane) on line 433
2016-06-28 19:10:01.354795 I  TVRec[14]: Checking input group recorders - begin
2016-06-28 19:10:01.354815 I  TVRec[14]: Checking input group recorders - done
2016-06-28 19:10:01.374293 I  TVRec[14]: StartedRecording(23806_2016-06-28T17:10:00Z) fn(/myth/tv/23806_20160628171000.ts)
2016-06-28 19:10:01.375548 I  TVRec[14]: Using profile 'Default' to record
2016-06-28 19:10:01.376048 I  TVRec[14]: ClearFlags(CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:622
2016-06-28 19:10:01.376069 I  TVRec[14]: SetRecordingStatus(Zaniechane->Strojenie) on line 625
2016-06-28 19:10:01.376178 I  TVRec[14]: Changing from None to RecordingOnly
2016-06-28 19:10:01.376212 I  TVRec[14]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:1431
2016-06-28 19:10:01.376257 I  TVRec[14]: HandleTuning Request: Program(ProgramInfo(23806_20160628171000.ts): channame(TVN24 HD) startts(Tue Jun 28 17:10:00 2016 GMT) endts(Tue Jun 28 17:15:00 2016 GMT)
             recstartts(Tue Jun 28 17:10:00 2016 GMT) recendts(Tue Jun 28 17:15:00 2016 GMT)
             title(55 (TVN24 HD))) channel() input() flags(Recording,)
2016-06-28 19:10:01.376588 I  TVRec[14]: TuningShutdowns(Program(ProgramInfo(23806_20160628171000.ts): channame(TVN24 HD) startts(Tue Jun 28 17:10:00 2016 GMT) endts(Tue Jun 28 17:15:00 2016 GMT)
             recstartts(Tue Jun 28 17:10:00 2016 GMT) recendts(Tue Jun 28 17:15:00 2016 GMT)
             title(55 (TVN24 HD))) channel(55) input(DVBInput) flags(Recording,))
2016-06-28 19:10:01.376753 I  TVRec[14]: HW Tuner: 14->14
2016-06-28 19:10:01.376764 I  TVRec[14]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop, @ tv_rec.cpp:3810
2016-06-28 19:10:01.376770 I  TVRec[14]: No recorder yet, calling TuningFrequency
2016-06-28 19:10:01.376773 I  TVRec[14]: TuningFrequency
2016-06-28 19:10:01.376929 I  DVBChan[14](/dev/dvb/adapter2/frontend0): Opening DVB channel
2016-06-28 19:10:01.377072 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Opening DVB channel
2016-06-28 19:10:01.393153 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Using DVB card /dev/dvb/adapter2/frontend0, with frontend 'Montage Technology M88DS3103'.
2016-06-28 19:10:01.393164 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Requested DVB channel is on non-SCR system
2016-06-28 19:10:01.393170 I  DiSEqCDevTree: Changing LNB voltage to 18V
2016-06-28 19:10:01.404796 C  ProgramInfo(): Failed to find recorded entry for 0.
2016-06-28 19:10:01.527231 E  ProgramInfo(18520_20160628170101.ts): GetPlaybackURL: '18520_20160628170101.ts' should be local, but it can not be found.
2016-06-28 19:10:02.394151 I  DiSEqCDevTree: Resetting DiSEqC Bus
2016-06-28 19:10:02.394177 I  DiSEqCDevTree: Sending DiSEqC Command: e0  0  0 
2016-06-28 19:10:02.702694 I  ChannelBase[13]: Input #13: 'DVBInput' schan(55) sourceid(8)
2016-06-28 19:10:02.705632 I  ChannelBase[14]: Input #14: 'DVBInput' schan(63) sourceid(8)
2016-06-28 19:10:02.705657 I  DTVChan[14](/dev/dvb/adapter2/frontend0): SetChannelByString(55): 
2016-06-28 19:10:02.705837 I  CardUtil:   Group ID 4
2016-06-28 19:10:02.706005 I  CardUtil:   Group ID 7
2016-06-28 19:10:02.706158 I  CardUtil:   Input ID 13
2016-06-28 19:10:02.706164 I  CardUtil:   Input ID 15
2016-06-28 19:10:02.706167 I  CardUtil:   Input ID 16
2016-06-28 19:10:02.706169 I  CardUtil:   Input ID 17
2016-06-28 19:10:02.706171 I  CardUtil:   Input ID 18
2016-06-28 19:10:02.706173 I  CardUtil:   Input ID 19
2016-06-28 19:10:02.706175 I  CardUtil:   Input ID 20
2016-06-28 19:10:02.706177 I  CardUtil:   Input ID 21
2016-06-28 19:10:02.706179 I  CardUtil:   Input ID 22
2016-06-28 19:10:02.706201 I  ChannelBase[14]: Input is free on 0/0
2016-06-28 19:10:02.707009 I  DVBChan[14](/dev/dvb/adapter2/frontend0): 11508000 8psk a auto auto a a auto a v fec: 3/4 msys: DVB-S2 rolloff: 0.35
2016-06-28 19:10:02.707145 I  DVBChan[14](/dev/dvb/adapter2/frontend0): tuning on slave channel
2016-06-28 19:10:02.707342 I  DVBChan[13](/dev/dvb/adapter2/frontend0): 
Old Params: 11470000 qpsk a auto auto a a auto a v fec: 5/6 msys: DVB-S rolloff: 0.35
New Params: 11508000 8psk a auto auto a a auto a v fec: 3/4 msys: DVB-S2 rolloff: 0.35
2016-06-28 19:10:02.707359 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Tune(): Tuning to 11508000kHz
2016-06-28 19:10:02.707420 I  DiSEqCDevTree: Changing LNB voltage to 13V
2016-06-28 19:10:02.951379 I  DVBChan: wait_for_backend: Status: 
2016-06-28 19:10:02.951390 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Tune(): Frequency tuning successful.
2016-06-28 19:10:02.951397 I  DTVChan[14](/dev/dvb/adapter2/frontend0): SetChannelByString(55): success
2016-06-28 19:10:02.951405 I  TVRec[14]: Starting Signal Monitor
2016-06-28 19:10:02.951417 I  TVRec[14]: SetupSignalMonitor(1, 0)
2016-06-28 19:10:02.951437 I  DVBChan[14](/dev/dvb/adapter2/frontend0): Opening DVB channel
2016-06-28 19:10:02.952712 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): Can measure Signal Strength
2016-06-28 19:10:02.953015 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): Can measure S/N
2016-06-28 19:10:02.953317 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): Can measure Bit Error Rate
2016-06-28 19:10:02.953616 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): Can count Uncorrected Blocks
2016-06-28 19:10:02.953625 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,)
2016-06-28 19:10:02.953647 I  TVRec[14]: Signal monitor successfully created
2016-06-28 19:10:02.953654 I  TVRec[14]: Setting up table monitoring.
2016-06-28 19:10:02.954902 I  TVRec[14]: Using profile 'Live TV' to record
2016-06-28 19:10:02.954925 I  TVRec[14]: MPEG program number: 15826
2016-06-28 19:10:02.954944 I  DTVSigMon[14](/dev/dvb/adapter2/frontend0)::SetProgramNumber(15826): 
2016-06-28 19:10:02.954960 I  MPEGStream[14](0x7f2cc40437a0): SetDesiredProgram(15826)
2016-06-28 19:10:02.954995 I  TVRec[14]: Successfully set up MPEG table monitoring.
2016-06-28 19:10:02.955157 I  TVRec[14]: SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3986
2016-06-28 19:10:02.955165 I  TVRec[14]: ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3987
2016-06-28 19:10:02.955172 I  TVRec[14]: SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3992
2016-06-28 19:10:02.955181 I  TVRec[14]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:4048
2016-06-28 19:10:02.955187 I  TVRec[14]: SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, @ tv_rec.cpp:4050
2016-06-28 19:10:02.955218 I  TVRec[14]: TuningSignalCheck: Still waiting.  Will timeout @ 19:15:00.000
2016-06-28 19:10:03.116715 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): Signal Locked
2016-06-28 19:10:03.116730 I  SH(/dev/dvb/adapter2/frontend0): AddListener(0x7f2cc40437a0) -- begin
2016-06-28 19:10:03.116734 I  SH(/dev/dvb/adapter2/frontend0): AddListener(0x7f2cc40437a0) -- locked
2016-06-28 19:10:03.216855 I  SH(/dev/dvb/adapter2/frontend0): AddListener(0x7f2cc40437a0) -- end
2016-06-28 19:10:03.471229 N  DTVSigMon[14](/dev/dvb/adapter2/frontend0): PMT says program 15826 is encrypted
2016-06-28 19:10:05.620689 I  TVRec[14]: TuningSignalCheck: Good signal
2016-06-28 19:10:05.620708 I  TVRec[14]: SetRecordingStatus(Strojenie->Nagrywam) on line 4170
2016-06-28 19:10:05.620750 I  TVRec[14]: TeardownSignalMonitor() -- begin
2016-06-28 19:10:05.620757 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): Stop() -- begin
2016-06-28 19:10:05.670813 I  SH(/dev/dvb/adapter2/frontend0): RemoveListener(0x7f2cc40437a0) -- begin
2016-06-28 19:10:05.670821 I  SH(/dev/dvb/adapter2/frontend0): RemoveListener(0x7f2cc40437a0) -- locked
2016-06-28 19:10:05.688947 I  SH(/dev/dvb/adapter2/frontend0): RemoveListener(0x7f2cc40437a0) -- end
2016-06-28 19:10:05.688955 I  DVBSigMon[14](/dev/dvb/adapter2/frontend0): Stop() -- end
2016-06-28 19:10:05.688995 I  TVRec[14]: TeardownSignalMonitor() -- end
2016-06-28 19:10:05.689003 I  TVRec[14]: ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder, @ tv_rec.cpp:4196
2016-06-28 19:10:05.689010 I  TVRec[14]: ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder, @ tv_rec.cpp:4198
2016-06-28 19:10:05.689401 I  TVRec[14]: Starting Recorder
2016-06-28 19:10:05.690593 I  TVRec[14]: Using profile 'Default' to record
2016-06-28 19:10:05.690600 I  TVRec[14]: rec->GetPathname(): '/myth/tv/23806_20160628171000.ts'
2016-06-28 19:10:05.690740 I  TVRec[14]: TuningNewRecorder - CreateRecorder()
2016-06-28 19:10:05.690804 I  DTVRec[14]: ResetForNewFile(void)
2016-06-28 19:10:05.690844 I  RecBase[14](/dev/dvb/adapter2/frontend0): SetRingBuffer(0x7f2cc4010810) '/myth/tv/23806_20160628171000.ts'
2016-06-28 19:10:05.690853 I  RecBase[14](/dev/dvb/adapter2/frontend0): SetRecording(0x7f2ca001b240) title(55 (TVN24 HD))
2016-06-28 19:10:05.691452 I  MPEGStream[14](0x7f2cc40437a0): SetDesiredProgram(15826)
2016-06-28 19:10:05.691463 I  DTVRec[14]: SetPAT(15826 on 0x39)
2016-06-28 19:10:05.691573 I  DTVRec[14]: SetPMT(15826, valid)
2016-06-28 19:10:05.691577 I  DTVRec[14]: SetPMT(15826)
2016-06-28 19:10:05.692481 I  DTVRec[14]: ResetForNewFile(void)
2016-06-28 19:10:05.692506 I  DVBRec[14](/dev/dvb/adapter2/frontend0): Card opened successfully
2016-06-28 19:10:05.692576 I  SH(/dev/dvb/adapter2/frontend0): AddListener(0x7f2cc40437a0) -- begin
2016-06-28 19:10:05.692582 I  SH(/dev/dvb/adapter2/frontend0): AddListener(0x7f2cc40437a0) -- locked
2016-06-28 19:10:05.697643 I  TVRec[14]: SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4466
2016-06-28 19:10:05.697676 I  TVRec[14]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4468
2016-06-28 19:10:05.701365 I  DevRdB(/dev/dvb/adapter2/frontend0): buffer size 32900 KB
2016-06-28 19:10:05.701378 I  DevRdB(/dev/dvb/adapter2/frontend0): Start() -- begin
2016-06-28 19:10:05.701433 I  DevRdB(/dev/dvb/adapter2/frontend0): Start() -- middle
2016-06-28 19:10:05.701442 I  DevRdB(/dev/dvb/adapter2/frontend0): Start() -- end
2016-06-28 19:10:05.729235 I  DTVRec[14]: SetPMT(15817, valid)
2016-06-28 19:10:05.729275 I  DTVRec[14]: SetPMT(15816, valid)
2016-06-28 19:10:05.729312 I  DTVRec[14]: SetPMT(15813, valid)
2016-06-28 19:10:05.729339 I  DTVRec[14]: SetPMT(15815, valid)
2016-06-28 19:10:05.729366 I  DTVRec[14]: SetPMT(15814, valid)
2016-06-28 19:10:05.729395 I  DTVRec[14]: SetPMT(15850, valid)
2016-06-28 19:10:05.729460 I  DTVRec[14]: SetPMT(15851, valid)
2016-06-28 19:10:05.729488 I  DTVRec[14]: SetPMT(15845, valid)
2016-06-28 19:10:05.729523 E  AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0xc1, continuity counter: 13 (expected 1).
2016-06-28 19:10:05.729571 I  DTVRec[14]: SetPMT(15818, valid)
2016-06-28 19:10:05.754421 I  DTVRec[14]: SetPMT(15831, valid)
2016-06-28 19:10:05.792699 I  SH(/dev/dvb/adapter2/frontend0): AddListener(0x7f2cc40437a0) -- end
2016-06-28 19:10:05.804761 I  DTVRec[14]: SetPMT(15828, valid)
2016-06-28 19:10:05.804797 I  DTVRec[14]: SetPMT(15825, valid)
2016-06-28 19:10:05.804847 I  DTVRec[14]: SetPMT(15827, valid)
2016-06-28 19:10:05.829683 I  DTVRec[14]: SetPMT(15821, valid)
2016-06-28 19:10:05.829727 I  DTVRec[14]: SetPMT(15823, valid)
2016-06-28 19:10:05.829764 I  DTVRec[14]: SetPMT(15822, valid)
2016-06-28 19:10:05.829826 I  DTVRec[14]: SetPMT(15824, valid)
2016-06-28 19:10:05.829900 I  DTVRec[14]: SetPMT(15830, valid)
2016-06-28 19:10:06.329109 I  DTVRec[14]: FindH264Keyframes: timescale: 50, tick: 1, framerate: 25000
2016-06-28 19:10:06.329433 I  DTVRec[14]: PID 0x217 Found Payload Start
2016-06-28 19:10:06.329753 I  DTVRec[14]: PID 0x352 Found Payload Start
2016-06-28 19:10:06.329805 I  DTVRec[14]: PID 0x353 Found Payload Start
2016-06-28 19:10:06.356086 N  AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2016-06-28 19:10:06.356616 I  Tuning recording: "55 (TVN24 HD)":"2016-06-28 19:10:00": channel 23806 on cardid 14, sourceid 8
2016-06-28 19:10:06.357139 I  Reschedule requested for CHECK -9 19377 0 UpdateRecStatus2 | 54 (TVN Fabula HD) | 2016-06-28 19:10:00 |  | 
2016-06-28 19:10:06.357176 I  Updating status for "55 (TVN24 HD)":"2016-06-28 19:10:00" on cardid 14 (Strojenie => Nagrywam)
2016-06-28 19:10:06.420074 I  Reschedule interrupted, will retry
2016-06-28 19:10:06.420129 I  Reschedule requested for PLACE Interrupted
2016-06-28 19:10:06.480354 I  TVRec[13]: StartRecording("54 (TVN Fabula HD)":"2016-06-28 19:10:00")
2016-06-28 19:10:06.480371 I  TVRec[13]: SetRecordingStatus(Strojenie->Zaniechane) on line 433
2016-06-28 19:10:06.482288 E  RecordingInfo::InsertProgram(ProgramInfo(18520_20160628171000.ts): channame(TVN Fabula HD) startts(Tue Jun 28 17:10:00 2016 GMT) endts(Tue Jun 28 17:15:00 2016 GMT)
             recstartts(Tue Jun 28 17:10:00 2016 GMT) recendts(Tue Jun 28 17:15:00 2016 GMT)
             title(54 (TVN Fabula HD))): recording already exists...
2016-06-28 19:10:06.500499 I  TVRec[13]: StartedRecording(18520_2016-06-28T17:10:01Z) fn(/myth/tv/18520_20160628171001.ts)
2016-06-28 19:10:06.501471 I  TVRec[13]: Using profile 'Default' to record
2016-06-28 19:10:06.501900 I  TVRec[13]: ClearFlags(CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:622
2016-06-28 19:10:06.501920 I  TVRec[13]: SetRecordingStatus(Zaniechane->Strojenie) on line 625
2016-06-28 19:10:06.502002 I  TVRec[13]: Changing from None to RecordingOnly
2016-06-28 19:10:06.502036 I  TVRec[13]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:1431
2016-06-28 19:10:06.502082 I  TVRec[13]: HandleTuning Request: Program(ProgramInfo(18520_20160628171001.ts): channame(TVN Fabula HD) startts(Tue Jun 28 17:10:00 2016 GMT) endts(Tue Jun 28 17:15:00 2016 GMT)
             recstartts(Tue Jun 28 17:10:01 2016 GMT) recendts(Tue Jun 28 17:15:00 2016 GMT)
             title(54 (TVN Fabula HD))) channel() input() flags(Recording,)
2016-06-28 19:10:06.502364 I  TVRec[13]: TuningShutdowns(Program(ProgramInfo(18520_20160628171001.ts): channame(TVN Fabula HD) startts(Tue Jun 28 17:10:00 2016 GMT) endts(Tue Jun 28 17:15:00 2016 GMT)
             recstartts(Tue Jun 28 17:10:01 2016 GMT) recendts(Tue Jun 28 17:15:00 2016 GMT)
             title(54 (TVN Fabula HD))) channel(54) input(DVBInput) flags(Recording,))
2016-06-28 19:10:06.502494 I  TVRec[13]: HW Tuner: 13->13
2016-06-28 19:10:06.502506 I  TVRec[13]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop, @ tv_rec.cpp:3810
2016-06-28 19:10:06.502513 I  TVRec[13]: No recorder yet, calling TuningFrequency
2016-06-28 19:10:06.502517 I  TVRec[13]: TuningFrequency
2016-06-28 19:10:06.502638 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Opening DVB channel
2016-06-28 19:10:06.502645 I  DTVChan[13](/dev/dvb/adapter2/frontend0): SetChannelByString(54): 
2016-06-28 19:10:06.502764 I  CardUtil:   Group ID 4
2016-06-28 19:10:06.502896 I  CardUtil:   Group ID 7
2016-06-28 19:10:06.503014 I  CardUtil:   Input ID 14
2016-06-28 19:10:06.503018 I  CardUtil:   Input ID 15
2016-06-28 19:10:06.503021 I  CardUtil:   Input ID 16
2016-06-28 19:10:06.503025 I  CardUtil:   Input ID 17
2016-06-28 19:10:06.503028 I  CardUtil:   Input ID 18
2016-06-28 19:10:06.503032 I  CardUtil:   Input ID 19
2016-06-28 19:10:06.503035 I  CardUtil:   Input ID 20
2016-06-28 19:10:06.503038 I  CardUtil:   Input ID 21
2016-06-28 19:10:06.503041 I  CardUtil:   Input ID 22
2016-06-28 19:10:06.503545 I  ChannelBase[13]: Input is free on 16/23806
2016-06-28 19:10:06.503906 E  DTVChan[13](/dev/dvb/adapter2/frontend0): SetChannelByString(54): Requested channel '54' is not available because the tuner is currently in use on another transport.
2016-06-28 19:10:06.503913 E  TVRec[13]: Failed to set channel to 54. Reverting to kState_None
2016-06-28 19:10:06.503943 I  TVRec[13]: Changing from RecordingOnly to None
2016-06-28 19:10:06.504070 I  TVRec[13]: EIT active scan is in 10min. window. If enabled, will (re)start after 192 sec.
2016-06-28 19:10:06.504079 I  TVRec[13]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:1431
2016-06-28 19:10:06.504088 I  TVRec[13]: HandleTuning Request: Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,)
2016-06-28 19:10:06.504095 I  TVRec[13]: TuningShutdowns(Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,))
2016-06-28 19:10:06.504103 I  TVRec[13]: TeardownRecorder()
2016-06-28 19:10:06.504109 I  TVRec[13]: ClearFlags(NeedToStartRecorder,RecorderRunning,) -> RunMainLoop, @ tv_rec.cpp:1223
2016-06-28 19:10:06.504324 I  TVRec[13]: FinishedRecording(18520_2016-06-28T17:10:01Z) Bad quality
			title: 54 (TVN Fabula HD)
			in recgroup: 1 - Goska status: Recorder Failed:Recorder Failed not_dummy finished_now
2016-06-28 19:10:06.504925 I  SaveVideoProperties(0x3a, 0x20)
2016-06-28 19:10:06.505579 I  TVRec[13]: FinishedRecording -- UPDATE_RECORDING_STATUS: Recorder Failed
2016-06-28 19:10:06.505672 I  Updating status for "54 (TVN Fabula HD)":"2016-06-28 19:10:00" on cardid 13 (Strojenie => Recorder Failed)
2016-06-28 19:10:06.505854 I  DVBChan[13](/dev/dvb/adapter2/frontend0): Closing DVB channel
2016-06-28 19:10:06.506024 I  TVRec[13]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop, @ tv_rec.cpp:3810
2016-06-28 19:10:06.506099 E  Failed to change end time on card 13 to 2016-06-28T17:15:00Z
2016-06-28 19:10:06.507627 I  Reschedule interrupted, will retry
2016-06-28 19:10:06.507676 I  Reschedule requested for CHECK -9 19377 0 UpdateRecStatus2 | 54 (TVN Fabula HD) | 2016-06-28 19:10:00 |  | 
2016-06-28 19:10:06.508023 I  Reschedule requested for PLACE Interrupted
2016-06-28 19:10:06.569199 I  Scheduled 2 items in 0.0 = 0.00 match + 0.00 check + 0.01 place
2016-06-28 19:10:06.807983 E  ProgramInfo(18520_20160628170101.ts): GetPlaybackURL: '18520_20160628170101.ts' should be local, but it can not be found.
2016-06-28 19:10:11.358814 N  AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min




More information about the mythtv-dev mailing list