Caller ID on VG200 with FXO-M1 H.323 Gateway

[Jake Pratt]

I am running CUCM 7.1.3, and I have one H.323 gateway (a VG200), which uses 2 FXO-M1 (2-port) cards to terminate local analog lines.  This particular gateway will not pass caller ID.  My other gateways, which use a PRI voice T1 work fine with caller ID.  It's been a long time since we hooked this up, but I seem to remember the CCIE that hooked it up saying that it is a limitation of either the card, or the VG200.  I don't remember.

Anyway, I'm trying to figure out if I can get this caller ID enabled on this gateway, or perhaps upgrade the componant that is not supported.

Here is one of my voice ports:

voice-port 1/0/0

connection plar opx 2501

caller-id enable

And the dial-peer for that port:

dial-peer voice 5 pots

preference 3

destination-pattern 9T

progress_ind setup enable 3

progress_ind alert enable 8

port 1/0/0

If anyone has any information or ideas on this, I would greatly appreciate it.  Please let me know if you need additional information from my gateway, call manager, or debug info on the calls.

Thanks

[Jake Pratt]

I have looked at a few other articles, and it seems that these cards won't pass caller ID with an MGCP gateway, but they should with an H.323, is that right?  I just pulled some "debug voip ccapi inout" stats while I made an inbound call, and it looks like I'm not getting caller ID at the gateway.  Here is the debug output.

+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

*Mar 29 16:20:07.537: cc_handle_periodic_timer: Calling the callback, ccTimerctx - 0x80DE44B8

*Mar 29 16:20:07.537: ccTimerStart: ccTimerctx - 0x80DE44B8

*Mar 29 09:20:24: %VTSP-4-FSM_BAD_EVENT: Invalid FSM Input on channel UNKNOWN: state=-1 event=-2145953604

*Mar 29 16:20:24.473: cc_api_call_setup_ind (vdbPtr=0x810E7784, callInfo={called=2501,called_oct3=0x81,calling=,calling_oct3=0x0,calling_oct3a=0x0,calling_xlated=false,subscriber_type_str=RegularLine,fdest=1,peer_tag=5, prog_ind=3,callingIE_present 0},callID=0x8126BD20)

*Mar 29 16:20:24.473: cc_api_call_setup_ind calling number is null, answer addr  dest pattern 9T e164_ans_addr 0 e164_dest_pattern 0

*Mar 29 16:20:24.473: cc_api_call_setup_ind type 2 , prot 0

*Mar 29 16:20:24.477: cc_process_call_setup_ind (event=0x810BDF4C)

*Mar 29 16:20:24.477: >>>>CCAPI handed cid 1817 with tag 5 to app "DEFAULT"

*Mar 29 16:20:24.477: sess_appl: ev(24=CC_EV_CALL_SETUP_IND), cid(1817), disp(0)

*Mar 29 16:20:24.477: sess_appl: ev(SSA_EV_CALL_SETUP_IND), cid(1817), disp(0)

*Mar 29 16:20:24.477: ssaCallSetupInd

*Mar 29 16:20:24.477: ccCallSetContext (callID=0x719, context=0x8126EE58)

*Mar 29 16:20:24.481: ssaCallSetupInd cid(1817), st(SSA_CS_MAPPING),oldst(0), ev(24)ev->e.evCallSetupInd.nCallInfo.finalDestFlag = 1

*Mar 29 16:20:24.481: ssaCallSetupInd finalDest cllng(), clled(2501)

*Mar 29 16:20:24.481: ssaCallSetupInd cid(1817), st(SSA_CS_CALL_SETTING),oldst(0), ev(24)dpMatchPeersMoreArg result= 0

*Mar 29 16:20:24.481: ssaSetupPeer cid(1817) peer list:  tag(100) called number (2501)  tag(101) called number (2501)

*Mar 29 16:20:24.481: ssaSetupPeer cid(1817), destPat(2501), matched(0), prefix(), peer(81099ACC), peer->encapType (2)

*Mar 29 16:20:24.481: ccCallProceeding (callID=0x719, prog_ind=0x0)

*Mar 29 16:20:24.481: ccCallSetupRequest (Inbound call = 0x719, outbound peer =100, dest=, params=0x810C82C8 mode=0, *callID=0x810C8638, prog_ind = 3) callingIE_present 0

*Mar 29 16:20:24.485: ccCallSetupRequest numbering_type 0x81

*Mar 29 16:20:24.485: ccCallSetupRequest encapType 2 clid_restrict_disable 1 null_orig_clg 1 clid_transparent 0 callingNumber

*Mar 29 16:20:24.485: dest pattern .T, called 2501, digit_strip 0

*Mar 29 16:20:24.485: callingNumber=, calledNumber=2501, redirectNumber= display_info= calling_oct3a=0

*Mar 29 16:20:24.485: accountNumber=, finalDestFlag=1,

guid=217b.c7a2.2b78.11cc.860c.ad7c.6266.e7f6

*Mar 29 16:20:24.485: peer_tag=100

*Mar 29 16:20:24.485: ccIFCallSetupRequestPrivate: (vdbPtr=0x81041FE8, dest=, callParams={called=2501,called_oct3=0x81, calling=,calling_oct3=0x0, calling_xlated=false,  subscriber_type_str=RegularLine, fdest=1, voice_peer_tag=100},mode=0x0) vdbPtr type = 1

*Mar 29 16:20:24.485: ccIFCallSetupRequestPrivate: (vdbPtr=0x81041FE8, dest=, callParams={called=2501, called_oct3 0x81,  calling=,calling_oct3 0x0, calling_xlated=false,  fdest=1, voice_peer_tag=100}, mode=0x0, xltrc=-5)

*Mar 29 16:20:24.489: ccSaveDialpeerTag (callID=0x719, dialpeer_tag=0x64)

*Mar 29 16:20:24.489: ccCallSetContext (callID=0x71A, context=0x8125B638)

*Mar 29 16:20:24.489: ccCallReportDigits (callID=0x719, enable=0x0)

*Mar 29 16:20:24.489: cc_api_call_report_digits_done (vdbPtr=0x810E7784, callID=0x719, disp=0)

*Mar 29 16:20:24.489: sess_appl: ev(53=CC_EV_CALL_REPORT_DIGITS_DONE), cid(1817), disp(0)

*Mar 29 16:20:24.489: cid(1817)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_REPORT_DIGITS_DONE)

oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(1)fDest(1)

*Mar 29 16:20:24.489: -cid2(1818)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_MAPPING)

*Mar 29 16:20:24.489: ssaReportDigitsDone cid(1817) peer list:  tag(101) called number (2501)

*Mar 29 16:20:24.493: ssaReportDigitsDone callid=1817 Reporting disabled.

*Mar 29 16:20:24.633: cc_api_call_proceeding(vdbPtr=0x81041FE8, callID=0x71A,

      prog_ind=0x0)

*Mar 29 16:20:24.633: sess_appl: ev(21=CC_EV_CALL_PROCEEDING), cid(1818), disp(0)

*Mar 29 16:20:24.633: cid(1818)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_PROCEEDING)

oldst(SSA_CS_MAPPING)cfid(-1)csize(0)in(0)fDest(0)

*Mar 29 16:20:24.637: -cid2(1817)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING)

*Mar 29 16:20:24.637: ssaCallProc

*Mar 29 16:20:24.637: ccGetDialpeerTag (callID=0x719)

*Mar 29 16:20:24.637: ssaIgnore cid(1818), st(SSA_CS_CALL_SETTING),oldst(1), ev(21)

*Mar 29 16:20:24.641: cc_api_call_alert(vdbPtr=0x81041FE8, callID=0x71A, prog_ind=0x0, sig_ind=0x1)

*Mar 29 16:20:24.641: sess_appl: ev(7=CC_EV_CALL_ALERT), cid(1818), disp(0)

*Mar 29 16:20:24.641: cid(1818)st(SSA_CS_CALL_SETTING)ev(SSA_EV_CALL_ALERT)

oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0)

*Mar 29 16:20:24.641: -cid2(1817)st2(SSA_CS_CALL_SETTING)oldst2(SSA_CS_CALL_SETTING)

*Mar 29 16:20:24.641: ssaAlert

*Mar 29 16:20:24.641: ccGetDialpeerTag (callID=0x719)

*Mar 29 16:20:24.641: ccCallAlert (callID=0x719, prog_ind=0x0, sig_ind=0x1)

*Mar 29 16:20:24.889: cc_api_call_notify(vdbPtr=0x81041FE8, callID=0x71A

*Mar 29 16:20:24.889: sess_appl: ev(20=CC_EV_CALL_NOTIFY), cid(1818), disp(0)

*Mar 29 16:20:24.889: cid(1818)st(SSA_CS_ALERT_RCVD)ev(SSA_EV_CALL_NOTIFY)

oldst(SSA_CS_CALL_SETTING)cfid(-1)csize(0)in(0)fDest(0)

*Mar 29 16:20:24.889: -cid2(1817)st2(SSA_CS_ALERT_RCVD)oldst2(SSA_CS_CALL_SETTING)

*Mar 29 16:20:24.889: ssaNotify

*Mar 29 16:20:24.893: ccCallNotify (callID=0x719)

*Mar 29 16:20:27.789: cc_api_call_notify(vdbPtr=0x81041FE8, callID=0x71A

*Mar 29 16:20:27.789: sess_appl: ev(20=CC_EV_CALL_NOTIFY), cid(1818), disp(0)

*Mar 29 16:20:27.789: cid(1818)st(SSA_CS_ALERT_RCVD)ev(SSA_EV_CALL_NOTIFY)

oldst(SSA_CS_ALERT_RCVD)cfid(-1)csize(0)in(0)fDest(0)

*Mar 29 16:20:27.789: -cid2(1817)st2(SSA_CS_ALERT_RCVD)oldst2(SSA_CS_CALL_SETTING)

*Mar 29 16:20:27.789: ssaNotify

*Mar 29 16:20:27.793: ccCallNotify (callID=0x7