IPP/IPPS slow printing
Hello,
I'm a Product Engineer at PaperCut Software working on the core print infrastructure for PaperCut Pocket and PaperCut Hive platforms. We are supporting a customer who is experiencing slow printing on a HP E77660 device printing over IPP. Based on the customer's tests printing the same document on the same device using a Windows queue takes about 1 min 20 seconds using a HP PCL6 V3 driver compared to 4 minutes 14 seconds when printed directly over IPP from PaperCut Hive. We have already conducted an internal investigation on our IPP stack, and found that the printer is spending the majority of the time (close to 4 minutes) printing the job once our IPP stack has delivered the document to the printer.
We have attempted to reproduce the issue on the same model running the same version of firmware (5.3) using the same document, and we got 1 min 30 seconds and 1 min 06 seconds on consecutive attempts, the difference of which could be the time it took for the device to warm up. However, we haven't been able to explain the discrepancy between the performance of the device at our offices and the customer site.
We're looking for help investigating the root cause and possible solutions for this. We're printing PDF documents over IPP.
Please find the report generated by our IPP stack below:
get-printer-attributes: received supported printer attributes, time - 1.005551s
create-job: create-job response status code: 0, jobId: 1, time - 995.7399ms
get-job-attributes: job state: 5, time - 63.2598ms
get-job-attributes: job state: 5, time - 61.4524ms
send-document: send-document response status code: 0, time - 4.6867755s
get-job-attributes: job state: 5, time - 48.038ms
get-job-attributes: job state: 5, time - 46.0054ms
get-job-attributes: job state: 5, time - 48.2531ms
get-job-attributes: job state: 5, time - 45.5572ms
get-job-attributes: job state: 5, time - 47.4273ms
get-job-attributes: job state: 5, time - 62.6549ms
get-job-attributes: job state: 5, time - 46.0297ms
get-job-attributes: job state: 5, time - 46.1437ms
get-job-attributes: job state: 5, time - 47.5627ms
get-job-attributes: job state: 5, time - 61.494ms
get-job-attributes: job state: 5, time - 59.7553ms
get-job-attributes: job state: 5, time - 61.1225ms
get-job-attributes: job state: 5, time - 46.8065ms
get-job-attributes: job state: 5, time - 61.2665ms
get-job-attributes: job state: 5, time - 46.1633ms
get-job-attributes: job state: 5, time - 46.2163ms
get-job-attributes: job state: 5, time - 45.3871ms
get-job-attributes: job state: 5, time - 46.6394ms
get-job-attributes: job state: 5, time - 47.3565ms
get-job-attributes: job state: 5, time - 69.1993ms
get-job-attributes: job state: 5, time - 67.4254ms
get-job-attributes: job state: 5, time - 44.9803ms
get-job-attributes: job state: 5, time - 46.2063ms
get-job-attributes: job state: 5, time - 45.5689ms
get-job-attributes: job state: 5, time - 45.8026ms
get-job-attributes: job state: 5, time - 77.0167ms
get-job-attributes: job state: 5, time - 75.8083ms
get-job-attributes: job state: 5, time - 46.2364ms
get-job-attributes: job state: 5, time - 45.578ms
get-job-attributes: job state: 5, time - 45.6085ms
get-job-attributes: job state: 5, time - 45.8195ms
get-job-attributes: job state: 5, time - 425.272ms
get-job-attributes: job state: 5, time - 47.2212ms
get-job-attributes: job state: 5, time - 61.6209ms
get-job-attributes: job state: 5, time - 46.0803ms
get-job-attributes: job state: 5, time - 47.4577ms
get-job-attributes: job state: 5, time - 46.4698ms
get-job-attributes: job state: 5, time - 45.761ms
get-job-attributes: job state: 5, time - 62.3103ms
get-job-attributes: job state: 5, time - 62.0781ms
get-job-attributes: job state: 5, time - 61.2551ms
get-job-attributes: job state: 5, time - 46.5687ms
get-job-attributes: job state: 5, time - 47.1626ms
get-job-attributes: job state: 5, time - 47.2865ms
get-job-attributes: job state: 5, time - 45.7183ms
get-job-attributes: job state: 5, time - 44.9254ms
get-job-attributes: job state: 5, time - 47.2107ms
get-job-attributes: job state: 5, time - 77.9795ms
get-job-attributes: job state: 5, time - 46.2157ms
get-job-attributes: job state: 5, time - 48.256ms
get-job-attributes: job state: 5, time - 60.664ms
get-job-attributes: job state: 5, time - 48.2523ms
get-job-attributes: job state: 5, time - 45.7932ms
get-job-attributes: job state: 5, time - 47.1202ms
get-job-attributes: job state: 5, time - 46.9633ms
get-job-attributes: job state: 5, time - 62.2022ms
get-job-attributes: job state: 5, time - 45.7315ms
get-job-attributes: job state: 5, time - 46.798ms
get-job-attributes: job state: 5, time - 45.479ms
get-job-attributes: job state: 5, time - 47.8318ms
get-job-attributes: job state: 5, time - 46.5443ms
get-job-attributes: job state: 5, time - 48.296ms
get-job-attributes: job state: 5, time - 46.9974ms
get-job-attributes: job state: 5, time - 46.9635ms
get-job-attributes: job state: 5, time - 46.3166ms
get-job-attributes: job state: 5, time - 45.8801ms
get-job-attributes: job state: 5, time - 46.8118ms
get-job-attributes: job state: 5, time - 45.9859ms
get-job-attributes: job state: 5, time - 61.041ms
get-job-attributes: job state: 5, time - 46.1109ms
get-job-attributes: job state: 5, time - 48.1071ms
get-job-attributes: job state: 5, time - 45.2414ms
get-job-attributes: job state: 5, time - 47.0253ms
get-job-attributes: job state: 5, time - 45.1994ms
get-job-attributes: job state: 5, time - 46.8742ms
get-job-attributes: job state: 5, time - 60.2882ms
get-job-attributes: job state: 5, time - 46.1002ms
get-job-attributes: job state: 5, time - 47.074ms
get-job-attributes: job state: 5, time - 47.2925ms
get-job-attributes: job state: 5, time - 60.9162ms
get-job-attributes: job state: 5, time - 47.9046ms
get-job-attributes: job state: 5, time - 61.9599ms
get-job-attributes: job state: 5, time - 46.1926ms
get-job-attributes: job state: 5, time - 46.685ms
get-job-attributes: job state: 5, time - 47.1065ms
get-job-attributes: job state: 5, time - 532.9842ms
get-job-attributes: job state: 5, time - 47.4393ms
get-job-attributes: job state: 5, time - 47.6723ms
get-job-attributes: job state: 5, time - 47.5761ms
get-job-attributes: job state: 5, time - 46.4918ms
get-job-attributes: job state: 5, time - 46.1946ms
get-job-attributes: job state: 5, time - 50.9838ms
get-job-attributes: job state: 5, time - 61.2648ms
get-job-attributes: job state: 5, time - 46.2589ms
get-job-attributes: job state: 5, time - 45.7666ms
get-job-attributes: job state: 5, time - 46.0645ms
get-job-attributes: job state: 5, time - 46.1369ms
get-job-attributes: job state: 5, time - 46.3149ms
get-job-attributes: job state: 5, time - 46.9399ms
get-job-attributes: job state: 5, time - 47.7516ms
get-job-attributes: job state: 5, time - 46.3045ms
get-job-attributes: job state: 5, time - 60.6889ms
get-job-attributes: job state: 5, time - 48.1045ms
get-job-attributes: job state: 5, time - 46.9316ms
get-job-attributes: job state: 5, time - 59.7312ms
get-job-attributes: job state: 5, time - 109.3208ms
get-job-attributes: job state: 5, time - 45.6223ms
get-job-attributes: job state: 5, time - 47.2682ms
get-job-attributes: job state: 5, time - 46.8001ms
get-job-attributes: job state: 5, time - 46.6772ms
get-job-attributes: job state: 9, time - 60.4371ms