[Premium+ HD] Zapsnelheid debuggen

basix · 23 augustus 2010

Geïrriteerd door de zapsnelheid ben ik begonnen met wat te debuggen op de azbox premium+. Geen timestamps dus moeten improviseren in de console, een shell scriptje op de bg draaien dat elke seconde de tijd weergeeft met +++TIMESTAMP.

Test setup:

Firmware version azbox-0.9.5020, clean install

Boot op RTL4 on Astra1

zap naar Kanaal Z, en terug naar RTL4 (gemarkeerd met ++++ZAP, heb dit in de terminal geplakt gelijktijdig met de zap via ab)

MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10

Local CCcam op dm7000 met kaart tvv + cd, multicas connected als cccam client.

Wat ik gemerkt heb is dat de zap actie pas na 2 tot 3 seconden gevolgd wordt door een frequentieverandering in de tuner ipv onmiddellijk. Zap op 11:38:15, frequentie verandering op 11:38:17, een rare fout, een diseq commando (eigenlijk overbodig omdat we op dezelfde sat zitten). Op 11:38:18, Demux start, Emu (multicas/openxcas) begint te draaien en uiteindelijk op 11:38:20, het zappen is voltooid en de ecm is gedecodeerd.

[Debug Mode] Begin of start.sh ###

Display mode: FND

++++TIMESTAMP Mon Aug 23 11:37:26 UTC 2010

Display mode: FND

mkdir: Cannot create directory `/DATA/meta/': File exists

Display mode: FND

interfaces ok

Display mode: FND

Using /MMP/modules/2.6.15/llad.ko

Using /MMP/modules/2.6.15/em8xxx.ko

++++TIMESTAMP Mon Aug 23 11:37:27 UTC 2010

Using /MMP/modules/2.6.15/sckernel.ko

Using /MMP/modules/2.6.15/usbserial.ko

Using /MMP/modules/2.6.15/ftdi_sio.ko

Using /MMP/modules/2.6.15/cdrom.ko

Using /MMP/modules/2.6.15/sr_mod.ko

Using /MMP/modules/2.6.15/cdfs.ko

insmod: cannot insert `/MMP/modules/2.6.15/cdfs.ko': Success (17): Success

Using /MMP/modules/2.6.15/fuse.ko

insmod: cannot insert `/MMP/modules/2.6.15/fuse.ko': Success (2): Success

Using /MMP/modules/2.6.15/rt61.ko

insmod: cannot insert `/MMP/modules/2.6.15/rt61.ko': Success (17): Success

Using /MMP/modules/2.6.15/rt3070sta.ko

insmod: cannot insert `/MMP/modules/2.6.15/rt3070sta.ko': Success (17): Success

insmod: rt2500.ko: no module by that name found

mknod: /dev/scard: File exists

mknod: /dev/fuse: File exists

Checking Security.

Init semaphore key(0x20080901) mode(384)

co164_send: call, devaddr = 0x28, subaddr = 0x00, size = 8

SEND DATA1 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

SEND DATA2 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

co164_recv: call, devaddr = 0x28 size = 8

RECV DATA = 0xf6 0x17 0x46 0xb8 0x00 0x4c 0x17 0xac

hx_security_check: SECURITY_CHECK_RESULT = 1 (rand = 16807)

++++TIMESTAMP Mon Aug 23 11:37:28 UTC 2010

++++TIMESTAMP Mon Aug 23 11:37:29 UTC 2010

#######################################################

# Tuner Detection - TD210 & TD9000A is only supported #

# Tuner Combination Type : TWIN #

#######################################################

TunerAPI nim_create_adaptor:472 detection_mode=1, combination_type=1

Init semaphore key(0x20090729) mode(384)

Init semaphore key(0x20090730) mode(384)

Init semaphore key(0x20080901) mode(384)

[TUNER_DETECTION] 1th TUNER Checking...

TunerAPI nim_open_adaptor:618 tuner_combination_type == TUNER_COMBINATION_TWIN

TunerAPI SH_findEmptyI2CBUS:74 Empty I2C bus is found at 0

TunerAPI InitTuner:109 Inializing TUNER_DVB-S(0) combination_type=1

===================TunerID: 0=======================

TunerAPI TunerReset:256 Tuner(0) Reset

TunerAPI TunerReset:259 Setting Tuner(0) RESET GPIO(30) to LOW

++++TIMESTAMP Mon Aug 23 11:37:31 UTC 2010

TunerAPI TunerReset:270 Setting Tuner(0) RESET GPIO(30) to HIGH

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

TunerAPI DVBS_Tuner_Init:1253 This receiver has a Samsung STV0903 tuner

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

TunerAPI InitTuner:171 TUNER_DVB-S(0): Initializing is done

TunerAPI nim_open_adaptor:807 Succesfully initialized TUNER[0] module : DVB-S

[TUNER_DETECTION] Open DVBS adaptor (i2c=0,1)

[TUNER_DETECTION] 2th TUNER Checking...

TunerAPI nim_open_adaptor:618 tuner_combination_type == TUNER_COMBINATION_TWIN

TunerAPI SH_findI2CBUS:60 Same I2C bus is found at 0

TunerAPI InitTuner:109 Inializing TUNER_DVB-S(1) combination_type=1

===================TunerID: 1=======================

TunerAPI TunerReset:256 Tuner(1) Reset

TunerAPI TunerReset:259 Setting Tuner(1) RESET GPIO(31) to LOW

TunerAPI TunerReset:270 Setting Tuner(1) RESET GPIO(31) to HIGH

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

TunerAPI DVBS_Tuner_Init:1253 This receiver has a Samsung STV0903 tuner

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

TunerAPI InitTuner:171 TUNER_DVB-S(1): Initializing is done

TunerAPI nim_open_adaptor:807 Succesfully initialized TUNER[1] module : DVB-S

[TUNER_DETECTION] Open DVBS adaptor (i2c=0,1)

TunerAPI DeinitTuner:185 Deinializing TUNER_DVB-S(0) combination_type=1

TunerAPI DeinitTuner:204 TUNER_DVB-S(0): Deinitializing is done

TunerAPI DeinitTuner:185 Deinializing TUNER_DVB-S(1) combination_type=1

TunerAPI DeinitTuner:204 TUNER_DVB-S(1): Deinitializing is done

[TUNER_DETECTION] The number of tuner is 2

/MMP/usr/bin/fusermount: failed to unmount /DATA/upnp: No such file or directory

Mount options = iocharset=UTF-8

Charset : successfully initialised charset='UTF-8'

/MMP/usr/bin/S.sh: line 129: [: -eq: unary operator expected

/MMP/usr/bin/S.sh: line 132: /MMP/etc/init.d/ushare: No such file or directory

++++TIMESTAMP Mon Aug 23 11:37:32 UTC 2010

==================================

XXXXXXXXXX START SDecoderServer !XXXXXXXX

==================================

g_msq_server_close_ex : key_request 0x59075AB key_response 0x59075AC

g_msq_server_close_ex : key_request 0x5AC149B0 key_response 0x5AC149B1

++++TIMESTAMP Mon Aug 23 11:37:33 UTC 2010

XXXXXXXXXX /SDecoderServer i2c / gpio init [Jul 30 2010 17:18:17] XXXXXXXX

1.0.0.0

g_msq_server_open_ex : key_request 0x59075AB key_response 0x59075AC

g_msq_server_open_ex : key_request 0x5AC149B0 key_response 0x5AC149B1

not use upnp

rm: cannot remove `/tmp/.upnp': No such file or directory

[TD210] INITIALIZED END

[TD210] SLEEP MODE OFF

HDD Detected

====================VERBOSITY NONE====================

Init semaphore key(0x20080901) mode(384)

co164_send: call, devaddr = 0x28, subaddr = 0x00, size = 8

SEND DATA1 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

SEND DATA2 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

co164_recv: call, devaddr = 0x28 size = 8

RECV DATA = 0xf6 0x17 0x46 0xb8 0x00 0x4c 0x17 0xac

hx_security_check: SECURITY_CHECK_RESULT = 1 (rand = 16807)

HHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHH

BUILDTD210: r5020 [Jul 30 2010 17:18:57]

BUILD QWUtil : r5020 [Jul 30 2010 17:18:33]

BUILD CRTVCore: r5020 [Jul 30 2010 17:18:58]

BUILD QWDTV : r5020 [Jul 30 2010 17:18:51]

_TD210

BUILD CRChipCtrl : r5020 [Jul 30 2010 17:18:47]

BUILD QWSmartCard : r5020 [Jul 30 2010 17:18:48]

BUILD QWDTV : r5020 [Jul 30 2010 17:18:51]

_TD210

BUILD CRChipCtrl : r5020 [Jul 30 2010 17:18:47]

BUILD QWSmartCard : r5020 [Jul 30 2010 17:18:48]

BUILD YGEPG : r5020 [Jul 30 2010 17:18:52]

HHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHH

create_iso_639_code_table ok

SCART Detected

Micom Project PAVO

++++TIMESTAMP Mon Aug 23 11:37:34 UTC 2010

vfd_chartable reading complite.

XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX

Comp : Jul 30 2010 17:18:04

Ver : SDecoderCtrl.so CommonCtrl 2.8.5.1.1961

XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX

g_msq_client_connect : key_request 0x5AC149B0 key_response 0x5AC149B1

Display mode: FND

hdparm: /dev/hdb: No such device or address

Init semaphore key(0x20090729) mode(384)

Init semaphore key(0x20090730) mode(384)

Init semaphore key(0x20080901) mode(384)

network ctrl start.............

network ctrl end.............

usb detect start.............

usb detect end..............

usb script start.............

run script = ACTION=init

NetworkCtrl>> running....1

vfork pid = 2808

vfork pid = 0

execle script = /MMP/usr/bin/usb_script.sh

envp[0] = ACTION=init

envp[1] = (null)

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

ERROR: nim_open_adaptor:677 Unsupported tuner type(0) for Twin Tuner 2

ERROR: nim_open_adaptor:677 Unsupported tuner type(0) for Twin Tuner 3

++++TIMESTAMP Mon Aug 23 11:37:36 UTC 2010

ERROR: http_stream_is_change_playlist_file:188 Cannot find http_streams.txt

LOAD EIT POOL : /Download/epg.dat

ERROR: load_eit_pool:1183 cannot open '/Download/epg.dat'

Size Info. - channel_info : 558 bytes

- last_pmtbuf : 128092 bytes

- last_catbuf : 128092 bytes

- xcas_list : 192 bytes

- gECMStatus : 234236 bytes

- gEMMStatus : 234236 bytes

- gKeySet : 3720 bytes

- gFilterStatus : 436604 bytes

- gCipherStatus : 992 bytes

- gCWStatus : 3348 bytes

[OPENXCAS_DTV:Mon Aug 23 11:37:36.980] openxcas_adaptor_create:2207 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:36.982] start_module:1959 ## start

[OPENXCAS_API:Mon Aug 23 11:37:36.986] openxcas_load_module:363 ## PATH = .

[OPENXCAS_API:Mon Aug 23 11:37:36.987] openxcas_load_module:372 ## -->skip PATH = .

[OPENXCAS_API:Mon Aug 23 11:37:36.988] openxcas_load_module:363 ## PATH = ..

[OPENXCAS_API:Mon Aug 23 11:37:36.988] openxcas_load_module:372 ## -->skip PATH = ..

[OPENXCAS_API:Mon Aug 23 11:37:36.991] openxcas_load_module:363 ## PATH = MultiCAS

[OPENXCAS_API:Mon Aug 23 11:37:36.993] openxcas_load_module_from:213 ## 1th module(MultiCAS) loaded(valid = 1, argument = None, xcasapi = 1)

[OPENXCAS_API:Mon Aug 23 11:37:36.994] openxcas_load_module:363 ## PATH = module.seq

[OPENXCAS_API:Mon Aug 23 11:37:36.996] openxcas_load_module:392 ## --> skip PATH = module.seq

[OPENXCAS_API:Mon Aug 23 11:37:36.997] openxcas_load_module:363 ## PATH = sh

[OPENXCAS_API:Mon Aug 23 11:37:36.997] openxcas_load_module:372 ## -->skip PATH = sh

[OPENXCAS_API:Mon Aug 23 11:37:36.998] openxcas_load_module:363 ## PATH = OpenXCAS

[OPENXCAS_API:Mon Aug 23 11:37:36.999] openxcas_load_module:392 ## --> skip PATH = OpenXCAS

[OPENXCAS_API:Mon Aug 23 11:37:36.999] openxcas_load_module:363 ## PATH = image

/root

NetworkCtrl>> ST_START = (eth0)

../file_control.cpp +881 mode=0 if_name=eth0 if_link_name=eth0

../file_control.cpp +902 __ping_host1=211.216.50.150 __ping_host2=211.216.50.160

NetworkCtrl>> ST_CHECK_CABLE 1 "eth0" un-plug 0 1

last_state = 0

, state = 1

======================================================

disk_total = 0GB, disk_data = 0GB, disk_free = 0GB

system = 0GB, used = 0GB, free = 0GB

======================================================

get_shared_buffer server status key 0x76413C25

++++TIMESTAMP Mon Aug 23 11:37:37 UTC 2010

rdate: Current time matches remote time.

Mon Aug 23 11:37:37 2010

rm: cannot remove `/tmp/.rdate_fail': No such file or directory

[OPENXCAS_API:Mon Aug 23 11:37:38.020] openxcas_load_module_from:219 ## fail to load openxcas module(/EMU/OpenXCAS/image/openxcas.conf), invalid conf file

[OPENXCAS_API:Mon Aug 23 11:37:38.021] openxcas_load_module:363 ## PATH = addons.conf

[OPENXCAS_API:Mon Aug 23 11:37:38.021] openxcas_load_module:392 ## --> skip PATH = addons.conf

[OPENXCAS_API:Mon Aug 23 11:37:38.022] openxcas_load_module:363 ## PATH = cccamd.list

[OPENXCAS_API:Mon Aug 23 11:37:38.022] openxcas_load_module:392 ## --> skip PATH = cccamd.list

[OPENXCAS_API:Mon Aug 23 11:37:38.023] openxcas_load_module:363 ## PATH = oscamCAS

[OPENXCAS_API:Mon Aug 23 11:37:38.025] openxcas_load_module_from:213 ## 2th module(oscamCAS) loaded(valid = 1, argument = None, xcasapi = 1)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.029] start_module:1974 ## Active XCAS module = 1

NETWORK>> ACTION=start

NETWORK>> IF_NAME=eth0

NETWORK>> MODE=0

NETWORK>> IP_DHCP_MODE=0

NETWORK>> IP_ADDR=192.168.1.73

NETWORK>> IP_SUBNET_MASK=255.255.255.0

NETWORK>> IP_GATEWAY=192.168.1.254

NETWORK>> DNS_DHCP_MODE=0

NETWORK>> DNS_IP_ADDR1=192.168.1.254

NETWORK>> DNS_IP_ADDR2=0.0.0.0

NETWORK>> WLAN_ESSID=

NETWORK>> WLAN_ENCYPTION=0

NETWORK>> WLAN_KEY=

NetworkCtrl>> ST_CHECK_CABLE 2 "eth0" OK!!!

last_state = 1

, state = 2

======================================================

disk_total = 0GB, disk_data = 0GB, disk_free = 0GB

system = 0GB, used = 0GB, free = 0GB

======================================================

[OPENXCAS_DTV:Mon Aug 23 11:37:38.419] start_module:1993 ## Run XCAS module = MultiCAS (XCASAPI = 1)

[OPENXCAS_API:Mon Aug 23 11:37:38.421] openxcas_create_channel:34 ## (MultiCAS)

killall: emu_module: no process killed

[OPENXCAS_DTV:Mon Aug 23 11:37:38.781] start_module:2082 ## end

[OPENXCAS_DTV:Mon Aug 23 11:37:38.838] adaptor_thread:1195 ## openxcas_control_thread start (pid = NetworkCtrl>> ST_CHECK_NETWORK = (eth0)

2848)

[OPENXCAS_API:Mon Aug 23 11:37:38.851] openxcas_adaptor_open_channel:221 ## (MultiCAS)

osdep_msq_client_connect : key_request 0x42E0AC94 key_response 0x42E0AC95

[OPENXCAS_DTV:Mon Aug 23 11:37:38.861] adaptor_thread:1311 ## 1th module is connected

[OPENXCAS_DTV:Mon Aug 23 11:37:38.865] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.869] resend_all_info:1109 ## No selected channel(0, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.874] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.877] resend_all_info:1109 ## No selected channel(1, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.883] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.886] resend_all_info:1109 ## No selected channel(2, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.894] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.897] resend_all_info:1109 ## No selected channel(3, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.903] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.906] resend_all_info:1109 ## No selected channel(4, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.912] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.915] resend_all_info:1109 ## No selected channel(5, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.921] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.924] resend_all_info:1109 ## No selected channel(6, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.930] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.934] resend_all_info:1109 ## No selected channel(7, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.938] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.942] resend_all_info:1109 ## No selected channel(8, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.947] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.950] resend_all_info:1109 ## No selected channel(9, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.954] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.958] resend_all_info:1109 ## No selected channel(10, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.963] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.966] resend_all_info:1109 ## No selected channel(11, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.971] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.974] resend_all_info:1109 ## No selected channel(12, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.978] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.983] resend_all_info:1109 ## No selected channel(13, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.987] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.991] resend_all_info:1109 ## No selected channel(14, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:38.996] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:38.999] resend_all_info:1109 ## No selected channel(15, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.005] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.008] resend_all_info:1109 ## No selected channel(16, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.013] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.016] resend_all_info:1109 ## No selected channel(17, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.020] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.026] resend_all_info:1109 ## No selected channel(18, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.030] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.034] resend_all_info:1109 ## No selected channel(19, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.039] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.044] resend_all_info:1109 ## No selected channel(20, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.049] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.054] resend_all_info:1109 ## No selected channel(21, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.060] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.064] resend_all_info:1109 ## No selected channel(22, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.069] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.072] resend_all_info:1109 ## No selected channel(23, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.079] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.083] resend_all_info:1109 ## No selected channel(24, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.088] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.091] resend_all_info:1109 ## No selected channel(25, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.098] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.101] resend_all_info:1109 ## No selected channel(26, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.107] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.111] resend_all_info:1109 ## No selected channel(27, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.117] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.120] resend_all_info:1109 ## No selected channel(28, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.126] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.129] resend_all_info:1109 ## No selected channel(29, 0)

[OPENXCAS_DTV:Mon Aug 23 11:37:39.135] resend_all_info:1080 ##

[OPENXCAS_DTV:Mon Aug 23 11:37:39.138] resend_all_info:1109 ## No selected channel(30, 0)

++++TIMESTAMP Mon Aug 23 11:37:39 UTC 2010

[EMU_MOD] main: Init Key

Open 1st [at EMU] :: ok..

##################################################################

DefaultKeyDate :: Date 20100810

emu_all_ktbl->KeyDate :: Date 20100810

##################################################################

UpdateAllKeyFlash2Ram :: Date 20100810

##################################################################

Viaccess MAX Index = 88

SECA MAX Index = 22

Irdeto MAX Index = 91

NAGRA MAX Index = 100

CrytoWorks MAX Index = 64

Conax MAX Index = 4

BISS MAX Index = 116

##################################################################

Open 1st [at EMU] :: ok.. -/EMU/OpenXCAS/MultiCAS/card_key.bin-

##################################################################

Define_CardKey_date :: Date 20090806

CARD_Key_Data->CardKeyDate :: Date 20090806

##################################################################

>> NAIrd_Act = 0

>> NAEmmU_f = 1 | NAEmmS_f = 1 | NAEmmG_f = 1 || NAAutoRoll = 1 || ActiveBlocker = 0

Open 1st [at EMU] :: ok.. -/EMU/OpenXCAS/MultiCAS/Data9F.bin-

Open 1st [at EMU] :: ok.. -/EMU/OpenXCAS/MultiCAS/splitterdata.bin-

##################################################################

>> Server-IP : 0.0.0.0 [Port = 3000]

>> LS_Mode = 4 | LS_Timeout_for_Recvfrom = 4 | LS_Sequence = 0 || LS_ClientIP_Count = 0

>>>>>>>>>>> Can't open prio-cfg file..

>> Prio_caid_ready = 0

[EMU_MOD] main: Init ecm/emm module

[EMU_MOD] main: Start EMU Module

>>>>>>>>>>> Can't open cardreader.cfg file..

DEVPATH=/dev/sda1

>>>>>>>>>>> line_str[12] : [splitter]

>>>>>>>>>>> line_str[19] : IP = 1.10.100.001

>>>>>>>>>>> line_str[13] : PORT = 3000

>>>>>>>>>>> line_str[22] : DN = Test.dyndns.tst

>>>>>>>>>>> line_str[2] :

>>>>>>>>>>> line_str[31] : CCCAM_NEWCAMD_WORK_TOGETHER = 1

>>>>>>>>>>> CCCAM_NEWCAMD_WORK_TOGETHER = 1

>>>>>>>>>>> Can't open ignore list file..

[OPENXCAS_MOD] main: Start EMU Module(WORKING DIR = /EMU/OpenXCAS)

[OPENXCAS_API:Mon Aug 23 11:37:39.543] load_xcas_control:98 ## loaded controller ID = 0x19760507

[OPENXCAS_API:Mon Aug 23 11:37:39.547] openxcas_open_with_smartcard:331 ## MultiCAS

I2C_GPIO_Create:64

Init semaphore key(0x20080901) mode(384)

PB_CS_CONFIG = 0x000e0040, new = 0x000e0040

Init semaphore key(0x09013406) mode(384)

init application layer

application layer is initailzed

init session layer

session layer is initailzed

init transport layer

transport layer is initailzed

init link layer

link layer is initailzed

init physical layer

physical layer is initailzed

ERROR: RF_I2C_Write:481 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0xca]

ERROR: nim_RF_cmd:1137 nim_RF_cmd Fail!!

ERROR: load_reserved_dat:216 cannot read the reserved_info from '/DISK2/reserved.dat'

Use code default verbosity.

Init semaphore key(0x09013407) mode(384)

Init semaphore key(0x09013405) mode(384)

Init semaphore key(0x09013403) mode(384)

Init semaphore key(0x09013404) mode(384)

Init semaphore key(0x09013402) mode(384)

Init semaphore key(0x09013407) mode(384)

Init semaphore key(0x09013401) mode(384)

PHYS_DRVOpen . Open PHYSICAL low level driver for 1 slot

PHYS_DRVOpen . Open PHYSICAL low level driver for 2 slot

Max Room For CAM Memory(1)

last_state = 2

, state = 3

======================================================

disk_total = 0GB, disk_data = 0GB, disk_free = 0GB

system = 0GB, used = 0GB, free = 0GB

======================================================

[OPENXCAS_API:Mon Aug 23 11:37:40.006] openxcas_create_channel:34 ## (MultiCAS)

[OPENXCAS_API:Mon Aug 23 11:37:40.006] openxcas_module_open_channel:86 ## (MultiCAS)

osdep_msq_server_open : key_request 0x42E0AC94 key_response 0x42E0AC95

osdep_msq_server_open : request msq is already created

osdep_msq_server_open : response msq is already created

[OPENXCAS_API:Mon Aug 23 11:37:40.007] openxcas_adaptor_open_channel:221 ## (MultiCAS)

osdep_msq_client_connect : key_request 0x42E0AC94 key_response 0x42E0AC95

[EMU_MOD] main: Init SmartCard :: Disable_SmartCardReader = 0

msq_receive: id(950277) nowait err(Identifier removed)

IVPal_Init:44 Start IVPal_Init With Dummy

IVHal_Init:33 Start IVHal_Init

IVHalDisp_Init:73 Start IVHalDisp_Init for Real

MMIDisp_Init:360 :: MMIDisp_Init Enter

MMIDisp_InitOSDBuffer:1210 :: MMIDisp_InitOSDBuffer Enter

MMIDisp_InitOSDBuffer:1222 :: MMIDisp_InitOSDBuffer Leave

MMIDisp_InitGFXScaler:1586 :: MMIDisp_InitGFXScaler Enter

MMIDisp_InitScaler:1084 :: MMIDisp_InitScaler Enter

MMIDisp_InitScaler:1135 :: Scaler Setup Default alpha_blending value = 0xff (Alpha0)

MMIDisp_InitScaler:1200 :: MMIDisp_InitScaler Leave

Init for CCcam.........[CCcam_1st_Init=0]

osdep_msq_client_connect : key_request 0x42E0AC94 key_response 0x42E0AC95

MMIDisp_InitGFXScaler:1750 :: MMIDisp_InitGFXScaler Leave

MMIDisp_SetSurfaces:304 :: MMIDisp_SetSurfaces Entered

MMIDisp_SetSurfaces:345 :: MMIDisp_SetSurfaces Leave

MMIDisp_Init:507 :: MMIDisp_Init Leave

IVHalDisp_Init:84 Finished IVHalDisp_Init for Real

Hal/Source/IVHalSys.c:IVHalSys_Init:931 Start IVHalSys_Init

Hal/Source/IVHalSys.c:IVHalSys_Init:944 Finished IVHalSys_Init

IVHal_Init:43 Finished IVHal_Init

[iVPalStr_GetLanguageIdx : 535] Language Index is 0.

IVPal_Init:98 Finished IVPal_Init With Dummy

[DBG] IVPalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp IVMstartup.so

291 ----------------------------------dlopen:0x1003f328

++++TIMESTAMP Mon Aug 23 11:37:40 UTC 2010

NETWORK>> ACTION=alive

NETWORK>> IF_NAME=eth0

NETWORK>> MODE=0

check mount /dev/sda1...

[DBG] MMIDisp_BlendRectangle (0, 0)(0, 0)(0, 0) 960 540

[DBG] IVPalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp IVMspy.so

291 ----------------------------------dlopen:0x1004e598

[DBG] IVPalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp IVMtvmngr.so

291 ----------------------------------dlopen:0x1004cd60

[iVPalSys_GetLanguageIdx : 1299] Language Index is 0.

++++TIMESTAMP Mon Aug 23 11:37:41 UTC 2010

[uTF8(249)] OrgPath = C:/Program/Image/MainMenu/menud_16.bmp

[uTF8(251)] DstPath = /MMP/hd/Program/Image/MainMenu/menud_16.bmp

[Error] ../../Source/IVCFileSys.c(406): Could not open file <IVERR_DEVICE_ERROR [10]>

[uTF8(249)] OrgPath = C:/Program/Image/MainMenu/menus_16.bmp

[uTF8(251)] DstPath = /MMP/hd/Program/Image/MainMenu/menus_16.bmp

[Error] ../../Source/IVCFileSys.c(406): Could not open file <IVERR_DEVICE_ERROR [10]>

[uTF8(249)] OrgPath = C:/Program/Image/MainMenu/num_16.bmp

[uTF8(251)] DstPath = /MMP/hd/Program/Image/MainMenu/num_16.bmp

[Error] ../../Source/IVCFileSys.c(406): Could not open file <IVERR_DEVICE_ERROR [10]>

[uTF8(249)] OrgPath = C:/Program/Image/MainMenu/menud_17.bmp

[uTF8(251)] DstPath = /MMP/hd/Program/Image/MainMenu/menud_17.bmp

[Error] ../../Source/IVCFileSys.c(406): Could not open file <IVERR_DEVICE_ERROR [10]>

[uTF8(249)] OrgPath = C:/Program/Image/MainMenu/menus_17.bmp

[uTF8(251)] DstPath = /MMP/hd/Program/Image/MainMenu/menus_17.bmp

[Error] ../../Source/IVCFileSys.c(406): Could not open file <IVERR_DEVICE_ERROR [10]>

[uTF8(249)] OrgPath = C:/Program/Image/MainMenu/num_17.bmp

[uTF8(251)] DstPath = /MMP/hd/Program/Image/MainMenu/num_17.bmp

[Error] ../../Source/IVCFileSys.c(406): Could not open file <IVERR_DEVICE_ERROR [10]>

[iVPalSys_GetLanguageIdx : 1299] Language Index is 0.

===> Starting user defined script

main:690 Ready to UI Working... Done

===> Mounting freenas & slug box

AZBox[~]$ Wait pid end here!

usb script end.............

wireless start.............

wireless end.............

usb space = 966590464

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

[Core_Common_Event :1264] event->cmd 0x1

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

[DBG] IVPalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp IVMtv.so

291 ----------------------------------dlopen:0x10249d58

[iVPalSys_GetLanguageIdx : 1299] Language Index is 0.

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

Error opening file Err = 9 Filename = /MMP/hd/Program/Image/TV/rec_player/st_rx64.png

Error opening file Err = 9 Filename = /MMP/hd/Program/Image/TV/rec_player/st_wx64.png

[zFileMountInfo] request FileMount info to File control <<

[Core_Common_Event :1264] event->cmd 0x2e

WARNING: BISS_load_keys:197 fail to open BISS conf file(/EMU/BISS/biss.conf)

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

++++TIMESTAMP Mon Aug 23 11:37:43 UTC 2010

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

file msg :116

[Core_Common_Event :1264] event->cmd 0xc6

-------------->[ 9058] ELAPSED TIME: 35

342 ----------------------------------dlclose:0x1003f328, return : 0

[Core_Common_Event :1264] event->cmd 0xe9

[Core_Common_Event :1264] event->cmd 0x62

[HXUTIL:MSG] Init semaphore key(0x90000510) mode(384)

hx_iptv_alloc_common_queue: the reference count of common queue is 1

hx_iptv_alloc_common_queue: the reference count of common queue is 2

[HXIPTV:MSG] [HTTP_STREAM] download_thread start (pid = 2910)

[HXUTIL:WAN] 0x00000001, 1

[HXIPTV:MSG] XXXXXX

Load file /DISK2/EPG_SERVER.dat

[HDD][/MMP]

[HDD][/PLUGINS]

[HDD][/DISK2]

[HDD][/Download]

[uSB1-1][/DATA/usb1/sda1]

FILE_CONTROL [FILE SECTION]>> OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

++++TIMESTAMP Mon Aug 23 11:37:44 UTC 2010

[load_epg_addr_conf:152]

[HXIPTV:MSG] [load_epg_addr_conf:165]Iptv.data_addr: kartina.pristavka.de / kartina.pristavka.de

CCcam :: connect[0] ret = 0

CCcam :: login succeed[0]

CCcam_login :: ret = 1 || serverx = 0

>> Skip CCcam_login By Disable CCCAM_Active[01]=0

[HXIPTV:MSG] epg_server : (kartina.pristavka.de:8083)

>> Skip CCcam_login By Disable CCCAM_Active[02]=0

WARNING: this production is not masterbox

WARNING: lock_tuner_use_channel:1522 0_Fail to find antenna(-1) in dtv(0)

XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX

Comp : Jul 30 2010 17:18:04

Ver : SDecoderCtrl.so, NormalPushCtrl 2.8.5.1.1961

XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX

g_msq_client_connect : key_request 0x59075AB key_response 0x59075AC

[DVB_S/diseqc.c],[1030] ->> DISEQC_Positioner iPosCmd 16, iPosition 19,

##########################################################

=====> STV0903_SendDiseqcCmd iCmdLength 4, 0xE0

>> Skip CCcam_login By Disable CCCAM_Active[03]=0

Starting MMAP

End MMAP 0x0x2c83e000

Starting MMAP

End MMAP 0x0x2aab5000

Starting MMAP

End MMAP 0x0x2ca3e000

Starting MMAP

End MMAP 0x0x2aad7000

Starting MMAP

End MMAP 0x0x2aadc000

Starting MMAP

End MMAP 0x0x2aae1000

Starting MMAP

##########################################################

End MMAP 0x0x2aae6000

>> Skip CCcam_login By Disable CCCAM_Active[04]=0

Starting MMAP

End MMAP 0x0x2ca5f000

Starting MMAP

End MMAP 0x0x2ca64000

Starting MMAP

End MMAP 0x0x2ca69000

Starting MMAP

End MMAP 0x0x2ca6e000

Starting MMAP

End MMAP 0x0x2ca73000

Starting MMAP

End MMAP 0x0x2ca78000

Starting MMAP

End MMAP 0x0x2ca7d000

4096

>> Skip CCcam_login By Disable CCCAM_Active[05]=0

>> Skip CCcam_login By Disable CCCAM_Active[06]=0

>> Skip CCcam_login By Disable CCCAM_Active[07]=0

>> Skip CCcam_login By Disable CCCAM_Active[08]=0

4096

>> Skip CCcam_login By Disable CCCAM_Active[09]=0

>> Skip CCcam_login By Disable CCCAM_Active[10]=0

>> Skip CCcam_login By Disable CCCAM_Active[11]=0

>> Skip CCcam_login By Disable CCCAM_Active[12]=0

>> Skip CCcam_login By Disable CCCAM_Active[13]=0

>> Skip CCcam_login By Disable CCCAM_Active[14]=0

>> Skip CCcam_login By Disable CCCAM_Active[15]=0

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

++++TIMESTAMP Mon Aug 23 11:37:46 UTC 2010

STV0903_SignalQuality 12.0dB

ERROR: load_record_dat:269 failed, /DATA/hdb1/recordfile is not directory

ERROR: serv_put_record_list:4328 cannot load recordlist at /DATA/hdb1/recordfile, forced list count = 0

ERROR: serv_put_record_list:4360 ERROR.. with serv_put_record_list, setup count = 0

[Core_Common_Event :1264] event->cmd 0x6c

tuner_count = 4

---------------------------------------------------

CRTVSetup Recv MSG(7)

---------------------------------------------------

vol = 0x8facd62(25)

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

[EMU_CTRL] main: OK got MyIP :: Init_CCcam ret=1

newcamd: All NEWCAMD_InActive to Connect to server

[EMU_CTRL] main: OK got MyIP :: newcamd_all_log_in ret=1

STV0903_SignalQuality 12.0dB

-->Start init_sc_main()...

init_sc_main::Default stack size = 2093056

init_sc_main::Creating threads with stack size = 3000000 bytes

sc_card[0].opened = 0xFFFFFFFF[-1]

sc_main_thread: stack size = 3000000 bytes

-->sc_main_Tid = 0x5015 [20501]

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x07d4

ERROR: check_sections:2320 0pid_filter_inverse_version_mask

[EMU_MOD] :: select channel with change latitude(sequence = 2) [latitude:192 | F:12344000 | P:0]

################################################################

Description : NEW STREAM INFO (Recording = 0, Live = 1, Lock = 1)

Channel Info : 0000 (VPID = 0x0200, APID = 0x0050)

Slot Info : TSI = 1, Fixed = X, Using = X

Retry Count : SLOT A = 0, SLOT B = 0

################################################################

[EMU_MOD] :: select channel[stream_id:0](sequence = 2)

[EMU_MOD] :: Satlatitude = 192 | frequency = 12344000 | polarization = 0

[EMU_MOD] :: v_pid = 0x200[512] | a_pid = 0x50[80] | d_pid = 20

[EMU_MOD] main: START_PMT_ECM(sequence = 2)

Make_CAS_Info>> SteamID = 0

Check PMT Global..

1.CaSystemId: 622 06

1st ETC[CaSystemId:0x622] CurPP: 02e2 || EcmPid: 0565

INSERT PMT DB || CAS ID 622 | ECM 565 | StreamType 0 | nPMT[0].PMTCasIDNum 1

Check PMT component..

2. -> StreamType = 0x02

2.CaSystemId:100

2nd SECA CurPP:006a

2nd temp:15(0f) iPMT[12+6]:11

2nd SECA ES mode: CurPP :006a || EcmPid: 0643

INSERT PMT DB || CAS ID 100 | ECM 643 | StreamType 2 | nPMT[0].PMTCasIDNum 2

2.CaSystemId:100

2nd SECA CurPP:006a

2nd temp:15(0f) iPMT[25+6]:11

2nd SECA ES mode: CurPP :006a || EcmPid: 070b

INSERT PMT DB || CAS ID 100 | ECM 70b | StreamType 2 | nPMT[0].PMTCasIDNum 3

2. -> StreamType = 0x04

2.CaSystemId:100

2nd SECA CurPP:006a

2nd temp:15(0f) iPMT[43+6]:11

2nd SECA ES mode: CurPP :006a || EcmPid: 0643

2.CaSystemId:100

2nd SECA CurPP:006a

2nd temp:15(0f) iPMT[56+6]:11

2nd SECA ES mode: CurPP :006a || EcmPid: 070b

2. -> StreamType = 0x06

Prio_caid_ready 0> nPMT[0].PMTCasID[0].Priority = 0 [caid=0x0100]

Prio_caid_ready 0> nPMT[0].PMTCasID[1].Priority = 0 [caid=0x0100]

Prio_caid_ready 0> nPMT[0].PMTCasID[2].Priority = 30 [caid=0x0622]

Final> nPMT[0].PMTCasID[0].Priority = 0 [caid=0x0100]

Final> nPMT[0].PMTCasID[1].Priority = 0 [caid=0x0100]

Final> nPMT[0].PMTCasID[2].Priority = 30 [caid=0x0622]

[EMU_MOD] main Request_CAS_id[0] = 0x0

[EMU_MOD] main >>> biss_act = 0

[EMU_MOD] Take_ecm_pid_for_emu: SteamID = 0, Req_CAS_id = 0x0, Req_Prov_id = 0x0, Act_CA_CNT = 0 [PMTCasIDNum:3]

[EMU_MOD] main >>> ecm_pid = 0x70b

Current Stream CA System ID[0]: 0x0622

Current Stream CA System ID[1]: 0x0100

NO LOCKED SLOT

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[EMU_CTRL] main: START_CAT_EMM [stream_id = 0]

[EMU_CTRL] main: START_CAT_EMM :: New CAT.. [cat_ret:1]

>>>>>[cat_arrive:0] last_cat_msg_size = 0x65[101]

[Core_Common_Event :1264] event->cmd 0xb5

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=1 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:37:47.544 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[Core_Common_Event :1264] event->cmd 0x24

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xffffffff || KMP = 0

[Core_Common_Event :1264] event->cmd 0xf5

WARNING: this production is not masterbox

[Core_Common_Event :1264] event->cmd 0xca

[Core_Common_Event :1264] event->cmd 0xc9

CCcam :: newcw[0] = 1 || timeout_count = 0.225

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

33

HEXDUMP: [QWDTV] Got Odd Key

E5

[Core_Common_Event :1264] event->cmd 0x8b

[Core_Common_Event :1264] event->cmd 0xce

[Core_Common_Event :1264] event->cmd 0x8c

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

[Core_Common_Event :1264] event->cmd 0x7

[Core_Common_Event :1264] event->cmd 0x51

[Core_Common_Event :1264] event->cmd 0xac

++++TIMESTAMP Mon Aug 23 11:37:48 UTC 2010

[Core_Common_Event :1264] event->cmd 0xac

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

++++TIMESTAMP Mon Aug 23 11:37:49 UTC 2010

++++TIMESTAMP Mon Aug 23 11:37:50 UTC 2010

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

++++TIMESTAMP Mon Aug 23 11:37:52 UTC 2010

++++TIMESTAMP Mon Aug 23 11:37:53 UTC 2010

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=0 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:37:53.603 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xa4 || KMP = 754

CCcam :: newcw[0] = 1 || timeout_count = 0.215

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

33

HEXDUMP: [QWDTV] Got Odd Key

DC

++++TIMESTAMP Mon Aug 23 11:37:54 UTC 2010

++++TIMESTAMP Mon Aug 23 11:37:55 UTC 2010

StcComp: current dist_to_ref=0 ms (0) delta_mean=0 sf=0 df=0 f=4% 278%

StcComp: init

StcComp: waiting 400ms before starting delta_ref measure

StcComp: current dist_to_ref=0 ms (0) delta_mean=0 sf=0 df=0 f=33% 290%

++++TIMESTAMP Mon Aug 23 11:37:56 UTC 2010

StcComp: current dist_to_ref=0 ms (3) delta_mean=3 sf=0 df=0 f=47% 540%

++++TIMESTAMP Mon Aug 23 11:37:57 UTC 2010

StcComp: current dist_to_ref=0 ms (8) delta_mean=8 sf=0 df=0 f=17% 563%

++++TIMESTAMP Mon Aug 23 11:37:59 UTC 2010

++++TIMESTAMP Mon Aug 23 11:38:00 UTC 2010

StcComp: current dist_to_ref=0 ms (13) delta_mean=13 sf=0 df=0 f=24% 536%

++++TIMESTAMP Mon Aug 23 11:38:01 UTC 2010

StcComp: current dist_to_ref=0 ms (17) delta_mean=17 sf=0 df=0 f=42% 515%

++++TIMESTAMP Mon Aug 23 11:38:02 UTC 2010

StcComp: current dist_to_ref=0 ms (20) delta_mean=20 sf=0 df=0 f=59% 563%

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=0 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:38:03.604 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xa4 || KMP = 753

++++TIMESTAMP Mon Aug 23 11:38:03 UTC 2010

CCcam :: newcw[0] = 1 || timeout_count = 0.310

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

F1

HEXDUMP: [QWDTV] Got Odd Key

DC

++++TIMESTAMP Mon Aug 23 11:38:04 UTC 2010

StcComp: current dist_to_ref=0 ms (24) delta_mean=24 sf=0 df=0 f=47% 560%

++++TIMESTAMP Mon Aug 23 11:38:06 UTC 2010

StcComp: current dist_to_ref=0 ms (26) delta_mean=26 sf=0 df=0 f=50% 544%

++++TIMESTAMP Mon Aug 23 11:38:07 UTC 2010

StcComp: current dist_to_ref=0 ms (29) delta_mean=29 sf=0 df=0 f=42% 545%

++++TIMESTAMP Mon Aug 23 11:38:08 UTC 2010

++++TIMESTAMP Mon Aug 23 11:38:09 UTC 2010

StcComp: current dist_to_ref=0 ms (32) delta_mean=32 sf=0 df=0 f=44% 538%

++++TIMESTAMP Mon Aug 23 11:38:10 UTC 2010

StcComp: current dist_to_ref=0 ms (36) delta_mean=36 sf=0 df=0 f=24% 516%

++++TIMESTAMP Mon Aug 23 11:38:11 UTC 2010

StcComp: current dist_to_ref=0 ms (39) delta_mean=39 sf=0 df=0 f=44% 532%

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

++++TIMESTAMP Mon Aug 23 11:38:13 UTC 2010

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=0 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:38:13.611 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xa4 || KMP = 752

CCcam :: newcw[0] = 1 || timeout_count = 0.235

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

F1

HEXDUMP: [QWDTV] Got Odd Key

39

StcComp: current dist_to_ref=0 ms (43) delta_mean=43 sf=0 df=0 f=22% 572%

++++TIMESTAMP Mon Aug 23 11:38:14 UTC 2010

++++TIMESTAMP Mon Aug 23 11:38:15 UTC 2010

StcComp: current dist_to_ref=0 ms (46) delta_mean=46 sf=0 df=0 f=15% 526%

++++++ZAP to kanaal z using arrow down+++++++

[Core_Common_Event :1264] event->cmd 0x57

[Core_Common_Event :1264] event->cmd 0xbf

[Core_Common_Event :1264] event->cmd 0x67

++++TIMESTAMP Mon Aug 23 11:38:16 UTC 2010

STV0903_SignalQuality 12.0dB

[Core_Common_Event :1264] event->cmd 0x67

StcComp: current dist_to_ref=0 ms (48) delta_mean=48 sf=0 df=0 f=50% 522%

[Core_Common_Event :1264] event->cmd 0x6b

[EMU_CTRL] main: STOP_PMT_ECM [stream_id=0]

[EMU_CTRL] main: >>>>>> STOP_CAT_EMM [stream_id = 0] <<<<<<

################################################################

Description : CLEAR STREAM INFO FOR LIVE (Recording = 0, Live = 1, Lock = 1)

Channel Info : 0000 (VPID = 0x0200, APID = 0x0050)

Slot Info : TSI = 1, Fixed = X, Using = X

Retry Count : SLOT A = 0, SLOT B = 0

CA System ID : 0x622

CA System ID : 0x100

################################################################

NO SWITCHING CONTROL(BYPASS)

TS SWITCHING at SINGLEMODE REG: 0x00

TS SWITCHING at TWINMODE REG: 0x98

++++TIMESTAMP Mon Aug 23 11:38:17 UTC 2010

NO SLOT ENALBLES STREAM

NO LOCKED SLOT

No Enable Module

===============================================================================================

1. DRAM0 Info

total_alloc_count = 0

total_alloc_size = 0

-----------------------------------------------------------------------------------------------

===============================================================================================

2. DRAM1 Info

total_alloc_count = 0

total_alloc_size = 0

-----------------------------------------------------------------------------------------------

===============================================================================================

ERROR: lock_status_by_ant:3122 tuner_id(-1) index(-1) is over max number(0)

ERROR: HXGetClockInfoEx:744 0_fail to get clock info task, TaskState None

STV0903_SignalQuality 12.0dB

[Core_Common_Event :1264] event->cmd 0xbf

[Core_Common_Event :1264] event->cmd 0x67

WARNING: lock_tuner_use_channel:1522 0_Fail to find antenna(-1) in dtv(0)

Starting MMAP

End MMAP 0x0x2c83e000

Starting MMAP

End MMAP 0x0x2aab5000

[DVB_S/diseqc.c],[1030] ->> DISEQC_Positioner iPosCmd 16, iPosition 19,

##########################################################

=====> STV0903_SendDiseqcCmd iCmdLength 4, 0xE0

Starting MMAP

End MMAP 0x0x2ca3e000

Starting MMAP

End MMAP 0x0x2aad7000

Starting MMAP

End MMAP 0x0x2aadc000

Starting MMAP

End MMAP 0x0x2aae1000

Starting MMAP

End MMAP 0x0x2aae6000

Starting MMAP

End MMAP 0x0x2ca5f000

Starting MMAP

End MMAP 0x0x2ca64000

Starting MMAP

End MMAP 0x0x2ca69000

Starting MMAP

End MMAP 0x0x2ca6e000

Starting MMAP

End MMAP 0x0x2ca73000

Starting MMAP

End MMAP 0x0x2ca78000

Starting MMAP

End MMAP 0x0x2ca7d000

4096

##########################################################

4096

++++TIMESTAMP Mon Aug 23 11:38:18 UTC 2010

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

semop: Resource temporarily unavailable

[Core_Common_Event :1264] event->cmd 0xac

WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x31f8

ERROR: check_sections:2320 0pid_filter_inverse_version_mask

[EMU_MOD] :: select channel with change latitude(sequence = 3) [latitude:192 | F:12722000 | P:0]

[EMU_MOD] :: select channel[stream_id:0](sequence = 3)

[EMU_MOD] :: Satlatitude = 192 | frequency = 12722000 | polarization = 0

[EMU_MOD] :: v_pid = 0x397[919] | a_pid = 0x398[920] | d_pid = ffff

[EMU_MOD] main: START_PMT_ECM(sequence = 3)

Make_CAS_Info>> SteamID = 0

Check PMT Global..

Check PMT component..

2. -> StreamType = 0x02

2.CaSystemId:100

2nd SECA CurPP:006c

2nd temp:15(0f) iPMT[0c+6]:11

2nd SECA ES mode: CurPP :006c || EcmPid: 0730

INSERT PMT DB || CAS ID 100 | ECM 730 | StreamType 2 | nPMT[0].PMTCasIDNum 1

2.CaSystemId:100

2nd SECA CurPP:006d

2nd temp:15(0f) iPMT[1f+6]:11

2nd SECA ES mode: CurPP :006d || EcmPid: 073a

INSERT PMT DB || CAS ID 100 | ECM 73a | StreamType 2 | nPMT[0].PMTCasIDNum 2

2.CaSystemId:500

2nd Via CurPP:042500 || EcmPid: 05a0

INSERT PMT DB || CAS ID 500 | ECM 5a0 | StreamType 2 | nPMT[0].PMTCasIDNum 3

2. -> StreamType = 0x03

2.CaSystemId:100

2nd SECA CurPP:006c

2nd temp:15(0f) iPMT[4e+6]:11

2nd SECA ES mode: CurPP :006c || EcmPid: 0730

2.CaSystemId:100

2nd SECA CurPP:006d

2nd temp:15(0f) iPMT[61+6]:11

2nd SECA ES mode: CurPP :006d || EcmPid: 073a

2.CaSystemId:500

2nd Via CurPP:042500 || EcmPid: 05a0

Prio_caid_ready 0> nPMT[0].PMTCasID[0].Priority = 0 [caid=0x0100]

Prio_caid_ready 0> nPMT[0].PMTCasID[1].Priority = 0 [caid=0x0100]

Prio_caid_ready 0> nPMT[0].PMTCasID[2].Priority = 20 [caid=0x0500]

Final> nPMT[0].PMTCasID[0].Priority = 0 [caid=0x0100]

Final> nPMT[0].PMTCasID[1].Priority = 0 [caid=0x0100]

Final> nPMT[0].PMTCasID[2].Priority = 20 [caid=0x0500]

[EMU_MOD] main Request_CAS_id[0] = 0x0

[EMU_MOD] main >>> biss_act = 0

[EMU_MOD] Take_ecm_pid_for_emu: SteamID = 0, Req_CAS_id = 0x0, Req_Prov_id = 0x0, Act_CA_CNT = 0 [PMTCasIDNum:3]

[EMU_MOD] main >>> ecm_pid = 0x73a

################################################################

Description : NEW STREAM INFO (Recording = 0, Live = 1, Lock = 1)

Channel Info : 0000 (VPID = 0x0397, APID = 0x0398)

Slot Info : TSI = 1, Fixed = X, Using = X

Retry Count : SLOT A = 0, SLOT B = 0

################################################################

Current Stream CA System ID[0]: 0x0100

Current Stream CA System ID[1]: 0x0500

NO LOCKED SLOT

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[EMU_CTRL] main: START_CAT_EMM [stream_id = 0]

[EMU_CTRL] main: START_CAT_EMM :: New CAT.. [cat_ret:1]

>>>>>[cat_arrive:0] last_cat_msg_size = 0x43[67]

[Core_Common_Event :1264] event->cmd 0xac

WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x31f8

ERROR: check_sections:2320 0pid_filter_inverse_version_mask

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=1 | CAID=0x0100>>>

[Core_Common_Event :1264] event->cmd 0x57

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:38:20.144 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xffffffff || KMP = 751

CCcam :: [0]Not Support for this ECM [tc=0]

[EMU_MOD] Emu_Act_ECM: do_ecm = 0 || ca_system_id = 0x100 [cipher_index=0x00]

[EMU_MOD] Emu_EnableMode: SteamID = 0 Act_CA_Count = 0 [Request_CAS_id = 0x0]

[EMU_MOD] Emu_EnableMode: ----- nPMT[steamID:0].PMTCasIDNum = 3 -----

[EMU_MOD] Emu_EnableMode: >> Act_CA_Count = 1 || nPMT[steamID].PMTCasIDNum = 3

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[Core_Common_Event :1264] event->cmd 0xbf

[EMU_MOD] main >>> biss_act = 0

[EMU_MOD] Take_ecm_pid_for_emu: SteamID = 0, Req_CAS_id = 0x0, Req_Prov_id = 0x0, Act_CA_CNT = 1 [PMTCasIDNum:3]

[EMU_MOD] main >>> ecm_pid = 0x730

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=0 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:38:20.287 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xffffffff || KMP = 751

++++TIMESTAMP Mon Aug 23 11:38:20 UTC 2010

CCcam :: newcw[0] = 1 || timeout_count = 0.290

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

2E

HEXDUMP: [QWDTV] Got Odd Key

1F

STV0903_SignalQuality 10.5dB

++++TIMESTAMP Mon Aug 23 11:38:21 UTC 2010

STV0903_SignalQuality 10.3dB

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=0 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:38:22.576 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xa3 || KMP = 750

++++TIMESTAMP Mon Aug 23 11:38:22 UTC 2010

CCcam :: newcw[0] = 1 || timeout_count = 0.255

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

2E

HEXDUMP: [QWDTV] Got Odd Key

31

STV0903_SignalQuality 10.7dB

++++TIMESTAMP Mon Aug 23 11:38:23 UTC 2010

STV0903_SignalQuality 10.3dB

[Core_Common_Event :1264] event->cmd 0xbf

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

FILE_CONTROL CONF_INFO << IN

FILE_CONTROL CONF_INFO << OUT

++++TIMESTAMP Mon Aug 23 11:38:24 UTC 2010

[HwDemuxPlay:1960] 0_start Demux

hardware demux is aleady started

++++TIMESTAMP Mon Aug 23 11:38:26 UTC 2010

++++TIMESTAMP Mon Aug 23 11:38:27 UTC 2010

++++TIMESTAMP Mon Aug 23 11:38:28 UTC 2010

StcComp: current dist_to_ref=0 ms (0) delta_mean=0 sf=0 df=0 f=5% 214%

StcComp: init

StcComp: waiting 400ms before starting delta_ref measure

StcComp: current dist_to_ref=0 ms (0) delta_mean=0 sf=0 df=0 f=3% 209%

++++TIMESTAMP Mon Aug 23 11:38:29 UTC 2010

StcComp: current dist_to_ref=0 ms (5) delta_mean=5 sf=0 df=0 f=41% 404%

++++TIMESTAMP Mon Aug 23 11:38:30 UTC 2010

++++TIMESTAMP Mon Aug 23 11:38:31 UTC 2010

StcComp: current dist_to_ref=0 ms (11) delta_mean=11 sf=0 df=0 f=25% 392%

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=0 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:38:32.770 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xa3 || KMP = 749

CCcam :: newcw[0] = 1 || timeout_count = 0.245

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

A8

HEXDUMP: [QWDTV] Got Odd Key

31

++++TIMESTAMP Mon Aug 23 11:38:33 UTC 2010

StcComp: current dist_to_ref=0 ms (15) delta_mean=15 sf=0 df=0 f=30% 394%

++++TIMESTAMP Mon Aug 23 11:38:34 UTC 2010

StcComp: current dist_to_ref=0 ms (20) delta_mean=20 sf=0 df=0 f=25% 407%

++++TIMESTAMP Mon Aug 23 11:38:35 UTC 2010

StcComp: current dist_to_ref=0 ms (24) delta_mean=24 sf=0 df=0 f=27% 387%

++++TIMESTAMP Mon Aug 23 11:38:36 UTC 2010

++++TIMESTAMP Mon Aug 23 11:38:37 UTC 2010

StcComp: current dist_to_ref=0 ms (28) delta_mean=28 sf=0 df=0 f=33% 408%

++++TIMESTAMP Mon Aug 23 11:38:39 UTC 2010

StcComp: current dist_to_ref=0 ms (32) delta_mean=32 sf=0 df=0 f=18% 401%

++++TIMESTAMP Mon Aug 23 11:38:40 UTC 2010

StcComp: current dist_to_ref=0 ms (35) delta_mean=35 sf=0 df=0 f=16% 397%

++++TIMESTAMP Mon Aug 23 11:38:41 UTC 2010

[EMU_MOD] Emu_CallBackECM: Emu_CallBackECM : Emu_No = 0 <<< Channel_change=0 | CAID=0x0100>>>

[EMU_MOD]-->EMM_ECM_ACT is 0

<<< OD :: Module Name MultiCAS | Ver 1.62E-A11[with LS+CCcam+NewCamd] | Date [TG2] 2010-08-10 >>>

[EMU_MOD] Emu_Act_ECM>> Mon Aug 23 11:38:42.606 <SteamID:0> emu_card_mode = 0[emu_card_mode_emm=0]

[EMU_MOD] biss_act = 0 || ca_system_id = 0x0100

CCcam :: [steamID:0] ShareInfo_count[0] = 14 || CaSystemId = 0x0100 || VSID = 0xa3 || KMP = 748

StcComp: current dist_to_ref=0 ms (38) delta_mean=38 sf=0 df=0 f=30% 391%

++++TIMESTAMP Mon Aug 23 11:38:42 UTC 2010

CCcam :: newcw[0] = 1 || timeout_count = 0.215

[EMU_MOD] Emu_Act_ECM: do_ecm = 1 || ca_system_id = 0x100 [cipher_index=0x00]

>>>>>[cat_arrive is 1] Now Try to act about CAT...

[EMU_CTRL] main: ncamd_CollectCAT ret = 0 ...

HEXDUMP: [QWDTV] Got Even Key

A8

HEXDUMP: [QWDTV] Got Odd Key

15

++++TIMESTAMP Mon Aug 23 11:38:43 UTC 2010

StcComp: current dist_to_ref=0 ms (41) delta_mean=41 sf=0 df=0 f=42% 398%

++++TIMESTAMP Mon Aug 23 11:38:45 UTC 2010

chrisch · 24 augustus 2010

Interessante studie! Zouden ze @ opensat hier ook mee bezig zijn?

basix · 24 augustus 2010

heb het alleszins gepost op het forum van opensat, maar helaas is het bericht niet goedgekeurd omdat het niet zou voldoen aan hun forumregels.

heb het op het Nederlandse forum ook al gezet, en nu hopen dat er hier op gereageerd wordt.

Zal ook eens op dezelfde manier een log posten voor het zappen tussen fta zenders.

wian · 26 augustus 2010

Heb geen AZbox (meer) om te testen, maar deze regel lijkt me wel relevant:

>>>>>>>>>>> Can't open ignore list file..

Als je alle CA IDs eruit filter die niet gebruikt worden, gaat het zappen wellicht sneller.

basix · 30 augustus 2010

zoals beloofd, voor fta:

Test setup:

Firmware version azbox-0.9.5020, clean install

Start op BVN op Astra1, zap naar ZDF, terug naar BVN, en weer terug naar ZDF (gemarkeerd met ++++ZAP, heb dit in de terminal geplakt gelijktijdig met de zap via ab)

Geen CAS enz. geinstalleerd.

Wat ik gemerkt heb is dat de zap actie pas na 2 tot 4 seconden gevolgd wordt door een frequentieverandering in de tuner ipv onmiddellijk. Zap op 16:19:19, frequentie verandering op 16:19:23, een rare fout, een diseq commando (eigenlijk overbodig omdat we op dezelfde sat zitten). Op 16:19:25 eindelijk beeld.

Hierbij kunnen we dus vaststellen dat hetzelfde probleem voor FTA zenders aanwezig is.

++++TIMESTAMP Mon Aug 30 16:18:16 UTC 2010

[Debug Mode] Begin of start.sh ###

Display mode: FND

mkdir: Cannot create directory `/DATA/meta/': File exists

Display mode: FND

interfaces ok

++++TIMESTAMP Mon Aug 30 16:18:17 UTC 2010

===> Stopping vsftpd...

killall: vsftpd: no process killed

===> Stopping services...

===> Stopping user defined script

===> Stopping mount

umount: /DATA/movie: Invalid argument

killall: SDecoderServer: no process killed

killall: event_dispatcher: no process killed

killall: app_control: no process killed

killall: ir_control: no process killed

killall: file_control: no process killed

killall: rssfeed: no process killed

killall: Polaris: no process killed

killall: browser_control: no process killed

killall: emu_module: no process killed

killall: sample_module: no process killed

++++TIMESTAMP Mon Aug 30 16:18:18 UTC 2010

killall: E_TUNER.A: no process killed

killall: SeleneTVCore: no process killed

killall: CRTVCore: no process killed

killall: TEMSControl: no process killed

killall: TVPortal_Player: no process killed

killall: TVPortalCore: no process killed

killall: youtube_player: no process killed

killall: radio_test: no process killed

killall: detector_test: no process killed

killall: boot_controler: no process killed

killall: update_agent: no process killed

killall: inadyn-advanced: no process killed

killall: DStar: no process killed

killall: EStar: no process killed

killall: JPlugins: no process killed

killall: nmbd: no process killed

killall: smbd: no process killed

/MMP/usr/bin/fusermount: failed to unmount /DATA/upnp: No such file or directory

/MMP/usr/bin/K.sh: line 110: /MMP/etc/init.d/ushare: No such file or directory

++++TIMESTAMP Mon Aug 30 16:18:19 UTC 2010

++++TIMESTAMP Mon Aug 30 16:18:20 UTC 2010

++++TIMESTAMP Mon Aug 30 16:18:21 UTC 2010

++++TIMESTAMP Mon Aug 30 16:18:22 UTC 2010

++++TIMESTAMP Mon Aug 30 16:18:23 UTC 2010

===> Stopping vsftpd...

killall: vsftpd: no process killed

===> Stopping services...

===> Stopping user defined script

===> Stopping mount

umount: /DATA/movie: Invalid argument

killall: JIPC_Server: no process killed

killall: SDecoderServer: no process killed

killall: event_dispatcher: no process killed

killall: app_control: no process killed

killall: ir_control: no process killed

killall: file_control: no process killed

++++TIMESTAMP Mon Aug 30 16:18:24 UTC 2010

killall: rssfeed: no process killed

killall: Polaris: no process killed

killall: browser_control: no process killed

killall: emu_module: no process killed

killall: sample_module: no process killed

killall: CRTVSetup: no process killed

killall: E_TUNER.A: no process killed

killall: SeleneTVCore: no process killed

killall: CRTVCore: no process killed

killall: TEMSControl: no process killed

killall: TVPortal_Player: no process killed

killall: TVPortalCore: no process killed

killall: youtube_player: no process killed

killall: radio_test: no process killed

killall: detector_test: no process killed

killall: boot_controler: no process killed

killall: update_agent: no process killed

killall: inadyn-advanced: no process killed

killall: DStar: no process killed

killall: EStar: no process killed

++++TIMESTAMP Mon Aug 30 16:18:25 UTC 2010

killall: JPlugins: no process killed

killall: nmbd: no process killed

killall: smbd: no process killed

/MMP/usr/bin/fusermount: failed to unmount /DATA/upnp: No such file or directory

/MMP/usr/bin/K.sh: line 110: /MMP/etc/init.d/ushare: No such file or directory

Display mode: FND

Using /MMP/modules/2.6.15/llad.ko

Using /MMP/modules/2.6.15/em8xxx.ko

++++TIMESTAMP Mon Aug 30 16:18:26 UTC 2010

Using /MMP/modules/2.6.15/sckernel.ko

Using /MMP/modules/2.6.15/usbserial.ko

Using /MMP/modules/2.6.15/ftdi_sio.ko

Using /MMP/modules/2.6.15/cdrom.ko

Using /MMP/modules/2.6.15/sr_mod.ko

Using /MMP/modules/2.6.15/cdfs.ko

Using /MMP/modules/2.6.15/fuse.ko

insmod: cannot insert `/MMP/modules/2.6.15/fuse.ko': Success (2): Success

Using /MMP/modules/2.6.15/rt61.ko

insmod: cannot insert `/MMP/modules/2.6.15/rt61.ko': Success (17): Success

Using /MMP/modules/2.6.15/rt3070sta.ko

insmod: cannot insert `/MMP/modules/2.6.15/rt3070sta.ko': Success (17): Success

insmod: rt2500.ko: no module by that name found

mknod: /dev/scard: File exists

mknod: /dev/fuse: File exists

Checking Security.

Init semaphore key(0x20080901) mode(384)

co164_send: call, devaddr = 0x28, subaddr = 0x00, size = 8

SEND DATA1 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

SEND DATA2 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

co164_recv: call, devaddr = 0x28 size = 8

RECV DATA = 0xf6 0x17 0x46 0xb8 0x00 0x4c 0x17 0xac

hx_security_check: SECURITY_CHECK_RESULT = 1 (rand = 16807)

++++TIMESTAMP Mon Aug 30 16:18:27 UTC 2010

++++TIMESTAMP Mon Aug 30 16:18:29 UTC 2010

#######################################################

# Tuner Detection - TD210 & TD9000A is only supported #

# Tuner Combination Type : TWIN #

#######################################################

TunerAPI nim_create_adaptor:472 detection_mode=1, combination_type=1

Init semaphore key(0x20090729) mode(384)

Init semaphore key(0x20090730) mode(384)

Init semaphore key(0x20080901) mode(384)

[TUNER_DETECTION] 1th TUNER Checking...

TunerAPI nim_open_adaptor:618 tuner_combination_type == TUNER_COMBINATION_TWIN

TunerAPI SH_findEmptyI2CBUS:74 Empty I2C bus is found at 0

TunerAPI InitTuner:109 Inializing TUNER_DVB-S(0) combination_type=1

===================TunerID: 0=======================

TunerAPI TunerReset:256 Tuner(0) Reset

TunerAPI TunerReset:259 Setting Tuner(0) RESET GPIO(30) to LOW

TunerAPI TunerReset:270 Setting Tuner(0) RESET GPIO(30) to HIGH

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

TunerAPI DVBS_Tuner_Init:1253 This receiver has a Samsung STV0903 tuner

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

TunerAPI InitTuner:171 TUNER_DVB-S(0): Initializing is done

TunerAPI nim_open_adaptor:807 Succesfully initialized TUNER[0] module : DVB-S

[TUNER_DETECTION] Open DVBS adaptor (i2c=0,1)

++++TIMESTAMP Mon Aug 30 16:18:30 UTC 2010

[TUNER_DETECTION] 2th TUNER Checking...

TunerAPI nim_open_adaptor:618 tuner_combination_type == TUNER_COMBINATION_TWIN

TunerAPI SH_findI2CBUS:60 Same I2C bus is found at 0

TunerAPI InitTuner:109 Inializing TUNER_DVB-S(1) combination_type=1

===================TunerID: 1=======================

TunerAPI TunerReset:256 Tuner(1) Reset

TunerAPI TunerReset:259 Setting Tuner(1) RESET GPIO(31) to LOW

TunerAPI TunerReset:270 Setting Tuner(1) RESET GPIO(31) to HIGH

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

TunerAPI DVBS_Tuner_Init:1253 This receiver has a Samsung STV0903 tuner

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

TunerAPI InitTuner:171 TUNER_DVB-S(1): Initializing is done

TunerAPI nim_open_adaptor:807 Succesfully initialized TUNER[1] module : DVB-S

[TUNER_DETECTION] Open DVBS adaptor (i2c=0,1)

TunerAPI DeinitTuner:185 Deinializing TUNER_DVB-S(0) combination_type=1

TunerAPI DeinitTuner:204 TUNER_DVB-S(0): Deinitializing is done

TunerAPI DeinitTuner:185 Deinializing TUNER_DVB-S(1) combination_type=1

TunerAPI DeinitTuner:204 TUNER_DVB-S(1): Deinitializing is done

[TUNER_DETECTION] The number of tuner is 2

/MMP/usr/bin/fusermount: failed to unmount /DATA/upnp: No such file or directory

Mount options = iocharset=UTF-8

Charset : successfully initialised charset='UTF-8'

/MMP/usr/bin/S.sh: line 129: [: -eq: unary operator expected

/MMP/usr/bin/S.sh: line 132: /MMP/etc/init.d/ushare: No such file or directory

++++TIMESTAMP Mon Aug 30 16:18:31 UTC 2010

==================================

XXXXXXXXXX START SDecoderServer !XXXXXXXX

==================================

g_msq_server_close_ex : key_request 0x59075AB key_response 0x59075AC

g_msq_server_close_ex : key_request 0x5AC149B0 key_response 0x5AC149B1

++++TIMESTAMP Mon Aug 30 16:18:32 UTC 2010

XXXXXXXXXX /SDecoderServer i2c / gpio init [Jul 30 2010 17:18:17] XXXXXXXX

1.0.0.0

g_msq_server_open_ex : key_request 0x59075AB key_response 0x59075AC

g_msq_server_open_ex : key_request 0x5AC149B0 key_response 0x5AC149B1

not use upnp

rm: cannot remove `/tmp/.upnp': No such file or directory

[TD210] INITIALIZED END

[TD210] SLEEP MODE OFF

HDD Detected

XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX

Comp : Jul 30 2010 17:18:04

Ver : SDecoderCtrl.so CommonCtrl 2.8.5.1.1961

XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX

g_msq_client_connect : key_request 0x5AC149B0 key_response 0x5AC149B1

SCART Detected

====================VERBOSITY NONE====================

Init semaphore key(0x20080901) mode(384)

co164_send: call, devaddr = 0x28, subaddr = 0x00, size = 8

SEND DATA1 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

SEND DATA2 = 0x42 0xc2 0x46 0xe3 0x89 0xac 0xfd 0xdf

co164_recv: call, devaddr = 0x28 size = 8

RECV DATA = Micom Project PAVO

0xf6 0x17 0x46 0xb8 0x00 0x4c 0x17 0xac

hx_security_check: SECURITY_CHECK_RESULT = 1 (rand = 16807)

HHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHH

BUILDTD210: r5020 [Jul 30 2010 17:18:57]

BUILD QWUtil : r5020 [Jul 30 2010 17:18:33]

BUILD CRTVCore: r5020 [Jul 30 2010 17:18:58]

BUILD QWDTV : r5020 [Jul 30 2010 17:18:51]

_TD210

BUILD CRChipCtrl : r5020 [Jul 30 2010 17:18:47]

BUILD QWSmartCard : r5020 [Jul 30 2010 17:18:48]

BUILD QWDTV : r5020 [Jul 30 2010 17:18:51]

_TD210

BUILD CRChipCtrl : r5020 [Jul 30 2010 17:18:47]

BUILD QWSmartCard : r5020 [Jul 30 2010 17:18:48]

BUILD YGEPG : r5020 [Jul 30 2010 17:18:52]

HHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHHH

create_iso_639_code_table ok

vfd_chartable reading complite.

network ctrl start.............

network ctrl end.............

usb detect start.............

usb detect end..............

usb script start.............

run script = ACTION=init

vfork pid = 0

execle script = /MMP/usr/bin/usb_script.sh

envp[0] = ACTION=init

envp[1] = (null)

vfork pid = 1837

NetworkCtrl>> running....1

++++TIMESTAMP Mon Aug 30 16:18:33 UTC 2010

Display mode: FND

NetworkCtrl>> ST_START = (eth0)

../file_control.cpp +881 mode=0 if_name=eth0 if_link_name=eth0

../file_control.cpp +902 __ping_host1=211.216.50.150 __ping_host2=211.216.50.160

NetworkCtrl>> ST_CHECK_CABLE 1 "eth0" un-plug 0 1

last_state = 0

, state = 1

======================================================

disk_total = 0GB, disk_data = 0GB, disk_free = 0GB

system = 0GB, used = 0GB, free = 0GB

======================================================

hdparm: /dev/hdb: No such device or address

Init semaphore key(0x20090729) mode(384)

Init semaphore key(0x20090730) mode(384)

Init semaphore key(0x20080901) mode(384)

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

ERROR: SH_I2C_HAL_Write_NoSubAddr:466 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0x10]

ERROR: InitLNB_A8293:534 Failure to init A8293 (0x30 0x82)

NETWORK>> ACTION=start

NETWORK>> IF_NAME=eth0

NETWORK>> MODE=0

NETWORK>> IP_DHCP_MODE=0

NETWORK>> IP_ADDR=192.168.1.71

NETWORK>> IP_SUBNET_MASK=255.255.255.0

NETWORK>> IP_GATEWAY=192.168.1.254

NETWORK>> DNS_DHCP_MODE=0

NETWORK>> DNS_IP_ADDR1=192.168.1.254

NETWORK>> DNS_IP_ADDR2=0.0.0.0

NETWORK>> WLAN_ESSID=

NETWORK>> WLAN_ENCYPTION=0

NETWORK>> WLAN_KEY=

NetworkCtrl>> ST_CHECK_CABLE 2 "eth0" OK!!!

last_state = 1

, state = 2

======================================================

disk_total = 0GB, disk_data = 0GB, disk_free = 0GB

system = 0GB, used = 0GB, free = 0GB

======================================================

STV6110_TunerWriteaddr: 0x00C0, start: 0, size: 8

ERROR: nim_open_adaptor:677 Unsupported tuner type(0) for Twin Tuner 2

ERROR: nim_open_adaptor:677 Unsupported tuner type(0) for Twin Tuner 3

++++TIMESTAMP Mon Aug 30 16:18:34 UTC 2010

NetworkCtrl>> ST_CHECK_NETWORK = (eth0)

ERROR: http_stream_is_change_playlist_file:188 Cannot find http_streams.txt

LOAD EIT POOL : /Download/epg.dat

ERROR: load_eit_pool:1183 cannot open '/Download/epg.dat'

Size Info. - channel_info : 558 bytes

- last_pmtbuf : 128092 bytes

- last_catbuf : 128092 bytes

- xcas_list : 192 bytes

- gECMStatus : 234236 bytes

- gEMMStatus : 234236 bytes

- gKeySet : 3720 bytes

- gFilterStatus : 436604 bytes

- gCipherStatus : 992 bytes

- gCWStatus : 3348 bytes

[OPENXCAS_DTV:Mon Aug 30 16:18:35.758] openxcas_adaptor_create:2207 ##

[OPENXCAS_DTV:Mon Aug 30 16:18:35.760] start_module:1959 ## start

[OPENXCAS_API:Mon Aug 30 16:18:35.761] openxcas_load_module_sequence:243 ## fail to open module list(/EMU/OpenXCAS/module.seq)[OPENXCAS_DTV:Mon Aug 30 16:18:35.761] start_module:1974 ## Active XCAS module = 0

[OPENXCAS_DTV:Mon Aug 30 16:18:35.950] start_module:2082 ## end

[OPENXCAS_DTV:Mon Aug 30 16:18:35.993] adaptor_thread:1195 ## openxcas_control_thread start (pid = 1869)

get_shared_buffer server status key 0x76413C25

rdate: Current time matches remote time.

Mon Aug 30 16:18:36 2010

rm: cannot remove `/tmp/.rdate_fail': No such file or directory

/root

Wait pid end here!

usb script end.............

wireless start.............

wireless end.............

++++TIMESTAMP Mon Aug 30 16:18:36 UTC 2010

last_state = 2

, state = 3

======================================================

disk_total = 0GB, disk_data = 0GB, disk_free = 0GB

system = 0GB, used = 0GB, free = 0GB

======================================================

usb space = 0

I2C_GPIO_Create:64

Init semaphore key(0x20080901) mode(384)

PB_CS_CONFIG = 0x000e0040, new = 0x000e0040

Init semaphore key(0x09013406) mode(384)

init application layer

application layer is initailzed

init session layer

session layer is initailzed

init transport layer

transport layer is initailzed

init link layer

link layer is initailzed

init physical layer

physical layer is initailzed

ERROR: RF_I2C_Write:481 Fail to RK i2c write nosubaddr [sCL=0x00, SDL=0x01, addr=0xca]

ERROR: nim_RF_cmd:1137 nim_RF_cmd Fail!!

ERROR: load_reserved_dat:216 cannot read the reserved_info from '/DISK2/reserved.dat'

Use code default verbosity.

Init semaphore key(0x09013407) mode(384)

Init semaphore key(0x09013405) mode(384)

Init semaphore key(0x09013403) mode(384)

Init semaphore key(0x09013404) mode(384)

Init semaphore key(0x09013402) mode(384)

Init semaphore key(0x09013407) mode(384)

Init semaphore key(0x09013401) mode(384)

PHYS_DRVOpen . Open PHYSICAL low level driver for 1 slot

PHYS_DRVOpen . Open PHYSICAL low level driver for 2 slot

Max Room For CAM Memory(1)

NETWORK>> ACTION=alive

NETWORK>> IF_NAME=eth0

NETWORK>> MODE=0

IVPal_Init:44 Start IVPal_Init With Dummy

IVHal_Init:33 Start IVHal_Init

IVHalDisp_Init:73 Start IVHalDisp_Init for Real

MMIDisp_Init:360 :: MMIDisp_Init Enter

MMIDisp_InitOSDBuffer:1210 :: MMIDisp_InitOSDBuffer Enter

MMIDisp_InitOSDBuffer:1222 :: MMIDisp_InitOSDBuffer Leave

MMIDisp_InitGFXScaler:1586 :: MMIDisp_InitGFXScaler Enter

MMIDisp_InitScaler:1084 :: MMIDisp_InitScaler Enter

MMIDisp_InitScaler:1135 :: Scaler Setup Default alpha_blending value = 0xff (Alpha0)

MMIDisp_InitScaler:1200 :: MMIDisp_InitScaler Leave

MMIDisp_InitGFXScaler:1750 :: MMIDisp_InitGFXScaler Leave

MMIDisp_SetSurfaces:304 :: MMIDisp_SetSurfaces Entered

MMIDisp_SetSurfaces:345 :: MMIDisp_SetSurfaces Leave

++++TIMESTAMP Mon Aug 30 16:18:37 UTC 2010

MMIDisp_Init:507 :: MMIDisp_Init Leave

IVHalDisp_Init:84 Finished IVHalDisp_Init for Real

Hal/Source/IVHalSys.c:IVHalSys_Init:931 Start IVHalSys_Init

Hal/Source/IVHalSys.c:IVHalSys_Init:944 Finished IVHalSys_Init

IVHal_Init:43 Finished IVHal_Init

[iVPalStr_GetLanguageIdx : 535] Language Index is 0.

IVPal_Init:98 Finished IVPal_Init With Dummy

[DBG] IVPalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp IVMstartup.so

291 ----------------------------------dlopen:0x1003dd78

[DBG] MMIDisp_BlendRectangle (0, 0)(0, 0)(0, 0) 960 540

[DBG] IVPalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp IVMspy.so

291 ----------------------------------dlopen:0x1004d200

[DBG] IVPalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp

[DBG] IVHalEM_CheckNativeApp IVMtvmngr.so

291 ----------------------------------dlopen:0x1004d538