[vine-users:077178] Re: Canon LBP-1210 プリンタの接続ポートが勝 手に変わって、印刷できな い

[宮脇利夫 <hibari50@xxxxxxxxxxxxxxxxx>]

宮脇です。
長南さん、いつも丁寧なフォローをありがとうございます。
返信が遅れまして、申し訳ありません。最近は忙しくて、じっくり落ち着いてパ
ソコンの前に座る時間がありませんでした。しかし、昨日から今日にかけて時間
が取れましたので、表題の問題についてじっくり取り込んでみました。
しかし、結論から先に言いますと、まだ問題は解決していません。
やってみたことは以下の通りです。

1) いろいろやってみましたが、どうしても問題が解決しない（canon LBP1210も
canon Pixus iP4100も、印刷できない）ので、思い切ってhomeディレクトリだけ
残して、最初からvine4.0をクリーンインストールし直しました。そして、apt-
get upgradeでvine4.1にアップグレードしました。
それから、栗山さんの[vine-users:070479] Canon LBP1210導入報告 に従って、
canon LBP1210をインストールしました。

2) その過程で、#/sbin/service ccpd start とした時、canon LBP1210ではなく
canon iP4100の方に電源が入りました。（canon iP4100は印刷する時、PCからの
信号で電源が入るような仕組みになっています）ーこれはおかしな現象です。
vineはクリーンインストールし直しましたので、canon iP4100は残っていないは
ずですし、canon iP4100はまだインストールしていないのですが・・。
cups印刷マネージャで見ると、canon LBP1210プロパティの「検出されたプリン
タ」の中に、caon iP4100があるのです！これは、homeディレクトリを残したか
らでしょうか、どうもよくわかりません。

> 2) 前のメールにも書きましたが、ccpd は動いていますか。ps ax で調べて
>    ください。
ccpdは現在、以下のようになっています。（以下の報告はみな、vineをインス
トールし直して、canon LBP1210を導入した現在の状態）

[root@giga root]# ps ax | grep ccpd
 3822 pts/0    S+     0:00 grep ccpd

> 3) /etc/ccpd.conf はどうなっていますか。
以下のようになっています。
# Canon Printer Daemon for CUPS Configuration Data
<Path>
# CUPS configuration file path.
#  Default  /etc/cups/

CUPS_ConfigPath   /etc/cups/

# Log directory path.
#  LogDirectoryPath /var/log/CCPD/

</Path>

# Printer entries.
#  Mapping each "Printer Name" to each "Printer Device Port".
#  The "Printer Name" has to be identical to the CUPS printer queue name.
#
#  For example, if you prepare a printer named "LBP3200" as a CUPS printer
#  queue name, and the printer is connected to the USB port "/dev/usb/lp0",
#  you can use the following three lines example just by removing the
#  comment symbol "#" of each line.
#<Printer  LBP3200>
#DevicePath  /dev/usb/lp0
#</Printer>

<Printer LBP1210>
DevicePath /dev/usb/lp0
</Printer>

<Ports>
# Status monitoring socket port.
#  Default 39787
UI_Port  39787
</Ports>

 4) cndrvcups-capt のバージョンはいくつなんですか。
cndrvcups-capt-1.30-1
cndrvcups-common-1.30-1　です。

> 5) http://localhost:631 にアクセスして、「プリンタの管理」を選ぶと、
>    問題のプリンタの「プリンタの状態」はどうなっていますか。「ジョブ
>    受付」になっているでしょうか。もし、「停止中」なら「プリンタの
>    開始」をクリックします。
ジョブは処理中となっていますが、canon LBP1210はだんまりです。

> 6) 印刷できないというのは、lpr -P LBP1210 test1.txt を実行したときの
>    ことですか(何かエラーが出ませんでしたか)。プレーン・テキストでは
>    なく、PS ファイルを印刷すると、どうなりますか(たとえば、/usr/share/
>    ghostscript/7.07/examples/cjk/gscjk_aj.ps)。OpenOffice や gedit や
>    Firefox からの印刷はどうですか。
これは、どんなものを印刷しても、プリンタはだんまりを決め込んでいます。

>    # つまり、ひょっとすると、次のような問題と同じかもしれないと
>    # 考えたのです。
>    # [vine-users:076727] emacs Sylpheed でのプリント(印刷)について」
>    # [vine-users:077099] CUPS を使ったコマンドラインからの印刷について
一応、上記のスレッドも見てみましたが、どうも私の問題とは違うようです。
私の問題は、canon LBP1210のプリンタで印刷しようとすると、接続ポート（印
刷ポート）が、canon iP4100の印刷ポートと重なり、どちらも印刷できなくなる
現象ではないかと思っています。ーちなみに、この時の印刷ポートは、cups印刷
マネージャのLBP1210プロパティで見ると、Canon Printer Deamon Port#1になっ
ています。canon iP4100の印刷ポートも、Canon Printer Deamon Port#1でした。
正常に印刷できていた時の印刷ポートは、canon LBP1210は Canon Laser shot
LBP1210で、canon iP4100は Canon iP_4100 だったと思います。

ちなみに、/etc/cups/printers.confは次のようになっています。

# Printer configuration file for CUPS v1.1.23
# Written by cupsd on 2007年07月03日 03時43分54秒
<DefaultPrinter LBP1210>
Info LBP1210
DeviceURI ccp:/var/ccpd/fifo0
State Idle
Accepting Yes
JobSheets none none
QuotaPeriod 0
PageLimit 0
KLimit 0
Autodetected No
</Printer>

3)LogLevel infoをLogLevel debugに変えた後の/var/log/cups/error_logは、以
下のようになっています。（少々長いですが、引用します）

I [03/Jul/2007:02:37:13 +0900] Listening to 0:631
I [03/Jul/2007:02:37:13 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:02:37:13 +0900] Configured for up to 100 clients.
I [03/Jul/2007:02:37:13 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:02:37:13 +0900] Full reload is required.
I [03/Jul/2007:02:37:13 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2805
PPDs...
I [03/Jul/2007:02:37:14 +0900] LoadPPDs: No new or changed PPDs...
I [03/Jul/2007:02:37:14 +0900] Full reload complete.
I [03/Jul/2007:02:47:02 +0900] Scheduler shutting down normally.
I [03/Jul/2007:02:47:02 +0900] Listening to 0:631
I [03/Jul/2007:02:47:02 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:02:47:02 +0900] Configured for up to 100 clients.
I [03/Jul/2007:02:47:02 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:02:47:02 +0900] Full reload is required.
I [03/Jul/2007:02:47:02 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2805
PPDs...
I [03/Jul/2007:02:47:02 +0900] LoadPPDs: Wrote "/etc/cups/ppds.dat",
2821 PPDs...
I [03/Jul/2007:02:47:02 +0900] Full reload complete.
I [03/Jul/2007:02:49:22 +0900] Saving printers.conf...
I [03/Jul/2007:02:49:22 +0900] New printer 'LBP1210' added by 'root'.
I [03/Jul/2007:02:49:22 +0900] Setting LBP1210 device-uri to
"ccp:/var/ccpd/fifo0" (was "file:/dev/null".)
I [03/Jul/2007:02:49:22 +0900] Saving printers.conf...
I [03/Jul/2007:02:49:22 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:02:49:22 +0900] Setting LBP1210 printer-is-accepting-jobs
to 1 (was 0.)
I [03/Jul/2007:02:49:22 +0900] Setting LBP1210 printer-state to 3 (was 5.)
I [03/Jul/2007:02:49:22 +0900] Saving printers.conf...
I [03/Jul/2007:02:49:22 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:02:52:19 +0900] Scheduler shutting down normally.
I [03/Jul/2007:02:52:19 +0900] Listening to 0:631
I [03/Jul/2007:02:52:19 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:02:52:19 +0900] Configured for up to 100 clients.
I [03/Jul/2007:02:52:19 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:02:52:19 +0900] Full reload is required.
I [03/Jul/2007:02:52:19 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:02:52:19 +0900] LoadPPDs: No new or changed PPDs...
I [03/Jul/2007:02:52:19 +0900] Full reload complete.
I [03/Jul/2007:02:52:30 +0900] Saving printers.conf...
I [03/Jul/2007:02:52:30 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:02:52:30 +0900] Setting LBP1210 device-uri to
"ccp:/var/ccpd/fifo0" (was "ccp:/var/ccpd/fifo0".)
I [03/Jul/2007:02:52:30 +0900] Saving printers.conf...
I [03/Jul/2007:02:52:30 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:02:52:30 +0900] Setting LBP1210 printer-is-accepting-jobs
to 1 (was 1.)
I [03/Jul/2007:02:52:30 +0900] Setting LBP1210 printer-state to 3 (was 3.)
I [03/Jul/2007:02:52:30 +0900] Saving printers.conf...
I [03/Jul/2007:02:52:30 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:02:53:56 +0900] Adding start banner page "none" to job 1.
I [03/Jul/2007:02:53:56 +0900] Adding end banner page "none" to job 1.
I [03/Jul/2007:02:53:56 +0900] Job 1 queued on 'LBP1210' by 'toshio'.
I [03/Jul/2007:02:53:56 +0900] Started filter
/usr/lib/cups/filter/cjktexttops (PID 3918) for job 1.
I [03/Jul/2007:02:53:56 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 3919) for job 1.
I [03/Jul/2007:02:53:56 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 3920) for job 1.
I [03/Jul/2007:02:53:56 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 3921) for job 1.
I [03/Jul/2007:02:54:22 +0900] Adding start banner page "none" to job 2.
I [03/Jul/2007:02:54:22 +0900] Adding end banner page "none" to job 2.
I [03/Jul/2007:02:54:22 +0900] Job 2 queued on 'LBP1210' by 'toshio'.
I [03/Jul/2007:02:54:22 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 14453) for job 2.
I [03/Jul/2007:02:54:22 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 14454) for job 2.
I [03/Jul/2007:02:54:22 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 14455) for job 2.
I [03/Jul/2007:02:55:41 +0900] Adding start banner page "none" to job 3.
I [03/Jul/2007:02:55:41 +0900] Adding end banner page "none" to job 3.
I [03/Jul/2007:02:55:41 +0900] Job 3 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:02:55:56 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was "ccp:/var/ccpd/fifo0".)
I [03/Jul/2007:02:55:56 +0900] Saving printers.conf...
I [03/Jul/2007:02:55:56 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 23893) for job 2.
I [03/Jul/2007:02:55:56 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 23895) for job 2.
I [03/Jul/2007:02:55:56 +0900] Started backend /usr/lib/cups/backend/hal
(PID 23896) for job 2.
I [03/Jul/2007:02:55:56 +0900] Printer 'LBP1210' modified by 'root'.
E [03/Jul/2007:02:55:56 +0900] PID 14454 stopped with status 0!
I [03/Jul/2007:02:55:56 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
I [03/Jul/2007:02:55:56 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:02:55:56 +0900] Saving printers.conf...
I [03/Jul/2007:02:55:56 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 23897) for job 2.
I [03/Jul/2007:02:55:56 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 23898) for job 2.
I [03/Jul/2007:02:55:56 +0900] Started backend /usr/lib/cups/backend/hal
(PID 23899) for job 2.
I [03/Jul/2007:02:55:56 +0900] Printer 'LBP1210' modified by 'root'.
E [03/Jul/2007:02:55:56 +0900] PID 14455 stopped with status 0!
I [03/Jul/2007:02:55:56 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
E [03/Jul/2007:02:55:56 +0900] PID 23893 stopped with status 0!
I [03/Jul/2007:02:55:56 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
E [03/Jul/2007:02:55:56 +0900] PID 23895 stopped with status 0!
I [03/Jul/2007:02:55:56 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
E [03/Jul/2007:02:55:56 +0900] PID 23896 stopped with status 0!
I [03/Jul/2007:02:55:56 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
I [03/Jul/2007:02:55:59 +0900] Adding start banner page "none" to job 4.
I [03/Jul/2007:02:55:59 +0900] Adding end banner page "none" to job 4.
I [03/Jul/2007:02:55:59 +0900] Job 4 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:02:56:29 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 32460) for job 3.
I [03/Jul/2007:02:56:29 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 32461) for job 3.
I [03/Jul/2007:02:56:29 +0900] Started backend /usr/lib/cups/backend/hal
(PID 32462) for job 3.
I [03/Jul/2007:02:56:29 +0900] Job 2 was cancelled by 'toshio'.
I [03/Jul/2007:02:58:35 +0900] Job 4 was cancelled by 'root'.
I [03/Jul/2007:02:58:38 +0900] Job 3 was cancelled by 'root'.
I [03/Jul/2007:02:58:51 +0900] Adding start banner page "none" to job 5.
I [03/Jul/2007:02:58:51 +0900] Adding end banner page "none" to job 5.
I [03/Jul/2007:02:58:51 +0900] Job 5 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:02:58:51 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 3819) for job 5.
I [03/Jul/2007:02:58:51 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 3820) for job 5.
I [03/Jul/2007:02:58:51 +0900] Started backend /usr/lib/cups/backend/hal
(PID 3821) for job 5.
I [03/Jul/2007:02:59:22 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:02:59:22 +0900] Saving printers.conf...
I [03/Jul/2007:02:59:22 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 11254) for job 5.
I [03/Jul/2007:02:59:22 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 11255) for job 5.
I [03/Jul/2007:02:59:22 +0900] Started backend /usr/lib/cups/backend/hal
(PID 11256) for job 5.
I [03/Jul/2007:02:59:22 +0900] Printer 'LBP1210' modified by 'root'.
E [03/Jul/2007:02:59:22 +0900] PID 3820 stopped with status 0!
I [03/Jul/2007:02:59:22 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
E [03/Jul/2007:02:59:22 +0900] PID 3821 stopped with status 0!
I [03/Jul/2007:02:59:22 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
I [03/Jul/2007:02:59:22 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:02:59:22 +0900] Saving printers.conf...
I [03/Jul/2007:02:59:22 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 11257) for job 5.
I [03/Jul/2007:02:59:22 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 11258) for job 5.
I [03/Jul/2007:02:59:22 +0900] Started backend /usr/lib/cups/backend/hal
(PID 11259) for job 5.
I [03/Jul/2007:02:59:22 +0900] Printer 'LBP1210' modified by 'root'.
E [03/Jul/2007:02:59:22 +0900] PID 11254 stopped with status 0!
I [03/Jul/2007:02:59:22 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
E [03/Jul/2007:02:59:22 +0900] PID 11255 stopped with status 0!
I [03/Jul/2007:02:59:22 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
E [03/Jul/2007:02:59:22 +0900] PID 11256 stopped with status 0!
I [03/Jul/2007:02:59:22 +0900] Hint: Try setting the LogLevel to "debug"
to find out more.
I [03/Jul/2007:02:59:29 +0900] Adding start banner page "none" to job 6.
I [03/Jul/2007:02:59:29 +0900] Adding end banner page "none" to job 6.
I [03/Jul/2007:02:59:29 +0900] Job 6 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:02:59:45 +0900] Adding start banner page "none" to job 7.
I [03/Jul/2007:02:59:45 +0900] Adding end banner page "none" to job 7.
I [03/Jul/2007:02:59:45 +0900] Job 7 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:03:00:05 +0900] Adding start banner page "none" to job 8.
I [03/Jul/2007:03:00:05 +0900] Adding end banner page "none" to job 8.
I [03/Jul/2007:03:00:05 +0900] Job 8 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:03:00:23 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 11633) for job 6.
I [03/Jul/2007:03:00:23 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 11634) for job 6.
I [03/Jul/2007:03:00:23 +0900] Started backend /usr/lib/cups/backend/hal
(PID 11635) for job 6.
I [03/Jul/2007:03:00:23 +0900] Job 5 was cancelled by 'root'.
I [03/Jul/2007:03:00:26 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 11678) for job 7.
I [03/Jul/2007:03:00:26 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 11679) for job 7.
I [03/Jul/2007:03:00:26 +0900] Started backend /usr/lib/cups/backend/hal
(PID 11680) for job 7.
I [03/Jul/2007:03:00:26 +0900] Job 6 was cancelled by 'root'.
I [03/Jul/2007:03:00:29 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 11729) for job 8.
I [03/Jul/2007:03:00:29 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 11730) for job 8.
I [03/Jul/2007:03:00:29 +0900] Started backend /usr/lib/cups/backend/hal
(PID 11731) for job 8.
I [03/Jul/2007:03:00:29 +0900] Job 7 was cancelled by 'root'.
I [03/Jul/2007:03:00:32 +0900] Job 8 was cancelled by 'root'.
I [03/Jul/2007:03:01:03 +0900] Adding start banner page "none" to job 9.
I [03/Jul/2007:03:01:03 +0900] Adding end banner page "none" to job 9.
I [03/Jul/2007:03:01:03 +0900] Job 9 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:03:01:03 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 11943) for job 9.
I [03/Jul/2007:03:01:03 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 11944) for job 9.
I [03/Jul/2007:03:01:03 +0900] Started backend /usr/lib/cups/backend/hal
(PID 11945) for job 9.
I [03/Jul/2007:03:01:20 +0900] Job 9 was cancelled by 'root'.
I [03/Jul/2007:03:01:54 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:03:01:54 +0900] Saving printers.conf...
I [03/Jul/2007:03:01:54 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:03:01:54 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:03:01:54 +0900] Saving printers.conf...
I [03/Jul/2007:03:01:54 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:03:02:11 +0900] Adding start banner page "none" to job 10.
I [03/Jul/2007:03:02:11 +0900] Adding end banner page "none" to job 10.
I [03/Jul/2007:03:02:11 +0900] Job 10 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:03:02:11 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 12226) for job 10.
I [03/Jul/2007:03:02:11 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 12227) for job 10.
I [03/Jul/2007:03:02:11 +0900] Started backend /usr/lib/cups/backend/hal
(PID 12228) for job 10.
I [03/Jul/2007:03:02:51 +0900] Adding start banner page "none" to job 11.
I [03/Jul/2007:03:02:51 +0900] Adding end banner page "none" to job 11.
I [03/Jul/2007:03:02:51 +0900] Job 11 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:03:03:10 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 12509) for job 11.
I [03/Jul/2007:03:03:10 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 12510) for job 11.
I [03/Jul/2007:03:03:10 +0900] Started backend /usr/lib/cups/backend/hal
(PID 12511) for job 11.
I [03/Jul/2007:03:03:10 +0900] Job 10 was cancelled by 'root'.
I [03/Jul/2007:03:03:13 +0900] Job 11 was cancelled by 'root'.
I [03/Jul/2007:03:04:01 +0900] Scheduler shutting down normally.
I [03/Jul/2007:03:08:45 +0900] Listening to 0:631
I [03/Jul/2007:03:08:45 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:03:08:45 +0900] Configured for up to 100 clients.
I [03/Jul/2007:03:08:45 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:03:08:45 +0900] Full reload is required.
I [03/Jul/2007:03:08:46 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:03:08:46 +0900] LoadPPDs: No new or changed PPDs...
I [03/Jul/2007:03:08:46 +0900] Full reload complete.
I [03/Jul/2007:03:09:39 +0900] Adding start banner page "none" to job 12.
I [03/Jul/2007:03:09:39 +0900] Adding end banner page "none" to job 12.
I [03/Jul/2007:03:09:39 +0900] Job 12 queued on 'LBP1210' by 'toshio'.
I [03/Jul/2007:03:09:39 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 3128) for job 12.
I [03/Jul/2007:03:09:39 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 3129) for job 12.
I [03/Jul/2007:03:09:39 +0900] Started backend /usr/lib/cups/backend/hal
(PID 3130) for job 12.
E [03/Jul/2007:03:10:13 +0900] add_printer: bad device-uri attribute
'hal:///org/freedesktop/Hal/devices/usb_device_4a9_1093_1118A3_if0_printer_noserial'!
E [03/Jul/2007:03:10:13 +0900] add_printer: bad device-uri attribute
'hal:///org/freedesktop/Hal/devices/usb_device_4a9_1093_1118A3_if0_printer_noserial'!
I [03/Jul/2007:03:10:27 +0900] Job 12 was cancelled by 'toshio'.
E [03/Jul/2007:03:10:37 +0900] add_printer: bad device-uri attribute
'hal:///org/freedesktop/Hal/devices/usb_device_4a9_1093_1118A3_if0_printer_noserial'!
E [03/Jul/2007:03:10:37 +0900] add_printer: bad device-uri attribute
'hal:///org/freedesktop/Hal/devices/usb_device_4a9_1093_1118A3_if0_printer_noserial'!
I [03/Jul/2007:03:10:51 +0900] Adding start banner page "none" to job 13.
I [03/Jul/2007:03:10:51 +0900] Adding end banner page "none" to job 13.
I [03/Jul/2007:03:10:51 +0900] Job 13 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:03:10:51 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 3200) for job 13.
I [03/Jul/2007:03:10:51 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 3201) for job 13.
I [03/Jul/2007:03:10:51 +0900] Started backend /usr/lib/cups/backend/hal
(PID 3202) for job 13.
E [03/Jul/2007:03:11:31 +0900] Unable to open /etc/cups/passwd.md5 - No
such file or directory
E [03/Jul/2007:03:11:31 +0900] cancel_job: "toshio" not authorized to
delete job id 13 owned by "root"!
I [03/Jul/2007:03:12:32 +0900] All jobs on 'LBP1210' were purged by 'root'.
I [03/Jul/2007:03:13:45 +0900] Printer 'LBP1210' deleted by 'root'.
I [03/Jul/2007:03:13:45 +0900] Saving printers.conf...
I [03/Jul/2007:03:19:23 +0900] Scheduler shutting down normally.
I [03/Jul/2007:03:19:24 +0900] Listening to 0:631
I [03/Jul/2007:03:19:24 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:03:19:24 +0900] Configured for up to 100 clients.
I [03/Jul/2007:03:19:24 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:03:19:24 +0900] Full reload is required.
I [03/Jul/2007:03:19:24 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:03:19:24 +0900] LoadPPDs: No new or changed PPDs...
I [03/Jul/2007:03:19:24 +0900] Full reload complete.
I [03/Jul/2007:03:19:35 +0900] Saving printers.conf...
I [03/Jul/2007:03:19:35 +0900] New printer 'LBP1210' added by 'root'.
I [03/Jul/2007:03:19:35 +0900] Setting LBP1210 device-uri to
"ccp:/var/ccpd/fifo0" (was "file:/dev/null".)
I [03/Jul/2007:03:19:35 +0900] Saving printers.conf...
I [03/Jul/2007:03:19:35 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:03:19:35 +0900] Setting LBP1210 printer-is-accepting-jobs
to 1 (was 0.)
I [03/Jul/2007:03:19:35 +0900] Setting LBP1210 printer-state to 3 (was 5.)
I [03/Jul/2007:03:19:35 +0900] Saving printers.conf...
I [03/Jul/2007:03:19:35 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:03:21:23 +0900] Adding start banner page "none" to job 1.
I [03/Jul/2007:03:21:23 +0900] Adding end banner page "none" to job 1.
I [03/Jul/2007:03:21:23 +0900] Job 1 queued on 'LBP1210' by 'toshio'.
I [03/Jul/2007:03:21:23 +0900] Started filter
/usr/lib/cups/filter/cjktexttops (PID 3653) for job 1.
I [03/Jul/2007:03:21:23 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 3654) for job 1.
I [03/Jul/2007:03:21:23 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 3655) for job 1.
I [03/Jul/2007:03:21:23 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 3656) for job 1.
I [03/Jul/2007:03:22:17 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was "ccp:/var/ccpd/fifo0".)
I [03/Jul/2007:03:22:17 +0900] Saving printers.conf...
I [03/Jul/2007:03:22:17 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:03:22:17 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:03:22:17 +0900] Saving printers.conf...
I [03/Jul/2007:03:22:17 +0900] Printer 'LBP1210' modified by 'root'.
I [03/Jul/2007:03:22:33 +0900] Adding start banner page "none" to job 2.
I [03/Jul/2007:03:22:33 +0900] Adding end banner page "none" to job 2.
I [03/Jul/2007:03:22:33 +0900] Job 2 queued on 'LBP1210' by 'toshio'.
I [03/Jul/2007:03:22:33 +0900] Started filter
/usr/lib/cups/filter/cjktexttops (PID 20061) for job 2.
I [03/Jul/2007:03:22:33 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 20062) for job 2.
I [03/Jul/2007:03:22:33 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 20063) for job 2.
I [03/Jul/2007:03:22:33 +0900] Started backend /usr/lib/cups/backend/hal
(PID 20064) for job 2.
I [03/Jul/2007:03:23:25 +0900] Adding start banner page "none" to job 3.
I [03/Jul/2007:03:23:25 +0900] Adding end banner page "none" to job 3.
I [03/Jul/2007:03:23:25 +0900] Job 3 queued on 'LBP1210' by 'root'.
I [03/Jul/2007:03:23:48 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 5133) for job 3.
I [03/Jul/2007:03:23:48 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 5135) for job 3.
I [03/Jul/2007:03:23:48 +0900] Started backend /usr/lib/cups/backend/hal
(PID 5136) for job 3.
I [03/Jul/2007:03:23:48 +0900] Job 2 was cancelled by 'toshio'.
E [03/Jul/2007:03:23:51 +0900] Unable to open /etc/cups/passwd.md5 -
EURoeEURI^EURe`EURS(EURE^\O~\?\EUR\e"EURa"\C,\£\i`\?\E`\e^EURI"EUR¢EURe^EUR?EUR?EURo'
E [03/Jul/2007:03:23:51 +0900] cancel_job: "toshio" not authorized to
delete job id 3 owned by "root"!
I [03/Jul/2007:03:29:46 +0900] Scheduler shutting down normally.
I [03/Jul/2007:03:29:46 +0900] Listening to 0:631
D [03/Jul/2007:03:29:46 +0900] AddLocation: added location '/'
D [03/Jul/2007:03:29:46 +0900] DenyIP: / deny 00000000/00000000
D [03/Jul/2007:03:29:46 +0900] AllowIP: / allow 7f000001/ffffffff
D [03/Jul/2007:03:29:46 +0900] AddLocation: added location '/admin'
D [03/Jul/2007:03:29:46 +0900] DenyIP: /admin deny 00000000/00000000
D [03/Jul/2007:03:29:46 +0900] AllowIP: /admin allow 7f000001/ffffffff
I [03/Jul/2007:03:29:46 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:03:29:46 +0900] Configured for up to 100 clients.
I [03/Jul/2007:03:29:46 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:03:29:46 +0900] Full reload is required.
D [03/Jul/2007:03:29:46 +0900] LoadAllPrinters: Loading printer LBP1210...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device "http"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo0"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo1"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo2"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo3"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo4"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo5"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo6"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo7"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"...
D [03/Jul/2007:03:29:46 +0900] LoadDevices: Added device
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_1093_1118A3_if0_printer_noserial"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device "smb"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device "lpd"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device "ipp"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device "scsi"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"serial:/dev/ttyS0?baud=115200"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"serial:/dev/ttyS1?baud=115200"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"serial:/dev/ttyS2?baud=115200"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"serial:/dev/ttyS3?baud=115200"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device "socket"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"parallel:/dev/lp0"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp0"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp1"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp2"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp3"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp4"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp5"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp6"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp7"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp8"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp9"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp10"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp11"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp12"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp13"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp14"...
D [03/Jul/2007:03:29:47 +0900] LoadDevices: Added device
"usb:/dev/usb/lp15"...
I [03/Jul/2007:03:29:47 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:03:29:47 +0900] LoadPPDs: No new or changed PPDs...
D [03/Jul/2007:03:29:47 +0900] LoadAllJobs: Scanning /var/spool/cups...
D [03/Jul/2007:03:29:47 +0900] LoadAllJobs: Loading attributes for job 1...
D [03/Jul/2007:03:29:47 +0900] LoadAllJobs: Loading attributes for job 2...
D [03/Jul/2007:03:29:47 +0900] LoadAllJobs: Loading attributes for job 3...
D [03/Jul/2007:03:29:47 +0900] LoadAllJobs: Auto-typing document file
d00003-001...
I [03/Jul/2007:03:29:47 +0900] Full reload complete.
D [03/Jul/2007:03:29:47 +0900] StartListening: NumListeners=1
D [03/Jul/2007:03:29:47 +0900] StartListening: address=00000000 port=631
D [03/Jul/2007:03:29:47 +0900] ResumeListening: setting input bits...
D [03/Jul/2007:03:29:47 +0900] StartJob(3, 0x8096b40)
D [03/Jul/2007:03:29:47 +0900] StartJob() id = 3, file = 0/1
D [03/Jul/2007:03:29:47 +0900] job-sheets=none,none
D [03/Jul/2007:03:29:47 +0900] banner_page = 0
D [03/Jul/2007:03:29:47 +0900] StartJob: argv =
"LBP1210","3","root","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00003-001"
D [03/Jul/2007:03:29:47 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:29:47 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:29:47 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:29:47 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:29:47 +0900] StartJob:
envp[10]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:29:47 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:29:47 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:29:47 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:29:47 +0900] StartJob: statusfds = [ 6 7 ]
D [03/Jul/2007:03:29:47 +0900] StartJob: filterfds[1] = [ 8 -1 ]
D [03/Jul/2007:03:29:47 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:29:47 +0900] StartJob: filterfds[0] = [ 9 10 ]
D [03/Jul/2007:03:29:47 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbf9f9a40, 0xbf9f8db0, 8,
10, 7)
I [03/Jul/2007:03:29:47 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 27383) for job 3.
D [03/Jul/2007:03:29:47 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:29:47 +0900] StartJob: filterfds[1] = [ 8 11 ]
D [03/Jul/2007:03:29:47 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbf9f9a40, 0xbf9f8db0,
9, 11, 7)
I [03/Jul/2007:03:29:47 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 27385) for job 3.
D [03/Jul/2007:03:29:47 +0900] StartJob: backend =
"/usr/lib/cups/backend/hal"
D [03/Jul/2007:03:29:47 +0900] StartJob: filterfds[0] = [ -1 9 ]
D [03/Jul/2007:03:29:47 +0900]
start_process("/usr/lib/cups/backend/hal", 0xbf9f9a40, 0xbf9f8db0, 8, 9, 7)
I [03/Jul/2007:03:29:47 +0900] Started backend /usr/lib/cups/backend/hal
(PID 27386) for job 3.
D [03/Jul/2007:03:29:47 +0900] [Job 3] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:29:47 +0900] [Job 3] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:29:47 +0900] [Job 3] pstocapt start.
D [03/Jul/2007:03:30:06 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:03:30:06 +0900] CloseClient: 7
D [03/Jul/2007:03:30:06 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:03:30:06 +0900] ReadClient: 7 POST / HTTP/1.1
D [03/Jul/2007:03:30:06 +0900] ProcessIPPRequest: 7 status_code=1
D [03/Jul/2007:03:30:06 +0900] ReadClient: 7 POST / HTTP/1.1
D [03/Jul/2007:03:30:06 +0900] ProcessIPPRequest: 7 status_code=1
D [03/Jul/2007:03:30:06 +0900] ReadClient: 7 POST / HTTP/1.1
D [03/Jul/2007:03:30:06 +0900] ProcessIPPRequest: 7 status_code=0
D [03/Jul/2007:03:30:06 +0900] CloseClient: 7
D [03/Jul/2007:03:30:06 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:03:30:06 +0900] ReadClient: 7 POST / HTTP/1.1
D [03/Jul/2007:03:30:06 +0900] ProcessIPPRequest: 7 status_code=0
D [03/Jul/2007:03:30:06 +0900] ReadClient: 7 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:30:06 +0900] SendFile: 7 file=9
D [03/Jul/2007:03:30:33 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:33 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:30:33 +0900] CloseClient: 9
D [03/Jul/2007:03:30:33 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:30:33 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:33 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] ProcessIPPRequest: 10 status_code=0
D [03/Jul/2007:03:30:33 +0900] CloseClient: 9
D [03/Jul/2007:03:30:33 +0900] ReadClient: 10 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:30:33 +0900] SendFile: 10 file=9
D [03/Jul/2007:03:30:36 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:36 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:36 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:36 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:36 +0900] CloseClient: 9
D [03/Jul/2007:03:30:36 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:36 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:36 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:37 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:37 +0900] AcceptClient: 11 from localhost:631.
D [03/Jul/2007:03:30:37 +0900] CloseClient: 9
D [03/Jul/2007:03:30:37 +0900] ReadClient: 11 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 11 status_code=1
D [03/Jul/2007:03:30:37 +0900] ReadClient: 11 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 11 status_code=1
D [03/Jul/2007:03:30:37 +0900] CloseClient: 11
D [03/Jul/2007:03:30:37 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:37 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:37 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:37 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:37 +0900] AcceptClient: 11 from localhost:631.
D [03/Jul/2007:03:30:37 +0900] CloseClient: 9
D [03/Jul/2007:03:30:37 +0900] ReadClient: 11 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 11 status_code=1
D [03/Jul/2007:03:30:37 +0900] ReadClient: 11 POST / HTTP/1.1
D [03/Jul/2007:03:30:37 +0900] ProcessIPPRequest: 11 status_code=1
D [03/Jul/2007:03:30:37 +0900] CloseClient: 11
D [03/Jul/2007:03:30:37 +0900] CloseClient: 10
D [03/Jul/2007:03:30:45 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:45 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:45 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:45 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:45 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:30:45 +0900] CloseClient: 9
D [03/Jul/2007:03:30:45 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:30:45 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:45 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:45 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:45 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:45 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] ProcessIPPRequest: 10 status_code=0
D [03/Jul/2007:03:30:45 +0900] CloseClient: 9
D [03/Jul/2007:03:30:45 +0900] ReadClient: 10 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:30:45 +0900] SendFile: 10 file=9
D [03/Jul/2007:03:30:48 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:48 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:48 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:48 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:48 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:30:48 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:30:48 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:48 +0900] CloseClient: 9
D [03/Jul/2007:03:30:48 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:30:48 +0900] ReadClient: 9 POST /printers/LBP1210 HTTP/1.1
D [03/Jul/2007:03:30:48 +0900] print_job: auto-typing file...
D [03/Jul/2007:03:30:48 +0900] print_job: request file type is text/plain.
D [03/Jul/2007:03:30:48 +0900] check_quotas: requesting-user-name = 'toshio'
D [03/Jul/2007:03:30:48 +0900] print_job: requesting-user-name = 'toshio'
I [03/Jul/2007:03:30:48 +0900] Adding start banner page "none" to job 4.
I [03/Jul/2007:03:30:48 +0900] Adding end banner page "none" to job 4.
I [03/Jul/2007:03:30:48 +0900] Job 4 queued on 'LBP1210' by 'toshio'.
D [03/Jul/2007:03:30:48 +0900] Job 4 hold_until = 0
D [03/Jul/2007:03:30:48 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:30:48 +0900] CloseClient: 9
D [03/Jul/2007:03:30:48 +0900] CloseClient: 10
D [03/Jul/2007:03:31:11 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:31:11 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:11 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:11 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:31:11 +0900] CloseClient: 10
D [03/Jul/2007:03:31:12 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:12 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:12 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:12 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:15 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:15 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:17 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:17 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:17 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:17 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:18 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:18 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:18 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:18 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:18 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:18 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:21 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:21 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:22 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:22 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:22 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:22 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:22 +0900] ReadClient: 9 POST /jobs HTTP/1.1
D [03/Jul/2007:03:31:22 +0900] CancelJob: id = 4
I [03/Jul/2007:03:31:22 +0900] Job 4 was cancelled by 'toshio'.
D [03/Jul/2007:03:31:22 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:22 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:22 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:24 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:24 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:24 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:24 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:24 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:24 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:27 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:27 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:27 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:27 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:28 +0900] CloseClient: 9
D [03/Jul/2007:03:31:46 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:31:46 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:46 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:46 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:46 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:31:46 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:31:46 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:46 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:31:46 +0900] CloseClient: 9
D [03/Jul/2007:03:31:46 +0900] ReadClient: 10 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:31:46 +0900] SendError: 10 code=401 (Unauthorized)
D [03/Jul/2007:03:31:46 +0900] CloseClient: 10
D [03/Jul/2007:03:31:46 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:31:46 +0900] ReadClient: 9 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:31:46 +0900] CancelJob: id = 1
D [03/Jul/2007:03:31:46 +0900] CancelJob: id = 2
D [03/Jul/2007:03:31:46 +0900] CancelJob: id = 3
D [03/Jul/2007:03:31:46 +0900] StopJob: id = 3, force = 0
D [03/Jul/2007:03:31:46 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:31:46 +0900] CancelJob: id = 4
I [03/Jul/2007:03:31:46 +0900] All jobs on 'LBP1210' were purged by 'root'.
D [03/Jul/2007:03:31:46 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:31:46 +0900] CloseClient: 9
D [03/Jul/2007:03:33:50 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:33:50 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:33:50 +0900] CloseClient: 6
D [03/Jul/2007:03:33:50 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:33:50 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:50 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:33:50 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:33:50 +0900] CloseClient: 6
D [03/Jul/2007:03:33:50 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:33:50 +0900] SendFile: 9 file=6
D [03/Jul/2007:03:33:53 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:33:53 +0900] CloseClient: 6
D [03/Jul/2007:03:33:53 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:33:53 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:33:53 +0900] CloseClient: 6
D [03/Jul/2007:03:33:53 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:33:53 +0900] CloseClient: 10
D [03/Jul/2007:03:33:53 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:33:53 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:33:53 +0900] CloseClient: 6
D [03/Jul/2007:03:33:53 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:33:53 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:33:53 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:33:53 +0900] CloseClient: 10
D [03/Jul/2007:03:33:53 +0900] CloseClient: 9
D [03/Jul/2007:03:33:57 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:57 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:57 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:57 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:33:57 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:33:57 +0900] CloseClient: 6
D [03/Jul/2007:03:33:57 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:33:57 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:57 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:57 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:57 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:33:57 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:33:57 +0900] CloseClient: 6
D [03/Jul/2007:03:33:57 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:33:57 +0900] SendFile: 9 file=6
D [03/Jul/2007:03:33:59 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:33:59 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:59 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:59 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:33:59 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:33:59 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:34:00 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:34:00 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:34:00 +0900] CloseClient: 6
D [03/Jul/2007:03:34:00 +0900] ReadClient: 10 POST /printers/LBP1210
HTTP/1.1
D [03/Jul/2007:03:34:00 +0900] print_job: auto-typing file...
D [03/Jul/2007:03:34:00 +0900] print_job: request file type is text/plain.
D [03/Jul/2007:03:34:00 +0900] check_quotas: requesting-user-name = 'toshio'
D [03/Jul/2007:03:34:00 +0900] print_job: requesting-user-name = 'toshio'
I [03/Jul/2007:03:34:00 +0900] Adding start banner page "none" to job 5.
I [03/Jul/2007:03:34:00 +0900] Adding end banner page "none" to job 5.
I [03/Jul/2007:03:34:00 +0900] Job 5 queued on 'LBP1210' by 'toshio'.
D [03/Jul/2007:03:34:00 +0900] Job 5 hold_until = 0
D [03/Jul/2007:03:34:00 +0900] StartJob(5, 0x8096b40)
D [03/Jul/2007:03:34:00 +0900] StartJob() id = 5, file = 0/1
D [03/Jul/2007:03:34:00 +0900] job-sheets=none,none
D [03/Jul/2007:03:34:00 +0900] banner_page = 0
D [03/Jul/2007:03:34:00 +0900] StartJob: argv =
"LBP1210","5","toshio","text1.txt","1","CNCopies=1 outputorder=normal
number-up=1 orientation-requested=3 brightness=100 gamma=1000
Filter=None PageSize=A4 MediaType=PlainPaper InputSlot=0 noCollate
Resolution=600 CNHalftone=pattern1 noCNTonerSaving CNTonerDensity=3
CNSuperSmooth","/var/spool/cups/d00005-001"
D [03/Jul/2007:03:34:00 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:34:00 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:34:00 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[9]="CONTENT_TYPE=text/plain"
D [03/Jul/2007:03:34:00 +0900] StartJob:
envp[10]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:34:00 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:34:00 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:34:00 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:34:00 +0900] StartJob: statusfds = [ 6 11 ]
D [03/Jul/2007:03:34:00 +0900] StartJob: filterfds[1] = [ 12 -1 ]
D [03/Jul/2007:03:34:00 +0900] StartJob: filter =
"/usr/lib/cups/filter/cjktexttops"
D [03/Jul/2007:03:34:00 +0900] StartJob: filterfds[0] = [ 13 14 ]
D [03/Jul/2007:03:34:00 +0900]
start_process("/usr/lib/cups/filter/cjktexttops", 0xbf9ee4f0,
0xbf9ed860, 12, 14, 11)
I [03/Jul/2007:03:34:00 +0900] Started filter
/usr/lib/cups/filter/cjktexttops (PID 19437) for job 5.
D [03/Jul/2007:03:34:00 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:34:00 +0900] StartJob: filterfds[1] = [ 12 15 ]
D [03/Jul/2007:03:34:00 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbf9ee4f0, 0xbf9ed860, 13,
15, 11)
I [03/Jul/2007:03:34:00 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 19438) for job 5.
D [03/Jul/2007:03:34:00 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:34:00 +0900] StartJob: filterfds[0] = [ 13 14 ]
D [03/Jul/2007:03:34:00 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbf9ee4f0, 0xbf9ed860,
12, 14, 11)
I [03/Jul/2007:03:34:00 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 19442) for job 5.
D [03/Jul/2007:03:34:00 +0900] StartJob: backend =
"/usr/lib/cups/backend/hal"
D [03/Jul/2007:03:34:00 +0900] StartJob: filterfds[1] = [ -1 12 ]
D [03/Jul/2007:03:34:00 +0900]
start_process("/usr/lib/cups/backend/hal", 0xbf9ee4f0, 0xbf9ed860, 13,
12, 11)
I [03/Jul/2007:03:34:00 +0900] Started backend /usr/lib/cups/backend/hal
(PID 19443) for job 5.
D [03/Jul/2007:03:34:00 +0900] ProcessIPPRequest: 10 status_code=0
D [03/Jul/2007:03:34:00 +0900] [Job 5] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:34:00 +0900] [Job 5] pstocapt start.
D [03/Jul/2007:03:34:00 +0900] CloseClient: 10
D [03/Jul/2007:03:34:00 +0900] CloseClient: 9
D [03/Jul/2007:03:34:00 +0900] [Job 5] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%DocumentFonts: Courier Times-Bold
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%Title:
/var/spool/cups/d00005-001 (mpage)
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%Creator: mpage 2.5.4 May 2004
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%CreationDate: Tue Jul  3
03:34:00 2007
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%Orientation: Portrait
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%DocumentMedia: A4 596 842
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%BoundingBox: 18 36 578 806
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%Pages: (atend)
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%EndComments
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%BeginProlog
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%EndProlog
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%Page: 1 1
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%Page: 1 1
D [03/Jul/2007:03:34:00 +0900] [Job 5] pw = 568.3, pl = 813.1
D [03/Jul/2007:03:34:00 +0900] [Job 5] PageLeft = 13.4, PageRight = 581.8
D [03/Jul/2007:03:34:00 +0900] [Job 5] PageTop = 827.6, PageBottom = 14.5
D [03/Jul/2007:03:34:00 +0900] [Job 5] PageWidth = 595.0, PageLength = 842.0
D [03/Jul/2007:03:34:00 +0900] [Job 5] 0 %%Trailer
D [03/Jul/2007:03:34:00 +0900] [Job 5] Saw Trailer!
D [03/Jul/2007:03:34:15 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:34:15 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:15 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:15 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:34:15 +0900] CloseClient: 10
D [03/Jul/2007:03:34:15 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:15 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:15 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:15 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:18 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:18 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:20 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:20 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:20 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:20 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:21 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:21 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:21 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:21 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:21 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:21 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:25 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:25 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:25 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:25 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:25 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:34:25 +0900] SendFile: 9 file=10
D [03/Jul/2007:03:34:28 +0900] CloseClient: 9
D [03/Jul/2007:03:34:32 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:34:32 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:32 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:32 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:33 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:33 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:34:33 +0900] CloseClient: 10
D [03/Jul/2007:03:34:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:33 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:33 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:34:33 +0900] SendFile: 9 file=10
D [03/Jul/2007:03:34:33 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:34:33 +0900] SendFile: 9 file=10
D [03/Jul/2007:03:34:33 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:33 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:34 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:34 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:38 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:38 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:38 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:38 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:38 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:38 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:43 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:43 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:43 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:43 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:43 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:43 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:46 +0900] ReadClient: 9 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:34:46 +0900] SendError: 9 code=401 (Unauthorized)
D [03/Jul/2007:03:34:46 +0900] CloseClient: 9
D [03/Jul/2007:03:34:46 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:34:46 +0900] ReadClient: 9 POST /admin/ HTTP/1.1
I [03/Jul/2007:03:34:46 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:03:34:46 +0900] Saving printers.conf...
D [03/Jul/2007:03:34:46 +0900] StopJob: id = 5, force = 1
D [03/Jul/2007:03:34:46 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:34:46 +0900] StartJob(5, 0x8096b40)
D [03/Jul/2007:03:34:46 +0900] StartJob() id = 5, file = 0/1
D [03/Jul/2007:03:34:46 +0900] job-sheets=none,none
D [03/Jul/2007:03:34:46 +0900] banner_page = 0
D [03/Jul/2007:03:34:46 +0900] StartJob: argv =
"LBP1210","5","toshio","text1.txt","1","CNCopies=1 outputorder=normal
number-up=1 orientation-requested=3 brightness=100 gamma=1000
Filter=None PageSize=A4 MediaType=PlainPaper InputSlot=0 noCollate
Resolution=600 CNHalftone=pattern1 noCNTonerSaving CNTonerDensity=3
CNSuperSmooth","/var/spool/cups/d00005-001"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[9]="CONTENT_TYPE=text/plain"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[10]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:34:46 +0900] StartJob: statusfds = [ 6 10 ]
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:34:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/cjktexttops"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/filter/cjktexttops", 0xbf9ee7a0,
0xbf9edb10, 11, 13, 10)
I [03/Jul/2007:03:34:46 +0900] Started filter
/usr/lib/cups/filter/cjktexttops (PID 23713) for job 5.
D [03/Jul/2007:03:34:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbf9ee7a0, 0xbf9edb10, 12,
14, 10)
I [03/Jul/2007:03:34:46 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 23714) for job 5.
D [03/Jul/2007:03:34:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbf9ee7a0, 0xbf9edb10,
11, 13, 10)
I [03/Jul/2007:03:34:46 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 23715) for job 5.
D [03/Jul/2007:03:34:46 +0900] StartJob: backend =
"/usr/lib/cups/backend/hal"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[1] = [ -1 11 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/backend/hal", 0xbf9ee7a0, 0xbf9edb10, 12,
11, 10)
I [03/Jul/2007:03:34:46 +0900] Started backend /usr/lib/cups/backend/hal
(PID 23716) for job 5.
I [03/Jul/2007:03:34:46 +0900] Printer 'LBP1210' modified by 'root'.
D [03/Jul/2007:03:34:46 +0900] ProcessIPPRequest: 9 status_code=0
E [03/Jul/2007:03:34:46 +0900] PID 19443 stopped with status 0!
D [03/Jul/2007:03:34:46 +0900] [Job 5] pstocapt start.
D [03/Jul/2007:03:34:46 +0900] ReadClient: 9 POST /admin/ HTTP/1.1
I [03/Jul/2007:03:34:46 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:03:34:46 +0900] Saving printers.conf...
D [03/Jul/2007:03:34:46 +0900] StopJob: id = 5, force = 1
D [03/Jul/2007:03:34:46 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:34:46 +0900] StartJob(5, 0x8096b40)
D [03/Jul/2007:03:34:46 +0900] StartJob() id = 5, file = 0/1
D [03/Jul/2007:03:34:46 +0900] job-sheets=none,none
D [03/Jul/2007:03:34:46 +0900] banner_page = 0
D [03/Jul/2007:03:34:46 +0900] StartJob: argv =
"LBP1210","5","toshio","text1.txt","1","CNCopies=1 outputorder=normal
number-up=1 orientation-requested=3 brightness=100 gamma=1000
Filter=None PageSize=A4 MediaType=PlainPaper InputSlot=0 noCollate
Resolution=600 CNHalftone=pattern1 noCNTonerSaving CNTonerDensity=3
CNSuperSmooth","/var/spool/cups/d00005-001"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[9]="CONTENT_TYPE=text/plain"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[10]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:34:46 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:34:46 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:34:46 +0900] StartJob: statusfds = [ 6 10 ]
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:34:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/cjktexttops"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/filter/cjktexttops", 0xbf9ee7a0,
0xbf9edb10, 11, 13, 10)
I [03/Jul/2007:03:34:46 +0900] Started filter
/usr/lib/cups/filter/cjktexttops (PID 23729) for job 5.
D [03/Jul/2007:03:34:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbf9ee7a0, 0xbf9edb10, 12,
14, 10)
I [03/Jul/2007:03:34:46 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 23730) for job 5.
D [03/Jul/2007:03:34:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbf9ee7a0, 0xbf9edb10,
11, 13, 10)
I [03/Jul/2007:03:34:46 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 23731) for job 5.
D [03/Jul/2007:03:34:46 +0900] StartJob: backend =
"/usr/lib/cups/backend/hal"
D [03/Jul/2007:03:34:46 +0900] StartJob: filterfds[1] = [ -1 11 ]
D [03/Jul/2007:03:34:46 +0900]
start_process("/usr/lib/cups/backend/hal", 0xbf9ee7a0, 0xbf9edb10, 12,
11, 10)
I [03/Jul/2007:03:34:46 +0900] Started backend /usr/lib/cups/backend/hal
(PID 23732) for job 5.
I [03/Jul/2007:03:34:46 +0900] Printer 'LBP1210' modified by 'root'.
D [03/Jul/2007:03:34:46 +0900] ProcessIPPRequest: 9 status_code=0
E [03/Jul/2007:03:34:46 +0900] PID 23715 stopped with status 0!
D [03/Jul/2007:03:34:46 +0900] [Job 5] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:34:46 +0900] [Job 5] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%DocumentFonts: Courier Times-Bold
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%Title:
/var/spool/cups/d00005-001 (mpage)
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%Creator: mpage 2.5.4 May 2004
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%CreationDate: Tue Jul  3
03:34:46 2007
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%Orientation: Portrait
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%DocumentMedia: A4 596 842
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%BoundingBox: 18 36 578 806
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%Pages: (atend)
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%EndComments
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%BeginProlog
D [03/Jul/2007:03:34:46 +0900] [Job 5] pstocapt start.
E [03/Jul/2007:03:34:46 +0900] PID 23716 stopped with status 0!
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%EndProlog
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%Page: 1 1
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%Page: 1 1
D [03/Jul/2007:03:34:46 +0900] [Job 5] pw = 568.3, pl = 813.1
D [03/Jul/2007:03:34:46 +0900] [Job 5] PageLeft = 13.4, PageRight = 581.8
D [03/Jul/2007:03:34:46 +0900] [Job 5] PageTop = 827.6, PageBottom = 14.5
D [03/Jul/2007:03:34:46 +0900] [Job 5] PageWidth = 595.0, PageLength = 842.0
D [03/Jul/2007:03:34:46 +0900] [Job 5] 0 %%Trailer
D [03/Jul/2007:03:34:46 +0900] [Job 5] Saw Trailer!
D [03/Jul/2007:03:34:48 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:48 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:48 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:48 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:48 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:48 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:53 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:53 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:53 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:53 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:34:53 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:34:53 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:34:57 +0900] CloseClient: 9
D [03/Jul/2007:03:35:01 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:35:01 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:01 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:01 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:35:01 +0900] CloseClient: 10
D [03/Jul/2007:03:35:01 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:01 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:01 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:01 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:04 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:04 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:06 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:06 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:06 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:06 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:07 +0900] Closing client 7 after 300 seconds of
inactivity...
D [03/Jul/2007:03:35:07 +0900] CloseClient: 7
D [03/Jul/2007:03:35:07 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:07 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:07 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:07 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:07 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:07 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:07 +0900] ReadClient: 9 POST /jobs HTTP/1.1
D [03/Jul/2007:03:35:08 +0900] CancelJob: id = 5
D [03/Jul/2007:03:35:08 +0900] StopJob: id = 5, force = 0
D [03/Jul/2007:03:35:08 +0900] StopJob: printer state is 3
I [03/Jul/2007:03:35:08 +0900] Job 5 was cancelled by 'toshio'.
D [03/Jul/2007:03:35:08 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:10 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:10 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:11 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:11 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:11 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:11 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:13 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:13 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:13 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:13 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:13 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:13 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:15 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:35:15 +0900] SendFile: 9 file=6
D [03/Jul/2007:03:35:15 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:35:15 +0900] ReadClient: 6 POST /printers/LBP1210 HTTP/1.1
D [03/Jul/2007:03:35:15 +0900] print_job: auto-typing file...
D [03/Jul/2007:03:35:15 +0900] print_job: request file type is
application/postscript.
D [03/Jul/2007:03:35:15 +0900] check_quotas: requesting-user-name = 'toshio'
D [03/Jul/2007:03:35:15 +0900] print_job: requesting-user-name = 'toshio'
D [03/Jul/2007:03:35:15 +0900] Adding default job-sheets values
"none,none"...
I [03/Jul/2007:03:35:15 +0900] Adding start banner page "none" to job 6.
I [03/Jul/2007:03:35:15 +0900] Adding end banner page "none" to job 6.
I [03/Jul/2007:03:35:15 +0900] Job 6 queued on 'LBP1210' by 'toshio'.
D [03/Jul/2007:03:35:15 +0900] Job 6 hold_until = 0
D [03/Jul/2007:03:35:15 +0900] StartJob(6, 0x8096b40)
D [03/Jul/2007:03:35:15 +0900] StartJob() id = 6, file = 0/1
D [03/Jul/2007:03:35:15 +0900] job-sheets=none,none
D [03/Jul/2007:03:35:15 +0900] banner_page = 0
D [03/Jul/2007:03:35:15 +0900] StartJob: argv =
"LBP1210","6","toshio","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00006-001"
D [03/Jul/2007:03:35:15 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:35:15 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:35:15 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:35:15 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:35:15 +0900] StartJob:
envp[10]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:35:15 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:35:15 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:35:15 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:35:15 +0900] StartJob: statusfds = [ 7 10 ]
D [03/Jul/2007:03:35:15 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:35:15 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:35:15 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:35:15 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbf9ee4f0, 0xbf9ed860, 11,
13, 10)
I [03/Jul/2007:03:35:15 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 23832) for job 6.
D [03/Jul/2007:03:35:15 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:35:15 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:35:15 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbf9ee4f0, 0xbf9ed860,
12, 14, 10)
I [03/Jul/2007:03:35:15 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 23833) for job 6.
D [03/Jul/2007:03:35:15 +0900] StartJob: backend =
"/usr/lib/cups/backend/hal"
D [03/Jul/2007:03:35:15 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [03/Jul/2007:03:35:15 +0900]
start_process("/usr/lib/cups/backend/hal", 0xbf9ee4f0, 0xbf9ed860, 11,
12, 10)
I [03/Jul/2007:03:35:15 +0900] Started backend /usr/lib/cups/backend/hal
(PID 23834) for job 6.
D [03/Jul/2007:03:35:15 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:35:15 +0900] [Job 6] pstocapt start.
D [03/Jul/2007:03:35:15 +0900] [Job 6] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:35:15 +0900] [Job 6] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:35:16 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:16 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:16 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:16 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:19 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:19 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:19 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:19 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:19 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:19 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:21 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:21 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:21 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:21 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:22 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:22 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:25 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:25 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:25 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:25 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:25 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:25 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:26 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:26 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:26 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:26 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:28 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:28 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:31 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:31 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:31 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:31 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:32 +0900] ReadClient: 9 POST /jobs HTTP/1.1
D [03/Jul/2007:03:35:32 +0900] CancelJob: id = 6
D [03/Jul/2007:03:35:32 +0900] StopJob: id = 6, force = 0
D [03/Jul/2007:03:35:32 +0900] StopJob: printer state is 3
I [03/Jul/2007:03:35:32 +0900] Job 6 was cancelled by 'toshio'.
D [03/Jul/2007:03:35:32 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:32 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:32 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:34 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:34 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:34 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:34 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:34 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:34 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:36 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:35:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:35:36 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:35:37 +0900] CloseClient: 9
D [03/Jul/2007:03:35:37 +0900] CloseClient: 6
I [03/Jul/2007:03:38:15 +0900] Scheduler shutting down normally.
D [03/Jul/2007:03:38:15 +0900] StopListening: closing all listen sockets.
D [03/Jul/2007:03:38:15 +0900] PauseListening: clearing input bits...
I [03/Jul/2007:03:38:15 +0900] Listening to 0:631
D [03/Jul/2007:03:38:15 +0900] AddLocation: added location '/'
D [03/Jul/2007:03:38:15 +0900] DenyIP: / deny 00000000/00000000
D [03/Jul/2007:03:38:15 +0900] AllowIP: / allow 7f000001/ffffffff
D [03/Jul/2007:03:38:15 +0900] AddLocation: added location '/admin'
D [03/Jul/2007:03:38:15 +0900] DenyIP: /admin deny 00000000/00000000
D [03/Jul/2007:03:38:15 +0900] AllowIP: /admin allow 7f000001/ffffffff
I [03/Jul/2007:03:38:15 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:03:38:15 +0900] Configured for up to 100 clients.
I [03/Jul/2007:03:38:15 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:03:38:15 +0900] Full reload is required.
D [03/Jul/2007:03:38:15 +0900] LoadAllPrinters: Loading printer LBP1210...
D [03/Jul/2007:03:38:15 +0900] LoadDevices: Added device "http"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo0"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo1"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo2"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo3"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo4"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo5"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo6"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo7"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_1093_1118A3_if0_printer_noserial"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device "smb"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device "lpd"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device "ipp"...
D [03/Jul/2007:03:38:16 +0900] LoadDevices: Added device "scsi"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"serial:/dev/ttyS0?baud=115200"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"serial:/dev/ttyS1?baud=115200"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"serial:/dev/ttyS2?baud=115200"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"serial:/dev/ttyS3?baud=115200"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device "socket"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"parallel:/dev/lp0"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp0"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp1"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp2"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp3"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp4"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp5"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp6"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp7"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp8"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp9"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp10"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp11"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp12"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp13"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp14"...
D [03/Jul/2007:03:38:17 +0900] LoadDevices: Added device
"usb:/dev/usb/lp15"...
I [03/Jul/2007:03:38:17 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:03:38:17 +0900] LoadPPDs: No new or changed PPDs...
D [03/Jul/2007:03:38:17 +0900] LoadAllJobs: Scanning /var/spool/cups...
D [03/Jul/2007:03:38:17 +0900] LoadAllJobs: Loading attributes for job 6...
D [03/Jul/2007:03:38:17 +0900] LoadAllJobs: Loading attributes for job 5...
I [03/Jul/2007:03:38:17 +0900] Full reload complete.
D [03/Jul/2007:03:38:17 +0900] StartListening: NumListeners=1
D [03/Jul/2007:03:38:17 +0900] StartListening: address=00000000 port=631
D [03/Jul/2007:03:38:17 +0900] ResumeListening: setting input bits...
D [03/Jul/2007:03:38:30 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:38:30 +0900] CloseClient: 6
D [03/Jul/2007:03:38:30 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:38:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:38:30 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:38:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:38:30 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:38:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:38:30 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:38:30 +0900] CloseClient: 6
D [03/Jul/2007:03:38:30 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:38:30 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:38:30 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:38:30 +0900] ReadClient: 6 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:38:30 +0900] SendFile: 6 file=8
D [03/Jul/2007:03:39:08 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:39:08 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:08 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:08 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:39:08 +0900] CloseClient: 9
D [03/Jul/2007:03:39:08 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:08 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:08 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:08 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:12 +0900] ReadClient: 8 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:39:12 +0900] SendFile: 8 file=9
D [03/Jul/2007:03:39:13 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:13 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:13 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:13 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:14 +0900] CloseClient: 8
D [03/Jul/2007:03:39:20 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:39:20 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:20 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:20 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:20 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:20 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:39:20 +0900] CloseClient: 9
D [03/Jul/2007:03:39:20 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:20 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:20 +0900] ReadClient: 8 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:39:20 +0900] SendFile: 8 file=9
D [03/Jul/2007:03:39:20 +0900] ReadClient: 8 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:39:20 +0900] SendFile: 8 file=9
D [03/Jul/2007:03:39:20 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:20 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:21 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:21 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:25 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:25 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:25 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:25 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:25 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:25 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:29 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:39:29 +0900] ReadClient: 9 POST /printers/LBP1210 HTTP/1.1
D [03/Jul/2007:03:39:29 +0900] print_job: auto-typing file...
D [03/Jul/2007:03:39:29 +0900] print_job: request file type is
application/postscript.
D [03/Jul/2007:03:39:29 +0900] check_quotas: requesting-user-name = 'root'
D [03/Jul/2007:03:39:29 +0900] print_job: requesting-user-name = 'root'
D [03/Jul/2007:03:39:29 +0900] Adding default job-sheets values
"none,none"...
I [03/Jul/2007:03:39:29 +0900] Adding start banner page "none" to job 7.
I [03/Jul/2007:03:39:29 +0900] Adding end banner page "none" to job 7.
I [03/Jul/2007:03:39:29 +0900] Job 7 queued on 'LBP1210' by 'root'.
D [03/Jul/2007:03:39:29 +0900] Job 7 hold_until = 0
D [03/Jul/2007:03:39:29 +0900] StartJob(7, 0x8096b40)
D [03/Jul/2007:03:39:29 +0900] StartJob() id = 7, file = 0/1
D [03/Jul/2007:03:39:29 +0900] job-sheets=none,none
D [03/Jul/2007:03:39:29 +0900] banner_page = 0
D [03/Jul/2007:03:39:29 +0900] StartJob: argv =
"LBP1210","7","root","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00007-001"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[10]="DEVICE_URI=ccp:/var/ccpd/fifo0"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:39:29 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:39:29 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:39:29 +0900] StartJob: statusfds = [ 10 11 ]
D [03/Jul/2007:03:39:29 +0900] StartJob: filterfds[1] = [ 12 -1 ]
D [03/Jul/2007:03:39:29 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:39:29 +0900] StartJob: filterfds[0] = [ 13 14 ]
D [03/Jul/2007:03:39:29 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfd63d80, 0xbfd630f0, 12,
14, 11)
I [03/Jul/2007:03:39:29 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 24402) for job 7.
D [03/Jul/2007:03:39:29 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:39:29 +0900] StartJob: filterfds[1] = [ 12 15 ]
D [03/Jul/2007:03:39:29 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfd63d80, 0xbfd630f0,
13, 15, 11)
I [03/Jul/2007:03:39:29 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 24403) for job 7.
D [03/Jul/2007:03:39:29 +0900] StartJob: backend =
"/usr/lib/cups/backend/ccp"
D [03/Jul/2007:03:39:30 +0900] StartJob: filterfds[0] = [ -1 13 ]
D [03/Jul/2007:03:39:30 +0900]
start_process("/usr/lib/cups/backend/ccp", 0xbfd63d80, 0xbfd630f0, 12,
13, 11)
I [03/Jul/2007:03:39:30 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 24404) for job 7.
D [03/Jul/2007:03:39:30 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:39:30 +0900] [Job 7] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:39:30 +0900] [Job 7] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:39:30 +0900] [Job 7] pstocapt start.
D [03/Jul/2007:03:39:30 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:30 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:30 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:30 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:30 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:30 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:35 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:35 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:35 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:40 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:40 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:40 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:40 +0900] CloseClient: 8
D [03/Jul/2007:03:39:40 +0900] CloseClient: 9
D [03/Jul/2007:03:39:44 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:39:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:44 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:44 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:39:44 +0900] CloseClient: 9
D [03/Jul/2007:03:39:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:44 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:44 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:44 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:47 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:47 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:49 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:49 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:49 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:54 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:54 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:54 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:54 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:59 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:59 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:39:59 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:39:59 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:39:59 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:39:59 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:39:59 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:39:59 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:39:59 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:39:59 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:39:59 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:39:59 +0900] AcceptClient: 11 from localhost:631.
D [03/Jul/2007:03:39:59 +0900] CloseClient: 9
D [03/Jul/2007:03:39:59 +0900] ReadClient: 11 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:39:59 +0900] SendError: 11 code=401 (Unauthorized)
D [03/Jul/2007:03:39:59 +0900] CloseClient: 11
D [03/Jul/2007:03:39:59 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:39:59 +0900] ReadClient: 9 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:39:59 +0900] CancelJob: id = 5
D [03/Jul/2007:03:39:59 +0900] CancelJob: id = 6
D [03/Jul/2007:03:39:59 +0900] CancelJob: id = 7
D [03/Jul/2007:03:39:59 +0900] StopJob: id = 7, force = 0
D [03/Jul/2007:03:39:59 +0900] StopJob: printer state is 3
I [03/Jul/2007:03:39:59 +0900] All jobs on 'LBP1210' were purged by 'root'.
D [03/Jul/2007:03:39:59 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:39:59 +0900] CloseClient: 9
D [03/Jul/2007:03:40:04 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:40:04 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:40:04 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:40:04 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:40:09 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:40:09 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:40:09 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:40:09 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:40:09 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:40:09 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:40:09 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:40:09 +0900] CloseClient: 9
D [03/Jul/2007:03:40:14 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:40:14 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:40:14 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:40:14 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:40:16 +0900] CloseClient: 8
I [03/Jul/2007:03:42:04 +0900] Scheduler shutting down normally.
D [03/Jul/2007:03:42:04 +0900] CloseClient: 6
D [03/Jul/2007:03:42:04 +0900] StopListening: closing all listen sockets.
D [03/Jul/2007:03:42:04 +0900] PauseListening: clearing input bits...
I [03/Jul/2007:03:42:05 +0900] Listening to 0:631
D [03/Jul/2007:03:42:05 +0900] AddLocation: added location '/'
D [03/Jul/2007:03:42:05 +0900] DenyIP: / deny 00000000/00000000
D [03/Jul/2007:03:42:05 +0900] AllowIP: / allow 7f000001/ffffffff
D [03/Jul/2007:03:42:05 +0900] AddLocation: added location '/admin'
D [03/Jul/2007:03:42:05 +0900] DenyIP: /admin deny 00000000/00000000
D [03/Jul/2007:03:42:05 +0900] AllowIP: /admin allow 7f000001/ffffffff
I [03/Jul/2007:03:42:05 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:03:42:05 +0900] Configured for up to 100 clients.
I [03/Jul/2007:03:42:05 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:03:42:05 +0900] Full reload is required.
D [03/Jul/2007:03:42:05 +0900] LoadAllPrinters: Loading printer LBP1210...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device "http"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo0"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo1"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo2"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo3"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo4"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo5"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo6"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo7"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_1093_1118A3_if0_printer_noserial"...
D [03/Jul/2007:03:42:05 +0900] LoadDevices: Added device "smb"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device "lpd"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device "ipp"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device "scsi"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"serial:/dev/ttyS0?baud=115200"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"serial:/dev/ttyS1?baud=115200"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"serial:/dev/ttyS2?baud=115200"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"serial:/dev/ttyS3?baud=115200"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device "socket"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"parallel:/dev/lp0"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp0"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp1"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp2"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp3"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp4"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp5"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp6"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp7"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp8"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp9"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp10"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp11"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp12"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp13"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp14"...
D [03/Jul/2007:03:42:06 +0900] LoadDevices: Added device
"usb:/dev/usb/lp15"...
I [03/Jul/2007:03:42:06 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:03:42:06 +0900] LoadPPDs: No new or changed PPDs...
D [03/Jul/2007:03:42:06 +0900] LoadAllJobs: Scanning /var/spool/cups...
I [03/Jul/2007:03:42:06 +0900] Full reload complete.
D [03/Jul/2007:03:42:06 +0900] StartListening: NumListeners=1
D [03/Jul/2007:03:42:06 +0900] StartListening: address=00000000 port=631
D [03/Jul/2007:03:42:06 +0900] ResumeListening: setting input bits...
D [03/Jul/2007:03:42:19 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:42:19 +0900] CloseClient: 6
D [03/Jul/2007:03:42:19 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:42:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:42:19 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:42:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:42:19 +0900] ProcessIPPRequest: 6 status_code=1
D [03/Jul/2007:03:42:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:42:19 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:42:19 +0900] CloseClient: 6
D [03/Jul/2007:03:42:19 +0900] AcceptClient: 6 from localhost:631.
D [03/Jul/2007:03:42:19 +0900] ReadClient: 6 POST / HTTP/1.1
D [03/Jul/2007:03:42:19 +0900] ProcessIPPRequest: 6 status_code=0
D [03/Jul/2007:03:42:19 +0900] ReadClient: 6 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:42:19 +0900] SendFile: 6 file=8
D [03/Jul/2007:03:42:36 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:36 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:36 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:36 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:36 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:42:36 +0900] CloseClient: 8
D [03/Jul/2007:03:42:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:42:36 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:36 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:36 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:36 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:36 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:42:36 +0900] CloseClient: 8
D [03/Jul/2007:03:42:36 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:42:36 +0900] SendFile: 9 file=8
D [03/Jul/2007:03:42:39 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:39 +0900] CloseClient: 8
D [03/Jul/2007:03:42:39 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:39 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:42:39 +0900] CloseClient: 8
D [03/Jul/2007:03:42:39 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:42:39 +0900] CloseClient: 10
D [03/Jul/2007:03:42:39 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:39 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:42:39 +0900] CloseClient: 8
D [03/Jul/2007:03:42:39 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:42:39 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:42:39 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:42:40 +0900] CloseClient: 10
D [03/Jul/2007:03:42:40 +0900] CloseClient: 9
D [03/Jul/2007:03:42:46 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:46 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:46 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:46 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:47 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:47 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:42:47 +0900] CloseClient: 8
D [03/Jul/2007:03:42:47 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] ProcessIPPRequest: 9 status_code=1
D [03/Jul/2007:03:42:47 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:47 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:47 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:47 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:47 +0900] ReadClient: 9 POST / HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:42:47 +0900] CloseClient: 8
D [03/Jul/2007:03:42:47 +0900] ReadClient: 9 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:42:47 +0900] SendFile: 9 file=8
D [03/Jul/2007:03:42:50 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:50 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:50 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:42:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:42:50 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:50 +0900] CloseClient: 8
D [03/Jul/2007:03:42:50 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:42:50 +0900] ReadClient: 8 POST /printers/LBP1210 HTTP/1.1
D [03/Jul/2007:03:42:50 +0900] print_job: auto-typing file...
D [03/Jul/2007:03:42:50 +0900] print_job: request file type is text/plain.
D [03/Jul/2007:03:42:50 +0900] check_quotas: requesting-user-name = 'toshio'
D [03/Jul/2007:03:42:50 +0900] print_job: requesting-user-name = 'toshio'
I [03/Jul/2007:03:42:50 +0900] Adding start banner page "none" to job 1.
I [03/Jul/2007:03:42:50 +0900] Adding end banner page "none" to job 1.
I [03/Jul/2007:03:42:50 +0900] Job 1 queued on 'LBP1210' by 'toshio'.
D [03/Jul/2007:03:42:50 +0900] Job 1 hold_until = 0
D [03/Jul/2007:03:42:50 +0900] StartJob(1, 0x8096b40)
D [03/Jul/2007:03:42:50 +0900] StartJob() id = 1, file = 0/1
D [03/Jul/2007:03:42:50 +0900] job-sheets=none,none
D [03/Jul/2007:03:42:50 +0900] banner_page = 0
D [03/Jul/2007:03:42:50 +0900] StartJob: argv =
"LBP1210","1","toshio","text1.txt","1","CNCopies=1 outputorder=normal
number-up=1 orientation-requested=3 brightness=100 gamma=1000
Filter=None PageSize=A4 MediaType=PlainPaper InputSlot=0 noCollate
Resolution=600 CNHalftone=pattern1 noCNTonerSaving CNTonerDensity=3
CNSuperSmooth","/var/spool/cups/d00001-001"
D [03/Jul/2007:03:42:50 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:42:50 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:42:50 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[9]="CONTENT_TYPE=text/plain"
D [03/Jul/2007:03:42:50 +0900] StartJob:
envp[10]="DEVICE_URI=ccp:/var/ccpd/fifo0"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:42:50 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:42:50 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:42:50 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:42:50 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:42:50 +0900] StartJob: statusfds = [ 10 11 ]
D [03/Jul/2007:03:42:50 +0900] StartJob: filterfds[1] = [ 12 -1 ]
D [03/Jul/2007:03:42:50 +0900] StartJob: filter =
"/usr/lib/cups/filter/cjktexttops"
D [03/Jul/2007:03:42:50 +0900] StartJob: filterfds[0] = [ 13 14 ]
D [03/Jul/2007:03:42:50 +0900]
start_process("/usr/lib/cups/filter/cjktexttops", 0xbfead4d0,
0xbfeac840, 12, 14, 11)
I [03/Jul/2007:03:42:50 +0900] Started filter
/usr/lib/cups/filter/cjktexttops (PID 24901) for job 1.
D [03/Jul/2007:03:42:50 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:42:50 +0900] StartJob: filterfds[1] = [ 12 15 ]
D [03/Jul/2007:03:42:50 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfead4d0, 0xbfeac840, 13,
15, 11)
I [03/Jul/2007:03:42:50 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 24902) for job 1.
D [03/Jul/2007:03:42:50 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:42:50 +0900] StartJob: filterfds[0] = [ 13 14 ]
D [03/Jul/2007:03:42:50 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfead4d0, 0xbfeac840,
12, 14, 11)
I [03/Jul/2007:03:42:50 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 24903) for job 1.
D [03/Jul/2007:03:42:50 +0900] StartJob: backend =
"/usr/lib/cups/backend/ccp"
D [03/Jul/2007:03:42:50 +0900] StartJob: filterfds[1] = [ -1 12 ]
D [03/Jul/2007:03:42:50 +0900]
start_process("/usr/lib/cups/backend/ccp", 0xbfead4d0, 0xbfeac840, 13,
12, 11)
I [03/Jul/2007:03:42:50 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 24906) for job 1.
D [03/Jul/2007:03:42:50 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:42:50 +0900] [Job 1] pstocapt start.
D [03/Jul/2007:03:42:50 +0900] CloseClient: 8
D [03/Jul/2007:03:42:50 +0900] [Job 1] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:42:50 +0900] CloseClient: 9
D [03/Jul/2007:03:42:50 +0900] [Job 1] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%DocumentFonts: Courier Times-Bold
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%Title:
/var/spool/cups/d00001-001 (mpage)
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%Creator: mpage 2.5.4 May 2004
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%CreationDate: Tue Jul  3
03:42:50 2007
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%Orientation: Portrait
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%DocumentMedia: A4 596 842
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%BoundingBox: 18 36 578 806
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%Pages: (atend)
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%EndComments
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%BeginProlog
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%EndProlog
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%Page: 1 1
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%Page: 1 1
D [03/Jul/2007:03:42:50 +0900] [Job 1] pw = 568.3, pl = 813.1
D [03/Jul/2007:03:42:50 +0900] [Job 1] PageLeft = 13.4, PageRight = 581.8
D [03/Jul/2007:03:42:50 +0900] [Job 1] PageTop = 827.6, PageBottom = 14.5
D [03/Jul/2007:03:42:50 +0900] [Job 1] PageWidth = 595.0, PageLength = 842.0
D [03/Jul/2007:03:42:50 +0900] [Job 1] 0 %%Trailer
D [03/Jul/2007:03:42:50 +0900] [Job 1] Saw Trailer!
D [03/Jul/2007:03:42:56 +0900] [Job 1] ccp: last data.
D [03/Jul/2007:03:42:56 +0900] [Job 1] ccp: end of send data.
D [03/Jul/2007:03:42:56 +0900] UpdateJob: job 1, file 0 is complete.
D [03/Jul/2007:03:42:56 +0900] CancelJob: id = 1
D [03/Jul/2007:03:42:56 +0900] StopJob: id = 1, force = 0
D [03/Jul/2007:03:42:56 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:43:02 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:43:02 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:02 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:02 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:43:02 +0900] CloseClient: 9
D [03/Jul/2007:03:43:03 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:03 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:03 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:03 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:08 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:08 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:08 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:08 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:09 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:09 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:12 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:12 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:12 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:12 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:12 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:12 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:13 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:13 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:13 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:13 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:13 +0900] ReadClient: 8 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:43:13 +0900] SendFile: 8 file=9
D [03/Jul/2007:03:43:13 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:43:13 +0900] ReadClient: 9 POST /printers/LBP1210 HTTP/1.1
D [03/Jul/2007:03:43:13 +0900] print_job: auto-typing file...
D [03/Jul/2007:03:43:13 +0900] print_job: request file type is
application/postscript.
D [03/Jul/2007:03:43:13 +0900] check_quotas: requesting-user-name = 'toshio'
D [03/Jul/2007:03:43:13 +0900] print_job: requesting-user-name = 'toshio'
D [03/Jul/2007:03:43:13 +0900] Adding default job-sheets values
"none,none"...
I [03/Jul/2007:03:43:13 +0900] Adding start banner page "none" to job 2.
I [03/Jul/2007:03:43:13 +0900] Adding end banner page "none" to job 2.
I [03/Jul/2007:03:43:13 +0900] Job 2 queued on 'LBP1210' by 'toshio'.
D [03/Jul/2007:03:43:13 +0900] Job 2 hold_until = 0
D [03/Jul/2007:03:43:13 +0900] StartJob(2, 0x8096b40)
D [03/Jul/2007:03:43:13 +0900] StartJob() id = 2, file = 0/1
D [03/Jul/2007:03:43:13 +0900] job-sheets=none,none
D [03/Jul/2007:03:43:13 +0900] banner_page = 0
D [03/Jul/2007:03:43:13 +0900] StartJob: argv =
"LBP1210","2","toshio","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00002-001"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[10]="DEVICE_URI=ccp:/var/ccpd/fifo0"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:43:13 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:43:13 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:43:13 +0900] StartJob: statusfds = [ 10 11 ]
D [03/Jul/2007:03:43:13 +0900] StartJob: filterfds[1] = [ 12 -1 ]
D [03/Jul/2007:03:43:13 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:43:13 +0900] StartJob: filterfds[0] = [ 13 14 ]
D [03/Jul/2007:03:43:13 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfead4d0, 0xbfeac840, 12,
14, 11)
I [03/Jul/2007:03:43:13 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 25130) for job 2.
D [03/Jul/2007:03:43:13 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:43:13 +0900] StartJob: filterfds[1] = [ 12 15 ]
D [03/Jul/2007:03:43:13 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfead4d0, 0xbfeac840,
13, 15, 11)
I [03/Jul/2007:03:43:13 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 25131) for job 2.
D [03/Jul/2007:03:43:13 +0900] StartJob: backend =
"/usr/lib/cups/backend/ccp"
D [03/Jul/2007:03:43:13 +0900] StartJob: filterfds[0] = [ -1 13 ]
D [03/Jul/2007:03:43:13 +0900]
start_process("/usr/lib/cups/backend/ccp", 0xbfead4d0, 0xbfeac840, 12,
13, 11)
I [03/Jul/2007:03:43:13 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 25132) for job 2.
D [03/Jul/2007:03:43:13 +0900] ProcessIPPRequest: 9 status_code=0
D [03/Jul/2007:03:43:13 +0900] [Job 2] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:43:13 +0900] [Job 2] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:43:13 +0900] [Job 2] pstocapt start.
D [03/Jul/2007:03:43:15 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:15 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:18 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:18 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:18 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:18 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:18 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:18 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:18 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:18 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:18 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:18 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:21 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:21 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:23 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:23 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:23 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:23 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:28 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:28 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:28 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:28 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:29 +0900] CloseClient: 8
D [03/Jul/2007:03:43:29 +0900] CloseClient: 9
D [03/Jul/2007:03:43:35 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:43:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:35 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:35 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:35 +0900] AcceptClient: 9 from localhost:631.
D [03/Jul/2007:03:43:35 +0900] CloseClient: 9
D [03/Jul/2007:03:43:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:35 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:35 +0900] ReadClient: 8 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:43:35 +0900] SendFile: 8 file=9
D [03/Jul/2007:03:43:35 +0900] ReadClient: 8 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:03:43:35 +0900] SendFile: 8 file=9
D [03/Jul/2007:03:43:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:35 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:36 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:36 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:40 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:40 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:40 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:45 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:45 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:45 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:45 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:45 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:45 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:46 +0900] ReadClient: 8 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:43:46 +0900] SendError: 8 code=401 (Unauthorized)
D [03/Jul/2007:03:43:46 +0900] CloseClient: 8
D [03/Jul/2007:03:43:46 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:43:46 +0900] ReadClient: 8 POST /admin/ HTTP/1.1
I [03/Jul/2007:03:43:46 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was "ccp:/var/ccpd/fifo0".)
I [03/Jul/2007:03:43:46 +0900] Saving printers.conf...
D [03/Jul/2007:03:43:46 +0900] StopJob: id = 2, force = 1
D [03/Jul/2007:03:43:46 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:43:46 +0900] StartJob(2, 0x8096b40)
D [03/Jul/2007:03:43:46 +0900] StartJob() id = 2, file = 0/1
D [03/Jul/2007:03:43:46 +0900] job-sheets=none,none
D [03/Jul/2007:03:43:46 +0900] banner_page = 0
D [03/Jul/2007:03:43:46 +0900] StartJob: argv =
"LBP1210","2","toshio","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00002-001"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[10]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:43:46 +0900] StartJob: statusfds = [ 9 10 ]
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:43:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:43:46 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfead780, 0xbfeacaf0, 11,
13, 10)
I [03/Jul/2007:03:43:46 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 25255) for job 2.
D [03/Jul/2007:03:43:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:43:46 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfead780, 0xbfeacaf0,
12, 14, 10)
I [03/Jul/2007:03:43:46 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 25256) for job 2.
D [03/Jul/2007:03:43:46 +0900] StartJob: backend =
"/usr/lib/cups/backend/hal"
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [03/Jul/2007:03:43:46 +0900]
start_process("/usr/lib/cups/backend/hal", 0xbfead780, 0xbfeacaf0, 11,
12, 10)
I [03/Jul/2007:03:43:46 +0900] Started backend /usr/lib/cups/backend/hal
(PID 25257) for job 2.
I [03/Jul/2007:03:43:46 +0900] Printer 'LBP1210' modified by 'root'.
D [03/Jul/2007:03:43:46 +0900] ProcessIPPRequest: 8 status_code=0
E [03/Jul/2007:03:43:46 +0900] PID 25131 stopped with status 0!
D [03/Jul/2007:03:43:46 +0900] [Job 2] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:43:46 +0900] [Job 2] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:43:46 +0900] ReadClient: 8 POST /admin/ HTTP/1.1
I [03/Jul/2007:03:43:46 +0900] Setting LBP1210 device-uri to
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
(was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:03:43:46 +0900] Saving printers.conf...
D [03/Jul/2007:03:43:46 +0900] StopJob: id = 2, force = 1
D [03/Jul/2007:03:43:46 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:43:46 +0900] StartJob(2, 0x8096b40)
D [03/Jul/2007:03:43:46 +0900] StartJob() id = 2, file = 0/1
D [03/Jul/2007:03:43:46 +0900] job-sheets=none,none
D [03/Jul/2007:03:43:46 +0900] banner_page = 0
D [03/Jul/2007:03:43:46 +0900] StartJob: argv =
"LBP1210","2","toshio","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00002-001"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[10]="DEVICE_URI=hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:43:46 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:43:46 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:43:46 +0900] StartJob: statusfds = [ 9 10 ]
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:43:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:43:46 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfead780, 0xbfeacaf0, 11,
13, 10)
I [03/Jul/2007:03:43:46 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 25258) for job 2.
D [03/Jul/2007:03:43:46 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:43:46 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfead780, 0xbfeacaf0,
12, 14, 10)
I [03/Jul/2007:03:43:46 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 25259) for job 2.
D [03/Jul/2007:03:43:46 +0900] StartJob: backend =
"/usr/lib/cups/backend/hal"
D [03/Jul/2007:03:43:46 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [03/Jul/2007:03:43:46 +0900]
start_process("/usr/lib/cups/backend/hal", 0xbfead780, 0xbfeacaf0, 11,
12, 10)
I [03/Jul/2007:03:43:46 +0900] Started backend /usr/lib/cups/backend/hal
(PID 25260) for job 2.
I [03/Jul/2007:03:43:46 +0900] Printer 'LBP1210' modified by 'root'.
D [03/Jul/2007:03:43:46 +0900] ProcessIPPRequest: 8 status_code=0
E [03/Jul/2007:03:43:46 +0900] PID 25132 stopped with status 0!
E [03/Jul/2007:03:43:46 +0900] PID 25255 stopped with status 0!
E [03/Jul/2007:03:43:46 +0900] PID 25256 stopped with status 0!
E [03/Jul/2007:03:43:46 +0900] PID 25257 stopped with status 0!
D [03/Jul/2007:03:43:46 +0900] [Job 2] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:43:46 +0900] [Job 2] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:43:46 +0900] [Job 2] pstocapt start.
D [03/Jul/2007:03:43:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:50 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:50 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:50 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:43:54 +0900] ReadClient: 8 POST /admin/ HTTP/1.1
I [03/Jul/2007:03:43:54 +0900] Setting LBP1210 device-uri to
"ccp:/var/ccpd/fifo0" (was
"hal:///org/freedesktop/Hal/devices/usb_device_4a9_2617_0139RNOd_if0_printer_noserial".)
I [03/Jul/2007:03:43:54 +0900] Saving printers.conf...
D [03/Jul/2007:03:43:54 +0900] StopJob: id = 2, force = 1
D [03/Jul/2007:03:43:54 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:43:54 +0900] StartJob(2, 0x8096b40)
D [03/Jul/2007:03:43:54 +0900] StartJob() id = 2, file = 0/1
D [03/Jul/2007:03:43:54 +0900] job-sheets=none,none
D [03/Jul/2007:03:43:54 +0900] banner_page = 0
D [03/Jul/2007:03:43:54 +0900] StartJob: argv =
"LBP1210","2","toshio","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00002-001"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[10]="DEVICE_URI=ccp:/var/ccpd/fifo0"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:43:54 +0900] StartJob: statusfds = [ 9 10 ]
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:43:54 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:43:54 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfead780, 0xbfeacaf0, 11,
13, 10)
I [03/Jul/2007:03:43:54 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 25293) for job 2.
D [03/Jul/2007:03:43:54 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:43:54 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfead780, 0xbfeacaf0,
12, 14, 10)
I [03/Jul/2007:03:43:54 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 25294) for job 2.
D [03/Jul/2007:03:43:54 +0900] StartJob: backend =
"/usr/lib/cups/backend/ccp"
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [03/Jul/2007:03:43:54 +0900]
start_process("/usr/lib/cups/backend/ccp", 0xbfead780, 0xbfeacaf0, 11,
12, 10)
I [03/Jul/2007:03:43:54 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 25295) for job 2.
I [03/Jul/2007:03:43:54 +0900] Printer 'LBP1210' modified by 'root'.
D [03/Jul/2007:03:43:54 +0900] ProcessIPPRequest: 8 status_code=0
E [03/Jul/2007:03:43:54 +0900] PID 25260 stopped with status 0!
D [03/Jul/2007:03:43:54 +0900] ReadClient: 8 POST /admin/ HTTP/1.1
I [03/Jul/2007:03:43:54 +0900] Setting LBP1210 device-uri to
"ccp:/var/ccpd/fifo0" (was "ccp:/var/ccpd/fifo0".)
I [03/Jul/2007:03:43:54 +0900] Saving printers.conf...
D [03/Jul/2007:03:43:54 +0900] StopJob: id = 2, force = 1
D [03/Jul/2007:03:43:54 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:43:54 +0900] StartJob(2, 0x8096b40)
D [03/Jul/2007:03:43:54 +0900] StartJob() id = 2, file = 0/1
D [03/Jul/2007:03:43:54 +0900] job-sheets=none,none
D [03/Jul/2007:03:43:54 +0900] banner_page = 0
D [03/Jul/2007:03:43:54 +0900] StartJob: argv =
"LBP1210","2","toshio","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00002-001"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[10]="DEVICE_URI=ccp:/var/ccpd/fifo0"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:43:54 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:43:54 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:43:54 +0900] StartJob: statusfds = [ 9 10 ]
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:43:54 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:43:54 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfead780, 0xbfeacaf0, 11,
13, 10)
I [03/Jul/2007:03:43:54 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 25296) for job 2.
D [03/Jul/2007:03:43:54 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:43:54 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfead780, 0xbfeacaf0,
12, 14, 10)
I [03/Jul/2007:03:43:54 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 25297) for job 2.
D [03/Jul/2007:03:43:54 +0900] StartJob: backend =
"/usr/lib/cups/backend/ccp"
D [03/Jul/2007:03:43:54 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [03/Jul/2007:03:43:54 +0900]
start_process("/usr/lib/cups/backend/ccp", 0xbfead780, 0xbfeacaf0, 11,
12, 10)
I [03/Jul/2007:03:43:54 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 25298) for job 2.
I [03/Jul/2007:03:43:54 +0900] Printer 'LBP1210' modified by 'root'.
D [03/Jul/2007:03:43:54 +0900] ProcessIPPRequest: 8 status_code=0
E [03/Jul/2007:03:43:54 +0900] PID 25259 stopped with status 0!
E [03/Jul/2007:03:43:54 +0900] PID 25293 stopped with status 0!
E [03/Jul/2007:03:43:54 +0900] PID 25294 stopped with status 0!
E [03/Jul/2007:03:43:54 +0900] PID 25295 stopped with status 0!
D [03/Jul/2007:03:43:54 +0900] [Job 2] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:43:54 +0900] [Job 2] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:43:54 +0900] [Job 2] pstocapt start.
D [03/Jul/2007:03:43:55 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:55 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:55 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:55 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:43:55 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:43:55 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:00 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:00 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:00 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:00 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:00 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:00 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:04 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:44:04 +0900] ReadClient: 10 POST /printers/LBP1210
HTTP/1.1
D [03/Jul/2007:03:44:04 +0900] print_job: auto-typing file...
D [03/Jul/2007:03:44:04 +0900] print_job: request file type is
application/postscript.
D [03/Jul/2007:03:44:04 +0900] check_quotas: requesting-user-name = 'root'
D [03/Jul/2007:03:44:04 +0900] print_job: requesting-user-name = 'root'
D [03/Jul/2007:03:44:04 +0900] Adding default job-sheets values
"none,none"...
I [03/Jul/2007:03:44:04 +0900] Adding start banner page "none" to job 3.
I [03/Jul/2007:03:44:04 +0900] Adding end banner page "none" to job 3.
I [03/Jul/2007:03:44:04 +0900] Job 3 queued on 'LBP1210' by 'root'.
D [03/Jul/2007:03:44:04 +0900] Job 3 hold_until = 0
D [03/Jul/2007:03:44:04 +0900] ProcessIPPRequest: 10 status_code=0
D [03/Jul/2007:03:44:05 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:05 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:05 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:05 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:05 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:05 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:10 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:10 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:10 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:10 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:10 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:10 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:11 +0900] CloseClient: 8
D [03/Jul/2007:03:44:11 +0900] CloseClient: 10
D [03/Jul/2007:03:44:15 +0900] AcceptClient: 8 from localhost:631.
D [03/Jul/2007:03:44:15 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:15 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:15 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:44:15 +0900] CloseClient: 10
D [03/Jul/2007:03:44:15 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:15 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:15 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:15 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:19 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:19 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:20 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:20 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:20 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:20 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:22 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:22 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:22 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:22 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:22 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:22 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:23 +0900] ReadClient: 8 POST /jobs HTTP/1.1
D [03/Jul/2007:03:44:23 +0900] CancelJob: id = 2
D [03/Jul/2007:03:44:23 +0900] StopJob: id = 2, force = 0
D [03/Jul/2007:03:44:23 +0900] StopJob: printer state is 3
D [03/Jul/2007:03:44:23 +0900] StartJob(3, 0x8096b40)
D [03/Jul/2007:03:44:23 +0900] StartJob() id = 3, file = 0/1
D [03/Jul/2007:03:44:23 +0900] job-sheets=none,none
D [03/Jul/2007:03:44:23 +0900] banner_page = 0
D [03/Jul/2007:03:44:23 +0900] StartJob: argv =
"LBP1210","3","root","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00003-001"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[10]="DEVICE_URI=ccp:/var/ccpd/fifo0"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:03:44:23 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:03:44:23 +0900] StartJob:
envp[16]="DBUS_SESSION_BUS_ADDRESS=unix:abstract=/tmp/dbus-YMyo9JIoXC,guid=423f8946c817d197664bd4e46d71bb00"
D [03/Jul/2007:03:44:23 +0900] StartJob: statusfds = [ 9 10 ]
D [03/Jul/2007:03:44:23 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:03:44:23 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:03:44:23 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:03:44:23 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfeae3d0, 0xbfead740, 11,
13, 10)
I [03/Jul/2007:03:44:23 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 25372) for job 3.
D [03/Jul/2007:03:44:23 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:03:44:23 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:03:44:23 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfeae3d0, 0xbfead740,
12, 14, 10)
I [03/Jul/2007:03:44:23 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 25373) for job 3.
D [03/Jul/2007:03:44:23 +0900] StartJob: backend =
"/usr/lib/cups/backend/ccp"
D [03/Jul/2007:03:44:23 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [03/Jul/2007:03:44:23 +0900]
start_process("/usr/lib/cups/backend/ccp", 0xbfeae3d0, 0xbfead740, 11,
12, 10)
I [03/Jul/2007:03:44:23 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 25374) for job 3.
I [03/Jul/2007:03:44:23 +0900] Job 2 was cancelled by 'toshio'.
D [03/Jul/2007:03:44:23 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:23 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:23 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:23 +0900] [Job 3] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:03:44:23 +0900] [Job 3] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:03:44:23 +0900] [Job 3] pstocapt start.
D [03/Jul/2007:03:44:25 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:25 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:25 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:25 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:25 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:25 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:28 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:28 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:28 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:28 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:28 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:28 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:30 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:30 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:30 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:30 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:31 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:31 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:34 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:34 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:34 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:34 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:34 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:34 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:35 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:35 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:35 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:40 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:40 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:40 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:45 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:45 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:45 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:45 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:50 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:50 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:50 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:44:55 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:55 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:44:55 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:44:55 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:45:00 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:45:00 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:45:00 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:45:00 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:45:03 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:45:03 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:45:03 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:45:03 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:45:03 +0900] ProcessIPPRequest: 10 status_code=1
D [03/Jul/2007:03:45:03 +0900] ReadClient: 10 POST / HTTP/1.1
D [03/Jul/2007:03:45:03 +0900] ProcessIPPRequest: 10 status_code=0
D [03/Jul/2007:03:45:03 +0900] AcceptClient: 11 from localhost:631.
D [03/Jul/2007:03:45:03 +0900] CloseClient: 10
D [03/Jul/2007:03:45:03 +0900] ReadClient: 11 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:45:03 +0900] SendError: 11 code=401 (Unauthorized)
D [03/Jul/2007:03:45:03 +0900] CloseClient: 11
D [03/Jul/2007:03:45:03 +0900] AcceptClient: 10 from localhost:631.
D [03/Jul/2007:03:45:03 +0900] ReadClient: 10 POST /admin/ HTTP/1.1
D [03/Jul/2007:03:45:03 +0900] CancelJob: id = 1
D [03/Jul/2007:03:45:03 +0900] CancelJob: id = 2
D [03/Jul/2007:03:45:03 +0900] CancelJob: id = 3
D [03/Jul/2007:03:45:03 +0900] StopJob: id = 3, force = 0
D [03/Jul/2007:03:45:03 +0900] StopJob: printer state is 3
I [03/Jul/2007:03:45:03 +0900] All jobs on 'LBP1210' were purged by 'root'.
D [03/Jul/2007:03:45:03 +0900] ProcessIPPRequest: 10 status_code=0
D [03/Jul/2007:03:45:03 +0900] CloseClient: 10
D [03/Jul/2007:03:45:05 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:45:05 +0900] ProcessIPPRequest: 8 status_code=0
D [03/Jul/2007:03:45:05 +0900] ReadClient: 8 POST / HTTP/1.1
D [03/Jul/2007:03:45:05 +0900] ProcessIPPRequest: 8 status_code=1
D [03/Jul/2007:03:45:08 +0900] CloseClient: 8
I [03/Jul/2007:03:46:48 +0900] Scheduler shutting down normally.
D [03/Jul/2007:03:46:48 +0900] CloseClient: 6
D [03/Jul/2007:03:46:48 +0900] StopListening: closing all listen sockets.
D [03/Jul/2007:03:46:48 +0900] PauseListening: clearing input bits...
I [03/Jul/2007:03:48:38 +0900] Listening to 0:631
D [03/Jul/2007:03:48:38 +0900] AddLocation: added location '/'
D [03/Jul/2007:03:48:38 +0900] DenyIP: / deny 00000000/00000000
D [03/Jul/2007:03:48:38 +0900] AllowIP: / allow 7f000001/ffffffff
D [03/Jul/2007:03:48:38 +0900] AddLocation: added location '/admin'
D [03/Jul/2007:03:48:38 +0900] DenyIP: /admin deny 00000000/00000000
D [03/Jul/2007:03:48:38 +0900] AllowIP: /admin allow 7f000001/ffffffff
I [03/Jul/2007:03:48:38 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:03:48:38 +0900] Configured for up to 100 clients.
I [03/Jul/2007:03:48:38 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:03:48:38 +0900] Full reload is required.
D [03/Jul/2007:03:48:38 +0900] LoadAllPrinters: Loading printer LBP1210...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device "http"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo0"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo1"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo2"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo3"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo4"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo5"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo6"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo7"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device "smb"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device "lpd"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device "ipp"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device "scsi"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"serial:/dev/ttyS0?baud=115200"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"serial:/dev/ttyS1?baud=115200"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"serial:/dev/ttyS2?baud=115200"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"serial:/dev/ttyS3?baud=115200"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device "socket"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"parallel:/dev/lp0"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"parallel:/dev/par1"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb://Canon/iP_4100"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb://Canon/LASER%20SHOT%20LBP-1210"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp2"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp3"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp4"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp5"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp6"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp7"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp8"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp9"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp10"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp11"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp12"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp13"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp14"...
D [03/Jul/2007:03:48:38 +0900] LoadDevices: Added device
"usb:/dev/usb/lp15"...
I [03/Jul/2007:03:48:38 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:03:48:39 +0900] LoadPPDs: No new or changed PPDs...
D [03/Jul/2007:03:48:39 +0900] LoadAllJobs: Scanning /var/spool/cups...
I [03/Jul/2007:03:48:39 +0900] Full reload complete.
D [03/Jul/2007:03:48:39 +0900] StartListening: NumListeners=1
D [03/Jul/2007:03:48:39 +0900] StartListening: address=00000000 port=631
D [03/Jul/2007:03:48:39 +0900] ResumeListening: setting input bits...
D [03/Jul/2007:03:48:43 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:03:48:43 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:03:48:43 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:03:48:43 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:03:48:43 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:03:48:43 +0900] CloseClient: 5
D [03/Jul/2007:03:53:23 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:03:53:23 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:03:53:23 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:03:53:23 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:03:53:23 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:03:53:23 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:03:53:23 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:03:53:23 +0900] CloseClient: 5
I [03/Jul/2007:04:05:56 +0900] Scheduler shutting down normally.
D [03/Jul/2007:04:05:56 +0900] StopListening: closing all listen sockets.
D [03/Jul/2007:04:05:56 +0900] PauseListening: clearing input bits...
I [03/Jul/2007:04:09:43 +0900] Listening to 0:631
D [03/Jul/2007:04:09:43 +0900] AddLocation: added location '/'
D [03/Jul/2007:04:09:43 +0900] DenyIP: / deny 00000000/00000000
D [03/Jul/2007:04:09:43 +0900] AllowIP: / allow 7f000001/ffffffff
D [03/Jul/2007:04:09:43 +0900] AddLocation: added location '/admin'
D [03/Jul/2007:04:09:43 +0900] DenyIP: /admin deny 00000000/00000000
D [03/Jul/2007:04:09:43 +0900] AllowIP: /admin allow 7f000001/ffffffff
I [03/Jul/2007:04:09:43 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:04:09:43 +0900] Configured for up to 100 clients.
I [03/Jul/2007:04:09:43 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:04:09:43 +0900] Full reload is required.
D [03/Jul/2007:04:09:43 +0900] LoadAllPrinters: Loading printer LBP1210...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device "http"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo0"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo1"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo2"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo3"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo4"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo5"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo6"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo7"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device "smb"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device "lpd"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device "ipp"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device "scsi"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"serial:/dev/ttyS0?baud=115200"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"serial:/dev/ttyS1?baud=115200"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"serial:/dev/ttyS2?baud=115200"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"serial:/dev/ttyS3?baud=115200"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device "socket"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"parallel:/dev/lp0"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"parallel:/dev/par1"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb://Canon/iP_4100"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb://Canon/LASER%20SHOT%20LBP-1210"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp2"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp3"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp4"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp5"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp6"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp7"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp8"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp9"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp10"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp11"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp12"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp13"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp14"...
D [03/Jul/2007:04:09:43 +0900] LoadDevices: Added device
"usb:/dev/usb/lp15"...
I [03/Jul/2007:04:09:43 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:04:09:44 +0900] LoadPPDs: No new or changed PPDs...
D [03/Jul/2007:04:09:44 +0900] LoadAllJobs: Scanning /var/spool/cups...
I [03/Jul/2007:04:09:44 +0900] Full reload complete.
D [03/Jul/2007:04:09:44 +0900] StartListening: NumListeners=1
D [03/Jul/2007:04:09:44 +0900] StartListening: address=00000000 port=631
D [03/Jul/2007:04:09:44 +0900] ResumeListening: setting input bits...
D [03/Jul/2007:04:09:47 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:04:09:47 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:09:47 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:09:47 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:09:47 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:09:47 +0900] CloseClient: 5
D [03/Jul/2007:04:16:08 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:04:16:08 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:16:08 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:16:08 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:04:16:08 +0900] CloseClient: 7
D [03/Jul/2007:04:16:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:16:09 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:16:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:16:09 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:04:16:11 +0900] CloseClient: 5
D [03/Jul/2007:04:21:10 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:04:21:10 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:10 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:10 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:11 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:04:21:11 +0900] ReadClient: 5 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:04:21:11 +0900] SendFile: 5 file=7
D [03/Jul/2007:04:21:15 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:16 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:20 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:21 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:25 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:26 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:30 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:31 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:35 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:36 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:40 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:41 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:45 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:46 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:50 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:51 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:21:55 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:21:56 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:00 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:01 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:05 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:06 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:10 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:11 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:16 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:16 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:21 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:21 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:26 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:26 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:31 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:31 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:36 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:36 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:41 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:41 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:46 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:46 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:51 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:51 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:22:56 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:22:56 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:01 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:01 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:06 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:06 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:11 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:11 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:16 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:16 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:21 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:21 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:26 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:26 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:31 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:31 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:36 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:36 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:41 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:41 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:46 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:46 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:51 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:51 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:23:56 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:23:56 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:01 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:01 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:06 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:06 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:11 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:11 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:16 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:16 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:21 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:21 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:26 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:26 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:31 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:31 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:36 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:36 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:41 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:41 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:46 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:04:24:46 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:04:24:48 +0900] CloseClient: 5
I [03/Jul/2007:04:25:07 +0900] Scheduler shutting down normally.
D [03/Jul/2007:04:25:07 +0900] StopListening: closing all listen sockets.
D [03/Jul/2007:04:25:07 +0900] PauseListening: clearing input bits...
I [03/Jul/2007:23:30:25 +0900] Listening to 0:631
D [03/Jul/2007:23:30:25 +0900] AddLocation: added location '/'
D [03/Jul/2007:23:30:25 +0900] DenyIP: / deny 00000000/00000000
D [03/Jul/2007:23:30:25 +0900] AllowIP: / allow 7f000001/ffffffff
D [03/Jul/2007:23:30:25 +0900] AddLocation: added location '/admin'
D [03/Jul/2007:23:30:25 +0900] DenyIP: /admin deny 00000000/00000000
D [03/Jul/2007:23:30:25 +0900] AllowIP: /admin allow 7f000001/ffffffff
I [03/Jul/2007:23:30:25 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [03/Jul/2007:23:30:25 +0900] Configured for up to 100 clients.
I [03/Jul/2007:23:30:25 +0900] Allowing up to 100 client connections per
host.
I [03/Jul/2007:23:30:25 +0900] Full reload is required.
D [03/Jul/2007:23:30:25 +0900] LoadAllPrinters: Loading printer LBP1210...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device "http"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo0"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo1"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo2"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo3"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo4"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo5"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo6"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"ccp:/var/ccpd/fifo7"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device "smb"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device "lpd"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device "ipp"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device "scsi"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"serial:/dev/ttyS0?baud=115200"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"serial:/dev/ttyS1?baud=115200"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"serial:/dev/ttyS2?baud=115200"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"serial:/dev/ttyS3?baud=115200"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device "socket"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"parallel:/dev/lp0"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"parallel:/dev/par1"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb://Canon/iP_4100"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb://Canon/LASER%20SHOT%20LBP-1210"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp2"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp3"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp4"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp5"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp6"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp7"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp8"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp9"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp10"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp11"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp12"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp13"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp14"...
D [03/Jul/2007:23:30:25 +0900] LoadDevices: Added device
"usb:/dev/usb/lp15"...
I [03/Jul/2007:23:30:25 +0900] LoadPPDs: Read "/etc/cups/ppds.dat", 2821
PPDs...
I [03/Jul/2007:23:30:26 +0900] LoadPPDs: No new or changed PPDs...
D [03/Jul/2007:23:30:26 +0900] LoadAllJobs: Scanning /var/spool/cups...
I [03/Jul/2007:23:30:26 +0900] Full reload complete.
D [03/Jul/2007:23:30:26 +0900] StartListening: NumListeners=1
D [03/Jul/2007:23:30:26 +0900] StartListening: address=00000000 port=631
D [03/Jul/2007:23:30:26 +0900] ResumeListening: setting input bits...
D [03/Jul/2007:23:30:29 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:23:30:29 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:30:29 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:30:29 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:30:29 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:30:29 +0900] CloseClient: 5
D [03/Jul/2007:23:33:29 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:23:33:29 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:29 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:29 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:23:33:29 +0900] CloseClient: 7
D [03/Jul/2007:23:33:30 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:30 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:30 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:30 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:35 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:35 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:38 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:38 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:38 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:38 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:38 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:38 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:38 +0900] ReadClient: 5 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:23:33:38 +0900] SendFile: 5 file=7
D [03/Jul/2007:23:33:38 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:23:33:38 +0900] ReadClient: 7 POST /printers/LBP1210 HTTP/1.1
D [03/Jul/2007:23:33:38 +0900] print_job: auto-typing file...
D [03/Jul/2007:23:33:38 +0900] print_job: request file type is
application/postscript.
D [03/Jul/2007:23:33:38 +0900] check_quotas: requesting-user-name = 'toshio'
D [03/Jul/2007:23:33:38 +0900] print_job: requesting-user-name = 'toshio'
D [03/Jul/2007:23:33:38 +0900] Adding default job-sheets values
"none,none"...
I [03/Jul/2007:23:33:38 +0900] Adding start banner page "none" to job 1.
I [03/Jul/2007:23:33:38 +0900] Adding end banner page "none" to job 1.
I [03/Jul/2007:23:33:38 +0900] Job 1 queued on 'LBP1210' by 'toshio'.
D [03/Jul/2007:23:33:38 +0900] Job 1 hold_until = 0
D [03/Jul/2007:23:33:38 +0900] StartJob(1, 0x8096e38)
D [03/Jul/2007:23:33:38 +0900] StartJob() id = 1, file = 0/1
D [03/Jul/2007:23:33:38 +0900] job-sheets=none,none
D [03/Jul/2007:23:33:38 +0900] banner_page = 0
D [03/Jul/2007:23:33:38 +0900] StartJob: argv =
"LBP1210","1","toshio","a~??a~?^(1)a~??a~??a~??a~?OEa~?z(","1","","/var/spool/cups/d00001-001"
D [03/Jul/2007:23:33:38 +0900] StartJob:
envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[2]="USER=root"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[4]="LANG=ja_JP"
D [03/Jul/2007:23:33:38 +0900] StartJob:
envp[5]="PPD=/etc/cups/ppd/LBP1210.ppd"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[7]="RIP_MAX_CACHE=8m"
D [03/Jul/2007:23:33:38 +0900] StartJob:
envp[8]="TMPDIR=/var/spool/cups/tmp"
D [03/Jul/2007:23:33:38 +0900] StartJob:
envp[9]="CONTENT_TYPE=application/postscript"
D [03/Jul/2007:23:33:38 +0900] StartJob:
envp[10]="DEVICE_URI=ccp:/var/ccpd/fifo0"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[11]="PRINTER=LBP1210"
D [03/Jul/2007:23:33:38 +0900] StartJob:
envp[12]="CUPS_DATADIR=/usr/share/cups"
D [03/Jul/2007:23:33:38 +0900] StartJob:
envp[13]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[14]="CUPS_SERVER=localhost"
D [03/Jul/2007:23:33:38 +0900] StartJob: envp[15]="IPP_PORT=631"
D [03/Jul/2007:23:33:38 +0900] StartJob: statusfds = [ 9 10 ]
D [03/Jul/2007:23:33:38 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [03/Jul/2007:23:33:38 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstops"
D [03/Jul/2007:23:33:38 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [03/Jul/2007:23:33:38 +0900]
start_process("/usr/lib/cups/filter/pstops", 0xbfc2e930, 0xbfc2dca0, 11,
13, 10)
I [03/Jul/2007:23:33:38 +0900] Started filter
/usr/lib/cups/filter/pstops (PID 3150) for job 1.
D [03/Jul/2007:23:33:38 +0900] StartJob: filter =
"/usr/lib/cups/filter/pstocapt"
D [03/Jul/2007:23:33:38 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [03/Jul/2007:23:33:38 +0900]
start_process("/usr/lib/cups/filter/pstocapt", 0xbfc2e930, 0xbfc2dca0,
12, 14, 10)
I [03/Jul/2007:23:33:38 +0900] Started filter
/usr/lib/cups/filter/pstocapt (PID 3151) for job 1.
D [03/Jul/2007:23:33:38 +0900] StartJob: backend =
"/usr/lib/cups/backend/ccp"
D [03/Jul/2007:23:33:38 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [03/Jul/2007:23:33:38 +0900]
start_process("/usr/lib/cups/backend/ccp", 0xbfc2e930, 0xbfc2dca0, 11,
12, 10)
I [03/Jul/2007:23:33:38 +0900] Started backend /usr/lib/cups/backend/ccp
(PID 3152) for job 1.
D [03/Jul/2007:23:33:38 +0900] ProcessIPPRequest: 7 status_code=0
D [03/Jul/2007:23:33:38 +0900] [Job 1] pstocapt start.
D [03/Jul/2007:23:33:38 +0900] [Job 1] Page = 595x842; 13,14 to 582,828
D [03/Jul/2007:23:33:38 +0900] [Job 1] slowcollate=0, slowduplex=0,
sloworder=0
D [03/Jul/2007:23:33:40 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:40 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:40 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:40 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:41 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:41 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:44 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:44 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:44 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:44 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:44 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:44 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:45 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:45 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:45 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:45 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:47 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:47 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:50 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:50 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:50 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:50 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:50 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:50 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:53 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:53 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:55 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:55 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:55 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:55 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:56 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:56 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:56 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:56 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:33:56 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:56 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:33:59 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:33:59 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:00 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:00 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:00 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:00 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:34:00 +0900] CloseClient: 5
D [03/Jul/2007:23:34:00 +0900] CloseClient: 7
D [03/Jul/2007:23:34:09 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:23:34:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:09 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:09 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:09 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:23:34:09 +0900] CloseClient: 7
D [03/Jul/2007:23:34:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:09 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:34:10 +0900] ReadClient: 5 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:23:34:10 +0900] SendFile: 5 file=7
D [03/Jul/2007:23:34:10 +0900] ReadClient: 5 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:23:34:10 +0900] SendFile: 5 file=7
D [03/Jul/2007:23:34:10 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:10 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:10 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:10 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:14 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:14 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:14 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:15 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:15 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:15 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:34:19 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:19 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:19 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:20 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:20 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:20 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:34:21 +0900] CloseClient: 5
D [03/Jul/2007:23:34:27 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:23:34:27 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:27 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:27 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:23:34:27 +0900] CloseClient: 7
D [03/Jul/2007:23:34:27 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:27 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:27 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:28 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:34:31 +0900] ReadClient: 5 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:23:34:31 +0900] SendFile: 5 file=7
D [03/Jul/2007:23:34:32 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:33 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:34:33 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:34:33 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:34:34 +0900] CloseClient: 5
D [03/Jul/2007:23:35:09 +0900] AcceptClient: 5 from localhost:631.
D [03/Jul/2007:23:35:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:09 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:09 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:09 +0900] AcceptClient: 7 from localhost:631.
D [03/Jul/2007:23:35:09 +0900] CloseClient: 7
D [03/Jul/2007:23:35:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:09 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:35:09 +0900] ReadClient: 5 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:23:35:09 +0900] SendFile: 5 file=7
D [03/Jul/2007:23:35:09 +0900] ReadClient: 5 GET /printers/LBP1210.ppd
HTTP/1.1
D [03/Jul/2007:23:35:09 +0900] SendFile: 5 file=7
D [03/Jul/2007:23:35:09 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:09 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:10 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:10 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:14 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:14 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:14 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:14 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:14 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:14 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:35:19 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:19 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:19 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:19 +0900] ProcessIPPRequest: 5 status_code=0
D [03/Jul/2007:23:35:19 +0900] ReadClient: 5 POST / HTTP/1.1
D [03/Jul/2007:23:35:19 +0900] ProcessIPPRequest: 5 status_code=1
D [03/Jul/2007:23:35:21 +0900] CloseClient: 5
-----------------------------
ここから、何か読みとれるでしょうか。とにかくcanon LBP1210からは印刷でき
ない状態で、（canon iP4100は現在、導入していない）困惑しています。