T38 REFUSE on request…[INCOMPATIBLE_DESTINATION] on incoming voicecall

Status
Not open for further replies.
R

Robert Hilbert-Lahl

New Member
Aug 22, 2017
5
0
1
63
hello;
i had FusionPBX 4.0x running successfully for about 2 years. now i did a vanilla clean install: new FreeBSD 11.0 (11.1: installer fails…); FusPBX 4.2.2
whatever I do, i cannot connect an external call to my pbx. every incoming call fails; codecs are matched, but then:


FS_LOG:
2017-08-22 07:15:36.394759 [NOTICE] switch_channel.c:1104 New Channel sofia/external/0664xxxxxxx@84.113.255.116 [53bc2dee-f886-e711-be51-080027b4c318]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0664xxxxxxx@84.113.255.116) Running State Change CS_NEW (Cur 1 Tot 1)
2017-08-22 07:15:36.394759 [DEBUG] sofia.c:9873 sofia/external/0664xxxxxxx@84.113.255.116 receiving invite from 195.34.155.139:5060 version: 1.6.19 git c28091b 2017-08-21 22:09:37Z 64bit
2017-08-22 07:15:36.394759 [DEBUG] sofia.c:7084 Channel sofia/external/0664xxxxxxx@84.113.255.116 entering state [received][100]
2017-08-22 07:15:36.394759 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=AudiocodesGW 397776074 397776070 IN IP4 195.34.155.139
s=Phone-Call
c=IN IP4 195.34.155.139
t=0 0
m=audio 55842 RTP/AVP 8 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
m=image 56836 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxMaxBuffer:3000
a=T38FaxMaxDatagram:560
a=T38FaxRateManagement:transferredTCF
a=T38FaxUdpEC:t38UDPRedundancy

2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/0664xxxxxxx@84.113.255.116 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2017-08-22 07:15:36.394759 [DEBUG] switch_core_codec.c:111 sofia/external/0664xxxxxxx@84.113.255.116 Original read codec set to PCMA:8
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4767 sofia/external/0664xxxxxxx@84.113.255.116 Set 2833 dtmf send payload to 101 recv payload to 101
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4028 sofia/external/0664xxxxxxx@84.113.255.116 T38 REFUSE on request
2017-08-22 07:15:36.394759 [NOTICE] sofia.c:7565 Hangup sofia/external/0664xxxxxxx@84.113.255.116 [CS_NEW] [INCOMPATIBLE_DESTINATION]
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:603 (sofia/external/0664xxxxxxx@84.113.255.116) State NEW
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0664xxxxxxx@84.113.255.116) Running State Change CS_HANGUP (Cur 1 Tot 1)
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:850 (sofia/external/0664xxxxxxx@84.113.255.116) Callstate Change DOWN -> HANGUP
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:852 (sofia/external/0664xxxxxxx@84.113.255.116) State HANGUP
2017-08-22 07:15:36.394759 [DEBUG] mod_sofia.c:438 Channel sofia/external/0664xxxxxxx@84.113.255.116 hanging up, cause: INCOMPATIBLE_DESTINATION
2017-08-22 07:15:36.394759 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 488
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:60 sofia/external/0664xxxxxxx@84.113.255.116 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:852 (sofia/external/0664xxxxxxx@84.113.255.116) State HANGUP going to sleep
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:619 (sofia/external/0664xxxxxxx@84.113.255.116) State Change CS_HANGUP -> CS_REPORTING
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0664xxxxxxx@84.113.255.116) Running State Change CS_REPORTING (Cur 1 Tot 1)
2017-08-22 07:15:36.394759 [DEBUG] switch_core_state_machine.c:938 (sofia/external/0664xxxxxxx@84.113.255.116) State REPORTING
2017-08-22 07:15:36.496053 [DEBUG] switch_core_state_machine.c:174 sofia/external/0664xxxxxxx@84.113.255.116 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2017-08-22 07:15:36.496053 [DEBUG] switch_core_state_machine.c:938 (sofia/external/0664xxxxxxx@84.113.255.116) State REPORTING going to sleep
2017-08-22 07:15:36.496053 [DEBUG] switch_core_state_machine.c:610 (sofia/external/0664xxxxxxx@84.113.255.116) State Change CS_REPORTING -> CS_DESTROY
2017-08-22 07:15:36.496053 [DEBUG] switch_core_session.c:1665 Session 1 (sofia/external/0664xxxxxxx@84.113.255.116) Locked, Waiting on external entities
2017-08-22 07:15:36.496053 [NOTICE] switch_core_session.c:1683 Session 1 (sofia/external/0664xxxxxxx@84.113.255.116) Ended
2017-08-22 07:15:36.496053 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/0664xxxxxxx@84.113.255.116 [CS_DESTROY]
2017-08-22 07:15:36.496053 [DEBUG] switch_core_state_machine.c:741 (sofia/external/0664xxxxxxx@84.113.255.116) Running State Change CS_DESTROY (Cur 0 Tot 1)
2017-08-22 07:15:36.496053 [DEBUG] switch_core_state_machine.c:751 (sofia/external/0664xxxxxxx@84.113.255.116) State DESTROY
2017-08-22 07:15:36.496053 [DEBUG] mod_sofia.c:343 sofia/external/0664xxxxxxx@84.113.255.116 SOFIA DESTROY
2017-08-22 07:15:36.496053 [DEBUG] switch_core_state_machine.c:181 sofia/external/0664xxxxxxx@84.113.255.116 Standard DESTROY
2017-08-22 07:15:36.496053 [DEBUG] switch_core_state_machine.c:751 (sofia/external/0664xxxxxxx@84.113.255.116) State DESTROY going to sleep
freeswitch@voipsrv01>

SysInfo:
Version 4.2.2
Git Information Branch: 4.2
Commit: 383f2ca88aa224828b3427843b483da13ac39265
Origin: https://github.com/fusionpbx/fusionpbx.git
Project Path /usr/local/www/fusionpbx
Switch Version 1.6.19 (64bit)
Operating System Information
Operating System FreeBSD voipsrv01 11.0-RELEASE-p1 FreeBSD 11.0-RELEASE-p1 #0 r306420: Thu Sep 29 01:43:23 UTC 2016 root@releng2.nyi.freebsd.org:/usr/obj/usr/src/sys/GENERIC amd64
Uptime 3:01PM up 19 secs, 0 users, load averages: 1.46, 0.36, 0.13
Date Tue, 22 Aug 2017 13:01:23 +0000


any ideas about that? read in an IRC transcript from april 2017 that ther is some kind of T38 bug; could it be…?

thank you very much for your help
greetings from austria/vienna
 
… as far as i can tell…
FPBX gets a SIP INVITE from my voip provider:
v=0
o=AudiocodesGW 397776074 397776070 IN IP4 195.34.155.139
s=Phone-Call
c=IN IP4 195.34.155.139
t=0 0
m=audio 55842 RTP/AVP 8 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
m=image 56836 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:14400
a=T38FaxMaxBuffer:3000
a=T38FaxMaxDatagram:560
a=T38FaxRateManagement:transferredTCF
a=T38FaxUdpEC:t38UDPRedundancy
Click to expand...

FPBX gets a code match:
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
Click to expand...

FPBX sets codec accordingly:
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/0664xxxxxxx@84.113.255.116 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2017-08-22 07:15:36.394759 [DEBUG] switch_core_codec.c:111 sofia/external/0664xxxxxxx@84.113.255.116 Original read codec set to PCMA:8
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4767 sofia/external/0664xxxxxxx@84.113.255.116 Set 2833 dtmf send payload to 101 recv payload to 101
Click to expand...

… but hen drops the call:
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4028 sofia/external/0664xxxxxxx@84.113.255.116 T38 REFUSE on request
2017-08-22 07:15:36.394759 [NOTICE] sofia.c:7565 Hangup sofia/external/0664xxxxxxx@84.113.255.116 [CS_NEW] [INCOMPATIBLE_DESTINATION]
Click to expand...

what makes me wonder is this line:
2017-08-22 07:15:36.394759 [DEBUG] switch_core_media.c:4028 sofia/external/0664xxxxxxx@84.113.255.116 T38 REFUSE on request

does that mean FPBX is assuming to receive a fax call?

all the same config on the old FPBX 4.0 works flawless…

pls. reply if you need further informations.
thanks in advance!
 
Last edited:
I'm not sure if that was fax coming in but its unusual to see that fax stuff in the incoming SDP, we don't really use fax here in the UK so I have never seen it myself.
 
Status
Not open for further replies.
Top Bottom