No compatible codecs with opus offer

Hi David,
I also have the same issue:“No compatible codecs, not accepting this offer!”
[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:5847 do_setnat: Setting NAT on RTP to On

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10373 process_sdp: Processing session-level SDP v=0… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10373 process_sdp: Processing session-level SDP o=- 3771645758 3771645758 IN IP4 XX.XX.XX.YY… OK.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10373 process_sdp: Processing session-level SDP s=pjmedia… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10373 process_sdp: Processing session-level SDP b=AS:117… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10373 process_sdp: Processing session-level SDP t=0 0… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10373 process_sdp: Processing session-level SDP a=X-nat:0… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XX.XX.XX.YY’ into…

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XX.XX.XX.YY’ and port ‘’.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP c=IN IP4 XX.XX.XX.YY… OK.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP b=TIAS:96000… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=rtcp:4001 IN IP4 XX.XX.XX.YY… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=sendrecv… OK.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=rtpmap:120 opus/48000/2… OK.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=fmtp:120 useinbandfec=1… OK.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000… OK.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=fmtp:96 0-16… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=ssrc:57777923 cname:7de6bd8537a4a756… UNSUPPORTED OR FAILED.

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: rtp_engine.c:1268 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 96 (0x7fb8b818fe88) from 0x7fb9326f7fd0 to 0x7fb9326f7fd0

[2019-07-09 10:22:38] DEBUG[2378][C-00006028]: rtp_engine.c:1268 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 120 (0x7fb8b81135d8) from 0x7fb9326f7fd0 to 0x7fb9326f7fd0

[2019-07-09 10:22:38] NOTICE[2378][C-00006028]: chan_sip.c:10936 process_sdp: No compatible codecs, not accepting this offer!

Your help is really appreciated.
Thank you

There is insufficient information. You have nor provided any information on the codecs that are acceptable.

Hi David,
From client side we only activate opus codec.But asterisk server support the following codecs:

     Translation times between formats (in microseconds) for one second of data
      Source Format (Rows) Destination Format (Columns)

      codec2  ulaw  alaw   gsm  g726 g726aal2 adpcm slin8 slin12 slin16 slin24 slin32 slin44 slin48 slin96 slin192 lpc10  g729 speex8 speex16 speex32  ilbc  g722 testlaw  opus

codec2 - 15000 15000 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 15000 23000
ulaw 15000 - 9150 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 15000 23000
alaw 15000 9150 - 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 15000 23000
gsm 15000 15000 15000 - 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 15000 23000
g726 15000 15000 15000 15000 - 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 15000 23000
g726aal2 15000 15000 15000 15000 15000 - 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 15000 23000
adpcm 15000 15000 15000 15000 15000 15000 - 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 15000 23000
slin8 6000 6000 6000 6000 6000 6000 6000 - 8000 8000 8000 8000 8000 8000 8000 8000 6000 6000 6000 14000 14000 6000 8250 6000 14000
slin12 14500 14500 14500 14500 14500 14500 14500 8500 - 8000 8000 8000 8000 8000 8000 8000 14500 14500 14500 14000 14000 14500 14000 14500 14000
slin16 14500 14500 14500 14500 14500 14500 14500 8500 8500 - 8000 8000 8000 8000 8000 8000 14500 14500 14500 6000 14000 14500 6000 14500 14000
slin24 14500 14500 14500 14500 14500 14500 14500 8500 8500 8500 - 8000 8000 8000 8000 8000 14500 14500 14500 14500 14000 14500 14500 14500 14000
slin32 14500 14500 14500 14500 14500 14500 14500 8500 8500 8500 8500 - 8000 8000 8000 8000 14500 14500 14500 14500 6000 14500 14500 14500 14000
slin44 14500 14500 14500 14500 14500 14500 14500 8500 8500 8500 8500 8500 - 8000 8000 8000 14500 14500 14500 14500 14500 14500 14500 14500 14000
slin48 14500 14500 14500 14500 14500 14500 14500 8500 8500 8500 8500 8500 8500 - 8000 8000 14500 14500 14500 14500 14500 14500 14500 14500 6000
slin96 14500 14500 14500 14500 14500 14500 14500 8500 8500 8500 8500 8500 8500 8500 - 8000 14500 14500 14500 14500 14500 14500 14500 14500 14500
slin192 14500 14500 14500 14500 14500 14500 14500 8500 8500 8500 8500 8500 8500 8500 8500 - 14500 14500 14500 14500 14500 14500 14500 14500 14500
lpc10 15000 15000 15000 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 - 15000 15000 23000 23000 15000 17250 15000 23000
g729 15000 15000 15000 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 - 15000 23000 23000 15000 17250 15000 23000
speex8 15000 15000 15000 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 - 23000 23000 15000 17250 15000 23000
speex16 23500 23500 23500 23500 23500 23500 23500 17500 17500 9000 17000 17000 17000 17000 17000 17000 23500 23500 23500 - 23000 23500 15000 23500 23000
speex32 23500 23500 23500 23500 23500 23500 23500 17500 17500 17500 17500 9000 17000 17000 17000 17000 23500 23500 23500 23500 - 23500 23500 23500 23000
ilbc 15000 15000 15000 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 - 17250 15000 23000
g722 15600 15600 15600 15600 15600 15600 15600 9600 17500 9000 17000 17000 17000 17000 17000 17000 15600 15600 15600 15000 23000 15600 - 15600 23000
testlaw 15000 15000 15000 15000 15000 15000 15000 9000 17000 17000 17000 17000 17000 17000 17000 17000 15000 15000 15000 23000 23000 15000 17250 - 23000
opus 23500 23500 23500 23500 23500 23500 23500 17500 17500 17500 17500 17500 17500 9000 17000 17000 23500 23500 23500 23500 23500 23500 23500 23500 -

Best regards,

Those are the codecs which can be transcoded. Those aren’t the ones allowed in the SIP configuration for the device in question.

HI again,
Please find below the sip.conf file.
;--------------------------------------------------------------------------------;
; Do NOT edit this file as it is auto-generated by FreePBX. ;
;--------------------------------------------------------------------------------;
; For information on adding additional paramaters to this file, please visit the ;
; FreePBX.org wiki page, or ask on IRC. This file was created by the new FreePBX ;
; BMO - Big Module Object. Any similarity in naming with BMO from Adventure Time ;
; is totally deliberate. ;
;--------------------------------------------------------------------------------;
faxdetect=no
vmexten=*97
useragent=FPBX-15.0.16(16.3.0)
language=en
disallow=all
allow=opus
allow=g729
allow=g722
context=from-sip-external
callerid=Unknown
notifyringing=yes
notifyhold=yes
tos_sip=cs3
tos_audio=ef
tos_video=af41
alwaysauthreject=yes
limitonpeers=yes
t1min=50
timert1=50
rtpend=20000
context=from-sip
callerid=Unknown
rtpstart=10000
tcpenable=no
callevents=no
bindport=5060
bindaddr=0.0.0.0
jbenable=no
checkmwi=10
maxexpiry=3600
minexpiry=120
srvlookup=no
tlsenable=no
allowguest=no
notifyhold=yes
rtptimeout=30
canreinvite=no
tlsbindaddr=[::]:5161
rtpkeepalive=0
videosupport=no
defaultexpiry=120
notifyringing=yes
maxcallbitrate=384
rtpholdtimeout=300
g726nonstandard=no
registertimeout=20
tlsclientmethod=tlsv1
registerattempts=0
nat=force_rport,comedia
ALLOW_SIP_ANON=no
tlscafile=/etc/ssl/certs/ca-certificates.crt
localnet=XXX.XXX.XXX.0/25

Thank you for your help.

That is the global configuration and does not have the specific configuration for the endpoint in question. You’d need to find it in the respective .conf or in the FreePBX UI.

Hii,
Sorry I am new in asterisk, what do you mean by respective .conf ? do you mean codex.conf?
[speex]
; CBR encoding quality [0…10]
; used only when vbr = false
quality => 3

; codec complexity [0…10]
; tradeoff between cpu/quality
complexity => 2

; perceptual enhancement [true / false]
; improves clarity of decoded speech
enhancement => true

; voice activity detection [true / false]
; reduces bitrate when no voice detected, used only for CBR
; (implicit in VBR/ABR)
vad => true

; variable bit rate [true / false]
; uses bit rate proportionate to voice complexity
vbr => true

; available bit rate [bps, 0 = off]
; encoding quality modulated to match this target bit rate
; not recommended with dtx or pp_vad - may cause bandwidth spikes
abr => 0

; VBR encoding quality [0-10]
; floating-point values allowed
vbr_quality => 4

; discontinuous transmission [true / false]
; stops transmitting completely when silence is detected
; pp_vad is far more effective but more CPU intensive
dtx => false

; preprocessor configuration
; these options only affect Speex v1.1.8 or newer

; enable preprocessor [true / false]
; allows dsp functionality below but incurs CPU overhead
preprocess => false

; preproc voice activity detection [true / false]
; more advanced equivalent of DTX, based on voice frequencies
pp_vad => false

; preproc automatic gain control [true / false]
pp_agc => false
pp_agc_level => 8000

; preproc denoiser [true / false]
pp_denoise => false

; preproc dereverb [true / false]
pp_dereverb => false
pp_dereverb_decay => 0.4
pp_dereverb_level => 0.3

; experimental bitrate changes depending on RTCP feedback [true / false]
experimental_rtcp_feedback => false

[plc]
; for all codecs which do not support native PLC
; this determines whether to perform generic PLC
; there is a minor performance penalty for this.
; By default plc is applied only when the 2 codecs
; in a channel are different.
genericplc => true
; Apply generic plc to channels even if the 2 codecs
; are the same. This forces transcoding via slin so
; the performance impact should be considered.
; Ignored if genericplc is not also enabled.
genericplc_on_equal_codecs => false

; Generate custom formats for formats requiring attributes.
; After defining the custom format, the name used in defining
; the format can be used throughout Asterisk in the format ‘allow’
; and ‘disallow’ options.
;
; Example: silk8 is a predefined custom format in this config file.
; Once this config file is loaded, silk8 can be used anywhere a
; peer’s codec capabilities are defined.
;
; In sip.conf ‘silk8’ can be defined as a capability for a peer.
; [peer1]
; type=peer
; host=dynamic
; disallow=all
; allow=silk8 ;custom codec defined in codecs.conf
;
; LIMITATIONS
; Custom formats can only be defined at startup. Any changes to this
; file made after startup will not take into effect until after Asterisk
; is restarted.
;

; Default Custom SILK format definitions, only one custom SILK format per
; sample rate is allowed.
[silk8]
type=silk
samprate=8000
fec=true ; turn on or off encoding with forward error correction.
; On recommended, off by default.
packetloss_percentage=10 ; Estimated packet loss percentage in uplink direction. This
; affects how much redundancy is built in when using fec.
; The higher the percentage, the larger amount of bandwidth is
; used. Default is 0%, 10% is recommended when fec is in use.

maxbitrate=10000 ; Use the table below to make sure a useful bitrate is choosen
; for maxbitrate. If not set or value is not within the bounds
; of the encoder, a default value is chosen.
;
; sample rate | bitrate range
; 8khz | 5000 - 20000 bps
; 12khz | 7000 - 25000 bps
; 16khz | 8000 - 30000 bps
; 24khz | 20000- 40000 bps
;
;dtx=true ; Encode using discontinuous transmission mode or not. Turning this
; on will save bandwidth during periods of silence at the cost of
; increased computational complexity. Off by default.

[silk12]
type=silk
samprate=12000
maxbitrate=12000
fec=true
packetloss_percentage=10;

[silk16]
type=silk
samprate=16000
maxbitrate=20000
fec=true
packetloss_percentage=10;

[silk24]
type=silk
samprate=24000
maxbitrate=30000
fec=true
packetloss_percentage=10;

; Default custom CELT codec definitions. Only one custom CELT definition is allowed
; per a sample rate.
;[celt44]
;type=celt
;samprate=44100 ; The samplerate in hz. This option is required.
;framesize=480 ; The framesize option represents the duration of each frame in samples.
; This must be a factor of 2. This option is only advertised in an SDP
; when it is set. Otherwise a default of framesize of 480 is assumed
; internally

;[celt48]
;type=celt
;samprate=48000

;[celt32]
;type=celt
;samprate=32000

;============================ OPUS Section Options ============================
;
; NOTE: Accurate documentation corresponding to your downloaded version of
; codec_opus is available from Asterisk’s CLI:
;
; *CLI> config show help codec_opus opus
;
;[opus]
;type= ; Must be of type “opus” (default: “”)
;packet_loss= ; Encoder’s packet loss percentage. Can be any number between 0
; and 100, inclusive. A higher value results in more loss
; resistance. (default: 0)
;complexity= ; Encoder’s computational complexity. Can be any number between 0
; and 10, inclusive. Note, 10 equals the highest complexity.
; (default: 10)
;max_bandwidth= ; Encoder’s maximum bandwidth allowed. Sets an upper bandwidth
; bound on the encoder. Can be any of the following: narrow,
; medium, wide, super_wide, full. (default: full)
;signal= ; Encoder’s signal type. Aids in mode selection on the encoder: Can
; be any of the following: auto, voice, music. (default: auto)
;application= ; Encoder’s application type. Can be any of the following: voip,
; audio, low_delay. (default: voip)
;max_playback_rate= ; Override the maximum playback rate in the offer’s SDP.
; Any value between 8000 and 48000 (inclusive) is valid,
; however typically it should match one of the usual opus
; bandwidths. (default: 48000)
;bitrate= ; Override the maximum average bitrate in the offer’s SDP. Any value
; between 500 and 512000 is valid. The following values are also
; allowed: auto, max. (default: auto)
;cbr= ; Override the constant bit rate parameter in the offer’s SDP. A value of
; 0/false/no represents a variable bit rate whereas 1/true/yes represents
; a constant bit rate. (default: no)
;fec= ; Override the use inband fec parameter in the offer’s SDP. A value of
; 0/false/no represents disabled whereas 1/true/yes represents enabled.
; (default: yes)
;dtx= ; Override the use dtx parameter in the offer’s SDP. A value of 0/false/no
; represents disabled whereas 1/true/yes represents enabled. (default: no)

;=============================== OPUS Examples ================================
;
;[opus]
;type=opus
;max_playback_rate=8000 ; Limit the maximum playback rate on the encoder
;fec=no ; No inband fec

;[myopus]
;type=opus
;max_bandwidth=wide ; Maximum encoded bandwidth set to wide band (0-8000 Hz
; ; audio bandwidth at 16Khz sample rate)
;cbr=yes ; Negotiate a constant bit rate

[opus]
type=opus
fec=yes
packet_loss=30
dtx=yes
cbr=yes
max_bandwidth=full
max_playback_rate=48000
bitrate=auto
signal=auto
application=voip

Your helps really appreciated.

No, in Asterisk there are various .conf files which configure things. FreePBX is creating those for you, including a configuration entry for the device. Since you are using FreePBX you should just look in the FreePBX web UI and find it.

You are missing the summary line that says lists the codecs offered and acceptable and the resulting selection.

Also the m= line seems to be missing from your log, event though it must have been there for Asterisk to recognize the presence of media level audio.

Can you confirm that the Asterisk daemon is a server in this scenario, as it is not possible to tell from the logs, and people tend to use server in a sense other than that use by SIP.

Hii , Yes I can access all the the conf files generated by FReePBX, but I can not find the file in question ?can u provide me the name plz?

It will be somewhere in the transitive closure, based on #include relations, of files based on /etc/asterisk/sip.conf and /etc/asterisk/users.conf.

Hii David,
Thank your for your time.
I am currently using Asterisk 16.3.0, and please find a more detailed log for my problem, I hope that it could help:
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:9467 __find_call: = Looking for Call ID: FeWRW2fyrtQz4r8L1rFqd.6nrHaajtsD (Checking From) --From tag anWwaUHQGwlD9zKHxar0kwJItiVpdRpD --To-tag
[2019-07-10 15:39:48] DEBUG[2298]: acl.c:990 ast_ouraddrfor: For destination ‘XXX.XXX.XXX.ZZZ’, our source address is ‘XXX.XXX.XXX.YYY’.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3958 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:9060 __sip_alloc: Allocating new SIP dialog for FeWRW2fyrtQz4r8L1rFqd.6nrHaajtsD - INVITE (No RTP)
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:29072 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1709 parse_sip_options: Begin: parsing SIP “Supported: replaces, 100rel, timer, norefersub”
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1724 parse_sip_options: Found SIP option: -replaces-
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1732 parse_sip_options: Matched SIP option: replaces
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1724 parse_sip_options: Found SIP option: -100rel-
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1732 parse_sip_options: Matched SIP option: 100rel
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1724 parse_sip_options: Found SIP option: -timer-
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1732 parse_sip_options: Matched SIP option: timer
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1724 parse_sip_options: Found SIP option: -norefersub-
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: sip/reqresp_parser.c:1732 parse_sip_options: Matched SIP option: norefersub
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.YYY’ into…
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.YYY’ and port ‘’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = ‘NDV952STS’ AND host = ‘dynamic’
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE host = ‘XXX.XXX.XXX.ZZZ’ AND callbackextension = ‘OOG105EXA’ AND port = ‘57216’
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE host = ‘XXX.XXX.XXX.ZZZ’ AND port = ‘57216’
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE ipaddr = ‘XXX.XXX.XXX.ZZZ’ AND port = ‘57216’
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: stasis.c:458 stasis_topic_create: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b8070dc0 created
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: stasis.c:458 stasis_topic_create: Topic ‘cache:893/endpoint:SIP/NDV952STS’: 0x7fa9b8034a00 created
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:31595 build_peer: -REALTIME- peer built. Name: NDV952STS. Peer objects: 3
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ’ into…
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:32230 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:32233 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2019-07-10 15:39:48] DEBUG[2078]: threadpool.c:536 grow: Increasing threadpool stasis/pool’s size by 1
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:9060 __sip_alloc: Allocating new SIP dialog for 468fbb31468bb1364a29a75055b9e4df@XXX.XXX.XXX.YYY:5060 - NOTIFY (No RTP)
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: acl.c:990 ast_ouraddrfor: For destination ‘XXX.XXX.XXX.ZZZ’, our source address is ‘XXX.XXX.XXX.YYY’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:3958 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:8849 change_callid_pvt: SIP call-id changed from ‘468fbb31468bb1364a29a75055b9e4df@XXX.XXX.XXX.YYY:5060’ to ‘612643411da81a280a2034ae04e19054@XXX.XXX.XXX.YYY:5060’
[2019-07-10 15:39:48] DEBUG[2078]: threadpool.c:536 grow: Increasing threadpool stasis/pool’s size by 1
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:3444 initialize_initreq: Initializing initreq for method NOTIFY - callid 612643411da81a280a2034ae04e19054@XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:3801 __sip_xmit: Trying to put ‘NOTIFY sip:’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:5712 realtime_peer: -REALTIME- loading peer from database to memory. Name: NDV952STS. Peer objects: 3
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:3801 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE host = ‘XXX.XXX.XXX.ZZZ’ AND port = ‘57216’
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE ipaddr = ‘XXX.XXX.XXX.ZZZ’ AND port = ‘57216’
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: stasis.c:458 stasis_topic_create: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b8046230 created
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: stasis.c:458 stasis_topic_create: Topic ‘cache:894/endpoint:SIP/NDV952STS’: 0x7fa9b8070f60 created
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:31595 build_peer: -REALTIME- peer built. Name: NDV952STS. Peer objects: 4
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ’ into…
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:32230 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:32233 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:9060 __sip_alloc: Allocating new SIP dialog for 78cc396a1de32c7b6926b28728122097@XXX.XXX.XXX.YYY:5060 - NOTIFY (No RTP)
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: acl.c:990 ast_ouraddrfor: For destination ‘XXX.XXX.XXX.ZZZ’, our source address is ‘XXX.XXX.XXX.YYY’.
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:3958 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2078]: threadpool.c:536 grow: Increasing threadpool stasis/pool’s size by 1
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:8849 change_callid_pvt: SIP call-id changed from ‘78cc396a1de32c7b6926b28728122097@XXX.XXX.XXX.YYY:5060’ to ‘7e2efd822dcd36e4481b1e7974ed0b5f@XXX.XXX.XXX.YYY:5060’
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:3444 initialize_initreq: Initializing initreq for method NOTIFY - callid 7e2efd822dcd36e4481b1e7974ed0b5f@XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:3801 __sip_xmit: Trying to put ‘NOTIFY sip:’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:48] DEBUG[2298][C-00000009]: chan_sip.c:5712 realtime_peer: -REALTIME- loading peer from database to memory. Name: NDV952STS. Peer objects: 4
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5287 sip_destroy_peer: Destroying SIP peer NDV952STS
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5326 sip_destroy_peer: -REALTIME- peer Destroyed. Name: NDV952STS. Realtime Peer objects: 3
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5287 sip_destroy_peer: Destroying SIP peer NDV952STS
[2019-07-10 15:39:48] DEBUG[58404]: stasis.c:403 topic_dtor: Topic ‘cache:893/endpoint:SIP/NDV952STS’: 0x7fa9b8034a00 destroyed
[2019-07-10 15:39:48] DEBUG[58404]: stasis.c:403 topic_dtor: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b8070dc0 destroyed
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5326 sip_destroy_peer: -REALTIME- peer Destroyed. Name: NDV952STS. Realtime Peer objects: 2
[2019-07-10 15:39:48] DEBUG[2298]: stasis_cache.c:912 caching_topic_exec: Attempting to remove an item from the cache:894/endpoint:SIP/NDV952STS cache that isn’t there: ast_endpoint_snapshot_type SIP/NDV952STS
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:9467 __find_call: = Looking for Call ID: K.SwnDP1PTPMjGQx90Ie9Fyngp5Sx-sj (Checking From) --From tag 3w-5O.ZaEKZxdg3vEt74qzKsc1dFS8u- --To-tag
[2019-07-10 15:39:48] DEBUG[58403]: stasis.c:403 topic_dtor: Topic ‘cache:894/endpoint:SIP/NDV952STS’: 0x7fa9b8070f60 destroyed
[2019-07-10 15:39:48] DEBUG[2298]: acl.c:990 ast_ouraddrfor: For destination ‘XXX.XXX.XXX.ZZZ’, our source address is ‘XXX.XXX.XXX.YYY’.
[2019-07-10 15:39:48] DEBUG[58403]: stasis.c:403 topic_dtor: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b8046230 destroyed
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3958 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:9060 __sip_alloc: Allocating new SIP dialog for K.SwnDP1PTPMjGQx90Ie9Fyngp5Sx-sj - REGISTER (No RTP)
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:29072 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.YYY’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.YYY’ and port ‘’.
[2019-07-10 15:39:48] DEBUG[2298]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = ‘NDV952STS’ AND host = ‘dynamic’
[2019-07-10 15:39:48] DEBUG[2298]: stasis.c:458 stasis_topic_create: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b8047930 created
[2019-07-10 15:39:48] DEBUG[2298]: stasis.c:458 stasis_topic_create: Topic ‘cache:895/endpoint:SIP/NDV952STS’: 0x7fa9b8092430 created
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:31595 build_peer: -REALTIME- peer built. Name: NDV952STS. Peer objects: 3
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘’.
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:32230 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:32233 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:9060 __sip_alloc: Allocating new SIP dialog for 443594002b4c29694a94d4597d172adf@XXX.XXX.XXX.YYY:5060 - NOTIFY (No RTP)
[2019-07-10 15:39:48] DEBUG[2298]: acl.c:990 ast_ouraddrfor: For destination ‘XXX.XXX.XXX.ZZZ’, our source address is ‘XXX.XXX.XXX.YYY’.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3958 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:8849 change_callid_pvt: SIP call-id changed from ‘443594002b4c29694a94d4597d172adf@XXX.XXX.XXX.YYY:5060’ to ‘05310b5a5055e09e3945136b04bdcc49@XXX.XXX.XXX.YYY:5060’
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3444 initialize_initreq: Initializing initreq for method NOTIFY - callid 05310b5a5055e09e3945136b04bdcc49@XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3801 __sip_xmit: Trying to put ‘NOTIFY sip:’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5712 realtime_peer: -REALTIME- loading peer from database to memory. Name: NDV952STS. Peer objects: 3
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3801 __sip_xmit: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:48] DEBUG[2298]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:48] DEBUG[2298]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = ‘NDV952STS’ AND host = ‘dynamic’
[2019-07-10 15:39:48] DEBUG[2298]: stasis.c:458 stasis_topic_create: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b80ad940 created
[2019-07-10 15:39:48] DEBUG[2298]: stasis.c:458 stasis_topic_create: Topic ‘cache:896/endpoint:SIP/NDV952STS’: 0x7fa9b8047d20 created
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:31595 build_peer: -REALTIME- peer built. Name: NDV952STS. Peer objects: 4
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘’.
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:48] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:32230 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:32233 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:9060 __sip_alloc: Allocating new SIP dialog for 201792294771c5eb058bd3186d6905d3@XXX.XXX.XXX.YYY:5060 - NOTIFY (No RTP)
[2019-07-10 15:39:48] DEBUG[2298]: acl.c:990 ast_ouraddrfor: For destination ‘XXX.XXX.XXX.ZZZ’, our source address is ‘XXX.XXX.XXX.YYY’.
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3958 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:8849 change_callid_pvt: SIP call-id changed from ‘201792294771c5eb058bd3186d6905d3@XXX.XXX.XXX.YYY:5060’ to ‘486392de2f1e001a0598d0f007cfa8f0@XXX.XXX.XXX.YYY:5060’
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3444 initialize_initreq: Initializing initreq for method NOTIFY - callid 486392de2f1e001a0598d0f007cfa8f0@XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3801 __sip_xmit: Trying to put ‘NOTIFY sip:’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5712 realtime_peer: -REALTIME- loading peer from database to memory. Name: NDV952STS. Peer objects: 4
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5287 sip_destroy_peer: Destroying SIP peer NDV952STS
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5326 sip_destroy_peer: -REALTIME- peer Destroyed. Name: NDV952STS. Realtime Peer objects: 3
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5287 sip_destroy_peer: Destroying SIP peer NDV952STS
[2019-07-10 15:39:48] DEBUG[58402]: stasis.c:403 topic_dtor: Topic ‘cache:895/endpoint:SIP/NDV952STS’: 0x7fa9b8092430 destroyed
[2019-07-10 15:39:48] DEBUG[58402]: stasis.c:403 topic_dtor: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b8047930 destroyed
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:5326 sip_destroy_peer: -REALTIME- peer Destroyed. Name: NDV952STS. Realtime Peer objects: 2
[2019-07-10 15:39:48] DEBUG[2298]: stasis_cache.c:912 caching_topic_exec: Attempting to remove an item from the cache:896/endpoint:SIP/NDV952STS cache that isn’t there: ast_endpoint_snapshot_type SIP/NDV952STS
[2019-07-10 15:39:48] DEBUG[58402]: stasis.c:403 topic_dtor: Topic ‘cache:896/endpoint:SIP/NDV952STS’: 0x7fa9b8047d20 destroyed
[2019-07-10 15:39:48] DEBUG[58402]: stasis.c:403 topic_dtor: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b80ad940 destroyed
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:4082 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 100 ms (t1 50 ms (Retrans id #36))
[2019-07-10 15:39:48] DEBUG[2298]: chan_sip.c:3801 __sip_xmit: Trying to put ‘NOTIFY sip:’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:50] DEBUG[2298]: chan_sip.c:9467 __find_call: = Looking for Call ID: FeWRW2fyrtQz4r8L1rFqd.6nrHaajtsD (Checking From) --From tag anWwaUHQGwlD9zKHxar0kwJItiVpdRpD --To-tag
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:29072 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:29097 handle_incoming: Ignoring SIP message because of retransmit (INVITE Seqno 12977, ours 12977)
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:26108 handle_request_invite_st: Incoming INVITE with ‘timer’ option supported
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:26118 handle_request_invite_st: INVITE also has “Session-Expires” header.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:30218 parse_session_expires: Session-Expires: 1800
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:26130 handle_request_invite_st: INVITE also has “Min-SE” header.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:30188 parse_minse: Received Min-SE: 90
[2019-07-10 15:39:50] DEBUG[2298]: chan_sip.c:9467 __find_call: = Looking for Call ID: FeWRW2fyrtQz4r8L1rFqd.6nrHaajtsD (Checking From) --From tag anWwaUHQGwlD9zKHxar0kwJItiVpdRpD --To-tag as543c2c28
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:29072 handle_incoming: **** Received ACK (6) - Command in SIP ACK
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:4585 __sip_ack: Stopping retransmission on ‘FeWRW2fyrtQz4r8L1rFqd.6nrHaajtsD’ of Response 12977: Match Found
[2019-07-10 15:39:50] DEBUG[2298]: chan_sip.c:9467 __find_call: = Looking for Call ID: FeWRW2fyrtQz4r8L1rFqd.6nrHaajtsD (Checking From) --From tag anWwaUHQGwlD9zKHxar0kwJItiVpdRpD --To-tag
[2019-07-10 15:39:50] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.YYY’ into…
[2019-07-10 15:39:50] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.YYY’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.YYY’ into…
[2019-07-10 15:39:50] DEBUG[2298]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.YYY’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:29072 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.YYY’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.YYY’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = ‘NDV952STS’ AND host = ‘dynamic’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE host = ‘XXX.XXX.XXX.ZZZ’ AND callbackextension = ‘OOG105EXA’ AND port = ‘57216’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE host = ‘XXX.XXX.XXX.ZZZ’ AND port = ‘57216’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:1389 mysql_reconnect: MySQL RealTime: Connection okay.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_config_mysql.c:378 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE ipaddr = ‘XXX.XXX.XXX.ZZZ’ AND port = ‘57216’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: stasis.c:458 stasis_topic_create: Topic ‘endpoint:SIP/NDV952STS’: 0x7fa9b8022250 created
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: stasis.c:458 stasis_topic_create: Topic ‘cache:897/endpoint:SIP/NDV952STS’: 0x7fa9b80429f0 created
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:31595 build_peer: -REALTIME- peer built. Name: NDV952STS. Peer objects: 3
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.ZZZ:57216’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.ZZZ’ and port ‘57216’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:32230 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:32233 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:9060 __sip_alloc: Allocating new SIP dialog for 02e5936749c7eef314bf18117318b0e8@XXX.XXX.XXX.YYY:5060 - NOTIFY (No RTP)
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: acl.c:990 ast_ouraddrfor: For destination ‘XXX.XXX.XXX.ZZZ’, our source address is ‘XXX.XXX.XXX.YYY’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:3958 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:8849 change_callid_pvt: SIP call-id changed from ‘02e5936749c7eef314bf18117318b0e8@XXX.XXX.XXX.YYY:5060’ to ‘7c49f7607d494fd641f9178114b21612@XXX.XXX.XXX.YYY:5060’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:3444 initialize_initreq: Initializing initreq for method NOTIFY - callid 7c49f7607d494fd641f9178114b21612@XXX.XXX.XXX.YYY:5060
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:3801 __sip_xmit: Trying to put ‘NOTIFY sip:’ onto UDP socket destined for XXX.XXX.XXX.ZZZ:57216
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:5712 realtime_peer: -REALTIME- loading peer from database to memory. Name: NDV952STS. Peer objects: 3
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: rtp_engine.c:511 ast_rtp_instance_new: Using engine ‘asterisk’ for RTP instance ‘0x7fa9b8071a70’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_rtp_asterisk.c:3510 rtp_allocate_transport: Allocated port 11164 for RTP instance ‘0x7fa9b8071a70’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_rtp_asterisk.c:3540 rtp_allocate_transport: Creating ICE session 0.0.0.0:11164 (11164) for RTP instance ‘0x7fa9b8071a70’
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.YYY’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.YYY’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.YYY’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.YYY’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: rtp_engine.c:528 ast_rtp_instance_new: RTP instance ‘0x7fa9b8071a70’ is setup and ready to go
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘ubuntu18-asterisk16-98’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘ubuntu18-asterisk16-98’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: acl.c:828 resolve_first: Multiple addresses. Using the first only
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: res_rtp_asterisk.c:7301 ast_rtp_prop_set: Setup RTCP on RTP instance ‘0x7fa9b8071a70’
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:5847 do_setnat: Setting NAT on RTP to On
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10373 process_sdp: Processing session-level SDP v=0… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10373 process_sdp: Processing session-level SDP o=- 3771751187 3771751187 IN IP4 XXX.XXX.XXX.XXX… OK.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10373 process_sdp: Processing session-level SDP s=pjmedia… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10373 process_sdp: Processing session-level SDP b=AS:117… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10373 process_sdp: Processing session-level SDP t=0 0… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10373 process_sdp: Processing session-level SDP a=X-nat:0… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting ‘XXX.XXX.XXX.XXX’ into…
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: netsock2.c:224 ast_sockaddr_split_hostport: …host ‘XXX.XXX.XXX.XXX’ and port ‘’.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP c=IN IP4 XXX.XXX.XXX.XXX… OK.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP b=TIAS:96000… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=rtcp:4001 IN IP4 XXX.XXX.XXX.XXX… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=sendrecv… OK.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=rtpmap:120 opus/48000/2… OK.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=fmtp:120 useinbandfec=1… OK.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000… OK.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=fmtp:96 0-16… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: chan_sip.c:10844 process_sdp: Processing media-level (audio) SDP a=ssrc:1538710237 cname:0349ef516afeb086… UNSUPPORTED OR FAILED.
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: rtp_engine.c:1268 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 96 (0x7fa9b8021848) from 0x7faa0749efd0 to 0x7faa0749efd0
[2019-07-10 15:39:50] DEBUG[2298][C-00000009]: rtp_engine.c:1268 ast_rtp_codecs_payloads_xover: Crossover copying tx to rx payload mapping 120 (0x7fa9b8064d78) from 0x7faa0749efd0 to 0x7faa0749efd0
[2019-07-10 15:39:50] NOTICE[2298][C-00000009]: chan_sip.c:10936 process_sdp: No compatible codecs, not accepting this offer!

And here is the users.conf file:
;
; User configuration
;
; Creating entries in users.conf is a “shorthand” for creating individual
; entries in each configuration file. Using users.conf is not intended to
; provide you with as much flexibility as using the separate configuration
; files (e.g. sip.conf, iax.conf, etc) but is intended to accelerate the
; simple task of adding users. Note that creating individual items (e.g.
; custom SIP peers, IAX friends, etc.) will allow you to override specific
; parameters within this file. Parameter names here are the same as they
; appear in the other configuration files. There is no way to change the
; value of a parameter here for just one subsystem.
;

[general]
;
; Full name of a user
;
fullname = New User
;
; Starting point of allocation of extensions
;
userbase = 6000
;
; Create voicemail mailbox and use use macro-stdexten
;
hasvoicemail = yes
;
; Set voicemail mailbox 6000 password to 1234
;
vmsecret = 1234
;
; Create SIP Peer
;
hassip = yes
;
; Create IAX friend
;
hasiax = yes
;
; Create H.323 friend
;
;hash323 = yes
;
; Create manager entry
;
hasmanager = no
;
; Set permissions for manager entry (see manager.conf.sample for documentation)
; (defaults to all permissions)
;managerread = system,call,log,verbose,command,agent,user,config
;managerwrite = system,call,log,verbose,command,agent,user,config
;
;
; MAC Address for res_phoneprov
;
;macaddress = 112233445566
;
; Auto provision the phone with res_phoneprov
;
;autoprov = yes
;
; Line Keys for hardphone
;
;LINEKEYS = 1
;
; Line number for hardphone
;
;linenumber = 1
;
; Local Caller ID number used with res_phoneprov and Asterisk GUI
;
;cid_number = 6000
;
; Remaining options are not specific to users.conf entries but are general.
;
callwaiting = yes
threewaycalling = yes
callwaitingcallerid = yes
transfer = yes
canpark = yes
cancallforward = yes
callreturn = yes
callgroup = 1
pickupgroup = 1
;nat = no

;[6000]
;fullname = Joe User
;description = Courtesy Phone In Lobby ; Used to provide a description of the
; peer in console output
;email = joe@foo.bar
;secret = 1234
;dahdichan = 1
;hasvoicemail = yes
;vmsecret = 1234
;hassip = yes
;hasiax = no
;hash323 = no
;hasmanager = no
;callwaiting = no
;context = international
;
; Some administrators choose alphanumeric extensions, but still want their
; users to be reachable by traditional numeric extensions, specified by the
; alternateexts entry.
;
;alternateexts = 7057,3249
;macaddress = 112233445566
;autoprov = yes
;LINEKEYS = 1
;linenumber = 1
;cid_number = 6000

You need the verbose message stream as well, typically taken from the full log, as this message (taken from an old version of hte source code) is critical to understanding what is happening and we need to see the raw SIP messages:

		ast_verbose("Capabilities: us - %s, peer - audio=%s/video=%s/text=%s, combined - %s\n",
			    ast_getformatname_multiple(s1, SIPBUFSIZE, p->capability),
			    ast_getformatname_multiple(s2, SIPBUFSIZE, peercapability),
			    ast_getformatname_multiple(s3, SIPBUFSIZE, vpeercapability),
			    ast_getformatname_multiple(s4, SIPBUFSIZE, tpeercapability),
			    ast_getformatname_multiple(s5, SIPBUFSIZE, newjointcapability));

Hi David,
Please find attached the full log with verbose message.

Best regardslogwithverbose.txt (47.5 KB)

Your verbosity level is too low. Generally 5 is recommended for debugging.

Hii David,
Yes the verbosity level for the attached log was 5. That’s how I change the verbosity level:
command ubuntu = asterisk -rvvvvv
command asterisk = core set verbose 5
inside file asterisk.conf = verbose=5

Thank you.

I couldn’t see any dialplan or the message I mentioned above.