openatv / enigma2

openatv-gui
GNU General Public License v2.0
200 stars 317 forks source link

[Channel / Service Scan] Crash if eg. OWIF zap or EPGRefresh run when open #3303

Open wedebe opened 6 months ago

wedebe commented 6 months ago

! Before you log an issue, have you updated to the most recent version? It's possible the issue may have already been fixed. Yes

Describe the bug / Actual behaviour: If the Channel / Service Scan page is open when (for example) a user uses OpenWebif to zap, or EPGRefresh is run automatically, oATV will crash.

Expected behaviour: oATV should not crash, instead the zap/EPGRefresh/any other command should be vetoed, ideally with an error/failed message to the function which called it to state why (eg. 'Channel Scan denied service change')

Has this issue started to happen just recently? No

To reproduce: Steps to reproduce the behavior:

  1. Open Channel / Service Scan
  2. Perform any type of scan and wait 'til completion
  3. In OpenWebif, try zap to any channel
  4. Wait a few seconds and see the bluescreen on tv

Screenshots screenshot_20240429111919 20240429112043

Image/Box Model (please complete the following information):

Additional context Another bug is that after clearing the error notification, the user is brought back to the Scanning Complete page. Pressing OK (Green button) - instead of saving the previously found channels - will re-start the scan, but increment from the previous number of found channels (in the case of the screenshot, 419 onwards, for a new total of 836. I haven't checked whether these are subsequently duplicated in the channel database.

Log:

11:20:08.5445 [Navigation] playing ref 1:0:19:1B1F:802:2:11A0000:0:0:0:
11:20:08.5452 [Navigation] playref 1:0:19:1B1F:802:2:11A0000:0:0:0:
11:20:08.5516 [Pixmap] setPixmapNum(0) failed!  Defined pixmaps: [].
11:20:08.5520 [eDVBServicePlay] time shift not enough diskspace for timeshift! (less than 1GB)
11:20:08.5523 [eDVBServicePlay] time shift not enough diskspace for timeshift! (less than 1GB)
11:20:08.5533 [Pixmap] setPixmapNum(0) failed!  Defined pixmaps: [].
11:20:08.5536 [eDVBServicePlay] time shift not enough diskspace for timeshift! (less than 1GB)
11:20:08.5540 [Notifications] RemovePopup id = ZapError
11:20:08.5542 [eDVBResourceManager] allocate channel.. 0802:0002
11:20:08.5542 [eDVBSatelliteEquipmentControl] direct_connected 1
11:20:08.5543 [eDVBSatelliteEquipmentControl] canTune 1
11:20:08.5543 [eDVBSatelliteEquipmentControl] lnb 0 found
11:20:08.5543 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
11:20:08.5543 [eDVBSatelliteEquipmentControl] sat 282 found
11:20:08.5543 ret1 15000
11:20:08.5544 [eDVBSatelliteEquipmentControl] ret5 15000
11:20:08.5544 [eDVBSatelliteEquipmentControl] ret 15000, score old 0
11:20:08.5544 [eDVBSatelliteEquipmentControl] score new 15000
11:20:08.5544 [eDVBSatelliteEquipmentControl] final score 15005
11:20:08.5544 [eDVBSatelliteEquipmentControl] direct_connected 0
11:20:08.5545 [eDVBSatelliteEquipmentControl] canTune 2
11:20:08.5545 [eDVBSatelliteEquipmentControl] lnb 0 found
11:20:08.5545 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
11:20:08.5545 [eDVBSatelliteEquipmentControl] sat 282 found
11:20:08.5545 ret1 15000
11:20:08.5546 [eDVBSatelliteEquipmentControl] ret5 15000
11:20:08.5546 [eDVBSatelliteEquipmentControl] ret 15000, score old 0
11:20:08.5546 [eDVBSatelliteEquipmentControl] score new 15000
11:20:08.5546 [eDVBSatelliteEquipmentControl] final score 15000
11:20:08.5549 [eDVBFrontend] m_need_delivery_system_workaround = 0
11:20:08.5549 [eDVBFrontend] opening frontend 0
11:20:08.5569 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
11:20:08.5582 Traceback (most recent call last):
11:20:08.5583   File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 45, in updateTunerMask
11:20:08.5587   File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 31, in updateFrontendData
11:20:08.5590   File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 50, in getFrontendData
11:20:08.5592   File "/usr/lib/enigma2/python/Components/ServiceScan.py", line 185, in <lambda>
11:20:08.5597 AttributeError: 'NoneType' object has no attribute 'getFrontend'
11:20:08.5597 [ePyObject] (PyObject_CallObject(<bound method FrontendInfo.updateTunerMask of <Components.Sources.FrontendInfo.FrontendInfo object at 0xae85e288>>,(1,)) failed)
11:20:12.3472 [gRC] Warning: Main thread is busy, displaying spinner!
11:20:18.5660 [eDVBFrontend0] tune
11:20:18.5661 [eDVBSatelliteEquipmentControl] direct_connected 1
11:20:18.5662 [eDVBSatelliteEquipmentControl] canTune 1
11:20:18.5662 [eDVBSatelliteEquipmentControl] lnb 0 found
11:20:18.5662 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
11:20:18.5663 [eDVBSatelliteEquipmentControl] sat 282 found
11:20:18.5663 ret1 15000
11:20:18.5663 [eDVBSatelliteEquipmentControl] ret5 15000
11:20:18.5663 [eDVBSatelliteEquipmentControl] ret 15000, score old 0
11:20:18.5664 [eDVBSatelliteEquipmentControl] score new 15000
11:20:18.5664 [eDVBSatelliteEquipmentControl] final score 15005
11:20:18.5665 has_external_rotor /home/root/rotor_0 no
11:20:18.5665 [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
11:20:18.5665 [eDVBFrontend0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0, t2mi_plp_id -1 t2mi_pid 4096
11:20:18.5666 tuning to 1097 MHz
11:20:18.5666 [eDVBChannel] OURSTATE: tuner 0 tuning
11:20:18.5666 [eDVBServicePMTHandler] allocate Channel: res 0
11:20:18.5667 [eDVBCIInterfaces] addPMTHandler 1:0:19:1B1F:802:2:11A0000:0:0:0:
11:20:18.5703 [eDVBChannel] getDemux cap=00
11:20:18.5703 [eDVBResourceManager] allocate demux cap=00
11:20:18.5704 [eDVBResourceManager] allocating demux adapter=0, demux=0, source=0 fesource=0
11:20:18.5704 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:18.5731 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:08 +0000] "GET /api/zap?sRef=1%3A0%3A19%3A1B1F%3A802%3A2%3A11A0000%3A0%3A0%3A0%3A&_=1714385951080 HTTP/1.1" 200 102 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:18.5742 Timing out client: IPv6Address(type='TCP', host='::ffff:192.168.43.154', port=49755, flowInfo=0, scopeID=0)
11:20:18.5749 Timing out client: IPv6Address(type='TCP', host='::ffff:192.168.43.154', port=49757, flowInfo=0, scopeID=0)
11:20:18.5755 Timing out client: IPv6Address(type='TCP', host='::ffff:192.168.43.154', port=49759, flowInfo=0, scopeID=0)
11:20:18.5766 [ServiceScanUpdates] ServiceScan_execEnd (3)
11:20:18.5771 [ServiceScanUpdates] Reading file: /etc/enigma2//lamedb
11:20:18.5789 [ServiceScanUpdates] Parsing content of file: /etc/enigma2//lamedb
11:20:18.5906 [StackTrace] ========== Stacktrace of active Python threads ===========
11:20:18.5907 [StackTrace] ========== Thread ID  0xabcfc360 =========================
11:20:18.5907 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1030, in _bootstrap
11:20:18.5908 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1073, in _bootstrap_inner
11:20:18.5908 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1010, in run
11:20:18.5909 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/_threads/_threadworker.py", line 48, in work
11:20:18.5909 [StackTrace] File: "/usr/lib/python3.12/queue.py", line 171, in get
11:20:18.5910 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 355, in wait
11:20:18.5910 [StackTrace] ========== Thread ID  0xac4fd360 =========================
11:20:18.5910 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1030, in _bootstrap
11:20:18.5911 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1073, in _bootstrap_inner
11:20:18.5911 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1010, in run
11:20:18.5911 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/_threads/_threadworker.py", line 48, in work
11:20:18.5912 [StackTrace] File: "/usr/lib/python3.12/queue.py", line 171, in get
11:20:18.5912 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 355, in wait
11:20:18.5912 [StackTrace] ========== Thread ID  0xaf1c0360 =========================
11:20:18.5913 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1030, in _bootstrap
11:20:18.5913 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1073, in _bootstrap_inner
11:20:18.5914 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 1010, in run
11:20:18.5914 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/_threads/_threadworker.py", line 48, in work
11:20:18.5914 [StackTrace] File: "/usr/lib/python3.12/queue.py", line 171, in get
11:20:18.5915 [StackTrace] File: "/usr/lib/python3.12/threading.py", line 355, in wait
11:20:18.5915 [StackTrace] ========== MainThread 0xb5871c20 =========================
11:20:18.5915 [StackTrace] File: "/usr/lib/enigma2/python/StartEnigma.py", line 936, in <module>
11:20:18.5916 [StackTrace]   runScreenTest()  # Start running the first screen.
11:20:18.5916 [StackTrace] File: "/usr/lib/enigma2/python/StartEnigma.py", line 453, in runScreenTest
11:20:18.5917 [StackTrace]   runReactor()
11:20:18.5917 [StackTrace] File: "/usr/lib/enigma2/python/StartEnigma.py", line 622, in runReactor
11:20:18.5917 [StackTrace]   reactor.run(installSignalHandlers=False)
11:20:18.5918 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/internet/base.py", line 695, in run
11:20:18.5918 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/internet/base.py", line 708, in mainLoop
11:20:18.5919 [StackTrace] File: "/usr/lib/enigma2/python/e2reactor.py", line 173, in doPoll
11:20:18.5919 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/python/log.py", line 96, in callWithLogger
11:20:18.5920 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/python/log.py", line 80, in callWithContext
11:20:18.5920 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/python/context.py", line 117, in callWithContext
11:20:18.5921 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/python/context.py", line 82, in callWithContext
11:20:18.5921 [StackTrace] File: "/usr/lib/enigma2/python/e2reactor.py", line 190, in _doReadOrWrite
11:20:18.5921 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/internet/tcp.py", line 248, in doRead
11:20:18.5922 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/internet/tcp.py", line 253, in _dataReceived
11:20:18.5922 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/web/http.py", line 3161, in dataReceived
11:20:18.5922 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/protocols/basic.py", line 549, in dataReceived
11:20:18.5923 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/web/http.py", line 2307, in lineReceived
11:20:18.5923 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/web/http.py", line 2440, in allContentReceived
11:20:18.5924 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/web/http.py", line 1020, in requestReceived
11:20:18.5924 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/web/server.py", line 227, in process
11:20:18.5924 [StackTrace] File: "/usr/lib/python3.12/site-packages/twisted/web/server.py", line 292, in render
11:20:18.5925 [StackTrace] File: "/home/oe1/atv7.4/build-enviroment/builds/openatv/release/hd51/tmp/work/cortexa15hf-neon-vfpv4-oe-linux-gnueabi/enigma2-plugin-extensions-openwebif/git/image/usr/lib/enigma2/python/Plugins/Extensions/OpenWebif/controllers/base.py", line 193, in render
11:20:18.5925 [StackTrace] File: "/home/oe1/atv7.4/build-enviroment/builds/openatv/release/hd51/tmp/work/cortexa15hf-neon-vfpv4-oe-linux-gnueabi/enigma2-plugin-extensions-openwebif/git/image/usr/lib/enigma2/python/Plugins/Extensions/OpenWebif/controllers/web.py", line 317, in P_zap
11:20:18.5925 [StackTrace] File: "/home/oe1/atv7.4/build-enviroment/builds/openatv/release/hd51/tmp/work/cortexa15hf-neon-vfpv4-oe-linux-gnueabi/enigma2-plugin-extensions-openwebif/git/image/usr/lib/enigma2/python/Plugins/Extensions/OpenWebif/controllers/models/control.py", line 149, in zapService
11:20:18.5926 [StackTrace] File: "/home/oe1/atv7.4/build-enviroment/builds/openatv/release/hd51/tmp/work/cortexa15hf-neon-vfpv4-oe-linux-gnueabi/enigma2-plugin-extensions-openwebif/git/image/usr/lib/enigma2/python/Plugins/Extensions/OpenWebif/controllers/models/control.py", line 66, in zapInServiceList
11:20:18.5926 [StackTrace] File: "/usr/lib/enigma2/python/Screens/ChannelSelection.py", line 2902, in performZap
11:20:18.5927 [StackTrace] File: "/usr/lib/enigma2/python/Screens/ChannelSelection.py", line 2854, in finalZap
11:20:18.5927 [StackTrace] File: "/usr/lib/enigma2/python/Screens/ChannelSelection.py", line 2481, in zap
11:20:18.5927 [StackTrace] File: "/usr/lib/enigma2/python/Components/Timeshift.py", line 504, in checkTimeshiftRunning
11:20:18.5928 [StackTrace] File: "/usr/lib/enigma2/python/Tools/BoundFunction.py", line 10, in __call__
11:20:18.5928 [StackTrace] File: "/usr/lib/enigma2/python/Screens/ChannelSelection.py", line 2496, in zapCheckTimeshiftCallback
11:20:18.5929 [StackTrace] File: "/usr/lib/enigma2/python/Navigation.py", line 373, in playService
11:20:18.5929 [StackTrace] File: "/usr/lib/enigma2/python/Components/config.py", line 2242, in getResolvedKey
11:20:18.5929 [StackTrace] ========== Stacktrace end ================================
11:20:18.6376 [ServiceScanUpdates] Found 0 new TV services
11:20:18.6379 [ServiceScanUpdates] Found 0 new radio services
11:20:18.6438 [eDVBDB] loading bouquet... /etc/enigma2//bouquets.tv
11:20:18.6441 [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.favourites.tv
11:20:18.6450 [eDVBDB] 125 entries in Bouquet userbouquet.favourites.tv

<SNIP>

11:20:18.6766 [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.LastScanned.tv
11:20:18.6788 [eDVBDB] 418 entries in Bouquet userbouquet.LastScanned.tv
11:20:18.6788 [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.ServiceScanUpdates.tv
11:20:18.6822 [eDVBDB] 569 entries in Bouquet userbouquet.ServiceScanUpdates.tv
11:20:18.6823 [eDVBDB] 12 entries in Bouquet bouquets.tv

<SNIP>

11:20:18.6834 [eDVBDB] Renumbering...
11:20:18.6834 [eDVBDB] Renumber Bouquets (TV), starting at 1
11:20:18.6834 [eDVBDB] Renumber Favourites (TV), starting at 1

<SNIP>

11:20:18.6856 [eDVBDB] Renumber Last Scanned, starting at 1
11:20:18.6856 [eDVBDB] Renumber Service Scan Updates, starting at 1
11:20:18.6912 [Skin] Processing screen 'MessageBox' position=(240,160), size=(800,400) for module 'MessageBox'.
11:20:18.7071 [Skin] Processing screen 'MessageBoxSummary', from list 'MessageBoxSummary, MessageBox_summary, ScreenSummary', position=(0,0), size=(132,64) for module 'MessageBoxSummary'.
11:20:18.7138 [SEC] set static current limiting
11:20:18.7213 [SEC] invalidate current switch params
11:20:18.7280 [eDVBFrontend] [SEC] tuner 0 setVoltage 1
11:20:18.7281 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 0
11:20:18.7607 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:18 +0000] "GET /api/pipinfo?_=1714385951081 HTTP/1.1" 200 33 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:18.7612 [eDVBFrontend] [SEC] tuner 0 sleep 10ms
11:20:18.7895 [SEC] update current switch params
11:20:18.7897 [SEC] tuner 0 startTuneTimeout 5000
11:20:18.7898 [SEC] tuner 0 setFrontend: events enabled
11:20:18.7898 setting frontend 0 events: on
11:20:18.8431 [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
11:20:18.8432 [eDVBFrontend] [SEC] tuner 0 sleep 500ms
11:20:19.0568 [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 2
11:20:19.0569 [eDVBChannel] OURSTATE: tuner 0 ok
11:20:19.0569 [eDVBLocalTimerHandler] Channel 0x11c3bb8 running.
11:20:19.0570 [eDVBChannel] getDemux cap=00
11:20:19.0570 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0572 [eDVBSectionReader] DMX_SET_FILTER pid=20
11:20:19.0577 [eEPGTransponderDataReader] channel 0x11c3bb8 running
11:20:19.0578 [eDVBChannel] getDemux cap=00
11:20:19.0579 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0579 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0581 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0581 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0582 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0583 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0584 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0585 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0586 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0586 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0587 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0587 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0588 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0589 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0589 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0589 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.0590 [eDVBResourceManager] stop release channel timer
11:20:19.0590 [eDVBChannel] getDemux cap=01
11:20:19.0591 [eEPGChannelData] next update in 2 sec
11:20:19.0591 [eDVBResourceManager] allocate demux cap=01
11:20:19.0591 [eDVBResourceManager] allocating shared demux adapter=0, demux=0, source=0
11:20:19.0592 [eDVBServicePMTHandler] ok ... now we start!!
11:20:19.0592 [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
11:20:19.0592 [eDVBServicePlay] have 1 video stream(s) (14b4), and 1 audio stream(s) (14b5), and the pcr pid is 14b4, and the text pid is 14b6
11:20:19.0594 [eDVBChannel] getDemux cap=01
11:20:19.0594 [eDVBResourceManager] allocate demux cap=01
11:20:19.0594 [eDVBResourceManager] allocating shared demux adapter=0, demux=0, source=0
TuxTxt cache cleared
11:20:19.0631 [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 0
11:20:19.0633 [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0
11:20:19.0633 [eTSMPEGDecoder] decoder state: play, vpid=14b4, apid=14b5
11:20:19.0634 [eDVBPCR0] DMX_SET_PES_FILTER pid=0x14b4 ok11:20:19.0635 [eDVBPCR0] DEMUX_START ok11:20:19.0862 [eDVBAudio0] DMX_SET_PES_FILTER pid=0x14b5 ok11:20:19.0863 [eDVBAudio0] DEMUX_START ok11:20:19.0866 [eDVBAudio0] AUDIO_SET_BYPASS bypass=0 ok11:20:19.0867 [eDVBAudio0] AUDIO_PAUSE ok11:20:19.0867 [eDVBAudio0] AUDIO_PLAY ok11:20:19.1224 [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
11:20:19.1226 [eDVBVideo] demux device: /dev/dvb/adapter0/demux0
11:20:19.1227 [eDVBVideo0] VIDEO_SET_STREAMTYPE 1 - ok11:20:19.1228 [eDVBVideo0] DMX_SET_PES_FILTER pid=0x14b4 ok11:20:19.1230 [eDVBVideo0] DEMUX_START ok11:20:19.1232 [eDVBVideo0] VIDEO_FREEZE ok11:20:19.1232 [eDVBVideo0] VIDEO_PLAY ok11:20:19.1285 [eDVBText0] DMX_SET_PES_FILTER pid=0x14b6 ok11:20:19.1285 [eDVBText0] DEMUX_START okTuxTxt cache cleared
TuxTxt: initialized
TuxTxt service started 14b6
11:20:19.1312 [eDVBVideo0] VIDEO_SLOWMOTION 0 ok11:20:19.1313 [eDVBVideo0] VIDEO_FAST_FORWARD 0 TuxTxt running thread...(14b6)
ok11:20:19.1314 [eDVBVideo0] VIDEO_CONTINUE ok11:20:19.1525 [eDVBAudio0] AUDIO_CONTINUE ok11:20:19.1526 [eDVBTeletextParser] Starting!
11:20:19.1526 [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
11:20:19.1527 [eDVBPESReader] Created. Opening demux
11:20:19.1527 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.1528 [eDVBTeletextParser] created teletext subtitle PES reader!
11:20:19.1529 [eDVBPESReader] Created. Opening demux
11:20:19.1529 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.1530 [eDVBTeletextParser] starting PES reader on pid=14b6
11:20:19.1530 [eDVBPESReader] DMX_SET_PES_FILTER pid=14b6
11:20:19.1607 [VolumeAdjust] Normal volume is 90.
11:20:19.1615 [eDVBCAService] new service 1:0:19:1B1F:802:2:11A0000:0:0:0:
11:20:19.1616 [eDVBCAService] add demux 0 to slot 0 service 1:0:19:1B1F:802:2:11A0000:0:0:0:
11:20:19.1617 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.1618 [eDVBSectionReader] DMX_SET_FILTER pid=0
11:20:19.1696 [eDVBServicePlay] time shift not enough diskspace for timeshift! (less than 1GB)
11:20:19.1816 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.1818 [eDVBSectionReader] DMX_SET_FILTER pid=18
11:20:19.1831 [Notifications] RemovePopup id = ZapError
11:20:19.1832 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.1834 [eDVBSectionReader] DMX_SET_FILTER pid=0
11:20:19.2994 [eDVBServicePMTHandler] PATready
11:20:19.2995 [eDVBServicePMTHandler] PAT TSID: 0x0802 (2050)
11:20:19.2996 [eDVBServicePMTHandler] use pmtpid 0100 for service_id 1b1f
11:20:19.2996 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.2997 [eDVBSectionReader] DMX_SET_FILTER pid=256
11:20:19.3003 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.3004 [eDVBSectionReader] DMX_SET_FILTER pid=0
11:20:19.3013 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.3013 [eDVBSectionReader] DMX_SET_FILTER pid=266
11:20:19.3017 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.3018 [eDVBSectionReader] DMX_SET_FILTER pid=17
11:20:19.3433 [SEC] set dynamic current limiting
11:20:19.5078 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.5080 [eDVBSectionReader] DMX_SET_FILTER pid=18
11:20:19.5101 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.5102 [eDVBSectionReader] DMX_SET_FILTER pid=261
11:20:19.5954 [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
11:20:19.5960 [eDVBServicePlay] have 1 video stream(s) (14b4), and 2 audio stream(s) (14b9, 14b5), and the pcr pid is 14b4, and the text pid is 14b6
11:20:19.5964 [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 0
11:20:19.5965 [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0
11:20:19.5966 [eTSMPEGDecoder] decoder state: play, vpid=14b4, apid=14b5
11:20:19.6037 [eDVBCIInterfaces] gotPMT
11:20:19.6042 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.6043 [eDVBSectionReader] DMX_SET_FILTER pid=256
11:20:19.7124 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.7125 [eDVBSectionReader] DMX_SET_FILTER pid=258
11:20:19.8494 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.8495 [eDVBSectionReader] DMX_SET_FILTER pid=256
11:20:19.9956 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:19.9957 [eDVBSectionReader] DMX_SET_FILTER pid=259
11:20:20.1699 [eDVBServicePlay] time shift not enough diskspace for timeshift! (less than 1GB)
11:20:20.1701 [Timeshift] Tune lock failed, could not start time shift.
11:20:20.2455 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:20.2456 [eDVBSectionReader] DMX_SET_FILTER pid=264
11:20:20.4322 [eDVBVideo0] VIDEO_GET_EVENT FRAME_RATE_CHANGED 25000 fps
11:20:20.4326 [eDVBVideo0] VIDEO_GET_EVENT SIZE_CHANGED 1920x1080 aspect 3
11:20:20.4406 [eDVBVideo0] VIDEO_GET_EVENT PROGRESSIVE_CHANGED 0
11:20:20.5139 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:20.5141 [eDVBSectionReader] DMX_SET_FILTER pid=263
11:20:20.7055 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:20.7056 [eDVBSectionReader] DMX_SET_FILTER pid=265
11:20:20.7224 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:20.7226 [eDVBSectionReader] DMX_SET_FILTER pid=257
11:20:20.8441 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:20.8442 [eDVBSectionReader] DMX_SET_FILTER pid=273
11:20:20.8483 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:20.8484 [eDVBSectionReader] DMX_SET_FILTER pid=260
11:20:20.8876 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
11:20:20.8877 [eDVBSectionReader] DMX_SET_FILTER pid=270
11:20:20.8961 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:20 +0000] "GET /api/pipinfo?_=1714385951082 HTTP/1.1" 200 33 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:21.0682 [eEPGChannelData] start reading events(1714386021)
11:20:21.0683 [eDVBSectionReader] DMX_SET_FILTER pid=3842
11:20:21.0686 [eDVBSectionReader] DMX_SET_FILTER pid=3003
11:20:21.0690 [eDVBSectionReader] DMX_SET_FILTER pid=18
11:20:21.0693 [eDVBSectionReader] DMX_SET_FILTER pid=18
11:20:21.0699 [eDVBSectionReader] DMX_SET_FILTER pid=18
11:20:21.0703 [eEPGChannelData] abort non avail OpenTV EIT reading
11:20:21.3230 [eDVBServicePMTHandler] sdt update done!
11:20:25.7270 [eDVBLocalTimerHandler] updateTime : 1714386025
11:20:25.7274 [eDVBLocalTimerHandler] Transponder time is 29/04/2024 11:20:25.
11:20:25.7275 [eDVBLocalTimerHandler] Difference is 0.
11:20:25.7276 [eDVBLocalTimerHandler] Difference less than 120, use transponder time.
11:20:25.7277 [eDVBLocalTimerHandler] Not changed.
11:20:25.7282 [eDVBLocalTimerHandler] updateTime : 0
11:20:25.7284 [eDVBChannel] getDemux cap=00
11:20:28.0703 [eEPGChannelData] abort non avail schedule reading
11:20:28.0710 [eEPGChannelData] abort non avail schedule other reading
11:20:28.1474 [eEPGChannelData] nownext finished(1714386028)
11:20:31.8990 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:29 +0000] "GET /api/pipinfo?_=1714385951083 HTTP/1.1" 200 33 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:40.1134 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:38 +0000] "GET /ajax/screenshot HTTP/1.1" 200 1033 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:40.1314 [eConsoleAppContainer] Starting /usr/bin/grab
11:20:40.3353 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:40 +0000] "GET /grab?format=jpg&mode=all&t=1714386040068 HTTP/1.1" 200 566306 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:42.2740 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:41 +0000] "GET /api/pipinfo?_=1714385951084 HTTP/1.1" 200 33 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:43.1813 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:42 +0000] "GET /api/pipinfo?_=1714385951085 HTTP/1.1" 200 33 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:43.1857 [eConsoleAppContainer] Starting /usr/bin/grab
11:20:43.2488 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:42 +0000] "GET /grab?format=jpg&mode=osd&t=1714386043112 HTTP/1.1" 200 62375 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:51.1354 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:50 +0000] "GET /api/pipinfo?_=1714385951086 HTTP/1.1" 200 33 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
11:20:51.1401 [eConsoleAppContainer] Starting /usr/bin/grab
11:20:51.1949 "::ffff:192.168.43.154" - - [29/Apr/2024:10:20:50 +0000] "GET /grab?format=jpg&mode=osd&t=1714386051070 HTTP/1.1" 200 62375 "http://192.168.43.147/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36"
jbleyel commented 6 months ago

Zap can be disabled while scanning.

But there is a much bigger problem here. What if you have a pending recording in round about 5 minutes and your scan takes more than 5 minutes?

So you need to prevent start scan if there is a pending recording. BUT you do not know how long the scan takes.

wedebe commented 6 months ago

But there is a much bigger problem here. What if you have a pending recording in round about 5 minutes and your scan takes more than 5 minutes?

Really good point!

What about extracting the Shutdown/Restart A recording is upcoming check & message into a reusable component with a parameter of timeAhead? Perhaps at 30minutes or similar for channel scan? Although a user would (probably) very rarely run a channel scan.

At the very least, should completed channel scans be saved automatically, user notified with a non-timed out message and the tuner released? (or whatever's causing the crash) ... I ask 'cos one use case might be to start a scan and go off to do other things or leave it 'running' overnight.

bt1v1 commented 6 months ago

`openATV Enigma2 crash log

crashdate=Mo Apr 29 23:27:48 2024 compiledate=Jan 5 2024 skin=MetrixHD/skin.xml sourcedate=2024-01-05 branch=7.3 rev=33052+276055f component=Enigma2

model=vuuno4kse machinebuild=vuuno4kse imageversion=7.3 imagebuild=20240115

kernelcmdline=root=/dev/mmcblk0p4 rootfstype=ext4 rootflags=data=journal rootwait rw coherent_pool=2M vmalloc=666m bmem=641m@350m bmem=641m@2430m

nimsockets: NIM Socket 0: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 0 I2C_Device: 4 NIM Socket 1: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 1 I2C_Device: 4 NIM Socket 2: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 2 I2C_Device: 4 NIM Socket 3: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 3 I2C_Device: 4 NIM Socket 4: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 4 I2C_Device: 4 NIM Socket 5: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 5 I2C_Device: 4 NIM Socket 6: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 6 I2C_Device: 4 NIM Socket 7: Type: DVB-C Name: Vuplus DVB-C NIM(BCM3148) Frontend_Device: 7 I2C_Device: 4

23:16:57.2592 [Enigma] Python path is '/usr/lib/enigma2/python'. 23:16:57.2593 [Enigma] DVB API version 5, DVB API version minor 11. 23:16:57.2594 [Enigma] Enigma debug level 3. 23:16:57.2594 [Enigma] sourcedate 2024-01-05 / 7.3 33052+276055f. 23:17:04.8977 Traceback (most recent call last): 23:17:04.8984 File "/usr/lib/enigma2/python/StartEnigma.py", line 66, in init plugin.call(reason=0, session=self) 23:17:04.8985 File "/usr/lib/enigma2/python/Plugins/SystemPlugins/FastChannelChange/plugin.py", line 473, in FCCSupportInit 23:17:04.8985 File "/usr/lib/enigma2/python/Plugins/SystemPlugins/FastChannelChange/plugin.py", line 70, in init 23:17:04.8986 AttributeError: 'BoxInformation' object has no attribute 'setMutableItem' 23:17:05.6330 [eServicePeer] ADD Peer vuuno4kse=vuuno4kse.local:8001 23:17:05.6345 [eServicePeer] ADD Peer vuzero4k=vuzero4k.local:8001 23:17:05.6590 [eServicePeer] ADD Peer vuuno4kse=vuuno4kse.local:8001 23:17:05.6610 [eServicePeer] ADD Peer vuzero4k=vuzero4k.local:8001 23:17:05.7665 [eDVBPESReader] Created. Opening demux 23:17:05.7666 [eDVBPESReader] Created. Opening demux 23:17:07.9153 2024-04-29 23:17:07,914 - DEBUG - Starting new HTTPS connection (1): api.msn.com:443 23:17:08.2137 2024-04-29 23:17:08,213 - DEBUG - https://api.msn.com:443 "GET /weatherfalcon/weather/overview?&lon=10.000654&lat=53.550341&locale=de-de&units=C&appId=9e21380c-ff19-4c78-b4ea-19558e93a5d3&apiKey=j5i4gDqHL6nGYwx5wi5kRhXjtf2c5qgFX9fzfk0TOo&ocid=superapp-mini-weather&wrapOData=false&includenowcasting=true&feature=lifeday&lifeDays=6 HTTP/1.1" 200 None 23:17:17.9966 [eDVBPESReader] Created. Opening demux 23:17:17.9967 [eDVBPESReader] Created. Opening demux 23:17:23.3476 [eDVBPESReader] Created. Opening demux 23:17:23.3478 [eDVBPESReader] Created. Opening demux 23:22:59.4030 [eDVBPESReader] Created. Opening demux 23:22:59.4032 [eDVBPESReader] Created. Opening demux 23:23:03.8312 [eDVBPESReader] Created. Opening demux 23:23:03.8313 [eDVBPESReader] Created. Opening demux 23:24:22.5216 [eDVBScan] the current channel's ID was not corrected - not adding channel. 23:25:32.0520 [eDVBScan] the current channel's ID was not corrected - not adding channel. 23:27:48.6551 Traceback (most recent call last): 23:27:48.6552 File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 45, in updateTunerMask 23:27:48.6555 File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 31, in updateFrontendData 23:27:48.6558 File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 50, in getFrontendData 23:27:48.6561 File "/usr/lib/enigma2/python/Components/ServiceScan.py", line 185, in 23:27:48.6567 AttributeError: 'NoneType' object has no attribute 'getFrontend' 23:27:48.6569 [ePyObject] (PyObject_CallObject(<bound method FrontendInfo.updateTunerMask of <Components.Sources.FrontendInfo.FrontendInfo object at 0xae1a9450>>,(2,)) failed)

dmesg `

I have the same problem with a VU+ uno 4k se. openatv 7.3

bt1v1 commented 6 months ago

I have the same problem with a VU+ uno 4k se. openatv 7.3

`openATV Enigma2 crash log

crashdate=Mo Apr 29 23:27:48 2024
compiledate=Jan  5 2024
skin=MetrixHD/skin.xml
sourcedate=2024-01-05
branch=7.3
rev=33052+276055f
component=Enigma2

model=vuuno4kse
machinebuild=vuuno4kse
imageversion=7.3
imagebuild=20240115

kernelcmdline=root=/dev/mmcblk0p4 rootfstype=ext4 rootflags=data=journal rootwait rw coherent_pool=2M vmalloc=666m bmem=641m@350m bmem=641m@2430m

nimsockets:
NIM Socket 0:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 0
    I2C_Device: 4
NIM Socket 1:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 1
    I2C_Device: 4
NIM Socket 2:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 2
    I2C_Device: 4
NIM Socket 3:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 3
    I2C_Device: 4
NIM Socket 4:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 4
    I2C_Device: 4
NIM Socket 5:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 5
    I2C_Device: 4
NIM Socket 6:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 6
    I2C_Device: 4
NIM Socket 7:
    Type: DVB-C
    Name: Vuplus DVB-C NIM(BCM3148)
    Frontend_Device: 7
    I2C_Device: 4

23:16:57.2592 [Enigma] Python path is '/usr/lib/enigma2/python'.
23:16:57.2593 [Enigma] DVB API version 5, DVB API version minor 11.
23:16:57.2594 [Enigma] Enigma debug level 3.
23:16:57.2594 [Enigma] sourcedate 2024-01-05 / 7.3 33052+276055f.
23:17:04.8977 Traceback (most recent call last):
23:17:04.8984   File "/usr/lib/enigma2/python/StartEnigma.py", line 66, in __init__
    plugin.__call__(reason=0, session=self)
23:17:04.8985   File "/usr/lib/enigma2/python/Plugins/SystemPlugins/FastChannelChange/plugin.py", line 473, in FCCSupportInit
23:17:04.8985   File "/usr/lib/enigma2/python/Plugins/SystemPlugins/FastChannelChange/plugin.py", line 70, in __init__
23:17:04.8986 AttributeError: 'BoxInformation' object has no attribute 'setMutableItem'
23:17:05.6330 [eServicePeer] ADD Peer vuuno4kse=vuuno4kse.local:8001
23:17:05.6345 [eServicePeer] ADD Peer vuzero4k=vuzero4k.local:8001
23:17:05.6590 [eServicePeer] ADD Peer vuuno4kse=vuuno4kse.local:8001
23:17:05.6610 [eServicePeer] ADD Peer vuzero4k=vuzero4k.local:8001
23:17:05.7665 [eDVBPESReader] Created. Opening demux
23:17:05.7666 [eDVBPESReader] Created. Opening demux
23:17:07.9153 2024-04-29 23:17:07,914 - DEBUG - Starting new HTTPS connection (1): api.msn.com:443
23:17:08.2137 2024-04-29 23:17:08,213 - DEBUG - https://api.msn.com:443 "GET /weatherfalcon/weather/overview?&lon=10.000654&lat=53.550341&locale=de-de&units=C&appId=9e21380c-ff19-4c78-b4ea-19558e93a5d3&apiKey=j5i4gDqHL6nGYwx5wi5kRhXjtf2c5qgFX9fzfk0TOo&ocid=superapp-mini-weather&wrapOData=false&includenowcasting=true&feature=lifeday&lifeDays=6 HTTP/1.1" 200 None
23:17:17.9966 [eDVBPESReader] Created. Opening demux
23:17:17.9967 [eDVBPESReader] Created. Opening demux
23:17:23.3476 [eDVBPESReader] Created. Opening demux
23:17:23.3478 [eDVBPESReader] Created. Opening demux
23:22:59.4030 [eDVBPESReader] Created. Opening demux
23:22:59.4032 [eDVBPESReader] Created. Opening demux
23:23:03.8312 [eDVBPESReader] Created. Opening demux
23:23:03.8313 [eDVBPESReader] Created. Opening demux
23:24:22.5216 [eDVBScan] the current channel's ID was not corrected - not adding channel.
23:25:32.0520 [eDVBScan] the current channel's ID was not corrected - not adding channel.
23:27:48.6551 Traceback (most recent call last):
23:27:48.6552   File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 45, in updateTunerMask
23:27:48.6555   File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 31, in updateFrontendData
23:27:48.6558   File "/usr/lib/enigma2/python/Components/Sources/FrontendInfo.py", line 50, in getFrontendData
23:27:48.6561   File "/usr/lib/enigma2/python/Components/ServiceScan.py", line 185, in <lambda>
23:27:48.6567 AttributeError: 'NoneType' object has no attribute 'getFrontend'
23:27:48.6569 [ePyObject] (PyObject_CallObject(<bound method FrontendInfo.updateTunerMask of <Components.Sources.FrontendInfo.FrontendInfo object at 0xae1a9450>>,(2,)) failed)

dmesg
`