[hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier

To: hylafax-users@xxxxxxxxxxx
Subject: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
From: "Jose Limeres" <jlimeres@xxxxxxxxx>
Date: Wed, 18 Apr 2007 18:10:48 +0200

Thanks Lee,
I include thereafter SIP logs from the Asterisk CLI and as an attachement are included IAX2 logs.
I could obtain any other logs as requested.
I saw there is the possibility to record the call as a wav file but I am unsure if this information would be useful.

I am pretty lost with this as at the moment the only thing I know is that faxes do not pass through but no clear indication of the reason. Could it be due to the line quality?. Actually it supports ADSL but with a very bad quality as the local loop is quite long.
Thanks,
Jose L.

CLI>
    -- Accepting AUTHENTICATED call from 127.0.0.1:
       > requested format = slin,
       > requested prefs = (),
       > actual format = alaw,
       > host prefs = (alaw),
       > priority = mine
    -- Executing [956855858@default:1] Dial("IAX2/200-1", "Zap/g1/956855858") in new stack
    -- Called g1/956855858
    -- Zap/4-1 answered IAX2/200-1
    -- Hungup 'Zap/4-1'
== Spawn extension (default, 956855858, 1) exited non-zero on 'IAX2/200-1'
    -- Hungup 'IAX2/200-1'
CLI>

> faxstat -s
JID    Pri    S    Owner    Number         Pages    Dials     TTS      Status
35     126 S    root         956855858    0:1          1:12       11.19     No carrier detected

On 18/04/07, Lee Howard <faxguy@xxxxxxxxxxxxxxxx> wrote:

These logs don't tell us anything other than what we already know: that
the modem isn't "hearing" any fax signalling from the caller's end.

You'll probably get better information examining debugging messages on
the Asterisk CLI.

Lee.

Jose Limeres wrote:

> Hi,
> I have an Hylafax server connected to Asterisk (using one FXO line)
> through Iaxmodem but up to date I have not been able to send or
> receive a single Fax though the call goes through.
> This is the log I get when I try to receive a Fax. On the other side,
> the Fax machine just stops the transmission after a few seconds.
> Anybody see any useful trace of information on these traces that could
> point me in the right direction?
>
> Thanks,
> Jose Limeres
>
> Jan 03 06:23:28.11: [ 2916]: SESSION BEGIN 000000041 34986986843203
> Jan 03 06:23: 28.11: [ 2916]: HylaFAX (tm) Version 4.3.1
> Jan 03 06:23:28.11: [ 2916]: CallID: "986844848" "" "<NONE>" "s"
> Jan 03 06:23:28.11: [ 2916]: MODEM set XON/XOFF/FLUSH: input ignored,
> output disabled
> Jan 03 06:23:28.11: [ 2916]: <-- [4:ATA\r]
> Jan 03 06:23:31.83: [ 2916]: --> [7:CONNECT]
> Jan 03 06:23:31.83: [ 2916]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX'
> Jan 03 06:23: 31.83: [ 2916]: STATE CHANGE: ANSWERING -> RECEIVING
> Jan 03 06:23:31.83: [ 2916]: RECV FAX: begin
> Jan 03 06:23:31.83: [ 2916]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86
> 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 CC 74 8C>
> Jan 03 06:23:31.83: [ 2916]: <-- data [32]
> Jan 03 06:23:31.83: [ 2916]: <-- data [2]
> Jan 03 06:23:32.75: [ 2916]: --> [7:CONNECT]
> Jan 03 06:23:32.75: [ 2916]: <-- HDLC<23:FF C0 02 F2 4A A2 2A F2 04 04
> 04 04 04 04 04 04 04 04 04 04 04 04 04>
> Jan 03 06:23:32.75: [ 2916]: <-- data [23]
> Jan 03 06:23:32.75: [ 2916]: <-- data [2]
> Jan 03 06:23:33.51: [ 2916]: --> [7:CONNECT]
> Jan 03 06:23: 33.51: [ 2916]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1
> 01 01 18>
> Jan 03 06:23:33.51: [ 2916]: <-- data [13]
> Jan 03 06:23:33.51: [ 2916]: <-- data [2]
> Jan 03 06:23:34.09: [ 2916]: --> [2:OK]
> Jan 03 06:23:34.09: [ 2916]: <-- [9:AT+FRH=3\r]
> Jan 03 06:23:41.10: [ 2916]: --> [0:]
> Jan 03 06:23:41.10: [ 2916]: MODEM <Empty line>
> Jan 03 06:23:41.10: [ 2916]: MODEM TIMEOUT: waiting for v.21 carrier
> Jan 03 06:23:41.10: [ 2916]: <-- data [1]
> Jan 03 06:23:41.11: [ 2916]: --> [2:OK]
> Jan 03 06:23:41.11: [ 2916]: DELAY 1500 ms
> Jan 03 06:23:42.61: [ 2916]: <-- [9:AT+FTH=3\r]
> ===============================================================
> REPEATED 3 TIMES
> ===============================================================
> Jan 03 06:24:08.37: [ 2916]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1
> 01 01 18>
> Jan 03 06:24:08.37: [ 2916]: <-- data [13]
> Jan 03 06:24:08.37: [ 2916]: <-- data [2]
> Jan 03 06:24:08.95: [ 2916]: --> [2:OK]
> Jan 03 06:24:08.95: [ 2916]: <-- [9:AT+FRH=3\r]
> Jan 03 06:24:15.96: [ 2916]: --> [0:]
> Jan 03 06:24:15.96: [ 2916]: MODEM <Empty line>
> Jan 03 06:24:15.96: [ 2916]: MODEM TIMEOUT: waiting for v.21 carrier
> Jan 03 06:24:15.96: [ 2916]: <-- data [1]
> Jan 03 06:24:15.97: [ 2916]: --> [2:OK]
> Jan 03 06:24:15.97: [ 2916]: RECV FAX: No answer (T.30 T1 timeout)
> Jan 03 06:24:15.97: [ 2916]: RECV FAX: end
> Jan 03 06:24: 15.97: [ 2916]: SESSION END
>
>

Bora1*CLI> sip set debug
SIP Debugging enabled
Bora1*CLI>
Bora1*CLI>
Really destroying SIP dialog '3c2682e31fbd-9bh2yjwyv6m4@snom360-0004132304B4' Me                       thod: REGISTER
Bora1*CLI> iax2 set debug
IAX2 Debugging Enabled
Bora1*CLI>
Bora1*CLI>
<--- SIP read from 192.168.1.110:5060 --->
REGISTER sip:192.168.1.105 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.110:5060;branch=z9hG4bK-80a948a4
From: "502" <sip:502@xxxxxxxxxxxxx>;tag=11d8eb0d9fbffdf6o0
To: "502" <sip:502@xxxxxxxxxxxxx>
Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx
CSeq: 1596 REGISTER
Max-Forwards: 70
Authorization: Digest username="502",realm="asterisk",nonce="75596c6b",uri="sip:                       192.168.1.105",algorithm=MD5,response="fcf6313dd9d5f7ba357417d8be0e12dd"
Contact: "502" <sip:502@xxxxxxxxxxxxx:5060>;expires=3600
User-Agent: Sipura/SPA841-3.1.3(a)
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER


<------------->
--- (12 headers 0 lines) ---
Using latest REGISTER request as basis request
Sending to 192.168.1.110 : 5060 (no NAT)

<--- Transmitting (no NAT) to 192.168.1.110:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.110:5060;branch=z9hG4bK-80a948a4;received=192.168.1.1                       10
From: "502" <sip:502@xxxxxxxxxxxxx>;tag=11d8eb0d9fbffdf6o0
To: "502" <sip:502@xxxxxxxxxxxxx>
Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx
CSeq: 1596 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:502@xxxxxxxxxxxxx>
Content-Length: 0


<------------>
[Jan  4 06:51:58] NOTICE[2898]: chan_sip.c:8151 check_auth: Correct auth, but ba                       sed on stale nonce received from '"502" <sip:502@xxxxxxxxxxxxx>'

<--- Transmitting (no NAT) to 192.168.1.110:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.110:5060;branch=z9hG4bK-80a948a4;received=192.168.1.1                       10
From: "502" <sip:502@xxxxxxxxxxxxx>;tag=11d8eb0d9fbffdf6o0
To: "502" <sip:502@xxxxxxxxxxxxx>;tag=as660b55b5
Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx
CSeq: 1596 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="1b804a49", stal                       e=true
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog 'e2a16939-dbdfb5ba@xxxxxxxxxxxxx' in 32000                        ms (Method: REGISTER)
Bora1*CLI>
<--- SIP read from 192.168.1.110:5060 --->
REGISTER sip:192.168.1.105 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.110:5060;branch=z9hG4bK-eda2c984
From: "502" <sip:502@xxxxxxxxxxxxx>;tag=11d8eb0d9fbffdf6o0
To: "502" <sip:502@xxxxxxxxxxxxx>
Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx
CSeq: 1597 REGISTER
Max-Forwards: 70
Authorization: Digest username="502",realm="asterisk",nonce="1b804a49",uri="sip:                       192.168.1.105",algorithm=MD5,response="94d95369aff64bb91cd6408368d8de40"
Contact: "502" <sip:502@xxxxxxxxxxxxx:5060>;expires=3600
User-Agent: Sipura/SPA841-3.1.3(a)
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER


<------------->
--- (12 headers 0 lines) ---
Using latest REGISTER request as basis request
Sending to 192.168.1.110 : 5060 (no NAT)

<--- Transmitting (no NAT) to 192.168.1.110:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.110:5060;branch=z9hG4bK-eda2c984;received=192.168.1.1                       10
From: "502" <sip:502@xxxxxxxxxxxxx>;tag=11d8eb0d9fbffdf6o0
To: "502" <sip:502@xxxxxxxxxxxxx>
Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx
CSeq: 1597 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:502@xxxxxxxxxxxxx>
Content-Length: 0


<------------>
    -- Saved useragent "Sipura/SPA841-3.1.3(a)" for peer 502
Bora1*CLI>
<--- Transmitting (no NAT) to 192.168.1.110:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.110:5060;branch=z9hG4bK-eda2c984;received=192.168.1.1                       10
From: "502" <sip:502@xxxxxxxxxxxxx>;tag=11d8eb0d9fbffdf6o0
To: "502" <sip:502@xxxxxxxxxxxxx>;tag=as660b55b5
Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx
CSeq: 1597 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:502@xxxxxxxxxxxxx:5060>;expires=3600
ate: Wed, 04 Jan 2006 06:51:58 GMT
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog 'e2a16939-dbdfb5ba@xxxxxxxxxxxxx' in 32000                        ms (Method: REGISTER)
Bora1*CLI> cd /etc/hilafax
No such command 'cd /etc/hilafax' (type 'help' for help)
Bora1*CLI> cd /etc/hylafax
No such command 'cd /etc/hylafax' (type 'help' for help)
Bora1*CLI> cd /etc
No such command 'cd /etc' (type 'help' for help)
Really destroying SIP dialog 'e2a16939-dbdfb5ba@xxxxxxxxxxxxx' Method: REGISTER
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW
   Timestamp: 00003ms  SCall: 22195  DCall: 00000 [127.0.0.1:4570]
   VERSION         : 2
   CALLING NUMBER  : 200
   CALLING NAME    : IAX Modem 1
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : 200
   CALLED NUMBER   : 986844848
   DNID            : 986844848

Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: AUTHREQ
   Timestamp: 00009ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   AUTHMETHODS     : 3
   CHALLENGE       : 472445766
   USERNAME        : 200

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: AUTHREP
   Timestamp: 00005ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
   MD5 RESULT      : 5875a453c59866684b4599a0577d8ad9
Bora1*CLI>
    -- Accepting AUTHENTICATED call from 127.0.0.1:
       > requested format = slin,
       > requested prefs = (),
       > actual format = alaw,
       > host prefs = (alaw),
       > priority = mine
    -- Executing [986844848@default:1] Dial("IAX2/200-1", "Zap/g1/986844848") in                        new stack
    -- Called g1/986844848
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACCEPT
   Timestamp: 00015ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   FORMAT          : 8

Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00015ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: PING
   Timestamp: 02001ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 003 Type: IAX     Subclass: PONG
   Timestamp: 02001ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX     Subclass: ACK
   Timestamp: 02001ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
    -- Zap/4-1 answered IAX2/200-1
Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 003 Type: CONTROL Subclass: ANSWER
   Timestamp: 03065ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 004 Type: IAX     Subclass: ACK
   Timestamp: 03065ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 003 Type: VOICE   Subclass: 8
   Timestamp: 03080ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 004 Type: VOICE   Subclass: 8
   Timestamp: 03100ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 004 ISeqno: 004 Type: IAX     Subclass: ACK
   Timestamp: 03100ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX     Subclass: ACK
   Timestamp: 03080ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 004 Type: IAX     Subclass: LAGRQ
   Timestamp: 10019ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 006 Type: IAX     Subclass: ACK
   Timestamp: 10019ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 006 Type: IAX     Subclass: LAGRP
   Timestamp: 10019ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 006 ISeqno: 005 Type: IAX     Subclass: ACK
   Timestamp: 10019ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 006 Type: IAX     Subclass: PING
   Timestamp: 12023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 006 Type: IAX     Subclass: PONG
   Timestamp: 12023ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 007 Type: IAX     Subclass: ACK
   Timestamp: 12023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 006 Type: IAX     Subclass: PING
   Timestamp: 20019ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 006 Type: IAX     Subclass: LAGRQ
   Timestamp: 20022ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 008 Type: IAX     Subclass: ACK
   Timestamp: 20019ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 008 Type: IAX     Subclass: PONG
   Timestamp: 20019ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
   RR_JITTER       : 19

Tx-Frame Retry[-01] -- OSeqno: 008 ISeqno: 007 Type: IAX     Subclass: ACK
   Timestamp: 20019ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 009 Type: IAX     Subclass: ACK
   Timestamp: 20022ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 009 Type: IAX     Subclass: LAGRP
   Timestamp: 20022ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 008 Type: IAX     Subclass: ACK
   Timestamp: 20022ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 009 Type: IAX     Subclass: PING
   Timestamp: 22023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 009 ISeqno: 009 Type: IAX     Subclass: PONG
   Timestamp: 22023ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 010 Type: IAX     Subclass: ACK
   Timestamp: 22023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 010 ISeqno: 009 Type: IAX     Subclass: LAGRQ
   Timestamp: 30024ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 011 Type: IAX     Subclass: ACK
   Timestamp: 30024ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 011 Type: IAX     Subclass: LAGRP
   Timestamp: 30024ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 011 ISeqno: 010 Type: IAX     Subclass: ACK
   Timestamp: 30024ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 011 Type: IAX     Subclass: PING
   Timestamp: 32023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 011 ISeqno: 011 Type: IAX     Subclass: PONG
   Timestamp: 32023ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 012 Type: IAX     Subclass: ACK
   Timestamp: 32023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 012 ISeqno: 011 Type: IAX     Subclass: PING
   Timestamp: 40024ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 013 ISeqno: 011 Type: IAX     Subclass: LAGRQ
   Timestamp: 40027ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 013 Type: IAX     Subclass: ACK
   Timestamp: 40024ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 013 Type: IAX     Subclass: PONG
   Timestamp: 40024ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
   RR_JITTER       : 14

Tx-Frame Retry[-01] -- OSeqno: 013 ISeqno: 012 Type: IAX     Subclass: ACK
   Timestamp: 40024ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 014 Type: IAX     Subclass: ACK
   Timestamp: 40027ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[Yes] -- OSeqno: 011 ISeqno: 013 Type: IAX     Subclass: PONG
   Timestamp: 40024ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
   RR_JITTER       : 14

Tx-Frame Retry[-01] -- OSeqno: 013 ISeqno: 012 Type: IAX     Subclass: ACK
   Timestamp: 40024ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 012 ISeqno: 014 Type: IAX     Subclass: LAGRP
   Timestamp: 40027ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 014 ISeqno: 013 Type: IAX     Subclass: ACK
   Timestamp: 40027ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[Yes] -- OSeqno: 012 ISeqno: 014 Type: IAX     Subclass: LAGRP
   Timestamp: 40027ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 014 ISeqno: 013 Type: IAX     Subclass: ACK
   Timestamp: 40027ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 013 ISeqno: 014 Type: IAX     Subclass: PING
   Timestamp: 42023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 014 ISeqno: 014 Type: IAX     Subclass: PONG
   Timestamp: 42023ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 014 ISeqno: 015 Type: IAX     Subclass: ACK
   Timestamp: 42023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 015 ISeqno: 014 Type: IAX     Subclass: LAGRQ
   Timestamp: 50028ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 014 ISeqno: 016 Type: IAX     Subclass: ACK
   Timestamp: 50028ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 014 ISeqno: 016 Type: IAX     Subclass: LAGRP
   Timestamp: 50028ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 016 ISeqno: 015 Type: IAX     Subclass: ACK
   Timestamp: 50028ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 015 ISeqno: 016 Type: IAX     Subclass: PING
   Timestamp: 52023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 016 ISeqno: 016 Type: IAX     Subclass: PONG
   Timestamp: 52023ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[Yes] -- OSeqno: 015 ISeqno: 016 Type: IAX     Subclass: PING
   Timestamp: 52023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 016 ISeqno: 016 Type: IAX     Subclass: ACK
   Timestamp: 52023ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 016 ISeqno: 017 Type: IAX     Subclass: ACK
   Timestamp: 52023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 017 ISeqno: 016 Type: IAX     Subclass: PING
   Timestamp: 60029ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 018 ISeqno: 016 Type: IAX     Subclass: LAGRQ
   Timestamp: 60032ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 016 ISeqno: 018 Type: IAX     Subclass: ACK
   Timestamp: 60029ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 016 ISeqno: 018 Type: IAX     Subclass: PONG
   Timestamp: 60029ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
   RR_JITTER       : 17

Tx-Frame Retry[-01] -- OSeqno: 018 ISeqno: 017 Type: IAX     Subclass: ACK
   Timestamp: 60029ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[Yes] -- OSeqno: 016 ISeqno: 018 Type: IAX     Subclass: PONG
   Timestamp: 60029ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
   RR_JITTER       : 17

Tx-Frame Retry[-01] -- OSeqno: 018 ISeqno: 017 Type: IAX     Subclass: ACK
   Timestamp: 60029ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 016 ISeqno: 019 Type: IAX     Subclass: ACK
   Timestamp: 60032ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 017 ISeqno: 019 Type: IAX     Subclass: LAGRP
   Timestamp: 60032ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 019 ISeqno: 018 Type: IAX     Subclass: ACK
   Timestamp: 60032ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[Yes] -- OSeqno: 017 ISeqno: 019 Type: IAX     Subclass: LAGRP
   Timestamp: 60032ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[-01] -- OSeqno: 019 ISeqno: 018 Type: IAX     Subclass: ACK
   Timestamp: 60032ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 018 ISeqno: 019 Type: IAX     Subclass: PING
   Timestamp: 62023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Tx-Frame Retry[000] -- OSeqno: 019 ISeqno: 019 Type: IAX     Subclass: PONG
   Timestamp: 62023ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 019 ISeqno: 020 Type: IAX     Subclass: ACK
   Timestamp: 62023ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
Rx-Frame Retry[ No] -- OSeqno: 019 ISeqno: 020 Type: IAX     Subclass: HANGUP
   Timestamp: 63103ms  SCall: 22195  DCall: 00001 [127.0.0.1:4570]
   CAUSE           : Normal disconnect

Tx-Frame Retry[-01] -- OSeqno: 020 ISeqno: 020 Type: IAX     Subclass: ACK
   Timestamp: 63103ms  SCall: 00001  DCall: 22195 [127.0.0.1:4570]
    -- Hungup 'Zap/4-1'
  == Spawn extension (default, 986844848, 1) exited non-zero on 'IAX2/200-1'
    -- Hungup 'IAX2/200-1'
Bora1*CLI>

References:
- [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
  - From: Jose Limeres
- Re: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
  - From: Lee Howard

Prev by Date: Re: [hylafax-users] Sending email to fax with attachements only using Faxmail
Next by Date: Re: [hylafax-users] Send fax ERRORS: COMREC invalid response received to PPS - No response to PPS repeated 3 times
Previous by thread: Re: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
Next by thread: Re: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
Index(es):
- Main
- Thread

Project hosted by iFAX Solutions