mgetty works fine , vgetty doesn't receive fax.

"Sami Peltomäki" (peltos@icenet.fi)
Wed, 3 Jun 1998 18:10:24 +0200


Hi,

I am using mgetty+sendfax 1.1.12.
When I use mgetty for receive FAX it works fine but 
vgetty can't receive FAX.  FAX sender and page is the same. 
Modem is V.90 & K56flex Rockwell modem. (CLASS 2)

Here is the vgetty.ttyS1 log-file.

06/03 18:10:30 yS1  vgetty: experimental test release 0.7.4 / 21Jan98
06/03 18:10:30 yS1  mgetty: experimental test release 1.1.12-Jan27
06/03 18:10:30 yS1  reading generic configuration from config file
/usr/local/etc/mgetty+sendfax/voice.conf
06/03 18:10:30 yS1  reading program vgetty configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/03 18:10:30 yS1  reading port ttyS1 configuration from config file
/usr/local/etc/mgetty+sendfax/voice.conf
06/03 18:10:30 yS1   reading /usr/local/etc/mgetty+sendfax/voice.conf...
. . .
06/03 18:10:30 yS1   do_makelock: lock='/var/lock/LCK..ttyS1'
06/03 18:10:30 yS1   lock made
06/03 18:10:31 yS1   tio_get_rs232_lines: status: RTS CTS DSR DTR
06/03 18:10:31 yS1  lowering DTR to reset Modem
06/03 18:10:31 yS1   tss: set speed to 38400 (017)
06/03 18:10:31 yS1   tio_set_flow_control( HARD )
06/03 18:10:31 yS1   waiting for line to clear (VTIME), read: 
06/03 18:10:32 yS1  send: \dATQ0V1H0[0d]
06/03 18:10:32 yS1  waiting for ``OK''
06/03 18:10:32 yS1   got: ATQ0V1H0[0d]
06/03 18:10:32 yS1    CND: ATQ0V1H0[0d][0a]OK ** found **
06/03 18:10:32 yS1  send: ATS0=0Q0&D3&C1[0d]
06/03 18:10:32 yS1  waiting for ``OK''
06/03 18:10:32 yS1   got: [0d]
06/03 18:10:32 yS1    CND: OK[0a]ATS0=0Q0&D3&C1[0d]
06/03 18:10:32 yS1    CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
06/03 18:10:32 yS1  mdm_send: 'AT+FCLASS=2'
06/03 18:10:32 yS1    got:[0d][0a]AT+FCLASS=2[0d]
06/03 18:10:32 yS1   mdm_command: string 'AT+FCLASS=2'
06/03 18:10:32 yS1    got:[0d][0a]OK[0d]
06/03 18:10:32 yS1   mdm_command: string 'OK' -> OK
06/03 18:10:32 yS1  mdm_send: 'AT+FCLASS=0'
06/03 18:10:32 yS1    got:[0a]AT+FCLASS=0[0d]
06/03 18:10:32 yS1   mdm_command: string 'AT+FCLASS=0'
06/03 18:10:32 yS1    got:[0d][0a]OK[0d]
06/03 18:10:32 yS1   mdm_command: string 'OK' -> OK
06/03 18:10:32 yS1  mdm_send: 'AT+FAA=1;+FCR=1'
06/03 18:10:32 yS1    got:[0a]AT+FAA=1;+FCR=1[0d]
06/03 18:10:32 yS1   mdm_command: string 'AT+FAA=1;+FCR=1'
06/03 18:10:32 yS1    got:[0d][0a]OK[0d]
06/03 18:10:32 yS1   mdm_command: string 'OK' -> OK
06/03 18:10:33 yS1  mdm_send: 'AT+FBOR=0'
06/03 18:10:33 yS1    got:[0a]AT+FBOR=0[0d]
06/03 18:10:33 yS1   mdm_command: string 'AT+FBOR=0'
06/03 18:10:33 yS1    got:[0d][0a]OK[0d]
06/03 18:10:33 yS1   mdm_command: string 'OK' -> OK
06/03 18:10:33 yS1  mdm_send: 'AT+FLID="xxx x xxx xx xxx"'
06/03 18:10:33 yS1    got:[0a]AT+FLID="xxx x xxx xx xxx"[0d]
06/03 18:10:33 yS1   mdm_command: string 'AT+FLID="xxx x xxx xx xxx"'
06/03 18:10:33 yS1    got:[0d][0a]OK[0d]
06/03 18:10:33 yS1   mdm_command: string 'OK' -> OK
06/03 18:10:33 yS1  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
06/03 18:10:33 yS1    got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
06/03 18:10:33 yS1   mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
06/03 18:10:33 yS1    got:[0d][0a]OK[0d]
06/03 18:10:33 yS1   mdm_command: string 'OK' -> OK
06/03 18:10:33 yS1   tss: set speed to 38400 (017)
06/03 18:10:33 yS1   tio_set_flow_control( HARD )
06/03 18:10:33 yS1  detecting voice modem type
06/03 18:10:33 yS1    vgetty: ATE1
06/03 18:10:33 yS1   voice command: '' -> 'OK|ATE1'
06/03 18:10:33 yS1    serial port: ATE1
06/03 18:10:33 yS1    serial port: OK
06/03 18:10:33 yS1   voice command: 'ATI' -> ''
06/03 18:10:33 yS1    vgetty: ATI
06/03 18:10:33 yS1    serial port: ATI
06/03 18:10:33 yS1    serial port: 56000
06/03 18:10:33 yS1    Rockwell: OK
06/03 18:10:33 yS1  Rockwell detected
06/03 18:10:33 yS1    vgetty: queued event RESET_WATCHDOG at position
0000
06/03 18:10:33 yS1  initializing ROCKWELL voice modem
06/03 18:10:33 yS1   voice command: 'AT#CLS?' -> ''
06/03 18:10:33 yS1    vgetty: AT#CLS?
06/03 18:10:33 yS1    Rockwell: AT#CLS?
06/03 18:10:33 yS1    Rockwell: 0
06/03 18:10:33 yS1   voice command: '' -> 'OK'
06/03 18:10:33 yS1    Rockwell: OK
06/03 18:10:33 yS1   voice command: 'AT#CLS=8' -> 'OK'
06/03 18:10:34 yS1    vgetty: AT#CLS=8
06/03 18:10:34 yS1    Rockwell: AT#CLS=8
06/03 18:10:34 yS1    Rockwell: OK
06/03 18:10:34 yS1   voice command: 'AT#VSP=70' -> 'OK'
06/03 18:10:34 yS1    vgetty: AT#VSP=70
06/03 18:10:34 yS1    Rockwell: <DLE> <h>
06/03 18:10:34 yS1    vgetty: queued event HANDSET_ON_HOOK at position
0001
06/03 18:10:34 yS1    Rockwell: 
06/03 18:10:34 yS1    Rockwell: AT#VSP=70
06/03 18:10:34 yS1    Rockwell: OK
06/03 18:10:34 yS1   voice command: 'AT#VSD=0' -> 'OK'
06/03 18:10:35 yS1    vgetty: AT#VSD=0
06/03 18:10:35 yS1    Rockwell: AT#VSD=0
06/03 18:10:35 yS1    Rockwell: OK
06/03 18:10:35 yS1   voice command: 'AT#VTD=3F,3F,3F' -> 'OK'
06/03 18:10:35 yS1    vgetty: AT#VTD=3F,3F,3F
06/03 18:10:35 yS1    Rockwell: AT#VTD=3F,3F,3F
06/03 18:10:35 yS1    Rockwell: OK
06/03 18:10:35 yS1   voice command: 'AT#VSS=1' -> 'OK'
06/03 18:10:35 yS1    vgetty: AT#VSS=1
06/03 18:10:35 yS1    Rockwell: AT#VSS=1
06/03 18:10:35 yS1    Rockwell: OK
06/03 18:10:35 yS1   voice command: 'AT#CLS=0' -> 'OK'
06/03 18:10:35 yS1    vgetty: AT#CLS=0
06/03 18:10:35 yS1    Rockwell: AT#CLS=0
06/03 18:10:36 yS1    Rockwell: OK
06/03 18:10:36 yS1   voice command: 'AT&K3' -> 'OK'
06/03 18:10:36 yS1    vgetty: AT&K3
06/03 18:10:36 yS1    Rockwell: AT&K3
06/03 18:10:36 yS1    Rockwell: OK
06/03 18:10:36 yS1   tio_set_flow_control( HARD )
06/03 18:10:36 yS1   waiting for line to clear (VTIME), read: 
06/03 18:10:36 yS1   removing lock file
06/03 18:10:36 yS1  waiting...
06/03 18:12:32 yS1    select returned 1
06/03 18:12:32 yS1   checking lockfiles, locking the line
06/03 18:12:32 yS1   makelock(ttyS1) called
06/03 18:12:32 yS1   do_makelock: lock='/var/lock/LCK..ttyS1'
06/03 18:12:32 yS1   lock made
06/03 18:12:32 yS1    vgetty: number of rings (2) was set directly
06/03 18:12:32 yS1  waiting for ``RING_''
06/03 18:12:32 yS1   got: [0d]
06/03 18:12:32 yS1    CND: OK[0a]RING3
06/03 18:12:32 yS1  found action string: ``RING3''
06/03 18:12:32 yS1  waiting for ``RING_''
06/03 18:12:32 yS1   got: [0d]
06/03 18:12:32 yS1    CND: RING3[0a][0d][0a]RING3
06/03 18:12:37 yS1  found action string: ``RING3''
06/03 18:12:37 yS1  reading ring_type ring3 configuration from config
file /usr/local/etc/mgetty+sendfax/voice.conf
06/03 18:12:37 yS1   reading /usr/local/etc/mgetty+sendfax/voice.conf...
. . .
06/03 18:12:37 yS1    vgetty: reading answer mode file /etc/answer/ring3
06/03 18:12:37 yS1    vgetty: answer mode is [fax]
06/03 18:12:37 yS1    vgetty: trying fax connection
06/03 18:12:37 yS1   voice command: 'AT#CLS=2' -> 'OK'
06/03 18:12:37 yS1    vgetty: AT#CLS=2
06/03 18:12:37 yS1    Rockwell: AT#CLS=2
06/03 18:12:38 yS1    Rockwell: OK
06/03 18:12:38 yS1   voice command: 'AT+FAA=0' -> 'OK'
06/03 18:12:38 yS1    vgetty: AT+FAA=0
06/03 18:12:38 yS1    Rockwell: AT+FAA=0
06/03 18:12:38 yS1    Rockwell: OK
06/03 18:12:38 yS1  mdm_send: 'AT+FBOR=0'
06/03 18:12:38 yS1    got:[0a]AT+FBOR=0[0d]
06/03 18:12:38 yS1   mdm_command: string 'AT+FBOR=0'
06/03 18:12:38 yS1    got:[0d][0a]OK[0d]
06/03 18:12:38 yS1   mdm_command: string 'OK' -> OK
06/03 18:12:38 yS1   vgetty: Restoring signal handlers
06/03 18:12:38 yS1   vgetty: No signal handlers were installed
06/03 18:12:38 yS1  send: ATA[0d]
06/03 18:12:38 yS1  waiting for ``CONNECT''
06/03 18:12:38 yS1   got: ATA[0d]
06/03 18:12:38 yS1    CND: RING3ATA[0d][0a]+FCON
06/03 18:12:47 yS1  found action string: ``+FCON''
06/03 18:12:47 yS1  start fax receiver...
06/03 18:12:47 yS1   fax receiver: entry
06/03 18:12:47 yS1   tss: set speed to 19200 (016)
06/03 18:12:47 yS1  fax_wait_for(OK)
06/03 18:12:47 yS1    got:[0a][0d][0a][0d][0a]+FHNG: 0[0d]
06/03 18:12:48 yS1   fax_wait_for: string '+FHNG: 0'
06/03 18:12:48 yS1  connection hangup: '+FHNG: 0'
06/03 18:12:48 yS1   (Normal and proper end of connection)
06/03 18:12:48 yS1    got:[0a][0d][0a]OK[0d]
06/03 18:12:48 yS1   fax_wait_for: string 'OK'** found **
06/03 18:12:48 yS1    looking for utmp entry... (my PID: 4564)
06/03 18:12:48 yS1   tio_set_flow_control( HARD XON_IN )
06/03 18:12:48 yS1  fax_get_pages: no pages to receive
06/03 18:12:48 yS1   fax receiver: hangup & end
06/03 18:12:48 yS1   fax_notify_mail: sending mail to: faxadmin
06/03 18:12:49 yS1   notify: '/usr/local/lib/mgetty+sendfax/new_fax 0 ''
0  >/dev/console 2>&1 </dev/null'
06/03 18:12:49 ##### fax dev=ttyS1, pid=4564, caller='none', name='',
id='', +FHNG=000, pages=0, time=00:00:11


This is mgetty.ttyS1 (same sender and page)

06/03 18:16:55 yS1  mgetty: experimental test release 1.1.12-Jan27
06/03 18:16:55 yS1  check for lockfiles
06/03 18:16:55 yS1   checklock: stat failed, no file
06/03 18:16:55 yS1  locking the line
06/03 18:16:55 yS1   makelock(ttyS1) called
06/03 18:16:55 yS1   do_makelock: lock='/var/lock/LCK..ttyS1'
06/03 18:16:55 yS1   lock made
06/03 18:16:55 yS1   tio_get_rs232_lines: status: RTS CTS DSR DTR
06/03 18:16:55 yS1  lowering DTR to reset Modem
06/03 18:16:56 yS1   tss: set speed to 38400 (017)
06/03 18:16:56 yS1   tio_set_flow_control( HARD )
06/03 18:16:56 yS1   waiting for line to clear (VTIME), read: 
06/03 18:16:56 yS1  send: \dATQ0V1H0[0d]
06/03 18:16:57 yS1  waiting for ``OK''
06/03 18:16:57 yS1   got: ATQ0V1H0[0d]
06/03 18:16:57 yS1    CND: ATQ0V1H0[0d][0a]OK ** found **
06/03 18:16:57 yS1  send: ATS0=0Q0&D3&C1[0d]
06/03 18:16:57 yS1  waiting for ``OK''
06/03 18:16:57 yS1   got: [0d]
06/03 18:16:57 yS1    CND: OK[0a]ATS0=0Q0&D3&C1[0d]
06/03 18:16:57 yS1    CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
06/03 18:16:57 yS1  mdm_send: 'AT+FCLASS=2'
06/03 18:16:57 yS1    got:[0d][0a]AT+FCLASS=2[0d]
06/03 18:16:57 yS1   mdm_command: string 'AT+FCLASS=2'
06/03 18:16:57 yS1    got:[0d][0a]OK[0d]
06/03 18:16:57 yS1   mdm_command: string 'OK' -> OK
06/03 18:16:57 yS1  mdm_send: 'AT+FAA=0;+FCR=1'
06/03 18:16:57 yS1    got:[0a]AT+FAA=0;+FCR=1[0d]
06/03 18:16:57 yS1   mdm_command: string 'AT+FAA=0;+FCR=1'
06/03 18:16:57 yS1    got:[0d][0a]OK[0d]
06/03 18:16:57 yS1   mdm_command: string 'OK' -> OK
06/03 18:16:57 yS1  mdm_send: 'AT+FBOR=0'
06/03 18:16:57 yS1    got:[0a]AT+FBOR=0[0d]
06/03 18:16:57 yS1   mdm_command: string 'AT+FBOR=0'
06/03 18:16:57 yS1    got:[0d][0a]OK[0d]
06/03 18:16:57 yS1   mdm_command: string 'OK' -> OK
06/03 18:16:57 yS1  mdm_send: 'AT+FLID="xxx x xxx xx xxx"'
06/03 18:16:57 yS1    got:[0a]AT+FLID="xxx x xxx xx xxx"[0d]
06/03 18:16:57 yS1   mdm_command: string 'AT+FLID="xxx x xxx xx xxx"'
06/03 18:16:57 yS1    got:[0d][0a]OK[0d]
06/03 18:16:57 yS1   mdm_command: string 'OK' -> OK
06/03 18:16:57 yS1  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
06/03 18:16:57 yS1    got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
06/03 18:16:57 yS1   mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
06/03 18:16:57 yS1    got:[0d][0a]OK[0d]
06/03 18:16:57 yS1   mdm_command: string 'OK' -> OK
06/03 18:16:57 yS1   waiting for line to clear (VTIME), read: 
06/03 18:16:57 yS1   removing lock file
06/03 18:16:57 yS1  waiting...
06/03 18:26:56 yS1    select returned 1
06/03 18:26:56 yS1   checking lockfiles, locking the line
06/03 18:26:56 yS1   makelock(ttyS1) called
06/03 18:26:56 yS1   do_makelock: lock='/var/lock/LCK..ttyS1'
06/03 18:26:56 yS1   lock made
06/03 18:26:56 yS1  waiting for ``RING''
06/03 18:26:56 yS1   got: [0d]
06/03 18:26:56 yS1    CND: OK[0a]RING ** found **
06/03 18:26:56 yS1  waiting for ``RING''
06/03 18:26:56 yS1   got: 2[0d]
06/03 18:26:56 yS1    CND: RING2[0a][0d][0a]RING ** found **
06/03 18:27:01 yS1  send: ATA[0d]
06/03 18:27:01 yS1  waiting for ``CONNECT''
06/03 18:27:01 yS1   got: 3[0d]
06/03 18:27:01 yS1    CND: RING3[0a]ATA[0d]
06/03 18:27:01 yS1    CND: ATA[0d][0a]+FCON
06/03 18:27:10 yS1  found action string: ``+FCON''
06/03 18:27:10 yS1  start fax receiver...
06/03 18:27:10 yS1   fax receiver: entry
06/03 18:27:10 yS1   tss: set speed to 19200 (016)
06/03 18:27:10 yS1  fax_wait_for(OK)
06/03 18:27:10 yS1    got:[0a][0d][0a][0d][0a]+FTSI: "                  
-"[0d]
06/03 18:27:12 yS1   fax_wait_for: string '+FTSI: "                  
-"'
06/03 18:27:12 yS1  fax_id: '+FTSI: "                   -"'
06/03 18:27:12 yS1    got:[0a][0d][0a]+FDCS: 1,3,0,2,0,0,0,0[0d]
06/03 18:27:12 yS1   fax_wait_for: string '+FDCS: 1,3,0,2,0,0,0,0'
06/03 18:27:12 yS1  transmission par.: '+FDCS: 1,3,0,2,0,0,0,0'
06/03 18:27:12 yS1    got:[0a][0d][0a]OK[0d]
06/03 18:27:12 yS1   fax_wait_for: string 'OK'** found **
06/03 18:27:12 yS1    looking for utmp entry... (my PID: 4623)
06/03 18:27:12 yS1   tio_set_flow_control( HARD XON_IN )
06/03 18:27:12 yS1  fax_send: 'AT+FDR'
06/03 18:27:12 yS1  fax_wait_for(CONNECT)
06/03 18:27:12 yS1    got:[0a]AT+FDR[0d]
06/03 18:27:12 yS1   fax_wait_for: string 'AT+FDR'
06/03 18:27:12 yS1    got:[0d][0a]+FCFR[0d]
06/03 18:27:14 yS1   fax_wait_for: string '+FCFR'
06/03 18:27:14 yS1    got:[0a][0d][0a]+FDCS: 1,3,0,2,0,0,0,0[0d]
06/03 18:27:17 yS1   fax_wait_for: string '+FDCS: 1,3,0,2,0,0,0,0'
06/03 18:27:17 yS1  transmission par.: '+FDCS: 1,3,0,2,0,0,0,0'
06/03 18:27:17 yS1    got:[0a][0d][0a]CONNECT[0d]
06/03 18:27:17 yS1   fax_wait_for: string 'CONNECT'** found **
06/03 18:27:17 yS1   sending DC2
06/03 18:27:17 yS1   fax_get_page_data: wait for EOL, got:
[0a][ff][ff][ff][ff][ff][ff][00]
06/03 18:27:17 yS1  fax_get_page_data: receiving
/var/spool/fax/incoming/ff5756b45S1-_--_.01...
06/03 18:27:37 yS1  fax_get_page_data: page end, bytes received: 24227
06/03 18:27:37 yS1  fax_wait_for(OK)
06/03 18:27:37 yS1    got:[0d][0a]+FPTS: 1,2219,0,0[0d]
06/03 18:27:37 yS1   fax_wait_for: string '+FPTS: 1,2219,0,0'
06/03 18:27:37 yS1  page status: +FPTS: 1,2219,0,0
06/03 18:27:37 yS1   2219 lines received, 0 lines bad, 0 bytes lost
06/03 18:27:37 yS1    got:[0a][0d][0a]+FET: 2[0d]
06/03 18:27:39 yS1   fax_wait_for: string '+FET: 2'
06/03 18:27:39 yS1    got:[0a][0d][0a]OK[0d]
06/03 18:27:39 yS1   fax_wait_for: string 'OK'** found **
06/03 18:27:39 yS1  fax_send: 'AT+FDR'
06/03 18:27:39 yS1  fax_wait_for(CONNECT)
06/03 18:27:39 yS1    got:[0a]AT+FDR[0d]
06/03 18:27:39 yS1   fax_wait_for: string 'AT+FDR'
06/03 18:27:39 yS1    got:[0d][0a]+FHNG: 0[0d]
06/03 18:27:42 yS1   fax_wait_for: string '+FHNG: 0'
06/03 18:27:42 yS1  connection hangup: '+FHNG: 0'
06/03 18:27:42 yS1   (Normal and proper end of connection)** found **
06/03 18:27:42 yS1   fax receiver: hangup & end
06/03 18:27:42 yS1   fax_notify_mail: sending mail to: faxadmin
06/03 18:27:42 yS1   notify: '/usr/local/lib/mgetty+sendfax/new_fax 0
'_                   -_' 1  /var/spool/fax/incoming/ff5756b45S1-_--_.01
>/dev/console 2>&1 </dev/null'
06/03 18:27:42 ##### fax dev=ttyS1, pid=4623, caller='none', name='',
id='_                   -_', +FHNG=000, pages=1, time=00:00:41

-Sami
-- 
 -========================- -============================-
| Sami Peltomäki           |  Phone: +358-(0)2-538 73 711 |
| Vainiolantie 7 A 4       |    BBS: +358-(0)2-538 73 712 |
| FIN-28400 ULVILA         |    Fax: +358-(0)2-538 73 713 |
| Finland                  | Mobile: +358-(0)400-722 560  |
 -=========================-=============================-
|             http://www.icenet.fi/~peltos      \|/       |
|                mailto:peltos@icenet.fi       (* *)      |
 -==========================================ooO-(_)-Ooo==-
   U s e r s   n e v e r   r e a d   m a n u a l s . . .