Re-done the test, double checked on “rtp set debug on”. This time I think I got the RTP output.
Below the test set up and the log.
The configuration for the test is (public IP and public hostname has been changed):
SIP phone 1 - ext: 2002 ip: 192.168.2.55
SIP phone 1 - ext: 2001 ip: 192.168.2.54
Router: ext iface: 74.254.229.26
Asterisk server: public ip 63.244.248.217, hostname 'asterisk.example.com’
IVR: ip 192.168.1.10 - ext: 1500 - it’s on another internal network to which asterisk is directly connected
Test and results:
- SIP1 calls the IVR: IVR voice is audible
- SIP2 tries to call the IVR: SIP1 stops getting the audio stream, SIP2 get the audio but with a very bad quality and often interrupted.
[May 26 12:31:23] DEBUG[55093] chan_sip.c: = Looking for Call ID: aaca99e9021742f3 (Checking From) --From tag f7b494064f --To-tag as2216d77f
[May 26 12:31:23] DEBUG[55093][C-0000049e] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[May 26 12:31:23] DEBUG[55093][C-0000049e] chan_sip.c: Stopping retransmission on ‘aaca99e9021742f3’ of Response 30272: Match Found
[May 26 12:31:23] DEBUG[8210][C-0000049e] res_rtp_asterisk.c: 0x7ffb6c07c750 – Probation learning mode pass with source address 74.254.229.26:61373
[May 26 12:31:23] DEBUG[8210][C-0000049e] res_rtp_asterisk.c: Setting RTCP address on RTP instance ‘0x7ffb6c028478’
[May 26 12:31:23] DEBUG[8210][C-0000049e] res_rtp_asterisk.c: RTP NAT: Got audio from other end. Now sending to address 74.254.229.26:61373
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028301, ts 1968404089, len 000160)
[May 26 12:31:23] DEBUG[8212][C-0000049e] res_rtp_asterisk.c: Ooh, format changed from none to ulaw
[May 26 12:31:23] DEBUG[8212][C-0000049e] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance ‘0x7ffb0c004658’
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029581, ts 1968404088, len 000160)
[May 26 12:31:23] DEBUG[8210][C-0000049e] res_rtp_asterisk.c: 0x7ffb6c07c750 – Probation learning mode pass with source address 74.254.229.26:61373
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028302, ts 1968404249, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029582, ts 1968404248, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028303, ts 1968404409, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029583, ts 1968404408, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028304, ts 1968404569, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029584, ts 1968404568, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028305, ts 1968404729, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029585, ts 1968404728, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028306, ts 1968404889, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029586, ts 1968404888, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028307, ts 1968405049, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029587, ts 1968405048, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028308, ts 1968405209, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029588, ts 1968405208, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028309, ts 1968405369, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029589, ts 1968405368, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028310, ts 1968405529, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029590, ts 1968405528, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028311, ts 1968405689, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029591, ts 1968405688, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028312, ts 1968405849, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029592, ts 1968405848, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028313, ts 1968406009, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029593, ts 1968406008, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028314, ts 1968406169, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029594, ts 1968406168, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028315, ts 1968406329, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029595, ts 1968406328, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028316, ts 1968406489, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029596, ts 1968406488, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028317, ts 1968406649, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 00, seq 029597, ts 1968406648, len 000160)
[May 26 12:31:24] DEBUG[8212][C-0000049e] res_rtp_asterisk.c: 0x7ffb0c01c2b0 – Probation learning mode pass with source address 192.168.1.10:13342
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011184, ts 2404030920, len 000160)
[May 26 12:31:24] DEBUG[8212][C-0000049e] chan_sip.c: Oooh, format changed to alaw
[May 26 12:31:24] DEBUG[8212][C-0000049e] channel.c: Channel SIP/1500-0000077a setting read format path: alaw -> ulaw
[May 26 12:31:24] DEBUG[8212][C-0000049e] channel.c: Channel SIP/1500-0000077a setting write format path: alaw -> alaw
[May 26 12:31:24] DEBUG[8210][C-0000049e] res_rtp_asterisk.c: Ooh, format changed from none to alaw
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 74.254.229.26:61373 (type 08, seq 041894, ts 2404030920, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011185, ts 2404031080, len 000160)
[…]
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028429, ts 1968424569, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 08, seq 029709, ts 1968424568, len 000160)
[May 26 12:31:23] DEBUG[8212][C-0000049e] res_rtp_asterisk.c: Got RTCP report of 28 bytes
[May 26 12:31:23] DEBUG[8212][C-0000049e] acl.c: Not an IPv4 nor IPv6 address, cannot get port.
[May 26 12:31:23] DEBUG[8212][C-0000049e] netsock2.c: Splitting ‘asterisk.example.com’ into…
[May 26 12:31:23] DEBUG[8212][C-0000049e] netsock2.c: …host ‘asterisk.example.com’ and port ‘’.
[May 26 12:31:23] DEBUG[8212][C-0000049e] acl.c: Not an IPv4 nor IPv6 address, cannot get port.
[May 26 12:31:23] DEBUG[8212][C-0000049e] acl.c: Attached to given IP address
[May 26 12:31:23] DEBUG[8212][C-0000049e] res_rtp_asterisk.c: Got RTCP report of 100 bytes
[May 26 12:31:23] DEBUG[8212][C-0000049e] acl.c: Not an IPv4 nor IPv6 address, cannot get port.
[May 26 12:31:23] DEBUG[8212][C-0000049e] netsock2.c: Splitting ‘asterisk.example.com’ into…
[May 26 12:31:23] DEBUG[8212][C-0000049e] netsock2.c: …host ‘asterisk.example.com’ and port ‘’.
[May 26 12:31:23] DEBUG[55128] netsock2.c: Splitting ‘192.168.1.10:13343’ into…
[May 26 12:31:23] DEBUG[55128] netsock2.c: …host ‘192.168.1.10’ and port ‘13343’.
[May 26 12:31:23] DEBUG[55128] netsock2.c: Splitting ‘63.244.248.217:0’ into…
[May 26 12:31:23] DEBUG[55128] netsock2.c: …host ‘63.244.248.217’ and port ‘0’.
[May 26 12:31:23] DEBUG[8212][C-0000049e] acl.c: Not an IPv4 nor IPv6 address, cannot get port.
[May 26 12:31:23] DEBUG[8212][C-0000049e] acl.c: Attached to given IP address
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011297, ts 2404049000, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 74.254.229.26:61373 (type 08, seq 042007, ts 2404049000, len 000160)
[May 26 12:31:23] DEBUG[55128] netsock2.c: Splitting ‘192.168.1.10:13343’ into…
[May 26 12:31:23] DEBUG[55128] netsock2.c: …host ‘192.168.1.10’ and port ‘13343’.
[May 26 12:31:23] DEBUG[55128] netsock2.c: Splitting ‘63.244.248.217:0’ into…
[May 26 12:31:23] DEBUG[55128] netsock2.c: …host ‘63.244.248.217’ and port ‘0’.
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028430, ts 1968424729, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 08, seq 029710, ts 1968424728, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011298, ts 2404049160, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 74.254.229.26:61373 (type 08, seq 042008, ts 2404049160, len 000160)
[May 26 12:31:23] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028431, ts 1968424889, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 08, seq 029711, ts 1968424888, len 000160)
[May 26 12:31:23] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011299, ts 2404049320, len 000160)
[…]
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 08, seq 029793, ts 1968438008, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011381, ts 2404062440, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 74.254.229.26:61373 (type 08, seq 042091, ts 2404062440, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028514, ts 1968438169, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 08, seq 029794, ts 1968438168, len 000160)
[May 26 12:31:24] DEBUG[8210][C-0000049e] res_rtp_asterisk.c: RTCP NAT: Got RTCP from other end. Now sending to address 74.254.229.26:61334
[May 26 12:31:24] DEBUG[8210][C-0000049e] res_rtp_asterisk.c: Got RTCP report of 80 bytes
[May 26 12:31:24] DEBUG[8210][C-0000049e] acl.c: Not an IPv4 nor IPv6 address, cannot get port.
[May 26 12:31:24] DEBUG[8210][C-0000049e] netsock2.c: Splitting ‘asterisk.example.com’ into…
[May 26 12:31:24] DEBUG[8210][C-0000049e] netsock2.c: …host ‘asterisk.example.com’ and port ‘’.
[May 26 12:31:24] DEBUG[8210][C-0000049e] acl.c: Not an IPv4 nor IPv6 address, cannot get port.
[May 26 12:31:24] DEBUG[8210][C-0000049e] acl.c: Attached to given IP address
[May 26 12:31:24] DEBUG[55128] netsock2.c: Splitting ‘74.254.229.26:61334’ into…
[May 26 12:31:24] DEBUG[55128] netsock2.c: …host ‘74.254.229.26’ and port ‘61334’.
[May 26 12:31:24] DEBUG[55128] netsock2.c: Splitting ‘63.244.248.217:0’ into…
[May 26 12:31:24] DEBUG[55128] netsock2.c: …host ‘63.244.248.217’ and port ‘0’.
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011382, ts 2404062600, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 74.254.229.26:61373 (type 08, seq 042092, ts 2404062600, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 74.254.229.26:61373 (type 08, seq 028515, ts 1968438329, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 192.168.1.10:13342 (type 08, seq 029795, ts 1968438328, len 000160)
[May 26 12:31:24] VERBOSE[8212][C-0000049e] res_rtp_asterisk.c: Got RTP packet from 192.168.1.10:13342 (type 08, seq 011383, ts 2404062760, len 000160)
[May 26 12:31:24] VERBOSE[8210][C-0000049e] res_rtp_asterisk.c: Sent RTP packet to 74.254.229.26:61373 (type 08, seq 042093, ts 2404062760, len 000160
[…]