342 replies to this topic

[Huevos]

Ok, before I build your patch here is some debug with tuner number added.

<  2017.286> [RecordTimer] activating state 1
<  2017.287> [RecordTimer] Found enough free space to record
<  2017.288> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181014 2005 - ITV London HD - New_ The Chase_ Celebrity Special'
<  2017.288> [Navigation] recording service: 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<  2017.288> [eDVBResourceManager] allocate channel.. 0812:0002
<  2017.288> [eDVBResourceManager] available channel.. 080e:0002
<  2017.288> [eDVBFrontend] opening frontend 0
<  2017.292> [eDVBFrontend] (0)tune
<  2017.292> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x121, guard_offset 0
<  2017.292> **** Tuning JESS
<  2017.292> **** frequency_mhz: 11097
<  2017.292> **** lo_mhz: 9750
<  2017.292> **** T: 1247
<  2017.292> **** position: 0
<  2017.292> **** ub: 0
<  2017.292> **** mode: 0
<  2017.292> **** JESS: 70 04 df 00
<  2017.292> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2017.292> [eDVBFrontend] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2017.292> [eDVBFrontend] tuning to 1209 mhz
<  2017.293> [eDVBChannel] OURSTATE: tuning
<  2017.293> [eDVBServicePMTHandler] allocate Channel: res 0
<  2017.293> [eDVBCIInterfaces] addPMTHandler 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<  2017.293> [eDVBChannel] getDemux cap=00
<  2017.293> [eDVBResourceManager] allocate demux cap=00
<  2017.293> [eDVBResourceManager] allocating demux adapter=0, demux=1, source=-1 fesource=0
<  2017.293> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  2017.294> [eEPGCache] saveEventToFile epg event id 397
<  2017.294> [RecordTimer] prepare ok, waiting for begin
<  2017.297> [Trashcan] probing folders
<  2017.299> [Trashcan] found following trashcan's: ['/media/hdd/.Trash', '/media/hdd/movie/.Trash']
<  2017.299> [Trashcan] looking in trashcan /media/hdd/.Trash
<  2017.299> [Trashcan] /media/hdd/.Trash: Size: 0
<  2017.299> [Trashcan] /media/hdd/.Trash: Size now: 0
<  2017.299> [Trashcan] looking in trashcan /media/hdd/movie/.Trash
<  2017.301> [RecordTimer] activating state 1
<  2017.302> [RecordTimer] Found enough free space to record
<  2017.303> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181014 2005 - Channel 5 HD - New_ World's Most Vicious Beasts'
<  2017.303> [Navigation] recording service: 1:0:19:1E46:809:2:11A0000:0:0:0:
<  2017.304> [eDVBResourceManager] allocate channel.. 0809:0002
<  2017.304> [eDVBResourceManager] available channel.. 080e:0002
<  2017.304> [eDVBResourceManager] available channel.. 0812:0002
<  2017.304> [eDVBFrontend] opening frontend 3
<  2017.306> [eDVBFrontend] (3)tune
<  2017.306> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2935, guard_offset 0
<  2017.306> **** Tuning JESS
<  2017.306> **** frequency_mhz: 10964
<  2017.306> **** lo_mhz: 9750
<  2017.306> **** T: 1114
<  2017.306> **** position: 0
<  2017.306> **** ub: 1
<  2017.306> **** mode: 2
<  2017.306> **** JESS: 70 0c 5a 02
<  2017.306> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2017.306> [eDVBFrontend] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  2017.306> [eDVBFrontend] tuning to 1421 mhz
<  2017.306> [eDVBChannel] OURSTATE: tuning
<  2017.306> [eDVBServicePMTHandler] allocate Channel: res 0
<  2017.306> [eDVBCIInterfaces] addPMTHandler 1:0:19:1E46:809:2:11A0000:0:0:0:
<  2017.306> [eDVBChannel] getDemux cap=00
<  2017.306> [eDVBResourceManager] allocate demux cap=00
<  2017.306> [eDVBResourceManager] allocating demux adapter=0, demux=2, source=-1 fesource=3
<  2017.307> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  2017.307> [eEPGCache] saveEventToFile epg event id 435
<  2017.308> [RecordTimer] prepare ok, waiting for begin
<  2017.310> [Trashcan] Cleanup already running
<  2017.311> [RecordTimer] activating state 1
<  2017.312> [RecordTimer] Found enough free space to record
<  2017.313> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181014 2005 - BBC One HD - Doctor Who'
<  2017.313> [Navigation] recording service: 1:0:19:1B1D:802:2:11A0000:0:0:0:
<  2017.313> [eDVBResourceManager] allocate channel.. 0802:0002
<  2017.313> [eDVBResourceManager] available channel.. 080e:0002
<  2017.313> [eDVBResourceManager] available channel.. 0812:0002
<  2017.313> [eDVBResourceManager] available channel.. 0809:0002
<  2017.313> [eDVBFrontend] opening frontend 4
<  2017.316> [eDVBFrontend] (4)tune
<  2017.316> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4158, guard_offset 0
<  2017.316> **** Tuning JESS
<  2017.316> **** frequency_mhz: 10847
<  2017.316> **** lo_mhz: 9750
<  2017.316> **** T: 997
<  2017.316> **** position: 0
<  2017.316> **** ub: 2
<  2017.316> **** mode: 0
<  2017.316> **** JESS: 70 13 e5 00
<  2017.316> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2017.316> [eDVBFrontend] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2017.316> [eDVBFrontend] tuning to 1679 mhz
<  2017.316> [eDVBChannel] OURSTATE: tuning
<  2017.316> [eDVBServicePMTHandler] allocate Channel: res 0
<  2017.316> [eDVBCIInterfaces] addPMTHandler 1:0:19:1B1D:802:2:11A0000:0:0:0:
<  2017.316> [eDVBChannel] getDemux cap=00
<  2017.316> [eDVBResourceManager] allocate demux cap=00
<  2017.316> [eDVBResourceManager] allocating demux adapter=0, demux=3, source=-1 fesource=4
<  2017.316> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  2017.317> [eEPGCache] saveEventToFile epg event id ee73
<  2017.318> [RecordTimer] prepare ok, waiting for begin
<  2017.318> [Trashcan] Cleanup already running
<  2017.319> [eDVBFrontend 0] set static current limiting
<  2017.319> [eDVBFrontend 3] set static current limiting
<  2017.319> [eDVBFrontend 4] set static current limiting
<  2017.320> [eDVBFrontend 0] invalidate current switch params
<  2017.320> [eDVBFrontend 3] invalidate current switch params
<  2017.320> [eDVBFrontend 4] invalidate current switch params
<  2017.320> [eDVBFrontend 0] setVoltage 1
<  2017.321> [eDVBFrontend 3] setVoltage 1
<  2017.322> [eDVBFrontend 4] setVoltage 1
<  2017.330> [Trashcan] /media/hdd/movie/.Trash: Size: 22142349699
<  2017.335> [eDVBFrontend 0] sleep 1500ms
<  2017.335> [eDVBFrontend 3] sleep 1500ms
<  2017.335> [eDVBFrontend 4] sleep 1500ms
<  2017.348> [Trashcan] /media/hdd/movie/.Trash: Size now: 22142349699
<  2017.349> [Task] job Components.Task.Job name=Cleaning Trashes #tasks=1 completed with [] in None
<  2018.835> [eDVBFrontend 0] setVoltage 2
<  2018.836> [eDVBFrontend 3] setVoltage 2
<  2018.836> [eDVBFrontend 4] setVoltage 2
<  2018.837> [eDVBFrontend 0] setTone 0
<  2018.838> [eDVBFrontend 3] setTone 0
<  2018.839> [eDVBFrontend 4] setTone 0
<  2018.840> [eDVBFrontend 0] sleep 75ms
<  2018.840> [eDVBFrontend 3] sleep 75ms
<  2018.840> [eDVBFrontend 4] sleep 75ms
<  2018.970> [eDVBFrontend 0] sendDiseqc: 7004df00(?)
<  2019.026> [eDVBFrontend 3] sendDiseqc: 700c5a02(?)
<  2019.082> [eDVBFrontend 4] sendDiseqc: 7013e500(?)
<  2019.083> [eDVBFrontend 0] sleep 100ms
<  2019.083> [eDVBFrontend 3] sleep 100ms
<  2019.083> [eDVBFrontend 4] sleep 100ms
<  2019.183> [eDVBFrontend 0] setVoltage 1
<  2019.184> [eDVBFrontend 3] setVoltage 1
<  2019.184> [eDVBFrontend 4] setVoltage 1
<  2019.185> [eDVBFrontend 0] update current switch params
<  2019.185> [eDVBFrontend 3] update current switch params
<  2019.185> [eDVBFrontend 4] update current switch params
<  2019.185> [eDVBFrontend 0] startTuneTimeout 5000
<  2019.185> [eDVBFrontend 3] startTuneTimeout 5000
<  2019.185> [eDVBFrontend 4] startTuneTimeout 5000
<  2019.185> [eDVBFrontend 0] setFrontend 1
<  2019.185> [eDVBFrontend] setting frontend 0
<  2019.193> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  2019.193> [eDVBFrontend 3] setFrontend 1
<  2019.193> [eDVBFrontend] setting frontend 3
<  2019.193> [eDVBFrontend 4] setFrontend 1
<  2019.193> [eDVBFrontend] setting frontend 4
<  2019.193> [eDVBFrontend 0] sleep 500ms
<  2019.200> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  2019.200> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  2019.200> [eDVBFrontend 3] sleep 500ms
<  2019.200> [eDVBFrontend 4] sleep 500ms
<  2019.240> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  2019.241> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  2019.250> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  2019.693> [eDVBFrontend 0] set dynamic current limiting
<  2019.701> [eDVBFrontend 3] set dynamic current limiting
<  2019.701> [eDVBFrontend 4] set dynamic current limiting
<  2024.185> [eDVBChannel] OURSTATE: failed, retune
<  2024.185> [eDVBFrontend] (0)tune
<  2024.185> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x121, guard_offset 0
<  2024.185> **** Tuning JESS
<  2024.185> **** frequency_mhz: 11097
<  2024.185> **** lo_mhz: 9750
<  2024.185> **** T: 1247
<  2024.185> **** position: 0
<  2024.185> **** ub: 0
<  2024.185> **** mode: 0
<  2024.185> **** JESS: 70 04 df 00
<  2024.185> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2024.186> [eDVBFrontend] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2024.186> [eDVBFrontend] tuning to 1209 mhz
<  2024.186> [eDVBChannel] OURSTATE: tuning
<  2024.186> [eDVBServicePMTHandler] tune failed.
<  2024.186> [eDVBServiceRecord] RECORD service event 1
<  2024.186> [eDVBServiceRecord] record failed to tune
<  2024.186> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2024.186> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2024.186> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  2024.186> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2024.186> [eDVBChannel] OURSTATE: failed, retune
<  2024.186> [eDVBFrontend] (3)tune
<  2024.186> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2935, guard_offset 0
<  2024.186> **** Tuning JESS
<  2024.186> **** frequency_mhz: 10964
<  2024.186> **** lo_mhz: 9750
<  2024.186> **** T: 1114
<  2024.186> **** position: 0
<  2024.186> **** ub: 1
<  2024.186> **** mode: 2
<  2024.186> **** JESS: 70 0c 5a 02
<  2024.186> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2024.186> [eDVBFrontend] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  2024.186> [eDVBFrontend] tuning to 1421 mhz
<  2024.186> [eDVBChannel] OURSTATE: tuning
<  2024.186> [eDVBServicePMTHandler] tune failed.
<  2024.186> [eDVBServiceRecord] RECORD service event 1
<  2024.186> [eDVBServiceRecord] record failed to tune
<  2024.186> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2024.186> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2024.186> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  2024.186> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2024.187> [eDVBChannel] OURSTATE: failed, retune
<  2024.187> [eDVBFrontend] (4)tune
<  2024.187> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4158, guard_offset 0
<  2024.187> **** Tuning JESS
<  2024.187> **** frequency_mhz: 10847
<  2024.187> **** lo_mhz: 9750
<  2024.187> **** T: 997
<  2024.187> **** position: 0
<  2024.187> **** ub: 2
<  2024.187> **** mode: 0
<  2024.187> **** JESS: 70 13 e5 00
<  2024.187> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2024.187> [eDVBFrontend] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2024.187> [eDVBFrontend] tuning to 1679 mhz
<  2024.187> [eDVBChannel] OURSTATE: tuning
<  2024.187> [eDVBServicePMTHandler] tune failed.
<  2024.187> [eDVBServiceRecord] RECORD service event 1
<  2024.187> [eDVBServiceRecord] record failed to tune
<  2024.187> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2024.187> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2024.187> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  2024.187> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2024.187> [eDVBFrontend 0] set static current limiting
<  2024.187> [eDVBFrontend 3] set static current limiting
<  2024.187> [eDVBFrontend 4] set static current limiting
<  2024.187> [eDVBFrontend 0] invalidate current switch params
<  2024.187> [eDVBFrontend 3] invalidate current switch params
<  2024.187> [eDVBFrontend 4] invalidate current switch params
<  2024.187> [eDVBFrontend 0] set sequence pos 3
<  2024.187> [eDVBFrontend 3] set sequence pos 3
<  2024.187> [eDVBFrontend 4] set sequence pos 3
<  2024.187> [eDVBFrontend 0] setVoltage 2
<  2024.188> [eDVBFrontend 3] setVoltage 2
<  2024.189> [eDVBFrontend 4] setVoltage 2
<  2024.189> [eDVBFrontend 0] setTone 0
<  2024.190> [eDVBFrontend 3] setTone 0
<  2024.192> [eDVBFrontend 4] setTone 0
<  2024.193> [eDVBFrontend 0] sleep 75ms
<  2024.193> [eDVBFrontend 3] sleep 75ms
<  2024.193> [eDVBFrontend 4] sleep 75ms
<  2024.323> [eDVBFrontend 0] sendDiseqc: 7004df00(?)
<  2024.379> [eDVBFrontend 3] sendDiseqc: 700c5a02(?)
<  2024.435> [eDVBFrontend 4] sendDiseqc: 7013e500(?)
<  2024.435> [eDVBFrontend 0] sleep 100ms
<  2024.435> [eDVBFrontend 3] sleep 100ms
<  2024.437> [eDVBFrontend 4] sleep 100ms
<  2024.535> [eDVBFrontend 0] setVoltage 1
<  2024.536> [eDVBFrontend 3] setVoltage 1
<  2024.537> [eDVBFrontend 0] update current switch params
<  2024.537> [eDVBFrontend 3] update current switch params
<  2024.537> [eDVBFrontend 0] startTuneTimeout 5000
<  2024.537> [eDVBFrontend 3] startTuneTimeout 5000
<  2024.537> [eDVBFrontend 0] setFrontend 1
<  2024.537> [eDVBFrontend] setting frontend 0
<  2024.537> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  2024.537> [eDVBFrontend 3] setFrontend 1
<  2024.537> [eDVBFrontend] setting frontend 3
<  2024.537> [eDVBFrontend 4] setVoltage 1
<  2024.538> [eDVBFrontend 0] sleep 500ms
<  2024.538> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  2024.538> [eDVBFrontend 3] sleep 500ms
<  2024.538> [eDVBFrontend 4] update current switch params
<  2024.538> [eDVBFrontend 4] startTuneTimeout 5000
<  2024.538> [eDVBFrontend 4] setFrontend 1
<  2024.538> [eDVBFrontend] setting frontend 4
<  2024.538> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  2024.538> [eDVBFrontend 4] sleep 500ms
<  2024.580> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  2024.581> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  2024.581> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  2025.038> [eDVBFrontend 0] set dynamic current limiting
<  2025.038> [eDVBFrontend 3] set dynamic current limiting
<  2025.038> [eDVBFrontend 4] set dynamic current limiting
<  2029.537> [eDVBChannel] OURSTATE: failed, retune
<  2029.537> [eDVBFrontend] (0)tune
<  2029.542> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x121, guard_offset 0
<  2029.542> **** Tuning JESS
<  2029.542> **** frequency_mhz: 11097
<  2029.542> **** lo_mhz: 9750
<  2029.542> **** T: 1247
<  2029.542> **** position: 0
<  2029.542> **** ub: 0
<  2029.542> **** mode: 0
<  2029.542> **** JESS: 70 04 df 00
<  2029.542> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2029.542> [eDVBFrontend] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2029.542> [eDVBFrontend] tuning to 1209 mhz
<  2029.542> [eDVBChannel] OURSTATE: tuning
<  2029.542> [eDVBServicePMTHandler] tune failed.
<  2029.542> [eDVBServiceRecord] RECORD service event 1
<  2029.542> [eDVBServiceRecord] record failed to tune
<  2029.542> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2029.542> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2029.542> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  2029.542> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2029.543> [eDVBChannel] OURSTATE: failed, retune
<  2029.543> [eDVBFrontend] (3)tune
<  2029.543> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2935, guard_offset 0
<  2029.543> **** Tuning JESS
<  2029.543> **** frequency_mhz: 10964
<  2029.543> **** lo_mhz: 9750
<  2029.543> **** T: 1114
<  2029.543> **** position: 0
<  2029.543> **** ub: 1
<  2029.543> **** mode: 2
<  2029.543> **** JESS: 70 0c 5a 02
<  2029.543> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2029.543> [eDVBFrontend] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  2029.543> [eDVBFrontend] tuning to 1421 mhz
<  2029.543> [eDVBChannel] OURSTATE: tuning
<  2029.543> [eDVBServicePMTHandler] tune failed.
<  2029.543> [eDVBServiceRecord] RECORD service event 1
<  2029.543> [eDVBServiceRecord] record failed to tune
<  2029.543> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2029.543> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2029.543> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  2029.543> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2029.543> [eDVBChannel] OURSTATE: failed, retune
<  2029.543> [eDVBFrontend] (4)tune
<  2029.543> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4158, guard_offset 0
<  2029.543> **** Tuning JESS
<  2029.543> **** frequency_mhz: 10847
<  2029.543> **** lo_mhz: 9750
<  2029.543> **** T: 997
<  2029.543> **** position: 0
<  2029.543> **** ub: 2
<  2029.543> **** mode: 0
<  2029.543> **** JESS: 70 13 e5 00
<  2029.543> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2029.543> [eDVBFrontend] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2029.543> [eDVBFrontend] tuning to 1679 mhz
<  2029.543> [eDVBChannel] OURSTATE: tuning
<  2029.543> [eDVBServicePMTHandler] tune failed.
<  2029.543> [eDVBServiceRecord] RECORD service event 1
<  2029.543> [eDVBServiceRecord] record failed to tune
<  2029.543> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2029.543> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2029.544> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  2029.544> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2029.544> [eDVBFrontend 0] set static current limiting
<  2029.544> [eDVBFrontend 3] set static current limiting
<  2029.544> [eDVBFrontend 4] set static current limiting
<  2029.544> [eDVBFrontend 0] invalidate current switch params
<  2029.544> [eDVBFrontend 3] invalidate current switch params
<  2029.544> [eDVBFrontend 4] invalidate current switch params
<  2029.544> [eDVBFrontend 0] set sequence pos 3
<  2029.544> [eDVBFrontend 3] set sequence pos 3
<  2029.544> [eDVBFrontend 4] set sequence pos 3
<  2029.544> [eDVBFrontend 0] setVoltage 2
<  2029.545> [eDVBFrontend 3] setVoltage 2
<  2029.545> [eDVBFrontend 4] setVoltage 2
<  2029.546> [eDVBFrontend 0] setTone 0
<  2029.547> [eDVBFrontend 3] setTone 0
<  2029.548> [eDVBFrontend 4] setTone 0
<  2029.549> [eDVBFrontend 0] sleep 75ms
<  2029.549> [eDVBFrontend 3] sleep 75ms
<  2029.549> [eDVBFrontend 4] sleep 75ms
<  2029.680> [eDVBFrontend 0] sendDiseqc: 7004df00(?)
<  2029.736> [eDVBFrontend 3] sendDiseqc: 700c5a02(?)
<  2029.792> [eDVBFrontend 4] sendDiseqc: 7013e500(?)
<  2029.792> [eDVBFrontend 0] sleep 100ms
<  2029.792> [eDVBFrontend 3] sleep 100ms
<  2029.792> [eDVBFrontend 4] sleep 100ms
<  2029.892> [eDVBFrontend 0] setVoltage 1
<  2029.893> [eDVBFrontend 3] setVoltage 1
<  2029.893> [eDVBFrontend 4] setVoltage 1
<  2029.894> [eDVBFrontend 0] update current switch params
<  2029.894> [eDVBFrontend 3] update current switch params
<  2029.894> [eDVBFrontend 4] update current switch params
<  2029.894> [eDVBFrontend 0] startTuneTimeout 5000
<  2029.894> [eDVBFrontend 3] startTuneTimeout 5000
<  2029.894> [eDVBFrontend 4] startTuneTimeout 5000
<  2029.894> [eDVBFrontend 0] setFrontend 1
<  2029.894> [eDVBFrontend] setting frontend 0
<  2029.894> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  2029.894> [eDVBFrontend 3] setFrontend 1
<  2029.894> [eDVBFrontend] setting frontend 3
<  2029.894> [eDVBFrontend 4] setFrontend 1
<  2029.894> [eDVBFrontend] setting frontend 4
<  2029.895> [eDVBFrontend 0] sleep 500ms
<  2029.895> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  2029.895> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  2029.895> [eDVBFrontend 3] sleep 500ms
<  2029.895> [eDVBFrontend 4] sleep 500ms
<  2029.940> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  2029.941> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  2029.941> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  2030.395> [eDVBFrontend 0] set dynamic current limiting
<  2030.395> [eDVBFrontend 3] set dynamic current limiting
<  2030.395> [eDVBFrontend 4] set dynamic current limiting
<  2034.894> [eDVBChannel] OURSTATE: failed, retune
<  2034.894> [eDVBFrontend] (0)tune
<  2034.894> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x121, guard_offset 0
<  2034.895> **** Tuning JESS
<  2034.895> **** frequency_mhz: 11097
<  2034.895> **** lo_mhz: 9750
<  2034.895> **** T: 1247
<  2034.895> **** position: 0
<  2034.895> **** ub: 0
<  2034.895> **** mode: 0
<  2034.895> **** JESS: 70 04 df 00
<  2034.895> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2034.895> [eDVBFrontend] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2034.895> [eDVBFrontend] tuning to 1209 mhz
<  2034.895> [eDVBChannel] OURSTATE: tuning
<  2034.895> [eDVBServicePMTHandler] tune failed.
<  2034.895> [eDVBServiceRecord] RECORD service event 1
<  2034.895> [eDVBServiceRecord] record failed to tune
<  2034.895> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2034.895> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2034.895> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  2034.895> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2034.895> [eDVBChannel] OURSTATE: failed, retune
<  2034.895> [eDVBFrontend] (3)tune
<  2034.895> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2935, guard_offset 0
<  2034.895> **** Tuning JESS
<  2034.895> **** frequency_mhz: 10964
<  2034.895> **** lo_mhz: 9750
<  2034.895> **** T: 1114
<  2034.895> **** position: 0
<  2034.895> **** ub: 1
<  2034.895> **** mode: 2
<  2034.895> **** JESS: 70 0c 5a 02
<  2034.895> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2034.895> [eDVBFrontend] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  2034.895> [eDVBFrontend] tuning to 1421 mhz
<  2034.895> [eDVBChannel] OURSTATE: tuning
<  2034.895> [eDVBServicePMTHandler] tune failed.
<  2034.896> [eDVBServiceRecord] RECORD service event 1
<  2034.896> [eDVBServiceRecord] record failed to tune
<  2034.896> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2034.896> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2034.896> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  2034.896> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2034.896> [eDVBChannel] OURSTATE: failed, retune
<  2034.896> [eDVBFrontend] (4)tune
<  2034.896> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4158, guard_offset 0
<  2034.896> **** Tuning JESS
<  2034.896> **** frequency_mhz: 10847
<  2034.896> **** lo_mhz: 9750
<  2034.896> **** T: 997
<  2034.896> **** position: 0
<  2034.896> **** ub: 2
<  2034.896> **** mode: 0
<  2034.896> **** JESS: 70 13 e5 00
<  2034.896> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2034.896> [eDVBFrontend] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2034.896> [eDVBFrontend] tuning to 1679 mhz
<  2034.896> [eDVBChannel] OURSTATE: tuning
<  2034.896> [eDVBServicePMTHandler] tune failed.
<  2034.896> [eDVBServiceRecord] RECORD service event 1
<  2034.896> [eDVBServiceRecord] record failed to tune
<  2034.896> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2034.896> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2034.896> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  2034.896> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2034.896> [eDVBFrontend 0] set static current limiting
<  2034.896> [eDVBFrontend 3] set static current limiting
<  2034.896> [eDVBFrontend 4] set static current limiting
<  2034.897> [eDVBFrontend 0] invalidate current switch params
<  2034.897> [eDVBFrontend 3] invalidate current switch params
<  2034.897> [eDVBFrontend 4] invalidate current switch params
<  2034.897> [eDVBFrontend 0] set sequence pos 3
<  2034.897> [eDVBFrontend 3] set sequence pos 3
<  2034.897> [eDVBFrontend 4] set sequence pos 3
<  2034.897> [eDVBFrontend 0] setVoltage 2
<  2034.897> [eDVBFrontend 3] setVoltage 2
<  2034.907> [eDVBFrontend 4] setVoltage 2
<  2034.908> [eDVBFrontend 0] setTone 0
<  2034.908> [eDVBFrontend 3] setTone 0
<  2034.909> [eDVBFrontend 4] setTone 0
<  2034.910> [eDVBFrontend 0] sleep 75ms
<  2034.910> [eDVBFrontend 3] sleep 75ms
<  2034.910> [eDVBFrontend 4] sleep 75ms
<  2035.040> [eDVBFrontend 0] sendDiseqc: 7004df00(?)
<  2035.096> [eDVBFrontend 3] sendDiseqc: 700c5a02(?)
<  2035.152> [eDVBFrontend 4] sendDiseqc: 7013e500(?)
<  2035.152> [eDVBFrontend 0] sleep 100ms
<  2035.153> [eDVBFrontend 3] sleep 100ms
<  2035.153> [eDVBFrontend 4] sleep 100ms
<  2035.252> [eDVBFrontend 0] setVoltage 1
<  2035.253> [eDVBFrontend 3] setVoltage 1
<  2035.254> [eDVBFrontend 4] setVoltage 1
<  2035.255> [eDVBFrontend 0] update current switch params
<  2035.255> [eDVBFrontend 3] update current switch params
<  2035.255> [eDVBFrontend 4] update current switch params
<  2035.255> [eDVBFrontend 0] startTuneTimeout 5000
<  2035.255> [eDVBFrontend 3] startTuneTimeout 5000
<  2035.255> [eDVBFrontend 4] startTuneTimeout 5000
<  2035.255> [eDVBFrontend 0] setFrontend 1
<  2035.255> [eDVBFrontend] setting frontend 0
<  2035.255> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  2035.255> [eDVBFrontend 3] setFrontend 1
<  2035.255> [eDVBFrontend] setting frontend 3
<  2035.255> [eDVBFrontend 4] setFrontend 1
<  2035.255> [eDVBFrontend] setting frontend 4
<  2035.255> [eDVBFrontend 0] sleep 500ms
<  2035.255> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  2035.255> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  2035.255> [eDVBFrontend 3] sleep 500ms
<  2035.255> [eDVBFrontend 4] sleep 500ms
<  2035.300> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  2035.301> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  2035.301> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  2035.755> [eDVBFrontend 0] set dynamic current limiting
<  2035.755> [eDVBFrontend 3] set dynamic current limiting
<  2035.755> [eDVBFrontend 4] set dynamic current limiting
<  2037.318> [RecordTimer] activating state 2
<  2037.321> [RecordTimer] start recording on tuner: A
<  2037.321> [RecordTimer] activating state 2
<  2037.322> [RecordTimer] start recording on tuner: D
<  2037.322> [RecordTimer] activating state 2
<  2037.322> [RecordTimer] start recording on tuner: E
<  2040.255> [eDVBChannel] OURSTATE: failed, retune
<  2040.255> [eDVBFrontend] (0)tune
<  2040.255> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x121, guard_offset 0
<  2040.255> **** Tuning JESS
<  2040.255> **** frequency_mhz: 11097
<  2040.255> **** lo_mhz: 9750
<  2040.255> **** T: 1247
<  2040.255> **** position: 0
<  2040.255> **** ub: 0
<  2040.255> **** mode: 0
<  2040.255> **** JESS: 70 04 df 00
<  2040.255> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2040.255> [eDVBFrontend] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2040.255> [eDVBFrontend] tuning to 1209 mhz
<  2040.255> [eDVBChannel] OURSTATE: tuning
<  2040.255> [eDVBServicePMTHandler] tune failed.
<  2040.255> [eDVBServiceRecord] RECORD service event 1
<  2040.255> [eDVBServiceRecord] record failed to tune
<  2040.256> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2040.256> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2040.256> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  2040.256> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2040.256> [eDVBChannel] OURSTATE: failed, retune
<  2040.256> [eDVBFrontend] (3)tune
<  2040.256> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2935, guard_offset 0
<  2040.256> **** Tuning JESS
<  2040.256> **** frequency_mhz: 10964
<  2040.256> **** lo_mhz: 9750
<  2040.256> **** T: 1114
<  2040.256> **** position: 0
<  2040.256> **** ub: 1
<  2040.256> **** mode: 2
<  2040.256> **** JESS: 70 0c 5a 02
<  2040.256> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2040.256> [eDVBFrontend] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  2040.256> [eDVBFrontend] tuning to 1421 mhz
<  2040.256> [eDVBChannel] OURSTATE: tuning
<  2040.256> [eDVBServicePMTHandler] tune failed.
<  2040.256> [eDVBServiceRecord] RECORD service event 1
<  2040.256> [eDVBServiceRecord] record failed to tune
<  2040.256> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2040.256> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2040.256> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  2040.256> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2040.256> [eDVBChannel] OURSTATE: failed, retune
<  2040.256> [eDVBFrontend] (4)tune
<  2040.256> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4158, guard_offset 0
<  2040.256> **** Tuning JESS
<  2040.256> **** frequency_mhz: 10847
<  2040.256> **** lo_mhz: 9750
<  2040.256> **** T: 997
<  2040.256> **** position: 0
<  2040.256> **** ub: 2
<  2040.256> **** mode: 0
<  2040.256> **** JESS: 70 13 e5 00
<  2040.256> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  2040.257> [eDVBFrontend] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  2040.257> [eDVBFrontend] tuning to 1679 mhz
<  2040.257> [eDVBChannel] OURSTATE: tuning
<  2040.257> [eDVBServicePMTHandler] tune failed.
<  2040.257> [eDVBServiceRecord] RECORD service event 1
<  2040.257> [eDVBServiceRecord] record failed to tune
<  2040.257> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  2040.257> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  2040.257> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  2040.257> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  2040.257> [eDVBFrontend 0] set static current limiting
<  2040.257> [eDVBFrontend 3] set static current limiting
<  2040.257> [eDVBFrontend 4] set static current limiting
<  2040.257> [eDVBFrontend 0] invalidate current switch params
<  2040.257> [eDVBFrontend 3] invalidate current switch params
<  2040.257> [eDVBFrontend 4] invalidate current switch params
<  2040.257> [eDVBFrontend 0] set sequence pos 3
<  2040.257> [eDVBFrontend 3] set sequence pos 3
<  2040.257> [eDVBFrontend 4] set sequence pos 3
<  2040.257> [eDVBFrontend 0] setVoltage 2
<  2040.258> [eDVBFrontend 3] setVoltage 2
<  2040.259> [eDVBFrontend 4] setVoltage 2
<  2040.259> [eDVBFrontend 0] setTone 0
<  2040.260> [eDVBFrontend 3] setTone 0
<  2040.260> [eDVBFrontend 4] setTone 0
<  2040.261> [eDVBFrontend 0] sleep 75ms
<  2040.261> [eDVBFrontend 3] sleep 75ms
<  2040.261> [eDVBFrontend 4] sleep 75ms
<  2040.392> [eDVBFrontend 0] sendDiseqc: 7004df00(?)
<  2040.448> [eDVBFrontend 3] sendDiseqc: 700c5a02(?)
<  2040.504> [eDVBFrontend 4] sendDiseqc: 7013e500(?)
<  2040.504> [eDVBFrontend 0] sleep 100ms
<  2040.506> [eDVBFrontend 3] sleep 100ms
<  2040.506> [eDVBFrontend 4] sleep 100ms
<  2040.604> [eDVBFrontend 0] setVoltage 1
<  2040.605> [eDVBFrontend 0] update current switch params
<  2040.605> [eDVBFrontend 0] startTuneTimeout 5000
<  2040.605> [eDVBFrontend 0] setFrontend 1
<  2040.605> [eDVBFrontend] setting frontend 0
<  2040.606> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  2040.606> [eDVBFrontend 0] sleep 500ms
<  2040.606> [eDVBFrontend 3] setVoltage 1
<  2040.607> [eDVBFrontend 4] setVoltage 1
<  2040.607> [eDVBFrontend 3] update current switch params
<  2040.607> [eDVBFrontend 4] update current switch params
<  2040.607> [eDVBFrontend 3] startTuneTimeout 5000
<  2040.607> [eDVBFrontend 4] startTuneTimeout 5000
<  2040.607> [eDVBFrontend 3] setFrontend 1
<  2040.607> [eDVBFrontend] setting frontend 3
<  2040.608> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  2040.608> [eDVBFrontend 4] setFrontend 1
<  2040.608> [eDVBFrontend] setting frontend 4
<  2040.608> [eDVBFrontend 3] sleep 500ms
<  2040.608> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  2040.608> [eDVBFrontend 4] sleep 500ms
<  2040.650> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  2040.650> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  2040.650> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  2041.106> [eDVBFrontend 0] set dynamic current limiting
<  2041.108> [eDVBFrontend 3] set dynamic current limiting
<  2041.108> [eDVBFrontend 4] set dynamic current limiting
<  2045.605> [eDVBChannel] OURSTATE: failed, retune

BTW, don't forget the Ultimo 4K can have DVB-S2 tuners from A-P and they are not all internally linked. Have to be linked externally. Is that still compatible with this patch?

[athoik]

The fronted number on the debug log, seems much better, you can clearly see 0 - 3 - 4 order repeating again and again.

I don't know what will happen on external linked tuners, that's why I mentioned several times, that re-tunes also need some care.

Let's see if the last approach seems better. Then we can also add some randomness to re-tunes.

Edited by athoik, 14 October 2018 - 19:38.

[athoik]

@Huevos, the initial m_tuning value is 1 (just after start is becoming 1).

 

                m_tuneTimer->start(0,true);
                m_tuning = 1;
                if (m_state != stateTuning)
                {
                        m_state = stateTuning;
                        m_stateChanged(this);
                }

So there is a minor change in patch. sec_fe->m_tuning == 1
 

diff --git a/lib/dvb/frontend.cpp b/lib/dvb/frontend.cpp
index 30918be..2c46c56 100644
--- a/lib/dvb/frontend.cpp
+++ b/lib/dvb/frontend.cpp
@@ -1583,6 +1583,14 @@ int eDVBFrontend::tuneLoopInt()  // called by m_tuneTimer
                                prev->inc_use();
                        }
                }
+               else if (!m_simulate && sec_fe != this && sec_fe->m_state == stateTuning && sec_fe->m_tuning == 1 &&
+                       m_sec_sequence && m_sec_sequence.begin() == m_sec_sequence.current())
+               {
+                       delay = 100 + rand() % 900; // random value between 100 and 999 ms
+                       eDebug("[eDVBFrontend %d] linked on %d is also initiating tuning, delaying %d ms", m_dvbid, sec_fe->getDVBID(), delay);
+                       m_tuneTimer->start(delay, true);
+                       return delay;
+               }
        }

        if ( m_sec_sequence && m_sec_sequence.current() != m_sec_sequence.end() )

Edited by athoik, 14 October 2018 - 19:51.

[Huevos]

Ok, I will build it now.

 

Also I was just thinking... the total delay time added in that unicable clause is...

m_params[DELAY_AFTER_ENABLE_VOLTAGE_BEFORE_SWITCH_CMDS] +
m_params[DELAY_AFTER_VOLTAGE_CHANGE_BEFORE_SWITCH_CMDS] +
m_params[DELAY_AFTER_LAST_DISEQC_CMD] +
m_params[DELAY_AFTER_VOLTAGE_CHANGE_BEFORE_MOTOR_CMD] 

So should the delay be at least that long?

[athoik]

This will also add a small ramdon delay on failed retunes.

@@ -2523,8 +2531,9 @@ RESULT eDVBFrontend::tune(const iDVBFrontendParameters &where, bool blindscan)
                        eDebug("[eDVBFrontend] tune setting type to %d from %d", type, m_type);
                        m_type = type;
                }
-               m_tuneTimer->start(0,true);
                m_tuning = 1;
+               // if we are re-tuning add a small random delay
+               m_tuneTimer->start(m_state == stateFailed ? rand() % 100 : 0, true);
                if (m_state != stateTuning)
                {
                        m_state = stateTuning;

[athoik]

So should the delay be at least that long?

I don't know. I should be as long as the protocol suggests.

[Huevos]

Ok, I have added both patches. Works for me on the first tests. Others should confirm.

<   456.248> [RecordTimer] activating state 1
<   456.250> [RecordTimer] Found enough free space to record
<   456.250> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181014 2322 - BBC One HD - This Is BBC One HD'
<   456.250> [Navigation] recording service: 1:0:19:1B1D:802:2:11A0000:0:0:0:
<   456.252> [eDVBResourceManager] allocate channel.. 0802:0002
<   456.252> [eDVBResourceManager] available channel.. 080e:0002
<   456.252> [eDVBFrontend] opening frontend 0
<   456.255> [eDVBFrontend] (0)tune
<   456.256> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<   456.256> **** Tuning JESS
<   456.256> **** frequency_mhz: 10847
<   456.256> **** lo_mhz: 9750
<   456.256> **** T: 997
<   456.256> **** position: 0
<   456.256> **** ub: 0
<   456.256> **** mode: 0
<   456.256> **** JESS: 70 03 e5 00
<   456.256> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<   456.256> [eDVBFrontend] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<   456.256> [eDVBFrontend] tuning to 1211 mhz
<   456.256> [eDVBChannel] OURSTATE: tuning
<   456.256> [eDVBServicePMTHandler] allocate Channel: res 0
<   456.256> [eDVBCIInterfaces] addPMTHandler 1:0:19:1B1D:802:2:11A0000:0:0:0:
<   456.256> [eDVBChannel] getDemux cap=00
<   456.256> [eDVBResourceManager] allocate demux cap=00
<   456.256> [eDVBResourceManager] allocating demux adapter=0, demux=1, source=-1 fesource=0
<   456.256> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   456.548> [eEPGCache] saveEventToFile epg event id dfb3
<   456.548> [RecordTimer] prepare ok, waiting for begin
<   456.550> [RecordTimer] activating state 1
<   456.551> [Trashcan] probing folders
<   456.553> [RecordTimer] Found enough free space to record
<   456.557> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181014 2322 - ITV London HD - Police in Danger_ Caught___'
<   456.558> [Navigation] recording service: 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<   456.558> [eDVBResourceManager] allocate channel.. 0812:0002
<   456.558> [eDVBResourceManager] available channel.. 080e:0002
<   456.558> [eDVBResourceManager] available channel.. 0802:0002
<   456.558> [eDVBFrontend] opening frontend 3
<   456.561> [eDVBFrontend] (3)tune
<   456.561> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2156, guard_offset 0
<   456.561> **** Tuning JESS
<   456.561> **** frequency_mhz: 11097
<   456.562> **** lo_mhz: 9750
<   456.562> **** T: 1247
<   456.562> **** position: 0
<   456.562> **** ub: 1
<   456.562> **** mode: 0
<   456.562> **** JESS: 70 0c df 00
<   456.562> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<   456.562> [eDVBFrontend] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<   456.562> [eDVBFrontend] tuning to 1421 mhz
<   456.562> [eDVBChannel] OURSTATE: tuning
<   456.562> [eDVBServicePMTHandler] allocate Channel: res 0
<   456.562> [eDVBCIInterfaces] addPMTHandler 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<   456.562> [eDVBChannel] getDemux cap=00
<   456.562> [eDVBResourceManager] allocate demux cap=00
<   456.562> [eDVBResourceManager] allocating demux adapter=0, demux=2, source=-1 fesource=3
<   456.562> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   456.563> [eEPGCache] saveEventToFile epg event id 446
<   456.563> [RecordTimer] prepare ok, waiting for begin
<   456.563> [Trashcan] found following trashcan's: ['/media/hdd/.Trash', '/media/hdd/movie/.Trash']
<   456.564> [Trashcan] looking in trashcan /media/hdd/.Trash
<   456.564> [Trashcan] Cleanup already running
<   456.565> [RecordTimer] activating state 1
<   456.566> [RecordTimer] Found enough free space to record
<   456.566> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181014 2322 - Channel 5 HD - New_ Big Brother'
<   456.566> [Navigation] recording service: 1:0:19:1E46:809:2:11A0000:0:0:0:
<   456.567> [eDVBResourceManager] allocate channel.. 0809:0002
<   456.567> [eDVBResourceManager] available channel.. 080e:0002
<   456.567> [eDVBResourceManager] available channel.. 0802:0002
<   456.567> [eDVBResourceManager] available channel.. 0812:0002
<   456.567> [eDVBFrontend] opening frontend 4
<   456.569> [eDVBFrontend] (4)tune
<   456.569> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<   456.569> **** Tuning JESS
<   456.569> **** frequency_mhz: 10964
<   456.569> **** lo_mhz: 9750
<   456.569> **** T: 1114
<   456.569> **** position: 0
<   456.569> **** ub: 2
<   456.569> **** mode: 2
<   456.569> **** JESS: 70 14 5a 02
<   456.569> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<   456.569> [eDVBFrontend] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<   456.569> [eDVBFrontend] tuning to 1681 mhz
<   456.569> [eDVBChannel] OURSTATE: tuning
<   456.569> [eDVBServicePMTHandler] allocate Channel: res 0
<   456.569> [eDVBCIInterfaces] addPMTHandler 1:0:19:1E46:809:2:11A0000:0:0:0:
<   456.569> [eDVBChannel] getDemux cap=00
<   456.569> [eDVBResourceManager] allocate demux cap=00
<   456.569> [eDVBResourceManager] allocating demux adapter=0, demux=3, source=-1 fesource=4
<   456.569> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   456.571> [eEPGCache] saveEventToFile epg event id 9571
<   456.571> [RecordTimer] prepare ok, waiting for begin
<   456.571> [Trashcan] Cleanup already running
<   456.572> [eDVBFrontend 0] set static current limiting
<   456.573> [Trashcan] /media/hdd/.Trash: Size: 0
<   456.573> [Trashcan] /media/hdd/.Trash: Size now: 0
<   456.574> [Trashcan] looking in trashcan /media/hdd/movie/.Trash
<   456.575> [eDVBFrontend 3] linked on 0 is also initiating tuning, delaying 366 ms
<   456.575> [eDVBFrontend 4] linked on 3 is also initiating tuning, delaying 734 ms
<   456.575> [eDVBFrontend 0] invalidate current switch params
<   456.575> [eDVBFrontend 0] setVoltage 1
<   456.576> [eDVBFrontend 0] sleep 1500ms
<   456.613> [Trashcan] /media/hdd/movie/.Trash: Size: 22725825486
<   456.633> [Trashcan] /media/hdd/movie/.Trash: Size now: 22725825486
<   456.634> [Task] job Components.Task.Job name=Cleaning Trashes #tasks=1 completed with [] in None
<   456.941> [eDVBFrontend 3] linked on 0 is also initiating tuning, delaying 498 ms
<   457.309> [eDVBFrontend 4] linked on 3 is also initiating tuning, delaying 611 ms
<   457.439> [eDVBFrontend 3] linked on 0 is also initiating tuning, delaying 758 ms
<   457.920> [eDVBFrontend 4] linked on 3 is also initiating tuning, delaying 217 ms
<   458.076> [eDVBFrontend 0] setVoltage 2
<   458.077> [eDVBFrontend 0] setTone 0
<   458.077> [eDVBFrontend 0] sleep 75ms
<   458.137> [eDVBFrontend 4] linked on 3 is also initiating tuning, delaying 765 ms
<   458.208> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<   458.209> [eDVBFrontend 3] linked on 0 is also initiating tuning, delaying 857 ms
<   458.209> [eDVBFrontend 0] sleep 100ms
<   458.309> [eDVBFrontend 0] setVoltage 1
<   458.309> [eDVBFrontend 0] update current switch params
<   458.309> [eDVBFrontend 0] startTuneTimeout 5000
<   458.309> [eDVBFrontend 0] setFrontend 1
<   458.309> [eDVBFrontend] setting frontend 0
<   458.363> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<   458.363> [eDVBFrontend 0] sleep 500ms
<   458.410> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<   458.461> [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 3
<   458.461> [eDVBChannel] OURSTATE: ok
<   458.461> [eDVBLocalTimerHandler] channel 0x198c7a0 running
<   458.462> [eDVBChannel] getDemux cap=00
<   458.462> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.462> [eDVBSectionReader] DMX_SET_FILTER pid=20
<   458.462> [eEPGCache] channel 0x198c7a0 running
<   458.463> [eDVBChannel] getDemux cap=00
<   458.463> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.463> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.463> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.463> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.463> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.463> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.464> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.464> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.464> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.464> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.464> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.464> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.464> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.465> [eDVBChannel] getDemux cap=01
<   458.465> [eDVBResourceManager] allocate demux cap=01
<   458.465> [eDVBResourceManager] allocating shared demux adapter=0, demux=1, source=0
<   458.465> [eEPGCache] next update in 2 sec
<   458.465> [eDVBServicePMTHandler] ok ... now we start!!
<   458.465> [eDVBServiceRecord] RECORD service event 5
<   458.465> [eDVBCAService] new service 1:0:19:1B1D:802:2:11A0000:0:0:0:
<   458.465> [eDVBCAService] add demux 1 to slot 0 service 1:0:19:1B1D:802:2:11A0000:0:0:0:
<   458.465> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.465> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   458.466> [eDVBServiceRecord] RECORD service event 6
<   458.466> [eDVBServiceRecord] tuned..
<   458.466> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.466> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   458.467> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.467> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   458.512> [eDVBServicePMTHandler] PATready
<   458.512> [eDVBServicePMTHandler] use pmtpid 0104 for service_id 1b1d
<   458.512> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.512> [eDVBSectionReader] DMX_SET_FILTER pid=260
<   458.512> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.513> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   458.514> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.514> [eDVBSectionReader] DMX_SET_FILTER pid=258
<   458.514> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.514> [eDVBSectionReader] DMX_SET_FILTER pid=17
<   458.843> [eDVBServiceRecord] RECORD service event 5
<   458.844> [eDVBCIInterfaces] gotPMT
<   458.844> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.845> [eDVBSectionReader] DMX_SET_FILTER pid=260
<   458.846> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   458.846> [eDVBSectionReader] DMX_SET_FILTER pid=260
<   458.863> [eDVBFrontend 0] set dynamic current limiting
<   458.903> [eDVBFrontend 4] linked on 3 is also initiating tuning, delaying 102 ms
<   459.005> [eDVBFrontend 4] linked on 3 is also initiating tuning, delaying 494 ms
<   459.043> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   459.044> [eDVBSectionReader] DMX_SET_FILTER pid=256
<   459.066> [eDVBFrontend 3] set static current limiting
<   459.066> [eDVBFrontend 3] invalidate current switch params
<   459.066> [eDVBFrontend 3] set sequence pos 3
<   459.066> [eDVBFrontend 3] setVoltage 2
<   459.067> [eDVBFrontend 3] setTone 0
<   459.067> [eDVBFrontend 3] sleep 75ms
<   459.198> [eDVBFrontend 3] sendDiseqc: 700cdf00(?)
<   459.198> [eDVBFrontend 3] sleep 100ms
<   459.298> [eDVBFrontend 3] setVoltage 1
<   459.299> [eDVBFrontend 3] update current switch params
<   459.299> [eDVBFrontend 3] startTuneTimeout 5000
<   459.299> [eDVBFrontend 3] setFrontend 1
<   459.299> [eDVBFrontend] setting frontend 3
<   459.302> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<   459.302> [eDVBFrontend 3] sleep 500ms
<   459.305> [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 3
<   459.315> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   459.316> [eDVBSectionReader] DMX_SET_FILTER pid=259
<   459.350> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<   459.401> [eDVBFrontend] (3)fe event: status 1f, inversion off, m_tuning 3
<   459.401> [eDVBChannel] OURSTATE: ok
<   459.401> [eDVBLocalTimerHandler] channel 0x198ae88 running
<   459.401> [eDVBChannel] getDemux cap=00
<   459.401> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.402> [eDVBSectionReader] DMX_SET_FILTER pid=20
<   459.402> [eEPGCache] channel 0x198ae88 running
<   459.402> [eDVBChannel] getDemux cap=00
<   459.402> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.403> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.403> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.403> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.403> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.403> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.403> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.403> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.404> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.404> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.404> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.404> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.404> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.404> [eDVBChannel] getDemux cap=01
<   459.404> [eEPGCache] next update in 2 sec
<   459.404> [eDVBResourceManager] allocate demux cap=01
<   459.405> [eDVBResourceManager] allocating shared demux adapter=0, demux=2, source=3
<   459.405> [eDVBServicePMTHandler] ok ... now we start!!
<   459.405> [eDVBServiceRecord] RECORD service event 5
<   459.405> [eDVBCAService] new service 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<   459.405> [eDVBCAService] add demux 2 to slot 0 service 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<   459.405> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.405> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   459.406> [eDVBServiceRecord] RECORD service event 6
<   459.406> [eDVBServiceRecord] tuned..
<   459.406> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.406> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   459.406> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.407> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   459.443> [eDVBServicePMTHandler] PATready
<   459.443> [eDVBServicePMTHandler] use pmtpid 0102 for service_id 5208
<   459.443> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.444> [eDVBSectionReader] DMX_SET_FILTER pid=258
<   459.444> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.444> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   459.446> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.446> [eDVBSectionReader] DMX_SET_FILTER pid=258
<   459.446> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.447> [eDVBSectionReader] DMX_SET_FILTER pid=17
<   459.499> [eDVBFrontend 4] set static current limiting
<   459.499> [eDVBFrontend 4] invalidate current switch params
<   459.499> [eDVBFrontend 4] set sequence pos 3
<   459.499> [eDVBFrontend 4] setVoltage 2
<   459.500> [eDVBFrontend 4] setTone 0
<   459.500> [eDVBFrontend 4] sleep 75ms
<   459.531> [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 3
<   459.631> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<   459.631> [eDVBFrontend 4] sleep 100ms
<   459.634> [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 3
<   459.642> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   459.642> [eDVBSectionReader] DMX_SET_FILTER pid=264
<   459.648> [eDVBServiceRecord] RECORD service event 5
<   459.648> [eDVBCIInterfaces] gotPMT
<   459.649> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.650> [eDVBSectionReader] DMX_SET_FILTER pid=258
<   459.651> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.651> [eDVBSectionReader] DMX_SET_FILTER pid=260
<   459.731> [eDVBFrontend 4] setVoltage 1
<   459.732> [eDVBFrontend 4] update current switch params
<   459.732> [eDVBFrontend 4] startTuneTimeout 5000
<   459.732> [eDVBFrontend 4] setFrontend 1
<   459.732> [eDVBFrontend] setting frontend 4
<   459.735> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<   459.735> [eDVBFrontend 4] sleep 500ms
<   459.751> [eDVBServiceRecord] now running: †BBC News‡ (1200 seconds)
<   459.751> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   459.751> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   459.762> [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 3
<   459.780> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<   459.802> [eDVBFrontend 3] set dynamic current limiting
<   459.831> [eDVBFrontend] (4)fe event: status 1f, inversion off, m_tuning 3
<   459.831> [eDVBChannel] OURSTATE: ok
<   459.831> [eDVBLocalTimerHandler] channel 0x198ef10 running
<   459.832> [eDVBChannel] getDemux cap=00
<   459.832> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.832> [eDVBSectionReader] DMX_SET_FILTER pid=20
<   459.832> [eEPGCache] channel 0x198ef10 running
<   459.832> [eDVBChannel] getDemux cap=00
<   459.832> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.833> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.833> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.833> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.833> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.833> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.833> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.834> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.834> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.834> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.834> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.834> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.834> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.834> [eDVBResourceManager] stop release channel timer
<   459.835> [eDVBChannel] getDemux cap=01
<   459.835> [eEPGCache] next update in 2 sec
<   459.835> [eDVBResourceManager] allocate demux cap=01
<   459.835> [eDVBResourceManager] allocating shared demux adapter=0, demux=3, source=4
<   459.835> [eDVBServicePMTHandler] ok ... now we start!!
<   459.835> [eDVBServiceRecord] RECORD service event 5
<   459.835> [eDVBCAService] new service 1:0:19:1E46:809:2:11A0000:0:0:0:
<   459.835> [eDVBCAService] add demux 3 to slot 0 service 1:0:19:1E46:809:2:11A0000:0:0:0:
<   459.835> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.835> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   459.836> [eDVBServiceRecord] RECORD service event 6
<   459.836> [eDVBServiceRecord] tuned..
<   459.836> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.836> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   459.836> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.837> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   459.847> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   459.847> [eDVBSectionReader] DMX_SET_FILTER pid=261
<   459.863> [eDVBServicePMTHandler] PATready
<   459.863> [eDVBServicePMTHandler] use pmtpid 0101 for service_id 1e46
<   459.863> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.863> [eDVBSectionReader] DMX_SET_FILTER pid=257
<   459.864> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.864> [eDVBSectionReader] DMX_SET_FILTER pid=0
<   459.865> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.865> [eDVBSectionReader] DMX_SET_FILTER pid=267
<   459.866> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   459.866> [eDVBSectionReader] DMX_SET_FILTER pid=17
<   459.946> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   459.947> [eDVBSectionReader] DMX_SET_FILTER pid=262
<   459.990> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   459.991> [eDVBSectionReader] DMX_SET_FILTER pid=257
<   460.044> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   460.045> [eDVBSectionReader] DMX_SET_FILTER pid=256
<   460.064> [eDVBServiceRecord] RECORD service event 5
<   460.065> [eDVBCIInterfaces] gotPMT
<   460.066> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   460.066> [eDVBSectionReader] DMX_SET_FILTER pid=257
<   460.069> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   460.069> [eDVBSectionReader] DMX_SET_FILTER pid=258
<   460.162> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   460.162> [eDVBSectionReader] DMX_SET_FILTER pid=263
<   460.235> [eDVBFrontend 4] set dynamic current limiting
<   460.246> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   460.246> [eDVBSectionReader] DMX_SET_FILTER pid=262
<   460.267> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   460.267> [eDVBSectionReader] DMX_SET_FILTER pid=259
<   460.312> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<   460.312> [eDVBSectionReader] DMX_SET_FILTER pid=265
<   460.373> [eDVBServicePMTHandler] sdt update done!
<   460.448> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   460.448> [eDVBSectionReader] DMX_SET_FILTER pid=263
<   460.451> [eDVBServiceRecord] now running: †New: Big Brother‡ (5400 seconds)
<   460.451> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   460.451> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   460.466> [eEPGCache] start caching events(1539552104)
<   460.466> [eDVBSectionReader] DMX_SET_FILTER pid=3842
<   460.466> [eDVBSectionReader] DMX_SET_FILTER pid=3003
<   460.466> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   460.467> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   460.468> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   460.468> [eDVBSectionReader] DMX_SET_FILTER pid=260
<   460.469> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   460.470> [eDVBSectionReader] DMX_SET_FILTER pid=700
<   460.471> [eDVBSectionReader] DMX_SET_FILTER pid=700
<   460.471> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<   460.471> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<   460.496> [eDVBSectionReader] DMX_SET_FILTER pid=57
<   460.650> [eDVBServiceRecord] now running: Police in Danger: Caught... (3300 seconds)
<   460.650> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<   460.650> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   460.665> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   460.665> [eDVBSectionReader] DMX_SET_FILTER pid=261
<   460.870> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   460.871> [eDVBSectionReader] DMX_SET_FILTER pid=256
<   461.069> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   461.070> [eDVBSectionReader] DMX_SET_FILTER pid=262
<   461.267> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   461.268> [eDVBSectionReader] DMX_SET_FILTER pid=266
<   461.404> [eEPGCache] start caching events(1539552105)
<   461.405> [eDVBSectionReader] DMX_SET_FILTER pid=3842
<   461.405> [eDVBSectionReader] DMX_SET_FILTER pid=3003
<   461.405> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   461.406> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   461.407> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   461.408> [eDVBSectionReader] DMX_SET_FILTER pid=700
<   461.409> [eDVBSectionReader] DMX_SET_FILTER pid=700
<   461.409> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<   461.409> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<   461.410> [eDVBSectionReader] DMX_SET_FILTER pid=57
<   461.465> [eDVBServicePMTHandler] sdt update done!
<   461.466> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   461.466> [eDVBSectionReader] DMX_SET_FILTER pid=264
<   461.611> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<   461.612> [eDVBSectionReader] DMX_SET_FILTER pid=257
<   461.835> [eEPGCache] start caching events(1539552105)
<   461.835> [eDVBSectionReader] DMX_SET_FILTER pid=3842
<   461.835> [eDVBSectionReader] DMX_SET_FILTER pid=3003
<   461.836> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   461.836> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   461.838> [eDVBSectionReader] DMX_SET_FILTER pid=18
<   461.839> [eDVBSectionReader] DMX_SET_FILTER pid=700
<   461.839> [eDVBSectionReader] DMX_SET_FILTER pid=700
<   461.839> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<   461.840> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<   461.840> [eDVBSectionReader] DMX_SET_FILTER pid=57
<   461.869> [eDVBServicePMTHandler] sdt update done!
<   463.487> [eDVBLocalTimerHandler] diff is 0
<   463.487> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
<   463.487> [eDVBLocalTimerHandler] not changed
<   463.488> [eDVBChannel] getDemux cap=00
<   464.291> [eDVBLocalTimerHandler] diff is -1
<   464.291> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
<   464.291> [eDVBLocalTimerHandler] set RTC Time
<   464.400> [eDVBLocalTimerHandler] update RTC
<   464.400> [eDVBLocalTimerHandler] time update to 23:21:47
<   464.400> [eDVBLocalTimerHandler] m_time_difference is -1
<   464.400> [eDVBLocalTimerHandler] slewing Linux Time by -01 seconds
<   464.400> [eDVBChannel] getDemux cap=00
<   465.182> [eDVBLocalTimerHandler] diff is 0
<   465.182> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
<   465.182> [eDVBLocalTimerHandler] not changed
<   465.182> [eDVBChannel] getDemux cap=00
<   467.497> [eEPGCache] abort non avail schedule reading
<   467.498> [eEPGCache] abort non avail schedule other reading
<   467.499> [eEPGCache] abort non avail virgin nownext reading
<   467.500> [eEPGCache] abort non avail virgin schedule reading
<   467.500> [eEPGCache] abort non avail netmed schedule reading
<   467.501> [eEPGCache] abort non avail netmed schedule other reading
<   467.501> [eEPGCache] abort non avail viasat reading
<   467.599> [eEPGCache] nownext finished(1539552111)
<   468.410> [eEPGCache] abort non avail schedule reading
<   468.412> [eEPGCache] abort non avail schedule other reading
<   468.412> [eEPGCache] abort non avail virgin nownext reading
<   468.413> [eEPGCache] abort non avail virgin schedule reading
<   468.413> [eEPGCache] abort non avail netmed schedule reading
<   468.414> [eEPGCache] abort non avail netmed schedule other reading
<   468.414> [eEPGCache] abort non avail viasat reading
<   468.504> [eEPGCache] nownext finished(1539552112)
<   468.841> [eEPGCache] abort non avail schedule reading
<   468.843> [eEPGCache] abort non avail schedule other reading
<   468.843> [eEPGCache] abort non avail virgin nownext reading
<   468.844> [eEPGCache] abort non avail virgin schedule reading
<   468.844> [eEPGCache] abort non avail netmed schedule reading
<   468.844> [eEPGCache] abort non avail netmed schedule other reading
<   468.845> [eEPGCache] abort non avail viasat reading
<   468.934> [eEPGCache] nownext finished(1539552112)
<   476.572> [RecordTimer] activating state 2
<   476.573> [eDVBServiceRecord] Recording to /media/hdd/movie/20181014 2322 - BBC One HD - This Is BBC One HD.ts...
<   476.575> [eDVBServiceRecord] start recording...
<   476.575> [eDVBServiceRecord] RECORD: have 1 video stream(s) (1518), and 2 audio stream(s) (151a, 1519) (151c, 151b), and the pcr pid is 1518, and the text pid is 151b
<   476.575> [eDVBServiceRecord] ADD PID: 0000
<   476.575> [eDVBServiceRecord] ADD PID: 0104
<   476.575> [eDVBServiceRecord] ADD PID: 1518
<   476.575> [eDVBServiceRecord] ADD PID: 1519
<   476.576> [eDVBServiceRecord] ADD PID: 151a
<   476.576> [eDVBServiceRecord] ADD PID: 151b
<   476.576> [eDVBServiceRecord] ADD PID: 151c
<   476.577> [setIoPrio] realtime level 7 ok
<   476.577> [eFilePushThreadRecorder] THREAD START
<   476.579> [RecordTimer] start recording on tuner: A
<   476.579> [RecordTimer] activating state 2
<   476.580> [eDVBServiceRecord] Recording to /media/hdd/movie/20181014 2322 - ITV London HD - Police in Danger_ Caught___.ts...
<   476.580> [eDVBServiceRecord] start recording...
<   476.580> [eDVBServiceRecord] RECORD: have 1 video stream(s) (0901), and 2 audio stream(s) (0903, 0902) (0905, 0904), and the pcr pid is 0901, and the text pid is 0904
<   476.580> [eDVBServiceRecord] ADD PID: 0000
<   476.580> [eDVBServiceRecord] ADD PID: 0102
<   476.580> [eDVBServiceRecord] ADD PID: 0901
<   476.580> [eDVBServiceRecord] ADD PID: 0902
<   476.581> [eDVBServiceRecord] ADD PID: 0903
<   476.581> [eDVBServiceRecord] ADD PID: 0904
<   476.581> [eDVBServiceRecord] ADD PID: 0905
<   476.582> [setIoPrio] realtime level 7 ok
<   476.582> [eFilePushThreadRecorder] THREAD START
<   476.583> [RecordTimer] start recording on tuner: D
<   476.584> [RecordTimer] activating state 2
<   476.584> [eDVBServiceRecord] Recording to /media/hdd/movie/20181014 2322 - Channel 5 HD - New_ Big Brother.ts...
<   476.584> [eDVBServiceRecord] start recording...
<   476.584> [eDVBServiceRecord] RECORD: have 1 video stream(s) (0901), and 2 audio stream(s) (0903, 0902) (0905, 0904), and the pcr pid is 0901, and the text pid is 0904
<   476.585> [eDVBServiceRecord] ADD PID: 0000
<   476.585> [eDVBServiceRecord] ADD PID: 0101
<   476.585> [eDVBServiceRecord] ADD PID: 0901
<   476.585> [eDVBServiceRecord] ADD PID: 0902
<   476.585> [eDVBServiceRecord] ADD PID: 0903
<   476.585> [eDVBServiceRecord] ADD PID: 0904
<   476.585> [eDVBServiceRecord] ADD PID: 0905
<   476.586> [setIoPrio] realtime level 7 ok
<   476.586> [eFilePushThreadRecorder] THREAD START
<   476.588> [RecordTimer] start recording on tuner: E

[Huevos]

Also I am going to send a pull request so we can control the Unicable delays independently of other LNB types. To me it doesn't make sense that Unicable and Universal LNBs are forced to use the same delays when their requirements are different.

[Erik Slagter]

Then focus on retries? Find why retries also take that much?

(because it will add an extra delay on concurrent tune on linked tuners for sure)

I am a bit worried that maybe some devices will get in a confused state from the initial quick train of SCR commands. It would be nice if we could confirm or reject that.

[Erik Slagter]

Also I am going to send a pull request so we can control the Unicable delays independently of other LNB types. To me it doesn't make sense that Unicable and Universal LNBs are forced to use the same delays when their requirements are different.

 

Let's discuss this. According to your findings the SEC commands are interleaved. I think we should focus on that for a minute, that result is quite unexpected. Why does that happen? AFAIK each frontend has it's own SEC queue and I would expect each queue to be filled independently and flushed/sent when complete.

This suggests all "prepare" request are handled in parallel and each SEC command is sent immediately. For independent tuners that is no issue, but for linked tuners it is.

 

It's completely contrary to my understanding of how it works.

[Huevos]

 

Also I am going to send a pull request so we can control the Unicable delays independently of other LNB types. To me it doesn't make sense that Unicable and Universal LNBs are forced to use the same delays when their requirements are different.

 

Let's discuss this. According to your findings the SEC commands are interleaved. I think we should focus on that for a minute, that result is quite unexpected. Why does that happen? AFAIK each frontend has it's own SEC queue and I would expect each queue to be filled independently and flushed/sent when complete.

This suggests all "prepare" request are handled in parallel and each SEC command is sent immediately. For independent tuners that is no issue, but for linked tuners it is.

 

It's completely contrary to my understanding of how it works.

 

Problem is not the linked tuners. It is that the commands are sent down the same cable. With Athoik's code the parallel commands for more than one tuner are delayed.

[Erik Slagter]

Problem is not the linked tuners. It is that the commands are sent down the same cable. With Athoik's code the parallel commands for more than one tuner are delayed.

And that actually IS the same problem.

 

Linked tuners = same cable and v.v.

 

It's an issue not specific for FBC tuners, it's the fact that they're linked / looped through.

[athoik]

This will also add a small ramdon delay on failed retunes.
 

@@ -2523,8 +2531,9 @@ RESULT eDVBFrontend::tune(const iDVBFrontendParameters &where, bool blindscan)
                        eDebug("[eDVBFrontend] tune setting type to %d from %d", type, m_type);
                        m_type = type;
                }
-               m_tuneTimer->start(0,true);
                m_tuning = 1;
+               // if we are re-tuning add a small random delay
+               m_tuneTimer->start(m_state == stateFailed ? rand() % 100 : 0, true);
                if (m_state != stateTuning)
                {
                        m_state = stateTuning;

@Huevos, only with above (without the delays on tuning part), does it make any difference?

I mean we do know that m_state was stateFailed when we are "retuning"? So that code really adds some delay or not?

(maybe an extra debug might need).

[Huevos]

@Athoik,

 

Here is what I am testing: https://github.com/H...9b53efdba73e43e

 

Start 3 simultaneous recordings.

 

Starts like this:

<   719.482> [eDVBFrontend 0] setVoltage 2
<   719.482> [eDVBFrontend 3] setVoltage 2
<   719.483> [eDVBFrontend 4] setVoltage 2
<   719.484> [eDVBFrontend 0] setTone 0
<   719.484> [eDVBFrontend 3] setTone 0
<   719.485> [eDVBFrontend 4] setTone 0
<   719.485> [eDVBFrontend 0] sleep 75ms
<   719.485> [eDVBFrontend 3] sleep 75ms
<   719.485> [eDVBFrontend 4] sleep 75ms
<   719.616> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<   719.672> [eDVBFrontend 3] sendDiseqc: 700cdf00(?)
<   719.728> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<   719.728> [eDVBFrontend 0] sleep 100ms
<   719.729> [eDVBFrontend 3] sleep 100ms
<   719.729> [eDVBFrontend 4] sleep 100ms

After a few seconds it goes out of sync sufficiently for the one of the recordings to start.

 

After 15 seconds more the other 2 recordings start.

<  4737.669> [RecordTimer] activating state 1
<  4737.670> [RecordTimer] Found enough free space to record
<  4737.671> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181017 0155 - BBC One HD - Jagged Edge'
<  4737.671> [Navigation] recording service: 1:0:19:1B1D:802:2:11A0000:0:0:0:
<  4737.672> [eDVBResourceManager] allocate channel.. 0802:0002
<  4737.672> [eDVBResourceManager] available channel.. 080e:0002
<  4737.672> [eDVBFrontend] opening frontend 0
<  4737.676> [eDVBFrontend] (0)tune
<  4737.676> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4737.676> **** Tuning JESS
<  4737.676> **** frequency_mhz: 10847
<  4737.676> **** lo_mhz: 9750
<  4737.676> **** T: 997
<  4737.676> **** position: 0
<  4737.676> **** ub: 0
<  4737.676> **** mode: 0
<  4737.676> **** JESS: 70 03 e5 00
<  4737.676> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4737.676> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4737.676> [eDVBFrontend 0] tuning to 1211 mhz
<  4737.676> [eDVBFrontend 0] m_state: 0, stateFailed: 2, stateTuning: 468340)
<  4737.676> [eDVBChannel] OURSTATE: tuning
<  4737.676> [eDVBServicePMTHandler] allocate Channel: res 0
<  4737.676> [eDVBCIInterfaces] addPMTHandler 1:0:19:1B1D:802:2:11A0000:0:0:0:
<  4737.676> [eDVBChannel] getDemux cap=00
<  4737.676> [eDVBResourceManager] allocate demux cap=00
<  4737.676> [eDVBResourceManager] allocating demux adapter=0, demux=1, source=-1 fesource=0
<  4737.676> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4737.677> [eEPGCache] saveEventToFile epg event id fed3
<  4737.677> [RecordTimer] prepare ok, waiting for begin
<  4737.679> [Trashcan] probing folders
<  4737.681> [Trashcan] found following trashcan's: ['/media/hdd/.Trash', '/media/hdd/movie/.Trash']
<  4737.682> [Trashcan] looking in trashcan /media/hdd/.Trash
<  4737.682> [Trashcan] /media/hdd/.Trash: Size: 0
<  4737.682> [Trashcan] /media/hdd/.Trash: Size now: 0
<  4737.682> [Trashcan] looking in trashcan /media/hdd/movie/.Trash
<  4737.689> [RecordTimer] activating state 1
<  4737.691> [RecordTimer] Found enough free space to record
<  4737.691> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181017 0155 - ITV London HD - Jackpot247'
<  4737.692> [Navigation] recording service: 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<  4737.692> [eDVBResourceManager] allocate channel.. 0812:0002
<  4737.692> [eDVBResourceManager] available channel.. 080e:0002
<  4737.692> [eDVBResourceManager] available channel.. 0802:0002
<  4737.692> [eDVBFrontend] opening frontend 3
<  4737.695> [eDVBFrontend] (3)tune
<  4737.695> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2156, guard_offset 0
<  4737.695> **** Tuning JESS
<  4737.695> **** frequency_mhz: 11097
<  4737.695> **** lo_mhz: 9750
<  4737.695> **** T: 1247
<  4737.695> **** position: 0
<  4737.695> **** ub: 1
<  4737.695> **** mode: 0
<  4737.695> **** JESS: 70 0c df 00
<  4737.695> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4737.695> [eDVBFrontend 3] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4737.695> [eDVBFrontend 3] tuning to 1421 mhz
<  4737.695> [eDVBFrontend 3] m_state: 0, stateFailed: 2, stateTuning: 468340)
<  4737.695> [eDVBChannel] OURSTATE: tuning
<  4737.695> [eDVBServicePMTHandler] allocate Channel: res 0
<  4737.695> [eDVBCIInterfaces] addPMTHandler 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<  4737.695> [eDVBChannel] getDemux cap=00
<  4737.695> [eDVBResourceManager] allocate demux cap=00
<  4737.695> [eDVBResourceManager] allocating demux adapter=0, demux=2, source=-1 fesource=3
<  4737.695> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4737.696> [eEPGCache] saveEventToFile epg event id 33
<  4737.699> [RecordTimer] prepare ok, waiting for begin
<  4737.700> [Trashcan] Cleanup already running
<  4737.700> [RecordTimer] activating state 1
<  4737.701> [RecordTimer] Found enough free space to record
<  4737.702> [RecordTimer] Filename calculated as: '/media/hdd/movie/20181017 0155 - Channel 5 HD - The Suffolk Strangler_ Killer___'
<  4737.702> [Navigation] recording service: 1:0:19:1E46:809:2:11A0000:0:0:0:
<  4737.703> [eDVBResourceManager] allocate channel.. 0809:0002
<  4737.703> [eDVBResourceManager] available channel.. 080e:0002
<  4737.703> [eDVBResourceManager] available channel.. 0802:0002
<  4737.703> [eDVBResourceManager] available channel.. 0812:0002
<  4737.703> [eDVBFrontend] opening frontend 4
<  4737.705> [eDVBFrontend] (4)tune
<  4737.705> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4737.705> **** Tuning JESS
<  4737.705> **** frequency_mhz: 10964
<  4737.705> **** lo_mhz: 9750
<  4737.705> **** T: 1114
<  4737.705> **** position: 0
<  4737.705> **** ub: 2
<  4737.705> **** mode: 2
<  4737.705> **** JESS: 70 14 5a 02
<  4737.705> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4737.705> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4737.705> [eDVBFrontend 4] tuning to 1681 mhz
<  4737.705> [eDVBFrontend 4] m_state: 0, stateFailed: 2, stateTuning: 468340)
<  4737.705> [eDVBChannel] OURSTATE: tuning
<  4737.705> [eDVBServicePMTHandler] allocate Channel: res 0
<  4737.705> [eDVBCIInterfaces] addPMTHandler 1:0:19:1E46:809:2:11A0000:0:0:0:
<  4737.705> [eDVBChannel] getDemux cap=00
<  4737.705> [eDVBResourceManager] allocate demux cap=00
<  4737.706> [eDVBResourceManager] allocating demux adapter=0, demux=3, source=-1 fesource=4
<  4737.706> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4737.706> [eEPGCache] saveEventToFile epg event id 4bf
<  4737.710> [RecordTimer] prepare ok, waiting for begin
<  4737.710> [Trashcan] /media/hdd/movie/.Trash: Size: 22455884478
<  4737.711> [Trashcan] Cleanup already running
<  4737.711> [eDVBFrontend 0] set static current limiting
<  4737.712> [eDVBFrontend 3] set static current limiting
<  4737.712> [eDVBFrontend 4] set static current limiting
<  4737.712> [eDVBFrontend 0] invalidate current switch params
<  4737.712> [eDVBFrontend 3] invalidate current switch params
<  4737.712> [eDVBFrontend 4] invalidate current switch params
<  4737.712> [eDVBFrontend 0] setVoltage 1
<  4737.713> [eDVBFrontend 3] setVoltage 1
<  4737.714> [eDVBFrontend 4] setVoltage 1
<  4737.715> [eDVBFrontend 0] sleep 1500ms
<  4737.715> [eDVBFrontend 3] sleep 1500ms
<  4737.715> [eDVBFrontend 4] sleep 1500ms
<  4737.733> [Trashcan] /media/hdd/movie/.Trash: Size now: 22455884478
<  4737.734> [Task] job Components.Task.Job name=Cleaning Trashes #tasks=1 completed with [] in None
<  4739.215> [eDVBFrontend 0] setVoltage 2
<  4739.216> [eDVBFrontend 3] setVoltage 2
<  4739.216> [eDVBFrontend 4] setVoltage 2
<  4739.217> [eDVBFrontend 0] setTone 0
<  4739.218> [eDVBFrontend 3] setTone 0
<  4739.218> [eDVBFrontend 4] setTone 0
<  4739.219> [eDVBFrontend 0] sleep 75ms
<  4739.219> [eDVBFrontend 3] sleep 75ms
<  4739.219> [eDVBFrontend 4] sleep 75ms
<  4739.350> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4739.406> [eDVBFrontend 3] sendDiseqc: 700cdf00(?)
<  4739.462> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4739.462> [eDVBFrontend 0] sleep 100ms
<  4739.462> [eDVBFrontend 3] sleep 100ms
<  4739.462> [eDVBFrontend 4] sleep 100ms
<  4739.562> [eDVBFrontend 0] setVoltage 1
<  4739.563> [eDVBFrontend 3] setVoltage 1
<  4739.564> [eDVBFrontend 4] setVoltage 1
<  4739.565> [eDVBFrontend 0] update current switch params
<  4739.565> [eDVBFrontend 3] update current switch params
<  4739.565> [eDVBFrontend 4] update current switch params
<  4739.565> [eDVBFrontend 0] startTuneTimeout 5000
<  4739.565> [eDVBFrontend 3] startTuneTimeout 5000
<  4739.565> [eDVBFrontend 4] startTuneTimeout 5000
<  4739.565> [eDVBFrontend 0] setFrontend 1
<  4739.565> [eDVBFrontend] setting frontend 0
<  4739.567> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4739.567> [eDVBFrontend 3] setFrontend 1
<  4739.567> [eDVBFrontend] setting frontend 3
<  4739.570> [eDVBFrontend 4] setFrontend 1
<  4739.570> [eDVBFrontend] setting frontend 4
<  4739.570> [eDVBFrontend 0] sleep 500ms
<  4739.575> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  4739.577> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4739.577> [eDVBFrontend 3] sleep 500ms
<  4739.577> [eDVBFrontend 4] sleep 500ms
<  4739.610> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  4739.620> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4739.620> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  4740.070> [eDVBFrontend 0] set dynamic current limiting
<  4740.077> [eDVBFrontend 3] set dynamic current limiting
<  4740.078> [eDVBFrontend 4] set dynamic current limiting
<  4744.565> [eDVBChannel] OURSTATE: failed, retune
<  4744.565> [eDVBFrontend] (0)tune
<  4744.565> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4744.565> **** Tuning JESS
<  4744.565> **** frequency_mhz: 10847
<  4744.565> **** lo_mhz: 9750
<  4744.565> **** T: 997
<  4744.565> **** position: 0
<  4744.565> **** ub: 0
<  4744.565> **** mode: 0
<  4744.565> **** JESS: 70 03 e5 00
<  4744.565> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4744.565> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4744.565> [eDVBFrontend 0] tuning to 1211 mhz
<  4744.565> [eDVBFrontend 0] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4744.565> [eDVBChannel] OURSTATE: tuning
<  4744.565> [eDVBServicePMTHandler] tune failed.
<  4744.565> [eDVBServiceRecord] RECORD service event 1
<  4744.565> [eDVBServiceRecord] record failed to tune
<  4744.565> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4744.565> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4744.565> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  4744.565> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4744.565> [eDVBChannel] OURSTATE: failed, retune
<  4744.566> [eDVBFrontend] (3)tune
<  4744.566> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2156, guard_offset 0
<  4744.566> **** Tuning JESS
<  4744.566> **** frequency_mhz: 11097
<  4744.566> **** lo_mhz: 9750
<  4744.566> **** T: 1247
<  4744.566> **** position: 0
<  4744.566> **** ub: 1
<  4744.566> **** mode: 0
<  4744.566> **** JESS: 70 0c df 00
<  4744.566> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4744.566> [eDVBFrontend 3] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4744.566> [eDVBFrontend 3] tuning to 1421 mhz
<  4744.566> [eDVBFrontend 3] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4744.566> [eDVBChannel] OURSTATE: tuning
<  4744.566> [eDVBServicePMTHandler] tune failed.
<  4744.566> [eDVBServiceRecord] RECORD service event 1
<  4744.566> [eDVBServiceRecord] record failed to tune
<  4744.566> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4744.566> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4744.566> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  4744.566> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4744.566> [eDVBChannel] OURSTATE: failed, retune
<  4744.566> [eDVBFrontend] (4)tune
<  4744.566> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4744.566> **** Tuning JESS
<  4744.566> **** frequency_mhz: 10964
<  4744.566> **** lo_mhz: 9750
<  4744.566> **** T: 1114
<  4744.566> **** position: 0
<  4744.566> **** ub: 2
<  4744.566> **** mode: 2
<  4744.566> **** JESS: 70 14 5a 02
<  4744.566> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4744.566> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4744.566> [eDVBFrontend 4] tuning to 1681 mhz
<  4744.566> [eDVBFrontend 4] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4744.566> [eDVBChannel] OURSTATE: tuning
<  4744.566> [eDVBServicePMTHandler] tune failed.
<  4744.566> [eDVBServiceRecord] RECORD service event 1
<  4744.566> [eDVBServiceRecord] record failed to tune
<  4744.566> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4744.566> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4744.566> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  4744.566> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4744.600> [eDVBFrontend 4] set static current limiting
<  4744.601> [eDVBFrontend 4] invalidate current switch params
<  4744.601> [eDVBFrontend 4] set sequence pos 3
<  4744.601> [eDVBFrontend 4] setVoltage 2
<  4744.602> [eDVBFrontend 4] setTone 0
<  4744.602> [eDVBFrontend 4] sleep 75ms
<  4744.627> [eDVBFrontend 3] set static current limiting
<  4744.627> [eDVBFrontend 3] invalidate current switch params
<  4744.627> [eDVBFrontend 3] set sequence pos 3
<  4744.627> [eDVBFrontend 3] setVoltage 2
<  4744.628> [eDVBFrontend 3] setTone 0
<  4744.628> [eDVBFrontend 3] sleep 75ms
<  4744.633> [eDVBFrontend 0] set static current limiting
<  4744.633> [eDVBFrontend 0] invalidate current switch params
<  4744.633> [eDVBFrontend 0] set sequence pos 3
<  4744.633> [eDVBFrontend 0] setVoltage 2
<  4744.634> [eDVBFrontend 0] setTone 0
<  4744.635> [eDVBFrontend 0] sleep 75ms
<  4744.733> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4744.789> [eDVBFrontend 3] sendDiseqc: 700cdf00(?)
<  4744.845> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4744.845> [eDVBFrontend 4] sleep 100ms
<  4744.845> [eDVBFrontend 3] sleep 100ms
<  4744.845> [eDVBFrontend 0] sleep 100ms
<  4744.945> [eDVBFrontend 4] setVoltage 1
<  4744.946> [eDVBFrontend 3] setVoltage 1
<  4744.946> [eDVBFrontend 0] setVoltage 1
<  4744.947> [eDVBFrontend 4] update current switch params
<  4744.947> [eDVBFrontend 3] update current switch params
<  4744.947> [eDVBFrontend 0] update current switch params
<  4744.947> [eDVBFrontend 4] startTuneTimeout 5000
<  4744.947> [eDVBFrontend 3] startTuneTimeout 5000
<  4744.947> [eDVBFrontend 0] startTuneTimeout 5000
<  4744.947> [eDVBFrontend 4] setFrontend 1
<  4744.947> [eDVBFrontend] setting frontend 4
<  4744.947> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4744.947> [eDVBFrontend 3] setFrontend 1
<  4744.947> [eDVBFrontend] setting frontend 3
<  4744.947> [eDVBFrontend 0] setFrontend 1
<  4744.947> [eDVBFrontend] setting frontend 0
<  4744.947> [eDVBFrontend 4] sleep 500ms
<  4744.947> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4744.947> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  4744.947> [eDVBFrontend 3] sleep 500ms
<  4744.947> [eDVBFrontend 0] sleep 500ms
<  4744.990> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  4744.990> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  4744.991> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4745.447> [eDVBFrontend 4] set dynamic current limiting
<  4745.448> [eDVBFrontend 3] set dynamic current limiting
<  4745.448> [eDVBFrontend 0] set dynamic current limiting
<  4749.947> [eDVBChannel] OURSTATE: failed, retune
<  4749.947> [eDVBFrontend] (4)tune
<  4749.952> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4749.952> **** Tuning JESS
<  4749.952> **** frequency_mhz: 10964
<  4749.952> **** lo_mhz: 9750
<  4749.952> **** T: 1114
<  4749.952> **** position: 0
<  4749.952> **** ub: 2
<  4749.952> **** mode: 2
<  4749.952> **** JESS: 70 14 5a 02
<  4749.952> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4749.952> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4749.952> [eDVBFrontend 4] tuning to 1681 mhz
<  4749.952> [eDVBFrontend 4] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4749.952> [eDVBChannel] OURSTATE: tuning
<  4749.952> [eDVBServicePMTHandler] tune failed.
<  4749.952> [eDVBServiceRecord] RECORD service event 1
<  4749.952> [eDVBServiceRecord] record failed to tune
<  4749.952> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4749.952> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4749.952> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  4749.952> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4749.952> [eDVBChannel] OURSTATE: failed, retune
<  4749.952> [eDVBFrontend] (3)tune
<  4749.952> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2156, guard_offset 0
<  4749.952> **** Tuning JESS
<  4749.952> **** frequency_mhz: 11097
<  4749.952> **** lo_mhz: 9750
<  4749.952> **** T: 1247
<  4749.952> **** position: 0
<  4749.952> **** ub: 1
<  4749.952> **** mode: 0
<  4749.952> **** JESS: 70 0c df 00
<  4749.952> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4749.952> [eDVBFrontend 3] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4749.953> [eDVBFrontend 3] tuning to 1421 mhz
<  4749.953> [eDVBFrontend 3] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4749.953> [eDVBChannel] OURSTATE: tuning
<  4749.953> [eDVBServicePMTHandler] tune failed.
<  4749.953> [eDVBServiceRecord] RECORD service event 1
<  4749.953> [eDVBServiceRecord] record failed to tune
<  4749.953> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4749.953> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4749.953> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  4749.953> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4749.953> [eDVBChannel] OURSTATE: failed, retune
<  4749.953> [eDVBFrontend] (0)tune
<  4749.953> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4749.953> **** Tuning JESS
<  4749.953> **** frequency_mhz: 10847
<  4749.953> **** lo_mhz: 9750
<  4749.953> **** T: 997
<  4749.953> **** position: 0
<  4749.953> **** ub: 0
<  4749.953> **** mode: 0
<  4749.953> **** JESS: 70 03 e5 00
<  4749.953> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4749.953> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4749.953> [eDVBFrontend 0] tuning to 1211 mhz
<  4749.953> [eDVBFrontend 0] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4749.953> [eDVBChannel] OURSTATE: tuning
<  4749.953> [eDVBServicePMTHandler] tune failed.
<  4749.953> [eDVBServiceRecord] RECORD service event 1
<  4749.953> [eDVBServiceRecord] record failed to tune
<  4749.953> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4749.953> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4749.953> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  4749.953> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4749.971> [eDVBFrontend 3] set static current limiting
<  4749.971> [eDVBFrontend 3] invalidate current switch params
<  4749.971> [eDVBFrontend 3] set sequence pos 3
<  4749.971> [eDVBFrontend 3] setVoltage 2
<  4749.972> [eDVBFrontend 3] setTone 0
<  4749.972> [eDVBFrontend 3] sleep 75ms
<  4749.978> [eDVBFrontend 0] set static current limiting
<  4749.978> [eDVBFrontend 0] invalidate current switch params
<  4749.978> [eDVBFrontend 0] set sequence pos 3
<  4749.978> [eDVBFrontend 0] setVoltage 2
<  4749.979> [eDVBFrontend 0] setTone 0
<  4749.980> [eDVBFrontend 0] sleep 75ms
<  4750.043> [eDVBFrontend 4] set static current limiting
<  4750.043> [eDVBFrontend 4] invalidate current switch params
<  4750.043> [eDVBFrontend 4] set sequence pos 3
<  4750.043> [eDVBFrontend 4] setVoltage 2
<  4750.044> [eDVBFrontend 4] setTone 0
<  4750.045> [eDVBFrontend 4] sleep 75ms
<  4750.103> [eDVBFrontend 3] sendDiseqc: 700cdf00(?)
<  4750.159> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4750.159> [eDVBFrontend 3] sleep 100ms
<  4750.215> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4750.216> [eDVBFrontend 0] sleep 100ms
<  4750.216> [eDVBFrontend 4] sleep 100ms
<  4750.259> [eDVBFrontend 3] setVoltage 1
<  4750.260> [eDVBFrontend 3] update current switch params
<  4750.260> [eDVBFrontend 3] startTuneTimeout 5000
<  4750.260> [eDVBFrontend 3] setFrontend 1
<  4750.260> [eDVBFrontend] setting frontend 3
<  4750.260> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  4750.260> [eDVBFrontend 3] sleep 500ms
<  4750.310> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  4750.316> [eDVBFrontend 0] setVoltage 1
<  4750.317> [eDVBFrontend 4] setVoltage 1
<  4750.318> [eDVBFrontend 0] update current switch params
<  4750.318> [eDVBFrontend 4] update current switch params
<  4750.318> [eDVBFrontend 0] startTuneTimeout 5000
<  4750.318> [eDVBFrontend 4] startTuneTimeout 5000
<  4750.318> [eDVBFrontend 0] setFrontend 1
<  4750.318> [eDVBFrontend] setting frontend 0
<  4750.318> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4750.318> [eDVBFrontend 4] setFrontend 1
<  4750.318> [eDVBFrontend] setting frontend 4
<  4750.318> [eDVBFrontend 0] sleep 500ms
<  4750.319> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4750.319> [eDVBFrontend 4] sleep 500ms
<  4750.361> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  4750.361> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4750.760> [eDVBFrontend 3] set dynamic current limiting
<  4750.819> [eDVBFrontend 0] set dynamic current limiting
<  4750.819> [eDVBFrontend 4] set dynamic current limiting
<  4755.260> [eDVBChannel] OURSTATE: failed, retune
<  4755.260> [eDVBFrontend] (3)tune
<  4755.260> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2156, guard_offset 0
<  4755.260> **** Tuning JESS
<  4755.260> **** frequency_mhz: 11097
<  4755.260> **** lo_mhz: 9750
<  4755.260> **** T: 1247
<  4755.260> **** position: 0
<  4755.260> **** ub: 1
<  4755.260> **** mode: 0
<  4755.260> **** JESS: 70 0c df 00
<  4755.260> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4755.260> [eDVBFrontend 3] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4755.260> [eDVBFrontend 3] tuning to 1421 mhz
<  4755.260> [eDVBFrontend 3] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4755.260> [eDVBChannel] OURSTATE: tuning
<  4755.260> [eDVBServicePMTHandler] tune failed.
<  4755.260> [eDVBServiceRecord] RECORD service event 1
<  4755.260> [eDVBServiceRecord] record failed to tune
<  4755.261> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4755.261> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4755.261> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  4755.261> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4755.308> [eDVBFrontend 3] set static current limiting
<  4755.308> [eDVBFrontend 3] invalidate current switch params
<  4755.308> [eDVBFrontend 3] set sequence pos 3
<  4755.308> [eDVBFrontend 3] setVoltage 2
<  4755.308> [eDVBFrontend 3] setTone 0
<  4755.309> [eDVBFrontend 3] sleep 75ms
<  4755.318> [eDVBChannel] OURSTATE: failed, retune
<  4755.318> [eDVBFrontend] (0)tune
<  4755.318> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4755.318> **** Tuning JESS
<  4755.318> **** frequency_mhz: 10847
<  4755.318> **** lo_mhz: 9750
<  4755.318> **** T: 997
<  4755.318> **** position: 0
<  4755.318> **** ub: 0
<  4755.318> **** mode: 0
<  4755.318> **** JESS: 70 03 e5 00
<  4755.318> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4755.319> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4755.319> [eDVBFrontend 0] tuning to 1211 mhz
<  4755.319> [eDVBFrontend 0] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4755.319> [eDVBChannel] OURSTATE: tuning
<  4755.319> [eDVBServicePMTHandler] tune failed.
<  4755.319> [eDVBServiceRecord] RECORD service event 1
<  4755.319> [eDVBServiceRecord] record failed to tune
<  4755.319> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4755.319> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4755.319> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  4755.319> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4755.319> [eDVBChannel] OURSTATE: failed, retune
<  4755.319> [eDVBFrontend] (4)tune
<  4755.319> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4755.319> **** Tuning JESS
<  4755.319> **** frequency_mhz: 10964
<  4755.319> **** lo_mhz: 9750
<  4755.319> **** T: 1114
<  4755.319> **** position: 0
<  4755.319> **** ub: 2
<  4755.319> **** mode: 2
<  4755.319> **** JESS: 70 14 5a 02
<  4755.319> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4755.319> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4755.319> [eDVBFrontend 4] tuning to 1681 mhz
<  4755.319> [eDVBFrontend 4] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4755.319> [eDVBChannel] OURSTATE: tuning
<  4755.319> [eDVBServicePMTHandler] tune failed.
<  4755.319> [eDVBServiceRecord] RECORD service event 1
<  4755.319> [eDVBServiceRecord] record failed to tune
<  4755.320> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4755.320> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4755.320> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  4755.320> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4755.330> [eDVBFrontend 0] set static current limiting
<  4755.330> [eDVBFrontend 0] invalidate current switch params
<  4755.330> [eDVBFrontend 0] set sequence pos 3
<  4755.330> [eDVBFrontend 0] setVoltage 2
<  4755.331> [eDVBFrontend 0] setTone 0
<  4755.331> [eDVBFrontend 0] sleep 75ms
<  4755.335> [eDVBFrontend 4] set static current limiting
<  4755.335> [eDVBFrontend 4] invalidate current switch params
<  4755.335> [eDVBFrontend 4] set sequence pos 3
<  4755.336> [eDVBFrontend 4] setVoltage 2
<  4755.336> [eDVBFrontend 4] setTone 0
<  4755.337> [eDVBFrontend 4] sleep 75ms
<  4755.443> [eDVBFrontend 3] sendDiseqc: 700cdf00(?)
<  4755.499> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4755.555> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4755.555> [eDVBFrontend 3] sleep 100ms
<  4755.555> [eDVBFrontend 0] sleep 100ms
<  4755.555> [eDVBFrontend 4] sleep 100ms
<  4755.655> [eDVBFrontend 3] setVoltage 1
<  4755.656> [eDVBFrontend 0] setVoltage 1
<  4755.656> [eDVBFrontend 4] setVoltage 1
<  4755.657> [eDVBFrontend 3] update current switch params
<  4755.657> [eDVBFrontend 0] update current switch params
<  4755.657> [eDVBFrontend 4] update current switch params
<  4755.657> [eDVBFrontend 3] startTuneTimeout 5000
<  4755.657> [eDVBFrontend 0] startTuneTimeout 5000
<  4755.657> [eDVBFrontend 4] startTuneTimeout 5000
<  4755.657> [eDVBFrontend 3] setFrontend 1
<  4755.657> [eDVBFrontend] setting frontend 3
<  4755.657> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  4755.657> [eDVBFrontend 0] setFrontend 1
<  4755.657> [eDVBFrontend] setting frontend 0
<  4755.657> [eDVBFrontend 4] setFrontend 1
<  4755.657> [eDVBFrontend] setting frontend 4
<  4755.657> [eDVBFrontend 3] sleep 500ms
<  4755.657> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4755.657> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4755.657> [eDVBFrontend 0] sleep 500ms
<  4755.657> [eDVBFrontend 4] sleep 500ms
<  4755.700> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  4755.700> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  4755.701> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4756.157> [eDVBFrontend 3] set dynamic current limiting
<  4756.158> [eDVBFrontend 0] set dynamic current limiting
<  4756.158> [eDVBFrontend 4] set dynamic current limiting
<  4757.711> [RecordTimer] activating state 2
<  4757.713> [RecordTimer] start recording on tuner: A
<  4757.713> [RecordTimer] activating state 2
<  4757.714> [RecordTimer] start recording on tuner: D
<  4757.714> [RecordTimer] activating state 2
<  4757.714> [RecordTimer] start recording on tuner: E
<  4760.657> [eDVBChannel] OURSTATE: failed, retune
<  4760.657> [eDVBFrontend] (3)tune
<  4760.659> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x2156, guard_offset 0
<  4760.659> **** Tuning JESS
<  4760.659> **** frequency_mhz: 11097
<  4760.659> **** lo_mhz: 9750
<  4760.660> **** T: 1247
<  4760.660> **** position: 0
<  4760.660> **** ub: 1
<  4760.660> **** mode: 0
<  4760.660> **** JESS: 70 0c df 00
<  4760.660> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4760.660> [eDVBFrontend 3] prepare_sat System 1 Freq 11097000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4760.660> [eDVBFrontend 3] tuning to 1421 mhz
<  4760.660> [eDVBFrontend 3] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4760.660> [eDVBChannel] OURSTATE: tuning
<  4760.660> [eDVBServicePMTHandler] tune failed.
<  4760.660> [eDVBServiceRecord] RECORD service event 1
<  4760.660> [eDVBServiceRecord] record failed to tune
<  4760.660> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4760.660> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4760.660> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 812 current: ffffffff
<  4760.660> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4760.660> [eDVBChannel] OURSTATE: failed, retune
<  4760.660> [eDVBFrontend] (0)tune
<  4760.660> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4760.660> **** Tuning JESS
<  4760.660> **** frequency_mhz: 10847
<  4760.660> **** lo_mhz: 9750
<  4760.660> **** T: 997
<  4760.660> **** position: 0
<  4760.660> **** ub: 0
<  4760.660> **** mode: 0
<  4760.660> **** JESS: 70 03 e5 00
<  4760.660> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4760.660> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4760.661> [eDVBFrontend 0] tuning to 1211 mhz
<  4760.661> [eDVBFrontend 0] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4760.661> [eDVBChannel] OURSTATE: tuning
<  4760.661> [eDVBServicePMTHandler] tune failed.
<  4760.661> [eDVBServiceRecord] RECORD service event 1
<  4760.661> [eDVBServiceRecord] record failed to tune
<  4760.661> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4760.661> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4760.661> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  4760.661> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4760.661> [eDVBChannel] OURSTATE: failed, retune
<  4760.661> [eDVBFrontend] (4)tune
<  4760.661> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4760.661> **** Tuning JESS
<  4760.661> **** frequency_mhz: 10964
<  4760.661> **** lo_mhz: 9750
<  4760.661> **** T: 1114
<  4760.661> **** position: 0
<  4760.661> **** ub: 2
<  4760.661> **** mode: 2
<  4760.661> **** JESS: 70 14 5a 02
<  4760.661> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4760.661> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4760.661> [eDVBFrontend 4] tuning to 1681 mhz
<  4760.661> [eDVBFrontend 4] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4760.661> [eDVBChannel] OURSTATE: tuning
<  4760.661> [eDVBServicePMTHandler] tune failed.
<  4760.661> [eDVBServiceRecord] RECORD service event 1
<  4760.661> [eDVBServiceRecord] record failed to tune
<  4760.661> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4760.661> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4760.661> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  4760.661> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4760.665> [eDVBFrontend 4] set static current limiting
<  4760.665> [eDVBFrontend 4] invalidate current switch params
<  4760.665> [eDVBFrontend 4] set sequence pos 3
<  4760.665> [eDVBFrontend 4] setVoltage 2
<  4760.666> [eDVBFrontend 4] setTone 0
<  4760.667> [eDVBFrontend 4] sleep 75ms
<  4760.716> [eDVBFrontend 0] set static current limiting
<  4760.716> [eDVBFrontend 0] invalidate current switch params
<  4760.716> [eDVBFrontend 0] set sequence pos 3
<  4760.716> [eDVBFrontend 0] setVoltage 2
<  4760.717> [eDVBFrontend 0] setTone 0
<  4760.718> [eDVBFrontend 0] sleep 75ms
<  4760.798> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4760.798> [eDVBFrontend 3] set static current limiting
<  4760.854> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4760.854> [eDVBFrontend 4] sleep 100ms
<  4760.854> [eDVBFrontend 3] invalidate current switch params
<  4760.854> [eDVBFrontend 0] sleep 100ms
<  4760.854> [eDVBFrontend 3] set sequence pos 3
<  4760.854> [eDVBFrontend 3] setVoltage 2
<  4760.855> [eDVBFrontend 3] setTone 0
<  4760.856> [eDVBFrontend 3] sleep 75ms
<  4760.987> [eDVBFrontend 3] sendDiseqc: 700cdf00(?)
<  4760.987> [eDVBFrontend 4] setVoltage 1
<  4760.987> [eDVBFrontend 0] setVoltage 1
<  4760.988> [eDVBFrontend 3] sleep 100ms
<  4760.988> [eDVBFrontend 4] update current switch params
<  4760.988> [eDVBFrontend 0] update current switch params
<  4760.988> [eDVBFrontend 4] startTuneTimeout 5000
<  4760.988> [eDVBFrontend 0] startTuneTimeout 5000
<  4760.988> [eDVBFrontend 4] setFrontend 1
<  4760.988> [eDVBFrontend] setting frontend 4
<  4760.988> [eDVBFrontend 0] setFrontend 1
<  4760.988> [eDVBFrontend] setting frontend 0
<  4760.988> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4760.988> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4760.988> [eDVBFrontend 4] sleep 500ms
<  4760.988> [eDVBFrontend 0] sleep 500ms
<  4761.030> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4761.030> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  4761.088> [eDVBFrontend 3] setVoltage 1
<  4761.089> [eDVBFrontend 3] update current switch params
<  4761.089> [eDVBFrontend 3] startTuneTimeout 5000
<  4761.089> [eDVBFrontend 3] setFrontend 1
<  4761.089> [eDVBFrontend] setting frontend 3
<  4761.089> [eDVBFrontend] (3)fe event: status 0, inversion off, m_tuning 1
<  4761.089> [eDVBFrontend 3] sleep 500ms
<  4761.130> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 3
<  4761.130> [eDVBFrontend] (3)fe event: status 7, inversion off, m_tuning 2
<  4761.182> [eDVBFrontend] (3)fe event: status 1f, inversion off, m_tuning 3
<  4761.182> [eDVBChannel] OURSTATE: ok
<  4761.182> [eDVBLocalTimerHandler] channel 0x198d190 running
<  4761.182> [eDVBChannel] getDemux cap=00
<  4761.182> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.183> [eDVBSectionReader] DMX_SET_FILTER pid=20
<  4761.183> [eEPGCache] channel 0x198d190 running
<  4761.183> [eDVBChannel] getDemux cap=00
<  4761.183> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.183> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.184> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.184> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.184> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.184> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.184> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.184> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.185> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.185> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.185> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.185> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.185> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.185> [eDVBChannel] getDemux cap=01
<  4761.185> [eDVBResourceManager] allocate demux cap=01
<  4761.185> [eDVBResourceManager] allocating shared demux adapter=0, demux=2, source=3
<  4761.185> [eDVBServicePMTHandler] ok ... now we start!!
<  4761.185> [eDVBServiceRecord] RECORD service event 5
<  4761.186> [eDVBCAService] new service 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<  4761.186> [eDVBCAService] add demux 2 to slot 0 service 1:0:19:5208:812:2:11A0000:0:0:0::ITV London HD
<  4761.186> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.186> [eEPGCache] next update in 2 sec
<  4761.186> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4761.187> [eDVBServiceRecord] RECORD service event 6
<  4761.187> [eDVBServiceRecord] tuned..
<  4761.187> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.187> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4761.187> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.188> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4761.257> [eDVBServicePMTHandler] PATready
<  4761.257> [eDVBServicePMTHandler] use pmtpid 0102 for service_id 5208
<  4761.257> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.257> [eDVBSectionReader] DMX_SET_FILTER pid=258
<  4761.257> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.258> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4761.259> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.259> [eDVBSectionReader] DMX_SET_FILTER pid=258
<  4761.259> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.260> [eDVBSectionReader] DMX_SET_FILTER pid=17
<  4761.461> [eDVBServiceRecord] RECORD service event 5
<  4761.461> [eDVBServiceRecord] Recording to /media/hdd/movie/20181017 0155 - ITV London HD - Jackpot247.ts...
<  4761.462> [eDVBServiceRecord] start recording...
<  4761.463> [eDVBServiceRecord] RECORD: have 1 video stream(s) (0901), and 2 audio stream(s) (0903, 0902) (0905, 0904), and the pcr pid is 0901, and the text pid is 0904
<  4761.463> [eDVBServiceRecord] ADD PID: 0000
<  4761.463> [eDVBServiceRecord] ADD PID: 0102
<  4761.463> [eDVBServiceRecord] ADD PID: 0901
<  4761.463> [eDVBServiceRecord] ADD PID: 0902
<  4761.463> [eDVBServiceRecord] ADD PID: 0903
<  4761.463> [eDVBServiceRecord] ADD PID: 0904
<  4761.463> [eDVBServiceRecord] ADD PID: 0905
<  4761.465> [setIoPrio] realtime level 7 ok
<  4761.465> [eFilePushThreadRecorder] THREAD START
<  4761.466> [eDVBCIInterfaces] gotPMT
<  4761.467> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.467> [eDVBSectionReader] DMX_SET_FILTER pid=258
<  4761.469> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.469> [eDVBSectionReader] DMX_SET_FILTER pid=260
<  4761.489> [eDVBFrontend 4] set dynamic current limiting
<  4761.489> [eDVBFrontend 0] set dynamic current limiting
<  4761.589> [eDVBFrontend 3] set dynamic current limiting
<  4761.660> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.661> [eDVBSectionReader] DMX_SET_FILTER pid=261
<  4761.852> [eDVBServiceRecord] getting PCR failed!
<  4761.852> [eDVBServiceRecord] now running: †Jackpot247‡ (9000 seconds)
<  4761.853> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.853> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4761.858> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4761.858> [eDVBSectionReader] DMX_SET_FILTER pid=256
<  4762.060> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4762.060> [eDVBSectionReader] DMX_SET_FILTER pid=262
<  4762.262> [eDVBDemux] open demux /dev/dvb/adapter0/demux2
<  4762.263> [eDVBSectionReader] DMX_SET_FILTER pid=263
<  4762.438> [eDVBServicePMTHandler] sdt update done!
<  4763.190> [eEPGCache] start caching events(1539734105)
<  4763.190> [eDVBSectionReader] DMX_SET_FILTER pid=3842
<  4763.190> [eDVBSectionReader] DMX_SET_FILTER pid=3003
<  4763.190> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4763.191> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4763.193> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4763.194> [eDVBSectionReader] DMX_SET_FILTER pid=700
<  4763.194> [eDVBSectionReader] DMX_SET_FILTER pid=700
<  4763.194> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<  4763.195> [eDVBSectionReader] DMX_SET_FILTER pid=5000
<  4763.195> [eDVBSectionReader] DMX_SET_FILTER pid=57
<  4765.988> [eDVBChannel] OURSTATE: failed, retune
<  4765.988> [eDVBFrontend] (4)tune
<  4765.988> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4765.989> **** Tuning JESS
<  4765.989> **** frequency_mhz: 10964
<  4765.989> **** lo_mhz: 9750
<  4765.989> **** T: 1114
<  4765.989> **** position: 0
<  4765.989> **** ub: 2
<  4765.989> **** mode: 2
<  4765.989> **** JESS: 70 14 5a 02
<  4765.989> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4765.989> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4765.989> [eDVBFrontend 4] tuning to 1681 mhz
<  4765.989> [eDVBFrontend 4] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4765.989> [eDVBChannel] OURSTATE: tuning
<  4765.989> [eDVBServicePMTHandler] tune failed.
<  4765.989> [eDVBServiceRecord] RECORD service event 1
<  4765.989> [eDVBServiceRecord] record failed to tune
<  4765.989> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4765.989> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4765.989> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  4765.989> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4765.989> [eDVBChannel] OURSTATE: failed, retune
<  4765.989> [eDVBFrontend] (0)tune
<  4765.989> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4765.989> **** Tuning JESS
<  4765.989> **** frequency_mhz: 10847
<  4765.989> **** lo_mhz: 9750
<  4765.989> **** T: 997
<  4765.989> **** position: 0
<  4765.989> **** ub: 0
<  4765.989> **** mode: 0
<  4765.989> **** JESS: 70 03 e5 00
<  4765.989> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4765.989> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4765.989> [eDVBFrontend 0] tuning to 1211 mhz
<  4765.989> [eDVBFrontend 0] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4765.990> [eDVBChannel] OURSTATE: tuning
<  4765.990> [eDVBServicePMTHandler] tune failed.
<  4765.990> [eDVBServiceRecord] RECORD service event 1
<  4765.990> [eDVBServiceRecord] record failed to tune
<  4765.990> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4765.990> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4765.990> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  4765.990> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4766.034> [eDVBFrontend 0] set static current limiting
<  4766.034> [eDVBFrontend 0] invalidate current switch params
<  4766.034> [eDVBFrontend 0] set sequence pos 3
<  4766.034> [eDVBFrontend 0] setVoltage 2
<  4766.035> [eDVBFrontend 0] setTone 0
<  4766.035> [eDVBFrontend 0] sleep 75ms
<  4766.087> [eDVBFrontend 4] set static current limiting
<  4766.087> [eDVBFrontend 4] invalidate current switch params
<  4766.087> [eDVBFrontend 4] set sequence pos 3
<  4766.087> [eDVBFrontend 4] setVoltage 2
<  4766.088> [eDVBFrontend 4] setTone 0
<  4766.089> [eDVBFrontend 4] sleep 75ms
<  4766.167> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4766.224> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4766.224> [eDVBFrontend 0] sleep 100ms
<  4766.224> [eDVBFrontend 4] sleep 100ms
<  4766.324> [eDVBFrontend 0] setVoltage 1
<  4766.325> [eDVBFrontend 4] setVoltage 1
<  4766.325> [eDVBFrontend 0] update current switch params
<  4766.325> [eDVBFrontend 4] update current switch params
<  4766.325> [eDVBFrontend 0] startTuneTimeout 5000
<  4766.325> [eDVBFrontend 4] startTuneTimeout 5000
<  4766.325> [eDVBFrontend 0] setFrontend 1
<  4766.326> [eDVBFrontend] setting frontend 0
<  4766.326> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4766.326> [eDVBFrontend 4] setFrontend 1
<  4766.326> [eDVBFrontend] setting frontend 4
<  4766.326> [eDVBFrontend 0] sleep 500ms
<  4766.326> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4766.326> [eDVBFrontend 4] sleep 500ms
<  4766.370> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  4766.370> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4766.826> [eDVBFrontend 0] set dynamic current limiting
<  4766.826> [eDVBFrontend 4] set dynamic current limiting
<  4767.871> [eDVBLocalTimerHandler] diff is 0
<  4767.871> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
<  4767.871> [eDVBLocalTimerHandler] not changed
<  4767.872> [eDVBChannel] getDemux cap=00
<  4770.195> [eEPGCache] abort non avail schedule reading
<  4770.197> [eEPGCache] abort non avail schedule other reading
<  4770.197> [eEPGCache] abort non avail virgin nownext reading
<  4770.198> [eEPGCache] abort non avail virgin schedule reading
<  4770.198> [eEPGCache] abort non avail netmed schedule reading
<  4770.198> [eEPGCache] abort non avail netmed schedule other reading
<  4770.198> [eEPGCache] abort non avail viasat reading
<  4770.288> [eEPGCache] nownext finished(1539734112)
<  4771.325> [eDVBChannel] OURSTATE: failed, retune
<  4771.326> [eDVBFrontend] (0)tune
<  4771.338> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4771.338> **** Tuning JESS
<  4771.338> **** frequency_mhz: 10847
<  4771.338> **** lo_mhz: 9750
<  4771.338> **** T: 997
<  4771.338> **** position: 0
<  4771.338> **** ub: 0
<  4771.338> **** mode: 0
<  4771.338> **** JESS: 70 03 e5 00
<  4771.338> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4771.338> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4771.338> [eDVBFrontend 0] tuning to 1211 mhz
<  4771.338> [eDVBFrontend 0] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4771.338> [eDVBChannel] OURSTATE: tuning
<  4771.338> [eDVBServicePMTHandler] tune failed.
<  4771.338> [eDVBServiceRecord] RECORD service event 1
<  4771.338> [eDVBServiceRecord] record failed to tune
<  4771.339> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4771.339> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4771.339> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  4771.339> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4771.339> [eDVBChannel] OURSTATE: failed, retune
<  4771.339> [eDVBFrontend] (4)tune
<  4771.339> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4771.339> **** Tuning JESS
<  4771.339> **** frequency_mhz: 10964
<  4771.339> **** lo_mhz: 9750
<  4771.339> **** T: 1114
<  4771.339> **** position: 0
<  4771.339> **** ub: 2
<  4771.339> **** mode: 2
<  4771.339> **** JESS: 70 14 5a 02
<  4771.339> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4771.339> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4771.339> [eDVBFrontend 4] tuning to 1681 mhz
<  4771.339> [eDVBFrontend 4] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4771.339> [eDVBChannel] OURSTATE: tuning
<  4771.339> [eDVBServicePMTHandler] tune failed.
<  4771.339> [eDVBServiceRecord] RECORD service event 1
<  4771.339> [eDVBServiceRecord] record failed to tune
<  4771.339> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4771.339> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4771.339> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  4771.339> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4771.383> [eDVBFrontend 4] set static current limiting
<  4771.383> [eDVBFrontend 4] invalidate current switch params
<  4771.383> [eDVBFrontend 4] set sequence pos 3
<  4771.384> [eDVBFrontend 4] setVoltage 2
<  4771.388> [eDVBFrontend 4] setTone 0
<  4771.390> [eDVBFrontend 4] sleep 75ms
<  4771.420> [eDVBFrontend 0] set static current limiting
<  4771.420> [eDVBFrontend 0] invalidate current switch params
<  4771.420> [eDVBFrontend 0] set sequence pos 3
<  4771.421> [eDVBFrontend 0] setVoltage 2
<  4771.421> [eDVBFrontend 0] setTone 0
<  4771.422> [eDVBFrontend 0] sleep 75ms
<  4771.521> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4771.577> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4771.577> [eDVBFrontend 4] sleep 100ms
<  4771.578> [eDVBFrontend 0] sleep 100ms
<  4771.677> [eDVBFrontend 4] setVoltage 1
<  4771.678> [eDVBFrontend 0] setVoltage 1
<  4771.679> [eDVBFrontend 4] update current switch params
<  4771.679> [eDVBFrontend 0] update current switch params
<  4771.679> [eDVBFrontend 4] startTuneTimeout 5000
<  4771.679> [eDVBFrontend 0] startTuneTimeout 5000
<  4771.679> [eDVBFrontend 4] setFrontend 1
<  4771.679> [eDVBFrontend] setting frontend 4
<  4771.679> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4771.679> [eDVBFrontend 0] setFrontend 1
<  4771.679> [eDVBFrontend] setting frontend 0
<  4771.679> [eDVBFrontend 4] sleep 500ms
<  4771.679> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4771.679> [eDVBFrontend 0] sleep 500ms
<  4771.721> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4771.721> [eDVBFrontend] (0)fe event: status 7, inversion off, m_tuning 2
<  4772.179> [eDVBFrontend 4] set dynamic current limiting
<  4772.180> [eDVBFrontend 0] set dynamic current limiting
<  4776.679> [eDVBChannel] OURSTATE: failed, retune
<  4776.679> [eDVBFrontend] (4)tune
<  4776.679> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0x4976, guard_offset 0
<  4776.679> **** Tuning JESS
<  4776.679> **** frequency_mhz: 10964
<  4776.679> **** lo_mhz: 9750
<  4776.679> **** T: 1114
<  4776.679> **** position: 0
<  4776.679> **** ub: 2
<  4776.679> **** mode: 2
<  4776.679> **** JESS: 70 14 5a 02
<  4776.679> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4776.679> [eDVBFrontend 4] prepare_sat System 0 Freq 10964250 Pol 0 SR 22000000 INV 2 FEC 4 orbpos 282 system 0 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0
<  4776.679> [eDVBFrontend 4] tuning to 1681 mhz
<  4776.680> [eDVBFrontend 4] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4776.680> [eDVBChannel] OURSTATE: tuning
<  4776.680> [eDVBServicePMTHandler] tune failed.
<  4776.680> [eDVBServiceRecord] RECORD service event 1
<  4776.680> [eDVBServiceRecord] record failed to tune
<  4776.680> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4776.680> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4776.680> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 809 current: ffffffff
<  4776.680> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4776.680> [eDVBChannel] OURSTATE: failed, retune
<  4776.680> [eDVBFrontend] (0)tune
<  4776.680> [eDVBSatelliteEquipmentControl] [prepare] UnicableTuningWord 0xe3, guard_offset 0
<  4776.680> **** Tuning JESS
<  4776.680> **** frequency_mhz: 10847
<  4776.680> **** lo_mhz: 9750
<  4776.680> **** T: 997
<  4776.680> **** position: 0
<  4776.680> **** ub: 0
<  4776.680> **** mode: 0
<  4776.680> **** JESS: 70 03 e5 00
<  4776.680> [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
<  4776.680> [eDVBFrontend 0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 2 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0
<  4776.680> [eDVBFrontend 0] tuning to 1211 mhz
<  4776.680> [eDVBFrontend 0] m_state: 2, stateFailed: 2, stateTuning: 468340)
<  4776.680> [eDVBChannel] OURSTATE: tuning
<  4776.680> [eDVBServicePMTHandler] tune failed.
<  4776.680> [eDVBServiceRecord] RECORD service event 1
<  4776.680> [eDVBServiceRecord] record failed to tune
<  4776.681> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!
<  4776.681> [eDVBServicePMTHandler] incorrect namespace. expected: 11a0000 current: ffffffff
<  4776.681> [eDVBServicePMTHandler] incorrect transport_stream_id. expected: 802 current: ffffffff
<  4776.681> [eDVBServicePMTHandler] incorrect original_network_id. expected: 2 current: ffffffff
<  4776.689> [eDVBFrontend 0] set static current limiting
<  4776.689> [eDVBFrontend 0] invalidate current switch params
<  4776.689> [eDVBFrontend 0] set sequence pos 3
<  4776.689> [eDVBFrontend 0] setVoltage 2
<  4776.690> [eDVBFrontend 0] setTone 0
<  4776.690> [eDVBFrontend 0] sleep 75ms
<  4776.821> [eDVBFrontend 0] sendDiseqc: 7003e500(?)
<  4776.822> [eDVBFrontend 4] set static current limiting
<  4776.822> [eDVBFrontend 0] sleep 100ms
<  4776.822> [eDVBFrontend 4] invalidate current switch params
<  4776.822> [eDVBFrontend 4] set sequence pos 3
<  4776.822> [eDVBFrontend 4] setVoltage 2
<  4776.823> [eDVBFrontend 4] setTone 0
<  4776.823> [eDVBFrontend 4] sleep 75ms
<  4776.954> [eDVBFrontend 4] sendDiseqc: 70145a02(?)
<  4776.954> [eDVBFrontend 0] setVoltage 1
<  4776.955> [eDVBFrontend 4] sleep 100ms
<  4776.955> [eDVBFrontend 0] update current switch params
<  4776.955> [eDVBFrontend 0] startTuneTimeout 5000
<  4776.955> [eDVBFrontend 0] setFrontend 1
<  4776.955> [eDVBFrontend] setting frontend 0
<  4776.955> [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
<  4776.955> [eDVBFrontend 0] sleep 500ms
<  4777.002> [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 2
<  4777.002> [eDVBChannel] OURSTATE: ok
<  4777.002> [eDVBLocalTimerHandler] channel 0x1968a18 running
<  4777.002> [eDVBChannel] getDemux cap=00
<  4777.002> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.002> [eDVBSectionReader] DMX_SET_FILTER pid=20
<  4777.002> [eEPGCache] channel 0x1968a18 running
<  4777.003> [eDVBChannel] getDemux cap=00
<  4777.003> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.003> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.003> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.003> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.003> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.003> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.004> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.004> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.004> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.004> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.004> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.004> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.004> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.005> [eDVBChannel] getDemux cap=01
<  4777.005> [eDVBResourceManager] allocate demux cap=01
<  4777.005> [eEPGCache] next update in 2 sec
<  4777.005> [eDVBResourceManager] allocating shared demux adapter=0, demux=1, source=0
<  4777.005> [eDVBServicePMTHandler] ok ... now we start!!
<  4777.005> [eDVBServiceRecord] RECORD service event 5
<  4777.005> [eDVBCAService] new service 1:0:19:1B1D:802:2:11A0000:0:0:0:
<  4777.005> [eDVBCAService] add demux 1 to slot 0 service 1:0:19:1B1D:802:2:11A0000:0:0:0:
<  4777.005> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.005> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4777.006> [eDVBServiceRecord] RECORD service event 6
<  4777.006> [eDVBServiceRecord] tuned..
<  4777.006> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.006> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4777.006> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.007> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4777.055> [eDVBFrontend 4] setVoltage 1
<  4777.056> [eDVBFrontend 4] update current switch params
<  4777.056> [eDVBFrontend 4] startTuneTimeout 5000
<  4777.056> [eDVBFrontend 4] setFrontend 1
<  4777.056> [eDVBFrontend] setting frontend 4
<  4777.059> [eDVBFrontend] (4)fe event: status 0, inversion off, m_tuning 1
<  4777.059> [eDVBFrontend 4] sleep 500ms
<  4777.100> [eDVBFrontend] (4)fe event: status 7, inversion off, m_tuning 2
<  4777.125> [eDVBServicePMTHandler] PATready
<  4777.125> [eDVBServicePMTHandler] use pmtpid 0104 for service_id 1b1d
<  4777.125> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.126> [eDVBSectionReader] DMX_SET_FILTER pid=260
<  4777.126> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.126> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4777.128> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.128> [eDVBSectionReader] DMX_SET_FILTER pid=258
<  4777.128> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.128> [eDVBSectionReader] DMX_SET_FILTER pid=17
<  4777.151> [eDVBFrontend] (4)fe event: status 1f, inversion off, m_tuning 3
<  4777.152> [eDVBChannel] OURSTATE: ok
<  4777.152> [eDVBLocalTimerHandler] channel 0x1994a38 running
<  4777.152> [eDVBChannel] getDemux cap=00
<  4777.152> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.152> [eDVBSectionReader] DMX_SET_FILTER pid=20
<  4777.153> [eEPGCache] channel 0x1994a38 running
<  4777.153> [eDVBChannel] getDemux cap=00
<  4777.153> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.153> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.153> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.153> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.153> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.153> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.154> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.154> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.154> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.154> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.154> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.154> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.154> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.155> [eDVBResourceManager] stop release channel timer
<  4777.155> [eDVBChannel] getDemux cap=01
<  4777.155> [eEPGCache] next update in 2 sec
<  4777.155> [eDVBResourceManager] allocate demux cap=01
<  4777.155> [eDVBResourceManager] allocating shared demux adapter=0, demux=3, source=4
<  4777.155> [eDVBServicePMTHandler] ok ... now we start!!
<  4777.155> [eDVBServiceRecord] RECORD service event 5
<  4777.155> [eDVBCAService] new service 1:0:19:1E46:809:2:11A0000:0:0:0:
<  4777.155> [eDVBCAService] add demux 3 to slot 0 service 1:0:19:1E46:809:2:11A0000:0:0:0:
<  4777.155> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.155> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4777.156> [eDVBServiceRecord] RECORD service event 6
<  4777.156> [eDVBServiceRecord] tuned..
<  4777.156> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.156> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4777.156> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.157> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4777.246> [eDVBServicePMTHandler] PATready
<  4777.246> [eDVBServicePMTHandler] use pmtpid 0101 for service_id 1e46
<  4777.246> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.247> [eDVBSectionReader] DMX_SET_FILTER pid=257
<  4777.247> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.247> [eDVBSectionReader] DMX_SET_FILTER pid=0
<  4777.249> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.249> [eDVBSectionReader] DMX_SET_FILTER pid=267
<  4777.249> [eDVBDemux] open demux /dev/dvb/adapter0/demux3
<  4777.249> [eDVBSectionReader] DMX_SET_FILTER pid=17
<  4777.349> [eDVBServiceRecord] now running: †Jagged Edge‡ (6300 seconds)
<  4777.349> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.350> [eDVBSectionReader] DMX_SET_FILTER pid=18
<  4777.437> [eDVBServiceRecord] RECORD service event 5
<  4777.437> [eDVBServiceRecord] Recording to /media/hdd/movie/20181017 0155 - BBC One HD - Jagged Edge.ts...
<  4777.439> [eDVBServiceRecord] start recording...
<  4777.440> [eDVBServiceRecord] RECORD: have 1 video stream(s) (1518), and 2 audio stream(s) (151a, 1519) (151c, 151b), and the pcr pid is 1518, and the text pid is 151b
<  4777.440> [eDVBServiceRecord] ADD PID: 0000
<  4777.440> [eDVBServiceRecord] ADD PID: 0104
<  4777.440> [eDVBServiceRecord] ADD PID: 1518
<  4777.440> [eDVBServiceRecord] ADD PID: 1519
<  4777.440> [eDVBServiceRecord] ADD PID: 151a
<  4777.440> [eDVBServiceRecord] ADD PID: 151b
<  4777.440> [eDVBServiceRecord] ADD PID: 151c
<  4777.442> [setIoPrio] realtime level 7 ok
<  4777.442> [eFilePushThreadRecorder] THREAD START
<  4777.443> [eDVBCIInterfaces] gotPMT
<  4777.443> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.444> [eDVBSectionReader] DMX_SET_FILTER pid=260
<  4777.445> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
<  4777.445> [eDVBSectionReader] DMX_SET_FILTER pid=260
<  4777.447> [eDVBServiceRecord] RECORD service event 5
<  4777.448> [eDVBServiceRecord] Recording to /media/hdd/movie/20181017 0155 - Channel 5 HD - The Suffolk Strangler_ Killer___.ts...
<  4777.448> [eDVBServiceRecord] start recording...
<  4777.449> [eDVBServiceRecord] RECORD: have 1 video stream(s) (0901), and 2 audio stream(s) (0903, 0902) (0905, 0904), and the pcr pid is 0901, and the text pid is 0904
<  4777.449> [eDVBServiceRecord] ADD PID: 0000
<  4777.449> [eDVBServiceRecord] ADD PID: 0101
<  4777.449> [eDVBServiceRecord] ADD PID: 0901
<  4777.449> [eDVBServiceRecord] ADD PID: 0902
<  4777.449> [eDVBServiceRecord] ADD PID: 0903
<  4777.449> [eDVBServiceRecord] ADD PID: 0904
<  4777.449> [eDVBServiceRecord] ADD PID: 0905

[Huevos]

Tried a few more times. Sometimes is works. Sometimes it fails. Tried increasing rand_max to 1 second. still suffers failures.

[Rob van der Does]

 

Problem is not the linked tuners. It is that the commands are sent down the same cable. With Athoik's code the parallel commands for more than one tuner are delayed.

And that actually IS the same problem.

 

Linked tuners = same cable and v.v.

 

It's an issue not specific for FBC tuners, it's the fact that they're linked / looped through.

 

 

And that confirms my findings with the 'old' Unicable LNB (using looped through tuners).
 

[Erik Slagter]

How do you mean, that is also appears on Unicable, not only JESS? I would expect that, just as similar problems with a "regular", but one or more tuners looped through. This issue hits all of them.

 

My simplistic high level approach would be that every group of looped through tuners should take a lock (as in mutex) as they're tuning and they're not finished. That's me easy talking, I am not at all sure this can be done in the way enigma implements it.

[Huevos]

 

 

Problem is not the linked tuners. It is that the commands are sent down the same cable. With Athoik's code the parallel commands for more than one tuner are delayed.

And that actually IS the same problem.

 

Linked tuners = same cable and v.v.

 

It's an issue not specific for FBC tuners, it's the fact that they're linked / looped through.

 

 

And that confirms my findings with the 'old' Unicable LNB (using looped through tuners).
 

 

You're missing my point. It is a matter of timing, not that the tuners are internally linked. If they were externally linked the timing problem still exists.

[Huevos]

Right now I don't think patch 2 is helping. Sometimes it works but it is hit and miss. I'm going to go back to the tuning delay patch and test with externally linked tuners where enigma doesn't know they are externally linked.

[Erik Slagter]

You're missing my point. It is a matter of timing, not that the tuners are internally linked. If they were externally linked the timing problem still exists.

As I understand it now, it's not really an issue of timing as well, but an issue of sequence. I looks like it can be solved without inserting any delay, it's just that enigma should address one tuner, on a shared cable, at a time. Whether they're externally or internally linked, the irrelevant.

 

Maybe if sumo is out I will have a look if we can sequence the tuning mechanism, but it might take a while.