So... there I was... testing my 5101 w/haxor r39 just across the street (different CMTS) and I got autoserve to work with an "unleashed" cfg, 32Mbit all for me, yay (wish I live across the street, at least till the orgy lasts).
Went back home and obviously the CMTS has a different security preventing auto-serve, requesting HFC/CVC validation, social security, birthday, credit card validation code, and all of those fancy giblets requested in order to complete the secret handshake code and verify if "you got soul".
So, I was working with lite, and fired up the Diag version and came up with this under the autoserve one[provisioned]:
And this back at home... [NON-Provisioned]:
So there's a point in which the "new" CMTS detects the HFC is not-provisioned in his db and sends him to the penalty box (TFTP10.7.224.1). I have tried forcing the provisioned TFTP even w/o forcing any configs (as I know that will not work here)... And you guessed it.... It didn't work either...
Will the certs of the "old security" work at home as they are valid? Or those are auto-signed by haxx (crap for the CMTS)?
Any clues comparing these two logs will be greatly appreciated.
thnx!
Went back home and obviously the CMTS has a different security preventing auto-serve, requesting HFC/CVC validation, social security, birthday, credit card validation code, and all of those fancy giblets requested in order to complete the secret handshake code and verify if "you got soul".
So, I was working with lite, and fired up the Diag version and came up with this under the autoserve one[provisioned]:
Code:
Running the system...
Beginning Cable Modem operation...
0x0000f2bc [Scan Downstream Thread] BcmVendorCmDownstreamScanThread::ThreadMain: (Scan Downstream Thread) Scanning for a Downstream Channel...
mot_scanList: Setting override freq @ 0
Favorite[0].freq = 723000000
Attempting Downstream FEC lock @ freq= 723000000 Hz, QAM64/256
CM> Found energy at frequency 723000000Hz! Publishing event kEventEnergyDetected...
0x0000f550 [CmDocsisCtlThread] BcmCmDocsisCtlThread::StartUsInit: (CmDocsisCtlThread) Locked on the downstream. Waiting for UCDs...
******************************************
DOWNSTREAM STATUS
******************************************
Tuner Frequency = 723000000 Hz
Carrier Offset = 5 Hz
Symbol rate = 5360537 sym/sec
SNR = 25 dB
QAM Mode = QAM256
Tuner AGC = 0xfff00000
IF AGC = 0x1a239624
Power Level = -17 dB
QAM = LOCKED
FEC = LOCKED
******************************************
CM> Selecting UCD for Us Channel 6
0x00010194 [CmDocsisCtlThread] BcmCmDocsisCtlThread::TestAndLaunchDsTimeSync: (CmDocsisCtlThread) starting ds time sync acquisition...
0x000104fa [CmDocsisCtlThread] BcmCmDocsisCtlThread::SyncDsSyncOk: (CmDocsisCtlThread) downstream time sync acquired...
0x000104fa [CmDocsisCtlThread] BcmCmDocsisCtlThread::DsSyncOkResumeUsInit: (CmDocsisCtlThread) pre-REG upstream target case...starting initial ranging.
Beginning initial ranging...
Using stored initial upstream power = 47.0 dBmV
0x000104fa [CmDocsisCtlThread] BcmCmDocsisCtlThread::SyncDsSyncOk: (CmDocsisCtlThread) rx unexpected kDsSyncOk indication...
Not logging event ID 2307948724, control for level 7 is 0.
CM> Adjusting the initial ranging power to 33.0 dBmV...
Logging event: No Ranging Response received - T3 time-out (US 6)
0x000108ec [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 67
elapsed time secs = 1
CM>
RNG-RSP Adj: tim=1862 power=24 freq=0 Stat=Continue
CM>
RNG-RSP Adj: tim=-1 power=24 freq=0 Stat=Continue
CM>
RNG-RSP Adj: tim=1 power=5 freq=-329 Stat=Success
******************************************
UPSTREAM STATUS
******************************************
Upstream Status = UP
Upstream Channel = 6
Upstream Frequency = 32000000 Hz
Upstream Power = 46 dBmV
Ranging SID = 0x95f
Upstream Symbol Rate = 2560000 sym/sec
******************************************
Calculating maximum number of IP filters:
Each IP filter consumes 1876 bytes of RAM.
Current free RAM is 417872 bytes.
Max heap reserved for IP filters (25%) = 104468
We can support 55 IP filters.
Calculating maximum number of LLC filters:
Each LLC filter consumes 716 bytes of RAM.
Current free RAM is 417852 bytes.
Max heap reserved for LLC filters (25%) = 104463
We can support 145 LLC filters.
Starting IP Initialization with DHCP...
DHCPc: Waiting 3 seconds before sending Discover; client id htype=1, value=00:1A:00:00:00:00
Not logging event ID 2307948624, control for level 7 is 0.
CM> DHCPc: Sending Discover packet; client id htype=1, value=00:1A:00:00:00:00
CM> DHCPc: Received an Offer from DHCP server 00:01:5C:xx:xx:xx (10.4.76.1); lease client id htype=1, value=00:1A:00:00:00:00
CM> DHCPc: Timed out waiting for offers for lease with client id htype=1, value=00:1A:00:00:00:00
DHCPc: Sending Request packet; client id htype=1, value=00:1A:00:00:00:00
DHCPc: Received an Ack from DHCP server 00:01:5C:xx:xx:xx (10.4.76.1); lease client id htype=1, value=00:1A:00:00:00:00
Current IP address is default 0.0.0.0.
0x00012fde [DHCP Client Thread] BcmEcosIpHalIf::ConfigureLeaseImpl: (IP Stack1 HalIf)
Configuring IP stack 1:
IP Address = 10.4.77.86 (primary IP address)
Subnet Mask = 255.255.252.0
Router = 10.4.76.1
IsPrimaryInterface = 1
Logging event: DHCP WARNING - Non-critical field invalid in response.
ARPing for default GW IP = 10.4.76.1
MAC = 00:01:5C:xx:xx:xx
DHCP completed successfully!
DHCP Settings:
Client Id = htype=1, value=00:1A:00:00:00:00
State = Renewing (5)
Static Lease = 0
AutoConfig Mode = IP, Subnet and Router
XID = 0x4780f64d
Number of Tries = 0
Max Discover Tries = 6
Max Request Tries = 6
DHCP server MAC addr = 00:01:5C:xx:xx:xx
Ignore NAKs = 0
My offered IP address = 10.4.77.86 (primary IP address)
(1) Subnet Mask = 255.255.252.0
(3) Router IP address = 10.4.76.1
(54) DHCP Server IP address = 192.168.10.9
(82) Relay Agent IP address = 10.4.76.1
TFTP Server IP address = 192.168.10.24
CM Configuration file = 'cm-001a0000000'
(2) UTC Time Offset = -18000 seconds
(4) Time Server IP address = 192.168.10.24
(6) Domain Name Server =
(7) Log Server IP address = 200.75.xxx.x
(51) Lease time = 3600 seconds
(58) T1 (renew) = 1800 seconds
(59) T2 (rebind) = 3150 seconds
Lease is infinite = 0
(122) PacketCable/CableHome -== SubOptions ==-
SubOpt(1) Primary Dhcp Server = 255.255.255.255
SB5102 CM Agent w/ BRCM Factory Support IpStackEvent: Ip=10.4.77.86, Subnet=255.255.252.0, Gateway=10.4.76.1
CmSnmpAgent::IpAddressAcquiredEvent for SB5102 CM Agent w/ BRCM Factory Support
IP addr = 10.4.77.86
Starting Time Of Day...
0x00013092 [CmDocsisIpThread] BcmDocsisTimeOfDayThread::SetTodServerIpAddress: (Time Of Day Thread) ToD servers: 192.168.10.24
Connecting to ToD server 192.168.10.24...
Sending UDP ToD request to server...
SNMP Agent Binding to 10.4.77.86:225
Not logging event ID 2291949724, control for level 7 is 0.
CM> UTC returned by ToD server 3562085236; UTC offset -18000
Current system time -> Fri Nov 16 15:07:16 2012
System start time -> Fri Nov 16 15:05:58 2012
Starting Tftp of configuration file...
Opening file 'cm-001a0000000' on 192.168.10.24 for reading...
tftp-enforce bypass is using 192.168.10.24:cm-001a0000000
Initiating fake TFTP Get (tftp-enforce bypass)
CM> 0x0001342a [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 78
elapsed time secs = 1
CM> Bypass failed.Error Operation timed out
0x00014d98 [CmDocsisIpThread] Tftp Client::GetReply: (Tftp Client) Timed out on socket select!
0x00014d98 [CmDocsisIpThread] Tftp Client::Send: (Tftp Client) Attempt #(1) Backoff (2) Exp Block #(1) Last Block #(0) Recv'd Block #(0)
tftp-enforce bypass is using 192.168.10.24:cm-001a0000000
Initiating fake TFTP Get (tftp-enforce bypass)
CM> 0x00015f5e [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 89
elapsed time secs = 1
CM> Bypass failed.Error Operation timed out
0x000170ca [CmDocsisIpThread] Tftp Client::GetReply: (Tftp Client) Timed out on socket select!
0x000170ca [CmDocsisIpThread] Tftp Client::Send: (Tftp Client) Attempt #(2) Backoff (4) Exp Block #(1) Last Block #(0) Recv'd Block #(0)
tftp-enforce bypass is using 192.168.10.24:cm-001a0000000
Initiating fake TFTP Get (tftp-enforce bypass)
CM> 0x00018a92 [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 101
elapsed time secs = 1
CM> 0x0001b5c6 [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 112
elapsed time secs = 1
CM> 0x0001dd12 [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 122
elapsed time secs = 1
CM> Bypass succeeded.File was 1304 bytes
0x00020198 [CmDocsisIpThread] Tftp Client::GetReply: (Tftp Client) Timed out on socket select!
0x00020198 [CmDocsisIpThread] Tftp Client::Send: (Tftp Client) Attempt #(3) Backoff (8) Exp Block #(1) Last Block #(0) Recv'd Block #(0)
tftp-enforce bypass is using 192.168.10.24:cm-001a0000000
Initiating fake TFTP Get (tftp-enforce bypass)
CM> 0x00020850 [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 133
elapsed time secs = 1
CM> Bypass failed.Error Operation timed out
0x0002338e [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 144
elapsed time secs = 1
CM> Logging event: Started Unicast Maintenance Ranging - No Response received - T3 time-out
0x000253d2 [CmDocsisIpThread] Tftp Client::GetReply: (Tftp Client) Timed out on socket select!
0x000253d2 [CmDocsisIpThread] Tftp Client::Send: (Tftp Client) Attempt #(4) Backoff (16) Exp Block #(1) Last Block #(0) Recv'd Block #(0)
tftp-enforce bypass is using 192.168.10.24:cm-001a0000000
Initiating fake TFTP Get (tftp-enforce bypass)
CM> 0x00025ec2 [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 155
elapsed time secs = 1
CM> Bypass failed.Error Operation timed out
0x00026b4c [CmDocsisIpThread] Tftp Client::GetReply: (Tftp Client) Timed out on socket select!
0x00026b4c [CmDocsisIpThread] Tftp Client::Send: (Tftp Client) Attempt #(5) Backoff (1) Exp Block #(1) Last Block #(0) Recv'd Block #(0)
tftp-enforce bypass is using 192.168.10.24:cm-001a0000000
Initiating fake TFTP Get (tftp-enforce bypass)
CM> Bypass failed.Error Operation timed out
0x000286fe [CmDocsisIpThread] Tftp Client::GetReply: (Tftp Client) Timed out on socket select!
0x000286fe [CmDocsisIpThread] Tftp Client::Send: (Tftp Client) Attempt #(1) Backoff (2) Exp Block #(3) Last Block #(2) Recv'd Block #(2)
Storing received cfg of size 1304 to memory
Tftp read < 512 bytes, we have reached end of file.
Tftp transfer complete!
TFTP Settings:
Stack Interface = 1
Server Ip Address = 192.168.10.24
Server Port Number = 32803
Total Blocks Read = 3
Total Bytes Read = 1304
Config file was read! IP Initialization completed...
MAX CPE per CM is being set to 32
TLV-11[1]: 1.3.6.1.2.1.69.1.2.1.7.1 -> 4 (i32)
TLV-11[2]: 1.3.6.1.2.1.69.1.2.1.2.1 -> 192.168.10.21
TLV-11[3]: 1.3.6.1.2.1.69.1.2.1.3.1 -> 255.255.255.255
TLV-11[4]: 1.3.6.1.2.1.69.1.2.1.4.1 -> private
TLV-11[5]: 1.3.6.1.2.1.69.1.2.1.5.1 -> 3 (i32)
TLV-11[6]: 1.3.6.1.2.1.69.1.2.1.6.1 -> @
TLV-11[7]: 1.3.6.1.2.1.69.1.2.1.7.2 -> 4 (i32)
TLV-11[8]: 1.3.6.1.2.1.69.1.2.1.2.2 -> 200.XX.XXX.26
TLV-11[9]: 1.3.6.1.2.1.69.1.2.1.3.2 -> 255.255.255.255
TLV-11[10]: 1.3.6.1.2.1.69.1.2.1.4.2 -> private
TLV-11[11]: 1.3.6.1.2.1.69.1.2.1.5.2 -> 3 (i32)
TLV-11[12]: 1.3.6.1.2.1.69.1.2.1.6.2 -> @
TLV-11[13]: 1.3.6.1.2.1.69.1.2.1.7.3 -> 4 (i32)
TLV-11[14]: 1.3.6.1.2.1.69.1.2.1.2.3 -> 192.168.129.128
TLV-11[15]: 1.3.6.1.2.1.69.1.2.1.3.3 -> 255.255.255.128
TLV-11[16]: 1.3.6.1.2.1.69.1.2.1.4.3 -> private
TLV-11[17]: 1.3.6.1.2.1.69.1.2.1.5.3 -> 3 (i32)
TLV-11[18]: 1.3.6.1.2.1.69.1.2.1.6.3 -> HEX:C7
TLV-11[19]: 1.3.6.1.2.1.69.1.2.1.7.4 -> 4 (i32)
TLV-11[20]: 1.3.6.1.2.1.69.1.2.1.2.4 -> 192.168.10.0
TLV-11[21]: 1.3.6.1.2.1.69.1.2.1.3.4 -> 255.255.255.0
TLV-11[22]: 1.3.6.1.2.1.69.1.2.1.4.4 -> private
TLV-11[23]: 1.3.6.1.2.1.69.1.2.1.5.4 -> 3 (i32)
TLV-11[24]: 1.3.6.1.2.1.69.1.2.1.6.4 -> @
TLV-11[25]: 1.3.6.1.2.1.69.1.2.1.7.5 -> 4 (i32)
TLV-11[26]: 1.3.6.1.2.1.69.1.2.1.2.5 -> 192.168.129.128
TLV-11[27]: 1.3.6.1.2.1.69.1.2.1.3.5 -> 255.255.255.128
TLV-11[28]: 1.3.6.1.2.1.69.1.2.1.4.5 -> private
TLV-11[29]: 1.3.6.1.2.1.69.1.2.1.5.5 -> 3 (i32)
TLV-11[30]: 1.3.6.1.2.1.69.1.2.1.6.5 -> @
TLV-11[31]: 1.3.6.1.2.1.69.1.2.1.7.6 -> 4 (i32)
TLV-11[32]: 1.3.6.1.2.1.69.1.2.1.2.6 -> 192.168.7.0
TLV-11[33]: 1.3.6.1.2.1.69.1.2.1.3.6 -> 255.255.255.0
TLV-11[34]: 1.3.6.1.2.1.69.1.2.1.4.6 -> private
TLV-11[35]: 1.3.6.1.2.1.69.1.2.1.5.6 -> 3 (i32)
TLV-11[36]: 1.3.6.1.2.1.69.1.2.1.6.6 -> @
TLV-11[37]: 1.3.6.1.2.1.69.1.2.1.7.7 -> 4 (i32)
TLV-11[38]: 1.3.6.1.2.1.69.1.2.1.2.7 -> 200.XX.XXX.93
TLV-11[39]: 1.3.6.1.2.1.69.1.2.1.3.7 -> 255.255.255.255
TLV-11[40]: 1.3.6.1.2.1.69.1.2.1.4.7 -> private
TLV-11[41]: 1.3.6.1.2.1.69.1.2.1.5.7 -> 3 (i32)
TLV-11[42]: 1.3.6.1.2.1.69.1.2.1.6.7 -> @
TLV-11[43]: 1.3.6.1.2.1.69.1.6.4.1.2.8 -> 4 (i32)
TLV-11[44]: 1.3.6.1.2.1.69.1.6.4.1.3.8 -> 1 (i32)
TLV-11[45]: 1.3.6.1.2.1.69.1.6.4.1.4.8 -> 0 (i32)
TLV-11[46]: 1.3.6.1.2.1.69.1.6.4.1.5.8 -> 3 (i32)
TLV-11[47]: 1.3.6.1.2.1.69.1.6.4.1.6.8 -> 1 (i32)
TLV-11[48]: 1.3.6.1.2.1.69.1.6.4.1.11.8 -> 6 (i32)
TLV-11[49]: 1.3.6.1.2.1.69.1.6.4.1.12.8 -> 137 (i32)
TLV-11[50]: 1.3.6.1.2.1.69.1.6.4.1.13.8 -> 139 (i32)
TLV-11[51]: 1.3.6.1.2.1.69.1.6.4.1.14.8 -> 137 (i32)
TLV-11[52]: 1.3.6.1.2.1.69.1.6.4.1.15.8 -> 139 (i32)
TLV-11[53]: 1.3.6.1.2.1.69.1.6.3.0 -> 2 (i32)
TLV-11[54]: 1.3.6.1.2.1.69.1.2.1.8.1 -> 1 (i32)
TLV-11[55]: 1.3.6.1.2.1.69.1.6.1.0 -> 2 (i32)
Time Of Day completed...
DefaultSnmpAgentClass::SystemTimeChangeEvent for SB5102 CM Agent w/ BRCM Factory Support
Not logging event ID 2291949524, control for level 7 is 0.
Not logging event ID 2291949324, control for level 7 is 0.
SB5102 CM Agent w/ BRCM Factory Support processing TLV-11's
SNMP packet sent to 10.4.77.86:225
0x000289f6 [DHCP Server Thread] BcmDhcpServerThread::ThreadMain: (DHCP Server Thread) Callback request expired:
timerDuration secs = 1
current time secs = 166
elapsed time secs = 1
55 TLV-11's OK.
Sending a REG-REQ to the CMTS...
Received a REG-RSP message from the CMTS...
0x00028ba4 [CmDocsisCtlThread] BcmCmDocsisCtlThread::RegRspMsgEvent: (CmDocsisCtlThread) We registered with a DOCSIS 1.1 config file!
0x00028bae [CmDocsisCtlThread] BcmCmDocsisCtlThread::TxRegAckMsg: (CmDocsisCtlThread) upstream already using standard short/long grant profiles. downshift NOT required.
0x00028bcc [CmDocsisCtlThread] BcmCmDocsisCtlThread::TxRegAckMsg: (CmDocsisCtlThread) upstream type 2 upshift to adv phy burst profiles!
Registration complete!
Process CVC
0x00028bcc [CmDocsisCtlThread] CmSecureDownload::ProcessConfigFileManufAndCosignerCvcs: (Secure Software Download) WARNING - No CVC included in config file; software upgrade can't be performed!
0x00028bcc [CmDocsisCtlThread] BcmCmDocsisCtlThread::ProcessCVC: (CmDocsisCtlThread) ERROR - Config file does not include a valid CVC!
DOCSIS CoS/QoS rate shaping enable is now 1
CmSnmpAgent::CmOperationalEvent for SB5102 CM Agent w/ BRCM Factory Support
CmSnmpAgent operating in 1.1 mode, including docsQos, excluding docsBpi
+++ No DH kickstart profiles or snmpCommunityTable entries installed.
We will operate in NMACCESS mode.
SB5102 CM Agent w/ BRCM Factory Support setting V1/V2 view to docsisNmAccessView
SB5102 CM Agent w/ BRCM Factory Support enabling management.
SB5102 CM Agent w/ BRCM Factory Support sending deferred traps...
Done w/ deferred traps.
SB5102 CPE Agent w/ BRCM Factory Support setting V1/V2 view to docsisNmAccessView
0x00028bd6 [CmDocsisCtlThread] BcmCmDocsisCtlThread::TestAndLaunchBpkm: (CmDocsisCtlThread) BPKM enabled. starting BPKM key requests.
SB5102 CM Event Log w/ BRCM Factory Support sending deferred async messages...
Sending syslog message from IF 1 to 200.75.xxx.x:
<133> CABLEMODEM [Motorola Corporation]: <2300955725> Ethernet link up - ready to pass packets
Done w/ deferred msgs
Not logging event ID 66040100, control for level 7 is 0.
CM> BPI initialization completed. Calling ConfigOperational().
Enabling network access for all CPE ports.
mot_scanList: Writing to Flash!
BcmCmDocsisStatusEventCodes::kCmIsOperational
Suspending SNMP Thread
0x00028e42 [CmDocsisCtlThread] BcmVendorCmApplication::StopDhcpServer: (VendorExtension CmApp) Shutting down DHCP Server...
0x00028e42 [CmDocsisCtlThread] BcmStandbySwitchThread::CmIsOperational: (Motorola Standby Switch Thread) Simulating a press of the standby switch to get the state configured properly.
0x00028e4c [IGMP Thread] BcmIgmpThread::Starting Igmp Thread...: (IGMP Thread)
0x00028e4c [Motorola Standby Switch Thread] BcmStandbySwitchThread::ThreadMain: (Motorola Standby Switch Thread) Standby switch was pressed!
0x00028e4c [Motorola Standby Switch Thread] BcmStandbySwitchThread::ProcessSwitchEvent: (Motorola Standby Switch Thread) Standby switch disabled in nonvol; ignoring event.
Not logging event ID 2296948624, control for level 7 is 0.And this back at home... [NON-Provisioned]:
Code:
Running the system...
Beginning Cable Modem operation...
0x00005e24 [Scan Downstream Thread] BcmVendorCmDownstreamScanThread::ThreadMain: (Scan Downstream Thread) Scanning for a Downstream Channel...
mot_scanList: Setting override freq @ 0
Favorite[0].freq = 699000000
Attempting Downstream FEC lock @ freq= 699000000 Hz, QAM64/256
CM> Found energy at frequency 699000000Hz! Publishing event kEventEnergyDetected...
0x00006072 [CmDocsisCtlThread] BcmCmDocsisCtlThread::StartUsInit: (CmDocsisCtlThread) Locked on the downstream. Waiting for UCDs...
******************************************
DOWNSTREAM STATUS
******************************************
Tuner Frequency = 699000000 Hz
Carrier Offset = 4 Hz
Symbol rate = 5360537 sym/sec
SNR = 38 dB
QAM Mode = QAM256
Tuner AGC = 0xfff00000
IF AGC = 0x144ac2b0
Power Level = 4 dB
QAM = LOCKED
FEC = LOCKED
******************************************
CM> Selecting UCD for Us Channel 10
0x00006964 [CmDocsisCtlThread] BcmCmDocsisCtlThread::TestAndLaunchDsTimeSync: (CmDocsisCtlThread) starting ds time sync acquisition...
0x00006c02 [CmDocsisCtlThread] BcmCmDocsisCtlThread::SyncDsSyncOk: (CmDocsisCtlThread) downstream time sync acquired...
0x00006c02 [CmDocsisCtlThread] BcmCmDocsisCtlThread::DsSyncOkResumeUsInit: (CmDocsisCtlThread) pre-REG upstream target case...starting initial ranging.
Beginning initial ranging...
Using stored initial upstream power = 45.0 dBmV
0x00006c02 [CmDocsisCtlThread] BcmCmDocsisCtlThread::SyncDsSyncOk: (CmDocsisCtlThread) rx unexpected kDsSyncOk indication...
Not logging event ID 2307948724, control for level 7 is 0.
CM>
RNG-RSP Adj: tim=1458 power=-3 freq=0 Stat=Continue
CM>
RNG-RSP Adj: tim=0 power=0 freq=-144 Stat=Success
******************************************
UPSTREAM STATUS
******************************************
Upstream Status = UP
Upstream Channel = 10
Upstream Frequency = 25000000 Hz
Upstream Power = 44 dBmV
Ranging SID = 0x1930
Upstream Symbol Rate = 2560000 sym/sec
******************************************
Calculating maximum number of IP filters:
Each IP filter consumes 1892 bytes of RAM.
Current free RAM is 419364 bytes.
Max heap reserved for IP filters (25%) = 104841
We can support 55 IP filters.
Calculating maximum number of LLC filters:
Each LLC filter consumes 744 bytes of RAM.
Current free RAM is 419344 bytes.
Max heap reserved for LLC filters (25%) = 104836
We can support 140 LLC filters.
Starting IP Initialization with DHCP...
DHCPc: Waiting 1 seconds before sending Discover; client id htype=1, value=00:1A:00:00:00:00
Not logging event ID 2307948624, control for level 7 is 0.
CM> DHCPc: Sending Discover packet; client id htype=1, value=00:1A:00:00:00:00
DHCPc: Received an Offer from DHCP server 00:01:5C:xx:xx:xx (10.7.224.1); lease client id htype=1, value=
CM> DHCPc: Timed out waiting for offers for lease with client id htype=1, value=00:1A:00:00:00:00
DHCPc: Sending Request packet; client id htype=1, value=00:1A:00:00:00:00
DHCPc: Received an Ack from DHCP server 00:01:5C:xx:xx:xx (10.7.224.1); lease client id htype=1, value=00:1A:00:00:00:00
Current IP address is default 0.0.0.0.
0x00007e22 [DHCP Client Thread] BcmEcosIpHalIf::ConfigureLeaseImpl: (IP Stack1 HalIf)
Configuring IP stack 1:
IP Address = 10.7.224.244 (primary IP address)
Subnet Mask = 255.255.252.0
Router = 10.7.224.1
IsPrimaryInterface = 1
Logging event: DHCP WARNING - Non-critical field invalid in response.
ARPing for default GW IP = 10.7.224.1
MAC = 00:01:5C:xx:xx:xx
DHCP completed successfully!
DHCP Settings:
Client Id = htype=1, value=00:1A:00:00:00:00
State = Renewing (5)
Static Lease = 0
AutoConfig Mode = IP, Subnet and Router
XID = 0x5e1d9dc7
Number of Tries = 0
Max Discover Tries = 6
Max Request Tries = 6
DHCP server MAC addr = 00:01:5C:xx:xx:xx
Ignore NAKs = 0
My offered IP address = 10.7.224.244 (primary IP address)
(1) Subnet Mask = 255.255.252.0
(3) Router IP address = 10.7.224.1
(54) DHCP Server IP address = 192.168.10.9
(82) Relay Agent IP address = 10.7.224.1
TFTP Server IP address = 10.7.224.1
CM Configuration file = 'cm-001a0000000'
(2) UTC Time Offset = -18000 seconds
(4) Time Server IP address = 192.168.10.24
(6) Domain Name Server =
(7) Log Server IP address = 200.XX.000.7
(51) Lease time = 3498 seconds
(58) T1 (renew) = 1749 seconds
(59) T2 (rebind) = 3060 seconds
Lease is infinite = 0
(122) PacketCable/CableHome -== SubOptions ==-
SubOpt(1) Primary Dhcp Server = 255.255.255.255
SB5102 CM Agent w/ BRCM Factory Support IpStackEvent: Ip=10.7.224.244, Subnet=255.255.252.0, Gateway=10.7.224.1
CmSnmpAgent::IpAddressAcquiredEvent for SB5102 CM Agent w/ BRCM Factory Support
IP addr = 10.7.224.244
Starting Time Of Day...
0x00007eb8 [CmDocsisIpThread] BcmDocsisTimeOfDayThread::SetTodServerIpAddress: (Time Of Day Thread) ToD servers: 192.168.10.24
Connecting to ToD server 192.168.10.24...
Sending UDP ToD request to server...
SNMP Agent Binding to 10.7.224.244:225
Not logging event ID 2291949724, control for level 7 is 0.
UTC returned by ToD server 3562091511; UTC offset -18000
Current system time -> Fri Nov 16 16:51:51 2012
System start time -> Fri Nov 16 16:51:19 2012
Starting Tftp of configuration file...
Opening file 'cm-001a0000000' on 10.7.224.1 for reading...
tftp-enforce bypass is using 10.7.224.1:cm-001a0000000
Initiating fake TFTP Get (tftp-enforce bypass)
Bypass succeeded.File was 1304 bytes
TFTPD Started
CM> Forcing Config TFTP IP to 127.0.0.1
Forcing Config File name to short.cfg
TFTPD received 1 from 10.7.224.244:1028
Storing received cfg of size 95 to memory
TFTPD Shut down
Tftp transfer complete!
TFTP Settings:
Stack Interface = 1
Server Ip Address = 10.7.224.1
Server Port Number = 1029
Total Blocks Read = 1
Total Bytes Read = 95
Config file was read! IP Initialization completed...
MAX CPE per CM is being set to 32
Time Of Day completed...
DefaultSnmpAgentClass::SystemTimeChangeEvent for SB5102 CM Agent w/ BRCM Factory Support
Sending a REG-REQ to the CMTS...
Not logging event ID 2291949524, control for level 7 is 0.
Not logging event ID 2291949324, control for level 7 is 0.
Received a REG-RSP message from the CMTS...
0x000082c8 [CmDocsisCtlThread] BcmCmDocsisCtlThread::RegRspMsgEvent: (CmDocsisCtlThread) ERROR -
REG-RSP response code NOT ok! rsp code = 11 (kRejAuthFailureBadHmac)
0x000082c8 [CmDocsisCtlThread] BcmCmDocsisCtlThread::SyncRestartErrorEvent: (CmDocsisCtlThread) reason: 17 (kNegOrBadRegRsp)
Not logging event ID 2436694040, control for level 7 is 0.
Logging event: Neg Or Bad Reg Rsp - Reinitialize MAC...
Deleting DOCSIS 1.0 CoS Settings for SID 0x1930
0x00008390 [CmDocsisCtlThread] BcmDocsisCmHalIf::DeleteAllServiceFlows: (DOCSIS CableModem HalIf) Deleting all Upstream and Downstream Service Flows, along with associated Classifiers and PHS rules...
Stopping DHCP/ToD/TFTP (client requested)...
The ToD thread was stopped.
DHCPc: Releasing the lease with client id htype=1, value=00:1A:00:00:00:00
DHCPc: Sending Release packet; client id htype=1, value=00:1A:00:00:00:00
0x0000839a [DHCP Client Thread] BcmDhcpClientIf::SendDhcpPacket: (DHCP ClientIf for IP Stack1) WARNING - Failed to send packet!
0x000083a4 [DHCP Client Thread] BcmDhcpClientIf::SendRelease: (DHCP ClientIf for IP Stack1) WARNING - Failed to create/send Release packet! Oh, well, no biggie...
0x000083a4 [DHCP Client Thread] BcmEcosIpHalIf::RemoveLeaseImpl: (IP Stack1 HalIf) Removing lease IP address 10.7.224.244 from IP stack 1
0x000083a4 [DHCP Client Thread] BcmEcosIpHalIf::RemoveLeaseImpl: (IP Stack1 HalIf) This is the last address on the stack; shutting the stack down:numberOfAddresses=1
0x000083a4 [DHCP Client Thread] BcmEcosIpHalIf::ShutdownIpStackImpl: (IP Stack1 HalIf)
Shutting down IP stack 1
SB5102 CM Agent w/ BRCM Factory Support IpStackEvent: Ip=0.0.0.0, Subnet=0.0.0.0, Gateway=0.0.0.0
DefaultSnmpAgentClass::RestartPendingEvent for SB5102 CM Agent w/ BRCM Factory Support
SB5102 CM Agent w/ BRCM Factory Support resetting to default state.
SB5102 CM Agent w/ BRCM Factory Support destroying users...
Pausing trap thread
SB5102 CM Agent w/ BRCM Factory Support destroying notifies...
Resuming trap thread
SB5102 CM Agent w/ BRCM Factory Support destroying views...
SB5102 CM Agent w/ BRCM Factory Support sending deferred traps...
Done w/ deferred traps.
SB5102 CM Event Log w/ BRCM Factory Support sending deferred async messages...
Done w/ deferred msgs
SB5102 CM Agent w/ BRCM Factory Support defering traps.
SB5102 CM Agent w/ BRCM Factory Support setting V1/V2 view to unrestricted
SB5102 CPE Agent w/ BRCM Factory Support setting V1/V2 view to docsisCpeView
Non-Vol Settings successfully written to the device.
0x0000849e [CmDocsisCtlThread] BcmCmDocsisCtlThread::ResetRngState: (CmDocsisCtlThread)
@@@@@ In ResetRngState, fRemainingInitRngPowerSteps 17
0x0000849e [Scan Downstream Thread] BcmVendorCmDownstreamScanThread::ThreadMain: (Scan Downstream Thread) Downstream Channel scan stopped!
0x000084b2 [Scan Downstream Thread] BcmVendorCmDownstreamScanThread::ThreadMain: (Scan Downstream Thread) Scanning for a Downstream Channel...
mot_scanList: Setting override freq @ 699000000
Scanning DS Channel at 699000000 Hz... (Initial target freq)
CM> Found energy at frequency 699000000Hz! Publishing event kEventEnergyDetected...
0x00008700 [CmDocsisCtlThread] BcmCmDocsisCtlThread::StartUsInit: (CmDocsisCtlThread) Locked on the downstream. Waiting for UCDs...So there's a point in which the "new" CMTS detects the HFC is not-provisioned in his db and sends him to the penalty box (TFTP10.7.224.1). I have tried forcing the provisioned TFTP even w/o forcing any configs (as I know that will not work here)... And you guessed it.... It didn't work either...
Will the certs of the "old security" work at home as they are valid? Or those are auto-signed by haxx (crap for the CMTS)?
Any clues comparing these two logs will be greatly appreciated.
thnx!
