Martin Reising wrote: > On Thu, Mar 02, 2006 at 12:22:56PM +0100, Alexander Fieroch wrote: > >> Unter Linux habe ich die ppd zum NEC Silentwriter2 S60P von >> linuxprinting.org und aus dem foomatic-Paket, > > Du hast also den Drucker mit > > foomatic-configure -s cups -n RTFDnix -c parallel:/dev/lp0 -p > NEC-Silentwriter2_S60P -d Postscript -o PageSize=A4 > > eingerichtet und trotz LogLevel debug2 in cupsd.conf keinen Fehler in > /var/log/cups/error_log bei deinen Tests gefunden?
Guter Hinweis. Ich habe nochmal den Drucker so wie du es oben beschrieben hast neu eingerichtet und die /var/log/cups/error_log ab dem Zeitpunkt, an dem ich die Testseite (über http://localhost:631) abgeschickt habe angehängt. Mir fällt jetzt nichts besonderes auf, was dafür verantwortlich sein könnte, dass der Drucker nichts ausspuckt. Laut Display wird der Job verarbeitet... Gruß, Alexander
d [02/Mar/2006:20:45:00 +0100] ReadClient: 5, used=0, file=-1 d [02/Mar/2006:20:45:00 +0100] ReadClient: httpGets returned EOF... D [02/Mar/2006:20:45:00 +0100] CloseClient: 5 d [02/Mar/2006:20:45:00 +0100] CloseClient: Removing fd 5 from InputSet and OutputSet... d [02/Mar/2006:20:45:00 +0100] AcceptClient(lis=0x808aba8) 0 NumClients = 0 D [02/Mar/2006:20:45:00 +0100] AcceptClient: 5 from localhost:631. d [02/Mar/2006:20:45:00 +0100] AcceptClient: Adding fd 5 to InputSet... d [02/Mar/2006:20:45:00 +0100] ReadClient: 5, used=0, file=-1 D [02/Mar/2006:20:45:00 +0100] ReadClient: 5 GET /printers/RTFDnix?op=print-test-page HTTP/1.1 d [02/Mar/2006:20:45:00 +0100] decode_auth(0xb789a008): Authorization string = "Basic cm9vdDppYk1NU3VMRnJvbw==" d [02/Mar/2006:20:45:00 +0100] decode_auth: 5 username="root" d [02/Mar/2006:20:45:00 +0100] IsAuthorized: con->uri = "/printers/RTFDnix?op=print-test-page" d [02/Mar/2006:20:45:00 +0100] FindBest: uri = "/printers/RTFDnix?op=print-test-page"... d [02/Mar/2006:20:45:00 +0100] FindBest: Location / Limit 7f d [02/Mar/2006:20:45:00 +0100] FindBest: Location /jobs Limit 7f d [02/Mar/2006:20:45:00 +0100] FindBest: Location /admin Limit 7f d [02/Mar/2006:20:45:00 +0100] FindBest: best = "/" d [02/Mar/2006:20:45:00 +0100] IsAuthorized: auth = 0, satisfy=0... d [02/Mar/2006:20:45:00 +0100] pipe_command: command="/usr/lib/cups/cgi-bin/printers.cgi", options="RTFDnix?op=print-test-page" d [02/Mar/2006:20:45:00 +0100] pipe_command: argv[0] = "RTFDnix" d [02/Mar/2006:20:45:00 +0100] pipe_command: argv[1] = "op=print-test-page" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[0] = "PATH=/bin:/usr/bin" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[1] = "SERVER_SOFTWARE=CUPS/1.1" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[2] = "GATEWAY_INTERFACE=CGI/1.1" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[3] = "SERVER_PROTOCOL=HTTP/1.1" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[4] = "REDIRECT_STATUS=1" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[5] = "CUPS_SERVER=localhost" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[6] = "IPP_PORT=631" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[7] = "SERVER_NAME=localhost" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[8] = "SERVER_PORT=631" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[9] = "REMOTE_ADDR=127.0.0.1" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[10] = "REMOTE_HOST=localhost" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[11] = "REMOTE_USER=root" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[12] = "LANG=en.ISO8859-15" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[13] = "TZ=Europe/Berlin" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[14] = "TMPDIR=/var/spool/cups/tmp" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[15] = "CUPS_DATADIR=/usr/share/cups" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[17] = "HTTP_COOKIE=Guestbook=" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[18] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.8.0.1) Gecko/Debian-1.8.0.1-5 Galeon/2.0.0 (Debian package 2.0.0-3)" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[19] = "SCRIPT_NAME=/printers/RTFDnix" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[20] = "REQUEST_METHOD=GET" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[21] = "QUERY_STRING=op=print-test-page" d [02/Mar/2006:20:45:00 +0100] pipe_command: envp[22] = "CUPS_ENCRYPTION=IfRequested" d [02/Mar/2006:20:45:00 +0100] AddCert: adding certificate for pid 7389 D [02/Mar/2006:20:45:00 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 7389 I [02/Mar/2006:20:45:00 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7389) D [02/Mar/2006:20:45:00 +0100] SendCommand: 5 file=8 d [02/Mar/2006:20:45:00 +0100] SendCommand: Adding fd 8 to InputSet... d [02/Mar/2006:20:45:00 +0100] SendCommand: Adding fd 5 to OutputSet... d [02/Mar/2006:20:45:00 +0100] AcceptClient(lis=0x808aba8) 0 NumClients = 1 D [02/Mar/2006:20:45:00 +0100] AcceptClient: 7 from localhost:631. d [02/Mar/2006:20:45:00 +0100] AcceptClient: Adding fd 7 to InputSet... d [02/Mar/2006:20:45:00 +0100] WriteClient: 5 Content-Type: text/html;charset=iso-8859-15 d [02/Mar/2006:20:45:00 +0100] WriteClient: 5 d [02/Mar/2006:20:45:00 +0100] WriteClient: 5 1171 bytes d [02/Mar/2006:20:45:00 +0100] ReadClient: 7, used=0, file=-1 D [02/Mar/2006:20:45:00 +0100] ReadClient: 7 POST /printers/RTFDnix HTTP/1.1 d [02/Mar/2006:20:45:00 +0100] decode_auth(0xb789cc3c): Authorization string = "" d [02/Mar/2006:20:45:00 +0100] decode_auth: 7 username="" d [02/Mar/2006:20:45:00 +0100] IsAuthorized: con->uri = "/printers/RTFDnix" d [02/Mar/2006:20:45:00 +0100] FindBest: uri = "/printers/RTFDnix"... d [02/Mar/2006:20:45:00 +0100] FindBest: Location / Limit 7f d [02/Mar/2006:20:45:00 +0100] FindBest: Location /jobs Limit 7f d [02/Mar/2006:20:45:00 +0100] FindBest: Location /admin Limit 7f d [02/Mar/2006:20:45:00 +0100] FindBest: best = "/" d [02/Mar/2006:20:45:00 +0100] IsAuthorized: auth = 0, satisfy=0... d [02/Mar/2006:20:45:00 +0100] POST /printers/RTFDnix d [02/Mar/2006:20:45:00 +0100] CONTENT_TYPE = application/ipp d [02/Mar/2006:20:45:00 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 14762, con->file = -1 d [02/Mar/2006:20:45:00 +0100] ReadClient: 7 REQUEST /var/spool/cups/00000001=9 d [02/Mar/2006:20:45:00 +0100] ReadClient: 7 writing 14542 bytes to 9 d [02/Mar/2006:20:45:00 +0100] ReadClient: 7 Closing data file 9, size = 14542. d [02/Mar/2006:20:45:00 +0100] ProcessIPPRequest(0xb789cc3c[7]): operation_id = 0002 d [02/Mar/2006:20:45:00 +0100] ProcessIPPRequest: URI="ipp://localhost/printers/RTFDnix" d [02/Mar/2006:20:45:00 +0100] print_job(0xb789cc3c[7], ipp://localhost/printers/RTFDnix) D [02/Mar/2006:20:45:00 +0100] print_job: request file type is application/postscript. d [02/Mar/2006:20:45:00 +0100] check_quotas(0xb789cc3c[7], 0x80addd0[RTFDnix]) D [02/Mar/2006:20:45:00 +0100] check_quotas: requesting-user-name = 'root' D [02/Mar/2006:20:45:00 +0100] print_job: requesting-user-name = 'root' D [02/Mar/2006:20:45:00 +0100] Adding default job-sheets values "none,none"... I [02/Mar/2006:20:45:00 +0100] Adding start banner page "none" to job 52. d [02/Mar/2006:20:45:00 +0100] copy_banner(0xb789cc3c[7], 0x82cc550[52], none) d [02/Mar/2006:20:45:00 +0100] add_file(con=0xb789cc3c[7], job=52, filetype=application/postscript, compression=0) I [02/Mar/2006:20:45:00 +0100] Adding end banner page "none" to job 52. d [02/Mar/2006:20:45:00 +0100] copy_banner(0xb789cc3c[7], 0x82cc550[52], none) I [02/Mar/2006:20:45:00 +0100] Job 52 queued on 'RTFDnix' by 'root'. D [02/Mar/2006:20:45:00 +0100] Job 52 hold_until = 0 d [02/Mar/2006:20:45:00 +0100] SaveJob: Closing file 9... D [02/Mar/2006:20:45:00 +0100] StartJob(52, 0x80addd0) D [02/Mar/2006:20:45:00 +0100] StartJob() id = 52, file = 0/1 D [02/Mar/2006:20:45:00 +0100] job-sheets=none,none D [02/Mar/2006:20:45:00 +0100] banner_page = 0 D [02/Mar/2006:20:45:00 +0100] StartJob: argv = "RTFDnix","52","root","Test Page","1","","/var/spool/cups/d00052-001" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[1]="SOFTWARE=CUPS/1.1" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[2]="USER=root" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[3]="CHARSET=iso-8859-15" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[4]="LANG=en" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[5]="TZ=Europe/Berlin" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[6]="PPD=/etc/cups/ppd/RTFDnix.ppd" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[8]="RIP_MAX_CACHE=8m" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[10]="CONTENT_TYPE=application/postscript" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[11]="DEVICE_URI=parallel:/dev/lp0" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[12]="PRINTER=RTFDnix" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[15]="CUPS_SERVER=localhost" D [02/Mar/2006:20:45:00 +0100] StartJob: envp[16]="IPP_PORT=631" d [02/Mar/2006:20:45:00 +0100] StartJob: Allocating status buffer... D [02/Mar/2006:20:45:00 +0100] StartJob: statusfds = [ 9 10 ] D [02/Mar/2006:20:45:00 +0100] StartJob: filterfds[1] = [ 11 -1 ] D [02/Mar/2006:20:45:00 +0100] StartJob: filter = "/usr/lib/cups/filter/pstops" D [02/Mar/2006:20:45:00 +0100] StartJob: filterfds[0] = [ 12 13 ] D [02/Mar/2006:20:45:00 +0100] start_process("/usr/lib/cups/filter/pstops", 0xbfaa4f90, 0xbfaa4508, 11, 13, 10) I [02/Mar/2006:20:45:00 +0100] Started filter /usr/lib/cups/filter/pstops (PID 7391) for job 52. D [02/Mar/2006:20:45:00 +0100] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip" D [02/Mar/2006:20:45:00 +0100] StartJob: filterfds[1] = [ 11 14 ] D [02/Mar/2006:20:45:00 +0100] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbfaa4f90, 0xbfaa4508, 12, 14, 10) I [02/Mar/2006:20:45:00 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 7392) for job 52. D [02/Mar/2006:20:45:00 +0100] StartJob: backend = "/usr/lib/cups/backend/parallel" D [02/Mar/2006:20:45:00 +0100] StartJob: filterfds[0] = [ -1 12 ] D [02/Mar/2006:20:45:00 +0100] start_process("/usr/lib/cups/backend/parallel", 0xbfaa4f90, 0xbfaa4508, 11, 12, 10) I [02/Mar/2006:20:45:00 +0100] Started backend /usr/lib/cups/backend/parallel (PID 7393) for job 52. d [02/Mar/2006:20:45:00 +0100] StartJob: Adding fd 9 to InputSet... d [02/Mar/2006:20:45:00 +0100] add_job_state_reasons(0xb789cc3c[7], 52) D [02/Mar/2006:20:45:00 +0100] ProcessIPPRequest: 7 status_code=0 d [02/Mar/2006:20:45:00 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet... d [02/Mar/2006:20:45:00 +0100] PID 7391 exited with no errors. D [02/Mar/2006:20:45:00 +0100] [Job 52] perl: warning: Setting locale failed. D [02/Mar/2006:20:45:00 +0100] [Job 52] perl: warning: Please check that your locale settings: D [02/Mar/2006:20:45:00 +0100] [Job 52] LANGUAGE = (unset), D [02/Mar/2006:20:45:00 +0100] [Job 52] LC_ALL = (unset), D [02/Mar/2006:20:45:00 +0100] [Job 52] LANG = "en" D [02/Mar/2006:20:45:00 +0100] [Job 52] are supported and installed on your system. D [02/Mar/2006:20:45:00 +0100] [Job 52] perl: warning: Falling back to the standard locale ("C"). D [02/Mar/2006:20:45:00 +0100] [Job 52] Page = 595x842; 0,0 to 595,842 D [02/Mar/2006:20:45:00 +0100] [Job 52] slowcollate=0, slowduplex=0, sloworder=0 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%BoundingBox: 0 0 612 792 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%Pages: 1 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%LanguageLevel: 1 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%DocumentData: Clean7Bit D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%DocumentSuppliedResources: procset testprint/1.1 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%Creator: Michael Sweet, Easy Software Products D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%CreationDate: May 11, 1999 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%Title: Test Page D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%EndComments D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%BeginProlog D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%BeginResource procset testprint 1.1 0 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%EndResource D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%EndProlog D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%Page: 1 1 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%Page: 1 1 D [02/Mar/2006:20:45:00 +0100] [Job 52] pw = 595.0, pl = 842.0 D [02/Mar/2006:20:45:00 +0100] [Job 52] PageLeft = 0.0, PageRight = 595.0 D [02/Mar/2006:20:45:00 +0100] [Job 52] PageTop = 842.0, PageBottom = 0.0 D [02/Mar/2006:20:45:00 +0100] [Job 52] PageWidth = 595.0, PageLength = 842.0 D [02/Mar/2006:20:45:00 +0100] [Job 52] 0 %%EOF D [02/Mar/2006:20:45:00 +0100] [Job 52] Saw EOF! d [02/Mar/2006:20:45:00 +0100] WriteClient: Removing fd 7 from OutputSet... d [02/Mar/2006:20:45:00 +0100] PID 7389 exited with no errors. d [02/Mar/2006:20:45:00 +0100] DeleteCert: removing certificate for pid 7389 d [02/Mar/2006:20:45:00 +0100] ReadClient: 7, used=0, file=-1 d [02/Mar/2006:20:45:00 +0100] ReadClient: httpGets returned EOF... D [02/Mar/2006:20:45:00 +0100] CloseClient: 7 d [02/Mar/2006:20:45:00 +0100] CloseClient: Removing fd 7 from InputSet and OutputSet... d [02/Mar/2006:20:45:00 +0100] WriteClient: Removing fd 5 from OutputSet... d [02/Mar/2006:20:45:00 +0100] WriteClient: Removing fd 8 from InputSet... d [02/Mar/2006:20:45:00 +0100] WriteClient: 5 Closing data file 8. D [02/Mar/2006:20:45:00 +0100] [Job 52] foomatic-rip version $Revision: 3.43.2.15 $ running... D [02/Mar/2006:20:45:00 +0100] [Job 52] Parsing PPD file ... D [02/Mar/2006:20:45:00 +0100] [Job 52] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip" D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option ColorSpace D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option PageSize D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option PageRegion D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option ImageableArea D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option PaperDimension D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option Duplex D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option Resolution D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option PreFilter D [02/Mar/2006:20:45:00 +0100] [Job 52] Added option Font D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] Parameter Summary D [02/Mar/2006:20:45:00 +0100] [Job 52] ----------------- D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] Spooler: cups D [02/Mar/2006:20:45:00 +0100] [Job 52] Printer: RTFDnix D [02/Mar/2006:20:45:00 +0100] [Job 52] PPD file: /etc/cups/ppd/RTFDnix.ppd D [02/Mar/2006:20:45:00 +0100] [Job 52] Printer model: NEC Silentwriter2 S60P Foomatic/Postscript (recommended) D [02/Mar/2006:20:45:00 +0100] [Job 52] Job title: Test Page D [02/Mar/2006:20:45:00 +0100] [Job 52] File(s) to be printed: D [02/Mar/2006:20:45:00 +0100] [Job 52] <STDIN> D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] ================================================ D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] File: <STDIN> D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] ================================================ D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] Reading PostScript input ... D [02/Mar/2006:20:45:00 +0100] [Job 52] --> This document is DSC-conforming! D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] ----------- D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%BeginProlog D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%EndProlog D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] ----------- D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%BeginSetup D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%BeginFeature: *PageSize A4 D [02/Mar/2006:20:45:00 +0100] [Job 52] Option: PageSize=A4 --> Option will be set by PostScript interpreter D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%BeginFeature: *PreFilter No D [02/Mar/2006:20:45:00 +0100] [Job 52] Option: PreFilter=No --> Setting option D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %% FoomaticRIPOptionSetting: PreFilter=No D [02/Mar/2006:20:45:00 +0100] [Job 52] Option: PreFilter=No --> Setting option D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%BeginFeature: *Resolution 300x300dpi D [02/Mar/2006:20:45:00 +0100] [Job 52] Option: Resolution=300x300dpi --> Setting option D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %% FoomaticRIPOptionSetting: Resolution=300x300dpi D [02/Mar/2006:20:45:00 +0100] [Job 52] Option: Resolution=300x300dpi --> Setting option D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%BeginFeature: *Duplex None D [02/Mar/2006:20:45:00 +0100] [Job 52] Option: Duplex=None --> Setting option D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %% FoomaticRIPOptionSetting: Duplex=None D [02/Mar/2006:20:45:00 +0100] [Job 52] Option: Duplex=None --> Setting option D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: %%EndSetup D [02/Mar/2006:20:45:00 +0100] [Job 52] Inserting PostScript code for CUPS' page accounting D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] ----------- D [02/Mar/2006:20:45:00 +0100] [Job 52] New page: 1 1 D [02/Mar/2006:20:45:00 +0100] [Job 52] Inserting option code into "PageSetup" section. D [02/Mar/2006:20:45:00 +0100] [Job 52] No page header or page header not DSC-conforming D [02/Mar/2006:20:45:00 +0100] [Job 52] Stopping search for page header options D [02/Mar/2006:20:45:00 +0100] [Job 52] Found: D [02/Mar/2006:20:45:00 +0100] [Job 52] pageHeight sub % Move down... D [02/Mar/2006:20:45:00 +0100] [Job 52] --> Output goes directly to the renderer now. D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] Starting renderer D [02/Mar/2006:20:45:00 +0100] [Job 52] JCL: <job data> D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] renderer PID kid4=7396 D [02/Mar/2006:20:45:00 +0100] [Job 52] renderer command: level=0; /usr/bin/printf "%%!\n%%%% %%%%\n<</HWResolution[300 300]>>setpagedevice\n<</Duplex false>>setpagedevice\n"; if [ $level -gt 0 ]; then if [ $level -lt 99 ]; then level=" -dLanguageLevel=$level"; else level=""; fi; gs -q -dPARANOIDSAFER -dNOPAUSE -dBATCH -sDEVICE=pswrite$level -sOutputFile=- -; else cat; fi D [02/Mar/2006:20:45:00 +0100] [Job 52] D [02/Mar/2006:20:45:00 +0100] [Job 52] Closing renderer D [02/Mar/2006:20:45:00 +0100] [Job 52] KID3 exited with status 0 D [02/Mar/2006:20:45:00 +0100] [Job 52] tail process done writing data to STDOUT D [02/Mar/2006:20:45:00 +0100] [Job 52] KID4 finished D [02/Mar/2006:20:45:00 +0100] [Job 52] KID3 finished D [02/Mar/2006:20:45:01 +0100] [Job 52] KID4 exited with status 0 D [02/Mar/2006:20:45:01 +0100] [Job 52] Renderer exit stat: 0 D [02/Mar/2006:20:45:01 +0100] [Job 52] Renderer process finished D [02/Mar/2006:20:45:01 +0100] [Job 52] D [02/Mar/2006:20:45:01 +0100] [Job 52] Closing foomatic-rip. d [02/Mar/2006:20:45:01 +0100] PID 7392 exited with no errors. d [02/Mar/2006:20:45:02 +0100] select_timeout: 11 seconds to process active jobs d [02/Mar/2006:20:45:02 +0100] PID 7393 exited with no errors. D [02/Mar/2006:20:45:02 +0100] UpdateJob: job 52, file 0 is complete. d [02/Mar/2006:20:45:02 +0100] UpdateJob: Removing fd 9 from InputSet... D [02/Mar/2006:20:45:02 +0100] CancelJob: id = 52 D [02/Mar/2006:20:45:02 +0100] StopJob: id = 52, force = 0 D [02/Mar/2006:20:45:02 +0100] StopJob: printer state is 3 d [02/Mar/2006:20:45:02 +0100] StopJob: Freeing status buffer... d [02/Mar/2006:20:45:02 +0100] SaveJob: Closing file 7... d [02/Mar/2006:20:45:02 +0100] SaveJob: Closing file 7... d [02/Mar/2006:20:45:03 +0100] ReadClient: 5, used=0, file=-1 D [02/Mar/2006:20:45:03 +0100] ReadClient: 5 GET /printers/RTFDnix HTTP/1.1 d [02/Mar/2006:20:45:03 +0100] decode_auth(0xb789a008): Authorization string = "Basic cm9vdDppYk1NU3VMRnJvbw==" d [02/Mar/2006:20:45:03 +0100] decode_auth: 5 username="root" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: con->uri = "/printers/RTFDnix" d [02/Mar/2006:20:45:03 +0100] FindBest: uri = "/printers/RTFDnix"... d [02/Mar/2006:20:45:03 +0100] FindBest: Location / Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /jobs Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /admin Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: best = "/" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: auth = 0, satisfy=0... d [02/Mar/2006:20:45:03 +0100] pipe_command: command="/usr/lib/cups/cgi-bin/printers.cgi", options="RTFDnix" d [02/Mar/2006:20:45:03 +0100] pipe_command: argv[0] = "RTFDnix" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[0] = "PATH=/bin:/usr/bin" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[1] = "SERVER_SOFTWARE=CUPS/1.1" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[2] = "GATEWAY_INTERFACE=CGI/1.1" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[3] = "SERVER_PROTOCOL=HTTP/1.1" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[4] = "REDIRECT_STATUS=1" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[5] = "CUPS_SERVER=localhost" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[6] = "IPP_PORT=631" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[7] = "SERVER_NAME=localhost" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[8] = "SERVER_PORT=631" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[9] = "REMOTE_ADDR=127.0.0.1" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[10] = "REMOTE_HOST=localhost" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[11] = "REMOTE_USER=root" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[12] = "LANG=en.ISO8859-15" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[13] = "TZ=Europe/Berlin" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[14] = "TMPDIR=/var/spool/cups/tmp" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[15] = "CUPS_DATADIR=/usr/share/cups" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[16] = "CUPS_SERVERROOT=/etc/cups" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[17] = "HTTP_COOKIE=Guestbook=" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[18] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.8.0.1) Gecko/Debian-1.8.0.1-5 Galeon/2.0.0 (Debian package 2.0.0-3)" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[19] = "SCRIPT_NAME=/printers/RTFDnix" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[20] = "REQUEST_METHOD=GET" d [02/Mar/2006:20:45:03 +0100] pipe_command: envp[21] = "CUPS_ENCRYPTION=IfRequested" d [02/Mar/2006:20:45:03 +0100] AddCert: adding certificate for pid 7400 D [02/Mar/2006:20:45:03 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 7400 I [02/Mar/2006:20:45:03 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7400) D [02/Mar/2006:20:45:03 +0100] SendCommand: 5 file=8 d [02/Mar/2006:20:45:03 +0100] SendCommand: Adding fd 8 to InputSet... d [02/Mar/2006:20:45:03 +0100] SendCommand: Adding fd 5 to OutputSet... d [02/Mar/2006:20:45:03 +0100] AcceptClient(lis=0x808aba8) 0 NumClients = 1 D [02/Mar/2006:20:45:03 +0100] AcceptClient: 7 from localhost:631. d [02/Mar/2006:20:45:03 +0100] AcceptClient: Adding fd 7 to InputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: 5 Content-Type: text/html;charset=iso-8859-15 d [02/Mar/2006:20:45:03 +0100] WriteClient: 5 d [02/Mar/2006:20:45:03 +0100] WriteClient: 5 1171 bytes d [02/Mar/2006:20:45:03 +0100] ReadClient: 7, used=0, file=-1 D [02/Mar/2006:20:45:03 +0100] ReadClient: 7 POST / HTTP/1.1 d [02/Mar/2006:20:45:03 +0100] decode_auth(0xb789cc3c): Authorization string = "" d [02/Mar/2006:20:45:03 +0100] decode_auth: 7 username="" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: con->uri = "/" d [02/Mar/2006:20:45:03 +0100] FindBest: uri = "/"... d [02/Mar/2006:20:45:03 +0100] FindBest: Location / Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /jobs Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /admin Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: best = "/" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: auth = 0, satisfy=0... d [02/Mar/2006:20:45:03 +0100] POST / d [02/Mar/2006:20:45:03 +0100] CONTENT_TYPE = application/ipp d [02/Mar/2006:20:45:03 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 141, con->file = -1 d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest(0xb789cc3c[7]): operation_id = 4001 d [02/Mar/2006:20:45:03 +0100] get_default(0xb789cc3c[7]) d [02/Mar/2006:20:45:03 +0100] copy_attrs(0x82cc050, 0x80ab600, 0x82cc920, 0) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cc050, 0x80ab628[printer-uri-supported,4,45]) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cc050, 0x80ab760[printer-name,4,42]) d [02/Mar/2006:20:45:03 +0100] copy_attrs(0x82cc050, 0x80a79b0, 0x82cc920, 0) D [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: 7 status_code=1 d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: Removing fd 7 from OutputSet... d [02/Mar/2006:20:45:03 +0100] ReadClient: 7, used=0, file=-1 D [02/Mar/2006:20:45:03 +0100] ReadClient: 7 POST / HTTP/1.1 d [02/Mar/2006:20:45:03 +0100] decode_auth(0xb789cc3c): Authorization string = "" d [02/Mar/2006:20:45:03 +0100] decode_auth: 7 username="" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: con->uri = "/" d [02/Mar/2006:20:45:03 +0100] FindBest: uri = "/"... d [02/Mar/2006:20:45:03 +0100] FindBest: Location / Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /jobs Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /admin Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: best = "/" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: auth = 0, satisfy=0... d [02/Mar/2006:20:45:03 +0100] POST / d [02/Mar/2006:20:45:03 +0100] CONTENT_TYPE = application/ipp d [02/Mar/2006:20:45:03 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 865, con->file = -1 d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest(0xb789cc3c[7]): operation_id = 000b d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: URI="ipp://localhost/printers/RTFDnix" d [02/Mar/2006:20:45:03 +0100] get_printer_attrs(0xb789cc3c[7], ipp://localhost/printers/RTFDnix) d [02/Mar/2006:20:45:03 +0100] add_printer_state_reasons(0xb789cc3c[7], 0x80addd0[RTFDnix]) d [02/Mar/2006:20:45:03 +0100] add_queued_job_count(0xb789cc3c[7], 0x80addd0[RTFDnix]) d [02/Mar/2006:20:45:03 +0100] copy_attrs(0x82cca90, 0x80ab600, 0x82cb5f0, 0) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cca90, 0x80ab628[printer-uri-supported,4,45]) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cca90, 0x80ab760[printer-name,4,42]) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cca90, 0x80ab7b0[printer-location,4,41]) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cca90, 0x80ab800[printer-info,4,41]) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cca90, 0x80ab9e8[device-uri,4,45]) d [02/Mar/2006:20:45:03 +0100] copy_attribute(0x82cca90, 0x80b7d08[printer-make-and-model,4,41]) d [02/Mar/2006:20:45:03 +0100] copy_attrs(0x82cca90, 0x80a79b0, 0x82cb5f0, 0) D [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: 7 status_code=1 d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: Removing fd 7 from OutputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: 5 1763 bytes d [02/Mar/2006:20:45:03 +0100] ReadClient: 7, used=0, file=-1 D [02/Mar/2006:20:45:03 +0100] ReadClient: 7 POST / HTTP/1.1 d [02/Mar/2006:20:45:03 +0100] decode_auth(0xb789cc3c): Authorization string = "" d [02/Mar/2006:20:45:03 +0100] decode_auth: 7 username="" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: con->uri = "/" d [02/Mar/2006:20:45:03 +0100] FindBest: uri = "/"... d [02/Mar/2006:20:45:03 +0100] FindBest: Location / Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /jobs Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: Location /admin Limit 7f d [02/Mar/2006:20:45:03 +0100] FindBest: best = "/" d [02/Mar/2006:20:45:03 +0100] IsAuthorized: auth = 0, satisfy=0... d [02/Mar/2006:20:45:03 +0100] POST / d [02/Mar/2006:20:45:03 +0100] CONTENT_TYPE = application/ipp d [02/Mar/2006:20:45:03 +0100] ReadClient: 7 con->data_encoding = length, con->data_remaining = 427, con->file = -1 d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest(0xb789cc3c[7]): operation_id = 000a d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: URI="ipp://localhost/printers/RTFDnix" d [02/Mar/2006:20:45:03 +0100] get_jobs(0xb789cc3c[7], ipp://localhost/printers/RTFDnix) d [02/Mar/2006:20:45:03 +0100] get_jobs: job->id = 50 d [02/Mar/2006:20:45:03 +0100] get_jobs: job->id = 51 d [02/Mar/2006:20:45:03 +0100] get_jobs: job->id = 52 D [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: 7 status_code=1 d [02/Mar/2006:20:45:03 +0100] ProcessIPPRequest: Adding fd 7 to OutputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: Removing fd 7 from OutputSet... d [02/Mar/2006:20:45:03 +0100] ReadClient: 7, used=0, file=-1 d [02/Mar/2006:20:45:03 +0100] ReadClient: httpGets returned EOF... D [02/Mar/2006:20:45:03 +0100] CloseClient: 7 d [02/Mar/2006:20:45:03 +0100] CloseClient: Removing fd 7 from InputSet and OutputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: Removing fd 5 from OutputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: Removing fd 8 from InputSet... d [02/Mar/2006:20:45:03 +0100] WriteClient: 5 Closing data file 8. d [02/Mar/2006:20:45:03 +0100] PID 7400 exited with no errors. d [02/Mar/2006:20:45:03 +0100] DeleteCert: removing certificate for pid 7400 d [02/Mar/2006:20:45:04 +0100] select_timeout: 30 seconds to send browse update d [02/Mar/2006:20:45:34 +0100] select_timeout: 31 seconds to send browse update