I am sending a Call from a SIPP originator, through the FreeSwitch box and back out to another SIPP terminator scenario.
The call goes through ok, everything happens as it should, however the call immediately drops, I have done egrep’s of both sides of the call and the BYE is defiantly coming from Freeswitch for some reason but I cannot work out why. Anyone any ideas?
If I register zipper on my laptop to FS and make a call works fine.
2017-06-18 22:26:34.199519 [DEBUG] sofia.c:9837 sofia/external/sipp@185.35.228.51:5060 receiving invite from 185.35.228.51:5060 version: 1.6.18 git 6e79667 2017-06-12 21:14:49Z 64bit 2017-06-18 22:26:34.219492 [DEBUG] sofia.c:7058 Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 185.35.228.51
s=-
c=IN IP4 185.35.228.51
t=0 0
m=audio 6000 RTP/AVP 0
a=rtpmap:0 PCMU/8000
2017-06-18 22:26:34.219492 [ERR] mod_xml_radius.c:933 Result of true match: 185.35.228.40 == ^185\.35\.229\.30
2017-06-18 22:26:34.219492 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success
2017-06-18 22:26:34.219492 [INFO] mod_dialplan_xml.c:637 Processing sipp <sipp>->441554555666 in context public
Dialplan: sofia/external/sipp@185.35.228.51:5060 Action bridge({sip_invite_from_uri=sip:${sip_from_user}@${sip_network_ip}}sofia/external/${destination_number}@185.35.229.30:5060) 2017-06-18 22:26:34.219492 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Sun, 18 Jun 2017 22:26:34 +0100]
2017-06-18 22:26:34.219492 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [h323-call-origin]=[originate]
2017-06-18 22:26:34.219492 [DEBUG] switch_channel.c:1250 sofia/external/sipp@185.35.228.51:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Sun, 18 Jun 2017 22:26:34 +0100] to event 2017-06-18 22:26:34.219492 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
Local SDP:
v=0
o=FreeSWITCH 1497789362 1497789363 IN IP4 185.35.228.40
s=FreeSWITCH
c=IN IP4 185.35.228.40
t=0 0
m=audio 31832 RTP/AVP 0 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
2017-06-18 22:26:34.219492 [ERR] mod_xml_radius.c:930 Didn't match: 185.35.229.30:5060 == ^185\.35\.229\.30
Local SDP:
v=0
o=FreeSWITCH 1497789362 1497789364 IN IP4 185.35.228.40
s=FreeSWITCH
c=IN IP4 185.35.228.40
t=0 0
m=audio 31832 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2017-06-18 22:26:34.259480 [DEBUG] sofia.c:7058 Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 185.35.228.48
s=-
c=IN IP4 185.35.228.48
t=0 0
m=audio 6000 RTP/AVP 0
a=rtpmap:0 PCMU/8000
2017-06-18 22:26:34.259480 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-06-18 22:26:34.259480 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-06-18 22:26:34.259480 [DEBUG] switch_core_media.c:4770 No 2833 in SDP. Disable 2833 dtmf and switch to INFO
2017-06-18 22:26:34.259480 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
2017-06-18 22:26:34.279530 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-06-18 22:26:34.279530 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-06-18 22:26:34.279530 [DEBUG] switch_core_media.c:4770 No 2833 in SDP. Disable 2833 dtmf and switch to INFO
2017-06-18 22:26:34.279530 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/external/sipp@185.35.228.51:5060] 185.35.228.40 port 23728 -> 185.35.228.51 port 6000 codec: 0 ms: 20 2017-06-18 22:26:34.279530 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
v=0
o=FreeSWITCH 1497797466 1497797467 IN IP4 185.35.228.40
s=FreeSWITCH
c=IN IP4 185.35.228.40
t=0 0
m=audio 23728 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=ptime:20
a=sendrecv
2017-06-18 22:26:34.279530 [DEBUG] switch_core_session.c:2814 sofia/external/sipp@185.35.228.51:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2017-06-18 22:26:34.279530 [DEBUG] switch_ivr_originate.c:3647 sofia/external/sipp@185.35.228.51:5060 skip receive message [ANSWER_EVENT] (channel is hungup already) 2017-06-18 22:26:34.319525 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
2017-06-18 22:26:34.319525 [INFO] mod_dptools.c:3418 Originate Failed. Cause: ORIGINATOR_CANCEL
2017-06-18 22:26:34.319525 [DEBUG] switch_core_session.c:2814 sofia/external/sipp@185.35.228.51:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2017-06-18 22:26:34.339569 [ERR] mod_xml_radius.c:930 Didn't match: 185.35.229.30 == ^185\.35\.229\.30
2017-06-18 22:26:34.339569 [ERR] mod_xml_radius.c:933 Result of true match: 185.35.228.40 == ^185\.35\.229\.30
2017-06-18 22:26:34.339569 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success
U 185.35.228.51:5060 -> 185.35.228.40:5080
Via: SIP/2.0/UDP 185.35.228.51:5060;branch=z9hG4bK-27036-1-0.
CSeq: 1 INVITE.
Max-Forwards: 70.
Subject: Performance Test.
Content-Type: application/sdp.
Content-Length: 137.
.
v=0.
o=user1 53655765 2353687637 IN IP4 185.35.228.51.
s=-.
c=IN IP4 185.35.228.51.
t=0 0.
m=audio 6000 RTP/AVP 0.
a=rtpmap:0 PCMU/8000.
#
U 185.35.228.40:5080 -> 185.35.228.51:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP 185.35.228.51:5060;branch=z9hG4bK-27036-1-0.
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170612T211449Z~6e79667c0a~64bit.
Content-Length: 0.
.
#
U 185.35.228.40:5080 -> 185.35.228.51:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 185.35.228.51:5060;branch=z9hG4bK-27036-1-0.
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170612T211449Z~6e79667c0a~64bit.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, refer.
Content-Length: 0.
.
#
U 185.35.228.40:5080 -> 185.35.228.51:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 185.35.228.51:5060;branch=z9hG4bK-27036-1-0.
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170612T211449Z~6e79667c0a~64bit.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 166.
.
v=0.
o=FreeSWITCH 1497797499 1497797500 IN IP4 185.35.228.40.
s=FreeSWITCH.
c=IN IP4 185.35.228.40.
t=0 0.
m=audio 25252 RTP/AVP 0.
a=rtpmap:0 PCMU/8000.
a=ptime:20.
#
U 185.35.228.51:5060 -> 185.35.228.40:5080
Via: SIP/2.0/UDP 185.35.228.51:5060;branch=z9hG4bK-27036-1-5.
CSeq: 1 ACK.
Max-Forwards: 70.
Subject: Performance Test.
Content-Length: 0.
.
#
U 185.35.228.40:5080 -> 185.35.228.51:5060
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKUBe0HvDjHF1XN.
Max-Forwards: 70.
CSeq: 108575311 BYE.
User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170612T211449Z~6e79667c0a~64bit.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY.
Supported: timer, path, replaces.
Reason: Q.850;cause=16;text="NORMAL_CLEARING".
Content-Length: 0.
.
#
U 185.35.228.51:5060 -> 185.35.228.40:5080
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKUBe0HvDjHF1XN.
CSeq: 108575311 BYE.
Content-Length: 0.
NGREP of SIP messages from FS to terminator
U 185.35.228.40:5080 -> 185.35.228.48:5060
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKXX0HNjFSB1D3c.
Max-Forwards: 69.
Call-ID: c755e7c8-cf13-1235-25a9-363165383663.
CSeq: 108575420 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170612T211449Z~6e79667c0a~64bit.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 222.
X-FS-Support: update_display,send_info.
.
v=0.
o=FreeSWITCH 1497801741 1497801743 IN IP4 185.35.228.40.
s=FreeSWITCH.
c=IN IP4 185.35.228.40.
t=0 0.
m=audio 21228 RTP/AVP 0 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
a=ptime:20.
#
U 185.35.228.48:5060 -> 185.35.228.40:5080
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKXX0HNjFSB1D3c.
Call-ID: c755e7c8-cf13-1235-25a9-363165383663.
CSeq: 108575420 INVITE.
Content-Length: 0.
.
#
U 185.35.228.48:5060 -> 185.35.228.40:5080
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKXX0HNjFSB1D3c.
Call-ID: c755e7c8-cf13-1235-25a9-363165383663.
CSeq: 108575420 INVITE.
Content-Type: application/sdp.
Content-Length: 137.
.
v=0.
o=user1 53655765 2353687637 IN IP4 185.35.228.48.
s=-.
c=IN IP4 185.35.228.48.
t=0 0.
m=audio 6000 RTP/AVP 0.
a=rtpmap:0 PCMU/8000.
#
U 185.35.228.40:5080 -> 185.35.228.48:5060
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKy6SaQD0v893Nr.
Max-Forwards: 70.
Call-ID: c755e7c8-cf13-1235-25a9-363165383663.
CSeq: 108575420 ACK.
Content-Length: 0.
.
#
U 185.35.228.40:5080 -> 185.35.228.48:5060
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKZFK3r8g05jt8K.
Max-Forwards: 70.
Call-ID: c755e7c8-cf13-1235-25a9-363165383663.
CSeq: 108575421 BYE.
User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170612T211449Z~6e79667c0a~64bit.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY.
Supported: timer, path, replaces.
Reason: SIP;cause=487;text="ORIGINATOR_CANCEL".
Content-Length: 0.
.
#
U 185.35.228.48:5060 -> 185.35.228.40:5080
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 185.35.228.40:5080;rport;branch=z9hG4bKZFK3r8g05jt8K.
Call-ID: c755e7c8-cf13-1235-25a9-363165383663.
CSeq: 108575421 BYE.
Content-Length: 0.
.