Receive fax in version.21

John Marshall (johnm@deverse.com)
Fri, 24 Sep 1999 20:02:45 -0700
Messages sorted by:[ date ][ thread ][ subject ][ author ]
Gert,

I have checked the two logs to try and understand the difference between .14
and .21 with respect to receiving faxes.

There are some differences in initialisation, and an extra bit of init stuff
is sent in the case of .21. Following this you will find the log session from
around the point where the fax tone is detected.

Note that I did find one other difference : in my voice.conf I have a
post_init_chat that I used to send to the 'CID=1' when I was trying to get
this to work in .21. I now see this in the init stuff. On version .14 this
keyword is ignored, and so is not sent to the modem. I'm wondering if this is
the real problem ?

When you have a .22 beta I would like to compile and install and see if I
still have the problem with this.

Regards


John M


During initialisation :

.14
09/18 16:16:06 yS1  detecting voice modem type
09/18 16:16:06 yS1    vgetty: ATE0
09/18 16:16:06 yS1    serial port: ATE0
09/18 16:16:06 yS1    serial port: OK
09/18 16:16:06 yS1   voice command: 'ATI' -> ''
09/18 16:16:06 yS1    vgetty: ATI
09/18 16:16:06 yS1    serial port: 56000
09/18 16:16:07 yS1    Rockwell: OK
09/18 16:16:07 yS1  Rockwell detected

.21
09/18 16:05:16 yS1  detecting voice modem type
09/18 16:05:16 yS1    vgetty: ATE0
09/18 16:05:16 yS1    serial port: ATE0
09/18 16:05:16 yS1    serial port: OK
09/18 16:05:16 yS1   voice command: 'ATI9' -> ''
09/18 16:05:16 yS1    vgetty: ATI9
09/18 16:05:16 yS1    serial port: 003781A3
09/18 16:05:16 yS1    serial port: OK
09/18 16:05:17 yS1   voice command: 'ATI' -> ''
09/18 16:05:17 yS1    vgetty: ATI
09/18 16:05:17 yS1    serial port: 56000
09/18 16:05:17 yS1    Rockwell: OK
09/18 16:05:17 yS1  Rockwell detected

....

09/18 16:05:19 yS1   running post_init_chat
09/18 16:05:19 yS1  send: AT#CID=0[0d]
09/18 16:05:19 yS1  waiting for ``OK''
09/18 16:05:19 yS1   got: [0d]
09/18 16:05:19 yS1    CND: OK[0a]OK ** found **


During outgoing message :

.14

09/18 16:16:32 yS1    vgetty: queued event FAX_CALLING_TONE at position 0009
09/18 16:16:32 yS1    vgetty: unqueued event FAX_CALLING_TONE at position 0009

09/18 16:16:32 yS1    vgetty: voice_handle_event got event FAX_CALLING_TONE
with data <o>
09/18 16:16:32 yS1   vgetty(1): FAX_CALLING_TONE
09/18 16:16:32 yS1    vgetty: Got pipe signal
09/18 16:16:32 yS1    vgetty: queued event SIGNAL_SIGPIPE at position 0010
09/18 16:16:32 yS1    vgetty: unqueued event SIGNAL_SIGPIPE at position 0010
09/18 16:16:32 yS1    vgetty: voice_handle_event got event SIGNAL_SIGPIPE with
data <o>
09/18 16:16:32 yS1   shell(2): STOP
09/18 16:16:33 yS1    vgetty: <VOICE DATA 15840 bytes>
09/18 16:16:33 yS1    vgetty: queued event RESET_WATCHDOG at position 0011
09/18 16:16:33 yS1    vgetty: unqueued event RESET_WATCHDOG at position 0011
09/18 16:16:33 yS1    vgetty: voice_handle_event got event RESET_WATCHDOG with
data <o>
09/18 16:16:33 yS1    vgetty: <INTERRUPT PLAY>
09/18 16:16:33 yS1   voice command: '' -> 'OK|VCON'
09/18 16:16:33 yS1    Rockwell: VCON
09/18 16:16:33 yS1   vgetty(1): READY
09/18 16:16:33 yS1    vgetty: Got pipe signal
09/18 16:16:33 yS1    vgetty: queued event SIGNAL_SIGPIPE at position 0012
09/18 16:16:33 yS1    vgetty: unqueued event SIGNAL_SIGPIPE at position 0012
09/18 16:16:33 yS1    vgetty: voice_handle_event got event SIGNAL_SIGPIPE with
data <
09/18 16:16:33 yS1   shell(1): GOODBYE
09/18 16:16:33 yS1   vgetty(1): GOODBYE SHELL
09/18 16:16:33 yS1    vgetty: Got child changed status signal
09/18 16:16:33 yS1    vgetty: queued event SIGNAL_SIGCHLD at position 0013
09/18 16:16:33 yS1   vgetty: shell exited normally with status 0x0002
09/18 16:16:33 yS1    vgetty: trying fax connection
09/18 16:16:33 yS1   voice command: 'AT#CLS=2' -> 'OK'
09/18 16:16:33 yS1    vgetty: AT#CLS=2
09/18 16:16:33 yS1    vgetty: unqueued event SIGNAL_SIGCHLD at position 0013
09/18 16:16:33 yS1    vgetty: voice_handle_event got event SIGNAL_SIGCHLD with
data <
09/18 16:16:33 yS1  vgetty: voice_handle_event got unknown event
SIGNAL_SIGCHLD with data <
09/18 16:16:33 yS1    Rockwell: OK
09/18 16:16:33 yS1   voice command: 'AT+FAA=0' -> 'OK'
09/18 16:16:33 yS1    vgetty: AT+FAA=0
09/18 16:16:33 yS1    Rockwell: OK
09/18 16:16:33 yS1   vgetty: Restoring signal handlers
09/18 16:16:34 yS1  mdm_send: 'AT+FBOR=0'
09/18 16:16:34 yS1    got:[0a][0d][0a]OK[0d]
09/18 16:16:34 yS1   mdm_command: string 'OK' -> OK
09/18 16:16:34 yS1  send: ATA[0d]
09/18 16:16:34 yS1  waiting for ``CONNECT''
09/18 16:16:34 yS1   got: [0d][0a]+FCON
09/18 16:16:39 yS1  found action string: ``+FCON''
09/18 16:16:39 yS1  start fax receiver...
09/18 16:16:39 yS1   fax receiver: entry
09/18 16:16:39 yS1   tss: set speed to 19200 (016)
09/18 16:16:39 yS1  fax_wait_for(OK)
09/18 16:16:39 yS1    got:[0a][0d][0a][0d][0a]+FTSI: "
1000000000"[0d]
09/18 16:16:41 yS1   fax_wait_for: string '+FTSI: "          1000000000"'
09/18 16:16:41 yS1  fax_id: '+FTSI: "          1000000000"'

.21

09/18 16:05:42 yS1    vgetty: queued event FAX_CALLING_TONE at position 0009
09/18 16:05:42 yS1    vgetty: unqueued event FAX_CALLING_TONE at position 0009

09/18 16:05:42 yS1    vgetty: voice_handle_event got event FAX_CALLING_TONE
with data <NUL>
09/18 16:05:42 yS1   vgetty(1): FAX_CALLING_TONE
09/18 16:05:42 yS1    vgetty: Got pipe signal
09/18 16:05:42 yS1    vgetty: queued event SIGNAL_SIGPIPE at position 0010
09/18 16:05:42 yS1    vgetty: unqueued event SIGNAL_SIGPIPE at position 0010
09/18 16:05:42 yS1    vgetty: voice_handle_event got event SIGNAL_SIGPIPE with
data <NUL>
09/18 16:05:42 yS1   shell(2): STOP
09/18 16:05:43 yS1    vgetty: <VOICE DATA 11880 bytes>
09/18 16:05:43 yS1    vgetty: queued event RESET_WATCHDOG at position 0011
09/18 16:05:43 yS1    vgetty: unqueued event RESET_WATCHDOG at position 0011
09/18 16:05:43 yS1    vgetty: voice_handle_event got event RESET_WATCHDOG with
data <NUL>
09/18 16:05:43 yS1    vgetty: <INTERRUPT PLAY>
09/18 16:05:43 yS1   voice command: '' -> 'OK|VCON'
09/18 16:05:43 yS1    Rockwell: VCON
09/18 16:05:43 yS1   vgetty(1): READY
09/18 16:05:43 yS1    vgetty: Got pipe signal
09/18 16:05:43 yS1    vgetty: queued event SIGNAL_SIGPIPE at position 0012
09/18 16:05:43 yS1    vgetty: unqueued event SIGNAL_SIGPIPE at position 0012
09/18 16:05:43 yS1    vgetty: voice_handle_event got event SIGNAL_SIGPIPE with
data <_>
09/18 16:05:43 yS1   shell(1): GOODBYE
09/18 16:05:43 yS1   vgetty(1): GOODBYE SHELL
09/18 16:05:43 yS1    vgetty: Got child changed status signal
09/18 16:05:43 yS1    vgetty: queued event SIGNAL_SIGCHLD at position 0013
09/18 16:05:43 yS1   vgetty: shell exited normally with status 0x0002
09/18 16:05:43 yS1    vgetty: trying fax connection
09/18 16:05:43 yS1   voice command: 'AT#CLS=0' -> 'OK'
09/18 16:05:43 yS1    vgetty: AT#CLS=0
09/18 16:05:43 yS1    vgetty: unqueued event SIGNAL_SIGCHLD at position 0013
09/18 16:05:43 yS1    vgetty: voice_handle_event got event SIGNAL_SIGCHLD with
data <_>
09/18 16:05:43 yS1    vgetty: voice_handle_event got unknown event
SIGNAL_SIGCHLD with data <_>
09/18 16:05:43 yS1    Rockwell: OK
09/18 16:05:43 yS1   voice command: 'AT' -> 'OK'
09/18 16:05:43 yS1    vgetty: AT
09/18 16:05:43 yS1    Rockwell: OK
09/18 16:05:43 yS1   voice command: 'AT+FAA=0' -> 'OK'
09/18 16:05:43 yS1    vgetty: AT+FAA=0
09/18 16:05:43 yS1    Rockwell: OK
09/18 16:05:43 yS1   vgetty: Restoring signal handlers
09/18 16:05:43 yS1  mdm_send: 'AT+FBOR=0'
09/18 16:05:43 yS1    got:[0a][0d][0a]OK[0d]
09/18 16:05:43 yS1   mdm_command: string 'OK' -> OK
09/18 16:05:43 yS1  send: ATA[0d]
09/18 16:05:43 yS1  waiting for ``CONNECT''
09/18 16:05:43 yS1   got: [0d]
09/18 16:06:40 yS1    CND: OK[0a]NO CARRIER
09/18 16:06:40 yS1  found action string: ``NO CARRIER''
09/18 16:06:40 ##### failed A_FAIL dev=ttyS1, pid=4767, caller='none',
conn='', name=''


Gert Doering wrote:

> Hi,
>
> On Tue, Sep 21, 1999 at 09:29:38PM -0700, John Marshall wrote:
> > I have created logs of a fax receive with both versions. I prefer to
> > avoid clogging the list with these huge log files, so I have placed them
> > at http://www.deverse.com/mgetty/
>
> Hmmm, I'm not really sure how I shall look at that (I'm on the road and
> have no WWW access - only e-mail).
>
> Could you go through the logs, and find the places where things start to
> differ, and send only the "different parts" to the list?
>
> Could very well be a bug...
>
> gert
> --
> Gert Doering
> Mobile communications ... right now writing from *Amsterdam* :-))
> ... mobile phone: +49 177 2160221 ... or mail me:  gert@greenie.muc.de