Asterisk 16.19.0 IAX trunk problems

Asterisk Asterisk Support
1

My IAX trunks stopped working after upgrading to Asterisk 16.19.0

Tx-Frame Retry[000] – OSeqno: 022 ISeqno: 024 Type: IAX Subclass: LAGRQ
Timestamp: 79998ms SCall: 27996 DCall: 12627 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 024 ISeqno: 023 Type: IAX Subclass: LAGRP
Timestamp: 79998ms SCall: 12627 DCall: 27996 169.255.228.100:4569
Tx-Frame Retry[-01] – OSeqno: 023 ISeqno: 025 Type: IAX Subclass: ACK
Timestamp: 79998ms SCall: 27996 DCall: 12627 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 025 ISeqno: 023 Type: IAX Subclass: LAGRQ
Timestamp: 80006ms SCall: 12627 DCall: 27996 169.255.228.100:4569
Tx-Frame Retry[000] – OSeqno: 023 ISeqno: 026 Type: IAX Subclass: LAGRP
Timestamp: 80006ms SCall: 27996 DCall: 12627 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 026 ISeqno: 024 Type: IAX Subclass: ACK
Timestamp: 80006ms SCall: 12627 DCall: 27996 169.255.228.100:4569
pbx*CLI> core show channels
Channel Location State Application(Data)
PJSIP/126-0000003a s@trunk:14 Ring Dial(IAX2/biolabvc2/0116082663
IAX2/biolabvc2-27996 s@external:1 Ringing AppDial((Outgoing Line))
2 active channels
1 active call
31 calls processed
Tx-Frame Retry[000] – OSeqno: 024 ISeqno: 026 Type: IAX Subclass: PING
Timestamp: 84002ms SCall: 27996 DCall: 12627 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 026 ISeqno: 025 Type: IAX Subclass: PONG
Timestamp: 84002ms SCall: 12627 DCall: 27996 169.255.228.100:4569
RR_JITTER : 0
RR_LOSS : 0
RR_PKTS : 1
RR_DELAY : 40
RR_DROPPED : 0
RR_OUTOFORDER : 0

Tx-Frame Retry[-01] – OSeqno: 025 ISeqno: 027 Type: IAX Subclass: ACK
Timestamp: 84002ms SCall: 27996 DCall: 12627 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 027 ISeqno: 025 Type: IAX Subclass: PING
Timestamp: 84009ms SCall: 12627 DCall: 27996 169.255.228.100:4569
Tx-Frame Retry[000] – OSeqno: 025 ISeqno: 028 Type: IAX Subclass: PONG
Timestamp: 84009ms SCall: 27996 DCall: 12627 169.255.228.100:4569
RR_JITTER : 0
RR_LOSS : 0
RR_PKTS : 2
RR_DELAY : 40
RR_DROPPED : 0
RR_OUTOFORDER : 0

Rx-Frame Retry[ No] – OSeqno: 028 ISeqno: 026 Type: IAX Subclass: ACK
Timestamp: 84009ms SCall: 12627 DCall: 27996 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 028 ISeqno: 026 Type: IAX Subclass: HANGUP
Timestamp: 85959ms SCall: 12627 DCall: 27996 169.255.228.100:4569
CAUSE CODE : 16

Tx-Frame Retry[-01] – OSeqno: 026 ISeqno: 029 Type: IAX Subclass: ACK
Timestamp: 85959ms SCall: 27996 DCall: 12627 169.255.228.100:4569
– Hungup ‘IAX2/biolabvc2-27996’
– No one is available to answer at this time (1:0/0/0)

The trunk doesn’t detect the call getting answered but I can confirm that my service provider processed the call and it was answered.

Regards

2

You haven’t provided a full IAX2 trace, it is incomplete. As well what version did you upgrade from?

3

I upgraded from 16.17.0

Here is more of the log:

[Sep 16 10:37:17] VERBOSE[26356][C-0000001f] pbx.c: Executing [s@trunk:14] Dial(“PJSIP/126-0000003a”, “IAX2/biolabvc2/0116082663,300,TU(connected,PJSIP/126,1631781437,1631781437.522)”) in new stack
[Sep 16 10:37:17] VERBOSE[26356][C-0000001f] app_dial.c: Called IAX2/biolabvc2/0116082663
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: Timestamp: 00006ms SCall: 27996 DCall: 00000 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: VERSION : 2
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLED NUMBER : 0116082663
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CODEC_PREFS : (g729)
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING NUMBER : 27114571826
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING PRESNTN : 0
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING TYPEOFN : 0
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING TRANSIT : 0
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING NAME : Merck Biolab
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: LANGUAGE : en
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: FORMAT : 256
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: FORMAT2 : g729
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CAPABILITY : 256
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CAPABILITY2 : g729
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: ADSICPE : 2
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: DATE TIME : 2021-09-16 10:37:16
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c:
[Sep 16 10:37:17] VERBOSE[21414] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
[Sep 16 10:37:17] VERBOSE[21414] chan_iax2.c: Timestamp: 00006ms SCall: 00001 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21414] chan_iax2.c: CALLTOKEN : 51 bytes
[Sep 16 10:37:17] VERBOSE[21414] chan_iax2.c:
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: Timestamp: 00009ms SCall: 27996 DCall: 00000 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: VERSION : 2
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLED NUMBER : 0116082663
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CODEC_PREFS : (g729)
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING NUMBER : 27114571826
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING PRESNTN : 0
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING TYPEOFN : 0
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING TRANSIT : 0
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLING NAME : Merck Biolab
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: LANGUAGE : en
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: FORMAT : 256
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: FORMAT2 : g729
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CAPABILITY : 256
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CAPABILITY2 : g729
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: ADSICPE : 2
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: DATE TIME : 2021-09-16 10:37:16
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: CALLTOKEN : 51 bytes
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c:
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c: Timestamp: 00009ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c: Timestamp: 00010ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c: AUTHMETHODS : 2
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c: CHALLENGE : \x35\x36\x32\x37\x34\x33\x32\x35\x39
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:17] VERBOSE[21418] chan_iax2.c:
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: Timestamp: 00016ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c: MD5 RESULT : a445465825da2f04317fce905eaf1502
[Sep 16 10:37:17] VERBOSE[21410] chan_iax2.c:
[Sep 16 10:37:17] VERBOSE[21413] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
[Sep 16 10:37:17] VERBOSE[21413] chan_iax2.c: Timestamp: 00016ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21413] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT
[Sep 16 10:37:17] VERBOSE[21413] chan_iax2.c: Timestamp: 00017ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:17] VERBOSE[21413] chan_iax2.c: FORMAT : 256
[Sep 16 10:37:17] VERBOSE[21413] chan_iax2.c: FORMAT2 : g729
[Sep 16 10:37:17] VERBOSE[21413] chan_iax2.c:
[Sep 16 10:37:17] VERBOSE[21413][C-0000001f] chan_iax2.c: Call accepted by 169.255.228.100:4569 (format g729)
[Sep 16 10:37:17] VERBOSE[21413][C-0000001f] chan_iax2.c: Format for call is (g729)
[Sep 16 10:37:17] VERBOSE[21413][C-0000001f] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
[Sep 16 10:37:17] VERBOSE[21413][C-0000001f] chan_iax2.c: Timestamp: 00017ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:18] VERBOSE[21420] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: ANSWER
[Sep 16 10:37:18] VERBOSE[21420] chan_iax2.c: Timestamp: 00920ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:18] VERBOSE[21420][C-0000001f] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK
[Sep 16 10:37:18] VERBOSE[21420][C-0000001f] chan_iax2.c: Timestamp: 00920ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:18] VERBOSE[21419] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: (255?)
[Sep 16 10:37:18] VERBOSE[21419] chan_iax2.c: Timestamp: 00923ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:18] VERBOSE[21419][C-0000001f] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK
[Sep 16 10:37:18] VERBOSE[21419][C-0000001f] chan_iax2.c: Timestamp: 00923ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:27] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 004 Type: IAX Subclass: LAGRQ
[Sep 16 10:37:27] VERBOSE[21410] chan_iax2.c: Timestamp: 10005ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:27] VERBOSE[21419] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 004 ISeqno: 003 Type: IAX Subclass: LAGRP
[Sep 16 10:37:27] VERBOSE[21419] chan_iax2.c: Timestamp: 10005ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:27] VERBOSE[21419][C-0000001f] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK
[Sep 16 10:37:27] VERBOSE[21419][C-0000001f] chan_iax2.c: Timestamp: 10005ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:27] VERBOSE[21413] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 005 ISeqno: 003 Type: IAX Subclass: LAGRQ
[Sep 16 10:37:27] VERBOSE[21413] chan_iax2.c: Timestamp: 10010ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:27] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 003 ISeqno: 006 Type: IAX Subclass: LAGRP
[Sep 16 10:37:27] VERBOSE[21410] chan_iax2.c: Timestamp: 10010ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:37] VERBOSE[21417] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 005 Type: IAX Subclass: LAGRQ
[Sep 16 10:37:37] VERBOSE[21417] chan_iax2.c: Timestamp: 20009ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:37] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 005 ISeqno: 008 Type: IAX Subclass: LAGRP
[Sep 16 10:37:37] VERBOSE[21410] chan_iax2.c: Timestamp: 20009ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:37] VERBOSE[21421] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 006 Type: IAX Subclass: ACK
[Sep 16 10:37:37] VERBOSE[21421] chan_iax2.c: Timestamp: 20009ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 006 ISeqno: 008 Type: IAX Subclass: PING
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: Timestamp: 21005ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 007 Type: IAX Subclass: PONG
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: Timestamp: 21005ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: RR_JITTER : 0
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: RR_LOSS : 0
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: RR_PKTS : 1
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: RR_DELAY : 40
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: RR_DROPPED : 0
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: RR_OUTOFORDER : 0
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c:
[Sep 16 10:37:38] VERBOSE[21419][C-0000001f] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 007 ISeqno: 009 Type: IAX Subclass: ACK
[Sep 16 10:37:38] VERBOSE[21419][C-0000001f] chan_iax2.c: Timestamp: 21005ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 009 ISeqno: 007 Type: IAX Subclass: PING
[Sep 16 10:37:38] VERBOSE[21419] chan_iax2.c: Timestamp: 21010ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 007 ISeqno: 010 Type: IAX Subclass: PONG
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: Timestamp: 21010ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: RR_JITTER : 0
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: RR_LOSS : 0
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: RR_PKTS : 2
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: RR_DELAY : 40
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: RR_DROPPED : 0
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c: RR_OUTOFORDER : 0
[Sep 16 10:37:38] VERBOSE[21410] chan_iax2.c:
[Sep 16 10:37:38] VERBOSE[21421] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 010 ISeqno: 008 Type: IAX Subclass: ACK
[Sep 16 10:37:38] VERBOSE[21421] chan_iax2.c: Timestamp: 21010ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:47] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 008 ISeqno: 010 Type: IAX Subclass: LAGRQ
[Sep 16 10:37:47] VERBOSE[21410] chan_iax2.c: Timestamp: 30003ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:47] VERBOSE[21416] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 010 ISeqno: 009 Type: IAX Subclass: LAGRP
[Sep 16 10:37:47] VERBOSE[21416] chan_iax2.c: Timestamp: 30003ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:47] VERBOSE[21416][C-0000001f] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 009 ISeqno: 011 Type: IAX Subclass: ACK
[Sep 16 10:37:47] VERBOSE[21416][C-0000001f] chan_iax2.c: Timestamp: 30003ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:47] VERBOSE[21420] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 011 ISeqno: 009 Type: IAX Subclass: LAGRQ
[Sep 16 10:37:47] VERBOSE[21420] chan_iax2.c: Timestamp: 30008ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:47] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 009 ISeqno: 012 Type: IAX Subclass: LAGRP
[Sep 16 10:37:47] VERBOSE[21410] chan_iax2.c: Timestamp: 30008ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:47] VERBOSE[21420] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 012 ISeqno: 010 Type: IAX Subclass: ACK
[Sep 16 10:37:47] VERBOSE[21420] chan_iax2.c: Timestamp: 30008ms SCall: 12627 DCall: 27996 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: Timestamp: 00017ms SCall: 14975 DCall: 00000 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: REFRESH : 60
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c:
[Sep 16 10:37:55] VERBOSE[21420] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
[Sep 16 10:37:55] VERBOSE[21420] chan_iax2.c: Timestamp: 00017ms SCall: 00001 DCall: 14975 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21420] chan_iax2.c: CALLTOKEN : 51 bytes
[Sep 16 10:37:55] VERBOSE[21420] chan_iax2.c:
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: Timestamp: 00018ms SCall: 14975 DCall: 00000 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: REFRESH : 60
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: CALLTOKEN : 51 bytes
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c:
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c: Timestamp: 00018ms SCall: 08207 DCall: 14975 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c: Timestamp: 00003ms SCall: 08207 DCall: 14975 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c: AUTHMETHODS : 2
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c: CHALLENGE : \x33\x34\x36\x38\x31\x30\x35\x31\x34
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:55] VERBOSE[21416] chan_iax2.c:
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: Timestamp: 00020ms SCall: 14975 DCall: 08207 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: REFRESH : 60
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c: MD5 RESULT : 4d429289d799a70354a25d081be9d879
[Sep 16 10:37:55] VERBOSE[21410] chan_iax2.c:
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: Timestamp: 00020ms SCall: 08207 DCall: 14975 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: Timestamp: 00006ms SCall: 08207 DCall: 14975 169.255.228.100:4569
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: USERNAME : biolabvc2
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: DATE TIME : 2021-09-16 10:37:54
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: REFRESH : 60
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: APPARENT ADDRES : IPV4 10.255.228.65:4569
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c:
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
[Sep 16 10:37:55] VERBOSE[21419] chan_iax2.c: Timestamp: 00006ms SCall: 14975 DCall: 08207 169.255.228.100:4569
[Sep 16 10:37:57] VERBOSE[21410] chan_iax2.c: Tx-Frame Retry[000] – OSeqno: 010 ISeqno: 012 Type: IAX Subclass: LAGRQ
[Sep 16 10:37:57] VERBOSE[21410] chan_iax2.c: Timestamp: 40002ms SCall: 27996 DCall: 12627 169.255.228.100:4569
[Sep 16 10:37:57] VERBOSE[21418] chan_iax2.c: Rx-Frame Retry[ No] – OSeqno: 012 ISeqno: 011 Type: IAX Subclass: LAGRP
[Sep 16 10:37:57] VERBOSE[21418] chan_iax2.c: Timestamp: 40002ms SCall: 12627 DCall: 27996 169.255.228.100:4569

4

And downgrading back to 16.17.0 causes it to work?

From looking at the logs there are no code changes for chan_iax2 between 16.17.0 and 16.19.0

5

We connect to a Asterisk 15.4.1 server below are both config files:

Asterisk 15.4.1
[biolabvc2]
jitterbuffer=yes
type=friend
forcejitterbuffer=yes
context=external-biolab
host=dynamic
username=biolabvc2
secret=***********
disallow=all
allow=g729:20
allow=alaw
trunk=yes
qualify=yes
tos=0xa0
description=Bio Labs

Asterisk 16.19
register => biolabvc2:************@X.X.X.X

[biolabvc2]
forcejitterbuffer=yes
jitterbuffer=yes
username=biolabvc2
secret=*************
type=friend
host=.X.X.X.X
context=external
qualify=yes
trunk=yes
disallow=all
allow=g729

All my other servers have migrated to PJSIP trunks because of this IAX problem, however this specific clients firewall doesn’t allow SIP so I need to stay with IAX.

Regards

6

I asked if you confirmed that downgrading to 16.17.0 made it work again, because from what I can tell there are no code changes in chan_iax2 between those two versions.

7

I can only downgrade after hours. I will be doing it this afternoon to confirm.

Regards

8

I can confirm that it works on Asterisk 16.17

-- Executing [s@trunk:14] Dial("PJSIP/126-0000003f", "IAX2/biolabvc2/0116082663,300,TU(connected,PJSIP/126,1631864090,1631864090.535)") in new stack
-- Called IAX2/biolabvc2/0116082663

Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00019ms SCall: 32102 DCall: 00000 169.255.228.100:4569
VERSION : 2
CALLED NUMBER : 0116082663
CODEC_PREFS : (g729)
CALLING NUMBER : 27114571826
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
CALLING NAME : Merck Biolab
LANGUAGE : en
USERNAME : biolabvc2
FORMAT : 256
FORMAT2 : g729
CAPABILITY : 256
CAPABILITY2 : g729
ADSICPE : 2
DATE TIME : 2021-09-17 09:34:50

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
Timestamp: 00019ms SCall: 00001 DCall: 32102 169.255.228.100:4569
CALLTOKEN : 51 bytes

Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00042ms SCall: 32102 DCall: 00000 169.255.228.100:4569
VERSION : 2
CALLED NUMBER : 0116082663
CODEC_PREFS : (g729)
CALLING NUMBER : 27114571826
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
CALLING NAME : Merck Biolab
LANGUAGE : en
USERNAME : biolabvc2
FORMAT : 256
FORMAT2 : g729
CAPABILITY : 256
CAPABILITY2 : g729
ADSICPE : 2
DATE TIME : 2021-09-17 09:34:50
CALLTOKEN : 51 bytes

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00042ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ
Timestamp: 00004ms SCall: 03532 DCall: 32102 169.255.228.100:4569
AUTHMETHODS : 2
CHALLENGE : \x36\x38\x38\x37\x36\x31\x35\x34\x30
USERNAME : biolabvc2

Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP
Timestamp: 00043ms SCall: 32102 DCall: 03532 169.255.228.100:4569
MD5 RESULT : 02171eb0fbb24a03efb9804ed28c6e4b

Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00043ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT
Timestamp: 00005ms SCall: 03532 DCall: 32102 169.255.228.100:4569
FORMAT : 256
FORMAT2 : g729

-- Call accepted by 169.255.228.100:4569 (format g729)
-- Format for call is (g729)

Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00005ms SCall: 32102 DCall: 03532 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: ANSWER
Timestamp: 01584ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK
Timestamp: 01584ms SCall: 32102 DCall: 03532 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: (255?)
Timestamp: 01587ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK
Timestamp: 01587ms SCall: 32102 DCall: 03532 169.255.228.100:4569
– IAX2/biolabvc2-32102 answered PJSIP/126-0000003f
– IAX2/biolabvc2-32102 Internal Gosub(connected,s,1(PJSIP/126,1631864090,1631864090.535)) start
– Executing [s@connected:1] Set(“IAX2/biolabvc2-32102”, “AGENT=PJSIP/126”) in new stack
– Executing [s@connected:2] Set(“IAX2/biolabvc2-32102”, “HOLDTIME=2”) in new stack
– Executing [s@connected:3] System(“IAX2/biolabvc2-32102”, “echo “1631864092|1631864090.535|outbound|PJSIP/126|CONNECT|2|1631864090.535|2” >> /var/log/asterisk/queue_log”) in new stack
– Executing [s@connected:4] Return(“IAX2/biolabvc2-32102”, “”) in new stack
== Spawn extension (external, , 1) exited non-zero on ‘IAX2/biolabvc2-32102’
– IAX2/biolabvc2-32102 Internal Gosub(connected,s,1(PJSIP/126,1631864090,1631864090.535)) complete GOSUB_RETVAL=
– Channel IAX2/biolabvc2-32102 joined ‘simple_bridge’ basic-bridge
– Channel PJSIP/126-0000003f joined ‘simple_bridge’ basic-bridge
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 004 Type: VOICE Subclass: 136
Timestamp: 01839ms SCall: 32102 DCall: 03532 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 004 ISeqno: 003 Type: IAX Subclass: ACK
Timestamp: 01839ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 004 ISeqno: 003 Type: VOICE Subclass: 136
Timestamp: 02127ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Tx-Frame Retry[-01] – OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK
Timestamp: 02127ms SCall: 32102 DCall: 03532 169.255.228.100:4569
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
[Sep 17 09:34:56] WARNING[58687]: chan_iax2.c:1229 jb_warning_output: Resyncing the jb. last_delay 11, this delay -20716, threshold 1082, new offset 20716
– – G.729 PLC
– – G.729 PLC
Tx-Frame Retry[000] – OSeqno: 003 ISeqno: 005 Type: IAX Subclass: LAGRQ
Timestamp: 10022ms SCall: 32102 DCall: 03532 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 005 ISeqno: 003 Type: IAX Subclass: LAGRQ
Timestamp: 213153826ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Tx-Frame Retry[000] – OSeqno: 004 ISeqno: 006 Type: IAX Subclass: LAGRP
Timestamp: 213153826ms SCall: 32102 DCall: 03532 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 006 ISeqno: 004 Type: IAX Subclass: LAGRP
Timestamp: 10022ms SCall: 03532 DCall: 32102 169.255.228.100:4569
Tx-Frame Retry[-01] – OSeqno: 004 ISeqno: 007 Type: IAX Subclass: ACK
Timestamp: 10022ms SCall: 32102 DCall: 03532 169.255.228.100:4569
Rx-Frame Retry[ No] – OSeqno: 006 ISeqno: 005 Type: IAX Subclass: ACK
Timestamp: 213153826ms SCall: 03532 DCall: 32102 169.255.228.100:4569
[Sep 17 09:35:00] WARNING[58686]: chan_iax2.c:1229 jb_warning_output: Resyncing the jb. last_delay -57, this delay -213123126, threshold 1084, new offset 213143842
– – G.729 PLC
– – G.729 PLC
– – G.729 PLC
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00001ms SCall: 14366 DCall: 00000 169.255.228.100:4569

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
Timestamp: 00001ms SCall: 00001 DCall: 14366 169.255.228.100:4569
Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00001ms SCall: 14366 DCall: 00001 169.255.228.100:4569

9

I would suggest filing an issue[1] then.

[1] System Dashboard - Digium/Asterisk JIRA