Conference lassie::ucx

5386.0. "LPD 4.1 eco4 problems ?" by BACHUS::DOBBENI () Fri Mar 28 1997 05:09

Hello,


A customer is reporting a problem when he prints a job from a pathworks client
to an LPD outbound queue. This problem happens with some jobs (e.g. excell
files) when they are big enough. I will append a part of the LPD logfile with
debugging turned on.

 In the logfile TESTHP.LOG you find the following error message :

 %UCX-E-LPD_BADCMD, Bad command td_fA021NVK_VCEI_VOLVO.BE
 -RMS-F-RSZ, invalid record size

a dir/full of the file gives:

irectory _$3$DKA300:[PWRK$ROOT.SPOOL]

PWRK$SPOOL_199704171607459330.DAT;1       File ID:  (52197,15,0)
Size:           90/92         Owner:    [SYSTEM]
Created:   17-MAR-1997 16:07:47.38
Revised:   17-MAR-1997 16:07:48.90 (2)
Expires:   <None specified>
Backup:    <No backup recorded>
Effective: <None specified>
Recording: <None specified>
File organization:  Sequential
Shelved state:      Online
File attributes:    Allocation: 92, Extend: 0, Global buffer count: 0, No
version limit, Contiguous best try
Record format:      Undefined
Record attributes:  None
RMS attributes:     None
Journaling enabled: None
File protection:    System:RWD, Owner:RWD, Group:RWD, World:

The ucx version is 4.1 eco4. Could this problem have some relationship with a
very old one, namely the ucx3.0 release notes states:

            o  An outbound job of a file with undefined file format and
                 containing more than 32767 bytes no longer causes the
                 symbiont to exit with:

                 -RMS-F-RSZ, invalid record size
  
or is this a local formatting problem on the client. I tried ti reproduce this
problem, but I couldn't.

Could someone find out, based on the log if this could be an LPD problem which
should be cld'd?

Best Regards,

Mia






%%%%%%%%%%%%                   17-MAR-1997 16:10:35.17  %%%%%%%%%%%%


%UCX-I-LPD_LOGSUC, Using log file SYS$SPECIFIC:[UCX_LPD]TESTHP.LOG





%%%%%%%%%%%%                   17-MAR-1997 16:10:35.37  %%%%%%%%%%%%


%UCX-I-LPD_SYMBRUN, Symbiont is running the queue TESTHP


(lpd$smb.c/680) SMB_DEBUG=7

#ucx$trnlnm:ename: st:444
(lpd$smb.c/695)SYS$SCRATCH undefined.

#ucx$trnlnm:ename:SYS$SPECIFIC:[UCX_LPD] st:1
(lpd$smb.c/710)Defining SYS$SCRATCH as SYS$SPECIFIC:[UCX_LPD] in process table.

#lpd$util.c\1128 :*******Process Statistics*******

#ucx$trnlnm:ename:_NLA0: st:1
SYS$OUTPUT = _NLA0:

#ucx$trnlnm:ename:_NLA0:QMAN00000057_000000D2.SYS$QUEUE_MANAGER st:1
SYS$INPUT = _NLA0:QMAN00000057_000000D2.SYS$QUEUE_MANAGER

#ucx$trnlnm:ename:_NLA0: st:1
SYS$ERROR = _NLA0:

accnt <start> 	astcnt 299	astlm 300

authpri 4	biolm 200

filcnt 196	fillm 200	jobtype 0

owner 0	uaf 0	uic 65540	username SYSTEM      

curpriv=203015E081 imagpriv=0000000000 procpriv=203015E081 authpriv=2000004001

#lpd$util.c\1160 :*******End of Process Statistics*******

#ucx$trnlnm:ename:SYS$SPECIFIC:[UCX_LPD] st:1
(lpd$smb.c/731)SYS$SCRATCH SYS$SPECIFIC:[UCX_LPD] st:1

#ucx$trnlnm:ename:SYS$OUTPUT: st:1
(lpd$smb.c/736)DBG$OUTPUT SYS$OUTPUT: st:1

#ucx$trnlnm:ename:SYS$INPUT: st:1
(lpd$smb.c/741)DBG$INPUT SYS$INPUT: st:1

BUILD4$:[UCX.V41.BL12_SSB.SRC_PAT.LPD]LPD$SMB.C;4: Symbiont activated

#ucx$trnlnm:ename: st:444
(lpd$smb.c/756)honor_vms_flagpages: 0

(lpd$smb.c/784) Pending job in queue at restart detected.

$WAKE status = 1

(lpd$smb.c/793) LPD Symbiont is initialized

(lpd$smb.c/796) sf 1 ef 0 af 0 tf 1

(lpd$smb.c/799)hibernating

 17 calls to LIB$GET_VM, 4 calls to LIB$FREE_VM, 14264 bytes still allocated !!!
KZ_DEBUG

(lpd$smb.c/804) woke up sf 1 ef 0 af 0 tf 1

 17 calls to LIB$GET_VM, 4 calls to LIB$FREE_VM, 14264 bytes still allocated !!!
KZ_DEBUG

(lpd$smb.c/2211) entering lpd$do_task

(lpd$printjob.c/284) entering lpd$printjob

(lpd$printjob.c/295) first file in the job

(lpd$printjob.c/343) entering check_remote_printer

(lpd$printjob.c/361) It's not a local printer.

(lpd$printjob.c/366) Spooler dir is /SYS$SPECIFIC/UCX_LPD/TESTHP, address is
1464767

(lpd$send.c/474) entering sendit pfd 0 cf 0

(lpd$send.c/479) first file in the job

(lpd$send.c/1510) entering send_from_sjc

(lpd$send.c/1515) first file in the job

(lpd$send.c/1544) local hostname - NVK.VCEI.VOLVO.BE

(lpd$send.c/3383) entering getseqnum 

(lpd$send.c/2538) Entering createfile 

(lpd$send.c/2707) entering card_RMS H NVK.VCEI.VOLVO.BE

(lpd$send.c/2707) entering card_RMS P PC001

(lpd$send.c/2707) entering card_RMS J PC001 PC001

(lpd$send.c/2707) entering card_RMS L PC001

(lpd$send.c/2707) entering card_RMS W 255

(lpd$send.c/1703) Printing with flagpage.

(lpd$send.c/1763) dfile: dfA020NVK.VCEI.VOLVO.BE

(lpd$send.c/1777) Sending data file dfA020NVK.VCEI.VOLVO.BE file count 1 inchar
0 

(lpd$send.c/1781) data file fid (52850,4,0) 

(lpd$send.c/2852) Entering openfile

(lpd$send.c/2867) Connecting RAB

(lpd$send.c/2910) entering convertfile

(lpd$send.c/2914) temporary file name td_fA020NVK_VCEI_VOLVO.BE

#ucx$trnlnm:ename: st:444
(lpd$send.c/2538) Entering createfile 

(lpd$send.c/3452) entering copyfile, fmt: 0

(lpd$send.c/3487) Read data block size 23933

(lpd$send.c/2852) Entering openfile

(lpd$send.c/2867) Connecting RAB

(lpd$send.c/835) entering openpr

(lpd$util.c/1476) entering getport: host testhp

(lpd$util.c/1505) connect to address 153.112.132.47: 

(lpd$send.c/975) sending to testhp

(lpd$send.c/1910) Command 23934 dfA020NVK.VCEI.VOLVO.BE



(lpd$send.c/3189) entering sendfile_RMS

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 1024

(lpd$send.c/3204) Read data block size 382

(lpd$send.c/2593) entering setfiletype

(lpd$send.c/2707) entering card_RMS l dfA020NVK.VCEI.VOLVO.BE

(lpd$send.c/2707) entering card_RMS N PWRK$SPOOL_199704171608056731.DAT

(lpd$send.c/2707) entering card_RMS U dfA020NVK.VCEI.VOLVO.BE

(lpd$send.c/2668) entering flush card 

(lpd$send.c/2676) Wrote O. into control file

(lpd$send.c/3331) Entering getfilesize 

(lpd$send.c/2086) Command 136 cfA020NVK.VCEI.VOLVO.BE



(lpd$send.c/3189) entering sendfile_RMS

(lpd$send.c/3204) Read data block size 136

(lpd$send.c/497) sendit st:1

(lpd$smb.c/2236) lpd$printjob st:1

(lpd$smb.c/812)do_task st:1

(lpd$smb.c/1951)lpd$stop_task

(lpd$smb.c/1998)smb$send_to_jobctl st:1
(lpd$smb.c/793) LPD Symbiont is initialized

(lpd$smb.c/796) sf 1 ef 0 af 0 tf 1

(lpd$smb.c/799)hibernating

 22 calls to LIB$GET_VM, 8 calls to LIB$FREE_VM, 14264 bytes still allocated !!!
KZ_DEBUG

(lpd$smb.c/804) woke up sf 1 ef 0 af 0 tf 1

 22 calls to LIB$GET_VM, 8 calls to LIB$FREE_VM, 14264 bytes still allocated !!!
KZ_DEBUG

(lpd$smb.c/2211) entering lpd$do_task

(lpd$printjob.c/284) entering lpd$printjob

(lpd$printjob.c/295) first file in the job

(lpd$printjob.c/343) entering check_remote_printer

(lpd$printjob.c/361) It's not a local printer.

(lpd$printjob.c/366) Spooler dir is /SYS$SPECIFIC/UCX_LPD/TESTHP, address is
1464767

(lpd$send.c/474) entering sendit pfd 0 cf 0

(lpd$send.c/479) first file in the job

(lpd$send.c/1510) entering send_from_sjc

(lpd$send.c/1515) first file in the job

(lpd$send.c/1544) local hostname - NVK.VCEI.VOLVO.BE

(lpd$send.c/3383) entering getseqnum 

(lpd$send.c/2538) Entering createfile 

(lpd$send.c/2707) entering card_RMS H NVK.VCEI.VOLVO.BE

(lpd$send.c/2707) entering card_RMS P PC001

(lpd$send.c/2707) entering card_RMS J PC001 PC001

(lpd$send.c/2707) entering card_RMS L PC001

(lpd$send.c/2707) entering card_RMS W 255

(lpd$send.c/1703) Printing with flagpage.

(lpd$send.c/1763) dfile: dfA021NVK.VCEI.VOLVO.BE

(lpd$send.c/1777) Sending data file dfA021NVK.VCEI.VOLVO.BE file count 1 inchar
0 

(lpd$send.c/1781) data file fid (52197,15,0) 

(lpd$send.c/2852) Entering openfile

(lpd$send.c/2867) Connecting RAB

(lpd$send.c/2910) entering convertfile

(lpd$send.c/2914) temporary file name td_fA021NVK_VCEI_VOLVO.BE

#ucx$trnlnm:ename: st:444
(lpd$send.c/2538) Entering createfile 

(lpd$send.c/3452) entering copyfile, fmt: 0

(lpd$send.c/3487) Read data block size 32767

(lpd$send.c/3540) Error writing file td_fA021NVK_VCEI_VOLVO.BE st:100004




%%%%%%%%%%%%                   17-MAR-1997 16:10:44.89  %%%%%%%%%%%%


%UCX-E-LPD_BADCMD, Bad command td_fA021NVK_VCEI_VOLVO.BE
-RMS-F-RSZ, invalid record size


(lpd$smb.c/812)do_task st:51047066

(lpd$smb.c/1951)lpd$stop_task

(lpd$smb.c/1998)smb$send_to_jobctl st:1
(lpd$smb.c/793) LPD Symbiont is initialized

(lpd$smb.c/796) sf 1 ef 0 af 0 tf 1

(lpd$smb.c/799)hibernating

 22 calls to LIB$GET_VM, 8 calls to LIB$FREE_VM, 14264 bytes still allocated !!!
KZ_DEBUG

(lpd$smb.c/1028)ast_handler

(lpd$smb.c/1034)read_message st:1

(lpd$smb.c/1079) stop_stream

(lpd$smb.c/1209)out of AST

(lpd$smb.c/804) woke up sf 1 ef 1 af 1 tf 0

 23 calls to LIB$GET_VM, 8 calls to LIB$FREE_VM, 14264 bytes still allocated !!!
KZ_DEBUG

(lpd$smb.c/2043)lpd$send_to_jobctl

(lpd$smb.c/2067)smb$send_to_jobctl st:1

(lpd$smb.c/878) close trace file and exit