[Erledigt] Zero 4K macht im laufenden Betrieb GUI Reboots

bimmler · 29. Dezember 2019, 19:20

Neue Erkenntnisse......oder auch nicht

Nach dem ich es nicht geschafft habe, die GUI Reboots an der neu aufgesetzten Zero 4K zu beenden, habe ich kurzer Hand meine Uno 4K SE mitgebracht um sie dort zu lassen bis die Zero 4K wieder normal läuft.

Dort hab ich sie angeschlossen und die Zero 4K wieder eingepackt und bin heim gefahren. Was soll ich sagen, jetzt macht die Uno 4K SE GUI Reboots die bei mir einwandfrei läuft

Was mir jetzt nur einfällt, es wurde für die Zero 4K und auch jetzt für die Uno 4K SE dort eine Fernbedienung Typ 2 der alten Solo2 benutzt. Das kann aber beim besten Willen nicht der Grund für den Käse sein denke ich.

Dann fällt mir nur noch das Netzwerk ein. Beide Kisten sind per LAN Kabel angeschlossen an eine Fritzbox. So wie hier bei mir auch. Könnte eine Hostdatei dafür schuld sein oder sonstiges am Netzwerk seitens der VU?

Ich fahre dort morgen vormittag wieder hin und hätte gerne paar Lösungsansätze.

Dibagger · 29. Dezember 2019, 19:24

Spannungschwankungen im Stromnetz dort? Plasmafernseher in der Nähe?

bimmler · 29. Dezember 2019, 19:47

Schwankungen im Stromnetz? Öhmm keine Ahnung.

Darüber hängt n billiger 4K LCD.

Bis vor zwei, drei Wochen lief dort problemlos ne Solo2. Die sollten nur mangels Flashspeicher erneuert werden.

hajeku123 · 29. Dezember 2019, 20:17

Netzwerkkabel rausziehen.
Wenn das Problem dann weg ist, kommt es von außerhalb der vu. (Entweder aus dem hausinternen Netzwerk oder Internet)
Portfreigaben am Router aktiv?

evl. auch CEC deaktivieren.

bimmler · 30. Dezember 2019, 01:04

Ja aber was verursacht denn sowas nur? Werd morgen den netzwerkadapter einmal deaktivieren und dann wieder aktivieren. Das Netzwerk ist das gleiche wie vor paar Wochen als die Solo2 normal dran lief.

Portfreigaben sind keine offen.

hgdo · 6. Januar 2020, 18:57

Mehrere Beiträge in separaten Thread ausgelagert: Problem mit Crashs der Zero 4K unter Blackhole

bimmler · 8. Januar 2020, 17:14

So hier bin ich wieder. Nun habe ich zugriff auf das Debug Log. Ich habe hier einen Zettel liegen wo ich gebeten habe immer die Uhrzeit auf zuschreiben wenn es einen GUI Neustart gab.

Dazu hier mal Auszüge aus dem Log bei den folgenden Uhrzeiten, u.a. : 6:40, 6:45, 6:55

Spoiler anzeigen

Quellcode

06:39:48.147 [e2-core] +- 1/2 TID 4e
06:39:48.514 [e2-core] ++ 2/2 TID 4e
06:39:48.514 [e2-core] done!
06:39:48.519 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:39:48.520 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668092.jpg
06:39:48.520 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:39:48.523 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:39:48.523 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668023.jpg
06:39:48.524 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:39:48.525 [e2-core] doing version filtering
06:39:48.525 [e2-core] 0012: 4e ef 14 0f 00 00
06:39:48.525 [e2-core] mask: ff ff ff 3f 00 00
06:39:48.525 [e2-core] mode: 00 00 00 3e 00 00
06:40:03.327 [e2-core] +- 1/2 TID 4e
06:40:03.710 [e2-core] ++ 2/2 TID 4e
06:40:03.711 [e2-core] done!
06:40:03.716 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:40:03.716 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668023.jpg
06:40:03.717 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:40:03.719 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:40:03.720 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668125.jpg
06:40:03.720 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:40:03.754 [e2-core] doing version filtering
06:40:03.755 [e2-core] 0012: 4e ef 14 11 00 00
06:40:03.755 [e2-core] mask: ff ff ff 3f 00 00
06:40:03.755 [e2-core] mode: 00 00 00 3e 00 00
06:41:19.999 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:42:59.000 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:44:39.000 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:46:17.999 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:47:56.999 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:49:35.999 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:49:47.626 [e2-core] +- 1/2 TID 4e
06:49:47.995 [e2-core] ++ 2/2 TID 4e
06:49:47.995 [e2-core] done!
06:49:48.001 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:49:48.001 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668023.jpg
06:49:48.002 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:49:48.004 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:49:48.005 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668125.jpg
06:49:48.005 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:49:48.006 [e2-core] doing version filtering
06:49:48.006 [e2-core] 0012: 4e ef 14 13 00 00
06:49:48.006 [e2-core] mask: ff ff ff 3f 00 00
06:49:48.006 [e2-core] mode: 00 00 00 3e 00 00
06:50:03.145 [e2-core] +- 1/2 TID 4e
06:50:03.520 [e2-core] ++ 2/2 TID 4e
06:50:03.520 [e2-core] done!
06:50:03.525 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:50:03.526 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668125.jpg
06:50:03.526 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:50:03.529 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
06:50:03.530 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668080.jpg
06:50:03.530 [e2-python] [92m[MetrixInfobarDescription] Image not exist
06:50:03.564 [e2-core] doing version filtering
06:50:03.564 [e2-core] 0012: 4e ef 14 15 00 00
06:50:03.564 [e2-core] mask: ff ff ff 3f 00 00
06:50:03.564 [e2-core] mode: 00 00 00 3e 00 00
06:51:15.000 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:52:34.769 [e2-core] no version filtering
06:52:34.769 [e2-core] 0014: 70 00 00 00 00 00
06:52:34.769 [e2-core] mask: fc 00 00 00 00 00
06:52:34.769 [e2-core] mode: 00 00 00 00 00 00
06:52:34.987 [e2-core] [eDVBLocalTimerHandler] diff is 0
06:52:34.987 [e2-core] [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
06:52:34.988 [e2-core] [eDVBLocalTimerHandler] not changed
06:52:54.999 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:54:33.999 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:56:13.000 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:57:53.000 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:59:31.999 [e2-python] next real activation isWed Jan 8 18:59:40 2020
06:59:48.194 [e2-core] +- 1/2 TID 4e
06:59:48.566 [e2-core] ++ 2/2 TID 4e
06:59:48.566 [e2-core] done!

Alles anzeigen

dg4sj · 8. Januar 2020, 17:37

von einem GUI Restart ist irgendwie nichts zu sehen

nur immer mal wieder wird eine Datei vermisst:

06:39:48.520 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.08.20/124668092.jpg
06:39:48.520 [e2-python] [92m[MetrixInfobarDescription] Image not exist

bimmler · 8. Januar 2020, 17:38

Hier wird EPG Share genutzt und extra Daten laden war aktiviert. Im Skin habe ich aber extra Daten NICHT anzeigen lassen per Skinpart. Nun habe ich den Skinpart aktiviert. Mal sehen ob es daran lag.

edit: Es wurde auch kein Crashlog erzeugt. Kein Plan. Hab das Debuglog durch geschaut und dies ist das einzige was zu den Uhrzeiten passt, wo der GUI Neustart passiert.

bimmler · 9. Januar 2020, 20:10

Ich war auf dem Holzweg, die GUI Reboots passieren immer noch.

Könnte sich einer noch mal das Log anschauen?

Aufgeschrieben wurde eine Störung u.a. um 18:30 Uhr (wobei leider auf dem Zettel alle Störzeiten ab- oder aufgerundet wurden wie ich sehe, könnte also auch 18:27 - 18:32 sein) Ich poste mal dazu einen Auszug aus dem Debuglog, ich finde das viel, wenn nicht sogar zuviel um 18:32 passiert

Spoiler anzeigen

Quellcode

18:10:08.919 [e2-core] [eEPGCache] next update in 60 min
18:10:18.020 [e2-core] no ancillary data in audio stream... abort radiotext pes parser
18:11:07.999 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:12:46.999 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:14:26.001 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:16:06.001 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:17:45.999 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:19:24.999 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:21:04.000 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:22:44.000 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:24:08.223 [e2-python] action -> InfobarShowHideActions toggleShow
18:24:14.551 [e2-python] action -> InfobarShowHideActions toggleShow
18:24:22.999 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:26:01.999 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:27:41.000 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:29:21.000 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:31:00.000 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:32:22.373 [e2-python] action -> InfobarChannelSelection showFavourites
18:32:22.374 [e2-python] playing service..
18:32:22.374 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:7C:C:85:C00000:0:0:0:
18:32:22.375 [e2-core] [eDVBCAService] free service 1:0:19:7C:C:85:C00000:0:0:0:
18:32:22.378 [e2-core] decoder state: play, vpid=-1, apid=-1
18:32:22.379 [e2-core] DEMUX_STOP - pcr - ok
18:32:22.380 [e2-core] DEMUX_STOP - video - ok
18:32:22.380 [e2-core] VIDEO_STOP - ok
18:32:22.404 [e2-core] AUDIO_STOP - ok
18:32:22.404 [e2-core] DEMUX_STOP - audio - ok
18:32:22.404 [e2-core] AUDIO_CONTINUE - ok
18:32:22.407 [e2-core] start release channel timer
18:32:22.416 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:22.417 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124653255.jpg
18:32:22.417 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:22.419 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:22.420 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/125278391.jpg
18:32:22.421 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:22.429 [e2-python] [#----] VTiCore not pauseable.
18:32:22.430 [e2-python] RemovePopup, id =ZapError
18:32:22.430 [e2-core] [eDVBCAService] remove channel 0x23206e0
18:32:22.430 [e2-core] [eDVBLocalTimerHandler] remove channel 0x23206e0
18:32:22.430 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:22.431 [e2-core] allocate channel.. 0411:0001
18:32:22.431 [e2-core] Slot 0, score 10002
18:32:22.431 [e2-core] [eDVBCAService] new channel 0x23206e0!
18:32:22.431 [e2-core] (0)tune
18:32:22.431 [e2-core] RotorCmd ffffffff, lastRotorCmd ffffffff
18:32:22.431 [e2-core] prepare_sat Freq 11082250 Pol 0 SR 22000000 INV 2 FEC 3 orbpos 192 system 1 modulation 2 pilot 2, rolloff 2, is_id -1, pls_mode 3, pls_code 0
18:32:22.431 [e2-core] tuning to 1332 mhz
18:32:22.431 [e2-core] OURSTATE: tuning
18:32:22.431 [e2-core] allocate Channel: res 0
18:32:22.431 [e2-core] allocate demux
18:32:22.431 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:22.432 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:22.432 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:2E9B:411:1:C00000:0:0:0:, SUPER RTL HD
18:32:22.435 [e2-core] [SEC] set static current limiting
18:32:22.437 [e2-core] [SEC] setTone 0
18:32:22.450 [e2-core] [SEC] sleep 25ms
18:32:22.475 [e2-core] set sequence pos 7
18:32:22.475 [e2-core] [SEC] invalidate current switch params
18:32:22.568 [e2-core] [SEC] sendDiseqc: e01038f2
18:32:22.568 [e2-core] [SEC] sleep 50ms
18:32:22.618 [e2-core] set sequence pos 3
18:32:22.618 [e2-core] set sequence pos 3
18:32:22.618 [e2-core] [SEC] update current switch params
18:32:22.618 [e2-core] [SEC] startTuneTimeout 5000
18:32:22.618 [e2-core] [SEC] setFrontend 1
18:32:22.618 [e2-core] setting frontend 0
18:32:22.618 [e2-core] [SEC] sleep 500ms
18:32:22.711 [e2-core] (0)fe event: status 0, inversion off, m_tuning 1
18:32:22.838 [e2-core] (0)fe event: status 1f, inversion off, m_tuning 2
18:32:22.838 [e2-core] OURSTATE: ok
18:32:22.839 [e2-core] [eDVBCAService] channel 0x23206e0 running
18:32:22.839 [e2-core] [eDVBLocalTimerHandler] channel 0x23206e0 running
18:32:22.839 [e2-core] no version filtering
18:32:22.839 [e2-core] 0014: 70 00 00 00 00 00
18:32:22.839 [e2-core] mask: fc 00 00 00 00 00
18:32:22.839 [e2-core] mode: 00 00 00 00 00 00
18:32:22.839 [e2-core] [eEPGCache] channel 0x23206e0 running
18:32:22.839 [e2-core] [eEPGCache] next update in 22 min
18:32:22.840 [e2-core] stop release channel timer
18:32:22.840 [e2-core] no version filtering
18:32:22.840 [e2-core] 0012: 4e 2e 9b 00 00 00
18:32:22.840 [e2-core] mask: ff ff ff 00 00 00
18:32:22.840 [e2-core] mode: 00 00 00 00 00 00
18:32:22.841 [e2-core] ok ... now we start!!
18:32:22.841 [e2-core] no version filtering
18:32:22.841 [e2-core] 0000: 00 00 00 00 00 00
18:32:22.841 [e2-core] mask: ff 00 00 00 00 00
18:32:22.841 [e2-core] mode: 00 00 00 00 00 00
18:32:22.841 [e2-core] eventNewProgramInfo 0 0
18:32:22.841 [e2-core] have 1 video stream(s) (012c), and 1 audio stream(s) (0136), and the pcr pid is 012c, and the text pid is 0140
18:32:22.841 [e2-core] allocate demux
18:32:22.844 [e2-core] disable teletext subtitles
18:32:22.845 [e2-core] decoder state: play, vpid=300, apid=310
18:32:22.845 [e2-core] DMX_SET_PES_FILTER(0x12c) - pcr - ok
18:32:22.845 [e2-core] DEMUX_START - pcr - ok
18:32:22.845 [e2-core] DMX_SET_PES_FILTER(0x136) - audio - ok
18:32:22.845 [e2-core] DEMUX_START - audio - ok
18:32:22.845 [e2-core] AUDIO_SET_BYPASS(0) - ok
18:32:22.845 [e2-core] AUDIO_PAUSE - ok
18:32:22.847 [e2-core] AUDIO_PLAY - ok
18:32:22.847 [e2-core] Video Device: /dev/dvb/adapter0/video0
18:32:22.847 [e2-core] demux device: /dev/dvb/adapter0/demux0
18:32:22.847 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
18:32:22.847 [e2-core] DMX_SET_PES_FILTER(0x12c) - video - ok
18:32:22.847 [e2-core] DEMUX_START - video - ok
18:32:22.848 [e2-core] VIDEO_FREEZE - ok
18:32:22.853 [e2-core] VIDEO_PLAY - ok
18:32:22.853 [e2-core] DMX_SET_PES_FILTER(0x140) - ttx - ok
18:32:22.853 [e2-core] DEMUX_START - ttx - ok
18:32:22.855 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:22.856 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:22.856 [e2-core] VIDEO_CONTINUE - ok
18:32:22.856 [e2-core] AUDIO_CONTINUE - ok
18:32:22.857 [e2-python] [#----] VTiCore not pauseable.
18:32:22.859 [e2-python] [AutoRes] service changed
18:32:22.986 [e2-core] + 1/1 TID 00
18:32:22.987 [e2-core] done!
18:32:22.987 [e2-core] PATready
18:32:22.987 [e2-core] use pmtpid 0035 for service_id 2e9b
18:32:22.987 [e2-core] no version filtering
18:32:22.987 [e2-core] 0035: 02 2e 9b 00 00 00
18:32:22.987 [e2-core] mask: ff ff ff 00 00 00
18:32:22.987 [e2-core] mode: 00 00 00 00 00 00
18:32:22.988 [e2-core] doing version filtering
18:32:22.988 [e2-core] 0000: 00 00 00 0f 00 00
18:32:22.988 [e2-core] mask: ff 00 00 3f 00 00
18:32:22.988 [e2-core] mode: 00 00 00 3e 00 00
18:32:23.062 [e2-core] +- 1/2 TID 4e
18:32:23.118 [e2-core] [SEC] set dynamic current limiting
18:32:23.125 [e2-core] + 1/1 TID 02
18:32:23.125 [e2-core] done!
18:32:23.126 [e2-core] eventNewProgramInfo 0 0
18:32:23.126 [e2-core] no version filtering
18:32:23.126 [e2-core] 0141: 74 00 00 00 00 00
18:32:23.126 [e2-core] mask: ff 00 00 00 00 00
18:32:23.126 [e2-core] mode: 00 00 00 00 00 00
18:32:23.127 [e2-core] [autoAudioTrack] use audio track: 0
18:32:23.127 [e2-core] have 1 video stream(s) (012c), and 1 audio stream(s) (0136), and the pcr pid is 012c, and the text pid is 0140
18:32:23.127 [e2-core] decoder state: play, vpid=300, apid=310
18:32:23.128 [e2-core] no version filtering
18:32:23.129 [e2-core] 0141: 74 00 00 00 00 00
18:32:23.129 [e2-core] mask: ff 00 00 00 00 00
18:32:23.129 [e2-core] mode: 00 00 00 00 00 00
18:32:23.129 [e2-core] [autoAudioTrack] use audio track: 0
18:32:23.132 [e2-core] [eDVBCAService] new service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:23.133 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:23.133 [e2-core] demux 0 mask 01 prevhash 00000000
18:32:23.133 [e2-core] [eDVBCAService] send 598 bytes
18:32:23.134 [e2-core] 9f 80 32 82 02 50 03 2e 9b 05 02 27 01 81 08 00 c0 00 00 04 11 00 01 82 02 01 00 84 02 00 35 09 6f 18 30 e1 55 8c 69 00 00 3d a3 0d 01 80 11 80 00 02 18 30 01 0f 9f 20 ff aa 15 02 80 11 00 00 00 64 00 03 03 21 00 00 00 02 18 30 00 00 00 64 aa 15 02 80 11 00 00 0b ea 00 03 03 21 00 00 00 02 18 30 00 00 0b ea 01 00 26 a3 0d 01 80 11 80 00 02 18 30 01 0d ab 20 ff aa 15 02 80 11 00 00 0b b8 00 03 03 21 00 00 00 02 18 43 00 00 0b b8 09 7b 18 43 e1 56 8c 75 00 00 43 8c 13 00 80 11 08 02 18 43 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 66 00 03 03 21 00 00 02 18 43 5d 00 00 66 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 43 5d 00 0b ea 00 01 00 2c 8c 13 00 80 11 08 02 18 43 20 0a 09 38 59 00 0d 7a 59 00 0d 7a aa 15 03 80 11 5d 00 0b b8 00 03 03 21 00 00 02 18 43 5d 00 0b b8 00 09 7b 18 60 e1 59 8c 75 00 00 43 8c 13 00 80 11 08 02 18 60 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 68 00 03 03 21 00 00 02 18 60 5d 00 00 68 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 60 5d 00 0b ea 00 01 00 2c 8c 13 00 80 11 08 02 18 60 20 0a 09 38 59 00 0d 7a 59 00 0d 7a aa 15 03 80 11 5d 00 0b b8 00 03 03 21 00 00 02 18 60 5d 00 0b b8 00 09 04 09 c4 e1 57 09 04 09 8c e1 58 09 04 06 cb e1 5e 09 7b 18 6a e1 5a 8c 75 00 00 43 8c 13 00 80 11 08 02 18 6a 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 6a 00 03 03 21 00 00 02 18 6a 5d 00 00 6a 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 6a 5d 00 0b ea 00 01 00 2c 8c 13 00 80 11 08 02 18 6a 20 0a 09 38 59 00 0d 7a 59 00 0d 7a aa 15 03 80 11 5d 00 0b b8 00 03 03 21 00 00 02 18 6a 5d 00 0b b8 00 09 04 18 6d e1 5b 09 04 09 8d e1 5c 09 04 18 42 e1 5f 09 06 4b 64 e1 5f 18 42 1b 01 2c 00 00 06 01 36 00 00 05 01 41 00 00 06 01 40 00 00 06 01 4a 00 00 0b 01 37 00 00 0c 01 38 00 00
18:32:23.134 [e2-core] doing version filtering
18:32:23.134 [e2-core] 0035: 02 2e 9b 05 00 00
18:32:23.134 [e2-core] mask: ff ff ff 3f 00 00
18:32:23.134 [e2-core] mode: 00 00 00 3e 00 00
18:32:23.240 [e2-core] + 1/1 TID 74
18:32:23.240 [e2-core] done!
18:32:23.240 [e2-core] AITready
18:32:23.241 [e2-core] Section Length : 104, Total Section Length : 107
18:32:23.241 [e2-core] found applicaions ids >> pid : ffffffff, orgid : 26, appid : 600
18:32:23.241 [e2-core] Save Data Len : [107]
18:32:23.241 [e2-core] Found : control[1], name[Super RTL Startleiste], url[http://hbbtv.superrtl.de/index.html]
18:32:23.241 [e2-python] Name : Super RTL Startleiste
18:32:23.242 [e2-python] URL : http://hbbtv.superrtl.de/index.html
18:32:23.242 [e2-python] OrgID : 26
18:32:23.242 [e2-python] AppID : 600
18:32:23.242 [e2-python] Control Code : 1
18:32:23.242 [e2-python] Profile Code : 0
18:32:23.242 [e2-python]
18:32:23.242 [e2-python] [#----] VTiCore (1, 'Super RTL Startleiste', 'http://hbbtv.superrtl.de/index.html', 26, 600, 0)
18:32:23.290 [e2-core] ++ 2/2 TID 4e
18:32:23.290 [e2-core] done!
18:32:23.295 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:23.295 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124653255.jpg
18:32:23.296 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:23.298 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:23.299 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/125278391.jpg
18:32:23.299 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:23.300 [e2-core] doing version filtering
18:32:23.300 [e2-core] 0012: 4e 2e 9b 03 00 00
18:32:23.300 [e2-core] mask: ff ff ff 3f 00 00
18:32:23.300 [e2-core] mode: 00 00 00 3e 00 00
18:32:23.860 [e2-python] [AutoRes] determineContent
18:32:23.861 [e2-python] [AutoRes] switch deinterlacer mode to auto
18:32:23.861 [e2-python] [AutoRes] new content is 1920x1080i25
18:32:23.861 [e2-python] [AutoRes] determined videomode1080p
18:32:24.665 [e2-core] sdt update done!
18:32:31.159 [e2-python] action -> InfobarChannelSelection showFavourites
18:32:31.160 [e2-python] playing service..
18:32:31.161 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:31.161 [e2-core] [eDVBCAService] free service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:31.165 [e2-core] decoder state: play, vpid=-1, apid=-1
18:32:31.165 [e2-core] DEMUX_STOP - pcr - ok
18:32:31.166 [e2-core] DEMUX_STOP - video - ok
18:32:31.166 [e2-core] VIDEO_STOP - ok
18:32:31.190 [e2-core] AUDIO_STOP - ok
18:32:31.190 [e2-core] DEMUX_STOP - audio - ok
18:32:31.190 [e2-core] AUDIO_CONTINUE - ok
18:32:31.191 [e2-core] DEMUX_STOP - ttx - ok
18:32:31.193 [e2-core] start release channel timer
18:32:31.202 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:31.203 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124556932.jpg
18:32:31.204 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:31.206 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:31.206 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124556878.jpg
18:32:31.207 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:31.215 [e2-python] [#----] VTiCore not pauseable.
18:32:31.216 [e2-python] RemovePopup, id =ZapError
18:32:31.216 [e2-core] [eDVBCAService] remove channel 0x23206e0
18:32:31.216 [e2-core] [eDVBLocalTimerHandler] remove channel 0x23206e0
18:32:31.216 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:31.217 [e2-core] allocate channel.. 000d:0085
18:32:31.217 [e2-core] Slot 0, score 10002
18:32:31.217 [e2-core] [eDVBCAService] new channel 0x23206e0!
18:32:31.217 [e2-core] (0)tune
18:32:31.217 [e2-core] RotorCmd ffffffff, lastRotorCmd ffffffff
18:32:31.217 [e2-core] prepare_sat Freq 11992500 Pol 0 SR 27500000 INV 2 FEC 9 orbpos 192 system 1 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 3, pls_code 0
18:32:31.217 [e2-core] tuning to 1392 mhz
18:32:31.217 [e2-core] OURSTATE: tuning
18:32:31.217 [e2-core] allocate Channel: res 0
18:32:31.217 [e2-core] allocate demux
18:32:31.217 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:31.218 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:31.218 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:7F:D:85:C00000:0:0:0:, 13th Street HD
18:32:31.220 [e2-core] [SEC] set static current limiting
18:32:31.220 [e2-core] set sequence pos 3
18:32:31.221 [e2-core] set sequence pos 7
18:32:31.221 [e2-core] [SEC] invalidate current switch params
18:32:31.314 [e2-core] [SEC] sendDiseqc: e01038f3
18:32:31.314 [e2-core] [SEC] sleep 50ms
18:32:31.364 [e2-core] set sequence pos 3
18:32:31.364 [e2-core] [SEC] setTone 1
18:32:31.377 [e2-core] [SEC] sleep 10ms
18:32:31.387 [e2-core] [SEC] update current switch params
18:32:31.387 [e2-core] [SEC] startTuneTimeout 5000
18:32:31.387 [e2-core] [SEC] setFrontend 1
18:32:31.387 [e2-core] setting frontend 0
18:32:31.387 [e2-core] [SEC] sleep 500ms
18:32:31.479 [e2-core] (0)fe event: status 0, inversion off, m_tuning 1
18:32:31.555 [e2-core] (0)fe event: status 1f, inversion off, m_tuning 2
18:32:31.555 [e2-core] OURSTATE: ok
18:32:31.555 [e2-core] [eDVBCAService] channel 0x23206e0 running
18:32:31.555 [e2-core] [eDVBLocalTimerHandler] channel 0x23206e0 running
18:32:31.555 [e2-core] no version filtering
18:32:31.555 [e2-core] 0014: 70 00 00 00 00 00
18:32:31.555 [e2-core] mask: fc 00 00 00 00 00
18:32:31.555 [e2-core] mode: 00 00 00 00 00 00
18:32:31.555 [e2-core] [eEPGCache] channel 0x23206e0 running
18:32:31.556 [e2-core] [eEPGCache] next update in 2 sec
18:32:31.556 [e2-core] stop release channel timer
18:32:31.556 [e2-core] no version filtering
18:32:31.556 [e2-core] 0012: 4e 00 7f 00 00 00
18:32:31.556 [e2-core] mask: ff ff ff 00 00 00
18:32:31.556 [e2-core] mode: 00 00 00 00 00 00
18:32:31.557 [e2-core] ok ... now we start!!
18:32:31.557 [e2-core] no version filtering
18:32:31.557 [e2-core] 0000: 00 00 00 00 00 00
18:32:31.557 [e2-core] mask: ff 00 00 00 00 00
18:32:31.557 [e2-core] mode: 00 00 00 00 00 00
18:32:31.557 [e2-core] eventNewProgramInfo 0 0
18:32:31.558 [e2-core] have 1 video stream(s) (02ff), and 1 audio stream(s) (0303), and the pcr pid is 02ff, and the text pid is ffffffff
18:32:31.558 [e2-core] allocate demux
18:32:31.560 [e2-core] disable teletext subtitles
18:32:31.568 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:31.568 [e2-core] decoder state: play, vpid=767, apid=771
18:32:31.569 [e2-core] DMX_SET_PES_FILTER(0x2ff) - pcr - ok
18:32:31.569 [e2-core] DEMUX_START - pcr - ok
18:32:31.569 [e2-core] DMX_SET_PES_FILTER(0x303) - audio - ok
18:32:31.569 [e2-core] DEMUX_START - audio - ok
18:32:31.569 [e2-core] AUDIO_SET_BYPASS(0) - ok
18:32:31.569 [e2-core] AUDIO_PAUSE - ok
18:32:31.571 [e2-core] AUDIO_PLAY - ok
18:32:31.571 [e2-core] Video Device: /dev/dvb/adapter0/video0
18:32:31.571 [e2-core] demux device: /dev/dvb/adapter0/demux0
18:32:31.571 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
18:32:31.571 [e2-core] DMX_SET_PES_FILTER(0x2ff) - video - ok
18:32:31.571 [e2-core] DEMUX_START - video - ok
18:32:31.571 [e2-core] VIDEO_FREEZE - ok
18:32:31.576 [e2-core] VIDEO_PLAY - ok
18:32:31.577 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:31.577 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:31.577 [e2-core] VIDEO_CONTINUE - ok
18:32:31.577 [e2-core] AUDIO_CONTINUE - ok
18:32:31.578 [e2-python] [#----] VTiCore not pauseable.
18:32:31.580 [e2-python] [AutoRes] service changed
18:32:31.584 [e2-core] + 1/1 TID 00
18:32:31.585 [e2-core] done!
18:32:31.586 [e2-core] PATready
18:32:31.587 [e2-core] use pmtpid 0062 for service_id 007f
18:32:31.587 [e2-core] no version filtering
18:32:31.587 [e2-core] 0062: 02 00 7f 00 00 00
18:32:31.587 [e2-core] mask: ff ff ff 00 00 00
18:32:31.587 [e2-core] mode: 00 00 00 00 00 00
18:32:31.588 [e2-core] doing version filtering
18:32:31.588 [e2-core] 0000: 00 00 00 3d 00 00
18:32:31.588 [e2-core] mask: ff 00 00 3f 00 00
18:32:31.588 [e2-core] mode: 00 00 00 3e 00 00
18:32:31.601 [e2-core] [eDVBLocalTimerHandler] diff is 0
18:32:31.601 [e2-core] [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
18:32:31.601 [e2-core] [eDVBLocalTimerHandler] not changed
18:32:31.694 [e2-core] + 1/1 TID 02
18:32:31.694 [e2-core] done!
18:32:31.694 [e2-core] eventNewProgramInfo 0 0
18:32:31.695 [e2-core] [autoAudioTrack] use audio track: 0
18:32:31.695 [e2-core] have 1 video stream(s) (02ff), and 2 audio stream(s) (0303, 0304), and the pcr pid is 02ff, and the text pid is ffffffff
18:32:31.703 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:31.703 [e2-core] decoder state: play, vpid=767, apid=771
18:32:31.703 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:31.703 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:31.703 [e2-core] VIDEO_CONTINUE - ok
18:32:31.703 [e2-core] AUDIO_CONTINUE - ok
18:32:31.704 [e2-core] [autoAudioTrack] use audio track: 0
18:32:31.708 [e2-core] [eDVBCAService] new service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:31.708 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:31.708 [e2-core] demux 0 mask 01 prevhash 00000000
18:32:31.708 [e2-core] [eDVBCAService] send 62 bytes
18:32:31.708 [e2-core] 9f 80 32 3a 03 00 7f 33 00 25 01 81 08 00 c0 00 00 00 0d 00 85 82 02 01 00 84 02 00 62 09 04 09 8c fa 54 09 04 09 c4 fb 54 09 04 09 8d fc 54 1b 02 ff 00 00 06 03 03 00 00 06 03 04 00 00
18:32:31.708 [e2-core] doing version filtering
18:32:31.708 [e2-core] 0062: 02 00 7f 33 00 00
18:32:31.708 [e2-core] mask: ff ff ff 3f 00 00
18:32:31.708 [e2-core] mode: 00 00 00 3e 00 00
18:32:31.888 [e2-core] [SEC] set dynamic current limiting
18:32:31.956 [e2-core] sdt update done!
18:32:31.957 [e2-core] -+ 1/2 TID 4e
18:32:32.354 [e2-python] action -> InfobarChannelSelection showFavourites
18:32:32.355 [e2-python] playing service..
18:32:32.356 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:32.356 [e2-core] [eDVBCAService] free service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:32.361 [e2-core] decoder state: play, vpid=-1, apid=-1
18:32:32.361 [e2-core] DEMUX_STOP - pcr - ok
18:32:32.362 [e2-core] DEMUX_STOP - video - ok
18:32:32.362 [e2-core] VIDEO_STOP - ok
18:32:32.386 [e2-core] AUDIO_STOP - ok
18:32:32.387 [e2-core] DEMUX_STOP - audio - ok
18:32:32.387 [e2-core] AUDIO_CONTINUE - ok
18:32:32.389 [e2-core] start release channel timer
18:32:32.397 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:32.399 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124509603.jpg
18:32:32.399 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:32.401 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:32.402 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124509671.jpg
18:32:32.402 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:32.404 [e2-python] [#----] VTiCore not pauseable.
18:32:32.404 [e2-python] RemovePopup, id =ZapError
18:32:32.404 [e2-core] use cached_channel
18:32:32.404 [e2-core] stop release channel timer
18:32:32.404 [e2-core] allocate Channel: res 0
18:32:32.405 [e2-core] no version filtering
18:32:32.405 [e2-core] 0012: 4e 00 6e 00 00 00
18:32:32.405 [e2-core] mask: ff ff ff 00 00 00
18:32:32.405 [e2-core] mode: 00 00 00 00 00 00
18:32:32.405 [e2-core] ok ... now we start!!
18:32:32.405 [e2-core] no version filtering
18:32:32.406 [e2-core] 0000: 00 00 00 00 00 00
18:32:32.406 [e2-core] mask: ff 00 00 00 00 00
18:32:32.406 [e2-core] mode: 00 00 00 00 00 00
18:32:32.406 [e2-core] eventNewProgramInfo 0 0
18:32:32.406 [e2-core] have 1 video stream(s) (04ff), and 1 audio stream(s) (0503), and the pcr pid is 04ff, and the text pid is ffffffff
18:32:32.408 [e2-core] disable teletext subtitles
18:32:32.416 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:32.416 [e2-core] decoder state: play, vpid=1279, apid=1283
18:32:32.416 [e2-core] DMX_SET_PES_FILTER(0x4ff) - pcr - ok
18:32:32.417 [e2-core] DEMUX_START - pcr - ok
18:32:32.417 [e2-core] DMX_SET_PES_FILTER(0x503) - audio - ok
18:32:32.417 [e2-core] DEMUX_START - audio - ok
18:32:32.417 [e2-core] AUDIO_SET_BYPASS(0) - ok
18:32:32.417 [e2-core] AUDIO_PAUSE - ok
18:32:32.419 [e2-core] AUDIO_PLAY - ok
18:32:32.419 [e2-core] Video Device: /dev/dvb/adapter0/video0
18:32:32.419 [e2-core] demux device: /dev/dvb/adapter0/demux0
18:32:32.419 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
18:32:32.419 [e2-core] DMX_SET_PES_FILTER(0x4ff) - video - ok
18:32:32.419 [e2-core] DEMUX_START - video - ok
18:32:32.419 [e2-core] VIDEO_FREEZE - ok
18:32:32.424 [e2-core] VIDEO_PLAY - ok
18:32:32.425 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:32.425 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:32.425 [e2-core] VIDEO_CONTINUE - ok
18:32:32.425 [e2-core] AUDIO_CONTINUE - ok
18:32:32.426 [e2-python] [#----] VTiCore not pauseable.
18:32:32.427 [e2-python] [AutoRes] service changed
18:32:32.430 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:32.430 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:32.430 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:6E:D:85:C00000:0:0:0:, Sky Atlantic HD
18:32:32.436 [e2-core] +- 1/2 TID 4e
18:32:32.467 [e2-core] + 1/1 TID 00
18:32:32.468 [e2-core] done!
18:32:32.468 [e2-core] PATready
18:32:32.468 [e2-core] use pmtpid 0064 for service_id 006e
18:32:32.468 [e2-core] no version filtering
18:32:32.468 [e2-core] 0064: 02 00 6e 00 00 00
18:32:32.468 [e2-core] mask: ff ff ff 00 00 00
18:32:32.468 [e2-core] mode: 00 00 00 00 00 00
18:32:32.469 [e2-core] doing version filtering
18:32:32.469 [e2-core] 0000: 00 00 00 3d 00 00
18:32:32.469 [e2-core] mask: ff 00 00 3f 00 00
18:32:32.469 [e2-core] mode: 00 00 00 3e 00 00
18:32:32.563 [e2-core] + 1/1 TID 02
18:32:32.563 [e2-core] done!
18:32:32.563 [e2-core] eventNewProgramInfo 0 0
18:32:32.564 [e2-core] [autoAudioTrack] use audio track: 0
18:32:32.564 [e2-core] have 1 video stream(s) (04ff), and 2 audio stream(s) (0503, 0504), and the pcr pid is 04ff, and the text pid is ffffffff
18:32:32.571 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:32.571 [e2-core] decoder state: play, vpid=1279, apid=1283
18:32:32.571 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:32.571 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:32.571 [e2-core] VIDEO_CONTINUE - ok
18:32:32.572 [e2-core] AUDIO_CONTINUE - ok
18:32:32.573 [e2-core] [autoAudioTrack] use audio track: 0
18:32:32.576 [e2-core] [eDVBCAService] new service 1:0:19:6E:D:85:C00000:0:0:0:
18:32:32.576 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:6E:D:85:C00000:0:0:0:
18:32:32.576 [e2-core] demux 0 mask 01 prevhash 00000000
18:32:32.576 [e2-core] [eDVBCAService] send 62 bytes
18:32:32.576 [e2-core] 9f 80 32 3a 03 00 6e 0f 00 25 01 81 08 00 c0 00 00 00 0d 00 85 82 02 01 00 84 02 00 64 09 04 09 8c fa 0e 09 04 09 c4 fb 0e 09 04 09 8d fc 0e 1b 04 ff 00 00 06 05 03 00 00 06 05 04 00 00
18:32:32.577 [e2-core] doing version filtering
18:32:32.577 [e2-core] 0064: 02 00 6e 0f 00 00
18:32:32.577 [e2-core] mask: ff ff ff 3f 00 00
18:32:32.577 [e2-core] mode: 00 00 00 3e 00 00
18:32:33.339 [e2-core] ++ 2/2 TID 4e
18:32:33.339 [e2-core] done!
18:32:33.343 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:33.344 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124509603.jpg
18:32:33.345 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:33.346 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:33.347 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124509671.jpg
18:32:33.347 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:33.348 [e2-core] doing version filtering
18:32:33.348 [e2-core] 0012: 4e 00 6e 39 00 00
18:32:33.348 [e2-core] mask: ff ff ff 3f 00 00
18:32:33.348 [e2-core] mode: 00 00 00 3e 00 00
18:32:33.428 [e2-python] [AutoRes] determineContent
18:32:33.428 [e2-python] [AutoRes] switch deinterlacer mode to auto
18:32:33.429 [e2-python] [AutoRes] new content is 1920x1080i25
18:32:33.429 [e2-python] [AutoRes] determined videomode1080p
18:32:33.564 [e2-core] [eEPGCache] start caching events(1578418353)
18:32:36.049 [e2-python] action -> InfobarChannelSelection showFavourites
18:32:36.050 [e2-python] playing service..
18:32:36.050 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:6E:D:85:C00000:0:0:0:
18:32:36.050 [e2-core] [eDVBCAService] free service 1:0:19:6E:D:85:C00000:0:0:0:
18:32:36.055 [e2-core] decoder state: play, vpid=-1, apid=-1
18:32:36.055 [e2-core] DEMUX_STOP - pcr - ok
18:32:36.056 [e2-core] DEMUX_STOP - video - ok
18:32:36.056 [e2-core] VIDEO_STOP - ok
18:32:36.081 [e2-core] AUDIO_STOP - ok
18:32:36.081 [e2-core] DEMUX_STOP - audio - ok
18:32:36.081 [e2-core] AUDIO_CONTINUE - ok
18:32:36.083 [e2-core] start release channel timer
18:32:36.093 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:36.094 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124569357.jpg
18:32:36.094 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:36.096 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:36.097 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124569375.jpg
18:32:36.097 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:36.099 [e2-python] [#----] VTiCore not pauseable.
18:32:36.100 [e2-python] RemovePopup, id =ZapError
18:32:36.100 [e2-core] [eDVBCAService] remove channel 0x23206e0
18:32:36.100 [e2-core] [eDVBLocalTimerHandler] remove channel 0x23206e0
18:32:36.100 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:36.100 [e2-core] [eEPGCache] lock already acquired --> retry
18:32:36.101 [e2-core] [eEPGCache] abort caching events !!
18:32:36.300 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:36.301 [e2-core] allocate channel.. 03f9:0001
18:32:36.301 [e2-core] Slot 0, score 10002
18:32:36.301 [e2-core] [eDVBCAService] new channel 0x23206e0!
18:32:36.301 [e2-core] (0)tune
18:32:36.301 [e2-core] RotorCmd ffffffff, lastRotorCmd ffffffff
18:32:36.302 [e2-core] prepare_sat Freq 11464250 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
18:32:36.302 [e2-core] tuning to 1714 mhz
18:32:36.302 [e2-core] OURSTATE: tuning
18:32:36.302 [e2-core] allocate Channel: res 0
18:32:36.302 [e2-core] allocate demux
18:32:36.302 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:36.302 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:36.302 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:EF77:3F9:1:C00000:0:0:0:, SIXX HD
18:32:36.314 [e2-core] [SEC] set static current limiting
18:32:36.314 [e2-core] [SEC] setTone 0
18:32:36.345 [e2-core] [SEC] sleep 25ms
18:32:36.371 [e2-core] set sequence pos 7
18:32:36.371 [e2-core] [SEC] invalidate current switch params
18:32:36.463 [e2-core] [SEC] sendDiseqc: e01038f2
18:32:36.463 [e2-core] [SEC] sleep 50ms
18:32:36.513 [e2-core] set sequence pos 3
18:32:36.513 [e2-core] set sequence pos 3
18:32:36.513 [e2-core] [SEC] update current switch params
18:32:36.513 [e2-core] [SEC] startTuneTimeout 5000
18:32:36.513 [e2-core] [SEC] setFrontend 1
18:32:36.513 [e2-core] setting frontend 0
18:32:36.513 [e2-core] [SEC] sleep 500ms
18:32:36.605 [e2-core] (0)fe event: status 0, inversion off, m_tuning 1
18:32:36.732 [e2-core] (0)fe event: status 1f, inversion off, m_tuning 2
18:32:36.733 [e2-core] OURSTATE: ok
18:32:36.733 [e2-core] [eDVBCAService] channel 0x23206e0 running
18:32:36.733 [e2-core] [eDVBLocalTimerHandler] channel 0x23206e0 running
18:32:36.733 [e2-core] no version filtering
18:32:36.733 [e2-core] 0014: 70 00 00 00 00 00
18:32:36.733 [e2-core] mask: fc 00 00 00 00 00
18:32:36.733 [e2-core] mode: 00 00 00 00 00 00
18:32:36.733 [e2-core] [eEPGCache] channel 0x23206e0 running
18:32:36.734 [e2-core] stop release channel timer
18:32:36.734 [e2-core] no version filtering
18:32:36.734 [e2-core] 0012: 4e ef 77 00 00 00
18:32:36.734 [e2-core] mask: ff ff ff 00 00 00
18:32:36.734 [e2-core] mode: 00 00 00 00 00 00
18:32:36.735 [e2-core] [eEPGCache] next update in 20 min
18:32:36.735 [e2-core] ok ... now we start!!
18:32:36.735 [e2-core] no version filtering
18:32:36.735 [e2-core] 0000: 00 00 00 00 00 00
18:32:36.735 [e2-core] mask: ff 00 00 00 00 00
18:32:36.735 [e2-core] mode: 00 00 00 00 00 00
18:32:36.735 [e2-core] eventNewProgramInfo 0 0
18:32:36.735 [e2-core] have 1 video stream(s) (03ff), and 1 audio stream(s) (0403), and the pcr pid is 03ff, and the text pid is 0023
18:32:36.735 [e2-core] allocate demux
18:32:36.738 [e2-core] disable teletext subtitles
18:32:36.739 [e2-core] decoder state: play, vpid=1023, apid=1027
18:32:36.739 [e2-core] DMX_SET_PES_FILTER(0x3ff) - pcr - ok
18:32:36.739 [e2-core] DEMUX_START - pcr - ok
18:32:36.739 [e2-core] DMX_SET_PES_FILTER(0x403) - audio - ok
18:32:36.740 [e2-core] DEMUX_START - audio - ok
18:32:36.740 [e2-core] AUDIO_SET_BYPASS(0) - ok
18:32:36.740 [e2-core] AUDIO_PAUSE - ok
18:32:36.741 [e2-core] AUDIO_PLAY - ok
18:32:36.742 [e2-core] Video Device: /dev/dvb/adapter0/video0
18:32:36.742 [e2-core] demux device: /dev/dvb/adapter0/demux0
18:32:36.742 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
18:32:36.742 [e2-core] DMX_SET_PES_FILTER(0x3ff) - video - ok
18:32:36.742 [e2-core] DEMUX_START - video - ok
18:32:36.742 [e2-core] VIDEO_FREEZE - ok
18:32:36.747 [e2-core] VIDEO_PLAY - ok
18:32:36.747 [e2-core] DMX_SET_PES_FILTER(0x23) - ttx - ok
18:32:36.747 [e2-core] DEMUX_START - ttx - ok
18:32:36.750 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:36.750 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:36.750 [e2-core] VIDEO_CONTINUE - ok
18:32:36.750 [e2-core] AUDIO_CONTINUE - ok
18:32:36.751 [e2-python] [#----] VTiCore not pauseable.
18:32:36.753 [e2-python] [AutoRes] service changed
18:32:36.767 [e2-core] + 1/1 TID 00
18:32:36.767 [e2-core] done!
18:32:36.767 [e2-core] PATready
18:32:36.767 [e2-core] use pmtpid 0063 for service_id ef77
18:32:36.768 [e2-core] no version filtering
18:32:36.768 [e2-core] 0063: 02 ef 77 00 00 00
18:32:36.768 [e2-core] mask: ff ff ff 00 00 00
18:32:36.768 [e2-core] mode: 00 00 00 00 00 00
18:32:36.768 [e2-core] doing version filtering
18:32:36.768 [e2-core] 0000: 00 00 00 35 00 00
18:32:36.768 [e2-core] mask: ff 00 00 3f 00 00
18:32:36.768 [e2-core] mode: 00 00 00 3e 00 00
18:32:36.968 [e2-core] -+ 1/2 TID 4e
18:32:37.013 [e2-core] [SEC] set dynamic current limiting
18:32:37.225 [e2-core] + 1/1 TID 02
18:32:37.225 [e2-core] done!
18:32:37.225 [e2-core] eventNewProgramInfo 0 0
18:32:37.226 [e2-core] no version filtering
18:32:37.226 [e2-core] 0405: 74 00 00 00 00 00
18:32:37.226 [e2-core] mask: ff 00 00 00 00 00
18:32:37.226 [e2-core] mode: 00 00 00 00 00 00
18:32:37.227 [e2-core] [autoAudioTrack] use audio track: 0
18:32:37.227 [e2-core] have 1 video stream(s) (03ff), and 1 audio stream(s) (0403), and the pcr pid is 03ff, and the text pid is 0023
18:32:37.227 [e2-core] decoder state: play, vpid=1023, apid=1027
18:32:37.228 [e2-core] no version filtering
18:32:37.229 [e2-core] 0405: 74 00 00 00 00 00
18:32:37.229 [e2-core] mask: ff 00 00 00 00 00
18:32:37.229 [e2-core] mode: 00 00 00 00 00 00
18:32:37.229 [e2-core] [autoAudioTrack] use audio track: 0
18:32:37.232 [e2-core] [eDVBCAService] new service 1:0:19:EF77:3F9:1:C00000:0:0:0:
18:32:37.233 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:EF77:3F9:1:C00000:0:0:0:
18:32:37.233 [e2-core] demux 0 mask 01 prevhash 00000000
18:32:37.234 [e2-core] [eDVBCAService] send 491 bytes
18:32:37.235 [e2-core] 9f 80 32 82 01 e5 03 ef 77 0b 01 cb 01 81 08 00 c0 00 00 03 f9 00 01 82 02 01 00 84 02 00 63 09 58 18 30 fc a4 8c 52 00 00 3d a3 0d 01 80 11 80 00 02 18 30 01 0f 9f 20 ff aa 15 02 80 11 00 00 00 64 00 03 03 21 00 00 00 02 18 30 00 00 00 64 aa 15 02 80 11 00 00 0b ea 00 03 03 21 00 00 00 02 18 30 00 00 0b ea 01 00 0f a3 0d 01 80 11 80 00 02 18 30 01 0d ab 20 ff 09 64 18 43 f9 a4 8c 5e 00 00 43 8c 13 00 80 11 08 02 18 43 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 66 00 03 03 21 00 00 02 18 43 5d 00 00 66 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 43 5d 00 0b ea 00 01 00 15 8c 13 00 80 11 08 02 18 43 20 0a 09 38 59 00 0d 7a 59 00 0d 7a 09 04 09 c4 fb a4 09 04 09 8c fa a4 09 64 18 60 fe a4 8c 5e 00 00 43 8c 13 00 80 11 08 02 18 60 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 68 00 03 03 21 00 00 02 18 60 5d 00 00 68 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 60 5d 00 0b ea 00 01 00 15 8c 13 00 80 11 08 02 18 60 20 0a 09 38 59 00 0d 7a 59 00 0d 7a 09 64 18 6a fd a4 8c 5e 00 00 43 8c 13 00 80 11 08 02 18 6a 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 6a 00 03 03 21 00 00 02 18 6a 5d 00 00 6a 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 6a 5d 00 0b ea 00 01 00 15 8c 13 00 80 11 08 02 18 6a 20 0a 09 38 59 00 0d 7a 59 00 0d 7a 09 04 09 8d f8 a4 09 04 18 6d fd a5 09 04 06 cb fb a5 09 04 18 42 fc a5 09 06 4b 64 fc a5 18 42 06 00 23 00 00 1b 03 ff 00 00 06 04 03 00 00 05 04 05 00 00
18:32:37.235 [e2-core] doing version filtering
18:32:37.235 [e2-core] 0063: 02 ef 77 0b 00 00
18:32:37.235 [e2-core] mask: ff ff ff 3f 00 00
18:32:37.235 [e2-core] mode: 00 00 00 3e 00 00
18:32:37.754 [e2-python] [AutoRes] determineContent
18:32:37.754 [e2-python] [AutoRes] switch deinterlacer mode to auto
18:32:37.755 [e2-python] [AutoRes] new content is 1920x1080i25
18:32:37.755 [e2-python] [AutoRes] determined videomode1080p
18:32:37.942 [e2-python] action -> InfobarChannelSelection openServiceList
18:32:37.943 [e2-python] playing service..
18:32:37.943 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:EF77:3F9:1:C00000:0:0:0:
18:32:37.943 [e2-core] [eDVBCAService] free service 1:0:19:EF77:3F9:1:C00000:0:0:0:
18:32:37.949 [e2-core] decoder state: play, vpid=-1, apid=-1
18:32:37.949 [e2-core] DEMUX_STOP - pcr - ok
18:32:37.950 [e2-core] DEMUX_STOP - video - ok
18:32:37.950 [e2-core] VIDEO_STOP - ok
18:32:37.975 [e2-core] AUDIO_STOP - ok
18:32:37.975 [e2-core] DEMUX_STOP - audio - ok
18:32:37.975 [e2-core] AUDIO_CONTINUE - ok
18:32:37.975 [e2-core] DEMUX_STOP - ttx - ok
18:32:37.978 [e2-core] start release channel timer
18:32:37.987 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:37.988 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124509603.jpg
18:32:37.988 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:37.990 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:37.991 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124509671.jpg
18:32:37.991 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:37.993 [e2-python] [#----] VTiCore not pauseable.
18:32:37.993 [e2-python] RemovePopup, id =ZapError
18:32:37.993 [e2-core] [eDVBCAService] remove channel 0x23206e0
18:32:37.993 [e2-core] [eDVBLocalTimerHandler] remove channel 0x23206e0
18:32:37.994 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:37.994 [e2-core] [eEPGCache] lock already acquired --> retry
18:32:38.194 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:38.194 [e2-core] allocate channel.. 000d:0085
18:32:38.195 [e2-core] Slot 0, score 10002
18:32:38.195 [e2-core] [eDVBCAService] new channel 0x23206e0!
18:32:38.195 [e2-core] (0)tune
18:32:38.195 [e2-core] RotorCmd ffffffff, lastRotorCmd ffffffff
18:32:38.195 [e2-core] prepare_sat Freq 11992500 Pol 0 SR 27500000 INV 2 FEC 9 orbpos 192 system 1 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 3, pls_code 0
18:32:38.195 [e2-core] tuning to 1392 mhz
18:32:38.195 [e2-core] OURSTATE: tuning
18:32:38.195 [e2-core] allocate Channel: res 0
18:32:38.195 [e2-core] allocate demux
18:32:38.195 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:38.195 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:38.196 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:6E:D:85:C00000:0:0:0:, Sky Atlantic HD
18:32:38.210 [e2-core] [SEC] set static current limiting
18:32:38.210 [e2-core] set sequence pos 3
18:32:38.211 [e2-core] set sequence pos 7
18:32:38.211 [e2-core] [SEC] invalidate current switch params
18:32:38.305 [e2-core] [SEC] sendDiseqc: e01038f3
18:32:38.306 [e2-core] [SEC] sleep 50ms
18:32:38.357 [e2-core] set sequence pos 3
18:32:38.357 [e2-core] [SEC] setTone 1
18:32:38.369 [e2-core] [SEC] sleep 10ms
18:32:38.387 [e2-core] [SEC] update current switch params
18:32:38.387 [e2-core] [SEC] startTuneTimeout 5000
18:32:38.387 [e2-core] [SEC] setFrontend 1
18:32:38.388 [e2-core] setting frontend 0
18:32:38.388 [e2-core] [SEC] sleep 500ms
18:32:38.480 [e2-core] (0)fe event: status 0, inversion off, m_tuning 1
18:32:38.480 [e2-python] action -> InfobarChannelSelection openServiceList
18:32:38.481 [e2-python] playing service..
18:32:38.484 [e2-core] start release channel timer
18:32:38.493 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:38.494 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124556932.jpg
18:32:38.494 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:38.496 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:38.497 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124556878.jpg
18:32:38.497 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:38.499 [e2-python] [#----] VTiCore not pauseable.
18:32:38.499 [e2-python] RemovePopup, id =ZapError
18:32:38.499 [e2-core] use cached_channel
18:32:38.499 [e2-core] [eDVBCAService] channel 0x23206e0 running
18:32:38.499 [e2-core] [eDVBLocalTimerHandler] channel 0x23206e0 running
18:32:38.500 [e2-core] no version filtering
18:32:38.500 [e2-core] 0014: 70 00 00 00 00 00
18:32:38.500 [e2-core] mask: fc 00 00 00 00 00
18:32:38.500 [e2-core] mode: 00 00 00 00 00 00
18:32:38.500 [e2-core] [eEPGCache] channel 0x23206e0 running
18:32:38.500 [e2-core] stop release channel timer
18:32:38.500 [e2-core] allocate Channel: res 0
18:32:38.500 [e2-core] [eEPGCache] next update in 2 sec
18:32:38.501 [e2-core] no version filtering
18:32:38.501 [e2-core] 0012: 4e 00 7f 00 00 00
18:32:38.501 [e2-core] mask: ff ff ff 00 00 00
18:32:38.501 [e2-core] mode: 00 00 00 00 00 00
18:32:38.501 [e2-core] ok ... now we start!!
18:32:38.502 [e2-core] no version filtering
18:32:38.502 [e2-core] 0000: 00 00 00 00 00 00
18:32:38.502 [e2-core] mask: ff 00 00 00 00 00
18:32:38.502 [e2-core] mode: 00 00 00 00 00 00
18:32:38.502 [e2-core] eventNewProgramInfo 0 0
18:32:38.502 [e2-core] have 1 video stream(s) (02ff), and 1 audio stream(s) (0303), and the pcr pid is 02ff, and the text pid is ffffffff
18:32:38.502 [e2-core] allocate demux
18:32:38.505 [e2-core] disable teletext subtitles
18:32:38.513 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:38.513 [e2-core] decoder state: play, vpid=767, apid=771
18:32:38.513 [e2-core] DMX_SET_PES_FILTER(0x2ff) - pcr - ok
18:32:38.513 [e2-core] DEMUX_START - pcr - ok
18:32:38.513 [e2-core] DMX_SET_PES_FILTER(0x303) - audio - ok
18:32:38.513 [e2-core] DEMUX_START - audio - ok
18:32:38.513 [e2-core] AUDIO_SET_BYPASS(0) - ok
18:32:38.513 [e2-core] AUDIO_PAUSE - ok
18:32:38.515 [e2-core] AUDIO_PLAY - ok
18:32:38.515 [e2-core] Video Device: /dev/dvb/adapter0/video0
18:32:38.515 [e2-core] demux device: /dev/dvb/adapter0/demux0
18:32:38.515 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
18:32:38.515 [e2-core] DMX_SET_PES_FILTER(0x2ff) - video - ok
18:32:38.515 [e2-core] DEMUX_START - video - ok
18:32:38.515 [e2-core] VIDEO_FREEZE - ok
18:32:38.521 [e2-core] VIDEO_PLAY - ok
18:32:38.521 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:38.521 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:38.521 [e2-core] VIDEO_CONTINUE - ok
18:32:38.521 [e2-core] AUDIO_CONTINUE - ok
18:32:38.522 [e2-python] [#----] VTiCore not pauseable.
18:32:38.524 [e2-python] [AutoRes] service changed
18:32:38.527 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:38.527 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:38.527 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:7F:D:85:C00000:0:0:0:, 13th Street HD
18:32:38.559 [e2-core] (0)fe event: status 1f, inversion off, m_tuning 2
18:32:38.559 [e2-core] OURSTATE: ok
18:32:38.573 [e2-core] + 1/1 TID 00
18:32:38.573 [e2-core] done!
18:32:38.573 [e2-core] PATready
18:32:38.573 [e2-core] use pmtpid 0062 for service_id 007f
18:32:38.573 [e2-core] no version filtering
18:32:38.573 [e2-core] 0062: 02 00 7f 00 00 00
18:32:38.573 [e2-core] mask: ff ff ff 00 00 00
18:32:38.573 [e2-core] mode: 00 00 00 00 00 00
18:32:38.574 [e2-core] doing version filtering
18:32:38.574 [e2-core] 0000: 00 00 00 3d 00 00
18:32:38.574 [e2-core] mask: ff 00 00 3f 00 00
18:32:38.574 [e2-core] mode: 00 00 00 3e 00 00
18:32:38.576 [e2-core] + 1/1 TID 02
18:32:38.576 [e2-core] done!
18:32:38.576 [e2-core] eventNewProgramInfo 0 0
18:32:38.577 [e2-core] [autoAudioTrack] use audio track: 0
18:32:38.577 [e2-core] have 1 video stream(s) (02ff), and 2 audio stream(s) (0303, 0304), and the pcr pid is 02ff, and the text pid is ffffffff
18:32:38.584 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:38.584 [e2-core] decoder state: play, vpid=767, apid=771
18:32:38.585 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:38.585 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:38.585 [e2-core] VIDEO_CONTINUE - ok
18:32:38.585 [e2-core] AUDIO_CONTINUE - ok
18:32:38.586 [e2-core] [autoAudioTrack] use audio track: 0
18:32:38.589 [e2-core] [eDVBCAService] new service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:38.590 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:38.590 [e2-core] demux 0 mask 01 prevhash 00000000
18:32:38.591 [e2-core] [eDVBCAService] send 62 bytes
18:32:38.591 [e2-core] 9f 80 32 3a 03 00 7f 33 00 25 01 81 08 00 c0 00 00 00 0d 00 85 82 02 01 00 84 02 00 62 09 04 09 8c fa 54 09 04 09 c4 fb 54 09 04 09 8d fc 54 1b 02 ff 00 00 06 03 03 00 00 06 03 04 00 00
18:32:38.591 [e2-core] doing version filtering
18:32:38.591 [e2-core] 0062: 02 00 7f 33 00 00
18:32:38.591 [e2-core] mask: ff ff ff 3f 00 00
18:32:38.591 [e2-core] mode: 00 00 00 3e 00 00
18:32:38.596 [e2-core] +- 1/2 TID 4e
18:32:38.888 [e2-core] [SEC] set dynamic current limiting
18:32:38.933 [e2-python] action -> InfobarChannelSelection openServiceList
18:32:38.934 [e2-python] playing service..
18:32:38.935 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:38.935 [e2-core] [eDVBCAService] free service 1:0:19:7F:D:85:C00000:0:0:0:
18:32:38.940 [e2-core] decoder state: play, vpid=-1, apid=-1
18:32:38.940 [e2-core] DEMUX_STOP - pcr - ok
18:32:38.941 [e2-core] DEMUX_STOP - video - ok
18:32:38.941 [e2-core] VIDEO_STOP - ok
18:32:38.965 [e2-core] AUDIO_STOP - ok
18:32:38.966 [e2-core] DEMUX_STOP - audio - ok
18:32:38.966 [e2-core] AUDIO_CONTINUE - ok
18:32:38.968 [e2-core] start release channel timer
18:32:38.976 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:38.977 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124653255.jpg
18:32:38.977 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:38.980 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:38.981 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/125278391.jpg
18:32:38.981 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:38.982 [e2-python] [#----] VTiCore not pauseable.
18:32:38.983 [e2-python] RemovePopup, id =ZapError
18:32:38.983 [e2-core] [eDVBCAService] remove channel 0x23206e0
18:32:38.983 [e2-core] [eDVBLocalTimerHandler] remove channel 0x23206e0
18:32:38.984 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:38.984 [e2-core] allocate channel.. 0411:0001
18:32:38.984 [e2-core] Slot 0, score 10002
18:32:38.984 [e2-core] [eDVBCAService] new channel 0x23206e0!
18:32:38.984 [e2-core] (0)tune
18:32:38.984 [e2-core] RotorCmd ffffffff, lastRotorCmd ffffffff
18:32:38.984 [e2-core] prepare_sat Freq 11082250 Pol 0 SR 22000000 INV 2 FEC 3 orbpos 192 system 1 modulation 2 pilot 2, rolloff 2, is_id -1, pls_mode 3, pls_code 0
18:32:38.984 [e2-core] tuning to 1332 mhz
18:32:38.985 [e2-core] OURSTATE: tuning
18:32:38.985 [e2-core] allocate Channel: res 0
18:32:38.985 [e2-core] allocate demux
18:32:38.985 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:38.985 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:38.985 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:2E9B:411:1:C00000:0:0:0:, SUPER RTL HD
18:32:38.990 [e2-core] [SEC] set static current limiting
18:32:38.993 [e2-core] [SEC] setTone 0
18:32:39.014 [e2-python] next real activation isTue Jan 7 18:59:40 2020
18:32:39.014 [e2-core] [SEC] sleep 25ms
18:32:39.039 [e2-core] set sequence pos 7
18:32:39.039 [e2-core] [SEC] invalidate current switch params
18:32:39.132 [e2-core] [SEC] sendDiseqc: e01038f2
18:32:39.132 [e2-core] [SEC] sleep 50ms
18:32:39.182 [e2-core] set sequence pos 3
18:32:39.182 [e2-core] set sequence pos 3
18:32:39.182 [e2-core] [SEC] update current switch params
18:32:39.182 [e2-core] [SEC] startTuneTimeout 5000
18:32:39.182 [e2-core] [SEC] setFrontend 1
18:32:39.182 [e2-core] setting frontend 0
18:32:39.182 [e2-core] [SEC] sleep 500ms
18:32:39.274 [e2-core] (0)fe event: status 0, inversion off, m_tuning 1
18:32:39.429 [e2-core] (0)fe event: status 1f, inversion off, m_tuning 2
18:32:39.429 [e2-core] OURSTATE: ok
18:32:39.429 [e2-core] [eDVBCAService] channel 0x23206e0 running
18:32:39.429 [e2-core] [eDVBLocalTimerHandler] channel 0x23206e0 running
18:32:39.430 [e2-core] no version filtering
18:32:39.430 [e2-core] 0014: 70 00 00 00 00 00
18:32:39.430 [e2-core] mask: fc 00 00 00 00 00
18:32:39.430 [e2-core] mode: 00 00 00 00 00 00
18:32:39.430 [e2-core] [eEPGCache] channel 0x23206e0 running
18:32:39.431 [e2-core] stop release channel timer
18:32:39.431 [e2-core] [eEPGCache] next update in 22 min
18:32:39.431 [e2-core] no version filtering
18:32:39.431 [e2-core] 0012: 4e 2e 9b 00 00 00
18:32:39.431 [e2-core] mask: ff ff ff 00 00 00
18:32:39.431 [e2-core] mode: 00 00 00 00 00 00
18:32:39.432 [e2-core] ok ... now we start!!
18:32:39.432 [e2-core] no version filtering
18:32:39.432 [e2-core] 0000: 00 00 00 00 00 00
18:32:39.432 [e2-core] mask: ff 00 00 00 00 00
18:32:39.432 [e2-core] mode: 00 00 00 00 00 00
18:32:39.433 [e2-core] eventNewProgramInfo 0 0
18:32:39.433 [e2-core] have 1 video stream(s) (012c), and 1 audio stream(s) (0136), and the pcr pid is 012c, and the text pid is 0140
18:32:39.433 [e2-core] allocate demux
18:32:39.437 [e2-core] disable teletext subtitles
18:32:39.439 [e2-core] decoder state: play, vpid=300, apid=310
18:32:39.439 [e2-core] DMX_SET_PES_FILTER(0x12c) - pcr - ok
18:32:39.439 [e2-core] DEMUX_START - pcr - ok
18:32:39.439 [e2-core] DMX_SET_PES_FILTER(0x136) - audio - ok
18:32:39.440 [e2-core] DEMUX_START - audio - ok
18:32:39.440 [e2-core] AUDIO_SET_BYPASS(0) - ok
18:32:39.440 [e2-core] AUDIO_PAUSE - ok
18:32:39.441 [e2-core] AUDIO_PLAY - ok
18:32:39.441 [e2-core] Video Device: /dev/dvb/adapter0/video0
18:32:39.442 [e2-core] demux device: /dev/dvb/adapter0/demux0
18:32:39.442 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
18:32:39.442 [e2-core] DMX_SET_PES_FILTER(0x12c) - video - ok
18:32:39.442 [e2-core] DEMUX_START - video - ok
18:32:39.442 [e2-core] VIDEO_FREEZE - ok
18:32:39.447 [e2-core] VIDEO_PLAY - ok
18:32:39.447 [e2-core] DMX_SET_PES_FILTER(0x140) - ttx - ok
18:32:39.447 [e2-core] DEMUX_START - ttx - ok
18:32:39.450 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:39.450 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:39.450 [e2-core] VIDEO_CONTINUE - ok
18:32:39.450 [e2-core] AUDIO_CONTINUE - ok
18:32:39.451 [e2-python] [#----] VTiCore not pauseable.
18:32:39.453 [e2-python] [AutoRes] service changed
18:32:39.572 [e2-core] +- 1/2 TID 4e
18:32:39.623 [e2-core] + 1/1 TID 00
18:32:39.623 [e2-core] done!
18:32:39.623 [e2-core] PATready
18:32:39.623 [e2-core] use pmtpid 0035 for service_id 2e9b
18:32:39.624 [e2-core] no version filtering
18:32:39.624 [e2-core] 0035: 02 2e 9b 00 00 00
18:32:39.624 [e2-core] mask: ff ff ff 00 00 00
18:32:39.624 [e2-core] mode: 00 00 00 00 00 00
18:32:39.624 [e2-core] doing version filtering
18:32:39.624 [e2-core] 0000: 00 00 00 0f 00 00
18:32:39.624 [e2-core] mask: ff 00 00 3f 00 00
18:32:39.624 [e2-core] mode: 00 00 00 3e 00 00
18:32:39.682 [e2-core] [SEC] set dynamic current limiting
18:32:39.763 [e2-core] + 1/1 TID 02
18:32:39.763 [e2-core] done!
18:32:39.763 [e2-core] eventNewProgramInfo 0 0
18:32:39.764 [e2-core] no version filtering
18:32:39.764 [e2-core] 0141: 74 00 00 00 00 00
18:32:39.764 [e2-core] mask: ff 00 00 00 00 00
18:32:39.764 [e2-core] mode: 00 00 00 00 00 00
18:32:39.764 [e2-core] [autoAudioTrack] use audio track: 0
18:32:39.765 [e2-core] have 1 video stream(s) (012c), and 1 audio stream(s) (0136), and the pcr pid is 012c, and the text pid is 0140
18:32:39.765 [e2-core] decoder state: play, vpid=300, apid=310
18:32:39.766 [e2-core] no version filtering
18:32:39.766 [e2-core] 0141: 74 00 00 00 00 00
18:32:39.766 [e2-core] mask: ff 00 00 00 00 00
18:32:39.766 [e2-core] mode: 00 00 00 00 00 00
18:32:39.767 [e2-core] [autoAudioTrack] use audio track: 0
18:32:39.770 [e2-core] [eDVBCAService] new service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:39.770 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:39.770 [e2-core] demux 0 mask 01 prevhash 00000000
18:32:39.770 [e2-core] [eDVBCAService] send 598 bytes
18:32:39.772 [e2-core] 9f 80 32 82 02 50 03 2e 9b 05 02 27 01 81 08 00 c0 00 00 04 11 00 01 82 02 01 00 84 02 00 35 09 6f 18 30 e1 55 8c 69 00 00 3d a3 0d 01 80 11 80 00 02 18 30 01 0f 9f 20 ff aa 15 02 80 11 00 00 00 64 00 03 03 21 00 00 00 02 18 30 00 00 00 64 aa 15 02 80 11 00 00 0b ea 00 03 03 21 00 00 00 02 18 30 00 00 0b ea 01 00 26 a3 0d 01 80 11 80 00 02 18 30 01 0d ab 20 ff aa 15 02 80 11 00 00 0b b8 00 03 03 21 00 00 00 02 18 43 00 00 0b b8 09 7b 18 43 e1 56 8c 75 00 00 43 8c 13 00 80 11 08 02 18 43 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 66 00 03 03 21 00 00 02 18 43 5d 00 00 66 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 43 5d 00 0b ea 00 01 00 2c 8c 13 00 80 11 08 02 18 43 20 0a 09 38 59 00 0d 7a 59 00 0d 7a aa 15 03 80 11 5d 00 0b b8 00 03 03 21 00 00 02 18 43 5d 00 0b b8 00 09 7b 18 60 e1 59 8c 75 00 00 43 8c 13 00 80 11 08 02 18 60 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 68 00 03 03 21 00 00 02 18 60 5d 00 00 68 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 60 5d 00 0b ea 00 01 00 2c 8c 13 00 80 11 08 02 18 60 20 0a 09 38 59 00 0d 7a 59 00 0d 7a aa 15 03 80 11 5d 00 0b b8 00 03 03 21 00 00 02 18 60 5d 00 0b b8 00 09 04 09 c4 e1 57 09 04 09 8c e1 58 09 04 06 cb e1 5e 09 7b 18 6a e1 5a 8c 75 00 00 43 8c 13 00 80 11 08 02 18 6a 20 0a 09 38 59 00 0f 9f 59 00 0f 9f aa 15 03 80 11 5d 00 00 6a 00 03 03 21 00 00 02 18 6a 5d 00 00 6a 00 aa 15 03 80 11 00 00 0b ea 00 03 03 21 00 00 02 18 6a 5d 00 0b ea 00 01 00 2c 8c 13 00 80 11 08 02 18 6a 20 0a 09 38 59 00 0d 7a 59 00 0d 7a aa 15 03 80 11 5d 00 0b b8 00 03 03 21 00 00 02 18 6a 5d 00 0b b8 00 09 04 18 6d e1 5b 09 04 09 8d e1 5c 09 04 18 42 e1 5f 09 06 4b 64 e1 5f 18 42 1b 01 2c 00 00 06 01 36 00 00 05 01 41 00 00 06 01 40 00 00 06 01 4a 00 00 0b 01 37 00 00 0c 01 38 00 00
18:32:39.772 [e2-core] doing version filtering
18:32:39.772 [e2-core] 0035: 02 2e 9b 05 00 00
18:32:39.772 [e2-core] mask: ff ff ff 3f 00 00
18:32:39.772 [e2-core] mode: 00 00 00 3e 00 00
18:32:39.780 [e2-core] ++ 2/2 TID 4e
18:32:39.780 [e2-core] done!
18:32:39.785 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:39.786 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124653255.jpg
18:32:39.786 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:39.788 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:39.789 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/125278391.jpg
18:32:39.789 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:39.790 [e2-core] doing version filtering
18:32:39.790 [e2-core] 0012: 4e 2e 9b 03 00 00
18:32:39.790 [e2-core] mask: ff ff ff 3f 00 00
18:32:39.790 [e2-core] mode: 00 00 00 3e 00 00
18:32:39.810 [e2-core] + 1/1 TID 74
18:32:39.810 [e2-core] done!
18:32:39.811 [e2-core] AITready
18:32:39.811 [e2-core] Section Length : 104, Total Section Length : 107
18:32:39.811 [e2-core] found applicaions ids >> pid : ffffffff, orgid : 26, appid : 600
18:32:39.811 [e2-core] Save Data Len : [107]
18:32:39.811 [e2-core] Found : control[1], name[Super RTL Startleiste], url[http://hbbtv.superrtl.de/index.html]
18:32:39.812 [e2-python] Name : Super RTL Startleiste
18:32:39.812 [e2-python] URL : http://hbbtv.superrtl.de/index.html
18:32:39.812 [e2-python] OrgID : 26
18:32:39.812 [e2-python] AppID : 600
18:32:39.812 [e2-python] Control Code : 1
18:32:39.812 [e2-python] Profile Code : 0
18:32:39.813 [e2-python]
18:32:39.813 [e2-python] [#----] VTiCore (1, 'Super RTL Startleiste', 'http://hbbtv.superrtl.de/index.html', 26, 600, 0)
18:32:40.465 [e2-core] sdt update done!
18:32:40.493 [e2-python] [AutoRes] determineContent
18:32:40.493 [e2-python] [AutoRes] switch deinterlacer mode to auto
18:32:40.494 [e2-python] [AutoRes] new content is 1920x1080i25
18:32:40.494 [e2-python] [AutoRes] determined videomode1080p
18:32:40.495 [e2-python] action -> InfobarChannelSelection openServiceList
18:32:40.495 [e2-python] playing service..
18:32:40.496 [e2-core] [eDVBCAService] free slot 0 demux 0 for service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:40.496 [e2-core] [eDVBCAService] free service 1:0:19:2E9B:411:1:C00000:0:0:0:
18:32:40.500 [e2-core] decoder state: play, vpid=-1, apid=-1
18:32:40.500 [e2-core] DEMUX_STOP - pcr - ok
18:32:40.501 [e2-core] DEMUX_STOP - video - ok
18:32:40.501 [e2-core] VIDEO_STOP - ok
18:32:40.526 [e2-core] AUDIO_STOP - ok
18:32:40.526 [e2-core] DEMUX_STOP - audio - ok
18:32:40.526 [e2-core] AUDIO_CONTINUE - ok
18:32:40.526 [e2-core] DEMUX_STOP - ttx - ok
18:32:40.528 [e2-core] start release channel timer
18:32:40.537 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:40.538 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124529923.jpg
18:32:40.538 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:40.540 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:40.541 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124527704.jpg
18:32:40.541 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:40.543 [e2-python] [#----] VTiCore not pauseable.
18:32:40.543 [e2-python] RemovePopup, id =ZapError
18:32:40.543 [e2-core] [eDVBCAService] remove channel 0x23206e0
18:32:40.544 [e2-core] [eDVBLocalTimerHandler] remove channel 0x23206e0
18:32:40.544 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:40.544 [e2-core] [eEPGCache] lock already acquired --> retry
18:32:40.744 [e2-core] [eEPGCache] remove channel 0x23206e0
18:32:40.745 [e2-core] allocate channel.. 000c:0085
18:32:40.745 [e2-core] Slot 0, score 10002
18:32:40.745 [e2-core] [eDVBCAService] new channel 0x23206e0!
18:32:40.745 [e2-core] (0)tune
18:32:40.745 [e2-core] RotorCmd ffffffff, lastRotorCmd ffffffff
18:32:40.745 [e2-core] prepare_sat Freq 12304500 Pol 0 SR 27500000 INV 2 FEC 9 orbpos 192 system 1 modulation 1 pilot 2, rolloff 0, is_id -1, pls_mode 3, pls_code 0
18:32:40.745 [e2-core] tuning to 1704 mhz
18:32:40.745 [e2-core] OURSTATE: tuning
18:32:40.745 [e2-core] allocate Channel: res 0
18:32:40.745 [e2-core] allocate demux
18:32:40.746 [e2-core] resolve: resolve ${sysconfdir}/scan_tp_valid_check.py
18:32:40.746 [e2-core] resolve: -> /etc/scan_tp_valid_check.py
18:32:40.746 [e2-python] [#----] VTiCore [VTiEvent] SERVICE_START 1:0:19:7C:C:85:C00000:0:0:0:, Fox HD
18:32:40.748 [e2-core] [SEC] set static current limiting
18:32:40.749 [e2-core] set sequence pos 3
18:32:40.750 [e2-core] set sequence pos 7
18:32:40.750 [e2-core] [SEC] invalidate current switch params
18:32:40.843 [e2-core] [SEC] sendDiseqc: e01038f3
18:32:40.843 [e2-core] [SEC] sleep 50ms
18:32:40.893 [e2-core] set sequence pos 3
18:32:40.893 [e2-core] [SEC] setTone 1
18:32:40.906 [e2-core] [SEC] sleep 10ms
18:32:40.916 [e2-core] [SEC] update current switch params
18:32:40.916 [e2-core] [SEC] startTuneTimeout 5000
18:32:40.916 [e2-core] [SEC] setFrontend 1
18:32:40.916 [e2-core] setting frontend 0
18:32:40.916 [e2-core] [SEC] sleep 500ms
18:32:41.009 [e2-core] (0)fe event: status 0, inversion off, m_tuning 1
18:32:41.084 [e2-core] (0)fe event: status 1f, inversion off, m_tuning 2
18:32:41.085 [e2-core] OURSTATE: ok
18:32:41.085 [e2-core] [eDVBCAService] channel 0x23206e0 running
18:32:41.085 [e2-core] [eDVBLocalTimerHandler] channel 0x23206e0 running
18:32:41.085 [e2-core] no version filtering
18:32:41.085 [e2-core] 0014: 70 00 00 00 00 00
18:32:41.085 [e2-core] mask: fc 00 00 00 00 00
18:32:41.085 [e2-core] mode: 00 00 00 00 00 00
18:32:41.085 [e2-core] [eEPGCache] channel 0x23206e0 running
18:32:41.086 [e2-core] stop release channel timer
18:32:41.086 [e2-core] no version filtering
18:32:41.086 [e2-core] 0012: 4e 00 7c 00 00 00
18:32:41.086 [e2-core] mask: ff ff ff 00 00 00
18:32:41.086 [e2-core] mode: 00 00 00 00 00 00
18:32:41.087 [e2-core] ok ... now we start!!
18:32:41.087 [e2-core] no version filtering
18:32:41.087 [e2-core] 0000: 00 00 00 00 00 00
18:32:41.087 [e2-core] mask: ff 00 00 00 00 00
18:32:41.087 [e2-core] mode: 00 00 00 00 00 00
18:32:41.087 [e2-core] eventNewProgramInfo 0 0
18:32:41.087 [e2-core] have 1 video stream(s) (09ff), and 1 audio stream(s) (0a03), and the pcr pid is 09ff, and the text pid is ffffffff
18:32:41.087 [e2-core] allocate demux
18:32:41.088 [e2-core] [eEPGCache] next update in 37 min
18:32:41.090 [e2-core] disable teletext subtitles
18:32:41.098 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:41.098 [e2-core] decoder state: play, vpid=2559, apid=2563
18:32:41.098 [e2-core] DMX_SET_PES_FILTER(0x9ff) - pcr - ok
18:32:41.098 [e2-core] DEMUX_START - pcr - ok
18:32:41.099 [e2-core] DMX_SET_PES_FILTER(0xa03) - audio - ok
18:32:41.099 [e2-core] DEMUX_START - audio - ok
18:32:41.099 [e2-core] AUDIO_SET_BYPASS(0) - ok
18:32:41.099 [e2-core] AUDIO_PAUSE - ok
18:32:41.100 [e2-core] AUDIO_PLAY - ok
18:32:41.100 [e2-core] Video Device: /dev/dvb/adapter0/video0
18:32:41.100 [e2-core] demux device: /dev/dvb/adapter0/demux0
18:32:41.101 [e2-core] VIDEO_SET_STREAMTYPE 1 - ok
18:32:41.101 [e2-core] DMX_SET_PES_FILTER(0x9ff) - video - ok
18:32:41.101 [e2-core] DEMUX_START - video - ok
18:32:41.101 [e2-core] VIDEO_FREEZE - ok
18:32:41.106 [e2-core] VIDEO_PLAY - ok
18:32:41.106 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:41.106 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:41.106 [e2-core] VIDEO_CONTINUE - ok
18:32:41.107 [e2-core] AUDIO_CONTINUE - ok
18:32:41.107 [e2-python] [#----] VTiCore not pauseable.
18:32:41.109 [e2-python] [AutoRes] service changed
18:32:41.153 [e2-core] [eDVBLocalTimerHandler] diff is 0
18:32:41.153 [e2-core] [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
18:32:41.153 [e2-core] [eDVBLocalTimerHandler] not changed
18:32:41.198 [e2-core] + 1/1 TID 00
18:32:41.198 [e2-core] done!
18:32:41.198 [e2-core] PATready
18:32:41.198 [e2-core] use pmtpid 0069 for service_id 007c
18:32:41.199 [e2-core] no version filtering
18:32:41.199 [e2-core] 0069: 02 00 7c 00 00 00
18:32:41.199 [e2-core] mask: ff ff ff 00 00 00
18:32:41.199 [e2-core] mode: 00 00 00 00 00 00
18:32:41.199 [e2-core] doing version filtering
18:32:41.199 [e2-core] 0000: 00 00 00 03 00 00
18:32:41.199 [e2-core] mask: ff 00 00 3f 00 00
18:32:41.199 [e2-core] mode: 00 00 00 3e 00 00
18:32:41.263 [e2-core] + 1/1 TID 02
18:32:41.263 [e2-core] done!
18:32:41.264 [e2-core] eventNewProgramInfo 0 0
18:32:41.264 [e2-core] [autoAudioTrack] use audio track: 0
18:32:41.264 [e2-core] have 1 video stream(s) (09ff), and 2 audio stream(s) (0a03, 0a04), and the pcr pid is 09ff, and the text pid is ffffffff
18:32:41.272 [e2-core] PES filter: DMX_SET_PES_FILTER - Invalid argument
18:32:41.272 [e2-core] decoder state: play, vpid=2559, apid=2563
18:32:41.272 [e2-core] VIDEO_SLOWMOTION(0) - ok
18:32:41.272 [e2-core] VIDEO_FAST_FORWARD(0) - ok
18:32:41.272 [e2-core] VIDEO_CONTINUE - ok
18:32:41.272 [e2-core] AUDIO_CONTINUE - ok
18:32:41.273 [e2-core] [autoAudioTrack] use audio track: 0
18:32:41.277 [e2-core] [eDVBCAService] new service 1:0:19:7C:C:85:C00000:0:0:0:
18:32:41.277 [e2-core] [eDVBCAService] add demux 0 to slot 0 service 1:0:19:7C:C:85:C00000:0:0:0:
18:32:41.277 [e2-core] demux 0 mask 01 prevhash 00000000
18:32:41.278 [e2-core] [eDVBCAService] send 62 bytes
18:32:41.278 [e2-core] 9f 80 32 3a 03 00 7c 03 00 25 01 81 08 00 c0 00 00 00 0c 00 85 82 02 01 00 84 02 00 69 09 04 09 c4 fb 19 09 04 09 8c fa 19 09 04 09 8d fc 19 1b 09 ff 00 00 06 0a 03 00 00 06 0a 04 00 00
18:32:41.278 [e2-core] doing version filtering
18:32:41.278 [e2-core] 0069: 02 00 7c 03 00 00
18:32:41.278 [e2-core] mask: ff ff ff 3f 00 00
18:32:41.278 [e2-core] mode: 00 00 00 3e 00 00
18:32:41.279 [e2-core] -+ 1/2 TID 4e
18:32:41.417 [e2-core] [SEC] set dynamic current limiting
18:32:42.110 [e2-python] [AutoRes] determineContent
18:32:42.110 [e2-python] [AutoRes] switch deinterlacer mode to auto
18:32:42.111 [e2-python] [AutoRes] new content is 1920x1080i25
18:32:42.111 [e2-python] [AutoRes] determined videomode1080p
18:32:42.268 [e2-core] ++ 2/2 TID 4e
18:32:42.269 [e2-core] done!
18:32:42.273 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:42.274 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124529923.jpg
18:32:42.274 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:42.276 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:42.277 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124527704.jpg
18:32:42.277 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:42.278 [e2-core] doing version filtering
18:32:42.279 [e2-core] 0012: 4e 00 7c 0b 00 00
18:32:42.279 [e2-core] mask: ff ff ff 3f 00 00
18:32:42.279 [e2-core] mode: 00 00 00 3e 00 00
18:32:42.665 [e2-core] sdt update done!
18:32:51.110 [e2-python] orbitalposition: 192
18:32:51.111 [e2-python] [92m[EPG Share] Transponder is in cache

Alles anzeigen

bimmler · 9. Januar 2020, 21:20

@hajeku123 oder @RickX : Ich werd echt nicht schlau aus dem Log. Könnt ihr bei Gelegenheit daraus was erkennen?

Nach dem ich das gesamte Debuglog überflogen hab, kann ich nur mit Gewissheit sagen das extrem viel passiert und gelogt wird wenn der GUI Neustart passiert.

hajeku123 · 9. Januar 2020, 21:42

wie @dg4sj bereits geschrieben hat, passieren da keine GUI Restarts.

Wie äußert sich der "GUI Restart" denn genau? Beschreibe mal, wie das aussieht.
Ich denke da erscheint sicherlich nur der Spinner, welcher als GUI Restart deklariert wird.

Ich würde meinen um 18:32 Uhr hat ein Streaming angefangen, Wenn das niemand im internen Netzwerk gestartet hat, kommt das von außen aus dem Internet.
...Hatte ja schon mal nach offenen Ports im Router gefragt...

EDIT
Hattest du das Netzwerkkabel mal testweise für eine Weile (ein / zwei / drei Tage) draußen gelassen und beobachtet, ob das Problem dann weg ist?

bimmler · 9. Januar 2020, 21:52

100% genau so als ob ich mit der Bedienung auf Menü gehe und dann GUI Neustart drücken würde.

Das komplette Bild verschwindet und alles wird schwarz, nach ca 2 Sekunden kommt der willkommen zurück Bildschirm, der Spinner ist oben links am arbeiten die Infobar erscheint. Nach wenigen Sekunden ist dann das TV Bild wieder da. Das passiert jeden Tag so ca 3 - 6 mal. Einfach beim ganz normalen TV schauen.

Es wird nicht gestreamt. Auch sind keine Ports offen im Router. Netzwerkadapter in der Box neugestartet und auch für einen Tag das Lankabel ausgesteckt. Passierte trotzdem.

hajeku123 · 9. Januar 2020, 22:03

Debug Modus steht auf Datei?
Wenn das passiert, gibt es dann zu diesen Zeitpunkt kein zweites Debug Log?
Wenn zu dem Zeitpunkt des GUI Restarts eine Aufnahme läuft, wird diese dann unterbrochen oder läuft weiter?

bimmler · 9. Januar 2020, 22:09

Jap. Steht auf Datei. Es ist ne Zero 4K. Da läuft nur das TV Programm. Keine Aufnahme. Ne es gab nur dieses Debuglog was stolze 25MB groß geworden ist. Lief paar Tage durch.

hajeku123 · 9. Januar 2020, 22:21

Das ist kein GUI Restart, scheint nur so auszusehen, was im Debuglog nicht sichtbar ist.
Probiere mal mit dem Standard VU Skin.

bimmler · 9. Januar 2020, 22:32

Ja klar, es sieht nur aus wie ein GUI Neustart. Aber es passiert leider täglich und ich weiß nicht warum. Ja werde einen anderen Skin probieren zum ausschließen einer Fehlerquelle. Aber ich selbst nutze auch den Skin...schon komisch.

Edit:
Da es wieder ein paar Tage dauert bis ich da bin, ein letzter verzweifelter Versuch aus der Ferne

@ditschi1691 Du bist doch auch ein Verfechter des Metrix Skin. Könnte er an dem Schlamassel schuld sein für die kurzem Reboots?

Log ist in Beitrag 30.

hajeku123 · 10. Januar 2020, 06:23

Ich würde die Kiste, so wie dir bereits geraten wurde, mit einem neuen Image aus dem Reiter VTI-Images neu betanken, dann die Online Updates einspielen und damit erst einmal weiter testen.
Vor allen keine vorhandene Imagesicherung, zurück spielen und die Kiste manuell neu einrichten.

bimmler · 10. Januar 2020, 10:16

Wenn nichts passiert, werde ich daran nicht herum kommen. Doch das extrem ärgerliche ist, das so die Kiste ja neu aufgesetzt wurde Inclusive Onlineupdates. Und nun wieder von vorne anfangen obwohl doch aus einem Debuglog ALLES ersichtlich sein sollte was da passiert.

RickX · 10. Januar 2020, 10:51

Ich habe mir den Log-Auszug aus #30 mal angesehen. Da ist definitiv zwischen 18:10 und 18:32 kein GUI-Reboot gewesen...

Auffällig ist höchstens, dass immer wieder versucht wird, Extra-Daten von der Platte zu lesen.
Verantwortlich dafür ist offensichtlich die Metrix-Infobar.

Quellcode

18:32:22.416 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:22.417 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/124653255.jpg
18:32:22.417 [e2-python] [92m[MetrixInfobarDescription] Image not exist
18:32:22.419 [e2-python] [92m[MetrixInfobarDescription] try exdata -> JSON
18:32:22.420 [e2-python] [92m[MetrixInfobarDescription] loadImage: /hdd/EPGImages/01.07.20/125278391.jpg
18:32:22.421 [e2-python] [92m[MetrixInfobarDescription] Image not exist

Die angefragten Bilder werden nie gefunden. Wenn ich mir die Zeiten dazu im Log ansehen, hat das aber wohl auch nicht zu größeren Verzögerungen geführt. Trotzdem würde ich hier mal nachsehen, ob die Konfiguration der Metrix-Infobar geändert werden kann, um den Zugriff auf die nicht vorhandenen EPGImages zu vermeiden.

Was auch noch auffällt sind einige Zap-Errors

Quellcode

18:32:36.100 [e2-python] RemovePopup, id =ZapError

Hier wurde wohl auf einen Sender gezappt, der nicht empfangen werden konnte. Das ist einige mal passiert.
Sieht man das beim Zappen? Wird dann die Box angezeigt, dass der Sender nicht empfangen werden kann?