Can anybody with more asterisk experience take a look at blow enclosed call “with debug set to ON”
and pinpoint reason why remote asterisk is not processing voice:
This debug from receiving asterisk server;
i5*CLI> iax2 set debug on
IAX2 Debugging Enabled
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ
Timestamp: 00018ms SCall: 12891 DCall: 00000 [192.168.143.7:4569]
USERNAME : home_server
REFRESH : 60
Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
Timestamp: 00018ms SCall: 00001 DCall: 12891 [192.168.143.7:4569]
CALLTOKEN : 51 bytes
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ
Timestamp: 00027ms SCall: 12891 DCall: 00000 [192.168.143.7:4569]
USERNAME : home_server
REFRESH : 60
CALLTOKEN : 51 bytes
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH
Timestamp: 00013ms SCall: 03601 DCall: 12891 [192.168.143.7:4569]
AUTHMETHODS : 3
CHALLENGE : \x31\x30\x35\x34\x34\x34\x32\x38\x38
USERNAME : home_server
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ
Timestamp: 00038ms SCall: 12891 DCall: 03601 [192.168.143.7:4569]
USERNAME : home_server
REFRESH : 60
MD5 RESULT : 408d45d46904c6c482347b076dafa70e
Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK
Timestamp: 00023ms SCall: 03601 DCall: 12891 [192.168.143.7:4569]
USERNAME : home_server
DATE TIME : 2024-02-02 23:56:20
REFRESH : 60
APPARENT ADDRES : IPV4 192.168.143.7:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00023ms SCall: 12891 DCall: 03601 [192.168.143.7:4569]
i5CLI>
i5CLI>
i5CLI>
i5CLI>
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00009ms SCall: 06809 DCall: 00000 [192.168.143.7:4569]
VERSION : 2
CALLED NUMBER : 877
CODEC_PREFS : (ulaw|gsm|ilbc|speex|g729|g723|alaw)
CALLING NUMBER : 55
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
Unknown IE 057 : Present
CALLING NAME : Joseph
LANGUAGE : en
USERNAME : home_server
FORMAT : 4
FORMAT2 : ulaw
CAPABILITY : 1807
CAPABILITY2 : Unknown
ADSICPE : 2
DATE TIME : 2024-02-02 23:56:32
Ignoring unknown information element ‘Unknown IE’ (57) of length 4
Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
Timestamp: 00009ms SCall: 00001 DCall: 06809 [192.168.143.7:4569]
CALLTOKEN : 51 bytes
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00019ms SCall: 06809 DCall: 00000 [192.168.143.7:4569]
VERSION : 2
CALLED NUMBER : 877
CODEC_PREFS : (ulaw|gsm|ilbc|speex|g729|g723|alaw)
CALLING NUMBER : 55
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
Unknown IE 057 : Present
CALLING NAME : Joseph
LANGUAGE : en
USERNAME : home_server
FORMAT : 4
FORMAT2 : ulaw
CAPABILITY : 1807
CAPABILITY2 : Unknown
ADSICPE : 2
DATE TIME : 2024-02-02 23:56:32
CALLTOKEN : 51 bytes
Ignoring unknown information element ‘Unknown IE’ (57) of length 4
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ
Timestamp: 00004ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
AUTHMETHODS : 3
CHALLENGE : \x31\x30\x34\x37\x35\x34\x33\x33\x39
USERNAME : home_server
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP
Timestamp: 00029ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
MD5 RESULT : e0f9db3405e467d1b1c78718bde48ae9
-- Accepting AUTHENTICATED call from 192.168.143.7:
-- > requested format = ulaw,
-- > requested prefs = (ulaw|gsm|ilbc|speex|g729|g723|alaw),
-- > actual format = ulaw,
-- > host prefs = (ulaw|alaw),
-- > priority = mine
Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT
Timestamp: 00015ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
FORMAT : 4
FORMAT2 : ulaw
-- Executing [877@extensions:1] Set("IAX2/home_server-3672", "recordfilename=55-877-2024_02_02_2356.wav") in new stack
-- Executing [877@extensions:2] MixMonitor("IAX2/home_server-3672", "55-877-2024_02_02_2356.wav") in new stack
-- Executing [877@extensions:3] Dial("IAX2/home_server-3672", "SIP/877,25,trw") in new stack
== Begin MixMonitor Recording IAX2/home_server-3672
== Using SIP RTP CoS mark 5
– Called SIP/877
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING
Timestamp: 00018ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00015ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK
Timestamp: 00018ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
– SIP/877-00000002 is ringing
Tx-Frame Retry[000] – OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: RINGING
Timestamp: 00021ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK
Timestamp: 00021ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 004 Type: IAX Subclass: LAGRQ
Timestamp: 10008ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 004 ISeqno: 003 Type: IAX Subclass: LAGRP
Timestamp: 10008ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK
Timestamp: 10008ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 005 ISeqno: 003 Type: IAX Subclass: LAGRQ
Timestamp: 10004ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 003 ISeqno: 006 Type: IAX Subclass: LAGRP
Timestamp: 10004ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[-01] – OSeqno: 006 ISeqno: 004 Type: IAX Subclass: ACK
Timestamp: 10004ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 004 ISeqno: 006 Type: IAX Subclass: LAGRQ
Timestamp: 20007ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 006 ISeqno: 005 Type: IAX Subclass: LAGRP
Timestamp: 20007ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 005 ISeqno: 007 Type: IAX Subclass: ACK
Timestamp: 20007ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 007 ISeqno: 005 Type: IAX Subclass: LAGRQ
Timestamp: 20003ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 005 ISeqno: 008 Type: IAX Subclass: LAGRP
Timestamp: 20003ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[-01] – OSeqno: 008 ISeqno: 006 Type: IAX Subclass: ACK
Timestamp: 20003ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 006 ISeqno: 008 Type: IAX Subclass: PING
Timestamp: 21008ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 008 ISeqno: 007 Type: IAX Subclass: PONG
Timestamp: 21008ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
RR_JITTER : 0
RR_LOSS : 0
RR_PKTS : 1
RR_DELAY : 40
RR_DROPPED : 0
RR_OUTOFORDER : 0
Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 009 Type: IAX Subclass: ACK
Timestamp: 21008ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 009 ISeqno: 007 Type: IAX Subclass: PING
Timestamp: 21004ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 010 Type: IAX Subclass: PONG
Timestamp: 21004ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
RR_JITTER : 0
RR_LOSS : 0
RR_PKTS : 2
RR_DELAY : 40
RR_DROPPED : 0
RR_OUTOFORDER : 0
Tx-Frame Retry[-01] – OSeqno: 010 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 21004ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
– Nobody picked up in 25000 ms
Tx-Frame Retry[000] – OSeqno: 010 ISeqno: 008 Type: CONTROL Subclass: (255?)
Timestamp: 25036ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
– Executing [877@extensions:4] Playback(“IAX2/home_server-3672”, “beep”) in new stack
Tx-Frame Retry[000] – OSeqno: 011 ISeqno: 008 Type: CONTROL Subclass: ANSWER
Timestamp: 25039ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 012 ISeqno: 008 Type: CONTROL Subclass: (255?)
Timestamp: 25042ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 011 Type: IAX Subclass: ACK
Timestamp: 25036ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 012 Type: IAX Subclass: ACK
Timestamp: 25039ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 013 Type: IAX Subclass: ACK
Timestamp: 25042ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
– <IAX2/home_server-3672> Playing ‘beep.gsm’ (language ‘en’)
Tx-Frame Retry[000] – OSeqno: 013 ISeqno: 008 Type: VOICE Subclass: 4
Timestamp: 25540ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 014 Type: IAX Subclass: ACK
Timestamp: 25540ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
– Executing [877@extensions:5] VoiceMail(“IAX2/home_server-3672”, “877”) in new stack
– <IAX2/home_server-3672> Playing ‘vm-intro.gsm’ (language ‘en’)
Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 014 Type: IAX Subclass: LAGRQ
Timestamp: 30006ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Tx-Frame Retry[000] – OSeqno: 014 ISeqno: 009 Type: IAX Subclass: LAGRP
Timestamp: 30006ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 009 ISeqno: 014 Type: IAX Subclass: HANGUP
Timestamp: 30009ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
CAUSE CODE : 0
Tx-Frame Retry[-01] – OSeqno: 014 ISeqno: 010 Type: IAX Subclass: ACK
Timestamp: 30009ms SCall: 03672 DCall: 06809 [192.168.143.7:4569]
== Spawn extension (extensions, 877, 5) exited non-zero on ‘IAX2/home_server-3672’
– Hungup ‘IAX2/home_server-3672’
== MixMonitor close filestream (mixed)
== End MixMonitor Recording IAX2/home_server-3672
Rx-Frame Retry[ No] – OSeqno: 009 ISeqno: 015 Type: IAX Subclass: ACK
Timestamp: 30006ms SCall: 06809 DCall: 03672 [192.168.143.7:4569]
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ
Timestamp: 00007ms SCall: 03936 DCall: 00000 [192.168.143.7:4569]
USERNAME : home_server
REFRESH : 60
Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
Timestamp: 00007ms SCall: 00001 DCall: 03936 [192.168.143.7:4569]
CALLTOKEN : 51 bytes
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: REGREQ
Timestamp: 00016ms SCall: 03936 DCall: 00000 [192.168.143.7:4569]
USERNAME : home_server
REFRESH : 60
CALLTOKEN : 51 bytes
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: REGAUTH
Timestamp: 00001ms SCall: 00230 DCall: 03936 [192.168.143.7:4569]
AUTHMETHODS : 3
CHALLENGE : \x31\x36\x32\x30\x35\x35\x36\x35\x30
USERNAME : home_server
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: REGREQ
Timestamp: 00024ms SCall: 03936 DCall: 00230 [192.168.143.7:4569]
USERNAME : home_server
REFRESH : 60
MD5 RESULT : 95672bad52507605985e8befc64ba06c
Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: REGACK
Timestamp: 00011ms SCall: 00230 DCall: 03936 [192.168.143.7:4569]
USERNAME : home_server
DATE TIME : 2024-02-02 23:57:10
REFRESH : 60
APPARENT ADDRES : IPV4 192.168.143.7:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00011ms SCall: 03936 DCall: 00230 [192.168.143.7:4569]