TIPS external call - BAD Invite

[iossi01]

Hello,
I have a new Centos installation of Incredible PBX 2021.
It connects and registers to SIP provider.
The provider said that this error is caused by my side but I really don't know what to do...
Got SIP response 484 "Address Incomplete" back from...
When trying to make calls I get the following error log

[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/PARTNER_OUT-00000001", "TECH=SIP") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/PARTNER_OUT-00000001", "SIPHEADERKEYS=Alert-Info") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:4] While("SIP/PARTNER_OUT-00000001", "1") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:5] Set("SIP/PARTNER_OUT-00000001", "sipheader=unset") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:6] ExecIf("SIP/PARTNER_OUT-00000001", "1?SIPRemoveHeader(Alert-Info") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/PARTNER_OUT-00000001", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/PARTNER_OUT-00000001", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/PARTNER_OUT-00000001", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/PARTNER_OUT-00000001", "0?SIPAddHeader(Alert-Info:unset)") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/PARTNER_OUT-00000001", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile("SIP/PARTNER_OUT-00000001", "") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:4] While("SIP/PARTNER_OUT-00000001", "0") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@func-apply-sipheaders:13] Return("SIP/PARTNER_OUT-00000001", "") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] app_stack.c: Spawn extension (from-trunk, 03xxxxxxx, 1) exited non-zero on 'SIP/PARTNER_OUT-00000001'
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] app_stack.c: SIP/PARTNER_OUT-00000001 Internal Gosub(func-apply-sipheaders,s,1(17)) complete GOSUB_RETVAL=
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] app_dial.c: Called SIP/PARTNER_OUT/
[2021-10-16 08:26:48] VERBOSE[27449][C-00000001] chan_sip.c: Got SIP response 484 "Address Incomplete" back from 212.xxx.xxx.xxx:5060
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:30] NoOp("SIP/703-00000000", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 28") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/703-00000000", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2021-10-16 08:26:48] VERBOSE[18247][C-00000001] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)

from the server side, I see that I send my internal IP address instead of the external one and no number.
See attached file

Please advise what to do.
Thank you

 

[dallas]

Check that your external IP address is shown in Settings, Asterisk SIP Settings, General SIP Settings, NAT Settings, External Address.

 

[iossi01]

Hi. Thank you.
The external IP was defined in NAT.
It has to be something else....

 

[iossi01]

Later edit.
I tried an Echo test without success
Here is the log
Maybe will help...

[2021-10-16 10:00:43] VERBOSE[23748][C-00000008] netsock2.c: Using SIP RTP TOS bits 184
[2021-10-16 10:00:43] VERBOSE[23748][C-00000008] netsock2.c: Using SIP RTP CoS mark 5
[2021-10-16 10:00:43] VERBOSE[27717][C-00000008] pbx.c: Executing [*43@from-internal:1] Set("SIP/703-0000000c", "CONNECTEDLINE(name-charset,i)=utf8") in new stack
[2021-10-16 10:00:43] VERBOSE[27717][C-00000008] pbx.c: Executing [*43@from-internal:2] Set("SIP/703-0000000c", "CONNECTEDLINE(name,i)=Echo Test") in new stack
[2021-10-16 10:00:43] VERBOSE[27717][C-00000008] pbx.c: Executing [*43@from-internal:3] Set("SIP/703-0000000c", "CONNECTEDLINE(num,i)=*43") in new stack
[2021-10-16 10:00:43] VERBOSE[27717][C-00000008] pbx.c: Executing [*43@from-internal:4] Answer("SIP/703-0000000c", "") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [*43@from-internal:5] Macro("SIP/703-0000000c", "user-callerid,") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/703-0000000c", "TOUCH_MONITOR=1634367643.17") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/703-0000000c", "CHANCONTEXT=") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/703-0000000c", "CHANCONTEXT=") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/703-0000000c", "CHANEXTENCONTEXT=703-0000000c") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/703-0000000c", "CHANEXTEN=703-0000000c") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:6] Set("SIP/703-0000000c", "CALLERID(number)=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/703-0000000c", "AMPUSER=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:8] Set("SIP/703-0000000c", "HOTDESCKCHAN=703-0000000c") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/703-0000000c", "HOTDESKEXTEN=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/703-0000000c", "HOTDESKCALL=0") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("SIP/703-0000000c", "0?Set(HOTDESKCALL=1)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("SIP/703-0000000c", "0?Set(CALLERID(name)=)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/703-0000000c", "0?report") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/703-0000000c", "1?Set(REALCALLERIDNUM=703)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/703-0000000c", "AMPUSER=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("SIP/703-0000000c", "0?limit") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/703-0000000c", "AMPUSERCIDNAME=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("SIP/703-0000000c", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/703-0000000c", "0?report") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:20] Set("SIP/703-0000000c", "AMPUSERCID=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:21] Set("SIP/703-0000000c", "__DIAL_OPTIONS=HhTtr") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:22] Set("SIP/703-0000000c", "CALLERID(all)="703" <703>") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("SIP/703-0000000c", "0?Set(CUSDIAL=)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("SIP/703-0000000c", "0?Set(CALLERID(all)="703" <703>)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/703-0000000c", "0?limit") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("SIP/703-0000000c", "0?Set(GROUP(concurrency_limit)=703)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("SIP/703-0000000c", "0?Set(CHANNEL(language)=)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/703-0000000c", "Macro Depth is 1") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/703-0000000c", "1?report2:macroerror") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/703-0000000c", "0?continue") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/703-0000000c", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/703-0000000c", "__TTL=64") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/703-0000000c", "1?continue") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/703-0000000c", "CALLERID(number)=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/703-0000000c", "CALLERID(name)=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/703-0000000c", "0?cnum") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/703-0000000c", "CDR(cnam)=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/703-0000000c", "CDR(cnum)=703") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/703-0000000c", "CHANNEL(language)=en") in new stack
[2021-10-16 10:00:44] VERBOSE[27717][C-00000008] pbx.c: Executing [*43@from-internal:6] Wait("SIP/703-0000000c", "1") in new stack
[2021-10-16 10:00:45] VERBOSE[27717][C-00000008] pbx.c: Executing [*43@from-internal:7] BackGround("SIP/703-0000000c", "demo-echotest,,,app-echo-test-echo") in new stack
[2021-10-16 10:00:45] VERBOSE[27717][C-00000008] file.c: <SIP/703-0000000c> Playing 'demo-echotest.ulaw' (language 'en')
[2021-10-16 10:00:50] WARNING[23748] chan_sip.c: Retransmission timeout reached on transmission CAADA677F5627D5119FE598570B4D0F4D890C78E for seqno 2 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6400ms with no response
[2021-10-16 10:00:50] WARNING[23748] chan_sip.c: Hanging up call CAADA677F5627D5119FE598570B4D0F4D890C78E - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Spawn extension (from-internal, *43, 7) exited non-zero on 'SIP/703-0000000c'
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Executing [h@from-internal:1] Macro("SIP/703-0000000c", "hangupcall") in new stack
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/703-0000000c", "1?theend") in new stack
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/703-0000000c", "0?Set(CDR(recordingfile)=)") in new stack
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/703-0000000c", " montior file= ") in new stack
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/703-0000000c", "1?skipagi") in new stack
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/703-0000000c", "") in new stack
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/703-0000000c' in macro 'hangupcall'
[2021-10-16 10:00:50] VERBOSE[27717][C-00000008] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/703-0000000c'

 

[dallas]

You have some sort of network problem. Is your pbx behind a router? Please describe the network architecture.
Do you have a static public IP address?

Playing 'demo-echotest.ulaw' (language 'en')

chan_sip.c: Retransmission timeout reached on transmission

If this is a new installation I would suggest you nuke it and start again. You may have missed a step during the install.
Which install guide did you follow?

 

[kenn10]

When asking for help always specify whether you are using chan_sip or chan_pjsip for your trunks and/or extensions. Programming will differ.

When your PBX system is behind a local router (home or business router) you must specify that your system uses NAT. This tells the system to translate your local IP to the external IP in transmissions.

If your trunk is set up using chan_sip, be sure to add this in your trunk settings.

nat=yes

Also, under Settings > Advanced SIP Settings be sure NAT is set to yes:


If you extensions are using chan_sip, go to the extensions advanced settings screen and set this:


Save all your changes and restart your server. Then see if the problem is fixed.

If the problem persists, start over following this guide for installation but keep in mind that IncrediblePBX2020 is already deprecated and the current version is IncrediblePBX2021 which runs on Debian 10 instead of CentOS.

Meet Incredible PBX 2020 for CentOS 7 – Nerd Vittles

nerdvittles.com

Happy New Year: Introducing Incredible PBX 2021 for Debian – Nerd Vittles

nerdvittles.com

 

[tbrummell]

As others have pointed out, it's a NAT problem. No one has asked, does your router have a NAT helper in it? If yes, or not sure, investigate and disable. You don't mention where the SIP trace was taken from, but it is pointing to a NAT problem somewhere.

 

[iossi01]

Thank you all. It was my mistake - maybe because I worked late at night

 

[dallas]

Do you want to explain what went wrong for anyone who might search for this issue in the future.

 

[hawk#1]

If you read his newest post, He still has problems. Changed symptoms some, but still not working