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

[debian-users:54881] Re: usb 接続のプリンタから印字したい。



Masuyama です。
> > # 「システム管理」→「印刷」の Test Page 以外のファイル (ポスト
> > # スクリプトファイルでもプレーンテキストでも) を印刷してログを
> > # くらべるのも、手がかりになるかもしれません。Test Page だけ印刷
> > # できないということも、ないとは言えませんから。
> > 
> > それから、http://localhost:631/ 経由で設定をやり直してみる
> > という方法もあります。結局、同じことかもしれませんが。
> > 
○ エラーログの表示

W [17/Feb/2011:00:40:50 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:00:40:50 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:00:40:50 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:00:40:50 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:00:40:50 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:00:40:50 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:00:40:50 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
W [17/Feb/2011:00:51:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:00:51:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:00:51:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:00:51:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:00:51:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:00:51:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:00:51:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
W [17/Feb/2011:00:51:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:00:51:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:00:51:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:00:51:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:00:51:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:00:51:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:00:51:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
E [17/Feb/2011:00:58:18 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
I [17/Feb/2011:00:58:51 +0900] Listening to ::1:631 (IPv6)
I [17/Feb/2011:00:58:51 +0900] Listening to 127.0.0.1:631 (IPv4)
I [17/Feb/2011:00:58:51 +0900] Listening to /var/run/cups/cups.sock
(Domain)
I [17/Feb/2011:00:58:51 +0900] Remote access is disabled.
D [17/Feb/2011:00:58:51 +0900] Added auto ServerAlias marine
I [17/Feb/2011:00:58:51 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [17/Feb/2011:00:58:51 +0900] Using default TempDir
of /var/spool/cups/tmp...
I [17/Feb/2011:00:58:51 +0900] Configured for up to 100 clients.
I [17/Feb/2011:00:58:51 +0900] Allowing up to 100 client connections per
host.
I [17/Feb/2011:00:58:51 +0900] Using policy "default" as the default!
D [17/Feb/2011:00:58:51 +0900] load_ppd:
Loading /var/cache/cups/Canon-LASER-SHOT-LBP-350.ipp2...
D [17/Feb/2011:00:58:51 +0900]
cupsdRegisterPrinter(p=0xb89daf00(Canon-LASER-SHOT-LBP-350))
D [17/Feb/2011:00:58:51 +0900] cupsdMarkDirty(---p--)
D [17/Feb/2011:00:58:51 +0900] cupsdSetBusyState: Printing jobs and
dirty files
I [17/Feb/2011:00:58:51 +0900] Partial reload complete.
I [17/Feb/2011:00:58:51 +0900] Listening to ::1:631 on fd 3...
I [17/Feb/2011:00:58:51 +0900] Listening to 127.0.0.1:631 on fd 6...
I [17/Feb/2011:00:58:51 +0900] Listening to /var/run/cups/cups.sock on
fd 7...
I [17/Feb/2011:00:58:51 +0900] Resuming new connection processing...
D [17/Feb/2011:00:58:51 +0900]
cupsdRegisterPrinter(p=0xb89daf00(Canon-LASER-SHOT-LBP-350))
D [17/Feb/2011:00:58:51 +0900] Discarding unused server-restarted
event...
D [17/Feb/2011:00:58:52 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:00:58:52 +0900] Report: clients=1
D [17/Feb/2011:00:58:52 +0900] Report: jobs=3
D [17/Feb/2011:00:58:52 +0900] Report: jobs-active=3
D [17/Feb/2011:00:58:52 +0900] Report: printers=1
D [17/Feb/2011:00:58:52 +0900] Report: printers-implicit=0
D [17/Feb/2011:00:58:52 +0900] Report: stringpool-string-count=1012
D [17/Feb/2011:00:58:52 +0900] Report: stringpool-alloc-bytes=9120
D [17/Feb/2011:00:58:52 +0900] Report: stringpool-total-bytes=23104
D [17/Feb/2011:00:58:52 +0900] Connection from localhost now encrypted.
D [17/Feb/2011:00:58:55 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:58:55 +0900] cupsdReadClient: 12
GET /admin/log/error_log HTTP/1.1
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:58:55 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:58:55 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:58:55 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:58:55 +0900] cupsdReadClient: 11 1.1 Get-Jobs 1
D [17/Feb/2011:00:58:55 +0900] Get-Jobs ipp://localhost/printers/
D [17/Feb/2011:00:58:55 +0900] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/printers/) from localhost
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:58:55 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:58:55 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:58:55 +0900] cupsdReadClient: 11 1.1 Get-Jobs 1
D [17/Feb/2011:00:58:55 +0900] Get-Jobs ipp://localhost/printers/
D [17/Feb/2011:00:58:55 +0900] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/printers/) from localhost
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:58:55 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:58:55 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:58:55 +0900] cupsdReadClient: 11 1.1
Create-Printer-Subscription 1
D [17/Feb/2011:00:58:55 +0900] Create-Printer-Subscription /
D [17/Feb/2011:00:58:55 +0900]
cupsdCreateSubscription(con=0xb89c3c78(11), uri="/")
D [17/Feb/2011:00:58:55 +0900] pullmethod="ippget"
D [17/Feb/2011:00:58:55 +0900] notify-lease-duration=86400
D [17/Feb/2011:00:58:55 +0900] notify-time-interval=0
D [17/Feb/2011:00:58:55 +0900] cupsdAddSubscription(mask=17800,
dest=(nil)(), job=(nil)(0), uri="(null)")
D [17/Feb/2011:00:58:55 +0900] Added subscription 7 for server
D [17/Feb/2011:00:58:55 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:58:55 +0900] Returning IPP successful-ok for
Create-Printer-Subscription (/) from localhost
D [17/Feb/2011:00:58:55 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:58:57 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:58:57 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:58:57 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:58:57 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:00:58:57 +0900] Get-Notifications /
D [17/Feb/2011:00:58:57 +0900] cupsdIsAuthorized:
requesting-user-name="root"
D [17/Feb/2011:00:58:57 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:58:57 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:10 +0900] cupsdAcceptClient: 13 from localhost
(Domain)
D [17/Feb/2011:00:59:10 +0900] cupsdReadClient: 13 POST / HTTP/1.1
D [17/Feb/2011:00:59:10 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:10 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:10 +0900] cupsdReadClient: 13 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:10 +0900] Get-Notifications /
D [17/Feb/2011:00:59:10 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:10 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:10 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:10 +0900] cupsdReadClient: 13 WAITING Closing on
EOF
D [17/Feb/2011:00:59:10 +0900] cupsdCloseClient: 13
D [17/Feb/2011:00:59:14 +0900] cupsdAcceptClient: 13 from localhost
(Domain)
D [17/Feb/2011:00:59:15 +0900] Connection from localhost now encrypted.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 13
POST /printers/Canon-LASER-SHOT-LBP-350 HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 13 1.1 Print-Job 1
D [17/Feb/2011:00:59:15 +0900] Print-Job
ipp://localhost/printers/Canon-LASER-SHOT-LBP-350
D [17/Feb/2011:00:59:15 +0900] [Job ???] Auto-typing file...
I [17/Feb/2011:00:59:15 +0900] [Job ???] Request file type is
application/vnd.cups-banner.
D [17/Feb/2011:00:59:15 +0900] cupsdMarkDirty(----J-)
D [17/Feb/2011:00:59:15 +0900] add_job: requesting-user-name="root"
D [17/Feb/2011:00:59:15 +0900] Adding default job-sheets values
"none,none"...
I [17/Feb/2011:00:59:15 +0900] [Job 4] Adding start banner page "none".
D [17/Feb/2011:00:59:15 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:59:15 +0900] cupsdMarkDirty(----J-)
I [17/Feb/2011:00:59:15 +0900] [Job 4] Adding end banner page "none".
I [17/Feb/2011:00:59:15 +0900] [Job 4] File of type
application/vnd.cups-banner queued by "root".
D [17/Feb/2011:00:59:15 +0900] [Job 4] hold_until=0
I [17/Feb/2011:00:59:15 +0900] [Job 4] Queued on
"Canon-LASER-SHOT-LBP-350" by "root".
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for Print-Job
(ipp://localhost/printers/Canon-LASER-SHOT-LBP-350) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:00:59:15 +0900] cupsdAcceptClient: 17 from localhost
(Domain)
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdAcceptClient: 19 from localhost
(Domain)
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:15 +0900] Get-Notifications /
D [17/Feb/2011:00:59:15 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 1.1 Get-Jobs 1
D [17/Feb/2011:00:59:15 +0900] Get-Jobs ipp://localhost/printers/
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/printers/) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 19 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:15 +0900] Get-Notifications /
D [17/Feb/2011:00:59:15 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:00:59:15 +0900] cupsdCloseClient: 16
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 WAITING Closing on
EOF
D [17/Feb/2011:00:59:15 +0900] cupsdCloseClient: 17
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 19 WAITING Closing on
EOF
D [17/Feb/2011:00:59:15 +0900] cupsdCloseClient: 19
D [17/Feb/2011:00:59:15 +0900] cupsdAcceptClient: 17 from localhost
(Domain)
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:15 +0900] Get-Notifications /
D [17/Feb/2011:00:59:15 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:15 +0900] Get-Notifications /
D [17/Feb/2011:00:59:15 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 1.1
Get-Job-Attributes 1
D [17/Feb/2011:00:59:15 +0900] Get-Job-Attributes ipp://localhost/jobs/4
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/4) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 1.1
Get-Job-Attributes 1
D [17/Feb/2011:00:59:15 +0900] Get-Job-Attributes ipp://localhost/jobs/4
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/4) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAcceptClient: 19 from localhost
(Domain)
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:00:59:15 +0900] cupsdCloseClient: 16
D [17/Feb/2011:00:59:15 +0900] Connection from localhost now encrypted.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 19 1.1
Get-Printer-Attributes 1
D [17/Feb/2011:00:59:15 +0900] Get-Printer-Attributes
ipp://marine:0/printers/Canon-LASER-SHOT-LBP-350
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Printer-Attributes
(ipp://marine:0/printers/Canon-LASER-SHOT-LBP-350) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 19 WAITING Closing on
EOF
D [17/Feb/2011:00:59:15 +0900] cupsdCloseClient: 19
D [17/Feb/2011:00:59:15 +0900] SSL shutdown successful!
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 17 WAITING Closing on
EOF
D [17/Feb/2011:00:59:15 +0900] cupsdCloseClient: 17
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 19 WAITING Closing on
EOF
D [17/Feb/2011:00:59:15 +0900] cupsdCloseClient: 19
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:15 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:15 +0900] Get-Notifications /
D [17/Feb/2011:00:59:15 +0900] cupsdIsAuthorized:
requesting-user-name="root"
D [17/Feb/2011:00:59:15 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:15 +0900] cupsdSetBusyState: Printing jobs and
dirty files
I [17/Feb/2011:00:59:22 +0900] Generating
printcap /var/run/cups/printcap...
I [17/Feb/2011:00:59:22 +0900] Saving job cache file
"/var/cache/cups/job.cache"...
I [17/Feb/2011:00:59:22 +0900] Saving subscriptions.conf...
D [17/Feb/2011:00:59:22 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:00:59:26 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:26 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:32 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:32 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:00:59:32 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:00:59:32 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:00:59:32 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:32 +0900] cupsdReadClient: 16 1.1
Get-Job-Attributes 1
D [17/Feb/2011:00:59:32 +0900] Get-Job-Attributes ipp://localhost/jobs/3
D [17/Feb/2011:00:59:32 +0900] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [17/Feb/2011:00:59:32 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:00:59:32 +0900] cupsdReadClient: 16 POST /jobs/ HTTP/1.1
D [17/Feb/2011:00:59:32 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:00:59:32 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:32 +0900] cupsdReadClient: 16 1.1 Cancel-Job 1
D [17/Feb/2011:00:59:32 +0900] Cancel-Job ipp://localhost/jobs/3
D [17/Feb/2011:00:59:32 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:32 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:59:32 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
I [17/Feb/2011:00:59:32 +0900] [Job 3] Job purged by "umiboz"
D [17/Feb/2011:00:59:32 +0900] [Job 3] Unloading...
I [17/Feb/2011:00:59:32 +0900] [Job 3] Purged by "umiboz".
D [17/Feb/2011:00:59:32 +0900] Returning IPP successful-ok for
Cancel-Job (ipp://localhost/jobs/3) from localhost
D [17/Feb/2011:00:59:32 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:32 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:32 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:33 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:33 +0900] cupsdReadClient: 12 POST / HTTP/1.1
D [17/Feb/2011:00:59:33 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:33 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:33 +0900] cupsdReadClient: 12 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:33 +0900] Get-Notifications /
D [17/Feb/2011:00:59:33 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:33 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:33 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:33 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:33 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:00:59:37 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:37 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 16 1.1
Get-Job-Attributes 1
D [17/Feb/2011:00:59:37 +0900] Get-Job-Attributes ipp://localhost/jobs/2
D [17/Feb/2011:00:59:37 +0900] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/2) from localhost
D [17/Feb/2011:00:59:37 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:37 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 16 POST /jobs/ HTTP/1.1
D [17/Feb/2011:00:59:37 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:37 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 16 1.1 Cancel-Job 1
D [17/Feb/2011:00:59:37 +0900] Cancel-Job ipp://localhost/jobs/2
D [17/Feb/2011:00:59:37 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:37 +0900] cupsdMarkDirty(-----S)
I [17/Feb/2011:00:59:37 +0900] [Job 2] Job purged by "umiboz"
D [17/Feb/2011:00:59:37 +0900] [Job 2] Unloading...
I [17/Feb/2011:00:59:37 +0900] [Job 2] Purged by "umiboz".
D [17/Feb/2011:00:59:37 +0900] Returning IPP successful-ok for
Cancel-Job (ipp://localhost/jobs/2) from localhost
D [17/Feb/2011:00:59:37 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:37 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:37 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 12 POST / HTTP/1.1
D [17/Feb/2011:00:59:37 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:37 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 12 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:37 +0900] Get-Notifications /
D [17/Feb/2011:00:59:37 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:37 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:37 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:37 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:37 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 16 1.1
Get-Job-Attributes 1
D [17/Feb/2011:00:59:42 +0900] Get-Job-Attributes ipp://localhost/jobs/1
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/1) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 16 POST /jobs/ HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 16 1.1 Cancel-Job 1
D [17/Feb/2011:00:59:42 +0900] Cancel-Job ipp://localhost/jobs/1
D [17/Feb/2011:00:59:42 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:42 +0900] cupsdMarkDirty(-----S)
I [17/Feb/2011:00:59:42 +0900] [Job 1] Job purged by "umiboz"
D [17/Feb/2011:00:59:42 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:59:42 +0900] cupsdMarkDirty(----J-)
D [17/Feb/2011:00:59:42 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:59:42 +0900] [Job 4] job-sheets=none,none
D [17/Feb/2011:00:59:42 +0900] [Job 4]
argv[0]="Canon-LASER-SHOT-LBP-350"
D [17/Feb/2011:00:59:42 +0900] [Job 4] argv[1]="4"
D [17/Feb/2011:00:59:42 +0900] [Job 4] argv[2]="root"
D [17/Feb/2011:00:59:42 +0900] [Job 4] argv[3]="Test Page"
D [17/Feb/2011:00:59:42 +0900] [Job 4] argv[4]="1"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
argv[5]="job-uuid=urn:uuid:5e3c9926-20cb-35f5-46c8-db1b2cbe98ce
job-originating-host-name=localhost"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
argv[6]="/var/spool/cups/d00004-001"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[6]="CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[7]="CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[8]="HOME=/var/spool/cups/tmp"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[10]="SERVER_ADMIN=root@marine"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[11]="SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[12]="TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[13]="TZ=Asia/Tokyo"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[14]="USER=root"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[16]="CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[17]="IPP_PORT=631"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[18]="CHARSET=utf-8"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[19]="LANG=ja_JP.UTF-8"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[20]="PPD=/etc/cups/ppd/Canon-LASER-SHOT-LBP-350.ppd"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[21]="RIP_MAX_CACHE=auto"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[22]="CONTENT_TYPE=application/vnd.cups-banner"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[23]="DEVICE_URI=usb://Canon/LASER%20SHOT%20LBP-350"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[24]="PRINTER_INFO=Canon
LASER SHOT LBP-350"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[25]="PRINTER_LOCATION="
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[26]="PRINTER=Canon-LASER-SHOT-LBP-350"
D [17/Feb/2011:00:59:42 +0900] [Job 4] envp[27]="CUPS_FILETYPE=document"
D [17/Feb/2011:00:59:42 +0900] [Job 4]
envp[28]="FINAL_CONTENT_TYPE=printer/Canon-LASER-SHOT-LBP-350"
I [17/Feb/2011:00:59:42 +0900] [Job 4] Started
filter /usr/lib/cups/filter/bannertops (PID 2928)
I [17/Feb/2011:00:59:42 +0900] [Job 4] Started
filter /usr/lib/cups/filter/pstopdf (PID 2929)
I [17/Feb/2011:00:59:42 +0900] [Job 4] Started
filter /usr/lib/cups/filter/pdftopdf (PID 2930)
I [17/Feb/2011:00:59:42 +0900] [Job 4] Started
filter /usr/lib/cups/filter/foomatic-rip (PID 2931)
I [17/Feb/2011:00:59:42 +0900] [Job 4] Started
backend /usr/lib/cups/backend/usb (PID 2932)
D [17/Feb/2011:00:59:42 +0900] cupsdMarkDirty(-----S)
I [17/Feb/2011:00:59:42 +0900] [Job 1] Purged by "umiboz".
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Cancel-Job (ipp://localhost/jobs/1) from localhost
D [17/Feb/2011:00:59:42 +0900] PID 2787
(/usr/lib/cups/filter/foomatic-rip) was terminated normally with signal
9.
D [17/Feb/2011:00:59:42 +0900] PID 2788 (/usr/lib/cups/backend/usb) was
terminated normally with signal 9.
D [17/Feb/2011:00:59:42 +0900] [Job 4]
load_banner(filename="/var/spool/cups/d00004-001")
D [17/Feb/2011:00:59:42 +0900] [Job 4] pstopdf 5 args: 4 root Test Page
1 job-uuid=urn:uuid:5e3c9926-20cb-35f5-46c8-db1b2cbe98ce
job-originating-host-name=localhost
D [17/Feb/2011:00:59:42 +0900] [Job 4]
PPD: /etc/cups/ppd/Canon-LASER-SHOT-LBP-350.ppd
D [17/Feb/2011:00:59:42 +0900] [Job 4] Getting input from file 
D [17/Feb/2011:00:59:42 +0900] [Job 4] foomatic-rip version 4.0.5.223
running...
D [17/Feb/2011:00:59:42 +0900] [Job 4] Parsing PPD file ...
D [17/Feb/2011:00:59:42 +0900] [Job 4] Added option PageSize
D [17/Feb/2011:00:59:42 +0900] [Job 4] Added option ImageableArea
D [17/Feb/2011:00:59:42 +0900] [Job 4] Added option PaperDimension
D [17/Feb/2011:00:59:42 +0900] [Job 4] Added option Resolution
D [17/Feb/2011:00:59:42 +0900] [Job 4] Added option Font
D [17/Feb/2011:00:59:42 +0900] [Job 4] 
D [17/Feb/2011:00:59:42 +0900] [Job 4] Parameter Summary
D [17/Feb/2011:00:59:42 +0900] [Job 4] -----------------
D [17/Feb/2011:00:59:42 +0900] [Job 4] 
D [17/Feb/2011:00:59:42 +0900] [Job 4] Spooler: cups
D [17/Feb/2011:00:59:42 +0900] [Job 4] Printer: Canon-LASER-SHOT-LBP-350
D [17/Feb/2011:00:59:42 +0900] [Job 4] Shell: /bin/bash
D [17/Feb/2011:00:59:42 +0900] [Job 4] PPD
file: /etc/cups/ppd/Canon-LASER-SHOT-LBP-350.ppd
D [17/Feb/2011:00:59:42 +0900] [Job 4] ATTR file: 
D [17/Feb/2011:00:59:42 +0900] [Job 4] Printer model: Canon LBP-350
Foomatic/lbp320 (recommended)
D [17/Feb/2011:00:59:42 +0900] [Job 4] Job title: Test Page
D [17/Feb/2011:00:59:42 +0900] [Job 4] File(s) to be printed:
D [17/Feb/2011:00:59:42 +0900] [Job 4] <STDIN>
D [17/Feb/2011:00:59:42 +0900] [Job 4] 
D [17/Feb/2011:00:59:42 +0900] [Job 4] Ghostscript extra search path
('GS_LIB'): /usr/share/cups/fonts
D [17/Feb/2011:00:59:42 +0900] [Job 4] Printing system options:
D [17/Feb/2011:00:59:42 +0900] [Job 4] Pondering option
'job-uuid=urn:uuid:5e3c9926-20cb-35f5-46c8-db1b2cbe98ce'
D [17/Feb/2011:00:59:42 +0900] [Job 4] Unknown option
job-uuid=urn:uuid:5e3c9926-20cb-35f5-46c8-db1b2cbe98ce.
D [17/Feb/2011:00:59:42 +0900] [Job 4] Pondering option
'job-originating-host-name=localhost'
D [17/Feb/2011:00:59:42 +0900] [Job 4] Unknown option
job-originating-host-name=localhost.
D [17/Feb/2011:00:59:42 +0900] [Job 4] Options from the PPD file:
D [17/Feb/2011:00:59:42 +0900] [Job 4] 
D [17/Feb/2011:00:59:42 +0900] [Job 4]
================================================
D [17/Feb/2011:00:59:42 +0900] [Job 4] 
D [17/Feb/2011:00:59:42 +0900] [Job 4] File: <STDIN>
D [17/Feb/2011:00:59:42 +0900] [Job 4] 
D [17/Feb/2011:00:59:42 +0900] [Job 4]
================================================
D [17/Feb/2011:00:59:42 +0900] [Job 4] 
D [17/Feb/2011:00:59:42 +0900] [Job 4] STATE: +connecting-to-device
D [17/Feb/2011:00:59:42 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:42 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:42 +0900] [Job 4] Page = 595x842; 18,36 to 577,806
D [17/Feb/2011:00:59:42 +0900] [Job 4] PNG image: 128x128x8,
color_type=6 (RGB+ALPHA)
D [17/Feb/2011:00:59:42 +0900] [Job 4] PNG image: 192x128x8,
color_type=2 (RGB)
D [17/Feb/2011:00:59:42 +0900] PID 2928
(/usr/lib/cups/filter/bannertops) exited with no errors.
D [17/Feb/2011:00:59:42 +0900] [Job 4] Resolution: 300x300
D [17/Feb/2011:00:59:42 +0900] [Job 4] Page size: A4
D [17/Feb/2011:00:59:42 +0900] [Job 4] Width: 595, height: 842, absolute
margins: 18, 36, 577, 806
D [17/Feb/2011:00:59:42 +0900] [Job 4] Relative margins: 18, 36, 18, 36
D [17/Feb/2011:00:59:42 +0900] [Job 4] PPD options: -r300
-dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842
D [17/Feb/2011:00:59:42 +0900] [Job 4] PostScript to be injected: 
D [17/Feb/2011:00:59:42 +0900] [Job 4] Running cat | /usr/bin/gs -q
-dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3
-dAutoRotatePages=/None -dAutoFilterColorImages=false
-dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr
-dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer
-dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -r300
-dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=-
-c .setpdfwrite -f -
D [17/Feb/2011:00:59:42 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:42 +0900] Get-Notifications /
D [17/Feb/2011:00:59:42 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAcceptClient: 18 from localhost
(Domain)
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:42 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:42 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 18 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 18 1.1 Get-Jobs 1
D [17/Feb/2011:00:59:42 +0900] Get-Jobs ipp://localhost/printers/
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/printers/) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:42 +0900] Get-Notifications /
D [17/Feb/2011:00:59:42 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdAcceptClient: 19 from localhost
(Domain)
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 18 WAITING Closing on
EOF
D [17/Feb/2011:00:59:42 +0900] cupsdCloseClient: 18
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 19 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:42 +0900] Get-Notifications /
D [17/Feb/2011:00:59:42 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:42 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 19 1.1
Get-Job-Attributes 1
D [17/Feb/2011:00:59:42 +0900] Get-Job-Attributes ipp://localhost/jobs/4
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/4) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 POST / HTTP/1.1
D [17/Feb/2011:00:59:42 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:42 +0900] Get-Notifications /
D [17/Feb/2011:00:59:42 +0900] cupsdIsAuthorized:
requesting-user-name="root"
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 12 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:42 +0900] Get-Notifications /
D [17/Feb/2011:00:59:42 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:42 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:42 +0900] cupsdReadClient: 19 WAITING Closing on
EOF
D [17/Feb/2011:00:59:42 +0900] cupsdCloseClient: 19
D [17/Feb/2011:00:59:42 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:42 +0900] [Job 4] Printer using device file
"/dev/usb/lp0"...
D [17/Feb/2011:00:59:42 +0900] [Job 4] STATE: -connecting-to-device
D [17/Feb/2011:00:59:42 +0900] [Job 4] backendRunLoop(print_fd=0,
device_fd=5, snmp_fd=-1, addr=(nil), use_bc=0, side_cb=0xb7744b10)
D [17/Feb/2011:00:59:42 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:59:43 +0900] cupsdAcceptClient: 18 from localhost
(Domain)
D [17/Feb/2011:00:59:43 +0900] [Job 1] Unloading...
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 18 POST / HTTP/1.1
D [17/Feb/2011:00:59:43 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:43 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 18 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:43 +0900] Get-Notifications /
D [17/Feb/2011:00:59:43 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:43 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:43 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:43 +0900] cupsdAcceptClient: 19 from localhost
(Domain)
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 18 WAITING Closing on
EOF
D [17/Feb/2011:00:59:43 +0900] cupsdCloseClient: 18
D [17/Feb/2011:00:59:43 +0900] cupsdAcceptClient: 18 from localhost
(Domain)
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 19 POST / HTTP/1.1
D [17/Feb/2011:00:59:43 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:43 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:43 +0900] cupsdAcceptClient: 20 from localhost
(Domain)
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 18 POST / HTTP/1.1
D [17/Feb/2011:00:59:43 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 20 POST / HTTP/1.1
D [17/Feb/2011:00:59:43 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 19 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:43 +0900] Get-Notifications /
D [17/Feb/2011:00:59:43 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:43 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 18 1.1 Get-Jobs 1
D [17/Feb/2011:00:59:43 +0900] Get-Jobs ipp://localhost/printers/
D [17/Feb/2011:00:59:43 +0900] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/printers/) from localhost
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 20 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:43 +0900] Get-Notifications /
D [17/Feb/2011:00:59:43 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:43 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:43 +0900] cupsdAcceptClient: 21 from localhost
(Domain)
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 18 WAITING Closing on
EOF
D [17/Feb/2011:00:59:43 +0900] cupsdCloseClient: 18
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 21 POST / HTTP/1.1
D [17/Feb/2011:00:59:43 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 21 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:43 +0900] Get-Notifications /
D [17/Feb/2011:00:59:43 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:00:59:43 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 19 WAITING Closing on
EOF
D [17/Feb/2011:00:59:43 +0900] cupsdCloseClient: 19
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 12 WAITING Closing on
EOF
D [17/Feb/2011:00:59:43 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 20 WAITING Closing on
EOF
D [17/Feb/2011:00:59:43 +0900] cupsdCloseClient: 20
D [17/Feb/2011:00:59:43 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 21 WAITING Closing on
EOF
D [17/Feb/2011:00:59:43 +0900] cupsdCloseClient: 21
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:59:43 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:43 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:43 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:00:59:43 +0900] Get-Notifications /
D [17/Feb/2011:00:59:43 +0900] cupsdIsAuthorized:
requesting-user-name="root"
D [17/Feb/2011:00:59:43 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:00:59:43 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:43 +0900] PID 2929 (/usr/lib/cups/filter/pstopdf)
exited with no errors.
D [17/Feb/2011:00:59:43 +0900] [Job 4] Filetype: PDF
D [17/Feb/2011:00:59:43 +0900] [Job 4] Storing temporary files
in /var/spool/cups/tmp
D [17/Feb/2011:00:59:43 +0900] PID 2930 (/usr/lib/cups/filter/pdftopdf)
exited with no errors.
D [17/Feb/2011:00:59:43 +0900] [Job 4] File contains 1 pages
D [17/Feb/2011:00:59:43 +0900] [Job 4] Starting renderer with command:
gs -dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE
-sDEVICE=lbp320 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842
-r300x300 -sOutputFile=-   /var/spool/cups/tmp/foomatic-MYxLyS 
D [17/Feb/2011:00:59:43 +0900] [Job 4] Starting process
"kid3" (generation 1)
D [17/Feb/2011:00:59:43 +0900] [Job 4] Starting process
"kid4" (generation 2)
D [17/Feb/2011:00:59:43 +0900] [Job 4] Starting process
"renderer" (generation 2)
D [17/Feb/2011:00:59:43 +0900] [Job 4] JCL: %-12345X@PJL
D [17/Feb/2011:00:59:43 +0900] [Job 4] <job data> 
D [17/Feb/2011:00:59:43 +0900] [Job 4] 
D [17/Feb/2011:00:59:43 +0900] [Job 4] Read 8192 bytes of print data...
D [17/Feb/2011:00:59:43 +0900] [Job 4] renderer exited with status 0
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 11 1.1
Get-Job-Attributes 1
D [17/Feb/2011:00:59:51 +0900] Get-Job-Attributes ipp://localhost/jobs/4
D [17/Feb/2011:00:59:51 +0900] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/4) from localhost
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 11 1.1
Cancel-Subscription 1
D [17/Feb/2011:00:59:51 +0900] Cancel-Subscription /
D [17/Feb/2011:00:59:51 +0900] cupsdIsAuthorized:
requesting-user-name="root"
D [17/Feb/2011:00:59:51 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:00:59:51 +0900] Returning IPP successful-ok for
Cancel-Subscription (/) from localhost
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 16
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:00:59:51 +0900] cupsdIsAuthorized: username=""
D [17/Feb/2011:00:59:51 +0900] cupsdSendHeader: 16 WWW-Authenticate:
Basic realm="CUPS", trc="y"
D [17/Feb/2011:00:59:51 +0900] cupsdCloseClient: 16
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdAcceptClient: 12 from localhost
(Domain)
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 12
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdAuthorize: Authorized as root using
PeerCred
D [17/Feb/2011:00:59:51 +0900] cupsdIsAuthorized: username="root"
I [17/Feb/2011:00:59:51 +0900] Installing config file
"/etc/cups/cupsd.conf"...
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:00:59:51 +0900] cupsdCloseClient: 11
D [17/Feb/2011:00:59:51 +0900] SSL shutdown successful!
D [17/Feb/2011:00:59:51 +0900] cupsdCloseClient: 13
D [17/Feb/2011:00:59:51 +0900] SSL shutdown successful!
D [17/Feb/2011:00:59:51 +0900] cupsdCloseClient: 12
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:00:59:51 +0900] cupsdCloseClient: 11
D [17/Feb/2011:00:59:51 +0900] cupsdReadClient: 13 WAITING Closing on
EOF
D [17/Feb/2011:00:59:51 +0900] cupsdCloseClient: 13
D [17/Feb/2011:00:59:51 +0900]
cupsdDeregisterPrinter(p=0xb89daf00(Canon-LASER-SHOT-LBP-350),
removeit=1)
I [17/Feb/2011:00:59:51 +0900] Saving job cache file
"/var/cache/cups/job.cache"...
I [17/Feb/2011:00:59:51 +0900] Saving subscriptions.conf...
D [17/Feb/2011:00:59:51 +0900] cupsdSetBusyState: Printing jobs
E [17/Feb/2011:01:13:35 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:13:35 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:13:39 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:13:39 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:13:44 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:13:47 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:13:47 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:14:34 +0900] Returning HTTP Forbidden for Cancel-Job
(ipp://localhost/jobs/4) from localhost
E [17/Feb/2011:01:17:48 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:17:48 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:19:55 +0900] Returning HTTP Forbidden for Cancel-Job
(ipp://localhost/jobs/5) from localhost
E [17/Feb/2011:01:21:26 +0900] SSL shutdown failed: Error in the push
function.
E [17/Feb/2011:01:23:15 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:23:15 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:23:24 +0900] [Job 5] Unable to queue job for
destination "Canon-LASER-SHOT-LBP-350"!
E [17/Feb/2011:01:23:30 +0900] [CGI] Saw EOF, expected '}'!
E [17/Feb/2011:01:23:52 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:23:52 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:25:16 +0900] [Job 5] Files have gone away!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 5!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 6!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 7!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 8!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 9!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 10!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 11!
E [17/Feb/2011:01:25:16 +0900] Missing <Job #> directive on line 12!
W [17/Feb/2011:01:25:34 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:01:25:34 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:01:25:34 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:01:25:34 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:01:25:34 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:01:25:34 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:01:25:34 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
E [17/Feb/2011:01:27:23 +0900] [CGI] Saw EOF, expected '}'!
E [17/Feb/2011:01:29:17 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:29:17 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:29:21 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:01:29:21 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:01:41:18 +0900] SSL shutdown failed: Error in the push
function.
W [17/Feb/2011:01:52:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:01:52:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:01:52:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:01:52:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:01:52:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:01:52:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:01:52:55 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
E [17/Feb/2011:01:55:12 +0900] Returning IPP
client-error-document-format-not-supported for Print-Job
(ipp://localhost/printers/Canon-LASER-SHOT-LBP-350) from localhost
E [17/Feb/2011:02:11:27 +0900] Returning IPP
client-error-document-format-not-supported for Print-Job
(ipp://localhost/printers/Canon-LASER-SHOT-LBP-350) from localhost
W [17/Feb/2011:13:36:54 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:13:36:54 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:13:36:54 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:13:36:54 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:13:36:54 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:13:36:54 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:13:36:54 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
E [17/Feb/2011:13:45:39 +0900] Returning IPP
client-error-document-format-not-supported for Print-Job
(ipp://localhost/printers/Canon-LASER-SHOT-LBP-350) from localhost
E [17/Feb/2011:13:45:59 +0900] Returning IPP
client-error-document-format-not-supported for Print-Job
(ipp://localhost/printers/Canon-LASER-SHOT-LBP-350) from localhost
E [17/Feb/2011:13:54:22 +0900] Unable to
fork /usr/lib/cups/filter/pdftopdf - Resource temporarily unavailable.
E [17/Feb/2011:13:54:22 +0900] [Job 8] Unable to start filter "pdftopdf"
- Success.
E [17/Feb/2011:13:54:22 +0900] [Job 8] Stopping job because the
scheduler could not execute a filter.
E [17/Feb/2011:16:10:10 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:16:10:10 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
E [17/Feb/2011:16:10:50 +0900] Returning HTTP Forbidden for
CUPS-Get-Devices (no URI) from localhost
E [17/Feb/2011:16:10:50 +0900] [CGI] CUPS-Get-Devices request failed
with status 401: Forbidden
W [17/Feb/2011:17:46:12 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:17:46:13 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:17:46:13 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:17:46:13 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:17:46:13 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:17:46:13 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:17:46:13 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
E [17/Feb/2011:19:40:31 +0900] cupsdAuthorize: pam_acct_mgmt() returned
12 (Authentication token is no longer valid; new one required)!
W [17/Feb/2011:19:41:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Series.ppd!
W [17/Feb/2011:19:41:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_600C_Photo_Series.ppd!
W [17/Feb/2011:19:41:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_630C.ppd!
W [17/Feb/2011:19:41:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_900C_Series.ppd!
W [17/Feb/2011:19:41:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_800C_Series.ppd!
W [17/Feb/2011:19:41:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_350C.ppd!
W [17/Feb/2011:19:41:37 +0900] [CGI] Missing Product
in /usr/share/ppd/hp-ppd/HP/HP_DeskJet_990C.ppd!
E [17/Feb/2011:19:47:14 +0900] cupsdAuthorize: pam_authenticate()
returned 7 (Authentication failure)!
E [17/Feb/2011:19:47:17 +0900] cupsdAuthorize: pam_authenticate()
returned 7 (Authentication failure)!
E [17/Feb/2011:19:47:33 +0900] cupsdAuthorize: pam_authenticate()
returned 7 (Authentication failure)!
E [17/Feb/2011:19:47:35 +0900] cupsdAuthorize: pam_authenticate()
returned 7 (Authentication failure)!
E [17/Feb/2011:19:51:04 +0900] cupsdAuthorize: pam_acct_mgmt() returned
12 (Authentication token is no longer valid; new one required)!
E [17/Feb/2011:19:51:11 +0900] cupsdAuthorize: pam_acct_mgmt() returned
12 (Authentication token is no longer valid; new one required)!
I [17/Feb/2011:19:51:18 +0900] Listening to 0.0.0.0:631 (IPv4)
I [17/Feb/2011:19:51:18 +0900] Listening to :::631 (IPv6)
I [17/Feb/2011:19:51:18 +0900] Listening to /var/run/cups/cups.sock
(Domain)
I [17/Feb/2011:19:51:18 +0900] Remote access is enabled.
D [17/Feb/2011:19:51:18 +0900] Added auto ServerAlias marine
I [17/Feb/2011:19:51:18 +0900] Loaded configuration file
"/etc/cups/cupsd.conf"
I [17/Feb/2011:19:51:18 +0900] Using default TempDir
of /var/spool/cups/tmp...
I [17/Feb/2011:19:51:18 +0900] Configured for up to 100 clients.
I [17/Feb/2011:19:51:18 +0900] Allowing up to 100 client connections per
host.
I [17/Feb/2011:19:51:18 +0900] Using policy "default" as the default!
D [17/Feb/2011:19:51:18 +0900] load_ppd:
Loading /var/cache/cups/Canon_LASER_SHOT_LBP-350.ipp2...
D [17/Feb/2011:19:51:18 +0900]
cupsdRegisterPrinter(p=0xb7bcda80(Canon_LASER_SHOT_LBP-350))
D [17/Feb/2011:19:51:18 +0900] cupsdMarkDirty(---p--)
D [17/Feb/2011:19:51:18 +0900] cupsdSetBusyState: Printing jobs and
dirty files
I [17/Feb/2011:19:51:18 +0900] Partial reload complete.
I [17/Feb/2011:19:51:18 +0900] Listening to 0.0.0.0:631 on fd 3...
I [17/Feb/2011:19:51:18 +0900] Listening to :::631 on fd 6...
I [17/Feb/2011:19:51:18 +0900] Listening to /var/run/cups/cups.sock on
fd 7...
I [17/Feb/2011:19:51:18 +0900] Resuming new connection processing...
D [17/Feb/2011:19:51:18 +0900]
cupsdRegisterPrinter(p=0xb7bcda80(Canon_LASER_SHOT_LBP-350))
D [17/Feb/2011:19:51:18 +0900] Discarding unused server-restarted
event...
D [17/Feb/2011:19:51:18 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:51:18 +0900] Report: clients=1
D [17/Feb/2011:19:51:18 +0900] Report: jobs=2
D [17/Feb/2011:19:51:18 +0900] Report: jobs-active=1
D [17/Feb/2011:19:51:18 +0900] Report: printers=1
D [17/Feb/2011:19:51:18 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:51:18 +0900] Report: stringpool-string-count=1014
D [17/Feb/2011:19:51:18 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:51:18 +0900] Report: stringpool-total-bytes=23088
D [17/Feb/2011:19:51:18 +0900] PID 3612
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [17/Feb/2011:19:51:18 +0900] cupsdReadClient: 11 GET /images/wait.gif
HTTP/1.1
D [17/Feb/2011:19:51:18 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:19:51:18 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:51:18 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:19:51:23 +0900] cupsdReadClient: 11
GET /admin/?OP=redirect HTTP/1.1
D [17/Feb/2011:19:51:23 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:19:51:23 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:51:23 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/admin.cgi"
D [17/Feb/2011:19:51:23 +0900] [CGI] argv[1] = "OP=redirect"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[18] = "CUPSD_AUTH_TYPE=Basic"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[19] = "LANG=ja.UTF8"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[21] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[26] = "SCRIPT_NAME=/admin/"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[28] = "REMOTE_USER=root"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[29] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[31] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[32] = "REQUEST_METHOD=GET"
D [17/Feb/2011:19:51:23 +0900] [CGI] envp[33] =
"QUERY_STRING=OP=redirect"
D [17/Feb/2011:19:51:23 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3613)
I [17/Feb/2011:19:51:23 +0900] Started
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=3613)
D [17/Feb/2011:19:51:23 +0900] cupsdSendCommand: 11 file=12
D [17/Feb/2011:19:51:23 +0900] [CGI] admin.cgi started...
D [17/Feb/2011:19:51:23 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:51:23 +0900] [CGI] http=0xb8181df8
D [17/Feb/2011:19:51:23 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:23 +0900] [CGI] redirecting with prefix
http://localhost:631!
D [17/Feb/2011:19:51:23 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:51:23 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:51:23 +0900] Script header: Location:
http://localhost:631/admin
D [17/Feb/2011:19:51:23 +0900] Script header: 
D [17/Feb/2011:19:51:23 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:19:51:23 +0900] cupsdReadClient: 11 GET /admin HTTP/1.1
D [17/Feb/2011:19:51:23 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:19:51:24 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:51:24 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/admin.cgi"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[18] = "CUPSD_AUTH_TYPE=Basic"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[19] = "LANG=ja.UTF8"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[21] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[26] = "SCRIPT_NAME=/admin"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[28] = "REMOTE_USER=root"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[29] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[31] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[32] = "REQUEST_METHOD=GET"
D [17/Feb/2011:19:51:24 +0900] [CGI] envp[33] = "QUERY_STRING="
D [17/Feb/2011:19:51:24 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3614)
I [17/Feb/2011:19:51:24 +0900] Started
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=3614)
D [17/Feb/2011:19:51:24 +0900] cupsdSendCommand: 11 file=12
D [17/Feb/2011:19:51:24 +0900] PID 3613
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [17/Feb/2011:19:51:24 +0900] [CGI] admin.cgi started...
D [17/Feb/2011:19:51:24 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:51:24 +0900] [CGI] http=0xb7989df8
D [17/Feb/2011:19:51:24 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:24 +0900] [CGI] No form data, showing main menu...
D [17/Feb/2011:19:51:24 +0900]
[CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [17/Feb/2011:19:51:24 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:51:24 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:51:24 +0900] cupsdReadClient: 16 1.1 Get-Subscriptions
1
D [17/Feb/2011:19:51:24 +0900] Get-Subscriptions ipp://localhost/
D [17/Feb/2011:19:51:24 +0900] Returning IPP successful-ok for
Get-Subscriptions (ipp://localhost/) from localhost
D [17/Feb/2011:19:51:24 +0900] Script header: Content-Type:
text/html;charset=utf-8
D [17/Feb/2011:19:51:24 +0900] Script header: 
D [17/Feb/2011:19:51:24 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:51:24 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:51:24 +0900] PID 3614
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [17/Feb/2011:19:51:24 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:19:51:29 +0900] cupsdReadClient: 11 POST /admin/ HTTP/1.1
D [17/Feb/2011:19:51:29 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:19:51:29 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:51:29 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/admin.cgi"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[18] = "CUPSD_AUTH_TYPE=Basic"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[19] = "LANG=ja.UTF8"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[21] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[26] = "SCRIPT_NAME=/admin/"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[28] = "REMOTE_USER=root"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[29] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[31] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[32] =
"HTTP_REFERER=http://localhost:631/admin";
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[34] = "CONTENT_LENGTH=62"
D [17/Feb/2011:19:51:29 +0900] [CGI] envp[35] =
"CONTENT_TYPE=application/x-www-form-urlencoded"
D [17/Feb/2011:19:51:29 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3615)
I [17/Feb/2011:19:51:29 +0900] Started
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=3615)
D [17/Feb/2011:19:51:29 +0900] cupsdSendCommand: 11 file=16
D [17/Feb/2011:19:51:29 +0900] [CGI] admin.cgi started...
D [17/Feb/2011:19:51:29 +0900] cupsdAcceptClient: 21 from localhost
(Domain)
D [17/Feb/2011:19:51:29 +0900] [CGI] http=0xb817edf8
D [17/Feb/2011:19:51:29 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:29 +0900] [CGI] op="config-server"...
D [17/Feb/2011:19:51:29 +0900] Script header: Content-Type:
text/html;charset=utf-8
D [17/Feb/2011:19:51:29 +0900] Script header: 
D [17/Feb/2011:19:51:29 +0900] cupsdReadClient: 21 WAITING Closing on
EOF
D [17/Feb/2011:19:51:29 +0900] cupsdCloseClient: 21
D [17/Feb/2011:19:51:29 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:19:51:29 +0900] PID 3615
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
I [17/Feb/2011:19:51:49 +0900] Generating
printcap /var/run/cups/printcap...
D [17/Feb/2011:19:51:49 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:51:59 +0900] cupsdAcceptClient: 16 from localhost:631
(IPv4)
D [17/Feb/2011:19:51:59 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:51:59 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:51:59 +0900] cupsdReadClient: 16 POST /admin/ HTTP/1.1
D [17/Feb/2011:19:51:59 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:51:59 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:51:59 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/admin.cgi"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[18] = "CUPSD_AUTH_TYPE=Basic"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[19] = "LANG=ja.UTF8"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[21] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[26] = "SCRIPT_NAME=/admin/"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[28] = "REMOTE_USER=root"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[29] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[31] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[32] =
"HTTP_REFERER=http://localhost:631/admin/";
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[34] = "CONTENT_LENGTH=3826"
D [17/Feb/2011:19:51:59 +0900] [CGI] envp[35] =
"CONTENT_TYPE=application/x-www-form-urlencoded"
D [17/Feb/2011:19:51:59 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3617)
I [17/Feb/2011:19:51:59 +0900] Started
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=3617)
D [17/Feb/2011:19:51:59 +0900] cupsdSendCommand: 16 file=21
D [17/Feb/2011:19:51:59 +0900] [CGI] admin.cgi started...
D [17/Feb/2011:19:51:59 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:51:59 +0900] [CGI] http=0xb8a06df8
D [17/Feb/2011:19:51:59 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:51:59 +0900] [CGI] op="config-server"...
D [17/Feb/2011:19:51:59 +0900] cupsdReadClient: 11
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:51:59 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:51:59 +0900] cupsdIsAuthorized: username=""
D [17/Feb/2011:19:51:59 +0900] cupsdSendHeader: 11 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:51:59 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:51:59 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:51:59 +0900] cupsdReadClient: 11
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:51:59 +0900] cupsdAuthorize: Authorized as root using
Local
D [17/Feb/2011:19:51:59 +0900] cupsdIsAuthorized: username="root"
E [17/Feb/2011:19:51:59 +0900] Authorized using Basic, expected
Negotiate!
D [17/Feb/2011:19:51:59 +0900] cupsdSendHeader: 11 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:51:59 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:51:59 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:51:59 +0900] cupsdReadClient: 11
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:51:59 +0900] cupsdAuthorize: Authorized as root using
Local
D [17/Feb/2011:19:51:59 +0900] cupsdIsAuthorized: username="root"
E [17/Feb/2011:19:51:59 +0900] Authorized using Basic, expected
Negotiate!
D [17/Feb/2011:19:51:59 +0900] cupsdSendHeader: 11 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:51:59 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:51:59 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:51:59 +0900] cupsdReadClient: 11
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:51:59 +0900] cupsdAuthorize: Authorized as root using
Local
D [17/Feb/2011:19:51:59 +0900] cupsdIsAuthorized: username="root"
E [17/Feb/2011:19:51:59 +0900] Authorized using Basic, expected
Negotiate!
D [17/Feb/2011:19:51:59 +0900] cupsdSendHeader: 11 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:51:59 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:51:59 +0900] Script header: Content-Type:
text/html;charset=utf-8
D [17/Feb/2011:19:51:59 +0900] Script header: 
D [17/Feb/2011:19:51:59 +0900] PID 3617
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [17/Feb/2011:19:51:59 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:52:00 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:52:00 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:52:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:52:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:52:00 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:19:52:00 +0900] Get-Notifications /
D [17/Feb/2011:19:52:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:52:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:52:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:52:00 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:52:00 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:52:01 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:52:01 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:52:01 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:52:01 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:52:01 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:19:52:01 +0900] CUPS-Get-Printers
D [17/Feb/2011:19:52:01 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:19:52:01 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:52:01 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:52:01 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:52:01 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:52:01 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:19:52:01 +0900] CUPS-Get-Classes
D [17/Feb/2011:19:52:01 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:19:52:01 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:52:01 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:52:01 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:52:05 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:52:05 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:52:05 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:52:05 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:52:05 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:52:08 +0900] cupsdReadClient: 16 GET /favicon.ico
HTTP/1.1
D [17/Feb/2011:19:52:08 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:52:08 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:52:08 +0900] cupsdReadClient: 16 Closing because
Keep-Alive disabled
D [17/Feb/2011:19:52:08 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:52:08 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:52:11 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:52:11 +0900] cupsdReadClient: 11 GET /favicon.ico
HTTP/1.1
D [17/Feb/2011:19:52:11 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:52:11 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:52:11 +0900] cupsdReadClient: 11 Closing because
Keep-Alive disabled
D [17/Feb/2011:19:52:11 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:52:11 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:52:27 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:52:27 +0900] Report: clients=1
D [17/Feb/2011:19:52:27 +0900] Report: jobs=2
D [17/Feb/2011:19:52:27 +0900] Report: jobs-active=1
D [17/Feb/2011:19:52:27 +0900] Report: printers=1
D [17/Feb/2011:19:52:27 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:52:27 +0900] Report: stringpool-string-count=1014
D [17/Feb/2011:19:52:27 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:52:27 +0900] Report: stringpool-total-bytes=23088
D [17/Feb/2011:19:52:27 +0900] cupsdReadClient: 11 POST /admin/ HTTP/1.1
D [17/Feb/2011:19:52:27 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:52:27 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:52:27 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/admin.cgi"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[18] = "CUPSD_AUTH_TYPE=Basic"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[19] = "LANG=ja.UTF8"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[21] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[26] = "SCRIPT_NAME=/admin/"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin/"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[28] = "REMOTE_USER=root"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[29] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[31] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[32] =
"HTTP_REFERER=http://localhost:631/admin/";
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[33] = "REQUEST_METHOD=POST"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[34] = "CONTENT_LENGTH=3826"
D [17/Feb/2011:19:52:27 +0900] [CGI] envp[35] =
"CONTENT_TYPE=application/x-www-form-urlencoded"
D [17/Feb/2011:19:52:27 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3620)
I [17/Feb/2011:19:52:27 +0900] Started
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=3620)
D [17/Feb/2011:19:52:27 +0900] cupsdSendCommand: 11 file=21
D [17/Feb/2011:19:52:27 +0900] [CGI] admin.cgi started...
D [17/Feb/2011:19:52:27 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:52:27 +0900] [CGI] http=0xb796cdf8
D [17/Feb/2011:19:52:27 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:52:27 +0900] [CGI] op="config-server"...
D [17/Feb/2011:19:52:27 +0900] cupsdReadClient: 16
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:52:27 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:52:27 +0900] cupsdIsAuthorized: username=""
D [17/Feb/2011:19:52:27 +0900] cupsdSendHeader: 16 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:52:27 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:52:27 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:52:27 +0900] cupsdReadClient: 16
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:52:27 +0900] cupsdAuthorize: Authorized as root using
Local
D [17/Feb/2011:19:52:27 +0900] cupsdIsAuthorized: username="root"
E [17/Feb/2011:19:52:27 +0900] Authorized using Basic, expected
Negotiate!
D [17/Feb/2011:19:52:27 +0900] cupsdSendHeader: 16 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:52:27 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:52:27 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:52:27 +0900] cupsdReadClient: 16
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:52:27 +0900] cupsdAuthorize: Authorized as root using
Local
D [17/Feb/2011:19:52:27 +0900] cupsdIsAuthorized: username="root"
E [17/Feb/2011:19:52:27 +0900] Authorized using Basic, expected
Negotiate!
D [17/Feb/2011:19:52:27 +0900] cupsdSendHeader: 16 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:52:27 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:52:27 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:52:27 +0900] cupsdReadClient: 16
PUT /admin/conf/cupsd.conf HTTP/1.1
D [17/Feb/2011:19:52:27 +0900] cupsdAuthorize: Authorized as root using
Local
D [17/Feb/2011:19:52:27 +0900] cupsdIsAuthorized: username="root"
E [17/Feb/2011:19:52:27 +0900] Authorized using Basic, expected
Negotiate!
D [17/Feb/2011:19:52:27 +0900] cupsdSendHeader: 16 WWW-Authenticate:
Negotiate
D [17/Feb/2011:19:52:27 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:52:27 +0900] Script header: Content-Type:
text/html;charset=utf-8
D [17/Feb/2011:19:52:27 +0900] Script header: 
D [17/Feb/2011:19:52:27 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:52:27 +0900] PID 3620
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [17/Feb/2011:19:53:00 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:53:00 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:53:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:00 +0900] cupsdReadClient: 16 1.1 Get-Notifications
1
D [17/Feb/2011:19:53:00 +0900] Get-Notifications /
D [17/Feb/2011:19:53:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:53:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:53:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:00 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:53:00 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:53:04 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:53:04 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:53:07 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:53:07 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:53:07 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:53:07 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:53:07 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:53:11 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:53:11 +0900] cupsdReadClient: 11
GET /admin/log/error_log HTTP/1.1
D [17/Feb/2011:19:53:11 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:11 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:53:11 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:26 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:53:26 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:53:26 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:26 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:26 +0900] cupsdReadClient: 16 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:19:53:26 +0900] CUPS-Get-Printers
D [17/Feb/2011:19:53:26 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:19:53:26 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:26 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:53:26 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:26 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:26 +0900] cupsdReadClient: 16 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:19:53:26 +0900] CUPS-Get-Classes
D [17/Feb/2011:19:53:26 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:19:53:26 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:26 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:53:26 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:53:27 +0900] Report: clients=1
D [17/Feb/2011:19:53:27 +0900] Report: jobs=2
D [17/Feb/2011:19:53:27 +0900] Report: jobs-active=1
D [17/Feb/2011:19:53:27 +0900] Report: printers=1
D [17/Feb/2011:19:53:27 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:53:27 +0900] Report: stringpool-string-count=1014
D [17/Feb/2011:19:53:27 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:53:27 +0900] Report: stringpool-total-bytes=23088
D [17/Feb/2011:19:53:27 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:53:27 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:53:27 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:27 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:27 +0900] cupsdReadClient: 16 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:19:53:27 +0900] CUPS-Get-Printers
D [17/Feb/2011:19:53:27 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:19:53:27 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:27 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:53:27 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:27 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:27 +0900] cupsdReadClient: 16 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:19:53:27 +0900] CUPS-Get-Classes
D [17/Feb/2011:19:53:27 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:19:53:27 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:27 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:53:27 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:53:33 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:53:33 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:53:33 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:33 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:33 +0900] cupsdReadClient: 16 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:19:53:33 +0900] CUPS-Get-Printers
D [17/Feb/2011:19:53:33 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:19:53:33 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:33 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:53:33 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:33 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:33 +0900] cupsdReadClient: 16 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:19:53:33 +0900] CUPS-Get-Classes
D [17/Feb/2011:19:53:33 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:19:53:33 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:33 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:53:33 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:53:43 +0900] cupsdAcceptClient: 16 from localhost:631
(IPv4)
D [17/Feb/2011:19:53:43 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:53:43 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:53:43 +0900] cupsdReadClient: 16
GET /admin/log/access_log HTTP/1.1
D [17/Feb/2011:19:53:43 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:43 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:53:43 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:44 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:53:44 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:53:44 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:44 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:44 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:19:53:44 +0900] CUPS-Get-Printers
D [17/Feb/2011:19:53:44 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:19:53:44 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:44 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:53:44 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:44 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:44 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:19:53:44 +0900] CUPS-Get-Classes
D [17/Feb/2011:19:53:44 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:19:53:44 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:44 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:53:44 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:53:52 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:53:52 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:53:52 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:52 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:52 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:19:53:52 +0900] CUPS-Get-Printers
D [17/Feb/2011:19:53:52 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:19:53:52 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:52 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:53:52 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:53:52 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:53:52 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:19:53:52 +0900] CUPS-Get-Classes
D [17/Feb/2011:19:53:52 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:19:53:52 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:53:52 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:53:52 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:54:00 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:54:00 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:54:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:54:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:54:00 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:19:54:00 +0900] Get-Notifications /
D [17/Feb/2011:19:54:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:54:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:54:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:54:00 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:54:00 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:54:09 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:54:09 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:54:09 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:54:09 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:54:09 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:54:16 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:54:16 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:54:16 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:54:16 +0900] cupsdReadClient: 11 GET /jobs/ HTTP/1.1
D [17/Feb/2011:19:54:16 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:54:17 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:54:17 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/jobs.cgi"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[18] = "CUPSD_AUTH_TYPE=Basic"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[19] = "LANG=ja.UTF8"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[21] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[22] = "SERVER_NAME=localhost"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[23] = "SERVER_PORT=631"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[24] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[26] = "SCRIPT_NAME=/jobs/"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[27] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/jobs/"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[28] = "REMOTE_USER=root"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[29] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[30] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[31] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[32] =
"HTTP_REFERER=http://localhost:631/admin";
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[33] = "REQUEST_METHOD=GET"
D [17/Feb/2011:19:54:17 +0900] [CGI] envp[34] = "QUERY_STRING="
D [17/Feb/2011:19:54:17 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/jobs.cgi (PID 3628)
I [17/Feb/2011:19:54:17 +0900] Started
"/usr/lib/cups/cgi-bin/jobs.cgi" (pid=3628)
D [17/Feb/2011:19:54:17 +0900] cupsdSendCommand: 11 file=16
D [17/Feb/2011:19:54:17 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:54:17 +0900] cupsdAcceptClient: 21 from localhost
(Domain)
D [17/Feb/2011:19:54:17 +0900] Script header: Content-Type:
text/html;charset=utf-8
D [17/Feb/2011:19:54:17 +0900] Script header: 
D [17/Feb/2011:19:54:17 +0900] cupsdReadClient: 21 POST / HTTP/1.1
D [17/Feb/2011:19:54:17 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:54:17 +0900] cupsdReadClient: 21 1.1 Get-Jobs 1
D [17/Feb/2011:19:54:17 +0900] Get-Jobs ipp://localhost/
D [17/Feb/2011:19:54:17 +0900] Returning IPP successful-ok for Get-Jobs
(ipp://localhost/) from localhost
D [17/Feb/2011:19:54:17 +0900] cupsdReadClient: 21 WAITING Closing on
EOF
D [17/Feb/2011:19:54:17 +0900] cupsdCloseClient: 21
D [17/Feb/2011:19:54:17 +0900] PID 3628 (/usr/lib/cups/cgi-bin/jobs.cgi)
exited with no errors.
D [17/Feb/2011:19:54:17 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:54:40 +0900] Report: clients=1
D [17/Feb/2011:19:54:40 +0900] Report: jobs=2
D [17/Feb/2011:19:54:40 +0900] Report: jobs-active=1
D [17/Feb/2011:19:54:40 +0900] Report: printers=1
D [17/Feb/2011:19:54:40 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:54:40 +0900] Report: stringpool-string-count=1014
D [17/Feb/2011:19:54:40 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:54:40 +0900] Report: stringpool-total-bytes=23088
D [17/Feb/2011:19:54:44 +0900] cupsdReadClient: 11
GET /admin/log/access_log HTTP/1.1
D [17/Feb/2011:19:54:44 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:54:44 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:54:44 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:55:00 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:55:00 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:55:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:55:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:55:00 +0900] cupsdReadClient: 16 1.1 Get-Notifications
1
D [17/Feb/2011:19:55:00 +0900] Get-Notifications /
D [17/Feb/2011:19:55:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:55:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:55:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:55:00 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:55:00 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:55:11 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:55:11 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:55:11 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:55:11 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:55:11 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:55:19 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:55:19 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:55:37 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:55:37 +0900] cupsdReadClient: 11
GET /admin/log/error_log HTTP/1.1
D [17/Feb/2011:19:55:37 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:55:37 +0900] cupsdAuthorize: Authorized as root using
Basic
D [17/Feb/2011:19:55:37 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:55:42 +0900] Report: clients=1
D [17/Feb/2011:19:55:43 +0900] Report: jobs=2
D [17/Feb/2011:19:55:43 +0900] Report: jobs-active=1
D [17/Feb/2011:19:55:43 +0900] Report: printers=1
D [17/Feb/2011:19:55:43 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:55:43 +0900] Report: stringpool-string-count=1014
D [17/Feb/2011:19:55:43 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:55:43 +0900] Report: stringpool-total-bytes=23088
D [17/Feb/2011:19:56:00 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:19:56:00 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:19:56:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:56:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:56:00 +0900] cupsdReadClient: 16 1.1 Get-Notifications
1
D [17/Feb/2011:19:56:00 +0900] Get-Notifications /
D [17/Feb/2011:19:56:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:56:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:56:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:56:00 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:19:56:00 +0900] cupsdCloseClient: 16
D [17/Feb/2011:19:56:13 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:56:13 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:56:13 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:56:13 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:56:13 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:56:19 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:56:19 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:56:44 +0900] Report: clients=0
D [17/Feb/2011:19:56:44 +0900] Report: jobs=2
D [17/Feb/2011:19:56:44 +0900] Report: jobs-active=1
D [17/Feb/2011:19:56:44 +0900] Report: printers=1
D [17/Feb/2011:19:56:44 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:56:44 +0900] Report: stringpool-string-count=1014
D [17/Feb/2011:19:56:44 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:56:44 +0900] Report: stringpool-total-bytes=23088
D [17/Feb/2011:19:57:00 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:57:00 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:57:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:57:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:57:00 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:19:57:00 +0900] Get-Notifications /
D [17/Feb/2011:19:57:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:57:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:57:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:57:00 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:57:00 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:57:01 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:57:01 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:57:01 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:57:01 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:57:01 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:19:57:01 +0900] CUPS-Get-Printers
D [17/Feb/2011:19:57:01 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:19:57:01 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:57:01 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:57:01 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:57:01 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:57:01 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:19:57:01 +0900] CUPS-Get-Classes
D [17/Feb/2011:19:57:01 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:19:57:01 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:57:01 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:57:01 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:57:15 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:57:15 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:57:15 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:57:15 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:57:15 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:57:46 +0900] Report: clients=0
D [17/Feb/2011:19:57:46 +0900] Report: jobs=2
D [17/Feb/2011:19:57:46 +0900] Report: jobs-active=1
D [17/Feb/2011:19:57:46 +0900] Report: printers=1
D [17/Feb/2011:19:57:46 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:57:46 +0900] Report: stringpool-string-count=1014
D [17/Feb/2011:19:57:46 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:57:46 +0900] Report: stringpool-total-bytes=23088
D [17/Feb/2011:19:58:00 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:58:00 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:58:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:58:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:58:00 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:19:58:00 +0900] Get-Notifications /
D [17/Feb/2011:19:58:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:58:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:58:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:58:00 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:58:00 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:58:17 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:58:17 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:58:17 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:58:17 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:58:17 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:58:39 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:58:39 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:58:39 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:58:39 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:58:39 +0900] cupsdReadClient: 11 1.1
Cancel-Subscription 1
D [17/Feb/2011:19:58:39 +0900] Cancel-Subscription /
D [17/Feb/2011:19:58:39 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:58:39 +0900] cupsdMarkDirty(-----S)
D [17/Feb/2011:19:58:39 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:19:58:39 +0900] Returning IPP successful-ok for
Cancel-Subscription (/) from localhost
D [17/Feb/2011:19:58:39 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:19:58:39 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:58:39 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:58:48 +0900] Report: clients=0
D [17/Feb/2011:19:58:48 +0900] Report: jobs=2
D [17/Feb/2011:19:58:48 +0900] Report: jobs-active=1
D [17/Feb/2011:19:58:48 +0900] Report: printers=1
D [17/Feb/2011:19:58:48 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:58:48 +0900] Report: stringpool-string-count=937
D [17/Feb/2011:19:58:48 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:58:48 +0900] Report: stringpool-total-bytes=21232
D [17/Feb/2011:19:59:00 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:19:59:00 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:19:59:00 +0900] cupsdSetBusyState: Active clients,
printing jobs, and dirty files
D [17/Feb/2011:19:59:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:59:00 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:19:59:00 +0900] Get-Notifications /
D [17/Feb/2011:19:59:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:19:59:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:19:59:00 +0900] cupsdSetBusyState: Printing jobs and
dirty files
D [17/Feb/2011:19:59:00 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:19:59:00 +0900] cupsdCloseClient: 11
I [17/Feb/2011:19:59:10 +0900] Saving subscriptions.conf...
D [17/Feb/2011:19:59:10 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:59:19 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:59:19 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:19:59:19 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:19:59:19 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:19:59:19 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:19:59:25 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:59:25 +0900] cupsdReadClient: 11
GET /admin/log/error_log HTTP/1.1
D [17/Feb/2011:19:59:25 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:59:25 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:59:25 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:59:25 +0900] cupsdReadClient: 11 GET /favicon.ico
HTTP/1.1
D [17/Feb/2011:19:59:25 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:59:25 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:59:25 +0900] cupsdReadClient: 11 Closing because
Keep-Alive disabled
D [17/Feb/2011:19:59:25 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:59:25 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:59:28 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:59:28 +0900] cupsdReadClient: 11 GET /favicon.ico
HTTP/1.1
D [17/Feb/2011:19:59:28 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:59:28 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:59:28 +0900] cupsdReadClient: 11 Closing because
Keep-Alive disabled
D [17/Feb/2011:19:59:28 +0900] cupsdCloseClient: 11
D [17/Feb/2011:19:59:28 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:59:35 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:19:59:35 +0900] cupsdReadClient: 11 GET /admin HTTP/1.1
D [17/Feb/2011:19:59:35 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:59:35 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:59:35 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/admin.cgi"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[18] = "LANG=ja.UTF8"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[20] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[21] = "SERVER_NAME=localhost"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[22] = "SERVER_PORT=631"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[25] = "SCRIPT_NAME=/admin"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[26] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[27] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[28] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[29] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[30] = "REQUEST_METHOD=GET"
D [17/Feb/2011:19:59:35 +0900] [CGI] envp[31] = "QUERY_STRING="
D [17/Feb/2011:19:59:35 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3718)
I [17/Feb/2011:19:59:35 +0900] Started
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=3718)
D [17/Feb/2011:19:59:35 +0900] cupsdSendCommand: 11 file=16
D [17/Feb/2011:19:59:35 +0900] [CGI] admin.cgi started...
D [17/Feb/2011:19:59:35 +0900] cupsdAcceptClient: 21 from localhost
(Domain)
D [17/Feb/2011:19:59:35 +0900] [CGI] http=0xb89c5df8
D [17/Feb/2011:19:59:35 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:19:59:35 +0900] [CGI] No form data, showing main menu...
D [17/Feb/2011:19:59:35 +0900]
[CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [17/Feb/2011:19:59:35 +0900] cupsdReadClient: 21 POST / HTTP/1.1
D [17/Feb/2011:19:59:35 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:59:35 +0900] cupsdReadClient: 21 1.1 Get-Subscriptions
1
D [17/Feb/2011:19:59:35 +0900] Get-Subscriptions ipp://localhost/
D [17/Feb/2011:19:59:35 +0900] Returning IPP successful-ok for
Get-Subscriptions (ipp://localhost/) from localhost
D [17/Feb/2011:19:59:35 +0900] Script header: Content-Type:
text/html;charset=utf-8
D [17/Feb/2011:19:59:35 +0900] Script header: 
D [17/Feb/2011:19:59:35 +0900] cupsdReadClient: 21 WAITING Closing on
EOF
D [17/Feb/2011:19:59:35 +0900] cupsdCloseClient: 21
D [17/Feb/2011:19:59:35 +0900] PID 3718
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [17/Feb/2011:19:59:35 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:59:39 +0900] cupsdReadClient: 11
GET /admin/log/access_log HTTP/1.1
D [17/Feb/2011:19:59:39 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:19:59:39 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:19:59:39 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:19:59:50 +0900] Report: clients=1
D [17/Feb/2011:19:59:50 +0900] Report: jobs=2
D [17/Feb/2011:19:59:50 +0900] Report: jobs-active=1
D [17/Feb/2011:19:59:50 +0900] Report: printers=1
D [17/Feb/2011:19:59:50 +0900] Report: printers-implicit=0
D [17/Feb/2011:19:59:50 +0900] Report: stringpool-string-count=937
D [17/Feb/2011:19:59:50 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:19:59:50 +0900] Report: stringpool-total-bytes=21232
D [17/Feb/2011:20:00:00 +0900] cupsdAcceptClient: 16 from localhost
(Domain)
D [17/Feb/2011:20:00:00 +0900] cupsdReadClient: 16 POST / HTTP/1.1
D [17/Feb/2011:20:00:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:20:00:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:20:00:00 +0900] cupsdReadClient: 16 1.1 Get-Notifications
1
D [17/Feb/2011:20:00:00 +0900] Get-Notifications /
D [17/Feb/2011:20:00:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:20:00:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:20:00:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:20:00:00 +0900] cupsdReadClient: 16 WAITING Closing on
EOF
D [17/Feb/2011:20:00:00 +0900] cupsdCloseClient: 16
D [17/Feb/2011:20:00:21 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:20:00:21 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:20:00:21 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:20:00:21 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:20:00:21 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:20:00:22 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:20:00:22 +0900] cupsdCloseClient: 11
D [17/Feb/2011:20:00:52 +0900] Report: clients=0
D [17/Feb/2011:20:00:52 +0900] Report: jobs=2
D [17/Feb/2011:20:00:52 +0900] Report: jobs-active=1
D [17/Feb/2011:20:00:52 +0900] Report: printers=1
D [17/Feb/2011:20:00:52 +0900] Report: printers-implicit=0
D [17/Feb/2011:20:00:52 +0900] Report: stringpool-string-count=937
D [17/Feb/2011:20:00:52 +0900] Report: stringpool-alloc-bytes=8808
D [17/Feb/2011:20:00:52 +0900] Report: stringpool-total-bytes=21232
D [17/Feb/2011:20:01:00 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:20:01:00 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:20:01:00 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:20:01:00 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:20:01:00 +0900] cupsdReadClient: 11 1.1 Get-Notifications
1
D [17/Feb/2011:20:01:00 +0900] Get-Notifications /
D [17/Feb/2011:20:01:00 +0900] cupsdIsAuthorized:
requesting-user-name="umiboz"
D [17/Feb/2011:20:01:00 +0900] Returning IPP successful-ok for
Get-Notifications (/) from localhost
D [17/Feb/2011:20:01:00 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:20:01:00 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:20:01:00 +0900] cupsdCloseClient: 11
D [17/Feb/2011:20:01:15 +0900] cupsdAcceptClient: 11 from localhost
(Domain)
D [17/Feb/2011:20:01:15 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:20:01:15 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:20:01:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:20:01:15 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Printers
1
D [17/Feb/2011:20:01:15 +0900] CUPS-Get-Printers
D [17/Feb/2011:20:01:15 +0900] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost
D [17/Feb/2011:20:01:15 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:20:01:15 +0900] cupsdReadClient: 11 POST / HTTP/1.1
D [17/Feb/2011:20:01:15 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:20:01:15 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:20:01:15 +0900] cupsdReadClient: 11 1.1 CUPS-Get-Classes
1
D [17/Feb/2011:20:01:15 +0900] CUPS-Get-Classes
D [17/Feb/2011:20:01:15 +0900] Returning IPP successful-ok for
CUPS-Get-Classes (no URI) from localhost
D [17/Feb/2011:20:01:15 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:20:01:15 +0900] cupsdReadClient: 11 WAITING Closing on
EOF
D [17/Feb/2011:20:01:15 +0900] cupsdCloseClient: 11
D [17/Feb/2011:20:01:23 +0900] cupsdAcceptClient: 11 from localhost:631
(IPv4)
D [17/Feb/2011:20:01:23 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:20:01:23 +0900] cupsdNetIFUpdate: "wlan0" =
192.168.1.6:631
D [17/Feb/2011:20:01:23 +0900] cupsdNetIFUpdate: "lo" = localhost:631
D [17/Feb/2011:20:01:23 +0900] cupsdNetIFUpdate: "wlan0" =
2408:86:455a:0:216:feff:fe07:9101:631
D [17/Feb/2011:20:01:23 +0900] cupsdNetIFUpdate: "wlan0" =
fe80::216:feff:fe07:9101%wlan0:631
D [17/Feb/2011:20:01:23 +0900] cupsdReadClient: 11 GET /admin HTTP/1.1
D [17/Feb/2011:20:01:23 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:20:01:23 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:20:01:23 +0900] [CGI] argv[0] =
"/usr/lib/cups/cgi-bin/admin.cgi"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[0] =
"CUPS_CACHEDIR=/var/cache/cups"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[1] =
"CUPS_DATADIR=/usr/share/cups"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[2] =
"CUPS_DOCROOT=/usr/share/cups/doc-root"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[3] =
"CUPS_FONTPATH=/usr/share/cups/fonts"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[4] =
"CUPS_REQUESTROOT=/var/spool/cups"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[5] =
"CUPS_SERVERBIN=/usr/lib/cups"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[6] =
"CUPS_SERVERROOT=/etc/cups"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[7] =
"CUPS_STATEDIR=/var/run/cups"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[8] =
"HOME=/var/spool/cups/tmp"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[9] =
"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[10] =
"SERVER_ADMIN=root@marine"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[11] = "SOFTWARE=CUPS/1.4.4"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[12] =
"TMPDIR=/var/spool/cups/tmp"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[13] = "TZ=Asia/Tokyo"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[14] = "USER=root"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[15] =
"CUPS_SERVER=/var/run/cups/cups.sock"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[16] =
"CUPS_ENCRYPTION=IfRequested"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[17] = "IPP_PORT=631"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[18] = "LANG=ja.UTF8"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[20] =
"GATEWAY_INTERFACE=CGI/1.1"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[21] = "SERVER_NAME=localhost"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[22] = "SERVER_PORT=631"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[23] = "REMOTE_ADDR=127.0.0.1"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[25] = "SCRIPT_NAME=/admin"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[26] =
"SCRIPT_FILENAME=/usr/share/cups/doc-root/admin"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[27] =
"SERVER_PROTOCOL=HTTP/1.1"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[28] =
"HTTP_COOKIE=org.cups.sid=9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[29] =
"HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; ja; rv:1.9.1.16)
Gecko/20110107 Iceweasel/3.5.16 (like Firefox/3.5.16)"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[30] =
"HTTP_REFERER=http://localhost:631/";
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[31] = "REQUEST_METHOD=GET"
D [17/Feb/2011:20:01:23 +0900] [CGI] envp[32] = "QUERY_STRING="
D [17/Feb/2011:20:01:23 +0900] [CGI]
Started /usr/lib/cups/cgi-bin/admin.cgi (PID 3727)
I [17/Feb/2011:20:01:23 +0900] Started
"/usr/lib/cups/cgi-bin/admin.cgi" (pid=3727)
D [17/Feb/2011:20:01:23 +0900] cupsdSendCommand: 11 file=16
D [17/Feb/2011:20:01:23 +0900] [CGI] admin.cgi started...
D [17/Feb/2011:20:01:23 +0900] cupsdAcceptClient: 21 from localhost
(Domain)
D [17/Feb/2011:20:01:23 +0900] [CGI] http=0xb949adf8
D [17/Feb/2011:20:01:23 +0900] [CGI] org.cups.sid cookie is
"9db6c2d3b034bd8698830b3edc6e7611"
D [17/Feb/2011:20:01:23 +0900] [CGI] No form data, showing main menu...
D [17/Feb/2011:20:01:23 +0900]
[CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [17/Feb/2011:20:01:23 +0900] cupsdReadClient: 21 POST / HTTP/1.1
D [17/Feb/2011:20:01:23 +0900] cupsdAuthorize: No authentication data
provided.
D [17/Feb/2011:20:01:23 +0900] cupsdReadClient: 21 1.1 Get-Subscriptions
1
D [17/Feb/2011:20:01:23 +0900] Get-Subscriptions ipp://localhost/
D [17/Feb/2011:20:01:23 +0900] Returning IPP successful-ok for
Get-Subscriptions (ipp://localhost/) from localhost
D [17/Feb/2011:20:01:23 +0900] Script header: Content-Type:
text/html;charset=utf-8
D [17/Feb/2011:20:01:23 +0900] Script header: 
D [17/Feb/2011:20:01:23 +0900] cupsdReadClient: 21 WAITING Closing on
EOF
D [17/Feb/2011:20:01:23 +0900] cupsdCloseClient: 21
D [17/Feb/2011:20:01:23 +0900] PID 3727
(/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [17/Feb/2011:20:01:23 +0900] cupsdSetBusyState: Printing jobs
D [17/Feb/2011:20:01:25 +0900] cupsdReadClient: 11
GET /admin/log/error_log HTTP/1.1
D [17/Feb/2011:20:01:25 +0900] cupsdSetBusyState: Active clients and
printing jobs
D [17/Feb/2011:20:01:25 +0900] cupsdAuthorize: No authentication data
provided.

----

Masuyama