E [02/Sep/2018:14:57:54 -0300] [Client 9] Returning HTTP Proibido for CUPS-Get-Devices (no URI) from localhost E [02/Sep/2018:14:58:00 -0300] [Client 15] Returning HTTP Proibido for CUPS-Get-Devices (no URI) from localhost E [02/Sep/2018:14:58:03 -0300] [Client 21] Returning HTTP Proibido for CUPS-Get-Devices (no URI) from localhost E [02/Sep/2018:14:58:13 -0300] [cups-deviced] PID 17575 (cups-brf) stopped with status 13! E [02/Sep/2018:14:58:35 -0300] [cups-deviced] PID 17601 (cups-brf) stopped with status 13! E [02/Sep/2018:17:45:19 -0300] [Client 8] Returning HTTP Proibido for CUPS-Get-Devices (no URI) from localhost E [02/Sep/2018:17:45:25 -0300] [cups-deviced] PID 932 (cups-brf) stopped with status 13! E [02/Sep/2018:17:46:11 -0300] [Job 1] Job stopped due to filter errors; please consult the error_log file for details. D [02/Sep/2018:17:46:11 -0300] [Job 1] The following messages were recorded from 17:45:47 to 17:46:11 D [02/Sep/2018:17:46:11 -0300] [Job 1] Applying default options... D [02/Sep/2018:17:46:11 -0300] [Job 1] Adding start banner page "none". D [02/Sep/2018:17:46:11 -0300] [Job 1] Adding end banner page "none". D [02/Sep/2018:17:46:11 -0300] [Job 1] File of type application/vnd.cups-pdf-banner queued by "dsilva". D [02/Sep/2018:17:46:11 -0300] [Job 1] hold_until=0 D [02/Sep/2018:17:46:11 -0300] [Job 1] Queued on "HP-DeskJet-3700-series" by "dsilva". D [02/Sep/2018:17:46:11 -0300] [Job 1] time-at-processing=1535921147 D [02/Sep/2018:17:46:11 -0300] [Job 1] 4 filters for job: D [02/Sep/2018:17:46:11 -0300] [Job 1] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [02/Sep/2018:17:46:11 -0300] [Job 1] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [02/Sep/2018:17:46:11 -0300] [Job 1] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99) D [02/Sep/2018:17:46:11 -0300] [Job 1] hpcups (application/vnd.cups-raster to printer/HP-DeskJet-3700-series, cost 0) D [02/Sep/2018:17:46:11 -0300] [Job 1] job-sheets=none,none D [02/Sep/2018:17:46:11 -0300] [Job 1] argv[0]="HP-DeskJet-3700-series" D [02/Sep/2018:17:46:11 -0300] [Job 1] argv[1]="1" D [02/Sep/2018:17:46:11 -0300] [Job 1] argv[2]="dsilva" D [02/Sep/2018:17:46:11 -0300] [Job 1] argv[3]="Test Page" D [02/Sep/2018:17:46:11 -0300] [Job 1] argv[4]="1" D [02/Sep/2018:17:46:11 -0300] [Job 1] argv[5]="job-uuid=urn:uuid:274257e4-868a-3438-7326-51410cf2f95d job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1535921147 time-at-processing=1535921147" D [02/Sep/2018:17:46:11 -0300] [Job 1] argv[6]="/var/spool/cups/d00001-001" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[1]="CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[6]="CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[7]="CUPS_STATEDIR=/run/cups" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[8]="HOME=/var/spool/cups/tmp" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[10]="SERVER_ADMIN=root@alq14" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[11]="SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[12]="TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[13]="USER=root" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[14]="CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[16]="CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[17]="IPP_PORT=631" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[18]="CHARSET=utf-8" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[19]="LANG=pt_BR.UTF-8" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[20]="PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[21]="RIP_MAX_CACHE=128m" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[23]="DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[24]="PRINTER_INFO=HP DeskJet 3700 series" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[25]="PRINTER_LOCATION=" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[26]="PRINTER=HP-DeskJet-3700-series" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[27]="PRINTER_STATE_REASONS=none" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[28]="CUPS_FILETYPE=document" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[30]="AUTH_I****" D [02/Sep/2018:17:46:11 -0300] [Job 1] Started filter /usr/lib/cups/filter/bannertopdf (PID 973) D [02/Sep/2018:17:46:11 -0300] [Job 1] Started filter /usr/lib/cups/filter/pdftopdf (PID 974) D [02/Sep/2018:17:46:11 -0300] [Job 1] Started filter /usr/lib/cups/filter/gstoraster (PID 975) D [02/Sep/2018:17:46:11 -0300] [Job 1] Started filter /usr/lib/cups/filter/hpcups (PID 976) D [02/Sep/2018:17:46:11 -0300] [Job 1] Started backend /usr/lib/cups/backend/hp (PID 977) D [02/Sep/2018:17:46:11 -0300] [Job 1] OUTFORMAT=\"(null)\", so output format will be CUPS/PWG Raster D [02/Sep/2018:17:46:11 -0300] [Job 1] PDF template file doesn\'t have form. It\'s okay. D [02/Sep/2018:17:46:11 -0300] [Job 1] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log. D [02/Sep/2018:17:46:11 -0300] [Job 1] PID 973 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [02/Sep/2018:17:46:11 -0300] [Job 1] Color Manager: Calibration Mode/Off D [02/Sep/2018:17:46:11 -0300] [Job 1] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:46:11 -0300] [Job 1] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:46:11 -0300] [Job 1] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D [02/Sep/2018:17:46:11 -0300] [Job 1] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:46:11 -0300] [Job 1] PID 974 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [02/Sep/2018:17:46:11 -0300] [Job 1] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:46:11 -0300] [Job 1] Calling GetProfileForQualifiers(RGB.Plain....) D [02/Sep/2018:17:46:11 -0300] [Job 1] Found profile /org/freedesktop/ColorManager/profiles/HP_DeskJet_3700_series_RGB__ D [02/Sep/2018:17:46:11 -0300] [Job 1] Calling org.freedesktop.ColorManager.Profile.Get(Filename) D [02/Sep/2018:17:46:11 -0300] [Job 1] Use profile filename: \'\' D [02/Sep/2018:17:46:11 -0300] [Job 1] Color Manager: ICC Profile: D [02/Sep/2018:17:46:11 -0300] [Job 1] Ghostscript using Any-Part-of-Pixel method to fill paths. D [02/Sep/2018:17:46:11 -0300] [Job 1] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c \'<>setpagedevice\' -f -_ D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[1]=\"CUPS_DATADIR=/usr/share/cups\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[6]=\"CUPS_SERVERROOT=/etc/cups\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[7]=\"CUPS_STATEDIR=/run/cups\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[8]=\"HOME=/var/spool/cups/tmp\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[10]=\"SERVER_ADMIN=root@alq14\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[11]=\"SOFTWARE=CUPS/2.2.8\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[12]=\"TMPDIR=/var/spool/cups/tmp\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[13]=\"USER=root\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[14]=\"CUPS_MAX_MESSAGE=2047\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[16]=\"CUPS_ENCRYPTION=IfRequested\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[17]=\"IPP_PORT=631\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[18]=\"CHARSET=utf-8\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[19]=\"LANG=pt_BR.UTF-8\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[20]=\"PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[21]=\"RIP_MAX_CACHE=128m\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[22]=\"CONTENT_TYPE=application/vnd.cups-pdf-banner\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[23]=\"DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[24]=\"PRINTER_INFO=HP DeskJet 3700 series\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[25]=\"PRINTER_LOCATION=\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[26]=\"PRINTER=HP-DeskJet-3700-series\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[27]=\"PRINTER_STATE_REASONS=none\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[28]=\"CUPS_FILETYPE=document\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\" D [02/Sep/2018:17:46:11 -0300] [Job 1] envp[30]=\"AUTH_INFO_REQUIRED=none\" D [02/Sep/2018:17:46:11 -0300] [Job 1] Start rendering... D [02/Sep/2018:17:46:11 -0300] [Job 1] Set job-printer-state-message to "Start rendering...", current level=INFO D [02/Sep/2018:17:46:11 -0300] [Job 1] Processing page 1... D [02/Sep/2018:17:46:11 -0300] [Job 1] Set job-printer-state-message to "Processing page 1...", current level=INFO D [02/Sep/2018:17:46:11 -0300] [Job 1] PAGE: 1 1\r D [02/Sep/2018:17:46:11 -0300] [Job 1] STATE: +connecting-to-device D [02/Sep/2018:17:46:11 -0300] [Job 1] Created directory: /var/net-snmp D [02/Sep/2018:17:46:11 -0300] [Job 1] Created directory: /var/net-snmp/mib_indexes D [02/Sep/2018:17:46:11 -0300] [Job 1] STATE: -connecting-to-device D [02/Sep/2018:17:46:11 -0300] [Job 1] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [02/Sep/2018:17:46:11 -0300] [Job 1] Processing page 2... D [02/Sep/2018:17:46:11 -0300] [Job 1] Set job-printer-state-message to "Processing page 2...", current level=INFO D [02/Sep/2018:17:46:11 -0300] [Job 1] Error: /invalidfileaccess in --run-- D [02/Sep/2018:17:46:11 -0300] [Job 1] Operand stack: D [02/Sep/2018:17:46:11 -0300] [Job 1] true (/var/spool/cups/tmp/gs_LGCily) D [02/Sep/2018:17:46:11 -0300] [Job 1] Execution stack: D [02/Sep/2018:17:46:11 -0300] [Job 1] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 2015 2 3 %oparray_pop 2014 2 3 %oparray_pop 1998 2 3 %oparray_pop D [02/Sep/2018:17:46:11 -0300] [Job 1] Dictionary stack: D [02/Sep/2018:17:46:11 -0300] [Job 1] --dict:998/1684(ro)(G)-- --dict:1/20(G)-- --dict:78/200(L)-- D [02/Sep/2018:17:46:11 -0300] [Job 1] Current allocation mode is local D [02/Sep/2018:17:46:11 -0300] [Job 1] GPL Ghostscript 9.23: Unrecoverable error, exit code 1 D [02/Sep/2018:17:46:11 -0300] [Job 1] Rendering completed D [02/Sep/2018:17:46:11 -0300] [Job 1] Set job-printer-state-message to "Rendering completed", current level=INFO D [02/Sep/2018:17:46:11 -0300] [Job 1] PID 975 (/usr/lib/cups/filter/gstoraster) stopped with status 1. D [02/Sep/2018:17:46:11 -0300] [Job 1] Hint: Try setting the LogLevel to "debug" to find out more. D [02/Sep/2018:17:46:11 -0300] [Job 1] PID 976 (/usr/lib/cups/filter/hpcups) exited with no errors. D [02/Sep/2018:17:46:11 -0300] [Job 1] ready to print D [02/Sep/2018:17:46:11 -0300] [Job 1] PID 977 (/usr/lib/cups/backend/hp) exited with no errors. D [02/Sep/2018:17:46:11 -0300] [Job 1] End of messages D [02/Sep/2018:17:46:11 -0300] [Job 1] printer-state=3(idle) D [02/Sep/2018:17:46:11 -0300] [Job 1] printer-state-message="ready to print" D [02/Sep/2018:17:46:11 -0300] [Job 1] printer-state-reasons=none E [02/Sep/2018:17:49:02 -0300] [Job 2] Job stopped due to filter errors; please consult the error_log file for details. D [02/Sep/2018:17:49:02 -0300] [Job 2] The following messages were recorded from 17:48:38 to 17:49:02 D [02/Sep/2018:17:49:02 -0300] [Job 2] Applying default options... D [02/Sep/2018:17:49:02 -0300] [Job 2] Adding start banner page "none". D [02/Sep/2018:17:49:02 -0300] [Job 2] Adding end banner page "none". D [02/Sep/2018:17:49:02 -0300] [Job 2] File of type application/vnd.cups-pdf-banner queued by "dsilva". D [02/Sep/2018:17:49:02 -0300] [Job 2] hold_until=0 D [02/Sep/2018:17:49:02 -0300] [Job 2] Queued on "HP-DeskJet-3700-series" by "dsilva". D [02/Sep/2018:17:49:02 -0300] [Job 2] time-at-processing=1535921318 D [02/Sep/2018:17:49:02 -0300] [Job 2] 4 filters for job: D [02/Sep/2018:17:49:02 -0300] [Job 2] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [02/Sep/2018:17:49:02 -0300] [Job 2] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [02/Sep/2018:17:49:02 -0300] [Job 2] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99) D [02/Sep/2018:17:49:02 -0300] [Job 2] hpcups (application/vnd.cups-raster to printer/HP-DeskJet-3700-series, cost 0) D [02/Sep/2018:17:49:02 -0300] [Job 2] job-sheets=none,none D [02/Sep/2018:17:49:02 -0300] [Job 2] argv[0]="HP-DeskJet-3700-series" D [02/Sep/2018:17:49:02 -0300] [Job 2] argv[1]="2" D [02/Sep/2018:17:49:02 -0300] [Job 2] argv[2]="dsilva" D [02/Sep/2018:17:49:02 -0300] [Job 2] argv[3]="Test Page" D [02/Sep/2018:17:49:02 -0300] [Job 2] argv[4]="1" D [02/Sep/2018:17:49:02 -0300] [Job 2] argv[5]="job-uuid=urn:uuid:83be6067-01c5-38a2-62aa-add865c8876e job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1535921318 time-at-processing=1535921318" D [02/Sep/2018:17:49:02 -0300] [Job 2] argv[6]="/var/spool/cups/d00002-001" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[1]="CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[6]="CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[7]="CUPS_STATEDIR=/run/cups" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[8]="HOME=/var/spool/cups/tmp" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[10]="SERVER_ADMIN=root@alq14" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[11]="SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[12]="TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[13]="USER=root" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[14]="CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[16]="CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[17]="IPP_PORT=631" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[18]="CHARSET=utf-8" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[19]="LANG=pt_BR.UTF-8" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[20]="PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[21]="RIP_MAX_CACHE=128m" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[23]="DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[24]="PRINTER_INFO=HP DeskJet 3700 series" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[25]="PRINTER_LOCATION=" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[26]="PRINTER=HP-DeskJet-3700-series" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[27]="PRINTER_STATE_REASONS=none" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[28]="CUPS_FILETYPE=document" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[30]="AUTH_I****" D [02/Sep/2018:17:49:02 -0300] [Job 2] Started filter /usr/lib/cups/filter/bannertopdf (PID 1278) D [02/Sep/2018:17:49:02 -0300] [Job 2] Started filter /usr/lib/cups/filter/pdftopdf (PID 1279) D [02/Sep/2018:17:49:02 -0300] [Job 2] Started filter /usr/lib/cups/filter/gstoraster (PID 1280) D [02/Sep/2018:17:49:02 -0300] [Job 2] Started filter /usr/lib/cups/filter/hpcups (PID 1281) D [02/Sep/2018:17:49:02 -0300] [Job 2] Started backend /usr/lib/cups/backend/hp (PID 1282) D [02/Sep/2018:17:49:02 -0300] [Job 2] PDF template file doesn\'t have form. It\'s okay. D [02/Sep/2018:17:49:02 -0300] [Job 2] OUTFORMAT=\"(null)\", so output format will be CUPS/PWG Raster D [02/Sep/2018:17:49:02 -0300] [Job 2] PID 1278 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [02/Sep/2018:17:49:02 -0300] [Job 2] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log. D [02/Sep/2018:17:49:02 -0300] [Job 2] PID 1279 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [02/Sep/2018:17:49:02 -0300] [Job 2] Color Manager: Calibration Mode/Off D [02/Sep/2018:17:49:02 -0300] [Job 2] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:49:02 -0300] [Job 2] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:49:02 -0300] [Job 2] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D [02/Sep/2018:17:49:02 -0300] [Job 2] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:49:02 -0300] [Job 2] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:49:02 -0300] [Job 2] Calling GetProfileForQualifiers(RGB.Plain....) D [02/Sep/2018:17:49:02 -0300] [Job 2] Found profile /org/freedesktop/ColorManager/profiles/HP_DeskJet_3700_series_RGB__ D [02/Sep/2018:17:49:02 -0300] [Job 2] Calling org.freedesktop.ColorManager.Profile.Get(Filename) D [02/Sep/2018:17:49:02 -0300] [Job 2] Use profile filename: \'\' D [02/Sep/2018:17:49:02 -0300] [Job 2] Color Manager: ICC Profile: D [02/Sep/2018:17:49:02 -0300] [Job 2] Ghostscript using Any-Part-of-Pixel method to fill paths. D [02/Sep/2018:17:49:02 -0300] [Job 2] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c \'<>setpagedevice\' -f -_ D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[1]=\"CUPS_DATADIR=/usr/share/cups\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[6]=\"CUPS_SERVERROOT=/etc/cups\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[7]=\"CUPS_STATEDIR=/run/cups\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[8]=\"HOME=/var/spool/cups/tmp\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[10]=\"SERVER_ADMIN=root@alq14\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[11]=\"SOFTWARE=CUPS/2.2.8\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[12]=\"TMPDIR=/var/spool/cups/tmp\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[13]=\"USER=root\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[14]=\"CUPS_MAX_MESSAGE=2047\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[16]=\"CUPS_ENCRYPTION=IfRequested\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[17]=\"IPP_PORT=631\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[18]=\"CHARSET=utf-8\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[19]=\"LANG=pt_BR.UTF-8\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[20]=\"PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[21]=\"RIP_MAX_CACHE=128m\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[22]=\"CONTENT_TYPE=application/vnd.cups-pdf-banner\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[23]=\"DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[24]=\"PRINTER_INFO=HP DeskJet 3700 series\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[25]=\"PRINTER_LOCATION=\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[26]=\"PRINTER=HP-DeskJet-3700-series\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[27]=\"PRINTER_STATE_REASONS=none\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[28]=\"CUPS_FILETYPE=document\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\" D [02/Sep/2018:17:49:02 -0300] [Job 2] envp[30]=\"AUTH_INFO_REQUIRED=none\" D [02/Sep/2018:17:49:02 -0300] [Job 2] Start rendering... D [02/Sep/2018:17:49:02 -0300] [Job 2] Set job-printer-state-message to "Start rendering...", current level=INFO D [02/Sep/2018:17:49:02 -0300] [Job 2] Processing page 1... D [02/Sep/2018:17:49:02 -0300] [Job 2] Set job-printer-state-message to "Processing page 1...", current level=INFO D [02/Sep/2018:17:49:02 -0300] [Job 2] PAGE: 1 1\r D [02/Sep/2018:17:49:02 -0300] [Job 2] STATE: +connecting-to-device D [02/Sep/2018:17:49:02 -0300] [Job 2] STATE: -connecting-to-device D [02/Sep/2018:17:49:02 -0300] [Job 2] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [02/Sep/2018:17:49:02 -0300] [Job 2] Processing page 2... D [02/Sep/2018:17:49:02 -0300] [Job 2] Set job-printer-state-message to "Processing page 2...", current level=INFO D [02/Sep/2018:17:49:02 -0300] [Job 2] Error: /invalidfileaccess in --run-- D [02/Sep/2018:17:49:02 -0300] [Job 2] Operand stack: D [02/Sep/2018:17:49:02 -0300] [Job 2] true (/var/spool/cups/tmp/gs_EiXuic) D [02/Sep/2018:17:49:02 -0300] [Job 2] Execution stack: D [02/Sep/2018:17:49:02 -0300] [Job 2] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 2015 2 3 %oparray_pop 2014 2 3 %oparray_pop 1998 2 3 %oparray_pop D [02/Sep/2018:17:49:02 -0300] [Job 2] Dictionary stack: D [02/Sep/2018:17:49:02 -0300] [Job 2] --dict:998/1684(ro)(G)-- --dict:1/20(G)-- --dict:78/200(L)-- D [02/Sep/2018:17:49:02 -0300] [Job 2] Current allocation mode is local D [02/Sep/2018:17:49:02 -0300] [Job 2] GPL Ghostscript 9.23: Unrecoverable error, exit code 1 D [02/Sep/2018:17:49:02 -0300] [Job 2] Rendering completed D [02/Sep/2018:17:49:02 -0300] [Job 2] Set job-printer-state-message to "Rendering completed", current level=INFO D [02/Sep/2018:17:49:02 -0300] [Job 2] PID 1280 (/usr/lib/cups/filter/gstoraster) stopped with status 1. D [02/Sep/2018:17:49:02 -0300] [Job 2] Hint: Try setting the LogLevel to "debug" to find out more. D [02/Sep/2018:17:49:02 -0300] [Job 2] PID 1281 (/usr/lib/cups/filter/hpcups) exited with no errors. D [02/Sep/2018:17:49:02 -0300] [Job 2] ready to print D [02/Sep/2018:17:49:02 -0300] [Job 2] PID 1282 (/usr/lib/cups/backend/hp) exited with no errors. D [02/Sep/2018:17:49:02 -0300] [Job 2] End of messages D [02/Sep/2018:17:49:02 -0300] [Job 2] printer-state=3(idle) D [02/Sep/2018:17:49:02 -0300] [Job 2] printer-state-message="ready to print" D [02/Sep/2018:17:49:02 -0300] [Job 2] printer-state-reasons=none E [02/Sep/2018:17:49:53 -0300] [cups-deviced] PID 1342 (cups-brf) stopped with status 13! E [02/Sep/2018:17:50:39 -0300] [Job 3] Job stopped due to filter errors; please consult the error_log file for details. D [02/Sep/2018:17:50:39 -0300] [Job 3] The following messages were recorded from 17:50:16 to 17:50:39 D [02/Sep/2018:17:50:39 -0300] [Job 3] Applying default options... D [02/Sep/2018:17:50:39 -0300] [Job 3] Adding start banner page "none". D [02/Sep/2018:17:50:39 -0300] [Job 3] Adding end banner page "none". D [02/Sep/2018:17:50:39 -0300] [Job 3] File of type application/vnd.cups-pdf-banner queued by "dsilva". D [02/Sep/2018:17:50:39 -0300] [Job 3] hold_until=0 D [02/Sep/2018:17:50:39 -0300] [Job 3] Queued on "HP-DeskJet-3700-series" by "dsilva". D [02/Sep/2018:17:50:39 -0300] [Job 3] time-at-processing=1535921416 D [02/Sep/2018:17:50:39 -0300] [Job 3] 4 filters for job: D [02/Sep/2018:17:50:39 -0300] [Job 3] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [02/Sep/2018:17:50:39 -0300] [Job 3] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [02/Sep/2018:17:50:39 -0300] [Job 3] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99) D [02/Sep/2018:17:50:39 -0300] [Job 3] hpcups (application/vnd.cups-raster to printer/HP-DeskJet-3700-series, cost 0) D [02/Sep/2018:17:50:39 -0300] [Job 3] job-sheets=none,none D [02/Sep/2018:17:50:39 -0300] [Job 3] argv[0]="HP-DeskJet-3700-series" D [02/Sep/2018:17:50:39 -0300] [Job 3] argv[1]="3" D [02/Sep/2018:17:50:39 -0300] [Job 3] argv[2]="dsilva" D [02/Sep/2018:17:50:39 -0300] [Job 3] argv[3]="Test Page" D [02/Sep/2018:17:50:39 -0300] [Job 3] argv[4]="1" D [02/Sep/2018:17:50:39 -0300] [Job 3] argv[5]="job-uuid=urn:uuid:e23dd6c5-015d-3417-45a8-8d2ee5d38fb7 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1535921416 time-at-processing=1535921416" D [02/Sep/2018:17:50:39 -0300] [Job 3] argv[6]="/var/spool/cups/d00003-001" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[1]="CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[6]="CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[7]="CUPS_STATEDIR=/run/cups" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[8]="HOME=/var/spool/cups/tmp" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[10]="SERVER_ADMIN=root@alq14" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[11]="SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[12]="TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[13]="USER=root" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[14]="CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[16]="CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[17]="IPP_PORT=631" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[18]="CHARSET=utf-8" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[19]="LANG=pt_BR.UTF-8" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[20]="PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[21]="RIP_MAX_CACHE=128m" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[23]="DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[24]="PRINTER_INFO=HP DeskJet 3700 series" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[25]="PRINTER_LOCATION=" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[26]="PRINTER=HP-DeskJet-3700-series" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[27]="PRINTER_STATE_REASONS=none" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[28]="CUPS_FILETYPE=document" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[30]="AUTH_I****" D [02/Sep/2018:17:50:39 -0300] [Job 3] Started filter /usr/lib/cups/filter/bannertopdf (PID 1373) D [02/Sep/2018:17:50:39 -0300] [Job 3] Started filter /usr/lib/cups/filter/pdftopdf (PID 1374) D [02/Sep/2018:17:50:39 -0300] [Job 3] Started filter /usr/lib/cups/filter/gstoraster (PID 1375) D [02/Sep/2018:17:50:39 -0300] [Job 3] Started filter /usr/lib/cups/filter/hpcups (PID 1376) D [02/Sep/2018:17:50:39 -0300] [Job 3] Started backend /usr/lib/cups/backend/hp (PID 1377) D [02/Sep/2018:17:50:39 -0300] [Job 3] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log. D [02/Sep/2018:17:50:39 -0300] [Job 3] OUTFORMAT=\"(null)\", so output format will be CUPS/PWG Raster D [02/Sep/2018:17:50:39 -0300] [Job 3] PDF template file doesn\'t have form. It\'s okay. D [02/Sep/2018:17:50:39 -0300] [Job 3] PID 1373 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [02/Sep/2018:17:50:39 -0300] [Job 3] Color Manager: Calibration Mode/Off D [02/Sep/2018:17:50:39 -0300] [Job 3] PID 1374 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [02/Sep/2018:17:50:39 -0300] [Job 3] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:50:39 -0300] [Job 3] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:50:39 -0300] [Job 3] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D [02/Sep/2018:17:50:39 -0300] [Job 3] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:50:39 -0300] [Job 3] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:50:39 -0300] [Job 3] Calling GetProfileForQualifiers(RGB.Plain....) D [02/Sep/2018:17:50:39 -0300] [Job 3] Found profile /org/freedesktop/ColorManager/profiles/HP_DeskJet_3700_series_RGB__ D [02/Sep/2018:17:50:39 -0300] [Job 3] Calling org.freedesktop.ColorManager.Profile.Get(Filename) D [02/Sep/2018:17:50:39 -0300] [Job 3] Use profile filename: \'\' D [02/Sep/2018:17:50:39 -0300] [Job 3] Color Manager: ICC Profile: D [02/Sep/2018:17:50:39 -0300] [Job 3] Ghostscript using Any-Part-of-Pixel method to fill paths. D [02/Sep/2018:17:50:39 -0300] [Job 3] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c \'<>setpagedevice\' -f -_ D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[1]=\"CUPS_DATADIR=/usr/share/cups\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[6]=\"CUPS_SERVERROOT=/etc/cups\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[7]=\"CUPS_STATEDIR=/run/cups\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[8]=\"HOME=/var/spool/cups/tmp\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[10]=\"SERVER_ADMIN=root@alq14\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[11]=\"SOFTWARE=CUPS/2.2.8\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[12]=\"TMPDIR=/var/spool/cups/tmp\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[13]=\"USER=root\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[14]=\"CUPS_MAX_MESSAGE=2047\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[16]=\"CUPS_ENCRYPTION=IfRequested\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[17]=\"IPP_PORT=631\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[18]=\"CHARSET=utf-8\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[19]=\"LANG=pt_BR.UTF-8\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[20]=\"PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[21]=\"RIP_MAX_CACHE=128m\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[22]=\"CONTENT_TYPE=application/vnd.cups-pdf-banner\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[23]=\"DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[24]=\"PRINTER_INFO=HP DeskJet 3700 series\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[25]=\"PRINTER_LOCATION=\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[26]=\"PRINTER=HP-DeskJet-3700-series\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[27]=\"PRINTER_STATE_REASONS=none\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[28]=\"CUPS_FILETYPE=document\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\" D [02/Sep/2018:17:50:39 -0300] [Job 3] envp[30]=\"AUTH_INFO_REQUIRED=none\" D [02/Sep/2018:17:50:39 -0300] [Job 3] Start rendering... D [02/Sep/2018:17:50:39 -0300] [Job 3] Set job-printer-state-message to "Start rendering...", current level=INFO D [02/Sep/2018:17:50:39 -0300] [Job 3] Processing page 1... D [02/Sep/2018:17:50:39 -0300] [Job 3] Set job-printer-state-message to "Processing page 1...", current level=INFO D [02/Sep/2018:17:50:39 -0300] [Job 3] PAGE: 1 1\r D [02/Sep/2018:17:50:39 -0300] [Job 3] STATE: +connecting-to-device D [02/Sep/2018:17:50:39 -0300] [Job 3] STATE: -connecting-to-device D [02/Sep/2018:17:50:39 -0300] [Job 3] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [02/Sep/2018:17:50:39 -0300] [Job 3] Processing page 2... D [02/Sep/2018:17:50:39 -0300] [Job 3] Set job-printer-state-message to "Processing page 2...", current level=INFO D [02/Sep/2018:17:50:39 -0300] [Job 3] Error: /invalidfileaccess in --run-- D [02/Sep/2018:17:50:39 -0300] [Job 3] Operand stack: D [02/Sep/2018:17:50:39 -0300] [Job 3] true (/var/spool/cups/tmp/gs_o0OSHQ) D [02/Sep/2018:17:50:39 -0300] [Job 3] Execution stack: D [02/Sep/2018:17:50:39 -0300] [Job 3] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 2015 2 3 %oparray_pop 2014 2 3 %oparray_pop 1998 2 3 %oparray_pop D [02/Sep/2018:17:50:39 -0300] [Job 3] Dictionary stack: D [02/Sep/2018:17:50:39 -0300] [Job 3] --dict:998/1684(ro)(G)-- --dict:1/20(G)-- --dict:78/200(L)-- D [02/Sep/2018:17:50:39 -0300] [Job 3] Current allocation mode is local D [02/Sep/2018:17:50:39 -0300] [Job 3] GPL Ghostscript 9.23: Unrecoverable error, exit code 1 D [02/Sep/2018:17:50:39 -0300] [Job 3] Rendering completed D [02/Sep/2018:17:50:39 -0300] [Job 3] Set job-printer-state-message to "Rendering completed", current level=INFO D [02/Sep/2018:17:50:39 -0300] [Job 3] PID 1375 (/usr/lib/cups/filter/gstoraster) stopped with status 1. D [02/Sep/2018:17:50:39 -0300] [Job 3] Hint: Try setting the LogLevel to "debug" to find out more. D [02/Sep/2018:17:50:39 -0300] [Job 3] PID 1376 (/usr/lib/cups/filter/hpcups) exited with no errors. D [02/Sep/2018:17:50:39 -0300] [Job 3] ready to print D [02/Sep/2018:17:50:39 -0300] [Job 3] PID 1377 (/usr/lib/cups/backend/hp) exited with no errors. D [02/Sep/2018:17:50:39 -0300] [Job 3] End of messages D [02/Sep/2018:17:50:39 -0300] [Job 3] printer-state=3(idle) D [02/Sep/2018:17:50:39 -0300] [Job 3] printer-state-message="ready to print" D [02/Sep/2018:17:50:39 -0300] [Job 3] printer-state-reasons=none E [02/Sep/2018:17:56:37 -0300] [cups-deviced] PID 895 (cups-brf) stopped with status 13! W [02/Sep/2018:17:56:48 -0300] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'HP-DeskJet-3700-series-Gray..\' already exists W [02/Sep/2018:17:56:48 -0300] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id \'HP-DeskJet-3700-series-RGB..\' already exists E [02/Sep/2018:17:57:13 -0300] [Job 4] Job stopped due to filter errors; please consult the error_log file for details. D [02/Sep/2018:17:57:13 -0300] [Job 4] The following messages were recorded from 17:56:50 to 17:57:13 D [02/Sep/2018:17:57:13 -0300] [Job 4] Applying default options... D [02/Sep/2018:17:57:13 -0300] [Job 4] Adding start banner page "none". D [02/Sep/2018:17:57:13 -0300] [Job 4] Adding end banner page "none". D [02/Sep/2018:17:57:13 -0300] [Job 4] File of type application/vnd.cups-pdf-banner queued by "dsilva". D [02/Sep/2018:17:57:13 -0300] [Job 4] hold_until=0 D [02/Sep/2018:17:57:13 -0300] [Job 4] Queued on "HP-DeskJet-3700-series" by "dsilva". D [02/Sep/2018:17:57:13 -0300] [Job 4] time-at-processing=1535921810 D [02/Sep/2018:17:57:13 -0300] [Job 4] 4 filters for job: D [02/Sep/2018:17:57:13 -0300] [Job 4] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32) D [02/Sep/2018:17:57:13 -0300] [Job 4] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [02/Sep/2018:17:57:13 -0300] [Job 4] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99) D [02/Sep/2018:17:57:13 -0300] [Job 4] hpcups (application/vnd.cups-raster to printer/HP-DeskJet-3700-series, cost 0) D [02/Sep/2018:17:57:13 -0300] [Job 4] job-sheets=none,none D [02/Sep/2018:17:57:13 -0300] [Job 4] argv[0]="HP-DeskJet-3700-series" D [02/Sep/2018:17:57:13 -0300] [Job 4] argv[1]="4" D [02/Sep/2018:17:57:13 -0300] [Job 4] argv[2]="dsilva" D [02/Sep/2018:17:57:13 -0300] [Job 4] argv[3]="Test Page" D [02/Sep/2018:17:57:13 -0300] [Job 4] argv[4]="1" D [02/Sep/2018:17:57:13 -0300] [Job 4] argv[5]="job-uuid=urn:uuid:bc3855c9-80e5-37cf-6d92-41db9055b93b job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1535921810 time-at-processing=1535921810" D [02/Sep/2018:17:57:13 -0300] [Job 4] argv[6]="/var/spool/cups/d00004-001" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[1]="CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[6]="CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[7]="CUPS_STATEDIR=/run/cups" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[8]="HOME=/var/spool/cups/tmp" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[10]="SERVER_ADMIN=root@alq14" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[11]="SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[12]="TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[13]="USER=root" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[14]="CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[16]="CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[17]="IPP_PORT=631" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[18]="CHARSET=utf-8" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[19]="LANG=pt_BR.UTF-8" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[20]="PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[21]="RIP_MAX_CACHE=128m" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[23]="DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[24]="PRINTER_INFO=HP DeskJet 3700 series" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[25]="PRINTER_LOCATION=" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[26]="PRINTER=HP-DeskJet-3700-series" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[27]="PRINTER_STATE_REASONS=none" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[28]="CUPS_FILETYPE=document" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[30]="AUTH_I****" D [02/Sep/2018:17:57:13 -0300] [Job 4] Started filter /usr/lib/cups/filter/bannertopdf (PID 926) D [02/Sep/2018:17:57:13 -0300] [Job 4] Started filter /usr/lib/cups/filter/pdftopdf (PID 927) D [02/Sep/2018:17:57:13 -0300] [Job 4] Started filter /usr/lib/cups/filter/gstoraster (PID 928) D [02/Sep/2018:17:57:13 -0300] [Job 4] Started filter /usr/lib/cups/filter/hpcups (PID 929) D [02/Sep/2018:17:57:13 -0300] [Job 4] Started backend /usr/lib/cups/backend/hp (PID 930) D [02/Sep/2018:17:57:13 -0300] [Job 4] OUTFORMAT=\"(null)\", so output format will be CUPS/PWG Raster D [02/Sep/2018:17:57:13 -0300] [Job 4] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log. D [02/Sep/2018:17:57:13 -0300] [Job 4] PDF template file doesn\'t have form. It\'s okay. D [02/Sep/2018:17:57:13 -0300] [Job 4] PID 926 (/usr/lib/cups/filter/bannertopdf) exited with no errors. D [02/Sep/2018:17:57:13 -0300] [Job 4] Color Manager: Calibration Mode/Off D [02/Sep/2018:17:57:13 -0300] [Job 4] PID 927 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [02/Sep/2018:17:57:13 -0300] [Job 4] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:57:13 -0300] [Job 4] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:57:13 -0300] [Job 4] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D [02/Sep/2018:17:57:13 -0300] [Job 4] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:57:13 -0300] [Job 4] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:57:13 -0300] [Job 4] Calling GetProfileForQualifiers(RGB.Plain....) D [02/Sep/2018:17:57:13 -0300] [Job 4] Failed to send: org.freedesktop.ColorManager.Device.NothingMatched:nothing matched expression \'RGB.Plain.,RGB.Plain.*,RGB.*.,RGB.*.*,*\' D [02/Sep/2018:17:57:13 -0300] [Job 4] Failed to get profile filename for cups-HP-DeskJet-3700-series D [02/Sep/2018:17:57:13 -0300] [Job 4] Color Manager: no profiles specified in PPD D [02/Sep/2018:17:57:13 -0300] [Job 4] Set job-printer-state-message to "Color Manager: no profiles specified in PPD", current level=INFO D [02/Sep/2018:17:57:13 -0300] [Job 4] Color Manager: ICC Profile: None D [02/Sep/2018:17:57:13 -0300] [Job 4] Ghostscript using Any-Part-of-Pixel method to fill paths. D [02/Sep/2018:17:57:13 -0300] [Job 4] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c \'<>setpagedevice\' -f -_ D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[1]=\"CUPS_DATADIR=/usr/share/cups\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[6]=\"CUPS_SERVERROOT=/etc/cups\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[7]=\"CUPS_STATEDIR=/run/cups\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[8]=\"HOME=/var/spool/cups/tmp\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[10]=\"SERVER_ADMIN=root@alq14\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[11]=\"SOFTWARE=CUPS/2.2.8\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[12]=\"TMPDIR=/var/spool/cups/tmp\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[13]=\"USER=root\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[14]=\"CUPS_MAX_MESSAGE=2047\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[16]=\"CUPS_ENCRYPTION=IfRequested\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[17]=\"IPP_PORT=631\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[18]=\"CHARSET=utf-8\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[19]=\"LANG=pt_BR.UTF-8\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[20]=\"PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[21]=\"RIP_MAX_CACHE=128m\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[22]=\"CONTENT_TYPE=application/vnd.cups-pdf-banner\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[23]=\"DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[24]=\"PRINTER_INFO=HP DeskJet 3700 series\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[25]=\"PRINTER_LOCATION=\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[26]=\"PRINTER=HP-DeskJet-3700-series\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[27]=\"PRINTER_STATE_REASONS=none\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[28]=\"CUPS_FILETYPE=document\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\" D [02/Sep/2018:17:57:13 -0300] [Job 4] envp[30]=\"AUTH_INFO_REQUIRED=none\" D [02/Sep/2018:17:57:13 -0300] [Job 4] Start rendering... D [02/Sep/2018:17:57:13 -0300] [Job 4] Set job-printer-state-message to "Start rendering...", current level=INFO D [02/Sep/2018:17:57:13 -0300] [Job 4] Processing page 1... D [02/Sep/2018:17:57:13 -0300] [Job 4] Set job-printer-state-message to "Processing page 1...", current level=INFO D [02/Sep/2018:17:57:13 -0300] [Job 4] PAGE: 1 1\r D [02/Sep/2018:17:57:13 -0300] [Job 4] STATE: +connecting-to-device D [02/Sep/2018:17:57:13 -0300] [Job 4] STATE: -connecting-to-device D [02/Sep/2018:17:57:13 -0300] [Job 4] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [02/Sep/2018:17:57:13 -0300] [Job 4] Processing page 2... D [02/Sep/2018:17:57:13 -0300] [Job 4] Set job-printer-state-message to "Processing page 2...", current level=INFO D [02/Sep/2018:17:57:13 -0300] [Job 4] Error: /invalidfileaccess in --run-- D [02/Sep/2018:17:57:13 -0300] [Job 4] Operand stack: D [02/Sep/2018:17:57:13 -0300] [Job 4] true (/var/spool/cups/tmp/gs_VHnpl5) D [02/Sep/2018:17:57:13 -0300] [Job 4] Execution stack: D [02/Sep/2018:17:57:13 -0300] [Job 4] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 2015 2 3 %oparray_pop 2014 2 3 %oparray_pop 1998 2 3 %oparray_pop D [02/Sep/2018:17:57:13 -0300] [Job 4] Dictionary stack: D [02/Sep/2018:17:57:13 -0300] [Job 4] --dict:998/1684(ro)(G)-- --dict:1/20(G)-- --dict:78/200(L)-- D [02/Sep/2018:17:57:13 -0300] [Job 4] Current allocation mode is local D [02/Sep/2018:17:57:13 -0300] [Job 4] GPL Ghostscript 9.23: Unrecoverable error, exit code 1 D [02/Sep/2018:17:57:13 -0300] [Job 4] Rendering completed D [02/Sep/2018:17:57:13 -0300] [Job 4] Set job-printer-state-message to "Rendering completed", current level=INFO D [02/Sep/2018:17:57:13 -0300] [Job 4] PID 928 (/usr/lib/cups/filter/gstoraster) stopped with status 1. D [02/Sep/2018:17:57:13 -0300] [Job 4] Hint: Try setting the LogLevel to "debug" to find out more. D [02/Sep/2018:17:57:13 -0300] [Job 4] PID 929 (/usr/lib/cups/filter/hpcups) exited with no errors. D [02/Sep/2018:17:57:13 -0300] [Job 4] ready to print D [02/Sep/2018:17:57:13 -0300] [Job 4] PID 930 (/usr/lib/cups/backend/hp) exited with no errors. D [02/Sep/2018:17:57:13 -0300] [Job 4] End of messages D [02/Sep/2018:17:57:13 -0300] [Job 4] printer-state=3(idle) D [02/Sep/2018:17:57:13 -0300] [Job 4] printer-state-message="ready to print" D [02/Sep/2018:17:57:13 -0300] [Job 4] printer-state-reasons=none E [02/Sep/2018:17:58:09 -0300] [Job 5] Job stopped due to filter errors; please consult the error_log file for details. D [02/Sep/2018:17:58:09 -0300] [Job 5] The following messages were recorded from 17:58:01 to 17:58:09 D [02/Sep/2018:17:58:09 -0300] [Job 5] Applying default options... D [02/Sep/2018:17:58:09 -0300] [Job 5] Adding start banner page "none". D [02/Sep/2018:17:58:09 -0300] [Job 5] Adding end banner page "none". D [02/Sep/2018:17:58:09 -0300] [Job 5] File of type application/pdf queued by "dsilva". D [02/Sep/2018:17:58:09 -0300] [Job 5] hold_until=0 D [02/Sep/2018:17:58:09 -0300] [Job 5] Queued on "HP-DeskJet-3700-series" by "dsilva". D [02/Sep/2018:17:58:09 -0300] [Job 5] time-at-processing=1535921881 D [02/Sep/2018:17:58:09 -0300] [Job 5] 3 filters for job: D [02/Sep/2018:17:58:09 -0300] [Job 5] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [02/Sep/2018:17:58:09 -0300] [Job 5] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99) D [02/Sep/2018:17:58:09 -0300] [Job 5] hpcups (application/vnd.cups-raster to printer/HP-DeskJet-3700-series, cost 0) D [02/Sep/2018:17:58:09 -0300] [Job 5] job-sheets=none,none D [02/Sep/2018:17:58:09 -0300] [Job 5] argv[0]="HP-DeskJet-3700-series" D [02/Sep/2018:17:58:09 -0300] [Job 5] argv[1]="5" D [02/Sep/2018:17:58:09 -0300] [Job 5] argv[2]="dsilva" D [02/Sep/2018:17:58:09 -0300] [Job 5] argv[3]="Imprimir Novo documento 1" D [02/Sep/2018:17:58:09 -0300] [Job 5] argv[4]="1" D [02/Sep/2018:17:58:09 -0300] [Job 5] argv[5]="InputSlot=Auto number-up=1 PageSize=Custom.595.28x841.89 MediaType=Plain OutputMode=Normal ColorModel=RGB job-uuid=urn:uuid:c497ec17-527c-337d-477d-8296607884a5 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1535921881 time-at-processing=1535921881" D [02/Sep/2018:17:58:09 -0300] [Job 5] argv[6]="/var/spool/cups/d00005-001" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[1]="CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[6]="CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[7]="CUPS_STATEDIR=/run/cups" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[8]="HOME=/var/spool/cups/tmp" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[10]="SERVER_ADMIN=root@alq14" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[11]="SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[12]="TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[13]="USER=root" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[14]="CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[16]="CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[17]="IPP_PORT=631" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[18]="CHARSET=utf-8" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[19]="LANG=pt_BR.UTF-8" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[20]="PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[21]="RIP_MAX_CACHE=128m" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[22]="CONTENT_TYPE=application/pdf" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[23]="DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[24]="PRINTER_INFO=HP DeskJet 3700 series" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[25]="PRINTER_LOCATION=" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[26]="PRINTER=HP-DeskJet-3700-series" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[27]="PRINTER_STATE_REASONS=none" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[28]="CUPS_FILETYPE=document" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[30]="AUTH_I****" D [02/Sep/2018:17:58:09 -0300] [Job 5] Started filter /usr/lib/cups/filter/pdftopdf (PID 960) D [02/Sep/2018:17:58:09 -0300] [Job 5] Started filter /usr/lib/cups/filter/gstoraster (PID 961) D [02/Sep/2018:17:58:09 -0300] [Job 5] Started filter /usr/lib/cups/filter/hpcups (PID 962) D [02/Sep/2018:17:58:09 -0300] [Job 5] Started backend /usr/lib/cups/backend/hp (PID 963) D [02/Sep/2018:17:58:09 -0300] [Job 5] pdftopdf: Last filter determined by the PPD: hpcups; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log. D [02/Sep/2018:17:58:09 -0300] [Job 5] OUTFORMAT=\"(null)\", so output format will be CUPS/PWG Raster D [02/Sep/2018:17:58:09 -0300] [Job 5] PID 960 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [02/Sep/2018:17:58:09 -0300] [Job 5] Color Manager: Calibration Mode/Off D [02/Sep/2018:17:58:09 -0300] [Job 5] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:58:09 -0300] [Job 5] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:58:09 -0300] [Job 5] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D [02/Sep/2018:17:58:09 -0300] [Job 5] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:58:09 -0300] [Job 5] Found device /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series D [02/Sep/2018:17:58:09 -0300] [Job 5] Calling GetProfileForQualifiers(RGB.Plain....) D [02/Sep/2018:17:58:09 -0300] [Job 5] Failed to send: org.freedesktop.ColorManager.Device.NothingMatched:nothing matched expression \'RGB.Plain.,RGB.Plain.*,RGB.*.,RGB.*.*,*\' D [02/Sep/2018:17:58:09 -0300] [Job 5] Failed to get profile filename for cups-HP-DeskJet-3700-series D [02/Sep/2018:17:58:09 -0300] [Job 5] Color Manager: no profiles specified in PPD D [02/Sep/2018:17:58:09 -0300] [Job 5] Set job-printer-state-message to "Color Manager: no profiles specified in PPD", current level=INFO D [02/Sep/2018:17:58:09 -0300] [Job 5] Color Manager: ICC Profile: None D [02/Sep/2018:17:58:09 -0300] [Job 5] Ghostscript using Any-Part-of-Pixel method to fill paths. D [02/Sep/2018:17:58:09 -0300] [Job 5] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dShowAcroForm -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=1008 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=101 -scupsPageSizeName=Custom -I/usr/share/cups/fonts -c \'<>setpagedevice\' -f -_ D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[1]=\"CUPS_DATADIR=/usr/share/cups\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[6]=\"CUPS_SERVERROOT=/etc/cups\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[7]=\"CUPS_STATEDIR=/run/cups\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[8]=\"HOME=/var/spool/cups/tmp\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[10]=\"SERVER_ADMIN=root@alq14\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[11]=\"SOFTWARE=CUPS/2.2.8\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[12]=\"TMPDIR=/var/spool/cups/tmp\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[13]=\"USER=root\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[14]=\"CUPS_MAX_MESSAGE=2047\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[16]=\"CUPS_ENCRYPTION=IfRequested\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[17]=\"IPP_PORT=631\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[18]=\"CHARSET=utf-8\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[19]=\"LANG=pt_BR.UTF-8\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[20]=\"PPD=/etc/cups/ppd/HP-DeskJet-3700-series.ppd\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[21]=\"RIP_MAX_CACHE=128m\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[22]=\"CONTENT_TYPE=application/pdf\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[23]=\"DEVICE_URI=hp:/net/DeskJet_3700_series?ip=10.0.0.7\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[24]=\"PRINTER_INFO=HP DeskJet 3700 series\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[25]=\"PRINTER_LOCATION=\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[26]=\"PRINTER=HP-DeskJet-3700-series\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[27]=\"PRINTER_STATE_REASONS=none\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[28]=\"CUPS_FILETYPE=document\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\" D [02/Sep/2018:17:58:09 -0300] [Job 5] envp[30]=\"AUTH_INFO_REQUIRED=none\" D [02/Sep/2018:17:58:09 -0300] [Job 5] Start rendering... D [02/Sep/2018:17:58:09 -0300] [Job 5] Set job-printer-state-message to "Start rendering...", current level=INFO D [02/Sep/2018:17:58:09 -0300] [Job 5] Processing page 1... D [02/Sep/2018:17:58:09 -0300] [Job 5] Set job-printer-state-message to "Processing page 1...", current level=INFO D [02/Sep/2018:17:58:09 -0300] [Job 5] PAGE: 1 1\r D [02/Sep/2018:17:58:09 -0300] [Job 5] STATE: +connecting-to-device D [02/Sep/2018:17:58:09 -0300] [Job 5] STATE: -connecting-to-device D [02/Sep/2018:17:58:09 -0300] [Job 5] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other D [02/Sep/2018:17:58:09 -0300] [Job 5] Processing page 2... D [02/Sep/2018:17:58:09 -0300] [Job 5] Set job-printer-state-message to "Processing page 2...", current level=INFO D [02/Sep/2018:17:58:09 -0300] [Job 5] Error: /invalidfileaccess in --run-- D [02/Sep/2018:17:58:09 -0300] [Job 5] Operand stack: D [02/Sep/2018:17:58:09 -0300] [Job 5] true (/var/spool/cups/tmp/gs_pfbaIn) D [02/Sep/2018:17:58:09 -0300] [Job 5] Execution stack: D [02/Sep/2018:17:58:09 -0300] [Job 5] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 2015 2 3 %oparray_pop 2014 2 3 %oparray_pop 1998 2 3 %oparray_pop D [02/Sep/2018:17:58:09 -0300] [Job 5] Dictionary stack: D [02/Sep/2018:17:58:09 -0300] [Job 5] --dict:998/1684(ro)(G)-- --dict:1/20(G)-- --dict:78/200(L)-- D [02/Sep/2018:17:58:09 -0300] [Job 5] Current allocation mode is local D [02/Sep/2018:17:58:09 -0300] [Job 5] Last OS error: No such file or directory D [02/Sep/2018:17:58:09 -0300] [Job 5] GPL Ghostscript 9.23: Unrecoverable error, exit code 1 D [02/Sep/2018:17:58:09 -0300] [Job 5] Rendering completed D [02/Sep/2018:17:58:09 -0300] [Job 5] Set job-printer-state-message to "Rendering completed", current level=INFO D [02/Sep/2018:17:58:09 -0300] [Job 5] PID 961 (/usr/lib/cups/filter/gstoraster) stopped with status 1. D [02/Sep/2018:17:58:09 -0300] [Job 5] Hint: Try setting the LogLevel to "debug" to find out more. D [02/Sep/2018:17:58:09 -0300] [Job 5] PID 962 (/usr/lib/cups/filter/hpcups) exited with no errors. D [02/Sep/2018:17:58:09 -0300] [Job 5] ready to print D [02/Sep/2018:17:58:09 -0300] [Job 5] PID 963 (/usr/lib/cups/backend/hp) exited with no errors. D [02/Sep/2018:17:58:09 -0300] [Job 5] End of messages D [02/Sep/2018:17:58:09 -0300] [Job 5] printer-state=3(idle) D [02/Sep/2018:17:58:09 -0300] [Job 5] printer-state-message="ready to print" D [02/Sep/2018:17:58:09 -0300] [Job 5] printer-state-reasons=none E [02/Sep/2018:17:59:25 -0300] [cups-deviced] PID 1010 (cups-brf) stopped with status 13! E [02/Sep/2018:17:59:47 -0300] Scheduler shutting down due to program error. I [02/Sep/2018:17:59:48 -0300] Listening to [v1.::1]:631 (IPv6) I [02/Sep/2018:17:59:48 -0300] Listening to 127.0.0.1:631 (IPv4) I [02/Sep/2018:17:59:48 -0300] Listening to /run/cups/cups.sock (Domain) E [02/Sep/2018:17:59:48 -0300] Unknown directive JobPrivateAccess on line 122 of /etc/cups/cupsd.conf. E [02/Sep/2018:17:59:48 -0300] Unknown directive JobPrivateValues on line 123 of /etc/cups/cupsd.conf. E [02/Sep/2018:17:59:48 -0300] Unknown directive SubscriptionPrivateAccess on line 124 of /etc/cups/cupsd.conf. E [02/Sep/2018:17:59:48 -0300] Unknown directive SubscriptionPrivateValues on line 125 of /etc/cups/cupsd.conf. I [02/Sep/2018:17:59:48 -0300] Remote access is disabled. D [02/Sep/2018:17:59:48 -0300] Added auto ServerAlias alq14 I [02/Sep/2018:17:59:48 -0300] Loaded configuration file "/etc/cups/cupsd.conf" D [02/Sep/2018:17:59:48 -0300] Using keychain "/etc/cups/ssl" for server name "alq14". D [02/Sep/2018:17:59:48 -0300] Repairing access permissions of "/etc/cups/classes.conf" I [02/Sep/2018:17:59:48 -0300] Using default TempDir of /var/spool/cups/tmp... I [02/Sep/2018:17:59:48 -0300] Configured for up to 100 clients. I [02/Sep/2018:17:59:48 -0300] Allowing up to 100 client connections per host. I [02/Sep/2018:17:59:48 -0300] Using policy "default" as the default. I [02/Sep/2018:17:59:48 -0300] Full reload is required. I [02/Sep/2018:17:59:48 -0300] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 78 types, 118 filters... D [02/Sep/2018:17:59:48 -0300] Loading printer HP-DeskJet-3700-series... D [02/Sep/2018:17:59:48 -0300] load_ppd: Loading /var/cache/cups/HP-DeskJet-3700-series.data... D [02/Sep/2018:17:59:48 -0300] cupsdRegisterPrinter(p=0x564afaf11380(HP-DeskJet-3700-series)) I [02/Sep/2018:17:59:48 -0300] Loading job cache file "/var/cache/cups/job.cache"... D [02/Sep/2018:17:59:48 -0300] [Job 4] Loading from cache... D [02/Sep/2018:17:59:48 -0300] [Job 5] Loading from cache... D [02/Sep/2018:17:59:48 -0300] [Job 5] Loading attributes... D [02/Sep/2018:17:59:48 -0300] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") D [02/Sep/2018:17:59:48 -0300] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") D [02/Sep/2018:17:59:48 -0300] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)") I [02/Sep/2018:17:59:48 -0300] Full reload complete. D [02/Sep/2018:17:59:48 -0300] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)") I [02/Sep/2018:17:59:48 -0300] Cleaning out old files in "/var/spool/cups/tmp". D [02/Sep/2018:17:59:48 -0300] cupsdCleanFiles(path="/var/spool/cups/tmp/.hplip", pattern="(null)") I [02/Sep/2018:17:59:48 -0300] Cleaning out old files in "/var/spool/cups/tmp/.hplip". D [02/Sep/2018:17:59:48 -0300] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp") I [02/Sep/2018:17:59:48 -0300] Cleaning out old files in "/var/cache/cups". D [02/Sep/2018:17:59:48 -0300] service_checkin: pid=1031 D [02/Sep/2018:17:59:48 -0300] service_checkin: 1 listeners. D [02/Sep/2018:17:59:48 -0300] service_add_listener: Listener #1 at fd 3, "/run/cups/cups.sock". D [02/Sep/2018:17:59:48 -0300] service_add_listener: Matched existing listener #1 to /run/cups/cups.sock. D [02/Sep/2018:17:59:48 -0300] Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd". D [02/Sep/2018:17:59:48 -0300] Calling FindDeviceById(cups-HP-DeskJet-3700-series) D [02/Sep/2018:17:59:48 -0300] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id \'cups-HP-DeskJet-3700-series\' does not exist D [02/Sep/2018:17:59:48 -0300] Using profile ID "HP-DeskJet-3700-series-Gray..". D [02/Sep/2018:17:59:48 -0300] Calling CreateProfile(HP-DeskJet-3700-series-Gray..,temp) D [02/Sep/2018:17:59:48 -0300] Created profile "/org/freedesktop/ColorManager/profiles/HP_DeskJet_3700_series_Gray__". D [02/Sep/2018:17:59:48 -0300] Using profile ID "HP-DeskJet-3700-series-RGB..". D [02/Sep/2018:17:59:48 -0300] Calling CreateProfile(HP-DeskJet-3700-series-RGB..,temp) D [02/Sep/2018:17:59:48 -0300] Created profile "/org/freedesktop/ColorManager/profiles/HP_DeskJet_3700_series_RGB__". I [02/Sep/2018:17:59:48 -0300] Registering ICC color profiles for "HP-DeskJet-3700-series". D [02/Sep/2018:17:59:48 -0300] Calling CreateDevice(cups-HP-DeskJet-3700-series,temp) D [02/Sep/2018:17:59:48 -0300] Created device "/org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series". D [02/Sep/2018:17:59:48 -0300] Calling /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series:AddProfile(/org/freedesktop/ColorManager/profiles/HP_DeskJet_3700_series_Gray__) [soft] D [02/Sep/2018:17:59:48 -0300] Calling /org/freedesktop/ColorManager/devices/cups_HP_DeskJet_3700_series:AddProfile(/org/freedesktop/ColorManager/profiles/HP_DeskJet_3700_series_RGB__) [soft] I [02/Sep/2018:17:59:48 -0300] Listening to [v1.::1]:631 on fd 8... I [02/Sep/2018:17:59:48 -0300] Listening to 127.0.0.1:631 on fd 9... I [02/Sep/2018:17:59:48 -0300] Listening to /run/cups/cups.sock on fd 3... I [02/Sep/2018:17:59:48 -0300] Resuming new connection processing... D [02/Sep/2018:17:59:48 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:17:59:48 -0300] cupsdAddCert: Adding certificate for PID 0 D [02/Sep/2018:17:59:48 -0300] Discarding unused server-started event... D [02/Sep/2018:17:59:48 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [02/Sep/2018:17:59:48 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:17:59:48 -0300] [Client 1] Server address is "/run/cups/cups.sock". D [02/Sep/2018:17:59:48 -0300] [Client 1] Accepted from localhost (Domain) D [02/Sep/2018:17:59:48 -0300] [Client 1] Waiting for request. D [02/Sep/2018:17:59:48 -0300] Report: clients=1 D [02/Sep/2018:17:59:48 -0300] Report: jobs=2 D [02/Sep/2018:17:59:48 -0300] Report: jobs-active=1 D [02/Sep/2018:17:59:48 -0300] Report: printers=1 D [02/Sep/2018:17:59:48 -0300] Report: stringpool-string-count=6447 D [02/Sep/2018:17:59:48 -0300] Report: stringpool-alloc-bytes=11248 D [02/Sep/2018:17:59:48 -0300] Report: stringpool-total-bytes=120344 I [02/Sep/2018:17:59:49 -0300] Expiring subscriptions... D [02/Sep/2018:17:59:51 -0300] [Client 1] POST / HTTP/1.1 D [02/Sep/2018:17:59:51 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:17:59:51 -0300] [Client 1] Read: status=200, state=6 D [02/Sep/2018:17:59:51 -0300] [Client 1] No authentication data provided. D [02/Sep/2018:17:59:51 -0300] [Client 1] 2.0 Get-Jobs 47 D [02/Sep/2018:17:59:51 -0300] Get-Jobs ipp://localhost/printers/ D [02/Sep/2018:17:59:51 -0300] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost. D [02/Sep/2018:17:59:51 -0300] [Client 1] Content-Length: 176 D [02/Sep/2018:17:59:51 -0300] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 I [02/Sep/2018:17:59:51 -0300] Expiring subscriptions... D [02/Sep/2018:17:59:51 -0300] [Client 1] con->http=0x564afb028230 D [02/Sep/2018:17:59:51 -0300] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=176, response=0x564afb024bc0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:17:59:51 -0300] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:17:59:51 -0300] [Client 1] bytes=0, http_state=0, data_remaining=176 D [02/Sep/2018:17:59:51 -0300] [Client 1] Flushing write buffer. D [02/Sep/2018:17:59:51 -0300] [Client 1] New state is HTTP_STATE_WAITING D [02/Sep/2018:17:59:51 -0300] [Client 1] Waiting for request. D [02/Sep/2018:17:59:51 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:17:59:51 -0300] [Client 1] POST / HTTP/1.1 D [02/Sep/2018:17:59:51 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:17:59:51 -0300] [Client 1] Read: status=200, state=6 D [02/Sep/2018:17:59:51 -0300] [Client 1] No authentication data provided. D [02/Sep/2018:17:59:51 -0300] [Client 1] 2.0 Get-Jobs 48 D [02/Sep/2018:17:59:51 -0300] Get-Jobs ipp://localhost/printers/ I [02/Sep/2018:17:59:51 -0300] [Client 1] Limiting Get-Jobs response to 500 jobs. D [02/Sep/2018:17:59:51 -0300] [Job 4] Loading attributes... D [02/Sep/2018:17:59:51 -0300] [Client 1] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost. D [02/Sep/2018:17:59:51 -0300] [Client 1] Content-Length: 190 D [02/Sep/2018:17:59:51 -0300] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:17:59:51 -0300] [Client 1] con->http=0x564afb028230 D [02/Sep/2018:17:59:51 -0300] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=190, response=0x564afb025460(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:17:59:51 -0300] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:17:59:51 -0300] [Client 1] bytes=0, http_state=0, data_remaining=190 D [02/Sep/2018:17:59:51 -0300] [Client 1] Flushing write buffer. D [02/Sep/2018:17:59:51 -0300] [Client 1] New state is HTTP_STATE_WAITING D [02/Sep/2018:17:59:51 -0300] [Client 1] Waiting for request. D [02/Sep/2018:17:59:51 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" I [02/Sep/2018:17:59:52 -0300] Expiring subscriptions... D [02/Sep/2018:18:01:26 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:01:26 -0300] [Client 2] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:01:26 -0300] [Client 2] Accepted from localhost (Domain) D [02/Sep/2018:18:01:26 -0300] [Client 2] Waiting for request. I [02/Sep/2018:18:01:26 -0300] Expiring subscriptions... D [02/Sep/2018:18:01:26 -0300] [Job 4] Unloading... D [02/Sep/2018:18:01:26 -0300] [Job 5] Unloading... D [02/Sep/2018:18:01:26 -0300] Report: clients=2 D [02/Sep/2018:18:01:26 -0300] Report: jobs=2 D [02/Sep/2018:18:01:26 -0300] Report: jobs-active=1 D [02/Sep/2018:18:01:26 -0300] Report: printers=1 D [02/Sep/2018:18:01:26 -0300] Report: stringpool-string-count=6395 D [02/Sep/2018:18:01:26 -0300] Report: stringpool-alloc-bytes=9960 D [02/Sep/2018:18:01:26 -0300] Report: stringpool-total-bytes=119288 D [02/Sep/2018:18:01:27 -0300] [Client 2] POST / HTTP/1.1 D [02/Sep/2018:18:01:27 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:01:27 -0300] [Client 2] Read: status=200, state=6 D [02/Sep/2018:18:01:27 -0300] [Client 2] No authentication data provided. D [02/Sep/2018:18:01:27 -0300] [Client 2] 2.0 Get-Printer-Attributes 49 D [02/Sep/2018:18:01:27 -0300] Get-Printer-Attributes ipp://localhost/printers/HP-DeskJet-3700-series D [02/Sep/2018:18:01:27 -0300] [Client 2] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP-DeskJet-3700-series) from localhost. D [02/Sep/2018:18:01:27 -0300] [Client 2] Content-Length: 10429 D [02/Sep/2018:18:01:27 -0300] [Client 2] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 I [02/Sep/2018:18:01:27 -0300] Expiring subscriptions... D [02/Sep/2018:18:01:27 -0300] [Client 2] con->http=0x564afb02c960 D [02/Sep/2018:18:01:27 -0300] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10429, response=0x564afaefbf10(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:01:27 -0300] [Client 2] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:01:27 -0300] [Client 2] bytes=0, http_state=0, data_remaining=10429 D [02/Sep/2018:18:01:27 -0300] [Client 2] Flushing write buffer. D [02/Sep/2018:18:01:27 -0300] [Client 2] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:01:27 -0300] [Client 2] Waiting for request. D [02/Sep/2018:18:01:27 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" I [02/Sep/2018:18:01:28 -0300] Expiring subscriptions... D [02/Sep/2018:18:03:31 -0300] [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:03:31 -0300] [Client 2] Closing connection. D [02/Sep/2018:18:03:31 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" I [02/Sep/2018:18:03:31 -0300] Expiring subscriptions... D [02/Sep/2018:18:03:31 -0300] Report: clients=1 D [02/Sep/2018:18:03:31 -0300] Report: jobs=2 D [02/Sep/2018:18:03:31 -0300] Report: jobs-active=1 D [02/Sep/2018:18:03:31 -0300] Report: printers=1 D [02/Sep/2018:18:03:31 -0300] Report: stringpool-string-count=6395 D [02/Sep/2018:18:03:31 -0300] Report: stringpool-alloc-bytes=9960 D [02/Sep/2018:18:03:31 -0300] Report: stringpool-total-bytes=119288 I [02/Sep/2018:18:03:32 -0300] Expiring subscriptions... D [02/Sep/2018:18:03:36 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:03:36 -0300] [Client 3] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:03:36 -0300] [Client 3] Accepted from localhost (Domain) D [02/Sep/2018:18:03:36 -0300] [Client 3] Waiting for request. I [02/Sep/2018:18:03:36 -0300] Expiring subscriptions... D [02/Sep/2018:18:03:36 -0300] [Client 3] POST /jobs/ HTTP/1.1 D [02/Sep/2018:18:03:36 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:03:36 -0300] [Client 3] Read: status=200, state=6 D [02/Sep/2018:18:03:36 -0300] [Client 3] No authentication data provided. D [02/Sep/2018:18:03:36 -0300] [Client 3] 2.0 Cancel-Job 50 D [02/Sep/2018:18:03:36 -0300] Cancel-Job ipp://localhost/jobs/5 D [02/Sep/2018:18:03:36 -0300] cupsdIsAuthorized: requesting-user-name="dsilva" D [02/Sep/2018:18:03:36 -0300] [Job 5] Loading attributes... D [02/Sep/2018:18:03:36 -0300] [Job 5] time-at-completed=1535922216 D [02/Sep/2018:18:03:36 -0300] cupsdMarkDirty(----S) D [02/Sep/2018:18:03:36 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" I [02/Sep/2018:18:03:36 -0300] [Job 5] Job canceled by \"dsilva\" I [02/Sep/2018:18:03:36 -0300] Expiring subscriptions... D [02/Sep/2018:18:03:36 -0300] cupsdMarkDirty(---J-) D [02/Sep/2018:18:03:36 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:03:36 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [02/Sep/2018:18:03:36 -0300] [Job 5] Canceled by "dsilva". D [02/Sep/2018:18:03:36 -0300] [Client 3] Returning IPP successful-ok for Cancel-Job (ipp://localhost/jobs/5) from localhost. D [02/Sep/2018:18:03:36 -0300] [Client 3] Content-Length: 75 D [02/Sep/2018:18:03:36 -0300] [Client 3] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:03:36 -0300] [Client 3] con->http=0x564afb02c960 D [02/Sep/2018:18:03:36 -0300] [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x564afb0251e0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:03:36 -0300] [Client 3] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:03:36 -0300] [Client 3] bytes=0, http_state=0, data_remaining=75 D [02/Sep/2018:18:03:36 -0300] [Client 3] Flushing write buffer. D [02/Sep/2018:18:03:36 -0300] [Client 3] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:03:36 -0300] [Client 3] Waiting for request. D [02/Sep/2018:18:03:36 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:03:36 -0300] [Client 3] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:03:36 -0300] [Client 3] Closing connection. D [02/Sep/2018:18:03:36 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [02/Sep/2018:18:03:37 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:03:37 -0300] [Client 4] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:03:37 -0300] [Client 4] Accepted from localhost (Domain) D [02/Sep/2018:18:03:37 -0300] [Client 4] Waiting for request. I [02/Sep/2018:18:03:37 -0300] Expiring subscriptions... D [02/Sep/2018:18:03:37 -0300] [Client 4] POST / HTTP/1.1 D [02/Sep/2018:18:03:37 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:03:37 -0300] [Client 4] Read: status=200, state=6 D [02/Sep/2018:18:03:37 -0300] [Client 4] No authentication data provided. D [02/Sep/2018:18:03:37 -0300] [Client 4] 2.0 Get-Notifications 51 D [02/Sep/2018:18:03:37 -0300] Get-Notifications / D [02/Sep/2018:18:03:37 -0300] cupsdIsAuthorized: requesting-user-name="dsilva" D [02/Sep/2018:18:03:37 -0300] [Client 4] Returning IPP successful-ok for Get-Notifications (/) from localhost. D [02/Sep/2018:18:03:37 -0300] [Client 4] Content-Length: 694 D [02/Sep/2018:18:03:37 -0300] [Client 4] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:03:37 -0300] [Client 4] con->http=0x564afb02c960 D [02/Sep/2018:18:03:37 -0300] [Client 4] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=694, response=0x564afb0258f0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:03:37 -0300] [Client 4] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:03:37 -0300] [Client 4] bytes=0, http_state=0, data_remaining=694 D [02/Sep/2018:18:03:37 -0300] [Client 4] Flushing write buffer. D [02/Sep/2018:18:03:37 -0300] [Client 4] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:03:37 -0300] [Client 4] Waiting for request. D [02/Sep/2018:18:03:37 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:03:37 -0300] [Client 4] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:03:37 -0300] [Client 4] Closing connection. D [02/Sep/2018:18:03:37 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [02/Sep/2018:18:03:38 -0300] Expiring subscriptions... I [02/Sep/2018:18:04:07 -0300] Saving job.cache... I [02/Sep/2018:18:04:07 -0300] Saving subscriptions.conf... D [02/Sep/2018:18:04:07 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" I [02/Sep/2018:18:04:07 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:10 -0300] [Client 5] Server address is "[v1.::1]". D [02/Sep/2018:18:04:10 -0300] [Client 5] Accepted from localhost:48836 (IPv6) D [02/Sep/2018:18:04:10 -0300] [Client 5] Waiting for request. I [02/Sep/2018:18:04:10 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:10 -0300] [Client 5] GET / HTTP/1.1 D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:10 -0300] [Client 5] Read: status=200, state=3 D [02/Sep/2018:18:04:10 -0300] [Client 5] No authentication data provided. D [02/Sep/2018:18:04:10 -0300] [Client 5] Processing GET / D [02/Sep/2018:18:04:10 -0300] [Client 5] filename="/usr/share/cups/doc/pt_BR/index.html", type=text/html D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdSendHeader: code=200, type="text/html", auth_type=0 D [02/Sep/2018:18:04:10 -0300] [Client 5] Sending file. D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2672, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2672, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] Flushing write buffer. D [02/Sep/2018:18:04:10 -0300] [Client 5] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:10 -0300] [Client 5] Waiting for request. D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:18:04:10 -0300] [Client 5] GET /cups.css HTTP/1.1 D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:10 -0300] [Client 5] Read: status=200, state=3 D [02/Sep/2018:18:04:10 -0300] [Client 5] No authentication data provided. D [02/Sep/2018:18:04:10 -0300] [Client 5] Processing GET /cups.css D [02/Sep/2018:18:04:10 -0300] [Client 5] filename="/usr/share/cups/doc/cups.css", type=text/css D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdSendHeader: code=200, type="text/css", auth_type=0 D [02/Sep/2018:18:04:10 -0300] [Client 5] Sending file. D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] Flushing write buffer. D [02/Sep/2018:18:04:10 -0300] [Client 5] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:10 -0300] [Client 5] Waiting for request. D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:18:04:10 -0300] [Client 5] GET /apple-touch-icon.png HTTP/1.1 D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:10 -0300] [Client 5] Read: status=200, state=3 D [02/Sep/2018:18:04:10 -0300] [Client 5] No authentication data provided. D [02/Sep/2018:18:04:10 -0300] [Client 5] Processing GET /apple-touch-icon.png D [02/Sep/2018:18:04:10 -0300] [Client 5] filename="/usr/share/cups/doc/apple-touch-icon.png", type=image/png D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdSendHeader: code=200, type="image/png", auth_type=0 D [02/Sep/2018:18:04:10 -0300] [Client 5] Sending file. D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6139, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6139, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] con->http=0x564afb02c960 D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6139, response=(nil)(), pipe_pid=0, file=15 D [02/Sep/2018:18:04:10 -0300] [Client 5] Flushing write buffer. D [02/Sep/2018:18:04:10 -0300] [Client 5] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:10 -0300] [Client 5] Waiting for request. D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:18:04:10 -0300] [Client 5] GET /favicon.ico HTTP/1.1 D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:10 -0300] [Client 5] Read: status=200, state=3 D [02/Sep/2018:18:04:10 -0300] [Client 5] No authentication data provided. D [02/Sep/2018:18:04:10 -0300] [Client 5] Processing GET /favicon.ico D [02/Sep/2018:18:04:10 -0300] [Client 5] cupsdSendHeader: code=404, type="text/html", auth_type=0 D [02/Sep/2018:18:04:10 -0300] [Client 5] Closing because Keep-Alive is disabled. D [02/Sep/2018:18:04:10 -0300] [Client 5] Closing connection. D [02/Sep/2018:18:04:10 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" I [02/Sep/2018:18:04:11 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:14 -0300] [Client 6] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:14 -0300] [Client 6] Accepted from localhost (Domain) D [02/Sep/2018:18:04:14 -0300] [Client 6] Waiting for request. I [02/Sep/2018:18:04:14 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:14 -0300] [Client 6] POST / HTTP/1.1 D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:14 -0300] [Client 6] Read: status=200, state=6 D [02/Sep/2018:18:04:14 -0300] [Client 6] No authentication data provided. D [02/Sep/2018:18:04:14 -0300] [Client 6] 2.0 CUPS-Get-Printers 1 D [02/Sep/2018:18:04:14 -0300] CUPS-Get-Printers D [02/Sep/2018:18:04:14 -0300] [Client 6] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost. D [02/Sep/2018:18:04:14 -0300] [Client 6] Content-Length: 488 D [02/Sep/2018:18:04:14 -0300] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:14 -0300] [Client 6] con->http=0x564afb02c960 D [02/Sep/2018:18:04:14 -0300] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=488, response=0x564afb025460(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:14 -0300] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:14 -0300] [Client 6] bytes=0, http_state=0, data_remaining=488 D [02/Sep/2018:18:04:14 -0300] [Client 6] Flushing write buffer. D [02/Sep/2018:18:04:14 -0300] [Client 6] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:14 -0300] [Client 6] Waiting for request. D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:18:04:14 -0300] [Client 6] POST / HTTP/1.1 D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:14 -0300] [Client 6] Read: status=200, state=6 D [02/Sep/2018:18:04:14 -0300] [Client 6] No authentication data provided. D [02/Sep/2018:18:04:14 -0300] [Client 6] 2.0 CUPS-Get-Classes 2 D [02/Sep/2018:18:04:14 -0300] CUPS-Get-Classes D [02/Sep/2018:18:04:14 -0300] [Client 6] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost. D [02/Sep/2018:18:04:14 -0300] [Client 6] Content-Length: 75 D [02/Sep/2018:18:04:14 -0300] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:14 -0300] [Client 6] con->http=0x564afb02c960 D [02/Sep/2018:18:04:14 -0300] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x564afb0251e0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:14 -0300] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:14 -0300] [Client 6] bytes=0, http_state=0, data_remaining=75 D [02/Sep/2018:18:04:14 -0300] [Client 6] Flushing write buffer. D [02/Sep/2018:18:04:14 -0300] [Client 6] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:14 -0300] [Client 6] Waiting for request. D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:18:04:14 -0300] [Client 6] POST / HTTP/1.1 D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:14 -0300] [Client 6] Read: status=200, state=6 D [02/Sep/2018:18:04:14 -0300] [Client 6] No authentication data provided. D [02/Sep/2018:18:04:14 -0300] [Client 6] 2.0 CUPS-Get-Default 3 D [02/Sep/2018:18:04:14 -0300] CUPS-Get-Default D [02/Sep/2018:18:04:14 -0300] [Client 6] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost. D [02/Sep/2018:18:04:14 -0300] [Client 6] Content-Length: 10429 D [02/Sep/2018:18:04:14 -0300] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:14 -0300] [Client 6] con->http=0x564afb02c960 D [02/Sep/2018:18:04:14 -0300] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10429, response=0x564afb025460(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:14 -0300] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:14 -0300] [Client 6] bytes=0, http_state=0, data_remaining=10429 D [02/Sep/2018:18:04:14 -0300] [Client 6] Flushing write buffer. D [02/Sep/2018:18:04:14 -0300] [Client 6] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:14 -0300] [Client 6] Waiting for request. D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:14 -0300] [Client 7] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:14 -0300] [Client 7] Accepted from localhost (Domain) D [02/Sep/2018:18:04:14 -0300] [Client 7] Waiting for request. D [02/Sep/2018:18:04:14 -0300] [Client 7] POST / HTTP/1.1 D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:14 -0300] [Client 7] Read: status=200, state=6 D [02/Sep/2018:18:04:14 -0300] [Client 7] No authentication data provided. D [02/Sep/2018:18:04:14 -0300] [Client 7] 2.0 Create-Printer-Subscriptions 4 D [02/Sep/2018:18:04:14 -0300] Create-Printer-Subscriptions / D [02/Sep/2018:18:04:14 -0300] create_subscriptions(con=0x564afb032df0(7), uri="/") D [02/Sep/2018:18:04:14 -0300] pullmethod="ippget" D [02/Sep/2018:18:04:14 -0300] notify-lease-duration=86400 D [02/Sep/2018:18:04:14 -0300] notify-time-interval=0 D [02/Sep/2018:18:04:14 -0300] cupsdAddSubscription(mask=38f, dest=(nil)(), job=(nil)(0), uri="(null)") D [02/Sep/2018:18:04:14 -0300] Added subscription #9 for server. D [02/Sep/2018:18:04:14 -0300] cupsdMarkDirty(----S) D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [02/Sep/2018:18:04:14 -0300] [Client 7] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost. D [02/Sep/2018:18:04:14 -0300] [Client 7] Content-Length: 107 D [02/Sep/2018:18:04:14 -0300] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:14 -0300] [Client 7] con->http=0x564afb033fb0 D [02/Sep/2018:18:04:14 -0300] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x564afb025a30(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:14 -0300] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:14 -0300] [Client 7] bytes=0, http_state=0, data_remaining=107 D [02/Sep/2018:18:04:14 -0300] [Client 7] Flushing write buffer. D [02/Sep/2018:18:04:14 -0300] [Client 7] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:14 -0300] [Client 7] Waiting for request. D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:14 -0300] [Client 7] POST / HTTP/1.1 D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:14 -0300] [Client 7] Read: status=200, state=6 D [02/Sep/2018:18:04:14 -0300] [Client 7] No authentication data provided. D [02/Sep/2018:18:04:14 -0300] [Client 7] 2.0 CUPS-Get-Printers 5 D [02/Sep/2018:18:04:14 -0300] CUPS-Get-Printers D [02/Sep/2018:18:04:14 -0300] [Client 7] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost. D [02/Sep/2018:18:04:14 -0300] [Client 7] Content-Length: 488 D [02/Sep/2018:18:04:14 -0300] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:14 -0300] [Client 7] con->http=0x564afb033fb0 D [02/Sep/2018:18:04:14 -0300] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=488, response=0x564afb0251e0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:14 -0300] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:14 -0300] [Client 7] bytes=0, http_state=0, data_remaining=488 D [02/Sep/2018:18:04:14 -0300] [Client 7] Flushing write buffer. D [02/Sep/2018:18:04:14 -0300] [Client 7] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:14 -0300] [Client 7] Waiting for request. D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:14 -0300] [Client 7] POST / HTTP/1.1 D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:14 -0300] [Client 7] Read: status=200, state=6 D [02/Sep/2018:18:04:14 -0300] [Client 7] No authentication data provided. D [02/Sep/2018:18:04:14 -0300] [Client 7] 2.0 CUPS-Get-Printers 6 D [02/Sep/2018:18:04:14 -0300] CUPS-Get-Printers D [02/Sep/2018:18:04:14 -0300] [Client 7] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost. D [02/Sep/2018:18:04:14 -0300] [Client 7] Content-Length: 488 D [02/Sep/2018:18:04:14 -0300] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:14 -0300] [Client 7] con->http=0x564afb033fb0 D [02/Sep/2018:18:04:14 -0300] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=488, response=0x564afb025970(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:14 -0300] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:14 -0300] [Client 7] bytes=0, http_state=0, data_remaining=488 D [02/Sep/2018:18:04:14 -0300] [Client 7] Flushing write buffer. D [02/Sep/2018:18:04:14 -0300] [Client 7] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:14 -0300] [Client 7] Waiting for request. D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:14 -0300] [Client 7] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:14 -0300] [Client 7] Closing connection. D [02/Sep/2018:18:04:14 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 6] POST / HTTP/1.1 D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 6] Read: status=200, state=6 D [02/Sep/2018:18:04:15 -0300] [Client 6] No authentication data provided. D [02/Sep/2018:18:04:15 -0300] [Client 6] 2.0 CUPS-Get-Printers 7 D [02/Sep/2018:18:04:15 -0300] CUPS-Get-Printers D [02/Sep/2018:18:04:15 -0300] [Client 6] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost. D [02/Sep/2018:18:04:15 -0300] [Client 6] Content-Length: 488 D [02/Sep/2018:18:04:15 -0300] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 I [02/Sep/2018:18:04:15 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:15 -0300] [Client 6] con->http=0x564afb02c960 D [02/Sep/2018:18:04:15 -0300] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=488, response=0x564afb01df40(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:15 -0300] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:15 -0300] [Client 6] bytes=0, http_state=0, data_remaining=488 D [02/Sep/2018:18:04:15 -0300] [Client 6] Flushing write buffer. D [02/Sep/2018:18:04:15 -0300] [Client 6] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:15 -0300] [Client 6] Waiting for request. D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 6] POST / HTTP/1.1 D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 6] Read: status=200, state=6 D [02/Sep/2018:18:04:15 -0300] [Client 6] No authentication data provided. D [02/Sep/2018:18:04:15 -0300] [Client 6] 2.0 CUPS-Get-Classes 8 D [02/Sep/2018:18:04:15 -0300] CUPS-Get-Classes D [02/Sep/2018:18:04:15 -0300] [Client 6] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost. D [02/Sep/2018:18:04:15 -0300] [Client 6] Content-Length: 75 D [02/Sep/2018:18:04:15 -0300] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:15 -0300] [Client 6] con->http=0x564afb02c960 D [02/Sep/2018:18:04:15 -0300] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x564afb026c90(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:15 -0300] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:15 -0300] [Client 6] bytes=0, http_state=0, data_remaining=75 D [02/Sep/2018:18:04:15 -0300] [Client 6] Flushing write buffer. D [02/Sep/2018:18:04:15 -0300] [Client 6] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:15 -0300] [Client 6] Waiting for request. D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 6] POST / HTTP/1.1 D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 6] Read: status=200, state=6 D [02/Sep/2018:18:04:15 -0300] [Client 6] No authentication data provided. D [02/Sep/2018:18:04:15 -0300] [Client 6] 2.0 CUPS-Get-Default 9 D [02/Sep/2018:18:04:15 -0300] CUPS-Get-Default D [02/Sep/2018:18:04:15 -0300] [Client 6] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost. D [02/Sep/2018:18:04:15 -0300] [Client 6] Content-Length: 10429 D [02/Sep/2018:18:04:15 -0300] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:15 -0300] [Client 6] con->http=0x564afb02c960 D [02/Sep/2018:18:04:15 -0300] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10429, response=0x564afb01df40(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:15 -0300] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:15 -0300] [Client 6] bytes=0, http_state=0, data_remaining=10429 D [02/Sep/2018:18:04:15 -0300] [Client 6] Flushing write buffer. D [02/Sep/2018:18:04:15 -0300] [Client 6] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:15 -0300] [Client 6] Waiting for request. D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 8] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:15 -0300] [Client 8] Accepted from localhost (Domain) D [02/Sep/2018:18:04:15 -0300] [Client 8] Waiting for request. D [02/Sep/2018:18:04:15 -0300] [Client 8] POST / HTTP/1.1 D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 8] Read: status=200, state=6 D [02/Sep/2018:18:04:15 -0300] [Client 8] No authentication data provided. D [02/Sep/2018:18:04:15 -0300] [Client 8] 2.0 Get-Notifications 10 D [02/Sep/2018:18:04:15 -0300] Get-Notifications / D [02/Sep/2018:18:04:15 -0300] cupsdIsAuthorized: requesting-user-name="dsilva" D [02/Sep/2018:18:04:15 -0300] [Client 8] Returning IPP successful-ok for Get-Notifications (/) from localhost. D [02/Sep/2018:18:04:15 -0300] [Client 8] Content-Length: 127 D [02/Sep/2018:18:04:15 -0300] [Client 8] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:15 -0300] [Client 8] con->http=0x564afb034b30 D [02/Sep/2018:18:04:15 -0300] [Client 8] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x564afb01df40(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:15 -0300] [Client 8] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:15 -0300] [Client 8] bytes=0, http_state=0, data_remaining=127 D [02/Sep/2018:18:04:15 -0300] [Client 8] Flushing write buffer. D [02/Sep/2018:18:04:15 -0300] [Client 8] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:15 -0300] [Client 8] Waiting for request. D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:15 -0300] [Client 8] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:15 -0300] [Client 8] Closing connection. D [02/Sep/2018:18:04:15 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [02/Sep/2018:18:04:16 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:18 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:18 -0300] [Client 9] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:18 -0300] [Client 9] Accepted from localhost (Domain) D [02/Sep/2018:18:04:18 -0300] [Client 9] Waiting for request. I [02/Sep/2018:18:04:18 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:18 -0300] [Client 9] POST / HTTP/1.1 D [02/Sep/2018:18:04:18 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:18 -0300] [Client 9] Read: status=200, state=6 D [02/Sep/2018:18:04:18 -0300] [Client 9] No authentication data provided. D [02/Sep/2018:18:04:18 -0300] [Client 9] 2.0 Cancel-Subscription 11 D [02/Sep/2018:18:04:18 -0300] Cancel-Subscription / D [02/Sep/2018:18:04:18 -0300] cupsdIsAuthorized: requesting-user-name="dsilva" D [02/Sep/2018:18:04:18 -0300] cupsdMarkDirty(----S) D [02/Sep/2018:18:04:18 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:18 -0300] [Client 9] Returning IPP successful-ok for Cancel-Subscription (/) from localhost. D [02/Sep/2018:18:04:18 -0300] [Client 9] Content-Length: 75 D [02/Sep/2018:18:04:18 -0300] [Client 9] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:18 -0300] [Client 9] con->http=0x564afb034b30 D [02/Sep/2018:18:04:18 -0300] [Client 9] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x564afb01df40(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:18 -0300] [Client 9] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:18 -0300] [Client 9] bytes=0, http_state=0, data_remaining=75 D [02/Sep/2018:18:04:18 -0300] [Client 9] Flushing write buffer. D [02/Sep/2018:18:04:18 -0300] [Client 9] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:18 -0300] [Client 9] Waiting for request. D [02/Sep/2018:18:04:18 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:18 -0300] [Client 9] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:18 -0300] [Client 9] Closing connection. D [02/Sep/2018:18:04:18 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [02/Sep/2018:18:04:18 -0300] [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:18 -0300] [Client 6] Closing connection. D [02/Sep/2018:18:04:18 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [02/Sep/2018:18:04:19 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:26 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:26 -0300] [Client 10] Server address is "[v1.::1]". D [02/Sep/2018:18:04:26 -0300] [Client 10] Accepted from localhost:48846 (IPv6) D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for request. I [02/Sep/2018:18:04:26 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:26 -0300] [Client 10] GET /help/overview.html HTTP/1.1 D [02/Sep/2018:18:04:26 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:26 -0300] [Client 10] Read: status=200, state=3 D [02/Sep/2018:18:04:26 -0300] [Client 10] No authentication data provided. D [02/Sep/2018:18:04:26 -0300] [Client 10] Processing GET /help/overview.html D [02/Sep/2018:18:04:26 -0300] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/help.cgi" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[10] = "SERVER_ADMIN=root@alq14" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[13] = "USER=root" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[17] = "IPP_PORT=631" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[18] = "LANG=pt_BR.UTF8" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[19] = "REDIRECT_STATUS=1" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[21] = "SERVER_NAME=localhost" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[22] = "SERVER_PORT=631" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[24] = "REMOTE_HOST=localhost" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[25] = "SCRIPT_NAME=/help/overview.html" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/help/overview.html" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[27] = "PATH_INFO=/overview.html" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:61.0) Gecko/20100101 Firefox/61.0" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[30] = "HTTP_REFERER=http://localhost:631/" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[31] = "REQUEST_METHOD=GET" D [02/Sep/2018:18:04:26 -0300] [CGI] envp[32] = "QUERY_STRING=" D [02/Sep/2018:18:04:26 -0300] [CGI] Started /usr/lib/cups/cgi-bin/help.cgi (PID 1316) I [02/Sep/2018:18:04:26 -0300] [Client 10] Started "/usr/lib/cups/cgi-bin/help.cgi" (pid=1316, file=15) D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [CGI] org.cups.sid cookie not found, initializing! D [02/Sep/2018:18:04:26 -0300] [CGI] org.cups.sid cookie is \"da682bf76bf34ba8b8d053c5b9bbcfee\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: SECTION=\"help\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [02/Sep/2018:18:04:26 -0300] [CGI] helpLoadIndex(filename=\"/var/cache/cups/help.index\", directory=\"/usr/share/cups/doc/help\") D [02/Sep/2018:18:04:26 -0300] [CGI] 797 nodes in help index... D [02/Sep/2018:18:04:26 -0300] [CGI] argv[0]=\"/usr/lib/cups/cgi-bin/help.cgi\" D [02/Sep/2018:18:04:26 -0300] [CGI] helpfile=\"overview.html\", filename=\"/usr/share/cups/doc/help/overview.html\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: TITLE=\"Overview of CUPS\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.8\" D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] Script header: Set-Cookie: org.cups.sid=da682bf76bf34ba8b8d053c5b9bbcfee; path=/; httponly; D [02/Sep/2018:18:04:26 -0300] [Client 10] Script header: Content-Type: text/html;charset=utf-8 D [02/Sep/2018:18:04:26 -0300] [Client 10] Script header: D [02/Sep/2018:18:04:26 -0300] [Client 10] Sending status 200 for CGI. D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [02/Sep/2018:18:04:26 -0300] [CGI] cgiClearVariables called. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: TOPIC=\"Getting Started\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: HELPFILE=\"overview.html\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetVariable: HELPTITLE=\"Overview of CUPS\" D [02/Sep/2018:18:04:26 -0300] [CGI] query=\"(null)\", topic=\"Getting Started\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[0]=\"/help/?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[0]=\"Getting Started\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[0]=\"0\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[1]=\"/help/options.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[1]=\"Command-Line Printing and Options\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[1]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[2]=\"/help/glossary.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[2]=\"Glossary\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[2]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[3]=\"/help/encryption.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[3]=\"Managing Encryption\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[3]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[4]=\"/help/policies.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[4]=\"Managing Operation Policies\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[4]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[5]=\"/help/overview.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[5]=\"Overview of CUPS\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[5]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[6]=\"#WORK\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[6]=\"How Does It Work?\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[6]=\"2\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[7]=\"#BEGIN\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[7]=\"Where Do I Begin?\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[7]=\"2\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[8]=\"/help/accounting.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[8]=\"Printer Accounting Basics\" D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[8]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[9]=\"/help/sharing.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[9]=\"Printer Sharing\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[9]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[10]=\"/help/security.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[10]=\"Server Security\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[10]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[11]=\"/help/license.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[11]=\"Software License Agreement\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[11]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[12]=\"/help/translation.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[12]=\"Translating and Customizing CUPS\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[12]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[13]=\"/help/cgi.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[13]=\"Using CGI Programs\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[13]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[14]=\"/help/kerberos.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[14]=\"Using Kerberos Authentication\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[14]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[15]=\"/help/network.html?TOPIC=Getting+Started&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[15]=\"Using Network Printers\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[15]=\"1\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[16]=\"/help/?TOPIC=Man+Pages&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[16]=\"Man Pages\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[16]=\"0\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[17]=\"/help/?TOPIC=Programming&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[17]=\"Programming\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[17]=\"0\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[18]=\"/help/?TOPIC=References&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[18]=\"References\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[18]=\"0\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMLINK[19]=\"/help/?TOPIC=Specifications&QUERY=\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMTEXT[19]=\"Specifications\" D [02/Sep/2018:18:04:26 -0300] [CGI] cgiSetArray: BMINDENT[19]=\"0\" D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:26 -0300] PID 1316 (/usr/lib/cups/cgi-bin/help.cgi) exited with no errors. D [02/Sep/2018:18:04:26 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:26 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1316, file=15 D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:26 -0300] [Client 10] Sending 0-length chunk. D [02/Sep/2018:18:04:26 -0300] [Client 10] Flushing write buffer. D [02/Sep/2018:18:04:26 -0300] [Client 10] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:26 -0300] [Client 10] Waiting for request. D [02/Sep/2018:18:04:26 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [02/Sep/2018:18:04:27 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:29 -0300] [Client 10] GET /admin HTTP/1.1 D [02/Sep/2018:18:04:29 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:29 -0300] [Client 10] Read: status=200, state=3 D [02/Sep/2018:18:04:29 -0300] [Client 10] No authentication data provided. D [02/Sep/2018:18:04:29 -0300] [Client 10] Processing GET /admin D [02/Sep/2018:18:04:29 -0300] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[10] = "SERVER_ADMIN=root@alq14" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[13] = "USER=root" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[17] = "IPP_PORT=631" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[18] = "LANG=pt_BR.UTF8" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[19] = "REDIRECT_STATUS=1" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[21] = "SERVER_NAME=localhost" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[22] = "SERVER_PORT=631" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[24] = "REMOTE_HOST=localhost" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[25] = "SCRIPT_NAME=/admin" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=da682bf76bf34ba8b8d053c5b9bbcfee" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:61.0) Gecko/20100101 Firefox/61.0" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[30] = "HTTP_REFERER=http://localhost:631/help/overview.html" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[31] = "REQUEST_METHOD=GET" D [02/Sep/2018:18:04:29 -0300] [CGI] envp[32] = "QUERY_STRING=" D [02/Sep/2018:18:04:29 -0300] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 1317) I [02/Sep/2018:18:04:29 -0300] [Client 10] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=1317, file=15) D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. I [02/Sep/2018:18:04:29 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:29 -0300] [CGI] admin.cgi started... D [02/Sep/2018:18:04:29 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:29 -0300] [Client 11] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:29 -0300] [Client 11] Accepted from localhost (Domain) D [02/Sep/2018:18:04:29 -0300] [Client 11] Waiting for request. D [02/Sep/2018:18:04:29 -0300] [CGI] http=0x55775a4447a0 D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: SECTION=\"admin\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [02/Sep/2018:18:04:29 -0300] [CGI] org.cups.sid cookie is \"da682bf76bf34ba8b8d053c5b9bbcfee\" D [02/Sep/2018:18:04:29 -0300] [CGI] No form data, showing main menu... D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: DEBUG_LOGGING=\"CHECKED\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: HAVE_GSSAPI=\"1\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: KERBEROS=\"\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: PRESERVE_JOBS=\"CHECKED\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: PRESERVE_JOB_HISTORY=\"Yes\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: PRESERVE_JOB_FILES=\"1d\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: MAX_CLIENTS=\"100\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: MAX_JOBS=\"500\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: MAX_LOG_SIZE=\"0\" D [02/Sep/2018:18:04:29 -0300] [CGI] /usr/share/cups/drivers/pscript5.dll: Arquivo ou diretório inexistente D [02/Sep/2018:18:04:29 -0300] [Client 11] POST / HTTP/1.1 D [02/Sep/2018:18:04:29 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:29 -0300] [Client 11] Read: status=200, state=6 D [02/Sep/2018:18:04:29 -0300] [Client 11] No authentication data provided. D [02/Sep/2018:18:04:29 -0300] [Client 11] 2.0 Get-Subscriptions 1 D [02/Sep/2018:18:04:29 -0300] Get-Subscriptions ipp://localhost/ I [02/Sep/2018:18:04:29 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:29 -0300] [Client 11] Returning IPP successful-ok for Get-Subscriptions (ipp://localhost/) from localhost. D [02/Sep/2018:18:04:29 -0300] [Client 11] Content-Length: 348 D [02/Sep/2018:18:04:29 -0300] [Client 11] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D [02/Sep/2018:18:04:29 -0300] [Client 11] con->http=0x564afb034b30 D [02/Sep/2018:18:04:29 -0300] [Client 11] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=348, response=0x564afb01f2d0(IPP_STATE_DATA), pipe_pid=0, file=-1 D [02/Sep/2018:18:04:29 -0300] [Client 11] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D [02/Sep/2018:18:04:29 -0300] [Client 11] bytes=0, http_state=0, data_remaining=348 D [02/Sep/2018:18:04:29 -0300] [Client 11] Flushing write buffer. D [02/Sep/2018:18:04:29 -0300] [Client 11] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:29 -0300] [Client 11] Waiting for request. D [02/Sep/2018:18:04:29 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.8\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.8\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_lease_duration[0]=\"86400\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_time_interval[0]=\"0\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_subscription_id[0]=\"3\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.8\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_lease_duration[1]=\"86400\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_time_interval[1]=\"0\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_subscription_id[1]=\"5\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.8\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_lease_duration[2]=\"86400\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_time_interval[2]=\"0\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetArray: notify_subscription_id[2]=\"8\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: TITLE=\"Administração\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Sep/2018:18:04:29 -0300] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.8\" D [02/Sep/2018:18:04:29 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] Script header: Content-Type: text/html;charset=utf-8 D [02/Sep/2018:18:04:29 -0300] [Client 10] Script header: D [02/Sep/2018:18:04:29 -0300] [Client 10] Sending status 200 for CGI. D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 11] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:29 -0300] [Client 11] Closing connection. D [02/Sep/2018:18:04:29 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:29 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] CGI data ready to be sent. D [02/Sep/2018:18:04:29 -0300] PID 1317 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors. D [02/Sep/2018:18:04:29 -0300] [Client 10] con->http=0x564afb02c960 D [02/Sep/2018:18:04:29 -0300] [Client 10] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1317, file=15 D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for CGI data. D [02/Sep/2018:18:04:29 -0300] [Client 10] Sending 0-length chunk. D [02/Sep/2018:18:04:29 -0300] [Client 10] Flushing write buffer. D [02/Sep/2018:18:04:29 -0300] [Client 10] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:29 -0300] [Client 10] Waiting for request. D [02/Sep/2018:18:04:29 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" I [02/Sep/2018:18:04:30 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:39 -0300] [Client 10] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:39 -0300] [Client 10] Closing connection. D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [02/Sep/2018:18:04:39 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:39 -0300] [Job 5] Unloading... D [02/Sep/2018:18:04:39 -0300] Report: clients=1 D [02/Sep/2018:18:04:39 -0300] Report: jobs=2 D [02/Sep/2018:18:04:39 -0300] Report: jobs-active=0 D [02/Sep/2018:18:04:39 -0300] Report: printers=1 D [02/Sep/2018:18:04:39 -0300] Report: stringpool-string-count=6473 D [02/Sep/2018:18:04:39 -0300] Report: stringpool-alloc-bytes=10632 D [02/Sep/2018:18:04:39 -0300] Report: stringpool-total-bytes=120968 D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [02/Sep/2018:18:04:39 -0300] [Client 12] Server address is "[v1.::1]". D [02/Sep/2018:18:04:39 -0300] [Client 12] Accepted from localhost:48848 (IPv6) D [02/Sep/2018:18:04:39 -0300] [Client 12] Waiting for request. D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:39 -0300] [Client 13] Server address is "[v1.::1]". D [02/Sep/2018:18:04:39 -0300] [Client 13] Accepted from localhost:48850 (IPv6) D [02/Sep/2018:18:04:39 -0300] [Client 13] Waiting for request. D [02/Sep/2018:18:04:39 -0300] [Client 12] POST /admin HTTP/1.1 D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:39 -0300] [Client 12] Read: status=200, state=6 D [02/Sep/2018:18:04:39 -0300] [Client 12] No authentication data provided. D [02/Sep/2018:18:04:39 -0300] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[10] = "SERVER_ADMIN=root@alq14" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[13] = "USER=root" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[17] = "IPP_PORT=631" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[18] = "LANG=pt_BR.UTF8" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[19] = "REDIRECT_STATUS=1" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[21] = "SERVER_NAME=localhost" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[22] = "SERVER_PORT=631" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[23] = "REMOTE_ADDR=[v1.::1]" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[24] = "REMOTE_HOST=localhost" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[25] = "SCRIPT_NAME=/admin" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=da682bf76bf34ba8b8d053c5b9bbcfee" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:61.0) Gecko/20100101 Firefox/61.0" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[30] = "HTTP_REFERER=http://localhost:631/admin" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[31] = "REQUEST_METHOD=POST" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[32] = "CONTENT_LENGTH=109" D [02/Sep/2018:18:04:39 -0300] [CGI] envp[33] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [02/Sep/2018:18:04:39 -0300] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 1318) I [02/Sep/2018:18:04:39 -0300] [Client 12] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=1318, file=17) D [02/Sep/2018:18:04:39 -0300] [Client 12] Waiting for CGI data. D [02/Sep/2018:18:04:39 -0300] [CGI] admin.cgi started... D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:39 -0300] [Client 14] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:39 -0300] [Client 14] Accepted from localhost (Domain) D [02/Sep/2018:18:04:39 -0300] [Client 14] Waiting for request. D [02/Sep/2018:18:04:39 -0300] [CGI] http=0x55ebccd5d7a0 D [02/Sep/2018:18:04:39 -0300] [CGI] cgiSetVariable: SECTION=\"admin\" D [02/Sep/2018:18:04:39 -0300] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [02/Sep/2018:18:04:39 -0300] [CGI] org.cups.sid cookie is \"da682bf76bf34ba8b8d053c5b9bbcfee\" D [02/Sep/2018:18:04:39 -0300] [CGI] cgiSetVariable: org.cups.sid=\"da682bf76bf34ba8b8d053c5b9bbcfee\" D [02/Sep/2018:18:04:39 -0300] [CGI] cgiSetVariable: OP=\"config-server\" D [02/Sep/2018:18:04:39 -0300] [CGI] cgiSetVariable: CHANGESETTINGS=\"Alterar configurações\" D [02/Sep/2018:18:04:39 -0300] [CGI] op=\"config-server\"... D [02/Sep/2018:18:04:39 -0300] [CGI] DefaultAuthType Basic D [02/Sep/2018:18:04:39 -0300] [Client 14] PUT /admin/conf/cupsd.conf HTTP/1.1 D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:39 -0300] [Client 14] Read: status=200, state=9 D [02/Sep/2018:18:04:39 -0300] [Client 14] No authentication data provided. D [02/Sep/2018:18:04:39 -0300] cupsdIsAuthorized: username="" D [02/Sep/2018:18:04:39 -0300] [Client 14] cupsdSendHeader: code=401, type="text/html", auth_type=0 D [02/Sep/2018:18:04:39 -0300] [Client 14] WWW-Authenticate: Basic realm=\"CUPS\", PeerCred, Local trc=\"y\" D [02/Sep/2018:18:04:39 -0300] [Client 14] Closing connection. D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:39 -0300] [CGI] cgi_passwd(prompt=\"Senha para cups em localhost? \") called! D [02/Sep/2018:18:04:39 -0300] [Client 12] CGI data ready to be sent. D [02/Sep/2018:18:04:39 -0300] [Client 12] con->http=0x564afb02c960 D [02/Sep/2018:18:04:39 -0300] [Client 12] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=1318, file=17 D [02/Sep/2018:18:04:39 -0300] [Client 12] Waiting for CGI data. D [02/Sep/2018:18:04:39 -0300] [Client 12] Script header: Status: 401 D [02/Sep/2018:18:04:39 -0300] [Client 12] Script header: D [02/Sep/2018:18:04:39 -0300] [Client 12] Sending status 401 for CGI. D [02/Sep/2018:18:04:39 -0300] [Client 12] cupsdSendHeader: code=401, type="text/html", auth_type=0 D [02/Sep/2018:18:04:39 -0300] [Client 12] WWW-Authenticate: Basic realm=\"CUPS\" D [02/Sep/2018:18:04:39 -0300] [Client 12] Flushing write buffer. D [02/Sep/2018:18:04:39 -0300] [Client 12] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:39 -0300] [Client 12] Waiting for request. D [02/Sep/2018:18:04:39 -0300] [Client 12] Closing because Keep-Alive is disabled. D [02/Sep/2018:18:04:39 -0300] [Client 12] Closing connection. D [02/Sep/2018:18:04:39 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [02/Sep/2018:18:04:39 -0300] PID 1318 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors. D [02/Sep/2018:18:04:44 -0300] [Client 13] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:44 -0300] [Client 13] Closing connection. D [02/Sep/2018:18:04:44 -0300] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" I [02/Sep/2018:18:04:44 -0300] Saving subscriptions.conf... D [02/Sep/2018:18:04:44 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files" I [02/Sep/2018:18:04:44 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [02/Sep/2018:18:04:45 -0300] [Client 15] Server address is "[v1.::1]". D [02/Sep/2018:18:04:45 -0300] [Client 15] Accepted from localhost:48852 (IPv6) D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for request. I [02/Sep/2018:18:04:45 -0300] Expiring subscriptions... D [02/Sep/2018:18:04:45 -0300] [Client 15] POST /admin HTTP/1.1 D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 15] Read: status=200, state=6 D [02/Sep/2018:18:04:45 -0300] [Client 15] Authorized as "root" using Basic. D [02/Sep/2018:18:04:45 -0300] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/admin.cgi" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[7] = "CUPS_STATEDIR=/run/cups" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[8] = "HOME=/var/spool/cups/tmp" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[10] = "SERVER_ADMIN=root@alq14" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[11] = "SOFTWARE=CUPS/2.2.8" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[13] = "USER=root" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[15] = "CUPS_SERVER=/run/cups/cups.sock" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[17] = "IPP_PORT=631" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[18] = "AUTH_TYPE=Basic" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[19] = "LANG=pt_BR.UTF8" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[20] = "REDIRECT_STATUS=1" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[22] = "SERVER_NAME=localhost" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[23] = "SERVER_PORT=631" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[24] = "REMOTE_ADDR=[v1.::1]" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[25] = "REMOTE_HOST=localhost" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[26] = "SCRIPT_NAME=/admin" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/cups/doc/admin" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[28] = "REMOTE_USER=root" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[30] = "HTTP_COOKIE=org.cups.sid=da682bf76bf34ba8b8d053c5b9bbcfee" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[31] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64; rv:61.0) Gecko/20100101 Firefox/61.0" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[32] = "HTTP_REFERER=http://localhost:631/admin" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[33] = "REQUEST_METHOD=POST" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[34] = "CONTENT_LENGTH=109" D [02/Sep/2018:18:04:45 -0300] [CGI] envp[35] = "CONTENT_TYPE=application/x-www-form-urlencoded" D [02/Sep/2018:18:04:45 -0300] cupsdAddCert: Adding certificate for PID 1323 D [02/Sep/2018:18:04:45 -0300] [CGI] Started /usr/lib/cups/cgi-bin/admin.cgi (PID 1323) I [02/Sep/2018:18:04:45 -0300] [Client 15] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=1323, file=16) D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [CGI] admin.cgi started... D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 16] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:45 -0300] [Client 16] Accepted from localhost (Domain) D [02/Sep/2018:18:04:45 -0300] [Client 16] Waiting for request. D [02/Sep/2018:18:04:45 -0300] [CGI] http=0x55e0b2dbe7a0 D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: SECTION=\"admin\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: REFRESH_PAGE=\"\" D [02/Sep/2018:18:04:45 -0300] [CGI] org.cups.sid cookie is \"da682bf76bf34ba8b8d053c5b9bbcfee\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: org.cups.sid=\"da682bf76bf34ba8b8d053c5b9bbcfee\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: OP=\"config-server\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: CHANGESETTINGS=\"Alterar configurações\" D [02/Sep/2018:18:04:45 -0300] [CGI] op=\"config-server\"... D [02/Sep/2018:18:04:45 -0300] [CGI] DefaultAuthType Basic D [02/Sep/2018:18:04:45 -0300] [Client 16] PUT /admin/conf/cupsd.conf HTTP/1.1 D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 16] Read: status=200, state=9 D [02/Sep/2018:18:04:45 -0300] [Client 16] No authentication data provided. D [02/Sep/2018:18:04:45 -0300] cupsdIsAuthorized: username="" D [02/Sep/2018:18:04:45 -0300] [Client 16] cupsdSendHeader: code=401, type="text/html", auth_type=0 D [02/Sep/2018:18:04:45 -0300] [Client 16] WWW-Authenticate: Basic realm=\"CUPS\", PeerCred, Local trc=\"y\" D [02/Sep/2018:18:04:45 -0300] [Client 16] Closing connection. D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 17] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:45 -0300] [Client 17] Accepted from localhost (Domain) D [02/Sep/2018:18:04:45 -0300] [Client 17] Waiting for request. D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 18] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:45 -0300] [Client 18] Accepted from localhost (Domain) D [02/Sep/2018:18:04:45 -0300] [Client 18] Waiting for request. D [02/Sep/2018:18:04:45 -0300] [Client 17] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:45 -0300] [Client 17] Closing connection. D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 19] Server address is "/run/cups/cups.sock". D [02/Sep/2018:18:04:45 -0300] [Client 19] Accepted from localhost (Domain) D [02/Sep/2018:18:04:45 -0300] [Client 19] Waiting for request. D [02/Sep/2018:18:04:45 -0300] [Client 18] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D [02/Sep/2018:18:04:45 -0300] [Client 18] Closing connection. D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 19] PUT /admin/conf/cupsd.conf HTTP/1.1 D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 19] Read: status=200, state=9 D [02/Sep/2018:18:04:45 -0300] [Client 19] Authorized as root using Local. D [02/Sep/2018:18:04:45 -0300] cupsdIsAuthorized: username="root" D [02/Sep/2018:18:04:45 -0300] [Client 19] Read: status=100, state=9 I [02/Sep/2018:18:04:45 -0300] [Client 19] Installing config file "/etc/cups/cupsd.conf"... D [02/Sep/2018:18:04:45 -0300] [Client 19] cupsdSendHeader: code=201, type="(null)", auth_type=0 D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 1] Closing connection. D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [Client 19] Closing connection. D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Active clients", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: refresh_page=\"5;URL=/admin/?OP=redirect\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: TITLE=\"Alterar configurações\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: REMOTE_USER=\"root\" D [02/Sep/2018:18:04:45 -0300] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.8\" D [02/Sep/2018:18:04:45 -0300] [Client 15] CGI data ready to be sent. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] Script header: Content-Type: text/html;charset=utf-8 D [02/Sep/2018:18:04:45 -0300] [Client 15] Script header: D [02/Sep/2018:18:04:45 -0300] [Client 15] Sending status 200 for CGI. D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdSendHeader: code=200, type="(null)", auth_type=0 D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] CGI data ready to be sent. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] CGI data ready to be sent. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] CGI data ready to be sent. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] CGI data ready to be sent. D [02/Sep/2018:18:04:45 -0300] [Client 15] con->http=0x564afb02c960 D [02/Sep/2018:18:04:45 -0300] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1323, file=16 D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for CGI data. D [02/Sep/2018:18:04:45 -0300] [Client 15] Sending 0-length chunk. D [02/Sep/2018:18:04:45 -0300] [Client 15] Flushing write buffer. D [02/Sep/2018:18:04:45 -0300] [Client 15] New state is HTTP_STATE_WAITING D [02/Sep/2018:18:04:45 -0300] [Client 15] Waiting for request. D [02/Sep/2018:18:04:45 -0300] [Client 15] Closing because Keep-Alive is disabled. D [02/Sep/2018:18:04:45 -0300] [Client 15] Closing connection. D [02/Sep/2018:18:04:45 -0300] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [02/Sep/2018:18:04:45 -0300] PID 1323 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors. E [02/Sep/2018:18:04:45 -0300] Scheduler shutting down due to program error. D [02/Sep/2018:18:04:45 -0300] Discarding unused server-stopped event... D [02/Sep/2018:18:04:45 -0300] Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd". I [02/Sep/2018:18:04:45 -0300] Saving job.cache... D [02/Sep/2018:18:04:45 -0300] cupsdStopSelect() E [02/Sep/2018:18:04:45 -0300] Unknown directive JobPrivateAccess on line 122 of /etc/cups/cupsd.conf. E [02/Sep/2018:18:04:45 -0300] Unknown directive JobPrivateValues on line 123 of /etc/cups/cupsd.conf. E [02/Sep/2018:18:04:45 -0300] Unknown directive SubscriptionPrivateAccess on line 124 of /etc/cups/cupsd.conf. E [02/Sep/2018:18:04:45 -0300] Unknown directive SubscriptionPrivateValues on line 125 of /etc/cups/cupsd.conf.