After using for some time ifhp with different models of HP printers without any problem on page counting, with the HP-4100 the usual mechanism for counting pages does not work correctly.
It seems that the process does not wait in the Do_waitend routine for job to be completed. Normally it count 0 pages in short jobs of 1 or 2 pages, and in other larger it always counts less. I think that HP has changed the behawiour of the HP-4100 printers, but maybe a little modification into the Do_waitend lines would solve this problem. A solution increasing the pagecount_poll parameter makes the printer to work too slowly. The problem has been proved in several HP-4100 printers. The version of firmware in all of them is: HP JetDirect: J4169A Firmware Version: L.20.24 With the HP-4000 and all others HP laser printers that I have, everything works correctly. It has been tried with the versions 3.3.10 and 3.4.7 of ifhp filter and the problem is the same. I attach the log file of the process, first for a HP-4100 that works badly and later for a HP-4000 that works correctly. Could anyone help me with this problem. Thanks in advance, Fernando Blanco
################################################################################################ HP-4100. ifhp 3.4.7 #:inf2:/sprt/LPRng/PRUEBAS_NUEVAS_VERSIONES/ifhp-3.4.7/src>echo "TEST" | ./ifhp -Tdev=laser4a%9100,trace,debug,logall,config=../ifhp.conf,mode l=hp4000 ifhp 18:35:59.841 [16964] main: using model 'hp4000' ifhp 18:36:00.859 [16964] Check_pagecount: pagecount using 'pjl info pagecount' ifhp 18:36:00.859 [16964] Process_job: setting up printer ifhp 18:36:00.859 [16964] Do_sync: getting sync using 'pjl echo' ifhp 18:36:01.018 [16964] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:36:01.018 [16964] Pr_status: printer status 'CODE=10001' ifhp 18:36:01.018 [16964] Pr_status: printer status 'DISPLAY="Done: :"' ifhp 18:36:01.018 [16964] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:36:01.018 [16964] Pr_status: printer status '' ifhp 18:36:01.701 [16964] Pr_status: printer status '@PJL ECHO [EMAIL PROTECTED]' ifhp 18:36:01.701 [16964] Pr_status: printer status '' ifhp 18:36:01.701 [16964] Do_sync: sync done ifhp 18:36:01.720 [16964] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:36:01.720 [16964] Pr_status: printer status 'CODE=10001' ifhp 18:36:01.720 [16964] Pr_status: printer status 'DISPLAY=":"' ifhp 18:36:01.720 [16964] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:36:01.720 [16964] Pr_status: printer status '' ifhp 18:36:01.915 [16964] Pr_status: printer status '@PJL INFO PAGECOUNT' ifhp 18:36:01.916 [16964] Pr_status: printer status '113' ifhp 18:36:01.916 [16964] Pr_status: printer status '' ifhp 18:36:01.916 [16964] Do_pagecount: pagecounter 113 after 1 attempts ifhp 18:36:01.916 [16964] Do_accounting: pagecounter 113 ifhp 18:36:01.917 [16964] Process_job: sending job file ifhp 18:36:01.917 [16964] Send_job: starting transfer ifhp 18:36:01.919 [16964] Use_file_util: file program = '/sprt/LPRng/file-3.27/file -m /sprt/LPRng/file-3.27/magic -' ifhp 18:36:01.921 [16964] Filter_file: started FILE_UTIL- 'file' ifhp 18:36:01.957 [16964] Use_file_util: file information = 'ascii_text' ifhp 18:36:01.957 [16964] Send_job: initial job type 'ascii_text' ifhp 18:36:01.958 [16964] Send_job: decoded job type 'PCL' ifhp 18:36:01.958 [16964] Send_job: job type 'PCL' ifhp 18:36:01.959 [16964] Send_job: transferring 5 bytes ifhp 18:36:01.959 [16964] Send_job: 100 percent done ifhp 18:36:01.959 [16964] Send_job: data sent ifhp 18:36:01.959 [16964] Process_job: sent job file ifhp 18:36:01.960 [16964] Do_waitend: getting end using 'pjl job/eoj' ifhp 18:36:02.404 [16964] Pr_status: printer status '@PJL INFO PAGECOUNT' ifhp 18:36:02.404 [16964] Pr_status: printer status '113' ifhp 18:36:02.404 [16964] Pr_status: printer status '' ifhp 18:36:03.671 [16964] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:36:03.671 [16964] Pr_status: printer status 'CODE=10023' ifhp 18:36:03.671 [16964] Pr_status: printer status 'DISPLAY="PROCESANDO TRBJO DE BANDEJA 2"' ifhp 18:36:03.671 [16964] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:36:03.671 [16964] Pr_status: printer status '' ifhp 18:36:04.176 [16964] Pr_status: printer status '@PJL USTATUS JOB' ifhp 18:36:04.176 [16964] Pr_status: printer status 'END' ifhp 18:36:04.176 [16964] Pr_status: printer status 'NAME=":"' ifhp 18:36:04.176 [16964] Pr_status: printer status 'PAGES=0' ifhp 18:36:04.176 [16964] Pr_status: printer status '' ifhp 18:36:04.773 [16964] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:36:04.774 [16964] Pr_status: printer status 'CODE=10030' ifhp 18:36:04.774 [16964] Pr_status: printer status 'DISPLAY=":"' ifhp 18:36:04.774 [16964] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:36:04.774 [16964] Pr_status: printer status '' ifhp 18:36:04.916 [16964] Pr_status: printer status '@PJL USTATUS JOB' ifhp 18:36:04.916 [16964] Pr_status: printer status 'START' ifhp 18:36:04.916 [16964] Pr_status: printer status 'NAME="18-36-01.960 PID 16964"' ifhp 18:36:04.916 [16964] Pr_status: printer status '' ifhp 18:36:04.916 [16964] Pr_status: printer status '@PJL USTATUS JOB' ifhp 18:36:04.916 [16964] Pr_status: printer status 'END' ifhp 18:36:04.917 [16964] Pr_status: printer status 'NAME="18-36-01.960 PID 16964"' ifhp 18:36:04.917 [16964] Pr_status: printer status 'PAGES=0' ifhp 18:36:04.917 [16964] Pr_status: printer status '' ifhp 18:36:04.917 [16964] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:36:04.917 [16964] Pr_status: printer status 'CODE=10023' ifhp 18:36:04.917 [16964] Pr_status: printer status 'DISPLAY="PROCESANDO TRBJO DE BANDEJA 2"' ifhp 18:36:04.917 [16964] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:36:04.918 [16964] Pr_status: printer status '' ifhp 18:36:04.918 [16964] Do_waitend: end of job detected ifhp 18:36:05.700 [16964] Pr_status: printer status '@PJL INFO PAGECOUNT' ifhp 18:36:05.701 [16964] Pr_status: printer status '113' ifhp 18:36:05.701 [16964] Pr_status: printer status '' ifhp 18:36:05.701 [16964] Do_pagecount: pagecounter 113 after 1 attempts ifhp 18:36:05.702 [16964] Do_accounting: pagecounter 113, pages 0 ifhp 18:36:05.702 [16964] Process_job: done #:inf2:/sprt/LPRng/PRUEBAS_NUEVAS_VERSIONES/ifhp-3.4.7/src> ################################################################################################ HP-4100. ifhp 3.4.7 #:inf2:/sprt/LPRng/PRUEBAS_NUEVAS_VERSIONES/ifhp-3.4.7/src>echo "TEST" | ./ifhp -Tdev=laser22b%9100,trace,debug,logall,config=../ifhp.conf,mod el=hp4000 ifhp 18:42:14.862 [19553] main: using model 'hp4000' ifhp 18:42:15.874 [19553] Check_pagecount: pagecount using 'pjl info pagecount' ifhp 18:42:15.874 [19553] Process_job: setting up printer ifhp 18:42:15.874 [19553] Do_sync: getting sync using 'pjl echo' ifhp 18:42:17.184 [19553] Pr_status: printer status '@PJL ECHO [EMAIL PROTECTED]' ifhp 18:42:17.185 [19553] Pr_status: printer status '' ifhp 18:42:17.185 [19553] Do_sync: sync done ifhp 18:42:17.198 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:17.198 [19553] Pr_status: printer status 'CODE=10023' ifhp 18:42:17.198 [19553] Pr_status: printer status 'DISPLAY="PROCESANDO TRABAJO"' ifhp 18:42:17.198 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:17.198 [19553] Pr_status: printer status '' ifhp 18:42:17.375 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:17.375 [19553] Pr_status: printer status 'CODE=10001' ifhp 18:42:17.376 [19553] Pr_status: printer status 'DISPLAY=":"' ifhp 18:42:17.376 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:17.376 [19553] Pr_status: printer status '' ifhp 18:42:17.575 [19553] Pr_status: printer status '@PJL INFO PAGECOUNT' ifhp 18:42:17.575 [19553] Pr_status: printer status '154273' ifhp 18:42:17.576 [19553] Pr_status: printer status '' ifhp 18:42:17.576 [19553] Do_pagecount: pagecounter 154273 after 1 attempts ifhp 18:42:17.576 [19553] Do_accounting: pagecounter 154273 ifhp 18:42:17.577 [19553] Process_job: sending job file ifhp 18:42:17.577 [19553] Send_job: starting transfer ifhp 18:42:17.579 [19553] Use_file_util: file program = '/sprt/LPRng/file-3.27/file -m /sprt/LPRng/file-3.27/magic -' ifhp 18:42:17.581 [19553] Filter_file: started FILE_UTIL- 'file' ifhp 18:42:17.620 [19553] Use_file_util: file information = 'ascii_text' ifhp 18:42:17.621 [19553] Send_job: initial job type 'ascii_text' ifhp 18:42:17.621 [19553] Send_job: decoded job type 'PCL' ifhp 18:42:17.621 [19553] Send_job: job type 'PCL' ifhp 18:42:17.622 [19553] Send_job: transferring 5 bytes ifhp 18:42:17.622 [19553] Send_job: 100 percent done ifhp 18:42:17.623 [19553] Send_job: data sent ifhp 18:42:17.623 [19553] Process_job: sent job file ifhp 18:42:17.623 [19553] Do_waitend: getting end using 'pjl job/eoj' ifhp 18:42:19.183 [19553] Pr_status: printer status '@PJL INFO PAGECOUNT' ifhp 18:42:19.183 [19553] Pr_status: printer status '154273' ifhp 18:42:19.184 [19553] Pr_status: printer status '' ifhp 18:42:19.376 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:19.376 [19553] Pr_status: printer status 'CODE=10023' ifhp 18:42:19.376 [19553] Pr_status: printer status 'DISPLAY="PROCESANDO TRABAJO"' ifhp 18:42:19.377 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:19.377 [19553] Pr_status: printer status '' ifhp 18:42:19.576 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:19.576 [19553] Pr_status: printer status 'CODE=10001' ifhp 18:42:19.576 [19553] Pr_status: printer status 'DISPLAY=":"' ifhp 18:42:19.577 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:19.577 [19553] Pr_status: printer status '' ifhp 18:42:19.776 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:19.776 [19553] Pr_status: printer status 'CODE=10023' ifhp 18:42:19.777 [19553] Pr_status: printer status 'DISPLAY="PROCESANDO TRBJO DE BANDEJA 2"' ifhp 18:42:19.777 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:19.777 [19553] Pr_status: printer status '' ifhp 18:42:19.976 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:19.976 [19553] Pr_status: printer status 'CODE=10030' ifhp 18:42:19.976 [19553] Pr_status: printer status 'DISPLAY=":"' ifhp 18:42:19.977 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:19.977 [19553] Pr_status: printer status '' ifhp 18:42:20.175 [19553] Pr_status: printer status '@PJL USTATUS JOB' ifhp 18:42:20.175 [19553] Pr_status: printer status 'START' ifhp 18:42:20.175 [19553] Pr_status: printer status 'NAME="18-42-17.624 PID 19553"' ifhp 18:42:20.176 [19553] Pr_status: printer status '' ifhp 18:42:20.375 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:20.375 [19553] Pr_status: printer status 'CODE=10023' ifhp 18:42:20.375 [19553] Pr_status: printer status 'DISPLAY="PROCESANDO TRBJO DE BANDEJA 2"' ifhp 18:42:20.376 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:20.376 [19553] Pr_status: printer status '' ifhp 18:42:29.453 [19553] Pr_status: printer status '@PJL USTATUS TIMED' ifhp 18:42:29.454 [19553] Pr_status: printer status 'CODE=10023' ifhp 18:42:29.454 [19553] Pr_status: printer status 'DISPLAY="PROCESANDO TRBJO DE BANDEJA 2"' ifhp 18:42:29.454 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:29.454 [19553] Pr_status: printer status '' ifhp 18:42:35.365 [19553] Pr_status: printer status '@PJL USTATUS DEVICE' ifhp 18:42:35.365 [19553] Pr_status: printer status 'CODE=10001' ifhp 18:42:35.365 [19553] Pr_status: printer status 'DISPLAY=":"' ifhp 18:42:35.365 [19553] Pr_status: printer status 'ONLINE=TRUE' ifhp 18:42:35.366 [19553] Pr_status: printer status '' ifhp 18:42:35.376 [19553] Pr_status: printer status '@PJL USTATUS PAGE' ifhp 18:42:35.376 [19553] Pr_status: printer status '1' ifhp 18:42:35.377 [19553] Pr_status: printer status '' ifhp 18:42:35.576 [19553] Pr_status: printer status '@PJL USTATUS JOB' ifhp 18:42:35.576 [19553] Pr_status: printer status 'END' ifhp 18:42:35.577 [19553] Pr_status: printer status 'NAME=":"' ifhp 18:42:35.577 [19553] Pr_status: printer status 'PAGES=0' ifhp 18:42:35.577 [19553] Pr_status: printer status '' ifhp 18:42:35.776 [19553] Pr_status: printer status '@PJL USTATUS JOB' ifhp 18:42:35.776 [19553] Pr_status: printer status 'END' ifhp 18:42:35.777 [19553] Pr_status: printer status 'NAME="18-42-17.624 PID 19553"' ifhp 18:42:35.777 [19553] Pr_status: printer status 'PAGES=0' ifhp 18:42:35.777 [19553] Pr_status: printer status '' ifhp 18:42:35.777 [19553] Do_waitend: end of job detected ifhp 18:42:37.183 [19553] Pr_status: printer status '@PJL INFO PAGECOUNT' ifhp 18:42:37.183 [19553] Pr_status: printer status '154274' ifhp 18:42:37.184 [19553] Pr_status: printer status '' ifhp 18:42:37.184 [19553] Do_pagecount: pagecounter 154274 after 1 attempts ifhp 18:42:37.184 [19553] Do_accounting: pagecounter 154274, pages 1 ifhp 18:42:37.184 [19553] Process_job: done #:inf2:/sprt/LPRng/PRUEBAS_NUEVAS_VERSIONES/ifhp-3.4.7/src>
