Mailing List Archive

to gigem: is multirec on dvb-s supposed to work in current master?
@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


_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: to gigem: is multirec on dvb-s supposed to work in current master? [ In reply to ]
On Tue, Jun 28, 2016 at 07:16:50PM +0200, Piotr Oniszczuk wrote:
> @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.

[logs deleted]

Legacy multirec shouldn't be affected. What version are you running,
aka what is the "mythbackend --version" output? It sounds like you
might not have the very latest since what you describe should already
be fixed.

David
--
David Engel
david@istwok.net
_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: to gigem: is multirec on dvb-s supposed to work in current master? [ In reply to ]
On Tue, Jun 28, 2016 at 01:05:03PM -0500, David Engel wrote:
> On Tue, Jun 28, 2016 at 07:16:50PM +0200, Piotr Oniszczuk wrote:
> > @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.
>
> [logs deleted]
>
> Legacy multirec shouldn't be affected. What version are you running,
> aka what is the "mythbackend --version" output? It sounds like you
> might not have the very latest since what you describe should already
> be fixed.

Nevermind. It's fixed now.

David
--
David Engel
david@istwok.net
_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: to gigem: is multirec on dvb-s supposed to work in current master? [ In reply to ]
> Wiadomość napisana przez David Engel <david@istwok.net> w dniu 28.06.2016, o godz. 20:47:
>
>>
>> be fixed.
>
> Nevermind. It's fixed now.
>
> David

David,
Perfect. This issue is now fixed!

I have however another Q: with recent changes every channel change is the same in contact: within the same mplex vs. between different mplexes.

Before - on my DVB-S tuners:
- channel change within the same molex was around 1-2sec and without ~0.5sec black screen
- channel change between different mplexes was 3-5sec with short black screen.

Now:
- every channel change has 3-5sec (or maybe even little longer) and always black screen.

If this is work-in-progress - forget this report.

If not - let me know how can I help with debugging/gathering more useful info for You info to improve situation.

br


_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: to gigem: is multirec on dvb-s supposed to work in current master? [ In reply to ]
On Wed, Jun 29, 2016 at 09:52:44AM +0200, Piotr Oniszczuk wrote:
>
> > Wiadomość napisana przez David Engel <david@istwok.net> w dniu 28.06.2016, o godz. 20:47:
> >
> >>
> >> be fixed.
> >
> > Nevermind. It's fixed now.
> >
> > David
>
> David,
> Perfect. This issue is now fixed!

Good.

> I have however another Q: with recent changes every channel change is the same in contact: within the same mplex vs. between different mplexes.
>
> Before - on my DVB-S tuners:
> - channel change within the same molex was around 1-2sec and without ~0.5sec black screen
> - channel change between different mplexes was 3-5sec with short black screen.
>
> Now:
> - every channel change has 3-5sec (or maybe even little longer) and always black screen.
>
> If this is work-in-progress - forget this report.
>
> If not - let me know how can I help with debugging/gathering more useful info for You info to improve situation.

It is a work in progress, but current behavior shouldn't change. I'll
have to look into it.

David
--
David Engel
david@istwok.net
_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: to gigem: is multirec on dvb-s supposed to work in current master? [ In reply to ]
> Wiadomość napisana przez David Engel <david@istwok.net> w dniu 29.06.2016, o godz. 16:38:
>
> On Wed, Jun 29, 2016 at 09:52:44AM +0200, Piotr Oniszczuk wrote:
>>
>>> Wiadomość napisana przez David Engel <david@istwok.net> w dniu 28.06.2016, o godz. 20:47:
>>>
>>>>
>>>> be fixed.
>>>
>>> Nevermind. It's fixed now.
>>>
>>> David
>>
>> David,
>> Perfect. This issue is now fixed!
>
> Good.
>

David,

FYI: current master not allows me to change between HLS channels.

Namelly:
sat_ch1->hls_ch1 - works
sat_ch1->hls_ch1->hls_ch2 - not works
sat_ch1->hls_ch1->sat_ch1->hls_ch2 - works

it is independent on reclimit for his recorder.

I have defined single hls recorder (id=21)

below is log from BE when I’m trying to change from his1 channel to hls2 (around 2016-07-05 10:02:36.551673)
It looks like his recorder input is excluded during his channel change time


2016-07-05 10:00:52.047894 N Expiring 0 MB for 23806 at 2016-07-05T07:55:47Z => "Wstajesz i wiesz"
2016-07-05 10:00:55.079301 N DeleteRecordedFiles - recording id 59458 filename /myth/tv/23806_20160705075547.ts
2016-07-05 10:00:55.079845 N DoDeleteINDB - recording id 59458 (chanid 23806 at 2016-07-05T07:55:47Z)
2016-07-05 10:00:59.282889 C ProgramInfo(): Failed to find recorded entry for 59458.
2016-07-05 10:01:45.224765 I MainServer: Excluding input 17
2016-07-05 10:01:45.231985 I MainServer: Input 18 is available on 0/0/0/4
2016-07-05 10:01:45.232001 I MainServer: Input 19 is available on 0/0/0/4
2016-07-05 10:01:45.232012 I MainServer: Input 20 is available on 0/0/0/4
2016-07-05 10:01:45.232021 I MainServer: Input 13 is available on 0/0/0/4
2016-07-05 10:01:45.232030 I MainServer: Input 14 is available on 0/0/0/4
2016-07-05 10:01:45.232039 I MainServer: Input 15 is available on 0/0/0/4
2016-07-05 10:01:45.232050 I MainServer: Input 16 is available on 0/0/0/4
2016-07-05 10:01:45.232059 I MainServer: Input 9 is available on 0/0/0/4
2016-07-05 10:01:45.232068 I MainServer: Input 10 is available on 0/0/0/4
2016-07-05 10:01:45.232077 I MainServer: Input 11 is available on 0/0/0/4
2016-07-05 10:01:45.232085 I MainServer: Input 12 is available on 0/0/0/4
2016-07-05 10:01:45.232100 I MainServer: Input 5 is available on 0/0/0/4
2016-07-05 10:01:45.232111 I MainServer: Input 6 is available on 0/0/0/4
2016-07-05 10:01:45.232120 I MainServer: Input 7 is available on 0/0/0/4
2016-07-05 10:01:45.232132 I MainServer: Input 8 is available on 0/0/0/4
2016-07-05 10:01:45.232141 I MainServer: Input 1 is available on 0/0/0/4
2016-07-05 10:01:45.232151 I MainServer: Input 2 is available on 0/0/0/4
2016-07-05 10:01:45.232160 I MainServer: Input 3 is available on 0/0/0/4
2016-07-05 10:01:45.232170 I MainServer: Input 4 is available on 0/0/0/4
2016-07-05 10:01:45.232181 I MainServer: Input 21 is available on 0/0/0/4
2016-07-05 10:01:45.234121 I MainServer: Excluding input 17
2016-07-05 10:01:45.240533 I MainServer: Input 18 is available on 0/0/0/4
2016-07-05 10:01:45.240547 I MainServer: Input 19 is available on 0/0/0/4
2016-07-05 10:01:45.240557 I MainServer: Input 20 is available on 0/0/0/4
2016-07-05 10:01:45.240566 I MainServer: Input 13 is available on 0/0/0/4
2016-07-05 10:01:45.240575 I MainServer: Input 14 is available on 0/0/0/4
2016-07-05 10:01:45.240583 I MainServer: Input 15 is available on 0/0/0/4
2016-07-05 10:01:45.240593 I MainServer: Input 16 is available on 0/0/0/4
2016-07-05 10:01:45.240601 I MainServer: Input 9 is available on 0/0/0/4
2016-07-05 10:01:45.240610 I MainServer: Input 10 is available on 0/0/0/4
2016-07-05 10:01:45.240618 I MainServer: Input 11 is available on 0/0/0/4
2016-07-05 10:01:45.240633 I MainServer: Input 12 is available on 0/0/0/4
2016-07-05 10:01:45.240647 I MainServer: Input 5 is available on 0/0/0/4
2016-07-05 10:01:45.240656 I MainServer: Input 6 is available on 0/0/0/4
2016-07-05 10:01:45.240667 I MainServer: Input 7 is available on 0/0/0/4
2016-07-05 10:01:45.240676 I MainServer: Input 8 is available on 0/0/0/4
2016-07-05 10:01:45.240687 I MainServer: Input 1 is available on 0/0/0/4
2016-07-05 10:01:45.240695 I MainServer: Input 2 is available on 0/0/0/4
2016-07-05 10:01:45.240706 I MainServer: Input 3 is available on 0/0/0/4
2016-07-05 10:01:45.240715 I MainServer: Input 4 is available on 0/0/0/4
2016-07-05 10:01:45.240725 I MainServer: Input 21 is available on 0/0/0/4
2016-07-05 10:01:45.805403 I TVRec[17]: StopLiveTV(void) curRec: 0x7f35e02c63a0 pseudoRec: 0x0
2016-07-05 10:01:45.816354 I TVRec[17]: Changing from WatchingLiveTV to None
2016-07-05 10:01:45.816399 I TVRec[17]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:1431
2016-07-05 10:01:45.816412 I TVRec[17]: HandleTuning Request: Program(NULL) channel() input() flags(KillRec,KillRingBuffer,)
2016-07-05 10:01:45.816421 I TVRec[17]: TuningShutdowns(Program(NULL) channel() input() flags(KillRec,KillRingBuffer,))
2016-07-05 10:01:45.816429 I TVRec[17]: TeardownRecorder(kFlagKillRec)
2016-07-05 10:01:45.816491 I SH(/dev/dvb/adapter8/frontend0): RemoveListener(0x7f370801a190) -- begin
2016-07-05 10:01:45.816501 I SH(/dev/dvb/adapter8/frontend0): RemoveListener(0x7f370801a190) -- locked
2016-07-05 10:01:45.828773 I DevRdB(/dev/dvb/adapter8/frontend0): Stop() -- begin
2016-07-05 10:01:45.828848 I DevRdB(/dev/dvb/adapter8/frontend0): Stop() -- end
2016-07-05 10:01:45.828853 I DevRdB(/dev/dvb/adapter8/frontend0): Stop() -- begin
2016-07-05 10:01:45.828856 I DevRdB(/dev/dvb/adapter8/frontend0): Stop() -- end
2016-07-05 10:01:45.830695 I SH(/dev/dvb/adapter8/frontend0): RemoveListener(0x7f370801a190) -- end
2016-07-05 10:01:45.830711 I DVBRec[17](/dev/dvb/adapter8/frontend0): Close() -- begin
2016-07-05 10:01:45.830735 I DVBRec[17](/dev/dvb/adapter8/frontend0): Close() -- end
2016-07-05 10:01:45.832200 I SaveVideoProperties(0x04, 0x04)
2016-07-05 10:01:45.836556 N Finished Recording: Container: MPEG2-TS Video Codec: h264 (1920x1088 A/R: 3 25fps) Audio Codec: mp2
2016-07-05 10:01:45.836644 I TVRec[17]: ClearFlags(NeedToStartRecorder,RecorderRunning,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:1223
2016-07-05 10:01:45.836708 I RecordingQuality() start(2016-07-05T08:00:00Z) end(2016-07-05T10:00:00Z) score(0)
2016-07-05 10:01:45.836893 I RecBase[17](/dev/dvb/adapter8/frontend0): SetRecording(0x0)
2016-07-05 10:01:45.837388 I TVRec[17]: FinishedRecording(23806_2016-07-05T08:00:00Z) damaged recq:<RecordingQuality overall_score="0" key="23806_2016-07-05T08:00:00Z" countinuity_error_count="0" packet_count="398787">
<Gap start="2016-07-05T08:01:44Z" end="2016-07-05T10:00:00Z" duration="7095" />
</RecordingQuality>
2016-07-05 10:01:45.837428 I TVRec[17]: FinishedRecording(23806_2016-07-05T08:00:00Z) Bad quality
title: Serwis informacyjny, Prognoza pogody
in recgroup: LiveTV status: Recorder Failed:Recorder Failed not_dummy finished_now
2016-07-05 10:01:45.837746 I Chain: Updated endtime for '23806_20160705080000' to 20160705080145
2016-07-05 10:01:45.838406 I SaveVideoProperties(0x3a, 0x32)
2016-07-05 10:01:45.838896 I ProgramInfo(23806_20160705080000.ts): RI Filesize=0, DB Filesize=75369388
2016-07-05 10:01:45.840257 I DVBChan[17](/dev/dvb/adapter8/frontend0): Closing DVB channel
2016-07-05 10:01:45.840548 I TVRec[17]: Tearing down RingBuffer
2016-07-05 10:01:45.840737 I TVRec[17]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:3810
2016-07-05 10:01:45.857994 I TVRec[21]: Changing from None to WatchingLiveTV
2016-07-05 10:01:45.858024 I TVRec[21]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:1431
2016-07-05 10:01:45.858035 I TVRec[21]: HandleTuning Request: Program(NULL) channel() input() flags(LiveTV,)
2016-07-05 10:01:45.858281 I CardUtil: Group ID 11
2016-07-05 10:01:45.858660 I ChannelBase[21]: Input is free on 0/0
2016-07-05 10:01:45.858861 I TVRec[21]: TuningShutdowns(Program(NULL) channel(301) input() flags(LiveTV,))
2016-07-05 10:01:45.859234 I TVRec[21]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:3810
2016-07-05 10:01:45.859243 I TVRec[21]: No recorder yet, calling TuningFrequency
2016-07-05 10:01:45.859246 I TVRec[21]: TuningFrequency
2016-07-05 10:01:45.859470 I IPTVChan[21]: Open()
2016-07-05 10:01:45.862490 I ChannelBase[21]: Input #21: 'MPEG2TS' schan(301) sourceid(10)
2016-07-05 10:01:45.862512 I IPTVChan[21]: SetStreamData(0x7f36f0015170) StreamHandler(0x0)
2016-07-05 10:01:45.862517 I IPTVChan[21]: Creating HLSStreamHandler
2016-07-05 10:01:45.862629 I HLSSH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): run() -- begin
2016-07-05 10:01:45.862641 I HLSReader: Throttle(false)
2016-07-05 10:01:45.862647 I HLSReader: Opening 'http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on'
2016-07-05 10:01:45.862655 I HLSSH: Creating new stream handler http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0) for [data]http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on[fectype][fec0][fec1]
2016-07-05 10:01:45.862663 I SH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): AddListener(0x7f36f0015170) -- begin
2016-07-05 10:01:45.862668 I SH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): AddListener(0x7f36f0015170) -- locked
2016-07-05 10:01:45.862674 I SH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): AddListener(0x7f36f0015170) -- end
2016-07-05 10:01:45.862684 I DTVChan[21](http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): SetChannelByString(301):
2016-07-05 10:01:45.862854 I CardUtil: Group ID 11
2016-07-05 10:01:45.863035 I ChannelBase[21]: Input is free on 0/0
2016-07-05 10:01:45.950271 I Loaded [data]http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8[fectype][fec0][fec1] for 10304
2016-07-05 10:01:45.950306 I IPTVChan[21]: Tune([data]http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8[fectype][fec0][fec1])
2016-07-05 10:01:45.950327 I IPTVChan[21]: CloseStreamHandler()
2016-07-05 10:01:45.950334 I SH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): RemoveListener(0x7f36f0015170) -- begin
2016-07-05 10:01:45.950340 I SH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): RemoveListener(0x7f36f0015170) -- locked
2016-07-05 10:01:45.950413 E HLSReader: Open 'http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on': not a valid playlist
2016-07-05 10:01:46.450631 I HLSReader: Throttle(false)
2016-07-05 10:01:46.450675 I HLSSH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): run() -- done
2016-07-05 10:01:46.450766 I SH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): RemoveListener(0x7f36f0015170) -- end
2016-07-05 10:01:46.450781 I HLSSH: Return(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)) has 1 handlers
2016-07-05 10:01:46.450787 I HLSSH: Closing handler for http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)
2016-07-05 10:01:46.450794 I HLSSH(http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): dtor
2016-07-05 10:01:46.450800 I HLSReader: dtor -- start
2016-07-05 10:01:46.450804 I HLSReader: Close -- start
2016-07-05 10:01:46.450808 I HLSReader: Cancel -- start
2016-07-05 10:01:46.450813 I HLSReader: Cancel -- done
2016-07-05 10:01:46.450819 I HLSReader: Close -- end
2016-07-05 10:01:46.450823 I HLSReader: dtor -- end
2016-07-05 10:01:46.450847 I IPTVChan[21]: SetStreamData(0x7f36f0015170) StreamHandler(0x0)
2016-07-05 10:01:46.450853 I IPTVChan[21]: Creating HLSStreamHandler
2016-07-05 10:01:46.451010 I HLSSH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): run() -- begin
2016-07-05 10:01:46.451025 I HLSReader: Throttle(false)
2016-07-05 10:01:46.451031 I HLSReader: Opening 'http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8'
2016-07-05 10:01:46.451081 I HLSSH: Creating new stream handler http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0) for [data]http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8[fectype][fec0][fec1]
2016-07-05 10:01:46.451094 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0015170) -- begin
2016-07-05 10:01:46.451101 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0015170) -- locked
2016-07-05 10:01:46.451110 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0015170) -- end
2016-07-05 10:01:46.451124 I IPTVChan[21]: Tuned to ([data]http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8[fectype][fec0][fec1])
2016-07-05 10:01:46.451130 I DTVChan[21](http://nasatv-lh.akamaihd.net/i/NASA_101@319270/index_1000_av-p.m3u8?sd=10&rebase=on(0)): SetChannelByString(301): success
2016-07-05 10:01:46.451142 I TVRec[21]: CreateLiveTVRingBuffer(301)
2016-07-05 10:01:46.451582 I TVRec[21]: GetProgramRingBufferForLiveTV()
2016-07-05 10:01:46.455224 N AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2016-07-05 10:01:46.465854 I HLSReader: Adding new stream 'http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8'
2016-07-05 10:01:46.465994 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Selected stream with 0 bitrate
2016-07-05 10:01:46.466036 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Open -- end
2016-07-05 10:01:46.466096 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8 playlist: run -- begin
2016-07-05 10:01:46.466185 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Throttle(true)
2016-07-05 10:01:46.466211 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8 worker: run -- begin
2016-07-05 10:01:46.531085 W LoadFromProgram(): SQL contains LIMIT clause, caller should be updated to use limit parameter instead
2016-07-05 10:01:46.536312 I TVRec[21]: StartedRecording(10304_2016-07-05T08:01:46Z) fn(/myth/tv/10304_20160705080146.ts)
2016-07-05 10:01:46.537260 N ApplyRecordRecGroupChange: LiveTV to LiveTV (2)
2016-07-05 10:01:46.537516 I Chain: Appended@3 '10304_20160705080146'
2016-07-05 10:01:46.538356 I TVRec[21]: Starting Signal Monitor
2016-07-05 10:01:46.538363 I TVRec[21]: SetupSignalMonitor(1, 1)
2016-07-05 10:01:46.538369 I IPTVChan[21]: Open()
2016-07-05 10:01:46.538413 I IPTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): ctor
2016-07-05 10:01:46.538418 I TVRec[21]: Signal monitor successfully created
2016-07-05 10:01:46.538423 I TVRec[21]: Setting up table monitoring.
2016-07-05 10:01:46.539377 I TVRec[21]: Using profile 'Live TV' to record
2016-07-05 10:01:46.539393 I TVRec[21]: MPEG program number: 0
2016-07-05 10:01:46.539413 I IPTVChan[21]: SetStreamData(0x7f36f0013f50) StreamHandler(0x7f36f001aaf0)
2016-07-05 10:01:46.539418 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0013f50) -- begin
2016-07-05 10:01:46.539422 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0013f50) -- locked
2016-07-05 10:01:46.539428 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0013f50) -- end
2016-07-05 10:01:46.539432 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): RemoveListener(0x7f36f0015170) -- begin
2016-07-05 10:01:46.539436 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): RemoveListener(0x7f36f0015170) -- locked
2016-07-05 10:01:46.539441 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): RemoveListener(0x7f36f0015170) -- end
2016-07-05 10:01:46.539452 I DTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0))::SetProgramNumber(0):
2016-07-05 10:01:46.539462 I MPEGStream[21](0x7f36f0013f50): SetDesiredProgram(0)
2016-07-05 10:01:46.539475 I TVRec[21]: Successfully set up MPEG table monitoring.
2016-07-05 10:01:46.539583 I TVRec[21]: SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3986
2016-07-05 10:01:46.539585 I IPTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): UpdateValues: start sigmon
2016-07-05 10:01:46.539593 I TVRec[21]: ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3987
2016-07-05 10:01:46.539593 I IPTVChan[21]: SetStreamData(0x7f36f0013f50) StreamHandler(0x7f36f001aaf0)
2016-07-05 10:01:46.539601 I TVRec[21]: SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3992
2016-07-05 10:01:46.539611 I TVRec[21]: SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4036
2016-07-05 10:01:46.539614 I DummyDTVRecorder -- started
2016-07-05 10:01:46.539620 I TVRec[21]: SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4038
2016-07-05 10:01:46.539627 I TVRec[21]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4048
2016-07-05 10:01:46.539635 I TVRec[21]: SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4050
2016-07-05 10:01:46.539658 I TVRec[21]: TuningSignalCheck: Still waiting. Will timeout @ 10:30:00.000
2016-07-05 10:01:46.539727 I TVRec[21]: SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:2814
2016-07-05 10:01:46.603073 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Throttling -- sleeping 2 secs.
2016-07-05 10:01:46.716502 I IPTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): HandlePAT pn: 0
2016-07-05 10:01:46.716547 E DTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): Program #0 not found in PAT!
Program Association Section
PSIP tableID(0x0) length(13) extension(0x1)
version(0) current(1) section(0) last_section(0)
tsid(1) programCount(1)
program number 1 has PID 0x01e0
2016-07-05 10:01:46.716557 E DTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): But there is only one program in the PAT, so we'll just use it
2016-07-05 10:01:46.716569 I DTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0))::SetProgramNumber(1):
2016-07-05 10:01:46.716592 I MPEGStream[21](0x7f36f0013f50): SetDesiredProgram(1)
2016-07-05 10:01:46.716604 I IPTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): HandlePAT pn: 1
2016-07-05 10:01:46.716647 W MPEGStream[21](0x7f36f0013f50): ProcessPAT: PAT is missing program, setting timeout
2016-07-05 10:01:46.740239 I TVRec[21]: TuningSignalCheck: Good signal
2016-07-05 10:01:46.740260 I TVRec[21]: SetRecordingStatus(Nagrywam->Nagrywam) on line 4170
2016-07-05 10:01:46.740307 I TVRec[21]: TeardownSignalMonitor() -- begin
2016-07-05 10:01:46.740325 I IPTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): dtor
2016-07-05 10:01:46.740333 I IPTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): Stop() -- begin
2016-07-05 10:01:46.790460 I IPTVChan[21]: SetStreamData(0x0) StreamHandler(0x7f36f001aaf0)
2016-07-05 10:01:46.790473 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): RemoveListener(0x7f36f0013f50) -- begin
2016-07-05 10:01:46.790480 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): RemoveListener(0x7f36f0013f50) -- locked
2016-07-05 10:01:47.716992 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Throttle(false)
2016-07-05 10:01:47.717018 I HLSSH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): run() -- done
2016-07-05 10:01:47.717055 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Throttle aborted
2016-07-05 10:01:47.717064 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Throttle done
2016-07-05 10:01:47.717106 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): RemoveListener(0x7f36f0013f50) -- end
2016-07-05 10:01:47.717126 I IPTVSigMon[21](http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): Stop() -- end
2016-07-05 10:01:47.717157 I TVRec[21]: TeardownSignalMonitor() -- end
2016-07-05 10:01:47.717171 I TVRec[21]: ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4196
2016-07-05 10:01:47.717183 I TVRec[21]: ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, @ tv_rec.cpp:4198
2016-07-05 10:01:47.717862 I TVRec[21]: Starting Recorder
2016-07-05 10:01:47.718431 I TVRec[21]: FinishedRecording(10304_2016-07-05T08:01:46Z) Good quality
title: Unknown
in recgroup: LiveTV status: Nagrywam:Nagrane is_dummy finished_now
2016-07-05 10:01:47.718809 I Chain: Updated endtime for '10304_20160705080146' to 20160705080147
2016-07-05 10:01:47.719587 I TVRec[21]: ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, @ tv_rec.cpp:4329
2016-07-05 10:01:47.721566 I TVRec[21]: Using profile 'Live TV' to record
2016-07-05 10:01:47.721886 I TVRec[21]: SwitchLiveTVRingBuffer(discont 1, set_next_rec 0) curRec(10304_2016-07-05T08:01:46Z) curRec.size(0)
2016-07-05 10:01:47.722087 I TVRec[21]: GetProgramRingBufferForLiveTV()
2016-07-05 10:01:47.725473 N AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2016-07-05 10:01:47.806202 W LoadFromProgram(): SQL contains LIMIT clause, caller should be updated to use limit parameter instead
2016-07-05 10:01:47.813210 I TVRec[21]: StartedRecording(10304_2016-07-05T08:01:47Z) fn(/myth/tv/10304_20160705080147.ts)
2016-07-05 10:01:47.815185 N ApplyRecordRecGroupChange: LiveTV to LiveTV (2)
2016-07-05 10:01:47.815370 I Chain: Appended@4 '10304_20160705080147'
2016-07-05 10:01:47.815600 I TVRec[21]: TuningNewRecorder - CreateRecorder()
2016-07-05 10:01:47.815660 I DTVRec[21]: ResetForNewFile(void)
2016-07-05 10:01:47.815677 W RecBase[21](): SetOption(mrl,http://www.avenard.org/files/media/playlist-hls.m3u): Option not recognized
2016-07-05 10:01:47.815706 E RecBase[21](http://www.avenard.org/files/media/playlist-hls.m3u): SetStrOption(...recordingtype): Option not in profile.
2016-07-05 10:01:47.815739 I RecBase[21](http://www.avenard.org/files/media/playlist-hls.m3u): SetRingBuffer(0x7f36f0015230) '/myth/tv/10304_20160705080147.ts'
2016-07-05 10:01:47.815747 I RecBase[21](http://www.avenard.org/files/media/playlist-hls.m3u): SetRecording(0x7f36f001d7c0) title(Unknown)
2016-07-05 10:01:47.816469 I MPEGStream[21](0x7f36f0013f50): SetDesiredProgram(1)
2016-07-05 10:01:47.816479 I DTVRec[21]: SetPAT(1 on 0x1e0)
2016-07-05 10:01:47.816502 I MPEGStream[21](0x7f36f0013f50): ProcessPAT: Good PAT seen after a bad PAT
2016-07-05 10:01:47.816545 I DTVRec[21]: SetPMT(1, valid)
2016-07-05 10:01:47.816550 I DTVRec[21]: SetPMT(1)
2016-07-05 10:01:47.817230 I IPTVRec: run -- begin
2016-07-05 10:01:47.817238 I DTVRec[21]: ResetForNewFile(void)
2016-07-05 10:01:47.817247 I IPTVChan[21]: Open()
2016-07-05 10:01:47.818350 I ChannelBase[21]: Input #21: 'MPEG2TS' schan(301) sourceid(10)
2016-07-05 10:01:47.818370 I IPTVRec: opened successfully
2016-07-05 10:01:47.818376 I IPTVChan[21]: SetStreamData(0x7f36f0013f50) StreamHandler(0x7f36f001aaf0)
2016-07-05 10:01:47.818382 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0013f50) -- begin
2016-07-05 10:01:47.818386 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0013f50) -- locked
2016-07-05 10:01:47.818471 I HLSSH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): run() -- begin
2016-07-05 10:01:47.818480 I http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8: Throttle(false)
2016-07-05 10:01:47.818485 I SH(http://bcoveliveios-i.akamaihd.net/hls/live/217434/3083279840001/master_900.m3u8(0)): AddListener(0x7f36f0013f50) -- end
2016-07-05 10:01:47.821873 I DTVRec[21]: FindH264Keyframes: timescale: 50, tick: 1, framerate: 25000
2016-07-05 10:01:47.822202 I DTVRec[21]: PID 0x1e1 Found Payload Start
2016-07-05 10:01:47.822265 I TVRec[21]: SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4466
2016-07-05 10:01:47.822282 I TVRec[21]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4468
2016-07-05 10:01:47.822421 I DTVRec[21]: PID 0x1e2 Found Payload Start
2016-07-05 10:01:47.847450 I DTVRec[21]: SetPAT(1 on 0x1e0)
2016-07-05 10:01:57.628269 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 6077 ms for avail(159096) > count(16384)
2016-07-05 10:02:03.164956 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 5083 ms for avail(458752) > count(16384)
2016-07-05 10:02:09.679888 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 5856 ms for avail(131072) > count(16384)
2016-07-05 10:02:15.244887 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 5083 ms for avail(196608) > count(16384)
2016-07-05 10:02:21.743841 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 5966 ms for avail(294912) > count(16384)
2016-07-05 10:02:27.299070 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 5088 ms for avail(302316) > count(16384)
2016-07-05 10:02:33.804421 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 6032 ms for avail(130848) > count(16384)
2016-07-05 10:02:36.551673 I MainServer: Excluding input 21
2016-07-05 10:02:36.561477 I MainServer: Input 17 is available on 0/0/0/4
2016-07-05 10:02:36.561493 I MainServer: Input 18 is available on 0/0/0/4
2016-07-05 10:02:36.561504 I MainServer: Input 19 is available on 0/0/0/4
2016-07-05 10:02:36.561514 I MainServer: Input 20 is available on 0/0/0/4
2016-07-05 10:02:36.561524 I MainServer: Input 13 is available on 0/0/0/4
2016-07-05 10:02:36.561533 I MainServer: Input 14 is available on 0/0/0/4
2016-07-05 10:02:36.561545 I MainServer: Input 15 is available on 0/0/0/4
2016-07-05 10:02:36.561554 I MainServer: Input 16 is available on 0/0/0/4
2016-07-05 10:02:36.561563 I MainServer: Input 9 is available on 0/0/0/4
2016-07-05 10:02:36.561573 I MainServer: Input 10 is available on 0/0/0/4
2016-07-05 10:02:36.561582 I MainServer: Input 11 is available on 0/0/0/4
2016-07-05 10:02:36.561592 I MainServer: Input 12 is available on 0/0/0/4
2016-07-05 10:02:36.561602 I MainServer: Input 5 is available on 0/0/0/4
2016-07-05 10:02:36.561611 I MainServer: Input 6 is available on 0/0/0/4
2016-07-05 10:02:36.561619 I MainServer: Input 7 is available on 0/0/0/4
2016-07-05 10:02:36.561629 I MainServer: Input 8 is available on 0/0/0/4
2016-07-05 10:02:36.561638 I MainServer: Input 1 is available on 0/0/0/4
2016-07-05 10:02:36.561646 I MainServer: Input 2 is available on 0/0/0/4
2016-07-05 10:02:36.561655 I MainServer: Input 3 is available on 0/0/0/4
2016-07-05 10:02:36.561663 I MainServer: Input 4 is available on 0/0/0/4
2016-07-05 10:02:36.565211 I MainServer: Excluding input 21
2016-07-05 10:02:36.572042 I MainServer: Input 17 is available on 0/0/0/4
2016-07-05 10:02:36.572055 I MainServer: Input 18 is available on 0/0/0/4
2016-07-05 10:02:36.572065 I MainServer: Input 19 is available on 0/0/0/4
2016-07-05 10:02:36.572074 I MainServer: Input 20 is available on 0/0/0/4
2016-07-05 10:02:36.572083 I MainServer: Input 13 is available on 0/0/0/4
2016-07-05 10:02:36.572091 I MainServer: Input 14 is available on 0/0/0/4
2016-07-05 10:02:36.572101 I MainServer: Input 15 is available on 0/0/0/4
2016-07-05 10:02:36.572109 I MainServer: Input 16 is available on 0/0/0/4
2016-07-05 10:02:36.572117 I MainServer: Input 9 is available on 0/0/0/4
2016-07-05 10:02:36.572126 I MainServer: Input 10 is available on 0/0/0/4
2016-07-05 10:02:36.572134 I MainServer: Input 11 is available on 0/0/0/4
2016-07-05 10:02:36.572143 I MainServer: Input 12 is available on 0/0/0/4
2016-07-05 10:02:36.572151 I MainServer: Input 5 is available on 0/0/0/4
2016-07-05 10:02:36.572160 I MainServer: Input 6 is available on 0/0/0/4
2016-07-05 10:02:36.572168 I MainServer: Input 7 is available on 0/0/0/4
2016-07-05 10:02:36.572175 I MainServer: Input 8 is available on 0/0/0/4
2016-07-05 10:02:36.572183 I MainServer: Input 1 is available on 0/0/0/4
2016-07-05 10:02:36.572191 I MainServer: Input 2 is available on 0/0/0/4
2016-07-05 10:02:36.572198 I MainServer: Input 3 is available on 0/0/0/4
2016-07-05 10:02:36.572206 I MainServer: Input 4 is available on 0/0/0/4
2016-07-05 10:02:39.367734 W RingBuf(/myth/tv/10304_20160705080147.ts): ReadPriv(..16384, normal) -- waited 5083 ms for avail(294912) > count(16384)

_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: to gigem: is multirec on dvb-s supposed to work in current master? [ In reply to ]
> Wiadomość napisana przez Piotr Oniszczuk <piotr.oniszczuk@gmail.com> w dniu 29.06.2016, o godz. 09:52:
>
>
>> Wiadomość napisana przez David Engel <david@istwok.net> w dniu 28.06.2016, o godz. 20:47:
>>
>>>
>>> be fixed.
>>
>> Nevermind. It's fixed now.
>>
>> David
>
> David,
> Perfect. This issue is now fixed!
>
> I have however another Q: with recent changes every channel change is the same in contact: within the same mplex vs. between different mplexes.
>
> Before - on my DVB-S tuners:
> - channel change within the same molex was around 1-2sec and without ~0.5sec black screen
> - channel change between different mplexes was 3-5sec with short black screen.
>
> Now:
> - every channel change has 3-5sec (or maybe even little longer) and always black screen.
>
> If this is work-in-progress - forget this report.
>
> If not - let me know how can I help with debugging/gathering more useful info for You info to improve situation.
>
> br
>
>
David,

I suspect current master issue (with longer channel change times within the same mplex) is result of regression in generation list of available inputs at channel change.

Namelly:

if I have chan1 and chan2 at mplex1 and using 4 virt.tuners (id17..20) for livetv then I see following:

-old code:
starting livetv with chan1: tuner17 is selected
changing livetv from chan1 to chan2: livetv still on tuner17
changing livetv from chan2 to chan1: livetv still on tuner17

-new code:
starting livetv with chan1: tuner17 is selected
changing livetv from chan1 to chan2: livetv is on tuner18
changing livetv from chan2 to chan1: livetv is on tuner17

It looks like in new code livetv - at channel change time - excludes current tuner from candidates to tune to new channel.

This hypothesis may explain:

- why in new code channel change time within the same mplex is the same long as between mplexes (as there is full tune on new tuner instead of retune on the same tuner with the same tuning parameters. Such retune are skipped by mythtv when old and new freq. are the same giving speedup for channel change within the same mplex)

- issue with his channels change I described in other replay in this thread.


_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org
Re: to gigem: is multirec on dvb-s supposed to work in current master? [ In reply to ]
On Tue, Jul 05, 2016 at 10:27:26AM +0200, Piotr Oniszczuk wrote:
>
> > Wiadomość napisana przez Piotr Oniszczuk <piotr.oniszczuk@gmail.com> w dniu 29.06.2016, o godz. 09:52:
> >
> >
> >> Wiadomość napisana przez David Engel <david@istwok.net> w dniu 28.06.2016, o godz. 20:47:
> >>
> >>>
> >>> be fixed.
> >>
> >> Nevermind. It's fixed now.
> >>
> >> David
> >
> > David,
> > Perfect. This issue is now fixed!
> >
> > I have however another Q: with recent changes every channel change is the same in contact: within the same mplex vs. between different mplexes.
> >
> > Before - on my DVB-S tuners:
> > - channel change within the same molex was around 1-2sec and without ~0.5sec black screen
> > - channel change between different mplexes was 3-5sec with short black screen.
> >
> > Now:
> > - every channel change has 3-5sec (or maybe even little longer) and always black screen.
> >
> > If this is work-in-progress - forget this report.
> >
> > If not - let me know how can I help with debugging/gathering more useful info for You info to improve situation.
> >
> > br
> >
> >
> David,
>
> I suspect current master issue (with longer channel change times within the same mplex) is result of regression in generation list of available inputs at channel change.
>
> Namelly:
>
> if I have chan1 and chan2 at mplex1 and using 4 virt.tuners (id17..20) for livetv then I see following:
>
> -old code:
> starting livetv with chan1: tuner17 is selected
> changing livetv from chan1 to chan2: livetv still on tuner17
> changing livetv from chan2 to chan1: livetv still on tuner17
>
> -new code:
> starting livetv with chan1: tuner17 is selected
> changing livetv from chan1 to chan2: livetv is on tuner18
> changing livetv from chan2 to chan1: livetv is on tuner17
>
> It looks like in new code livetv - at channel change time - excludes current tuner from candidates to tune to new channel.
>
> This hypothesis may explain:
>
> - why in new code channel change time within the same mplex is the same long as between mplexes (as there is full tune on new tuner instead of retune on the same tuner with the same tuning parameters. Such retune are skipped by mythtv when old and new freq. are the same giving speedup for channel change within the same mplex)
>
> - issue with his channels change I described in other replay in this thread.

I believe these issues are fixed. I'm sorry it took so long. It was
actually done last week, but the changes were mixed in with other
things I was working on that weren't ready yet.

David
--
David Engel
david@istwok.net
_______________________________________________
mythtv-dev mailing list
mythtv-dev@mythtv.org
http://lists.mythtv.org/mailman/listinfo/mythtv-dev
http://wiki.mythtv.org/Mailing_List_etiquette
MythTV Forums: https://forum.mythtv.org