A duplex printing problem
Don Felix
dfelix at cadence.com
Tue Aug 16 17:06:14 PDT 2005
I have some users who use a hand rolled 'a2ps' script to produce 2-up pages (with margins, etc they prefer to anything I can get out of the real a2ps) ... when sent to a CUPS queue which is set up for duplex printing, their output prints out single sided.
It's probably unreasonable to expect anyone to debug the user's little script, but if anyone can see what the issue is I'd really appreciate it.
Here is their script, below that is the resulting error_log stuff from CUPS
#! /bin/sh
# File: csli:/user/evan/bin/a2ps
# Created: Wed May 25 11:32:18 1988 by evan at csli (Evan Kirshenbaum)
# Version: 1.0(1)
# Description: Convert files from ascii to PostScript.
#
# Usage: a2ps [- name] | [file1 [file2 [...]]]
#
# Writes to standard output (I generally pipe through
# lpr). Files are prepared for printing landscape, two to
# a page, boxed. Header for each page has the format:
#
# File-Name "Page" Page-Number
#
# where page-number starts at 1 for each file. Each
# sheet in the batch has a footer in the format:
#
# Print-Time user at hostname Sheet-Number
#
# If no arguments or the first argument is '-', stdin is printed.
# If an argument follows the '-', it is printed as the filename
# instead of "stdin". Arguments following the second are ignored
# when the first argument is '-'. N.B.: if a '-' appears
# anywhere but the first argument, the filename will be printed
# "stdin" and stdin will be printed for that file. Following
# filenames will still be printed.
#
# Requires 'expand' (change tabs to spaces) and 'trim80' (fold
# lines longer than 80 characters and put '\' in 81st character
# position of each folded line), 'date', 'whoami' and 'hostname'
# in the search path.
#
# Prints error message to stderr if you try to print a directory.
#
#
# Generate PostScript prologue
cat << !!END!!OF!!PS!!PROLOGUE!!
%!PS-Adobe-1.0
%! PostScript Source Code
% File: csli:/user/evan/bin/a2ps.ps
% Created: Wed May 25 11:22:04 1988 by evan at csli (Evan Kirshenbaum)
% Version: 1.0(1)
% Description: PostScript prolog for a2ps ascii to PostScript program.
%
% Edit History:
% 11/29/88 cplai at daisy added pnumbuf to allow printing of
% 10 or more pages
/xdef {exch def} bind def
/inch {72 mul} bind def
/getfont {exch findfont exch scalefont} bind def
/filenamesize 12 def
/filenamefont /Helvetica-Bold filenamesize getfont def
/linesperpage 66 def
/datesize filenamesize 2 sub def
/datefont /Helvetica datesize getfont def
/headersize filenamesize 4 add def
/bodysize 6.8 def
/bodyfont /Courier bodysize getfont def
/sidemargin 4 def
/topmargin 4 def
/pagewidth
bodyfont setfont (0) stringwidth pop 80 mul sidemargin dup add add
def
/pageheight
bodysize linesperpage mul topmargin dup add add headersize add
def
/uppery 8.5 inch pageheight add 2 div def
/upperx [ 11 inch pagewidth 2 mul sub 3 div
dup 2 mul pagewidth add ] def
/pnumbuf 10 string def
/endpage
{ pageside 1 eq
{ /pageside 0 def
numberpage
copypage erasepage
/sheet sheet 1 add def }
{ /pageside 1 def }
ifelse
/pagenum pagenum 1 add def
} bind def
/numberpage
{ 11 inch upperx 0 get sub sidemargin add
8.5 inch uppery sub headersize sub moveto
datefont setfont
sheet pnumbuf cvs show
0 inch upperx 0 get add sidemargin sub
8.5 inch uppery sub headersize sub moveto
date show
/userhalfwidth user stringwidth pop 2 div def
5.5 inch userhalfwidth sub
8.5 inch uppery sub headersize sub moveto
user show
} bind def
/newfile
{ /filename xdef
/pagenum 1 def
} bind def
/cleanup
{ pageside 1 eq
{numberpage showpage} if
} bind def
/startdoc
{ 8.5 inch 0 inch translate
90 rotate
/pageside 0 def
/sheet 1 def
} bind def
/startpage
{ printheader
printborder
upperx pageside get sidemargin add
uppery topmargin sub bodysize sub headersize sub moveto
bodyfont setfont
} bind def
/s { gsave
show
grestore
0 bodysize neg rmoveto
} bind def
/printheader
{ upperx pageside get uppery headersize sub 1 add moveto
datefont setfont
/pnum pagenum pnumbuf cvs def
/pnumwidth pnum stringwidth pop (Page ) stringwidth pop add def
gsave
pagewidth sidemargin sub pnumwidth sub
3 rmoveto (Page ) show pnum show
grestore
gsave
filenamefont setfont
pagewidth sidemargin sub pnumwidth sub
filename stringwidth pop sub 2 div 2 rmoveto filename show
grestore
} bind def
/printborder
{ upperx pageside get uppery moveto
gsave
pagewidth 0 rlineto
0 pageheight neg rlineto
pagewidth neg 0 rlineto
closepath stroke
grestore
0 headersize neg rmoveto pagewidth 0 rlineto stroke
} bind def
!!END!!OF!!PS!!PROLOGUE!!
# Define current date and time and start document
#date +"/date (%d %h 19%y %r) def"
# above worked for Sun, but doesn't for MIPS, which doesn't understand
# +format, so just use the output of date as is
echo '/date' "(`date`)" def
echo '/user' "(`whoami`@`hostname`)" def
echo "startdoc"
# Process each file on the command line, take standard input
# if there are no arguments on the command line
if [ $# -eq 0 ]; then
list="stdin"
file="-"
elif [ $1 = "-" ]; then
if [ $# -eq 1 ]; then
list="stdin"
else
list="$2"
fi
file="-"
else
list="$*"
file="$1"
fi
for i in $list
do
if [ $file != "-" ]; then
if [ -d $i ]; then
echo "$i is a directory, not printed" 1>&2
continue
fi
file="$i"
if [ $i = "-" ]; then
i="stdin"
fi
fi
echo "($i) newfile"
cat $file | expand | trim80 | sed 's/[\\()]/\\&/g' \
| awk 'BEGIN { line=0; print "startpage" }
(line>65 || $0~/^
/) { line=0; print "endpage startpage" }
$0~/^
*$/ { next }
{ print "(" $0 ") s"; line++ }
END {print "endpage"}'
if [ $file = "-" ]; then
file="stdin"
fi
done
echo "cleanup"
Also here is dubug log output - though I don't see any errors thrown by CUPS:
d [16/Aug/2005:16:50:44 -0600] AcceptClient(lis=0x8082988) 0 NumClients = 0
D [16/Aug/2005:16:50:44 -0600] AcceptClient: 6 from localhost:631.
d [16/Aug/2005:16:50:44 -0600] AcceptClient: Adding fd 6 to InputSet...
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
D [16/Aug/2005:16:50:44 -0600] ReadClient: 6 POST / HTTP/1.1
d [16/Aug/2005:16:50:44 -0600] decode_auth(0xb70fa008): Authorization string = ""
d [16/Aug/2005:16:50:44 -0600] decode_auth: 6 username=""
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: con->uri = "/"
d [16/Aug/2005:16:50:44 -0600] FindBest: uri = "/"...
d [16/Aug/2005:16:50:44 -0600] FindBest: Location / Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: Location /admin Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: best = "/"
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: auth = 0, satisfy=0...
d [16/Aug/2005:16:50:44 -0600] POST /
d [16/Aug/2005:16:50:44 -0600] CONTENT_TYPE = application/ipp
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 140, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest(0xb70fa008[6]): operation_id = 4002
d [16/Aug/2005:16:50:44 -0600] get_printers(0xb70fa008[6], 0)
d [16/Aug/2005:16:50:44 -0600] add_printer_state_reasons(0xb70fa008[6], 0x81ef7c0[sdy13d03])
d [16/Aug/2005:16:50:44 -0600] add_queued_job_count(0xb70fa008[6], 0x81ef7c0[sdy13d03])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x829c7d0, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x81fa520[printer-name,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x81f1f28[job-sheets-default,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x8088dc8, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] add_printer_state_reasons(0xb70fa008[6], 0x808ea68[sdy13s03])
d [16/Aug/2005:16:50:44 -0600] add_queued_job_count(0xb70fa008[6], 0x808ea68[sdy13s03])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x808c3a8, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x808c508[printer-name,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x808c718[job-sheets-default,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x8088dc8, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] add_printer_state_reasons(0xb70fa008[6], 0x81fc7b8[sdy13t03])
d [16/Aug/2005:16:50:44 -0600] add_queued_job_count(0xb70fa008[6], 0x81fc7b8[sdy13t03])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x81f0188, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x81f8e08[printer-name,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x81f1290[job-sheets-default,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x8088dc8, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] add_printer_state_reasons(0xb70fa008[6], 0x81f7ba8[sj12d01])
d [16/Aug/2005:16:50:44 -0600] add_queued_job_count(0xb70fa008[6], 0x81f7ba8[sj12d01])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x829c7a8, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x80a7168[printer-name,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x81fa648[job-sheets-default,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x8088dc8, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] add_printer_state_reasons(0xb70fa008[6], 0x81b4c70[sj12s01])
d [16/Aug/2005:16:50:44 -0600] add_queued_job_count(0xb70fa008[6], 0x81b4c70[sj12s01])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x819e598, 0x81e5b68, 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x81f2d18[printer-name,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a9330, 0x81b7e00[job-sheets-default,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a9330, 0x8088dc8, 0x81e5b68, 0)
D [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: 6 status_code=1
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [16/Aug/2005:16:50:44 -0600] WriteClient: Removing fd 6 from OutputSet...
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
D [16/Aug/2005:16:50:44 -0600] ReadClient: 6 POST / HTTP/1.1
d [16/Aug/2005:16:50:44 -0600] decode_auth(0xb70fa008): Authorization string = ""
d [16/Aug/2005:16:50:44 -0600] decode_auth: 6 username=""
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: con->uri = "/"
d [16/Aug/2005:16:50:44 -0600] FindBest: uri = "/"...
d [16/Aug/2005:16:50:44 -0600] FindBest: Location / Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: Location /admin Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: best = "/"
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: auth = 0, satisfy=0...
d [16/Aug/2005:16:50:44 -0600] POST /
d [16/Aug/2005:16:50:44 -0600] CONTENT_TYPE = application/ipp
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 140, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest(0xb70fa008[6]): operation_id = 4005
d [16/Aug/2005:16:50:44 -0600] get_printers(0xb70fa008[6], 1)
D [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: 6 status_code=1
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [16/Aug/2005:16:50:44 -0600] WriteClient: Removing fd 6 from OutputSet...
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
D [16/Aug/2005:16:50:44 -0600] ReadClient: 6 POST / HTTP/1.1
d [16/Aug/2005:16:50:44 -0600] decode_auth(0xb70fa008): Authorization string = ""
d [16/Aug/2005:16:50:44 -0600] decode_auth: 6 username=""
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: con->uri = "/"
d [16/Aug/2005:16:50:44 -0600] FindBest: uri = "/"...
d [16/Aug/2005:16:50:44 -0600] FindBest: Location / Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: Location /admin Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: best = "/"
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: auth = 0, satisfy=0...
d [16/Aug/2005:16:50:44 -0600] POST /
d [16/Aug/2005:16:50:44 -0600] CONTENT_TYPE = application/ipp
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 80, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest(0xb70fa008[6]): operation_id = 4001
d [16/Aug/2005:16:50:44 -0600] get_default(0xb70fa008[6])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x81b6830, 0x808c3a8, (nil), 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c3d0[printer-uri-supported,4,45])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c440[uri-authentication-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c4b0[uri-security-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c508[printer-name,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c558[printer-location,4,41])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c5a8[printer-info,4,41])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c5f8[printer-more-info,4,45])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c660[job-quota-period,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c6a0[job-k-limit,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c6d8[job-page-limit,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c718[job-sheets-default,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808c780[device-uri,4,45])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a2eb0[color-supported,4,22])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a2ef0[pages-per-minute,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a3d58[printer-make-and-model,4,41])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x8099be0[media-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x809db70[media-default,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a2c80[output-bin-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a65f0[sides-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a6688[sides-default,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a16d0[finishings-supported,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a16a8[finishings-default,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a45f0[printer-type,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x81b6830, 0x8088dc8, (nil), 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f708[pdl-override-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f2a8[ipp-versions-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80a2d28[operations-supported,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f598[multiple-document-jobs-supported,4,22])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f570[multiple-operation-time-out,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f068[multiple-document-handling-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f428[charset-configured,4,47])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x809d9c0[charset-supported,4,47])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f3e8[natural-language-configured,4,48])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f3a8[generated-natural-language-supported,4,48])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f360[document-format-default,4,49])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f110[document-format-supported,4,80000049])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x8088e38[compression-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f318[job-priority-supported,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f0a8[job-priority-default,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f040[copies-supported,4,33])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808efe8[copies-default,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x80884b8[page-ranges-supported,4,22])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f470[number-up-supported,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x8088490[number-up-default,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f528[orientation-requested-supported,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x8089028[orientation-requested-default,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f5e8[job-hold-until-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x8088df0[job-hold-until-default,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x81b6830, 0x808f760[job-sheets-supported,4,42])
D [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: 6 status_code=0
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [16/Aug/2005:16:50:44 -0600] WriteClient: Removing fd 6 from OutputSet...
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:44 -0600] ReadClient: httpGets returned EOF...
D [16/Aug/2005:16:50:44 -0600] CloseClient: 6
d [16/Aug/2005:16:50:44 -0600] CloseClient: Removing fd 6 from InputSet and OutputSet...
d [16/Aug/2005:16:50:44 -0600] AcceptClient(lis=0x8082988) 0 NumClients = 0
D [16/Aug/2005:16:50:44 -0600] AcceptClient: 6 from localhost:631.
d [16/Aug/2005:16:50:44 -0600] AcceptClient: Adding fd 6 to InputSet...
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
D [16/Aug/2005:16:50:44 -0600] ReadClient: 6 POST / HTTP/1.1
d [16/Aug/2005:16:50:44 -0600] decode_auth(0xb70fa008): Authorization string = ""
d [16/Aug/2005:16:50:44 -0600] decode_auth: 6 username=""
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: con->uri = "/"
d [16/Aug/2005:16:50:44 -0600] FindBest: uri = "/"...
d [16/Aug/2005:16:50:44 -0600] FindBest: Location / Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: Location /admin Limit 7f
d [16/Aug/2005:16:50:44 -0600] FindBest: best = "/"
d [16/Aug/2005:16:50:44 -0600] IsAuthorized: auth = 0, satisfy=0...
d [16/Aug/2005:16:50:44 -0600] POST /
d [16/Aug/2005:16:50:44 -0600] CONTENT_TYPE = application/ipp
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 179, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 171, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 137, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 100, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 51, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 1, con->file = -1
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest(0xb70fa008[6]): operation_id = 000b
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: URI="ipp://localhost/printers/sdy13t03"
d [16/Aug/2005:16:50:44 -0600] get_printer_attrs(0xb70fa008[6], ipp://localhost/printers/sdy13t03)
d [16/Aug/2005:16:50:44 -0600] add_printer_state_reasons(0xb70fa008[6], 0x81fc7b8[sdy13t03])
d [16/Aug/2005:16:50:44 -0600] add_queued_job_count(0xb70fa008[6], 0x81fc7b8[sdy13t03])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a7cc0, 0x81f0188, (nil), 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x82a1ed8[printer-uri-supported,4,45])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x82a1218[uri-authentication-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f4548[uri-security-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f8e08[printer-name,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x82a1de8[printer-location,4,41])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f26f8[printer-info,4,41])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f12f8[printer-more-info,4,45])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f1218[job-quota-period,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f11d8[job-k-limit,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f03c0[job-page-limit,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f1290[job-sheets-default,4,42])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81f11b0[device-uri,4,45])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x80a8e58[color-supported,4,22])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x829f410[pages-per-minute,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x829f438[printer-make-and-model,4,41])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81b5428[media-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81fc3b0[media-default,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x80a7960[output-bin-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81faf98[sides-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x81e44d0[sides-default,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x829e430[finishings-supported,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x829e278[finishings-default,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x829efd8[printer-type,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attrs(0x80a7cc0, 0x8088dc8, (nil), 0)
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f708[pdl-override-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f2a8[ipp-versions-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x80a2d28[operations-supported,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f598[multiple-document-jobs-supported,4,22])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f570[multiple-operation-time-out,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f068[multiple-document-handling-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f428[charset-configured,4,47])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x809d9c0[charset-supported,4,47])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f3e8[natural-language-configured,4,48])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f3a8[generated-natural-language-supported,4,48])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f360[document-format-default,4,49])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f110[document-format-supported,4,80000049])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x8088e38[compression-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f318[job-priority-supported,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f0a8[job-priority-default,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f040[copies-supported,4,33])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808efe8[copies-default,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x80884b8[page-ranges-supported,4,22])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f470[number-up-supported,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x8088490[number-up-default,4,21])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f528[orientation-requested-supported,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x8089028[orientation-requested-default,4,23])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f5e8[job-hold-until-supported,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x8088df0[job-hold-until-default,4,44])
d [16/Aug/2005:16:50:44 -0600] copy_attribute(0x80a7cc0, 0x808f760[job-sheets-supported,4,42])
D [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: 6 status_code=0
d [16/Aug/2005:16:50:44 -0600] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [16/Aug/2005:16:50:44 -0600] WriteClient: Removing fd 6 from OutputSet...
d [16/Aug/2005:16:50:44 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:44 -0600] ReadClient: httpGets returned EOF...
D [16/Aug/2005:16:50:44 -0600] CloseClient: 6
d [16/Aug/2005:16:50:44 -0600] CloseClient: Removing fd 6 from InputSet and OutputSet...
d [16/Aug/2005:16:50:45 -0600] AcceptClient(lis=0x8082988) 0 NumClients = 0
D [16/Aug/2005:16:50:45 -0600] AcceptClient: 6 from localhost:631.
d [16/Aug/2005:16:50:45 -0600] AcceptClient: Adding fd 6 to InputSet...
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
D [16/Aug/2005:16:50:45 -0600] ReadClient: 6 POST /printers/sdy13t03 HTTP/1.1
d [16/Aug/2005:16:50:45 -0600] decode_auth(0xb70fa008): Authorization string = ""
d [16/Aug/2005:16:50:45 -0600] decode_auth: 6 username=""
d [16/Aug/2005:16:50:45 -0600] IsAuthorized: con->uri = "/printers/sdy13t03"
d [16/Aug/2005:16:50:45 -0600] FindBest: uri = "/printers/sdy13t03"...
d [16/Aug/2005:16:50:45 -0600] FindBest: Location / Limit 7f
d [16/Aug/2005:16:50:45 -0600] FindBest: Location /admin Limit 7f
d [16/Aug/2005:16:50:45 -0600] FindBest: best = "/"
d [16/Aug/2005:16:50:45 -0600] IsAuthorized: auth = 0, satisfy=0...
d [16/Aug/2005:16:50:45 -0600] POST /printers/sdy13t03
d [16/Aug/2005:16:50:45 -0600] CONTENT_TYPE = application/ipp
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11737, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11729, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11695, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11658, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11609, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11578, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11551, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11519, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11475, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11427, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=9, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 con->data_encoding = length, con->data_remaining = 11404, con->file = -1
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 REQUEST /var/spool/cups/00000032=8
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 writing 11394 bytes to 8
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6 Closing data file 8, size = 11394.
d [16/Aug/2005:16:50:45 -0600] ProcessIPPRequest(0xb70fa008[6]): operation_id = 0002
d [16/Aug/2005:16:50:45 -0600] ProcessIPPRequest: URI="ipp://localhost/printers/sdy13t03"
d [16/Aug/2005:16:50:45 -0600] print_job(0xb70fa008[6], ipp://localhost/printers/sdy13t03)
D [16/Aug/2005:16:50:45 -0600] print_job: auto-typing file...
D [16/Aug/2005:16:50:45 -0600] print_job: request file type is application/postscript.
d [16/Aug/2005:16:50:45 -0600] check_quotas(0xb70fa008[6], 0x81fc7b8[sdy13t03])
D [16/Aug/2005:16:50:45 -0600] check_quotas: requesting-user-name = 'dfelix'
D [16/Aug/2005:16:50:45 -0600] print_job: requesting-user-name = 'dfelix'
I [16/Aug/2005:16:50:45 -0600] Adding start banner page "standard" to job 42.
d [16/Aug/2005:16:50:45 -0600] copy_banner(0xb70fa008[6], 0x81e5e50[42], standard)
d [16/Aug/2005:16:50:45 -0600] add_file(con=0xb70fa008[6], job=42, filetype=application/postscript, compression=0)
d [16/Aug/2005:16:50:45 -0600] add_file(con=0xb70fa008[6], job=42, filetype=application/postscript, compression=0)
I [16/Aug/2005:16:50:45 -0600] Adding end banner page "none" to job 42.
d [16/Aug/2005:16:50:45 -0600] copy_banner(0xb70fa008[6], 0x81e5e50[42], none)
I [16/Aug/2005:16:50:45 -0600] Job 42 queued on 'sdy13t03' by 'dfelix'.
D [16/Aug/2005:16:50:45 -0600] Job 42 hold_until = 0
d [16/Aug/2005:16:50:45 -0600] SaveJob: Closing file 8...
D [16/Aug/2005:16:50:45 -0600] StartJob(42, 0x81fc7b8)
D [16/Aug/2005:16:50:45 -0600] StartJob() id = 42, file = 0/2
D [16/Aug/2005:16:50:45 -0600] job-sheets=standard,none
D [16/Aug/2005:16:50:45 -0600] banner_page = 1
D [16/Aug/2005:16:50:45 -0600] StartJob: argv = "sdy13t03","42","dfelix","standard input","1","document-name=standard\ input","/var/spool/cups/d00042-001"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[2]="USER=root"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[3]="CHARSET=iso-8859-1"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[4]="LANG=en_US"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[5]="TZ=America/Denver"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[6]="PPD=/etc/cups/ppd/sdy13t03.ppd"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[10]="CONTENT_TYPE=application/postscript"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[11]="DEVICE_URI=socket://sdy13s03:9100"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[12]="PRINTER=sdy13t03"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[15]="CUPS_SERVER=localhost"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[16]="IPP_PORT=631"
d [16/Aug/2005:16:50:45 -0600] StartJob: Allocating status buffer...
D [16/Aug/2005:16:50:45 -0600] StartJob: statusfds = [ 8 9 ]
D [16/Aug/2005:16:50:45 -0600] StartJob: filterfds[1] = [ 11 -1 ]
D [16/Aug/2005:16:50:45 -0600] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [16/Aug/2005:16:50:45 -0600] StartJob: filterfds[0] = [ 12 13 ]
D [16/Aug/2005:16:50:45 -0600] start_process("/usr/lib/cups/filter/pstops", 0xbffedb70, 0xbffecee0, 11, 13, 9)
I [16/Aug/2005:16:50:45 -0600] Started filter /usr/lib/cups/filter/pstops (PID 4466) for job 42.
D [16/Aug/2005:16:50:45 -0600] StartJob: backend = "/usr/lib/cups/backend/socket"
D [16/Aug/2005:16:50:45 -0600] StartJob: filterfds[1] = [ -1 11 ]
D [16/Aug/2005:16:50:45 -0600] start_process("/usr/lib/cups/backend/socket", 0xbffedb70, 0xbffecee0, 12, 11, 9)
I [16/Aug/2005:16:50:45 -0600] Started backend /usr/lib/cups/backend/socket (PID 4467) for job 42.
d [16/Aug/2005:16:50:45 -0600] StartJob: Adding fd 8 to InputSet...
d [16/Aug/2005:16:50:45 -0600] add_job_state_reasons(0xb70fa008[6], 42)
D [16/Aug/2005:16:50:45 -0600] ProcessIPPRequest: 6 status_code=0
d [16/Aug/2005:16:50:45 -0600] ProcessIPPRequest: Adding fd 6 to OutputSet...
d [16/Aug/2005:16:50:45 -0600] select_timeout: 1 seconds to send browse update
d [16/Aug/2005:16:50:45 -0600] WriteClient: Removing fd 6 from OutputSet...
I [16/Aug/2005:16:50:45 -0600] [Job 42] Attempting to connect to host sdy13s03 on port 9100
d [16/Aug/2005:16:50:45 -0600] ReadClient: 6, used=0, file=-1
d [16/Aug/2005:16:50:45 -0600] ReadClient: httpGets returned EOF...
D [16/Aug/2005:16:50:45 -0600] CloseClient: 6
d [16/Aug/2005:16:50:45 -0600] CloseClient: Removing fd 6 from InputSet and OutputSet...
I [16/Aug/2005:16:50:45 -0600] [Job 42] Connected to host, sending print job...
D [16/Aug/2005:16:50:45 -0600] [Job 42] Page = 612x792; 12,12 to 600,780
D [16/Aug/2005:16:50:45 -0600] [Job 42] slowcollate=0, slowduplex=0, sloworder=0
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%BoundingBox: 0 0 612 792
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%Pages: 1
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%LanguageLevel: 1
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%DocumentData: Clean7Bit
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%DocumentSuppliedResources: procset bannerprint/1.0
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%Creator: Michael Sweet, Easy Software Products
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%CreationDate: May 10, 2000
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%Title: Test Page
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%EndComments
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%BeginProlog
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%BeginResource procset bannerprint 1.1 0
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%EndResource
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%EndProlog
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%Page: 1 1
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%Page: 1 1
D [16/Aug/2005:16:50:45 -0600] [Job 42] pw = 587.5, pl = 768.0
D [16/Aug/2005:16:50:45 -0600] [Job 42] PageLeft = 12.2, PageRight = 599.8
D [16/Aug/2005:16:50:45 -0600] [Job 42] PageTop = 780.1, PageBottom = 12.1
D [16/Aug/2005:16:50:45 -0600] [Job 42] PageWidth = 612.0, PageLength = 792.0
D [16/Aug/2005:16:50:45 -0600] [Job 42] 0 %%EOF
D [16/Aug/2005:16:50:45 -0600] [Job 42] Saw EOF!
D [16/Aug/2005:16:50:45 -0600] [Job 42] pw = 587.5, pl = 768.0
D [16/Aug/2005:16:50:45 -0600] [Job 42] PageLeft = 12.2, PageRight = 599.8
D [16/Aug/2005:16:50:45 -0600] [Job 42] PageTop = 780.1, PageBottom = 12.1
D [16/Aug/2005:16:50:45 -0600] [Job 42] PageWidth = 612.0, PageLength = 792.0
I [16/Aug/2005:16:50:45 -0600] [Job 42] Print file sent, waiting for printer to finish...
d [16/Aug/2005:16:50:45 -0600] PID 4466 exited with no errors.
I [16/Aug/2005:16:50:45 -0600] [Job 42] Received 84 bytes of back-channel data!
D [16/Aug/2005:16:50:45 -0600] [Job 42] DATA: @PJL USTATUS DEVICE<0D><0A>CODE=10030<0D><0A>DISPLAY="42 dfelix standa rd
D [16/Aug/2005:16:50:45 -0600] [Job 42] DATA: input"<0D><0A>ONLINE=TRUE<0D><0A><0C>
I [16/Aug/2005:16:50:45 -0600] [Job 42] Received 49 bytes of back-channel data!
D [16/Aug/2005:16:50:45 -0600] [Job 42] DATA: @PJL USTATUS JOB<0D><0A>START<0D><0A>NAME="standard input"<0D><0A><0C>
d [16/Aug/2005:16:50:45 -0600] PID 4467 exited with no errors.
D [16/Aug/2005:16:50:45 -0600] UpdateJob: job 42, file 0 is complete.
d [16/Aug/2005:16:50:45 -0600] UpdateJob: Removing fd 8 from InputSet...
D [16/Aug/2005:16:50:45 -0600] StartJob(42, 0x81fc7b8)
D [16/Aug/2005:16:50:45 -0600] StartJob() id = 42, file = 1/2
D [16/Aug/2005:16:50:45 -0600] job-sheets=standard,none
D [16/Aug/2005:16:50:45 -0600] banner_page = 0
D [16/Aug/2005:16:50:45 -0600] StartJob: argv = "sdy13t03","42","dfelix","standard input","1","document-name=standard\ input","/var/spool/cups/d00042-002"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[2]="USER=root"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[3]="CHARSET=iso-8859-1"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[4]="LANG=en_US"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[5]="TZ=America/Denver"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[6]="PPD=/etc/cups/ppd/sdy13t03.ppd"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[10]="CONTENT_TYPE=application/postscript"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[11]="DEVICE_URI=socket://sdy13s03:9100"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[12]="PRINTER=sdy13t03"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[15]="CUPS_SERVER=localhost"
D [16/Aug/2005:16:50:45 -0600] StartJob: envp[16]="IPP_PORT=631"
D [16/Aug/2005:16:50:45 -0600] StartJob: statusfds = [ 6 8 ]
D [16/Aug/2005:16:50:45 -0600] StartJob: filterfds[1] = [ 9 -1 ]
D [16/Aug/2005:16:50:45 -0600] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [16/Aug/2005:16:50:45 -0600] StartJob: filterfds[0] = [ 11 12 ]
D [16/Aug/2005:16:50:45 -0600] start_process("/usr/lib/cups/filter/pstops", 0xbfff9090, 0xbfff8400, 9, 12, 8)
I [16/Aug/2005:16:50:45 -0600] Started filter /usr/lib/cups/filter/pstops (PID 4468) for job 42.
D [16/Aug/2005:16:50:45 -0600] StartJob: backend = "/usr/lib/cups/backend/socket"
D [16/Aug/2005:16:50:45 -0600] StartJob: filterfds[1] = [ -1 9 ]
D [16/Aug/2005:16:50:45 -0600] start_process("/usr/lib/cups/backend/socket", 0xbfff9090, 0xbfff8400, 11, 9, 8)
I [16/Aug/2005:16:50:45 -0600] Started backend /usr/lib/cups/backend/socket (PID 4469) for job 42.
d [16/Aug/2005:16:50:45 -0600] StartJob: Adding fd 6 to InputSet...
D [16/Aug/2005:16:50:45 -0600] [Job 42] Page = 612x792; 12,12 to 600,780
D [16/Aug/2005:16:50:45 -0600] [Job 42] slowcollate=0, slowduplex=0, sloworder=0
I [16/Aug/2005:16:50:45 -0600] [Job 42] Attempting to connect to host sdy13s03 on port 9100
I [16/Aug/2005:16:50:45 -0600] [Job 42] Connected to host, sending print job...
d [16/Aug/2005:16:50:45 -0600] PID 4468 exited with no errors.
d [16/Aug/2005:16:50:46 -0600] select_timeout: 4 seconds to send browse update
I [16/Aug/2005:16:50:50 -0600] [Job 42] Received 49 bytes of back-channel data!
D [16/Aug/2005:16:50:50 -0600] [Job 42] DATA: @PJL USTATUS JOB<0D><0A>START<0D><0A>NAME="standard input"<0D><0A><0C>
I [16/Aug/2005:16:50:50 -0600] [Job 42] Print file sent, waiting for printer to finish...
d [16/Aug/2005:16:50:50 -0600] PID 4469 exited with no errors.
D [16/Aug/2005:16:50:50 -0600] UpdateJob: job 42, file 1 is complete.
d [16/Aug/2005:16:50:50 -0600] UpdateJob: Removing fd 6 from InputSet...
D [16/Aug/2005:16:50:50 -0600] CancelJob: id = 42
D [16/Aug/2005:16:50:50 -0600] StopJob: id = 42, force = 0
D [16/Aug/2005:16:50:50 -0600] StopJob: printer state is 3
d [16/Aug/2005:16:50:50 -0600] StopJob: Freeing status buffer...
d [16/Aug/2005:16:50:50 -0600] SaveJob: Closing file 6...
d [16/Aug/2005:16:50:50 -0600] SaveJob: Closing file 6...
d [16/Aug/2005:16:50:51 -0600] select_timeout: 1 seconds to
More information about the cups
mailing list