cups reagiert extram langsam, Druck nicht möglich

[Raphalon]

Hallo,

habe 11.3 installiert und wollte jetzt auf einem Epson Stylus SX510W drucken. Den Treiber habe ich von http://avasys.jp/eng/linux_driver/download/lsb/epson-inkjet/escp/ für das Modell passend geladen und installiert.

Probleme:
1.) cups (http://localhost:631/) reagiert extreeeem langsam. Es dauert mitunter bis zu einer Minute, bis die Seite reagiert. Habe die Pakete mal aktualisiert, einen Reboot gemacht, aber es hilft nichts. Woran kann das liegen?
2.) den Drucker konnte ich mit dem dann passenden Treiber einrichten, doch kann ich nicht drucken. Das Fehlerlog (/var/log/cups/error_log) zeigt folgendes:

• E [20/Nov/2010:11:59:05 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/drv/sample.drv"!
  E [20/Nov/2010:11:59:05 +0100] [cups-driverd] Skipping "/usr/share/ppd": loop detected!
  E [20/Nov/2010:11:59:52 +0100] [Job 2] Job stopped due to filter errors; please consult the error_log file for details.

=> Sieht das nach einem Treiber-Problem aus?

Auf der Browser-Admin-Seite (http://localhost:631/jobs/) wird folgendes angezeigt:

• gestoppt
  "/opt/epson-inkjet-printer-stylus-tx550w-series/cups/lib/filter/epson_inkjet_printer_filter failed"

Diese Datei (/opt/epson-inkjet-printer-stylus-tx550w-series/cups/lib/filter/epson_inkjet_printer_filter) liegt im Binärformat vor.

Am Drucker kann es nicht liegen. Auf einem anderen Rechner unter Suse 11.2 gibt es keinerlei Probleme mit demselben Treiber.

Hier noch meine /etc/cups/printers.conf

# Printer configuration file for CUPS v1.4.4
# Written by cupsd on 2010-11-20 12:00
# DO NOT EDIT THIS FILE WHEN CUPSD IS RUNNING
<Printer Epson_Stylus_SX510W>
Info Epson Stylus SX510W
Location 
MakeModel Epson Stylus SX510W Series - epson-inkjet-printer 1.0.0-1lsb3.2 (Seiko Epson Corporation LSB 3.2)
DeviceURI usb://EPSON/Stylus%20SX510W
State Idle
StateTime 1290250792
Type 45068
Filter application/vnd.cups-raw 0 -
Filter application/vnd.cups-raster 0 /opt/epson-inkjet-printer-stylus-tx550w-series/cups/lib/filter/epson_inkjet_printer_filter
Accepting Yes
Shared Yes
JobSheets none none
QuotaPeriod 0
PageLimit 0
KLimit 0
OpPolicy default
ErrorPolicy stop-printer
</Printer>

Gruß,

Raphalon

 

[tomm.fa]

Nicht das ich mich damit wirklich auskennen würde (auch wenn es für mich nach einem Problem mit dem Treiber aussieht), aber:

E [20/Nov/2010:11:59:52 +0100] [Job 2] Job stopped due to filter errors; please consult the error_log file for details.

Wie sieht es denn dann unter http://localhost:631/admin/log/error_log? aus?

 

[Raphalon]

Das waren genau die Fehlermeldungen, die ich im ersten Beitrag eingefügt habe (die WebGui greift hier nur auf /var/log/cups/error_log zu und stellt es im Browser dar). Ich hänge mal aber die für diesen Druckversuch gesamten Einträge bei (mittels tail -f sichergestellt, daß sie auch zu diesem einen Druckauftrag gehören und vollständig sind). "E" für error sind aber nur die ersten drei Einträge.

E [20/Nov/2010:11:59:05 +0100] [cups-driverd] Bad driver information file "/usr/share/cups/drv/sample.drv"!
E [20/Nov/2010:11:59:05 +0100] [cups-driverd] Skipping "/usr/share/ppd": loop detected!
E [20/Nov/2010:11:59:52 +0100] [Job 2] Job stopped due to filter errors; please consult the error_log file for details.
D [20/Nov/2010:11:59:52 +0100] [Job 2] The following messages were recorded from 11:59:51 to 11:59:52
D [20/Nov/2010:11:59:52 +0100] [Job 2] Merging.
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] Selecting.
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] Constructing.
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] Putting.
D [20/Nov/2010:11:59:52 +0100] [Job 2] [595.3 841.9]
D [20/Nov/2010:11:59:52 +0100] [Job 2] /.MediaSize
D [20/Nov/2010:11:59:52 +0100] [Job 2] 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] /%MediaDestination
D [20/Nov/2010:11:59:52 +0100] [Job 2] 8
D [20/Nov/2010:11:59:52 +0100] [Job 2] /cupsBitsPerColor
D [20/Nov/2010:11:59:52 +0100] [Job 2] 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] /%MediaSource
D [20/Nov/2010:11:59:52 +0100] [Job 2] 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] /cupsColorOrder
D [20/Nov/2010:11:59:52 +0100] [Job 2] 1
D [20/Nov/2010:11:59:52 +0100] [Job 2] /cupsColorSpace
D [20/Nov/2010:11:59:52 +0100] [Job 2] 1
D [20/Nov/2010:11:59:52 +0100] [Job 2] /cupsCompression
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] true
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -device-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_put_params(0x80ad2dc, 0xbfd4a578)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Setting cupsBitsPerColor to 8...
D [20/Nov/2010:11:59:52 +0100] [Job 2] Setting cupsColorOrder to 0...
D [20/Nov/2010:11:59:52 +0100] [Job 2] Setting cupsColorSpace to 1...
D [20/Nov/2010:11:59:52 +0100] [Job 2] Setting cupsCompression to 1...
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_set_color_info(0x80ad2dc)
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsEncodeLUT[0] = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsEncodeLUT[65535] = 255
D [20/Nov/2010:11:59:52 +0100] [Job 2] num_components = 3, depth = 24
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsColorSpace = 1, cupsColorOrder = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsBitsPerPixel = 24, cupsBitsPerColor = 8
D [20/Nov/2010:11:59:52 +0100] [Job 2] max_gray = 0, dither_grays = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] max_color = 255, dither_colors = 256
D [20/Nov/2010:11:59:52 +0100] [Job 2] Updating PageSize to [595 842]...
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups->header.Duplex = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups->header.Tumble = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups->page = 1
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsPPD = 0x81fe1d8
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsPPD->flip_duplex = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] (4) Flip: X=0 Y=0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups->header.cupsPageSizeName =
D [20/Nov/2010:11:59:52 +0100] [Job 2] size = A4
D [20/Nov/2010:11:59:52 +0100] [Job 2] margins[] = [ 0.116667 0.116667 0.116666 0.116667 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] Setting initial media size, [595 842] = 2892x4125 pixels...
D [20/Nov/2010:11:59:52 +0100] [Job 2] ppd = 0x81fe1d8
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595.300 841.900 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] margins = [ 0.117 0.117 0.117 0.117 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWResolution = [ 360.000 360.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] Result of putting.
D [20/Nov/2010:11:59:52 +0100] [Job 2] false
D [20/Nov/2010:11:59:52 +0100] [Job 2] -device-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] Installing.
D [20/Nov/2010:11:59:52 +0100] [Job 2] false
D [20/Nov/2010:11:59:52 +0100] [Job 2] -device-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_matrix(0x80ad2dc, 0xbfd4a548)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Portrait matrix: XX=+1 XY=0 YX=0 YY=-1
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595 841 ], HWResolution = [ 360 360 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] matrix = [ 5.000 0.000 0.000 -5.000 -42.000 4163.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_matrix(0x80ad2dc, 0xbfd4a508)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Portrait matrix: XX=+1 XY=0 YX=0 YY=-1
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595 841 ], HWResolution = [ 360 360 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] matrix = [ 5.000 0.000 0.000 -5.000 -42.000 4163.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_params(0x80ad2dc, 0xbfd4a570)
D [20/Nov/2010:11:59:52 +0100] [Job 2] before gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] after gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] Leaving cups_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_matrix(0x80ad2dc, 0xbfd4a588)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Portrait matrix: XX=+1 XY=0 YX=0 YY=-1
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595 841 ], HWResolution = [ 360 360 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] matrix = [ 5.000 0.000 0.000 -5.000 -42.000 4163.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_params(0x80ad2dc, 0xbfd4a570)
D [20/Nov/2010:11:59:52 +0100] [Job 2] before gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] after gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] Leaving cups_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_matrix(0x80ad2dc, 0xbfd4a598)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Portrait matrix: XX=+1 XY=0 YX=0 YY=-1
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595 841 ], HWResolution = [ 360 360 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] matrix = [ 5.000 0.000 0.000 -5.000 -42.000 4163.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_params(0x80ad2dc, 0xbfd4a570)
D [20/Nov/2010:11:59:52 +0100] [Job 2] before gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] after gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] Leaving cups_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_matrix(0x80ad2dc, 0xbfd4a4a0)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Portrait matrix: XX=+1 XY=0 YX=0 YY=-1
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595 841 ], HWResolution = [ 360 360 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] matrix = [ 5.000 0.000 0.000 -5.000 -42.000 4163.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_matrix(0x80ad2dc, 0xbfd4a518)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Portrait matrix: XX=+1 XY=0 YX=0 YY=-1
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595 841 ], HWResolution = [ 360 360 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] matrix = [ 5.000 0.000 0.000 -5.000 -42.000 4163.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] Finishing.
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -dict-
D [20/Nov/2010:11:59:52 +0100] [Job 2] -mark-
D [20/Nov/2010:11:59:52 +0100] [Job 2] **** DSC comment: /Page
D [20/Nov/2010:11:59:52 +0100] [Job 2] << /DSC_struct -dsc_data_struct- /PageNum 1 >>
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_put_params(0x80ad2dc, 0xbfd4a578)
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_set_color_info(0x80ad2dc)
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsEncodeLUT[0] = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsEncodeLUT[65535] = 255
D [20/Nov/2010:11:59:52 +0100] [Job 2] num_components = 3, depth = 24
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsColorSpace = 1, cupsColorOrder = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsBitsPerPixel = 24, cupsBitsPerColor = 8
D [20/Nov/2010:11:59:52 +0100] [Job 2] max_gray = 0, dither_grays = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] max_color = 255, dither_colors = 256
D [20/Nov/2010:11:59:52 +0100] [Job 2] ppd = 0x81fe1d8
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595.300 841.900 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] margins = [ 0.000 -0.000 0.000 0.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWResolution = [ 360.000 360.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] **** DSC comment: /PageBoundingBox
D [20/Nov/2010:11:59:52 +0100] [Job 2] << /DSC_struct -dsc_data_struct- >>
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_put_params(0x80ad2dc, 0xbfd4a578)
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_set_color_info(0x80ad2dc)
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsEncodeLUT[0] = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsEncodeLUT[65535] = 255
D [20/Nov/2010:11:59:52 +0100] [Job 2] num_components = 3, depth = 24
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsColorSpace = 1, cupsColorOrder = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsBitsPerPixel = 24, cupsBitsPerColor = 8
D [20/Nov/2010:11:59:52 +0100] [Job 2] max_gray = 0, dither_grays = 0
D [20/Nov/2010:11:59:52 +0100] [Job 2] max_color = 255, dither_colors = 256
D [20/Nov/2010:11:59:52 +0100] [Job 2] ppd = 0x81fe1d8
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595.300 841.900 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] margins = [ 0.000 -0.000 0.000 0.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWResolution = [ 360.000 360.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_matrix(0x80ad2dc, 0xbfd49dd0)
D [20/Nov/2010:11:59:52 +0100] [Job 2] Portrait matrix: XX=+1 XY=0 YX=0 YY=-1
D [20/Nov/2010:11:59:52 +0100] [Job 2] width = 2892, height = 4125
D [20/Nov/2010:11:59:52 +0100] [Job 2] PageSize = [ 595 841 ], HWResolution = [ 360 360 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] HWMargins = [ 8.400 8.400 8.400 8.400 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] matrix = [ 5.000 0.000 0.000 -5.000 -42.000 4163.000 ]
D [20/Nov/2010:11:59:52 +0100] [Job 2] Wrote 1 pages...
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_get_params(0x80ad2dc, 0xbfd4a570)
D [20/Nov/2010:11:59:52 +0100] [Job 2] before gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] after gdev_prn_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] Leaving cups_get_params()
D [20/Nov/2010:11:59:52 +0100] [Job 2] cups_print_pages(0x80ad2dc, 0xb6b854c0, 1)
D [20/Nov/2010:11:59:52 +0100] [Job 2] cupsBitsPerPixel = 24, cupsWidth = 2892, cupsBytesPerLine = 8676, srcbytes = 8676
D [20/Nov/2010:11:59:52 +0100] [Job 2] End of messages
D [20/Nov/2010:11:59:52 +0100] [Job 2] printer-state=3(idle)
D [20/Nov/2010:11:59:52 +0100] [Job 2] printer-state-message="Processing page 1..."
D [20/Nov/2010:11:59:52 +0100] [Job 2] printer-state-reasons=none