[cups] Why is 10 page print job taking over an hour?

Thu Jun 20 08:04:01 PDT 2019

These log entries below from the page_log file show two 10 page print jobs, 167347 and 167348.   Job 167347 completed within a minute. Then job 167348 begins at 8:38 AM, the final entry is 9:47 AM (See output below).  Can anyone tell me what could be causing the print job to take an hour?

I've since enabled debug logging, which will hopefully provide more detail.

hp9-prt dbs 167437 [19/Jun/2019:08:38:13 -0400] 1 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:16 -0400] 2 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:18 -0400] 3 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:19 -0400] 4 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:19 -0400] 5 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:20 -0400] 6 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:20 -0400] 7 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:20 -0400] 8 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:20 -0400] 9 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:21 -0400] 10 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:22 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:24 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:25 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:27 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:31 -0400] total 2 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:36 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167437 [19/Jun/2019:08:38:44 -0400] total 10 - 8.60.9.146 (stdin) - -

grep 167438 page_log
hp9-prt dbs 167438 [19/Jun/2019:08:39:34 -0400] 1 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:36 -0400] 2 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:38 -0400] 3 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:39 -0400] 4 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:39 -0400] 5 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:40 -0400] 6 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:40 -0400] 7 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:40 -0400] 8 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:41 -0400] 9 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:41 -0400] 10 1 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:43 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:44 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:45 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:48 -0400] total 0 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:51 -0400] total 2 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:39:56 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:05 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:06 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:08 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:10 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:13 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:19 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:27 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:28 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:30 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:32 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:35 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:41 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:49 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:50 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:08:40:52 -0400] total 6 - 8.60.9.146 (stdin) - -

HUNDREDS OF ADDITIONAL LOG ENTRIES HERE.....................

hp9-prt dbs 167438 [19/Jun/2019:09:47:05 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:09:47:10 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:09:47:19 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:09:47:20 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:09:47:21 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:09:47:24 -0400] total 6 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:09:47:27 -0400] total 9 - 8.60.9.146 (stdin) - -
hp9-prt dbs 167438 [19/Jun/2019:09:47:33 -0400] total 10 - 8.60.9.146 (stdin) - -

******************************************************************************************
This message may contain confidential or proprietary information intended only for the use of the
addressee(s) named above or may contain information that is legally privileged. If you are
not the intended addressee, or the person responsible for delivering it to the intended addressee,
you are hereby notified that reading, disseminating, distributing or copying this message is strictly
prohibited. If you have received this message by mistake, please immediately notify us by
replying to the message and delete the original message and any copies immediately thereafter.

If you received this email as a commercial message and would like to opt out of future commercial
messages, please let us know and we will remove you from our distribution list.

Thank you.~
******************************************************************************************
FAFLD