Buy or Renew
Buy or Renew
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
1517
Views
23
Helpful
6
Replies

Problem to place calls

Martin Bauer
Level 1
Level 1

‎02-25-2013 09:13 AM - edited ‎03-16-2019 03:55 PM

Hello everyone, I come humbly ask some help to solve my problem.

I'm having troubles to places calls from CUCM using the router 2811 as gateway. When the call is made and the router 2821 is used no problem is related. This issue seems to happen only with IP Communicator calls, because when the call is originate by a ATA 186 (192.168.1.253 or .251) the call is complete normally.

No problems are related when placing calls from 192.168.0.X using router 2821 as gateway (but no IPComm on the side 192.168.0.X exists).

The network topology is the following:

Here is a debug of the call, if someone could help. Thanks very much.

019615: *Feb 21 14:42:51.960: TCB47FB4454 setting property TCP_TOS (11) 48555EE0

019616: *Feb 21 14:42:52.204: TCP0: ACK timeout timer expired

R2811_MF_RS#

019617: *Feb 21 14:42:53.108: //-1/xxxxxxxxxxxx/CCAPI/ccAppShutdownMode:

   ccAppShutdownMode: remove it from the queue

R2811_MF_RS#

019618: *Feb 21 14:42:55.440: TCB4851BB9C created

019619: *Feb 21 14:42:55.440: Reserved port 1720 in Transport Port Agent for TCP IP type 1

019620: *Feb 21 14:42:55.440: TCP0: state was LISTEN -> SYNRCVD [1720 -> 192.168.1.207(34297)]

019621: *Feb 21 14:42:55.440: TCP: tcb 4851BB9C connection to 192.168.1.207:34297, peer MSS 1460, MSS is 516

019622: *Feb 21 14:42:55.440: TCP: sending SYN, seq 1635138926, ack 972178556

019623: *Feb 21 14:42:55.440: TCP0: Connection to 192.168.1.207:34297, advertising MSS 1060

019624: *Feb 21 14:42:55.480: TCP0: state was SYNRCVD -> ESTAB [1720 -> 192.168.1.207(34297)]

019625: *Feb 21 14:42:55.480: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 0 Event 0x1

019626: *Feb 21 14:42:55.480: TCB4830C88C accepting 4851BB9C from 192.168.1.207.34297

019627: *Feb 21 14:42:55.480: TCB4851BB9C setting property TCP_PID (8) 4733BAB4

019628: *Feb 21 14:42:55.480: TCB4851BB9C setting property TCP_NO_DELAY (1) 4733BAB8

019629: *Feb 21 14:42:55.480: TCB4851BB9C setting property TCP keepalive timeout (17) 4733BB3C

019630: *Feb 21 14:42:55.484: TCB4851BB9C setting property TCP_NO_DELAY (1) 4733BB14

019631: *Feb 21 14:42:55.484: TCB4851BB9C setting property TCP_ALWAYSPUSH (15) 4733BB14

019632: *Feb 21 14:42:55.484: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x4603A824) is in use

019633: *Feb 21 14:42:55.492: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

019634: *Feb 21 14:42:55.492: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x484D9450, len=219, msgPtr=0x47E07E70

019635: *Feb 21 14:42:55.492: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

019636: *Feb 21 14:42:55.492: H225.0 INCOMING ENCODE BUFFER::= 20B0060008914A000501401F0041006E00610020005000610075006C00610000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000022C0B50000120F436973636F43616C6C4D616E616765720031000104003CA494565000F08B33C92D6112E80FD301C0A8017300D50D80000700C0A801CF06B8110000F08B33C92D6112E80FD301C0A80173010001000100010010A001000F0140B50000120880A4000400010200

019637: *Feb 21 14:42:55.492:

019638: *Feb 21 14:42:55.492: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=

    {

      h323-uu-pdu

      {

        h323-message-body setup :

        {

          protocolIdentifier { 0 0 8 2250 0 5 }

          sourceAddress

          {

            h323-ID : {"Ana Paula..."}

          }

          sourceInfo

          {

            vendor

            {

              vendor

              {

                t35CountryCode 181

                t35Extension 0

                manufacturerCode 18

              }

              productId '436973636F43616C6C4D616E61676572'H

              versionId '31'H

            }

            terminal

            {

            }

            mc FALSE

            undefinedNode FALSE

          }

          destinationAddress

          {

            dialedDigits : "097161232"

          }

          activeMC FALSE

          conferenceID '00F08B33C92D6112E80FD301C0A80173'H

          conferenceGoal create : NULL

          callType pointToPoint : NULL

          sourceCallSignalAddress ipAddress :

          {

            ip 'C0A801CF'H

            port 1720

          }

          callIdentifier

          {

            guid '00F08B33C92D6112E80FD301C0A80173'H

          }

          mediaWaitForConnect FALSE

          canOverlapSend FALSE

          multipleCalls FALSE

          maintainConnection FALSE

        }

        h245Tunneling FALSE

        nonStandardControl

        {

          {

            nonStandardIdentifier h221NonStandard :

            {

              t35CountryCode 181

              t35Extension 0

              manufacturerCode 18

            }

            data '80A4000400010200'H

          }

        }

      }

    }

019639: *Feb 21 14:42:55.500: H225 NONSTD INCOMING ENCODE BUFFER::= 80A4000400010200

019640: *Feb 21 14:42:55.500:

019641: *Feb 21 14:42:55.500: H225 NONSTD INCOMING PDU ::=

value H323_UU_NonStdInfo ::=

    {

      callMgrParam

      {

        interclusterVersion 2

        enterpriseID {}

      }

    }

019642: *Feb 21 14:42:55.500: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN

019643: *Feb 21 14:42:55.500: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry

019644: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/setup_ind: callingNumber[6028] calledNumber[097161232]

019645: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/setup_ind: ---- calling IE present

019646: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/setup_ind: ====== PI = 0

019647: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/setup_ind: Receive: infoXCap 0

019648: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/setup_ind: Receive: infoXCap ccb 0

019649: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/setup_ind: Receive bearer cap infoXRate 16, rateMult 0

019650: *Feb 21 14:42:55.500: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry

019651: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/cch323_determine_source_ip_group_info: No Source IP group match with Src CarrierId/Zone ID

019652: *Feb 21 14:42:55.500: //26747/00F08B33E80F/H323/cch323_determine_source_ip_group_info: No Source IP group matched with rte id/zone_id/acl

019653: *Feb 21 14:42:55.504: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

019654: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_determine_source_ip_group_info: No Source IP group match with Incoming Called Number

019655: *Feb 21 14:42:55.504: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

019656: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_determine_source_ip_group_info: No Source IP group match with Destination pattern

019657: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/setup_ind:

setup_ind: is_overlap = 0, info_complete = 0

019658: *Feb 21 14:42:55.504: //-1/xxxxxxxxxxxx/H323/cch323_is_preauth_reqd: FALSE

019659: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/setup_ind: Call Manager detected

019660: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 192.168.0.250; dest address = 192.168.1.207

019661: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_SETUP_IND while at state H225_IDLE

019662: *Feb 21 14:42:55.504: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = 0

019663: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/common_idle_setupInd_hdlr: full match is found

019664: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_set_preferred_codec: Using Voice Class Codec, tag = 1

019665: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_set_peer: peer: 484FCAFC, peer->voice_peer_tag: E7EF1, ccb: 4603A824

019666: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_set_peer: ccb h245addr bits = system[0x0007]

019667: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_set_peer: ccb ccm bits = system[0x0028]

019668: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_SETUP state

019669: *Feb 21 14:42:55.504: TCB4851BB9C setting property TCP_TOS (11) 4733B8EC

019670: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_set_h245_state_mc_mode_incoming: h245 state m/c mode=0x10F, h323_ctl=0x2F

019671: *Feb 21 14:42:55.504: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = 0

019672: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_create_incoming_callinfo_block: peer 45856664, voice_peer_tag 950001, ccb: 4603A824

019673: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/cch323_create_incoming_callinfo_block: Calling Party is CCM

019674: *Feb 21 14:42:55.504: //26747/00F08B33E80F/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0

019675: *Feb 21 14:42:55.504: //-1/00F08B33E80F/CCAPI/cc_api_display_ie_subfields:

   cc_api_call_setup_ind_common:

   cisco-username=Ana Paula

   ----- ccCallInfo IE subfields -----

   cisco-ani=6028

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=1

   dest=097161232

   cisco-desttype=0

   cisco-destplan=0

   cisco-rdie=FFFFFFFF

   cisco-rdn=

   cisco-rdntype=-1

   cisco-rdnplan=-1

   cisco-rdnpi=-1

   cisco-rdnsi=-1

   cisco-redirectreason=-1   fwd_final_type =0

   final_redirectNumber =

   hunt_group_timeout =0

019676: *Feb 21 14:42:55.508: //-1/00F08B33E80F/CCAPI/cc_api_call_setup_ind_common:

   Interface=0x47158B60, Call Info(

   Calling Number=6028,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

   Called Number=097161232(TON=Unknown, NPI=Unknown),

   Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE,

   Incoming Dial-peer=950001, Progress Indication=NULL(0), Calling IE Present=TRUE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=26747

019677: *Feb 21 14:42:55.508: //-1/00F08B33E80F/CCAPI/ccCheckClipClir:

   In: Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

019678: *Feb 21 14:42:55.508: //-1/00F08B33E80F/CCAPI/ccCheckClipClir:

   Out: Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

019679: *Feb 21 14:42:55.508: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019680: *Feb 21 14:42:55.508: :cc_get_feature_vsa malloc success

019681: *Feb 21 14:42:55.508: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019682: *Feb 21 14:42:55.508:  cc_get_feature_vsa count is 1

019683: *Feb 21 14:42:55.508: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019684: *Feb 21 14:42:55.508: :FEATURE_VSA attributes are: feature_name:0,feature_time:1185185696,feature_id:26747

019685: *Feb 21 14:42:55.508: //26747/00F08B33E80F/CCAPI/cc_api_call_setup_ind_common:

   Set Up Event Sent;

   Call Info(Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

   Called Number=097161232(TON=Unknown, NPI=Unknown))

019686: *Feb 21 14:42:55.508: //26747/00F08B33E80F/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indications

019687: *Feb 21 14:42:55.508: //-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:

cch323_create_call_info_type_container: creating callInfoType container for callID = 26747

019688: *Feb 21 14:42:55.508: //26747/00F08B33E80F/H323/cch323_update_call_info_from_dial_peer: Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300

019689: *Feb 21 14:42:55.508: //26747/00F08B33E80F/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0

019690: *Feb 21 14:42:55.508: //26747/00F08B33E80F/CCAPI/cc_process_call_setup_ind:

   Event=0x472CC070

019691: *Feb 21 14:42:55.512: //26747/00F08B33E80F/CCAPI/ccCallSetContext:

   Context=0x45FEBAE8

019692: *Feb 21 14:42:55.512: //26747/00F08B33E80F/CCAPI/cc_process_call_setup_ind:

   >>>>CCAPI handed cid 26747 with tag 950001 to app "_ManagedAppProcess_Default"

019693: *Feb 21 14:42:55.516: //26747/00F08B33E80F/CCAPI/ccCallProceeding:

   Progress In

R2811_MF_RS#dication=NULL(0)

019694: *Feb 21 14:42:55.520: //26747/00F08B33E80F/CCAPI/ccCallSetupRequest:

   Destination=, Calling IE Present=TRUE, Mode=0,

   Outgoing Dial-peer=900, Params=0x45FF85C8, Progress Indication=NULL(0)

019695: *Feb 21 14:42:55.520: //26747/00F08B33E80F/CCAPI/ccCheckClipClir:

   In: Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

019696: *Feb 21 14:42:55.520: //26747/00F08B33E80F/CCAPI/ccCheckClipClir:

   Out: Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

019697: *Feb 21 14:42:55.520: //26747/00F08B33E80F/CCAPI/ccCallSetupRequest:

   Destination Pattern=0[7-9]T, Called Number=097161232, Digit Strip=TRUE

019698: *Feb 21 14:42:55.520: //26747/00F08B33E80F/CCAPI/ccCallSetupRequest:

   Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

   Called Number=097161232(TON=Unknown, NPI=Unknown),

   Redirect Number=, Display Info=

   Account Number=Ana Paula, Final Destination Flag=TRUE,

   Guid=00F08B33-C92D-6112-E80F-D301C0A80173, Outgoing Dial-peer=900

019699: *Feb 21 14:42:55.520: //26747/00F08B33E80F/CCAPI/cc_api_display_ie_subfields:

   ccCallSetupRequest:

   cisco-username=Ana Paula

   ----- ccCallInfo IE subfields -----

   cisco-ani=6028

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=1

   dest=097161232

   cisco-desttype=0

   cisco-destplan=0

   cisco-rdie=FFFFFFFF

   cisco-rdn=

   cisco-rdntype=-1

   cisco-rdnplan=-1

   cisco-rdnpi=-1

   cisco-rdnsi=-1

   cisco-redirectreason=-1   fwd_final_type =0

   final_redirectNumber =

   hunt_group_timeout =0

019700: *Feb 21 14:42:55.524: //26747/00F08B33E80F/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x46A6ADA0, Interface Type=6, Destination=, Mode=0x0,

   Call Params(Calling Number=6028,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

   Called Number=097161232(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=900, Call Count On=FALSE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

019701: *Feb 21 14:42:55.524: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019702: *Feb 21 14:42:55.524: :cc_get_feature_vsa malloc success

019703: *Feb 21 14:42:55.524: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019704: *Feb 21 14:42:55.524:  cc_get_feature_vsa count is 2

019705: *Feb 21 14:42:55.524: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019706: *Feb 21 14:42:55.524: :FEATURE_VSA attributes are: feature_name:0,feature_time:1185182888,feature_id:26748

019707: *Feb 21 14:42:55.524: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=26747

019708: *Feb 21 14:42:55.524: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_SET_MODE: data ptr=0x498424A0

019709: *Feb 21 14:42:55.524: //-1/xxxxxxxxxxxx/H323/cch323_set_mode: callID=26747, flow Mode=1 spi_mode=0x6

019710: *Feb 21 14:42:55.524: //26748/00F08B33E80F/CCAPI/ccIFCallSetupRequestPrivate:

   SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

019711: *Feb 21 14:42:55.524: //26748/00F08B33E80F/CCAPI/ccCallSetContext:

   Context=0x45FF8578

019712: *Feb 21 14:42:55.524: //26747/00F08B33E80F/CCAPI/ccSaveDialpeerTag:

   Outgoing Dial-peer=900

019713: *Feb 21 14:42:55.524: //26747/00F08B33E80F/H323/cch323_do_set_dialpeer_tag: gw_id=1

019714: *Feb 21 14:42:55.524: //26747/00F08B33E80F/H323/cch323_do_call_proceeding: gw_id=1

019715: *Feb 21 14:42:55.524: //26747/00F08B33E80F/H323/cch323_do_call_proceeding: set_mode NOT called yet...saved deferred CALL_PROC

019716: *Feb 21 14:42:55.524: //26747/00F08B33E80F/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_LISTEN_REQ_EVENT

019717: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_h245_connection_sm: state=0, event=0, ccb=4603A824, listen state=0

019718: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_h245_connection_sm: H245_LISTEN: Received event H245_LISTEN_REQ_EVENT while at H245_NONE state

019719: *Feb 21 14:42:55.528: TCB48050EC4 created

019720: *Feb 21 14:42:55.528: TCB48050EC4 setting property TCP_PID (8) 4733BABC

019721: *Feb 21 14:42:55.528: TCB48050EC4 setting property TCP_NO_DELAY (1) 4733BAC0

019722: *Feb 21 14:42:55.528: TCB48050EC4 setting property TCP_NONBLOCKING_WRITE (10) 4733BB40

019723: *Feb 21 14:42:55.528: TCB48050EC4 setting property TCP_NONBLOCKING_READ (14) 4733BB40

019724: *Feb 21 14:42:55.528: TCB48050EC4 setting property TCP_REUSEADDR (13) 4733BB44

019725: *Feb 21 14:42:55.528: TCB48050EC4 setting property TCP_VRFTABLEID (20) 4733BB6C

019726: *Feb 21 14:42:55.528: TCP: Random local port generated 45122, network 1

019727: *Feb 21 14:42:55.528: TCB48050EC4 bound to UNKNOWN.45122

019728: *Feb 21 14:42:55.528: Reserved port 45122 in Transport Port Agent for TCP IP type 0

019729: *Feb 21 14:42:55.528: TCB48050EC4 listening with queue 32

019730: *Feb 21 14:42:55.528: TCB48050EC4 setting property TCP_ACCESS_CHECK (6) 41385888

019731: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_h245_set_new_state: Changing from H245_NONE state to H245_WAITING state

019732: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_process_set_mode: Setting inbound leg mode flags to 0x10F, flow-mode to FLOW_THROUGH

019733: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_process_set_mode: Sending deferred CALL_PROC

019734: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_do_call_proceeding: gw_id=1

019735: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_do_call_proceeding: set_mode called so we can proceed with CALLPROC

019736: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_SETUP

019737: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/cch323_h225_set_new_state: Changing from H225_SETUP state to H225_CALLPROC state

019738: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/delay_h245_transport_address: CCM-ITS compatibility delay transport address for callid[687B]

019739: *Feb 21 14:42:55.528: //26747/00F08B33E80F/H323/generic_send_callproc: ====== PI = 0

019740: *Feb 21 14:42:55.528: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=

    {

      h323-uu-pdu

      {

        h323-message-body callProceeding :

        {

          protocolIdentifier { 0 0 8 2250 0 4 }

          destinationInfo

          {

            vendor

            {

              vendor

              {

                t35CountryCode 181

                t35Extension 0

                manufacturerCode 18

              }

            }

            gateway

            {

              protocol

              {

                voice :

                {

                  supportedPrefixes

                  {

                  }

                }

              }

            }

            mc FALSE

            undefinedNode FALSE

          }

          h245Address ipAddress :

          {

            ip 'C0A800FA'H

            port 45122

          }

          callIdentifier

          {

            guid '00F08B33C92D6112E80FD301C0A80173'H

          }

          multipleCalls FALSE

          maintainConnection FALSE

        }

        h245Tunneling FALSE

      }

    }

019741: *Feb 21 14:42:55.536: H225.0 OUTGOING ENCODE BUFFER::= 21C0060008914A00042800B500001240013C05010000C0A800FAB042110C110000F08B33C92D6112E80FD301C0A801730100010010800100

019742: *Feb 21 14:42:55.536:

019743: *Feb 21 14:42:55.540: //26748/00F08B33E80F/CCAPI/cc_api_call_proceeding:

   Interface=0x46A6ADA0, Progress Indication=NULL(0)

019744: *Feb 21 14:42:56.792: //26748/00F08B33E80F/CCAPI/cc_api_call_disconnected:

   Cause Value=34, Interface=0x46A6ADA0, Call Id=26748

019745: *Feb 21 14:42:56.792: //26748/00F08B33E80F/CCAPI/cc_api_call_disconnected:

   Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)

019746: *Feb 21 14:42:56.792: //26747/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:

   release reserved xcoding resource.

019747: *Feb 21 14:42:56.792: //26748/00F08B33E80F/CCAPI/ccCallSetAAA_Accounting:

   Accounting=1, Call Id=26748

019748: *Feb 21 14:42:56.792: //26748/00F08B33E80F/CCAPI/ccCallDisconnect:

   Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=34)

019749: *Feb 21 14:42:56.792: //26748/00F08B33E80F/CCAPI/ccCallDisconnect:

   Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)

019750: *Feb 21 14:42:56.800: //26748/00F08B33E80F/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x46A6ADA0, Tag=0x0, Call Id=26748,

   Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry Count=0)

019751: *Feb 21 14:42:56.800: //26748/00F08B33E80F/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

019752: *Feb 21 14:42:56.800: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

019753: *Feb 21 14:42:56.800: :cc_free_feature_vsa freeing 46A474A0

019754: *Feb 21 14:42:56.800: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

019755: *Feb 21 14:42:56.800:  vsacount in free is 1

019756: *Feb 21 14:42:56.800: //26747/00F08B33E80F/CCAPI/ccCallSetupRequest:

   Destination=, Calling IE Present=TRUE, Mode=0,

   Outgoing Dial-peer=901, Params=0x45FF0390, Progress Indication=NULL(0)

019757: *Feb 21 14:42:56.800: //26747/00F08B33E80F/CCAPI/ccCheckClipClir:

   In: Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

019758: *Feb 21 14:42:56.800: //26747/00F08B33E80F/CCAPI/ccCheckClipClir:

   Out: Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

019759: *Feb 21 14:42:56.800: //26747/00F08B33E80F/CCAPI/ccCallSetupRequest:

   Destination Pattern=0[7-9]T, Called Number=097161232, Digit Strip=TRUE

019760: *Feb 21 14:42:56.804: //26747/00F08B33E80F/CCAPI/ccCallSetupRequest:

   Calling Number=6028(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

   Called Number=097161232(TON=Unknown, NPI=Unknown),

   Redirect Number=, Display Info=

   Account Number=Ana Paula, Final Destination Flag=TRUE,

   Guid=00F08B33-C92D-6112-E80F-D301C0A80173, Outgoing Dial-peer=901

019761: *Feb 21 14:42:56.804: //26747/00F08B33E80F/CCAPI/cc_api_display_ie_subfields:

   ccCallSetupRequest:

   cisco-username=Ana Paula

   ----- ccCallInfo IE subfields -----

   cisco-ani=6028

   cisco-anitype=0

   cisco-aniplan=0

   cisco-anipi=0

   cisco-anisi=1

   dest=097161232

   cisco-desttype=0

   cisco-destplan=0

   cisco-rdie=FFFFFFFF

   cisco-rdn=

   cisco-rdntype=-1

   cisco-rdnplan=-1

   cisco-rdnpi=-1

   cisco-rdnsi=-1

   cisco-redirectreason=-1   fwd_final_type =0

   final_redirectNumber =

   hunt_group_timeout =0

019762: *Feb 21 14:42:56.804: //26747/00F08B33E80F/CCAPI/ccIFCallSetupRequestPrivate:

   Interface=0x472DC084, Interface Type=6, Destination=, Mode=0x0,

   Call Params(Calling Number=6028,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

   Called Number=097161232

R2811_MF_RS#(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

   Subscriber Type Str=Unknown, FinalDestinationFlag=TRUE, Outgoing Dial-peer=901, Call Count On=FALSE,

   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

019763: *Feb 21 14:42:56.804: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019764: *Feb 21 14:42:56.804: :cc_get_feature_vsa malloc success

019765: *Feb 21 14:42:56.804: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019766: *Feb 21 14:42:56.804:  cc_get_feature_vsa count is 2

019767: *Feb 21 14:42:56.804: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

019768: *Feb 21 14:42:56.804: :FEATURE_VSA attributes are: feature_name:0,feature_time:1185182888,feature_id:26749

019769: *Feb 21 14:42:56.804: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=26747

019770: *Feb 21 14:42:56.804: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_SET_MODE: data ptr=0x498428C0

019771: *Feb 21 14:42:56.804: //-1/xxxxxxxxxxxx/H323/cch323_set_mode: callID=26747, flow Mode=1 spi_mode=0x6

019772: *Feb 21 14:42:56.804: //26749/00F08B33E80F/CCAPI/ccIFCallSetupRequestPrivate:

   SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

019773: *Feb 21 14:42:56.804: //26749/00F08B33E80F/CCAPI/ccCallSetContext:

   Context=0x45FF0340

019774: *Feb 21 14:42:56.804: //26747/00F08B33E80F/CCAPI/ccSaveDialpeerTag:

   Outgoing Dial-peer=901

019775: *Feb 21 14:42:56.804: //26747/00F08B33E80F/H323/cch323_do_set_dialpeer_tag: gw_id=1

019776: *Feb 21 14:42:56.812: //26749/00F08B33E80F/CCAPI/cc_api_call_proceeding:

   Interface=0x472DC084, Progress Indication=NULL(0)

R2811_MF_RS#

019777: *Feb 21 14:43:00.024: //26749/00F08B33E80F/CCAPI/cc_api_call_alert:

   Interface=0x472DC084, Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1)

019778: *Feb 21 14:43:00.028: //26749/00F08B33E80F/CCAPI/cc_api_call_alert:

   Call Entry(Retry Count=0, Responsed=TRUE)

019779: *Feb 21 14:43:00.028: //26747/00F08B33E80F/CCAPI/ccCallAlert:

   Progress Indication=INBAND(8), Signal Indication=SIGNAL RINGBACK(1)

019780: *Feb 21 14:43:00.028: //26747/00F08B33E80F/CCAPI/ccCallAlert:

   Call Entry(Responsed=TRUE, AlertSent=TRUE)

019781: *Feb 21 14:43:00.028: //26749/00F08B33E80F/CCAPI/cc_api_get_called_ccm_detected:

   CallInfo(ccm detected=0)

019782: *Feb 21 14:43:00.028: //26747/00F08B33E80F/CCAPI/ccCallNotify:

   Data Bitmask=0x7, Call Id=26747

019783: *Feb 21 14:43:00.028: //26749/00F08B33E80F/CCAPI/cc_api_get_called_ccm_detected:

   CallInfo(ccm detected=0)

019784: *Feb 21 14:43:00.028: //-1/xxxxxxxxxxxx/H323/cch323_post_call_service_msg: callID=26747, notify data mask=0x00000007

019785: *Feb 21 14:43:00.028: //26747/xxxxxxxxxxxx/CCAPI/ccConferenceCreate:

   (confID=0x46039B2C, callID1=0x687B, callID2=0x687D, tag=0x0)

019786: *Feb 21 14:43:00.028: //26747/00F08B33E80F/CCAPI/ccConferenceCreate:

   Conference Id=0x46039B2C, Call Id1=26747, Call Id2=26749, Tag=0x0

019787: *Feb 21 14:43:00.028: //26747/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

019788: *Feb 21 14:43:00.028: cc_api_get_xcode_stream : 4369

019789: *Feb 21 14:43:00.028: //26747/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:

   Conference Id=0x2FD2, Source Interface=0x47158B60, Source Call Id=26747,

   Destination Call Id=26749, Disposition=0x0, Tag=0x0

019790: *Feb 21 14:43:00.032: //26749/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done:

   Conference Id=0x2FD2, Source Interface=0x472DC084, Source Call Id=26749,

   Destination Call Id=26747, Disposition=0x0, Tag=0xFFFFFFFF

019791: *Feb 21 14:43:00.032: //26747/00F08B33E80F/CCAPI/cc_generic_bridge_done:

   Conference Id=0x2FD2, Source Interface=0x472DC084, Source Call Id=26749,

   Destination Call Id=26747, Disposition=0x0, Tag=0xFFFFFFFF

019792: *Feb 21 14:43:00.032: //26747/00F08B33E80F/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x2FD2, Destination Call Id=26749)

019793: *Feb 21 14:43:00.032: //26749/00F08B33E80F/CCAPI/ccConferenceCreate:

   Call Entry(Conference Id=0x2FD2, Destination Call Id=26747)

019794: *Feb 21 14:43:00.032: //26749/00F08B33E80F/CCAPI/cc_api_caps_ind:

   Destination Interface=0x47158B60, Destination Call Id=26747, Source Call Id=26749,

   Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,

   Modem=0x2, Codec Bytes=20, Signal Type=3)

019795: *Feb 21 14:43:00.032: //26749/00F08B33E80F/CCAPI/cc_api_caps_ind:

   Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

   Playout Max=250(ms), Fax Nom=300(ms))

019796: *Feb 21 14:43:00.032: //-1/xxxxxxxxxxxx/H323/cch323_post_caps_ind: callID=26747, ExtendedCaps not present

019797: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/cch323_call_alert: gw_id=1

019798: *Feb 21 14:43:00.032: //26747/00F08B33E80F/CCAPI/cc_api_get_delay_xport:

   CallInfo(delay xport=FALSE)

019799: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/cch323_put_embedded_obj_to_ccb: ccb=0x4603A824, tag=18, size=83

019800: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/cch323_put_embedded_obj_to_ccb: Insertion PASSED, embedded at 0x4822B0D0

019801: *Feb 21 14:43:00.032: //-1/xxxxxxxxxxxx/H323/cch323_call_alert: stored alert data display_info  in ccb

019802: *Feb 21 14:43:00.032: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H225 SM

019803: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_ALERT while at state H225_CALLPROC

019804: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/cch323_h225_set_new_state: Changing from H225_CALLPROC state to H225_ALERT state

019805: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/delay_h245_transport_address: CCM-ITS compatibility delay transport address for callid[687B]

019806: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/generic_send_alert: ====== PI = 8

019807: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/cch323_get_embedded_obj_from_ccb: ccb=0x4603A824, tag=18, size=83

019808: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/cch323_get_embedded_obj_from_ccb: Extraction PASSED from 0x4822B0D0

019809: *Feb 21 14:43:00.032: //26747/00F08B33E80F/H323/generic_send_alert: get ALERT displayInfo

019810: *Feb 21 14:43:00.036: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=

    {

      h323-uu-pdu

      {

        h323-message-body alerting :

        {

          protocolIdentifier { 0 0 8 2250 0 4 }

          destinationInfo

          {

            vendor

            {

              vendor

              {

                t35CountryCode 181

                t35Extension 0

                manufacturerCode 18

              }

            }

            gateway

            {

              protocol

              {

                voice :

                {

                  supportedPrefixes

                  {

                  }

                }

              }

            }

            mc FALSE

            undefinedNode FALSE

          }

          h245Address ipAddress :

          {

            ip 'C0A800FA'H

            port 45122

          }

          callIdentifier

          {

            guid '00F08B33C92D6112E80FD301C0A80173'H

          }

          multipleCalls FALSE

          maintainConnection FALSE

        }

        h245Tunneling FALSE

      }

    }

019811: *Feb 21 14:43:00.040: H225.0 OUTGOING ENCODE BUFFER::= 23C0060008914A00042800B500001240013C05010000C0A800FAB0421B0C00110000F08B33C92D6112E80FD301C0A801730100010010800100

019812: *Feb 21 14:43:00.040:

019813: *Feb 21 14:43:00.040: //-1/xxxxxxxxxxxx/H323/cch323_do_call_notify: gw_id=1, callID=26747, cs_data present

019814: *Feb 21 14:43:00.040: //26747/00F08B33E80F/H323/cch323_put_embedded_obj_to_ccb: ccb=0x4603A824, tag=15, size=128

019815: *Feb 21 14:43:00.040: //26747/00F08B33E80F/H323/cch323_put_embedded_obj_to_ccb: Insertion PASSED, embedded at 0x4822AE90

019816: *Feb 21 14:43:00.040: //-1/xxxxxxxxxxxx/H323/cch323_do_call_notify: stored notify_data.display_info  in ccb

019817: *Feb 21 14:43:00.040: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x20 internal event to H225 SM

019818: *Feb 21 14:43:00.040: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_NOTIFY while at state H225_ALERT

019819: *Feb 21 14:43:00.040: //26747/00F08B33E80F/H323/send_notify_msg: Notify data found, mask=0x00000007

019820: *Feb 21 14:43:00.040: //26747/00F08B33E80F/H323/send_notify_msg: Sending NOTIFY Display Info IE =

019821: *Feb 21 14:43:00.040: //26747/00F08B33E80F/H323/send_notify_msg: Sending NOTIFY Notification Indicator IE = 113

019822: *Feb 21 14:43:00.040: //26747/00F08B33E80F/H323/send_notify_msg: Sending NOTIFY Connected Number as IE

019823: *Feb 21 14:43:00.044: //26747/00F08B33E80F/H323/send_notify_msg: [cnum]/[oct]/[oct3a] = [097161232]/[0x00]/[0x00]

019824: *Feb 21 14:43:00.044: H225.0 OUTGOING PDU ::=

value H323_UserInformation ::=

    {

      h323-uu-pdu

      {

        h323-message-body notify :

        {

          protocolIdentifier { 0 0 8 2250 0 4 }

          callIdentifier

          {

            guid '00F08B33C92D6112E80FD301C0A80173'H

          }

        }

        h245Tunneling FALSE

      }

    }

019825: *Feb 21 14:43:00.044: H225.0 OUTGOING ENCODE BUFFER::= 28501900060008914A00040000F08B33C92D6112E80FD301C0A8017310800100

019826: *Feb 21 14:43:00.044:

019827: *Feb 21 14:43:00.044: //26747/00F08B33E80F/H323/h323_open_rtp_stream: Media In-active notification object not attached to ccb

019828: *Feb 21 14:43:00.044: //26747/00F08B33E80F/H323/cch323_set_dtmf_iw_enabled: negotiated dtmf relay: 0, dtmf_iw_enabled: 0, dtmf_sccp_enabled: 0

019829: *Feb 21 14:43:00.044: //26747/00F08B33E80F/H323/cch323_caps_ind: gw_id=1

019830: *Feb 21 14:43:00.044: //26747/00F08B33E80F/H323/cch323_peer_caps_ind_common: Load DSP with Preferred codec(6) g711alaw, Bytes=160

019831: *Feb 21 14:43:00.044: //26747/00F08B33E80F/H323/cch323_peer_caps_ind_common: Set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_INBAND_VOICE

019832: *Feb 21 14:43:00.044: //26747/00F08B33E80F/CCAPI/cc_process_notify_bridge_done:

   Conference Id=0x2FD2, Call Id1=26747, Call Id2=26749

019833: *Feb 21 14:43:00.092: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

019834: *Feb 21 14:43:00.092: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x484D9450, len=46, msgPtr=0x4838D924

019835: *Feb 21 14:43:00.092: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

019836: *Feb 21 14:43:00.092: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A00051100110000F08B33C92D6112E80FD301C0A8017310800100

019837: *Feb 21 14:43:00.096:

019838: *Feb 21 14:43:00.096: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=

    {

      h323-uu-pdu

      {

        h323-message-body releaseComplete :

        {

          protocolIdentifier { 0 0 8 2250 0 5 }

          callIdentifier

          {

            guid '00F08B33C92D6112E80FD301C0A80173'H

          }

        }

        h245Tunneling FALSE

      }

    }

019839: *Feb 21 14:43:00.096: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN

019840: *Feb 21 14:43:00.096: //26747/00F08B33E80F/H323/release_ind: Disconnect cause 47 location code 1

019841: *Feb 21 14:43:00.096: //-1/xxxxxxxxxxxx/H323/h323_set_release_source_for_peer: ownCallId[26747], src[2]

019842: *Feb 21 14:43:00.096: //26747/00F08B33E80F/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 192.168.0.250; dest address = 192.168.1.207

019843: *Feb 21 14:43:00.096: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_ALERT

019844: *Feb 21 14:43:00.096: //26747/00F08B33E80F/H323/cch323_traverse_enum_contact_list: State = 3

019845: *Feb 21 14:43:00.096: //26747/00F08B33E80F/CCAPI/cc_api_call_disconnected:

   Cause Value=47, Interface=0x47158B60, Call Id=26747

019846: *Feb 21 14:43:00.096: //26747/00F08B33E80F/CCAPI/cc_api_call_disconnected:

   Call Entry(Responsed=TRUE, Cause Value=47, Retry Count=0)

019847: *Feb 21 14:43:00.100: TCP0: FIN processed

019848: *Feb 21 14:43:00.100: TCP0: state was ESTAB -> CLOSEWAIT [1720 -> 192.168.1.207(34297)]

019849: *Feb 21 14:43:00.100: //26747/00F08B33E80F/CCAPI/ccConferenceDestroy:

   Conference Id=0x2FD2, Tag=0x0

019850: *Feb 21 14:43:00.100: //26747/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x2FD2, Source Interface=0x47158B60, Source Call Id=26747,

   Destination Call Id=26749, Disposition=0x0, Tag=0x0

019851: *Feb 21 14:43:00.100: //26749/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done:

   Conference Id=0x2FD2, Source Interface=0x472DC084, Source Call Id=26749,

   Destination Call Id=26747, Disposition=0x0, Tag=0x0

019852: *Feb 21 14:43:00.100: //26747/00F08B33E80F/CCAPI/cc_generic_bridge_done:

   Conference I

R2811_MF_RS#d=0x2FD2, Source Interface=0x472DC084, Source Call Id=26749,

   Destination Call Id=26747, Disposition=0x0, Tag=0x0

019853: *Feb 21 14:43:00.100: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

019854: *Feb 21 14:43:00.100: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x484D9450, len=46, msgPtr=0x4838D924

019855: *Feb 21 14:43:00.100: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

019856: *Feb 21 14:43:00.104: H225.0 INCOMING ENCODE BUFFER::= 2580060008914A00051100110000F08B33C92D6112E80FD301C0A8017310800100

019857: *Feb 21 14:43:00.104:

019858: *Feb 21 14:43:00.104: H225.0 INCOMING PDU ::=

value H323_UserInformation ::=

    {

      h323-uu-pdu

      {

        h323-message-body releaseComplete :

        {

          protocolIdentifier { 0 0 8 2250 0 5 }

          callIdentifier

          {

            guid '00F08B33C92D6112E80FD301C0A80173'H

          }

        }

        h245Tunneling FALSE

      }

    }

019859: *Feb 21 14:43:00.104: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN

019860: *Feb 21 14:43:00.104: //26747/00F08B33E80F/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 192.168.0.250; dest address = 192.168.1.207

019861: *Feb 21 14:43:00.104: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_ALERT

019862: *Feb 21 14:43:00.104: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

019863: *Feb 21 14:43:00.104: TCP0: state was CLOSEWAIT -> LASTACK [1720 -> 192.168.1.207(34297)]

019864: *Feb 21 14:43:00.104: TCP0: sending FIN

019865: *Feb 21 14:43:00.104: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 2

019866: *Feb 21 14:43:00.108: //-1/xxxxxxxxxxxx/H323/cch323_cleanup_xport: hashDestroy for TcpFDTbl

019867: *Feb 21 14:43:00.108: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2D internal event to H225 SM

019868: *Feb 21 14:43:00.108: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_CONN_LOST while at state H225_ALERT

019869: *Feb 21 14:43:00.108: //26749/00F08B33E80F/CCAPI/ccCallDisconnect:

   Cause Value=47, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

019870: *Feb 21 14:43:00.108: //26749/00F08B33E80F/CCAPI/ccCallDisconnect:

   Cause Value=47, Call Entry(Responsed=TRUE, Cause Value=47)

019871: *Feb 21 14:43:00.108: //26749/00F08B33E80F/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

019872: *Feb 21 14:43:00.112: //26747/00F08B33E80F/CCAPI/ccCallDisconnect:

   Cause Value=47, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=47)

019873: *Feb 21 14:43:00.112: //26747/00F08B33E80F/CCAPI/ccCallDisconnect:

   Cause Value=47, Call Entry(Responsed=TRUE, Cause Value=47)

019874: *Feb 21 14:43:00.112: //26747/00F08B33E80F/CCAPI/cc_api_get_transfer_info:

   Transfer Number Is Null

019875: *Feb 21 14:43:00.116: //26749/00F08B33E80F/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x472DC084, Tag=0x0, Call Id=26749,

   Call Entry(Disconnect Cause=47, Voice Class Cause Code=0, Retry Count=0)

019876: *Feb 21 14:43:00.116: //26749/00F08B33E80F/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

019877: *Feb 21 14:43:00.116: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

019878: *Feb 21 14:43:00.116: :cc_free_feature_vsa freeing 46A474A0

019879: *Feb 21 14:43:00.116: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

019880: *Feb 21 14:43:00.116:  vsacount in free is 1

019881: *Feb 21 14:43:00.116: //26747/00F08B33E80F/H323/cch323_do_call_disconnect: gw_id=1, discCause=47

019882: *Feb 21 14:43:00.116: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM

019883: *Feb 21 14:43:00.116: //26747/00F08B33E80F/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ALERT

019884: *Feb 21 14:43:00.116: //26747/00F08B33E80F/H323/cch323_h225_set_new_state: Changing from H225_ALERT state to H225_IDLE state

019885: *Feb 21 14:43:00.116: //26747/00F08B33E80F/H323/cch323_call_generic_cleanup: Disconnected flag 1

019886: *Feb 21 14:43:00.116: //26747/00F08B33E80F/H323/cch323_store_call_history_entry: Trying to store call history/remove call_info while RTP session exists

019887: *Feb 21 14:43:00.116: //26747/00F08B33E80F/H323/cch323_store_call_history_entry: Call Active Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300

019888: *Feb 21 14:43:00.116: //26747/00F08B33E80F/H323/cch323_store_call_history_entry: Call History Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300

019889: *Feb 21 14:43:00.120: //26747/00F08B33E80F/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_IDLE

019890: *Feb 21 14:43:00.120: //26747/00F08B33E80F/H323/defaultHdlr: DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE

019891: *Feb 21 14:43:00.120: Released port 45122 in Transport Port Agent for TCP IP type 0 delay 240000

019892: *Feb 21 14:43:00.120: TCP0: state was LISTEN -> CLOSED [45122 -> UNKNOWN(0)]

019893: *Feb 21 14:43:00.120: TCB 0x48050EC4 destroyed

019894: *Feb 21 14:43:00.120: //-1/xxxxxxxxxxxx/H323/h323_gw_clean_send_blocked_watch: fd 3

019895: *Feb 21 14:43:00.120: //26747/00F08B33E80F/CCAPI/cc_api_call_disconnect_done:

   Disposition=0, Interface=0x47158B60, Tag=0x0, Call Id=26747,

   Call Entry(Disconnect Cause=47, Voice Class Cause Code=0, Retry Count=0)

019896: *Feb 21 14:43:00.120: //26747/00F08B33E80F/CCAPI/cc_api_call_disconnect_done:

   Call Disconnect Event Sent

019897: *Feb 21 14:43:00.120: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

019898: *Feb 21 14:43:00.120: :cc_free_feature_vsa freeing 46A47F98

019899: *Feb 21 14:43:00.120: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

019900: *Feb 21 14:43:00.120:  vsacount in free is 0

019901: *Feb 21 14:43:00.120: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x4603A824)

019902: *Feb 21 14:43:00.144: TCP0: Got ACK for our FIN

019903: *Feb 21 14:43:00.144: TCP0: state was LASTACK -> CLOSED [1720 -> 192.168.1.207(34297)]

019904: *Feb 21 14:43:00.144: Released port 1720 in Transport Port Agent for TCP IP type 1 delay 240000

019905: *Feb 21 14:43:00.144: TCB 0x4851BB9C destroyed

R2811_MF_RS#

019906: *Feb 21 14:43:21.960: TCB47FB4454 setting property TCP_TOS (11) 48555EE0

019907: *Feb 21 14:43:22.208: TCP0: ACK timeout timer expired

R2811_MF_RS#

R2811_MF_RS#

R2811_MF_RS#

R2811_MF_RS#

019908: *Feb 21 14:43:51.960: TCB47FB4454 setting property TCP_TOS (11) 48555EE0

019909: *Feb 21 14:43:52.204: TCP0: ACK timeout timer expired

R2811_MF_RS#

R2811_MF_RS#

R2811_MF_RS#

Labels:
0 Helpful
6 Replies 6

Bruno Rangel
Spotlight
Spotlight

‎03-01-2013 06:59 PM

Hi Martin

Just checked your output, I saw Cause code 47 is "No Resources" It can usually be traced to either a lack of DSP resources, channels, or more often than not a codec issue.

Also found a Cause code 34, this is a very common cause code and happens when the network is not able to determine what to do with the call being made.No Circuit/Channel Available.

Check the region of your MTP and Annunciator against the region of your H323 trunk, and see if you will need to transcode.  If so, make sure you have a transcoder available.

Cheers
Bruno Rangel

"Se você quiser alguém em quem confiar, confie em si mesmo. Quem acredita sempre alcança"
Renato Russo

Cheers
Bruno Rangel
Please remember to rate helpful responses using the star bellow and identify helpful or correct answers

Martin Bauer
Level 1
Level 1
In response to Bruno Rangel

‎03-07-2013 06:09 AM

I would like to thank you both for time spent with this situation. Sometimes what we need is just a push to start walkig.

Thanks for your ideas I could realize the problem is (still is) something to do with codec issue. By changing the device pool, i could make calls.

Now I'm going to study better this configs to first understand the working flow and manage the options to the right concept.

Thanks again.

Mártin

0 Helpful

Ayodeji Okanlawon
VIP Alumni
VIP Alumni

‎03-01-2013 07:57 PM

Can you describe your scenario. Can you alas send your config. Looks like the gateway attempted to use g711a for the call.

What is the region setting between the phone and the gateway?
What codec is your inbound dial-peer and outbound dial-peer set to


Sent from Cisco Technical Support Android App

Please rate all useful posts

Martin Bauer
Level 1
Level 1
In response to Ayodeji Okanlawon

‎03-04-2013 08:44 AM

Hello aokanlawon, the scenario is: IPCom --> CUCM --> Router 2811

In the figure I post above there is a better view of scenario.

This is the gateway config:

R2811_MF_RS#sh run

Building configuration...

Current configuration : 10126 bytes

!

version 12.4

no service pad

service tcp-keepalives-in

service tcp-keepalives-out

service timestamps debug datetime msec localtime

service timestamps log datetime msec localtime

service password-encryption

service sequence-numbers

!

hostname R2811_MF_RS

!

boot-start-marker

boot system flash:c2800nm-entservicesk9-mz.124-15.T6.bin

boot-end-marker

!

card type e1 0 1

logging buffered 8192

logging console emergencies

enable secret 5 $1$H2R5$H/EXELkoAN8OXvHITtUzH/

enable password 7 096F471A1A0A

!

no aaa new-model

network-clock-participate wic 1

network-clock-select 1 E1 0/1/0

dot11 syslog

no ip source-route

no ip gratuitous-arps

!

!

ip cef

!

!

no ip bootp server

no ip domain lookup

multilink bundle-name authenticated

!

!

stcapp feature access-code

!

!

voice-card 0

dspfarm

dsp services dspfarm

!

!

voice call send-alert

voice call convert-discpi-to-prog

voice call carrier capacity active

voice rtp send-recv

!

voice service pots

supported-language pt

!

voice service voip

no fax-relay sg3-to-g3

h323

!

!

voice class codec 1

codec preference 1 g711alaw

codec preference 2 g711ulaw

codec preference 3 g729r8

codec preference 4 g729br8

!

!

!

voice class h323 1

h225 timeout tcp establish 5

!

!

!

!

!

!

!

!

!

!

voice translation-rule 1

rule 1 /^5\(..$\)/ /95\1/

!

voice translation-rule 2

rule 1 /^00/ /021/

!

voice translation-rule 3

rule 1 /^0/ //

!

voice translation-rule 14

rule 1 /^00/ /014/

!

voice translation-rule 1551

rule 1 /^01551/ //

!

!

voice translation-profile 95xx

translate called 1

!

voice translation-profile RETIRA51

translate called 1551

!

voice translation-profile SRST

translate called 2

!

voice translation-profile SRST_014

translate called 14

!

voice translation-profile SRST_LOCAL

translate called 3

!

!

!

!

!

!

username telsinc privilege 15 password 7 111A090014061903092B25

username admin privilege 15 password 7 00091526560B5B51

username nonononono 5 password 7 151F041E012C272130

archive

log config

  hidekeys

!

!

controller E1 0/1/0

framing NO-CRC4

ds0-group 0 timeslots 1-15,17-31 type r2-digital r2-compelled ani

cas-custom 0

  country brazil

  metering

  double-answer

  category 2

  answer-signal group-b 1

  request-category 4

  dnis-digits min 1 max 65

  ani-digits min 1 max 65

  dnis-complete

!

ip tcp selective-ack

ip tcp path-mtu-discovery

ip tftp source-interface FastEthernet0/0

!

class-map match-all SCCP

match protocol skinny

class-map match-any VOIP_Class

match access-group name VOZ_ACL

class-map match-any SIGNAL_VOZ_VIDEO_Class

match access-group name SIGNAL_VOZ_VIDEO

class-map match-all H323

match protocol h323

!

!

!

!

!

interface Loopback0

description Usada pelo Music On Hold

ip address 1.1.1.1 255.255.255.255

!

interface FastEthernet0/0

ip address 192.168.0.250 255.255.255.0

duplex auto

speed auto

h323-gateway voip interface

h323-gateway voip bind srcaddr 192.168.0.250

!

interface FastEthernet0/1

no ip address

shutdown

duplex auto

speed auto

!

interface Serial0/0/0

mtu 1100

no ip address

encapsulation frame-relay IETF

!

interface Serial0/0/0.111 point-to-point

ip address 189.56.172.102 255.255.255.252

snmp trap link-status

frame-relay interface-dlci 111 CISCO

frame-relay ip rtp header-compression

!

interface Serial0/0/1

no ip address

shutdown

clock rate 2000000

!

ip forward-protocol nd

ip route 0.0.0.0 0.0.0.0 192.168.0.1

ip route 189.56.217.176 255.255.255.252 189.56.172.101

ip route 192.168.1.0 255.255.255.0 189.56.172.101 name rede

!

!

no ip http server

ip http authentication local

no ip http secure-server

!

ip access-list extended SIGNAL_VOZ_VIDEO

permit ip any any dscp af31

permit ip any any dscp cs3

permit tcp any any eq 5060

permit udp any any eq 5060

permit tcp any any eq 1720

permit udp any any eq 1719

permit udp any any eq 1718

permit tcp any any range 2000 2004

ip access-list extended VOZ_ACL

permit ip 192.168.0.0 0.0.0.255 192.168.1.0 0.0.0.255 dscp ef

permit udp 192.168.0.0 0.0.0.255 192.168.1.0 0.0.0.255 range 14384 32768

!

!

map-class frame-relay FR_QOS

frame-relay cir 1024000

frame-relay bc 10240

frame-relay mincir 1024000

access-list 23 remark CONTROLE DE ACESSO TELNET

access-list 23 permit 192.168.0.0 0.0.255.255

access-list 23 deny   any log

!

!

!

control-plane

!

!

!

voice-port 0/1/0:0

cptone BR

!

voice-port 0/2/0

cptone BR

timeouts call-disconnect 0

timeouts wait-release 5

connection plar 9521

!

voice-port 0/2/1

cptone BR

timeouts call-disconnect 0

timeouts wait-release 5

connection plar 9521

!

voice-port 0/2/2

cptone BR

timeouts call-disconnect 0

timeouts wait-release 5

connection plar 9521

!

voice-port 0/2/3

supervisory disconnect anytone

cptone BR

connection plar 9521

!

voice-port 0/3/0

cptone BR

connection plar 9521

!

voice-port 0/3/1

cptone BR

connection plar 9521

!

voice-port 0/3/2

cptone BR

connection plar 9521

!

voice-port 0/3/3

cptone BR

connection plar 9521

!

ccm-manager music-on-hold

!

!

sccp local FastEthernet0/0

sccp ccm 192.168.1.207 identifier 1 version 5.0.1

sccp

!

sccp ccm group 1

associate ccm 1 priority 1

associate profile 2 register CFB_POA

associate profile 1 register XCODE_RS

!

dspfarm profile 1 transcode

codec g711ulaw

codec g711alaw

codec g729ar8

codec g729abr8

codec g729r8

codec g729br8

maximum sessions 3

associate application SCCP

!

dspfarm profile 2 conference

codec g711ulaw

codec g711alaw

codec g729ar8

codec g729abr8

codec g729r8

codec g729br8

maximum sessions 2

associate application SCCP

!

dial-peer cor custom

name int

name local

name ld

name intl

!

!

dial-peer cor list int

member int

!

dial-peer cor list local

member local

!

dial-peer cor list ld

member ld

!

dial-peer cor list intl

member intl

!

!

dial-peer voice 100 pots

translation-profile incoming 95xx

destination-pattern 0T

progress_ind setup enable 3

incoming called-number .

direct-inward-dial

port 0/1/0:0

!

dial-peer voice 101 pots

preference 2

destination-pattern 0T

direct-inward-dial

port 0/3/0

!

dial-peer voice 102 pots

preference 3

destination-pattern 0T

direct-inward-dial

port 0/3/1

!

dial-peer voice 103 pots

preference 4

destination-pattern 0T

direct-inward-dial

port 0/3/2

!

dial-peer voice 104 pots

preference 5

destination-pattern 0T

direct-inward-dial

port 0/3/3

!

dial-peer voice 950001 voip

description ##CallManager##

destination-pattern 95..

progress_ind setup enable 3

voice-class codec 1

voice-class h323 1

session target ipv4:192.168.1.207

incoming called-number .

dtmf-relay h245-alphanumeric

no vad

!

dial-peer voice 120001 voip

description ##CallManager##

destination-pattern 12..

voice-class codec 1

voice-class h323 1

session target ipv4:192.168.1.207

dtmf-relay h245-alphanumeric

no vad

!

dial-peer voice 900 pots

description ##CELFIX##

preference 1

destination-pattern 0[7-9]T

port 0/2/0

!

dial-peer voice 901 pots

description ##CELFIX##

preference 2

destination-pattern 0[7-9]T

port 0/2/1

!

dial-peer voice 902 pots

description ##CELFIX##

preference 3

destination-pattern 0[7-9]T

port 0/2/2

!

dial-peer voice 903 pots

description ##CELFIX##

preference 4

destination-pattern 0[7-9]T

port 0/2/3

!

dial-peer voice 904 pots

description ##TRONCO##

preference 5

destination-pattern 0[7-9]T

!

dial-peer voice 910 pots

description ##CELFIX##

preference 1

destination-pattern 00..[7-9]T

port 0/2/0

prefix 021

!

dial-peer voice 911 pots

description ##CELFIX##

preference 2

destination-pattern 00..[7-9]T

port 0/2/1

prefix 021

!

dial-peer voice 912 pots

description ##CELFIX##

preference 3

destination-pattern 00..[7-9]T

port 0/2/2

prefix 021

!

dial-peer voice 913 pots

description ##CELFIX##

preference 4

destination-pattern 00..[7-9]T

port 0/2/3

prefix 021

!

dial-peer voice 914 pots

description ##TRONCO##

preference 5

destination-pattern 00..[7-9]T

prefix 021

!

dial-peer voice 920 pots

description ##Tronco##

preference 1

destination-pattern 00..[2-6]T

port 0/1/0:0

prefix 014

!

dial-peer voice 921 pots

description ##Analogica##

preference 2

destination-pattern 00..[2-6]T

port 0/3/0

prefix 014

!

dial-peer voice 922 pots

description ##Analogica##

preference 3

destination-pattern 00..[2-6]T

port 0/3/1

prefix 014

!

dial-peer voice 923 pots

description ##Analogica##

preference 4

destination-pattern 00..[2-6]T

port 0/3/2

prefix 014

!

dial-peer voice 924 pots

description ##Analogica##

preference 5

destination-pattern 00..[2-6]T

port 0/3/3

prefix 014

!

dial-peer voice 930 pots

description ##Tronco##

preference 1

destination-pattern 000T

prefix 0014

!

dial-peer voice 931 pots

description ##Analogica##

destination-pattern 000T

port 0/3/0

prefix 0014

!

dial-peer voice 932 pots

description ##Analogica##

preference 3

destination-pattern 000T

port 0/3/1

prefix 0014

!

dial-peer voice 933 pots

description ##Analogica##

preference 4

destination-pattern 000T

port 0/3/2

prefix 0014

!

dial-peer voice 934 pots

description ##Analogica##

preference 5

destination-pattern 000T

port 0/3/3

prefix 0014

!

dial-peer voice 2 pots

destination-pattern 8#T

direct-inward-dial

port 0/1/0:0

!

!

num-exp 9500 9521

!

call-manager-fallback

secondary-dialtone 0

max-conferences 8 gain -6

transfer-system full-consult

ip source-address 192.168.0.250 port 2000

max-ephones 42

max-dn 80 dual-line

system message primary Trabalhando em modo SRST

system message secondary Modo SRST

transfer-pattern .T

call-forward pattern .T

time-zone 17

time-format 24

date-format dd-mm-yy

!

!

ephone-dn-template  1

!

privilege exec level 5 ping

privilege exec level 5 show stcapp device summary

privilege exec level 5 show stcapp device

privilege exec level 5 show stcapp

privilege exec level 5 show

!

line con 0

session-timeout 10

exec-timeout 30 0

logging synchronous

login local

line aux 0

line vty 0 4

session-timeout 10

exec-timeout 20 0

privilege level 15

password 7 106D000A0618

logging synchronous

login local

notify

transport input telnet

line vty 5 15

session-timeout 10

exec-timeout 20 0

logging synchronous

login local

notify

transport input telnet

!

scheduler allocate 20000 1000

!

end

The H323 trunk is configure with device pool using a different region from the phone.

The gateway uses a Custom Region RS and the phone uses Default region.

Region Relationships

Region                 Audio Codec  Video Call Bandwidth 

Default                 G.711                      None 

RS                      G.729                      None 

SP_ATA-Port 2     G.711                      None 

Bruno, thanks for helping. How I enable transcoder? At the router or CUCM? or both?

0 Helpful

Ayodeji Okanlawon
VIP Alumni
VIP Alumni
In response to Martin Bauer

‎03-07-2013 07:56 AM

Martin,

So here is what I think is going on..

Your incoming dial-peer is set to use voice-class codec..(which advertises G729 and G711)

Your region setting between IPCOM and gateway =G729 (hence this call leg will use g729)

Your outbound call is pots call so that will be g711

So your call flow looks like this..

Inbound--------------------g729-------gateway-----------G711------------PSTN

As you can see in this call you will need a xcoder for this call to work.

The other ATA call that is working has a call flow like this

inbound----=g711---gateway-------g711----PSTN (so that works without a xcoder)

You can either configure your gateway for xcoding or use G711 between your gateway and IPCOM

Please rate all useful posts

"opportunity is a haughty goddess who waste no time with those who are unprepared"

Please rate all useful posts

Bruno Rangel
Spotlight
Spotlight
In response to Ayodeji Okanlawon

‎03-07-2013 12:39 PM

Hi Martin

Just to complete the good finds from aokanlawon (+5 Stars), in order to have a Xcode/Transcode configured look on those links, transcoding is required to enable communication between different Codecs. For example, inbond Calls are G.711 only and will not be reachable from a side if Inter-region codec has been set as G.729. To make this communication possible, a transcoder sitting at terminating gateway do media conversion from one codec to another.

IOS Transconding

CUCM XCODE

Cheers
Bruno Rangel

"Se você quiser alguém em quem confiar, confie em si mesmo. Quem acredita sempre alcança"
Renato Russo

Cheers
Bruno Rangel
Please remember to rate helpful responses using the star bellow and identify helpful or correct answers
Customers Also Viewed These Support Documents