[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

[debian-users:47320] Re: CUPSでNPDLを使う場合



 武藤様 返答ありがとうございます。

 gs-esp(mtu5)を適用してテストを行ってみました。
 B5サイズは出るようになりましたがA5では相変わらずB5で出ます。


 また、/var/log/cups/error_logをdebugにして取り直してみました。
 参考になりますでしょうか・・・

 よろしくおねがいいいたします。


D [25/Sep/2006:09:09:35 +0900] StopJob: id = 33, force = 1
D [25/Sep/2006:09:09:35 +0900] StopJob: printer state is 3
I [25/Sep/2006:09:09:35 +0900] Listening to 0:631
D [25/Sep/2006:09:09:35 +0900] AddLocation: added location '/'
D [25/Sep/2006:09:09:35 +0900] DenyIP: / deny 00000000/00000000
D [25/Sep/2006:09:09:35 +0900] AllowIP: / allow 7f000001/ffffffff
D [25/Sep/2006:09:09:35 +0900] AddLocation: added location '/jobs'
D [25/Sep/2006:09:09:35 +0900] AddLocation: added location '/admin'
D [25/Sep/2006:09:09:35 +0900] DenyIP: /admin deny 00000000/00000000
D [25/Sep/2006:09:09:35 +0900] AllowIP: /admin allow 7f000001/ffffffff
I [25/Sep/2006:09:09:35 +0900] Loaded configuration file "/etc/cups/cupsd.conf"
I [25/Sep/2006:09:09:35 +0900] Configured for up to 100 clients.
I [25/Sep/2006:09:09:35 +0900] Allowing up to 100 client connections per host.
I [25/Sep/2006:09:09:35 +0900] Full reload is required.
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer escp...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp1...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp11...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp12...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp2...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp3...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp4...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp5...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer lp6...
D [25/Sep/2006:09:09:35 +0900] LoadAllPrinters: Loading printer ps3...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ipp"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "smb"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "http"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "lpd"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "parallel:/dev/lp0"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "socket"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp0"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp1"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp2"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp3"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp4"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp5"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp6"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp7"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp8"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp9"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp10"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp11"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp12"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp13"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp14"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "usb:/dev/usb/lp15"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo0"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo1"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo2"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo3"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo4"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo5"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo6"...
D [25/Sep/2006:09:09:35 +0900] LoadDevices: Added device "ccp:/var/ccpd/fifo7"...
I [25/Sep/2006:09:09:35 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2563 PPDs...
I [25/Sep/2006:09:09:35 +0900] LoadPPDs: No new or changed PPDs...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Scanning /var/spool/cups...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 1...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 2...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 4...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 3...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 5...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 6...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 7...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 8...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 9...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 10...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 11...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 12...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 13...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 14...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 15...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 33...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 19...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 34...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 35...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 36...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 23...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Loading attributes for job 31...
D [25/Sep/2006:09:09:35 +0900] LoadAllJobs: Auto-typing document file d00033-001...
I [25/Sep/2006:09:09:35 +0900] Full reload complete.
D [25/Sep/2006:09:09:35 +0900] StartListening: NumListeners=1
D [25/Sep/2006:09:09:35 +0900] StartListening: address=00000000 port=631
D [25/Sep/2006:09:09:35 +0900] ResumeListening: setting input bits...
D [25/Sep/2006:09:09:35 +0900] StartJob(33, 0x80ad908)
D [25/Sep/2006:09:09:35 +0900] StartJob() id = 33, file = 0/1
D [25/Sep/2006:09:09:35 +0900] job-sheets=none,none
D [25/Sep/2006:09:09:35 +0900] banner_page = 0
D [25/Sep/2006:09:09:35 +0900] StartJob: argv = "lp1","33","root","Test
Page","1","","/var/spool/cups/d00033-001"
D [25/Sep/2006:09:09:35 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[2]="USER=root"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[3]="CHARSET=iso-8859-1"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[4]="LANG=en_US"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[5]="TZ=Asia/Tokyo"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[6]="PPD=/etc/cups/ppd/lp1.ppd"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [25/Sep/2006:09:09:35 +0900] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [25/Sep/2006:09:09:35 +0900] StartJob:
envp[10]="CONTENT_TYPE=application/postscript"
D [25/Sep/2006:09:09:36 +0900] StartJob:
envp[11]="DEVICE_URI=lpd://10.1.2.150/queue_1"
D [25/Sep/2006:09:09:36 +0900] StartJob: envp[12]="PRINTER=lp1"
D [25/Sep/2006:09:09:36 +0900] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups"
D [25/Sep/2006:09:09:36 +0900] StartJob:
envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [25/Sep/2006:09:09:36 +0900] StartJob: envp[15]="CUPS_SERVER=localhost"
D [25/Sep/2006:09:09:36 +0900] StartJob: envp[16]="IPP_PORT=631"
D [25/Sep/2006:09:09:36 +0900] StartJob: statusfds = [ 5 6 ]
D [25/Sep/2006:09:09:36 +0900] StartJob: filterfds[1] = [ 7 -1 ]
D [25/Sep/2006:09:09:36 +0900] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [25/Sep/2006:09:09:36 +0900] StartJob: filterfds[0] = [ 8 9 ]
D [25/Sep/2006:09:09:36 +0900] start_process("/usr/lib/cups/filter/pstops",
0xbfffb4b0, 0xbfffa820, 7, 9, 6)
I [25/Sep/2006:09:09:36 +0900] Started filter /usr/lib/cups/filter/pstops (PID
9864) for job 33.
D [25/Sep/2006:09:09:36 +0900] StartJob: filter =
"/usr/lib/cups/filter/foomatic-rip"
D [25/Sep/2006:09:09:36 +0900] StartJob: filterfds[1] = [ 7 10 ]
D [25/Sep/2006:09:09:36 +0900]
start_process("/usr/lib/cups/filter/foomatic-rip", 0xbfffb4b0, 0xbfffa820, 8, 10, 6)
I [25/Sep/2006:09:09:36 +0900] Started filter /usr/lib/cups/filter/foomatic-rip
(PID 9865) for job 33.
D [25/Sep/2006:09:09:36 +0900] StartJob: backend = "/usr/lib/cups/backend/lpd"
D [25/Sep/2006:09:09:36 +0900] StartJob: filterfds[0] = [ -1 8 ]
D [25/Sep/2006:09:09:36 +0900] start_process("/usr/lib/cups/backend/lpd",
0xbfffb4b0, 0xbfffa820, 7, 8, 6)
I [25/Sep/2006:09:09:36 +0900] Started backend /usr/lib/cups/backend/lpd (PID
9866) for job 33.
D [25/Sep/2006:09:09:36 +0900] [Job 33] Page = 595x842; 0,0 to 595,842
D [25/Sep/2006:09:09:36 +0900] [Job 33] slowcollate=0, slowduplex=0, sloworder=0
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%BoundingBox: 0 0 612 792
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%Pages: 1
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%LanguageLevel: 1
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%DocumentData: Clean7Bit
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%DocumentSuppliedResources: procset
testprint/1.1
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%DocumentNeededResources: font
Helvetica Helvetica-Bold Times-Roman
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%Creator: Michael Sweet, Easy
Software Products
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%CreationDate: May 11, 1999
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%Title: Test Page
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%EndComments
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%BeginProlog
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%BeginResource procset testprint 1.1 0
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%EndResource
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%EndProlog
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%Page: 1 1
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%Page: 1 1
D [25/Sep/2006:09:09:36 +0900] [Job 33] pw = 595.0, pl = 842.0
D [25/Sep/2006:09:09:36 +0900] [Job 33] PageLeft = 0.0, PageRight = 595.0
D [25/Sep/2006:09:09:36 +0900] [Job 33] PageTop = 842.0, PageBottom = 0.0
D [25/Sep/2006:09:09:36 +0900] [Job 33] PageWidth = 595.0, PageLength = 842.0
D [25/Sep/2006:09:09:36 +0900] [Job 33] perl: warning: Setting locale failed.
D [25/Sep/2006:09:09:36 +0900] [Job 33] perl: warning: Please check that your
locale settings:
D [25/Sep/2006:09:09:36 +0900] [Job 33] LANGUAGE = (unset),
D [25/Sep/2006:09:09:36 +0900] [Job 33] LC_ALL = (unset),
D [25/Sep/2006:09:09:36 +0900] [Job 33] LANG = "en_US"
D [25/Sep/2006:09:09:36 +0900] [Job 33] are supported and installed on your system.
D [25/Sep/2006:09:09:36 +0900] [Job 33] perl: warning: Falling back to the
standard locale ("C").
D [25/Sep/2006:09:09:36 +0900] [Job 33] foomatic-rip version $Revision: 3.43.2.9
$ running...
D [25/Sep/2006:09:09:36 +0900] [Job 33] Parsing PPD file ...
D [25/Sep/2006:09:09:36 +0900] [Job 33] *cupsFilter:
"application/vnd.cups-postscript 0 foomatic-rip"
D [25/Sep/2006:09:09:36 +0900] [Job 33] Added option ColorSpace
D [25/Sep/2006:09:09:36 +0900] [Job 33] Added option PageSize
D [25/Sep/2006:09:09:36 +0900] [Job 33] Added option PageRegion
D [25/Sep/2006:09:09:36 +0900] [Job 33] Added option ImageableArea
D [25/Sep/2006:09:09:36 +0900] [Job 33] Added option PaperDimension
D [25/Sep/2006:09:09:36 +0900] [Job 33] Added option Resolution
D [25/Sep/2006:09:09:36 +0900] [Job 33] Added option Font
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] Parameter Summary
D [25/Sep/2006:09:09:36 +0900] [Job 33] -----------------
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] Spooler: cups
D [25/Sep/2006:09:09:36 +0900] [Job 33] Printer: lp1
D [25/Sep/2006:09:09:36 +0900] [Job 33] PPD file: /etc/cups/ppd/lp1.ppd
D [25/Sep/2006:09:09:36 +0900] [Job 33] Printer model: NEC MultiWriter
Foomatic/npdl (recommended)
D [25/Sep/2006:09:09:36 +0900] [Job 33] Job title: Test Page
D [25/Sep/2006:09:09:36 +0900] [Job 33] File(s) to be printed:
D [25/Sep/2006:09:09:36 +0900] [Job 33] <STDIN>
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33]
================================================
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] File: <STDIN>
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33]
================================================
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] Reading PostScript input ...
D [25/Sep/2006:09:09:36 +0900] [Job 33] --> This document is DSC-conforming!
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] -----------
D [25/Sep/2006:09:09:36 +0900] [Job 33] Found: %%BeginProlog
D [25/Sep/2006:09:09:36 +0900] [Job 33] 0 %%EOF
D [25/Sep/2006:09:09:36 +0900] [Job 33] Saw EOF!
D [25/Sep/2006:09:09:36 +0900] [Job 33] Found: %%EndProlog
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] -----------
D [25/Sep/2006:09:09:36 +0900] [Job 33] Found: %%BeginSetup
D [25/Sep/2006:09:09:36 +0900] [Job 33] Found: %%BeginFeature: *PageSize A4
D [25/Sep/2006:09:09:36 +0900] [Job 33] Option: PageSize=A4 --> Option will be
set by PostScript interpreter
D [25/Sep/2006:09:09:36 +0900] [Job 33] Found: %%BeginFeature: *Resolution
400x400dpi
D [25/Sep/2006:09:09:36 +0900] [Job 33] Option: Resolution=400x400dpi --> Option
will be set by PostScript interpreter
D [25/Sep/2006:09:09:36 +0900] [Job 33] Found: %%EndSetup
D [25/Sep/2006:09:09:36 +0900] [Job 33] Inserting PostScript code for CUPS' page
accounting
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] -----------
D [25/Sep/2006:09:09:36 +0900] [Job 33] New page:  1 1
D [25/Sep/2006:09:09:36 +0900] [Job 33] Inserting option code into "PageSetup"
section.
D [25/Sep/2006:09:09:36 +0900] [Job 33] No page header or page header not
DSC-conforming
D [25/Sep/2006:09:09:36 +0900] [Job 33] Stopping search for page header options
D [25/Sep/2006:09:09:36 +0900] [Job 33] Found:
D [25/Sep/2006:09:09:36 +0900] [Job 33] pageHeight sub			% Move down...
D [25/Sep/2006:09:09:36 +0900] [Job 33] --> Output goes directly to the renderer
now.
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] Starting renderer
D [25/Sep/2006:09:09:36 +0900] [Job 33] JCL: <job data>
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] renderer PID kid4=9868
D [25/Sep/2006:09:09:36 +0900] [Job 33] renderer command: gs -q -dBATCH
-dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=npdl -sOutputFile=- -
D [25/Sep/2006:09:09:36 +0900] [Job 33] perl: warning: Setting locale failed.
D [25/Sep/2006:09:09:36 +0900] [Job 33] perl: warning: Please check that your
locale settings:
D [25/Sep/2006:09:09:36 +0900] [Job 33] LANGUAGE = (unset),
D [25/Sep/2006:09:09:36 +0900] [Job 33] LC_ALL = (unset),
D [25/Sep/2006:09:09:36 +0900] [Job 33] LANG = "en_US"
D [25/Sep/2006:09:09:36 +0900] [Job 33] are supported and installed on your system.
D [25/Sep/2006:09:09:36 +0900] [Job 33] perl: warning: Falling back to the
standard locale ("C").
D [25/Sep/2006:09:09:36 +0900] [Job 33] foomatic-gswrapper: gs '-dBATCH'
'-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=npdl' '-sOutputFile=/dev/fd/3'
'/dev/fd/0' 3>&1 1>&2
D [25/Sep/2006:09:09:36 +0900] [Job 33]
D [25/Sep/2006:09:09:36 +0900] [Job 33] Closing renderer
D [25/Sep/2006:09:09:37 +0900] [Job 33] KID3 exited with status 0
D [25/Sep/2006:09:09:37 +0900] [Job 33] tail process done writing data to STDOUT
D [25/Sep/2006:09:09:37 +0900] [Job 33] KID4 exited with status 0
D [25/Sep/2006:09:09:37 +0900] [Job 33] Renderer exit stat: 0
D [25/Sep/2006:09:09:37 +0900] [Job 33] KID4 finished
D [25/Sep/2006:09:09:37 +0900] [Job 33] KID3 finished
D [25/Sep/2006:09:09:37 +0900] [Job 33] Renderer process finished
D [25/Sep/2006:09:09:37 +0900] [Job 33]
D [25/Sep/2006:09:09:37 +0900] [Job 33] Closing foomatic-rip.
D [25/Sep/2006:09:09:39 +0900] AcceptClient: 6 from localhost:631.
D [25/Sep/2006:09:09:39 +0900] ReadClient: 6 GET
/admin/?op=config-printer&printer_name=lp12 HTTP/1.1
D [25/Sep/2006:09:09:39 +0900] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID
= 9871
I [25/Sep/2006:09:09:39 +0900] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9871)
D [25/Sep/2006:09:09:39 +0900] SendCommand: 6 file=8
D [25/Sep/2006:09:09:39 +0900] AcceptClient: 9 from localhost:631.
D [25/Sep/2006:09:09:39 +0900] AcceptClient: 10 from localhost:631.
D [25/Sep/2006:09:09:39 +0900] ReadClient: 10 POST / HTTP/1.1
D [25/Sep/2006:09:09:39 +0900] ProcessIPPRequest: 10 status_code=0
D [25/Sep/2006:09:09:39 +0900] ReadClient: 10 GET /printers/lp12.ppd HTTP/1.1
D [25/Sep/2006:09:09:39 +0900] SendFile: 10 file=11
D [25/Sep/2006:09:09:39 +0900] AcceptClient: 11 from localhost:631.
D [25/Sep/2006:09:09:39 +0900] ReadClient: 11 POST / HTTP/1.1
D [25/Sep/2006:09:09:39 +0900] ProcessIPPRequest: 11 status_code=1
D [25/Sep/2006:09:09:40 +0900] ReadClient: 9 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 9 status_code=0
D [25/Sep/2006:09:09:40 +0900] CloseClient: 9
D [25/Sep/2006:09:09:40 +0900] CloseClient: 10
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 GET /favicon.ico HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] SendError: 6 code=404 (Not Found)
D [25/Sep/2006:09:09:40 +0900] CloseClient: 6
D [25/Sep/2006:09:09:40 +0900] ReadClient: 11 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 11 status_code=1
D [25/Sep/2006:09:09:40 +0900] ReadClient: 11 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 11 status_code=0
D [25/Sep/2006:09:09:40 +0900] CloseClient: 11
D [25/Sep/2006:09:09:40 +0900] AcceptClient: 6 from localhost:631.
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:41 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:41 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:41 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:41 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:41 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:41 +0900] ReadClient: 8 POST /admin HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID
= 9882
I [25/Sep/2006:09:09:41 +0900] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9882)
D [25/Sep/2006:09:09:41 +0900] SendCommand: 8 file=10
D [25/Sep/2006:09:09:41 +0900] AcceptClient: 9 from localhost:631.
D [25/Sep/2006:09:09:41 +0900] AcceptClient: 11 from localhost:631.
D [25/Sep/2006:09:09:41 +0900] ReadClient: 11 POST / HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] ProcessIPPRequest: 11 status_code=0
D [25/Sep/2006:09:09:41 +0900] ReadClient: 11 GET /printers/lp12.ppd HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] SendFile: 11 file=12
D [25/Sep/2006:09:09:41 +0900] ReadClient: 9 POST /admin/ HTTP/1.1
D [25/Sep/2006:09:09:41 +0900] SendError: 9 code=401 (Unauthorized)
D [25/Sep/2006:09:09:41 +0900] CloseClient: 9
D [25/Sep/2006:09:09:42 +0900] AcceptClient: 9 from localhost:631.
D [25/Sep/2006:09:09:42 +0900] ReadClient: 9 POST /admin/ HTTP/1.1
D [25/Sep/2006:09:09:42 +0900] add_printer: Copied PPD file successfully!
I [25/Sep/2006:09:09:42 +0900] Saving printers.conf...
I [25/Sep/2006:09:09:42 +0900] Printer 'lp12' modified by 'root'.
D [25/Sep/2006:09:09:42 +0900] ProcessIPPRequest: 9 status_code=0
D [25/Sep/2006:09:09:42 +0900] CloseClient: 11
D [25/Sep/2006:09:09:42 +0900] CloseClient: 9
D [25/Sep/2006:09:09:42 +0900] ReadClient: 8 GET /favicon.ico HTTP/1.1
D [25/Sep/2006:09:09:42 +0900] SendError: 8 code=404 (Not Found)
D [25/Sep/2006:09:09:42 +0900] CloseClient: 8
D [25/Sep/2006:09:09:42 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:42 +0900] ReadClient: 8 GET /printers HTTP/1.1
D [25/Sep/2006:09:09:42 +0900] CGI /usr/lib/cups/cgi-bin/printers.cgi started -
PID = 9883
I [25/Sep/2006:09:09:42 +0900] Started "/usr/lib/cups/cgi-bin/printers.cgi"
(pid=9883)
D [25/Sep/2006:09:09:42 +0900] SendCommand: 8 file=10
D [25/Sep/2006:09:09:42 +0900] AcceptClient: 9 from localhost:631.
D [25/Sep/2006:09:09:42 +0900] ReadClient: 9 POST / HTTP/1.1
D [25/Sep/2006:09:09:42 +0900] ProcessIPPRequest: 9 status_code=1
D [25/Sep/2006:09:09:42 +0900] ReadClient: 9 POST / HTTP/1.1
D [25/Sep/2006:09:09:43 +0900] ProcessIPPRequest: 9 status_code=1
D [25/Sep/2006:09:09:43 +0900] CloseClient: 9
D [25/Sep/2006:09:09:43 +0900] ReadClient: 8 GET /favicon.ico HTTP/1.1
D [25/Sep/2006:09:09:43 +0900] SendError: 8 code=404 (Not Found)
D [25/Sep/2006:09:09:43 +0900] CloseClient: 8
D [25/Sep/2006:09:09:44 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:44 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:44 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:44 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:09:44 +0900] CloseClient: 8
D [25/Sep/2006:09:09:44 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:44 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:09:44 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:44 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:44 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:44 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:44 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:45 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:45 +0900] ReadClient: 8 GET
/printers/lp12?op=print-test-page HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] CGI /usr/lib/cups/cgi-bin/printers.cgi started -
PID = 9894
I [25/Sep/2006:09:09:45 +0900] Started "/usr/lib/cups/cgi-bin/printers.cgi"
(pid=9894)
D [25/Sep/2006:09:09:45 +0900] SendCommand: 8 file=10
D [25/Sep/2006:09:09:45 +0900] AcceptClient: 9 from localhost:631.
D [25/Sep/2006:09:09:45 +0900] ReadClient: 9 POST /printers/lp12 HTTP/1.1
D [25/Sep/2006:09:09:45 +0900] print_job: request file type is
application/postscript.
D [25/Sep/2006:09:09:45 +0900] check_quotas: requesting-user-name = 'root'
D [25/Sep/2006:09:09:45 +0900] print_job: requesting-user-name = 'root'
D [25/Sep/2006:09:09:45 +0900] Adding default job-sheets values "none,none"...
I [25/Sep/2006:09:09:45 +0900] Adding start banner page "none" to job 37.
I [25/Sep/2006:09:09:45 +0900] Adding end banner page "none" to job 37.
I [25/Sep/2006:09:09:45 +0900] Job 37 queued on 'lp12' by 'root'.
D [25/Sep/2006:09:09:45 +0900] Job 37 hold_until = 0
D [25/Sep/2006:09:09:45 +0900] StartJob(37, 0x80b7230)
D [25/Sep/2006:09:09:45 +0900] StartJob() id = 37, file = 0/1
D [25/Sep/2006:09:09:45 +0900] job-sheets=none,none
D [25/Sep/2006:09:09:45 +0900] banner_page = 0
D [25/Sep/2006:09:09:45 +0900] StartJob: argv = "lp12","37","root","Test
Page","1","","/var/spool/cups/d00037-001"
D [25/Sep/2006:09:09:45 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[2]="USER=root"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[3]="CHARSET=iso-8859-1"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[4]="LANG=en_US"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[5]="TZ=Asia/Tokyo"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[6]="PPD=/etc/cups/ppd/lp12.ppd"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [25/Sep/2006:09:09:45 +0900] StartJob:
envp[10]="CONTENT_TYPE=application/postscript"
D [25/Sep/2006:09:09:45 +0900] StartJob:
envp[11]="DEVICE_URI=lpd://192.168.20.6/queue_12"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[12]="PRINTER=lp12"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups"
D [25/Sep/2006:09:09:45 +0900] StartJob:
envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[15]="CUPS_SERVER=localhost"
D [25/Sep/2006:09:09:45 +0900] StartJob: envp[16]="IPP_PORT=631"
D [25/Sep/2006:09:09:45 +0900] StartJob: statusfds = [ 11 12 ]
D [25/Sep/2006:09:09:45 +0900] StartJob: filterfds[1] = [ 13 -1 ]
D [25/Sep/2006:09:09:45 +0900] StartJob: filter = "/usr/lib/cups/filter/necfilter"
D [25/Sep/2006:09:09:45 +0900] StartJob: filterfds[0] = [ 14 15 ]
D [25/Sep/2006:09:09:45 +0900] start_process("/usr/lib/cups/filter/necfilter",
0xbffeff60, 0xbffef2d0, 13, 15, 12)
I [25/Sep/2006:09:09:45 +0900] Started filter /usr/lib/cups/filter/necfilter
(PID 9895) for job 37.
D [25/Sep/2006:09:09:45 +0900] StartJob: backend = "/usr/lib/cups/backend/lpd"
D [25/Sep/2006:09:09:45 +0900] StartJob: filterfds[1] = [ -1 13 ]
D [25/Sep/2006:09:09:45 +0900] start_process("/usr/lib/cups/backend/lpd",
0xbffeff60, 0xbffef2d0, 14, 13, 12)
I [25/Sep/2006:09:09:45 +0900] Started backend /usr/lib/cups/backend/lpd (PID
9896) for job 37.
D [25/Sep/2006:09:09:45 +0900] ProcessIPPRequest: 9 status_code=0
D [25/Sep/2006:09:09:45 +0900] CloseClient: 9
D [25/Sep/2006:09:09:46 +0900] ReadClient: 8 GET /favicon.ico HTTP/1.1
D [25/Sep/2006:09:09:46 +0900] SendError: 8 code=404 (Not Found)
D [25/Sep/2006:09:09:46 +0900] CloseClient: 8
D [25/Sep/2006:09:09:46 +0900] [Job 37] Connected on ports 515 (local 1022)...
D [25/Sep/2006:09:09:46 +0900] [Job 37] lpd_command 02 queue_12
D [25/Sep/2006:09:09:46 +0900] [Job 37] Sending command string (10 bytes)...
D [25/Sep/2006:09:09:46 +0900] [Job 37] Reading command status...
D [25/Sep/2006:09:09:46 +0900] [Job 37] lpd_command returning 0
D [25/Sep/2006:09:09:46 +0900] [Job 37] Control file is:
D [25/Sep/2006:09:09:46 +0900] [Job 37] Hdebian-r31
D [25/Sep/2006:09:09:46 +0900] [Job 37] Proot
D [25/Sep/2006:09:09:46 +0900] [Job 37] JTest Page
D [25/Sep/2006:09:09:46 +0900] [Job 37] ldfA896debian-r31
D [25/Sep/2006:09:09:46 +0900] [Job 37] UdfA896debian-r31
D [25/Sep/2006:09:09:46 +0900] [Job 37] NTest Page
D [25/Sep/2006:09:09:46 +0900] [Job 37] lpd_command 02 76 cfA896debian-r31
D [25/Sep/2006:09:09:46 +0900] [Job 37] Sending command string (21 bytes)...
D [25/Sep/2006:09:09:46 +0900] [Job 37] Reading command status...
D [25/Sep/2006:09:09:46 +0900] [Job 37] lpd_command returning 0
D [25/Sep/2006:09:09:46 +0900] [Job 37] lpd_command 03 50 dfA896debian-r31
D [25/Sep/2006:09:09:46 +0900] [Job 37] Sending command string (21 bytes)...
D [25/Sep/2006:09:09:46 +0900] [Job 37] Reading command status...
D [25/Sep/2006:09:09:46 +0900] [Job 37] lpd_command returning 0
D [25/Sep/2006:09:09:46 +0900] UpdateJob: job 37, file 0 is complete.
D [25/Sep/2006:09:09:46 +0900] CancelJob: id = 37
D [25/Sep/2006:09:09:46 +0900] StopJob: id = 37, force = 0
D [25/Sep/2006:09:09:46 +0900] StopJob: printer state is 3
D [25/Sep/2006:09:09:47 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:47 +0900] ReadClient: 8 GET /printers HTTP/1.1
D [25/Sep/2006:09:09:47 +0900] CGI /usr/lib/cups/cgi-bin/printers.cgi started -
PID = 9898
I [25/Sep/2006:09:09:47 +0900] Started "/usr/lib/cups/cgi-bin/printers.cgi"
(pid=9898)
D [25/Sep/2006:09:09:47 +0900] SendCommand: 8 file=10
D [25/Sep/2006:09:09:47 +0900] AcceptClient: 9 from localhost:631.
D [25/Sep/2006:09:09:47 +0900] ReadClient: 9 POST / HTTP/1.1
D [25/Sep/2006:09:09:47 +0900] ProcessIPPRequest: 9 status_code=1
D [25/Sep/2006:09:09:47 +0900] ReadClient: 9 POST / HTTP/1.1
D [25/Sep/2006:09:09:47 +0900] ProcessIPPRequest: 9 status_code=1
D [25/Sep/2006:09:09:47 +0900] CloseClient: 9
D [25/Sep/2006:09:09:48 +0900] ReadClient: 8 GET /favicon.ico HTTP/1.1
D [25/Sep/2006:09:09:48 +0900] SendError: 8 code=404 (Not Found)
D [25/Sep/2006:09:09:48 +0900] CloseClient: 8
D [25/Sep/2006:09:09:49 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:49 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:49 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:49 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:09:49 +0900] CloseClient: 8
D [25/Sep/2006:09:09:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:49 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:09:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:49 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:49 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:49 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:54 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:54 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:54 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:54 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:54 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:54 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:54 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:09:54 +0900] CloseClient: 8
D [25/Sep/2006:09:09:54 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:54 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:09:54 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:54 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:54 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:54 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:54 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:54 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:55 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:55 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:55 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:55 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:55 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:55 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:55 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:55 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:55 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:55 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:55 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:55 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:55 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:55 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:59 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:09:59 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:59 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:59 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:59 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:09:59 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:09:59 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:09:59 +0900] CloseClient: 8
D [25/Sep/2006:09:09:59 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:59 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:09:59 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:59 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:59 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:59 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:09:59 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:09:59 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:00 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:00 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:00 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:00 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:00 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:00 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:00 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:00 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:00 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:00 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:00 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:00 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:00 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:00 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:04 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:04 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:04 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:04 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:04 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:04 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:04 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:04 +0900] CloseClient: 8
D [25/Sep/2006:09:10:04 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:04 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:04 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:04 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:04 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:05 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:05 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:09 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:09 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:09 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:09 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:09 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:09 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:09 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:09 +0900] CloseClient: 8
D [25/Sep/2006:09:10:09 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:09 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:09 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:09 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:09 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:09 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:09 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:09 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:10 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:10 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:10 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:10 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:10 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:10 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:10 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:10 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:10 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:10 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:10 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:10 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:10 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:10 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:14 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:14 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:14 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:14 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:14 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:14 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:14 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:14 +0900] CloseClient: 8
D [25/Sep/2006:09:10:14 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:14 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:14 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:14 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:14 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:14 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:14 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:15 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:15 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:15 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:15 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:15 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:15 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:15 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:15 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:19 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:19 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:19 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:19 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:19 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:19 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:19 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:19 +0900] CloseClient: 8
D [25/Sep/2006:09:10:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:19 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:19 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:19 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:19 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:20 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:20 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:20 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:20 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:20 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:20 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:20 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:20 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:20 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:20 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:20 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:20 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:20 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:20 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:24 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:24 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:24 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:24 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:24 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:24 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:24 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:24 +0900] CloseClient: 8
D [25/Sep/2006:09:10:24 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:24 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:24 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:24 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:24 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:24 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:24 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:24 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:25 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:25 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:25 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:25 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:25 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:25 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:25 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:25 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:25 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:25 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:25 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:25 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:25 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:25 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:29 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:29 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:29 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:29 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:29 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:29 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:29 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:29 +0900] CloseClient: 8
D [25/Sep/2006:09:10:29 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:29 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:29 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:29 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:29 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:30 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:34 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:34 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:34 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:34 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:34 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:34 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:34 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:34 +0900] CloseClient: 8
D [25/Sep/2006:09:10:34 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:34 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:34 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:34 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:34 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:34 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:34 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:35 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:35 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:35 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:35 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:35 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:35 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:35 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:35 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:39 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:39 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:39 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:39 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:39 +0900] CloseClient: 8
D [25/Sep/2006:09:10:39 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:39 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:39 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:39 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:39 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:39 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:40 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:40 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:44 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:44 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:44 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:44 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:44 +0900] CloseClient: 8
D [25/Sep/2006:09:10:44 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:44 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:44 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:44 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:44 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:45 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:45 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:49 +0900] AcceptClient: 8 from localhost:631.
D [25/Sep/2006:09:10:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:49 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:49 +0900] ProcessIPPRequest: 8 status_code=1
D [25/Sep/2006:09:10:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [25/Sep/2006:09:10:49 +0900] ProcessIPPRequest: 8 status_code=0
D [25/Sep/2006:09:10:49 +0900] CloseClient: 8
D [25/Sep/2006:09:10:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:49 +0900] ProcessIPPRequest: 6 status_code=0
D [25/Sep/2006:09:10:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:49 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:49 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:49 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1
D [25/Sep/2006:09:10:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [25/Sep/2006:09:10:50 +0900] ProcessIPPRequest: 6 status_code=1