term mon VoiceGateway#77.241.64.49 *Jan 3 03:57:13.699: RADIUS: Acct-Delay-Time [41] 6 0 h323chan_dgram_send:Sent UDP msg. Bytes sent: 66 to 77.241.64.21:1812 fd=2 *Jan 3 03:57:17.735: RASLib::GW_RASSendGRQ: GRQ (seq# 3774) sent to 77.241.64.21 *Jan 3 03:57:18.367: ISDN Se7/7:15 SC LIFd: LIF_StartTimer: timer (0x63F25734), ticks (1000), event (0x1240) *Jan 3 03:57:18.367: ISDN Se7/7:15 SC LIFd: LIF_StartTimer: timer (0x63F25778), ticks (30000), event (0x1250) *Jan 3 03:57:18.471: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49260], tagID[24], instID[-1], data[0x6464310C], dataSize[4] *Jan 3 03:57:18.471: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6601F844], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[3] *Jan 3 03:57:18.471: //49260/821F3171AA43/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:57:18.471: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65F6B828], tdObject[0x6601F844] *Jan 3 03:57:18.471: //49260/821F3171AA43/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:57:18.471: ccTDUtilDumpAllElemInProfileTab: profileTable[0x65F6B828], numBuckets[11], numEntries[2] *Jan 3 03:57:18.471: Bucket { 0 } ------>0x6601F844[0x0,t-24,l-4,d-0x6601F864,m-3,u-187041,g-FACE0FFF] *Jan 3 03:57:18.471: *Jan 3 03:57:18.471: Bucket { 6 } ------>0x64F4F518[0x0,t-19,m-1,g-FACE0FFF 0x6500E48C,i-0 ] *Jan 3 03:57:18.471: *Jan 3 03:57:18.475: //49258/821F3171AA43/CCAPI/cc_api_call_disconnected: (vdbPtr=0x641D4BB4, callID=0xC06A, cause=0x10, rawmsg=0x0) *Jan 3 03:57:18.475: //49258/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(11=CC_EV_CALL_DISCONNECTED), cid(49258), disp(0) *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:4506/ssaTraceSct: cid(49258)st(SSA_CS_CONFERENCED_ALERT)ev(SSA_EV_CALL_DISCONNECTED) oldst(SSA_CS_CONFERENCING_ALERT)cfid(4506)csize(0)in(1)fDest(1) *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:4506/ssaTraceSct: -cid2(49260)st2(SSA_CS_CONFERENCED_ALERT)oldst2(SSA_CS_CONFERENCED_ALERT) *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:4506/ssaDisconnectedAlert: *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:4506/ssaDisconnectedAlert: redirect_numbers(0) *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:4506/ssaDebugPeers: ssaFlushPeerTagQueue cid(49258) peer list: tag(1) called number (064125290) *Jan 3 03:57:18.475: //49260/821F3171AA43/SSAPP:0:4506/ssaAAA_CheckAccounting: accounting generation enabled *Jan 3 03:57:18.475: //49260/821F3171AA43/CCAPI/ccCallSetAAA_Accounting: callID=0xC06C, accounting=0 *Jan 3 03:57:18.475: //49258/821F3171AA43/CCAPI/ccConferenceDestroy: (confID=0x119A, tag=0x0) *Jan 3 03:57:18.475: //49258/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0x119A, srcIF=0x641D4BB4, srcCallID=0xC06A, dstCallID=0xC06C, disposition=0 tag=0x0) *Jan 3 03:57:18.475: //49260/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0x119A, srcIF=0x648EF15C, srcCallID=0xC06C, dstCallID=0xC06A, disposition=0 tag=0x0) *Jan 3 03:57:18.475: //49258/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(30=CC_EV_CONF_DESTROY_DONE), cid(49258), disp(0) *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:-1/ssaTraceSct: cid(49258)st(SSA_CS_CONF_DESTROYING)ev(SSA_EV_CONF_DESTROY_DONE) oldst(SSA_CS_CONFERENCED_ALERT)cfid(-1)csize(0)in(1)fDest(1) *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:-1/ssaTraceSct: -cid2(49260)st2(SSA_CS_CONF_DESTROYING)oldst2(SSA_CS_CONFERENCED_ALERT) *Jan 3 03:57:18.475: //49260/821F3171AA43/SSAPP:0:4506/ssaConfDestroyDone: *Jan 3 03:57:18.475: //49258/821F3171AA43/SSAPP:210:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(49258) peer list: (empty) *Jan 3 03:57:18.475: //49258/821F3171AA43/CCAPI/ccCallDisconnect: (callID=0xC06A, cause=0x10 tag=0x0) *Jan 3 03:57:18.475: //49258/821F3171AA43/CCAPI/ccCallDisconnect: calling accounting start for callID=49258 leg_type=1 *Jan 3 03:57:18.479: //49258/821F3171AA43/CCAPI/ccCallDisconnect: existing_cause = 0x10, new_cause = 0x10 *Jan 3 03:57:18.479: //49258/821F3171AA43/CCAPI/ccCallDisconnect: using the existing_cause 0x10 *Jan 3 03:57:18.479: //49258/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49258], tagID[24], instID[-1] *Jan 3 03:57:18.479: //49260/821F3171AA43/CCAPI/ccCallDisconnect: (callID=0xC06C, cause=0x10 tag=0x0) *Jan 3 03:57:18.479: //49260/821F3171AA43/CCAPI/ccCallDisconnect: calling accounting start for callID=49260 leg_type=0 *Jan 3 03:57:18.479: //49260/821F3171AA43/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10 *Jan 3 03:57:18.479: //49260/821F3171AA43/CCAPI/cc_api_get_transfer_info: (callID=0xC06C) *Jan 3 03:57:18.479: //49260/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49260], tagID[24], instID[-1] *Jan 3 03:57:19.047: //49295//MSM :/ms_stop_play: mgdTstop at 2d03h (cause MS_STOP_COMPLETE) *Jan 3 03:57:19.047: //49295//MSM :/ms_stop_play: Play Stopped at 2d03h *Jan 3 03:57:19.047: //49295/66D5F4B58D5A/CCAPI/ccDisassociateStream: (callID=0xC08F) *Jan 3 03:57:19.047: //49295//MSM :/ms_stop_play: calling CBF for stream_id 1 *Jan 3 03:57:19.047: //49295//MSW :/msu_synth_ms_play_complete: context=0x641E5120, use_dynamic=1, dynamicElement=0x0, cause=MS_STOP_COMPLETE, stream_id=1, duration=3781, rate=0 *Jan 3 03:57:19.047: //49295//MSW :/msu_call_app: app_cbf=0x6133F27C Event = MSW_EV_SYNTHESIZER(1), Context 0x6503B7C4, Type MSW_SYNTH_TYPE_SYNTHESIZE(2), Reason MSW_SYNTH_REASON_GENERIC_SUCCESS(13) *Jan 3 03:57:19.047: //49295//TCL2:/TclInterpHandler: Tcl handler 0x64ED3FFC processing MSW_EV_SYNTHESIZER *Jan 3 03:57:19.047: //49295//TCL2:/TclInterpDriver: *Jan 3 03:57:19.047: //49295//TCL2:/TclEventPreprocessing: *Jan 3 03:57:19.047: //49295//TCL2:/MakeTransition: FSM Transtion: ([6 ]GETACCOUNT,[84]ev_media_done)---([-1]IGNORE)--->([6 ]GETACCOUNT) *Jan 3 03:57:21.175: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=1, digit_begin_flags=0x1, rtp_timestamp=0x2075612C rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:57:21.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49288], tagID[24], instID[-1], data[0x6464311C], dataSize[4] *Jan 3 03:57:21.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64F4F518], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[3] *Jan 3 03:57:21.199: //49288/9E37933AAA48/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:57:21.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64898248], tdObject[0x64F4F518] *Jan 3 03:57:21.199: //49288/9E37933AAA48/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:57:21.199: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64898248], numBuckets[11], numEntries[2] *Jan 3 03:57:21.199: Bucket { 0 } ------>0x64F4F518[0x0,t-24,l-4,d-0x64F4F538,m-3,u-187044,g-FACE0FFF] *Jan 3 03:57:21.199: *Jan 3 03:57:21.199: Bucket { 6 } ------>0x650009CC[0x0,t-19,m-1,g-FACE0FFF 0x6465BD34,i-0 ] *Jan 3 03:57:21.199: *Jan 3 03:57:21.199: //49287/9E37933AAA48/CCAPI/cc_api_call_disconnected: (vdbPtr=0x641D4BB4, callID=0xC087, cause=0x10, rawmsg=0x0) *Jan 3 03:57:21.199: //49287/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(11=CC_EV_CALL_DISCONNECTED), cid(49287), disp(0) *Jan 3 03:57:21.199: //49287/9E37933AAA48/SSAPP:200:4510/ssaTraceSct: cid(49287)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_DISCONNECTED) oldst(SSA_CS_CONFERENCING)cfid(4510)csize(2)in(1)fDest(1) *Jan 3 03:57:21.199: //49287/9E37933AAA48/SSAPP:200:4510/ssaTraceSct: -cid2(49288)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_ACTIVE) *Jan 3 03:57:21.203: //49287/9E37933AAA48/SSAPP:200:4510/ssaDebugPeers: ssaFlushPeerTagQueue cid(49287) peer list: (empty) *Jan 3 03:57:21.203: ssa: Disconnected cid(49287) state(5) cause(0x10) *Jan 3 03:57:21.203: //49287/9E37933AAA48/CCAPI/ccConferenceDestroy: (confID=0x119E, tag=0x0) *Jan 3 03:57:21.203: //49287/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0x119E, srcIF=0x641D4BB4, srcCallID=0xC087, dstCallID=0xC088, disposition=0 tag=0x0) *Jan 3 03:57:21.203: //49288/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0x119E, srcIF=0x648EF15C, srcCallID=0xC088, dstCallID=0xC087, disposition=0 tag=0x0) *Jan 3 03:57:21.203: //49287/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(30=CC_EV_CONF_DESTROY_DONE), cid(49287), disp(0) *Jan 3 03:57:21.203: //49287/9E37933AAA48/SSAPP:200:-1/ssaTraceSct: cid(49287)st(SSA_CS_CONF_DESTROYING)ev(SSA_EV_CONF_DESTROY_DONE) oldst(SSA_CS_ACTIVE)cfid(-1)csize(2)in(1)fDest(1) *Jan 3 03:57:21.203: //49287/9E37933AAA48/SSAPP:200:-1/ssaTraceSct: -cid2(49288)st2(SSA_CS_CONF_DESTROYING)oldst2(SSA_CS_ACTIVE) *Jan 3 03:57:21.203: //49288/9E37933AAA48/SSAPP:0:4510/ssaConfDestroyDone: *Jan 3 03:57:21.203: //49287/9E37933AAA48/SSAPP:200:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(49287) peer list: (empty) *Jan 3 03:57:21.203: //49287/9E37933AAA48/CCAPI/ccCallDisconnect: (callID=0xC087, cause=0x10 tag=0x0) *Jan 3 03:57:21.203: //49287/9E37933AAA48/CCAPI/ccCallDisconnect: calling accounting start for callID=49287 leg_type=1 *Jan 3 03:57:21.203: //49287/9E37933AAA48/CCAPI/ccCallDisconnect: existing_cause = 0x10, new_cause = 0x10 *Jan 3 03:57:21.203: //49287/9E37933AAA48/CCAPI/ccCallDisconnect: using the existing_cause 0x10 *Jan 3 03:57:21.203: //49287/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49287], tagID[24], instID[-1] *Jan 3 03:57:21.203: //49287/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[49287] *Jan 3 03:57:21.203: //49288/9E37933AAA48/CCAPI/ccCallDisconnect: (callID=0xC088, cause=0x10 tag=0x0) *Jan 3 03:57:21.203: //49288/9E37933AAA48/CCAPI/ccCallDisconnect: calling accounting start for callID=49288 leg_type=0 *Jan 3 03:57:21.203: //49288/9E37933AAA48/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10 *Jan 3 03:57:21.203: //49288/9E37933AAA48/CCAPI/cc_api_get_transfer_info: (callID=0xC088) *Jan 3 03:57:21.203: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49288], tagID[24], instID[-1] *Jan 3 03:57:21.203: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 10 *Jan 3 03:57:21.207: //-1/xxxxxxxxxxxx/CCAPI/g113_calculate_impairment: (delay=79, loss=0), Io=0 Iq=0 Idte=0 Idd=2 Ie=0 Itot=2 *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC087, mask=1 *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC087, mask=1 *Jan 3 03:57:21.207: //49287/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[49287] *Jan 3 03:57:21.207: //49287/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49287], tagID[7], instID[-1] *Jan 3 03:57:21.207: //49287/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[49287] *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC087, mask=2 *Jan 3 03:57:21.207: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote IP is 77.241.64.31 *Jan 3 03:57:21.207: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is FastEthernet0/0 *Jan 3 03:57:21.207: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of entry: 11, voip: 11, mmoip: 0 *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x641D4BB4, callID=0xC087, disp=0, tag=0x0) *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/cc_api_call_disconnect_done: cause=16,retry=0,vcCauseCode=0 *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/cc_delete_call_entry: Decrement call volume counter 13 *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/cc_delete_call_entry: current call volume: 12 *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/cc_delete_call_entry: entry's incoming TRUE. *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/cc_delete_call_entry: is_incoming is FALSE *Jan 3 03:57:21.207: //49287/9E37933AAA48/CCAPI/cc_delete_call_entry: Deleting profileTable[0x64677554] *Jan 3 03:57:21.207: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x64677554) *Jan 3 03:57:21.207: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x65FCE114] tagID[20] *Jan 3 03:57:21.211: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x65F2078C] tagID[6] *Jan 3 03:57:21.211: RADIUS/ENCODE(00014108):Orig. component type = VOICE *Jan 3 03:57:21.211: RADIUS(00014108): Config NAS IP: 0.0.0.0 *Jan 3 03:57:21.211: RADIUS(00014108): sending *Jan 3 03:57:21.211: //49287/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(49287), disp(0) *Jan 3 03:57:21.211: //49287/9E37933AAA48/SSAPP:200:-1/ssaTraceSct: cid(49287)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_CONF_DESTROYING)cfid(-1)csize(2)in(1)fDest(1) *Jan 3 03:57:21.211: //49287/9E37933AAA48/SSAPP:200:-1/ssaTraceSct: -cid2(49288)st2(SSA_CS_DISCONNECTING)oldst2(SSA_CS_ACTIVE) *Jan 3 03:57:21.211: //49287/9E37933AAA48/SSAPP:200:-1/ssaDisconnectDone: *Jan 3 03:57:21.211: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:57:21.215: RADIUS(00014108): Send Accounting-Request to 77.241.64.21:1813 id 1646/76, len 971 *Jan 3 03:57:21.215: RADIUS: authenticator 80 95 BE 86 1C 73 C9 58 - 4B 16 B8 B3 52 16 86 83 *Jan 3 03:57:21.215: RADIUS: Acct-Session-Id [44] 10 "0001FE1B" *Jan 3 03:57:21.215: RADIUS: Calling-Station-Id [31] 10 "63000021" *Jan 3 03:57:21.215: RADIUS: Called-Station-Id [30] 12 "0795433451" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:57:21.215: RADIUS: Cisco AVpair [1] 59 "call-id=8104e72e-1027-9894-73ce-5d8da025fcbe@77.241.64.31" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 21 *Jan 3 03:57:21.215: RADIUS: Cisco AVpair [1] 15 "iphop=count:2" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:21.215: RADIUS: Cisco AVpair [1] 25 "iphop=hop1:77.241.64.31" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:21.215: RADIUS: Cisco AVpair [1] 25 "iphop=hop2:77.241.64.31" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:21.215: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:56:40.435 UTC Mon Jan 3 2000" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:21.215: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:21.215: RADIUS: Conf-Id [24] 50 "h323-conf-id=9E37933A C0C811D3 AA48E9B4 7DB0F344" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:21.215: RADIUS: h323-call-origin [26] 25 "h323-call-origin=answer" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 27 *Jan 3 03:57:21.215: RADIUS: h323-call-type [27] 21 "h323-call-type=VoIP" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:57:21.215: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=9E37933A C0C811D3 AA48E9B4 7DB0F344" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 26 *Jan 3 03:57:21.215: RADIUS: Cisco AVpair [1] 20 "subscriber=Unknown" *Jan 3 03:57:21.215: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:57:21.219: RADIUS: Cisco AVpair [1] 24 "session-protocol=sipv2" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 43 *Jan 3 03:57:21.219: RADIUS: Cisco AVpair [1] 37 "gw-rxd-cdn=ton:0,npi:0,#:0795433451" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 58 *Jan 3 03:57:21.219: RADIUS: h323-connect-time [28] 52 "h323-connect-time=*03:57:02.467 UTC Mon Jan 3 2000" *Jan 3 03:57:21.219: RADIUS: Acct-Input-Octets [42] 6 144320 *Jan 3 03:57:21.219: RADIUS: Acct-Output-Octets [43] 6 114724 *Jan 3 03:57:21.219: RADIUS: Acct-Input-Packets [47] 6 902 *Jan 3 03:57:21.219: RADIUS: Acct-Output-Packets [48] 6 721 *Jan 3 03:57:21.219: RADIUS: Acct-Session-Time [46] 6 19 *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 61 *Jan 3 03:57:21.219: RADIUS: h323-disconnect-tim[29] 55 "h323-disconnect-time=*03:57:21.197 UTC Mon Jan 3 2000" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:21.219: RADIUS: h323-disconnect-cau[30] 26 "h323-disconnect-cause=10" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 40 *Jan 3 03:57:21.219: RADIUS: h323-remote-address[23] 34 "h323-remote-address=77.241.64.31" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 24 *Jan 3 03:57:21.219: RADIUS: Cisco AVpair [1] 18 "release-source=2" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 28 *Jan 3 03:57:21.219: RADIUS: h323-voice-quality [31] 22 "h323-voice-quality=0" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 41 *Jan 3 03:57:21.219: RADIUS: Cisco AVpair [1] 35 "remote-media-address=77.241.64.31" *Jan 3 03:57:21.219: RADIUS: Vendor, Cisco [26] 51 *Jan 3 03:57:21.219: RADIUS: Cisco AVpair [1] 45 "gw-rxd-cgn=ton:0,npi:0,pi:0,si:1,#:63000021" *Jan 3 03:57:21.219: RADIUS: User-Name [1] 10 "63000021" *Jan 3 03:57:21.219: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:57:21.219: RADIUS: Acct-Status-Type [40] 6 Stop [2] *Jan 3 03:57:21.219: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:57:21.219: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:57:21.223: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49288], tagID[27], instID[-1] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[27] of callID[49288] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49288], tagID[28], instID[-1] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[28] of callID[49288] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49288], tagID[29], instID[-1] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[29] of callID[49288] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49288], tagID[30], instID[-1] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[30] of callID[49288] *Jan 3 03:57:21.223: //49288/9E37933AAA48/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC088, mask=1 *Jan 3 03:57:21.223: //49288/9E37933AAA48/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC088, mask=1 *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[20] of callID[49288] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[49288] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49288], tagID[8], instID[-1] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[8] of callID[49288] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49288], tagID[18], instID[-1] *Jan 3 03:57:21.223: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[18] of callID[49288] *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC088, mask=2 *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/ccGetCallActiveByCallID: cc_spi_call_get() returned -7. (setup_time=0x11D5836, index=0x1) *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x648EF15C, callID=0xC088, disp=0, tag=0x0) *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/cc_api_call_disconnect_done: cause=16,retry=0,vcCauseCode=0 *Jan 3 03:57:21.227: //49288/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[6] of callID[49288] *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/cc_api_call_disconnect_done: *Jan 3 03:57:21.227: cannot find prefix for dp 2 called number 795433451 *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/cc_delete_call_entry: not incoming entry *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/cc_delete_call_entry: entry's incoming FALSE. *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/cc_delete_call_entry: is_incoming is FALSE *Jan 3 03:57:21.227: //49288/9E37933AAA48/CCAPI/cc_delete_call_entry: Deleting profileTable[0x64898248] *Jan 3 03:57:21.227: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x64898248) *Jan 3 03:57:21.227: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64F4F518] tagID[24] *Jan 3 03:57:21.227: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructMultiInstHolderObject: MultiInstTDObj[0x650009CC] tagID[19] numInst[1] *Jan 3 03:57:21.227: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x6465BD34] tagID[19] *Jan 3 03:57:21.227: RADIUS: Received from id 1646/76 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:57:21.227: RADIUS: authenticator BB 5B 5C 8F 4B CB 5C EE - 0C 19 8A 9C 8F 3E D4 B0 *Jan 3 03:57:21.231: RADIUS/ENCODE(0001410A):Orig. component type = VOICE *Jan 3 03:57:21.231: RADIUS/ENCODE: Skip encoding 0 length AAA Cisco vsa out-intrfc-desc *Jan 3 03:57:21.231: RADIUS(0001410A): Using existing nas_port 109 *Jan 3 03:57:21.231: RADIUS(0001410A): Config NAS IP: 0.0.0.0 *Jan 3 03:57:21.231: RADIUS(0001410A): sending *Jan 3 03:57:21.231: //49288/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(49288), disp(0) *Jan 3 03:57:21.231: //49288/9E37933AAA48/SSAPP:0:-1/ssaTraceSct: cid(49288)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_ACTIVE)cfid(-1)csize(1)in(0)fDest(0) *Jan 3 03:57:21.231: //49288/9E37933AAA48/SSAPP:0:-1/ssaDisconnectDone: *Jan 3 03:57:21.231: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:57:21.231: RADIUS(0001410A): Send Accounting-Request to 77.241.64.21:1813 id 1646/77, len 952 *Jan 3 03:57:21.231: RADIUS: authenticator 0B C5 CC 87 64 F2 03 23 - 02 FB C4 1F 8F 34 CA D7 *Jan 3 03:57:21.231: RADIUS: Acct-Session-Id [44] 10 "0001FE1D" *Jan 3 03:57:21.231: RADIUS: Calling-Station-Id [31] 10 "63000021" *Jan 3 03:57:21.231: RADIUS: Called-Station-Id [30] 11 "795433451" *Jan 3 03:57:21.231: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:21.231: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:56:40.455 UTC Mon Jan 3 2000" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:21.235: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:21.235: RADIUS: Conf-Id [24] 50 "h323-conf-id=9E37933A C0C811D3 AA48E9B4 7DB0F344" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 34 *Jan 3 03:57:21.235: RADIUS: h323-call-origin [26] 28 "h323-call-origin=originate" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:21.235: RADIUS: h323-call-type [27] 26 "h323-call-type=Telephony" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:57:21.235: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=9E37933A C0C811D3 AA48E9B4 7DB0F344" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 26 *Jan 3 03:57:21.235: RADIUS: Cisco AVpair [1] 20 "subscriber=Unknown" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 43 *Jan 3 03:57:21.235: RADIUS: Cisco AVpair [1] 37 "gw-rxd-cdn=ton:0,npi:0,#:0795433451" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 58 *Jan 3 03:57:21.235: RADIUS: h323-connect-time [28] 52 "h323-connect-time=*03:57:02.459 UTC Mon Jan 3 2000" *Jan 3 03:57:21.235: RADIUS: Acct-Input-Octets [42] 6 114724 *Jan 3 03:57:21.235: RADIUS: Acct-Output-Octets [43] 6 144320 *Jan 3 03:57:21.235: RADIUS: Acct-Input-Packets [47] 6 721 *Jan 3 03:57:21.235: RADIUS: Acct-Output-Packets [48] 6 902 *Jan 3 03:57:21.235: RADIUS: Acct-Session-Time [46] 6 19 *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 61 *Jan 3 03:57:21.235: RADIUS: h323-disconnect-tim[29] 55 "h323-disconnect-time=*03:57:21.223 UTC Mon Jan 3 2000" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:21.235: RADIUS: h323-disconnect-cau[30] 26 "h323-disconnect-cause=10" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 35 *Jan 3 03:57:21.235: RADIUS: Cisco AVpair [1] 29 "h323-ivr-out=Tariff:Unknown" *Jan 3 03:57:21.235: RADIUS: Vendor, Cisco [26] 24 *Jan 3 03:57:21.235: RADIUS: Cisco AVpair [1] 18 "release-source=2" *Jan 3 03:57:21.239: RADIUS: Vendor, Cisco [26] 28 *Jan 3 03:57:21.239: RADIUS: h323-voice-quality [31] 22 "h323-voice-quality=0" *Jan 3 03:57:21.239: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:21.239: RADIUS: Cisco AVpair [1] 50 "alert-timepoint=*03:56:45.933 UTC Mon Jan 3 2000" *Jan 3 03:57:21.239: RADIUS: Vendor, Cisco [26] 51 *Jan 3 03:57:21.239: RADIUS: Cisco AVpair [1] 45 "gw-rxd-cgn=ton:0,npi:0,pi:0,si:1,#:63000021" *Jan 3 03:57:21.239: RADIUS: Vendor, Cisco [26] 51 *Jan 3 03:57:21.239: RADIUS: Cisco AVpair [1] 45 "gw-final-xlated-cdn=ton:0,npi:0,#:795433451" *Jan 3 03:57:21.239: RADIUS: Vendor, Cisco [26] 60 *Jan 3 03:57:21.239: RADIUS: Cisco AVpair [1] 54 "gw-final-xlated-cgn=ton:0,npi:0,pi:0,si:1,#:63000021" *Jan 3 03:57:21.239: RADIUS: User-Name [1] 10 "63000021" *Jan 3 03:57:21.239: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:57:21.239: RADIUS: Acct-Status-Type [40] 6 Stop [2] *Jan 3 03:57:21.239: RADIUS: NAS-Port-Type [61] 6 Async [0] *Jan 3 03:57:21.239: RADIUS: Vendor, Cisco [26] 19 *Jan 3 03:57:21.239: RADIUS: cisco-nas-port [2] 13 "Serial7/1:9" *Jan 3 03:57:21.239: RADIUS: NAS-Port [5] 6 109 *Jan 3 03:57:21.239: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:57:21.239: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:57:21.239: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:57:21.243: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0xFEBD, int id = 0x0 *Jan 3 03:57:21.243: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFEBD cr 0x7EB3 state 10 event 0x84 ces 1 *Jan 3 03:57:21.243: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7EB3 CC_DISCONNECT_REQ:Ux_DiscReq(nlcb) *Jan 3 03:57:21.243: ISDN Se7/1:15 SC Q931d: L3_TxSarMsg: InfoLen = 9 octets 0x08027EB34508028090 *Jan 3 03:57:21.243: ISDN Se7/1:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x6485269C datasize = 25 *Jan 3 03:57:21.243: ISDN Se7/1:15 SC Q931d: data = 0x6485269C000003000240049400010806 *Jan 3 03:57:21.243: 08027EB34508028090 *Jan 3 03:57:21.243: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x64679078), ticks (30000), event (0x1315) *Jan 3 03:57:21.243: ISDN Se7/1:15 SC Q931d: L3_state_change: callref 0x7EB3 old CALL_ACTIVE, new DISCONNECT_REQUEST *Jan 3 03:57:21.243: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x641A8A5C), ticks (1000), event (0x1240) *Jan 3 03:57:21.243: RADIUS: Received from id 1646/77 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:57:21.243: RADIUS: authenticator F2 5C 8D 9F E1 CD 09 E3 - 84 88 E5 8C 24 31 01 3D *Jan 3 03:57:21.247: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:57:21.771: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=2,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:57:22.779: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=6,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:57:23.987: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=9, digit_begin_flags=0x1, rtp_timestamp=0x2075B91C rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:57:24.091: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=9,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:57:24.859: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=9, digit_begin_flags=0x1, rtp_timestamp=0x2075D42C rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:57:24.955: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=9,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:57:25.891: //49295/66D5F4B58D5A/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC08F, digit=3,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:57:26.835: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:57:26.835: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0 *Jan 3 03:57:26.835: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFEBE cr 0x7EB4 state 3 event 0x45 ces 1 *Jan 3 03:57:26.835: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7EB4 DISCONNECT:Ux_Disconnect(nlcb) *Jan 3 03:57:26.835: ISDN Se7/1:15 SC Q931d: L3_state_change: callref 0x7EB4 old OUT_CALL_PROC, new DISCONNECT_IND *Jan 3 03:57:26.839: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0xFEBE, int id = 0x0 *Jan 3 03:57:26.839: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFEBE cr 0x7EB4 state 12 event 0x85 ces 1 *Jan 3 03:57:26.839: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7EB4 CC_RELEASE_REQ:U12_ReleaseReq(nlcb) *Jan 3 03:57:26.839: ISDN Se7/1:15 SC Q931d: L3_TxSarMsg: InfoLen = 5 octets 0x08027EB44D *Jan 3 03:57:26.839: ISDN Se7/1:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x6485269C datasize = 21 *Jan 3 03:57:26.839: ISDN Se7/1:15 SC Q931d: data = 0x6485269C000003000240049400010806 *Jan 3 03:57:26.839: 08027EB44D *Jan 3 03:57:26.839: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x64E9D2B0), ticks (4000), event (0x1318) *Jan 3 03:57:26.839: ISDN Se7/1:15 SC Q931d: L3_state_change: callref 0x7EB4 old DISCONNECT_IND, new RELEASE_REQUEST *Jan 3 03:57:26.839: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x641A8A5C), ticks (1000), event (0x1240) *Jan 3 03:57:26.839: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x64E55380], magic[FACE0FFF] *Jan 3 03:57:26.839: //49299/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:57:26.839: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x64DEE7CC] with objects in container[0x64E55380] *Jan 3 03:57:26.839: //49299/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:57:26.839: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64DEE7CC], numBuckets[11], numEntries[1] *Jan 3 03:57:26.839: Bucket { 6 } ------>0x64736AC4[0x0,t-19,m-1,g-FACE0FFF 0x64E86868,i-0 ] *Jan 3 03:57:26.839: *Jan 3 03:57:26.839: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x64E55380] *Jan 3 03:57:26.843: //49299/66D5F4B58D5A/CCAPI/cc_api_call_disconnected: (vdbPtr=0x648EF15C, callID=0xC093, cause=0x3, rawmsg=0x645AEF78) *Jan 3 03:57:26.843: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49295], tagID[24], instID[-1], data[0x645B49C4], dataSize[4] *Jan 3 03:57:26.843: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64F9F500], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[1] *Jan 3 03:57:26.843: //49295/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:57:26.843: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65FEF53C], tdObject[0x64F9F500] *Jan 3 03:57:26.843: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64EC1C68] tagID[24] *Jan 3 03:57:26.843: //49295/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:57:26.843: ccTDUtilDumpAllElemInProfileTab: profileTable[0x65FEF53C], numBuckets[11], numEntries[3] *Jan 3 03:57:26.843: Bucket { 0 } ------>0x64F9F500[0x0,t-24,l-4,d-0x64F9F520,m-1,u-187049,g-FACE0FFF] *Jan 3 03:57:26.843: *Jan 3 03:57:26.843: Bucket { 5 } ------>0x64F4F308[0x0,t-6,l-16,d-0x64F4F328,m-1,u-187049,g-FACE0FFF] *Jan 3 03:57:26.843: *Jan 3 03:57:26.843: Bucket { 6 } ------>0x64EA78E0[0x0,t-19,m-1,g-FACE0FFF 0x64E4B9C0,i-0 ] *Jan 3 03:57:26.843: *Jan 3 03:57:26.843: //49299/66D5F4B58D5A/CCAPI/ccCallSetAAA_Accounting: callID=0xC093, accounting=1 *Jan 3 03:57:26.843: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[24], instID[-1] *Jan 3 03:57:26.843: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[49299] *Jan 3 03:57:26.843: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49299], tagID[24], instID[-1], data[0x65037C94], dataSize[4] *Jan 3 03:57:26.843: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64E76FCC], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[8] *Jan 3 03:57:26.843: //49299/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:57:26.843: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64DEE7CC], tdObject[0x64E76FCC] *Jan 3 03:57:26.843: //49299/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:57:26.843: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64DEE7CC], numBuckets[11], numEntries[2] *Jan 3 03:57:26.843: Bucket { 0 } ------>0x64E76FCC[0x0,t-24,l-4,d-0x64E76FEC,m-8,u-187049,g-FACE0FFF] *Jan 3 03:57:26.843: *Jan 3 03:57:26.843: Bucket { 6 } ------>0x64736AC4[0x0,t-19,m-1,g-FACE0FFF 0x64E86868,i-0 ] *Jan 3 03:57:26.843: *Jan 3 03:57:26.843: //49299/66D5F4B58D5A/CCAPI/ccCallDisconnect: (callID=0xC093, cause=0x3 tag=0x0) *Jan 3 03:57:26.843: //49299/66D5F4B58D5A/CCAPI/ccCallDisconnect: existing_cause = 0x3, new_cause = 0x3 *Jan 3 03:57:26.847: //49299/66D5F4B58D5A/CCAPI/ccCallDisconnect: using the existing_cause 0x3 *Jan 3 03:57:26.847: //49299/66D5F4B58D5A/CCAPI/cc_api_get_transfer_info: (callID=0xC093) *Jan 3 03:57:26.847: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[24], instID[-1] *Jan 3 03:57:26.847: //49295/66D5F4B58D5A/CCAPI/ccCallSetupRequest: (Inbound call = 0xC08F, outbound peer =1, dest=, params=0x6503A12C mode=0, *callID=0x6503A128, prog_ind = 3callingIE_present 1) *Jan 3 03:57:26.847: //49295/66D5F4B58D5A/CCAPI/ccCallSetupRequest: *Jan 3 03:57:26.847: ccCallSetupRequest numbering_type 0xC1 *Jan 3 03:57:26.847: //49295/66D5F4B58D5A/CCAPI/ccCallSetupRequest: *Jan 3 03:57:26.847: ccCallSetupRequest: calling number is:65518709 *Jan 3 03:57:26.847: //49295/66D5F4B58D5A/CCAPI/ccCallSetupRequest: calling oct3a is:0x83 *Jan 3 03:57:26.847: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:57:26.847: ccCheckClipClir: calling number is: "65518709", calling oct3a is: 0x83 *Jan 3 03:57:26.847: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:57:26.847: Calling Party number is Network Provided *Jan 3 03:57:26.847: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:57:26.847: Leaving ccCheckClipClir calling number is: "65518709" calling oct3 is: 0x21 calling oct3a is: 0x83 *Jan 3 03:57:26.847: //49295/66D5F4B58D5A/CCAPI/ccCallSetupRequest: after ccCheckClipClir - calling oct3a is:0x83 *Jan 3 03:57:26.847: //49295/66D5F4B58D5A/CCAPI/ccCallSetupRequest: dest pattern .T, called 63009999, digit_strip 1 *Jan 3 03:57:26.847: //49295/66D5F4B58D5A/CCAPI/ccCallSetupRequest: *Jan 3 03:57:26.847: callingNumber=65518709, calledNumber=63009999, redirectNumber= display_info= calling_oct3a=83 *Jan 3 03:57:26.847: accountNumber=1234567899, finalDestFlag=1, guid=66d5.f4b5.1c3b.11dd.8d5a.0014.4f61.4a9e *Jan 3 03:57:26.847: peer_tag=1 *Jan 3 03:57:26.847: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields: *Jan 3 03:57:26.847: ccCallSetupRequest: *Jan 3 03:57:26.847: cisco-username=1234567899 *Jan 3 03:57:26.847: ----- ccCallInfo IE subfields ----- *Jan 3 03:57:26.847: cisco-ani=65518709 *Jan 3 03:57:26.847: cisco-anitype=2 *Jan 3 03:57:26.847: cisco-aniplan=1 *Jan 3 03:57:26.847: cisco-anipi=0 *Jan 3 03:57:26.847: cisco-anisi=3 *Jan 3 03:57:26.847: dest=63009999 *Jan 3 03:57:26.847: cisco-desttype=4 *Jan 3 03:57:26.847: cisco-destplan=1 *Jan 3 03:57:26.847: cisco-rdn= *Jan 3 03:57:26.847: cisco-rdntype=-1 *Jan 3 03:57:26.847: cisco-rdnplan=-1 *Jan 3 03:57:26.847: cisco-rdnpi=-1 *Jan 3 03:57:26.847: cisco-rdnsi=-1 *Jan 3 03:57:26.847: cisco-redirectreason=-1 *Jan 3 03:57:26.847: //49295/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: (vdbPtr=0x645EA758, dest=, callParams={called=63009999,called_oct3=0xC1, calling=65518709,calling_oct3=0x21, calling_oct3a= 0x83, calling_xlated=false, subscriber_type_str=RegularLine, fdest=1, voice_peer_tag=1},mode=0x0, appl_call_id=) *Jan 3 03:57:26.847: //49295/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: *Jan 3 03:57:26.847: ccIFCallSetupRequestPrivate: src route label tgt route label tg_label_flag 0x0 *Jan 3 03:57:26.847: //49295/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: vdbPtr type = 6 *Jan 3 03:57:26.847: //49295/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: *Jan 3 03:57:26.851: //49295/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: (vdbPtr=0x645EA758, dest=, callParams={called=63009999, called_oct3 0xC1, calling=65518709,calling_oct3 0x21, calling_oct3a 0x83, calling_xlated=false, fdest=1, voice_peer_tag=1}, mode=0x0, xltrc=-4) *Jan 3 03:57:26.851: //49295/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: *Jan 3 03:57:26.851: //49300/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: not incoming entry *Jan 3 03:57:26.851: //49300/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming FALSE. *Jan 3 03:57:26.851: //49300/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/cc_set_voice_port_value: *Jan 3 03:57:26.851: CC_IF_TELEPHONY: echo =0, playout = 0 *Jan 3 03:57:26.851: //49300/66D5F4B58D5A/CCAPI/ccCallSetContext: (callID=0xC094, context=0x6503A0E4) *Jan 3 03:57:26.851: //49295/66D5F4B58D5A/CCAPI/ccSaveDialpeerTag: (callID=0xC08F, dialpeer_tag=1) *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x64E55380], magic[FACE0FFF] *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x64E55380, tagID=19, dataSize=4, instID=0,modifier=1 *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64F8CA18], nxtElem[0x0], magic[0xFACE0FFF] tagID[19], dataLen[4], modif[1] *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64F8CA18] instID[0] into container[0x64E55380] *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructMultiInstHolderObject: multiHolder[0x64F69F4C], nxtElem[0x0], magic[0xFACE0FFF], tagID[19], dataLen[0], modif[-1], numInst[0] *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtPushInstToMultiInstHolder: Successful in pushing instance object[0x64F8CA18] into holder[0x64F69F4C] *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x6474C810], numBuckets[11], numEntries[0] *Jan 3 03:57:26.851: //49300/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x6474C810] with objects in container[0x64E55380] *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[6] for the tag[19] *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x6474C810], tdObject[0x64F69F4C] *Jan 3 03:57:26.851: //49300/66D5F4B58D5A/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:57:26.851: ccTDUtilDumpAllElemInProfileTab: profileTable[0x6474C810], numBuckets[11], numEntries[1] *Jan 3 03:57:26.851: Bucket { 6 } ------>0x64F69F4C[0x0,t-19,m-1,g-FACE0FFF 0x64F8CA18,i-0 ] *Jan 3 03:57:26.851: *Jan 3 03:57:26.851: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x64E55380] *Jan 3 03:57:26.855: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[27], instID[-1] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[27] of callID[49299] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[28], instID[-1] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[28] of callID[49299] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[29], instID[-1] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[29] of callID[49299] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[30], instID[-1] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[30] of callID[49299] *Jan 3 03:57:26.855: //49299/66D5F4B58D5A/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC093, mask=1 *Jan 3 03:57:26.855: //49299/66D5F4B58D5A/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC093, mask=1 *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[20] of callID[49299] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[49299] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[8], instID[-1] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[8] of callID[49299] *Jan 3 03:57:26.855: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49299], tagID[18], instID[-1] *Jan 3 03:57:26.859: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[18] of callID[49299] *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC093, mask=2 *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/ccGetCallActiveByCallID: cc_spi_call_get() returned -7. (setup_time=0x11D6A46, index=0x1) *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x648EF15C, callID=0xC093, disp=0, tag=0x0) *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/cc_api_call_disconnect_done: cause=3,retry=0,vcCauseCode=0 *Jan 3 03:57:26.859: //49299/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[6] of callID[49299] *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/cc_api_call_disconnect_done: *Jan 3 03:57:26.859: cannot find prefix for dp 2 called number 63009999 *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/cc_delete_call_entry: not incoming entry *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/cc_delete_call_entry: entry's incoming FALSE. *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/cc_delete_call_entry: is_incoming is FALSE *Jan 3 03:57:26.859: //49299/66D5F4B58D5A/CCAPI/cc_delete_call_entry: Deleting profileTable[0x64DEE7CC] *Jan 3 03:57:26.859: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x64DEE7CC) *Jan 3 03:57:26.859: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64E76FCC] tagID[24] *Jan 3 03:57:26.859: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructMultiInstHolderObject: MultiInstTDObj[0x64736AC4] tagID[19] numInst[1] *Jan 3 03:57:26.859: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64E86868] tagID[19] *Jan 3 03:57:26.863: RADIUS/ENCODE(00014116):Orig. component type = VOICE *Jan 3 03:57:26.863: RADIUS/ENCODE: Skip encoding 0 length AAA Cisco vsa out-intrfc-desc *Jan 3 03:57:26.863: RADIUS(00014116): Using existing nas_port 108 *Jan 3 03:57:26.863: RADIUS(00014116): Config NAS IP: 0.0.0.0 *Jan 3 03:57:26.863: RADIUS(00014116): sending *Jan 3 03:57:26.863: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:57:26.863: RADIUS(00014116): Send Accounting-Request to 77.241.64.21:1813 id 1646/82, len 897 *Jan 3 03:57:26.863: RADIUS: authenticator 6F 6D CB 19 AD 85 6A AF - 3F 54 F9 DA 9E 91 2E 85 *Jan 3 03:57:26.863: RADIUS: Acct-Session-Id [44] 10 "0001FE30" *Jan 3 03:57:26.863: RADIUS: Calling-Station-Id [31] 10 "65518709" *Jan 3 03:57:26.863: RADIUS: Called-Station-Id [30] 10 "63009999" *Jan 3 03:57:26.863: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:26.863: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:57:26.699 UTC Mon Jan 3 2000" *Jan 3 03:57:26.863: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:26.863: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:57:26.863: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:26.863: RADIUS: Conf-Id [24] 50 "h323-conf-id=66D5F4B5 1C3B11DD 8D5A0014 4F614A9E" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 34 *Jan 3 03:57:26.867: RADIUS: h323-call-origin [26] 28 "h323-call-origin=originate" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:26.867: RADIUS: h323-call-type [27] 26 "h323-call-type=Telephony" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:57:26.867: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=66D5F4B5 1C3B11DD 8D5A0014 4F614A9E" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:57:26.867: RADIUS: Cisco AVpair [1] 24 "subscriber=RegularLine" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 41 *Jan 3 03:57:26.867: RADIUS: Cisco AVpair [1] 35 "gw-rxd-cdn=ton:4,npi:1,#:63009999" *Jan 3 03:57:26.867: RADIUS: Acct-Input-Octets [42] 6 0 *Jan 3 03:57:26.867: RADIUS: Acct-Output-Octets [43] 6 0 *Jan 3 03:57:26.867: RADIUS: Acct-Input-Packets [47] 6 0 *Jan 3 03:57:26.867: RADIUS: Acct-Output-Packets [48] 6 0 *Jan 3 03:57:26.867: RADIUS: Acct-Session-Time [46] 6 0 *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 58 *Jan 3 03:57:26.867: RADIUS: h323-connect-time [28] 52 "h323-connect-time=*03:57:26.855 UTC Mon Jan 3 2000" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 61 *Jan 3 03:57:26.867: RADIUS: h323-disconnect-tim[29] 55 "h323-disconnect-time=*03:57:26.855 UTC Mon Jan 3 2000" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:26.867: RADIUS: h323-disconnect-cau[30] 25 "h323-disconnect-cause=3" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 35 *Jan 3 03:57:26.867: RADIUS: Cisco AVpair [1] 29 "h323-ivr-out=Tariff:Unknown" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 24 *Jan 3 03:57:26.867: RADIUS: Cisco AVpair [1] 18 "release-source=3" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 28 *Jan 3 03:57:26.867: RADIUS: h323-voice-quality [31] 22 "h323-voice-quality=0" *Jan 3 03:57:26.867: RADIUS: Vendor, Cisco [26] 51 *Jan 3 03:57:26.867: RADIUS: Cisco AVpair [1] 45 "gw-rxd-cgn=ton:2,npi:1,pi:0,si:3,#:65518709" *Jan 3 03:57:26.871: RADIUS: Vendor, Cisco [26] 50 *Jan 3 03:57:26.871: RADIUS: Cisco AVpair [1] 44 "gw-final-xlated-cdn=ton:4,npi:1,#:63009999" *Jan 3 03:57:26.871: RADIUS: Vendor, Cisco [26] 60 *Jan 3 03:57:26.871: RADIUS: Cisco AVpair [1] 54 "gw-final-xlated-cgn=ton:2,npi:1,pi:0,si:3,#:65518709" *Jan 3 03:57:26.871: RADIUS: User-Name [1] 12 "1234567899" *Jan 3 03:57:26.871: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:57:26.871: RADIUS: Acct-Status-Type [40] 6 Stop [2] *Jan 3 03:57:26.871: RADIUS: NAS-Port-Type [61] 6 Async [0] *Jan 3 03:57:26.871: RADIUS: Vendor, Cisco [26] 19 *Jan 3 03:57:26.871: RADIUS: cisco-nas-port [2] 13 "Serial7/1:8" *Jan 3 03:57:26.871: RADIUS: NAS-Port [5] 6 108 *Jan 3 03:57:26.871: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:57:26.871: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:57:26.871: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:57:26.871: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: override plan/type, comparing 65518709/., (regexp) *Jan 3 03:57:26.871: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: overriding plan/type for 65518709, isdn/national to isdn/network *Jan 3 03:57:26.871: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: override plan/type, comparing 63009999/., (regexp) *Jan 3 03:57:26.871: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: overriding plan/type for 63009999, isdn/subscriber to isdn/network *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: override plan/type, comparing 65518709/., (regexp) *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: overriding plan/type for 65518709, isdn/national to isdn/network *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: override plan/type, comparing 63009999/., (regexp) *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: overriding plan/type for 63009999, isdn/subscriber to isdn/network *Jan 3 03:57:26.875: AAA/BIND(00014117): Bind i/f Serial7/0:1 *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0xFEBF, int id = 0x0 *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: L3_Go: call_id 0xFEBF cr 0x0 state 0 event 0x80 ces 1 *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: L3_ProcessEvent: callref = 0x0000 CC_SETUP_REQ:U0_SetupReq(nlcb) *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: L3_TxSarMsg: InfoLen = 102 octets 0x08027EB50504038090A31804E1808382 *Jan 3 03:57:26.875: 1C399E8100036774640000002E49414D *Jan 3 03:57:26.875: 2C0D0A4743492C363664356634623531 *Jan 3 03:57:26.875: 63336231316464386435613030313434 *Jan 3 03:57:26.875: 663631346139650D0A0D0A1E0281836C *Jan 3 03:57:26.875: 0A318336353531383730397009B13633 *Jan 3 03:57:26.875: 303039393939 *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x644659C8 datasize = 118 *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: data = 0x644659C8000003000240043F00010800 *Jan 3 03:57:26.875: 08027EB50504038090A31804E1808382 *Jan 3 03:57:26.875: 1C399E8100036774640000002E49414D *Jan 3 03:57:26.875: 2C0D0A4743492C363664356634623531 *Jan 3 03:57:26.875: 63336231316464386435613030313434 *Jan 3 03:57:26.875: 663631346139650D0A0D0A1E0281836C *Jan 3 03:57:26.875: 0A318336353531383730397009B13633 *Jan 3 03:57:26.875: 303039393939 *Jan 3 03:57:26.875: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64E51B28), ticks (4000), event (0x1313) *Jan 3 03:57:26.875: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x7EB5 old NULL_STATE, new CALL_INIT *Jan 3 03:57:26.879: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:57:26.879: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64AE699C), ticks (1000), event (0x1240) *Jan 3 03:57:26.879: RADIUS: Received from id 1646/82 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:57:26.879: RADIUS: authenticator 39 1E 39 AF 37 BD 35 9F - 78 C7 3F 6C 49 07 39 0B *Jan 3 03:57:26.879: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0 *Jan 3 03:57:26.879: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFEBE cr 0x7EB4 state 19 event 0x5A ces 1 *Jan 3 03:57:26.879: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7EB4 RELEASE_COMP:U19_ReleaseComp(nlcb) *Jan 3 03:57:26.879: ISDN Se7/1:15 SC Q931d: L3_state_change: callref 0x7EB4 old RELEASE_REQUEST, new NULL_STATE *Jan 3 03:57:26.879: ISDN Se7/1:15 SC Q931d: CC_CHAN_GetUserpri: using call_id = 0xFEBE *Jan 3 03:57:26.879: ISDN Se7/1:15 SC Q931d: CC_CHAN_ReleaseChanpri: channel 8 *Jan 3 03:57:26.883: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x642DB49C), ticks (30000), event (0x1250) *Jan 3 03:57:32.715: //49295//TCL2:/TclInterpHandler: Tcl handler 0x64ED3FFC processing APP_EV_LEG_TIMER *Jan 3 03:57:32.715: //49295//TCL2:/TclInterpDriver: *Jan 3 03:57:32.715: //49295//TCL2:/TclEventPreprocessing: *Jan 3 03:57:32.715: //49295//TCL2:/MakeTransition: FSM Transtion: ([4 ]CALLDISCONNECT,[1 ]ev_leg_timer)---([1 ]act_Cleanup)--->([4 ]CALLDISCONNECT) *Jan 3 03:57:32.715: //-1//TCL2:HN0B25F9D8:/TclCallProcess: Interp Done *Jan 3 03:57:32.715: //49295//TCL2:/TclInterpCleanup: Terminate TRUE Terminated FALSE{HAN[TCL_HAND][NULL ] ( LEG[49295 ][TCL_HAND][LEG_INCCONNECTED(5)][Cause(0)][UC=1 ])} *Jan 3 03:57:32.715: //49295//TCL2:/TclInterpCleanup: *Jan 3 03:57:32.715: TclInterpCleanup: pInterp->lastFailureCause=27, pLeg->discCause=16 *Jan 3 03:57:32.715: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[24], instID[-1] *Jan 3 03:57:32.715: //49295/66D5F4B58D5A/CCAPI/ccCallDisconnect: (callID=0xC08F, cause=0x10 tag=0x0) *Jan 3 03:57:32.715: //49295/66D5F4B58D5A/CCAPI/ccCallDisconnect: calling accounting start for callID=49295 leg_type=1 *Jan 3 03:57:32.715: //49295/66D5F4B58D5A/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10 *Jan 3 03:57:32.715: //49295/66D5F4B58D5A/CCAPI/cc_api_get_transfer_info: (callID=0xC08F) *Jan 3 03:57:32.715: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[24], instID[-1] *Jan 3 03:57:32.715: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry: callID is -1 *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[27], instID[-1] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[27] of callID[49295] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[28], instID[-1] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[28] of callID[49295] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[29], instID[-1] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[29] of callID[49295] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[30], instID[-1] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[30] of callID[49295] *Jan 3 03:57:32.723: //49295/66D5F4B58D5A/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC08F, mask=1 *Jan 3 03:57:32.723: //49295/66D5F4B58D5A/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC08F, mask=1 *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[20] of callID[49295] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[49295] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[7], instID[-1] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[49295] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49295], tagID[18], instID[-1] *Jan 3 03:57:32.723: //49295/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[18] of callID[49295] *Jan 3 03:57:32.723: //49295/66D5F4B58D5A/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC08F, mask=2 *Jan 3 03:57:32.723: //49295/66D5F4B58D5A/CCAPI/ccGetCallActiveByCallID: cc_spi_call_get() returned -7. (setup_time=0x11D65C8, index=0x1) *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x645EA758, callID=0xC08F, disp=0, tag=0x0) *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_api_call_disconnect_done: cause=16,retry=0,vcCauseCode=0 *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_delete_call_entry: ccFreeRawMsgInfo(0x645AF2A8) *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_delete_call_entry: Decrement call volume counter 11 *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_delete_call_entry: current call volume: 10 *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_delete_call_entry: entry's incoming TRUE. *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_delete_call_entry: is_incoming is TRUE *Jan 3 03:57:32.727: //49295/66D5F4B58D5A/CCAPI/cc_delete_call_entry: Deleting profileTable[0x65FEF53C] *Jan 3 03:57:32.727: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x65FEF53C) *Jan 3 03:57:32.727: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64665F1C] tagID[24] *Jan 3 03:57:32.727: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64F4F308] tagID[6] *Jan 3 03:57:32.727: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructMultiInstHolderObject: MultiInstTDObj[0x64EA78E0] tagID[19] numInst[1] *Jan 3 03:57:32.727: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64E4B9C0] tagID[19] *Jan 3 03:57:32.727: RADIUS/ENCODE(00014111):Orig. component type = VOICE *Jan 3 03:57:32.727: RADIUS(00014111): Using existing nas_port 27 *Jan 3 03:57:32.731: RADIUS(00014111): Config NAS IP: 0.0.0.0 *Jan 3 03:57:32.731: RADIUS(00014111): sending *Jan 3 03:57:32.731: //49295//TCL2:/TclInterpHandler: Tcl handler 0x64ED3FFC processing CC_EV_CALL_DISCONNECT_DONE *Jan 3 03:57:32.731: //49295//TCL2:/TclInterpCleaner: *Jan 3 03:57:32.731: //49295//MSW :/msw_destroy: *Jan 3 03:57:32.731: //-1//MSW :/msw_stop: genericStream=0x641E511C, reason=MSW_REASON_DISCONNECTED *Jan 3 03:57:32.731: //-1//MSW :/msw_synth_stop: genericStream=0x641E511C, mediaStream=0x65F35138, rtspStream=0x64EA9640 reason=MSW_SYNTH_REASON_DISCONNECTED, current_state=MSW_S_IDLE *Jan 3 03:57:32.731: //49295//MSW :/msw_synth_stop: Stream not currently active *Jan 3 03:57:32.731: //-1//MSW :/msw_recrd_stop: genericStream=0x641E511C, mediaStream=0x64EAF428, rtspStream=0x64EA9648 reason=MSW_RECRD_REASON_DISCONNECTED current_state=MSW_S_IDLE *Jan 3 03:57:32.731: //-1//MSW :/msw_recrd_stop: Stream not currently active *Jan 3 03:57:32.731: //-1//MSW :/msw_recog_stop: genericStream=0x641E511C, mrcpStream=0x64E5BBBC reason=MSW_RECOG_REASON_DISCONNECTED, current_state=MSW_S_IDLE *Jan 3 03:57:32.731: //-1//MSW :/msw_synth_stop: genericStream=0x641E511C, mediaStream=0x65F35138, rtspStream=0x64EA9640 reason=MSW_SYNTH_REASON_DISCONNECTED, current_state=MSW_S_IDLE *Jan 3 03:57:32.731: //49295//MSW :/msw_synth_stop: Stream not currently active *Jan 3 03:57:32.731: //-1//MSW :/msw_recrd_stop: genericStream=0x641E511C, mediaStream=0x64EAF428, rtspStream=0x64EA9648 reason=MSW_RECRD_REASON_DISCONNECTED current_state=MSW_S_IDLE *Jan 3 03:57:32.731: //-1//MSW :/msw_recrd_stop: Stream not currently active *Jan 3 03:57:32.731: //-1//MSW :/msw_recog_stop: genericStream=0x641E511C, mrcpStream=0x64E5BBBC reason=MSW_RECOG_REASON_DISCONNECTED, current_state=MSW_S_IDLE *Jan 3 03:57:32.731: //-1//TCL2:HN0B25F9D8:/TclCallProcess: Interp Done *Jan 3 03:57:32.731: //-1//TCL2:HN0B25F9D8:/TclInterpCleanup: Terminate TRUE Terminated TRUE{HAN[TCL_HAND][NULL ] ( )} *Jan 3 03:57:32.731: //-1//TCL2:HN0B25F9D8:/TclFreeInterp: {HAN[TCL_HAND][NULL ] ( )} *Jan 3 03:57:32.735: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:57:32.735: RADIUS(00014111): Send Accounting-Request to 77.241.64.21:1813 id 1646/89, len 784 *Jan 3 03:57:32.735: RADIUS: authenticator 2F B7 AD 69 10 0E D8 1E - FE B2 5E 78 00 BE 16 51 *Jan 3 03:57:32.735: RADIUS: Acct-Session-Id [44] 10 "0001FE26" *Jan 3 03:57:32.735: RADIUS: Calling-Station-Id [31] 10 "65518709" *Jan 3 03:57:32.735: RADIUS: Called-Station-Id [30] 10 "63009999" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:32.735: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:57:15.203 UTC Mon Jan 3 2000" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:32.735: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:32.735: RADIUS: Conf-Id [24] 50 "h323-conf-id=66D5F4B5 1C3B11DD 8D5A0014 4F614A9E" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:32.735: RADIUS: h323-call-origin [26] 25 "h323-call-origin=answer" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:32.735: RADIUS: h323-call-type [27] 26 "h323-call-type=Telephony" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:57:32.735: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=66D5F4B5 1C3B11DD 8D5A0014 4F614A9E" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:57:32.735: RADIUS: Cisco AVpair [1] 24 "subscriber=RegularLine" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 41 *Jan 3 03:57:32.735: RADIUS: Cisco AVpair [1] 35 "gw-rxd-cdn=ton:4,npi:1,#:63009999" *Jan 3 03:57:32.735: RADIUS: Vendor, Cisco [26] 58 *Jan 3 03:57:32.735: RADIUS: h323-connect-time [28] 52 "h323-connect-time=*03:57:15.203 UTC Mon Jan 3 2000" *Jan 3 03:57:32.735: RADIUS: Acct-Input-Octets [42] 6 0 *Jan 3 03:57:32.735: RADIUS: Acct-Output-Octets [43] 6 30240 *Jan 3 03:57:32.735: RADIUS: Acct-Input-Packets [47] 6 0 *Jan 3 03:57:32.735: RADIUS: Acct-Output-Packets [48] 6 126 *Jan 3 03:57:32.739: RADIUS: Acct-Session-Time [46] 6 18 *Jan 3 03:57:32.739: RADIUS: Vendor, Cisco [26] 61 *Jan 3 03:57:32.739: RADIUS: h323-disconnect-tim[29] 55 "h323-disconnect-time=*03:57:32.723 UTC Mon Jan 3 2000" *Jan 3 03:57:32.739: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:32.739: RADIUS: h323-disconnect-cau[30] 26 "h323-disconnect-cause=10" *Jan 3 03:57:32.739: RADIUS: Vendor, Cisco [26] 35 *Jan 3 03:57:32.739: RADIUS: Cisco AVpair [1] 29 "h323-ivr-out=Tariff:Unknown" *Jan 3 03:57:32.739: RADIUS: Vendor, Cisco [26] 24 *Jan 3 03:57:32.739: RADIUS: Cisco AVpair [1] 18 "release-source=6" *Jan 3 03:57:32.739: RADIUS: Vendor, Cisco [26] 28 *Jan 3 03:57:32.739: RADIUS: h323-voice-quality [31] 22 "h323-voice-quality=0" *Jan 3 03:57:32.739: RADIUS: Vendor, Cisco [26] 51 *Jan 3 03:57:32.739: RADIUS: Cisco AVpair [1] 45 "gw-rxd-cgn=ton:2,npi:1,pi:0,si:3,#:65518709" *Jan 3 03:57:32.739: RADIUS: User-Name [1] 10 "65518709" *Jan 3 03:57:32.739: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:57:32.739: RADIUS: Acct-Status-Type [40] 6 Stop [2] *Jan 3 03:57:32.739: RADIUS: NAS-Port-Type [61] 6 Async [0] *Jan 3 03:57:32.739: RADIUS: Vendor, Cisco [26] 20 *Jan 3 03:57:32.739: RADIUS: cisco-nas-port [2] 14 "Serial7/0:27" *Jan 3 03:57:32.739: RADIUS: NAS-Port [5] 6 27 *Jan 3 03:57:32.739: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:57:32.739: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:57:32.739: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:57:32.743: ISDN Se7/0:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0x35B, int id = 0x0 *Jan 3 03:57:32.743: ISDN Se7/0:15 SC Q931d: L3_Go: call_id 0x35B cr 0x886C state 10 event 0x84 ces 1 *Jan 3 03:57:32.743: ISDN Se7/0:15 SC Q931d: L3_ProcessEvent: callref = 0x886C CC_DISCONNECT_REQ:Ux_DiscReq(nlcb) *Jan 3 03:57:32.743: ISDN Se7/0:15 SC Q931d: L3_TxSarMsg: InfoLen = 9 octets 0x0802886C4508028090 *Jan 3 03:57:32.743: ISDN Se7/0:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x644659C8 datasize = 25 *Jan 3 03:57:32.743: ISDN Se7/0:15 SC Q931d: data = 0x644659C8000003000240049400010806 *Jan 3 03:57:32.743: 0802886C4508028090 *Jan 3 03:57:32.743: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64E4E248), ticks (30000), event (0x1315) *Jan 3 03:57:32.743: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x886C old CALL_ACTIVE, new DISCONNECT_REQUEST *Jan 3 03:57:32.743: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64AE699C), ticks (1000), event (0x1240) *Jan 3 03:57:32.747: RADIUS: Received from id 1646/89 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:57:32.747: RADIUS: authenticator 39 B8 F2 A5 46 3C BC 8B - F2 69 5C 02 3D A9 B6 5C *Jan 3 03:57:32.747: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x642DB49C), ticks (30000), event (0x1250) *Jan 3 03:57:34.915: //49260/xxxxxxxxxxxx/CCAPI/cc_get_call_entry: callID (0xC06C) not found *Jan 3 03:57:34.947: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:57:34.947: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0 *Jan 3 03:57:34.947: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFCFA cr 0x7CF0 state 10 event 0x25 ces 1 *Jan 3 03:57:34.947: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7CF0 SUSPEND:U10_Suspend(nlcb) *Jan 3 03:57:34.947: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x64E08B7C), ticks (180000), event (0x1317) *Jan 3 03:57:34.947: //48332/3ABCBE0BA932/CCAPI/cc_api_call_suspend_ind: (vdbPtr=0x648EF15C, callID=0xBCCC *Jan 3 03:57:34.947: //48332/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(61=CC_EV_CALL_SUSPEND), cid(48332), disp(0) *Jan 3 03:57:34.947: //48332/3ABCBE0BA932/SSAPP:0:4454/ssaTraceSct: cid(48332)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_SUSPEND) oldst(SSA_CS_ACTIVE)cfid(4454)csize(0)in(0)fDest(0) *Jan 3 03:57:34.947: //48332/3ABCBE0BA932/SSAPP:0:4454/ssaTraceSct: -cid2(48329)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_CONFERENCING_ALERT) *Jan 3 03:57:34.947: //48329/3ABCBE0BA932/SSAPP:0:4454/ssaSuspend: *Jan 3 03:57:34.947: //48329/3ABCBE0BA932/CCAPI/ccCallSuspend: (callID=0xBCC9) *Jan 3 03:57:34.947: //48332/3ABCBE0BA932/CCAPI/ccCallSuspendAck: (callID=0xBCCC) *Jan 3 03:57:34.951: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0xFCFA, int id = 0x0 *Jan 3 03:57:34.951: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFCFA cr 0x7CF0 state 10 event 0xB0 ces 1 *Jan 3 03:57:34.951: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7CF0 CC_SUSPEND_ACK_REQ:U10_SuspendAckReq(nlcb) *Jan 3 03:57:37.975: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 10 *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC06A, mask=1 *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC06A, mask=1 *Jan 3 03:57:37.975: //49258/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[49258] *Jan 3 03:57:37.975: //49258/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49258], tagID[7], instID[-1] *Jan 3 03:57:37.975: //49258/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[49258] *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC06A, mask=2 *Jan 3 03:57:37.975: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote IP is 77.241.64.31 *Jan 3 03:57:37.975: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is FastEthernet0/0 *Jan 3 03:57:37.975: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of entry: 9, voip: 9, mmoip: 0 *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x641D4BB4, callID=0xC06A, disp=-11, tag=0x0) *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/cc_api_call_disconnect_done: cause=16,retry=0,vcCauseCode=0 *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/cc_delete_call_entry: Decrement call volume counter 10 *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/cc_delete_call_entry: current call volume: 9 *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/cc_delete_call_entry: entry's incoming TRUE. *Jan 3 03:57:37.975: //49258/821F3171AA43/CCAPI/cc_delete_call_entry: is_incoming is FALSE *Jan 3 03:57:37.979: //49258/821F3171AA43/CCAPI/cc_delete_call_entry: Deleting profileTable[0x6474082C] *Jan 3 03:57:37.979: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x6474082C) *Jan 3 03:57:37.979: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64F089BC] tagID[24] *Jan 3 03:57:37.979: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x65F00B4C] tagID[20] *Jan 3 03:57:37.979: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64E4A794] tagID[6] *Jan 3 03:57:37.979: RADIUS/ENCODE(000140DE):Orig. component type = VOICE *Jan 3 03:57:37.979: RADIUS(000140DE): Config NAS IP: 0.0.0.0 *Jan 3 03:57:37.979: RADIUS(000140DE): sending *Jan 3 03:57:37.979: //49258/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(49258), disp(-11) *Jan 3 03:57:37.979: //49258/821F3171AA43/SSAPP:210:-1/ssaTraceSct: cid(49258)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_CONF_DESTROYING)cfid(-1)csize(1)in(1)fDest(1) *Jan 3 03:57:37.979: //49258/821F3171AA43/SSAPP:210:-1/ssaDisconnectDone: *Jan 3 03:57:37.983: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:57:37.983: RADIUS(000140DE): Send Accounting-Request to 77.241.64.21:1813 id 1646/90, len 969 *Jan 3 03:57:37.983: RADIUS: authenticator FB 73 85 54 27 5F 20 35 - 80 9D 79 E8 71 AD 44 92 *Jan 3 03:57:37.983: RADIUS: Acct-Session-Id [44] 10 "0001FDDC" *Jan 3 03:57:37.983: RADIUS: Calling-Station-Id [31] 10 "63001000" *Jan 3 03:57:37.983: RADIUS: Called-Station-Id [30] 11 "064125290" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:57:37.983: RADIUS: Cisco AVpair [1] 59 "call-id=bc3491ec-1c47-e99d-baf3-7c9cc11d2625@77.241.64.31" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 21 *Jan 3 03:57:37.983: RADIUS: Cisco AVpair [1] 15 "iphop=count:2" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:37.983: RADIUS: Cisco AVpair [1] 25 "iphop=hop1:77.241.64.31" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:37.983: RADIUS: Cisco AVpair [1] 25 "iphop=hop2:77.241.64.31" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:37.983: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:55:53.299 UTC Mon Jan 3 2000" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:37.983: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:57:37.983: RADIUS: Conf-Id [24] 50 "h323-conf-id=821F3171 C0C811D3 AA43E9B4 7DB0F344" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:57:37.983: RADIUS: h323-call-origin [26] 25 "h323-call-origin=answer" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 27 *Jan 3 03:57:37.983: RADIUS: h323-call-type [27] 21 "h323-call-type=VoIP" *Jan 3 03:57:37.983: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:57:37.983: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=821F3171 C0C811D3 AA43E9B4 7DB0F344" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 26 *Jan 3 03:57:37.987: RADIUS: Cisco AVpair [1] 20 "subscriber=Unknown" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:57:37.987: RADIUS: Cisco AVpair [1] 24 "session-protocol=sipv2" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 42 *Jan 3 03:57:37.987: RADIUS: Cisco AVpair [1] 36 "gw-rxd-cdn=ton:0,npi:0,#:064125290" *Jan 3 03:57:37.987: RADIUS: Acct-Input-Octets [42] 6 674240 *Jan 3 03:57:37.987: RADIUS: Acct-Output-Octets [43] 6 193134 *Jan 3 03:57:37.987: RADIUS: Acct-Input-Packets [47] 6 4214 *Jan 3 03:57:37.987: RADIUS: Acct-Output-Packets [48] 6 1221 *Jan 3 03:57:37.987: RADIUS: Acct-Session-Time [46] 6 0 *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 58 *Jan 3 03:57:37.987: RADIUS: h323-connect-time [28] 52 "h323-connect-time=*03:57:37.975 UTC Mon Jan 3 2000" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 61 *Jan 3 03:57:37.987: RADIUS: h323-disconnect-tim[29] 55 "h323-disconnect-time=*03:57:37.975 UTC Mon Jan 3 2000" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:57:37.987: RADIUS: h323-disconnect-cau[30] 26 "h323-disconnect-cause=10" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 40 *Jan 3 03:57:37.987: RADIUS: h323-remote-address[23] 34 "h323-remote-address=77.241.64.31" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 24 *Jan 3 03:57:37.987: RADIUS: Cisco AVpair [1] 18 "release-source=2" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 28 *Jan 3 03:57:37.987: RADIUS: h323-voice-quality [31] 22 "h323-voice-quality=0" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 41 *Jan 3 03:57:37.987: RADIUS: Cisco AVpair [1] 35 "remote-media-address=77.241.64.31" *Jan 3 03:57:37.987: RADIUS: Vendor, Cisco [26] 51 *Jan 3 03:57:37.987: RADIUS: Cisco AVpair [1] 45 "gw-rxd-cgn=ton:0,npi:0,pi:0,si:1,#:63001000" *Jan 3 03:57:37.987: RADIUS: User-Name [1] 10 "63001000" *Jan 3 03:57:37.987: RADIUS: Acct-Authentic [45] 6 noval0 [0]*Jan 3 03:58:31.567: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x7EC7 old NULL_STATE, new CALL_INIT *Jan 3 03:58:31.571: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64AE699C), ticks (1000), event (0x1240) *Jan 3 03:58:31.571: RADIUS: Received from id 1646/142 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:58:31.571: RADIUS: authenticator 3C 92 2E 44 95 25 C2 44 - 68 CE A5 F0 FC 06 99 F9 *Jan 3 03:58:31.571: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x642DB49C), ticks (30000), event (0x1250) *Jan 3 03:58:33.655: h323chan_chn_process_read_socket *Jan 3 03:58:33.655: h323chan_chn_process_read_socket: fd=0 of type LISTENING has data *Jan 3 03:58:33.655: //-1/xxxxxxxxxxxx/CCAPI/cc_api_supported_data: data_mode=0x10000 *Jan 3 03:58:33.663: h323chan_chn_process_read_socket *Jan 3 03:58:33.663: h323chan_chn_process_read_socket: fd=9 of type ACCEPTED has data *Jan 3 03:58:33.663: h323chan_chn_process_read_socket: h323chan accepted/connected fd=9 *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x65F46CC4], magic[FACE0FFF] *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65F46CC4, tagID=6, dataSize=16, instID=-1,modifier=2 *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64EA7DEC], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[2] *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64EA7DEC] instID[-1] into container[0x65F46CC4] *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65F46CC4, tagID=5, dataSize=44, instID=-1,modifier=4 *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64EA7E4C], nxtElem[0x0], magic[0xFACE0FFF] tagID[5], dataLen[44], modif[4] *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64EA7E4C] instID[-1] into container[0x65F46CC4] *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65F46CC4, tagID=22, dataSize=12, instID=-1,modifier=4 *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64E96F20], nxtElem[0x0], magic[0xFACE0FFF] tagID[22], dataLen[12], modif[4] *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64E96F20] instID[-1] into container[0x65F46CC4] *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields: *Jan 3 03:58:33.667: cc_api_call_setup_ind: *Jan 3 03:58:33.667: cisco-username=213.232.84.48 *Jan 3 03:58:33.667: ----- ccCallInfo IE subfields ----- *Jan 3 03:58:33.667: cisco-ani= *Jan 3 03:58:33.667: cisco-anitype=0 *Jan 3 03:58:33.667: cisco-aniplan=0 *Jan 3 03:58:33.667: cisco-anipi=0 *Jan 3 03:58:33.667: cisco-anisi=0 *Jan 3 03:58:33.667: dest=2040#96227095853 *Jan 3 03:58:33.667: cisco-desttype=0 *Jan 3 03:58:33.667: cisco-destplan=0 *Jan 3 03:58:33.667: cisco-rdn= *Jan 3 03:58:33.667: cisco-rdntype=-1 *Jan 3 03:58:33.667: cisco-rdnplan=-1 *Jan 3 03:58:33.667: cisco-rdnpi=-1 *Jan 3 03:58:33.667: cisco-rdnsi=-1 *Jan 3 03:58:33.667: cisco-redirectreason=-1 *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x641CD818, callInfo={called=2040#96227095853,called_oct3=0x80,calling=,calling_oct3=0x0,calling_oct3a=0x0,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=0, prog_ind=0,callingIE_present 0, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x660F02A8) *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 1 *Jan 3 03:58:33.667: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x641CD818, callInfo={called=2040#96227095853, calling=, fdest=1 peer_tag=0}, callID=0x660F02A8) *Jan 3 03:58:33.667: //49333/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 11 *Jan 3 03:58:33.667: //49333/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 12 *Jan 3 03:58:33.667: //49333/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE. *Jan 3 03:58:33.667: //49333/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x65F656EC], numBuckets[11], numEntries[0] *Jan 3 03:58:33.671: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x65F656EC] with objects in container[0x65F46CC4] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65F656EC], tdObject[0x64EA7DEC] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[0] for the tag[5] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65F656EC], tdObject[0x64EA7E4C] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[0] for the tag[22] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65F656EC], tdObject[0x64E96F20] *Jan 3 03:58:33.671: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:58:33.671: ccTDUtilDumpAllElemInProfileTab: profileTable[0x65F656EC], numBuckets[11], numEntries[3] *Jan 3 03:58:33.671: Bucket { 0 } ------>0x64EA7E4C[0x64E96F20,t-5,l-44,d-0x64EA7E6C,m-4,u-187116,g-FACE0FFF]------>0x64E96F20[0x0,t-22,l-12,d-0x64E96F40,m-4,u-187116,g-FACE0FFF] *Jan 3 03:58:33.671: *Jan 3 03:58:33.671: Bucket { 5 } ------>0x64EA7DEC[0x0,t-6,l-16,d-0x64EA7E0C,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.671: *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x65F46CC4] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49333], tagID[32], instID[-1], data[0x660F0474], dataSize[4] *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64F600E0], nxtElem[0x0], magic[0xFACE0FFF] tagID[32], dataLen[4], modif[2] *Jan 3 03:58:33.671: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:58:33.671: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65F656EC], tdObject[0x64F600E0] *Jan 3 03:58:33.671: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:58:33.671: ccTDUtilDumpAllElemInProfileTab: profileTable[0x65F656EC], numBuckets[11], numEntries[4] *Jan 3 03:58:33.671: Bucket { 0 } ------>0x64EA7E4C[0x64E96F20,t-5,l-44,d-0x64EA7E6C,m-4,u-187116,g-FACE0FFF]------>0x64E96F20[0x0,t-22,l-12,d-0x64E96F40,m-4,u-187116,g-FACE0FFF] *Jan 3 03:58:33.671: *Jan 3 03:58:33.671: Bucket { 3 } ------>0x64F600E0[0x0,t-32,l-4,d-0x64F60100,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.671: *Jan 3 03:58:33.671: Bucket { 5 } ------>0x64EA7DEC[0x0,t-6,l-16,d-0x64EA7E0C,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.671: *Jan 3 03:58:33.675: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is 213.232.84.48 *Jan 3 03:58:33.675: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0 *Jan 3 03:58:33.675: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 12, voip: 12, mmoip: 0 *Jan 3 03:58:33.675: AAA/BIND(0001414C): Bind i/f *Jan 3 03:58:33.675: //49333/45F3A72F9CBB/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0B5, mask=2 *Jan 3 03:58:33.675: //49333/45F3A72F9CBB/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0xC0B5, mask=2 flag=1 *Jan 3 03:58:33.675: //49333/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[20] of callID[49333] *Jan 3 03:58:33.675: //49333/45F3A72F9CBB/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0xC0B5, mask=1 flag=1 *Jan 3 03:58:33.675: //49333/45F3A72F9CBB/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0B5, mask=1 *Jan 3 03:58:33.675: //49333/45F3A72F9CBB/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0B5, mask=1 *Jan 3 03:58:33.675: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49333], tagID[33], instID[-1], data[0x660F0CC0], dataSize[4] *Jan 3 03:58:33.675: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64657128], nxtElem[0x0], magic[0xFACE0FFF] tagID[33], dataLen[4], modif[2] *Jan 3 03:58:33.675: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:58:33.675: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65F656EC], tdObject[0x64657128] *Jan 3 03:58:33.675: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:58:33.675: ccTDUtilDumpAllElemInProfileTab: profileTable[0x65F656EC], numBuckets[11], numEntries[5] *Jan 3 03:58:33.675: Bucket { 0 } ------>0x64EA7E4C[0x64E96F20,t-5,l-44,d-0x64EA7E6C,m-4,u-187116,g-FACE0FFF]------>0x64E96F20[0x0,t-22,l-12,d-0x64E96F40,m-4,u-187116,g-FACE0FFF] *Jan 3 03:58:33.675: *Jan 3 03:58:33.675: Bucket { 3 } ------>0x64F600E0[0x0,t-32,l-4,d-0x64F60100,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.675: *Jan 3 03:58:33.675: Bucket { 5 } ------>0x64EA7DEC[0x0,t-6,l-16,d-0x64EA7E0C,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.679: *Jan 3 03:58:33.679: Bucket { 8 } ------>0x64657128[0x0,t-33,l-4,d-0x64657148,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.679: *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/CCAPI/cc_process_call_setup_ind: (event=0x645ABD08) *Jan 3 03:58:33.679: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [2040#96227095853], calling# [], consultid [] *Jan 3 03:58:33.679: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [2040#96227095853], calling# [], consultid [] *Jan 3 03:58:33.679: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 49333 with tag 0 to app "DEFAULT" *Jan 3 03:58:33.679: RADIUS/ENCODE(0001414C):Orig. component type = VOICE *Jan 3 03:58:33.679: RADIUS/ENCODE: Skip encoding 0 length AAA attribute clid *Jan 3 03:58:33.679: RADIUS(0001414C): Config NAS IP: 0.0.0.0 *Jan 3 03:58:33.679: RADIUS(0001414C): sending *Jan 3 03:58:33.679: //49333/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(49333), disp(0) *Jan 3 03:58:33.679: //49333/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(49333), disp(0) *Jan 3 03:58:33.679: //49333/xxxxxxxxxxxx/SSAPP:-1:-1/ssaCallSetupInd: *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/CCAPI/ccCallSetContext: (callID=0xC0B5, context=0x646AE104) *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaCallSetupInd: cid(49333), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1 *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaCallSetupInd: src route label=, tgt route label= tg_label_flag 0x0 *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaCallSetupInd: finalDest cllng(), clled(2040#96227095853) tgt_route_label()tg_label_flag 0x0 *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaCallSetupInd: cid(49333), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0 *Jan 3 03:58:33.679: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaDebugPeers: ssaSetupPeer cid(49333) peer list: tag(1) called number (2040#96227095853) tag(3) called number (2040#96227095853) tag(2) called number (2040#96227095853) tag(3009999) called number (2040#96227095853) *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaSetupPeer: dialpeer tags in rotary= 1 3 2 3009999 *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaSetupPeer: cid(49333), destPat(2040#96227095853), matched(0), prefix(), peer(64BA9B00), peer->encapType (1) *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallProceeding: (callID=0xC0B5, prog_ind=0x0) *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallSetupRequest: (Inbound call = 0xC0B5, outbound peer =1, dest=, params=0x645BFF08 mode=0, *callID=0x645C04D8, prog_ind = 0callingIE_present 0) *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallSetupRequest: *Jan 3 03:58:33.683: ccCallSetupRequest numbering_type 0x80 *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallSetupRequest: *Jan 3 03:58:33.683: ccCallSetupRequest: calling number is: *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallSetupRequest: calling oct3a is:0x0 *Jan 3 03:58:33.683: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:58:33.683: ccCheckClipClir: calling number is: "", calling oct3a is: 0x0 *Jan 3 03:58:33.683: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:58:33.683: Calling Party number is User Provided *Jan 3 03:58:33.683: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:58:33.683: Leaving ccCheckClipClir calling number is: "" calling oct3 is: 0x0 calling oct3a is: 0x0 *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallSetupRequest: after ccCheckClipClir - calling oct3a is:0x0 *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallSetupRequest: dest pattern .T, called 2040#96227095853, digit_strip 1 *Jan 3 03:58:33.683: //49333/45F3A72F9CBB/CCAPI/ccCallSetupRequest: *Jan 3 03:58:33.683: callingNumber=, calledNumber=2040#96227095853, redirectNumber= display_info= calling_oct3a=0 *Jan 3 03:58:33.683: accountNumber=213.232.84.48, finalDestFlag=1, guid=45f3.a72f.b81b.a742.9cbb.2a92.7398.d875 *Jan 3 03:58:33.683: peer_tag=1 *Jan 3 03:58:33.683: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields: *Jan 3 03:58:33.683: ccCallSetupRequest: *Jan 3 03:58:33.683: cisco-username=213.232.84.48 *Jan 3 03:58:33.683: ----- ccCallInfo IE subfields ----- *Jan 3 03:58:33.683: cisco-ani= *Jan 3 03:58:33.683: cisco-anitype=0 *Jan 3 03:58:33.683: cisco-aniplan=0 *Jan 3 03:58:33.683: cisco-anipi=0 *Jan 3 03:58:33.683: cisco-anisi=0 *Jan 3 03:58:33.683: dest=2040#96227095853 *Jan 3 03:58:33.683: cisco-desttype=0 *Jan 3 03:58:33.683: cisco-destplan=0 *Jan 3 03:58:33.683: cisco-rdn= *Jan 3 03:58:33.683: cisco-rdntype=-1 *Jan 3 03:58:33.683: cisco-rdnplan=-1 *Jan 3 03:58:33.683: cisco-rdnpi=-1 *Jan 3 03:58:33.683: cisco-rdnsi=-1 *Jan 3 03:58:33.683: cisco-redirectreason=-1 *Jan 3 03:58:33.683: //49333/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: (vdbPtr=0x645EA758, dest=, callParams={called=2040#96227095853,called_oct3=0x80, calling=,calling_oct3=0x0, calling_oct3a= 0x0, calling_xlated=false, subscriber_type_str=Unknown, fdest=1, voice_peer_tag=1},mode=0x0, appl_call_id=) *Jan 3 03:58:33.683: //49333/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: *Jan 3 03:58:33.683: ccIFCallSetupRequestPrivate: src route label tgt route label tg_label_flag 0x0 *Jan 3 03:58:33.683: //49333/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: vdbPtr type = 6 *Jan 3 03:58:33.683: //49333/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: *Jan 3 03:58:33.683: //49333/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: (vdbPtr=0x645EA758, dest=, callParams={called=2040#96227095853, called_oct3 0x80, calling=,calling_oct3 0x0, calling_oct3a 0x0, calling_xlated=false, fdest=1, voice_peer_tag=1}, mode=0x0, xltrc=-4) *Jan 3 03:58:33.687: //49333/xxxxxxxxxxxx/CCAPI/ccIFCallSetupRequestPrivate: *Jan 3 03:58:33.687: //49334/45F3A72F9CBB/CCAPI/cc_insert_call_entry: not incoming entry *Jan 3 03:58:33.687: //49334/45F3A72F9CBB/CCAPI/cc_insert_call_entry: entry's incoming FALSE. *Jan 3 03:58:33.687: //49334/45F3A72F9CBB/CCAPI/cc_insert_call_entry: is_incoming is FALSE *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/cc_set_voice_port_value: *Jan 3 03:58:33.687: CC_IF_TELEPHONY: echo =0, playout = 0 *Jan 3 03:58:33.687: //49333/45F3A72F9CBB/CCAPI/ccSaveDialpeerTag: (callID=0xC0B5, dialpeer_tag=1) *Jan 3 03:58:33.687: //49334/45F3A72F9CBB/CCAPI/ccCallSetContext: (callID=0xC0B6, context=0x64731AC0) *Jan 3 03:58:33.687: //49333/45F3A72F9CBB/CCAPI/ccCallReportDigits: (callID=0xC0B5, enable=0x0) *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x65F1C5FC], magic[FACE0FFF] *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x65F1C5FC, tagID=19, dataSize=4, instID=0,modifier=1 *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6473A608], nxtElem[0x0], magic[0xFACE0FFF] tagID[19], dataLen[4], modif[1] *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6473A608] instID[0] into container[0x65F1C5FC] *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructMultiInstHolderObject: multiHolder[0x6473A65C], nxtElem[0x0], magic[0xFACE0FFF], tagID[19], dataLen[0], modif[-1], numInst[0] *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtPushInstToMultiInstHolder: Successful in pushing instance object[0x6473A608] into holder[0x6473A65C] *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x646C3394], numBuckets[11], numEntries[0] *Jan 3 03:58:33.687: //49334/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x646C3394] with objects in container[0x65F1C5FC] *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[6] for the tag[19] *Jan 3 03:58:33.687: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x646C3394], tdObject[0x6473A65C] *Jan 3 03:58:33.687: //49334/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:58:33.687: ccTDUtilDumpAllElemInProfileTab: profileTable[0x646C3394], numBuckets[11], numEntries[1] *Jan 3 03:58:33.687: Bucket { 6 } ------>0x6473A65C[0x0,t-19,m-1,g-FACE0FFF 0x6473A608,i-0 ] *Jan 3 03:58:33.687: *Jan 3 03:58:33.691: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x65F1C5FC] *Jan 3 03:58:33.691: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:58:33.691: RADIUS(0001414C): Send Accounting-Request to 77.241.64.21:1813 id 1646/144, len 503 *Jan 3 03:58:33.691: RADIUS: authenticator 13 40 C0 F1 52 5D 0E B5 - 0F 9F 2B 38 E4 F0 8D 5F *Jan 3 03:58:33.691: RADIUS: Acct-Session-Id [44] 10 "0001FE85" *Jan 3 03:58:33.691: RADIUS: Called-Station-Id [30] 18 "2040#96227095853" *Jan 3 03:58:33.691: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:58:33.691: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:58:33.675 UTC Mon Jan 3 2000" *Jan 3 03:58:33.691: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:58:33.691: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:58:33.691: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:58:33.691: RADIUS: Conf-Id [24] 50 "h323-conf-id=45F3A72F B81BA742 9CBB2A92 7398D875" *Jan 3 03:58:33.691: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:58:33.691: RADIUS: h323-call-origin [26] 25 "h323-call-origin=answer" *Jan 3 03:58:33.691: RADIUS: Vendor, Cisco [26] 27 *Jan 3 03:58:33.691: RADIUS: h323-call-type [27] 21 "h323-call-type=VoIP" *Jan 3 03:58:33.691: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:58:33.691: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=45F3A72F B81BA742 9CBB2A92 7398D875" *Jan 3 03:58:33.691: RADIUS: Vendor, Cisco [26] 26 *Jan 3 03:58:33.695: RADIUS: Cisco AVpair [1] 20 "subscriber=Unknown" *Jan 3 03:58:33.695: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:58:33.695: RADIUS: Cisco AVpair [1] 24 "session-protocol=cisco" *Jan 3 03:58:33.695: RADIUS: Vendor, Cisco [26] 49 *Jan 3 03:58:33.695: RADIUS: Cisco AVpair [1] 43 "gw-rxd-cdn=ton:0,npi:0,#:2040#96227095853" *Jan 3 03:58:33.695: RADIUS: User-Name [1] 15 "213.232.84.48" *Jan 3 03:58:33.695: RADIUS: Ascend-Connection-Pr[196] 6 Call Up [10] *Jan 3 03:58:33.695: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:58:33.695: RADIUS: Cisco AVpair [1] 26 "connect-progress=Call Up" *Jan 3 03:58:33.695: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:58:33.695: RADIUS: Acct-Status-Type [40] 6 Start [1] *Jan 3 03:58:33.695: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:58:33.695: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:58:33.695: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:58:33.695: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif: expect factor = 10 *Jan 3 03:58:33.695: //49311/D0FED6CCAA53/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC09F, mask=1 *Jan 3 03:58:33.695: //49311/D0FED6CCAA53/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC09F, mask=1 *Jan 3 03:58:33.695: //49311/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[23] of callID[49311] *Jan 3 03:58:33.695: //49311/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49311], tagID[7], instID[-1] *Jan 3 03:58:33.695: //49311/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[7] of callID[49311] *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC09F, mask=2 *Jan 3 03:58:33.699: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: the remote IP is 77.241.64.30 *Jan 3 03:58:33.699: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: hwidb is FastEthernet0/0 *Jan 3 03:58:33.699: //-1/xxxxxxxxxxxx/CCAPI/cc_decr_if_call_volume: reduce callnum of entry: 11, voip: 11, mmoip: 0 *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/cc_api_call_disconnect_done: (vdbPtr=0x641D4BB4, callID=0xC09F, disp=-11, tag=0x0) *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/cc_api_call_disconnect_done: cause=16,retry=0,vcCauseCode=0 *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/cc_delete_call_entry: Decrement call volume counter 12 *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/cc_delete_call_entry: current call volume: 11 *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/cc_delete_call_entry: entry's incoming TRUE. *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/cc_delete_call_entry: is_incoming is FALSE *Jan 3 03:58:33.699: //49311/D0FED6CCAA53/CCAPI/cc_delete_call_entry: Deleting profileTable[0x64F6FBA4] *Jan 3 03:58:33.699: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDHashProfileTab: Destructor Profile Table (0x64F6FBA4) *Jan 3 03:58:33.699: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x6474E24C] tagID[20] *Jan 3 03:58:33.699: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64EB1B3C] tagID[6] *Jan 3 03:58:33.703: RADIUS: Received from id 1646/144 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:58:33.703: RADIUS: authenticator 14 A2 4A FF 7E 41 7C 0C - 8F F1 0C 13 F4 41 3B 9F *Jan 3 03:58:33.703: RADIUS/ENCODE(00014129):Orig. component type = VOICE *Jan 3 03:58:33.703: RADIUS(00014129): Config NAS IP: 0.0.0.0 *Jan 3 03:58:33.703: RADIUS(00014129): sending *Jan 3 03:58:33.703: //49311/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(49311), disp(-11) *Jan 3 03:58:33.703: //49311/D0FED6CCAA53/SSAPP:901:-1/ssaTraceSct: cid(49311)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE) oldst(SSA_CS_CONF_DESTROYING)cfid(-1)csize(1)in(1)fDest(1) *Jan 3 03:58:33.703: //49311/D0FED6CCAA53/SSAPP:901:-1/ssaDisconnectDone: *Jan 3 03:58:33.703: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:58:33.703: RADIUS(00014129): Send Accounting-Request to 77.241.64.21:1813 id 1646/145, len 971 *Jan 3 03:58:33.703: RADIUS: authenticator 45 4B 0E 2A 44 71 5D 9A - F0 15 6F 0B 3C 3E 2F C4 *Jan 3 03:58:33.707: RADIUS: Acct-Session-Id [44] 10 "0001FE51" *Jan 3 03:58:33.707: RADIUS: Calling-Station-Id [31] 10 "63000999" *Jan 3 03:58:33.707: RADIUS: Called-Station-Id [30] 12 "0777972733" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 59 "call-id=d7b76707-4699-58b9-db8c-aeee5f57c4f0@77.241.64.30" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 21 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 15 "iphop=count:2" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 25 "iphop=hop1:77.241.64.30" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 25 "iphop=hop2:77.241.64.30" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:58:33.707: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:58:05.627 UTC Mon Jan 3 2000" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:58:33.707: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:58:33.707: RADIUS: Conf-Id [24] 50 "h323-conf-id=D0FED6CC C0C811D3 AA53E9B4 7DB0F344" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:58:33.707: RADIUS: h323-call-origin [26] 25 "h323-call-origin=answer" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 27 *Jan 3 03:58:33.707: RADIUS: h323-call-type [27] 21 "h323-call-type=VoIP" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=D0FED6CC C0C811D3 AA53E9B4 7DB0F344" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 26 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 20 "subscriber=Unknown" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 24 "session-protocol=sipv2" *Jan 3 03:58:33.707: RADIUS: Vendor, Cisco [26] 43 *Jan 3 03:58:33.707: RADIUS: Cisco AVpair [1] 37 "gw-rxd-cdn=ton:0,npi:0,#:0777972733" *Jan 3 03:58:33.711: RADIUS: Acct-Input-Octets [42] 6 160 *Jan 3 03:58:33.711: RADIUS: Acct-Output-Octets [43] 6 23041 *Jan 3 03:58:33.711: RADIUS: Acct-Input-Packets [47] 6 1 *Jan 3 03:58:33.711: RADIUS: Acct-Output-Packets [48] 6 145 *Jan 3 03:58:33.711: RADIUS: Acct-Session-Time [46] 6 0 *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 58 *Jan 3 03:58:33.711: RADIUS: h323-connect-time [28] 52 "h323-connect-time=*03:58:33.695 UTC Mon Jan 3 2000" *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 61 *Jan 3 03:58:33.711: RADIUS: h323-disconnect-tim[29] 55 "h323-disconnect-time=*03:58:33.695 UTC Mon Jan 3 2000" *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:58:33.711: RADIUS: h323-disconnect-cau[30] 26 "h323-disconnect-cause=10" *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 40 *Jan 3 03:58:33.711: RADIUS: h323-remote-address[23] 34 "h323-remote-address=77.241.64.30" *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 24 *Jan 3 03:58:33.711: RADIUS: Cisco AVpair [1] 18 "release-source=2" *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 28 *Jan 3 03:58:33.711: RADIUS: h323-voice-quality [31] 22 "h323-voice-quality=0" *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 41 *Jan 3 03:58:33.711: RADIUS: Cisco AVpair [1] 35 "remote-media-address=77.241.64.30" *Jan 3 03:58:33.711: RADIUS: Vendor, Cisco [26] 51 *Jan 3 03:58:33.711: RADIUS: Cisco AVpair [1] 45 "gw-rxd-cgn=ton:0,npi:0,pi:0,si:1,#:63000999" *Jan 3 03:58:33.711: RADIUS: User-Name [1] 10 "63000999" *Jan 3 03:58:33.711: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:58:33.711: RADIUS: Acct-Status-Type [40] 6 Stop [2] *Jan 3 03:58:33.711: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:58:33.711: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:58:33.711: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: override plan/type, comparing 2040#96227095853/., (regexp) *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: overriding plan/type for 2040#96227095853, unknown/unknown to isdn/network *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: override plan/type, comparing 2040#96227095853/., (regexp) *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: isdn_parser_map_lkup: overriding plan/type for 2040#96227095853, unknown/unknown to isdn/network *Jan 3 03:58:33.715: AAA/BIND(0001414D): Bind i/f Serial7/0:2 *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0xFED2, int id = 0x0 *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: L3_Go: call_id 0xFED2 cr 0x0 state 0 event 0x80 ces 1 *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: L3_ProcessEvent: callref = 0x0000 CC_SETUP_REQ:U0_SetupReq(nlcb) *Jan 3 03:58:33.715: ISDN Se7/0:15 SC Q931d: L3_TxSarMsg: InfoLen = 94 octets 0x08027EC80504038090A31804E1808383 *Jan 3 03:58:33.715: 1C399E0100036774640000002E49414D *Jan 3 03:58:33.715: 2C0D0A4743492C343566336137326662 *Jan 3 03:58:33.715: 38316261373432396362623261393237 *Jan 3 03:58:33.719: 333938643837350D0A0D0A7011B13230 *Jan 3 03:58:33.719: 3430233936323237303935383533 *Jan 3 03:58:33.719: ISDN Se7/0:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x644659C8 datasize = 110 *Jan 3 03:58:33.719: ISDN Se7/0:15 SC Q931d: data = 0x644659C8000003000240043F00010800 *Jan 3 03:58:33.719: 08027EC80504038090A31804E1808383 *Jan 3 03:58:33.719: 1C399E0100036774640000002E49414D *Jan 3 03:58:33.719: 2C0D0A4743492C343566336137326662 *Jan 3 03:58:33.719: 38316261373432396362623261393237 *Jan 3 03:58:33.719: 333938643837350D0A0D0A7011B13230 *Jan 3 03:58:33.719: 3430233936323237303935383533 *Jan 3 03:58:33.719: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64E51B28), ticks (4000), event (0x1313) *Jan 3 03:58:33.719: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x7EC8 old NULL_STATE, new CALL_INIT *Jan 3 03:58:33.719: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64AE699C), ticks (1000), event (0x1240) *Jan 3 03:58:33.719: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49333], tagID[33], instID[-1], data[0x660F0CC0], dataSize[4] *Jan 3 03:58:33.719: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64EA5D74], nxtElem[0x0], magic[0xFACE0FFF] tagID[33], dataLen[4], modif[2] *Jan 3 03:58:33.719: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:58:33.719: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65F656EC], tdObject[0x64EA5D74] *Jan 3 03:58:33.719: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64657128] tagID[33] *Jan 3 03:58:33.719: //49333/45F3A72F9CBB/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:58:33.723: ccTDUtilDumpAllElemInProfileTab: profileTable[0x65F656EC], numBuckets[11], numEntries[5] *Jan 3 03:58:33.723: Bucket { 0 } ------>0x64EA7E4C[0x64E96F20,t-5,l-44,d-0x64EA7E6C,m-4,u-187116,g-FACE0FFF]------>0x64E96F20[0x0,t-22,l-12,d-0x64E96F40,m-4,u-187116,g-FACE0FFF] *Jan 3 03:58:33.723: *Jan 3 03:58:33.723: Bucket { 3 } ------>0x64F600E0[0x0,t-32,l-4,d-0x64F60100,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.723: *Jan 3 03:58:33.723: Bucket { 5 } ------>0x64EA7DEC[0x0,t-6,l-16,d-0x64EA7E0C,m-1,u-187116,g-FACE0FFF] *Jan 3 03:58:33.723: *Jan 3 03:58:33.723: Bucket { 8 } ------>0x64EA5D74[0x0,t-33,l-4,d-0x64EA5D94,m-2,u-187116,g-FACE0FFF] *Jan 3 03:58:33.723: *Jan 3 03:58:33.723: //49333/45F3A72F9CBB/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x641CD818, callID=0xC0B5, disp=0) *Jan 3 03:58:33.723: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaDebugPeers: ssaReportDigitsDone cid(49333) peer list: tag(3) called number (2040#96227095853) tag(2) called number (2040#96227095853) tag(3009999) called number (2040#96227095853) *Jan 3 03:58:33.723: //49333/45F3A72F9CBB/SSAPP:0:-1/ssaReportDigitsDone: callid=49333 Reporting disabled. *Jan 3 03:58:33.727: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x642DB49C), ticks (30000), event (0x1250) *Jan 3 03:58:36.187: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x642DB49C), ticks (30000), event (0x1250) *Jan 3 03:58:36.187: ISDN Se7/0:15 SC Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0 *Jan 3 03:58:36.187: ISDN Se7/0:15 SC Q931d: L3_Go: call_id 0xFED1 cr 0x7EC7 state 3 event 0x1 ces 1 *Jan 3 03:58:36.187: ISDN Se7/0:15 SC Q931d: L3_ProcessEvent: callref = 0x7EC7 ALERTING:U3_Alerting(nlcb) *Jan 3 03:58:36.187: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x7EC7 old OUT_CALL_PROC, new CALL_DELIVERED *Jan 3 03:58:36.187: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x64EAAA4C], magic[FACE0FFF] *Jan 3 03:58:36.187: //49332/E070166BAA5A/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:58:36.187: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x64FA285C] with objects in container[0x64EAAA4C] *Jan 3 03:58:36.187: //49332/E070166BAA5A/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:58:36.187: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64FA285C], numBuckets[11], numEntries[1] *Jan 3 03:58:36.187: Bucket { 6 } ------>0x64F71660[0x0,t-19,m-1,g-FACE0FFF 0x64FAE670,i-0 ] *Jan 3 03:58:36.187: *Jan 3 03:58:36.187: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x64EAAA4C] *Jan 3 03:58:36.187: //49332/E070166BAA5A/CCAPI/cc_api_call_alert: (vdbPtr=0x645EA758, callID=0xC0B4, prog_ind=0x8, sig_ind=0x1, rawmsgPtr=0x645AECD0) *Jan 3 03:58:36.187: //49332/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(7=CC_EV_CALL_ALERT), cid(49332), disp(0) *Jan 3 03:58:36.187: //49332/E070166BAA5A/SSAPP:0:-1/ssaTraceSct: cid(49332)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_ALERT) oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0) *Jan 3 03:58:36.187: //49332/E070166BAA5A/SSAPP:0:-1/ssaTraceSct: -cid2(49331)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING) *Jan 3 03:58:36.187: //49331/E070166BAA5A/SSAPP:901:-1/ssaAlert: *Jan 3 03:58:36.187: //49331/E070166BAA5A/CCAPI/ccGetDialpeerTag: (callID=0xC0B3) *Jan 3 03:58:36.191: //49331/E070166BAA5A/CCAPI/ccCallAlert: (callID=0xC0B3, prog_ind=0x8, sig_ind=0x1) *Jan 3 03:58:36.191: //49331/E070166BAA5A/CCAPI/ccGetDialpeerTag: (callID=0xC0B3) *Jan 3 03:58:36.191: //49331/E070166BAA5A/CCAPI/ccConferenceCreate: (confID=0x645C05EC, callID1=0xC0B3, callID2=0xC0B4, tag=0x0) *Jan 3 03:58:36.191: //49331/E070166BAA5A/CCAPI/ccConferenceCreate: (pConf=0x64E54EA0) *Jan 3 03:58:36.191: //49331/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: (confID=0x11A2, srcIF=0x641D4BB4, srcCallID=0xC0B3, dstCallID=0xC0B4, disposition=0, tag=0x0) *Jan 3 03:58:36.191: //49332/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: (confID=0x11A2, srcIF=0x645EA758, srcCallID=0xC0B4, dstCallID=0xC0B3, disposition=0, tag=0x0) *Jan 3 03:58:36.191: //49332/E070166BAA5A/CCAPI/cc_api_caps_ind: (dstVdbPtr=0x641D4BB4, dstCallId=0xC0B3, srcCallId=0xC0B4, caps={codec=0x0, fax_rate=0x0, vad=0x0, modem=0x2 codec_bytes=0, signal_type=0}) *Jan 3 03:58:36.191: //49332/E070166BAA5A/CCAPI/cc_api_caps_ind: (Playout: mode 1, initial 60, min 40, max 200, fax 300) *Jan 3 03:58:36.191: //49331/E070166BAA5A/CCAPI/cc_api_caps_ind: (dstVdbPtr=0x645EA758, dstCallId=0xC0B4, srcCallId=0xC0B3, caps={codec=0x1, fax_rate=0x2, vad=0x2, modem=0x0 codec_bytes=160, signal_type=1}) *Jan 3 03:58:36.191: //49331/E070166BAA5A/CCAPI/cc_api_caps_ind: (Playout: mode 1, initial 60, min 40, max 250, fax 300) *Jan 3 03:58:36.191: //49331/E070166BAA5A/CCAPI/cc_api_caps_ack: (dstVdbPtr=0x645EA758, dstCallId=0xC0B4, srcCallId=0xC0B3, caps={codec=0x1, fax_rate=0x2, vad=0x2, modem=0x0 codec_bytes=160, signal_type=1, seq_num_start=5942}) *Jan 3 03:58:36.191: //-1/xxxxxxxxxxxx/CCAPI/cc_process_notify_bridge_done: (event=0x645ACC18) *Jan 3 03:58:36.191: //49332/E070166BAA5A/CCAPI/cc_api_caps_ack: (dstVdbPtr=0x641D4BB4, dstCallId=0xC0B3, srcCallId=0xC0B4, caps={codec=0x1, fax_rate=0x2, vad=0x2, modem=0x0 codec_bytes=160, signal_type=1, seq_num_start=5942}) *Jan 3 03:58:36.195: //49331/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(29=CC_EV_CONF_CREATE_DONE), cid(49331), disp(0) *Jan 3 03:58:36.195: //49331/E070166BAA5A/SSAPP:901:4514/ssaTraceSct: cid(49331)st(SSA_CS_CONFERENCING_ALERT)ev(SSA_EV_CONF_CREATE_DONE) oldst(SSA_CS_CALL_SETTING)cfid(4514)csize(0)in(1)fDest(1) *Jan 3 03:58:36.195: //49331/E070166BAA5A/SSAPP:901:4514/ssaTraceSct: -cid2(49332)st2(SSA_CS_CONFERENCING_ALERT)oldst2(SSA_CS_CALL_SETTING) *Jan 3 03:58:36.195: //49332/E070166BAA5A/SSAPP:0:4514/ssaConfCreateDoneAlert: *Jan 3 03:58:36.195: //49332/E070166BAA5A/SSAPP:0:4514/ssaConfCreateDoneAlert: Other call leg not found VoiceGateway# *Jan 3 03:59:16.595: //-1//TCL2:/TclFreeUnusedInterp: *Jan 3 03:59:16.595: //-1//TCL2:/TclFreeUnusedInterp: Deleting pInterp *Jan 3 03:59:16.595: //-1//TCL2:/DeleteInterp: Destroyed *Jan 3 03:59:18.195: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x642DB49C), ticks (30000), event (0x1250) *Jan 3 03:59:18.195: ISDN Se7/0:15 SC Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0 *Jan 3 03:59:18.195: ISDN Se7/0:15 SC Q931d: L3_Go: call_id 0x35D cr 0x886E state 0 event 0x5 ces 1 *Jan 3 03:59:18.195: ISDN Se7/0:15 SC Q931d: L3_ProcessEvent: callref = 0x886E SETUP:U0_Setup(nlcb) *Jan 3 03:59:18.195: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x886E old NULL_STATE, new CALL_PRESENT *Jan 3 03:59:18.199: AAA/BIND(00014158): Bind i/f Serial7/0:22 *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x64F56594], magic[FACE0FFF] *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x64F56594, tagID=6, dataSize=16, instID=-1,modifier=1 *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64F07524], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[1] *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64F07524] instID[-1] into container[0x64F56594] *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x64F56594, tagID=19, dataSize=4, instID=0,modifier=1 *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64F56A38], nxtElem[0x0], magic[0xFACE0FFF] tagID[19], dataLen[4], modif[1] *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64F56A38] instID[0] into container[0x64F56594] *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructMultiInstHolderObject: multiHolder[0x64E75F30], nxtElem[0x0], magic[0xFACE0FFF], tagID[19], dataLen[0], modif[-1], numInst[0] *Jan 3 03:59:18.199: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtPushInstToMultiInstHolder: Successful in pushing instance object[0x64F56A38] into holder[0x64E75F30] *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields: *Jan 3 03:59:18.203: cc_api_call_setup_ind: *Jan 3 03:59:18.203: cisco-username= *Jan 3 03:59:18.203: ----- ccCallInfo IE subfields ----- *Jan 3 03:59:18.203: cisco-ani=65518709 *Jan 3 03:59:18.203: cisco-anitype=2 *Jan 3 03:59:18.203: cisco-aniplan=1 *Jan 3 03:59:18.203: cisco-anipi=0 *Jan 3 03:59:18.203: cisco-anisi=3 *Jan 3 03:59:18.203: dest=63009999 *Jan 3 03:59:18.203: cisco-desttype=4 *Jan 3 03:59:18.203: cisco-destplan=1 *Jan 3 03:59:18.203: cisco-rdn= *Jan 3 03:59:18.203: cisco-rdntype=-1 *Jan 3 03:59:18.203: cisco-rdnplan=-1 *Jan 3 03:59:18.203: cisco-rdnpi=-1 *Jan 3 03:59:18.203: cisco-rdnsi=-1 *Jan 3 03:59:18.203: cisco-redirectreason=-1 *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x645EA758, callInfo={called=63009999,called_oct3=0xC1,calling=65518709,calling_oct3=0x21,calling_oct3a=0x83,calling_xlated=false,subscriber_type_str=RegularLine,fdest=1,peer_tag=99, prog_ind=3,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x64E2E074) *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 4 , prot 0 *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:59:18.203: ccCheckClipClir: calling number is: "65518709", calling oct3a is: 0x83 *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:59:18.203: Calling Party number is Network Provided *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:59:18.203: Leaving ccCheckClipClir calling number is: "65518709" calling oct3 is: 0x21 calling oct3a is: 0x83 *Jan 3 03:59:18.203: //49340/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 9 *Jan 3 03:59:18.203: //49340/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 10 *Jan 3 03:59:18.203: //49340/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE. *Jan 3 03:59:18.203: //49340/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is TRUE *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x64F507CC], numBuckets[11], numEntries[0] *Jan 3 03:59:18.203: //49340/B0263EDD8D75/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x64F507CC] with objects in container[0x64F56594] *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6] *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64F507CC], tdObject[0x64F07524] *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[6] for the tag[19] *Jan 3 03:59:18.203: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64F507CC], tdObject[0x64E75F30] *Jan 3 03:59:18.203: //49340/B0263EDD8D75/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:59:18.203: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64F507CC], numBuckets[11], numEntries[2] *Jan 3 03:59:18.203: Bucket { 5 } ------>0x64F07524[0x0,t-6,l-16,d-0x64F07544,m-1,u-187161,g-FACE0FFF] *Jan 3 03:59:18.207: *Jan 3 03:59:18.207: Bucket { 6 } ------>0x64E75F30[0x0,t-19,m-1,g-FACE0FFF 0x64F56A38,i-0 ] *Jan 3 03:59:18.207: *Jan 3 03:59:18.207: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x64F56594] *Jan 3 03:59:18.207: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: not the VoIP or MMoIP *Jan 3 03:59:18.207: AAA: parse name=Serial7/0:23 idb type=-1 tty=-1 *Jan 3 03:59:18.207: AAA: name=Serial7/0:23 flags=0x55 type=1 shelf=0 slot=7 adapter=0 port=0 channel=23 *Jan 3 03:59:18.207: AAA/BIND(00014159): Bind i/f *Jan 3 03:59:18.207: //49340/B0263EDD8D75/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0BC, mask=2 *Jan 3 03:59:18.207: //49340/B0263EDD8D75/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0xC0BC, mask=2 flag=1 *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByRef: No tdObject found in profileTable for tagID[20] of callID[49340] *Jan 3 03:59:18.207: //49340/B0263EDD8D75/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0xC0BC, mask=1 flag=1 *Jan 3 03:59:18.207: //49340/B0263EDD8D75/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0BC, mask=1 *Jan 3 03:59:18.207: //49340/B0263EDD8D75/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0BC, mask=1 *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49340], tagID[11], instID[-1] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[11] of callID[49340] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49340], tagID[12], instID[-1] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[12] of callID[49340] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49340], tagID[9], instID[-1] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[9] of callID[49340] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49340], tagID[10], instID[-1] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[10] of callID[49340] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49340], tagID[16], instID[-1] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[16] of callID[49340] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49340], tagID[14], instID[-1] *Jan 3 03:59:18.207: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[14] of callID[49340] *Jan 3 03:59:18.211: //49340/B0263EDD8D75/CCAPI/cc_process_call_setup_ind: (event=0x645ABD08) *Jan 3 03:59:18.211: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [63009999], calling# [65518709], consultid [] *Jan 3 03:59:18.211: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [63009999], calling# [65518709], consultid [] *Jan 3 03:59:18.211: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node *Jan 3 03:59:18.211: //-1//TCL2:/TclGetInterp: Script IVR *Jan 3 03:59:18.211: //49340/B0263EDD8D75/CCAPI/ccCallSetContext: (callID=0xC0BC, context=0x65042A24) *Jan 3 03:59:18.211: //49340/B0263EDD8D75/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 49340 with tag 99 to app "IVR" *Jan 3 03:59:18.211: RADIUS/ENCODE(00014159):Orig. component type = VOICE *Jan 3 03:59:18.211: RADIUS(00014159): Storing nasport 23 in rad_db *Jan 3 03:59:18.211: RADIUS(00014159): Config NAS IP: 0.0.0.0 *Jan 3 03:59:18.211: RADIUS(00014159): sending *Jan 3 03:59:18.211: //49340//TCL2:/TclInterpHandler: Tcl handler 0x64DBE928 processing CC_EV_CALL_SETUP_IND *Jan 3 03:59:18.215: //49340//TCL2:/TclInterpDriver: *Jan 3 03:59:18.215: //49340//TCL2:/TclEventPreprocessing: *Jan 3 03:59:18.215: //-1//TCL2:/TclIncomingCallBlock: *Jan 3 03:59:18.215: //49340//TCL2:/MakeTransition: FSM Transtion: ([1 ]CALL_INIT,[28]ev_setup_indication)---([10]act_Setup)--->([7 ]AUTHENTICATE) *Jan 3 03:59:18.215: //49340/B0263EDD8D75/CCAPI/ccCallSetupAck: (callID=0xC0BC) *Jan 3 03:59:18.215: //49340/B0263EDD8D75/CCAPI/cc_api_set_transfer_info: (transfer= 0, callID=0xC0BC) *Jan 3 03:59:18.215: //49340/B0263EDD8D75/CCAPI/cc_api_set_transfer_info: call transfer reset) *Jan 3 03:59:18.215: //49340/B0263EDD8D75/CCAPI/ccCallProceeding: (callID=0xC0BC, prog_ind=0x0) *Jan 3 03:59:18.215: //49340/B0263EDD8D75/CCAPI/ccCallConnect: (callID=0xC0BC), prog_ind=0, cData->bitmask=0x0 *Jan 3 03:59:18.215: //49340/B0263EDD8D75/CCAPI/ccCallConnect: setting callEntry->connected to TRUE *Jan 3 03:59:18.215: AAA/BIND(0001415A): Bind i/f *Jan 3 03:59:18.215: AAA/AUTHEN/LOGIN (0001415A): Pick method list 'h323' *Jan 3 03:59:18.215: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[49340], tagID[15], instID[-1] *Jan 3 03:59:18.215: //49340/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[15] of callID[49340] *Jan 3 03:59:18.215: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:59:18.215: RADIUS(00014159): Send Accounting-Request to 77.241.64.21:1813 id 1646/161, len 503 *Jan 3 03:59:18.215: RADIUS: authenticator 44 BD DA D7 D0 09 6F 46 - 22 3C 4D 76 55 BA 1E FB *Jan 3 03:59:18.219: RADIUS: Acct-Session-Id [44] 10 "0001FE9C" *Jan 3 03:59:18.219: RADIUS: Calling-Station-Id [31] 10 "65518709" *Jan 3 03:59:18.219: RADIUS: Called-Station-Id [30] 10 "63009999" *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:59:18.219: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:59:18.207 UTC Mon Jan 3 2000" *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:59:18.219: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:59:18.219: RADIUS: Conf-Id [24] 50 "h323-conf-id=B0263EDD 1C3B11DD 8D750014 4F614A9E" *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:59:18.219: RADIUS: h323-call-origin [26] 25 "h323-call-origin=answer" *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:59:18.219: RADIUS: h323-call-type [27] 26 "h323-call-type=Telephony" *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:59:18.219: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=B0263EDD 1C3B11DD 8D750014 4F614A9E" *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:59:18.219: RADIUS: Cisco AVpair [1] 24 "subscriber=RegularLine" *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 41 *Jan 3 03:59:18.219: RADIUS: Cisco AVpair [1] 35 "gw-rxd-cdn=ton:4,npi:1,#:63009999" *Jan 3 03:59:18.219: RADIUS: User-Name [1] 10 "65518709" *Jan 3 03:59:18.219: RADIUS: Ascend-Connection-Pr[196] 6 Call Up [10] *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:59:18.219: RADIUS: Cisco AVpair [1] 26 "connect-progress=Call Up" *Jan 3 03:59:18.219: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:59:18.219: RADIUS: Acct-Status-Type [40] 6 Start [1] *Jan 3 03:59:18.219: RADIUS: NAS-Port-Type [61] 6 Async [0] *Jan 3 03:59:18.219: RADIUS: Vendor, Cisco [26] 20 *Jan 3 03:59:18.223: RADIUS: cisco-nas-port [2] 14 "Serial7/0:23" *Jan 3 03:59:18.223: RADIUS: NAS-Port [5] 6 23 *Jan 3 03:59:18.223: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:59:18.223: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:59:18.223: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:59:18.223: RADIUS/ENCODE(0001415A):Orig. component type = VOICE *Jan 3 03:59:18.223: RADIUS(0001415A): Config NAS IP: 0.0.0.0 *Jan 3 03:59:18.223: RADIUS/ENCODE(0001415A): acct_session_id: 130717 *Jan 3 03:59:18.223: RADIUS(0001415A): sending *Jan 3 03:59:18.223: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:59:18.223: RADIUS: Received from id 1646/161 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:59:18.223: RADIUS: authenticator 3A 84 63 F1 CA AC B8 74 - F2 CC 64 E8 77 0D D8 B8 *Jan 3 03:59:18.223: ISDN Se7/0:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0x35D, int id = 0x0 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_Go: call_id 0x35D cr 0x886E state 6 event 0x88 ces 1 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_ProcessEvent: callref = 0x886E CC_CALL_PROC_REQ:U6_CallProcReq(nlcb) *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_TxSarMsg: InfoLen = 11 octets 0x0802886E021804E9808397 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x644659C8 datasize = 27 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: data = 0x644659C8000003000240049400010806 *Jan 3 03:59:18.227: 0802886E021804E9808397 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x886E old CALL_PRESENT, new IN_CALL_PROC *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0x35D, int id = 0x0 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_Go: call_id 0x35D cr 0x886E state 9 event 0x81 ces 1 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_ProcessEvent: callref = 0x886E CC_SETUP_RESP:U9_SetupResp(nlcb) *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_TxSarMsg: InfoLen = 5 octets 0x0802886E07 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x644659C8 datasize = 21 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: data = 0x644659C8000003000240049400010806 *Jan 3 03:59:18.227: 0802886E07 *Jan 3 03:59:18.227: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64E523C0), ticks (4000), event (0x131C) *Jan 3 03:59:18.227: ISDN Se7/0:15 SC Q931d: L3_state_change: callref 0x886E old IN_CALL_PROC, new CONNECT_REQUEST *Jan 3 03:59:18.227: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64AE699C), ticks (1000), event (0x1240) *Jan 3 03:59:18.227: RADIUS(0001415A): Send Access-Request to 77.241.64.21:1812 id 1645/227, len 98 *Jan 3 03:59:18.227: RADIUS: authenticator D6 8B 78 1C 99 11 19 C9 - 23 46 7A E8 AE F4 6D 5B *Jan 3 03:59:18.227: RADIUS: User-Name [1] 10 "65518709" *Jan 3 03:59:18.231: RADIUS: User-Password [2] 18 * *Jan 3 03:59:18.231: RADIUS: Vendor, Cisco [26] 38 *Jan 3 03:59:18.231: RADIUS: Cisco AVpair [1] 32 "h323-ivr-out=transactionID:482" *Jan 3 03:59:18.231: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:59:18.231: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:59:18.231: RADIUS: Received from id 1645/227 77.241.64.21:1812, Access-Reject, len 46 *Jan 3 03:59:18.231: RADIUS: authenticator 75 1A 63 B6 D7 B9 D3 EA - 1A 7A 52 60 C2 FC B4 D8 *Jan 3 03:59:18.231: RADIUS: Vendor, Cisco [26] 26 *Jan 3 03:59:18.231: RADIUS: h323-return-code [103] 20 "h323-return-code=1" *Jan 3 03:59:18.231: RADIUS(0001415A): Received from id 1645/227 *Jan 3 03:59:18.231: //-1//TCL2:HN0B27DA58:/TclInterpHandler: Tcl handler 0x64DBE928 processing APP_EV_AUTHENTICATE_DONE *Jan 3 03:59:18.231: //-1//TCL2:HN0B27DA58:/TclInterpDriver: *Jan 3 03:59:18.231: //-1//TCL2:HN0B27DA58:/TclEventPreprocessing: *Jan 3 03:59:18.231: //-1//TCL2:HN0B27DA58:/TclEventPreprocessing: *Jan 3 03:59:18.231: TclEventPreprocessing: app_event->e.evAuthenticateDone.status = 2 *Jan 3 03:59:18.231: //-1//TCL2:HN0B27DA58:/TclEventPreprocessing: *Jan 3 03:59:18.231: TclEventPreprocessing: lastFailureCause = 57 *Jan 3 03:59:18.231: //-1//TCL2:HN0B27DA58:/MakeTransition: FSM Transtion: ([7 ]AUTHENTICATE,[85]ev_authenticate_done)---([7 ]act_Authenticated)--->([9 ]GETDEST) *Jan 3 03:59:18.235: //49340/B0263EDD8D75/CCAPI/ccSetDigitTimeouts: (callID=0xC0BC), initial=-1000, inter=-1000 *Jan 3 03:59:18.235: //49340/B0263EDD8D75/CCAPI/ccCallReportDigits: (callID=0xC0BC, enable=0x1) *Jan 3 03:59:18.235: //49340/B0263EDD8D75/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x645EA758, callID=0xC0BC, disp=0) *Jan 3 03:59:18.235: //-1//DPM :DP537:/pc_mc_addToDynamicS: (1) flash:enter_card_number.au *Jan 3 03:59:18.235: //-1//MCM :/mc_createFromFileUrl: Getting a media content: name=enter_card_number.au url=flash:enter_card_number.au load fast, load in background, fetchtimeout=-1 maxage=-1, maxstale=-1 *Jan 3 03:59:18.235: //49340//MSW :/msw_create: cbf=0x6133F27C *Jan 3 03:59:18.235: //-1//MSM :MS870:/ms_create: Iniz ply_timer *Jan 3 03:59:18.235: //49340//MSW :/msw_synth_open: mediaStream 0x64FE67C4 created *Jan 3 03:59:18.235: //49340//MSW :/msw_synth_open: rtspStream 0x641E53E8 created, status=RTSP_STATUS_SUCCESS, session_id=0x341 (833) *Jan 3 03:59:18.235: //-1//MSM :MS871:/ms_create: Iniz ply_timer *Jan 3 03:59:18.235: //49340//MSW :/msw_recrd_open: mediaStream 0x65F124BC created *Jan 3 03:59:18.235: //49340//MSW :/msw_recrd_open: rtspStream 0x641E53F0 created, status=RTSP_STATUS_SUCCESS, session_id=0x342 (834) *Jan 3 03:59:18.235: //-1//MSW :/msw_set_backup_server: server[0] = '', genericStream=0x641E4AFC *Jan 3 03:59:18.235: //-1//MSW :/msw_set_backup_server: server[1] = '', genericStream=0x641E4AFC *Jan 3 03:59:18.235: //-1//MSW :/msw_associate_call: callID=0xC0BC(49340), genericStream=0x641E4AFC *Jan 3 03:59:18.235: //49340//MSW :/msw_get_stream_state: genericStream 0x641E4AFC is in state MSW_S_IDLE *Jan 3 03:59:18.235: //49340//MSW :/msw_synth_start: current_stream_id=1, content 0x0, dynamicS 0x65F54694, current_state=MSW_S_IDLE *Jan 3 03:59:18.235: //49340//MSW :/msu_synth_partial_play: Media Stream URL *Jan 3 03:59:18.235: //49340/B0263EDD8D75/CCAPI/ccAssociateStream: (callID=0xC0BC coder=5 dtmf-relay=4 vad=0 recordFunc=0x0 evQ=0x626C99B4, streamContext=0x64FE67C4, recordContext=0x0) *Jan 3 03:59:18.239: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x64AE699C), ticks (1000), event (0x1240) *Jan 3 03:59:18.239: ISDN Se7/0:15 SC LIFd: LIF_StartTimer: timer (0x642DB49C), ticks (30000), event (0x1250) *Jan 3 03:59:22.123: //49340//MSM :/ms_stop_play: mgdTstop at 2d03h (cause MS_STOP_COMPLETE) *Jan 3 03:59:22.123: //49340//MSM :/ms_stop_play: Play Stopped at 2d03h *Jan 3 03:59:22.123: //49340/B0263EDD8D75/CCAPI/ccDisassociateStream: (callID=0xC0BC) *Jan 3 03:59:22.123: //49340//MSM :/ms_stop_play: calling CBF for stream_id 1 *Jan 3 03:59:22.123: //49340//MSW :/msu_synth_ms_play_complete: context=0x641E4B00, use_dynamic=1, dynamicElement=0x0, cause=MS_STOP_COMPLETE, stream_id=1, duration=3781, rate=0 *Jan 3 03:59:22.123: //49340//MSW :/msu_call_app: app_cbf=0x6133F27C Event = MSW_EV_SYNTHESIZER(1), Context 0x65042A24, Type MSW_SYNTH_TYPE_SYNTHESIZE(2), Reason MSW_SYNTH_REASON_GENERIC_SUCCESS(13) *Jan 3 03:59:22.123: //49340//TCL2:/TclInterpHandler: Tcl handler 0x64DBE928 processing MSW_EV_SYNTHESIZER *Jan 3 03:59:22.123: //49340//TCL2:/TclInterpDriver: *Jan 3 03:59:22.123: //49340//TCL2:/TclEventPreprocessing: *Jan 3 03:59:22.123: //49340//TCL2:/MakeTransition: FSM Transtion: ([6 ]GETACCOUNT,[84]ev_media_done)---([-1]IGNORE)--->([6 ]GETACCOUNT) *Jan 3 03:59:22.763: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=1, digit_begin_flags=0x1, rtp_timestamp=0x1240C252 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:22.871: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=1,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:23.415: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=3, digit_begin_flags=0x1, rtp_timestamp=0x1240D682 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:23.519: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=3,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:24.899: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=2, digit_begin_flags=0x1, rtp_timestamp=0x124104DA rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:24.999: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=2,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:25.243: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=4, digit_begin_flags=0x1, rtp_timestamp=0x12410FB2 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:25.347: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=4,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:25.463: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=5, digit_begin_flags=0x1, rtp_timestamp=0x124116AA rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:25.571: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=5,duration=102,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:25.679: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=6, digit_begin_flags=0x1, rtp_timestamp=0x12411D5A rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:25.783: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=6,duration=104,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:25.919: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=7, digit_begin_flags=0x1, rtp_timestamp=0x124124D2 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:26.071: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=7,duration=154,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:26.183: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=8, digit_begin_flags=0x1, rtp_timestamp=0x12412D12 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:26.287: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=8,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:26.583: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=9, digit_begin_flags=0x1, rtp_timestamp=0x12413972 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:27.303: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=9, digit_begin_flags=0x1, rtp_timestamp=0x12415012 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:27.411: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=9,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:27.867: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=2, digit_begin_flags=0x1, rtp_timestamp=0x124161B2 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:27.971: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=2,duration=104,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:28.079: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=3, digit_begin_flags=0x1, rtp_timestamp=0x12416862 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:28.187: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=3,duration=106,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:29.067: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:59:29.067: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0 *Jan 3 03:59:29.067: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFED7 cr 0x7ECD state 3 event 0x3 ces 1 *Jan 3 03:59:29.067: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7ECD PROGRESS:U3_Progress(nlcb) *Jan 3 03:59:29.067: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x64FB4AC0], magic[FACE0FFF] *Jan 3 03:59:29.067: //49343/A94075F690A8/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:59:29.067: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x64F86BE8] with objects in container[0x64FB4AC0] *Jan 3 03:59:29.067: //49343/A94075F690A8/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:59:29.067: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64F86BE8], numBuckets[11], numEntries[1] *Jan 3 03:59:29.067: Bucket { 6 } ------>0x6466A930[0x0,t-19,m-1,g-FACE0FFF 0x64F4DA28,i-0 ] *Jan 3 03:59:29.067: *Jan 3 03:59:29.067: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x64FB4AC0] *Jan 3 03:59:29.067: //49343/A94075F690A8/CCAPI/cc_api_call_cut_progress: (vdbPtr=0x648EF15C, callID=0xC0BF, prog_ind=0x2, sig_ind=0x2, cause=0x0) *Jan 3 03:59:29.067: //49343/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(22=CC_EV_CALL_PROGRESS), cid(49343), disp(0) *Jan 3 03:59:29.067: //49343/A94075F690A8/SSAPP:0:-1/ssaTraceSct: cid(49343)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_PROGRESS) oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0) *Jan 3 03:59:29.067: //49343/A94075F690A8/SSAPP:0:-1/ssaTraceSct: -cid2(49337)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING) *Jan 3 03:59:29.067: //49337/A94075F690A8/SSAPP:0:-1/ssaCutProgress: *Jan 3 03:59:29.067: //49337/A94075F690A8/CCAPI/ccGetDialpeerTag: (callID=0xC0B9) *Jan 3 03:59:29.067: //49337/A94075F690A8/CCAPI/ccCallCutProgress: (callID=0xC0B9, prog_ind=0x8, sig_ind=0x2, cause=0x0) *Jan 3 03:59:29.067: //49337/A94075F690A8/CCAPI/ccCallAlert: (callID=0xC0B9, prog_ind=0x8, sig_ind=0x2) *Jan 3 03:59:29.071: //49337/A94075F690A8/CCAPI/ccConferenceCreate: (confID=0x645C05EC, callID1=0xC0B9, callID2=0xC0BF, tag=0x0) *Jan 3 03:59:29.071: //49337/A94075F690A8/CCAPI/ccConferenceCreate: (pConf=0x64E55140) *Jan 3 03:59:29.071: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[49337], tagID[33], instID[-1], data[0x65F0E004], dataSize[4] *Jan 3 03:59:29.071: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64EB40A0], nxtElem[0x0], magic[0xFACE0FFF] tagID[33], dataLen[4], modif[2] *Jan 3 03:59:29.071: //49337/A94075F690A8/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:59:29.071: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64FE143C], tdObject[0x64EB40A0] *Jan 3 03:59:29.071: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64E5B610] tagID[33] *Jan 3 03:59:29.071: //49337/A94075F690A8/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:59:29.071: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64FE143C], numBuckets[11], numEntries[6] *Jan 3 03:59:29.071: Bucket { 0 } ------>0x640A613C[0x6600B858,t-5,l-44,d-0x640A615C,m-4,u-187157,g-FACE0FFF]------>0x6600B858[0x64F4FB2C,t-22,l-12,d-0x6600B878,m-4,u-187157,g-FACE0FFF]------>0x64F4FB2C[0x0,t-24,l-4,d-0x64F4FB4C,m-1,u-187171,g-FACE0FFF] *Jan 3 03:59:29.071: *Jan 3 03:59:29.071: Bucket { 3 } ------>0x6472B7C0[0x0,t-32,l-4,d-0x6472B7E0,m-2,u-187157,g-FACE0FFF] *Jan 3 03:59:29.071: *Jan 3 03:59:29.071: Bucket { 5 } ------>0x646810CC[0x0,t-6,l-16,d-0x646810EC,m-1,u-187171,g-FACE0FFF] *Jan 3 03:59:29.071: *Jan 3 03:59:29.071: Bucket { 8 } ------>0x64EB40A0[0x0,t-33,l-4,d-0x64EB40C0,m-2,u-187172,g-FACE0FFF] *Jan 3 03:59:29.071: *Jan 3 03:59:29.071: //49337/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: (confID=0x11A3, srcIF=0x641CD818, srcCallID=0xC0B9, dstCallID=0xC0BF, disposition=0, tag=0x0) *Jan 3 03:59:29.071: //49343/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: (confID=0x11A3, srcIF=0x648EF15C, srcCallID=0xC0BF, dstCallID=0xC0B9, disposition=0, tag=0x0) *Jan 3 03:59:29.071: //49343/A94075F690A8/CCAPI/cc_api_caps_ind: (dstVdbPtr=0x641CD818, dstCallId=0xC0B9, srcCallId=0xC0BF, caps={codec=0x0, fax_rate=0x0, vad=0x0, modem=0x2 codec_bytes=0, signal_type=0}) *Jan 3 03:59:29.071: //49343/A94075F690A8/CCAPI/cc_api_caps_ind: (Playout: mode 1, initial 60, min 40, max 200, fax 300) *Jan 3 03:59:29.071: //-1/xxxxxxxxxxxx/CCAPI/cc_process_notify_bridge_done: (event=0x645ABD08) *Jan 3 03:59:29.075: //49337/A94075F690A8/CCAPI/cc_api_caps_ind: (dstVdbPtr=0x648EF15C, dstCallId=0xC0BF, srcCallId=0xC0B9, caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x0 codec_bytes=20, signal_type=2}) *Jan 3 03:59:29.075: //49337/A94075F690A8/CCAPI/cc_api_caps_ind: (Playout: mode 1, initial 60, min 40, max 250, fax 300) *Jan 3 03:59:29.075: //49337/A94075F690A8/CCAPI/cc_api_caps_ack: (dstVdbPtr=0x648EF15C, dstCallId=0xC0BF, srcCallId=0xC0B9, caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x0 codec_bytes=20, signal_type=2, seq_num_start=0}) *Jan 3 03:59:29.075: //49343/A94075F690A8/CCAPI/cc_api_caps_ack: (dstVdbPtr=0x641CD818, dstCallId=0xC0B9, srcCallId=0xC0BF, caps={codec=0x4, fax_rate=0x2, vad=0x2, modem=0x0 codec_bytes=20, signal_type=2, seq_num_start=0}) *Jan 3 03:59:29.075: //49337/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(29=CC_EV_CONF_CREATE_DONE), cid(49337), disp(0) *Jan 3 03:59:29.075: //49337/A94075F690A8/SSAPP:0:4515/ssaTraceSct: cid(49337)st(SSA_CS_CONFERENCING_PROGRESS)ev(SSA_EV_CONF_CREATE_DONE) oldst(SSA_CS_CALL_SETTING)cfid(4515)csize(0)in(1)fDest(1) *Jan 3 03:59:29.075: //49337/A94075F690A8/SSAPP:0:4515/ssaTraceSct: -cid2(49343)st2(SSA_CS_CONFERENCING_PROGRESS)oldst2(SSA_CS_CALL_SETTING) *Jan 3 03:59:29.075: //49343/A94075F690A8/SSAPP:0:4515/ssaConfCreateDoneAlert: *Jan 3 03:59:29.075: //49343/A94075F690A8/SSAPP:0:4515/ssaConfCreateDoneAlert: Other call leg not found *Jan 3 03:59:29.887: //49345/B7089DF48D77/CCAPI/cc_api_call_alert: (vdbPtr=0x641D4BB4, callID=0xC0C1, prog_ind=0x0, sig_ind=0x1, rawmsgPtr=0x0) *Jan 3 03:59:29.887: //49345/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(7=CC_EV_CALL_ALERT), cid(49345), disp(0) *Jan 3 03:59:29.887: //49345/B7089DF48D77/SSAPP:0:-1/ssaTraceSct: cid(49345)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_ALERT) oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0) *Jan 3 03:59:29.887: //49345/B7089DF48D77/SSAPP:0:-1/ssaTraceSct: -cid2(49344)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING) *Jan 3 03:59:29.887: //49344/B7089DF48D77/SSAPP:801:-1/ssaAlert: *Jan 3 03:59:29.887: //49344/B7089DF48D77/CCAPI/ccGetDialpeerTag: (callID=0xC0C0) *Jan 3 03:59:29.887: //49344/B7089DF48D77/CCAPI/ccCallAlert: (callID=0xC0C0, prog_ind=0x0, sig_ind=0x1) *Jan 3 03:59:29.887: //49344/B7089DF48D77/CCAPI/ccGetDialpeerTag: (callID=0xC0C0) *Jan 3 03:59:29.891: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0x35E, int id = 0x0 *Jan 3 03:59:29.891: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0x35E cr 0x8480 state 9 event 0x86 ces 1 *Jan 3 03:59:29.891: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x8480 CC_ALERTING_REQ:U9_AlertingReq(nlcb) *Jan 3 03:59:29.891: ISDN Se7/1:15 SC Q931d: L3_TxSarMsg: InfoLen = 9 octets 0x08028480011E028188 *Jan 3 03:59:29.891: ISDN Se7/1:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x6485269C datasize = 25 *Jan 3 03:59:29.891: ISDN Se7/1:15 SC Q931d: data = 0x6485269C000003000240049400010806 *Jan 3 03:59:29.891: 08028480011E028188 *Jan 3 03:59:29.891: ISDN Se7/1:15 SC Q931d: L3_state_change: callref 0x8480 old IN_CALL_PROC, new CALL_RECEIVED *Jan 3 03:59:29.891: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x641A8A5C), ticks (1000), event (0x1240) *Jan 3 03:59:34.955: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:59:34.955: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0 *Jan 3 03:59:34.955: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFCFA cr 0x7CF0 state 10 event 0x45 ces 1 *Jan 3 03:59:34.955: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7CF0 DISCONNECT:Ux_Disconnect(nlcb) *Jan 3 03:59:34.955: ISDN Se7/1:15 SC Q931d: L3_state_change: callref 0x7CF0 old CALL_ACTIVE, new DISCONNECT_IND *Jan 3 03:59:34.959: ISDN Se7/1:15 SC Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0xFCFA, int id = 0x0 *Jan 3 03:59:34.959: ISDN Se7/1:15 SC Q931d: L3_Go: call_id 0xFCFA cr 0x7CF0 state 12 event 0x85 ces 1 *Jan 3 03:59:34.959: ISDN Se7/1:15 SC Q931d: L3_ProcessEvent: callref = 0x7CF0 CC_RELEASE_REQ:U12_ReleaseReq(nlcb) *Jan 3 03:59:34.959: ISDN Se7/1:15 SC Q931d: L3_TxSarMsg: InfoLen = 5 octets 0x08027CF04D *Jan 3 03:59:34.959: ISDN Se7/1:15 SC Q931d: srl_send_l3_pak: source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ priv_len = 4 int_id = 0x6485269C datasize = 21 *Jan 3 03:59:34.959: ISDN Se7/1:15 SC Q931d: data = 0x6485269C000003000240049400010806 *Jan 3 03:59:34.959: 08027CF04D *Jan 3 03:59:34.959: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x64E08C58), ticks (4000), event (0x1318) *Jan 3 03:59:34.959: ISDN Se7/1:15 SC Q931d: L3_state_change: callref 0x7CF0 old DISCONNECT_IND, new RELEASE_REQUEST *Jan 3 03:59:34.959: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x641A8A5C), ticks (1000), event (0x1240) *Jan 3 03:59:34.959: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x646511B0], magic[FACE0FFF] *Jan 3 03:59:34.959: //48332/3ABCBE0BA932/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:59:34.959: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x648C362C] with objects in container[0x646511B0] *Jan 3 03:59:34.963: //48332/3ABCBE0BA932/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:59:34.963: ccTDUtilDumpAllElemInProfileTab: profileTable[0x648C362C], numBuckets[11], numEntries[1] *Jan 3 03:59:34.963: Bucket { 6 } ------>0x64ED3934[0x0,t-19,m-1,g-FACE0FFF 0x64F7A598,i-0 ] *Jan 3 03:59:34.963: *Jan 3 03:59:34.963: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x646511B0] *Jan 3 03:59:34.963: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilSetDataInstance: Setting data for callID[48329], tagID[24], instID[-1], data[0x645B49FC], dataSize[4] *Jan 3 03:59:34.963: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64ED29F8], nxtElem[0x0], magic[0xFACE0FFF] tagID[24], dataLen[4], modif[1] *Jan 3 03:59:34.963: //48329/3ABCBE0BA932/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:59:34.963: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x64E98D60], tdObject[0x64ED29F8] *Jan 3 03:59:34.963: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructInstanceTDObject: tdObject[0x64F8557C] tagID[24] *Jan 3 03:59:34.963: //48329/3ABCBE0BA932/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:59:34.963: ccTDUtilDumpAllElemInProfileTab: profileTable[0x64E98D60], numBuckets[11], numEntries[3] *Jan 3 03:59:34.963: Bucket { 0 } ------>0x64ED29F8[0x0,t-24,l-4,d-0x64ED2A18,m-1,u-187177,g-FACE0FFF] *Jan 3 03:59:34.963: *Jan 3 03:59:34.963: Bucket { 1 } ------>0x64F0B0DC[0x0,t-20,l-28,d-0x64F0B0FC,m-3,u-184259,g-FACE0FFF] *Jan 3 03:59:34.963: *Jan 3 03:59:34.963: Bucket { 5 } ------>0x64F6419C[0x0,t-6,l-16,d-0x64F641BC,m-1,u-184259,g-FACE0FFF] *Jan 3 03:59:34.963: *Jan 3 03:59:34.963: //48332/3ABCBE0BA932/CCAPI/cc_api_call_disconnected: (vdbPtr=0x648EF15C, callID=0xBCCC, cause=0x10, rawmsg=0x645AFD48) *Jan 3 03:59:34.963: //48332/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(11=CC_EV_CALL_DISCONNECTED), cid(48332), disp(0) *Jan 3 03:59:34.963: //48332/3ABCBE0BA932/SSAPP:0:4454/ssaTraceSct: cid(48332)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_DISCONNECTED) oldst(SSA_CS_ACTIVE)cfid(4454)csize(0)in(0)fDest(0) *Jan 3 03:59:34.963: //48332/3ABCBE0BA932/SSAPP:0:4454/ssaTraceSct: -cid2(48329)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_CONFERENCING_ALERT) *Jan 3 03:59:34.963: ssa: Disconnected cid(48332) state(5) cause(0x10) *Jan 3 03:59:34.963: //48329/3ABCBE0BA932/CCAPI/ccConferenceDestroy: (confID=0x1166, tag=0x0) *Jan 3 03:59:34.963: //48329/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0x1166, srcIF=0x641D4BB4, srcCallID=0xBCC9, dstCallID=0xBCCC, disposition=0 tag=0x0) *Jan 3 03:59:34.963: //48332/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: (confID=0x1166, srcIF=0x648EF15C, srcCallID=0xBCCC, dstCallID=0xBCC9, disposition=0 tag=0x0) *Jan 3 03:59:34.967: //48329/xxxxxxxxxxxx/SSAPP:-1:-1/sess_appl: ev(30=CC_EV_CONF_DESTROY_DONE), cid(48329), disp(0) *Jan 3 03:59:34.967: //48329/3ABCBE0BA932/SSAPP:0:4454/ssaTraceSct: cid(48329)st(SSA_CS_CONF_DESTROYING)ev(SSA_EV_CONF_DESTROY_DONE) oldst(SSA_CS_CONFERENCING_ALERT)cfid(4454)csize(0)in(1)fDest(1) *Jan 3 03:59:34.967: //48329/3ABCBE0BA932/SSAPP:0:4454/ssaTraceSct: -cid2(48332)st2(SSA_CS_CONF_DESTROYING)oldst2(SSA_CS_ACTIVE) *Jan 3 03:59:34.967: //48332/3ABCBE0BA932/SSAPP:0:-1/ssaConfDestroyDone: *Jan 3 03:59:34.967: //48329/3ABCBE0BA932/SSAPP:0:-1/ssaDebugPeers: ssaFlushPeerTagQueue cid(48329) peer list: (empty) *Jan 3 03:59:34.967: //48329/3ABCBE0BA932/CCAPI/ccCallDisconnect: (callID=0xBCC9, cause=0x10 tag=0x0) *Jan 3 03:59:34.967: //48329/3ABCBE0BA932/CCAPI/ccCallDisconnect: calling accounting start for callID=48329 leg_type=1 *Jan 3 03:59:34.967: //48329/3ABCBE0BA932/CCAPI/ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10 *Jan 3 03:59:34.967: //48329/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[48329], tagID[24], instID[-1] *Jan 3 03:59:34.967: //48332/3ABCBE0BA932/CCAPI/ccCallDisconnect: (callID=0xBCCC, cause=0x10 tag=0x0) *Jan 3 03:59:34.967: //48332/3ABCBE0BA932/CCAPI/ccCallDisconnect: calling accounting start for callID=48332 leg_type=0 *Jan 3 03:59:34.967: //48332/3ABCBE0BA932/CCAPI/ccCallDisconnect: existing_cause = 0x10, new_cause = 0x10 *Jan 3 03:59:34.967: //48332/3ABCBE0BA932/CCAPI/ccCallDisconnect: using the existing_cause 0x10 *Jan 3 03:59:34.967: //48332/3ABCBE0BA932/CCAPI/cc_api_get_transfer_info: (callID=0xBCCC) *Jan 3 03:59:34.967: //48332/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: CallID[48332], tagID[24], instID[-1] *Jan 3 03:59:34.967: //48332/xxxxxxxxxxxx/CCAPI/ccTDUtilGetDataByValue: No tdObject found in profileTable for tagID[24] of callID[48332] *Jan 3 03:59:34.971: ISDN Se7/1:15 SC LIFd: LIF_StartTimer: timer (0x63F45410), ticks (30000), event (0x1250) *Jan 3 03:59:35.975: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=2, digit_begin_flags=0x1, rtp_timestamp=0x12425F22 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:36.083: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=2,duration=106,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:36.195: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=3, digit_begin_flags=0x1, rtp_timestamp=0x1242660A rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:36.303: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=3,duration=106,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:36.979: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=6, digit_begin_flags=0x1, rtp_timestamp=0x12427E5A rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:37.079: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=6,duration=104,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:37.995: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=8, digit_begin_flags=0x1, rtp_timestamp=0x12429E32 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:38.099: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=8,duration=105,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:38.387: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=9, digit_begin_flags=0x1, rtp_timestamp=0x1242AA8A rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:38.491: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=9,duration=104,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:39.227: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=1, digit_begin_flags=0x1, rtp_timestamp=0x1242C4B2 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:39.331: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=1,duration=104,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0 *Jan 3 03:59:39.475: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=2, digit_begin_flags=0x1, rtp_timestamp=0x1242CC72 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:39.939: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_begin: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=4, digit_begin_flags=0x1, rtp_timestamp=0x1242DB02 rtp_expiration=0x0, dest_mask=0x1) *Jan 3 03:59:40.043: //49340/B0263EDD8D75/CCAPI/cc_api_call_digit_end: (dstVdbPtr=0x0, dstCallId=0xFFFFFFFF, srcCallId=0xC0BC, digit=4,duration=106,xruleCallingTag=0,xruleCalledTag=0, dest_mask=0x1), digit_tone_mode=0h323chan_dgram_send:Sent UDP msg. Bytes sent: 66 to 77.241.64.21:1812 fd=2 *Jan 3 03:59:43.735: RASLib::GW_RASSendGRQ: GRQ (seq# 3777) sent to 77.241.64.21 *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructTDUsrContainer: usrContainer[0x640A6090], magic[FACE0FFF] *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x640A6090, tagID=6, dataSize=16, instID=-1,modifier=3 *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x64E161FC], nxtElem[0x0], magic[0xFACE0FFF] tagID[6], dataLen[16], modif[3] *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x64E161FC] instID[-1] into container[0x640A6090] *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/ccTDUtilAddDataToUsrContainer: container=0x640A6090, tagID=20, dataSize=28, instID=-1,modifier=3 *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructInstanceTDObject: tdObject[0x6467DDE0], nxtElem[0x0], magic[0xFACE0FFF] tagID[20], dataLen[28], modif[3] *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToContainer: Adding tdObject[0x6467DDE0] instID[-1] into container[0x640A6090] *Jan 3 03:59:44.779: //-1/xxxxxxxxxxxx/CCAPI/cc_api_display_ie_subfields: *Jan 3 03:59:44.779: cc_api_call_setup_ind: *Jan 3 03:59:44.779: cisco-username=97317786616 *Jan 3 03:59:44.779: ----- ccCallInfo IE subfields ----- *Jan 3 03:59:44.779: cisco-ani=97317786616 *Jan 3 03:59:44.779: cisco-anitype=0 *Jan 3 03:59:44.779: cisco-aniplan=0 *Jan 3 03:59:44.779: cisco-anipi=0 *Jan 3 03:59:44.779: cisco-anisi=0 *Jan 3 03:59:44.779: dest=5064#96264912930 *Jan 3 03:59:44.783: cisco-desttype=0 *Jan 3 03:59:44.783: cisco-destplan=0 *Jan 3 03:59:44.783: cisco-rdn= *Jan 3 03:59:44.783: cisco-rdntype=0 *Jan 3 03:59:44.783: cisco-rdnplan=0 *Jan 3 03:59:44.783: cisco-rdnpi=0 *Jan 3 03:59:44.783: cisco-rdnsi=0 *Jan 3 03:59:44.783: cisco-redirectreason=-1 *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x641D4BB4, callInfo={called=5064#96264912930,called_oct3=0x80,calling=97317786616,calling_oct3=0x0,calling_oct3a=0x80,calling_xlated=false,subscriber_type_str=Unknown,fdest=1,peer_tag=3009999, prog_ind=0,callingIE_present 1, src_route_label=, tgt_route_label= clid_transparent=0},callID=0x6476F3CC) *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: type 0 , prot 3 *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:59:44.783: ccCheckClipClir: calling number is: "97317786616", calling oct3a is: 0x80 *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:59:44.783: Calling Party number is User Provided *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccCheckClipClir: *Jan 3 03:59:44.783: Leaving ccCheckClipClir calling number is: "97317786616" calling oct3 is: 0x0 calling oct3a is: 0x80 *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_setup_ind: (vdbPtr=0x641D4BB4, callInfo={called=5064#96264912930, calling=97317786616, fdest=1 peer_tag=3009999}, callID=0x6476F3CC) *Jan 3 03:59:44.783: //49358/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: Increment call volume: 10 *Jan 3 03:59:44.783: //49358/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: current call volume: 11 *Jan 3 03:59:44.783: //49358/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: entry's incoming TRUE. *Jan 3 03:59:44.783: //49358/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry: is_incoming is FALSE *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccTDConstructHashProfileTab: profileTable[0x65EFA96C], numBuckets[11], numEntries[0] *Jan 3 03:59:44.783: //49358/0C18D7F6AA6B/CCAPI/ccTDPvtProfileTableBuildManager: Invoking necessary profileTable updaters... *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: Updating profileTable[0x65EFA96C] with objects in container[0x640A6090] *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[5] for the tag[6] *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65EFA96C], tdObject[0x64E161FC] *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtUpdateProfileTabFromContainer: obtained key[1] for the tag[20] *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccTDPvtAddObjectToProfileBucket: profileTable[0x65EFA96C], tdObject[0x6467DDE0] *Jan 3 03:59:44.783: //49358/0C18D7F6AA6B/CCAPI/ccTDPvtProfileTableBuildManager: *Jan 3 03:59:44.783: ccTDUtilDumpAllElemInProfileTab: profileTable[0x65EFA96C], numBuckets[11], numEntries[2] *Jan 3 03:59:44.783: Bucket { 1 } ------>0x6467DDE0[0x0,t-20,l-28,d-0x6467DE00,m-3,u-187187,g-FACE0FFF] *Jan 3 03:59:44.783: *Jan 3 03:59:44.783: Bucket { 5 } ------>0x64E161FC[0x0,t-6,l-16,d-0x64E1621C,m-3,u-187187,g-FACE0FFF] *Jan 3 03:59:44.783: *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/ccTDDestructTDUsrContainer: Container[0x640A6090] *Jan 3 03:59:44.783: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: remote IP is 216.168.162.82 *Jan 3 03:59:44.787: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: hwidb is FastEthernet0/0 *Jan 3 03:59:44.787: //-1/xxxxxxxxxxxx/CCAPI/cc_incr_if_call_volume: added for entry: 10, voip: 10, mmoip: 0 *Jan 3 03:59:44.787: AAA/BIND(00014173): Bind i/f *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0CE, mask=2 *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0xC0CE, mask=2 flag=1 *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/ccCallSetVoipFlag: ccCallSetVoipFlag: callID=0xC0CE, mask=1 flag=1 *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0CE, mask=1 *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/ccCallGetVoipFlag: ccCallGetVoipFlag: callID=0xC0CE, mask=1 *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/cc_process_call_setup_ind: (event=0x645ABD08) *Jan 3 03:59:44.787: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: matching parameters - called# [5064#96264912930], calling# [97317786616], consultid [] *Jan 3 03:59:44.787: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search: Searching for node with called# [5064#96264912930], calling# [97317786616], consultid [] *Jan 3 03:59:44.787: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup: No matching node *Jan 3 03:59:44.787: //-1//TCL2:/TclGetInterp: Script IVR *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/ccCallSetContext: (callID=0xC0CE, context=0x6503E584) *Jan 3 03:59:44.787: //49358/0C18D7F6AA6B/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 49358 with tag 3009999 to app "IVR" *Jan 3 03:59:44.787: RADIUS/ENCODE(00014173):Orig. component type = VOICE *Jan 3 03:59:44.791: RADIUS(00014173): Config NAS IP: 0.0.0.0 *Jan 3 03:59:44.791: RADIUS(00014173): sending *Jan 3 03:59:44.791: //49358//TCL2:/TclInterpHandler: Tcl handler 0x65F1DA08 processing CC_EV_CALL_SETUP_IND *Jan 3 03:59:44.791: //49358//TCL2:/TclInterpDriver: *Jan 3 03:59:44.791: //49358//TCL2:/TclEventPreprocessing: *Jan 3 03:59:44.791: //-1//TCL2:/TclIncomingCallBlock: *Jan 3 03:59:44.791: //49358//TCL2:/MakeTransition: FSM Transtion: ([1 ]CALL_INIT,[28]ev_setup_indication)---([10]act_Setup)--->([7 ]AUTHENTICATE) *Jan 3 03:59:44.791: //49358/0C18D7F6AA6B/CCAPI/ccCallSetupAck: (callID=0xC0CE) *Jan 3 03:59:44.791: //49358/0C18D7F6AA6B/CCAPI/cc_api_set_transfer_info: (transfer= 0, callID=0xC0CE) *Jan 3 03:59:44.791: //49358/0C18D7F6AA6B/CCAPI/cc_api_set_transfer_info: call transfer reset) *Jan 3 03:59:44.791: //49358/0C18D7F6AA6B/CCAPI/ccCallProceeding: (callID=0xC0CE, prog_ind=0x0) *Jan 3 03:59:44.791: //49358/0C18D7F6AA6B/CCAPI/ccCallConnect: (callID=0xC0CE), prog_ind=0, cData->bitmask=0x0 *Jan 3 03:59:44.791: //49358/0C18D7F6AA6B/CCAPI/ccCallConnect: setting callEntry->connected to TRUE *Jan 3 03:59:44.791: AAA/BIND(00014174): Bind i/f *Jan 3 03:59:44.791: AAA/AUTHEN/LOGIN (00014174): Pick method list 'h323' *Jan 3 03:59:44.791: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:59:44.795: RADIUS(00014173): Send Accounting-Request to 77.241.64.21:1813 id 1646/189, len 628 *Jan 3 03:59:44.795: RADIUS: authenticator 9C 3B 5C 91 88 2C B5 CB - 5E 53 88 5C FA C3 52 F7 *Jan 3 03:59:44.795: RADIUS: Acct-Session-Id [44] 10 "0001FEC4" *Jan 3 03:59:44.795: RADIUS: Calling-Station-Id [31] 13 "97317786616" *Jan 3 03:59:44.795: RADIUS: Called-Station-Id [30] 18 "5064#96264912930" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 60 *Jan 3 03:59:44.795: RADIUS: Cisco AVpair [1] 54 "call-id=766743-3419153372-317504@nxtnyc02.ivanet.net" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 21 *Jan 3 03:59:44.795: RADIUS: Cisco AVpair [1] 15 "iphop=count:1" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 33 *Jan 3 03:59:44.795: RADIUS: Cisco AVpair [1] 27 "iphop=hop1:216.168.162.82" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:59:44.795: RADIUS: h323-setup-time [25] 50 "h323-setup-time=*03:59:44.777 UTC Mon Jan 3 2000" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:59:44.795: RADIUS: h323-gw-id [33] 26 "h323-gw-id=VoiceGateway." *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 56 *Jan 3 03:59:44.795: RADIUS: Conf-Id [24] 50 "h323-conf-id=0C18D7F6 C0C911D3 AA6BE9B4 7DB0F344" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 31 *Jan 3 03:59:44.795: RADIUS: h323-call-origin [26] 25 "h323-call-origin=answer" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 27 *Jan 3 03:59:44.795: RADIUS: h323-call-type [27] 21 "h323-call-type=VoIP" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 65 *Jan 3 03:59:44.795: RADIUS: Cisco AVpair [1] 59 "h323-incoming-conf-id=0C18D7F6 C0C911D3 AA6BE9B4 7DB0F344" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 26 *Jan 3 03:59:44.795: RADIUS: Cisco AVpair [1] 20 "subscriber=Unknown" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 30 *Jan 3 03:59:44.795: RADIUS: Cisco AVpair [1] 24 "session-protocol=sipv2" *Jan 3 03:59:44.795: RADIUS: Vendor, Cisco [26] 49 *Jan 3 03:59:44.795: RADIUS: Cisco AVpair [1] 43 "gw-rxd-cdn=ton:0,npi:0,#:5064#96264912930" *Jan 3 03:59:44.799: RADIUS: User-Name [1] 13 "97317786616" *Jan 3 03:59:44.799: RADIUS: Ascend-Connection-Pr[196] 6 Call Up [10] *Jan 3 03:59:44.799: RADIUS: Vendor, Cisco [26] 32 *Jan 3 03:59:44.799: RADIUS: Cisco AVpair [1] 26 "connect-progress=Call Up" *Jan 3 03:59:44.799: RADIUS: Acct-Authentic [45] 6 noval0 [0] *Jan 3 03:59:44.799: RADIUS: Acct-Status-Type [40] 6 Start [1] *Jan 3 03:59:44.799: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:59:44.799: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:59:44.799: RADIUS: Acct-Delay-Time [41] 6 0 *Jan 3 03:59:44.799: RADIUS/ENCODE(00014174):Orig. component type = VOICE *Jan 3 03:59:44.799: RADIUS(00014174): Config NAS IP: 0.0.0.0 *Jan 3 03:59:44.803: RADIUS/ENCODE(00014174): acct_session_id: 130757 *Jan 3 03:59:44.803: RADIUS(00014174): sending *Jan 3 03:59:44.803: RADIUS: Received from id 1646/189 77.241.64.21:1813, Accounting-response, len 20 *Jan 3 03:59:44.803: RADIUS: authenticator A8 57 38 A2 38 5F 0B A3 - AF A3 A8 78 BD 7D D5 F5 *Jan 3 03:59:44.803: RADIUS/ENCODE: Best Local IP-Address 77.241.64.49 for Radius-Server 77.241.64.21 *Jan 3 03:59:44.803: RADIUS(00014174): Send Access-Request to 77.241.64.21:1812 id 1645/231, len 101 *Jan 3 03:59:44.803: RADIUS: authenticator F8 94 B8 DA 7F 3F 66 4A - 85 59 0B FD BC EE D2 C0 *Jan 3 03:59:44.803: RADIUS: User-Name [1] 13 "97317786616" *Jan 3 03:59:44.803: RADIUS: User-Password [2] 18 * *Jan 3 03:59:44.803: RADIUS: Vendor, Cisco [26] 38 *Jan 3 03:59:44.803: RADIUS: Cisco AVpair [1] 32 "h323-ivr-out=transactionID:486" *Jan 3 03:59:44.803: RADIUS: Service-Type [6] 6 Login [1] *Jan 3 03:59:44.803: RADIUS: NAS-IP-Address [4] 6 77.241.64.49 *Jan 3 03:59:45.047: h323chan_chn_process_read_socket *Jan 3 03:59:45.047: h323chan_chn_process_read_socket: fd=0 of type LISTENING has data *Jan 3 03:59:45.047: //-1/xxxxxxxxxxxx/CCAPI/cc_api_supported_data: data_mode=0x10000 *Jan 3 03:59:48.367: ISDN Se7/7:15 SC LIFd: LIF_StartTimer: timer (0x63F25734), ticks (1000), event (0x1240) *Jan 3 03:59:48.367: ISDN Se7/7:15 SC LIFd: LIF_StartTimer: timer (0x63F25778), ticks (30000), event (0x1250)h323chan_dgram_send:Sent UDP msg. Bytes sent: 66 to 77.241.64.21:1812 fd=2 *Jan 3 03:59:48.735: RASLib::GW_RASSendGRQ: GRQ (seq# 3777) sent to 77.241.64.21