Newsgroups: vmsnet.uucp
Subject: Re: VAX to Ultrix uucp connection problems
From: mark@totaltec.com
Date: 25 Aug 92 12:57:01 EST
Distribution: usa
Organization: Total Tec Systems, Inc.

In article <1992Aug20.112405.633@cmkrnl.com>, jeh@cmkrnl.com writes:
> In article <1992Aug19.162557.640@totaltec.com>, mark@totaltec.com writes:
>> I have a question for you. 
>> I have a VAX running VMS V5.5 and UUCP 2.0 and I am trying to get it to
>> communicate with a DECstation 5000 running Ultrix 4.2a. I set up the VAX
>> to poll the DECstation. It logs in ok and negotiates properly but then
>> I get error messages trying to open files on the VAX side?
>> 
>> It appears to get these message when the DECstation is trying to send 
>> something to the VAX. UUCP on the VAX doesn't even see the work files
>> for the DECstation on the VAX! Confused yet?
> 
> PLEASE, EVERYBODY:  WHEN POSTING A PROBLEM DESCRIPTION, INCLUDE:
> 
> 	- the version of DECUS uucp
> 	- the version of VMS
> 	- output from uucp.log showing the problem
> 
Sorry I got two out of three right. I didn't think to include the output
in it but here it is. The first set is after I tried to send mail to
myself from the VAX to the DECstation, the second set of output is when
I tried to send mail to myself from the DECstation to the VAX.
*********
This is after I did a mail send uucp%"chubaka!mark" on obi2
*********

p:uucp    h:-       [08/25-11:29:36-2040061e]  STARTUP         (Version cmkrnl-2.0 {Jun 19 1992 15:59:46}) u:MARK
p:uucp ?> h:chubaka [08/25-11:29:37-2040061e]   Outgoing       (_LTA20: 9600 bps VISTA2/PORT_15) u:MARK
p:uucp -> h:chubaka [08/25-11:29:38-2040061e]   Calling        (chubaka) u:MARK
p:uucp -> h:chubaka [08/25-11:29:38-2040061e]   Script log     (Null script OK) u:MARK
Script ended successfully
Changing to state get_local
Sending slowly: 
0x0D
Doing expects for state get_local

Received: 
tick 
Timed out (0 secs)
Changing to state get_local

Sending slowly: 
0x0D 
Doing expects for state get_local

Received: 
0x0D 
0x0A                            
V C P - 1 0 0 0   V E 3 . 2 7 1 
0x0D 
0x0A 
0x0D 
0x0A 
P l e a s e   t y p e   H E L P   i f  y o u   n e e d   a s s i s t a n c e 
0x0D 
0x0A 
0x0D 
0x0A 
L o c a l > 

Matched: 
> 
Changing to state got_local

Sending slowly: 
C O N N E C T   

Sending slowly: 
c h u b a k a 

Sending slowly: 
0x0D 
Fell through to state abd_nospw

Sending slowly: 
0x0D 
Doing expects for state abd_nospw

Received: 
  
C O N N E C T   c h u b a k a 
0x0D 
0x0D 
0x0A 
L o c a l - 0 1 0 -   S e s s i o n   1   t o  C H U B A K A   e s t a b l i s h e d 
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
U L T R I X   V 4 . 2 A   ( R e v .   4 4 )   ( c h u b a k a ) 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0D 
0x0A 
0x0D 
l o g i n 
Matched: 
o g i n 
Changing to state gotunp

Sending slowly: 
u u _ o b i 2 

Sending slowly: 
0x0D 
Doing expects for state gotunp

Received: 
: 
  
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
U L T R I X   V 4 . 2 A   ( R e v .   4 4 )   ( c h u b a k a ) 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0D 
0x0A 
0x0D 
l o g i n :   u u _ o b i 2 
0x0D 
0x0A 
P a s s w o r d 

Matched: 
a s s w o r d 
Changing to state gotpas

Sending slowly: 
g o m e u x 

Sending slowly: 
0x0D 
p:uucp => h:chubaka [08/25-11:29:45-2040061e]   Script log     (Unix login ok) u:MARK    
Script ended successfully
Doing expects for state expstring

Received: 
: 
0x0D 
0x0A 
tick 
tick 
0x10 
S h e r e 

Matched: 
0x10 S h e r e 
Changing to state gotit
Script ended successfully

Reading null-term str (getstring), rcvd: 
0x00 


Sending: 
0x10 S o b i 2   - Q 0   - V M S 0x00 
Expecting <DLE> (getstring):

Reading null-term str (getstring), rcvd: 
0x10 
R 
O 
K 
0x00 

Expecting <DLE> (getstring):

Reading null-term str (getstring), rcvd: 
0x10 
P 
g 
f 
0x00 


Sending: 
0x10 U g 0x00 
QIO data/req: 54/36 rd: 281/14 rah: 10/17
11:29:48.26       rcv_ctl() xwmax set to 3
11:29:48.34       rcv_ctl() xbmax set to 64
11:29:48.42       rcv_ctl() xwmax set to 3
p:uucp => h:chubaka [08/25-11:29:48-2040061e]   Startup ok     (Protocol: (g, xmt(3/64), rcv(7/64))) u:MARK    
g Protocol Shutdown:
	Data Packets rcvd        2
	ACKs sent                1
	Data Packets sent        2
	ACKs rcvd                2
	Other ctl pkts rcvd      4
	Other ctl pkts sent      4
	Ignored 4.3BSD NULs      2
QIO data/req: 170/7 rd: 180/12 rah: 0/2

Sending: 
0x10 O O O O O O O 0x00 
Doing expects for state expstring

Received: 
0x10 
0x09 
`" 
`* 
0x08 
0x09 
0x10 
O 
O 
O 
O 
O 
O 

Matched: 
O O O O O O 
Changing to state gotit
Script ended successfully

Sending: 
0x10 O O O O O O O 0x00 
p:uucp >> h:chubaka [08/25-11:29:49-2040061e]   End of call    (complete : 11 secs) u:MARK    
p:uucp >> h:chubaka [08/25-11:29:50-2040061e]   Dropping DTR   () u:MARK    
Fell through to state wakemodem

Sending slowly: 
0x0D 
Doing expects for state wakemodem

Received: 
0x00 
0x10 
O 
O 
O 
O 
O 
O 
0x00 
0x0D 
0x0A 
L o c a l > 

Matched: 
> 
Changing to state local

Sending slowly: 
l o g o u t 

Sending slowly: 
0x0D 
Fell through to state done
Script ended successfully
p:uucp ?> h:-       [08/25-11:30:12-2040061e]  Exiting         (Version cmkrnl-2.0) u:-       

*********
This is a directory of UUCP_SPOOL
*********
Directory $1$DUA2:[UUCP.SPOOL]

B.OBI20W9;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
B.OBI20WA;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
B.OBI20WB;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
B.OBI20WC;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
B.OBI20WD;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
B.OBI20WI;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
C.CHUBAKA_A0W9;1     [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
C.CHUBAKA_A0WA;1     [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
C.CHUBAKA_A0WB;1     [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
C.CHUBAKA_A0WC;1     [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
C.CHUBAKA_A0WD;1     [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
C.CHUBAKA_A0WI;1     [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
CHUBAKA.DIR;1        [UUCP,UUCP_LOGIN]     (RWE,RWE,RE,)
D.OBI20W9;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
D.OBI20WA;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
D.OBI20WB;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
D.OBI20WC;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
D.OBI20WD;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)
D.OBI20WI;1          [UUCP,UUCP_LOGIN]     (RWED,RWED,RWED,)

Total of 19 files.

*********
This is after I do a mail send mark@obi2.UUCP on the Ultrix system.
**********
p:uucp    h:-       [08/25-11:31:21-2040061e]  STARTUP         (Version cmkrnl-2.0 {Jun 19 1992 15:59:46}) u:MARK    
p:uucp ?> h:chubaka [08/25-11:31:22-2040061e]   Outgoing       (_LTA20: 9600 bps VISTA2/PORT_15) u:MARK    
p:uucp -> h:chubaka [08/25-11:31:22-2040061e]   Calling        (chubaka) u:MARK    
p:uucp -> h:chubaka [08/25-11:31:22-2040061e]   Script log     (Null script OK) u:MARK    
Script ended successfully
Changing to state get_local
Sending slowly: 
0x0D 
Doing expects for state get_local

Received: 
tick 
Timed out (0 secs)
Changing to state get_local

Sending slowly: 
0x0D 
Doing expects for state get_local

Received: 
0x0D 
0x0A 
V C P - 1 0 0 0   V E 3 . 2 7 1 
0x0D 
0x0A 
0x0D 
0x0A 
P l e a s e   t y p e   H E L P   i f   y o u   n e e d   a s s i s t a n c e 
0x0D 
0x0A 
0x0D 
0x0A 
L o c a l > 

Matched: 
> 
Changing to state got_local

Sending slowly: 
C O N N E C T   

Sending slowly: 
c h u b a k a 

Sending slowly: 
0x0D 
Fell through to state abd_nospw

Sending slowly: 
0x0D 
Doing expects for state abd_nospw

Received: 
  
C O N N E C T   c h u b a k a 
0x0D 
0x0D 
0x0A 
L o c a l   - 0 1 0 -   S e s s i o n   1   t o   C H U B A K A   e s t a b l i s h e d 
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
U L T R I X   V 4 . 2 A   ( R e v .   4 4 )   ( c h u b a k a ) 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0D 
0x0A 
0x0D 
l o g i n 

Matched: 
o g i n 
Changing to state gotunp

Sending slowly: 
u u _ o b i 2 

Sending slowly: 
0x0D 
Doing expects for state gotunp

Received: 
: 
  
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0A 
U L T R I X   V 4 . 2 A   ( R e v .   4 4 )   ( c h u b a k a ) 
0x0D 
0x0D 
0x0A 
0x0D 
0x0D 
0x0D 
0x0A 
0x0D 
l o g i n :   u u _ o b i 2 
0x0D 
0x0A 
P a s s w o r d 

Matched: 
a s s w o r d 
Changing to state gotpas

Sending slowly: 
g o m e u x 

Sending slowly: 
0x0D 
p:uucp => h:chubaka [08/25-11:31:30-2040061e]   Script log     (Unix login ok) u:MARK    
Script ended successfully
Doing expects for state expstring

Received: 
: 
0x0D 
0x0A 
0x10 
S h e r e 

Matched: 
0x10 S h e r e 
Changing to state gotit
Script ended successfully

Reading null-term str (getstring), rcvd: 
0x00 


Sending: 
0x10 S o b i 2   - Q 0   - V M S 0x00 
Expecting <DLE> (getstring):

Reading null-term str (getstring), rcvd: 
0x10 
R 
O 
K 
0x00 

Expecting <DLE> (getstring):

Reading null-term str (getstring), rcvd: 
0x10 
P 
g 
f 
0x00 


Sending: 
0x10 U g 0x00 
QIO data/req: 54/36 rd: 281/14 rah: 10/15
11:31:30.63       rcv_ctl() xwmax set to 3
11:31:30.71       rcv_ctl() xbmax set to 64
11:31:30.79       rcv_ctl() xwmax set to 3
p:uucp => h:chubaka [08/25-11:31:30-2040061e]   Startup ok     (Protocol: (g, xmt(3/64), rcv(7/64))) u:MARK    
p:uucp >> h:chubaka [08/25-11:31:31-2040061e]   They want to send (S D.chubakab001K D.chubakab001K daemon - D.chubakab001K 0666) u:daemon  
Invalid Destination Filename UUCP_DISK:[UUCP.SPOOL.chubaka]D.CHUBAKAB001_K
p:uucp >> h:chubaka [08/25-11:31:31-2040061e] * Can't open Destination file (SN2) u:daemon  
p:uucp >> h:chubaka [08/25-11:31:33-2040061e]   They want to send (S D.chubakaX001I X.chubakaX001I daemon - D.chubakaX001I 0666) u:daemon  
file_close errno=1
p:uucp >> h:chubaka [08/25-11:31:33-2040061e] * File rcv failed (see debug log) u:daemon  
g Protocol Shutdown:
	Data Packets rcvd        8
	ACKs sent                8
	Data Packets sent        5
	ACKs rcvd                5
	Other ctl pkts rcvd      4
	Other ctl pkts sent      4
	Ignored 4.3BSD NULs      8
QIO data/req: 422/17 rd: 630/33 rah: 0/8

Sending: 
0x10 O O O O O O O 0x00 
Doing expects for state expstring

Received: 
0x10 
0x09 
`" 
`* 
0x08 
0x09 
0x10 
O 
O 
O 
O 
O 
O 

Matched: 
O O O O O O 
Changing to state gotit
Script ended successfully

Sending: 
0x10 O O O O O O O 0x00 
p:uucp >> h:chubaka [08/25-11:31:35-2040061e]   End of call    (complete : 13 secs) u:daemon  
p:uucp >> h:chubaka [08/25-11:31:35-2040061e]   Dropping DTR   () u:daemon  
Fell through to state wakemodem

Sending slowly: 
0x0D 
Doing expects for state wakemodem

Received: 
0x00 
0x10 
O 
O 
O 
O 
O 
O 
0x00 
0x10 
O 
O 
O 
O 
0x0D 
0x0A 
L o c a l > 
Matched: 
> 
Changing to state local

Sending slowly: 
l o g o u t 

Sending slowly: 
0x0D 
Fell through to state done
Script ended successfully
p:uucp ?> h:-       [08/25-11:31:57-2040061e]  Exiting         (Version cmkrnl-2.0) u:-       

> If possible, try to reproduce the problem with relevant debug bits enabled,
> and include the output from the debug log.  For this problem, a debug mask
> of 0177 (octal) would be helpful.  
> 
> 	--- Jamie Hanrahan, Kernel Mode Consulting, San Diego CA
> uucp 'g' protocol guru and release coordinator, VMSnet (DECUS uucp) W.G., and 
> Chair, VMS Programming and Internals Working Group, U.S. DECUS VAX Systems SIG 
> Internet:  jeh@cmkrnl.com, hanrahan@eisner.decus.org, or jeh@crash.cts.com
> Uucp:  ...{crash,eisner,uunet}!cmkrnl!jeh
Newsgroups: vmsnet.uucp