Call Trace - Spirou SIP to SS7
For SIP to SS7, below is Call Trace displaying the received SIP INFO message and the interworked SS7 ITX message with the C2/C3 parameters. Relevant sections are identified.
18:54:41.962 CALL(SIP) (00:00013:00) RCVD INVITE W/SDP from 10.129.47.12:5060 udp CSeq:1
18:54:41.962 CALL(SIP) (00:00013:00) with Via sent-by: 10.129.47.12
18:54:41.962 CALL(SIP) (00:00013:00) Call-id: 1-2032@10.129.47.12
18:54:41.962 CALL(SIP) (00:00013:00) and Msg Size: 641
18:54:41.962 CALL(SIP) (00:00013:00)
---> [10.129.47.12, 5060]
INVITE sip:3387@10.129.47.15:5060 SIP/2.0\r\n
Via: SIP/2.0/UDP 10.129.47.12:5060\r\n
From: sipp <sip:8888@10.129.47.12:5060>;tag=1\r\n
To: sut <sip:3387@10.129.47.15:5060>\r\n
Call-ID: 1-2032@10.129.47.12\r\n
Cseq: 1 INVITE\r\n
Contact: sip:8888@10.129.47.12:5060\r\n
Max-Forwards: 70\r\n
Subject: Performance Test\r\n
Content-Type: application/sdp\r\n
Content-Length: 269\r\n
Accept: application/sdp\r\n
\r\n
v=0\r\n
o=CiscoSystemsSIP-IPPhone-UserAgent 15616 4992 IN IP4 10
.129.47.12\r\n
s=SIP Call\r\n
c=IN IP4 10.129.47.12\r\n
t=0 0\r\n
m=audio 10000 RTP/AVP 18 0 8 110\r\n
a=rtpmap:18 G729a/8000\r\n
a=rtpmap:8 PCMA/8000\r\n
a=rtpmap:0 PCMU/8000\r\n
a=rtpmap:110 telephone-event/8000\r\n
a=fmtp:110 0-15\r\n
18:54:41.962 CALL(SIP) (00:00013:00) SENT 100 Trying to 10.129.47.12:5060
18:54:41.962 CALL(SIP) (00:00013:00)
<--- [10.129.47.12, 5060 <- 10.129.47.15, 5060]
SIP/2.0 100 Trying\r\n
Via: SIP/2.0/UDP 10.129.47.12:5060;received=10.129.47.12
\r\n
Call-ID: 1-2032@10.129.47.12\r\n
From: sipp <sip:8888@10.129.47.12:5060>;tag=1\r\n
To: sut <sip:3387@10.129.47.15:5060>\r\n
CSeq: 1 INVITE\r\n
Server: Dialogic-SIP/10.5.3.110 1010_Primary 0\r\n
Content-Length: 0\r\n
\r\n
18:54:41.962 CALL(SIP) (00:00013:00) SENT Route Control to L4
18:54:41.962 CALL(L4) (00:00013:00) RCVD Route Control from SIP
18:54:41.962 CALL(L4) (00:00013:00) Accessing Route Table 2
18:54:41.962 CALL(L4) (00:00013:00) Accessing Resource Table 1
18:54:41.962 CALL(L4) (00:00013:00) Hunting Algorithm is Round Robin
18:54:41.962 CALL(L4) (00:00013:00) SENT Route Control Ack to SIP
18:54:41.962 CALL(SIP) (00:00013:00) RCVD Route Control Ack from L4
18:54:41.962 CALL(SIP) (00:00013:00) Session Group Profile ID is 1
18:54:41.962 CALL(SIP) (00:00013:00) SENT Route Control to L4
18:54:41.962 CALL(L4) (00:00013:00) RCVD Route Control from SIP
18:54:41.962 CALL(L4) (00:00013:00) Accessing IP Bearer Profiles
18:54:41.962 CALL(L4) (00:00013:00) Profile Id 1 (RG 769)
18:54:41.962 CALL(L4) (00:00013:00) SENT Route Control Ack to SIP
18:54:41.962 CALL(SIP) (00:00013:00) RCVD Route Control Ack from L4
18:54:41.962 CALL(SIP) (00:00013:00) m line (audio) codec list: 18 0 8 110
18:54:41.962 CALL(SIP) (00:00013:00) RTP Type: 18, name: G729A, clk: 8000
18:54:41.962 CALL(SIP) (00:00013:00) RTP Type: 8, name: PCMA, clk: 8000
18:54:41.962 CALL(SIP) (00:00013:00) RTP Type: 0, name: PCMU, clk: 8000
18:54:41.962 CALL(SIP) (00:00013:00) RTP Type: 110, name: telephone-event, clk:
8000
18:54:41.962 CALL(SIP) (00:00013:00) CODEC Negotiation Priority LOCAL
18:54:41.962 CALL(SIP) (00:00013:00) CPC (Default)
18:54:41.962 CALL(SIP) (00:00013:00) Leg 0 associated with hndl(4987), LTS(1036
)(span 4096 chan 12)
18:54:41.962 CALL(SIP) (00:00013:00) SENT Setup to L4
18:54:41.972 CALL(L4) (00:00013:00) RCVD Setup Ind from SIP
18:54:41.972 CALL(L4) (00:00013:00) SENT RFS to GCL
18:54:41.972 CALL(GCL) (00:00013:00) RCVD RFS DN=[3387] ANI=[8888] from L4
18:54:41.972 CALL(GCL) (00:00013:00) ANI APRI=[0],SI=[0],Category=[10]
18:54:41.972 CALL(GCL) (00:00013:00) DN NOA=[3]
18:54:41.972 CALL(GCL) (00:00013:00) Incoming Channel Group = 1 [IMG_SIP]
18:54:41.972 CALL(GCL) (00:00013:00) SENT Make Call Request to GCL
18:54:41.972 CALL(GCL) (00:00013:01) SENT Route Control to L4
18:54:41.972 CALL(L4) (00:00013:00) RCVD RFS response from GCL
18:54:41.972 CALL(L4) (00:00013:00) RCVD Route Control from GCL
18:54:41.972 CALL(L4) (00:00013:00) Accessing Route Table 5
18:54:41.972 CALL(L4) (00:00013:00) Accessing Route Table 5
18:54:41.972 CALL(GCL) (00:00013:01) RCVD Route Control Ack (Mid Stream Router)
from L4
18:54:41.972 CALL(GCL) (00:00013:01) Outgoing Channel Group = 0 [SS7_Out]
18:54:41.972 CALL(GCL) (00:00013:01) SENT Route Control to L4
18:54:41.972 CALL(L4) (00:00013:01) RCVD Route Control from GCL
18:54:41.972 CALL(L4) (00:00013:01) Accessing Resource Table 1
18:54:41.972 CALL(L4) (00:00013:01) Resource Group ID is 32
18:54:41.972 CALL(L4) (00:00013:01) Hunting Algorithm is ROUND_ROBIN_CLOCKWISE
18:54:41.972 CALL(L4) (00:00013:01) IW: Override TMR (03)
18:54:41.972 CALL(L4) (00:00013:01) IW: Override BC/USI
18:54:41.972 CALL(L4) (00:00013:01) RCVD Ported Number
18:54:41.972 CALL(L4) (00:00013:01) SENT Outseize Ctrl to SS7
18:54:41.972 CALL(GCL) (00:00013:01) RCVD CPE of ADDRESS INFO from L4
18:54:41.972 CALL(GCL) (00:00013:01) Leg 1 associated with LTS(78)(span 29 chan
14)
18:54:41.972 CALL(SS7) (00:00013:01) RCVD Outseize Ctrl (0x809:0x1012:12) from
L4
18:54:41.972 CALL(SS7) (00:00013:01)
SENT: STK: 0 PRI: 00
DPC: 2-2-2 OPC: 1-1-1
SLS: 0x0c CIC: 00012 (0x000c)
IAM [0048000a03020604031033780a040310888800]
18:54:41.972 CALL(SS7) (00:00013:01) SENT IAM to (0x809:0x1012:12)
18:54:42.032 CALL(SS7) (00:00013:01)
RCVD: STK: 0 PRI: 00
DPC: 1-1-1 OPC: 2-2-2
SLS: 0x00 CIC: 00012 (0x000c)
ACM [00000129000000]
18:54:42.032 CALL(SS7) (00:00013:01) RCVD ACM from (0x1012:0x809:12)
18:54:42.032 CALL(SS7) (00:00013:01) SENT Cut Thru (0x1012:0x809:12) to L4
18:54:42.032 CALL(SS7) (00:00013:01) SENT Outseize ACK (0x1012:0x809:12) to L4
18:54:42.032 CALL(L4) (00:00013:01) RCVD Cut Thru from SS7
18:54:42.032 CALL(L4) (00:00013:01) RCVD Outseize ACK from SS7
18:54:42.032 CALL(GCL) (00:00013:00) SENT Connect w/Pad Response to L4
18:54:42.032 CALL(L4) (00:00013:01) SENT CutThru to L4
18:54:42.032 CALL(L4) (00:00000:00) IW: ACM --> 18x procedure by default
18:54:42.032 CALL(L4) (00:00000:00) IW: RFC3398 ACM->18x condition [00]
18:54:42.032 CALL(L4) (00:00013:00) SENT Progress to SIP
18:54:42.032 CALL(L4) (00:00013:00) SENT connect_1way: r_ts=0x4e l_ts=0x40c to
TSI
18:54:42.032 CALL(L4) (00:00013:01) SENT connect_1way: r_ts=0x40c l_ts=0x4e to
TSI
18:54:42.032 CALL(SS7) (00:00013:01) RCVD Host Connect from (0x809:0x1012:12) f
rom L4
18:54:42.032 CALL(SIP) (00:00013:00) RCVD Progress from L4
18:54:42.032 CALL(SIP) (00:00013:00) Set media src IP 10.129.47.16:8048
18:54:42.032 CALL(SIP) (00:00013:00) Set media dst IP 10.129.47.12:10000
18:54:42.032 CALL(SIP) (00:00013:00) CODEC Negotiation Priority LOCAL
18:54:42.032 CALL(SIP) (00:00013:00) Set media PLD ID 2
18:54:42.032 CALL(SIP) (00:00013:00) Set media PLD Size 20, Multiplier:4
18:54:42.032 CALL(SIP) (00:00013:00) RFC 2833 DTMF Relay Enabled, pld type 110
18:54:42.032 CALL(SIP) (00:00013:00) Set Fax Type to Bypass
18:54:42.032 CALL(SIP) (00:00013:00) Source Port Validation : Enabled
18:54:42.032 CALL(SIP) (00:00013:00) SENT L3-L3 Outseize Ctrl to VPPL
18:54:42.032 CALL(IP) (00:00013:00) RCVD Outseize ([vts 12] [m0.0.3]) from L3P
(span 4096 chan 12)
18:54:42.032 CALL(IP) (00:00013:00) RTP: (Src) 10.129.47.16:8048
18:54:42.032 CALL(IP) (00:00013:00) RTP: (Dst) 10.129.47.12:10000
18:54:42.032 CALL(IP) (00:00013:00) Security on RTP(SRTP) Disabled mod: 0 vts
12
18:54:42.042 CALL(IP) (00:00013:00) SrcPortValidation: Enabled
18:54:42.042 CALL(IP) (00:00013:00) VoIP Codec is G711Mulaw; Payload Size is 2
0 ms
18:54:42.042 CALL(IP) (00:00013:00) Fax Mode is Bypass, Modem Mode is Bypass
18:54:42.042 CALL(IP) (00:00013:00) RFC2833 DTMF Relay in use, Dynamic Payload
Type is 110
18:54:42.052 CALL(IP) (00:00013:00) Initial MID is disabled
18:54:42.052 CALL(IP) (00:00013:00) SENT Outseize ACK (toPvid: x66) to L3P
18:54:42.052 CALL(SIP) (00:00013:00) RCVD Outseize Ack from VPPL
18:54:42.052 CALL(SIP) (00:00013:00) SENT 183 Session Progress W/SDP to 10.129.
47.12:5060 UDP
18:54:42.052 CALL(SIP) (00:00013:00)
<--- [10.129.47.12, 5060 <- 10.129.47.15, 5060]
SIP/2.0 183 Session Progress\r\n
Via: SIP/2.0/UDP 10.129.47.12:5060;received=10.129.47.12
\r\n
Call-ID: 1-2032@10.129.47.12\r\n
From: sipp <sip:8888@10.129.47.12:5060>;tag=1\r\n
To: sut <sip:3387@10.129.47.15:5060>;tag=a94c095b773be1d
d6e8d668a785a9c8443c1ac32\r\n
Contact: <sip:3387@10.129.47.15:5060>\r\n
CSeq: 1 INVITE\r\n
Allow: OPTIONS, CANCEL, UPDATE\r\n
Server: Dialogic-SIP/10.5.3.110 1010_Primary 0\r\n
Content-Type: application/sdp\r\n
Content-Length: 233\r\n
\r\n
v=0\r\n
o=Dialogic_SDP 12 0 IN IP4 10.129.47.15\r\n
s=Dialogic-SIP\r\n
c=IN IP4 10.129.47.16\r\n
t=0 0\r\n
m=audio 8048 RTP/AVP 0 110\r\n
a=rtpmap:0 PCMU/8000\r\n
a=rtpmap:110 telephone-event/8000\r\n
a=fmtp:110 0-15\r\n
a=silenceSupp:off - - - -\r\n
a=ptime:20\r\n
18:54:42.052 CALL(SIP) (00:00013:00) RCVD Cut Thru from VPPL
18:54:42.052 CALL(SIP) (00:00013:00) RCVD Connect from VPPL
18:54:45.032 CALL(SS7) (00:00013:01)
RCVD: STK: 0 PRI: 00
DPC: 1-1-1 OPC: 2-2-2
SLS: 0x00 CIC: 00012 (0x000c)
ANM [00]
18:54:45.032 CALL(SS7) (00:00013:01) RCVD ANM from (0x1012:0x809:12)
18:54:45.032 CALL(SS7) (00:00013:01) SENT Connect (0x1012:0x809:12) to L4
18:54:45.032 CALL(L4) (00:00013:01) RCVD Connect from SS7
18:54:45.032 CALL(L4) (00:00013:01) SENT CPE of ANSWER to GCL
18:54:45.032 CALL(GCL) (00:00013:01) RCVD CPE of ANSWER from L4
18:54:45.032 CALL(GCL) (00:00013:01) SENT Call Answered to GCL
18:54:45.032 CALL(GCL) (00:00013:00) SENT CPE of ANSWER to L4
18:54:45.032 CALL(L4) (00:00013:00) RCVD CPE of ANSWER from GCL
18:54:45.032 CALL(L4) (00:00013:00) SENT Connect to SIP
18:54:45.032 CALL(SIP) (00:00013:00) RCVD Connect from L4
18:54:45.032 CALL(SIP) (00:00013:00) Session Refresh is not Needed
18:54:45.032 CALL(SIP) (00:00013:00) SENT 200 OK INVITE W/SDP to 10.129.47.12:5
060 UDP
18:54:45.032 CALL(SIP) (00:00013:00)
<--- [10.129.47.12, 5060 <- 10.129.47.15, 5060]
SIP/2.0 200 OK\r\n
Via: SIP/2.0/UDP 10.129.47.12:5060;received=10.129.47.12
\r\n
Call-ID: 1-2032@10.129.47.12\r\n
From: sipp <sip:8888@10.129.47.12:5060>;tag=1\r\n
To: sut <sip:3387@10.129.47.15:5060>;tag=a94c095b773be1d
d6e8d668a785a9c8443c1ac32\r\n
Contact: <sip:3387@10.129.47.15:5060>\r\n
CSeq: 1 INVITE\r\n
Server: Dialogic-SIP/10.5.3.110 1010_Primary 0\r\n
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBS
CRIBE, NOTIFY, INFO, REFER, UPDATE\r\n
Supported: path, replaces, timer, tdialog\r\n
Accept: application/sdp, application/dtmf-relay, text/pl
ain\r\n
Content-Type: application/sdp\r\n
Content-Length: 233\r\n
\r\n
v=0\r\n
o=Dialogic_SDP 12 0 IN IP4 10.129.47.15\r\n
s=Dialogic-SIP\r\n
c=IN IP4 10.129.47.16\r\n
t=0 0\r\n
m=audio 8048 RTP/AVP 0 110\r\n
a=rtpmap:0 PCMU/8000\r\n
a=rtpmap:110 telephone-event/8000\r\n
a=fmtp:110 0-15\r\n
a=silenceSupp:off - - - -\r\n
a=ptime:20\r\n
18:54:45.042 CALL(SIP) (00:00013:00) RCVD ACK from 10.129.47.12:5060 udp CSeq:1
Msg Size:352
18:54:45.042 CALL(SIP) (00:00013:00) with Via sent-by: 10.129.47.12 udp
18:54:45.042 CALL(SIP) (00:00013:00)
---> [10.129.47.12, 5060]
ACK sip:3387@10.129.47.15:5060 SIP/2.0\r\n
Via: SIP/2.0/UDP 10.129.47.12:5060\r\n
From: sipp <sip:8888@10.129.47.12:5060>;tag=1\r\n
To: sut <sip:3387@10.129.47.15:5060>;tag=a94c095b773be1d
d6e8d668a785a9c8443c1ac32\r\n
Call-ID: 1-2032@10.129.47.12\r\n
Cseq: 1 ACK\r\n
Contact: sip:8888@10.129.47.12:5060\r\n
Max-Forwards: 70\r\n
Subject: Performance Test\r\n
Content-Length: 0\r\n
\r\n
18:54:48.062 CALL(SIP) (00:00013:00) RCVD INFO from 10.129.47.12:5060 udp CSeq:
2 Msg Size:389
Relevant section
18:54:48.062 CALL(SIP) (00:00013:00)
---> [10.129.47.12, 5060]
INFO sip:3387@10.129.47.15:5060 SIP/2.0\r\n
Via: SIP/2.0/UDP 10.129.47.12:5060\r\n
From: sipp <sip:8888@10.129.47.12:5060>;tag=1\r\n
To: sut <sip:3387@10.129.47.15:5060>;tag=a94c095b773be1d
d6e8d668a785a9c8443c1ac32\r\n
Call-ID: 1-2032@10.129.47.12\r\n
Cseq: 2 INFO\r\n
Contact: sip:8888@10.129.47.12:5060\r\n
Max-Forwards: 70\r\n
Subject: ITX rate\r\n
Content-Type: text/plain\r\n
Content-Length: 15\r\n
\r\n
C3=001,C2=002\r\n
18:54:48.062 CALL(SIP) (00:00013:00) INFO using sgp_id=1
18:54:48.062 CALL(SIP) (00:00013:00) RCVD INFO from 10.129.47.12:5060 udp CSeq:
2 Msg Size:389
18:54:48.062 CALL(SIP) (00:00013:00) INFO using sgp_id=1
18:54:48.062 CALL(SIP) (00:00013:00) spirou/itx payload detected(c2=2,c3=1)
18:54:48.062 CALL(SIP) (00:00013:00) SENT Mid Call Info to L4
18:54:48.062 CALL(SIP) (00:00013:00) SENT 200 OK INFO to 10.129.47.12:5060 UDP
18:54:48.062 CALL(SIP) (00:00013:00)
<--- [10.129.47.12, 5060 <- 10.129.47.15, 5060]
SIP/2.0 200 OK\r\n
Via: SIP/2.0/UDP 10.129.47.12:5060;received=10.129.47.12
\r\n
Call-ID: 1-2032@10.129.47.12\r\n
From: sipp <sip:8888@10.129.47.12:5060>;tag=1\r\n
To: sut <sip:3387@10.129.47.15:5060>;tag=a94c095b773be1d
d6e8d668a785a9c8443c1ac32\r\n
CSeq: 2 INFO\r\n
Server: Dialogic-SIP/10.5.3.110 1010_Primary 0\r\n
Allow: INVITE, BYE, REGISTER, ACK, OPTIONS, CANCEL, SUBS
CRIBE, NOTIFY, INFO, REFER, UPDATE\r\n
Accept: application/sdp, application/dtmf-relay, text/pl
ain\r\n
Accept-Encoding: identity\r\n
Accept-Language: en\r\n
Supported: path, replaces, timer, tdialog\r\n
Content-Length: 0\r\n
\r\n
18:54:48.062 CALL(L4) (00:00013:00) SENT Ppl Ev. Ind to GCL
18:54:48.062 CALL(GCL) (00:00013:01) SENT Ppl Ev. Req to L4
18:54:48.062 CALL(L4) (00:00013:01) SENT Ppl Ev. Req to SS7
18:54:48.062 CALL(SS7) (00:00013:01) RCVD PPL Event Request (0x809:0x1012:12) f
rom L4
18:54:48.062 CALL(SS7) (00:00013:01)
SENT: STK: 0 PRI: 00
DPC: 2-2-2 OPC: 1-1-1
SLS: 0x0c CIC: 00012 (0x000c)
UDM1 [010200]
Relevant section
18:54:48.062 CALL(SS7) (00:00013:01) SENT USR_DEF1 to (0x809:0x1012:12)
18:54:48.112 CALL(SS7) (00:00013:01)
RCVD: STK: 0 PRI: 00
DPC: 1-1-1 OPC: 2-2-2
SLS: 0x00 CIC: 00012 (0x000c)
UDM2 [00]
18:54:48.112 CALL(SS7) (00:00013:01) RCVD USR_DEF2 from (0x1012:0x809:12)
18:54:49.082 CALL(SIP) (00:00013:00) RCVD INFO from 10.129.47.12:5060 udp CSeq:
3 Msg Size:387