lpq has bad performance

[sven.loth at 1und1.com]

Update:

I did some tests with 100 printjobs.

an "lpq -a" command takes more than 11 seconds !!!! to return.

real    0m11.011s
user    0m0.023s
sys     0m0.067s


The stacktrace of "lpq -a" shows that the time() function is every second command called by lpq.

Example:

recv(3, "!\0\23job-printer-up-time\0\4A\31\302\316", 2048, 0) = 28
time(NULL)                              = 1092207317
recv(3, "E\0\vprinter-uri\0+ipp://localhost:"..., 2048, 0) = 59
time(NULL)                              = 1092207317
recv(3, "B\0\31job-originating-user-name\0\6ss"..., 2048, 0) = 36
time(NULL)                              = 1092207317
recv(3, "B\0\10job-name\0\26Letter_20040811-085"..., 2048, 0) = 35
time(NULL)                              = 1092207317
recv(3, "I\0\17document-format\0\30application/"..., 2048, 0) = 44
time(NULL)                              = 1092207317
recv(3, "B\0\njob-sheets\0\4noneB\0\0\0\4none", 2048, 0) = 28
time(NULL)                              = 1092207317
recv(3, "!\0\fjob-priority\0\4\0\0\0002", 2048, 0) = 21
time(NULL)                              = 1092207317
recv(3, "B\0\31job-originating-host-name\0\tlo"..., 2048, 0) = 39
time(NULL)                              = 1092207317
recv(3, "!\0\6job-id\0\4\0\0N\243", 2048, 0) = 15
time(NULL)                              = 1092207317
recv(3, "#\0\tjob-state\0\4\0\0\0\3", 2048, 0) = 18
time(NULL)                              = 1092207317
recv(3, "!\0\32job-media-sheets-completed\0\4\0"..., 2048, 0) = 35
time(NULL)                              = 1092207317
recv(3, "E\0\17job-printer-uri\0,http://local"..., 2048, 0) = 64
time(NULL)                              = 1092207317
recv(3, "B\0\10job-name\0\26Letter_20040811-085"..., 2048, 0) = 35
time(NULL)                              = 1092207317
recv(3, "!\0\fjob-k-octets\0\4\0\0\1\374", 2048, 0) = 21
time(NULL)                              = 1092207317
recv(3, "!\0\20time-at-creation\0\4A\31\302\272", 2048, 0) = 25
time(NULL)                              = 1092207317
recv(3, "\23\0\22time-at-processing\0\0", 2048, 0) = 23
time(NULL)                              = 1092207317
recv(3, "\23\0\21time-at-completed\0\0", 2048, 0) = 22
time(NULL)                              = 1092207317
recv(3, "D\0\16job-hold-until\0\7no-hold", 2048, 0) = 26
time(NULL)                              = 1092207317
recv(3, "D\0\21job-state-reasons\0\17printer-st"..., 2048, 0) = 37
time(NULL)                              = 1092207317
recv(3, "\2E\0\rjob-more-info\0\37http://localh"..., 2048, 0) = 50
time(NULL)                              = 1092207317
recv(3, "E\0\7job-uri\0\37http://localhost:631"..., 2048, 0) = 43
time(NULL)                              = 1092207317


Is that a bug or a feature ;-) ?

Sven Loth
Billing-Systeme
1&1 Internet AG