Rockwell problems.

Marc Eberhard (marc@athene.thphy.uni-duesseldorf.de)
Thu, 12 Feb 1998 07:49:49 +0100


-----BEGIN PGP SIGNED MESSAGE-----

Hi!

Jeremy Hansen wrote:
> Ok, I have a Zoom 2800 PnP modem, which uses Rockwell
> chipset. I'm having problem playing the greeting. I
> took a 7200hz wav file and converted using wavtopvf and
> then with pvftormd Rockwell 4 etc. Here's the output from
> one attempt.

Let's see... initialization and ring detection works fine...

> 02/04 21:12:06 yS1 voice command: 'ATA' -> 'VCON'
> 02/04 21:12:06 yS1 vgetty: ATA
> 02/04 21:12:06 yS1 Rockwell: <DLE> <h>
> 02/04 21:12:06 yS1 vgetty: queued event HANDSET_ON_HOOK at position 0002
> 02/04 21:12:06 yS1 Rockwell: 
> 02/04 21:12:06 yS1 Rockwell: VCON

Ok, we picked up the phone.

> 02/04 21:12:06 yS1 playing voice file /var/spool/voice/messages/welcome.rmd
> 02/04 21:12:06 yS1 vgetty: raw modem data header found
> 02/04 21:12:06 yS1 vgetty: modem type Rockwell found
> 02/04 21:12:06 yS1 vgetty: compression method 0x0004, speed 7200, bits 4
> 02/04 21:12:06 yS1 vgetty: queued event RESET_WATCHDOG at position 0003
> 02/04 21:12:06 yS1 voice command: 'AT#VBS=4' -> 'OK'
> 02/04 21:12:06 yS1 vgetty: AT#VBS=4
> 02/04 21:12:06 yS1 Rockwell: OK

This also worked fine.

> 02/04 21:12:07 yS1 vgetty: unqueued event RESET_WATCHDOG at position 0000
> 02/04 21:12:07 yS1 vgetty: voice_handle_event got event RESET_WATCHDOG with data <
> 02/04 21:12:07 yS1 vgetty: unqueued event HANDSET_ON_HOOK at position 0001
> 02/04 21:12:07 yS1 vgetty: voice_handle_event got event HANDSET_ON_HOOK with data <
> 02/04 21:12:07 yS1 vgetty: unqueued event HANDSET_ON_HOOK at position 0002
> 02/04 21:12:07 yS1 vgetty: voice_handle_event got event HANDSET_ON_HOOK with data <
> 02/04 21:12:07 yS1 vgetty: unqueued event RESET_WATCHDOG at position 0003
> 02/04 21:12:07 yS1 vgetty: voice_handle_event got event RESET_WATCHDOG with data <o>
> 02/04 21:12:15 yS1 vgetty: unexpected byte _ from voice modem
> 02/04 21:12:15 yS1 vgetty: unexpected byte _ from voice modem
> 02/04 21:12:15 yS1 vgetty: unexpected byte O from voice modem
> 02/04 21:12:15 yS1 vgetty: unexpected byte K from voice modem

Huh, what's this? It seems the modem likes to send OK or ERROR
sequences while in playback modem. This is strange... it is not
supposed to send anything else then <DLE> shielded event codes... but
vgetty just ignores them, probably the best to do.

[...]

... but it works. At least there are no problems that are visible in
the logfile.

> 02/04 21:12:51 yS1 voice command: 'AT#VTS=[933,0,15]' -> ''
> 02/04 21:12:51 yS1 vgetty: AT#VTS=[933,0,15]
> 02/04 21:12:52 yS1 voice command: '' -> 'OK'
> 02/04 21:12:52 yS1 Rockwell: OK

Sending the beep.

> 02/04 21:12:53 yS1 recording voice file /var/spool/voice/incoming/va03240.rmd
> 02/04 21:12:53 yS1 vgetty: queued event RESET_WATCHDOG at position 0007
> 02/04 21:12:53 yS1 voice command: 'AT#VBS=2' -> 'OK'
> 02/04 21:12:53 yS1 vgetty: AT#VBS=2
> 02/04 21:12:53 yS1 Rockwell: OK
> 02/04 21:12:53 yS1 vgetty: queued event RESET_WATCHDOG at position 0008
> 02/04 21:12:53 yS1 vgetty: unqueued event RESET_WATCHDOG at position 0005
> 02/04 21:12:53 yS1 vgetty: voice_handle_event got event RESET_WATCHDOG with data <_>
> 02/04 21:12:53 yS1 vgetty: unqueued event RESET_WATCHDOG at position 0006
> 02/04 21:12:53 yS1 vgetty: voice_handle_event got event RESET_WATCHDOG with data <_>
> 02/04 21:12:53 yS1 vgetty: unqueued event RESET_WATCHDOG at position 0007
> 02/04 21:12:53 yS1 vgetty: voice_handle_event got event RESET_WATCHDOG with data <d>
> 02/04 21:12:53 yS1 vgetty: unqueued event RESET_WATCHDOG at position 0008
> 02/04 21:12:53 yS1 vgetty: voice_handle_event got event RESET_WATCHDOG with data <o>
> 02/04 21:12:53 yS1 voice command: 'AT&K3' -> 'OK'
> 02/04 21:12:54 yS1 vgetty: AT&K3
> 02/04 21:12:54 yS1 Rockwell: OK
> 02/04 21:12:54 yS1 tio_set_flow_control( HARD XON_IN )
> 02/04 21:12:54 yS1 voice command: 'AT#VRX' -> 'CONNECT'
> 02/04 21:12:54 yS1 vgetty: AT#VRX
> 02/04 21:12:54 yS1 Rockwell: CONNECT
> 02/04 21:13:01 yS1 Rockwell: <DLE> <s>
> 02/04 21:13:01 yS1 vgetty: queued event NO_VOICE_ENERGY at position 0009
> 02/04 21:13:01 yS1 vgetty: unqueued event NO_VOICE_ENERGY at position 0009
> 02/04 21:13:01 yS1 vgetty: voice_handle_event got event NO_VOICE_ENERGY with data <_>
> 02/04 21:13:01 yS1 vgetty: <STOP RECORDING>
> 02/04 21:13:01 yS1 Rockwell: <VOICE DATA 12959 bytes>
> 02/04 21:13:01 yS1 Rockwell: <DLE> <ETX>

Recording also works. The modem reports no voice energy after 7
seconds, which is the default silence timeout. So it tries a data/fax
connection.

> 02/04 21:13:01 yS1 voice command: '' -> 'OK|VCON'
> 02/04 21:13:01 yS1 Rockwell: VCON
> 02/04 21:13:01 yS1 vgetty: trying data connection
> 02/04 21:13:01 yS1 voice command: 'AT#CLS=0' -> 'OK'
> 02/04 21:13:01 yS1 vgetty: AT#CLS=0
> 02/04 21:13:01 yS1 Rockwell: OK
> 02/04 21:13:02 yS1 voice command: 'AT+FAA=0' -> 'OK'
> 02/04 21:13:02 yS1 vgetty: AT+FAA=0
> 02/04 21:13:02 yS1 Rockwell: OK
> 02/04 21:13:02 yS1 vgetty: Restoring signal handlers
> 02/04 21:13:02 yS1 send: ATA[0d]
> 02/04 21:13:02 yS1 waiting for ``CONNECT''
> 02/04 21:13:02 yS1 got: [0d][0a]NO CARRIER
> 02/04 21:13:48 yS1 found action string: ``NO CARRIER''
> 02/04 21:13:48 ##### failed A_FAIL dev=ttyS1, pid=3240, caller='none', conn='', name=''

Well, but there is no fax... next try...

> 02/04 21:13:49 yS1 send: \dATQ0V1H0[0d]
> 02/04 21:13:50 yS1 waiting for ``OK''
> 02/04 21:13:50 yS1 got: [0d][0a]OK ** found **
> 02/04 21:13:50 yS1 send: ATS0=0Q0&D3&C1[0d]
> 02/04 21:13:50 yS1 waiting for ``OK''
> 02/04 21:13:50 yS1 got: [0d]
> 02/04 21:13:50 yS1 CND: OK[0a]ATQ0V1H0[0d]
> 02/04 21:13:50 yS1 CND: ATQ0V1H0[0d][0a]OK ** found **

Huh? Here we are out of sync. It since the mgetty lib does not check
the command echo it seems to read the OK of the previous command...
(Gert: Now you know, why I used to check this)! The rest of the
initialization must fail...

Bye,
Marc
_______________________________________________________________________________
Email: Marc.Eberhard@Poseidon.ThPhy.Uni-Duesseldorf.DE
WWW: http://Poseidon.ThPhy.Uni-Duesseldorf.DE/~marc/
PGP: http://Poseidon.ThPhy.Uni-Duesseldorf.DE/~marc/Marc_PGP.key

-----BEGIN PGP SIGNATURE-----
Version: 2.6.3ia
Charset: noconv

iQCVAwUBNOKbijR1rYTfG0TdAQH36QQA01FklrlIFG2PeapQ6x8wRFBR2VnWt/fG
ReTPyorRiGZLlImLSLhl4oRNcAFRxzuxrCATNZDi3T1CXAn5vRRBxqc8iIJdSCmX
P2U9x9bRbsAbMHBf1IkhWpO6sOv+WvvM7QKRKIecTMzbyt9I4CXNIrufqtJiQhYU
Q3Bgz8JxEMg=
=UqyJ
-----END PGP SIGNATURE-----
.