here is whats in the log when I try to tune one of the channels...
Code: Select all
Jan 1 03:45:44 ocur[21]: ocur: cas_NotifyCopyControlBits:1226 : [2] CCI 00 arrived for program# 102
Jan 1 03:46:00 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:46:00 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:46:46 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:46:46 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:47:22 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:47:22 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:47:44 ocur[21]: upnp: [192.168.180.2] Get(cas[0]): CardAuthorization "Validated, validation message is received, authenticated, and the IDs match those in the current binding"
Jan 1 03:47:44 ocur[21]: upnp: [192.168.180.2] Get(cas[0]): OOB_MER "39 db/10"
Jan 1 03:47:52 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:47:52 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:48:04 ocur[21]: upnp: [192.168.180.2] Get(cas[0]): CardAuthorization "Validated, validation message is received, authenticated, and the IDs match those in the current binding"
Jan 1 03:48:04 ocur[21]: upnp: [192.168.180.2] Get(cas[0]): OOB_MER "39 db/10"
Jan 1 03:48:23 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:48:23 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): tr_status "Disabled"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): downstream_status "Unavailable"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): upstream_status "Unavailable"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): authentication_status "Unavailable"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): tr_operational_status "Unavailable"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): max_upgrade_time "Unavailable"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): WhichTuners ""
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): NumChannels "0"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): NumMapsRecv "0"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): MfgId "Unavailable"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): SoftwareVersion "Unavailable"
Jan 1 03:48:34 ocur[21]: upnp: [192.168.180.2] Get(octa[6]): HardwareVersion "Unavailable"
Jan 1 03:48:54 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:48:54 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:49:27 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:49:27 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:50:00 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:50:00 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:50:29 ocur[21]: upnp: [192.168.180.2] Calling Action(cas[2]) SetChannel
Jan 1 03:50:29 ocur[21]: ocur: cas_SetChannel_impl:2779 : [2] Attempting to SetChannel (Channel=1221 SourceId=0 Mode=0)
Jan 1 03:50:29 ocur[21]: upnp: [192.168.180.2] Action(cas[2]) SetChannel success
Jan 1 03:50:29 ocur[21]: ocur: mux_SetProgramVariable:1804 : [2] Program number set to 0, was 102
Jan 1 03:50:29 ocur[21]: ocur: cas_hal_TuneChannel:3002 : [2] Tune to 1221 (mod=q256, freq=261000, program=63, std=9)
Jan 1 03:50:29 ocur[21]: ocur: mux_SetProgramVariable:1804 : [2] Program number set to 63, was 0
Jan 1 03:50:29 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0000
Jan 1 03:50:29 ocur[21]: ocur: tuner_SetUntranslatedFrequency_ex:661 : [2] Locked: 1 Acc E: 0
Jan 1 03:50:29 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x1ffb
Jan 1 03:50:30 ocur[21]: ocur: tuner_SetUntranslatedFrequency_ex:747 : [2] Tune finished to freq 261000
Jan 1 03:50:30 ocur[21]: ocur: cas_GetPatPmtAndPids:1981 : [2] Getting pmt for program 63
Jan 1 03:50:30 ocur[21]: ocur: cas_GetPatPmtAndPids:2023 : [2] Got pmt for program# 63
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0030
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0031
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0032
Jan 1 03:50:30 ocur[21]: upnp: Event(tuner[2]): PCRLock, "1"
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0840
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0432
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0841
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0842
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0843
Jan 1 03:50:30 ocur[21]: ocur: mux_pid_union:329 : [2] Adding pid 0x0844
Jan 1 03:50:30 ocur[21]: ocur: cas_SetChannel_delay:2657 : [2] Channel is scrambled, expecting CCI
Jan 1 03:50:30 ocur[21]: ocur: cas_SetChannel_delay:2666 : [2] Sending ca_pmt to CableCARD for program number 63 index 0
Jan 1 03:50:30 ocur[21]: upnp: [192.168.180.2] Get(tuner[2]): Frequency "261000"
Jan 1 03:50:30 ocur[21]: upnp: [192.168.180.2] Get(tuner[2]): Modulation "QAM256"
Jan 1 03:50:30 ocur[21]: upnp: [192.168.180.2] Get(mux[2]): ProgramNumber "63"
Jan 1 03:50:33 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:50:33 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:50:34 ocur[21]: ocur: cas_hal_ViewProgram_async:3506 WARNING: [2] Timed out waiting for CCI (recvd 0)
Jan 1 03:51:22 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:51:22 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 03:51:54 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 03:51:54 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 04:01:29 ocur[21]: ocur: debug_WriteStampFile:84 : STT time Thu Jul 26 01:51:16 2012 UTC
Jan 1 04:06:45 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 04:06:45 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 04:07:27 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 04:07:27 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 04:07:58 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 04:07:58 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 04:08:29 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 04:08:29 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success
Jan 1 04:08:59 ocur[21]: upnp: [192.168.180.2] Calling Action(ua[2]) UserActivityDetected
Jan 1 04:08:59 ocur[21]: upnp: [192.168.180.2] Action(ua[2]) UserActivityDetected success