ok, I did a gatekeeper call 10 and saw this on the second call:
*Mar 2 00:43:00.373: gk_process: QUEUE_EVENT (minor 0) wakeup
*Mar 2 00:43:00.385: gk_rassrv_arq: arqp=0x658E7DD0, crv=0x13,
answerCall=0
*Mar 2 00:43:00.397: gk_process: QUEUE_EVENT (minor 0) wakeup
*Mar 2 00:43:00.405: gk_rassrv_arq: arqp=0x65932F28, crv=0xB,
answerCall=0
*Mar 2 00:43:00.405: gk_rassrv_sep_arq: ARQ Didn't use GK_AAA_PROC
*Mar 2 00:43:00.409: gk_call_new: src_endptp=0x6527E460,
dst_endptp=0x0, src_pxp=0x0, dst_pxp=0x0, bw=160, crv=11,
whichcrv=0x1, circuit=0x0, capacity=0x0, ret_callpp=0x64DAD580
*Mar 2 00:43:00.413: gk_call_find_endpts: NOT_FOUND
*Mar 2 00:43:00.417: gk_call_new: checking for default (CLI)
carrier for sep endpt 0x6527E460
*Mar 2 00:43:00.417: gk_dns_query: No Name servers
*Mar 2 00:43:00.421: rassrv_get_addrinfo: (2#3003) Matched tech-
prefix 2#
*Mar 2 00:43:00.421: rassrv_get_addrinfo: (2#3003) Matched zone
prefix 3 and remainder 003
*Mar 2 00:43:00.425: rassrv_arq_select_viazone: about to check the
source side, src_zonep=0x6527E578
*Mar 2 00:43:00.429: rassrv_arq_select_viazone: matched zone is
home, and z_invianamelen=0
*Mar 2 00:43:00.429: rassrv_arq_select_viazone: about to check the
destination side, dst_zonep=0x6527E578
*Mar 2 00:43:00.433: rassrv_arq_select_viazone: matched zone is
home, and z_outvianamelen=0
*Mar 2 00:43:00.549: gk_process: QUEUE_EVENT (minor 0) wakeup
*Mar 2 00:43:00.557: gk_rassrv_arq: arqp=0x658E7DD0, crv=0xA2,
answerCall=1
*Mar 2 00:43:00.561: gk_rassrv_dep_arq: ARQ Didn't use GK_AAA_PROC
*Mar 2 00:43:00.565: gk_call_find_crv: endptp=0x64C23A64, crv=162:
*Mar 2 00:43:00.565: gk_call_find_crv: crv is DEP
*Mar 2 00:43:00.569: gk_call_delete: callp=64787CA4
So, the second call IS coming as 16k G.729...
So, this isn't a gatekeeper problem, it is a CCME/H.323 problem...
on the CCME, I see:
Aug 2 09:28:58.677: ARJ (seq# 3305) rcvdparse_arj_nonstd: ARJ
Nonstd decode succeeded, remlen = -2107606760
Which is the ARJ originating from CCME... but I have no idea what
that error means...
Jonathan
On Fri, Aug 1, 2008 at 11:35 PM, Jonathan Charles
<[EMAIL PROTECTED]> wrote:
> Bottom half of the email from 1902
>
>
>
> Jonathan
>
> On Fri, Aug 1, 2008 at 8:25 PM, Vik Malhi <[EMAIL PROTECTED]>
wrote:
>> Can you post the output of deb gatek call 10 from the
gatekeeper... For both
>> calls.
>>
>> On Aug 1, 2008, at 5:03 PM, "Jonathan Charles"
<[EMAIL PROTECTED]> wrote:
>>
>>> Cuz that was long, I did notice this ARJ from CCME:
>>>
>>> Aug 1 18:58:33.333: ARJ (seq# 2138) rcvdparse_arj_nonstd: ARJ
Nonstd
>>> decode succeeded, remlen = -2107606760
>>>
>>> A search found no hits...
>>>
>>> .131 is the CCME, .124 is CCM
>>>
>>> .63 is the GK...
>>>
>>>
>>> Jonathan
>>>
>>> On Fri, Aug 1, 2008 at 7:02 PM, Jonathan Charles <[EMAIL PROTECTED]
>
>>> wrote:
>>>>
>>>> OK, here are the debugs from CCME and the GK...
>>>>
>>>> Still no BRQs... not sure why I am getting the ARJ...
>>>>
>>>> First call succeeds with G711, second call fails.
>>>>
>>>>
>>>>
>>>>
>>>> CCME#deb ras
>>>> H.323 RAS Messages debugging is on
>>>> CCME#term mon
>>>> CCME#
>>>> Aug 1 18:58:24.988: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:24.988: h323chan_chn_process_read_socket: fd=0 of
type
>>>> LISTENING has data
>>>> Aug 1 18:58:24.992: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:24.992: h323chan_chn_process_read_socket: fd=3 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:24.992: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=3
>>>> h323chan_dgram_send:Sent UDP msg. Bytes sent: 153 to
10.0.0.63:1719 fd=2
>>>>
>>>> Aug 1 18:58:25.000: RASLib::GW_RASSendARQ: ARQ (seq# 2136)
sent to
>>>> 10.0.0.63
>>>> Aug 1 18:58:25.088: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:25.092: h323chan_chn_process_read_socket: fd=2 of
type
>>>> CONNECTED has data
>>>> CCME#
>>>> Aug 1 18:58:25.092: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=2
>>>>
>>>> Aug 1 18:58:25.092: h323chan_dgram_recvdata:rcvd from
[10.0.0.63:1719]
>>>> on fd=2
>>>>
>>>> Aug 1 18:58:25.092: ACF (seq# 2136) rcvd
>>>> CCME#h323chan_dgram_send:Sent UDP msg. Bytes sent: 155 to
10.0.0.63:1719
>>>> fd=2
>>>>
>>>> Aug 1 18:58:28.161: RASLib::GW_RASSendIRR: IRR (seq# 2137)
sent to
>>>> 10.0.0.63
>>>> Aug 1 18:58:28.177: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:28.177: h323chan_chn_process_read_socket: fd=4 of
type
>>>> LISTENING has data
>>>> Aug 1 18:58:28.189: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:28.189: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:28.189: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>>
>>>> Aug 1 18:58:28.193: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:28.193: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:28.193: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>>
>>>> Aug 1 18:58:28.197: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:28.197: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:28.197: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>>
>>>> Aug 1 18:58:28.205: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:28.205: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:28.205: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>>
>>>> Aug 1 18:58:28.285: h323chan_chn_process_read_socket
>>>> CCME#
>>>> Aug 1 18:58:28.285: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:28.285: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>>
>>>> CCME#
>>>> Aug 1 18:58:33.277: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:33.277: h323chan_chn_process_read_socket: fd=0 of
type
>>>> LISTENING has data
>>>> Aug 1 18:58:33.277: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:33.277: h323chan_chn_process_read_socket: fd=6 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:33.277: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=6
>>>> h323chan_dgram_send:Sent UDP msg. Bytes sent: 153 to
10.0.0.63:1719 fd=2
>>>>
>>>> Aug 1 18:58:33.285: RASLib::GW_RASSendARQ: ARQ (seq# 2138)
sent to
>>>> 10.0.0.63
>>>> Aug 1 18:58:33.333: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:33.333: h323chan_chn_process_read_socket: fd=2 of
type
>>>> CONNECTED has data
>>>> CCME#
>>>> Aug 1 18:58:33.333: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=2
>>>>
>>>> Aug 1 18:58:33.333: h323chan_dgram_recvdata:rcvd from
[10.0.0.63:1719]
>>>> on fd=2
>>>>
>>>> Aug 1 18:58:33.333: ARJ (seq# 2138) rcvdparse_arj_nonstd: ARJ
Nonstd
>>>> decode succeeded, remlen = -2107606760
>>>> Aug 1 18:58:33.413: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:33.413: h323chan_chn_process_read_socket: fd=6 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:33.413: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=6
>>>>
>>>> CCME#h323chan_dgram_send:Sent UDP msg. Bytes sent: 83 to
10.0.0.63:1719
>>>> fd=2
>>>>
>>>> Aug 1 18:58:35.261: RASLib::GW_RASSendRRQ: RRQ (seq# 2139)
sent to
>>>> 10.0.0.63
>>>> Aug 1 18:58:35.317: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:35.317: h323chan_chn_process_read_socket: fd=2 of
type
>>>> CONNECTED has data
>>>> Aug 1 18:58:35.317: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=2
>>>>
>>>> Aug 1 18:58:35.317: h323chan_dgram_recvdata:rcvd from
[10.0.0.63:1719]
>>>> on fd=2
>>>>
>>>> Aug 1 18:58:35.321: RCF (seq# 2139) rcvd
>>>> CCME#
>>>> Aug 1 18:58:37.680: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:37.680: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:37.680: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>>
>>>> Aug 1 18:58:37.684: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:37.688: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:37.688: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>>
>>>> Aug 1 18:58:37.697: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:37.697: h323chan_chn_process_read_socket: fd=3 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:37.697: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=3
>>>>
>>>> Aug 1 18:58:37.701: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:37.701: h323chan_chn_process_read_socket: fd=5 of
type
>>>> ACCEPTED has data
>>>> Aug 1 18:58:37.701: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=5
>>>> h323chan_dgram_send:Sent UDP msg. Bytes sent: 104 to
10.0.0.63:1719 fd=2
>>>>
>>>> Aug 1 18:58:37.713: RASLib::GW_RASSendDRQ: DRQ (seq# 2140)
sent to
>>>> 10.0.0.63
>>>> Aug 1 18:58:37.773: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:37.773: h323chan_chn_process_read_socket: fd=3 of
type
>>>> ACCEPTED has data
>>>> CCME#
>>>> Aug 1 18:58:37.773: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=3
>>>>
>>>> Aug 1 18:58:37.821: h323chan_chn_process_read_socket
>>>> Aug 1 18:58:37.821: h323chan_chn_process_read_socket: fd=2 of
type
>>>> CONNECTED has data
>>>> Aug 1 18:58:37.821: h323chan_chn_process_read_socket: h323chan
>>>> accepted/connected fd=2
>>>>
>>>> Aug 1 18:58:37.821: h323chan_dgram_recvdata:rcvd from
[10.0.0.63:1719]
>>>> on fd=2
>>>>
>>>> Aug 1 18:58:37.821: DCF (seq# 2140) rcvd
>>>> CCME#un all
>>>> All possible debugging has been turned off
>>>> CCME#
>>>>
>>>> GK#
>>>> GK#
>>>> GK#deb ras
>>>> H.323 RAS Messages debugging is on
>>>> GK#deb gatek
>>>> GK#deb gatekeeper main 10
>>>> GK#term mon
>>>> GK#
>>>> *Mar 1 10:14:28.421: gk_process: got a TIMER event
>>>>
>>>> *Mar 1 10:14:28.421: gk_handle_timers
>>>>
>>>> *Mar 1 10:14:28.425: gk_handle_timers: managed timer expired
0x6441AE50
>>>>
>>>> *Mar 1 10:14:28.473: gk_process: got a TIMER event
>>>>
>>>> *Mar 1 10:14:28.473: gk_handle_timers
>>>>
>>>> *Mar 1 10:14:28.477: gk_handle_timers: managed timer expired
0x6441ACA0
>>>>
>>>> GK#
>>>> *Mar 1 10:14:30.281: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:30.285: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 112 from 10.0.0.124:51236
>>>> *Mar 1 10:14:30.293: ARQ (seq# 503) rcvd
>>>> *Mar 1 10:14:30.297: gk_rassrv_arq: arqp=0x646F2270, crv=0x10,
>>>> answerCall=0
>>>> *Mar 1 10:14:30.297: gk_rassrv_sep_arq: ARQ Didn't use
GK_AAA_PROC
>>>> *Mar 1 10:14:30.301: gk_dns_query: No Name servers
>>>> *Mar 1 10:14:30.305: rassrv_get_addrinfo: (2#3002) Matched
tech-prefix
>>>> 2#
>>>> *Mar 1 10:14:30.305: rassrv_get_addrinfo: (2#3002) Matched zone
>>>> prefix 3 and remainder 002
>>>> *Mar 1 10:14:30.309: rassrv_arq_select_viazone: about to check
the
>>>> source side, src_zonep=0x6527E578
>>>> *Mar 1 10:14:30.309: rassrv_arq_select_viazone: matched zone
is home,
>>>> and z_invianamelen=0
>>>> *Mar 1 10:14:30.313: rassrv_arq_select_viazone: about to check
the
>>>> destination side, dst_zonep=0x6527E578
>>>> *Mar 1 10:14:30.317: rassrv_arq_select_viazone: matched zone
is home,
>>>> and z_outvianamelen=0
>>>> *Mar 1 10:14:30.325: IPSOCK_RAS_sendto: msg length 43 from
>>>> 10.0.0.63:1719 to 10.0.0.124: 51236
>>>> *Mar 1 10:14:30.329: RASLib::RASSendACF: ACF (seq# 503)
sent to
>>>> 10.0.0.124
>>>> *Mar 1 10:14:30.369: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:30.369: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 153 from 10.0.0.131:64455
>>>> GK#
>>>> *Mar 1 10:14:30.377: ARQ (seq# 2136) rcvdparse_arq_nonstd: ARQ
Nonstd
>>>> decode succeeded, remlen = 1692063440
>>>> *Mar 1 10:14:30.385: gk_rassrv_arq: arqp=0x646F2270, crv=0x9F,
>>>> answerCall=1
>>>> *Mar 1 10:14:30.389: gk_rassrv_dep_arq: ARQ Didn't use
GK_AAA_PROC
>>>> *Mar 1 10:14:30.397: IPSOCK_RAS_sendto: msg length 30 from
>>>> 10.0.0.63:1719 to 10.0.0.131: 64455
>>>> *Mar 1 10:14:30.401: RASLib::RASSendACF: ACF (seq# 2136)
sent
>>>> to 10.0.0.131
>>>> GK#
>>>> *Mar 1 10:14:33.549: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:33.553: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 155 from 10.0.0.131:64455
>>>> *Mar 1 10:14:33.561: IRR (seq# 2137) rcvdparse_prCll_nonstd:
prCll
>>>> Nonstd decode succeeded, remlen = 1692063440
>>>> *Mar 1 10:14:33.573: gk_rassrv_irr: irrp=0x646F2528, from
>>>> 10.0.0.131:64455
>>>> GK#
>>>> *Mar 1 10:14:38.533: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:38.537: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 112 from 10.0.0.124:51236
>>>> *Mar 1 10:14:38.545: ARQ (seq# 504) rcvd
>>>> *Mar 1 10:14:38.549: gk_rassrv_arq: arqp=0x646F2270, crv=0x11,
>>>> answerCall=0
>>>> *Mar 1 10:14:38.553: IPSOCK_RAS_sendto: msg length 13 from
>>>> 10.0.0.63:1719 to 10.0.0.124: 51236
>>>> *Mar 1 10:14:38.557: RASLib::RASSendARJ: ARJ (seq# 504)
sent to
>>>> 10.0.0.124
>>>> *Mar 1 10:14:38.625: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:38.629: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 111 from 10.0.0.124:51236
>>>> *Mar 1 10:14:38.637: ARQ (seq# 505) rcvd
>>>> *Mar 1 10:14:38.641: gk_rassrv_arq: arqp=0x646F2270, crv=0xA,
>>>> answerCall=0
>>>> *Mar 1 10:14:38.641: gk_rassrv_sep_arq: ARQ Didn't use
GK_AAA_PROC
>>>> *Mar 1 10:14:38.645: gk_dns_query: No Name servers
>>>> *Mar 1 10:14:38.649: rassrv_get_addrinfo: (2#3003) Matched
tech-prefix
>>>> 2#
>>>> *Mar 1 10:14:38.649: rassrv_get_addrinfo: (2#3003) Matched zone
>>>> prefix 3 and remainder 003
>>>> *Mar 1 10:14:38.653: rassrv_arq_select_viazone: about to check
the
>>>> source side, src_zonep=0x6527E578
>>>> *Mar 1 10:14:38.653: rassrv_arq_select_viazone: matched zone
is home,
>>>> and z_invianamelen=0
>>>> *Mar 1 10:14:38.657: rassrv_arq_select_viazone: about to check
the
>>>> destination side, dst_zonep=0x6527E578
>>>> *Mar 1 10:14:38.661: rassrv_arq_select_viazone: matched zone
is home,
>>>> and z_outvianamelen=0
>>>> *Mar 1 10:14:38.669: IPSOCK_RAS_sendto: msg length 42 from
>>>> 10.0.0.63:1719 to 10.0.0.124: 51236
>>>> *Mar 1 10:14:38.673: RASLib::RASSendACF: ACF (seq# 505)
sent to
>>>> 10.0.0.124
>>>> *Mar 1 10:14:38.685: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:38.689: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 153 from 10.0.0.131:64455
>>>> *Mar 1 10:14:38.697: ARQ (seq# 2138) rcvdparse_arq_nonstd: ARQ
Nonstd
>>>> decode succeeded, remlen = 1692063440
>>>> *Mar 1 10:14:38.705: gk_rassrv_arq: arqp=0x646E6784, crv=0xA0,
>>>> answerCall=1
>>>> GK#
>>>> *Mar 1 10:14:38.705: gk_rassrv_dep_arq: ARQ Didn't use
GK_AAA_PROC
>>>> *Mar 1 10:14:38.713: IPSOCK_RAS_sendto: msg length 13 from
>>>> 10.0.0.63:1719 to 10.0.0.131: 64455
>>>> *Mar 1 10:14:38.717: RASLib::RASSendARJ: ARJ (seq# 2138)
sent
>>>> to 10.0.0.131
>>>> *Mar 1 10:14:38.781: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:38.785: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 269 from 10.0.0.124:51236
>>>> *Mar 1 10:14:38.793: DRQ (seq# 506) rcvd
>>>> *Mar 1 10:14:38.805: IPSOCK_RAS_sendto: msg length 3 from
>>>> 10.0.0.63:1719 to 10.0.0.124: 51236
>>>> *Mar 1 10:14:38.809: RASLib::RASSendDCF: DCF (seq# 506)
sent to
>>>> 10.0.0.124
>>>> GK#
>>>> *Mar 1 10:14:40.705: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:40.709: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 83 from 10.0.0.131:64455
>>>> *Mar 1 10:14:40.717: RRQ (seq# 2139) rcvd
>>>> *Mar 1 10:14:40.725: IPSOCK_RAS_sendto: msg length 64 from
>>>> 10.0.0.63:1719 to 10.0.0.131: 64455
>>>> *Mar 1 10:14:40.729: RASLib::RASSendRCF: RCF (seq# 2139)
sent
>>>> to 10.0.0.131
>>>> GK#
>>>> *Mar 1 10:14:43.145: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:43.145: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 279 from 10.0.0.124:51236
>>>> *Mar 1 10:14:43.153: DRQ (seq# 507) rcvd
>>>> *Mar 1 10:14:43.169: IPSOCK_RAS_sendto: msg length 3 from
>>>> 10.0.0.63:1719 to 10.0.0.124: 51236
>>>> *Mar 1 10:14:43.169: RASLib::RASSendDCF: DCF (seq# 507)
sent to
>>>> 10.0.0.124
>>>> *Mar 1 10:14:43.181: gk_process: QUEUE_EVENT (minor 0) wakeup
>>>> *Mar 1 10:14:43.185: RecvUDP_IPSockData successfully rcvd
message
>>>> of length 104 from 10.0.0.131:64455
>>>> *Mar 1 10:14:43.189: DRQ (seq# 2140) rcvd
>>>> GK#parse_rasusginfo_nonstd: Ras Usage Info Nonstd decode
succeeded,
>>>> remlen = 1692063440
>>>> *Mar 1 10:14:43.201: IPSOCK_RAS_sendto: msg length 3 from
>>>> 10.0.0.63:1719 to 10.0.0.131: 64455
>>>> *Mar 1 10:14:43.201: RASLib::RASSendDCF: DCF (seq# 2140)
sent
>>>> to 10.0.0.131
>>>> *Mar 1 10:14:43.477: gk_process: got a TIMER event
>>>>
>>>> *Mar 1 10:14:43.477: gk_handle_timers
>>>>
>>>> *Mar 1 10:14:43.481: gk_handle_timers: managed timer expired
0x6441ACA0
>>>>
>>>> GK#un all
>>>> Port Statistics for unclassified packets is not turned on.
>>>>
>>>> All possible debugging has been turned off
>>>> GK#
>>>> GK#
>>>> GK#
>>>>
>>>>
>>>> On Fri, Aug 1, 2008 at 6:41 PM, Vik Malhi <[EMAIL PROTECTED]>
wrote:
>>>>>
>>>>> Try putting each trunk in a separate route group and put both
rg's on
>>>>> the
>>>>> Rl.
>>>>>
>>>>> On Aug 1, 2008, at 1:56 PM, "Jonathan Charles" <[EMAIL PROTECTED]
>
>>>>> wrote:
>>>>>
>>>>>> So, I configured a GK with a single zone, I have a route
group with a
>>>>>> 711-only trunk to GK and a 729-only trunk to GK.. (711
first), BRQ
>>>>>> Enabled is set to true...
>>>>>>
>>>>>> First call goes 711, works fine... second call always says
busy.
>>>>>>
>>>>>> The GK never sees a BRQ from CCM...
>>>>>>
>>>>>> If I change the bw to 256, 2 711 calls work fine.
>>>>>>
>>>>>> If I reverse order the trunks in the RG, I can get bunches of
729
>>>>>> calls...
>>>>>>
>>>>>> gatekeeper
>>>>>> zone local home bmft.net 10.0.0.63
>>>>>> zone local away cisco.com
>>>>>> zone prefix home 1* gw-priority 10 trunk_711_1
>>>>>> zone prefix home 1* gw-priority 9 trunk_729_1
>>>>>> zone prefix home 1* gw-priority 0 CCME
>>>>>> zone prefix home 3* gw-priority 10 CCME
>>>>>> zone prefix home 3* gw-priority 0 trunk_711_1
>>>>>> gw-type-prefix 1#*
>>>>>> bandwidth total zone home 145
>>>>>> no shutdown
>>>>>>
>>>>>>
>>>>>> Router#sh gateke end
>>>>>> GATEKEEPER ENDPOINT REGISTRATION
>>>>>> ================================
>>>>>> CallSignalAddr Port RASSignalAddr Port Zone Name
Type
>>>>>> Flags
>>>>>> --------------- ----- --------------- ----- ---------
----
>>>>>> -----
>>>>>> 10.0.0.124 51237 10.0.0.124 51236 home
VOIP-GW
>>>>>> H323-ID: trunk_729_1
>>>>>> Voice Capacity Max.= Avail.= Current.= 0
>>>>>> 10.0.0.124 51238 10.0.0.124 51236 home
VOIP-GW
>>>>>> H323-ID: trunk_711_1
>>>>>> Voice Capacity Max.= Avail.= Current.= 0
>>>>>> 10.0.0.131 1720 10.0.0.131 64455 home
VOIP-GW
>>>>>> H323-ID: CCME
>>>>>> Voice Capacity Max.= Avail.= Current.= 0
>>>>>> Total number of active registrations = 3
>>>>>>
>>>>>> Router#
>>>>>>
>>>>>> I see the GK rejecting the second call.. shouldn't I see a
BRQ from
>>>>>> CCM?
>>>>>>
>>>>>>
>>>>>> Debug RAS:
>>>>>>
>>>>>> *Mar 1 07:05:48.286: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 1
>>>>>> 12 from 10.0.0.124:51236
>>>>>> *Mar 1 07:05:48.294: ARQ (seq# 28) rcvd
>>>>>> *Mar 1 07:05:48.306: IPSOCK_RAS_sendto: msg length 43 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.124: 51236
>>>>>> *Mar 1 07:05:48.310: RASLib::RASSendACF: ACF (seq# 28)
sent to
>>>>>> 10.0.0.124
>>>>>> *Mar 1 07:05:48.326: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 1
>>>>>> 53 from 10.0.0.131:64455
>>>>>> *Mar 1 07:05:48.334: ARQ (seq# 1862) rcvdparse_arq_nonstd:
ARQ Nonstd
>>>>>> decode su
>>>>>> cceeded, remlen = 1692063440
>>>>>> *Mar 1 07:05:48.346: IPSOCK_RAS_sendto: msg length 30 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.131: 64455
>>>>>> Router#
>>>>>> *Mar 1 07:05:48.350: RASLib::RASSendACF: ACF (seq#
1862) sent to
>>>>>> 10.0.0.1
>>>>>> 31
>>>>>> Router#
>>>>>> *Mar 1 07:05:50.926: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 9
>>>>>> 7 from 10.0.0.124:51236
>>>>>> *Mar 1 07:05:50.934: RRQ (seq# 29) rcvd
>>>>>> *Mar 1 07:05:50.946: IPSOCK_RAS_sendto: msg length 64 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.124: 51236
>>>>>> *Mar 1 07:05:50.946: RASLib::RASSendRCF: RCF (seq# 29)
sent to
>>>>>> 10.0.0.124
>>>>>> *Mar 1 07:05:50.950: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 9
>>>>>> 8 from 10.0.0.124:51236
>>>>>> *Mar 1 07:05:50.958: RRQ (seq# 30) rcvd
>>>>>> *Mar 1 07:05:50.970: IPSOCK_RAS_sendto: msg length 64 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.124: 51236
>>>>>> *Mar 1 07:05:50.974: RASLib::RASSendRCF: RCF (seq# 30)
sent to
>>>>>> 10.0.0.124
>>>>>> Router#
>>>>>> *Mar 1 07:05:52.654: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 1
>>>>>> 55 from 10.0.0.131:64455
>>>>>> *Mar 1 07:05:52.666: IRR (seq# 1863) rcvdparse_prCll_nonstd:
prCll
>>>>>> Nonstd
>>>>>> decod
>>>>>> e succeeded, remlen = 1692063440
>>>>>> Router#
>>>>>> *Mar 1 07:05:56.298: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 1
>>>>>> 12 from 10.0.0.124:51236
>>>>>> *Mar 1 07:05:56.306: ARQ (seq# 31) rcvd
>>>>>> *Mar 1 07:05:56.314: IPSOCK_RAS_sendto: msg length 13 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.124: 51236
>>>>>> *Mar 1 07:05:56.318: RASLib::RASSendARJ: ARJ (seq# 31)
sent to
>>>>>> 10.0.0.124
>>>>>> *Mar 1 07:05:56.330: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 1
>>>>>> 11 from 10.0.0.124:51236
>>>>>> *Mar 1 07:05:56.334: ARQ (seq# 32) rcvd
>>>>>> *Mar 1 07:05:56.350: IPSOCK_RAS_sendto: msg length 42 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.124: 51236
>>>>>> *Mar 1 07:05:56.354: RASLib::RASSendACF: ACF (seq# 32)
sent to
>>>>>> 10.0.0.124
>>>>>> *Mar 1 07:05:56.442: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 1
>>>>>> 53 from 10.0.0.131:64455
>>>>>> *Mar 1 07:05:56.450: ARQ (seq# 1864) rcvdparse_arq_nonstd:
ARQ Nonstd
>>>>>> decode su
>>>>>> cceeded, remlen = 1692063440
>>>>>> *Mar 1 07:05:56.466: IPSOCK_RAS_sendto: msg length 13 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.131: 64455
>>>>>> *Mar 1 07:05:56.466:
>>>>>> Router# RASLib::RASSendARJ: ARJ (seq# 1864) sent to 10.0.0.131
>>>>>> *Mar 1 07:05:56.486: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 2
>>>>>> 69 from 10.0.0.124:51236
>>>>>> *Mar 1 07:05:56.494: DRQ (seq# 33) rcvd
>>>>>> *Mar 1 07:05:56.506: IPSOCK_RAS_sendto: msg length 3 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.124: 51236
>>>>>> *Mar 1 07:05:56.510: RASLib::RASSendDCF: DCF (seq# 33)
sent to
>>>>>> 10.0.0.124
>>>>>> Router#
>>>>>> *Mar 1 07:05:59.186: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 1
>>>>>> 04 from 10.0.0.131:64455
>>>>>> *Mar 1 07:05:59.190: DRQ (seq# 1865)
rcvdparse_rasusginfo_nonstd: Ras
>>>>>> Usage Inf
>>>>>> o Nonstd decode succeeded, remlen = 1692063440
>>>>>> *Mar 1 07:05:59.202: IPSOCK_RAS_sendto: msg length 3 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.131: 64455
>>>>>> *Mar 1 07:05:59.202: RASLib::RASSendDCF: DCF (seq#
1865) sent to
>>>>>> 10.0.0.1
>>>>>> 31
>>>>>> *Mar 1 07:05:59.206: RecvUDP_IPSockData successfully rcvd
message of
>>>>>> length 2
>>>>>> 79 from 10.0.0.124:51236
>>>>>> *Mar 1 07:05:59.214: DRQ (seq# 34) rcvd
>>>>>> *Mar 1 07:05:59.230: IPSOCK_RAS_sendto: msg length 3 from
>>>>>> 10.0.0.63:1719 to
>>>>>> 10.0.0.124: 51236
>>>>>
>>>>
>>
>
>>> 10.0.0.124: 51236
>>>>>
>>>>
>>
>
