Delay in receiving the call

Dear asterisk team,
Please I noticed a 25 sec delay to receive my call, I got this in debug mode:
“Retransmission timeout reached on transmission 78a3f7c97da0183359481210386118fb@XXX.XXX.XXX.YYY:5060 for seqno 102 (Critical Request) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32001ms with no response”.

What was the result of investigating the possibilities in https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions ?

I do believe that’s the wrong link :smiley:

Oops! That was from a completely different forum! Hopefully fixed.

Hi David,
Thank you for your reply, yes I already checked this link:

  • A NAT device in the signalling path. A misconfigured NAT device is in the signalling path and stops SIP messages.
    —> (we are not working under nat)
  • A firewall that blocks messages or reroutes them wrongly in an attempt to assist in a too clever way.
    —> We did the test with no firewall and still having the same issue, the problem is so frequent and this delay is really annoying .

Your help is really appreciated , maybe a sip configuration was missed, how can I attach my sip conf? or if I can get a working version for sip conf ?

Best regards,

The next possibility is network congestion.

Hi David,
I don’t think it’s a connectivity issue, because the scenario is reproducible at any time.This delay appears in the following case:
client A try to reach client B, but at this moment client B was unreachable and not connected to the sip server. After one sec B was successfully connected to the server (while A still calling) but the call is not redirected to B only after 20-25 sec with the following warning in the log:Retransmission timeout reached on transmission 78a3f7c97da0183359481210386118fb@XXX.XXX.XXX.YYY:5060 for seqno 102 (Critical Request) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 32001ms with no response

Your help is really appreciated.
Best regards,

Hi David,
Please find below the log retrieved from the server maybe it can help.I really appreciate your help;

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] netsock2.c: …host XXX.XXX.XXX.ZZZ’ and port ‘’.

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] netsock2.c: Splitting XXX.XXX.XXX.ZZZ’ into…

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] netsock2.c: …host XXX.XXX.XXX.ZZZ’ and port ‘’.

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] rtp_engine.c: RTP instance ‘0x7f0a70020a10’ is setup and ready to go

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] netsock2.c: Splitting ‘ubuntu18-asterisk16-98’ into…

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] netsock2.c: …host ‘ubuntu18-asterisk16-98’ and port ‘’.

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] acl.c: Multiple addresses. Using the first only

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] res_rtp_asterisk.c: Setup RTCP on RTP instance ‘0x7f0a70020a10’

[2019-06-01 10:18:59] VERBOSE[42921][C-00000019] netsock2.c: Using SIP RTP TOS bits 184

[2019-06-01 10:18:59] VERBOSE[42921][C-00000019] netsock2.c: Using SIP RTP CoS mark 5

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Setting NAT on RTP to On

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] acl.c: For destination XXX.XXX.XXX.WWW’, our source address is XXX.XXX.XXX.ZZZ’.

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Target address 212.98.157.130:51258 is not local, substituting externaddr

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Setting AST_TRANSPORT_UDP with address XXX.XXX.XXX.YYY:5060

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Setting NAT on RTP to On

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: SIP call-id changed from ‘480042580b0d785768054ffc17bd818d@XXX.XXX.XXX.XXX:5060’ to ‘49d5a75b5a59566e1200d78c27e645e6@XXX.XXX.XXX.YYY:5060’

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] stasis.c: Topic ‘channel:1559384339.45’: 0x7f0a70029490 created

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] stasis.c: Topic ‘cache:952/channel:1559384339.45’: 0x7f0a7001f950 created

[2019-06-01 10:18:59] DEBUG[1598] res_odbc.c: Reusing ODBC handle 0x7f0a58054450 from class ‘asteriskcdrdb’

[2019-06-01 10:18:59] DEBUG[1598] cel_odbc.c: Executing SQL statement: [INSERT INTO cel (eventtype, eventtime, cid_name, cid_num, cid_ani, cid_rdnis, cid_dnid, exten, context, channame, appname, appdata, amaflags, accountcode, uniqueid, linkedid, peer, userdeftype, extra) VALUES (‘CHAN_START’, {ts ‘2019-06-01 10:18:59.010531’}, ‘’, ‘’, ‘’, ‘’, ‘’, ‘s’, ‘from-sip’, ‘SIP/OOG105EXA-0000002d’, ‘’, ‘’, 3, ‘’, ‘1559384339.45’, ‘1559384305.43’, ‘’, ‘’, ‘’)]

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] channel.c: Channel 0x7f0a7001a9c0 ‘SIP/OOG105EXA-0000002d’ allocated

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: *** Our native formats are (speex)

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: *** Joint capabilities are (speex)

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: *** Our capabilities are (g729|speex|ilbc|g722|h264)

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: *** AST_CODEC_CHOOSE formats are speex

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: *** Our preferred formats from the incoming channel are (speex)

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: This channel will not be able to handle video.

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] res_config_mysql.c: MySQL RealTime: Connection okay.

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = ‘’ AND priority = ‘1’ AND context = ‘from-sip’

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] res_config_mysql.c: MySQL RealTime: Connection okay.

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE ‘\_%’ ESCAPE ‘\’ AND priority = ‘1’ AND context = ‘from-sip’ ORDER BY exten

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] channel_internal_api.c: Channel Call ID changing from [C-00000019] to [C-00000019]

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Outgoing Call for OOG105EXA

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Updating call counter for outgoing call

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: This call needs video offers, but there’s no video support enabled!

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: ** Our capability: (speex|g729|ilbc|g722|h264) Video flag: False Text flag: False

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: ** Our prefcodec: (speex)

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: – Done with adding codecs to SDP

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Done building SDP. Settling with this capability: (speex|g729|ilbc|g722|h264)

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Initializing initreq for method INVITE - callid 49d5a75b5a59566e1200d78c27e645e6@XXX.XXX.XXX.YYY:5060

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] chan_sip.c: Trying to put ‘INVITE sip:’ onto UDP socket destined for 212.98.157.130:51258

[2019-06-01 10:18:59] VERBOSE[42921][C-00000019] app_dial.c: Called SIP/OOG105EXA

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] channel.c: Channel SIP/OOG105EXA-0000002d setting read format path: speex -> speex

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] channel.c: Channel SIP/NDV952STS-0000002b setting write format path: speex -> speex

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] channel.c: Channel SIP/NDV952STS-0000002b setting read format path: speex -> speex

[2019-06-01 10:18:59] DEBUG[42921][C-00000019] channel.c: Channel SIP/OOG105EXA-0000002d setting write format path: speex -> speex

[2019-06-01 10:18:59] DEBUG[1598] res_odbc.c: Releasing ODBC handle 0x7f0a58054450 into pool

[2019-06-01 10:18:59] DEBUG[1631] chan_sip.c: = Looking for Call ID: 11aac5d2216329b334c33a7a1ada150b@XXX.XXX.XXX.YYY:5060 (Checking To) --From tag as3df63865 --To-tag z9hG4bK0ac661da

[2019-06-01 10:18:59] DEBUG[1631] chan_sip.c: Stopping retransmission on ‘11aac5d2216329b334c33a7a1ada150b@XXX.XXX.XXX.YYY:5060’ of Request 102: Match Found

[2019-06-01 10:18:59] DEBUG[1631] chan_sip.c: Got 200 accepted on NOTIFY 11aac5d2216329b334c33a7a1ada150b@XXX.XXX.XXX.YYY:5060

[2019-06-01 10:18:59] DEBUG[1631] chan_sip.c: Destroying SIP dialog 11aac5d2216329b334c33a7a1ada150b@XXX.XXX.XXX.YYY:5060

[2019-06-01 10:18:59] DEBUG[1631] chan_sip.c: = Looking for Call ID: 49d5a75b5a59566e1200d78c27e645e6@XXX.XXX.XXX.YYY:5060 (Checking To) --From tag as795dbb8d --To-tag

[2019-06-01 10:18:59] DEBUG[1631][C-00000019] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on ‘49d5a75b5a59566e1200d78c27e645e6@XXX.XXX.XXX.YYY:5060’ Request 102: Found

[2019-06-01 10:18:59] DEBUG[1631][C-00000019] chan_sip.c: SIP response 100 to standard invite

[2019-06-01 10:18:59] DEBUG[1631] chan_sip.c: = Looking for Call ID: 49d5a75b5a59566e1200d78c27e645e6@XXX.XXX.XXX.YYY:5060 (Checking To) --From tag as795dbb8d --To-tag xP7sNJtjcG69Wy2agfRhvJQTXc-XsTV7

[2019-06-01 10:18:59] DEBUG[1631][C-00000019] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on ‘49d5a75b5a59566e1200d78c27e645e6@XXX.XXX.XXX.YYY:5060’ Request 102: Found

[2019-06-01 10:18:59] DEBUG[1631][C-00000019] chan_sip.c: SIP response 180 to standard invite

[2019-06-01 10:18:59] DEBUG[1466] devicestate.c: No provider found, checking channel drivers for SIP - OOG105EXA

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Checking device state for peer OOG105EXA

[2019-06-01 10:18:59] DEBUG[1466] res_config_mysql.c: MySQL RealTime: Connection okay.

[2019-06-01 10:18:59] DEBUG[1466] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = ‘OOG105EXA’ AND host = ‘dynamic’

[2019-06-01 10:18:59] DEBUG[1466] stasis.c: Topic ‘endpoint:SIP/OOG105EXA’: 0x7f0a6c0071d0 created

[2019-06-01 10:18:59] DEBUG[1466] stasis.c: Topic ‘cache:953/endpoint:SIP/OOG105EXA’: 0x7f0a6c009d30 created

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: -REALTIME- peer built. Name: OOG105EXA. Peer objects: 13

[2019-06-01 10:18:59] DEBUG[1466] netsock2.c: Splitting XXX.XXX.XXX.WWW’ into…

[2019-06-01 10:18:59] DEBUG[1466] netsock2.c: …host XXX.XXX.XXX.WWW’ and port ‘’.

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port.

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port.

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: -REALTIME- loading peer from database to memory. Name: OOG105EXA. Peer objects: 13

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Destroying SIP peer OOG105EXA

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: -REALTIME- peer Destroyed. Name: OOG105EXA. Realtime Peer objects: 12

[2019-06-01 10:18:59] DEBUG[1466] devicestate.c: Changing state for SIP/OOG105EXA - state 1 (Not in use)

[2019-06-01 10:18:59] DEBUG[1466] devicestate.c: No provider found, checking channel drivers for SIP - OOG105EXA

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Checking device state for peer OOG105EXA

[2019-06-01 10:18:59] DEBUG[43306] stasis.c: Topic ‘cache:953/endpoint:SIP/OOG105EXA’: 0x7f0a6c009d30 destroyed

[2019-06-01 10:18:59] DEBUG[43306] stasis.c: Topic ‘endpoint:SIP/OOG105EXA’: 0x7f0a6c0071d0 destroyed

[2019-06-01 10:18:59] DEBUG[1466] res_config_mysql.c: MySQL RealTime: Connection okay.

[2019-06-01 10:18:59] DEBUG[1466] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = ‘OOG105EXA’ AND host = ‘dynamic’

[2019-06-01 10:18:59] DEBUG[1466] stasis.c: Topic ‘endpoint:SIP/OOG105EXA’: 0x7f0a6c00ea70 created

[2019-06-01 10:18:59] DEBUG[1466] stasis.c: Topic ‘cache:954/endpoint:SIP/OOG105EXA’: 0x7f0a6c00c2f0 created

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: -REALTIME- peer built. Name: OOG105EXA. Peer objects: 13

[2019-06-01 10:18:59] DEBUG[1466] netsock2.c: Splitting XXX.XXX.XXX.WWW’ into…

[2019-06-01 10:18:59] DEBUG[1466] netsock2.c: …host XXX.XXX.XXX.WWW’ and port ‘’.

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port.

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port.

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: -REALTIME- loading peer from database to memory. Name: OOG105EXA. Peer objects: 13

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: Destroying SIP peer OOG105EXA

[2019-06-01 10:18:59] DEBUG[1466] chan_sip.c: -REALTIME- peer Destroyed. Name: OOG105EXA. Realtime Peer objects: 12

[2019-06-01 10:18:59] DEBUG[1466] devicestate.c: Changing state for SIP/OOG105EXA - state 1 (Not in use)

[2019-06-01 10:18:59] DEBUG[1650] app_queue.c: Device ‘SIP/OOG105EXA’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.

[2019-06-01 10:18:59] DEBUG[43306] stasis.c: Topic ‘cache:954/endpoint:SIP/OOG105EXA’: 0x7f0a6c00c2f0 destroyed

[2019-06-01 10:18:59] DEBUG[43306] stasis.c: Topic ‘endpoint:SIP/OOG105EXA’: 0x7f0a6c00ea70 destroyed

[2019-06-01 10:18:59] VERBOSE[42921][C-00000019] app_dial.c: SIP/OOG105EXA-0000002d is ringing

Best regards

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.