cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1005
Views
0
Helpful
16
Replies

Hunt group but the final number is located in another site

ievalleyccie
Level 1
Level 1

The customer has site-1 with hunt group (pilot 1000 and list 1100,1101 and the final number is 4501)  but the final number is in another site (Site-2)

when i make a call to 1000 it goes first to 1100 then 1101 but it gives busy tone when it should dial 4501 )

We can access 4501 through voip dial peer

Should i use another method other than the hunt group to fulfill that or what ?

16 Replies 16

Chris Deren
Hall of Fame
Hall of Fame

I take it you have CME systems.  You can try defining num-exp or building another ephone-dn with forward all setting set to 4501.

Chris

I tried the following but it still give busy

ephone-hunt 1 sequential

pilot 1000

list 1100,1101

final 4444

timeout 5

ephone-dn  4

number 4444

call-forward all 4501

even with the 

num-exp 4444 4501

From CME Admin guide:

final final-number

Defines the last number in the ephone hunt group, after which the call  is no longer redirected. Can be an ephone-dn primary or secondary  number, a voice-mail pilot number, a pilot number of another hunt group,  or an FXS number.

Note When  a final number is defined as a pilot number of another hunt group, the  pilot number of the first hunt group cannot be configured as a final  number in any other hunt group.

So, it does not look like you would be able to send it to another system.

Chris

Chris,

one should be able to use whatever destionation you want, as final number. Maybe some debug voip dialpeer and/or relevant traces would clarify why isn't working.

Is there any other method to do the same object other than hunt group ?

Here's the debug with the following config

ephone-hunt 1 sequential

pilot 1000

list 1111, 1112

final 4444

timeout 5

num-exp 4444 3334

*Mar  1 00:12:27.691: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Calling Number=3333, Called Number=1000, Voice-Interface=0x0,

   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

   Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.695: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=1000

*Mar  1 00:12:27.699: //-1/D761793B8008/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Mar  1 00:12:27.703: //-1/D761793B8008/DPM/dpMatchCore:

   Dial String=1000, Expanded String=1000, Calling Number=

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.707: //-1/D761793B8008/DPM/dpMatchCore:

   Result=-1

*Mar  1 00:12:27.711: //-1/D761793B8008/DPM/dpMatchPeertype:exit@5398

*Mar  1 00:12:27.711: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_ANSWER; Calling Number=3333

*Mar  1 00:12:27.715: //-1/D761793B8008/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Mar  1 00:12:27.715: //-1/D761793B8008/DPM/dpMatchCore:

   Dial String=, Expanded String=, Calling Number=3333T

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.719: //-1/D761793B8008/DPM/dpMatchCore:

   Result=-1

*Mar  1 00:12:27.723: //-1/D761793B8008/DPM/dpMatchPeertype:exit@5398

*Mar  1 00:12:27.723: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_ORIGINATE; Calling Number=3333

*Mar  1 00:12:27.727: //-1/D761793B8008/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Mar  1 00:12:27.731: //-1/D761793B8008/DPM/dpMatchCore:

   Dial String=, Expanded String=, Calling Number=3333T

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.735: //-1/D761793B8008/DPM/MatchNextPeer:

   Result=Success(0); Incoming Dial-peer=1 Is Matched

*Mar  1 00:12:27.735: //-1/D761793B8008/DPM/dpMatchPeertype:exit@5398

*Mar  1 00:12:27.735: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=1

*Mar  1 00:12:27.735: //-1/D761793B8008/DPM/dpAssociateIncomingPeerSPI:exit@5932

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Calling Number=3333, Called Number=1000, Voice-Interface=0x0,

   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

   Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_INCOMING_DNIS; Called Number=1000

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchCore:

   Dial String=1000, Expanded String=1000, Calling Number=

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchCore:

   Result=-1

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchPeertype:exit@5398

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_ANSWER; Calling Number=3333

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchCore:

   Dial String=, Expanded String=, Calling Number=3333T

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchCore:

   Result=-1

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchPeertype:exit@5398

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Match Rule=DP_MATCH_ORIGINATE; Calling Number=3333

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchPeertype:

   Is Incoming=TRUE, Number Expansion=FALSE

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchCore:

   Dial String=, Expanded String=, Calling Number=3333T

   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/MatchNextPeer:

   Result=Success(0); Incoming Dial-peer=1 Is Matched

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpMatchPeertype:exit@5398

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpAssociateIncomingPeerCore:

   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=1

*Mar  1 00:12:27.743: //-1/D761793B8008/DPM/dpAssociateIncomingPeerSPI:exit@5932

*Mar  1 00:12:27.783: //-1/D761793B8008/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=1000, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.787: //-1/D761793B8008/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=1000

*Mar  1 00:12:27.787: //-1/D761793B8008/DPM/dpMatchCore:

   Dial String=1000, Expanded String=1000, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.787: //-1/D761793B8008/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=20006 Is Matched

*Mar  1 00:12:27.787: //-1/D761793B8008/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*Mar  1 00:12:27.787: //-1/D761793B8008/DPM/dpMatchPeersMoreArg:

   Result=SUCCESS(0)

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=20006

*Mar  1 00:12:27.787: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=3333, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.787: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=3333

*Mar  1 00:12:27.787: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=3333, Expanded String=3333, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.787: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=1 Is Matched

*Mar  1 00:12:27.787: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*Mar  1 00:12:27.791: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

   Result=SUCCESS(0)

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=1

*Mar  1 00:12:27.799: //-1/D955F59E8011/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=A1000A000, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.803: //-1/D955F59E8011/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=A1000A000

*Mar  1 00:12:27.803: //-1/D955F59E8011/DPM/dpMatchCore:

   Dial String=A1000A000, Expanded String=A1000A000, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:27.807: //-1/D955F59E8011/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=20005 Is Matched

*Mar  1 00:12:27.807: //-1/D955F59E8011/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*Mar  1 00:12:27.807: //-1/D955F59E8011/DPM/dpMatchPeersMoreArg:

   Result=SUCCESS(0)

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=20005

*Mar  1 00:12:32.887: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=3333, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:32.891: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=3333

*Mar  1 00:12:32.891: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=3333, Expanded String=3333, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:32.895: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=1 Is Matched

*Mar  1 00:12:32.895: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*Mar  1 00:12:32.895: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

   Result=SUCCESS(0)

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=1

*Mar  1 00:12:32.967: //-1/DC6A88408013/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=A1000A001, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:32.971: //-1/DC6A88408013/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=A1000A001

*Mar  1 00:12:32.975: //-1/DC6A88408013/DPM/dpMatchCore:

   Dial String=A1000A001, Expanded String=A1000A001, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:32.979: //-1/DC6A88408013/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=20007 Is Matched

*Mar  1 00:12:32.983: //-1/DC6A88408013/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*Mar  1 00:12:32.983: //-1/DC6A88408013/DPM/dpMatchPeersMoreArg:

   Result=SUCCESS(0)

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=20007

*Mar  1 00:12:38.043: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=3333, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:38.047: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=3333

*Mar  1 00:12:38.051: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:

   Dial String=3333, Expanded String=3333, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:38.055: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=1 Is Matched

*Mar  1 00:12:38.059: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*Mar  1 00:12:38.063: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

   Result=SUCCESS(0)

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=1

*Mar  1 00:12:38.131: //-1/DF7E80BF8015/DPM/dpMatchPeersCore:

   Calling Number=, Called Number=4444, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:38.135: //-1/DF7E80BF8015/DPM/dpMatchPeersCore:

   Match Rule=DP_MATCH_DEST; Called Number=4444

*Mar  1 00:12:38.139: //-1/DF7E80BF8015/DPM/dpMatchCore:

   Dial String=4444, Expanded String=3334, Calling Number=

   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH

*Mar  1 00:12:38.143: //-1/DF7E80BF8015/DPM/MatchNextPeer:

   Result=Success(0); Outgoing Dial-peer=1 Is Matched

*Mar  1 00:12:38.147: //-1/DF7E80BF8015/DPM/dpMatchPeersCore:

   Result=Success(0) after DP_MATCH_DEST

*Mar  1 00:12:38.151: //-1/DF7E80BF8015/DPM/dpMatchPeersMoreArg:

   Result=SUCCESS(0)

   List of Matched Outgoing Dial-peer(s):

     1: Dial-peer Tag=1

That is success now need a ccsip message or cch323 trace depending on the protocol you're using.

how can i get that traces?

e.g. debug ccsip message.

*Mar  1 02:00:05.463: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

*Mar  1 02:00:05.467: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66332F1C, len=310, msgPtr=0x65E15638

*Mar  1 02:00:05.471: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

*Mar  1 02:00:05.483: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN

*Mar  1 02:00:05.487: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: CCB not found; allocating one

*Mar  1 02:00:05.491: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x643E22C8) is in use

*Mar  1 02:00:05.491: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry

*Mar  1 02:00:05.495: //42/E051C577802F/H323/setup_ind: callingNumber[3333] calledNumber[1000]

*Mar  1 02:00:05.499: //42/E051C577802F/H323/setup_ind: ---- calling IE present

*Mar  1 02:00:05.499: //42/E051C577802F/H323/setup_ind: ====== PI = 3

*Mar  1 02:00:05.499: //42/E051C577802F/H323/setup_ind: Receive: infoXCap 0

*Mar  1 02:00:05.499: //42/E051C577802F/H323/setup_ind: Receive: infoXCap ccb 0

*Mar  1 02:00:05.499: //42/E051C577802F/H323/setup_ind: Receive bearer cap infoXRate 16, rateMult 0

*Mar  1 02:00:05.499: //42/E051C577802F/H323/cch323_prnt_rawmsg: (for setup_ind): raw message is 59 bytes:

*Mar  1 02:00:05.499: //42/E051C577802F/H323/cch323_prnt_rawmsg:     1C 39 9E 01 00 03 67 74 64 00 00 00 2E 49 41 4D

*Mar  1 02:00:05.499: //42/E051C577802F/H323/cch323_prnt_rawmsg:     2C 0D 0A 47 43 49 2C 65 30 35 31 63 35 37 37 32

*Mar  1 02:00:05.499: //42/E051C577802F/H323/cch323_prnt_rawmsg:     62 65 65 31 31 64 36 38 30 32 66 39 61 65 35 30

*Mar  1 02:00:05.499: //42/E051C577802F/H323/cch323_prnt_rawmsg:     38 30 65 32 34 39 31 0D 0A 0D 0A

*Mar  1 02:00:05.499: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry

*Mar  1 02:00:05.499: //42/E051C577802F/H323/cch323_determine_source_ip_group_info: No Source IP group match with Src CarrierId/Zone ID

*Mar  1 02:00:05.503: //42/E051C577802F/H323/cch323_determine_source_ip_group_info: No Source IP group matched with rte id/zone_id/acl

*Mar  1 02:00:05.503: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

*Mar  1 02:00:05.503: //42/E051C577802F/H323/cch323_determine_source_ip_group_info: No Source IP group match with Incoming Called Number

*Mar  1 02:00:05.507: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry

*Mar  1 02:00:05.507: //42/E051C577802F/H323/cch323_determine_source_ip_group_info: No Source IP group match with Destination pattern

*Mar  1 02:00:05.507: //42/E051C577802F/H323/setup_ind:

setup_ind: is_overlap = 0, info_complete = 0

*Mar  1 02:00:05.507: //-1/xxxxxxxxxxxx/H323/cch323_is_preauth_reqd: FALSE

*Mar  1 02:00:05.507: //42/E051C577802F/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 172.16.200.1; dest address = 172.16.200.2

*Mar  1 02:00:05.507: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_FS_SETUP_IND while at state H225_IDLE

*Mar  1 02:00:05.507: //42/E051C577802F/H323/idle_fsSetupInd_hdlr: Setup ccb 0x643E22C8

*Mar  1 02:00:05.511: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = 0

*Mar  1 02:00:05.511: //42/E051C577802F/H323/act_fastStartSetupInd: full match is found

*Mar  1 02:00:05.511: //42/E051C577802F/H323/cch323_set_preferred_codec: Not using Voice Class Codec

*Mar  1 02:00:05.511: //42/E051C577802F/H323/cch323_set_peer: peer: 65E13F74, peer->voice_peer_tag: 1, ccb: 643E22C8

*Mar  1 02:00:05.511: //42/E051C577802F/H323/cch323_set_peer: ccb h245addr bits = system[0x0007]

*Mar  1 02:00:05.511: //42/E051C577802F/H323/cch323_set_peer: ccb ccm bits = system[0x0028]

*Mar  1 02:00:05.511: //42/E051C577802F/H323/cch323_fastStart_codec_match: ccb->remote_fastStart=0x65D418BC

*Mar  1 02:00:05.515: //42/E051C577802F/H323/cch323_fastStart_codec_match: symm_mask=4, tempOtherCodec=16, templocalCodec=16, audioFastStartArray=0x6642DD74

*Mar  1 02:00:05.515: //42/E051C577802F/H323/cch323_fastStart_codec_match: Setting local audio_cap_mask

*Mar  1 02:00:05.515: //42/E051C577802F/H323/h245_set_local_audio_mask: Near-end Pref Codecs = G729IETF

*Mar  1 02:00:05.515: //42/E051C577802F/H323/cch323_fastStart_codec_match: Inbound legs state_mc_mode=0x10F

*Mar  1 02:00:05.515: //42/E051C577802F/H323/cch323_fastStart_codec_match: Executing legacy code

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_selectFastStart_codecs: Codec: loc(16), rem(16); Bytes: loc(20), Fwd(20), Rev(20)

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_build_olc_for_ccapi: audioFastStartArray=0x6642DD74

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_build_olc_for_ccapi: channel_info ptr=0x663333EC, ccb ptr=0x643E22C8

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_build_olc_for_ccapi: Channel Information:

        Logical Channel Number (fwd): 1

        Logical Channel Number (rev): 1

        Channel address (fwd/rev):        0xAC10C802

        RTP  Channel (fwd/rev):           17566

        RTCP Channel (fwd/rev):           17567

        QoS Capability (fwd/rev):         0

        Symmetric Audio Codec:            16

        Symmetric Audio Codec Bytes:      20

        Flow Mode:                        0

        Silence Suppression:              2

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_build_olc_for_ccapi: NumOfElements = 1 idx = 1

*Mar  1 02:00:05.519: //42/E051C577802F/H323/act_fastStartSetupInd: codec match = 1

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_REQ_FS_SETUP state

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_update_faststart_media_info: Remote qosCapability 0

*Mar  1 02:00:05.519: //42/E051C577802F/H323/process_fastStart_setup_for_qos: This seems to be a normal call with Sync OFF; just do a generic notify

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_set_h245_state_mc_mode_incoming: h245 state m/c mode=0x10F, h323_ctl=0x2F

*Mar  1 02:00:05.519: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = 0

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_create_incoming_callinfo_block: peer 64A9FBFC, voice_peer_tag 1, ccb: 643E22C8

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_prnt_rawmsg: (for generic_setup_treatment): raw message is 59 bytes:

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_prnt_rawmsg:     1C 39 9E 01 00 03 67 74 64 00 00 00 2E 49 41 4D

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_prnt_rawmsg:     2C 0D 0A 47 43 49 2C 65 30 35 31 63 35 37 37 32

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_prnt_rawmsg:     62 65 65 31 31 64 36 38 30 32 66 39 61 65 35 30

*Mar  1 02:00:05.519: //42/E051C577802F/H323/cch323_prnt_rawmsg:     38 30 65 32 34 39 31 0D 0A 0D 0A

*Mar  1 02:00:05.519: //42/E051C577802F/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0

*Mar  1 02:00:05.523: //42/E051C577802F/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indications

*Mar  1 02:00:05.527: //42/E051C577802F/H323/cch323_update_call_info_from_dial_peer: Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300

*Mar  1 02:00:05.527: //42/E051C577802F/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0

*Mar  1 02:00:05.559: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=42

*Mar  1 02:00:05.563: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_SET_MODE: data ptr=0x64E755A8

*Mar  1 02:00:05.563: //-1/xxxxxxxxxxxx/H323/cch323_set_mode: callID=42, flow Mode=1 spi_mode=0x6

*Mar  1 02:00:05.567: //42/E051C577802F/H323/cch323_do_set_dialpeer_tag: gw_id=1

*Mar  1 02:00:05.599: //42/E051C577802F/H323/cch323_do_call_proceeding: gw_id=1

*Mar  1 02:00:05.603: //42/E051C577802F/H323/cch323_do_call_proceeding: set_mode NOT called yet...saved deferred CALL_PROC

*Mar  1 02:00:05.603: //42/E051C577802F/H323/cch323_build_fastStart_cap_response: selectCodec=16, codec_mask=4, configured_codecBytes=20, forward_codecBytes=20, reverse_codecBytes=20, audioFastStartArray=0x6642DD74

*Mar  1 02:00:05.611: //42/E051C577802F/H323/cch323_prepare_fastStart_cap_response: Entry

*Mar  1 02:00:05.611: //42/E051C577802F/H323/cch323_prepare_fastStart_cap_response: Retrieved qosCapability of 0

*Mar  1 02:00:05.615: //42/E051C577802F/H323/cch323_prepare_fastStart_cap_response: Filling in qosCapability field to 0

*Mar  1 02:00:05.623: //42/E051C577802F/H323/cch323_prepare_fastStart_cap_response: audioFastStartArray = 0x0

*Mar  1 02:00:05.623: //42/E051C577802F/H323/cch323_prepare_fastStart_cap_response: ccb->local_fastStart=0x65D41AB4, srcAddr=0xAC10C801, lport=19150, rport=17566, rc=1

*Mar  1 02:00:05.627: //42/E051C577802F/H323/cch323_build_fastStart_cap_response: local_fastStart=0x65D41AB4, negotiated_codec=16, negotiated_codec_bytes=20

*Mar  1 02:00:05.635: //42/E051C577802F/H323/h323_open_rtp_stream: Media In-active notification object not attached to ccb

*Mar  1 02:00:05.635: //42/E051C577802F/H323/cch323_rtp_open_notify: Entry

*Mar  1 02:00:05.635: //42/E051C577802F/H323/cch323_do_cap_ind: Registry cap_ind is not done

*Mar  1 02:00:05.635: //42/E051C577802F/H323/cch323_build_fastStart_cap_response: Done

*Mar  1 02:00:05.635: //42/E051C577802F/H323/cch323_process_set_mode: Setting inbound leg mode flags to 0x10F, flow-mode to FLOW_THROUGH

*Mar  1 02:00:05.635: //42/E051C577802F/H323/cch323_process_set_mode: Sending deferred CALL_PROC

*Mar  1 02:00:05.635: //42/E051C577802F/H323/cch323_do_call_proceeding: gw_id=1

*Mar  1 02:00:05.635: //42/E051C577802F/H323/cch323_do_call_proceeding: set_mode called so we can proceed with CALLPROC

*Mar  1 02:00:05.635: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_REQ_FS_SETUP

*Mar  1 02:00:05.639: //42/E051C577802F/H323/cch323_h225_set_new_state: Changing from H225_REQ_FS_SETUP state to H225_ACC_FS_CALLPROC state

*Mar  1 02:00:05.639: //42/E051C577802F/H323/generic_send_callproc: ====== PI = 0

*Mar  1 02:00:05.643: //42/E051C577802F/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE

*Mar  1 02:00:05.643: //42/E051C577802F/H323/h245_iwf_set_new_state: changing from IWF_OLC_IDLE state to IWF_OLC_DONE state

*Mar  1 02:00:05.647: //42/E051C577802F/H323/fastStartIdle: H245_EV_OLC_IN/OUT_DONE sent to olc

*Mar  1 02:00:05.647: //42/E051C577802F/H323/h245_olc_in_sm: Received H245_EV_OLC_IN_DONE while at state H245_OLC_IN_STATE_IDLE

*Mar  1 02:00:05.651: //42/E051C577802F/H323/h245_olc_in_set_new_state: Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_ESTABLISHED state

*Mar  1 02:00:05.655: //42/E051C577802F/H323/h245_olc_out_sm: Received H245_EV_OLC_OUT_DONE while at state H245_OLC_OUT_STATE_IDLE

*Mar  1 02:00:05.655: //42/E051C577802F/H323/h245_olc_out_set_new_state: Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_ESTABLISHED state

*Mar  1 02:00:05.655: //42/E051C577802F/H323/fastStartIdle: ccb->status_1 = 0x1

*Mar  1 02:00:05.691: //42/E051C577802F/H323/cch323_call_alert: gw_id=1

*Mar  1 02:00:05.695: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H225 SM

*Mar  1 02:00:05.695: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_ALERT while at state H225_ACC_FS_CALLPROC

*Mar  1 02:00:05.699: //42/E051C577802F/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_CALLPROC state to H225_ACC_FS_ALERT state

*Mar  1 02:00:05.699: //42/E051C577802F/H323/generic_send_alert: ====== PI = 0

*Mar  1 02:00:10.743: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=42

*Mar  1 02:00:10.747: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_SET_MODE: data ptr=0x64E756E8

*Mar  1 02:00:10.747: //42/E051C577802F/H323/cch323_do_set_dialpeer_tag: gw_id=1

*Mar  1 02:00:10.819: //42/E051C577802F/H323/cch323_call_alert: gw_id=1

*Mar  1 02:00:10.819: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H225 SM

*Mar  1 02:00:10.823: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_ALERT while at state H225_ACC_FS_ALERT

*Mar  1 02:00:10.823: //42/E051C577802F/H323/default_cleanup_rawbuf_hdlr: Unexpected H225_EV_ALERT event in H225_ACC_FS_ALERT state

*Mar  1 02:00:15.875: //42/E051C577802F/H323/cch323_do_call_disconnect: gw_id=1, discCause=19

*Mar  1 02:00:15.879: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM

*Mar  1 02:00:15.879: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ACC_FS_ALERT

*Mar  1 02:00:15.883: //42/E051C577802F/H323/cch323_h225_send_release: Cause = 19; Location = 0

*Mar  1 02:00:15.883: //42/E051C577802F/H323/cch323_h225_send_release: h225TerminateRequest: src address = -1408186367; dest address = 172.16.200.2

*Mar  1 02:00:15.891: //42/E051C577802F/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_ALERT state to H225_WAIT_FOR_REL_COMP state

*Mar  1 02:00:15.995: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1

*Mar  1 02:00:15.995: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x66332F1C, len=46, msgPtr=0x66353BA0

*Mar  1 02:00:15.999: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225

*Mar  1 02:00:16.007: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN

*Mar  1 02:00:16.007: //42/E051C577802F/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 172.16.200.1; dest address = 172.16.200.2

*Mar  1 02:00:16.011: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_WAIT_FOR_REL_COMP

*Mar  1 02:00:16.015: //42/E051C577802F/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_REL_COMP state to H225_IDLE state

*Mar  1 02:00:16.015: //42/E051C577802F/H323/cch323_call_generic_cleanup: Disconnected flag 1

*Mar  1 02:00:16.015: //42/E051C577802F/H323/cch323_store_call_history_entry: Trying to store call history/remove call_info while RTP session exists

*Mar  1 02:00:16.015: //42/E051C577802F/H323/cch323_store_call_history_entry: Call Active Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300

*Mar  1 02:00:16.015: //42/E051C577802F/H323/cch323_store_call_history_entry: Call History Playout Mode: 1,Init 60, Min 40, Max 250, Fax 300

*Mar  1 02:00:16.019: //42/E051C577802F/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_IDLE

*Mar  1 02:00:16.019: //42/E051C577802F/H323/defaultHdlr: DEFAULT: Received IWF_EV_H245_DISCONN in state IWF_IDLE

*Mar  1 02:00:16.031: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x643E22C8)

From this side and trace, all is normal. So problem has to be on the other system.

*Mar  1 02:00:05.695: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_ALERT while at state H225_ACC_FS_CALLPROC

*Mar  1 02:00:05.699: //42/E051C577802F/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_CALLPROC state to H225_ACC_FS_ALERT state

remote phone is ringing

*Mar  1 02:00:10.823: //42/E051C577802F/H323/default_cleanup_rawbuf_hdlr: Unexpected H225_EV_ALERT event in H225_ACC_FS_ALERT state

again remote tells that phone is ringing.

*Mar  1 02:00:15.875: //42/E051C577802F/H323/cch323_do_call_disconnect: gw_id=1, discCause=19

*Mar  1 02:00:15.879: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM

*Mar  1 02:00:15.879: //42/E051C577802F/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ACC_FS_ALERT

*Mar  1 02:00:15.883: //42/E051C577802F/H323/cch323_h225_send_release: Cause = 19; Location = 0

cause 19 = no answer

Here is the debug from the other system

and i can call 3334 from all the IP phones in the hunt group directly

*Mar  1 03:04:42.327: //-1/xxxxxxxxxxxx/H323/cch323_h225FD_Print: fd [2] Accepts newcall
*Mar  1 03:04:42.335: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_SETUP while at state H225_IDLE
*Mar  1 03:04:42.335: //60/E8DC3AC2804D/H323/check_qos_and_send_setup: Setup ccb 0x663D546C
*Mar  1 03:04:42.339: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_FS_SETUP while at state H225_IDLE
*Mar  1 03:04:42.339: //60/E8DC3AC2804D/H323/idle_fsSetup_hdlr: Setup ccb 0x663D546C
*Mar  1 03:04:42.347: //60/E8DC3AC2804D/H323/generic_send_setup:
generic_send_setup: is_overlap = 0, info_complete = 0
*Mar  1 03:04:42.351: //60/E8DC3AC2804D/H323/generic_send_setup: sending calling IE
*Mar  1 03:04:42.351: //60/E8DC3AC2804D/H323/generic_send_setup: ====== PI = 3
*Mar  1 03:04:42.355: //60/E8DC3AC2804D/H323/generic_send_setup: Send infoXCap=128, infoXRate=16, rateMult=0, xMode=128, info_layer1_prot=163
*Mar  1 03:04:42.355: //60/E8DC3AC2804D/H323/generic_send_setup: src address = 172.16.200.2; dest address = 1.1.1.1
*Mar  1 03:04:42.367: //60/E8DC3AC2804D/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_REQ_FS_SETUP state
*Mar  1 03:04:42.371: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE
*Mar  1 03:04:42.575: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type CALLPROCIND_CHOSEN
*Mar  1 03:04:42.579: //60/E8DC3AC2804D/H323/callproc_ind: ====== PI = 0
*Mar  1 03:04:42.583: //60/E8DC3AC2804D/H323/cch323_h225_receiver: CALLPROCIND_CHOSEN: src address = 172.16.200.2; dest address = 1.1.1.1
*Mar  1 03:04:42.583: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_FS_CALLPROC_IND while at state H225_REQ_FS_SETUP
*Mar  1 03:04:42.587: //60/E8DC3AC2804D/H323/cch323_h225_set_new_state: Changing from H225_REQ_FS_SETUP state to H225_ACC_FS_CALLPROC state
*Mar  1 03:04:42.599: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE
*Mar  1 03:04:42.599: //60/E8DC3AC2804D/H323/h245_iwf_set_new_state: changing from IWF_OLC_IDLE state to IWF_OLC_DONE state
*Mar  1 03:04:42.603: //60/E8DC3AC2804D/H323/fastStartIdle: H245_EV_OLC_IN/OUT_DONE sent to olc
*Mar  1 03:04:42.603: //60/E8DC3AC2804D/H323/h245_olc_in_sm: Received H245_EV_OLC_IN_DONE while at state H245_OLC_IN_STATE_IDLE
*Mar  1 03:04:42.607: //60/E8DC3AC2804D/H323/h245_olc_in_set_new_state: Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_ESTABLISHED state
*Mar  1 03:04:42.611: //60/E8DC3AC2804D/H323/h245_olc_out_sm: Received H245_EV_OLC_OUT_DONE while at state H245_OLC_OUT_STATE_IDLE
*Mar  1 03:04:42.611: //60/E8DC3AC2804D/H323/h245_olc_out_set_new_state: Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_ESTABLISHED state
*Mar  1 03:04:42.615: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_IDLE
*Mar  1 03:04:42.615: //60/E8DC3AC2804D/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_CONNECT_REQ_EVENT
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_CONNECT_REQ_EVENT while at H245_NONE state
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/cch323_h245_connection_sm: In connection_sm; doing tunneling
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/cch323_h245_set_new_state: Changing from H245_NONE state to H245_CONNECTED state
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/h245_iwf_set_new_state: changing from IWF_IDLE state to IWF_AWAIT_H245_CONN state
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_H245_CONNECTED while at state IWF_AWAIT_H245_CONN
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_H245_CONN state to IWF_AWAIT_CAP_MSD_RESP state
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_REQ while at state IDLE
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/h245_cap_out_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MSD while at state H245_MS_NONE
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/cch323_run_h245_ms_sm: Sent MSD Request
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/h245_ms_set_new_state: Changing from H245_MS_NONE state to H245_MS_OUTGOING_WAIT state
*Mar  1 03:04:42.619: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar  1 03:04:42.623: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar  1 03:04:42.691: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type ALERTIND_CHOSEN
*Mar  1 03:04:42.695: //60/E8DC3AC2804D/H323/alert_ind: ====== PI = 0
*Mar  1 03:04:42.695: //60/E8DC3AC2804D/H323/alert_ind: delay H245 address in alert
*Mar  1 03:04:42.699: //60/E8DC3AC2804D/H323/cch323_h225_receiver: ALERTIND_CHOSEN: src address = 172.16.200.2; dest address = 172.16.200.1
*Mar  1 03:04:42.703: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_ALERT_IND while at state H225_ACC_FS_CALLPROC
*Mar  1 03:04:42.703: //60/E8DC3AC2804D/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_CALLPROC state to H225_ACC_FS_ALERT state
*Mar  1 03:04:42.715: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar  1 03:04:42.715: //60/E8DC3AC2804D/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 172.16.200.2; dest address = 172.16.200.1
*Mar  1 03:04:42.719: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_ALERT
*Mar  1 03:04:42.723: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar  1 03:04:42.743: //60/E8DC3AC2804D/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_IND while at state IDLE
*Mar  1 03:04:42.743: //60/E8DC3AC2804D/H323/h245_cap_in_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
*Mar  1 03:04:42.743: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_CAP_IND while at state IWF_AWAIT_CAP_MSD_RESP
*Mar  1 03:04:42.743: //60/E8DC3AC2804D/H323/h245_handle_cap_ind: TCS received from remote end. Setting h245 status flag H245_CAP_REQUEST_RCVD
*Mar  1 03:04:42.743: //60/E8DC3AC2804D/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_RESP while at state AWAITING_RESPONSE
*Mar  1 03:04:42.743: //60/E8DC3AC2804D/H323/h245_cap_in_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
*Mar  1 03:04:42.743: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_ALERT
*Mar  1 03:04:42.751: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 172.16.200.2; dest address = 172.16.200.1
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_ALERT
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_IND while at state H245_MS_OUTGOING_WAIT
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/MSDetermination: rtt=60 rsdnum=866, ltt=60 lsdnum=5481
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/MSDetermination: Am SLAVE, ccb->h245.h245_mdStatus = 0x0
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/cch323_run_h245_ms_sm: MS_Determine_indication to Appl: Sent MSD ACK!
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/h245_ms_set_new_state: Changing from H245_MS_OUTGOING_WAIT state to H245_MS_INCOMING_WAIT state
*Mar  1 03:04:42.751: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_ALERT
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_MSD_ACK_SENT while at state IWF_AWAIT_CAP_MSD_RESP
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/h245_iwf_common_msdacksent: Negotiated codecs and dtmf are initialised in ccb
*Mar  1 03:04:42.755: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 172.16.200.2; dest address = 172.16.200.1
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_ALERT
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_CFM while at state AWAITING_RESPONSE
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/h245_cap_out_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_CAP_CFM while at state IWF_AWAIT_CAP_MSD_RESP
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_CAP_MSD_RESP state to IWF_AWAIT_MSD_RESP state
*Mar  1 03:04:42.755: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 172.16.200.2; dest address = 172.16.200.1
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_ALERT
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_MSD_RESP
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_CFM while at state H245_MS_INCOMING_WAIT
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/cch323_run_h245_ms_sm: Local Status = 0x2, Remote Status = 0x2
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/cch323_run_h245_ms_sm: MSD Determine CFM to APPL, both sides agree!
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/h245_ms_set_new_state: Changing from H245_MS_INCOMING_WAIT state to H245_MS_NONE state
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_MSD_CFM while at state IWF_AWAIT_MSD_RESP
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/h245_iwf_handle_init_olc: Turning off h245 status flag H245_MSD_ACK_SENT_CODEC_INIT_DONE
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_MSD_RESP state to IWF_ACTIVE state
*Mar  1 03:04:42.755: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_H245_SUCCESS while at state H225_ACC_FS_ALERT
*Mar  1 03:04:48.843: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN
*Mar  1 03:04:48.847: //60/E8DC3AC2804D/H323/release_ind: Disconnect cause 19 location code 0
*Mar  1 03:04:48.851: //60/E8DC3AC2804D/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 172.16.200.2; dest address = 172.16.200.1
*Mar  1 03:04:48.851: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_ACC_FS_ALERT
*Mar  1 03:04:48.867: //60/E8DC3AC2804D/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ACC_FS_ALERT
*Mar  1 03:04:48.871: //60/E8DC3AC2804D/H323/cch323_h225_send_release: Cause = 19; Location = 0
*Mar  1 03:04:48.875: //60/E8DC3AC2804D/H323/cch323_h225_send_release: h225TerminateRequest: src address = -1408186366; dest address = 1.1.1.1
*Mar  1 03:04:48.879: //60/E8DC3AC2804D/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_ALERT state to H225_IDLE state
*Mar  1 03:04:48.887: //60/E8DC3AC2804D/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_ACTIVE
*Mar  1 03:04:48.891: //60/E8DC3AC2804D/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_DISCONNECT_EVENT
*Mar  1 03:04:48.891: //60/E8DC3AC2804D/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_DISCONNECT_EVENT while at H245_CONNECTED state
*Mar  1 03:04:48.895: //60/E8DC3AC2804D/H323/h245_iwf_set_new_state: changing from IWF_ACTIVE state to IWF_IDLE state

On site 2 there is a reference to an 1.1.1.1 address. what is that ?

What is that you experience, how the call fails exactly you haven't even said.

Are you a student ans  that a lab you're doing ?

The customer asked for that scenario and i am trying to test it  before the final implementation

1.1.1.1 is lo0 on R1 and i changed the soure ip on that router to physical interface

From Phone_3333 (R2)  calls the pilot 1000 (R1) and the listed phones (1111 , 1112) ring properly but the final phone (3334) gives Busy

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: