Start aus Standby dauert zum Teil 5 Minuten

webwude · 28. Januar 2018, 22:21

Hallo zusammen,

ich habe derzeit den Effekt, dass das "Aufwachen" aus dem Standby, insbesondere nach ungefähr einem Tag, fast 5 Minuten dauert.

Im Gegensatz zu einem "normalen" Start, bei dem dank CEC Plugin auch der TV angeht, passiert hier außer einem etwas helleren LCD Display der Box erst mal nichts. Mache ich den TV manuell an, sehe ich eine schwarzen Bildschirm und in meinem Fall den Fluid Spinner, sprich, die Box arbeitet. Warte ich ein paar Minuten ab, kommt die Box irgendwann richtig hoch.

Gibt es Logs, die ich checken kann, um herauszufinden, was hier den Startup dermaßen verzögert? Ein System-Log? Zeitlich kann ich das ganze ziemlich gut eingrenzen (im übrigen bleibt beispielsweise die Uhr im LCD Display stehen, bis die Box wirklich hoch kommt). Daher bin ich für jeden Debug-Tipp dankbar.

Vielen Dank und viele Grüße
w.

Dieter59 · 28. Januar 2018, 22:31

Hast Du evtl. am Netzwerkzugang etwas geändert und die Box kommt nicht mehr z.B. auf ein NAS oder ins Internet?

Man, mal wieder die 5 Minuten Änderungszeit um:

Du kannst auch unter Menü-> VTI -> EInstellungen System -> Seite 5/5 Debug ausgabe aktivieren.
Di Datei steht dann unter /media/hdd Vielleicht kann man da etwas rauslesen, da müsste aber evtl ein Spezialist ran um es zu interpretieren...

webwude · 28. Januar 2018, 23:03

Dieter59 schrieb:

Hast Du evtl. am Netzwerkzugang etwas geändert und die Box kommt nicht mehr z.B. auf ein NAS oder ins Internet?

Eigentlich nicht. Keine Änderungen in letzter Zeit...

Gesendet von meinem VTR-L29 mit Tapatalk

Dieter59 · 28. Januar 2018, 23:29

Dann versuch es mal mit dem Debug-Log, vielleicht erkennt man da Wartezeiten beim hochfahren

webwude · 30. Januar 2018, 21:35

Ok, heute war es wieder. Debug war an. Ich denke, das war der relevante Teil - EPGShare lief parallel. Ich habe bei Bedarf auch das komplette Log.
Die Verzögerung war heute "nur" 2 Minuten.

Quellcode

21:16:39.935 [e2-python] action -> StandbyActions power
21:16:39.935 [e2-python] leave standby
21:16:40.067 [e2-python] RemovePopup, id = goStandby
21:16:40.077 [e2-python] playing service..
21:16:40.090 [e2-core] allocate channel.. 03fb:0001
21:16:40.090 [e2-core] Slot 0, score 15005
21:16:40.090 [e2-core] Slot 1, score 15005
21:16:40.090 [e2-core] Slot 2, score 15005
21:16:40.090 [e2-core] Slot 3, score 15005
21:16:40.090 [e2-core] Slot 4, score 15005
21:16:40.090 [e2-core] Slot 5, score 15005
21:16:40.090 [e2-core] Slot 6, score 15005
21:16:40.090 [e2-core] Slot 7, score 15005
21:16:40.090 [e2-core] opening frontend 0
21:16:40.095 [e2-core] [eDVBCAService] new channel 0x2e57658!
21:16:40.095 [e2-core] (0)tune
21:16:40.095 [e2-core] [prepare] JESS
21:16:40.095 [e2-core] [prepare] UnicableTuningWord 0x466c02
21:16:40.095 [e2-core] RotorCmd ffffffff, lastRotorCmd ffffffff
21:16:40.095 [e2-core] prepare_sat Freq 11493750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0, is_id -1, pls_mode 3, pls_code 0
21:16:40.095 [e2-core] tuning to 1260 mhz
21:16:40.096 [e2-core] OURSTATE: tuning
21:16:40.096 [e2-core] allocate Channel: res 0
21:16:40.096 [e2-core] allocate demux
21:16:40.096 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
21:16:40.096 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
21:16:40.096 [e2-core] [eDVBServiceFCCPlay::changeFCCMode][1:0:19:283D:3FB:1:C00000:0:0:0:] enable FCC decoding.
21:16:40.112 [e2-python] looking for Extended Data EventID: 32664
21:16:40.113 [e2-python] Extended Data Event found
21:16:40.113 [e2-python] Event has Extended Data: {"title":"In aller Freundschaft","originaltitle":"In aller Freundschaft","subtitle":"Wieder auf Anfang","airtime":1517338800,"categoryName":"Serien","genre":"Arztserie","year":"2018","isNew":"1","country":"D","ageRating":"","season":"21","episode":"6","channelid":"1","isLastEpisode":"","highlightCategory":"Tipp","image":"http:\/\/images.tvmovie.de\/220x142\/North\/7db08150-0124-11e8-8223-5b0b40400806-1.jpg","imagebase":"","showviewNumber":"","conclusion":"Dr. Globisch muss die OP-Methode bestimmen","isDolbyDigital":"","movieStarValue":"","ratings":"","leadText":"","search":"http:\/\/thetvdb.com\/api\/GetSeries.php?seriesname=In%20aller%20Freundschaft&language=de","id":"106507152","imdbid":""}
21:16:41.220 [e2-python] looking for Extended Data EventID: 32665
21:16:41.220 [e2-python] Extended Data Event found
21:16:41.221 [e2-python] Event has Extended Data: {"title":"Report Mainz","originaltitle":"Report Mainz","subtitle":"","airtime":1517341500,"categoryName":"Reportage","genre":"Politmagazin","year":"2018","isNew":"","country":"D","ageRating":"","season":"","episode":"","channelid":"1","isLastEpisode":"","highlightCategory":"","image":"http:\/\/images.tvmovie.de\/220x142\/North\/1f8caca0-0522-11e8-ae37-239b1f324c25-1.jpg","imagebase":"","showviewNumber":"","conclusion":"","isDolbyDigital":"","movieStarValue":"","ratings":"","leadText":"","search":"","id":"106507336","imdbid":""}
21:16:42.396 [e2-core] main thread is non-idle! display spinner!
21:16:42.581 [e2-python] Infobar Renderer Error: object of type 'NoneType' has no len()
21:16:42.582 [e2-python] Infobar Renderer Error: object of type 'NoneType' has no len()
21:16:42.585 [e2-python] not pauseable.
21:16:42.586 [e2-python] RemovePopup, id = ZapError
21:16:42.588 [e2-core] setLCDBrightness 255
21:17:22.419 [e2-python] [92m[EPG Share] Paket 28 von 96 geladen[0m
21:17:22.419 [e2-python] [92m[EPG Share] Lade fehlende Bilder in den Cache Paket 29 von 96[0m
21:17:22.642 [e2-python] send: 'GET /ZAm9CSiLJ5 HTTP/1.1\r\nHost: we.tl\r\nConnection: keep-alive\r\nAccept-Encoding: gzip, deflate\r\nAccept: */*\r\nUser-Agent: python-requests/2.12.4\r\n\r\n'
21:17:22.692 [e2-python] reply: 'HTTP/1.1 302 Moved Temporarily\r\n'
21:17:22.693 [e2-python] header: Content-Type: text/html;charset=utf-8
21:17:22.693 [e2-python] header: Content-Length: 0
21:17:22.693 [e2-python] header: Connection: keep-alive
21:17:22.693 [e2-python] header: Cache-Control: public
21:17:22.693 [e2-python] header: Date: Tue, 30 Jan 2018 20:05:31 GMT
21:17:22.693 [e2-python] header: Location: https://wetransfer.com/downloads/a26238ae653aa13ed740c256dafd585c20180129233431/bacf0a
21:17:22.693 [e2-python] header: Server: nginx/1.12.1
21:17:22.694 [e2-python] header: X-Content-Type-Options: nosniff
21:17:22.694 [e2-python] header: X-Request-Id: 4eaeaa81445e3684c2cdd22d66af5519
21:17:22.694 [e2-python] header: X-XSS-Protection: 1; mode=block
21:17:22.694 [e2-python] header: Age: 711
21:17:22.694 [e2-python] header: X-Cache: Hit from cloudfront
21:17:22.694 [e2-python] header: Via: 1.1 e53ffdce5e776700d43635731052fba6.cloudfront.net (CloudFront)
21:17:22.694 [e2-python] header: X-Amz-Cf-Id: qVLJ13JAo9PWhDRuUoyiWEgh3yQfEAvrpaz66-sepcJOY2mpbL7inw==
21:17:23.021 [e2-python] send: 'GET /downloads/a26238ae653aa13ed740c256dafd585c20180129233431/bacf0a HTTP/1.1\r\nHost: wetransfer.com\r\nConnection: keep-alive\r\nAccept-Encoding: gzip, deflate\r\nAccept: */*\r\nUser-Agent: python-requests/2.12.4\r\n\r\n'
21:17:23.271 [e2-python] reply: 'HTTP/1.1 200 OK\r\n'
21:17:23.272 [e2-python] header: Date: Tue, 30 Jan 2018 20:17:23 GMT
21:17:23.272 [e2-python] header: Content-Type: text/html; charset=utf-8
21:17:23.272 [e2-python] header: Transfer-Encoding: chunked
21:17:23.272 [e2-python] header: Connection: keep-alive
21:17:23.273 [e2-python] header: X-Frame-Options: SAMEORIGIN
21:17:23.273 [e2-python] header: X-XSS-Protection: 1; mode=block
21:17:23.273 [e2-python] header: X-Content-Type-Options: nosniff
21:17:23.273 [e2-python] header: Cache-Control: no-cache, no-store, max-age=0, must-revalidate
21:17:23.273 [e2-python] header: Pragma: no-cache
21:17:23.273 [e2-python] header: Expires: Fri, 01 Jan 1990 00:00:00 GMT
21:17:23.273 [e2-python] header: Vary: Accept-Encoding, Origin
21:17:23.273 [e2-python] header: Content-Encoding: gzip
21:17:23.273 [e2-python] header: Set-Cookie: _wt_session=BAh7B0kiD3Nlc3Npb25faWQGOgZFVEkiJTBmMDE1Njg1ZjI2ZTQ2NDNlOWFhOWI2YzE1NGZmZmU0BjsAVEkiEF9jc3JmX3Rva2VuBjsARkkiMVdOa2RhcExmbVNwR09SWkIyR1ZwQ2szMzBCWkdOeTd6aHlBcGZpUTE2T1U9BjsARg%3D%3D--f09e371505655e391a13eb70b2ea940fb3eb2be8; domain=wetransfer.com; path=/; secure; HttpOnly
21:17:23.274 [e2-python] header: X-Request-Id: 60bbdd1f-92eb-44d9-9840-eea79f7bc1d9
21:17:23.274 [e2-python] header: X-Opaque: dev-1.wt-24439
21:17:23.274 [e2-python] header: X-Runtime: 0.151329
21:17:23.274 [e2-python] header: Strict-Transport-Security: max-age=15552000; includeSubDomains;
21:17:23.381 [e2-python] https://wetransfer.com/api/ui/transfers/a26238ae653aa13ed740c256dafd585c20180129233431/bacf0a/download
21:17:23.382 [e2-python] a26238ae653aa13ed740c256dafd585c20180129233431
21:17:23.382 [e2-python]
21:17:23.382 [e2-python] bacf0a
21:17:23.720 [e2-python] send: 'POST /api/ui/transfers/a26238ae653aa13ed740c256dafd585c20180129233431/bacf0a/download HTTP/1.1\r\nHost: wetransfer.com\r\nConnection: keep-alive\r\nAccept-Encoding: gzip, deflate\r\nAccept: */*\r\nUser-Agent: python-requests/2.12.4\r\nContent-Length: 13\r\nContent-Type: application/x-www-form-urlencoded\r\n\r\nrecipient_id='
21:17:23.843 [e2-python] reply: 'HTTP/1.1 200 OK\r\n'
21:17:23.845 [e2-python] header: Date: Tue, 30 Jan 2018 20:17:23 GMT
21:17:23.845 [e2-python] header: Content-Type: application/json
21:17:23.845 [e2-python] header: Transfer-Encoding: chunked
21:17:23.845 [e2-python] header: Connection: keep-alive
21:17:23.845 [e2-python] header: X-Compatibility-Version: 5
21:17:23.845 [e2-python] header: Set-Cookie: wt_ls=327f774f21d7554d; path=/; expires=Tue, 30 Jan 2018 23:17:23 -0000
21:17:23.846 [e2-python] header: X-Content-Type-Options: nosniff
21:17:23.846 [e2-python] header: Vary: Accept-Encoding, Origin
21:17:23.846 [e2-python] header: Content-Encoding: gzip
21:17:23.846 [e2-python] header: ETag: W/"af98c287c67230695762b4032cdb7267"
21:17:23.846 [e2-python] header: Cache-Control: max-age=0, private, must-revalidate
21:17:23.846 [e2-python] header: X-Request-Id: eab7899d-3e3e-46e7-8947-1d1f2dfa6aea
21:17:23.846 [e2-python] header: X-Opaque: dev-1.wt-42301
21:17:23.846 [e2-python] header: X-Runtime: 0.039648
21:17:23.846 [e2-python] header: Strict-Transport-Security: max-age=15552000; includeSubDomains;
21:17:23.866 [e2-python] {u'direct_link': u'https://download.wetransfer.com/eu2/a26238ae653aa13ed740c256dafd585c20180129233431/images.zip?token=eyJhbGciOiJIUzI1NiJ9.eyJ1bmlxdWUiOiJhMjYyMzhhZTY1M2FhMTNlZDc0MGMyNTZkYWZkNTg1YzIwMTgwMTI5MjMzNDMxIiwicHJvZmlsZSI6ImV1MiIsImZpbGVuYW1lIjoiaW1hZ2VzLnppcCIsImVzY2FwZWQiOiJmYWxzZSIsImV4cGlyZXMiOjE1MTczNDQwNDMsIndheWJpbGxfdXJsIjoiaHR0cDovL3Byb2R1Y3Rpb24uYmFja2VuZC5zZXJ2aWNlLmV1LXdlc3QtMS53dDo5MjkyL3dheWJpbGwvdjEvMTM5YmZkOTUyNzgxMmY1NjZmYzJjMGJkMDczN2ExODU3ODJiZWU5MWNlOGY1MDBjMzcyZDU4ZmRkNDg0In0.AVZm3wMFaLVC0oB_yCeXvVui89GnwjpoDOZ9k1T4UIs'}
21:17:24.066 [e2-python] send: 'GET /eu2/a26238ae653aa13ed740c256dafd585c20180129233431/images.zip?token=eyJhbGciOiJIUzI1NiJ9.eyJ1bmlxdWUiOiJhMjYyMzhhZTY1M2FhMTNlZDc0MGMyNTZkYWZkNTg1YzIwMTgwMTI5MjMzNDMxIiwicHJvZmlsZSI6ImV1MiIsImZpbGVuYW1lIjoiaW1hZ2VzLnppcCIsImVzY2FwZWQiOiJmYWxzZSIsImV4cGlyZXMiOjE1MTczNDQwNDMsIndheWJpbGxfdXJsIjoiaHR0cDovL3Byb2R1Y3Rpb24uYmFja2VuZC5zZXJ2aWNlLmV1LXdlc3QtMS53dDo5MjkyL3dheWJpbGwvdjEvMTM5YmZkOTUyNzgxMmY1NjZmYzJjMGJkMDczN2ExODU3ODJiZWU5MWNlOGY1MDBjMzcyZDU4ZmRkNDg0In0.AVZm3wMFaLVC0oB_yCeXvVui89GnwjpoDOZ9k1T4UIs HTTP/1.1\r\nHost: download.wetransfer.com\r\nConnection: keep-alive\r\nAccept-Encoding: gzip, deflate\r\nAccept: */*\r\nUser-Agent: python-requests/2.12.4\r\n\r\n'
21:17:24.178 [e2-python] reply: 'HTTP/1.1 200 OK\r\n'
21:17:24.179 [e2-python] header: Content-Type: application/zip
21:17:24.179 [e2-python] header: Content-Length: 14726613
21:17:24.179 [e2-python] header: Connection: keep-alive
21:17:24.179 [e2-python] header: Server: nginx/1.12.1
21:17:24.180 [e2-python] header: Date: Tue, 30 Jan 2018 20:17:24 GMT
21:17:24.180 [e2-python] header: Content-Transfer-Encoding: binary
21:17:24.180 [e2-python] header: Content-Encoding: none
21:17:24.180 [e2-python] header: Cache-Control: private, no-transform, no-store
21:17:24.180 [e2-python] header: Allow: GET, HEAD
21:17:24.180 [e2-python] header: Content-Disposition: attachment
21:17:24.180 [e2-python] header: X-Revision: b72732f2aa8cc5350d23d87fa9a5b9f0bce60d34
21:17:24.180 [e2-python] header: X-Transfer-Id: a26238ae653aa13ed740c256dafd585c20180129233431
21:17:24.180 [e2-python] header: Accept-Ranges: bytes
21:17:24.180 [e2-python] header: X-Fast-Send-Dispatch: hijack
21:17:24.181 [e2-python] header: X-Cache: Miss from cloudfront
21:17:24.181 [e2-python] header: Via: 1.1 37c3a16c37bbeefc157b7c347e4a8fc8.cloudfront.net (CloudFront)
21:17:24.181 [e2-python] header: X-Amz-Cf-Id: -0-NG4uBuGJbDcyWkATF2WLHn6u73PifjWOZR7nHyZ82aUtY7Emu2Q==
21:18:02.682 [e2-python] [AutoShutDown] leave standby . . .
21:18:02.683 [e2-python] [AutoShutDown] No ShutDownTimer to stop
21:18:02.708 [e2-core] [SEC] set static current limiting
21:18:02.764 [e2-python] [AutoTimeShift] start timeshift
21:18:02.764 [e2-python] [AutoTimeShift] no ts interface
21:18:02.789 [e2-python] next real activation is Tue Jan 30 21:54:40 2018
21:18:02.792 [e2-core] [eVTiApp] starting...
21:18:02.792 [e2-core] before: 1
21:18:02.792 [e2-core] after: 1
21:18:02.792 [e2-core] old thread joined 0
21:18:02.792 [e2-core] [eVTiApp] connecting...
21:18:02.810 [e2-core] [SEC] invalidate current switch params
21:18:02.811 [e2-core] [SEC] setVoltage 1
21:18:02.812 [e2-core] [SEC] sleep 200ms
21:18:03.012 [e2-core] [SEC] setVoltage 2
21:18:03.013 [e2-core] [SEC] setTone 0
21:18:03.014 [e2-core] [SEC] sleep 20ms
21:18:03.090 [e2-core] [SEC] sendDiseqc: 70466c02
21:18:03.091 [e2-core] [SEC] sleep 50ms
21:18:03.142 [e2-core] [SEC] setVoltage 1
21:18:03.143 [e2-core] [SEC] update current switch params
21:18:03.143 [e2-core] [SEC] startTuneTimeout 5000
21:18:03.143 [e2-core] [SEC] setFrontend 1
21:18:03.143 [e2-core] setting frontend 0
21:18:03.143 [e2-core] [SEC] sleep 500ms
21:18:03.388 [e2-core] (0)fe event: status 0, inversion off, m_tuning 1
21:18:03.437 [e2-core] (0)fe event: status 7, inversion off, m_tuning 2
21:18:03.456 [e2-python] [92m[EPG Share] Paket 29 von 96 geladen[0m
21:18:03.456 [e2-python] [92m[EPG Share] Lade fehlende Bilder in den Cache Paket 30 von 96[0m
21:18:03.644 [e2-core] [SEC] set dynamic current limiting

Alles anzeigen

RickX · 30. Januar 2018, 21:40

Das sieht so aus, als würde EPGshare da Daten aus dem Internet laden.
Du kannst ja mal versuchen, ob es ohne EPGshare schneller geht.

Dieter59 · 30. Januar 2018, 21:47

Ja, das hab ich auch so interpretiert, ich denke es liegt am Download der Bilder in EPGShare, schalte vielleicht zuerst das mal ab...könnte sein, dass der Server ab und zu mal etwas viel Last hat,das würde auch das eher zufällige Verhalten erklären

webwude · 30. Januar 2018, 21:56

Ja. Aber warum bleibt deswegen die Box im Standby Hochfahren hängen? Wenn ich Epgshare während des Betriebs starte, hängt die Box auch nicht...

Trotzdem. Ich lasse Epgshare mal nachts laufen, man sehen, ob das was ändert...

Gesendet von meinem VTR-L29 mit Tapatalk

Dieter59 · 30. Januar 2018, 22:02

Zumindest ich kann Dir das Warum nicht erklären. Ich könnte nur mutmaßen, aber das hilft nicht weiter. Das was @RickX und ich gefunden habe ist einfach nur eine Vermutung und ich würde es an Deiner Stelle einfach mal ausprobieren. Oder Du musst einen der Profis (ich glaube EPGShare ist von @einfall und / oder @hmmmdada) fragen.