=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2013.10.21 15:01:21 =~=~=~=~=~=~=~=~=~=~=~=
[EPGC] start cleanloop
[EPGC] stop cleanloop
[EPGC] 959621 bytes for cache used
It's now  Mon Oct 21 15:01:40 2013
[TIMER] activating state 1
[TIMER] prepare ok, waiting for begin
[timer.py] next activation: 1382360520 (in 19994 ms)
It's now  Mon Oct 21 15:02:00 2013
[TIMER] activating state 2
[TIMER] wakeup and zap
leave standby
[59021.659000] set input to 0
[59021.660000] set fb to 2(0)
[59021.660000] using 50.00Hz as MonitorRefreshRate
[59021.742000] SET FORMAT: display[0], format=27
[59021.742000] HDMI mode
[59021.743000] use EDID to detect colorspace
[59021.743000] using colorspace: 1
[59021.857000] bcm_video_analog_port_setup (fmt=7)
[59021.958000] SET FORMAT: display[1], format=7
[59022.058000] set current avs auto aspect to 16:9
[59022.059000] disable wss on display 0
[59022.060000] set wss 7 on display 1
[59022.060000] set sb to 3(1)
[59022.061000] set mute 0 (111) 0
cannot open /proc/stb/audio/j1_mute(No such file or directory)
[TIMER] activating state 3
[TIMER] stop recording
next real activation is Mon Oct 21 20:14:40 2013
[timer.py] next activation: 1382360620 (in 99570 ms)
playing 1:0:19:283D:3FB:1:C00000:0:0:0:
[TeleText] service started
not pauseable.
RemovePopup, id = ZapError
allocate channel.. 03fb:0001:00c00000
opening frontend 0
[59022.158000] bcm4506: channel 0 is in normale mode now
[eDVBCAService] new channel 0x2be8320!
(0)tune
RotorCmd ffffffff, lastRotorCmd ffffffff
prepare_sat System 1 Freq 11493750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0
tuning to 1743 mhz
OURSTATE: tuning
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:19:283D:3FB:1:C00000:0:0:0:
recheckPMTHAndlers()
recheck 0x2be265c 1:0:19:283D:3FB:1:C00000:0:0:0:
allocate demux
[Cec] power on
[FanControl]: setting fan values: fanid = 0, voltage = 15, pwm = 0
cec event!
attached: hdmi attached
attached: hdmi cec address acknowledged
my physical: 1.2.0.0  logical 3
[SEC] lock 0
cec event!
send 3 -> 0 : 04 
[SEC] set static current limiting
[SEC] invalidate current switch params
[SEC] setVoltage 2
(0)setVoltage 18V
[SEC] sleep 10ms
[SEC] update current switch params
[SEC] startTuneTimeout 5000
[SEC] setFrontend 1
(0)setting frontend
(0)fe event: status 0, freq 1743750, inversion off, m_tuning 1 F
[SEC] unlock
[SEC] sleep 500ms
(0)fe event: status 1, freq 1743750, inversion off, m_tuning 1 N
[59022.253000] CEC send ok!
cec event!
send 3 -> f : 82 12 00 
[59022.368000] CEC send ok!
(0)fe event: status 0, freq 1743750, inversion off, m_tuning 2 N
(0)fe event: status 1f, freq 1744024, inversion off, m_tuning 3 N
OURSTATE: ok
[eDVBCAService] channel 0x2be8320 running
[eDVBLocalTimeHandler] channel 0x2be8320 running
no version filtering
0014:  70 00 00 00 00 00
mask:  fc 00 00 00 00 00
mode:  00 00 00 00 00 00
[eEPGCache] channel 0x2be8320 running
stop release channel timer
[EPGC] next update in 2 sec
no version filtering
0012:  4e 28 3d 00 00 00
mask:  ff ff ff 00 00 00
mode:  00 00 00 00 00 00
ok ... now we start!!
no version filtering
0000:  00 00 00 00 00 00
mask:  ff 00 00 00 00 00
mode:  00 00 00 00 00 00
eventNewProgramInfo 0 0
have 1 video stream(s) (13ed), and 1 audio stream(s) (13f2), and the pcr pid is 13ed, and the text pid is 13f0
allocate demux
disable teletext subtitles
decoder state: play, vpid=5101, apid=5106
DMX_SET_PES_FILTER(0x[59022.579000] start pcr0 on dmx 3 pid:13ed index 5
13ed) - pcr - ok
DEMUX_START - pcr - ok
DMX_SET_PES_FILTER(0x13f2) - audio - ok
DEMUX_START - audio - ok
AUDIO_SET_BYP[59022.581000] start audio on dmx 3 pid:13f2
ASS(0) - [59022.582000] audio xpt start
[59022.582000] sync is off so we enable it
[59022.583000] demux-index 3 pcr0pid 13ed
[59022.583000] using 5 as pcr0 pidchannel
[59022.585000] AC3
ok
AUDIO_PAUSE - ok
AUDIO_PLAY - [59022.585000] audio: decoder start 0
[59022.588000] audio delay 18000 (200 ms)
[59022.588000] total video delay: 18000 (200 ms)
ok
Video Device: /dev/dvb/adapter0/video0
demux device: /dev/dvb/adapter0/demux3
VIDEO_SET_STREAM[59022.590000] start video0 on dmx 3 pid:13ed
[59022.591000] RAP first pts 0x89719c54, stc 0x13c6b6e7a, pcr invalid
TYPE 1 - ok
DMX[59022.591000] no valid pcr data yet... set stc to pts!
_SET_PES_FILTER([59022.592000] total video delay: 18000 (200 ms)
[59022.592000] audio_cdb_itb_error_isr! underflow
0x13ed) - video - ok
DEMUX_START - video - ok
VIDEO_FREEZE - ok
VIDEO_PLAY - [59022.597000] VIDEO0: start decode
[59022.597000] audio_cdb_itb_error_isr! underflow
ok
DMX_SET_PES_FILTER(0x13f0) - ttx - ok
DEMUX_START - ttx - ok
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
[59022.601000] audio_cdb_itb_error_isr! underflow
not pauseable.
[59022.605000] audio_cdb_itb_error_isr! underflow
[59022.609000] audio_cdb_itb_error_isr! underflow
[59022.613000] audio_cdb_itb_error_isr! underflow
[59022.617000] audio_cdb_itb_error_isr! underflow
[59022.621000] audio_cdb_itb_error_isr! underflow
[59022.623000] ch0: lock
[59022.623000] RAP sampling rate info isr (48000)
[59022.624000] eModeChange Isr.. set source_changed_event!
[TeleText] serviceInfoChanged
[TeleText] TXT PID 5104 DEMUX 3
[TeleText] send array('B', [1, 19, 240, 3])
+ 1/1 TID 00
done!
PATready
use pmtpid 13ec for service_id 283d
no version filtering
13ec:  02 28 3d 00 00 00
mask:  ff ff ff 00 00 00
mode:  00 00 00 00 00 00
doing version filtering
0000:  00 00 00 0f 00 00
mask:  ff 00 00 3f 00 00
mode:  00 00 00 3e 00 00
[SEC] set dynamic current limiting
[eDVBLocalTimeHandler] Receiver time is 'Mon Oct 21 15:02:01 2013'
[eDVBLocalTimeHandler] Transponder time is 'Mon Oct 21 15:02:01 2013'
[eDVBLocalTimeHandler] diff is 0
[eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
[eDVBLocalTimeHandler] not changed
[59022.962000] VIDEO0: first pts 0x89734aee
[59023.113000] enable tsm0
+ 1/1 TID 02
done!
[eDVBCAService] new service 1:0:19:283D:3FB:1:C00000:0:0:0:
[eDVBCAService] add demux 3 to slot 0 service 1:0:19:283D:3FB:1:C00000:0:0:0:
demux 3 mask 08 prevhash 00000000
[eDVBCAService] send 79 bytes
9f 80 32 4b 03 28 3d 0b 00 13 01 81 08 00 c0 00 00 03 fb 00 01 82 02 08 03 84 02 13 ec 05 04 92 00 00 0b 08 7b 00 00 1b 13 ed 00 00 03 13 ee 00 00 03 13 ef 00 00 06 13 f0 00 00 06 13 f1 00 00 06 13 f2 00 00 06 13 f4 00 00 0b 14 34 00 00 
recheckPMTHAndlers()
recheck 0x2be265c 1:0:19:283D:3FB:1:C00000:0:0:0:
HBBTV broadcast data on pid 087b
Rds PID 13f4 detected ? ! ?
HBBTV broadcast data on pid 1434
[eDVBCIInterfaces] gotPMT
eventNewProgramInfo 0 0
start ait on pid 0492
no version filtering
0492:  74 00 00 00 00 00
mask:  ff 00 00 00 00 00
mode:  00 00 00 00 00 00
have 1 video stream(s) (13ed), and 3 audio stream(s) (13ee, 13ef, 13f2), and the pcr pid is 13ed, and the text pid is 13f0
decoder state: play, vpid=5101, apid=5106
HBBTV broadcast data on pid 087b
Rds PID 13f4 detected ? ! ?
HBBTV broadcast data on pid 1434
[TeleText] serviceInfoChanged
[TeleText] TXT PID 5104 DEMUX 3
[TeleText] send array('B', [1, 19, 240, 3])
doing version filtering
13ec:  02 28 3d 0b 00 00
mask:  ff ff ff 3f 00 00
mode:  00 00 00 3e 00 00
-+ 1/2 TID 4e
sdt update done!
++ 2/2 TID 4e
done!
doing version filtering
0012:  4e 28 3d 31 00 00
mask:  ff ff ff 3f 00 00
mode:  00 00 00 3e 00 00
[EPGC] start caching events(1382360522)
+ 1/1 TID 74
done!
[eHbbtv] aitChanged pid=0x492
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:HBBTV-Start-DSMCC+Web
[eHbbtv] New Application: HBBTV-Start-DSMCC+Web (orgid=19, appid=1, urlBase=http://itv.ard.de/ardstart/, initalPath=index.html, controlCode=1, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.1
[eHbbtv] We have 1 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.1' resolved to 'http://itv.ard.de/ardstart/index.html'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:EPG
[eHbbtv] New Application: EPG (orgid=19, appid=2, urlBase=http://itv.ard.de/ardepg/, initalPath=index.php, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.2
[eHbbtv] We have 2 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.2' resolved to 'http://itv.ard.de/ardepg/index.php'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:ARD-Text-DSMCC
[eHbbtv] New Application: ARD-Text-DSMCC (orgid=19, appid=4, urlBase=http://itv2.ard.de/ardtext/, initalPath=index.html, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.4
[eHbbtv] We have 3 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.4' resolved to 'http://itv2.ard.de/ardtext/index.html'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:Tagesschau
[eHbbtv] New Application: Tagesschau (orgid=19, appid=5, urlBase=http://www.tagesschau.de/, initalPath=hbbtv, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.5
[eHbbtv] We have 4 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.5' resolved to 'http://www.tagesschau.de/hbbtv'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:Mediathek DasErste
[eHbbtv] New Application: Mediathek DasErste (orgid=19, appid=20, urlBase=http://hbbtv.daserste.de/, initalPath=index.php, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.20
[eHbbtv] We have 5 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.20' resolved to 'http://hbbtv.daserste.de/index.php'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:HBBTV NDR-Mediathek
[eHbbtv] New Application: HBBTV NDR-Mediathek (orgid=19, appid=27, urlBase=http://hbbtv.ndr.de/, initalPath=index.html, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.27
[eHbbtv] We have 6 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.27' resolved to 'http://hbbtv.ndr.de/index.html'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:HBBTV ARD-Mediathek
[eHbbtv] New Application: HBBTV ARD-Mediathek (orgid=19, appid=3, urlBase=http://hbbtv.ardmediathek.de/hbbtv-ard/, initalPath=mediathek/?devicegroup=hbbtv, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.3
[eHbbtv] We have 7 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.3' resolved to 'http://hbbtv.ardmediathek.de/hbbtv-ard/mediathek/?devicegroup=hbbtv'
[eOipfApplication] Profile 0000, Version 1.0.2
[eOipfApplication] deu:BR-Mediathek
[eHbbtv] New Application: BR-Mediathek (orgid=19, appid=35, urlBase=http://hbbtv.br.gl-systemhaus.de/hbbtv/, initalPath=index.html, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.35
[eHbbtv] We have 8 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.35' resolved to 'http://hbbtv.br.gl-systemhaus.de/hbbtv/index.html'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:HBBTV Tatort
[eHbbtv] New Application: HBBTV Tatort (orgid=19, appid=42, urlBase=http://hbbtv-tatort.daserste.de/, initalPath=index.php, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.42
[eHbbtv] We have 9 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.42' resolved to 'http://hbbtv-tatort.daserste.de/index.php'
[eOipfApplication] Profile 0000, Version 1.1.1
[eOipfApplication] deu:HBBTV NDR Wahl 2013
[eHbbtv] New Application: HBBTV NDR Wahl 2013 (orgid=19, appid=46, urlBase=http://hbbtv.dimap.tagesschau.de/esystem/dehe13/studio/ard/, initalPath=esystem_hbbtv.hbbtv, controlCode=2, visibility=2)
[eHbbtv] Resolving Application Locator: dvb://current.ait/19.46
[eHbbtv] We have 10 app(s) for service 1.1019.10301
[eHbbtv] 'dvb://current.ait/19.46' resolved to 'http://hbbtv.dimap.tagesschau.de/esystem/dehe13/studio/ard/esystem_hbbtv.hbbtv'
doing version filtering
0492:  74 00 00 05 00 00
mask:  ff 00 00 3f 00 00
mode:  00 00 00 3e 00 00
[59024.963000] HDMI: force disconnect!
[59024.963000] HDMI: now disconnected
[59024.963000] HDMI: stop CEC
cec event!
nothing attached!
[59030.271000] CEC recv ok!
cec event!
cec event!
handle message 0 -> 3 : 8d 02 
	Menu Request - Type: Query
cec event!
send 3 -> 0 : 8e 01 
[59030.358000] CEC send ok!
[59030.525000] CEC recv ok!
cec event!
handle message 0 -> f : 32 64 65 75 
	Set Menu Language: deu
[59030.958000] CEC recv ok!
cec event!
handle message 0 -> 3 : 
[EPGC] abort non avail schedule other reading
[EPGC] abort non avail viasat reading
[EPGC] nownext finished(1382360531)
[59033.016000] CEC recv ok!
cec event!
handle message 0 -> 3 : 46 
	Give OSD Name
cec event!
send 3 -> 0 : 47 44 72 65 61 6d 62 6f 78 
[59033.272000] CEC send ok!
[59033.343000] CEC recv ok!
cec event!
handle message 0 -> 3 : 8c 
	Give Device Vendor ID
cec event!
send 3 -> f : 87 00 09 34 
[59033.478000] CEC send ok!
[59033.643000] CEC recv ok!
cec event!
handle message 0 -> 3 : a0 00 00 f0 23 
	Vendor Command with ID
cec event!
send 3 -> 0 : 00 a0 00 
[59033.753000] CEC send ok!
[59033.818000] CEC recv ok!
cec event!
handle message 0 -> 3 : 9f 
	Get CEC Version
cec event!
send 3 -> 0 : 9e 04 
[59033.905000] CEC send ok!
[59033.969000] CEC recv ok!
cec event!
handle message 0 -> 3 : 83 
	Get Physical Address
cec event!
send 3 -> f : 84 12 00 03 
[59034.104000] CEC send ok!
[59034.805000] CEC recv ok!
cec event!
handle message 4 -> f : 87 08 00 46 
	Device Vendor ID 080046
[59035.494000] CEC recv ok!
cec event!
handle message 4 -> f : 84 14 00 04 
	Report Physical Address 
1.4.0.0 device type 4 (Playback Device)
[59035.611000] CEC recv ok!
cec event!
handle message 0 -> f : 85 
	Request Active Source
cec event!
send 3 -> f : 82 12 00 
[59035.722000] CEC send ok!
[59035.810000] CEC recv ok!
cec event!
handle message 0 -> 3 : 8d 02 
	Menu Request - Type: Query
cec event!
send 3 -> 0 : 8e 01 
[59035.896000] CEC send ok!
[59035.984000] CEC recv ok!
cec event!
handle message 0 -> 3 : 8d 02 
	Menu Request - Type: Query
cec event!
send 3 -> 0 : 8e 01 
[59036.071000] CEC send ok!
[59037.773000] HDMI: now connected
[59037.801000] HDMI: EDID read ok
[59037.822000] using 50.00Hz as MonitorRefreshRate
[59037.921000] SET FORMAT: display[0], format=27
[59037.922000] HDMI mode
[59037.923000] use EDID to detect colorspace
[59037.923000] using colorspace: 1
[59038.037000] bcm_video_analog_port_setup (fmt=7)
[59038.138000] SET FORMAT: display[1], format=7
[59038.238000] set current avs auto aspect to 16:9
[59038.239000] disable wss on display 0
[59038.239000] set wss 7 on display 1
[59038.240000] HDMI: start CEC
[59038.240000] set sb to 3(1)
cec event!
attached: hdmi attached
[59038.302000] HDMI: use logical CEC address 3
cec event!
attached: hdmi attached
attached: hdmi cec address acknowledged
my physical: 1.2.0.0  logical 3
[59038.440000] CEC send ok!
[EPGC] start cleanloop
[EPGC] stop cleanloop
[EPGC] 963067 bytes for cache used
no ancillary data in audio stream... abort radiotext pes parser