Hello everybody !
From one day (restart) to another - and after months of stable connection - the IAX2 trunk binding two systems does not connect anymore.
I checked if the way to each system is free:
doing the ping from one system:
nc -z -v -u pbxonline.mydomain.com 4569
and listening on the other system:
tcpdump -n udp port 4569
I did this in both directions and received the ping on the other side.
I set the following on both systems:
core set verbose 5
core set debug 5
iax2 set debug on
and got on one system something like this (the static ip of this system is 51.89.119.116):
[2020-08-17 15:03:42] DEBUG[1956]: chan_iax2.c:2664 peercnt_add: ip callno count incremented to 7 for 87.132.155.113
[2020-08-17 15:03:42] DEBUG[1956]: chan_iax2.c:3201 __find_callno: Creating new call structure 3584
[2020-08-17 15:03:42] DEBUG[1944]: chan_iax2.c:3413 send_packet: Sending 7 on 3584/0 to 87.132.155.113:4569
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00007ms SCall: 03584 DCall: 00000 87.132.155.113:4569
[2020-08-17 15:03:42] DEBUG[1948]: chan_iax2.c:2664 peercnt_add: ip callno count incremented to 8 for 87.132.155.113
[2020-08-17 15:03:42] DEBUG[1948]: chan_iax2.c:3201 __find_callno: Creating new call structure 6927
[2020-08-17 15:03:42] DEBUG[1944]: chan_iax2.c:3413 send_packet: Sending 3 on 6927/0 to 87.132.155.113:4569
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00003ms SCall: 06927 DCall: 00000 87.132.155.113:4569
[2020-08-17 15:03:43] DEBUG[1947]: chan_iax2.c:3413 send_packet: Sending 7 on 3584/0 to 87.132.155.113:4569
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00007ms SCall: 03584 DCall: 00000 87.132.155.113:4569
[2020-08-17 15:03:43] DEBUG[1954]: chan_iax2.c:3413 send_packet: Sending 3 on 6927/0 to 87.132.155.113:4569
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00003ms SCall: 06927 DCall: 00000 87.132.155.113:4569
[2020-08-17 15:03:44] DEBUG[1943]: chan_sip.c:4089 retrans_pkt: ** SIP timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans id #5))
[2020-08-17 15:03:44] DEBUG[1943]: chan_sip.c:3805 __sip_xmit: Trying to put ‘SIP/2.0 403’ onto UDP socket destined for 163.172.118.206:54916
[2020-08-17 15:03:48] DEBUG[1953]: chan_iax2.c:3413 send_packet: Sending 7 on 3584/0 to 87.132.155.113:4569
Tx-Frame Retry[002] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00007ms SCall: 03584 DCall: 00000 87.132.155.113:4569
[2020-08-17 15:03:48] DEBUG[1952]: chan_iax2.c:3413 send_packet: Sending 3 on 6927/0 to 87.132.155.113:4569
Tx-Frame Retry[002] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00003ms SCall: 06927 DCall: 00000 87.132.155.113:4569
[2020-08-17 15:03:48] DEBUG[1943]: chan_sip.c:4089 retrans_pkt: ** SIP timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans id #5))
[2020-08-17 15:03:48] DEBUG[1943]: chan_sip.c:3805 __sip_xmit: Trying to put ‘SIP/2.0 403’ onto UDP socket destined for 163.172.118.206:54916
and on the other system (dynamic IP was 87.132.155.113) this kind of debug:
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00010ms SCall: 03653 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00010ms SCall: 12226 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00010ms SCall: 03653 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00010ms SCall: 12226 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00013ms SCall: 05776 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00013ms SCall: 06876 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00013ms SCall: 05776 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00013ms SCall: 06876 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00015ms SCall: 00500 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00015ms SCall: 07912 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00015ms SCall: 00500 DCall: 00000 [51.89.119.116:4569]
Tx-Frame Retry[001] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00015ms SCall: 07912 DCall: 00000 [51.89.119.116:4569]
I am fighting with this since 24 hours and I have the impression that I miss the forest because of the trees…
Thanks a lot for any feedback !