Thursday, December 23, 2004

Speed Tests - DVG-1120M & WRT54G combos



comment: first three tests were done at 0036 0039 0058 CST 2004/12/23
last test was done at 1217 CST 2004/12/23

Telnet Log - ATT CallVantage Initialization Sequence

*** TA connected directly to DSL Modem - Successful initiation

Welcome to VoIP Gateway system!!!

username:admin
password:
192.168.15.1>
000003926 XmlTask: Cannot connect to the http server(retries=3), ret=-7
000003926 XmlTask: Unable to update IP address, ret=-6
000003926 HttpXml_Update_IP failed, system tick: 3926
000003927 Execute HttpXml_Update_IP, system tick: 3927
000003927 Connecting to http://sasvp.callvantage.att.com/servlet/SasvpTaServlet.
..

000003938 Building XML Document...
000003938 XML Document mac addr: [removed]
000003938 XML Document ip addr: 209.169.[nn].[nn]
000003938 XML Build ATT Subsequent Create request...
000003938 TCP ACK Sequence is: 2a79b33d
000003938 XML Subsequent Create Request auth-counter: 521797
000003938 XML Subsequent Create Request auth-code:
000003939 Sending HTTP request...
000003939 Waiting from http://sasvp.callvantage.att.com/servlet/SasvpTaServlet..
.

000003990 999 bytes read. Parsing HTTP response...
http content type : 0, mean: text/plain
000003990 XmlTask: HTTP response-code=200, content-length=800
000003991 att_res->status=success

000003991 att_res->filename=1_1_ACCESSASX1_M_N_1L

000003991 att_res->method=http

000003991 att_res->address=http://sasvpdl.callvantage.att.com/download/version1c
onfig/

000003991 att_res->fqdn=aa4500071200.f1.voip.att.net

000003991 Response XML counter-reset-value: 11
000003991 Response XML config-hash: AF1BFB1C9F892FC3E945B87628C43BB5
000003991 Response XML boot-hash: D4FDCEF8CED54B81C4EC0FDA2CDC8EAB
000003991 Response XML code-hash: 70B9CFCFAABA2A0A8DDB12BFD60BCA1B
000003991 Response XML boot-version: b08
000003991 Response XML code-version: 18d
000003991 Response XML auth-code:
000003991 Local Computation of auth-code HMAC-MD5:
http://sasvpdl.callvantage.att.com/download/version1config/1_1_ACCESSASX1_M_N_1L
000003991 Connecting to http://sasvpdl.callvantage.att.com/download/version1conf
ig/1_1_ACCESSASX1_M_N_1L...

000004009 Sending HTTP request...
000004009 Waiting from http://sasvpdl.callvantage.att.com/download/version1confi
g/1_1_ACCESSASX1_M_N_1L...

000004040 744 bytes read. Parsing HTTP response...
http content type : 3, mean: application/octet-stream
000004040 HttpXml_Parse_Config_File: Set = <0>
000004040 HttpXml_Parse_Config_File: Set = <20>
000004040 HttpXml_Parse_Config_File: Set = <5>
000004040 HttpXml_Parse_Config_File: Set = <12.194.243.5:2427>
000004040 HttpXml_Parse_Config_File: Set = <1>
000004040 HttpXml_Parse_Config_File: Set = <1>
000004040 HttpXml_Parse_Config_File: Set = <0>
000004040 Connecting to http://sasvpdl.callvantage.att.com/download/version1conf
ig/1120mL1-1a1-20041122R2.0M18
000004041 Sending HTTP request...
000004041 Waiting from http://sasvpdl.callvantage.att.com/download/version1confi
g/1120mL1-1a1-20041122R2.0M18d
000004051 XMLTASK: Http Hearder=HTTP/1.1 200 OK

000004051 XMLTASK: Http Hearder=Date: Thu, 23 Dec 2004 17:36:09 GMT

000004051 XMLTASK: Http Hearder=Server: Apache/2.0.52 (Unix) mod_ssl/2.0.52 Ope
nSSL/0.9.7d mod_jk/1.2.6-dev

000004051 XMLTASK: Http Hearder=Last-Modified: Thu, 16 Dec 2004 17:36:53 GMT

000004052 XMLTASK: Http Hearder=ETag: "17601-15f2f1-19899740"

000004052 XMLTASK: Http Hearder=Accept-Ranges: bytes

000004052 XMLTASK: Http Hearder=Content-Length: 1438449

000004052 XMLTASK: Http Hearder=Connection: close

000004052 XMLTASK: Http Hearder=Content-Type: application/octet-stream

000004052 XMLTASK: Http Hearder=

000004052 Return Get Response Header

Total_block=5033772 content length=1438449
product_id=122
Same image is detected, aborted download
000004052 HttpXml_Tcp_Recv 1 recv count=-2
Upgrade Image file fail!
000004052 HttpXml_Parse_Config_File: Set = <1120ml1-1a1-20041122r2.>
000004052 Connecting to http://sasvpdl.callvantage.att.com/download/version1conf
ig/dvg1120m_promcode_b08...

000004060 Sending HTTP request...
000004060 Waiting from http://sasvpdl.callvantage.att.com/download/version1confi
g/dvg1120m_promcode_b08...

000004070 XMLTASK: Http Hearder=HTTP/1.1 200 OK

000004070 XMLTASK: Http Hearder=Date: Thu, 23 Dec 2004 17:36:10 GMT

000004070 XMLTASK: Http Hearder=Server: Apache/2.0.52 (Unix) mod_ssl/2.0.52 Ope
nSSL/0.9.7d mod_jk/1.2.6-dev

000004070 XMLTASK: Http Hearder=Last-Modified: Thu, 16 Dec 2004 17:36:55 GMT

000004070 XMLTASK: Http Hearder=ETag: "1760d-2d8b2-19a81bc0"

000004070 XMLTASK: Http Hearder=Accept-Ranges: bytes

000004070 XMLTASK: Http Hearder=Content-Length: 186546

000004071 XMLTASK: Http Hearder=Connection: close

000004071 XMLTASK: Http Hearder=Content-Type: application/octet-stream

000004071 XMLTASK: Http Hearder=

000004071 Return Get Response Header
Total_block=5068128 content length=186546
product_id=120
Same image is detected, aborted download
000004184 HttpXml_Tcp_Recv 1 recv count=-3
Upgrade Prom file fail!
000004184 HttpXml_Parse_Config_File: Set =
000004184 HttpXml_Parse_Config_File: Set = <1>
000004184 HttpXml_Parse_Config_File: Set = <100>
000004184 HttpXml_Parse_Config_File: Set = <3>
000004184 HttpXml_Parse_Config_File: Set = <0>
000004184 HttpXml_Parse_Config_File: Set = <0>
000004184 HttpXml_Parse_Config_File: Set = <0>
000004184 HttpXml_Parse_Config_File: Set = <100>
000004184 HttpXml_Parse_Config_File: Set = <86400000>
000004184 HttpXml_Parse_Config_File: Set = <10800000>
Forced jitter values turned ON.
Using user-given values, nom_delay=60,max_delay=140
000004185 HttpXml_Parse_Config_File: Set =
000004185 HttpXml_Parse_Config_File: Set =
000004185 HttpXml_Parse_Config_File: Set = <-5>
000004185 HttpXml_Parse_Config_File: Set = <-7>
000004185 HttpXml_Http_Get_Config_File OK
000004185 XmlTask: successfully provisioned...
mspu_update_provisioned_data(0)
HF_DBG:mspup_add_persistent_event
000004185 CreateResponse: status=success, fn=1_1_ACCESSASX1_M_N_1L, method=http,
addr=http://sasvpdl.callvanta
HF_DBG:mspup_add_persistent_event
HF_DBG:mspup_add_persistent_event

Telnet session while Restarting DVG-1120M

[Used Web Interface to Restart DVG-1120M]
[Telnet in almost immediately after restart]
[note: Status light=slow flashing red for entire session,
never turns green or amber]
Welcome to VoIP Gateway system!!!

username:admin
password:
192.168.15.1>
192.168.15.1>
[pick up phone, dial ***, hang up on answer]
tcid =0 on hook wait for dlcx timer start
HF_DBG:mspup_add_persistent_event

[pick up phone, dial ***, hang up on answer]
tcid =0 on hook wait for dlcx timer start
HF_DBG:mspup_add_persistent_event

[pick up phone, dial ***, fast busy, following text
comes up immediately as fast busy tone starts]

000012677 Execute HttpXml_Update_IP, system tick: 12677
000012677 Connecting to http://sasvp.callvantage.att.com/servlet/SasvpTaServlet.
..

000012688 Building XML Document...
000012688 XML Document mac addr: [removed]
000012688 XML Document ip addr: 10.0.0.2
000012688 XML Build ATT Update Request...

000012688 XML Update Request auth-counter: 12
000012688 XML Update Request auth-counter: [removed]
000012689 Sending HTTP request...
000012689 Waiting from http://sasvp.callvantage.att.com/servlet/SasvpTaServlet..
.

000012730 436 bytes read. Parsing HTTP response...
http content type : 0, mean: text/plain
000012730 XmlTask: HTTP response-code=200, content-length=237
000012730 att_res->status=success

000012730 att_res->filename=

000012730 att_res->method=

000012731 att_res->address=

000012731 att_res->fqdn=

000012731 UpdateResponse: status=success
000012731 NMM: 0, Set Channel State Done (SUCCESS)
000012731 NMM: 1, Set Channel State Done (SUCCESS)
000012731 mgcpev: tcid 1 unconfigured
000012751 XGCP: Gateway has been shutdown
000012751 NMM: 0, Signaling: xGCP, Protocol: FXS Loop Start
000012751 NMM: 0, Set Channel State Done (SUCCESS)
HF_DBG:mspu_ev_update_devent,flash_seen
xspu_switch_in
HF_DBG:mspup_add_persistent_event
000012751 NMM: 0, Channel Config Done (SUCCESS)
000012751 NMM: 0, Set Channel State Done (SUCCESS)
000012751 NMM: 1, Signaling: xGCP, Protocol: FXS Loop Start
000012751 NMM: 1, Set Channel State Done (SUCCESS)
HF_DBG:mspu_ev_update_devent,flash_seen
xspu_switch_in
000012751 NMM: 1, Channel Config Done (SUCCESS)
000012751 NMM: 1, Set Channel State Done (SUCCESS)
000012751 XGCP: Gateway has been restarted
tcid =0 on hook wait for dlcx timer start
HF_DBG:mspup_add_persistent_event
000013301 DIM: tone off TCID 0 has no DSP

[pick up phone, dial ***, spend approx 3 minutes listening
to voice mail, call is interrupted with fast busy and following
text scrolls immediately]

000024751 Execute HttpXml_Update_IP, system tick: 24751
000024751 Connecting to http://sasvp.callvantage.att.com/servlet/SasvpTaServlet.
..

000024762 Building XML Document...
000024762 XML Document mac addr: [removed]
000024762 XML Document ip addr: 10.0.0.2
000024762 XML Build ATT Update Request...

000024762 XML Update Request auth-counter: 13
000024762 XML Update Request auth-counter: [removed]
000024762 Sending HTTP request...
000024762 Waiting from http://sasvp.callvantage.att.com/servlet/SasvpTaServlet..
.

000024825 436 bytes read. Parsing HTTP response...
http content type : 0, mean: text/plain
000024825 XmlTask: HTTP response-code=200, content-length=237
000024825 att_res->status=success

000024825 att_res->filename=

000024825 att_res->method=

000024825 att_res->address=

000024825 att_res->fqdn=

000024825 UpdateResponse: status=success
000024825 NMM: 0, Set Channel State Done (SUCCESS)
000024826 NMM: 1, Set Channel State Done (SUCCESS)
000024826 mgcpev: tcid 1 unconfigured
000024846 XGCP: Gateway has been shutdown
000024846 NMM: 0, Signaling: xGCP, Protocol: FXS Loop Start
000024846 NMM: 0, Set Channel State Done (SUCCESS)
HF_DBG:mspu_ev_update_devent,flash_seen
xspu_switch_in
HF_DBG:mspup_add_persistent_event
000024846 NMM: 0, Channel Config Done (SUCCESS)
000024846 NMM: 0, Set Channel State Done (SUCCESS)
000024846 NMM: 1, Signaling: xGCP, Protocol: FXS Loop Start
000024846 NMM: 1, Set Channel State Done (SUCCESS)
HF_DBG:mspu_ev_update_devent,flash_seen
xspu_switch_in
000024846 NMM: 1, Channel Config Done (SUCCESS)
000024846 NMM: 1, Set Channel State Done (SUCCESS)
000024847 XGCP: Gateway has been restarted
tcid =0 on hook wait for dlcx timer start
HF_DBG:mspup_add_persistent_event
000025267 DIM: tone off TCID 0 has no DSP
HF_DBG:mspup_add_persistent_event

Wednesday, December 22, 2004

Experimenting with DVG1120M behind WRT54G

Basics:
Firmware: DVG-1120M = R2.0M18d
Firmware: WRT54G v1.0 = v3.01.3
Connections: DVG-1120M -> WRT54G -> DSL Modem
WRT54G IPaddr= 10.0.0.1
DVG-1120M IPaddr= 10.0.0.2 (WAN side)
DVG-1120M IPaddr= 192.168.15.1 (LAN side)

DVG-1120M Boot Sequence:
Status=Green
Status=Flashing Red
Status=Flashing Amber (<1sec) status="Flashing">20sec)
Login Screen comes up on Browser pointed to 192.168.15.1
DEVICE INFORMATION SCREEN/DSP Version=Not yet loaded
Status=Flashing Red (>1min)
DSP Version=Not yet loaded
Status=Flashing Red (>1min)
[note: dial-tone available]
Status=Flashing Red
DSP Version=
Cap 0xfc10, Rel 8.0.31.5, Build , DSP C5402, Platform VPBX/GG6E,
Cap2 0x269a, Cap3 0x9, Chans 2, EC tail len 64, PCM chans 0
NOTE: I can make short outbound calls with the flashing red light.
I can use AT&T short codes (e.g. ***). Calls will only last approx
1-2 minutes. They will disconnect automagically.
COMMENT:
Status/Alarm This LED will remain green when the CPE is either
performing a self-test or booting up. The LED will flash green slowly
when the system is ready for a connection with the Call Agent. It
will remain red when the self-test or booting up is failed. It will
flash red slowly when the system is ready but cannot

receive an acknowledgment from the Call Agent.