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 >>>> >>> >