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