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=0x719)
*Mar 29 16:20:28.149: cc_api_call_connected(vdbPtr=0x81041FE8, callID=0x71A), prog_ind = 0cc_api_call_connected: setting callEntry->connected to TRUE
*Mar 29 16:20:28.153: sess_appl: ev(8=CC_EV_CALL_CONNECTED), cid(1818), disp(0)
*Mar 29 16:20:28.153: cid(1818)st(SSA_CS_ALERT_RCVD)ev(SSA_EV_CALL_CONNECTED)
oldst(SSA_CS_ALERT_RCVD)cfid(-1)csize(0)in(0)fDest(0)
*Mar 29 16:20:28.153: -cid2(1817)st2(SSA_CS_ALERT_RCVD)oldst2(SSA_CS_CALL_SETTING)
*Mar 29 16:20:28.153: ssaConnect
*Mar 29 16:20:28.153: ccGetDialpeerTag (callID=0x719)
*Mar 29 16:20:28.153: ccConferenceCreate (confID=0x810C869C, callID1=0x719, callID2=0x71A, tag=0x0)
*Mar 29 16:20:28.157: cc_api_bridge_done (confID=0x37C, srcIF=0x81041FE8, srcCallID=0x71A, dstCallID=0x719, disposition=0, tag=0x0)
*Mar 29 16:20:28.157: cc_api_bridge_done (confID=0x37C, srcIF=0x810E7784, srcCallID=0x719, dstCallID=0x71A, disposition=0, tag=0x0)
*Mar 29 16:20:28.157: cc_api_caps_ind (dstVdbPtr=0x81041FE8, dstCallId=0x71A, srcCallId=0x719,
caps={codec=0x2EBFB, fax_rate=0x7F, vad=0x3, modem=0x2
codec_bytes=0, signal_type=3})
*Mar 29 16:20:28.157: cc_api_caps_ind (Playout: mode 0, initial 60,min 40, max 200)
*Mar 29 16:20:28.157: cc_api_caps_ind (dstVdbPtr=0x810E7784, dstCallId=0x719, srcCallId=0x71A,
caps={codec=0x1, fax_rate=0x2, vad=0x1, modem=0x0
codec_bytes=160, signal_type=1})
*Mar 29 16:20:28.157: cc_api_caps_ind (Playout: mode 0, initial 60,min 40, max 200)
*Mar 29 16:20:28.161: cc_api_caps_ack (dstVdbPtr=0x810E7784, dstCallId=0x719, srcCallId=0x71A,
caps={codec=0x1, fax_rate=0x2, vad=0x1, modem=0x0
codec_bytes=160, signal_type=1, seq_num_start=3415})
*Mar 29 16:20:28.161: cc_api_caps_ack (dstVdbPtr=0x81041FE8, dstCallId=0x71A, srcCallId=0x719,
caps={codec=0x1, fax_rate=0x2, vad=0x1, modem=0x0
codec_bytes=160, signal_type=1, seq_num_start=3415})
*Mar 29 16:20:28.161: cc_api_voice_mode_event , callID=0x719
*Mar 29 16:20:28.161: Call Pointer =8126EE58
*Mar 29 16:20:28.161: sess_appl: ev(29=CC_EV_CONF_CREATE_DONE), cid(1817), disp(0)
*Mar 29 16:20:28.165: cid(1817)st(SSA_CS_CONFERENCING)ev(SSA_EV_CONF_CREATE_DONE)
oldst(SSA_CS_CALL_SETTING)cfid(892)csize(2)in(1)fDest(1)
*Mar 29 16:20:28.165: -cid2(1818)st2(SSA_CS_CONFERENCING)oldst2(SSA_CS_ALERT_RCVD)
*Mar 29 16:20:28.165: ssaConfCreateDone
*Mar 29 16:20:28.165: ccCallConnect (callID=0x719), prog_ind = 0ccCallConnect: setting callEntry->connected to TRUE
*Mar 29 16:20:28.165: ssaFlushPeerTagQueue cid(1817) peer list: tag(101) called number (2501)
*Mar 29 16:20:28.165: sess_appl: ev(51=CC_EV_VOICE_MODE_DONE), cid(1817), disp(0)
*Mar 29 16:20:28.165: cid(1817)st(SSA_CS_ACTIVE)ev(SSA_EV_VOICE_MODE_DONE)
oldst(SSA_CS_CONFERENCING)cfid(892)csize(2)in(1)fDest(1)
*Mar 29 16:20:28.165: -cid2(1818)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_ALERT_RCVD)
*Mar 29 16:20:28.169: ssaIgnore cid(1817), st(SSA_CS_ACTIVE),oldst(5), ev(51)
*Mar 29 16:20:28.169: cc_process_notify_bridge_done (event=0x810C15C8)
*Mar 29 16:20:28.921: cc_api_call_disconnected(vdbPtr=0x810E7784, callID=0x719, cause=0x10)
*Mar 29 16:20:28.921: sess_appl: ev(11=CC_EV_CALL_DISCONNECTED), cid(1817), disp(0)
*Mar 29 16:20:28.921: cid(1817)st(SSA_CS_ACTIVE)ev(SSA_EV_CALL_DISCONNECTED)
oldst(SSA_CS_ACTIVE)cfid(892)csize(2)in(1)fDest(1)
*Mar 29 16:20:28.921: -cid2(1818)st2(SSA_CS_ACTIVE)oldst2(SSA_CS_ALERT_RCVD)
*Mar 29 16:20:28.921: ssa: Disconnected cid(1817) state(5) cause(0x10)
*Mar 29 16:20:28.921: ccConferenceDestroy (confID=0x37C, tag=0x0)
*Mar 29 16:20:28.925: cc_api_bridge_drop_done (confID=0x37C, srcIF=0x81041FE8, srcCallID=0x71A, dstCallID=0x719, disposition=0 tag=0x0)
*Mar 29 16:20:28.925: cc_api_bridge_drop_done (confID=0x37C, srcIF=0x810E7784, srcCallID=0x719, dstCallID=0x71A, disposition=0 tag=0x0)
*Mar 29 16:20:28.925: sess_appl: ev(30=CC_EV_CONF_DESTROY_DONE), cid(1817), disp(0)
*Mar 29 16:20:28.925: cid(1817)st(SSA_CS_CONF_DESTROYING)ev(SSA_EV_CONF_DESTROY_DONE)
oldst(SSA_CS_ACTIVE)cfid(-1)csize(2)in(1)fDest(1)
*Mar 29 16:20:28.925: -cid2(1818)st2(SSA_CS_CONF_DESTROYING)oldst2(SSA_CS_ALERT_RCVD)
*Mar 29 16:20:28.925: ssaConfDestroyDone
*Mar 29 16:20:28.925: ccCallDisconnect (callID=0x719, cause=0x10 tag=0x0)
*Mar 29 16:20:28.925: ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10
*Mar 29 16:20:28.929: ccCallDisconnect (callID=0x71A, cause=0x10 tag=0x0)
*Mar 29 16:20:28.929: ccCallDisconnect: existing_cause = 0x0, new_cause = 0x10
*Mar 29 16:20:28.937: cc_api_call_disconnect_done(vdbPtr=0x810E7784, callID=0x719, disp=0, tag=0x0)
*Mar 29 16:20:28.937: sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(1817), disp(0)
*Mar 29 16:20:28.937: cid(1817)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)
oldst(SSA_CS_CONF_DESTROYING)cfid(-1)csize(2)in(1)fDest(1)
*Mar 29 16:20:28.937: -cid2(1818)st2(SSA_CS_DISCONNECTING)oldst2(SSA_CS_ALERT_RCVD)
*Mar 29 16:20:28.941: ssaDisconnectDone
*Mar 29 16:20:29.133: cc_api_icpif: expect factor = 0
*Mar 29 16:20:29.137: cc_api_call_disconnect_done(vdbPtr=0x81041FE8, callID=0x71A, disp=0, tag=0x0)
*Mar 29 16:20:29.137: sess_appl: ev(12=CC_EV_CALL_DISCONNECT_DONE), cid(1818), disp(0)
*Mar 29 16:20:29.137: cid(1818)st(SSA_CS_DISCONNECTING)ev(SSA_EV_CALL_DISCONNECT_DONE)
oldst(SSA_CS_ALERT_RCVD)cfid(-1)csize(1)in(0)fDest(0)
*Mar 29 16:20:29.137: ssaDisconnectDone
+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
Perhaps, I don't have the service on the lines, or perhaps it's in the way the VG200 is configured. Here is my "sanitized" VG200 config. Does it look like I'm missing something? Or do I just need to verify that I have caller ID service on my lines?
version 12.2
no service pad
service timestamps debug datetime msec
service timestamps log datetime localtime
service password-encryption
!
hostname STGeorge-VG200
!
logging buffered 4096 debugging
enable secret 5 $1$tIqo$/Z/vge98mUOWXcoJ8m5vJ.
!
clock timezone MST -7
clock summer-time MST recurring
ip subnet-zero
no ip domain-lookup
!
call rsvp-sync
!
voice class codec 1
codec preference 1 g711ulaw
codec preference 2 g729r8
!
!
!
voice class h323 1
h225 timeout tcp establish 3
!
!
!
!
!
interface FastEthernet0/0
ip address 10.1.112.3 255.255.255.0
speed 100
full-duplex
h323-gateway voip bind srcaddr 10.1.112.3
!
ip classless
ip route 0.0.0.0 0.0.0.0 10.1.112.1
no ip http server
ip pim bidir-enable
!
logging trap debugging
logging ******
snmp-server community ******* RO
snmp-server enable traps tty
!
voice-port 1/0/0
connection plar opx 2501
description ****
caller-id enable
!
voice-port 1/0/1
connection plar opx 2501
description ****
caller-id enable
!
voice-port 1/1/0
connection plar opx 2501
description ****
caller-id enable
!
voice-port 1/1/1
connection plar opx 2501
description ****
caller-id enable
!
dial-peer voice 5 pots
preference 3
destination-pattern 9T
progress_ind setup enable 3
progress_ind alert enable 8
port 1/0/0
!
dial-peer voice 6 pots
preference 2
destination-pattern 9T
progress_ind setup enable 3
progress_ind alert enable 8
port 1/0/1
!
dial-peer voice 7 pots
preference 1
destination-pattern 9T
progress_ind setup enable 3
progress_ind alert enable 8
port 1/1/0
!
dial-peer voice 8 pots
destination-pattern 9T
progress_ind setup enable 3
progress_ind alert enable 8
port 1/1/1
!
dial-peer voice 100 voip
destination-pattern .T
voice-class codec 1
voice-class h323 1
session target ipv4:10.1.1.21
dtmf-relay h245-alphanumeric
no vad
!
dial-peer voice 101 voip
preference 1
destination-pattern .T
voice-class codec 1
voice-class h323 1
session target ipv4:10.1.1.20
dtmf-relay h245-alphanumeric
no vad
!
!
line con 0
line aux 0
line vty 0 4
privilege level 15
password ******
login
!
end
