|
Hello
New user here.
I was hoping that someone may shed some light on an issue I'm having with an Avaya S8XXX SIP trunk to a Cisco 2851 CME Router. I am able to place calls from 2 7970 (sip) to the Avaya system but I am unable to call from the Avaya side. Here is the config and I have also added a test call from the Avaya side with debug ccsip all. Any help would be greatly appreciated.
Thanks
Building configuration...
Current configuration : 3463 bytes
!
version 12.4
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname Router
!
boot-start-marker
boot system flash:c2800nm-ipvoicek9-mz.124-22.T.bin
boot-end-marker
!
logging message-counter syslog
!
no aaa new-model
!
dot11 syslog
ip source-route
!
!
ip cef
!
ip dhcp pool data
!
ip dhcp pool voice
network 163.230.4.0 255.255.255.0
option 150 ip 163.230.4.19
default-router 163.230.4.1
!
!
no ipv6 cef
multilink bundle-name authenticated
!
!
!
!
!
!
!
voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
no supplementary-service sip moved-temporarily
no supplementary-service sip refer
sip
registrar server expires max 1200 min 300
no update-callerid
!
!
!
!
!
!
!
!
!
!
!
!
!
voice register global
mode cme
source-address 163.230.4.19 port 5060
max-dn 20
max-pool 10
load 7970 SIP70.8-4-2S
authenticate register
authenticate realm sentara.com
dialplan-pattern 1 8.... extension-length 5
tftp-path flash:
create profile sync 0001302259266223
!
voice register dn 1
number 89990
!
voice register dn 2
number 89991
!
voice register dn 3
number 89992
!
voice register dn 4
number 89993
!
voice register dialplan 1
type 7940-7960-others
pattern 1 89...
pattern 2 87...
!
voice register pool 1
id mac 0007.0E57.88C5
type 7970
number 1 dn 1
number 2 dn 2
incoming called-number 89990
presence call-list
dtmf-relay sip-notify
username joe password cisco
codec g711ulaw
!
voice register pool 2
id mac 0007.0E57.87FD
type 7970
number 1 dn 3
number 2 dn 4
dtmf-relay sip-notify
username joe1 password cisco
codec g711ulaw
!
!
!
voice-card 0
!
!
!
!
!
archive
log config
hidekeys
!
!
!
!
!
!
interface GigabitEthernet0/0
ip address 163.230.4.19 255.255.255.224
ip information-reply
ip flow ingress
duplex auto
speed auto
no mop enabled
!
interface GigabitEthernet0/1
no ip address
shutdown
duplex auto
speed auto
!
ip forward-protocol nd
ip route 0.0.0.0 0.0.0.0 163.230.4.1
!
no ip http server
no ip http secure-server
!
!
!
!
!
tftp-server flash:term70.default.loads
tftp-server flash:SCCP70.8-3-3S.loads
tftp-server flash:jar70sccp.8-3-2-27.sbn
tftp-server flash:dsp70.8-3-2-27.sbn
tftp-server flash:cvm70sccp.8-3-2-27.sbn
tftp-server flash:cnu70.8-3-2-27.sbn
tftp-server flash:apps70.8-3-2-27.sbn
tftp-server flash:SIP3951.8-1-1b.loads
tftp-server flash:SIP3951.8-1-1-4.zz
tftp-server flash:SIP70.8-4-2S.loads
tftp-server flash:cnu70.8-4-1-23.sbn
tftp-server flash:apps70.8-4-1-23.sbn
tftp-server flash:dsp70.8-4-1-23.sbn
tftp-server flash:jar70sip.8-4-1-23.sbn
tftp-server flash:cvm70sip.8-4-1-23.sbn
tftp-server flash:term71.default.loads
tftp-server flash:cvm70sip.8-3-0-50.sbn
tftp-server flash:jar70sip.8-3-0-50.sbn
tftp-server flash:dsp70.8-3-0-50.sbn
tftp-server flash:apps70.8-3-0-50.sbn
tftp-server flash:cnu70.8-3-0-50.sbn
tftp-server flash:SIP70.8-3-1S.load
!
control-plane
!
!
!
ccm-manager fax protocol cisco
!
mgcp fax t38 ecm
!
!
!
dial-peer voice 2 voip
destination-pattern 8....
b2bua
session protocol sipv2
session target ipv4:10.103.63.109
session transport tcp
dtmf-relay sip-notify
codec g711ulaw
no vad
!
!
presence
presence call-list
allow subscribe
!
sip-ua
permit hostname dns:sentara.com
presence enable
handle-replaces
!
!
line con 0
line aux 0
line vty 0 4
login
!
scheduler allocate 20000 1000
end
Router#debug ccsip all
This may severely impact system performance. Continue? [confirm]
All SIP Call tracing is enabled
Router#
*Jan 12 17:44:25.591: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_process_network_msg: HEADER LINE READ FAILURE DUE TO RS->EO
F
*Jan 12 17:44:25.591: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_network_message: process_network_msg: not complete
*Jan 12 17:44:25.591: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_newmsg_to_spi: process_network_msg: not complete
*Jan 12 17:44:25.595: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_process_network_msg: HEADER LINE READ FAILURE DUE TO RS->EO
F
*Jan 12 17:44:25.595: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_network_message: process_network_msg: not complete
*Jan 12 17:44:25.595: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_newmsg_to_spi: process_network_msg: not complete
*Jan 12 17:44:25.795: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_process_network_msg: Content Length 213, Bytes Remaining 18
*Jan 12 17:44:25.795: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_network_message: process_network_msg: not complete
*Jan 12 17:44:25.795: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_newmsg_to_spi: process_network_msg: not complete
*Jan 12 17:44:25.795: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 fo
r event 1
*Jan 12 17:44:25.795: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Jan 12 17:44:25.795: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x46A582E0, addr=1
0.103.63.109, port=32549, connid=2, transport=TCP
*Jan 12 17:44:25.795: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:89990@sentara.com SIP/2.0
From: "Poland, Joe" <sip:anonymous@anonymous.invalid>;tag=09c98d983e8dd11d534935866c00
To: "89990" <sip:89990@sentara.com>
Call-ID: 09c98d983e8dd11e534935866c00
CSeq: 1 INVITE
Max-Forwards: 70
Route: <sip:163.230.4.19;lr;phase=terminating;transport=tcp>
Record-Route: <sip:10.103.63.109;lr;transport=tcp>
Via: SIP/2.0/TCP 10.103.63.109;branch=z9hG4bK09c98d983e8dd11f534935866c00
User-Agent: Avaya CM/R015x.01.0.414.3
Supported: 100rel, timer, replaces, join, histinfo
Allow: INVITE, CANCEL, BYE, ACK, PRACK, SUBSCRIBE, NOTIFY, REFER, OPTIONS, INFO, PUBLISH
Contact: "Poland, Joe" <sip:10.103.63.109;transport=tcp>
Session-Expires: 1200;refresher=uac
Min-SE: 1200
P-Asserted-Identity: "Poland, Joe" <sip:sentara.com>
Accept-Language: en
Privacy: id
Content-Type: application/sdp
History-Info: <sip:89990@sentara.com>;index=1
History-Info: "89990" <sip:89990@sentara.com>;index=1.1
Alert-Info: <cid:internal@sentara.com>;avaya-cm-alert-type=internal
Content-Length: 213
v=0
o=- 1 1 IN IP4 10.103.63.109
s=-
c=IN IP4 10.101.63.124
b=AS:64
t=0 0
m=audio 25128 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIAddContextToTable: Added context(0x47775790) with key=[37] to tab
le
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.103.63.109
,Port 32549, Transport 2, SentBy Port 5060
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/State/sipSPIChangeState: 0x47775790 : State change from (STATE_NONE, SUBSTAT
E_NONE) to (STATE_IDLE, SUBSTATE_NONE)
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.103.63.109
,Port 32549, Transport 2, SentBy Port 5060
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.103.63.109
,Port 32549, Transport 2, SentBy Port 5060
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Info/sipSPICheckIpip: VOIP dialpeer (peer=0x45B631B4) found for sip_user: 89
990
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Error/sipSPIGetReqBaseCallInfo: Multiple From header information found "Pola
nd, Joe" <sip:anonymous@anonymous.invalid>;tag=09c98d983e8dd11d534935866c00
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Info/ccsip_set_cc_cause_for_spi_err: Categorized cause:100, category:100
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_set_release_source_for_peer: ownCallId[-1], src[6]
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Info/sipSPIUaddCcbToUASReqTable: ****Adding to UAS Request table.
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x47775790 key=09c98d983e8dd1
1e534935866c0089990
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Info/sipSPIUaddCcbToUASRespTable: ****Adding to UAS Response table.
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Info/sipSPIUaddCcbToTable: Added to table. ccb=0x47775790 key=09c98d983e8dd1
1e534935866c00C5B38-115A
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Transport/sipSPITransportSendMessage: msg=0x46D178C0, addr=10.103.63.109, po
rt=32549, sentBy_port=5060, is_req=0, transport=2, switch=0, callBack=0x41596100
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Transport/sipSPITransportSendMessage: Proceedable for sending msg immediatel
y
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Transport/sipTransportLogicSendMsg: switch transport is 0
*Jan 12 17:44:25.799: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportPostSendMessage: Posting send for msg=0x46D178C0, addr
=10.103.63.109, port=32549, connId=2 for TCP
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/Info/sentErrResDisconnecting: Sent an 3456XX Error Response
*Jan 12 17:44:25.799: //-1/7D07309E8021/SIP/State/sipSPIChangeState: 0x47775790 : State change from (STATE_IDLE, SUBSTAT
E_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
*Jan 12 17:44:25.803: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 400 Bad Request - 'Malformed/Missing FROM: field'
Reason: Q.850;cause=100
Date: Mon, 12 Jan 2009 17:44:25 GMT
From: "Poland, Joe" <sip:anonymous@anonymous.invalid>;tag=09c98d983e8dd11d534935866c00
Allow-Events: telephone-event
Content-Length: 0
To: "89990" <sip:89990@sentara.com>;tag=C5B38-115A
Call-ID: 09c98d983e8dd11e534935866c00
Via: SIP/2.0/TCP 10.103.63.109;branch=z9hG4bK09c98d983e8dd11f534935866c00
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 1 INVITE
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Info/httpish_msg_process_network_msg: HEADER LINE READ FAILURE DUE TO RS->EO
F
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_network_message: process_network_msg: not complete
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Info/sip_tcp_newmsg_to_spi: process_network_msg: not complete
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 fo
r event 1
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Transport/sipTransportProcessNWNewConnMsg: context=0x00000000
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Transport/sipConnectionManagerProcessNewConnMsg: gConnTab=0x46A582E0, addr=1
0.103.63.109, port=32549, connid=2, transport=TCP
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:89990@sentara.com SIP/2.0
From: "Poland, Joe" <sip:anonymous@anonymous.invalid>;tag=09c98d983e8dd11d534935866c00
To: "89990" <sip:89990@sentara.com>;tag=C5B38-115A
Call-ID: 09c98d983e8dd11e534935866c00
Via: SIP/2.0/TCP 10.103.63.109;branch=z9hG4bK09c98d983e8dd11f534935866c00
CSeq: 1 ACK
Max-Forwards: 70
Route: <sip:163.230.4.19;lr;phase=terminating;transport=tcp>
User-Agent: Avaya CM/R015x.01.0.414.3
Content-Length: 0
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Info/ccsip_new_msg_preprocessor: Checking Invite Dialog
*Jan 12 17:44:25.831: //-1/7D07309E8021/SIP/Info/sipSPIFindCcbUASRespTable: *****CCB found in UAS Response table. ccb=0x
47775790
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.103.63.109
,Port 32549, Transport 2, SentBy Port 5060
*Jan 12 17:44:25.831: //-1/xxxxxxxxxxxx/SIP/Info/sipSPISetDateHeader: Clock Time Zone is UTC, same as GMT: Using GMT
*Jan 12 17:44:25.835: //-1/xxxxxxxxxxxx/SIP/Transport/sipSPIUpdateResponseInfo: Dialog Transaction Address 10.103.63.109
,Port 32549, Transport 2, SentBy Port 5060
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/State/sipSPIChangeState: 0x47775790 : State change from (STATE_DISCONNECTING
, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x47775790
State of The Call : STATE_DEAD
TCP Sockets Used : YES
Calling Number :
Called Number : 89990
Source IP Address (Sig ): 163.230.4.19
Destn SIP Req Addr:Port : 10.103.63.109:0
Destn SIP Resp Addr:Port : 10.103.63.109:32549
Destination Name : 10.103.63.109
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC) : 100
Disconnect Cause (SIP) : 400
*Jan 12 17:44:25.835: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIDeleteContextFromTable: Context for key=[37] removed.
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/sipSPIUdeleteCcbFromUASReqTable: ****Deleting from UAS Request table.
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x47775790 key=09c9
8d983e8dd11e534935866c0089990
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/sipSPIUdeleteCcbFromUASRespTable: ****Deleting from UAS Response table.
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/sipSPIUdeleteCcbFromTable: Deleting from table. ccb=0x47775790 key=09c9
8d983e8dd11e534935866c00C5B38-115A
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/sipSPIFlushEventBufferQueue: There are 0 events on the internal queue t
hat are going to be free'd
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/ccsip_qos_cleanup: Entry
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/sipSPI_ipip_free_codec_profile: Codec Profiles Freed
*Jan 12 17:44:25.835: //-1/7D07309E8021/SIP/Info/sipSPIUfreeOneCCB: Freeing ccb 47775790
*Jan 12 17:44:25.835: //-1/xxxxxxxxxxxx/SIP/Info/sipSPIGetContextFromTable: NO context for key[37]no debug all
All possible debugging has been turned off
Router#
Router# |