Arris TM822G/Serial info

From TechInfoDepot
Jump to navigationJump to search

Info derived from

boot log

U-Boot 1.2.0 (Feb  1 2013 - 12:00:50)
PSPU-Boot 1.2.1.62

DRAM:  64 MB
Winbond W25Q128BV flash found
Flash: 16 MB
In:    serial
Out:   serial
Err:   serial
Skipping RAM test
*** ACTIMAGE = 2, will try to boot UBFI2 stored @0x487F0000
## Executing script at 487f0000
============== Running script =========
*** Running from UBFI2 partition @0x487F0000
Load address = 0x487f1670 (0x1670)
Kernel address = 0x487f16bc (0x16bc)
kernel size = 0xd1944
FS address = 0x488c3000 (0xd3000)
FS size = 0x43a000
NVRAM offset = 0xfa0000
NVRAM size = 0x50000
ARRS3 offset =
ARRS3 size =
UBFI3 offset =
UBFI3 size =
ARRS4 offset =
ARRS4 size =
UBFI4 offset =
UBFI4 size =
dual flash = 0
*** UBFI2 bootscript executed successfully.
Start booting...
## Booting image at 487f1670 ...
   Image Name:   Multi Image File
   Image Type:   ARM Linux Multi-File Image (uncompressed)
   Data Size:    5290320 Bytes =  5 MB
   Load Address: 80a00000
   Entry Point:  80a00000
   Contents:
   Image 0:   858436 Bytes = 838.3 kB
   Image 1:  4431872 Bytes =  4.2 MB
   Verifying Checksum ... OK
OK

Starting kernel ...

Starting LZMA Uncompression Algorithm.
Compressed file is LZMA format.
CPU: ARMv6-compatible processor [410fb764] revision 4 (ARMv6TEJ), cr=00c538ff
Machine: puma5
Ignoring unrecognised tag 0x00000000
Memory policy: ECC disabled, Data cache writethrough
Reserved 2048k DSP memory starting from Physical 0x83e00000
CPU0: D VIPT write-back cache
CPU0: I cache: 32768 bytes, associativity 4, 32 byte lines, 256 sets
CPU0: D cache: 16384 bytes, associativity 4, 32 byte lines, 128 sets
Real-Time Preemption Support (C) 2004-2006 Ingo Molnar
Built 1 zonelists.  Total pages: 15872
WARNING: experimental RCU implementation.
Interrupt controller revision : 4e822100
PID hash table entries: 256 (order: 8, 1024 bytes)
Power & Sleep Controller @ 0xd8621000 Initialized [id-0x44822905]
USB PHY POR Bypass enabled
Board type: tnetc550
Initialized Peripheral Port Remap Register to base : 0x50000000
Puma5 Timer0 initialized
Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
Mount-cache hash table entries: 512
PAL_cppi4Init : There is a room for 1314 more descriptors
Off chip memory allocated: 349184
QMgr Init: buffer after alignment: 83c00000
avalanche_ppd_init: PP system  initialized successfully.
avalanche_ppd_init(811):return code 0x00
HIL Core: Profile intrusive has been Loaded.
IP route cache hash table entries: 512 (order: -1, 2048 bytes)
TCP established hash table entries: 2048 (order: 4, 65536 bytes)
TCP bind hash table entries: 1024 (order: 2, 28672 bytes)
squashfs: LZMA suppport for slax.org by jro
TI LED driver initialized [major=254]
Loading cpgmac driver for puma5
   Rudek 00000002
Cpmac: Reset GPIO pin is set as 14
TI CPGMAC_F Linux DDA version 0.1 - CPGMAC_F DDC version 0.2
Cpmac: Installed 1 instances.
Serial Flash [Bus:0 CS:0] : w25q128bv 16384KB, 4096 sectors each   4KB
partitions[0] = {.name = U-Boot, .offset = 0x00000000,.size = 0x00020000 (128K) }
partitions[1] = {.name = env1, .offset = 0x00020000,.size = 0x00010000 (64K) }
partitions[2] = {.name = env2, .offset = 0x00030000,.size = 0x00010000 (64K) }
partitions[3] = {.name = UBFI1, .offset = 0x00040000,.size = 0x007b0000 (7872K) }
partitions[4] = {.name = UBFI2, .offset = 0x007f0000,.size = 0x007b0000 (7872K) }
partitions[5] = {.name = Kernel, .offset = 0x007f16bc,.size = 0x000d1944 (838K) }
partitions[6] = {.name = RootFileSystem, .offset = 0x008c3000,.size = 0x0043a000 (4328K) }
partitions[7] = {.name = nvram, .offset = 0x00fa0000,.size = 0x00050000 (320K) }
Serial Flash [Bus:0 CS:1] : No device found
VBUS off(0)-GPIO14(HIGH)
ether gadget: using random host ethernet address
eth_bind: create usb pids( )
VFS: Mounted root (squashfs filesystem) readonly.
init started: BusyBox v1.15.2 (2012-08-03 18:29:46 EDT)
starting pid 123, tty '/dev/tts/0': '/etc/init.d/rcS > /dev/console 2> /dev/console'

========================================================
ARRIS Touchstone Docsis 3.0 Firmware
Build release TS070555_080312_MODEL_7_8
Build date: Friday, August 03 2012, 18:30:58
Copyright (c) 2007 - 2011 ARRIS Group Inc.
========================================================

Running /etc/scripts/z_arris_check.sh...
Running /etc/scripts/sys_check.sh...
Running /etc/scripts/pacm_check.sh...
----------------------------------------------------------
Enable_Startup = 1
Startup_File = /etc/scripts/sys_startup.sh
----------------------------------------------------------
EMIF Controller and DDR PHY in Sync
Using DOCSIS Initialization process parameters: -us_channels 4
Using DOCSIS MAC Manager parameters: -x -m
starting pid 169, tty '/dev/tts/0': '/usr/sbin/mini_cli'
pcd: Starting TI Process Control Daemon.
pcd: Error: Unable to open configuration file /etc/scripts/arris_tr69.pcd.
pcd: Loaded 81 rules.
pcd: Initialization complete.
pcd: Starting process /usr/sbin/watchdog_rt (Rule SYSTEM_WATCHDOG).
pcd: Starting process /usr/sbin/logger (Rule SYSTEM_LOGGER).
TI Watchdog-RT daemon started <kick interval = 10 seconds>
pcd: Rule SYSTEM_WATCHDOG: Success (Process /usr/sbin/watchdog_rt (170)).
Delaying mini_cli execution until init complete
TI Logger: Init complete
pcd: Rule SYSTEM_LOGGER: Success (Process /usr/sbin/logger (171)).
pcd: Starting process /usr/sbin/gptimer (Rule SYSTEM_GPTIMER).
pcd: Starting process /usr/sbin/gim (Rule SYSTEM_GIM).
pcd: Rule SYSTEM_GPTIMER: Success (Process /usr/sbin/gptimer (172)).
[INFO] [COMMON_COMPONENTS.GPTIMER(pid=172)]: Initialized successfully, timer tick 50ms
[INFO] [COMMON_COMPONENTS.GIM(pid=173)]: GIM initializing...
[INFO] [COMMON_COMPONENTS.GIM(pid=173)]: GIM initialization complete. GIM_MODULE=3086
pcd: Rule SYSTEM_GIM: Success (Process /usr/sbin/gim (173)).
pcd: Rule SYSTEM_LASTRULE: Success.
pcd: Starting process echo (Rule DOCSIS_APPRUN).
pcd: Rule DOCSIS_APPRUN: Success (Process echo (175)).
pcd: Starting process /usr/sbin/docsis_init_once (Rule DOCSIS_INITONCE).
pcd: Starting process insmod (Rule DOCSIS_BRIDGE).
Dbridge MDF control init done
pcd: Rule DOCSIS_BRIDGE: Success (Process insmod (177)).
pcd: Starting process insmod (Rule DOCSIS_CNI).
pcd: Rule DOCSIS_CNI: Success (Process insmod (179)).
[WARNING] [DOCSIS.QOS(pid=176)]: Failed reset DSX TA SM to IDLE on TA DB Reset
pcd: Setting low priority for softirq-net-rx task.
pcd: Rule DOCSIS_INITONCE: Success (Process /usr/sbin/docsis_init_once (176)).
pcd: Starting process insmod (Rule DOCSIS_HALVLYNQ).
pcd: Starting process /usr/sbin/cm_status (Rule DOCSIS_CMSTATUS).
pcd: Rule DOCSIS_HALVLYNQ: Success (Process insmod (184)).
pcd: Starting process insmod (Rule DOCSIS_SOCIFDRV).
RX Init CPPI: open free descriptor queue
RX Init CPPI: allocate descriptors
init_cppi_rx: Allocate descriptors to the embedded free queues
RX Init CPPI: Fill free discriptor queue
RX Init CPPI: Set DMA channel
init_cppi_rx: Call PAL_cppi4RxChOpen channel=12, REF_CNT=1 BUF COUNT=512
RX Init CPPI: Init accumulator
RX Init RX CPPI pass
TX Init CPPI: open free descriptor queue
TX Init CPPI: allocate descriptors
init_cppi_tx: Allocate descriptors to the embedded free queues
TX Init CPPI: Fill free discriptor queue
TX Init CPPI: Create CPGMAC TX handler
TX Init CPPI: open mpeg TX channel
TX Init CPPI pass
WQ Init CPPI: open queue descriptor queue
WQ Init pass
MPEG driver initialization complete.
pcd: Rule DOCSIS_SOCIFDRV: Success (Process insmod (187)).
pcd: Starting process mknod (Rule DOCSIS_MKNODSOCIFDRV).
pcd: Rule DOCSIS_MKNODSOCIFDRV: Success (Process mknod (190)).
pcd: Starting process insmod (Rule DOCSIS_MNG).
pcd: Rule DOCSIS_CMSTATUS: Success (Process /usr/sbin/cm_status (185)).
pcd: Rule DOCSIS_MNG: Success (Process insmod (191)).
pcd: Starting process mknod (Rule DOCSIS_MKNODMNG).
pcd: Starting process insmod (Rule DOCSIS_KINTR).
pcd: Rule DOCSIS_MKNODMNG: Success (Process mknod (193)).
pcd: Starting process insmod (Rule DOCSIS_FILTERCLASS).
pcd: Rule DOCSIS_KINTR: Success (Process insmod (194)).
pcd: Starting process /usr/sbin/hal_event_mbox (Rule DOCSIS_HALEVENTMBOX).
pcd: Starting process /usr/sbin/hal_cmd_mbox (Rule DOCSIS_HALCMDMBOX).
pcd: Rule DOCSIS_FILTERCLASS: Success (Process insmod (197)).
pcd: Starting process /bin/sh (Rule DOCSIS_FCDEV).
pcd: Rule DOCSIS_HALCMDMBOX: Success (Process /usr/sbin/hal_cmd_mbox (206)).
pcd: Rule DOCSIS_HALEVENTMBOX: Success (Process /usr/sbin/hal_event_mbox (205)).
pcd: Starting process /usr/sbin/mlx (Rule DOCSIS_MLX).
pcd: Rule DOCSIS_FCDEV: Success (Process /bin/sh (208)).
pcd: Starting process /usr/sbin/dbridge_init (Rule DOCSIS_DBRIDGEINIT).
Ingress Hook on  eth0
Ingress Hook on  eth0
Dbridge Init SUCCESS
Autoconfiguration disabled for br0
Autoconfiguration disabled for lbr0
pcd: Rule DOCSIS_MLX: Success (Process /usr/sbin/mlx (213)).
pcd: Starting process /usr/sbin/hal_tuner_mgr (Rule DOCSIS_HALTUNERMGR).
HAL_MacDsUnicastDataFilterAdd: system_rev = 1
HAL_MacDsUnicastDataFilterAdd: system_rev = 1
Autoconfiguration disabled for lan0
[ERROR] [DOCSIS.HAL(pid=213)]:  ICC_send_message failed
Autoconfiguration disabled for wan0
HAL_MacDsUnicastDataFilterAdd: system_rev = 1
HAL_MacDsUnicastDataFilterAdd: system_rev = 1
Autoconfiguration disabled for cni0
pcd: Rule DOCSIS_HALTUNERMGR: Success (Process /usr/sbin/hal_tuner_mgr (231)).
pcd: Rule DOCSIS_DBRIDGEINIT: Success (Process /usr/sbin/dbridge_init (222)).
pcd: Starting process /bin/sh (Rule DOCSIS_DBRIDGEMDFINIT).
pcd: Starting process /bin/sh (Rule DOCSIS_DBRIDGEL2VPNDSI).
pcd: Starting process /usr/sbin/arris_init (Rule DOCSIS_ARRIS_INIT).
pcd: Rule DOCSIS_DBRIDGEMDFINIT: Success (Process /bin/sh (234)).
pcd: Starting process insmod (Rule DOCSIS_PP).
Create DOCSIS Packet Processor counters char device done
Init Docsis Packet Processor counters DB done.
DOCSIS Packet Processor counters control init done
DOCSIS Packet Processor psm control init done
DOCSIS Packet Processor control init done
pcd: Rule DOCSIS_PP: Success (Process insmod (245)).
pcd: Starting process /bin/sh (Rule DOCSIS_PPDEV).
pcd: Rule DOCSIS_DBRIDGEL2VPNDSI: Success (Process /bin/sh (235)).
pcd: Rule DOCSIS_HALREADY: Success.
pcd: Starting process /usr/sbin/mptint (Rule DOCSIS_MPT_INT).
pcd: Rule DOCSIS_MPT_INT: Success (Process /usr/sbin/mptint (250)).
pcd: Starting process /usr/sbin/dispatcher (Rule DOCSIS_DISPATCHER).
pcd: Starting process /usr/sbin/bpi_auth (Rule DOCSIS_BPIAUTH).
netlink_open: initialized fd 6
pcd: Rule DOCSIS_DISPATCHER: Success (Process /usr/sbin/dispatcher (251)).
pcd: Rule DOCSIS_PPDEV: Success (Process /bin/sh (248)).
pcd: Rule DOCSIS_BPIAUTH: Success (Process /usr/sbin/bpi_auth (252)).
pcd: Starting process /usr/sbin/bpi_tek (Rule DOCSIS_BPITEK).
pcd: Starting process /usr/sbin/bpi_sa_map (Rule DOCSIS_BPISAMAP).
[INFO] [ARRIS.INIT(pid=237)]: ************** ARRIS Init ***************
[INFO] [ARRIS.NVM(pid=237)]: NV Memory Manager Initialization complete
[INFO] [ARRIS.DB(pid=237)]: Database Manager Initialization complete
[INFO] [ARRIS.DB(pid=237)]: ARRIS MTA Surveillance Port Initialization complete
[INFO] [ARRIS.DB(pid=237)]: ARRIS SPEEDTEST Database Manager Initialization complete
[INFO] [ARRIS.INIT(pid=237)]: **************** App Mode ***************
pcd: Rule DOCSIS_BPITEK: Success (Process /usr/sbin/bpi_tek (258)).
pcd: Rule DOCSIS_BPISAMAP: Success (Process /usr/sbin/bpi_sa_map (259)).
[INFO] [ARRIS.INIT(pid=237)]: Embedded bootrev 1.2.1.61 not newer than existing rev
Thu Jan  1 00:00:09 1970 [USER_DEFINED] [ARRIS.RESET_REASON(pid=237)(INIT_check_reset_type:992)]: HW or Power-On Reset
[INFO] [ARRIS.INIT(pid=237)]: ********** Initializing Telnet **********
[WARNING] [ARRIS.SYS(pid=237)]: PCD delayed in starting rule DOCSIS_ARRIS_TELNET!
[INFO] [ARRIS.INIT(pid=237)]: ********** Initializing SSH **********
pcd: Rule DOCSIS_ARRIS_INIT: Success (Process /usr/sbin/arris_init (237)).
pcd: Starting process dropbear (Rule DOCSIS_ARRIS_DROPBEAR).
pcd: Starting process utelnetd (Rule DOCSIS_ARRIS_TELNET).
pcd: Starting process /usr/sbin/snmp_agent_cm (Rule DOCSIS_SNMPAGENT).
pcd: Starting process /usr/sbin/qos_dsx_sm (Rule DOCSIS_QOSDSXSM).
pcd: Starting process /usr/sbin/docsis_mac_driver (Rule DOCSIS_MACDRIVER).
pcd: Starting process /usr/sbin/ti_webserver (Rule DOCSIS_ARRIS_WEBSERVER).
pcd: Starting process /usr/sbin/ledd (Rule DOCSIS_ARRIS_LED).
pcd: Rule DOCSIS_ARRIS_LED: Success (Process /usr/sbin/ledd (270)).
pcd: Rule DOCSIS_ARRIS_WEBSERVER: Success (Process /usr/sbin/ti_webserver (269)).
pcd: Rule DOCSIS_ARRIS_TELNET: Success (Process utelnetd (264)).
pcd: Rule DOCSIS_ARRIS_DROPBEAR: Success (Process dropbear (263)).
pcd: Starting process /sbin/udhcpd (Rule DOCSIS_ARRIS_DHCP_SERV).
pcd: Rule DOCSIS_ARRIS_DHCP_SERV: Success (Process /sbin/udhcpd (271)).
[WARNING] [ARRIS.SYS(pid=237)]: PCD delayed in starting rule DOCSIS_ARRIS_DROPBEAR!
[INIT] [ARRIS.RF_SCAN(pid=237)]: Initializing RF frequency scanner
[INFO] [ARRIS.RF(pid=237)]: RF Initialization complete
[INFO] [ARRIS.INIT(pid=237)]: ********* ARRIS Init Complete  **********
telnetd: starting
  port: 23; interface: any; login program: /usr/sbin/mini_cli
[263] Jan 01 00:00:14 Not backgrounding
info, udhcp server (v0.9.7) started
info, interface: lan0, start : c0a86414 end : c0a86464




                           `!MMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMM::~
                               ``!MMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMMM!:~` ~
                                    !MMMMMMMMMMMMMMMMMMMMMMM!:`     :~~
                                     :MMMMMMMMMMMMMMMM!~        :~~~~
                                   .:MMMMMMMMMM!:~           ~~~~~~
                              ..:MMMMMMM!:~`             :~~~~~~~
                         .:MMMMMM:~`                ::~~~~~~~~~
                    .:MMMMM:~                    .!!!!!!: ~~~~
              ..:MMM:~`                         .!!!!`      ~
        ..:MM:~`                                !!`
   .:M:~`


        AA              RRRRRRR          RRRRRRR          III         SSSSS
       AAAA             RRRRRRRRR        RRRRRRRRR        III       SSSSSSSSS
      AAAAAA            RRR    RRR       RRR    RRR       III      SSS    SS
     AAA  AAA           RRR   RRRR       RRR   RRRR       III       SSSS
    AAA    AAA          RRRRRRRRR        RRRRRRRRR        III         SSSSSS
   AAAAAAAAAAAA         RRR  RRR         RRR  RRR         III            SSSS
  AAA        AAA        RRR   RRR        RRR   RRR        III       SS    SSS
 AA            AA       RRR    RRR       RRR    RRR       III      SSSSSSSSS
A                A      RRR       R      RRR       R      III        SSSSS


          Copyright 2012, ARRIS Group, Inc., All rights reserved



pcd: Rule DOCSIS_QOSDSXSM: Success (Process /usr/sbin/qos_dsx_sm (266)).

Enter password> [INFO] [DOCSIS.HAL(pid=231)]: Device #0 initialization (HAL_mxl261DeviceGlobalInitSrv) Start
pcd: Rule DOCSIS_SNMPAGENT: Success (Process /usr/sbin/snmp_agent_cm (265)).
SNMPA_CreateSocketEntry : type 0, addr = 0, port = 0, ifname = /var/tmp/cm_snmp_ctrl List = (nil)
SNMPA_CreateSocketEntry : Entry = 0x6F860
Cntl Entry created
pcd: Starting process /usr/sbin/eventmgr_cm (Rule DOCSIS_EVENTMNGR).
[INFO] [COMMON_COMPONENTS.EVENT_MNGR(pid=273)]: Control socket created successfully
[INIT] [COMMON_COMPONENTS.EVENT_MNGR(pid=273)]: Init successfully
[INFO] [COMMON_COMPONENTS.EVENT_MNGR(pid=273)]: Opening SNMP service
[INFO] [COMMON_COMPONENTS.EVENT_MNGR(pid=273)]: Starting main loop
pcd: Rule DOCSIS_EVENTMNGR: Success (Process /usr/sbin/eventmgr_cm (273)).
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR8).
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR7).
[ERROR] [DOCSIS.HAL(pid=267)]: HAL_MacDsPdsp2Init: ---->>>> PDSP2_INFO(0x4bd08) = 0x009C4000
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR6).
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR5).
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR4).
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR3).
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR2).
pcd: Starting process /usr/sbin/downstream_manager (Rule DOCSIS_DSMNGR1).
pcd: Rule DOCSIS_DSMNGR6: Success (Process /usr/sbin/downstream_manager (276)).
pcd: Rule DOCSIS_DSMNGR5: Success (Process /usr/sbin/downstream_manager (277)).
pcd: Rule DOCSIS_DSMNGR7: Success (Process /usr/sbin/downstream_manager (275)).
pcd: Rule DOCSIS_DSMNGR8: Success (Process /usr/sbin/downstream_manager (274)).
pcd: Rule DOCSIS_DSMNGR3: Success (Process /usr/sbin/downstream_manager (279)).
pcd: Rule DOCSIS_DSMNGR2: Success (Process /usr/sbin/downstream_manager (280)).
pcd: Rule DOCSIS_DSMNGR1: Success (Process /usr/sbin/downstream_manager (281)).
pcd: Rule DOCSIS_DSMNGR4: Success (Process /usr/sbin/downstream_manager (278)).
pcd: Starting process /usr/sbin/upstream_manager (Rule DOCSIS_USMNGR1).
pcd: Starting process /usr/sbin/upstream_manager (Rule DOCSIS_USMNGR2).
[INFO] [DOCSIS.HAL(pid=267)]:  PGA enable. AMP_OFF - Normal
pcd: Starting process /usr/sbin/upstream_manager (Rule DOCSIS_USMNGR3).
pcd: Starting process /usr/sbin/upstream_manager (Rule DOCSIS_USMNGR4).
pcd: Rule DOCSIS_USMNGR3: Success (Process /usr/sbin/upstream_manager (284)).
pcd: Rule DOCSIS_USMNGR4: Success (Process /usr/sbin/upstream_manager (285)).
pcd: Rule DOCSIS_USMNGR2: Success (Process /usr/sbin/upstream_manager (283)).
pcd: Rule DOCSIS_USMNGR1: Success (Process /usr/sbin/upstream_manager (282)).
SNMPA_CreateSocketEntry : type 1, addr = c0a86401, port = 161, ifname = lan0 List = 0x6f860
SNMPA_CreateSocketEntry : Entry = 0x6FD58
SNMPA_CreateSocketEntry : type 1, addr = c0a86401, port = 162, ifname = lan0 List = 0x6f860
SNMPA_CreateSocketEntry : Entry = 0x70850
pcd: Rule DOCSIS_MACDRIVER: Success (Process /usr/sbin/docsis_mac_driver (267)).
pcd: Starting process /usr/sbin/docsis_mac_manager (Rule DOCSIS_MACMANAGER).
pcd: Rule DOCSIS_MACMANAGER: Success (Process /usr/sbin/docsis_mac_manager (286)).
pcd: Rule DOCSIS_LASTRULE: Success.
pcd: Starting process insmod (Rule PACM_MTANI).
mtani: Network interface initialized successfully
BUG: scheduling with irqs disabled: insmod/0x00000000/287
caller is wait_for_completion+0x94/0xf0
RTNL: assertion failed at net/ipv4/devinet.c (1115)
RTNL: assertion failed at net/ipv6/addrconf.c (2170)
RTNL: assertion failed at net/ipv6/addrconf.c (1644)
RTNL: assertion failed at net/ipv6/addrconf.c (456)
RTNL: assertion failed at net/ipv6/addrconf.c (367)
Autoconfiguration disabled for esafe0
mtani: Network interface registered successfully
pcd: Rule PACM_MTANI: Success (Process insmod (287)).
pcd: Starting process /usr/sbin/pacm_init (Rule PACM_INIT).
Production config saved to NVRAMAutoconfiguration disabled for mta0

 PACM_MANAGER_HAL_MacDsUnicastDataFilterAdd: system_rev = 1
TOD_DELAY_SEC_PRHAL_MacDsUnicastDataFilterAdd: system_rev = 1
OV InitialisedSTA MAC initialized to E8:3E:FC:00:04:20PACM - Exec PACM_Prov_DbInit
PACM - Exec PACM_MSM_DbInit
PACM - Exec Security_InitDataBase
PACM - Exec SnmpDB_Init
pcd: Rule PACM_INIT: Success (Process /usr/sbin/pacm_init (291)).
pcd: Starting process insmod (Rule VOICE_INSMXPMOD).
pcd: Starting process /usr/sbin/pacm_vendor_app (Rule PACM_VENDORAPP).
pcd: Starting process /usr/sbin/pacm_snmp_agent (Rule PACM_SNMP).
pcd: Starting process /usr/sbin/pacm_security (Rule PACM_SECURITY).
pcd: Starting process /usr/sbin/pacm_event_mgr (Rule PACM_EVENTMGR).
pcd: Starting process /usr/sbin/pacm_doim (Rule PACM_DOIM).
[INFO] [PACM.PROVISION(pid=291)]: Initializing Privisioning Database
[INFO] [PACM.PROVISION(pid=291)]: PACM_Prov_DbDefaultSet: entry point mask = 02
[INFO] [PACM.PROVISION(pid=291)]: set default opt 122
[INFO] [PACM.MTA_CONTROL(pid=291)]: Initializing MSM Database
[INFO] [PACM.SECURITY(pid=291)]: Security_InitDataBase succeeded data address 43f4d8c
MXP_TMR: Calibrating MXP Timer...   Ticks/sec=200
Starting MXP timer
MXP module loaded
pcd: Rule VOICE_INSMXPMOD: Success (Process insmod (292)).
pcd: Starting process insmod (Rule VOICE_INSMXPMEM).
[INFO] [PACM.EV_MGR(pid=296)]: Success initialize enterprise number
[INFO] [PACM.EV_MGR(pid=296)]: Success initialize vendor ID: ARRIS
[INFO] [PACM.EV_MGR(pid=296)]: Success initialize vendor event table
[INFO] [COMMON_COMPONENTS.EVENT_MNGR(pid=296)]: Control socket created successfully
[INIT] [COMMON_COMPONENTS.EVENT_MNGR(pid=296)]: Init successfully
pcd: Rule PACM_EVENTMGR: Success (Process /usr/sbin/pacm_event_mgr (296)).
MXP memory module loaded
pcd: Rule VOICE_INSMXPMEM: Success (Process insmod (301)).
[INFO] [COMMON_COMPONENTS.EVENT_MNGR(pid=296)]: Starting main loop
pcd: Starting process insmod (Rule VOICE_INSDSPMOD).
pcd: Rule PACM_DOIM: Success (Process /usr/sbin/pacm_doim (297)).
205: PUMAV DSPSS is IN  RESET (pre)
208: PUMAV TDM is OUT of  RESET (pre)
58: Disabling  PUMAV DSPSS
61: Taking PUMAV DSPSS IN  RESET
75: Set DSPSS in power saving mode PSC_DISABLE...
pcd: Rule PACM_VENDORAPP: Success (Process /usr/sbin/pacm_vendor_app (293)).
[INFO] [PACM.DOIM(pid=297)]: Start DOIM proc
[INFO] [PACM.DOIM(pid=297)]: DOIM start running
[INFO] [PACM.DOIM(pid=297)]: DoIM was initiated succesfully
[INFO] [PACM.VENDOR_APP(pid=293)]: Starting vendor application main loop
Processing configuration file...
Found command: sysdescr eMTA SNMP Agent
Found command: read-community   public
Found command: write-community  private
Found command: trap-community   SNMP-trap
Found command: nv-file     /etc/snmp_mta_nv.txt
Hit EOF of config file


****HOST INFORMATION****
sysDescr is "eMTA SNMP Agent"
sysContact is "ARRIS Interactive, L.L.C."
sysLocation is "ARRIS USA"
System Boot time was Thu Jan  1 00:00:22 1970
System object identifier has 14 components:
        0.0.0.0.0.0.0.0.0.0.0.0.0.0.


****SNMP AGENT INFORMATION****
Community names:
        Read:   public
        Write:  private
        Trap:   SNMP-trap
         nv-file: /etc/snmp_mta_nv.txt


SNMPA_CreateSocketEntry : type 0, addr = 0, port = 0, ifname = /var/tmp/mta_snmp_ctrl List = (nil)
SNMPA_CreateSocketEntry : Entry = 0x64878
Cntl Entry created
HW_DSP module loaded
pcd: Rule VOICE_INSDSPMOD: Success (Process insmod (304)).
pcd: Starting process insmod (Rule VOICE_INSTELEHAL).
[INFO] [PACM.SNMP(pid=294)]: Finished to init eSafe
[INFO] [PACM.SNMP(pid=294)]: Setting engine ID to 0000118b03e83efc00042001
pcd: Rule PACM_SECURITY: Success (Process /usr/sbin/pacm_security (295)).
TIUHAL: Finished TDM configuration
TIUHAL using DOCSIS clock source
pcd: Rule VOICE_INSTELEHAL: Success (Process insmod (308)).
pcd: Starting process udevstart (Rule VOICE_UDEVSTART).
[INFO] [PACM.SECURITY(pid=295)]: Start Security Manager
[INFO] [PACM.SECURITY(pid=295)]: Security_StateMachineInit
[INFO] [PACM.SECURITY(pid=295)]: Security CLI redirected to console
[INFO] [PACM.SECURITY(pid=295)]: Allocated tickets (FLASH storage is  enabled)
[ERROR] [PACM.SECURITY(pid=295)]: Failed to get tickets from FLASH, using local
[INFO] [PACM.SECURITY(pid=295)]: Ticket store initialized current version 986bd320
[INFO] [PACM.SECURITY(pid=295)]: Init Km Realms
[INFO] [PACM.SECURITY(pid=295)]: Init Km CMS
[INFO] [PACM.SECURITY(pid=295)]: Created ICC dest point
[INFO] [PACM.SECURITY(pid=295)]: Realm DB Init
[INFO] [PACM.SECURITY(pid=295)]: Init SecProvdata
[INFO] [PACM.SECURITY(pid=295)]: Resetting Prov Serv Data
[INFO] [PACM.SECURITY(pid=295)]: InvalidateFlashTickets from NV: 0
[INFO] [PACM.SECURITY(pid=295)]: Init CMS DB
[INFO] [PACM.SECURITY(pid=295)]: Endpoint DB reset
[INFO] [PACM.SECURITY(pid=295)]: Init Km Realms
[INFO] [PACM.SECURITY(pid=295)]: Init Km CMS
[INFO] [PACM.SECURITY(pid=295)]: Reset certs, location: /nvram/2/certificates
[INFO] [PACM.SECURITY(pid=295)]: Certs in nvram ok
[INFO] [PACM.SECURITY(pid=295)]: Spawn all Security threads
[WARNING] [PACM.SECURITY(pid=295)]: Socket Manager not created
[INFO] [PACM.SECURITY(pid=295)]: Succesfully got IPC socket 5
[INFO] [PACM.SECURITY(pid=295)]: DHDb manager created
[INFO] [PACM.SECURITY(pid=295)]: DH DB manager runs
pcd: Rule VOICE_UDEVSTART: Success (Process udevstart (314)).
pcd: Starting process /usr/sbin/ggncs (Rule VOICE_GGAPP).
[INFO] [PACM.VOIM(pid=295)]: VoIM was initiated succesfully with ID 2061
[INFO] [PACM.SECURITY(pid=295)]: SecurityManager start....
[INFO] [PACM.SECURITY(pid=295)]: Security Manager going to wait for icc messages
Initializing signal_handler
tcb_alloc: task id=1,name=mxproot,busy=1
tcb_alloc: task id=2,name=XSPY,busy=1
tcb_alloc: task id=3,name=XTELNET,busy=1
tcb_alloc: task id=4,name=ROOT,busy=1
>>>> rootInit <<<<<
Creating Golden Gateway application...
Registered with TIUHAL version 3
TID ID string detected: VE88276PTC014
tiuhw_settcb_alloc: task id=5,name=NWIF01,busy=1
tcb_alloc: task id=6,name=DQMM,busy=1
up_chipselect: cs for tcid 0 = 0 fxo: 33
tiuhw_setup_chipselect: cs for tcid 1 = 0 fxo: 33
Loading dsp image: /lib/dspimage.ld
tcb_alloc: task id=7,name=DLMM,busy=1
tcb_alloc: task id=8,name=ISU,busy=1
tcb_alloc: task id=9,name=VIM,busy=1
tcb_alloc: task id=10,name=CCU,busy=1
tcb_alloc: task id=11,name=RTCP,busy=1
tcb_alloc: task id=12,name=PMA,busy=1
tcb_alloc: task id=13,name=DEX,busy=1
DSPMOD query DSP address syscall: DSP Start address = 0x83E00000
HW_DSP DSP External Config Register set to: 0x83000000, Start: 0x83E00000
58: Disabling  PUMAV DSPSS
61: Taking PUMAV DSPSS IN  RESET
75: Set DSPSS in power saving mode PSC_DISABLE...
tcb_alloc: task id=14,name=CPMU,busy=1
DSP external memory mapped to 0x047C4000 (User Sp) = 0x83E00000 (Kernel Sp)
dimhw_init_dsp: dpr_base mapped to 0x039C4000 (User sp)
tiuo_hw_init, tcid 0, timeslot 1
tiuo_hw_init, tcid 1, timeslot 2
[INFO] [PACM.MTA_CONTROL(pid=316)]: PACM_MSMGetLoopVoltageStatusInit: Inform voice of voltage state Down
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - NCSDK Version: 2.7.0
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - ROOT:Number of tcids in the system  2: 2: 2: 7
[ERROR] [VOICE.XSPY(pid=316)]: 0000000tcb_alloc: task id=15,name=tLCMSG,busy=1
tcb_alloc: task id=16,name=tLC,busy=1
770 - MAS Version: 2.7.2(Build 55)
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - Following features compiled out of the build:
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_DNLD_PAGED_BOOT
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_DNLD_DIRECT
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_DNLD_MSG
[ERROR] [VOICE.XStcb_alloc: task id=17,name=tCPCLI,busy=1
tcb_alloc: task id=18,name=tCPCAS,busy=1
tcb_alloc: task id=19,name=tCALLP,busy=1
tcb_alloc: task id=20,name=NMM,busy=1
tcb_alloc: task id=21,name=VOIMFE,busy=1
PY(pid=316)]: 0000000770 -      DIM_DNLD_RST_DIRECT
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     Dtcb_free: task id=4, name=ROOT
IM_CHAN_IF
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     ANNC_IF
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_RTCP_IF
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_SIG_IF
[ERROR]58: Disabling  PUMAV DSPSS
61: Taking PUMAV DSPSS IN  RESET
75: Set DSPSS in power saving mode PSC_DISABLE...
 [VOICE.XSPY(pid=316)]: 0000000770 -    DIM_AUDIO_IF
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_AFE_IF
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_LOMGR_GEN_FIFO
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_LOMGR_CHN_FIFO
hw_config: tcid: 0 onhook volts = 48, offhook current = 26,  country = 1
hw_config: tcid: 0 ringer type: 1 frequency: 20 amplitude: 66 offset: 0
Received request for gain settings for TCID: 0, rx = -4, tx = -2
hw_config: tcid: 1 onhook volts = 48, offhook current = 26,  country = 1
hw_config: tcid: 1 ringer type: 1 frequency: 20 amplitude: 66 offset: 0
Received request for gain settings for TCID: 1, rx = -4, tx = -2
[  1] Voice> FXS FXR = 16
Attempting to delete TCB: 4
XtDelete called
lmxp_tcb_free called for 4
pcd: Rule VOICE_GGAPP: Success (Process /usr/sbin/ggncs (316)).
pcd: Rule VOICE_LASTRULE: Success.
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_LOMGR_MAIL_BOX
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     PROTO_AAL2
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     PROTO_CLRDT
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     PROTO_MODEM
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_3PROC
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     DIM_DETAILED_SPY
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - Following features not defined in build and have been enabled
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - by default; REVIEW to remove potential unecessary code:
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 -     All features are accounted for!!!
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - ISU: isu_module_init isu_event_base 0x0, task 0x00000000

[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - ISU: isu_create 0x00000008 queue 1

[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - CCU: ccu_module_init ccu_event_base 0x0, task 0x00000000

[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - CCU: ccu_create 0x0000000A queue 1 watchdog timer 2

[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - DIM: status_request_period out of range [20 - 10000]
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - DIM: status_request_period set to default [1000]
[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - TIU max tcids = 2, poll mode = 0, poll period = 5

[ERROR] [VOICE.XSPY(pid=316)]: 0000000770 - TIU: Polling period must be multiple of 2 msec
[ERROR] [VOICE.XSPY(pid=316)]: 0000000774 - xSPU: init xgcpif error, can't continue
[INFO] [ARRIS.SYS(pid=316)]: Arris PacketCable Initialization Start
[INFO] [ARRIS.INIT(pid=316)]: LC_init process
[INFO] [PACM.MTA_CONTROL(pid=316)]: PACM_MSMGetLoopVoltageStatusInit: Inform voice of voltage state Down
[INFO] [PACM.MTA_CONTROL(pid=316)]: PACM_MSMGetLoopVoltageStatusInit: Inform voice of voltage state Down
[INFO] [PACM.MTA_CONTROL(pid=316)]: PACM_MSMGetLoopVoltageStatusInit: Inform voice of voltage state Down
[INFO] [PACM.MTA_CONTROL(pid=316)]: PACM_MSMGetLoopVoltageStatusInit: Inform voice of voltage state Down
[INFO] [ARRIS.SYS(pid=316)]: Arris PacketCable Initialization Done
[INFO] [ARRIS.INIT(pid=316)]: LC task started
[INFO] [ARRIS.INIT(pid=316)]: LC init complete, starting TOS
[INFO] [ARRIS.INIT(pid=316)]: LCMSG task started
[ERROR] [VOICE.XSPY(pid=316)]: 0000000807 - ROOT: VOIM_FE initialized
[INFO] [PACM.MTA_CONTROL(pid=316)]: PACM_MSMGetLoopVoltageStatusInit: Inform voice of voltage state Down
[INFO] [PACM.MTA_CONTROL(pid=316)]: PACM_MSMGetLoopVoltageStatusInit: Inform voice of voltage state Down
[ERROR] [VOICE.XSPY(pid=316)]: 0000000812 - DIM: 0:*, dimp_find_and_init_lo_mgr(). max simple msg len (128) < sizeof(mgb_t)(130)
[INFO] [PACM.VOIM(pid=294)]: VoIM was initiated succesfully with ID 2054
pcd: Rule PACM_SNMP: Success (Process /usr/sbin/pacm_snmp_agent (294)).
pcd: Rule PACM_INITDONE: Success.
pcd: Starting process /usr/sbin/vendor_psm (Rule PACM_APPPSM).
pcd: Starting process /usr/sbin/pacm_mta_control (Rule PACM_MTACTRL).
pcd: Rule PACM_MTACTRL: Success (Process /usr/sbin/pacm_mta_control (341)).
pcd: Rule PACM_APPPSM: Success (Process /usr/sbin/vendor_psm (340)).
pcd: Rule PACM_LASTRULE: Success.
pcd: Warning: Got READY event, but rule end condition is different.
PCD API: ICC wait return value -768
[WARNING] [PACM.VENDOR_APP(pid=340)]: DDR is not in Async mode, clock reduction will not be performed
[INFO] [PACM.MTA_CONTROL(pid=341)]: starting main of PACM MSM.....
[INFO] [PACM.VOIM(pid=341)]: VoIM was initiated succesfully with ID 2055
[INFO] [PACM.MTA_CONTROL(pid=341)]: Starting TI_PACM_MODULE_MSM
[INFO] [PACM.MTA_CONTROL(pid=341)]: Setting IPv6 Support to docsis - False
[INFO] [PACM.MTA_CONTROL(pid=293)]: send response message ID 0
[INFO] [PACM.MTA_CONTROL(pid=341)]: BBU system not supported
[INFO] [PACM.MTA_CONTROL(pid=341)]: LOOPV: Setting loop voltage status OFF
[ERROR] [VOICE.XSPY(pid=316)]: 0000001034 - DIM: , CHECKSUM=594758: Enabling  PUMAV DSPSS
61: Taking PUMAV DSPSS OUT of  RESET
88: Set DSPSS in power saving mode PSC_ENABLE...
5
[ERROR] [VOICE.XSPY(pid=316)]: 0000001035 - DIM: 0:*, Core Download complete!
[ERROR] [VOICE.XSPY(pid=316)]: 0000001039 - DIM: 0:*, dimp_find_and_init_lo_mgr(). max simple msg len (128) < sizeof(mgb_t)(130)
[ERROR] [VOICE.XSPY(pid=316)]: 0000001041 - DSPA: DSP 0,Image 0:Download done!
[INFO] [PACM.SNMP(pid=294)]: Setting Voice default configuration
[INFO] [PACM.SNMP(pid=294)]: Done Setting Voice default configuration, sending event MSM_ev_DefaultsSetted
[INFO] [PACM.MTA_CONTROL(pid=294)]: Sending Event MSM_ev_ConfigureDone, SNMP is ready.
[INFO] [PACM.VOIM(pid=341)]: Waiting until Voice init finishes
[INFO] [PACM.VOIM(pid=341)]: Voice Init done
[INFO] [PACM.MTA_CONTROL(pid=341)]: Starting MSM.....
[INFO] [PACM.MTA_CONTROL(pid=341)]: enter MSM_act_StartCM
[INFO] [DOCSIS.ESAFE(pid=341)]: Init ESAFE dev: 0 - mta0, RngholdoffMask 0x00000004, DHCP str EMTA, MAC Address E8:3E:FC:00:04:20
[INFO] [DOCSIS.CHM(pid=267)]: Init 8 DOCSIS DS channels
[INFO] [DOCSIS.CHM(pid=267)]: Init 4 US channels
[INFO] [DOCSIS.FREQ_SCAN(pid=267)]:
[INFO] [DOCSIS.FREQ_SCAN(pid=267)]: ***   CM SYNCHRONIZING...   ***
[INFO] [DOCSIS.FREQ_SCAN(pid=267)]:
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 669000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 714000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   669000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 0 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 669000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 729000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 774000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   729000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 669000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 729000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 753000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 798000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   753000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 729000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 753000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> Changing scan state from QAM Detection (0) to Preset (1) Cycle=1 Dwell=200
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> Changing scan state from Preset (1) to WB Quick (2) Cycle=1 Dwell=100
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 954000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 999000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 993000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 987000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 981000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 975000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 969000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 963000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 957000000 Hz
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on secondary DS 951000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 753000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 951000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 951000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 945000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 939000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 933000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 927000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 921000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 915000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 909000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 858000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 903000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 897000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 891000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 885000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 879000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 873000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 867000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 861000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 855000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 849000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 843000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 837000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 831000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 825000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 819000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 813000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 762000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 807000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 801000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 795000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 789000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 783000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 777000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 771000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 765000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 759000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 753000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 747000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 741000000 Hz
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on secondary DS 735000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 951000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 735000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 735000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 729000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 723000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 717000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 666000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 711000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 705000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 699000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 693000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 687000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 681000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 675000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 669000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 663000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 657000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 651000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 645000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 639000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 633000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 627000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 621000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 570000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 615000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 609000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 603000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 597000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 591000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 585000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 579000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 573000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 669000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 714000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   669000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 735000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 669000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 729000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 774000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   729000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 669000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 729000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 570000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 567000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 561000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 555000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 549000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 543000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 537000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 531000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 525000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 474000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 519000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 513000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 507000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 501000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 495000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 489000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 483000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 477000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 471000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 465000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 459000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 453000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 447000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 441000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 435000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 429000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 378000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 423000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 417000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 411000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 405000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 399000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 393000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 387000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 381000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 375000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 369000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 363000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 357000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 351000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 345000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 339000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 333000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 282000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 327000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 321000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 315000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 309000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 303000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 297000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 291000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 285000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 279000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 273000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 267000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 261000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 255000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 249000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 243000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 237000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 186000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 231000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 225000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 219000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 213000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 207000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 201000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 195000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 189000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 183000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 177000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 171000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 165000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 159000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 153000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 147000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 141000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 90000000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 135000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 129000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 123000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 117000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 111000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 105000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 99000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 93000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 753000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 798000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   753000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 729000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 753000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 669000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 714000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   669000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 753000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 669000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: Approx Scan speed on previous cycle:  Freqs=152 Time=5.430091 (27.992 f/s)
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> Changing scan state from WB Quick (2) to WB HRC Quick (3) Cycle=1 Dwell=100
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 952750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 997750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 991750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 985750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 979750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 973750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 967750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 961750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 955750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 949750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 943750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 937750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 931750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 925750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 919750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 913750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 907750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 856750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 901750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 895750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 889750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 883750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 877750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 871750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 865750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 859750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 853750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 847750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 841750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 835750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 829750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 823750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 817750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 811750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 760750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 805750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 799750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 793750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 787750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 781750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 775750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 769750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 763750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 757750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 751750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 745750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 739750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 733750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 727750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 721750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 715750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 664750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 709750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 703750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 697750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 691750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 685750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 679750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 673750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 667750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 661750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 655750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 649750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 643750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 637750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 631750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 625750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 619750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 568750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 613750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 607750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 601750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 595750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 589750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 583750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 577750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 571750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 565750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 559750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 553750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 547750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 541750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 535750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 529750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 523750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 729000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 774000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   729000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 669000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 729000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> NB Scanning 753000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 798000000 Hz (96000000 CBW)
[INFO] [DOCSIS.DS(pid=281)]: DS[1]: Try to lock on primary DS   753000000Hz...
[WARNING] [DOCSIS.CHDB_RX(pid=281)]: Could not find DCID for freq 729000000 (port 1)
[WARNING] [DOCSIS.DS(pid=281)]: Failed to Find DCID for DS Port 1.
[INFO] [DOCSIS.DS(pid=281)]: (port 1) Failed to lock on frequency 753000000Hz. reason 4
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 472750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 517750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 511750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 505750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 499750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 493750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 487750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 481750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 475750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 469750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 463750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 457750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 451750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 445750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 439750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 433750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 427750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 376750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 421750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 415750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 409750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 403750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 397750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 391750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 385750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 379750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 373750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 367750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 361750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 355750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 349750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 343750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 337750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 331750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 280750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 325750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 319750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 313750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 307750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 301750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 295750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 289750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 283750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 277750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 271750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 265750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 259750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 253750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 247750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 241750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 235750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 184750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 229750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 223750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 217750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 211750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 205750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 199750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 193750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 187750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 181750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 175750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 169750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 163750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 157750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 151750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 145750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 139750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 88750000 Hz (96000000 CBW)
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 133750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 127750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 121750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 115750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 1 Scanning 109750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 2 Scanning 103750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 3 Scanning 97750000 Hz
[INFO] [ARRIS.RF_SCAN(pid=281)]: ----> WB 4 Scanning 93000000 Hz
[INFO] [ARRIS.RF_SCAN(pid=267)]: Approx Scan speed on previous cycle:  Freqs=152 Time=5.090086 (29.862 f/s)
[INFO] [ARRIS.RF_SCAN(pid=267)]: ----> Changing scan state from WB HRC Quick (3) to WB MHz Quick (4) Cycle=1 Dwell=100
[INFO] [ARRIS.RF_SCAN(pid=267)]: --------> Tuner 0 moved to 954000000 Hz (96000000 CBW)