I'm trying to get accounting on the network printer Xerox N2125.
My linux box now is a RedHat 7.1 and I installed LPRng-3.7.4-22 and ifhp-3.4.4-2
I have problems to get the right pagecount at the end of the job (it
results to be the same as at the start of the job): this is a piece of the acct file:
START 'A=root@cesaro+442' 'P=cdc4n' 'n=root' 'H=cesaro.dm.unipi.it' 'D=1015856729'
filestart '-q31446' '-p175397' '-t2002-03-11-15:25:29.867' '-Aroot@cesaro+442'
'-nroot' '-Pcdc4n'
fileend '-b0' '-T2' '-q31446' '-p175397' '-t2002-03-11-15:25:31.870'
'-Aroot@cesaro+442' '-nroot' '-Pcdc4n'
END 't=3' 'p=0' 's=175397' 'q=175397' 'D=1015856732' 'A=root@cesaro+442' 'P=cdc4n'
'n=root' 'H=cesaro.dm.unipi.it' 'S=1015856729'
My printcap.local file is:
cdc4n:\
:sh:\
:ml=0:\
:mx=0:\
:sd=/var/spool/lpd/cdc4n:\
:af=acct:\
:as=|/usr/libexec/filters/accounting.pl start:\
:ae=|/usr/libexec/filters/accounting.pl end:\
:lp=cdc4%9100:\
:lpd_bounce=true:\
:ifhp=if_option=pagecount,of_options=pagecount,debug=3:\
:if=/usr/lib/lpfilters/ifhp:
P.Powell suggested me to add the debug=3 option and I obtained the stuff
you find in attachment (this doesn't seems to help me). Does anyone know
why I obtain the same pagecount at the start and at the end of the job ?
(Anyway,the job has been printed correctly and if I print another job
I see the pagecount has been increased by the number of pages of
the previous job)
Alessandra La Spina
--
=========================================
Alessandra La Spina
Dipartimento di Matematica
Universita' di Pisa
tel. +39-050-844263
e-mail: [EMAIL PROTECTED]
www: http://www.dm.unipi.it/cdc/index.html
Printer: cdc4n@cesaro
Queue: no printable jobs in queue
Status: printing finished at 15:19:30.282
Status: accounting at end at 15:19:30.284
Status: Filter_file: pgm '|/usr/libexec/filters/accounting.pl end' exited with status
'JSUCC' at 15:19:30.926
Status: finished 'root@cesaro+394', status 'JSUCC' at 15:19:30.927
Status: subserver pid 31396 exit status 'JSUCC' at 15:19:30.929
Status: [EMAIL PROTECTED]: job 'cfA394cesaro.dm.unipi.it' printed at
15:19:30.945
Status: job 'cfA394cesaro.dm.unipi.it' removed at 15:19:30.971
Status: waiting for subserver to exit at 15:25:28.912
Status: subserver pid 31444 starting at 15:25:28.943
Status: accounting at start at 15:25:28.944
Status: Filter_file: pgm '|/usr/libexec/filters/accounting.pl start' exited with
status 'JSUCC' at 15:25:29.603
Status: opening device 'cdc4%9100' at 15:25:29.604
Status: printing job 'root@cesaro+442' at 15:25:29.620
Status: processing 'dfA442cesaro.dm.unipi.it', size 145, format 'f', IF filter 'ifhp'
at 15:25:29.621
Status: IF filter 'ifhp' filter msg - 'Version ifhp-3.4.4' at 15:25:29.706
Status: IF filter 'ifhp' filter msg - '$Id: ifhp.c,v 1.74 2000/12/25 01:53:06
papowell Exp papowell $' at 15:25:29.707
Status: IF filter 'ifhp' filter msg - 'Copyright 1992-2000 Patrick Powell
<[EMAIL PROTECTED]>' at 15:25:29.707
Status: IF filter 'ifhp' filter finished at 15:25:31.872
Status: printing finished at 15:25:31.873
Status: accounting at end at 15:25:31.875
Status: Filter_file: pgm '|/usr/libexec/filters/accounting.pl end' exited with status
'JSUCC' at 15:25:32.542
Status: finished 'root@cesaro+442', status 'JSUCC' at 15:25:32.542
Status: subserver pid 31444 exit status 'JSUCC' at 15:25:32.545
Status: [EMAIL PROTECTED]: job 'cfA442cesaro.dm.unipi.it' printed at
15:25:32.560
Status: job 'cfA442cesaro.dm.unipi.it' removed at 15:25:32.586
Filter_status: utmax 10240, Outlen 0 at 15:25:29.961
Filter_status: Pjl_job: pjl_console '1' at 15:25:29.961
Filter_status: Pjl_eoj: Pjl 1, job '1', flag 1 at 15:25:29.961
Filter_status: Do_waitend: sending '^[%-12345X@PJL
Filter_status: @PJL JOB NAME = "15-25-29.961 PID 31446" DISPLAY = "root"
Filter_status: @PJL USTATUS JOB = ON
Filter_status: @PJL EOJ NAME = "15-25-29.961 PID 31446"
Filter_status: ^[%-12345X' at 15:25:29.962
Filter_status: Do_waitend: Outlen 144 at 15:25:29.962
Filter_status: Init_outbuf: Outbuf 0x8067ec0, Outmax 10240, Outlen 144 at
15:25:29.962
Filter_status: Do_waitend: len 0 at 15:25:29.962
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 4, max 102, list
0x8071ac0 at 15:25:29.963
Filter_status: [ 0]='code=10023' at 15:25:29.963
Filter_status: [ 1]='display="Elabora..."' at 15:25:29.963
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:29.963
Filter_status: [ 3]='pagecount=175397' at 15:25:29.963
Filter_status: Do_waitend: Outlen 0 at 15:25:29.963
Filter_status: Read_status_timeout: timeout 300, read() returned 51, count 51 at
15:25:30.800
Filter_status: Read_status_timeout: read count 51, '@PJL USTATUS JOB^M
Filter_status: START^M
Filter_status: NAME="PID 31446"^M
Filter_status: ID=61^M
Filter_status: ^L' at 15:25:30.801
Filter_status: Do_waitend: len 0 at 15:25:30.801
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:30.801
Filter_status: [ 0]='code=10023' at 15:25:30.801
Filter_status: [ 1]='display="Elabora..."' at 15:25:30.802
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:30.802
Filter_status: [ 3]='id=61' at 15:25:30.802
Filter_status: [ 4]='job=START' at 15:25:30.802
Filter_status: [ 5]='name="PID 31446"' at 15:25:30.802
Filter_status: [ 6]='pagecount=175397' at 15:25:30.802
Filter_status: Do_waitend: Outlen 0 at 15:25:30.803
Filter_status: Read_status_timeout: timeout 299, read() returned 71, count 71 at
15:25:30.858
Filter_status: Read_status_timeout: read count 71, '@PJL USTATUS DEVICE^M
Filter_status: CODE=10023^M
Filter_status: DISPLAY="Elabora..."^M
Filter_status: ONLINE = TRUE^M
Filter_status: ^L' at 15:25:30.858
Filter_status: Do_waitend: len 0 at 15:25:30.859
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:30.859
Filter_status: [ 0]='code=10023' at 15:25:30.859
Filter_status: [ 1]='display="Elabora..."' at 15:25:30.859
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:30.859
Filter_status: [ 3]='id=61' at 15:25:30.859
Filter_status: [ 4]='job=START' at 15:25:30.859
Filter_status: [ 5]='name="PID 31446"' at 15:25:30.860
Filter_status: [ 6]='pagecount=175397' at 15:25:30.860
Filter_status: Do_waitend: Outlen 0 at 15:25:30.860
Filter_status: Read_status_timeout: timeout 299, read() returned 71, count 71 at
15:25:30.918
Filter_status: Read_status_timeout: read count 71, '@PJL USTATUS DEVICE^M
Filter_status: CODE=10023^M
Filter_status: DISPLAY="Elabora..."^M
Filter_status: ONLINE = TRUE^M
Filter_status: ^L' at 15:25:30.918
Filter_status: Do_waitend: len 0 at 15:25:30.919
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:30.919
Filter_status: [ 0]='code=10023' at 15:25:30.919
Filter_status: [ 1]='display="Elabora..."' at 15:25:30.919
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:30.919
Filter_status: [ 3]='id=61' at 15:25:30.919
Filter_status: [ 4]='job=START' at 15:25:30.920
Filter_status: [ 5]='name="PID 31446"' at 15:25:30.920
Filter_status: [ 6]='pagecount=175397' at 15:25:30.920
Filter_status: Do_waitend: Outlen 0 at 15:25:30.920
Filter_status: Read_status_timeout: timeout 299, read() returned 71, count 71 at
15:25:30.978
Filter_status: Read_status_timeout: read count 71, '@PJL USTATUS DEVICE^M
Filter_status: CODE=10023^M
Filter_status: DISPLAY="Elabora..."^M
Filter_status: ONLINE = TRUE^M
Filter_status: ^L' at 15:25:30.978
Filter_status: Do_waitend: len 0 at 15:25:30.979
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:30.979
Filter_status: [ 0]='code=10023' at 15:25:30.979
Filter_status: [ 1]='display="Elabora..."' at 15:25:30.979
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:30.979
Filter_status: [ 3]='id=61' at 15:25:30.979
Filter_status: [ 4]='job=START' at 15:25:30.980
Filter_status: [ 5]='name="PID 31446"' at 15:25:30.980
Filter_status: [ 6]='pagecount=175397' at 15:25:30.980
Filter_status: Do_waitend: Outlen 0 at 15:25:30.980
Filter_status: Read_status_timeout: timeout 299, read() returned 77, count 77 at
15:25:31.038
Filter_status: Read_status_timeout: read count 77, '@PJL USTATUS DEVICE^M
Filter_status: CODE=10023^M
Filter_status: DISPLAY="root one.ps:cdc4"^M
Filter_status: ONLINE = TRUE^M
Filter_status: ^L' at 15:25:31.038
Filter_status: Do_waitend: len 0 at 15:25:31.039
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:31.039
Filter_status: [ 0]='code=10023' at 15:25:31.039
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.039
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.040
Filter_status: [ 3]='id=61' at 15:25:31.040
Filter_status: [ 4]='job=START' at 15:25:31.040
Filter_status: [ 5]='name="PID 31446"' at 15:25:31.040
Filter_status: [ 6]='pagecount=175397' at 15:25:31.040
Filter_status: Do_waitend: Outlen 0 at 15:25:31.041
Filter_status: Read_status_timeout: timeout 298, read() returned 77, count 77 at
15:25:31.098
Filter_status: Read_status_timeout: read count 77, '@PJL USTATUS DEVICE^M
Filter_status: CODE=10023^M
Filter_status: DISPLAY="root one.ps:cdc4"^M
Filter_status: ONLINE = TRUE^M
Filter_status: ^L' at 15:25:31.098
Filter_status: Do_waitend: len 0 at 15:25:31.099
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:31.099
Filter_status: [ 0]='code=10023' at 15:25:31.099
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.100
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.100
Filter_status: [ 3]='id=61' at 15:25:31.100
Filter_status: [ 4]='job=START' at 15:25:31.100
Filter_status: [ 5]='name="PID 31446"' at 15:25:31.100
Filter_status: [ 6]='pagecount=175397' at 15:25:31.100
Filter_status: Do_waitend: Outlen 0 at 15:25:31.100
Filter_status: Read_status_timeout: timeout 298, read() returned 1, count 1 at
15:25:31.101
Filter_status: Read_status_timeout: read count 1, '^D' at 15:25:31.101
Filter_status: Do_waitend: len 0 at 15:25:31.101
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:31.101
Filter_status: [ 0]='code=10023' at 15:25:31.102
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.102
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.102
Filter_status: [ 3]='id=61' at 15:25:31.102
Filter_status: [ 4]='job=START' at 15:25:31.102
Filter_status: [ 5]='name="PID 31446"' at 15:25:31.102
Filter_status: [ 6]='pagecount=175397' at 15:25:31.102
Filter_status: Do_waitend: Outlen 0 at 15:25:31.103
Filter_status: Read_status_timeout: timeout 298, read() returned 1, count 1 at
15:25:31.176
Filter_status: Read_status_timeout: read count 1, '^D' at 15:25:31.176
Filter_status: Do_waitend: len 0 at 15:25:31.176
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:31.176
Filter_status: [ 0]='code=10023' at 15:25:31.176
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.177
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.177
Filter_status: [ 3]='id=61' at 15:25:31.177
Filter_status: [ 4]='job=START' at 15:25:31.177
Filter_status: [ 5]='name="PID 31446"' at 15:25:31.177
Filter_status: [ 6]='pagecount=175397' at 15:25:31.177
Filter_status: Do_waitend: Outlen 0 at 15:25:31.177
Filter_status: Read_status_timeout: timeout 298, read() returned 77, count 77 at
15:25:31.599
Filter_status: Read_status_timeout: read count 77, '@PJL USTATUS DEVICE^M
Filter_status: CODE=10023^M
Filter_status: DISPLAY="root one.ps:cdc4"^M
Filter_status: ONLINE = TRUE^M
Filter_status: ^L' at 15:25:31.599
Filter_status: Do_waitend: len 0 at 15:25:31.600
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:31.600
Filter_status: [ 0]='code=10023' at 15:25:31.600
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.600
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.601
Filter_status: [ 3]='id=61' at 15:25:31.601
Filter_status: [ 4]='job=START' at 15:25:31.601
Filter_status: [ 5]='name="PID 31446"' at 15:25:31.601
Filter_status: [ 6]='pagecount=175397' at 15:25:31.601
Filter_status: Do_waitend: Outlen 0 at 15:25:31.601
Filter_status: Read_status_timeout: timeout 298, read() returned 77, count 77 at
15:25:31.659
Filter_status: Read_status_timeout: read count 77, '@PJL USTATUS DEVICE^M
Filter_status: CODE=10023^M
Filter_status: DISPLAY="root one.ps:cdc4"^M
Filter_status: ONLINE = TRUE^M
Filter_status: ^L' at 15:25:31.662
Filter_status: Do_waitend: len 0 at 15:25:31.662
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:31.663
Filter_status: [ 0]='code=10023' at 15:25:31.663
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.663
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.663
Filter_status: [ 3]='id=61' at 15:25:31.663
Filter_status: [ 4]='job=START' at 15:25:31.663
Filter_status: [ 5]='name="PID 31446"' at 15:25:31.664
Filter_status: [ 6]='pagecount=175397' at 15:25:31.664
Filter_status: Do_waitend: Outlen 0 at 15:25:31.664
Filter_status: Read_status_timeout: timeout 298, read() returned 64, count 64 at
15:25:31.723
Filter_status: Read_status_timeout: read count 64, '@PJL USTATUS JOB^M
Filter_status: START^M
Filter_status: NAME="15-25-29.961 PID 31446"^M
Filter_status: ID=61^M
Filter_status: ^L' at 15:25:31.723
Filter_status: Do_waitend: len 0 at 15:25:31.724
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 7, max 102, list
0x8071ac0 at 15:25:31.724
Filter_status: [ 0]='code=10023' at 15:25:31.724
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.724
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.724
Filter_status: [ 3]='id=61' at 15:25:31.725
Filter_status: [ 4]='job=START' at 15:25:31.725
Filter_status: [ 5]='name="15-25-29.961 PID 31446"' at 15:25:31.725
Filter_status: [ 6]='pagecount=175397' at 15:25:31.725
Filter_status: Do_waitend: Outlen 0 at 15:25:31.725
Filter_status: Read_status_timeout: timeout 298, read() returned 82, count 82 at
15:25:31.779
Filter_status: Read_status_timeout: read count 82, '@PJL USTATUS JOB^M
Filter_status: END^M
Filter_status: NAME="15-25-29.961 PID 31446"^M
Filter_status: PAGES=0^M
Filter_status: ID=61^M
Filter_status: RESULT=OK^M
Filter_status: ^L' at 15:25:31.780
Filter_status: Do_waitend: len 0 at 15:25:31.781
Filter_status: Dump_line_list: Do_waitend - Devstatus - count 9, max 102, list
0x8071ac0 at 15:25:31.781
Filter_status: [ 0]='code=10023' at 15:25:31.781
Filter_status: [ 1]='display="root one.ps:cdc4"' at 15:25:31.781
Filter_status: [ 2]='[EMAIL PROTECTED]' at 15:25:31.781
Filter_status: [ 3]='id=61' at 15:25:31.781
Filter_status: [ 4]='job=END' at 15:25:31.782
Filter_status: [ 5]='name="15-25-29.961 PID 31446"' at 15:25:31.782
Filter_status: [ 6]='pagecount=175397' at 15:25:31.782
Filter_status: [ 7]='pages=0' at 15:25:31.782
Filter_status: [ 8]='result=OK' at 15:25:31.782
Filter_status: Do_waitend: end of job detected at 15:25:31.782
Filter_status: Current_pagecounter: starting, use_pjl 1, use_ps 0, timeout 0 at
15:25:31.783
Filter_status: Init_outbuf: Outbuf 0x8067ec0, Outmax 10240, Outlen 0 at 15:25:31.783
Filter_status: Current_pagecounter: using '^[%-12345X@PJL
Filter_status: @PJL INFO PAGECOUNT
Filter_status: ^[%-12345X' at 15:25:31.783
Filter_status: Init_outbuf: Outbuf 0x8067ec0, Outmax 10240, Outlen 43 at 15:25:31.784
Filter_status: Current_pagecounter: write len 0 at 15:25:31.784
Filter_status: Read_status_timeout: timeout 20, read() returned 40, count 40 at
15:25:31.867
Filter_status: Read_status_timeout: read count 40, '@PJL INFO PAGECOUNT^M
Filter_status: PAGECOUNT=175397^M
Filter_status: ^L' at 15:25:31.867
Filter_status: Current_pagecounter: write len 0 at 15:25:31.868
Filter_status: Current_pagecounter: page 1, pagecounter 175397 at 15:25:31.868
Filter_status: Do_pagecount: pagecounter 175397 after 1 attempts at 15:25:31.868
Filter_status: Init_outbuf: Outbuf 0x8067ec0, Outmax 10240, Outlen 0 at 15:25:31.869
Filter_status: Pjl_console: flag 1, start 0, at 15:25:31.869
Filter_status: Pjl_console: console msg '' at 15:25:31.869
Filter_status: End_of_job: clearing console at end at 15:25:31.869
Filter_status: Init_outbuf: Outbuf 0x8067ec0, Outmax 10240, Outlen 34 at 15:25:31.870
Filter_status: Do_accounting: pagecounter 175397, pages 0 at 15:25:31.870
Filter_status: Accounting script '<NULL>', file 'acct', npages 0 at 15:25:31.870
Filter_status: Accounting: script '<NULL>', Accountfile 'acct', output 'fileend '-b0'
'-T2' '-q31446' '-p175397' '-t2002-03-11-15:25:31.870'' at 15:25:31.870
Filter_status: Accounting: writing to 4, 'fileend '-b0' '-T2' '-q31446' '-p175397'
'-t2002-03-11-15:25:31.870' '-Aroot@cesaro+442' '-nroot' '-Pcdc4n'' at 15:25:31.871
Filter_status: Process_job: done at 15:25:31.871
Filter_status: cleanup: Signal 'No signal', Errorcode 0 at 15:25:31.871
