[Pkg-cups-devel] Bug#593338: cups: REGRESSION (1.3.8 -> 1.4.4): pstops produces postscript that hangs Ricoh Aficio 3035

[Timo Juhani Lindfors]

Hi,

here's the debug output ask suggested by upstream. I have no idea why
cups thinks the input needs to be converted from PS to PDF and then
back to PS.


I [06/Sep/2010:15:35:34 +0300] Listening to 0.0.0.0:631 (IPv4)
I [06/Sep/2010:15:35:34 +0300] Listening to :::631 (IPv6)
I [06/Sep/2010:15:35:34 +0300] Listening to /var/run/cups/cups.sock (Domain)
I [06/Sep/2010:15:35:34 +0300] Remote access is enabled.
D [06/Sep/2010:15:35:34 +0300] Added auto ServerAlias infra
I [06/Sep/2010:15:35:34 +0300] Loaded configuration file "/etc/cups/cupsd.conf"
I [06/Sep/2010:15:35:34 +0300] Using default TempDir of /var/spool/cups/tmp...
I [06/Sep/2010:15:35:34 +0300] Configured for up to 100 clients.
I [06/Sep/2010:15:35:34 +0300] Allowing up to 100 client connections per host.
I [06/Sep/2010:15:35:34 +0300] Using policy "default" as the default!
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/farkku.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3113ec30(farkku))
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/kunkku.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3125cd00(kunkku))
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/kunkku-broken.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee314e0c60(kunkku-broken))
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/kunkku_raw.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3124f730(kunkku_raw))
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/kyykky.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee31260ab0(kyykky))
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/narkku.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee31138830(narkku))
D [06/Sep/2010:15:35:34 +0300] cupsdMarkDirty(P-----)
D [06/Sep/2010:15:35:34 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /etc/cups/ppd/ryykky.ppd...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3136fb90(ryykky))
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/virkku.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee31370810(virkku))
D [06/Sep/2010:15:35:34 +0300] load_ppd: Loading /var/cache/cups/virkku-old.ipp2...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee311cc150(virkku-old))
D [06/Sep/2010:15:35:34 +0300] cupsdMarkDirty(---p--)
I [06/Sep/2010:15:35:34 +0300] Partial reload complete.
I [06/Sep/2010:15:35:34 +0300] Listening to 0.0.0.0:631 on fd 5...
I [06/Sep/2010:15:35:34 +0300] Listening to :::631 on fd 7...
I [06/Sep/2010:15:35:34 +0300] Listening to /var/run/cups/cups.sock on fd 8...
I [06/Sep/2010:15:35:34 +0300] Resuming new connection processing...
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3113ec30(farkku))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3125cd00(kunkku))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee314e0c60(kunkku-broken))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3124f730(kunkku_raw))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee31260ab0(kyykky))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee31138830(narkku))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee3136fb90(ryykky))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee31370810(virkku))
D [06/Sep/2010:15:35:34 +0300] cupsdRegisterPrinter(p=0x7fee311cc150(virkku-old))
D [06/Sep/2010:15:35:34 +0300] Discarding unused server-restarted event...
D [06/Sep/2010:15:35:35 +0300] Report: clients=0
D [06/Sep/2010:15:35:35 +0300] Report: jobs=499
D [06/Sep/2010:15:35:35 +0300] Report: jobs-active=1
D [06/Sep/2010:15:35:35 +0300] Report: printers=9
D [06/Sep/2010:15:35:35 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:35:35 +0300] Report: stringpool-string-count=278801
D [06/Sep/2010:15:35:35 +0300] Report: stringpool-alloc-bytes=25232
D [06/Sep/2010:15:35:35 +0300] Report: stringpool-total-bytes=4710920
D [06/Sep/2010:15:35:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:35:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:35:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:35:52 +0300] cupsdAcceptClient: 14 from localhost (Domain)
D [06/Sep/2010:15:35:52 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:52 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:52 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:52 +0300] cupsdReadClient: 14 1.1 Get-Jobs 1
D [06/Sep/2010:15:35:52 +0300] Get-Jobs ipp://localhost/
D [06/Sep/2010:15:35:52 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [06/Sep/2010:15:35:52 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:52 +0300] cupsdReadClient: 14 WAITING Closing on EOF
D [06/Sep/2010:15:35:52 +0300] cupsdCloseClient: 14
D [06/Sep/2010:15:35:55 +0300] cupsdAcceptClient: 14 from localhost (Domain)
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:35:55 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [06/Sep/2010:15:35:55 +0300] CUPS-Get-Default
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAcceptClient: 15 from localhost (Domain)
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 15 WAITING Closing on EOF
D [06/Sep/2010:15:35:55 +0300] cupsdCloseClient: 15
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 CUPS-Get-Classes 1
D [06/Sep/2010:15:35:55 +0300] CUPS-Get-Classes
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:35:55 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:35:55 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:35:55 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 Get-Jobs 1
D [06/Sep/2010:15:35:55 +0300] Get-Jobs ipp://localhost/printers/kyykky
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/kyykky) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 1.1 Get-Jobs 1
D [06/Sep/2010:15:35:55 +0300] Get-Jobs ipp://localhost/
D [06/Sep/2010:15:35:55 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [06/Sep/2010:15:35:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:35:55 +0300] cupsdReadClient: 14 WAITING Closing on EOF
D [06/Sep/2010:15:35:55 +0300] cupsdCloseClient: 14
D [06/Sep/2010:15:36:03 +0300] cupsdAcceptClient: 14 from localhost (Domain)
D [06/Sep/2010:15:36:03 +0300] cupsdReadClient: 14 POST / HTTP/1.1
D [06/Sep/2010:15:36:03 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:03 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:03 +0300] cupsdReadClient: 14 1.1 Get-Printer-Attributes 1
D [06/Sep/2010:15:36:03 +0300] Get-Printer-Attributes ipp://localhost:631/printers/kunkku-broken
D [06/Sep/2010:15:36:03 +0300] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/kunkku-broken) from localhost
D [06/Sep/2010:15:36:03 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:03 +0300] cupsdReadClient: 14 POST /printers/kunkku-broken HTTP/1.1
D [06/Sep/2010:15:36:03 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:03 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:03 +0300] cupsdReadClient: 14 1.1 Create-Job 1
D [06/Sep/2010:15:36:03 +0300] Create-Job ipp://localhost:631/printers/kunkku-broken
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(----J-)
D [06/Sep/2010:15:36:03 +0300] add_job: requesting-user-name="lindi"
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Adding start banner page "none".
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Queued on "kunkku-broken" by "lindi".
D [06/Sep/2010:15:36:03 +0300] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/kunkku-broken) from localhost
D [06/Sep/2010:15:36:03 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:03 +0300] cupsdReadClient: 14 POST /printers/kunkku-broken HTTP/1.1
D [06/Sep/2010:15:36:03 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:03 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:03 +0300] cupsdReadClient: 14 1.1 Send-Document 1
D [06/Sep/2010:15:36:03 +0300] Send-Document ipp://localhost:631/printers/kunkku-broken
D [06/Sep/2010:15:36:03 +0300] cupsdIsAuthorized: requesting-user-name="lindi"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Auto-typing file...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Request file type is application/postscript.
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(----J-)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] File of type application/postscript queued by "lindi".
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Adding end banner page "none".
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(----J-)
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(----J-)
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] job-sheets=none,none
D [06/Sep/2010:15:36:03 +0300] [Job 9607] argv[0]="kunkku-broken"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] argv[1]="9607"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] argv[2]="lindi"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] argv[3]="testcase1.ps"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] argv[4]="1"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:d4fb0383-575b-36f2-5be8-2af505b745ad job-originating-host-name=localhost"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] argv[6]="/var/spool/cups/d09607-001"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[8]="HOME=/var/spool/cups/tmp"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[10]="SERVER_ADMIN=root at infra"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[11]="SOFTWARE=CUPS/1.4.4"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[13]="TZ=Europe/Helsinki"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[14]="USER=root"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[17]="IPP_PORT=631"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[18]="CHARSET=utf-8"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[19]="LANG=en_US.UTF-8"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[20]="PPD=/etc/cups/ppd/kunkku-broken.ppd"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[21]="RIP_MAX_CACHE=1015567k"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[22]="CONTENT_TYPE=application/postscript"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[23]="DEVICE_URI=socket://kunkku:9100"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[24]="PRINTER_INFO=Do not use"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[25]="PRINTER_LOCATION="
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[26]="PRINTER=kunkku-broken"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[27]="CUPS_FILETYPE=document"
D [06/Sep/2010:15:36:03 +0300] [Job 9607] envp[28]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Started filter /usr/lib/cups/filter/pstopdf (PID 23763)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Started filter /usr/lib/cups/filter/pdftopdf (PID 23764)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Started filter /usr/lib/cups/filter/cpdftocps (PID 23765)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Started backend /usr/lib/cups/backend/socket (PID 23766)
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/kunkku-broken) from localhost
D [06/Sep/2010:15:36:03 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:03 +0300] cupsdReadClient: 14 WAITING Closing on EOF
D [06/Sep/2010:15:36:03 +0300] cupsdCloseClient: 14
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: +connecting-to-device
D [06/Sep/2010:15:36:03 +0300] [Job 9607] pstopdf 6 args: 9607 lindi testcase1.ps 1 finishings=3 number-up=1 job-uuid=urn:uuid:d4fb0383-575b-36f2-5be8-2af505b745ad job-originating-host-name=localhost /var/spool/cups/d09607-001
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PPD: /etc/cups/ppd/kunkku-broken.ppd
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Looking up "kunkku"...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Connecting to kunkku:9100
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Connecting to printer...
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -connecting-to-device
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Connected to printer...
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Connected to 10.7.7.1:9100 (IPv4)...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] pdftops argv[5] = 9607 lindi testcase1.ps 1 finishings=3 number-up=1 job-uuid=urn:uuid:d4fb0383-575b-36f2-5be8-2af505b745ad job-originating-host-name=localhost
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PPD: /etc/cups/ppd/kunkku-broken.ppd
D [06/Sep/2010:15:36:03 +0300] [Job 9607] /usr/bin/pdftops supports '-origpagesizes': yes
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PostScript Level: 3
D [06/Sep/2010:15:36:03 +0300] [Job 9607] ATTR: marker-colors=#000000,#000000
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(P-----)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] ATTR: marker-names="VÀri","HukkavÀri"
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(P-----)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] ATTR: marker-types=toner,wasteToner
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(P-----)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] ATTR: marker-levels=-1,-1
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(P-----)
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -media-low-report
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -media-empty-warning
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -toner-low-report
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -toner-empty-warning
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -door-open-report
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -media-jam-warning
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -input-tray-missing-warning
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -output-tray-missing-warning
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -marker-supply-missing-warning
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -output-area-almost-full-report
D [06/Sep/2010:15:36:03 +0300] [Job 9607] STATE: -output-area-full-warning
D [06/Sep/2010:15:36:03 +0300] [Job 9607] backendRunLoop(print_fd=0, device_fd=5, snmp_fd=6, addr=0x7fa46482c4d8, use_bc=1, side_cb=0x7fa462bd7ed0)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Resolution: 600
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Duplex: no
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Page size: A4
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Resolution: 600
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Page size: A4
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Width: 595, height: 842, absolute margins: 12, 12, 583, 830
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Relative margins: 12, 12, 12, 12
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PPD options: -r600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PostScript to be injected: 
D [06/Sep/2010:15:36:03 +0300] [Job 9607] 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 -r600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -sOutputFile=-  -c .setpdfwrite -f -
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Width: 595, height: 842, absolute margins: 12, 12, 583, 830
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Relative margins: 12, 12, 12, 12
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PPD options: -level3 -origpagesizes
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PostScript to be injected: 
D [06/Sep/2010:15:36:03 +0300] PID 23763 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [06/Sep/2010:15:36:03 +0300] PID 23764 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Device copies: 1; device collate: 
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Running /usr/bin/pdftops  -level3 -origpagesizes /var/spool/cups/tmp/pdftops.oM3HqA -
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Running pstops '9607' 'lindi' 'testcase1.ps' '1' ' finishings=3 job-uuid=urn:uuid:d4fb0383-575b-36f2-5be8-2af505b745ad job-originating-host-name=localhost'
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Page = 595x842; 12,12 to 583,830
D [06/Sep/2010:15:36:03 +0300] [Job 9607] slow_collate=0, slow_duplex=0, slow_order=0
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Before copy_comments - %!PS-Adobe-3.0
D [06/Sep/2010:15:36:03 +0300] [Job 9607] %!PS-Adobe-3.0
D [06/Sep/2010:15:36:03 +0300] [Job 9607] %%LanguageLevel: 3
D [06/Sep/2010:15:36:03 +0300] [Job 9607] %%DocumentSuppliedResources: (atend)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] %%DocumentMedia: plain 595 842 0 () ()
D [06/Sep/2010:15:36:03 +0300] [Job 9607] %%BoundingBox: 0 0 595 842
D [06/Sep/2010:15:36:03 +0300] [Job 9607] %%Pages: 1
D [06/Sep/2010:15:36:03 +0300] [Job 9607] %%EndComments
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Before copy_prolog - %%BeginDefaults
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 4096 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Before copy_setup - %%BeginSetup
D [06/Sep/2010:15:36:03 +0300] [Job 9607] ATTR: marker-levels=-1,-1
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(P-----)
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Before page loop - %%Page: 1 1
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Copying page 1...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PAGE: 1 1
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] [Job 9607] pagew = 571.0, pagel = 818.0
D [06/Sep/2010:15:36:03 +0300] [Job 9607] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PageLeft = 12.0, PageRight = 583.0
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PageTop = 830.0, PageBottom = 12.0
D [06/Sep/2010:15:36:03 +0300] [Job 9607] PageWidth = 595.0, PageLength = 842.0
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 4096 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 1 pages...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 8192 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Read 4360 bytes of print data...
D [06/Sep/2010:15:36:03 +0300] [Job 9607] Wrote 4360 bytes of print data...
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Print file sent, waiting for printer to finish...
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] PID 23765 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
D [06/Sep/2010:15:36:03 +0300] [Job 9607] ATTR: marker-levels=-1,-1
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(P-----)
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Ready to print.
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:03 +0300] PID 23766 (/usr/lib/cups/backend/socket) exited with no errors.
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:36:03 +0300] [Job 9607] Job completed.
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(----J-)
D [06/Sep/2010:15:36:03 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:36:04 +0300] Saving printers.conf...
I [06/Sep/2010:15:36:04 +0300] Generating printcap /var/run/cups/printcap...
I [06/Sep/2010:15:36:04 +0300] Saving job cache file "/var/cache/cups/job.cache"...
I [06/Sep/2010:15:36:04 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:36:04 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:36:04 +0300] [Job 9607] Unloading...
D [06/Sep/2010:15:36:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:36:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:36:15 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:15 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:25 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:36:25 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:36:25 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:36:25 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:36:25 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:36:35 +0300] Report: clients=0
D [06/Sep/2010:15:36:35 +0300] Report: jobs=499
D [06/Sep/2010:15:36:35 +0300] Report: jobs-active=1
D [06/Sep/2010:15:36:35 +0300] Report: printers=9
D [06/Sep/2010:15:36:35 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:36:35 +0300] Report: stringpool-string-count=278858
D [06/Sep/2010:15:36:35 +0300] Report: stringpool-alloc-bytes=25712
D [06/Sep/2010:15:36:35 +0300] Report: stringpool-total-bytes=4711856
I [06/Sep/2010:15:36:45 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:36:45 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:36:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:36:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:36:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:36:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:54 +0300] cupsdAcceptClient: 16 from 10.7.6.28:631 (IPv4)
D [06/Sep/2010:15:36:54 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:54 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:54 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:54 +0300] cupsdReadClient: 16 1.1 Get-Jobs 1
D [06/Sep/2010:15:36:54 +0300] Get-Jobs ipp://localhost/
D [06/Sep/2010:15:36:54 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from 10.7.6.28
D [06/Sep/2010:15:36:54 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:54 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:36:54 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:36:55 +0300] cupsdAcceptClient: 16 from 10.7.6.28:631 (IPv4)
D [06/Sep/2010:15:36:55 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:55 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:36:55 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:36:55 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:55 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:55 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [06/Sep/2010:15:36:55 +0300] CUPS-Get-Default
D [06/Sep/2010:15:36:55 +0300] Returning IPP successful-ok for CUPS-Get-Default (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:55 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:55 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:55 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:55 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:36:55 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:36:55 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:55 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:55 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:36:55 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:36:56 +0300] cupsdAcceptClient: 16 from 10.7.6.28:631 (IPv4)
D [06/Sep/2010:15:36:56 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:56 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:56 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:56 +0300] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
D [06/Sep/2010:15:36:56 +0300] Get-Printer-Attributes ipp://localhost:631/printers/t
D [06/Sep/2010:15:36:56 +0300] Get-Printer-Attributes client-error-not-found: The printer or class was not found.
D [06/Sep/2010:15:36:56 +0300] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost:631/printers/t) from 10.7.6.28
D [06/Sep/2010:15:36:56 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:56 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:36:56 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:36:58 +0300] cupsdAcceptClient: 16 from 10.7.6.28:631 (IPv4)
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:36:58 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [06/Sep/2010:15:36:58 +0300] CUPS-Get-Default
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for CUPS-Get-Default (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAcceptClient: 17 from 10.7.6.28:631 (IPv4)
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 17 WAITING Closing on EOF
D [06/Sep/2010:15:36:58 +0300] cupsdCloseClient: 17
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [06/Sep/2010:15:36:58 +0300] CUPS-Get-Classes
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:36:58 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:36:58 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:36:58 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 Get-Jobs 1
D [06/Sep/2010:15:36:58 +0300] Get-Jobs ipp://localhost/printers/kyykky
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/kyykky) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 1.1 Get-Jobs 1
D [06/Sep/2010:15:36:58 +0300] Get-Jobs ipp://localhost/
D [06/Sep/2010:15:36:58 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from 10.7.6.28
D [06/Sep/2010:15:36:58 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:36:58 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:36:58 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:37:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:37:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:37:15 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:37:15 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:37:15 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:37:16 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:37:16 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:37:16 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:37:16 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:37:16 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:37:37 +0300] Report: clients=0
D [06/Sep/2010:15:37:37 +0300] Report: jobs=499
D [06/Sep/2010:15:37:37 +0300] Report: jobs-active=1
D [06/Sep/2010:15:37:37 +0300] Report: printers=9
D [06/Sep/2010:15:37:37 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:37:37 +0300] Report: stringpool-string-count=278900
D [06/Sep/2010:15:37:37 +0300] Report: stringpool-alloc-bytes=25760
D [06/Sep/2010:15:37:37 +0300] Report: stringpool-total-bytes=4712368
D [06/Sep/2010:15:37:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:37:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:37:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:37:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:38:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:38:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:38:15 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:38:15 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:38:15 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:38:18 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:38:18 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:38:18 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:38:18 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:38:18 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:38:23 +0300] cupsdAcceptClient: 16 from 10.7.2.3:631 (IPv4)
D [06/Sep/2010:15:38:23 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:38:23 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:38:23 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:38:23 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:38:23 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:38:23 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.2.3
D [06/Sep/2010:15:38:23 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:38:23 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:38:23 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:38:23 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:38:23 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [06/Sep/2010:15:38:23 +0300] CUPS-Get-Classes
D [06/Sep/2010:15:38:23 +0300] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from 10.7.2.3
D [06/Sep/2010:15:38:23 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:38:23 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:38:23 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:38:39 +0300] Report: clients=0
D [06/Sep/2010:15:38:39 +0300] Report: jobs=499
D [06/Sep/2010:15:38:39 +0300] Report: jobs-active=1
D [06/Sep/2010:15:38:39 +0300] Report: printers=9
D [06/Sep/2010:15:38:39 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:38:39 +0300] Report: stringpool-string-count=278961
D [06/Sep/2010:15:38:39 +0300] Report: stringpool-alloc-bytes=25808
D [06/Sep/2010:15:38:39 +0300] Report: stringpool-total-bytes=4713504
D [06/Sep/2010:15:38:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:38:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:38:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:38:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:39:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:39:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:39:15 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:39:15 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:39:15 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:39:20 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:39:20 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:39:20 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:39:20 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:39:20 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:39:41 +0300] Report: clients=0
D [06/Sep/2010:15:39:41 +0300] Report: jobs=499
D [06/Sep/2010:15:39:41 +0300] Report: jobs-active=1
D [06/Sep/2010:15:39:41 +0300] Report: printers=9
D [06/Sep/2010:15:39:41 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:39:41 +0300] Report: stringpool-string-count=278970
D [06/Sep/2010:15:39:41 +0300] Report: stringpool-alloc-bytes=25808
D [06/Sep/2010:15:39:41 +0300] Report: stringpool-total-bytes=4713704
D [06/Sep/2010:15:39:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:39:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:39:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:39:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 POST /admin/ HTTP/1.1
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 1.1 Resume-Printer 1
D [06/Sep/2010:15:40:01 +0300] Resume-Printer ipp://localhost/printers/kunkku
D [06/Sep/2010:15:40:01 +0300] cupsdIsAuthorized: username=""
D [06/Sep/2010:15:40:01 +0300] Returning HTTP Unauthorized for Resume-Printer (ipp://localhost/printers/kunkku) from localhost
D [06/Sep/2010:15:40:01 +0300] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:40:01 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:40:01 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:40:01 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 POST /admin/ HTTP/1.1
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdAuthorize: Authorized as root using PeerCred
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 1.1 Resume-Printer 1
D [06/Sep/2010:15:40:01 +0300] Resume-Printer ipp://localhost/printers/kunkku
D [06/Sep/2010:15:40:01 +0300] cupsdIsAuthorized: username="root"
D [06/Sep/2010:15:40:01 +0300] cupsdRegisterPrinter(p=0x7fee3125cd00(kunkku))
I [06/Sep/2010:15:40:01 +0300] Printer "kunkku" started by "root".
D [06/Sep/2010:15:40:01 +0300] Returning IPP successful-ok for Resume-Printer (ipp://localhost/printers/kunkku) from localhost
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:40:01 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:40:01 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 POST /admin/ HTTP/1.1
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 1.1 Resume-Printer 1
D [06/Sep/2010:15:40:01 +0300] Resume-Printer ipp://localhost/printers/virkku
D [06/Sep/2010:15:40:01 +0300] cupsdIsAuthorized: username=""
D [06/Sep/2010:15:40:01 +0300] Returning HTTP Unauthorized for Resume-Printer (ipp://localhost/printers/virkku) from localhost
D [06/Sep/2010:15:40:01 +0300] cupsdSendHeader: 16 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:40:01 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:40:01 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:40:01 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 POST /admin/ HTTP/1.1
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdAuthorize: Authorized as root using PeerCred
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 1.1 Resume-Printer 1
D [06/Sep/2010:15:40:01 +0300] Resume-Printer ipp://localhost/printers/virkku
D [06/Sep/2010:15:40:01 +0300] cupsdIsAuthorized: username="root"
D [06/Sep/2010:15:40:01 +0300] cupsdRegisterPrinter(p=0x7fee31370810(virkku))
I [06/Sep/2010:15:40:01 +0300] Printer "virkku" started by "root".
D [06/Sep/2010:15:40:01 +0300] Returning IPP successful-ok for Resume-Printer (ipp://localhost/printers/virkku) from localhost
D [06/Sep/2010:15:40:01 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:40:01 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:40:01 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:40:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:40:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:40:15 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:40:15 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:40:15 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:40:22 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:40:22 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:40:22 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:40:22 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:40:22 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:40:43 +0300] Report: clients=0
D [06/Sep/2010:15:40:43 +0300] Report: jobs=499
D [06/Sep/2010:15:40:43 +0300] Report: jobs-active=1
D [06/Sep/2010:15:40:43 +0300] Report: printers=9
D [06/Sep/2010:15:40:43 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:40:43 +0300] Report: stringpool-string-count=278979
D [06/Sep/2010:15:40:43 +0300] Report: stringpool-alloc-bytes=25808
D [06/Sep/2010:15:40:43 +0300] Report: stringpool-total-bytes=4713904
D [06/Sep/2010:15:40:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:40:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:40:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:40:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:41:07 +0300] cupsdAcceptClient: 16 from 10.7.2.3:631 (IPv4)
D [06/Sep/2010:15:41:07 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:07 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:41:07 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:07 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:41:07 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:41:07 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.2.3
D [06/Sep/2010:15:41:07 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:41:07 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:07 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:41:07 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:07 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [06/Sep/2010:15:41:07 +0300] CUPS-Get-Classes
D [06/Sep/2010:15:41:07 +0300] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from 10.7.2.3
D [06/Sep/2010:15:41:07 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:41:07 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:41:07 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:41:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:41:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:41:15 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:41:15 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:41:15 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:15 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:41:15 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:41:15 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:41:15 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:41:15 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:41:33 +0300] cupsdAcceptClient: 16 from localhost (Domain)
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:41:33 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [06/Sep/2010:15:41:33 +0300] CUPS-Get-Default
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAcceptClient: 17 from localhost (Domain)
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 17 WAITING Closing on EOF
D [06/Sep/2010:15:41:33 +0300] cupsdCloseClient: 17
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [06/Sep/2010:15:41:33 +0300] CUPS-Get-Classes
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:41:33 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:41:33 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:41:33 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 Get-Jobs 1
D [06/Sep/2010:15:41:33 +0300] Get-Jobs ipp://localhost/printers/kyykky
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/kyykky) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 1.1 Get-Jobs 1
D [06/Sep/2010:15:41:33 +0300] Get-Jobs ipp://localhost/
D [06/Sep/2010:15:41:33 +0300] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [06/Sep/2010:15:41:33 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:33 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:41:33 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:41:43 +0300] cupsdAcceptClient: 16 from 10.7.2.3:631 (IPv4)
D [06/Sep/2010:15:41:43 +0300] Report: clients=1
D [06/Sep/2010:15:41:43 +0300] Report: jobs=499
D [06/Sep/2010:15:41:43 +0300] Report: jobs-active=1
D [06/Sep/2010:15:41:43 +0300] Report: printers=9
D [06/Sep/2010:15:41:43 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:41:43 +0300] Report: stringpool-string-count=279056
D [06/Sep/2010:15:41:43 +0300] Report: stringpool-alloc-bytes=25904
D [06/Sep/2010:15:41:43 +0300] Report: stringpool-total-bytes=4715200
D [06/Sep/2010:15:41:43 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:43 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:43 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:43 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:41:43 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:41:43 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.2.3
D [06/Sep/2010:15:41:43 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:43 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:41:43 +0300] cupsdSetBusyState: Active clients and printing jobs
D [06/Sep/2010:15:41:43 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:41:43 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [06/Sep/2010:15:41:43 +0300] CUPS-Get-Classes
D [06/Sep/2010:15:41:43 +0300] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from 10.7.2.3
D [06/Sep/2010:15:41:43 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:41:43 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:41:43 +0300] cupsdCloseClient: 16
D [06/Sep/2010:15:41:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:41:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:41:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:41:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:42:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:42:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:42:15 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:42:15 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:42:15 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:42:16 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:42:16 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:42:16 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:42:16 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:42:16 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:42:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:42:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:42:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:42:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:42:45 +0300] Report: clients=0
D [06/Sep/2010:15:42:45 +0300] Report: jobs=499
D [06/Sep/2010:15:42:45 +0300] Report: jobs-active=1
D [06/Sep/2010:15:42:45 +0300] Report: printers=9
D [06/Sep/2010:15:42:45 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:42:45 +0300] Report: stringpool-string-count=279125
D [06/Sep/2010:15:42:45 +0300] Report: stringpool-alloc-bytes=25952
D [06/Sep/2010:15:42:45 +0300] Report: stringpool-total-bytes=4716520
D [06/Sep/2010:15:43:15 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:43:15 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:43:15 +0300] cupsdMarkDirty(-----S)
I [06/Sep/2010:15:43:15 +0300] Saving subscriptions.conf...
D [06/Sep/2010:15:43:15 +0300] cupsdSetBusyState: Printing jobs
D [06/Sep/2010:15:43:18 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:43:18 +0300] cupsdNetIFUpdate: "eth0" = 10.7.2.8:631
D [06/Sep/2010:15:43:18 +0300] cupsdNetIFUpdate: "eth0:1" = 10.7.2.2:631
D [06/Sep/2010:15:43:18 +0300] cupsdNetIFUpdate: "lo" = localhost:631
D [06/Sep/2010:15:43:18 +0300] cupsdNetIFUpdate: "eth0" = fe80::250:8dff:fed7:2e83%eth0:631
D [06/Sep/2010:15:43:45 +0300] [Job 9484] Connecting to multics.lan:631
I [06/Sep/2010:15:43:45 +0300] [Job 9484] Connecting to printer...
D [06/Sep/2010:15:43:45 +0300] cupsdMarkDirty(-----S)
D [06/Sep/2010:15:43:45 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:43:45 +0300] Report: clients=0
D [06/Sep/2010:15:43:45 +0300] Report: jobs=499
D [06/Sep/2010:15:43:45 +0300] Report: jobs-active=1
D [06/Sep/2010:15:43:45 +0300] Report: printers=9
D [06/Sep/2010:15:43:45 +0300] Report: printers-implicit=0
D [06/Sep/2010:15:43:45 +0300] Report: stringpool-string-count=279134
D [06/Sep/2010:15:43:45 +0300] Report: stringpool-alloc-bytes=25952
D [06/Sep/2010:15:43:45 +0300] Report: stringpool-total-bytes=4716720
D [06/Sep/2010:15:44:03 +0300] cupsdAcceptClient: 16 from 10.7.2.3:631 (IPv4)
D [06/Sep/2010:15:44:03 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:44:03 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:44:03 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:44:03 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [06/Sep/2010:15:44:03 +0300] CUPS-Get-Printers
D [06/Sep/2010:15:44:03 +0300] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from 10.7.2.3
D [06/Sep/2010:15:44:03 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:44:03 +0300] cupsdReadClient: 16 POST / HTTP/1.1
D [06/Sep/2010:15:44:03 +0300] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [06/Sep/2010:15:44:03 +0300] cupsdAuthorize: No authentication data provided.
D [06/Sep/2010:15:44:03 +0300] cupsdReadClient: 16 1.1 CUPS-Get-Classes 1
D [06/Sep/2010:15:44:03 +0300] CUPS-Get-Classes
D [06/Sep/2010:15:44:03 +0300] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from 10.7.2.3
D [06/Sep/2010:15:44:03 +0300] cupsdSetBusyState: Printing jobs and dirty files
D [06/Sep/2010:15:44:03 +0300] cupsdReadClient: 16 WAITING Closing on EOF
D [06/Sep/2010:15:44:03 +0300] cupsdCloseClient: 16