[cups.bugs] Cupsys printer failures: lost jobs

Tom Allison tallison at tacocat.net
Sat Jan 15 05:57:18 PST 2005


This is the debug2 level output for a 23 page document that I was trying
to print.  This is fairly typical on my server.  I also typically do not
get 23 pages.  It's usually 5-8 pages at a time.  The problem has been
increasing to the point where this printer server is almost unusable.

Typically, jobs that are <5 pages are fine, but when you get into print 
jobs in excess of 20 pages you can start seeing problems.  It's not 
consistent either.  In the last ten minutes, after rebooting the server, 
I was able to print 20 pages OK and failed on 7.  Then I sent 4 jobs in 
rapid succession to the printer and they all went into queue and printed 
just fine.  File sizes were on the order of 4, 7, 13 pages respectively 
-- not large.  I'm not printing page ~100 of 236 on a job that initially 
failed without printing one page and no errors.  I restarted it from the 
queue and it died again on page 113.  Logs just keep showing data being 
pushed to the printer well beyond this last page...  UGH!

Memory usage is on the order of 14M Virt, 11M Res, 3M Shr for foomatic 
from top.  'gs' shows about 12M Shared.  I don't see anything to 
indicate that I'm out of memory or disk space.

The only information that isn't presented herein is the fact that the
printer is on a USB port.

This is becoming critical.  Nothing reports an error, yet few print jobs
are working correctly.  After a system reboot, you have a better chance
of success.

Any suggestion?



d [15/Jan/2005:08:12:48 -0500] select_timeout: 1 seconds to send browse
update
d [15/Jan/2005:08:12:49 -0500] select_timeout: 6 seconds to send browse
update
d [15/Jan/2005:08:12:55 -0500] select_timeout: 25 seconds to send browse
update
d [15/Jan/2005:08:13:00 -0500] AcceptClient(lis=0x808cb30) 0 NumClients = 0
D [15/Jan/2005:08:13:00 -0500] AcceptClient: 5 from 192.168.1.10:631.
d [15/Jan/2005:08:13:00 -0500] AcceptClient: Adding fd 5 to InputSet...
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=-1
D [15/Jan/2005:08:13:00 -0500] ReadClient: 5 POST / HTTP/1.1
d [15/Jan/2005:08:13:00 -0500] decode_auth(0x403a4008): Authorization
string = ""
d [15/Jan/2005:08:13:00 -0500] decode_auth: 5 username=""
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: con->uri = "/"
d [15/Jan/2005:08:13:00 -0500] FindBest: uri = "/"...
d [15/Jan/2005:08:13:00 -0500] FindBest: Location / Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /jobs Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /admin Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: best = "/"
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: auth = 0, satisfy=0...
d [15/Jan/2005:08:13:00 -0500] POST /
d [15/Jan/2005:08:13:00 -0500] CONTENT_TYPE = application/ipp
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 137, con->file = -1
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest(0x403a4008[5]):
operation_id = 4002
d [15/Jan/2005:08:13:00 -0500] get_printers(0x403a4008[5], 0)
d [15/Jan/2005:08:13:00 -0500] add_printer_state_reasons(0x403a4008[5],
0x8098868[Color_InkJet])
d [15/Jan/2005:08:13:00 -0500] add_queued_job_count(0x403a4008[5],
0x8098868[Color_InkJet])
d [15/Jan/2005:08:13:00 -0500] copy_attrs(0x8191338, 0x8096098,
0x8191720, 0)
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8191338,
0x8096200[printer-name,4,42])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8191338,
0x8096420[job-sheets-default,4,42])
d [15/Jan/2005:08:13:00 -0500] copy_attrs(0x8191338, 0x808d8c0,
0x8191720, 0)
d [15/Jan/2005:08:13:00 -0500] add_printer_state_reasons(0x403a4008[5],
0x809c690[Laser])
d [15/Jan/2005:08:13:00 -0500] add_queued_job_count(0x403a4008[5],
0x809c690[Laser])
d [15/Jan/2005:08:13:00 -0500] copy_attrs(0x8191338, 0x80a6808,
0x8191720, 0)
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8191338,
0x809c5c8[printer-name,4,42])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8191338,
0x8098760[job-sheets-default,4,42])
d [15/Jan/2005:08:13:00 -0500] copy_attrs(0x8191338, 0x808d8c0,
0x8191720, 0)
D [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: 5 status_code=1
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: Adding fd 5 to
OutputSet...
d [15/Jan/2005:08:13:00 -0500] WriteClient: Removing fd 5 from OutputSet...
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=-1
D [15/Jan/2005:08:13:00 -0500] ReadClient: 5 POST / HTTP/1.1
d [15/Jan/2005:08:13:00 -0500] decode_auth(0x403a4008): Authorization
string = ""
d [15/Jan/2005:08:13:00 -0500] decode_auth: 5 username=""
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: con->uri = "/"
d [15/Jan/2005:08:13:00 -0500] FindBest: uri = "/"...
d [15/Jan/2005:08:13:00 -0500] FindBest: Location / Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /jobs Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /admin Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: best = "/"
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: auth = 0, satisfy=0...
d [15/Jan/2005:08:13:00 -0500] POST /
d [15/Jan/2005:08:13:00 -0500] CONTENT_TYPE = application/ipp
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 137, con->file = -1
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest(0x403a4008[5]):
operation_id = 4005
d [15/Jan/2005:08:13:00 -0500] get_printers(0x403a4008[5], 1)
D [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: 5 status_code=1
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: Adding fd 5 to
OutputSet...
d [15/Jan/2005:08:13:00 -0500] WriteClient: Removing fd 5 from OutputSet...
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=-1
D [15/Jan/2005:08:13:00 -0500] ReadClient: 5 POST / HTTP/1.1
d [15/Jan/2005:08:13:00 -0500] decode_auth(0x403a4008): Authorization
string = ""
d [15/Jan/2005:08:13:00 -0500] decode_auth: 5 username=""
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: con->uri = "/"
d [15/Jan/2005:08:13:00 -0500] FindBest: uri = "/"...
d [15/Jan/2005:08:13:00 -0500] FindBest: Location / Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /jobs Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /admin Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: best = "/"
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: auth = 0, satisfy=0...
d [15/Jan/2005:08:13:00 -0500] POST /
d [15/Jan/2005:08:13:00 -0500] CONTENT_TYPE = application/ipp
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 77, con->file = -1
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=69, file=-1
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 69, con->file = -1
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest(0x403a4008[5]):
operation_id = 4001
d [15/Jan/2005:08:13:00 -0500] get_default(0x403a4008[5])
d [15/Jan/2005:08:13:00 -0500] copy_attrs(0x8190980, 0x80a6808, (nil), 0)
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80a6830[printer-uri-supported,4,45])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c4d0[uri-authentication-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c570[uri-security-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c5c8[printer-name,4,42])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80a6a88[printer-location,4,41])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80a6ac0[printer-info,4,41])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80a6ae8[printer-more-info,4,45])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8096660[job-quota-period,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80966a0[job-k-limit,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80966d8[job-page-limit,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098760[job-sheets-default,4,42])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80987b0[device-uri,4,45])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809b2a8[color-supported,4,22])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c438[pages-per-minute,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c168[printer-make-and-model,4,41])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098240[media-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80a64b0[media-default,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099cb8[sides-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809be48[sides-default,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098820[finishings-supported,4,23])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c128[finishings-default,4,23])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c288[printer-type,4,23])
d [15/Jan/2005:08:13:00 -0500] copy_attrs(0x8190980, 0x808d8c0, (nil), 0)
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80995c0[pdl-override-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099160[ipp-versions-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x809c2d0[operations-supported,4,23])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099450[multiple-document-jobs-supported,4,22])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099428[multiple-operation-time-out,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098f20[multiple-document-handling-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80992e0[charset-configured,4,47])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80a6900[charset-supported,4,47])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80992a0[natural-language-configured,4,48])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099260[generated-natural-language-supported,4,48])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099218[document-format-default,4,49])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098fc8[document-format-supported,4,80000049])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x808d910[compression-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80991d0[job-priority-supported,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098f60[job-priority-default,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098ef8[copies-supported,4,33])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8098ea0[copies-default,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80921b8[page-ranges-supported,4,22])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099328[number-up-supported,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8092190[number-up-default,4,21])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80993e0[orientation-requested-supported,4,23])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8092068[orientation-requested-default,4,23])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x80994a0[job-hold-until-supported,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x808d8e8[job-hold-until-default,4,44])
d [15/Jan/2005:08:13:00 -0500] copy_attribute(0x8190980,
0x8099618[job-sheets-supported,4,42])
D [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: 5 status_code=0
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: Adding fd 5 to
OutputSet...
d [15/Jan/2005:08:13:00 -0500] select_timeout: 20 seconds to send browse
update
d [15/Jan/2005:08:13:00 -0500] WriteClient: Removing fd 5 from OutputSet...
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=-1
d [15/Jan/2005:08:13:00 -0500] ReadClient: httpGets returned EOF...
D [15/Jan/2005:08:13:00 -0500] CloseClient: 5
d [15/Jan/2005:08:13:00 -0500] CloseClient: Removing fd 5 from InputSet
and OutputSet...
d [15/Jan/2005:08:13:00 -0500] AcceptClient(lis=0x808cb30) 0 NumClients = 0
D [15/Jan/2005:08:13:00 -0500] AcceptClient: 5 from 192.168.1.10:631.
d [15/Jan/2005:08:13:00 -0500] AcceptClient: Adding fd 5 to InputSet...
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=-1
D [15/Jan/2005:08:13:00 -0500] ReadClient: 5 POST /printers/Laser HTTP/1.1
d [15/Jan/2005:08:13:00 -0500] decode_auth(0x403a4008): Authorization
string = ""
d [15/Jan/2005:08:13:00 -0500] decode_auth: 5 username=""
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: con->uri = "/printers/Laser"
d [15/Jan/2005:08:13:00 -0500] FindBest: uri = "/printers/Laser"...
d [15/Jan/2005:08:13:00 -0500] FindBest: Location / Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /jobs Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: Location /admin Limit 7f
d [15/Jan/2005:08:13:00 -0500] FindBest: best = "/"
d [15/Jan/2005:08:13:00 -0500] IsAuthorized: auth = 0, satisfy=0...
d [15/Jan/2005:08:13:00 -0500] POST /printers/Laser
d [15/Jan/2005:08:13:00 -0500] CONTENT_TYPE = application/ipp
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 1054714, con->file = -1
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 REQUEST
/var/spool/cups/0000000a=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 26064 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 1028338, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 20272 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 1008066, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 11584 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 996482, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 10136 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 986346, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 979106, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 973314, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 967522, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 960282, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 954490, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 948698, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 942906, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 937114, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 931322, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 925530, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 919738, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 913946, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 908154, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 899466, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 893674, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 886434, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 880642, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 874850, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 869058, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 863266, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 857474, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 851682, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 845890, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 841546, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 834306, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 828514, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 821274, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 815482, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 809690, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 801002, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 793762, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 787970, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 780730, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 774938, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 769146, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 763354, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 756114, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 750322, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 743082, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 737290, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 730050, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 724258, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 718466, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 711226, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 705434, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 698194, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 690954, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 683714, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 677922, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 672130, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 666338, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 660546, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 654754, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 647514, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 640274, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 634482, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 630138, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 624346, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 618554, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 611314, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 605522, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 599730, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 591042, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 583802, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 578010, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 572218, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 566426, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 559186, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 553394, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 546154, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 540362, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 534570, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 530226, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 524434, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 518642, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 514298, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 508506, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 502714, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 496922, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 488234, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 480994, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 475202, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 467962, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 460722, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 454930, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 447690, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 441898, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 436106, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 431762, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 424522, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 420178, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 415834, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 411490, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 405698, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 398458, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 10136 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 388322, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 379634, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 372394, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 366602, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 360810, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 355018, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 350674, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 344882, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 337642, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 331850, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 326058, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 320266, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 314474, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 308682, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 301442, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 295650, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 289858, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 281170, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 273930, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 268138, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 262346, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 255106, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 247866, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 242074, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 234834, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 227594, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 223250, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 217458, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 211666, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 205874, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 198634, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 194290, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 188498, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 181258, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 174018, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 168226, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 160986, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 155194, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 149402, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 143610, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 134922, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 127682, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 120442, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 113202, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 107410, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 100170, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 94378, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 87138, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 79898, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 75554, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 8688 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 66866, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 59626, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 53834, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 46594, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 7240 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 39354, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 35010, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 29218, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 23426, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 17634, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 11842, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 5792 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 6050, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 4344 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 con->data_encoding =
length, con->data_remaining = 1706, con->file = 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 writing 1706 bytes to 6
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5 Closing data file 6, size =
1054402.
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest(0x403a4008[5]):
operation_id = 0002
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest:
URI="ipp://192.168.1.2:631/printers/Laser"
d [15/Jan/2005:08:13:00 -0500] print_job(0x403a4008[5],
ipp://192.168.1.2:631/printers/Laser)
D [15/Jan/2005:08:13:00 -0500] print_job: auto-typing file...
D [15/Jan/2005:08:13:00 -0500] print_job: request file type is
application/postscript.
D [15/Jan/2005:08:13:00 -0500] CancelJob: id = 643
d [15/Jan/2005:08:13:00 -0500] check_quotas(0x403a4008[5], 0x809c690[Laser])
D [15/Jan/2005:08:13:00 -0500] check_quotas: requesting-user-name =
'tallison'
D [15/Jan/2005:08:13:00 -0500] print_job: requesting-user-name = 'tallison'
I [15/Jan/2005:08:13:00 -0500] Adding start banner page "none" to job 743.
d [15/Jan/2005:08:13:00 -0500] copy_banner(0x403a4008[5],
0x8192888[743], none)
d [15/Jan/2005:08:13:00 -0500] add_file(con=0x403a4008[5], job=743,
filetype=application/postscript, compression=0)
I [15/Jan/2005:08:13:00 -0500] Adding end banner page "none" to job 743.
d [15/Jan/2005:08:13:00 -0500] copy_banner(0x403a4008[5],
0x8192888[743], none)
I [15/Jan/2005:08:13:00 -0500] Job 743 queued on 'Laser' by 'tallison'.
D [15/Jan/2005:08:13:00 -0500] Job 743 hold_until = 0
d [15/Jan/2005:08:13:00 -0500] SaveJob: Closing file 6...
D [15/Jan/2005:08:13:00 -0500] StartJob(743, 0x809c690)
D [15/Jan/2005:08:13:00 -0500] StartJob() id = 743, file = 0/1
D [15/Jan/2005:08:13:00 -0500] job-sheets=none,none
D [15/Jan/2005:08:13:00 -0500] banner_page = 0
D [15/Jan/2005:08:13:00 -0500] StartJob: argv =
"Laser","743","tallison","Mason HQ: Mason Developer's Manual (version
1.27)","1","","/var/spool/cups/d00743-001"
D [15/Jan/2005:08:13:00 -0500] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[2]="USER=root"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[3]="CHARSET=iso-8859-1"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[4]="LANG=en"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[5]="TZ=US/Michigan"
D [15/Jan/2005:08:13:00 -0500] StartJob:
envp[6]="PPD=/etc/cups/ppd/Laser.ppd"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [15/Jan/2005:08:13:00 -0500] StartJob:
envp[9]="TMPDIR=/var/spool/cups/tmp"
D [15/Jan/2005:08:13:00 -0500] StartJob:
envp[10]="CONTENT_TYPE=application/postscript"
D [15/Jan/2005:08:13:00 -0500] StartJob:
envp[11]="DEVICE_URI=usb://Kyocera/Mita%20FS-1010"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[12]="PRINTER=Laser"
D [15/Jan/2005:08:13:00 -0500] StartJob:
envp[13]="CUPS_DATADIR=/usr/share/cups"
D [15/Jan/2005:08:13:00 -0500] StartJob:
envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[15]="CUPS_SERVER=localhost"
D [15/Jan/2005:08:13:00 -0500] StartJob: envp[16]="IPP_PORT=631"
d [15/Jan/2005:08:13:00 -0500] StartJob: Allocating status buffer...
D [15/Jan/2005:08:13:00 -0500] StartJob: statusfds = [ 6 8 ]
D [15/Jan/2005:08:13:00 -0500] StartJob: filterfds[1] = [ 9 -1 ]
D [15/Jan/2005:08:13:00 -0500] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [15/Jan/2005:08:13:00 -0500] StartJob: filterfds[0] = [ 10 11 ]
D [15/Jan/2005:08:13:00 -0500]
start_process("/usr/lib/cups/filter/pstops", 0xbfff0150, 0xbffef4c0, 9,
11, 8)
I [15/Jan/2005:08:13:00 -0500] Started filter
/usr/lib/cups/filter/pstops (PID 2874) for job 743.
D [15/Jan/2005:08:13:00 -0500] StartJob: filter =
"/usr/lib/cups/filter/foomatic-rip"
D [15/Jan/2005:08:13:00 -0500] StartJob: filterfds[1] = [ 9 12 ]
D [15/Jan/2005:08:13:00 -0500]
start_process("/usr/lib/cups/filter/foomatic-rip", 0xbfff0150,
0xbffef4c0, 10, 12, 8)
I [15/Jan/2005:08:13:00 -0500] Started filter
/usr/lib/cups/filter/foomatic-rip (PID 2875) for job 743.
D [15/Jan/2005:08:13:00 -0500] StartJob: backend =
"/usr/lib/cups/backend/usb"
D [15/Jan/2005:08:13:00 -0500] StartJob: filterfds[0] = [ -1 10 ]
D [15/Jan/2005:08:13:00 -0500]
start_process("/usr/lib/cups/backend/usb", 0xbfff0150, 0xbffef4c0, 9, 10, 8)
I [15/Jan/2005:08:13:00 -0500] Started backend /usr/lib/cups/backend/usb
(PID 2876) for job 743.
d [15/Jan/2005:08:13:00 -0500] StartJob: Adding fd 6 to InputSet...
d [15/Jan/2005:08:13:00 -0500] add_job_state_reasons(0x403a4008[5], 743)
D [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: 5 status_code=0
d [15/Jan/2005:08:13:00 -0500] ProcessIPPRequest: Adding fd 5 to
OutputSet...
D [15/Jan/2005:08:13:00 -0500] [Job 743] Page = 612x792; 0,0 to 612,792
D [15/Jan/2005:08:13:00 -0500] [Job 743] slowcollate=0, slowduplex=0,
sloworder=0
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%Creator: The X Print
Server's PostScript DDX (xprint.mozdev.org, release 100000, FreeType
version 2.1.8)
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%Title: Mason HQ: Mason
Developer's Manual (version 1.27)
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%EndComments
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%BeginProlog
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%BeginProcSet:
XServer_PS_Functions
D [15/Jan/2005:08:13:00 -0500] [Job 743] perl: warning: Setting locale
failed.
D [15/Jan/2005:08:13:00 -0500] [Job 743] perl: warning: Please check
that your locale settings:
D [15/Jan/2005:08:13:00 -0500] [Job 743] LANGUAGE = (unset),
D [15/Jan/2005:08:13:00 -0500] [Job 743] LC_ALL = (unset),
D [15/Jan/2005:08:13:00 -0500] [Job 743] LANG = "en"
D [15/Jan/2005:08:13:00 -0500] [Job 743] are supported and installed on
your system.
D [15/Jan/2005:08:13:00 -0500] [Job 743] perl: warning: Falling back to
the standard locale ("C").
d [15/Jan/2005:08:13:00 -0500] WriteClient: Removing fd 5 from OutputSet...
d [15/Jan/2005:08:13:00 -0500] ReadClient: 5, used=0, file=-1
d [15/Jan/2005:08:13:00 -0500] ReadClient: httpGets returned EOF...
D [15/Jan/2005:08:13:00 -0500] CloseClient: 5
d [15/Jan/2005:08:13:00 -0500] CloseClient: Removing fd 5 from InputSet
and OutputSet...
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%EndProcSet
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%EndProlog
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%BeginSetup
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%Pages: atend
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%EndSetup
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%Page: 1 1
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%Page: 1 1
D [15/Jan/2005:08:13:00 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:00 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:00 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:00 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:00 -0500] [Job 743] 0 %%BeginFont:
NimbusRomNo9L-Regu_iso8859-2
D [15/Jan/2005:08:13:01 -0500] [Job 743] Printer using device file
"/dev/usb/lp1"...
D [15/Jan/2005:08:13:01 -0500] [Job 743] LPGETSTATUS returned a port
status of 18...
D [15/Jan/2005:08:13:01 -0500] [Job 743] foomatic-rip version $Revision:
3.43.2.6 $ running...
D [15/Jan/2005:08:13:01 -0500] [Job 743] Parsing PPD file ...
D [15/Jan/2005:08:13:01 -0500] [Job 743] *cupsFilter:
"application/vnd.cups-postscript 0 foomatic-rip"
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option ColorSpace
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option PageSize
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option PageRegion
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option ImageableArea
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option PaperDimension
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option Duplex
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option Resolution
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option PreFilter
D [15/Jan/2005:08:13:01 -0500] [Job 743] Added option Font
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] Parameter Summary
D [15/Jan/2005:08:13:01 -0500] [Job 743] -----------------
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] Spooler: cups
D [15/Jan/2005:08:13:01 -0500] [Job 743] Printer: Laser
D [15/Jan/2005:08:13:01 -0500] [Job 743] PPD file: /etc/cups/ppd/Laser.ppd
D [15/Jan/2005:08:13:01 -0500] [Job 743] Printer model: Kyocera FS-1010
Foomatic/Postscript (recommended)
D [15/Jan/2005:08:13:01 -0500] [Job 743] Job title: Mason HQ: Mason
Developers Manual version 1.27
D [15/Jan/2005:08:13:01 -0500] [Job 743] File(s) to be printed:
D [15/Jan/2005:08:13:01 -0500] [Job 743] <STDIN>
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743]
================================================
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] File: <STDIN>
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743]
================================================
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] Reading PostScript input ...
D [15/Jan/2005:08:13:01 -0500] [Job 743] --> This document is
DSC-conforming!
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%BeginProlog
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%EndProlog
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%BeginSetup
D [15/Jan/2005:08:13:01 -0500] [Job 743] Inserting PostScript code for
CUPS' page accounting
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%BeginFeature:
*PageSize Letter
D [15/Jan/2005:08:13:01 -0500] [Job 743] Option: PageSize=Letter -->
Option will be set by PostScript interpreter
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%BeginFeature:
*PreFilter No
D [15/Jan/2005:08:13:01 -0500] [Job 743] Option: PreFilter=No -->
Setting option
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%
FoomaticRIPOptionSetting: PreFilter=No
D [15/Jan/2005:08:13:01 -0500] [Job 743] Option: PreFilter=No -->
Setting option
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%BeginFeature:
*Resolution default
D [15/Jan/2005:08:13:01 -0500] [Job 743] Option: Resolution=default -->
Setting option
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%
FoomaticRIPOptionSetting: Resolution=default
D [15/Jan/2005:08:13:01 -0500] [Job 743] Option: Resolution=default -->
Setting option
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%BeginFeature: *Duplex
Notcapable
D [15/Jan/2005:08:13:01 -0500] [Job 743] Option: Duplex=Notcapable -->
Setting option
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%
FoomaticRIPOptionSetting: Duplex=Notcapable
D [15/Jan/2005:08:13:01 -0500] [Job 743] Option: Duplex=Notcapable -->
Setting option
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found: %%EndSetup
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:01 -0500] [Job 743] New page:  1 1
D [15/Jan/2005:08:13:01 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:01 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:01 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:01 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:01 -0500] [Job 743]
598CCDB4211AD140E522B9DB749BE1CC3A736B21432C06411298BFC514879668
D [15/Jan/2005:08:13:01 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] Starting renderer
D [15/Jan/2005:08:13:01 -0500] [Job 743] JCL: <job data>
D [15/Jan/2005:08:13:01 -0500] [Job 743]
D [15/Jan/2005:08:13:01 -0500] [Job 743] renderer PID kid4=2878
D [15/Jan/2005:08:13:01 -0500] [Job 743] renderer command: level=0;
printf "%%!\n%%%% %%%%\n"; if (( $level > 0 )); then if (( $level < 99
)); then level=" -dLanguageLevel=$level"; else level=""; fi; gs -q
-dPARANOIDSAFER -dNOPAUSE -dBATCH -sDEVICE=pswrite$level -sOutputFile=-
-; else cat; fi
D [15/Jan/2005:08:13:01 -0500] [Job 743] 0 %%EndFont
D [15/Jan/2005:08:13:02 -0500] [Job 743] 0 %%BeginFont:
NimbusSanL-Regu_iso8859-15
D [15/Jan/2005:08:13:02 -0500] [Job 743] 0 %%EndFont
D [15/Jan/2005:08:13:02 -0500] [Job 743] 0 %%BeginFont:
NimbusSanL-Bold_iso8859-15
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%EndFont
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%BeginFont:
NimbusSanL-ReguItal_iso8859-15
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%EndFont
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%Page: 2 2
D [15/Jan/2005:08:13:03 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:03 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:03 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:03 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:03 -0500] [Job 743]
D [15/Jan/2005:08:13:03 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:03 -0500] [Job 743] New page:  2 2
D [15/Jan/2005:08:13:03 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:03 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:03 -0500] [Job 743] %%Page: 2 2
D [15/Jan/2005:08:13:03 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:03 -0500] [Job 743]
D [15/Jan/2005:08:13:03 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:03 -0500] [Job 743] 0 %%BeginFont:
NimbusMonL-Regu_iso8859-15
D [15/Jan/2005:08:13:03 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:03 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:03 -0500] [Job 743] 450 450 4200 5699 R
D [15/Jan/2005:08:13:03 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:03 -0500] [Job 743]
d [15/Jan/2005:08:13:04 -0500] select_timeout: 11 seconds to process
active jobs
D [15/Jan/2005:08:13:05 -0500] [Job 743] 0 %%EndFont
D [15/Jan/2005:08:13:05 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:05 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:05 -0500] [Job 743] 0 %%Page: 3 3
D [15/Jan/2005:08:13:05 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:05 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:05 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:05 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:05 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:05 -0500] [Job 743]
D [15/Jan/2005:08:13:05 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:05 -0500] [Job 743] New page:  3 3
D [15/Jan/2005:08:13:05 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:05 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:05 -0500] [Job 743] %%Page: 3 3
D [15/Jan/2005:08:13:05 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:05 -0500] [Job 743]
D [15/Jan/2005:08:13:05 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:05 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:05 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:05 -0500] [Job 743] [  /space  /space  /space
/space  /m  /y  /space  /dollar  /n  /o  /u  /n  /space  /equal  /space
  /quotesingle  /W  /o  /r  /l  /d  /quotesingle  /semicolon  ]{gs
glyphshow gr 36 0 rm}fa gr gs
D [15/Jan/2005:08:13:05 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:05 -0500] [Job 743]
d [15/Jan/2005:08:13:06 -0500] select_timeout: 11 seconds to process
active jobs
D [15/Jan/2005:08:13:09 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:09 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:09 -0500] [Job 743] 0 %%Page: 4 4
D [15/Jan/2005:08:13:09 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:09 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:09 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:09 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:09 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:09 -0500] [Job 743]
D [15/Jan/2005:08:13:09 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:09 -0500] [Job 743] New page:  4 4
D [15/Jan/2005:08:13:09 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:09 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:09 -0500] [Job 743] %%Page: 4 4
D [15/Jan/2005:08:13:09 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:09 -0500] [Job 743]
D [15/Jan/2005:08:13:09 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:09 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:09 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:09 -0500] [Job 743] 0 g
D [15/Jan/2005:08:13:09 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:09 -0500] [Job 743]
D [15/Jan/2005:08:13:10 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:10 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:10 -0500] [Job 743] 0 %%Page: 5 5
D [15/Jan/2005:08:13:10 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:10 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:10 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:10 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:10 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:10 -0500] [Job 743]
D [15/Jan/2005:08:13:10 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:10 -0500] [Job 743] New page:  5 5
D [15/Jan/2005:08:13:10 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:10 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:10 -0500] [Job 743] %%Page: 5 5
D [15/Jan/2005:08:13:10 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:10 -0500] [Job 743]
D [15/Jan/2005:08:13:10 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:10 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:10 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:10 -0500] [Job 743] 0 g
D [15/Jan/2005:08:13:10 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:10 -0500] [Job 743]
d [15/Jan/2005:08:13:11 -0500] select_timeout: 9 seconds to send browse
update
d [15/Jan/2005:08:13:20 -0500] select_timeout: 11 seconds to process
active jobs
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%Page: 6 6
D [15/Jan/2005:08:13:26 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:26 -0500] [Job 743]
D [15/Jan/2005:08:13:26 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:26 -0500] [Job 743] New page:  6 6
D [15/Jan/2005:08:13:26 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:26 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:26 -0500] [Job 743] %%Page: 6 6
D [15/Jan/2005:08:13:26 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:26 -0500] [Job 743]
D [15/Jan/2005:08:13:26 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:26 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:26 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:26 -0500] [Job 743] gr gs
D [15/Jan/2005:08:13:26 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:26 -0500] [Job 743]
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%Page: 7 7
D [15/Jan/2005:08:13:26 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:26 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:26 -0500] [Job 743]
D [15/Jan/2005:08:13:26 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:26 -0500] [Job 743] New page:  7 7
D [15/Jan/2005:08:13:26 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:26 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:26 -0500] [Job 743] %%Page: 7 7
D [15/Jan/2005:08:13:26 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:26 -0500] [Job 743]
D [15/Jan/2005:08:13:26 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:26 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:26 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:26 -0500] [Job 743] cl n
D [15/Jan/2005:08:13:26 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:26 -0500] [Job 743]
d [15/Jan/2005:08:13:27 -0500] select_timeout: 5 seconds to send browse
update
d [15/Jan/2005:08:13:32 -0500] select_timeout: 11 seconds to process
active jobs
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%Page: 8 8
D [15/Jan/2005:08:13:35 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:35 -0500] [Job 743] New page:  8 8
D [15/Jan/2005:08:13:35 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] %%Page: 8 8
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:35 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] 450 450 4200 5699 R
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%Page: 9 9
D [15/Jan/2005:08:13:35 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:35 -0500] [Job 743] New page:  9 9
D [15/Jan/2005:08:13:35 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] %%Page: 9 9
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:35 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] [  ]{glyphshow}fa gr gs
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%Page: 10 10
D [15/Jan/2005:08:13:35 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:35 -0500] [Job 743] New page:  10 10
D [15/Jan/2005:08:13:35 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] %%Page: 10 10
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:35 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 g
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%Page: 11 11
D [15/Jan/2005:08:13:35 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:35 -0500] [Job 743] New page:  11 11
D [15/Jan/2005:08:13:35 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] %%Page: 11 11
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:35 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] [  /at  /underscore  ]{gs
glyphshow gr 36 0 rm}fa gr gs
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%Page: 12 12
D [15/Jan/2005:08:13:35 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:35 -0500] [Job 743] New page:  12 12
D [15/Jan/2005:08:13:35 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] %%Page: 12 12
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:35 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] [  /e  /x  /p  /e  /n  /s  /i
/v  /e  /space  /t  /o  /space  /c  /r  /e  /a  /t  /e  /parenright
/comma  /space  /d  /e  /c  /l  /a  /r  /i  /n  /g  /space  /s  /u  /b
/r  /o  /u  /t  /i  /n  /e  /s  /space  /parenleft  /b  /o  /t  /h
/space  /n  /a  /m  /e  /d  /space  /a  /n  /d  /space  /a  /n  /o  /n
/y  /m  /o  /u  /s  /parenright  /comma  /space  /a  /n  /d  /space  /i
  /n  /i  /t  /i  /a  /l  /i  /z  /i  /n  /g  /space  /s  /t  /a  /t  /e
  /period  ]{glyphshow}fa gr gs
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%Page: 13 13
D [15/Jan/2005:08:13:35 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:35 -0500] [Job 743] New page:  13 13
D [15/Jan/2005:08:13:35 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] %%Page: 13 13
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:35 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:35 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:35 -0500] [Job 743] [  /space  /space  /space
/space  /less  /f  /o  /n  /t  /space  /s  /i  /z  /e  /equal  /quotedbl
  /four  /quotedbl  /space  /f  /a  /c  /e  /equal  /quotedbl  /V  /e
/r  /d  /a  /n  /a  /comma  /A  /r  /i  /a  /l  /comma  /H  /e  /l  /v
/e  /t  /i  /c  /a  /quotedbl  /greater  ]{gs glyphshow gr 36 0 rm}fa gr gs
D [15/Jan/2005:08:13:35 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%Page: 14 14
D [15/Jan/2005:08:13:35 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:35 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:35 -0500] [Job 743]
D [15/Jan/2005:08:13:35 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:35 -0500] [Job 743] New page:  14 14
D [15/Jan/2005:08:13:35 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 14 14
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] [  ]{glyphshow}fa gr gs
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 15 15
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  15 15
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 15 15
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] [  /t  /h  /e  /space  /n  /e
/x  /t  /space  /s  /e  /c  /t  /i  /o  /n  ]{glyphshow}fa gr gs
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 16 16
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  16 16
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 16 16
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] [  ]{glyphshow}fa gr gs
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 17 17
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  17 17
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 17 17
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] /NimbusMonL-Regu_iso8859-15 61 f Tf
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 18 18
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  18 18
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 18 18
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] 1 w
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 19 19
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  19 19
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 19 19
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] [  /dollar  /s  /t  /a  /t  /u
  /s  ]{gs glyphshow gr 36 0 rm}fa gr gs
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 20 20
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  20 20
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 20 20
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] [  /w  /h  /i  /c  /h  /space
/r  /e  /d  /u  /c  /e  /s  /space  /t  /h  /e  /space  /p  /r  /e  /v
/i  /o  /u  /s  /space  /e  /x  /a  /m  /p  /l  /e  /s  /space  /t  /o
/space  /j  /u  /s  /t  /colon  ]{glyphshow}fa gr gs
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 21 21
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  21 21
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 21 21
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] cl n
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 22 22
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  22 22
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 22 22
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] [  /less  /percent  /i  /n  /i
  /t  /greater  ]{gs glyphshow gr 36 0 rm}fa gr gs
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageHeader
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Page: 23 23
D [15/Jan/2005:08:13:36 -0500] [Job 743] pw = 612.0, pl = 792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageLeft = 0.0, PageRight = 612.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageTop = 792.0, PageBottom = 0.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] PageWidth = 612.0, PageLength =
792.0
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageOrientation: Portrait
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] -----------
D [15/Jan/2005:08:13:36 -0500] [Job 743] New page:  23 23
D [15/Jan/2005:08:13:36 -0500] [Job 743] Inserting option code into
"PageSetup" section.
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] %%Page: 23 23
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes to the FIFO
buffer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] No page header or page header
not DSC-conforming
D [15/Jan/2005:08:13:36 -0500] [Job 743] Stopping search for page header
options
D [15/Jan/2005:08:13:36 -0500] [Job 743] Found:
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 g
D [15/Jan/2005:08:13:36 -0500] [Job 743] --> Output goes directly to the
renderer now.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%PageTrailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] 0 %%Trailer
D [15/Jan/2005:08:13:36 -0500] [Job 743] Saw Trailer!
D [15/Jan/2005:08:13:36 -0500] [Job 743] Saw EOF!
d [15/Jan/2005:08:13:36 -0500] PID 2874 exited with no errors.
D [15/Jan/2005:08:13:36 -0500] [Job 743]
D [15/Jan/2005:08:13:36 -0500] [Job 743] Closing renderer
D [15/Jan/2005:08:13:36 -0500] [Job 743] tail process done writing data
to STDOUT
D [15/Jan/2005:08:13:36 -0500] [Job 743] KID4 finished
D [15/Jan/2005:08:13:36 -0500] [Job 743] KID3 finished
D [15/Jan/2005:08:13:37 -0500] [Job 743] KID4 exited with status 0
D [15/Jan/2005:08:13:37 -0500] [Job 743] KID3 exited with status 0
D [15/Jan/2005:08:13:37 -0500] [Job 743] Renderer exit stat: 0
D [15/Jan/2005:08:13:37 -0500] [Job 743] Renderer process finished
D [15/Jan/2005:08:13:37 -0500] [Job 743]
D [15/Jan/2005:08:13:37 -0500] [Job 743] Closing foomatic-rip.
d [15/Jan/2005:08:13:37 -0500] PID 2876 exited with no errors.
d [15/Jan/2005:08:13:39 -0500] PID 2875 exited with no errors.
D [15/Jan/2005:08:13:39 -0500] UpdateJob: job 743, file 0 is complete.
d [15/Jan/2005:08:13:39 -0500] UpdateJob: Removing fd 6 from InputSet...
D [15/Jan/2005:08:13:39 -0500] CancelJob: id = 743
D [15/Jan/2005:08:13:39 -0500] StopJob: id = 743, force = 0
D [15/Jan/2005:08:13:39 -0500] StopJob: printer state is 3
d [15/Jan/2005:08:13:39 -0500] StopJob: Freeing status buffer...
d [15/Jan/2005:08:13:39 -0500] SaveJob: Closing file 5...
d [15/Jan/2005:08:13:39 -0500] SaveJob: Closing file 5...
d [15/Jan/2005:08:13:40 -0500] select_timeout: 4 seconds to update root
certificate
d [15/Jan/2005:08:13:44 -0500] DeleteCert: removing certificate for pid 0
d [15/Jan/2005:08:13:44 -0500] AddCert: adding certificate for pid 0
d [15/Jan/2005:08:13:44 -0500] select_timeout: 7 seconds to send browse
update
d [15/Jan/2005:08:13:51 -0500] select_timeout: 20 seconds to send browse
update
d [15/Jan/2005:08:14:11 -0500] select_timeout: 11 seconds to send browse
update
d [15/Jan/2005:08:14:22 -0500] select_timeout: 20 seconds to send browse
update
d [15/Jan/2005:08:14:42 -0500] select_timeout: 11 seconds to send browse
update
d [15/Jan/2005:08:14:53 -0500] select_timeout: 20 seconds to send browse
update
d [15/Jan/2005:08:15:13 -0500] select_timeout: 11 seconds to send browse
update
d [15/Jan/2005:08:15:24 -0500] select_timeout: 20 seconds to send browse
update
d [15/Jan/2005:08:15:44 -0500] select_timeout: 11 seconds to send browse
update
d [15/Jan/2005:08:15:55 -0500] select_timeout: 20 seconds to send browse
update






More information about the cups mailing list