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
62
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
 
R

Robert Hilbert-Lahl

New Member
Aug 22, 2017
5
0
1
62
… 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:
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,044
565
113
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