I [12/Oct/2012:15:32:30 +0000] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=3007) D [12/Oct/2012:15:32:30 +0000] cupsdSendCommand: 14 file=16 d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=16, read_cb=0x7f79898bcaf0, write_cb=(nil), data=0x7f798af55b20) D [12/Oct/2012:15:32:30 +0000] [CGI] admin.cgi started... d [12/Oct/2012:15:32:30 +0000] cupsdAcceptClient(lis=0x7f798ae89810(9)) Clients=1 D [12/Oct/2012:15:32:30 +0000] cupsdAcceptClient: 15 from localhost (Domain) d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=15, read_cb=0x7f79898c0590, write_cb=(nil), data=0x7f798af6c6c0) D [12/Oct/2012:15:32:30 +0000] [CGI] http=0x7f71656cc940 D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: SECTION="admin" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: REFRESH_PAGE="" D [12/Oct/2012:15:32:30 +0000] [CGI] org.cups.sid cookie is "9eecadaa2a887edde61900c84c6a63e2" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: org.cups.sid="9eecadaa2a887edde61900c84c6a63e2" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: PRINTER_NAME="mf-test" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: OP="set-printer-options" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: OptionTray="2Cassette" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: LargeCapacityTray="NotInstalled" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: InnerTray2="NotInstalled" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: ShiftTray="NotInstalled" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: Finisher="FinTIGRIS" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIPaperPolicy="PromptUser" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: PageSize="Letter" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: PageSize.Width="0" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: PageSize.Height="0" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: PageSize.Units="pt" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: InputSlot="Auto" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: Duplex="DuplexNoTumble" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: Resolution="600dpi" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: Collate="False" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIPrintMode="0rhit" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: Rimagesm="Off" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: ColorModel="Gray" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RPSBitsPerPixel="1BitsPerPixel" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RPSRGBcorrect="DetailBright" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RPSColorRendDict="Auto" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RPSDitherType="Auto" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RPSBlackMode="gray" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RPSBlackOverPrint="False" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RPSColorSep="None" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: Rcmyksimulation="Off" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: MediaType="Auto" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: OutputBin="Default" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: StapleLocation="None" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIPunch="None" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIRotateBy180="Off" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIOrientOvr="Off" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIWatermark="Off" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIWMText="Confidential" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIwmFont="HelveticaB" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIwmSize="36" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIwmAngle="45Deg" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: RIwmTextStyle="Gray" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: JobType="Normal" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: LockedPrintPassword="None" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: LockedPrintPassword.Password="" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: DocServerPassword="None" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: DocServerPassword.Password="" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: UserCode="None" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: UserCode.UserCode="" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: job_sheets_start="none" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: job_sheets_start.UserCode="" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: job_sheets_end="none" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: job_sheets_end.UserCode="" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: printer_error_policy="retry-job" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: printer_error_policy.UserCode="" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: printer_op_policy="default" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: printer_op_policy.UserCode="" D [12/Oct/2012:15:32:30 +0000] [CGI] op="set-printer-options"... D [12/Oct/2012:15:32:30 +0000] [CGI] do_set_options(http=0x7f71656cc940, is_class=0) D [12/Oct/2012:15:32:30 +0000] [CGI] printer="mf-test", uri="ipp://localhost/printers/mf-test"... D [12/Oct/2012:15:32:30 +0000] [CGI] Got PPD file: "/var/spool/cups/tmp/5078380eb2290" D [12/Oct/2012:15:32:30 +0000] [CGI] Set Collate to False... D [12/Oct/2012:15:32:30 +0000] [CGI] Set ColorModel to Gray... D [12/Oct/2012:15:32:30 +0000] [CGI] Set DocServerPassword to None... D [12/Oct/2012:15:32:30 +0000] [CGI] Set Duplex to DuplexNoTumble... D [12/Oct/2012:15:32:30 +0000] [CGI] Set Finisher to FinTIGRIS... D [12/Oct/2012:15:32:30 +0000] [CGI] Set InnerTray2 to NotInstalled... D [12/Oct/2012:15:32:30 +0000] [CGI] Set InputSlot to Auto... D [12/Oct/2012:15:32:30 +0000] [CGI] Set JobType to Normal... D [12/Oct/2012:15:32:30 +0000] [CGI] Set LargeCapacityTray to NotInstalled... D [12/Oct/2012:15:32:30 +0000] [CGI] Set LockedPrintPassword to None... D [12/Oct/2012:15:32:30 +0000] [CGI] Set MediaType to Auto... D [12/Oct/2012:15:32:30 +0000] [CGI] Set OptionTray to 2Cassette... D [12/Oct/2012:15:32:30 +0000] [CGI] Set OutputBin to Default... D [12/Oct/2012:15:32:30 +0000] [CGI] Didn't find PageRegion... D [12/Oct/2012:15:32:30 +0000] [CGI] Set PageSize to Letter... D [12/Oct/2012:15:32:30 +0000] [CGI] Set Rcmyksimulation to Off... D [12/Oct/2012:15:32:30 +0000] [CGI] Set Resolution to 600dpi... D [12/Oct/2012:15:32:30 +0000] [CGI] Set Rimagesm to Off... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIOrientOvr to Off... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIPaperPolicy to PromptUser... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIPrintMode to 0rhit... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIPunch to None... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIRotateBy180 to Off... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIWatermark to Off... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIwmAngle to 45Deg... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIwmFont to HelveticaB... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIwmSize to 36... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIWMText to Confidential... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RIwmTextStyle to Gray... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RPSBitsPerPixel to 1BitsPerPixel... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RPSBlackMode to gray... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RPSBlackOverPrint to False... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RPSColorRendDict to Auto... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RPSColorSep to None... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RPSDitherType to Auto... D [12/Oct/2012:15:32:30 +0000] [CGI] Set RPSRGBcorrect to DetailBright... D [12/Oct/2012:15:32:30 +0000] [CGI] Set ShiftTray to NotInstalled... D [12/Oct/2012:15:32:30 +0000] [CGI] Set StapleLocation to None... D [12/Oct/2012:15:32:30 +0000] [CGI] Set UserCode to None... D [12/Oct/2012:15:32:30 +0000] [CGI] Setting options... d [12/Oct/2012:15:32:30 +0000] cupsdReadClient(con=0x7f798af6c6c0(15)) con->http.error=0 con->http.used=0, con->http.state=0 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=0, con->file=-1 D [12/Oct/2012:15:32:30 +0000] cupsdReadClient: 15 POST /admin/ HTTP/1.1 D [12/Oct/2012:15:32:30 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" d [12/Oct/2012:15:32:30 +0000] cupsdFindBest: uri = "/admin/"... d [12/Oct/2012:15:32:30 +0000] cupsdFindBest: Location /admin/conf Limit 7f d [12/Oct/2012:15:32:30 +0000] cupsdFindBest: Location /admin Limit 7f d [12/Oct/2012:15:32:30 +0000] cupsdFindBest: Location / Limit 7f d [12/Oct/2012:15:32:30 +0000] cupsdFindBest: best = /admin d [12/Oct/2012:15:32:30 +0000] cupsdAuthorize: con->uri="/admin/", con->best=0x7f798ae895d0(/admin) d [12/Oct/2012:15:32:30 +0000] cupsdAuthorize: Authorization="" D [12/Oct/2012:15:32:30 +0000] cupsdAuthorize: No authentication data provided. d [12/Oct/2012:15:32:30 +0000] cupsdIsAuthorized: con->uri="/admin/", con->best=0x7f798ae895d0(/admin) d [12/Oct/2012:15:32:30 +0000] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=4 d [12/Oct/2012:15:32:30 +0000] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW... D [12/Oct/2012:15:32:30 +0000] cupsdIsAuthorized: username="" d [12/Oct/2012:15:32:30 +0000] cupsdSendError(con=0x7f798af6c6c0(15), code=401, auth_type=0 D [12/Oct/2012:15:32:30 +0000] cupsdSendHeader: 15 WWW-Authenticate: Basic realm="CUPS", trc="y" D [12/Oct/2012:15:32:30 +0000] cupsdCloseClient: 15 D [12/Oct/2012:15:32:30 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" d [12/Oct/2012:15:32:30 +0000] cupsdRemoveSelect(fd=15) D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: TITLE="Set Printer Options" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: SERVER_NAME="printserver-test.local" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: REMOTE_USER="korobkin" D [12/Oct/2012:15:32:30 +0000] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.4" d [12/Oct/2012:15:32:30 +0000] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl") d [12/Oct/2012:15:32:30 +0000] [CGI] lang="en_US.UTF8", locale="/en_US"... d [12/Oct/2012:15:32:30 +0000] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 0... d [12/Oct/2012:15:32:30 +0000] [CGI] "{title}" at 200... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting "{refresh_page?" at 369, result=0... d [12/Oct/2012:15:32:30 +0000] [CGI] Skip first part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 369... d [12/Oct/2012:15:32:30 +0000] [CGI] "{refresh_page}" at 419... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 422 on character ':'... d [12/Oct/2012:15:32:30 +0000] [CGI] Output second part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 422... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 423 on character '}'... d [12/Oct/2012:15:32:30 +0000] write_pipe(con=0x7f798af55b20(14)) CGI output on fd 16 d [12/Oct/2012:15:32:30 +0000] cupsdRemoveSelect(fd=16) d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=14, read_cb=(nil), write_cb=0x7f79898be2a0, data=0x7f798af55b20) d [12/Oct/2012:15:32:30 +0000] cupsdWriteClient(con=0x7f798af55b20(14)) response=(nil)(-1), file=16 pipe_pid=3007 state=7 d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=16, read_cb=0x7f79898bcaf0, write_cb=(nil), data=0x7f798af55b20) D [12/Oct/2012:15:32:30 +0000] Script header: Content-Type: text/html;charset=utf-8 D [12/Oct/2012:15:32:30 +0000] Script header: d [12/Oct/2012:15:32:30 +0000] cupsdWriteClient(con=0x7f798af55b20(14)) response=(nil)(-1), file=16 pipe_pid=3007 state=7 d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=16, read_cb=0x7f79898bcaf0, write_cb=(nil), data=0x7f798af55b20) d [12/Oct/2012:15:32:30 +0000] cupsdRemoveSelect(fd=14) d [12/Oct/2012:15:32:30 +0000] [CGI] Finished "{refresh_page?", out=0x7f71642ff780... d [12/Oct/2012:15:32:30 +0000] [CGI] "{title}" at 475... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting "{SECTION=admin" at 803, result=1... d [12/Oct/2012:15:32:30 +0000] [CGI] Output first part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 803... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 804 on character ':'... d [12/Oct/2012:15:32:30 +0000] write_pipe(con=0x7f798af55b20(14)) CGI output on fd 16 d [12/Oct/2012:15:32:30 +0000] cupsdRemoveSelect(fd=16) d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=14, read_cb=(nil), write_cb=0x7f79898be2a0, data=0x7f798af55b20) d [12/Oct/2012:15:32:30 +0000] cupsdWriteClient(con=0x7f798af55b20(14)) response=(nil)(-1), file=16 pipe_pid=3007 state=7 d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=16, read_cb=0x7f79898bcaf0, write_cb=(nil), data=0x7f798af55b20) d [12/Oct/2012:15:32:30 +0000] cupsdWriteClient(con=0x7f798af55b20(14)) response=(nil)(-1), file=16 pipe_pid=3007 state=7 d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=16, read_cb=0x7f79898bcaf0, write_cb=(nil), data=0x7f798af55b20) d [12/Oct/2012:15:32:30 +0000] cupsdRemoveSelect(fd=14) d [12/Oct/2012:15:32:30 +0000] [CGI] Skip second part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 804... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 807 on character '}'... d [12/Oct/2012:15:32:30 +0000] [CGI] Finished "{SECTION=admin", out=0x7f71642ff780... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting "{SECTION=classes" at 905, result=0... d [12/Oct/2012:15:32:30 +0000] [CGI] Skip first part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 905... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 906 on character ':'... d [12/Oct/2012:15:32:30 +0000] [CGI] Output second part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 906... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 909 on character '}'... d [12/Oct/2012:15:32:30 +0000] [CGI] Finished "{SECTION=classes", out=0x7f71642ff780... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting "{SECTION=help" at 1000, result=0... d [12/Oct/2012:15:32:30 +0000] [CGI] Skip first part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1000... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 1001 on character ':'... d [12/Oct/2012:15:32:30 +0000] [CGI] Output second part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1001... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 1004 on character '}'... d [12/Oct/2012:15:32:30 +0000] [CGI] Finished "{SECTION=help", out=0x7f71642ff780... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting "{SECTION=jobs" at 1101, result=0... d [12/Oct/2012:15:32:30 +0000] [CGI] Skip first part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1101... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 1102 on character ':'... d [12/Oct/2012:15:32:30 +0000] [CGI] Output second part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1102... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 1105 on character '}'... d [12/Oct/2012:15:32:30 +0000] [CGI] Finished "{SECTION=jobs", out=0x7f71642ff780... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting "{SECTION=printers" at 1194, result=0... d [12/Oct/2012:15:32:30 +0000] [CGI] Skip first part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1194... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 1195 on character ':'... d [12/Oct/2012:15:32:30 +0000] [CGI] Output second part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1195... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 1198 on character '}'... d [12/Oct/2012:15:32:30 +0000] [CGI] Finished "{SECTION=printers", out=0x7f71642ff780... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting "{SECTION=help" at 1423, result=0... d [12/Oct/2012:15:32:30 +0000] [CGI] Skip first part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1423... d [12/Oct/2012:15:32:30 +0000] [CGI] "{?QUERY}" at 1431... d [12/Oct/2012:15:32:30 +0000] [CGI] Returning at file position 1432 on character ':'... d [12/Oct/2012:15:32:30 +0000] [CGI] Output second part... d [12/Oct/2012:15:32:30 +0000] [CGI] Starting at file position 1432... d [12/Oct/2012:15:32:30 +0000] write_pipe(con=0x7f798af55b20(14)) CGI output on fd 16 d [12/Oct/2012:15:32:30 +0000] cupsdRemoveSelect(fd=16) d [12/Oct/2012:15:32:30 +0000] cupsdAddSelect(fd=14, read_cb=(nil), write_cb=0x7f79898be2a0, data=0x7f798af55b20) d [12/Oct/2012:15:32:30 +0000] process_children() d [12/Oct/2012:15:32:30 +0000] cupsdFinishProcess(pid=3007, name=0x7fffcee9e410, namelen=1024, job_id=0x7fffcee9e008(0)) = "/usr/lib/cups/cgi-bin/admin.cgi" d [12/Oct/2012:15:32:30 +0000] cupsdDeleteCert: Removing certificate for PID 3007 D [12/Oct/2012:15:32:30 +0000] PID 3007 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.