68 replies to this topic

[r00tc0d3]

Ik weet wat timeshift is en dat is hier niet aan de orde. Heet gaat om een opname die stop en weggegooid wordt doordat enigma op standby wordt gezet met een fallback tuner. Niet meer, niet minder.

[WanWizard]

Dan is de discussie inmiddels alle kanten aan het op gaan.

 

Ik wil het morgen ook nog wel even proberen, het blijft lastig als niemand het lijkt te kunnen reproduceren.

[r00tc0d3]

Hier stopt de opname ook bij het in standby zetten van mijn uno4k. De tuner en de HDD zitten in mijn Solo4k. De Uno4k heeft alleen C en de Solo4k heeft C en S2 tuner. Voor de opname was de S2 tuner van de Solo4k in gebruik als fallback. Kijken is geen probleem, want dan staat de Uno4k niet in stand-by. (op de Solo4k staat VTi)

carlo666 meldt het ook met een uno4k.

Bijzonder dat dit niet te reproduceren is. Ik heb het nog met een clean install geprobeerd en ging ook fout.

Je moet wel blijven kijken naar een programma die loopt en waarvan een opname gestart is met bijvoorbeeld de record knop. Daarna op standby en dan stop het opnemen en de entry wordt weggegooid.

Ik bedoel de entry in de opname lijst, niet de opname zelf. Die blijft staan met een lengte van toen enigma nog niet op standby was.

Edited by r00tc0d3, 8 January 2018 - 22:28.

[WanWizard]

Net nog hier geprobeerd.

 

Rode knop indrukken, eerste optie kiezen (stoppen na einde event), even laten lopen, box in standby zetten. Als ik nu op schijf kijk dat zie ik de file gewoon groeien, de opname loopt dus gewoon door. 

 

Ik haal 'm weer uit standby, open de timerlist, en de opname staat er nog gewoon, keurig doorlopend.

 

Dus ik weet 't niet...

[littlesat]

Eindelijk iemand die het ook nabootst en ziet dat er geen probleem is...
Het enige wat iets kan stoppen is de server box zelf... door te stoppen met de stream omdat een tuner ergens anders voor nodig is op de serverbox.

Edited by littlesat, 9 January 2018 - 00:19.

[r00tc0d3]

Ik heb het net ook even weer geprobeerd en het werkt echt niet. De log bewijst het ook.

Maar goed als jullie denken dat het allemaal perfect werkt dan houdt alles op. Erg jammer deze reactie.

Ik weet waar ik het over heb en had jullie graag geholpen maar met deze instelling komen we niet verder.

[littlesat]

Zolang wij het niet kunnen nabootsen is er niets te debuggen... zo is het helaas eenmaal...
wanneer heb je voor het laatst vers geflashed?

[r00tc0d3]

Daar ben ik het niet mee eens. Zonder het te kunnen reproduceren kun je wel degelijk debuggen. Het is mijn werk en heb dit soort zaken regelmatig met klanten moeten doen. Het vraagt wel iets van de klant...

En tuurlijk is het veel makkelijker als het te reproduceren is.

 

Ik heb de Vu Uno 4k SE opnieuw geflashed met de zip file van 08-01-2018 en daarna een update gedaan. Daarna de minimale instelling gedaan. NAS, fallback en een bouquet. Zie enigma2 settings file.

config.audio.volume=100
config.av.videomode.DVI=1080p
config.av.videoport=DVI
config.av.videorate.1080p=multi
config.hdmicec.bookmarks=['/hdd/']
config.misc.do_overscanwizard=false
config.misc.firstrun=false
config.misc.initialchannelselection=false
config.misc.languageselected=false
config.misc.rcused=0
config.misc.startCounter=3
config.misc.videowizardenabled=false
config.movielist.videodirs=['/hdd/movie/']
config.OpenWebif.vpn_access=true
config.plugins.bluetoothsetup.vurcuSkipFwVer=0
config.plugins.CableScan.frequency=403.0
config.plugins.CableScan.modulation=5
config.plugins.configurationbackup.backupdirs=['/etc/default_gw', '/etc/enigma2/', '/etc/hostname', '/etc/network/interfaces', '/etc/resolv.conf', '/etc/wpa_supplicant.ath0.conf', '/etc/wpa_supplicant.conf', '/etc/wpa_supplicant.wlan0.conf']
config.recording.keep_timers=7
config.recording.margin_after=5
config.recording.margin_before=3
config.recording.offline_decode_delay=1000
config.seek.selfdefined_13=15
config.seek.selfdefined_46=60
config.seek.selfdefined_79=300
config.tv.lastroot=1:7:1:0:0:0:0:0:0:0:FROM BOUQUET "bouquets.tv" ORDER BY bouquet;1:7:1:0:0:0:0:0:0:0:FROM BOUQUET "userbouquet.engeland.tv" ORDER BY bouquet;
config.tv.lastservice=1:0:19:1B1D:802:2:11A0000:0:0:0:
config.usage.allowed_timeshift_paths=['/media/hdd/']
config.usage.menu_sort_weight={'mainmenu': {'submenu': {}}}
config.usage.movielist_trashcan_days=8
config.usage.movielist_trashcan_reserve=40
config.usage.remote_fallback=http://10.33.10.71:8001
config.usage.remote_fallback_enabled=true
config.usage.setup_level=expert

Nu volgt de log vanaf het moment dat ik op de instant record knop druk.

action ->  InfobarInstantRecord instantRecord
self.recording: []
[eDVBServicePlay] timeshift
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'key_red' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'h_red' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'key_green' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'h_green' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'key_yellow' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'h_yellow' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'key_blue' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
[Skin] SKIN ERROR in screen 'ChoiceBox' widget 'widget': {PLi-FullNightHD/skin.xml}: component with name 'h_blue' was not found in skin of screen 'ChoiceBox'!. Please contact the skin's author!
action ->  WizardActions ok
pre:
[]
[TIMER] record time changed, start prepare is now: Tue Jan  9 11:30:50 2018
[TimerSanityCheck] possible bug: unknown conflict!
[Timer] Record RecordTimerEntry(name=Homes Under the Hammer, begin=Tue Jan  9 11:31:10 2018, serviceref=1:0:19:1B1D:802:2:11A0000:0:0:0:, justplay=False)
[TIMER] activating state 1
[TIMER] Filename calculated as: '/media/net/tv/movie/20180109 1131 - BBC One HD - Homes Under the Hammer'
recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0xac2e4998> >
[add callback]
[eDVBResourceManager] allocate channel.. 0802:0002
[eDVBResourceManager] available channel.. 0802:0002
[eDVBResourceManager] found shared channel..
[eDVBServicePMTHandler] allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:19:1B1D:802:2:11A0000:0:0:0:
[eDVBChannel] getDemux cap=01
[eDVBServicePMTHandler] ok ... now we start!!
[eDVBServiceRecord] RECORD service event 5
[TIMER] record event 6
[eDVBCAService] new service 1:0:19:1B1D:802:2:11A0000:0:0:0:
[eDVBCAService] add demux 12 to slot 0 service 1:0:19:1B1D:802:2:11A0000:0:0:0:
[eDVBDemux] open demux /dev/dvb/adapter0/demux12
[eDVBSectionReader] DMX_SET_FILTER pid=0
[eDVBServiceRecord] RECORD service event 6
[eDVBServiceRecord] tuned..
[eDVBDemux] open demux /dev/dvb/adapter0/demux12
[eDVBSectionReader] DMX_SET_FILTER pid=18
[TIMER] record event 2
[eDVBChannel] getDemux cap=00
[eEPGCache] saveEventToFile epg event id 92a0
[TIMER] prepare ok, waiting for begin
[TIMER] activating state 2
[TIMER] start recording
getResolvedKey config.usage.blinking_rec_symbol_during_recording failed !! (Typo??)
getResolvedKey config.usage.blinking_rec_symbol_during_recording failed !! (Typo??)
[TIMER] record event 0
AddPopup, id = None
warning, skin is missing element WarningPixmap in <class 'Screens.MessageBox.MessageBox'>(A record has been started:
Homes Under the Hammer)
[eDVBServiceRecord] Recording to /media/net/tv/movie/20180109 1131 - BBC One HD - Homes Under the Hammer.ts...
[eDVBServiceRecord] start recording...
[eDVBServiceRecord] RECORD: have 1 video stream(s) (1518), and 1 audio stream(s) (151a) (0000), and the pcr pid is 1518, and the text pid is 151b
[eDVBServiceRecord] ADD PID: 0000
[eDVBServiceRecord] ADD PID: 1518
[eDVBServiceRecord] ADD PID: 151a
[eDVBServiceRecord] ADD PID: 151b
[setIoPrio] realtime level 7 ok
[eFilePushThreadRecorder] THREAD START
[TIMER] record event 4
after:
[RecordTimerEntry(name=Homes Under the Hammer, begin=Tue Jan  9 11:31:10 2018, serviceref=1:0:19:1B1D:802:2:11A0000:0:0:0:, justplay=False)]
[eDVBServicePMTHandler] PATready
[eDVBServicePMTHandler] use pmtpid 0104 for service_id 1b1d
[eDVBDemux] open demux /dev/dvb/adapter0/demux12
[eDVBSectionReader] DMX_SET_FILTER pid=260
[eDVBDemux] open demux /dev/dvb/adapter0/demux12
[eDVBSectionReader] DMX_SET_FILTER pid=0
[eDVBServiceRecord] RECORD service event 5
[eDVBServiceRecord] start recording...
[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
[eDVBServiceRecord] ADD PID: 0104
[eDVBServiceRecord] ADD PID: 1519
[eDVBServiceRecord] ADD PID: 151c
[TIMER] record event 4
[TIMER] record event 6
[eDVBCIInterfaces] gotPMT
[eDVBDemux] open demux /dev/dvb/adapter0/demux12
[eDVBSectionReader] DMX_SET_FILTER pid=260
[eDVBServiceRecord] getting PCR failed!
[eDVBServiceRecord] now running: Homes Under the Hammer (3600 seconds)
[TIMER] record event 9
[eDVBDemux] open demux /dev/dvb/adapter0/demux12
[eDVBSectionReader] DMX_SET_FILTER pid=18
Timeout!
action ->  GlobalActions power_down
[Standby] enter standby
[eFilePushThread] stopping thread
[eFilePushThread] STOP
[eDVBServicePlay] timeshift
[eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
[eDVBVideo0] DEMUX_STOP  ok
[eDVBVideo0] VIDEO_STOP ok
[eDVBVideo0] destroy
[eDVBAudio0] AUDIO_STOP ok
[eDVBAudio0] DEMUX_STOP ok
[eDVBAudio0] AUDIO_CONTINUE ok
[eDVBAudio0] destroy
[eDVBText0] DEMUX_STOP ok
TuxTxt stopped service 151b
[eDVBText0] destroy
cleaning up
TuxTxt cache cleared
getResolvedKey config.usage.blinking_rec_symbol_during_recording failed !! (Typo??)
getResolvedKey config.usage.blinking_rec_symbol_during_recording failed !! (Typo??)
getResolvedKey config.usage.blinking_rec_symbol_during_recording failed !! (Typo??)
[eDboxLCD] setLCDBrightness 25
[eDVBChannel] pvrEvent evtStopped
[eDVBServiceRecord] RECORD service event 12
[eDVBServiceRecord] stop recording!
[eFilePushThreadRecorder] stopping thread.
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] buffer usage histogram (40 buffers of 188 kB)
[eDVBRecordFileThread]   1:     48
[eDVBRecordFileThread]   2:     38
[eFilePushThreadRecorder] THREAD STOP
[eDVBTSTools] setSource loading streaminfo for /media/net/tv/movie/20180109 1131 - BBC One HD - Homes Under the Hammer.ts
[TIMER] record event 5
[TIMER] record event 10
[Timer] Remove RecordTimerEntry(name=Homes Under the Hammer, begin=Tue Jan  9 11:31:10 2018, serviceref=1:0:19:1B1D:802:2:11A0000:0:0:0:, justplay=False)
time changed
[TIMER] activating state 3
[TIMER] stop recording
[eDVBServiceRecord] stop recording!
[eDVBServiceRecord] (was not recording)
[TIMER] record event 5
[TIMER] record event 1
[remove callback]
state:  3
in processed:  True
in running:  False
[Trashcan] bytesToRemove -2965710000128 /media/net/tv/movie/.Trash
[Trashcan] Bytes to remove remaining: -2965710000128 /media/net/tv/movie/.Trash
[Trashcan] Size after purging: 6165310 /media/net/tv/movie/.Trash
[eDVBCAService] free slot 0 demux 12 for service 1:0:19:1B1D:802:2:11A0000:0:0:0:
[eDVBCAService] free service 1:0:19:1B1D:802:2:11A0000:0:0:0:
[eDVBLocalTimerHandler] remove channel 0x106bbf8
[eEPGCache] remove channel 0x106bbf8
[DVBCAHandler] no more services

Er staan wel wat verdachte zaken in als:

  [eDVBServicePlay] timeshift

  [TimerSanityCheck] possible bug: unknown conflict!

 

Het heeft niets met een timeshift te maken en de andere spreek voor zich. Ik kan ze niet op waarde schatten omdat ik de code niet ken.

 

Met welke ontvangers hebben jullie getest? Zowel de lokale als de fallback ontvanger.

 

Hopelijk kunnen jullie hier wel iets mee.

[littlesat]

Als ik net niet kan reproduceren is we geen bug die ik kan oplossen. Dan is het iets anders wat op je box staat...
Pro beer eerst eens een verse flash zonder plugins...

Edited by littlesat, 9 January 2018 - 11:59.

[r00tc0d3]

Dat heb ik gedaan. Welke plugin zou ik dan geïnstalleerd moeten hebben?

Heb je niets aan de log?

En wederom welke ontvangers gebruiken jullie?

Als je de log hebt en de code kent dan kun je ook debuggen zonder het te kunnen reproduceren. Mocht je dan twijfels hebben over hoe hij door de code heen loopt dan voeg je logging toe. Desnoods op een branch.

Waar een wil is, is een weg...

Edited by r00tc0d3, 9 January 2018 - 12:40.

[littlesat]

In de log zie ik alleen dat een opname gestopt wordt en daarna wordt er iets met de trash can gedaan wat hoe of wat het triggert geen idee... ook geen timing te zien... dat de timer sanity niets doet kan kloppen want je doet een opname Via fallback en niet op een lokale tuner dat is precies wat het moet doen. Getest op h7, hd51, formuler1 en ultimo4k

Edited by littlesat, 9 January 2018 - 13:47.

[WanWizard]

Kern van het probleem is

action ->  GlobalActions power_down
[Standby] enter standby
[eFilePushThread] stopping thread
[eFilePushThread] STOP

de hamvraag is, waarom.

 

Wat je nog zou kunnen doen is Enigma in debug mode starten:

init 4
ENIGMA_DEBUG_LVL=4 enigma2 | tee /root/debug.log

en dan hetzelfde nog een keer doen. Wellicht logt dat meer.

[r00tc0d3]

De bovenstaande log is gemaakt met ENIGMA_DEBUG_LVL=4 GST_DEBUG=*soup*:6,*dvb*:6 /usr/bin/enigma2.sh

[WanWizard]

Ok.

 

Dan wordt het voor iemand in de code duiken...

[r00tc0d3]

Kan helaas nog niet bouwen. Docker op een Mac met openembedded is geen goede combinatie 

[littlesat]

En ik vind niets wat de opname stop zou zetten... is de
Box bekabeld verbonden?

[r00tc0d3]

Hij is idd via een kabel verbonden. De 2 ontvangers staan 1.5 meter uit elkaar met een switch ertussen. 

[littlesat]

Ga je niet toevallig als de client box op standby gaat verder kijken via de server box?

[r00tc0d3]

Nee, de server box staat op standby en blijft op standby. Staat achter een deurtje.
Heb tevens de client nog met het handje (zonder ab) op standby gezet en weer aan en geeft hetzelfde resultaat.

[littlesat]

Hoe veel tuners heeft de server en welke!