I would appreciate if you could spare the time to have a look at the
following.
After the initial success of getting pagecounting working I found that I
was getting 3 copies of each printjob. Part of the logfile and the
printcap are included below. In the printcap if I remove the ifhp line I
get a single copy but loose the pagecounting. Whereas with it included as
shown I get the three copies with each of the 3 copies shown in the
accounting file. Setup is RedHat 7.1, ifhp 3.4.7, lpr 3.7.9 (also seen
with 3.7.4-23 redhat rpm), hp4plus (also seen on hp4050).
The error is as follows.
transfer to pr1@pr1 failed error 'ERROR TRANSFERRING DATA' sending data
file '
[snip from printcap.local, minimum filter lines to show error]
pr1:\
:sh:\
:ml=0:\
:mx=0:\
:sd=/var/spool/lpd/pr1:\
:rm=pr1:\
:lpd_bounce=true:\
:if=/usr/libexec/filters/ifhp:\
:ifhp=model=hp4plus,dev=pr1%9100
[snip from log, this block is repeated 3 times until JFAIL = 3]
15:43:44.987 client1 [1000] (Worker - Remote) pr1: *** end ***
15:43:44.987 client1 [1000] (Worker - Remote) pr1: Fix_send_auth: name
'<NULL>'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: Fix_send_auth: no
security
15:43:44.987 client1 [1000] (Worker - Remote) pr1: setstatus: msg
'sending job 'barry@client1+998' to pr1@pr1'
15:43:44.988 client1 [1000] (Worker - Remote) pr1: setstatus: msg
'connecting to 'pr1', attempt 1'
15:43:45.080 client1 [1000] (Worker - Remote) pr1: setstatus: msg
'connected to 'pr1''
15:43:45.080 client1 [1000] (Worker - Remote) pr1: setstatus: msg
'requesting printer pr1@pr1'
15:43:45.098 client1 [1000] (Worker - Remote) pr1: setstatus: msg
'sending control file 'cfA998client1.ipaddress' to pr1@pr1'
15:43:45.133 client1 [1000] (Worker - Remote) pr1: setstatus: msg
'completed sending 'cfA998client1.ipaddress' to pr1@pr1'
15:43:45.133 client1 [1000] (Worker - Remote) pr1: setstatus: msg
'sending data file 'dfA998client1.ipaddress' to pr1@pr1'
15:45:14.736 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'job
'barry@client1+998' transfer to pr1@pr1 failed
error 'ERROR TRANSFERRING DATA'
sending data file 'dfA998client1.ipaddress' to pr1@pr1'
15:45:14.736 client1 [1000] (Worker -
Remote) pr1: Remote_job: barry@client1+998, status 'ERROR TRANSFERRING
DATA'
15:45:14.737 client1 [1000] (Worker -
Remote) pr1: Make_temp_fd_in_dir: fd 4, name
'/var/spool/lpd/pr1/temp02UZoXFH'
15:45:14.737 client1 [1000] (Worker -
Remote) pr1: Do_accounting: command 'jobend $H $n $P $k $b $t', af
'acct', expanded 'jobend '-Hclient1.ipaddress' '-nbarry' '-Ppr1'
'-kcfA998client1.ipaddress' '-b567743' '-t 15:45:14.000''
15:45:14.737 client1 [1000] (Worker - Remote) pr1: cleanup: done,
exit(32)
15:45:14.737 client1 [999] (Server) pr1: Wait_for_subserver: pid 1000,
status 'exit status 32 (JFAIL)'
15:45:14.738 client1 [999] (Server) pr1: Wait_for_subserver: pid 1000
final status JFAIL
15:45:14.738 client1 [999] (Server) pr1: setstatus: msg 'subserver pid
1000 exit status 'JFAIL''
15:45:14.738 client1 [999] (Server) pr1: Get_hold_file: checking on
'hfA998'
15:45:14.738 client1 [999] (Server) pr1: Wait_for_subserver: server pid
1000 for 'pr1' for 'hfA998' '<NULL>' finished
15:45:14.738 client1 [999] (Server) pr1: setmessage: msg 'EXITSTATUS
JFAIL'
15:45:14.738 client1 [999] (Server) pr1: Update_status: again - status
'JFAIL', id 'cfA998client1.ipaddress', dest id '<NULL>'
15:45:14.738 client1 [999] (Server) pr1: setmessage: msg 'PROCESSSTATUS
JFAIL'
15:45:14.738 client1 [999] (Server) pr1: Update_status: JFAIL - attempt
1, max 3
15:45:14.738 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp008GMLL6'
Many thanks for your time
Barry Wright
15:43:05.150 client1 [999] (Server) pr1: Update_spool_info: file 'control.pr1'
15:43:05.151 client1 [999] (Server) pr1: Do_queue_jobs: printable 1, held 0, move 0
15:43:05.151 client1 [999] (Server) pr1: Do_queue_jobs: after Scan_queue next fd 5
15:43:05.151 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:43:05.151 client1 [999] (Server) pr1: Do_queue_jobs: MAIN LOOP
15:43:05.151 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 before scan 0
15:43:05.151 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:43:05.151 client1 [999] (Server) pr1: Do_queue_jobs: chooser '<NULL>',
chooser_routine 0
15:43:05.151 client1 [999] (Server) pr1: Do_queue_jobs: checking 'pr1'
15:43:05.151 client1 [999] (Server) pr1: Do_queue_jobs: job_to_do 0, use_subserver
0, working 0
15:43:05.152 client1 [999] (Server) pr1: Do_queue_jobs: starting job
'barry@client1+998' on 'pr1'
15:43:05.152 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 5, name
'/var/spool/lpd/pr1/temp00ByopXt'
15:43:05.152 client1 [999] (Server) pr1: Do_queue_jobs: trimming status file
'/var/spool/lpd/pr1'/'status'
15:43:05.152 client1 [999] (Server) pr1: Trim_status_file: file 'status' max 10, min
0
15:43:05.152 client1 [999] (Server) pr1: Trim_status_file: 'status' max 10, min 0,
size 8962
15:43:05.152 client1 [999] (Server) pr1: Fork_subserver: starting 'pr1'
15:43:05.152 client1 [999] (Server) pr1: Start_worker: fd 0
15:43:05.152 client1 [999] (Server) pr1: Dump_line_list: Start_worker - parms -
0xbfffe680, count 4, max 102, list 0x80b4508
15:43:05.152 client1 [999] (Server) pr1: [ 0] 0x80b5310 ='call=printer'
15:43:05.152 client1 [999] (Server) pr1: [ 1] 0x80b3cc8 ='hf_name=hfA998'
15:43:05.152 client1 [999] (Server) pr1: [ 2] 0x80b46c0 ='printer=pr1'
15:43:05.152 client1 [999] (Server) pr1: [ 3] 0x80b46a8 ='subserver=0x0'
15:43:05.154 client1 [1000] (Worker) pr1: Service_worker: begin
15:43:05.154 client1 [1000] (Worker) pr1: Fix_Rm_Rp_info: printer name 'pr1'
15:43:05.154 client1 [1000] (Worker) Reset_config: starting
15:43:05.154 client1 [1000] (Worker) pr1: Select_pc_info: looking for 'pr1', depth 0
15:43:05.154 client1 [1000] (Worker) pr1: Find_pc_info: found name 'pr1'
15:43:05.154 client1 [1000] (Worker) pr1: Select_pc_info: returning 'pr1'
15:43:05.154 client1 [1000] (Worker) pr1: Fix_Rm_Rp_info: Printer 'pr1', Queue
'pr1', Lp '<NULL>', Rp 'pr1', Rh 'pr1'
15:43:05.155 client1 [1000] (Worker) pr1: Setup_printer: printer now 'pr1', spool
dir '/var/spool/lpd/pr1'
15:43:05.155 client1 [1000] (Worker) pr1: Service_worker: pid 1000
15:43:05.155 client1 [1000] (Worker) pr1: Get_hold_file: checking on 'hfA998'
15:43:05.155 client1 [1000] (Worker) pr1: Make_temp_fd_in_dir: fd 4, name
'/var/spool/lpd/pr1/temp00I5cxph'
15:43:05.155 client1 [1000] (Worker - Remote) pr1: Service_worker: sending
'barry@client1+998' to 'pr1@pr1'
15:43:05.155 client1 [1000] (Worker - Remote) pr1: Remote_job: barry@client1+998
15:43:05.155 client1 [1000] (Worker - Remote) pr1: Setup_user_reporting:
Allow_user_logging 0, host '<NULL>'
15:43:05.155 client1 [1000] (Worker - Remote) pr1: Do_accounting: command 'jobstart
$H $n $P $k $b $t', af 'acct', expanded 'jobstart '-Hclient1.ipaddress' '-nbarry'
'-Ppr1' '-kcfA998client1.ipaddress' '-b567743' '-t 15:43:05.000''
15:43:05.155 client1 [1000] (Worker - Remote) pr1: Remote_job: accounting status
JSUCC
15:43:05.156 client1 [1000] (Worker - Remote) pr1: Make_temp_fd_in_dir: fd 4, name
'/var/spool/lpd/pr1/temp01powGR4'
15:43:05.156 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'processing
'dfA998client1.ipaddress', size 567743, format 'f', IF filter 'ifhp''
15:43:05.156 client1 [1000] (Worker - Remote) pr1: Trim_status_file: file
'status.pr1' max 10, min 0
15:43:05.156 client1 [1000] (Worker - Remote) pr1: Trim_status_file: 'status.pr1'
max 10, min 0, size 2532
15:43:05.156 client1 [1000] (Worker - Remote) pr1: Make_passthrough: cmd
'/usr/libexec/filters/ifhp', flags '$A $B $C $D $E $F $G $H $I $J $K $L $M $N $O $P $Q
$R $S $T $U $V $W $X $Y $Z $a $b $c $d $e $f $g $h $i $j $k $l $m $n $o $p $q $r $s $t
$u $v $w $x $y $z $-a'
15:43:05.156 client1 [1000] (Worker - Remote) pr1: Split_cmd_line: line
'/usr/libexec/filters/ifhp'
15:43:05.156 client1 [1000] (Worker - Remote) pr1: Dump_line_list: Split_cmd_line -
0xbfffd6a0, count 1, max 102, list 0x80b8ff8
15:43:05.156 client1 [1000] (Worker - Remote) pr1: [ 0] 0x80aeff8
='/usr/libexec/filters/ifhp'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: Dump_line_list:
Setup_env_for_process - 0xbfffd690, count 12, max 102, list 0x80b94e0
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 0] 0x80b9750
='CONTROL=Abarry@client1+998
CA
D 15:43:05.149
Hclient1.ipaddress
Jdilbert-test-netscape.ps
Pbarry
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 1] 0x80b92b8 ='HOME=/root'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 2] 0x80b92f8 ='IFS= '
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 3] 0x80b9198
='LD_LIBRARY_PATH=/lib:/usr/lib:/usr/local/lib'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 4] 0x80b9340 ='LOGDIR=/root'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 5] 0x80b9328 ='LOGNAME=root'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 6] 0x80b9248
='PATH=/bin:/usr/bin:/usr/local/bin'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 7] 0x80b98d0
='PRINTCAP_ENTRY=pr1
:if=/usr/libexec/filters/ifhp
:ifhp=model=hp4plus,dev=pr1%9100
:lp=/var/spool/lpd/pr1/temp01powGR4
:lpd_bounce=true
:ml=0
:mx=0
:rm=pr1
:sd=/var/spool/lpd/pr1
:sh
'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 8] 0x80aef88 ='PRINTER=pr1'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 9] 0x80b5fe8 ='SHELL=/bin/sh'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [10] 0x80b60c8
='SPOOL_DIR=/var/spool/lpd/pr1'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [11] 0x80b59e8 ='USER=root'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: Dump_line_list: Make_passthrough
- cmd - 0xbfffd6a0, count 28, max 102, list 0x80b8ff8
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 0] 0x80aeff8
='/usr/libexec/filters/ifhp'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 1] 0x80b93a8
='-Abarry@client1+998'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 2] 0x80b5fd8 ='-CA'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 3] 0x80b93c0 ='-D
15:43:05.149'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 4] 0x80b6008 ='-Ff'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 5] 0x80b93e0
='-Hclient1.ipaddress'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 6] 0x80b9400
='-Jdilbert-test-netscape.ps'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 7] 0x80b9420
='-Ndilbert-test-netscape.ps'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 8] 0x80b60a8 ='-Ppr1'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [ 9] 0x80b60b8 ='-Qpr1'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [10] 0x80b91f8 ='-aacct'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [11] 0x80b9208 ='-b567743'
15:43:05.157 client1 [1000] (Worker - Remote) pr1: [12] 0x80b9440
='-d/var/spool/lpd/pr1'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [13] 0x80b9228
='-edfA998client1.ipaddress'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [14] 0x80b9460
='-fdilbert-test-netscape.ps'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [15] 0x80b9480
='-hclient1.ipaddress'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [16] 0x80b9288 ='-j998'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [17] 0x80b94a0
='-kcfA998client1.ipaddress'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [18] 0x80b92a8 ='-l66'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [19] 0x80b92c8 ='-nbarry'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [20] 0x80b92e8 ='-ppr1'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [21] 0x80b9308 ='-rpr1'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [22] 0x80b9318 ='-sstatus'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [23] 0x80b94c0 ='-t
15:43:05.000'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [24] 0x80b9358 ='-w80'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [25] 0x80b9368 ='-x0'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [26] 0x80b9378 ='-y0'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [27] 0x80b9398 ='acct'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: Make_passthrough: fd count 3,
root 0
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [0]=5
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [1]=4
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [2]=8
15:43:05.158 client1 [1000] (Worker - Remote) pr1: Dump_line_list: Make_passthrough
- env - 0xbfffd690, count 12, max 102, list 0x80b94e0
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 0] 0x80b9750
='CONTROL=Abarry@client1+998
CA
D 15:43:05.149
Hclient1.ipaddress
Jdilbert-test-netscape.ps
Pbarry
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 1] 0x80b92b8 ='HOME=/root'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 2] 0x80b92f8 ='IFS= '
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 3] 0x80b9198
='LD_LIBRARY_PATH=/lib:/usr/lib:/usr/local/lib'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 4] 0x80b9340 ='LOGDIR=/root'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 5] 0x80b9328 ='LOGNAME=root'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 6] 0x80b9248
='PATH=/bin:/usr/bin:/usr/local/bin'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 7] 0x80b98d0
='PRINTCAP_ENTRY=pr1
:if=/usr/libexec/filters/ifhp
:ifhp=model=hp4plus,dev=pr1%9100
:lp=/var/spool/lpd/pr1/temp01powGR4
:lpd_bounce=true
:ml=0
:mx=0
:rm=pr1
:sd=/var/spool/lpd/pr1
:sh
'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 8] 0x80aef88 ='PRINTER=pr1'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [ 9] 0x80b5fe8 ='SHELL=/bin/sh'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [10] 0x80b60c8
='SPOOL_DIR=/var/spool/lpd/pr1'
15:43:05.158 client1 [1000] (Worker - Remote) pr1: [11] 0x80b59e8 ='USER=root'
15:43:05.159 client1 [999] (Server) pr1: Start_worker: pid 1000
15:43:05.159 client1 [999] (Server) pr1: Do_queue_jobs: MAIN LOOP
15:43:05.159 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 before scan 0
15:43:05.159 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:43:05.159 client1 [999] (Server) pr1: Do_queue_jobs: job_to_do -1, use_subserver
-1, working 1
15:43:05.159 client1 [999] (Server) pr1: setstatus: msg 'waiting for subserver to
exit'
15:43:05.159 client1 [999] (Server) pr1: Trim_status_file: file 'status.pr1' max 10,
min 0
15:43:05.159 client1 [999] (Server) pr1: Trim_status_file: 'status.pr1' max 10, min
0, size 2688
15:43:44.986 client1 [1000] (Worker - Remote) pr1: Wait_for_pid: returning 'JSUCC',
exit status 'exit status 0 (JSUCC)'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'IF filter 'ifhp'
filter finished'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'printing
finished'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: setmessage: msg 'SENDING
OLDID=barry@client1+998 NEWID=barry@client1+998 DEST=pr1@pr1'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: *** Job Send_job- starting *** -
0xbfffded0
15:43:44.986 client1 [1000] (Worker - Remote) pr1: info - 0xbfffded0, count 29, max
102, list 0x80b54e8
15:43:44.986 client1 [1000] (Worker - Remote) pr1: [ 0] 0x80b4a70
='A=barry@client1+998'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: [ 1] 0x80b4970 ='C=A'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: [ 2] 0x80b7078
='cf_out_image=Hclient1.ipaddress
Pbarry
Jdilbert-test-netscape.ps
CA
Abarry@client1+998
D 15:43:05.149
Ndilbert-test-netscape.ps
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: [ 3] 0x80b4ad8 ='D=
15:43:05.149'
15:43:44.986 client1 [1000] (Worker - Remote) pr1: [ 4] 0x80b6cd8
='datafile_count=1'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 5] 0x80b6e50
='datafiles=Ndilbert-test-netscape.ps^AfdfA998client1.ipaddress^AUdfA998client1.ipaddress^A'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 6] 0x80aefb0
='df_name=dfA998client1.ipaddress'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 7] 0x80b46b8
='file_hostname=client1.ipaddress'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 8] 0x80b6368
='filenames=dilbert-test-netscape.ps'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 9] 0x80b59f8 ='format=f'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [10] 0x80b6390
='H=client1.ipaddress'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [11] 0x80b46e0 ='held=0x0'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [12] 0x80b62d8
='hf_name=hfA998'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [13] 0x80b62f0
='hold_class=0x0'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [14] 0x80b6308 ='hold_time=0x0'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [15] 0x80b6320
='J=dilbert-test-netscape.ps'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [16] 0x80b6340
='job_time=0x3bf088b9'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [17] 0x80b5ed8
='job_time_usec=0x24558'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [18] 0x80aefd8
='N=dilbert-test-netscape.ps'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [19] 0x80b6cc8 ='number=998'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [20] 0x80b5ef8 ='P=barry'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [21] 0x80b5f08 ='printable=0x1'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [22] 0x80b5f20 ='priority=A'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [23] 0x80b5f30 ='Q=pr1'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [24] 0x80b5f40 ='server=1000'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [25] 0x80b5f50 ='size=567743'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [26] 0x80b5f60
='start_time=0x3bf088b9'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [27] 0x80b69a8
='transfername=cfA998client1.ipaddress'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [28] 0x80b5fb0 ='update_time=
15:43:05.155'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: datafiles - count 1
15:43:44.987 client1 [1000] (Worker - Remote) pr1: datafile[0] - 0x80b6630, count
6, max 102, list 0x80b67d8
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 0] 0x80b6a38 ='copies=0x1'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 1] 0x80b6a68 ='format=f'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 2] 0x80b6a18
='N=dilbert-test-netscape.ps'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 3] 0x80b6978
='openname=/var/spool/lpd/pr1/temp01powGR4'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 4] 0x80b6a48 ='size=0'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: [ 5] 0x80b5f80
='transfername=dfA998client1.ipaddress'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: destination - 0xbfffdee8, count
0, max 0, list 0x0
15:43:44.987 client1 [1000] (Worker - Remote) pr1: *** end ***
15:43:44.987 client1 [1000] (Worker - Remote) pr1: Fix_send_auth: name '<NULL>'
15:43:44.987 client1 [1000] (Worker - Remote) pr1: Fix_send_auth: no security
15:43:44.987 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'sending job
'barry@client1+998' to pr1@pr1'
15:43:44.988 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'connecting to
'pr1', attempt 1'
15:43:45.080 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'connected to
'pr1''
15:43:45.080 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'requesting
printer pr1@pr1'
15:43:45.098 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'sending control
file 'cfA998client1.ipaddress' to pr1@pr1'
15:43:45.133 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'completed sending
'cfA998client1.ipaddress' to pr1@pr1'
15:43:45.133 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'sending data file
'dfA998client1.ipaddress' to pr1@pr1'
15:45:14.736 client1 [1000] (Worker - Remote) pr1: setstatus: msg 'job
'barry@client1+998' transfer to pr1@pr1 failed
error 'ERROR TRANSFERRING DATA'
sending data file 'dfA998client1.ipaddress' to pr1@pr1'
15:45:14.736 client1 [1000] (Worker - Remote) pr1: Remote_job: barry@client1+998,
status 'ERROR TRANSFERRING DATA'
15:45:14.737 client1 [1000] (Worker - Remote) pr1: Make_temp_fd_in_dir: fd 4, name
'/var/spool/lpd/pr1/temp02UZoXFH'
15:45:14.737 client1 [1000] (Worker - Remote) pr1: Do_accounting: command 'jobend $H
$n $P $k $b $t', af 'acct', expanded 'jobend '-Hclient1.ipaddress' '-nbarry' '-Ppr1'
'-kcfA998client1.ipaddress' '-b567743' '-t 15:45:14.000''
15:45:14.737 client1 [1000] (Worker - Remote) pr1: cleanup: done, exit(32)
15:45:14.737 client1 [999] (Server) pr1: Wait_for_subserver: pid 1000, status 'exit
status 32 (JFAIL)'
15:45:14.738 client1 [999] (Server) pr1: Wait_for_subserver: pid 1000 final status
JFAIL
15:45:14.738 client1 [999] (Server) pr1: setstatus: msg 'subserver pid 1000 exit
status 'JFAIL''
15:45:14.738 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:45:14.738 client1 [999] (Server) pr1: Wait_for_subserver: server pid 1000 for
'pr1' for 'hfA998' '<NULL>' finished
15:45:14.738 client1 [999] (Server) pr1: setmessage: msg 'EXITSTATUS JFAIL'
15:45:14.738 client1 [999] (Server) pr1: Update_status: again - status 'JFAIL', id
'cfA998client1.ipaddress', dest id '<NULL>'
15:45:14.738 client1 [999] (Server) pr1: setmessage: msg 'PROCESSSTATUS JFAIL'
15:45:14.738 client1 [999] (Server) pr1: Update_status: JFAIL - attempt 1, max 3
15:45:14.738 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp008GMLL6'
15:45:14.738 client1 [999] (Server) pr1: setstatus: msg 'job
'cfA998client1.ipaddress' attempt 1, trying 3 times'
15:45:14.738 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp01btb9zJ'
15:45:14.739 client1 [999] (Server) pr1: Update_spool_info: file 'control.pr1'
15:45:14.739 client1 [999] (Server) pr1: Do_queue_jobs: MAIN LOOP
15:45:14.739 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 before scan 0
15:45:14.739 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:45:14.739 client1 [999] (Server) pr1: Do_queue_jobs: chooser '<NULL>',
chooser_routine 0
15:45:14.739 client1 [999] (Server) pr1: Do_queue_jobs: checking 'pr1'
15:45:14.739 client1 [999] (Server) pr1: Do_queue_jobs: job_to_do 0, use_subserver
0, working 0
15:45:14.739 client1 [999] (Server) pr1: Do_queue_jobs: starting job
'cfA998client1.ipaddress' on 'pr1'
15:45:14.740 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp00Fy8zom'
15:45:14.740 client1 [999] (Server) pr1: Do_queue_jobs: trimming status file
'/var/spool/lpd/pr1'/'status'
15:45:14.740 client1 [999] (Server) pr1: Trim_status_file: file 'status' max 10, min
0
15:45:14.740 client1 [999] (Server) pr1: Trim_status_file: 'status' max 10, min 0,
size 9839
15:45:14.740 client1 [999] (Server) pr1: Fork_subserver: starting 'pr1'
15:45:14.740 client1 [999] (Server) pr1: Start_worker: fd 0
15:45:14.740 client1 [999] (Server) pr1: Dump_line_list: Start_worker - parms -
0xbfffe680, count 4, max 102, list 0x80b5198
15:45:14.740 client1 [999] (Server) pr1: [ 0] 0x80b5368 ='call=printer'
15:45:14.740 client1 [999] (Server) pr1: [ 1] 0x80b3c50 ='hf_name=hfA998'
15:45:14.740 client1 [999] (Server) pr1: [ 2] 0x80b5338 ='printer=pr1'
15:45:14.740 client1 [999] (Server) pr1: [ 3] 0x80b3c80 ='subserver=0x0'
15:45:14.740 client1 [1007] (Worker) pr1: Service_worker: begin
15:45:14.741 client1 [1007] (Worker) pr1: Fix_Rm_Rp_info: printer name 'pr1'
15:45:14.741 client1 [1007] (Worker) Reset_config: starting
15:45:14.741 client1 [1007] (Worker) pr1: Select_pc_info: looking for 'pr1', depth 0
15:45:14.741 client1 [1007] (Worker) pr1: Find_pc_info: found name 'pr1'
15:45:14.741 client1 [1007] (Worker) pr1: Select_pc_info: returning 'pr1'
15:45:14.741 client1 [1007] (Worker) pr1: Fix_Rm_Rp_info: Printer 'pr1', Queue
'pr1', Lp '<NULL>', Rp 'pr1', Rh 'pr1'
15:45:14.741 client1 [1007] (Worker) pr1: Setup_printer: printer now 'pr1', spool
dir '/var/spool/lpd/pr1'
15:45:14.741 client1 [1007] (Worker) pr1: Service_worker: pid 1007
15:45:14.741 client1 [1007] (Worker) pr1: Get_hold_file: checking on 'hfA998'
15:45:14.742 client1 [1007] (Worker) pr1: Make_temp_fd_in_dir: fd 3, name
'/var/spool/lpd/pr1/temp00wOz8cZ'
15:45:14.742 client1 [1007] (Worker) pr1: Service_worker: attempt 1, sleeping 10
15:45:14.742 client1 [1007] (Worker) pr1: setstatus: msg 'attempt 2, sleeping 10
before retry'
15:45:14.742 client1 [1007] (Worker) pr1: Trim_status_file: file 'status.pr1' max
10, min 0
15:45:14.742 client1 [1007] (Worker) pr1: Trim_status_file: 'status.pr1' max 10, min
0, size 4258
15:45:14.742 client1 [999] (Server) pr1: Start_worker: pid 1007
15:45:14.742 client1 [999] (Server) pr1: Do_queue_jobs: MAIN LOOP
15:45:14.742 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 before scan 0
15:45:14.742 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:45:14.742 client1 [999] (Server) pr1: Do_queue_jobs: job_to_do -1, use_subserver
-1, working 1
15:45:14.743 client1 [999] (Server) pr1: setstatus: msg 'waiting for subserver to
exit'
15:45:24.748 client1 [1007] (Worker - Remote) pr1: Service_worker: sending
'cfA998client1.ipaddress' to 'pr1@pr1'
15:45:24.748 client1 [1007] (Worker - Remote) pr1: Remote_job:
cfA998client1.ipaddress
15:45:24.748 client1 [1007] (Worker - Remote) pr1: Setup_user_reporting:
Allow_user_logging 0, host '<NULL>'
15:45:24.748 client1 [1007] (Worker - Remote) pr1: Do_accounting: command 'jobstart
$H $n $P $k $b $t', af 'acct', expanded 'jobstart '-Hclient1.ipaddress' '-nbarry'
'-Ppr1' '-kcfA998client1.ipaddress' '-b567743' '-t 15:45:24.000''
15:45:24.748 client1 [1007] (Worker - Remote) pr1: Remote_job: accounting status
JSUCC
15:45:24.748 client1 [1007] (Worker - Remote) pr1: Make_temp_fd_in_dir: fd 5, name
'/var/spool/lpd/pr1/temp014kmqMK'
15:45:24.749 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'processing
'dfA998client1.ipaddress', size 567743, format 'f', IF filter 'ifhp''
15:45:24.749 client1 [1007] (Worker - Remote) pr1: Make_passthrough: cmd
'/usr/libexec/filters/ifhp', flags '$A $B $C $D $E $F $G $H $I $J $K $L $M $N $O $P $Q
$R $S $T $U $V $W $X $Y $Z $a $b $c $d $e $f $g $h $i $j $k $l $m $n $o $p $q $r $s $t
$u $v $w $x $y $z $-a'
15:45:24.749 client1 [1007] (Worker - Remote) pr1: Split_cmd_line: line
'/usr/libexec/filters/ifhp'
15:45:24.749 client1 [1007] (Worker - Remote) pr1: Dump_line_list: Split_cmd_line -
0xbfffd6a0, count 1, max 102, list 0x80b8ff8
15:45:24.749 client1 [1007] (Worker - Remote) pr1: [ 0] 0x80b63c8
='/usr/libexec/filters/ifhp'
15:45:24.749 client1 [1007] (Worker - Remote) pr1: Dump_line_list:
Setup_env_for_process - 0xbfffd690, count 12, max 102, list 0x80b94b8
15:45:24.749 client1 [1007] (Worker - Remote) pr1: [ 0] 0x80b9728
='CONTROL=Abarry@client1+998
CA
D 15:43:05.149
Hclient1.ipaddress
Jdilbert-test-netscape.ps
Pbarry
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:45:24.749 client1 [1007] (Worker - Remote) pr1: [ 1] 0x80b92a8 ='HOME=/root'
15:45:24.749 client1 [1007] (Worker - Remote) pr1: [ 2] 0x80b92e8 ='IFS= '
15:45:24.749 client1 [1007] (Worker - Remote) pr1: [ 3] 0x80b9198
='LD_LIBRARY_PATH=/lib:/usr/lib:/usr/local/lib'
15:45:24.749 client1 [1007] (Worker - Remote) pr1: [ 4] 0x80b9330 ='LOGDIR=/root'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 5] 0x80b9318 ='LOGNAME=root'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 6] 0x80aef88
='PATH=/bin:/usr/bin:/usr/local/bin'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 7] 0x80b98a8
='PRINTCAP_ENTRY=pr1
:if=/usr/libexec/filters/ifhp
:ifhp=model=hp4plus,dev=pr1%9100
:lp=/var/spool/lpd/pr1/temp014kmqMK
:lpd_bounce=true
:ml=0
:mx=0
:rm=pr1
:sd=/var/spool/lpd/pr1
:sh
'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 8] 0x80b6468 ='PRINTER=pr1'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 9] 0x80aefb0 ='SHELL=/bin/sh'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [10] 0x80aeed8
='SPOOL_DIR=/var/spool/lpd/pr1'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [11] 0x80b6378 ='USER=root'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: Dump_line_list: Make_passthrough
- cmd - 0xbfffd6a0, count 27, max 102, list 0x80b8ff8
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 0] 0x80b63c8
='/usr/libexec/filters/ifhp'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 1] 0x80b6458 ='-CA'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 2] 0x80b9398 ='-D
15:43:05.149'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 3] 0x80aeec8 ='-Ff'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 4] 0x80b93b8
='-Hclient1.ipaddress'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 5] 0x80b93d8
='-Jdilbert-test-netscape.ps'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 6] 0x80b93f8
='-Ndilbert-test-netscape.ps'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 7] 0x80aef68 ='-Ppr1'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 8] 0x80aef78 ='-Qpr1'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [ 9] 0x80b91e8 ='-aacct'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [10] 0x80b91f8 ='-b567743'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [11] 0x80b9418
='-d/var/spool/lpd/pr1'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [12] 0x80b9218
='-edfA998client1.ipaddress'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [13] 0x80b9438
='-fdilbert-test-netscape.ps'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [14] 0x80b9458
='-hclient1.ipaddress'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [15] 0x80b9278 ='-j998'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [16] 0x80b9478
='-kcfA998client1.ipaddress'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [17] 0x80b9298 ='-l66'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [18] 0x80b92b8 ='-nbarry'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [19] 0x80b92d8 ='-ppr1'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [20] 0x80b92f8 ='-rpr1'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [21] 0x80b9308 ='-sstatus'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [22] 0x80b9498 ='-t
15:45:24.000'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [23] 0x80b9348 ='-w80'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [24] 0x80b9358 ='-x0'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [25] 0x80b9368 ='-y0'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [26] 0x80b9388 ='acct'
15:45:24.750 client1 [1007] (Worker - Remote) pr1: Make_passthrough: fd count 3,
root 0
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [0]=6
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [1]=5
15:45:24.750 client1 [1007] (Worker - Remote) pr1: [2]=8
15:45:24.750 client1 [1007] (Worker - Remote) pr1: Dump_line_list: Make_passthrough
- env - 0xbfffd690, count 12, max 102, list 0x80b94b8
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 0] 0x80b9728
='CONTROL=Abarry@client1+998
CA
D 15:43:05.149
Hclient1.ipaddress
Jdilbert-test-netscape.ps
Pbarry
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 1] 0x80b92a8 ='HOME=/root'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 2] 0x80b92e8 ='IFS= '
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 3] 0x80b9198
='LD_LIBRARY_PATH=/lib:/usr/lib:/usr/local/lib'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 4] 0x80b9330 ='LOGDIR=/root'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 5] 0x80b9318 ='LOGNAME=root'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 6] 0x80aef88
='PATH=/bin:/usr/bin:/usr/local/bin'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 7] 0x80b98a8
='PRINTCAP_ENTRY=pr1
:if=/usr/libexec/filters/ifhp
:ifhp=model=hp4plus,dev=pr1%9100
:lp=/var/spool/lpd/pr1/temp014kmqMK
:lpd_bounce=true
:ml=0
:mx=0
:rm=pr1
:sd=/var/spool/lpd/pr1
:sh
'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 8] 0x80b6468 ='PRINTER=pr1'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [ 9] 0x80aefb0 ='SHELL=/bin/sh'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [10] 0x80aeed8
='SPOOL_DIR=/var/spool/lpd/pr1'
15:45:24.751 client1 [1007] (Worker - Remote) pr1: [11] 0x80b6378 ='USER=root'
15:46:03.734 client1 [1007] (Worker - Remote) pr1: Wait_for_pid: returning 'JSUCC',
exit status 'exit status 0 (JSUCC)'
15:46:03.734 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'IF filter 'ifhp'
filter finished'
15:46:03.734 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'printing
finished'
15:46:03.734 client1 [1007] (Worker - Remote) pr1: setmessage: msg 'SENDING
OLDID=<NULL> NEWID=barry@client1+998 DEST=pr1@pr1'
15:46:03.734 client1 [1007] (Worker - Remote) pr1: *** Job Send_job- starting *** -
0xbfffded0
15:46:03.735 client1 [1007] (Worker - Remote) pr1: info - 0xbfffded0, count 30, max
102, list 0x80b5540
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 0] 0x80b6d08
='A=barry@client1+998'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 1] 0x80b3c28 ='attempt=0x1'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 2] 0x80af068 ='C=A'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 3] 0x80b60c8
='cf_out_image=Hclient1.ipaddress
Pbarry
Jdilbert-test-netscape.ps
CA
Abarry@client1+998
D 15:43:05.149
Ndilbert-test-netscape.ps
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 4] 0x80b5d10 ='D=
15:43:05.149'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 5] 0x80b6d20
='datafile_count=1'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 6] 0x80b5f80
='datafiles=Ndilbert-test-netscape.ps^AfdfA998client1.ipaddress^AUdfA998client1.ipaddress^A'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 7] 0x80b63f0
='df_name=dfA998client1.ipaddress'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 8] 0x80b5768
='file_hostname=client1.ipaddress'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 9] 0x80b56e0
='filenames=dilbert-test-netscape.ps'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [10] 0x80b6388 ='format=f'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [11] 0x80b4f78
='H=client1.ipaddress'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [12] 0x80b4f98 ='held=0x0'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [13] 0x80b4fa8
='hf_name=hfA998'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [14] 0x80b4fc0
='hold_class=0x0'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [15] 0x80af100 ='hold_time=0x0'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [16] 0x80af118
='J=dilbert-test-netscape.ps'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [17] 0x80aedb0
='job_time=0x3bf088b9'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [18] 0x80aedc8
='job_time_usec=0x24558'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [19] 0x80b6418
='N=dilbert-test-netscape.ps'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [20] 0x80b6cc8 ='number=998'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [21] 0x80aede8 ='P=barry'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [22] 0x80aedf8 ='printable=0x1'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [23] 0x80aee10 ='priority=A'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [24] 0x80aee20 ='Q=pr1'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [25] 0x80aee30 ='server=1007'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [26] 0x80aee40 ='size=567743'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [27] 0x80aee50
='start_time=0x3bf0893a'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [28] 0x80b69a8
='transfername=cfA998client1.ipaddress'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [29] 0x80aeea0 ='update_time=
15:45:14.742'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: datafiles - count 1
15:46:03.735 client1 [1007] (Worker - Remote) pr1: datafile[0] - 0x80b6630, count
6, max 102, list 0x80b67d8
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 0] 0x80b6a58 ='copies=0x1'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 1] 0x80b6a88 ='format=f'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 2] 0x80b6a38
='N=dilbert-test-netscape.ps'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 3] 0x80b6978
='openname=/var/spool/lpd/pr1/temp014kmqMK'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 4] 0x80b6a68 ='size=0'
15:46:03.735 client1 [1007] (Worker - Remote) pr1: [ 5] 0x80b6cd8
='transfername=dfA998client1.ipaddress'
15:46:03.736 client1 [1007] (Worker - Remote) pr1: destination - 0xbfffdee8, count
0, max 0, list 0x0
15:46:03.736 client1 [1007] (Worker - Remote) pr1: *** end ***
15:46:03.736 client1 [1007] (Worker - Remote) pr1: Fix_send_auth: name '<NULL>'
15:46:03.736 client1 [1007] (Worker - Remote) pr1: Fix_send_auth: no security
15:46:03.736 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'sending job
'barry@client1+998' to pr1@pr1'
15:46:03.736 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'connecting to
'pr1', attempt 1'
15:46:03.829 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'connected to
'pr1''
15:46:03.829 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'requesting
printer pr1@pr1'
15:46:03.847 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'sending control
file 'cfA998client1.ipaddress' to pr1@pr1'
15:46:03.876 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'completed sending
'cfA998client1.ipaddress' to pr1@pr1'
15:46:03.876 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'sending data file
'dfA998client1.ipaddress' to pr1@pr1'
15:47:33.486 client1 [1007] (Worker - Remote) pr1: setstatus: msg 'job
'barry@client1+998' transfer to pr1@pr1 failed
error 'ERROR TRANSFERRING DATA'
sending data file 'dfA998client1.ipaddress' to pr1@pr1'
15:47:33.486 client1 [1007] (Worker - Remote) pr1: Remote_job:
cfA998client1.ipaddress, status 'ERROR TRANSFERRING DATA'
15:47:33.487 client1 [1007] (Worker - Remote) pr1: Make_temp_fd_in_dir: fd 5, name
'/var/spool/lpd/pr1/temp02USK7Xk'
15:47:33.487 client1 [1007] (Worker - Remote) pr1: Do_accounting: command 'jobend $H
$n $P $k $b $t', af 'acct', expanded 'jobend '-Hclient1.ipaddress' '-nbarry' '-Ppr1'
'-kcfA998client1.ipaddress' '-b567743' '-t 15:47:33.000''
15:47:33.487 client1 [1007] (Worker - Remote) pr1: cleanup: done, exit(32)
15:47:33.488 client1 [999] (Server) pr1: Wait_for_subserver: pid 1007, status 'exit
status 32 (JFAIL)'
15:47:33.488 client1 [999] (Server) pr1: Wait_for_subserver: pid 1007 final status
JFAIL
15:47:33.488 client1 [999] (Server) pr1: setstatus: msg 'subserver pid 1007 exit
status 'JFAIL''
15:47:33.488 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:47:33.488 client1 [999] (Server) pr1: Wait_for_subserver: server pid 1007 for
'pr1' for 'hfA998' '<NULL>' finished
15:47:33.488 client1 [999] (Server) pr1: setmessage: msg 'EXITSTATUS JFAIL'
15:47:33.488 client1 [999] (Server) pr1: Update_status: again - status 'JFAIL', id
'cfA998client1.ipaddress', dest id '<NULL>'
15:47:33.488 client1 [999] (Server) pr1: setmessage: msg 'PROCESSSTATUS JFAIL'
15:47:33.488 client1 [999] (Server) pr1: Update_status: JFAIL - attempt 2, max 3
15:47:33.488 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp00SaRmAW'
15:47:33.488 client1 [999] (Server) pr1: setstatus: msg 'job
'cfA998client1.ipaddress' attempt 2, trying 3 times'
15:47:33.488 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp01MgAaMw'
15:47:33.489 client1 [999] (Server) pr1: Update_spool_info: file 'control.pr1'
15:47:33.489 client1 [999] (Server) pr1: Do_queue_jobs: MAIN LOOP
15:47:33.489 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 before scan 0
15:47:33.489 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:47:33.489 client1 [999] (Server) pr1: Do_queue_jobs: chooser '<NULL>',
chooser_routine 0
15:47:33.489 client1 [999] (Server) pr1: Do_queue_jobs: checking 'pr1'
15:47:33.489 client1 [999] (Server) pr1: Do_queue_jobs: job_to_do 0, use_subserver
0, working 0
15:47:33.489 client1 [999] (Server) pr1: Do_queue_jobs: starting job
'cfA998client1.ipaddress' on 'pr1'
15:47:33.490 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp00Q4O1X6'
15:47:33.490 client1 [999] (Server) pr1: Do_queue_jobs: trimming status file
'/var/spool/lpd/pr1'/'status'
15:47:33.490 client1 [999] (Server) pr1: Trim_status_file: file 'status' max 10, min
0
15:47:33.490 client1 [999] (Server) pr1: Trim_status_file: 'status' max 10, min 0,
size 10716
15:47:33.490 client1 [999] (Server) pr1: Fork_subserver: starting 'pr1'
15:47:33.490 client1 [999] (Server) pr1: Start_worker: fd 0
15:47:33.490 client1 [999] (Server) pr1: Dump_line_list: Start_worker - parms -
0xbfffe680, count 4, max 102, list 0x80b5f48
15:47:33.490 client1 [999] (Server) pr1: [ 0] 0x80b6118 ='call=printer'
15:47:33.490 client1 [999] (Server) pr1: [ 1] 0x80b0828 ='hf_name=hfA998'
15:47:33.490 client1 [999] (Server) pr1: [ 2] 0x80b60e8 ='printer=pr1'
15:47:33.490 client1 [999] (Server) pr1: [ 3] 0x80b6478 ='subserver=0x0'
15:47:33.490 client1 [1016] (Worker) pr1: Service_worker: begin
15:47:33.491 client1 [1016] (Worker) pr1: Fix_Rm_Rp_info: printer name 'pr1'
15:47:33.491 client1 [1016] (Worker) Reset_config: starting
15:47:33.491 client1 [1016] (Worker) pr1: Select_pc_info: looking for 'pr1', depth 0
15:47:33.491 client1 [1016] (Worker) pr1: Find_pc_info: found name 'pr1'
15:47:33.491 client1 [1016] (Worker) pr1: Select_pc_info: returning 'pr1'
15:47:33.491 client1 [1016] (Worker) pr1: Fix_Rm_Rp_info: Printer 'pr1', Queue
'pr1', Lp '<NULL>', Rp 'pr1', Rh 'pr1'
15:47:33.491 client1 [1016] (Worker) pr1: Setup_printer: printer now 'pr1', spool
dir '/var/spool/lpd/pr1'
15:47:33.491 client1 [1016] (Worker) pr1: Service_worker: pid 1016
15:47:33.491 client1 [1016] (Worker) pr1: Get_hold_file: checking on 'hfA998'
15:47:33.492 client1 [1016] (Worker) pr1: Make_temp_fd_in_dir: fd 3, name
'/var/spool/lpd/pr1/temp00uoq09G'
15:47:33.492 client1 [1016] (Worker) pr1: Service_worker: attempt 2, sleeping 20
15:47:33.492 client1 [1016] (Worker) pr1: setstatus: msg 'attempt 3, sleeping 20
before retry'
15:47:33.492 client1 [1016] (Worker) pr1: Trim_status_file: file 'status.pr1' max
10, min 0
15:47:33.492 client1 [1016] (Worker) pr1: Trim_status_file: 'status.pr1' max 10, min
0, size 5962
15:47:33.492 client1 [999] (Server) pr1: Start_worker: pid 1016
15:47:33.492 client1 [999] (Server) pr1: Do_queue_jobs: MAIN LOOP
15:47:33.492 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 before scan 0
15:47:33.492 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:47:33.492 client1 [999] (Server) pr1: Do_queue_jobs: job_to_do -1, use_subserver
-1, working 1
15:47:33.492 client1 [999] (Server) pr1: setstatus: msg 'waiting for subserver to
exit'
15:47:53.498 client1 [1016] (Worker - Remote) pr1: Service_worker: sending
'cfA998client1.ipaddress' to 'pr1@pr1'
15:47:53.498 client1 [1016] (Worker - Remote) pr1: Remote_job:
cfA998client1.ipaddress
15:47:53.498 client1 [1016] (Worker - Remote) pr1: Setup_user_reporting:
Allow_user_logging 0, host '<NULL>'
15:47:53.498 client1 [1016] (Worker - Remote) pr1: Do_accounting: command 'jobstart
$H $n $P $k $b $t', af 'acct', expanded 'jobstart '-Hclient1.ipaddress' '-nbarry'
'-Ppr1' '-kcfA998client1.ipaddress' '-b567743' '-t 15:47:53.000''
15:47:53.498 client1 [1016] (Worker - Remote) pr1: Remote_job: accounting status
JSUCC
15:47:53.498 client1 [1016] (Worker - Remote) pr1: Make_temp_fd_in_dir: fd 5, name
'/var/spool/lpd/pr1/temp01dVM5Qy'
15:47:53.499 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'processing
'dfA998client1.ipaddress', size 567743, format 'f', IF filter 'ifhp''
15:47:53.499 client1 [1016] (Worker - Remote) pr1: Make_passthrough: cmd
'/usr/libexec/filters/ifhp', flags '$A $B $C $D $E $F $G $H $I $J $K $L $M $N $O $P $Q
$R $S $T $U $V $W $X $Y $Z $a $b $c $d $e $f $g $h $i $j $k $l $m $n $o $p $q $r $s $t
$u $v $w $x $y $z $-a'
15:47:53.499 client1 [1016] (Worker - Remote) pr1: Split_cmd_line: line
'/usr/libexec/filters/ifhp'
15:47:53.499 client1 [1016] (Worker - Remote) pr1: Dump_line_list: Split_cmd_line -
0xbfffd6a0, count 1, max 102, list 0x80b8ff8
15:47:53.499 client1 [1016] (Worker - Remote) pr1: [ 0] 0x80b5898
='/usr/libexec/filters/ifhp'
15:47:53.499 client1 [1016] (Worker - Remote) pr1: Dump_line_list:
Setup_env_for_process - 0xbfffd690, count 12, max 102, list 0x80b94a8
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 0] 0x80b9718
='CONTROL=Abarry@client1+998
CA
D 15:43:05.149
Hclient1.ipaddress
Jdilbert-test-netscape.ps
Pbarry
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 1] 0x80b9298 ='HOME=/root'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 2] 0x80b92d8 ='IFS= '
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 3] 0x80b5a18
='LD_LIBRARY_PATH=/lib:/usr/lib:/usr/local/lib'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 4] 0x80b9320 ='LOGDIR=/root'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 5] 0x80b9308 ='LOGNAME=root'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 6] 0x80b9228
='PATH=/bin:/usr/bin:/usr/local/bin'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 7] 0x80b9898
='PRINTCAP_ENTRY=pr1
:if=/usr/libexec/filters/ifhp
:ifhp=model=hp4plus,dev=pr1%9100
:lp=/var/spool/lpd/pr1/temp01dVM5Qy
:lpd_bounce=true
:ml=0
:mx=0
:rm=pr1
:sd=/var/spool/lpd/pr1
:sh
'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 8] 0x80b5938 ='PRINTER=pr1'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 9] 0x80b5908 ='SHELL=/bin/sh'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [10] 0x80b5968
='SPOOL_DIR=/var/spool/lpd/pr1'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [11] 0x80b5848 ='USER=root'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: Dump_line_list: Make_passthrough
- cmd - 0xbfffd6a0, count 27, max 102, list 0x80b8ff8
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 0] 0x80b5898
='/usr/libexec/filters/ifhp'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 1] 0x80b5928 ='-CA'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 2] 0x80b9388 ='-D
15:43:05.149'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 3] 0x80b5958 ='-Ff'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 4] 0x80b93a8
='-Hclient1.ipaddress'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 5] 0x80b93c8
='-Jdilbert-test-netscape.ps'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 6] 0x80b93e8
='-Ndilbert-test-netscape.ps'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 7] 0x80b59f8 ='-Ppr1'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 8] 0x80b5a08 ='-Qpr1'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [ 9] 0x80b91d8 ='-aacct'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [10] 0x80b91e8 ='-b567743'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [11] 0x80b9408
='-d/var/spool/lpd/pr1'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [12] 0x80b9208
='-edfA998client1.ipaddress'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [13] 0x80b9428
='-fdilbert-test-netscape.ps'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [14] 0x80b9448
='-hclient1.ipaddress'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [15] 0x80b9268 ='-j998'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [16] 0x80b9468
='-kcfA998client1.ipaddress'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [17] 0x80b9288 ='-l66'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [18] 0x80b92a8 ='-nbarry'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [19] 0x80b92c8 ='-ppr1'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [20] 0x80b92e8 ='-rpr1'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [21] 0x80b92f8 ='-sstatus'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [22] 0x80b9488 ='-t
15:47:53.000'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [23] 0x80b9338 ='-w80'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [24] 0x80b9348 ='-x0'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [25] 0x80b9358 ='-y0'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: [26] 0x80b9378 ='acct'
15:47:53.500 client1 [1016] (Worker - Remote) pr1: Make_passthrough: fd count 3,
root 0
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [0]=6
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [1]=5
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [2]=8
15:47:53.501 client1 [1016] (Worker - Remote) pr1: Dump_line_list: Make_passthrough
- env - 0xbfffd690, count 12, max 102, list 0x80b94a8
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 0] 0x80b9718
='CONTROL=Abarry@client1+998
CA
D 15:43:05.149
Hclient1.ipaddress
Jdilbert-test-netscape.ps
Pbarry
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 1] 0x80b9298 ='HOME=/root'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 2] 0x80b92d8 ='IFS= '
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 3] 0x80b5a18
='LD_LIBRARY_PATH=/lib:/usr/lib:/usr/local/lib'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 4] 0x80b9320 ='LOGDIR=/root'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 5] 0x80b9308 ='LOGNAME=root'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 6] 0x80b9228
='PATH=/bin:/usr/bin:/usr/local/bin'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 7] 0x80b9898
='PRINTCAP_ENTRY=pr1
:if=/usr/libexec/filters/ifhp
:ifhp=model=hp4plus,dev=pr1%9100
:lp=/var/spool/lpd/pr1/temp01dVM5Qy
:lpd_bounce=true
:ml=0
:mx=0
:rm=pr1
:sd=/var/spool/lpd/pr1
:sh
'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 8] 0x80b5938 ='PRINTER=pr1'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [ 9] 0x80b5908 ='SHELL=/bin/sh'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [10] 0x80b5968
='SPOOL_DIR=/var/spool/lpd/pr1'
15:47:53.501 client1 [1016] (Worker - Remote) pr1: [11] 0x80b5848 ='USER=root'
15:48:32.870 client1 [1016] (Worker - Remote) pr1: Wait_for_pid: returning 'JSUCC',
exit status 'exit status 0 (JSUCC)'
15:48:32.870 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'IF filter 'ifhp'
filter finished'
15:48:32.870 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'printing
finished'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: setmessage: msg 'SENDING
OLDID=<NULL> NEWID=barry@client1+998 DEST=pr1@pr1'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: *** Job Send_job- starting *** -
0xbfffded0
15:48:32.871 client1 [1016] (Worker - Remote) pr1: info - 0xbfffded0, count 30, max
102, list 0x80aedb0
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 0] 0x80b6cf8
='A=barry@client1+998'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 1] 0x80b0858 ='attempt=0x2'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 2] 0x80b40e0 ='C=A'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 3] 0x80b55c0
='cf_out_image=Hclient1.ipaddress
Pbarry
Jdilbert-test-netscape.ps
CA
Abarry@client1+998
D 15:43:05.149
Ndilbert-test-netscape.ps
Qpr1
Ndilbert-test-netscape.ps
fdfA998client1.ipaddress
UdfA998client1.ipaddress
'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 4] 0x80b4150 ='D=
15:43:05.149'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 5] 0x80b6d10
='datafile_count=1'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 6] 0x80b5968
='datafiles=Ndilbert-test-netscape.ps^AfdfA998client1.ipaddress^AUdfA998client1.ipaddress^A'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 7] 0x80b58c0
='df_name=dfA998client1.ipaddress'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 8] 0x80b4368
='file_hostname=client1.ipaddress'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [ 9] 0x80aef50
='filenames=dilbert-test-netscape.ps'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [10] 0x80b5858 ='format=f'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [11] 0x80aef78
='H=client1.ipaddress'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [12] 0x80aef98 ='held=0x0'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [13] 0x80aefa8
='hf_name=hfA998'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [14] 0x80b3a48
='hold_class=0x0'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [15] 0x80b3a60 ='hold_time=0x0'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [16] 0x80b4568
='J=dilbert-test-netscape.ps'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [17] 0x80b4588
='job_time=0x3bf088b9'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [18] 0x80af110
='job_time_usec=0x24558'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [19] 0x80b58e8
='N=dilbert-test-netscape.ps'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [20] 0x80b6cb8 ='number=998'
15:48:32.871 client1 [1016] (Worker - Remote) pr1: [21] 0x80b3a78 ='P=barry'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [22] 0x80af130 ='printable=0x1'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [23] 0x80af148 ='priority=A'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [24] 0x80af158 ='Q=pr1'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [25] 0x80af168 ='server=1016'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [26] 0x80af178 ='size=567743'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [27] 0x80af188
='start_time=0x3bf089c5'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [28] 0x80b6998
='transfername=cfA998client1.ipaddress'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [29] 0x80b52f0 ='update_time=
15:47:33.492'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: datafiles - count 1
15:48:32.872 client1 [1016] (Worker - Remote) pr1: datafile[0] - 0x80af1a8, count
6, max 102, list 0x80b67c8
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [ 0] 0x80b6a48 ='copies=0x1'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [ 1] 0x80b6a78 ='format=f'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [ 2] 0x80b6a28
='N=dilbert-test-netscape.ps'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [ 3] 0x80b6968
='openname=/var/spool/lpd/pr1/temp01dVM5Qy'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [ 4] 0x80b6a58 ='size=0'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: [ 5] 0x80b6cc8
='transfername=dfA998client1.ipaddress'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: destination - 0xbfffdee8, count
0, max 0, list 0x0
15:48:32.872 client1 [1016] (Worker - Remote) pr1: *** end ***
15:48:32.872 client1 [1016] (Worker - Remote) pr1: Fix_send_auth: name '<NULL>'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: Fix_send_auth: no security
15:48:32.872 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'sending job
'barry@client1+998' to pr1@pr1'
15:48:32.872 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'connecting to
'pr1', attempt 1'
15:48:32.962 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'connected to
'pr1''
15:48:32.962 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'requesting
printer pr1@pr1'
15:48:32.983 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'sending control
file 'cfA998client1.ipaddress' to pr1@pr1'
15:48:33.017 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'completed sending
'cfA998client1.ipaddress' to pr1@pr1'
15:48:33.017 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'sending data file
'dfA998client1.ipaddress' to pr1@pr1'
15:50:02.618 client1 [1016] (Worker - Remote) pr1: setstatus: msg 'job
'barry@client1+998' transfer to pr1@pr1 failed
error 'ERROR TRANSFERRING DATA'
sending data file 'dfA998client1.ipaddress' to pr1@pr1'
15:50:02.618 client1 [1016] (Worker - Remote) pr1: Remote_job:
cfA998client1.ipaddress, status 'ERROR TRANSFERRING DATA'
15:50:02.619 client1 [1016] (Worker - Remote) pr1: Make_temp_fd_in_dir: fd 5, name
'/var/spool/lpd/pr1/temp02t0Mivf'
15:50:02.619 client1 [1016] (Worker - Remote) pr1: Do_accounting: command 'jobend $H
$n $P $k $b $t', af 'acct', expanded 'jobend '-Hclient1.ipaddress' '-nbarry' '-Ppr1'
'-kcfA998client1.ipaddress' '-b567743' '-t 15:50:02.000''
15:50:02.619 client1 [1016] (Worker - Remote) pr1: cleanup: done, exit(32)
15:50:02.619 client1 [999] (Server) pr1: Wait_for_subserver: pid 1016, status 'exit
status 32 (JFAIL)'
15:50:02.619 client1 [999] (Server) pr1: Wait_for_subserver: pid 1016 final status
JFAIL
15:50:02.619 client1 [999] (Server) pr1: setstatus: msg 'subserver pid 1016 exit
status 'JFAIL''
15:50:02.620 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:50:02.620 client1 [999] (Server) pr1: Wait_for_subserver: server pid 1016 for
'pr1' for 'hfA998' '<NULL>' finished
15:50:02.620 client1 [999] (Server) pr1: setmessage: msg 'EXITSTATUS JFAIL'
15:50:02.620 client1 [999] (Server) pr1: Update_status: again - status 'JFAIL', id
'cfA998client1.ipaddress', dest id '<NULL>'
15:50:02.620 client1 [999] (Server) pr1: setmessage: msg 'PROCESSSTATUS JFAIL'
15:50:02.620 client1 [999] (Server) pr1: Update_status: JFAIL - attempt 3, max 3
15:50:02.620 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp004j2jCN'
15:50:02.620 client1 [999] (Server) pr1: setstatus: msg 'job
'cfA998client1.ipaddress', attempt 3, allowed 3'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure:
send_failure_action 'remove'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: outstr 'remove'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'succ'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'jsucc'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'success'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'jsuccess'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'abort'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'jabort'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'hold'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'jhold'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: comparing 'remove'
to 'remove'
15:50:02.620 client1 [999] (Server) pr1: Decode_transfer_failure: result 'JREMOVE'
15:50:02.620 client1 [999] (Server) pr1: setstatus: msg 'send_failure_action
'JREMOVE''
15:50:02.621 client1 [999] (Server) pr1: setstatus: msg 'job
'cfA998client1.ipaddress', removing job - status JREMOVE'
15:50:02.621 client1 [999] (Server) pr1: Update_status: again - status 'JREMOVE', id
'cfA998client1.ipaddress', dest id '<NULL>'
15:50:02.621 client1 [999] (Server) pr1: setmessage: msg 'PROCESSSTATUS JREMOVE'
15:50:02.621 client1 [999] (Server) pr1: Make_temp_fd_in_dir: fd 6, name
'/var/spool/lpd/pr1/temp01DCTEgu'
15:50:02.621 client1 [999] (Server) pr1: setstatus: msg 'removing job
'cfA998client1.ipaddress' - JREMOVE'
15:50:02.621 client1 [999] (Server) pr1: setmessage: msg 'REMOVE'
15:50:02.621 client1 [999] (Server) pr1: setmessage: msg 'remove START'
15:50:02.622 client1 [999] (Server) pr1: setmessage: msg 'remove SUCCESS'
15:50:02.622 client1 [999] (Server) pr1: Update_spool_info: file 'control.pr1'
15:50:02.622 client1 [999] (Server) pr1: Do_queue_jobs: MAIN LOOP
15:50:02.622 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 before scan 0
15:50:02.622 client1 [999] (Server) pr1: Get_hold_file: checking on 'hfA998'
15:50:02.622 client1 [999] (Server) pr1: Do_queue_jobs: job_to_do -1, use_subserver
-1, working 0
15:50:02.622 client1 [999] (Server) pr1: Do_queue_jobs: nothing to do
15:50:04.628 client1 [999] (Server) pr1: Do_queue_jobs: Susr1 at end 0
15:50:04.628 client1 [999] (Server) pr1: cleanup: done, exit(0)
