basix Geplaatst: 23 augustus 2010 Delen Geplaatst: 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 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 [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 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 Link naar reactie Delen op andere sites Meer opties om te delen...
chrisch Geplaatst: 24 augustus 2010 Delen Geplaatst: 24 augustus 2010 Interessante studie! Zouden ze @ opensat hier ook mee bezig zijn? Logic will get you from A to B. Imagination will take you everywhere. Link naar reactie Delen op andere sites Meer opties om te delen...
basix Geplaatst: 24 augustus 2010 Auteur Delen Geplaatst: 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. Link naar reactie Delen op andere sites Meer opties om te delen...
wian Geplaatst: 26 augustus 2010 Delen Geplaatst: 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. hobby4all Link naar reactie Delen op andere sites Meer opties om te delen...
basix Geplaatst: 30 augustus 2010 Auteur Delen Geplaatst: 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 Display mode: FND 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 [iVPalSys_GetLanguageIdx : 1299] Language Index is 0. 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 [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]> FILE_CONTROL CONF_INFO << IN FILE_CONTROL CONF_INFO << OUT [iVPalSys_GetLanguageIdx : 1299] Language Index is 0. 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 main:FILE_CONTROL CONF_INFO << OUT FILE_CONTROL CONF_INFO << IN 690 Ready to UI Working... Done [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 [DBG] IVPalEM_CheckNativeApp [DBG] IVHalEM_CheckNativeApp [DBG] IVHalEM_CheckNativeApp IVMtv.so FILE_CONTROL CONF_INFO << OUT 291 ----------------------------------dlopen:0x1024ac70 [iVPalSys_GetLanguageIdx : 1299] Language Index is 0. 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 << ++++TIMESTAMP Mon Aug 30 16:18:39 UTC 2010 342 ----------------------------------dlclose:0x1003dd78, return : 0 [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 << IN FILE_CONTROL CONF_INFO << OUT file msg :116 [HDD][/MMP] [HDD][/PLUGINS] [HDD][/DISK2] [HDD][/Download] 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 [Core_Common_Event :1264] event->cmd 0xc6 -------------->[ 9058] ELAPSED TIME: 52 FILE_CONTROL CONF_INFO << IN FILE_CONTROL CONF_INFO << OUT FILE_CONTROL CONF_INFO << IN FILE_CONTROL CONF_INFO << OUT [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] XXXXXX Load file /DISK2/EPG_SERVER.dat [HXIPTV:MSG] [HTTP_STREAM] download_thread start (pid = 1902) [HXUTIL:WAN] 0x00000001, 1 ===> Starting user defined script ===> Mounting freenas & slug box mkdir: Cannot create directory `/DATA/movie/slug': File exists ++++TIMESTAMP Mon Aug 30 16:18:41 UTC 2010 mkdir: Cannot create directory `/DATA/movie/freenas': File exists AZBox[~]$ [load_epg_addr_conf:152] [HXIPTV:MSG] [load_epg_addr_conf:165]Iptv.data_addr: kartina.pristavka.de / kartina.pristavka.de [HXIPTV:MSG] epg_server : (kartina.pristavka.de:8083) 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 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 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 ++++TIMESTAMP Mon Aug 30 16:18:42 UTC 2010 4096 [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 semop: Resource temporarily unavailable 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 semop: Resource temporarily unavailable [Core_Common_Event :1264] event->cmd 0x6c semop: Resource temporarily unavailable semop: Resource temporarily unavailable --------------------------------------------------- CRTVSetup Recv MSG(7) --------------------------------------------------- vol = 0xb504f34(28) [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x13a1 [OPENXCAS_DTV:Mon Aug 30 16:18:42.927] openxcas_send_channel_info:1528 ## stream_id = 0, sequence = 2, service_id = 0x13a1, vpid = 0x0203, apid = 0x0060, dpid = 0x0024 [OPENXCAS_DTV:Mon Aug 30 16:18:42.928] openxcas_send_channel_info:1567 ## Not found active cas module(stream_id = 0) [OPENXCAS_DTV:Mon Aug 30 16:18:42.928] openxcas_send_pmt:1585 ## stream_id = 0, sequence = 2 ERROR: check_sections:2320 0pid_filter_inverse_version_mask ################################################################ Description : NEW STREAM INFO (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x0203, APID = 0x0060) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ ################################################################ Description : NOT SCRAMBLED STREAM (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x0203, APID = 0x0060) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ FILE_CONTROL CONF_INFO << IN FILE_CONTROL CONF_INFO << OUT FILE_CONTROL CONF_INFO << IN FILE_CONTROL CONF_INFO << OUT [Core_Common_Event :1264] event->cmd 0xb5 semop: Resource temporarily unavailable [Core_Common_Event :1264] event->cmd 0x24 [Core_Common_Event :1264] event->cmd 0xf5 WARNING: this production is not masterbox tuner_count = 4 [Core_Common_Event :1264] event->cmd 0x51 [Core_Common_Event :1264] event->cmd 0xca [OPENXCAS_DTV:Mon Aug 30 16:18:43.608] openxcas_send_cat:1656 ## stream_id = 0, sequence = 2 [Core_Common_Event :1264] event->cmd 0xc9 [Core_Common_Event :1264] event->cmd 0x8b [Core_Common_Event :1264] event->cmd 0xce FILE_CONTROL CONF_INFO << IN FILE_CONTROL CONF_INFO << OUT [Core_Common_Event :1264] event->cmd 0x8c [Core_Common_Event :1264] event->cmd 0x7 [Core_Common_Event :1264] event->cmd 0xac [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 30 16:18:44 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:45 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:46 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:47 UTC 2010 [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started ++++TIMESTAMP Mon Aug 30 16:18:48 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:49 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:51 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:52 UTC 2010 StcComp: current dist_to_ref=0 ms (0) delta_mean=0 sf=0 df=0 f=16% 212% 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=12% 220% ++++TIMESTAMP Mon Aug 30 16:18:53 UTC 2010 StcComp: current dist_to_ref=0 ms (8) delta_mean=8 sf=0 df=0 f=11% 391% ++++TIMESTAMP Mon Aug 30 16:18:54 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:55 UTC 2010 StcComp: current dist_to_ref=0 ms (16) delta_mean=16 sf=0 df=0 f=24% 413% ++++TIMESTAMP Mon Aug 30 16:18:56 UTC 2010 StcComp: current dist_to_ref=0 ms (24) delta_mean=24 sf=0 df=0 f=21% 418% ++++TIMESTAMP Mon Aug 30 16:18:57 UTC 2010 StcComp: current dist_to_ref=0 ms (32) delta_mean=32 sf=0 df=0 f=37% 414% ++++TIMESTAMP Mon Aug 30 16:18:58 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:18:59 UTC 2010 StcComp: current dist_to_ref=0 ms (38) delta_mean=38 sf=0 df=0 f=8% 428% ++++TIMESTAMP Mon Aug 30 16:19:01 UTC 2010 StcComp: current dist_to_ref=0 ms (44) delta_mean=44 sf=0 df=0 f=16% 392% ++++TIMESTAMP Mon Aug 30 16:19:02 UTC 2010 StcComp: current dist_to_ref=0 ms (50) delta_mean=50 sf=0 df=0 f=12% 426% ++++TIMESTAMP Mon Aug 30 16:19:03 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:04 UTC 2010 StcComp: current dist_to_ref=0 ms (56) delta_mean=56 sf=0 df=0 f=39% 421% ++++TIMESTAMP Mon Aug 30 16:19:05 UTC 2010 StcComp: current dist_to_ref=0 ms (61) delta_mean=61 sf=0 df=0 f=23% 400% ++++TIMESTAMP Mon Aug 30 16:19:06 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:07 UTC 2010 StcComp: current dist_to_ref=0 ms (67) delta_mean=67 sf=0 df=0 f=19% 423% ++++TIMESTAMP Mon Aug 30 16:19:08 UTC 2010 StcComp: current dist_to_ref=0 ms (73) delta_mean=73 sf=0 df=0 f=30% 408% [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started ++++TIMESTAMP Mon Aug 30 16:19:09 UTC 2010 StcComp: current dist_to_ref=0 ms (78) delta_mean=78 sf=0 df=0 f=9% 406% ++++TIMESTAMP Mon Aug 30 16:19:11 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:12 UTC 2010 StcComp: current dist_to_ref=0 ms (84) delta_mean=84 sf=0 df=0 f=9% 391% ++++TIMESTAMP Mon Aug 30 16:19:13 UTC 2010 StcComp: current dist_to_ref=0 ms (89) delta_mean=89 sf=0 df=0 f=10% 425% ++++TIMESTAMP Mon Aug 30 16:19:14 UTC 2010 StcComp: current dist_to_ref=1 ms (94) delta_mean=94 sf=0 df=0 f=11% 387% ++++TIMESTAMP Mon Aug 30 16:19:15 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:16 UTC 2010 StcComp: current dist_to_ref=1 ms (100) delta_mean=100 sf=0 df=0 f=30% 419% ++++TIMESTAMP Mon Aug 30 16:19:17 UTC 2010 StcComp: current dist_to_ref=1 ms (105) delta_mean=105 sf=0 df=0 f=15% 419% ++++TIMESTAMP Mon Aug 30 16:19:18 UTC 2010 StcComp: current dist_to_ref=1 ms (110) delta_mean=110 sf=0 df=0 f=42% 422% ++++TIMESTAMP Mon Aug 30 16:19:19 UTC 2010 ++++++ZAP to ZDF arrow up ++++TIMESTAMP Mon Aug 30 16:19:20 UTC 2010 [Core_Common_Event :1264] event->cmd 0x57 StcComp: current dist_to_ref=1 ms (114) delta_mean=114 sf=0 df=0 f=12% 405% [Core_Common_Event :1264] event->cmd 0xbf [Core_Common_Event :1264] event->cmd 0x67 STV0903_SignalQuality 13.0dB ++++TIMESTAMP Mon Aug 30 16:19:22 UTC 2010 [Core_Common_Event :1264] event->cmd 0x67 STV0903_SignalQuality 12.8dB [Core_Common_Event :1264] event->cmd 0x6b [OPENXCAS_DTV:Mon Aug 30 16:19:22.761] openxcas_clear_pmt:1621 ## stream_id = 0, sequence = 2 [OPENXCAS_DTV:Mon Aug 30 16:19:22.762] openxcas_clear_cat:1692 ## stream_id = 0, sequence = 2 ################################################################ Description : CLEAR STREAM INFO FOR LIVE (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x0203, APID = 0x0060) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ NO SWITCHING CONTROL(BYPASS) TS SWITCHING at SINGLEMODE REG: 0x00 TS SWITCHING at TWINMODE REG: 0x98 StcComp: current dist_to_ref=1 ms (119) delta_mean=119 sf=0 df=0 f=24% 407% NO SLOT ENALBLES STREAM NO LOCKED SLOT No Enable Module Wrong Header.0x0e =============================================================================================== 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 [Core_Common_Event :1264] event->cmd 0xbf [Core_Common_Event :1264] event->cmd 0x67 ++++TIMESTAMP Mon Aug 30 16:19:23 UTC 2010 [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 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 [DVB_S/diseqc.c],[1030] ->> DISEQC_Positioner iPosCmd 16, iPosition 19, ########################################################## =====> STV0903_SendDiseqcCmd iCmdLength 4, 0xE0 End MMAP 0x0x2ca6e000 Starting MMAP End MMAP 0x0x2ca73000 Starting MMAP End MMAP 0x0x2ca78000 Starting MMAP End MMAP 0x0x2ca7d000 4096 ########################################################## 4096 [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 semop: Resource temporarily unavailable [Core_Common_Event :1264] event->cmd 0xac ++++TIMESTAMP Mon Aug 30 16:19:24 UTC 2010 [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x6d66 [OPENXCAS_DTV:Mon Aug 30 16:19:24.727] openxcas_send_channel_info:1528 ## stream_id = 0, sequence = 3, service_id = 0x6d66, vpid = 0x006e, apid = 0x0078, dpid = 0x0082 [OPENXCAS_DTV:Mon Aug 30 16:19:24.728] openxcas_send_channel_info:1567 ## Not found active cas module(stream_id = 0) [OPENXCAS_DTV:Mon Aug 30 16:19:24.728] openxcas_send_pmt:1585 ## stream_id = 0, sequence = 3 ERROR: check_sections:2320 0pid_filter_inverse_version_mask ################################################################ Description : NEW STREAM INFO (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x006E, APID = 0x0078) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ ################################################################ Description : NOT SCRAMBLED STREAM (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x006E, APID = 0x0078) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ [OPENXCAS_DTV:Mon Aug 30 16:19:24.766] openxcas_send_cat:1656 ## stream_id = 0, sequence = 3 [Core_Common_Event :1264] event->cmd 0xac [Core_Common_Event :1264] event->cmd 0x57 [Core_Common_Event :1264] event->cmd 0xbf ++++TIMESTAMP Mon Aug 30 16:19:25 UTC 2010 ++++ And we have picture after roughly 4-5 seconds STV0903_SignalQuality 11.8dB STV0903_SignalQuality 11.7dB ++++TIMESTAMP Mon Aug 30 16:19:26 UTC 2010 STV0903_SignalQuality 11.7dB ++++TIMESTAMP Mon Aug 30 16:19:27 UTC 2010 STV0903_SignalQuality 11.8dB [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 30 16:19:28 UTC 2010 [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started ++++TIMESTAMP Mon Aug 30 16:19:30 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:31 UTC 2010 StcComp: current dist_to_ref=0 ms (0) delta_mean=0 sf=0 df=0 f=3% 305% 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=13% 332% ++++TIMESTAMP Mon Aug 30 16:19:33 UTC 2010 StcComp: current dist_to_ref=0 ms (5) delta_mean=5 sf=0 df=0 f=12% 636% ++++TIMESTAMP Mon Aug 30 16:19:35 UTC 2010 StcComp: current dist_to_ref=0 ms (12) delta_mean=12 sf=0 df=0 f=8% 650% StcComp: current dist_to_ref=0 ms (17) delta_mean=17 sf=0 df=0 f=13% 635% ++++TIMESTAMP Mon Aug 30 16:19:38 UTC 2010 StcComp: current dist_to_ref=0 ms (22) delta_mean=22 sf=0 df=0 f=16% 664% ++++TIMESTAMP Mon Aug 30 16:19:39 UTC 2010 StcComp: current dist_to_ref=0 ms (27) delta_mean=27 sf=0 df=0 f=15% 644% StcComp: current dist_to_ref=0 ms (31) delta_mean=31 sf=0 df=0 f=12% 643% ++++TIMESTAMP Mon Aug 30 16:19:42 UTC 2010 StcComp: current dist_to_ref=0 ms (35) delta_mean=35 sf=0 df=0 f=9% 647% ++++TIMESTAMP Mon Aug 30 16:19:44 UTC 2010 StcComp: current dist_to_ref=0 ms (39) delta_mean=39 sf=0 df=0 f=12% 651% ++++TIMESTAMP Mon Aug 30 16:19:46 UTC 2010 StcComp: current dist_to_ref=0 ms (42) delta_mean=42 sf=0 df=0 f=25% 685% ++++TIMESTAMP Mon Aug 30 16:19:47 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:48 UTC 2010 StcComp: current dist_to_ref=0 ms (45) delta_mean=45 sf=0 df=0 f=23% 656% StcComp: current dist_to_ref=0 ms (49) delta_mean=49 sf=0 df=0 f=20% 642% [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started ++++TIMESTAMP Mon Aug 30 16:19:50 UTC 2010 StcComp: current dist_to_ref=0 ms (52) delta_mean=52 sf=0 df=0 f=19% 652% ++++TIMESTAMP Mon Aug 30 16:19:52 UTC 2010 StcComp: current dist_to_ref=0 ms (56) delta_mean=56 sf=0 df=0 f=27% 641% ++++TIMESTAMP Mon Aug 30 16:19:53 UTC 2010 StcComp: current dist_to_ref=0 ms (60) delta_mean=60 sf=0 df=0 f=8% 670% ++++TIMESTAMP Mon Aug 30 16:19:54 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:56 UTC 2010 StcComp: current dist_to_ref=0 ms (64) delta_mean=64 sf=0 df=0 f=36% 636% ++++TIMESTAMP Mon Aug 30 16:19:57 UTC 2010 StcComp: current dist_to_ref=0 ms (68) delta_mean=68 sf=0 df=0 f=12% 647% ++++TIMESTAMP Mon Aug 30 16:19:58 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:19:59 UTC 2010 StcComp: current dist_to_ref=0 ms (71) delta_mean=71 sf=0 df=0 f=23% 667% ++++TIMESTAMP Mon Aug 30 16:20:00 UTC 2010 StcComp: current dist_to_ref=0 ms (74) delta_mean=74 sf=0 df=0 f=17% 671% ++++TIMESTAMP Mon Aug 30 16:20:01 UTC 2010 StcComp: current dist_to_ref=0 ms (78) delta_mean=78 sf=0 df=0 f=14% 646% ++++TIMESTAMP Mon Aug 30 16:20:02 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:03 UTC 2010 StcComp: current dist_to_ref=0 ms (81) delta_mean=81 sf=0 df=0 f=20% 658% ++++TIMESTAMP Mon Aug 30 16:20:04 UTC 2010 StcComp: current dist_to_ref=0 ms (84) delta_mean=84 sf=0 df=0 f=11% 654% ++++++ZAP back to BVN 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 30 16:20:06 UTC 2010 STV0903_SignalQuality 11.8dB [Core_Common_Event :1264] event->cmd 0x67 StcComp: current dist_to_ref=0 ms (87) delta_mean=87 sf=0 df=0 f=13% 665% STV0903_SignalQuality 11.8dB [Core_Common_Event :1264] event->cmd 0x6b [OPENXCAS_DTV:Mon Aug 30 16:20:07.653] openxcas_clear_pmt:1621 ## stream_id = 0, sequence = 3 [OPENXCAS_DTV:Mon Aug 30 16:20:07.661] openxcas_clear_cat:1692 ## stream_id = 0, sequence = 3 ################################################################ Description : CLEAR STREAM INFO FOR LIVE (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x006E, APID = 0x0078) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ NO SWITCHING CONTROL(BYPASS) ++++TIMESTAMP Mon Aug 30 16:20:07 UTC 2010 TS SWITCHING at SINGLEMODE REG: 0x00 TS SWITCHING at TWINMODE REG: 0x98 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 [Core_Common_Event :1264] event->cmd 0xbf [Core_Common_Event :1264] event->cmd 0x67 [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 30 16:20:08 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 semop: Resource temporarily unavailable [Core_Common_Event :1264] event->cmd 0x57 [Core_Common_Event :1264] event->cmd 0xbf [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x13a1 [OPENXCAS_DTV:Mon Aug 30 16:20:09.411] openxcas_send_channel_info:1528 ## stream_id = 0, sequence = 4, service_id = 0x13a1, vpid = 0x0203, apid = 0x0060, dpid = 0x0024 [OPENXCAS_DTV:Mon Aug 30 16:20:09.411] openxcas_send_channel_info:1567 ## Not found active cas module(stream_id = 0) [OPENXCAS_DTV:Mon Aug 30 16:20:09.412] openxcas_send_pmt:1585 ## stream_id = 0, sequence = 4 ERROR: check_sections:2320 0pid_filter_inverse_version_mask ################################################################ Description : NEW STREAM INFO (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x0203, APID = 0x0060) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ ################################################################ Description : NOT SCRAMBLED STREAM (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x0203, APID = 0x0060) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ [Core_Common_Event :1264] event->cmd 0x67 STV0903_SignalQuality 13.2dB [OPENXCAS_DTV:Mon Aug 30 16:20:09.812] openxcas_send_cat:1656 ## stream_id = 0, sequence = 4 ++++TIMESTAMP Mon Aug 30 16:20:09 UTC 2010 ++++ And we have picture after roughly 4+ seconds [Core_Common_Event :1264] event->cmd 0x67 STV0903_SignalQuality 13.0dB ++++TIMESTAMP Mon Aug 30 16:20:11 UTC 2010 [Core_Common_Event :1264] event->cmd 0x67 STV0903_SignalQuality 13.1dB ++++TIMESTAMP Mon Aug 30 16:20:12 UTC 2010 STV0903_SignalQuality 12.8dB [Core_Common_Event :1264] event->cmd 0x67 ++++TIMESTAMP Mon Aug 30 16:20:13 UTC 2010 STV0903_SignalQuality 12.9dB [Core_Common_Event :1264] event->cmd 0x67 [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 30 16:20:14 UTC 2010 [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started ++++TIMESTAMP Mon Aug 30 16:20:15 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:16 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:17 UTC 2010 StcComp: current dist_to_ref=0 ms (0) delta_mean=0 sf=0 df=0 f=3% 221% 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=12% 201% ++++TIMESTAMP Mon Aug 30 16:20:18 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:20 UTC 2010 StcComp: current dist_to_ref=0 ms (6) delta_mean=6 sf=0 df=0 f=11% 420% ++++TIMESTAMP Mon Aug 30 16:20:21 UTC 2010 StcComp: current dist_to_ref=0 ms (14) delta_mean=14 sf=0 df=0 f=16% 401% ++++TIMESTAMP Mon Aug 30 16:20:22 UTC 2010 StcComp: current dist_to_ref=0 ms (20) delta_mean=20 sf=0 df=0 f=22% 419% ++++TIMESTAMP Mon Aug 30 16:20:23 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:24 UTC 2010 StcComp: current dist_to_ref=0 ms (27) delta_mean=27 sf=0 df=0 f=47% 418% ++++TIMESTAMP Mon Aug 30 16:20:25 UTC 2010 StcComp: current dist_to_ref=0 ms (32) delta_mean=32 sf=0 df=0 f=12% 418% ++++TIMESTAMP Mon Aug 30 16:20:26 UTC 2010 StcComp: current dist_to_ref=0 ms (38) delta_mean=38 sf=0 df=0 f=32% 399% ++++TIMESTAMP Mon Aug 30 16:20:27 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:28 UTC 2010 StcComp: current dist_to_ref=0 ms (44) delta_mean=44 sf=0 df=0 f=10% 388% ++++TIMESTAMP Mon Aug 30 16:20:30 UTC 2010 StcComp: current dist_to_ref=0 ms (50) delta_mean=50 sf=0 df=0 f=11% 426% ++++TIMESTAMP Mon Aug 30 16:20:31 UTC 2010 StcComp: current dist_to_ref=0 ms (55) delta_mean=55 sf=0 df=0 f=11% 433% ++++TIMESTAMP Mon Aug 30 16:20:32 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:33 UTC 2010 StcComp: current dist_to_ref=0 ms (60) delta_mean=60 sf=0 df=0 f=10% 401% ++++TIMESTAMP Mon Aug 30 16:20:34 UTC 2010 StcComp: current dist_to_ref=0 ms (66) delta_mean=66 sf=0 df=0 f=10% 405% ++++TIMESTAMP Mon Aug 30 16:20:35 UTC 2010 [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started ++++TIMESTAMP Mon Aug 30 16:20:36 UTC 2010 StcComp: current dist_to_ref=0 ms (71) delta_mean=71 sf=0 df=0 f=22% 417% ++++TIMESTAMP Mon Aug 30 16:20:37 UTC 2010 StcComp: current dist_to_ref=0 ms (76) delta_mean=76 sf=0 df=0 f=4% 433% ++++TIMESTAMP Mon Aug 30 16:20:38 UTC 2010 StcComp: current dist_to_ref=0 ms (82) delta_mean=82 sf=0 df=0 f=9% 400% ++++TIMESTAMP Mon Aug 30 16:20:39 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:41 UTC 2010 StcComp: current dist_to_ref=0 ms (87) delta_mean=87 sf=0 df=0 f=36% 431% ++++TIMESTAMP Mon Aug 30 16:20:42 UTC 2010 StcComp: current dist_to_ref=1 ms (93) delta_mean=93 sf=0 df=0 f=43% 401% ++++TIMESTAMP Mon Aug 30 16:20:43 UTC 2010 StcComp: current dist_to_ref=1 ms (98) delta_mean=98 sf=0 df=0 f=16% 406% ++++TIMESTAMP Mon Aug 30 16:20:44 UTC 2010 ++++TIMESTAMP Mon Aug 30 16:20:45 UTC 2010 StcComp: current dist_to_ref=1 ms (102) delta_mean=102 sf=0 df=0 f=33% 413% ++++TIMESTAMP Mon Aug 30 16:20:46 UTC 2010 StcComp: current dist_to_ref=1 ms (108) delta_mean=108 sf=0 df=0 f=22% 385% ++++TIMESTAMP Mon Aug 30 16:20:47 UTC 2010 ++++++ZAP to zdf again arrow up [Core_Common_Event :1264] event->cmd 0x57 [Core_Common_Event :1264] event->cmd 0xbf [Core_Common_Event :1264] event->cmd 0x67 StcComp: current dist_to_ref=1 ms (112) delta_mean=112 sf=0 df=0 f=55% 414% ++++TIMESTAMP Mon Aug 30 16:20:48 UTC 2010 STV0903_SignalQuality 12.9dB [Core_Common_Event :1264] event->cmd 0x67 ++++TIMESTAMP Mon Aug 30 16:20:50 UTC 2010 [Core_Common_Event :1264] event->cmd 0x6b [OPENXCAS_DTV:Mon Aug 30 16:20:50.240] openxcas_clear_pmt:1621 ## stream_id = 0, sequence = 4 [OPENXCAS_DTV:Mon Aug 30 16:20:50.241] openxcas_clear_cat:1692 ## stream_id = 0, sequence = 4 ################################################################ Description : CLEAR STREAM INFO FOR LIVE (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x0203, APID = 0x0060) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ NO SWITCHING CONTROL(BYPASS) TS SWITCHING at SINGLEMODE REG: 0x00 TS SWITCHING at TWINMODE REG: 0x98 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 ----------------------------------------------------------------------------------------------- =============================================================================================== STV0903_SignalQuality 13.0dB [Core_Common_Event :1264] event->cmd 0xbf [Core_Common_Event :1264] event->cmd 0x67 [Core_Common_Event :1264] event->cmd 0x67 WARNING: lock_tuner_use_channel:1522 0_Fail to find antenna(-1) in dtv(0) [DVB_S/diseqc.c],[1030] ->> DISEQC_Positioner iPosCmd 16, iPosition 19, ########################################################## =====> STV0903_SendDiseqcCmd iCmdLength 4, 0xE0 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 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 [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 semop: Resource temporarily unavailable [Core_Common_Event :1264] event->cmd 0x57 semop: Resource temporarily unavailable [Core_Common_Event :1264] event->cmd 0xbf [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started [HwDemuxPlay:1960] 0_start Demux hardware demux is aleady started WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x6d66 [OPENXCAS_DTV:Mon Aug 30 16:20:51.962] openxcas_send_channel_info:1528 ## stream_id = 0, sequence = 5, service_id = 0x6d66, vpid = 0x006e, apid = 0x0078, dpid = 0x0082 [OPENXCAS_DTV:Mon Aug 30 16:20:51.963] openxcas_send_channel_info:1567 ## Not found active cas module(stream_id = 0) [OPENXCAS_DTV:Mon Aug 30 16:20:51.963] openxcas_send_pmt:1585 ## stream_id = 0, sequence = 5 ERROR: check_sections:2320 0pid_filter_inverse_version_mask ################################################################ Description : NEW STREAM INFO (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x006E, APID = 0x0078) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ ################################################################ Description : NOT SCRAMBLED STREAM (Recording = 0, Live = 1, Lock = 1) Channel Info : 0000 (VPID = 0x006E, APID = 0x0078) Slot Info : TSI = 1, Fixed = X, Using = X Retry Count : SLOT A = 0, SLOT B = 0 ################################################################ [OPENXCAS_DTV:Mon Aug 30 16:20:52.005] openxcas_send_cat:1656 ## stream_id = 0, sequence = 5 [Core_Common_Event :1264] event->cmd 0x67 ++++TIMESTAMP Mon Aug 30 16:20:51 UTC 2010 ++++ And we have picture after roughly 4 seconds WARNING: check_sections:2094 0_current audio pid exist in new PMT of SID(0x6d66 ERROR: check_sections:2320 0pid_filter_inverse_version_mask STV0903_SignalQuality 11.8dB [Core_Common_Event :1264] event-> Link naar reactie Delen op andere sites Meer opties om te delen...
D_liver Geplaatst: 30 augustus 2010 Delen Geplaatst: 30 augustus 2010 Citaat: Hierbij kunnen we dus vaststellen dat hetzelfde probleem voor FTA zenders aanwezig is. Nee want het duurt bij mij echt geen 5 sec voor ik beeld heb zeker niet op fta en bij gecodeerde kanalen moet het al een coderings wisseling zijn wil het zo lang duren. 2 x Maxytec Multibox 4K 1 x Zgemma H9 combo Link naar reactie Delen op andere sites Meer opties om te delen...
basix Geplaatst: 30 augustus 2010 Auteur Delen Geplaatst: 30 augustus 2010 Zou je dezelfde test kunnen uitvoeren dan? Met putty telnetten, printable log opzetten naar een bestandje K_debug.sh Een loop scriptje dat elke seconde de tijd weergeeft starten met & S.sh +++Zap kopieren naar klembord. En rechtsklik in putty tegelijk met de zap op ab. Graag ook meer details over je setup. Alvast bedankt. Link naar reactie Delen op andere sites Meer opties om te delen...
Aanbevolen berichten
Maak een account aan of log in om te reageren
Je moet een lid zijn om een reactie te kunnen achterlaten
Account aanmaken
Registreer voor een nieuwe account in onze community. Het is erg gemakkelijk!
Registreer een nieuwe accountInloggen
Heb je reeds een account? Log hier in.
Nu inloggen