OpenATV 6.1 - Booten aus Deep Standby dauert bis zu 3 Minuten

**atv1138** · 04.11.2017, 13:35

Hallo zusammen,

Bei meiner Vu+ Solo 4K hat sich der Bootvorgang auf bis zu 3 Minuten verlängert und ich habe mir mit der Doku aus dem Openatv Wiki mal den Bootvorgang geloggt und angeschaut.

Leider ist es nicht offensichtlich, wie die Aussetzer zustande kommen, da die betroffenen Module keine weitere Auskunft geben.
Vielleicht kann aber jemand von Euch Wissenden erklären, was da klemmt oder mir sagen, wie ich noch genauere Debug Informationen zur Verfügung stellen kann:

Der geloggte Bootvorgang dauert insgesamt 122 Sekunden. Davon hängt die Box 77 Sekunden (19 + 18 + 20 + 20)

1) Direkt nach den ersten 2 Sekunden hängt das Modul grc.cpp für 19 Sekunden:

Code:

Start des Vorgangs: 00:29:24.4682

...

[0m[37m00:29:25.2144 [36mComponents/Renderer/Picon.py:30 [96monMountpointAdded [37m [Picon] adding path: /usr/share/enigma2/picon/
[0m[37m00:29:26.7811 [32mgdi/grc.cpp:193 [92mthread [97m[gRC] main thread is non-idle! display spinner!

	grc.cpp lässt 19 Sekunden den Spinner drehen ...

[0m[37m00:29:45.2717 [36mComponents/NimManager.py:987 [96mreadTransponders [37m Reading satellites.xml
[0m[37m00:29:45.5179 [32mdvb/sec.cpp:1395 [92mclear [97meDVBSatelliteEquipmentControl::clear()

2) Danach vergehen 7 Sekunden und grc.cpp hängt wieder für weitere 18 Sekunden:

Code:

[0m[37m00:29:54.1257 [32mgdi/grc.cpp:193 [92mthread [97m[gRC] main thread is non-idle! display spinner!

	grc.cpp lässt 18 Sekunden den Spinner drehen ...

[0m[37m00:30:12.5341 [32mdvb/epgcache.cpp:442 [92msetCacheFile [97m[eEPGCache] setCacheFile read/write epg data from/to '/etc/enigma2/epg.dat'

3) Jetzt hängt epgcache.cpp für ca. 20 Sekunden beim Lesen der Events von /etc/enigma2/epg.dat:

Code:

[0m[37m00:30:12.5342 [32mdvb/epgcache.cpp:454 [92mtimeUpdated [97m[eEPGCache] time updated.. start EPG Mainloop
[0m[37m00:30:12.8207 [32mdvb/epgcache.cpp:1378 [92mload [97m[eEPGCache] 62940 events read from /etc/enigma2/epg.dat

	epgcache.cpp liest 20 Sekunden 62940 events von /etc/enigma2/epg.dat (sind das zu viele ... ist das zu lange für diese Zahl von Events?)

[0m[37m00:30:32.7470 [32mbase/console.cpp:110 [92mexecute [97m[eConsoleAppContainer] Starting /bin/sh
[0m[37m00:30:32.7477 [32mbase/console.cpp:110 [92mexecute [97m[eConsoleAppContainer] Starting /bin/sh

4) Der letzte Aussetzer kommt von Ipkg.py beim Hinzufügen der OPKG Destination /media/hdd

Code:

[0m[91m00:30:33.8129 [36mComponents/PluginComponent.py:56 [96mreadPluginList [91m Plugin  Extensions/WebInterface failed to load: No module named plugin
[0m[37m00:30:33.8295 [36mComponents/Ipkg.py:28 [96mopkgAddDestination [37m [Ipkg] Added to OPKG destinations: /media/hdd

	Ipkg.py hängt weitere 20 Sekunden ...

[0m[37m00:30:53.8586 [36mComponents/Ipkg.py:28 [96mopkgAddDestination [37m [Ipkg] Added to OPKG destinations: /
[0m[37m00:30:53.9130 [36mPlugins/Extensions/Infopanel/plugin.py:173 [96m<module> [37m [Info-Panel] machinebrand: Vu+

Ich habe überhaupt kein Gefühl, ob diese Zeiten realistisch sind und was da im Hintergrund passiert, aber der Bootvorgang ist eben deutlich länger geworden.
Ich bilde mir ein, dass der Bootvorgang kürzer wird, wenn ich weniger Aufnahmen auf der Festplatte habe. Ich habe die Tage so ca. 10-15 Aufnahmen gelöscht und da bin ich von ca. 150 Sekunden schon auf 122 Sekunden herunter gekommen. Aber das sollte ja nicht beabsichtigt sein, dass die Zahl der Aufnahmen den Bootvorgang so erheblich beeinflusst (... wenn es da überhaupt einen Zusammenhang gibt ...?).

Ich hoffe, dass ich schon gut vorgearbeitet habe und sich jetzt jemand findet, der mir erklären kann, wo hier die Zeit bleibt.

Vielen Dank und Grüße
Justus

**atv1138** · 04.11.2017, 14:05

Ich habe mittlerweile mehrere Versuche durch die Debug Aktivierung unter Einstellungen => System => Log mitgeschnitten. Ich kann die Logs gerne alle liefern es finden sich aber in allen die oben genannten Aussetzer von grc.cpp, epgcache.cpp und Ipkg.py ...

Ich habe mittlerweile fast alle meine Autotimer deaktiviert und die EPG Konfiguration verschlankt (bin von Speicherort Flash auf HDD umgeschwenkt), aber es bleiben immer die gleichen Hänger.

Immerhin bin ich jetzt schon auf um die 2 Minuten runter ...

Ich habe mal die aktuellste Debug Log Datei eingefügt und die Zeiten, wo es hängt hier mal wieder hervorgehoben.

Meine Holde ist gar nicht begeistert, wenn das Einschalten mehr als 2 Minuten dauert und den ganzen Tag Standby ist bei 23 Watt versus < 3Watt im Deep Standby auf Dauer nicht erschwinglich ... Ich würde mich über Eure Ratschläge freuen ...

Enigma2-debug-20171104_14-10-01.log

Code:

Start:
14:10:01.7202 [   ] base/e2avahi.cpp:360 avahi_timeout_new [Avahi] avahi_timeout_new

...

erster Hänger:
14:10:02.7126 {   } Components/Renderer/Picon.py:30 onMountpointAdded [Picon] adding path: /usr/share/enigma2/picon/
14:10:04.1764 [   ] gdi/grc.cpp:193 thread [gRC] main thread is non-idle! display spinner!

... fast 20 Sekunden ...

14:10:23.6290 {   } Components/NimManager.py:987 readTransponders Reading satellites.xml
14:10:23.8907 [   ] dvb/sec.cpp:1395 clear eDVBSatelliteEquipmentControl::clear()

...

zweiter Hänger:
14:10:23.8999 {   } Components/NimManager.py:2293 InitNimManager [NimManager] slotname = G, slotdescription = Vuplus DVB-S NIM(7376 FBC), multitype = False, current type = DVB-S2
14:10:23.9001 {   } Components/NimManager.py:2293 InitNimManager [NimManager] slotname = H, slotdescription = Vuplus DVB-S NIM(7376 FBC), multitype = False, current type = DVB-S2
14:10:23.9375 { E } RecordTimer.py:36 <module> [RecordTimer] import from 'Screens.InfoBar import InfoBar' failed: cannot import name InfoBar

... 10 Sekunden ...

14:10:34.3988 [   ] dvb/db.cpp:1037 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//bouquets.tv
14:10:34.3995 [   ] dvb/db.cpp:1037 loadBouquet [eDVBDB] loading bouquet... /etc/enigma2//userbouquet.justus__tv_.tv

...

dritter und vierter Hänger:
14:10:37.8691 {   } Components/EpgLoadSave.py:29 start [EPGC Loads] Poller enabled.
14:10:37.8694 {   } Components/EpgLoadSave.py:68 start [EPGC Save] Poller enabled.
14:10:38.1681 [   ] gdi/grc.cpp:193 thread [gRC] main thread is non-idle! display spinner!

... 20 Sekunden ...

14:10:57.9087 [   ] dvb/epgcache.cpp:442 setCacheFile [eEPGCache] setCacheFile read/write epg data from/to '/media/hdd/epg.dat'
14:10:57.9088 [   ] dvb/epgcache.cpp:454 timeUpdated [eEPGCache] time updated.. start EPG Mainloop
14:10:58.4200 [   ] dvb/epgcache.cpp:1378 load [eEPGCache] 61477 events read from /media/hdd/epg.dat

... 20 Sekunden ...

14:11:18.1270 [   ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /bin/sh
14:11:18.1277 [   ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /bin/sh

...

fünfter Hänger:
14:11:18.5562 { E } Plugins/Extensions/EPGRefresh/plugin.py:111 <module> [EPGRefresh] Error registering Notification-Domain: 'module' object has no attribute 'notificationQueue'
14:11:19.3761 { E } Components/PluginComponent.py:56 readPluginList Plugin  Extensions/WebInterface failed to load: No module named plugin
14:11:19.6435 {   } Components/Ipkg.py:28 opkgAddDestination [Ipkg] Added to OPKG destinations: /media/hdd

... 20 Sekunden ...

14:11:39.6658 {   } Components/Ipkg.py:28 opkgAddDestination [Ipkg] Added to OPKG destinations: /
14:11:39.7452 {   } Plugins/Extensions/Infopanel/plugin.py:173 <module> [Info-Panel] machinebrand: Vu+
14:11:39.7454 {   } Plugins/Extensions/Infopanel/plugin.py:174 <module> [Info-Panel] machinename: Solo4K

...

Bootvorgang ist hier - glaube ich - zu Ende:
14:11:58.9890 <   > dvb_ci/dvbci_datetimemgr.cpp:15 receivedAPDU [CI DT] SESSION(4)/DATETIME 9f 84 40: 05

letzter Eintrag im Log:
14:14:47.3892 {   } Components/Task.py:375 jobDone job Components.Task.Job name=AutoTimer #tasks=8 completed with [] in None
14:17:27.9116 [   ] base/console.cpp:110 execute [eConsoleAppContainer] Starting sdparm
14:17:29.2181 [   ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 100(16)

**Rosalie911** · 12.12.2017, 15:57

Hallo Gemeinde
möchte jetzt hier auch ein Problem meinerseits zu diesem Problem posten

habe auf meiner duo2 auch openATV 6.1 drauf letztes Update Dezember 2017

habe Image neu installiert.Alles neu eingestellt und dann ein Fullbackup gemacht.
soweit alles OK.
nächsten Tag spiele ich ein neues Bouquet ein .mache einen gui neustart .
Noch alles in Ordnung
nächster Tag .Start aus Deepstandbye dauert der Start bis Bild kommt fast 4 minuten (ist ja wohl ein Witz)

Festplatte kann als Ursache ausgeschlossen werden ,da keine drin.

Unter diesen Umständen ein einfaches Plugin online installiert und was soll ich sagen der Neustart nur der Oberfläche dauert gar 4 Minuten!

Da ich das Problem schon mal hatte ,nehm ich natürlich mein Vollbackup und installiere es gleich nach abschalten mit rückwärtigem Schalter .

nach dieser Backup installation braucht die Box genausolange wie einen Tag vorher als fast 4 Minuten.
dieses hatte ich auch schon durch , also ich gebe der Boxs 5 Minuten Zeit nach dem ausschalten per rückwärtigen Schalter.

Spiele das gleiche Backupimage nochmal ein und die Box startet innerhalb 1 Minute.

Was ist da los?

kann da einer etwas helfen?

vielen Dank im voraus

**madie** · 12.12.2017, 15:59

ohne log, schwierig. meist sind netzwerkfreigaben die nicht erreichbar sind, schuld.

**zeini** · 12.12.2017, 16:02

Tippe auch auf Netzwerkmounts, die nicht erreichbar sind.

**Rosalie911** · 12.12.2017, 16:22

Hallo

Netzwerkfreigaben sind im moment noch nicht vergeben.

mfg

**madie** · 12.12.2017, 16:32

mach mal e2 log bitte.

Code:

init 4

Code:

enigma2

in Telnet eingeben. Dann die Ausgabe hier posten.

Mit STRG+c stopst du E² und mit

Code:

init 3

wird E² wieder normal geladen.

**Rosalie911** · 13.12.2017, 04:02

Hallo

mach ich Heute nach der Arbeit

mfg

**Rosalie911** · 13.12.2017, 14:04

Hallo

hab nach deinen Angaben in Telnet eingegeben
da läuft eine Liste ab die ich mit strg+c gestoppt habe
aber wie kopiere ich jetzt den Text aus Telnet um ihn hier zu posten?
bin da nicht so bewandert.
habe versucht einen Rahmen um den Text zu ziehen aber das geht nur bis zum Fensterrand unten nicht weiter also kein auto abwärtsscrollen
und dann kopiert es nur den Teil den ich mit der linken Maustaste gezogen habe. Wie kann ich den gesamten Text kopieren?

im Moment startet die Box normal

mfg