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

To: Jose Limeres <jlimeres@xxxxxxxxx>
Subject: Re: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
From: Lee Howard <faxguy@xxxxxxxxxxxxxxxx>
Cc: hylafax-users@xxxxxxxxxxx
Date: Wed, 18 Apr 2007 11:26:25 -0700

I would suggest trying Asterisk versions 1.2.x. Is anyone here using iaxmodem successfully with Asterisk 1.4 ?

Lee.

Jose Limeres wrote:

Thanks Lee,

This my configuration: FXO_LINE ----> ASTERISK 1.4.2 ---> IAXMODEM ------> HYLAFAX TDM400P ZAPTEL 1.4.1 0.2.1 4.3.1 1 FXO line Running on a Debian machine 2.6.18. I may try going down to Asterisk 1.4.0 and I will also investigate the libiax2 I have installed and maybe reinstall iaxmodem, which is now with static linking. I may try dynamic linking. Regards, Jose L.

On 18/04/07, *Lee Howard* <faxguy@xxxxxxxxxxxxxxxx <mailto:faxguy@xxxxxxxxxxxxxxxx>> wrote:
    This information doesn't tell us anything new or give me any
    indication
    of where the problem is.  These logs tell us plainly that there is no
    audio going between the two IAX2 endpoints.  Here's a single frame
    communication that seems weird:
Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 003 Type: VOICE Subclass: 8 Timestamp: 03080ms SCall: 00001 DCall: 22195 [127.0.0.1:4570 <http://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 <http://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 <http://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 <http://127.0.0.1:4570>]

That's OSeqno 004. Here's OSeqno 003:

Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 02001ms SCall: 22195 DCall: 00001 [ 127.0.0.1:4570 <http://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 <http://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 <http://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 <http://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 <http://127.0.0.1:4570>]
    I'm not an IAX2 expert, but these look messed up.  It's been a
    long time
    since I worked hard with the IAX2 protocol enough to be able to say
    what, exactly is going wrong here, but these don't look right to me.
    Certainly the fault could be with libiax2 in iaxmodem.  It's been a
    while since I updated from the iaxclient CVS repository.  Maybe
    something has been fixed.  However, can I suggest that you try a
    different Asterisk version.  What version are you using, anyway?
Lee.

Jose Limeres wrote:

> 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 <http://127.0.0.1> < http://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 <mailto:faxguy@xxxxxxxxxxxxxxxx> > <mailto:faxguy@xxxxxxxxxxxxxxxx <mailto: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 <http://192.168.1.110:5060> ---> >REGISTER sip:192.168.1.105 <http://192.168.1.105> SIP/2.0 >Via: SIP/2.0/UDP 192.168.1.110:5060 <http://192.168.1.110:5060>;branch=z9hG4bK-80a948a4 >From: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>>;tag=11d8eb0d9fbffdf6o0 >To: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>> >Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx <mailto:e2a16939-dbdfb5ba@xxxxxxxxxxxxx> >CSeq: 1596 REGISTER >Max-Forwards: 70 >Authorization: Digest username="502",realm="asterisk",nonce="75596c6b",uri="sip: 192.168.1.105 <http://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 <http://192.168.1.110> : 5060 (no NAT) > ><--- Transmitting (no NAT) to 192.168.1.110:5060 <http://192.168.1.110:5060> ---> >SIP/2.0 100 Trying >Via: SIP/2.0/UDP 192.168.1.110:5060 <http://192.168.1.110:5060>;branch=z9hG4bK-80a948a4;received= 192.168.1.1 <http://192.168.1.1> 10 >From: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>>;tag=11d8eb0d9fbffdf6o0 >To: "502" < sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>> >Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx <mailto: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 <mailto: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 <mailto:sip:502@xxxxxxxxxxxxx>>' > ><--- Transmitting (no NAT) to 192.168.1.110:5060 <http://192.168.1.110:5060> ---> >SIP/2.0 401 Unauthorized >Via: SIP/2.0/UDP 192.168.1.110:5060 <http://192.168.1.110:5060>;branch=z9hG4bK-80a948a4;received=192.168.1.1 <http://192.168.1.1> 10 >From: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx> >;tag=11d8eb0d9fbffdf6o0 >To: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>>;tag=as660b55b5 >Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx <mailto: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 <mailto:e2a16939-dbdfb5ba@xxxxxxxxxxxxx>' in 32000 ms (Method: REGISTER) >Bora1*CLI> ><--- SIP read from 192.168.1.110:5060 <http://192.168.1.110:5060> ---> >REGISTER sip:192.168.1.105 <http://192.168.1.105> SIP/2.0 >Via: SIP/2.0/UDP 192.168.1.110:5060 <http://192.168.1.110:5060>;branch=z9hG4bK-eda2c984 >From: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>>;tag=11d8eb0d9fbffdf6o0 >To: "502" < sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>> >Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx <mailto:e2a16939-dbdfb5ba@xxxxxxxxxxxxx> >CSeq: 1597 REGISTER >Max-Forwards: 70 >Authorization: Digest username="502",realm="asterisk",nonce="1b804a49",uri="sip: 192.168.1.105 <http://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 <http://192.168.1.110> : 5060 (no NAT) > ><--- Transmitting (no NAT) to 192.168.1.110:5060 <http://192.168.1.110:5060> ---> >SIP/2.0 100 Trying >Via: SIP/2.0/UDP 192.168.1.110:5060 <http://192.168.1.110:5060>;branch=z9hG4bK-eda2c984;received=192.168.1.1 <http://192.168.1.1> 10 >From: "502" < sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>>;tag=11d8eb0d9fbffdf6o0 >To: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>> >Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx <mailto: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 <mailto: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 <http://192.168.1.110:5060> ---> >SIP/2.0 200 OK >Via: SIP/2.0/UDP 192.168.1.110:5060 <http://192.168.1.110:5060>;branch=z9hG4bK-eda2c984;received= 192.168.1.1 <http://192.168.1.1> 10 >From: "502" <sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>>;tag=11d8eb0d9fbffdf6o0 >To: "502" < sip:502@xxxxxxxxxxxxx <mailto:sip:502@xxxxxxxxxxxxx>>;tag=as660b55b5 >Call-ID: e2a16939-dbdfb5ba@xxxxxxxxxxxxx <mailto: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 <mailto: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 <mailto: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 <http://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 <http://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 <http://127.0.0.1:4570>] > MD5 RESULT : 5875a453c59866684b4599a0577d8ad9 >Bora1*CLI> > -- Accepting AUTHENTICATED call from 127.0.0.1 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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> > >

____________________ HylaFAX(tm) Users Mailing List _______________________
 To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
 *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*

Follow-Ups:
- Re: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
  - From: Jose Limeres

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
- Re: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
  - From: Jose Limeres

Prev by Date: Re: [hylafax-users] MODEM TIMEOUT: waiting for v.21 carrier
Next by Date: [hylafax-users] Sending a fax, but the receiving end is manual
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