I [16/Mar/2007:11:54:34 +1100] Full reload is required. D [16/Mar/2007:11:54:34 +1100] LoadAllPrinters: Loading printer tp0... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "ipp"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "http"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "epson:/dev/lp0"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "canon:/dev/lp0"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "smb"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "lpd"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "parallel:/dev/lp0"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "socket"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb://Canon/MP780"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb://Canon/MP780%20FAX"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp2"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp3"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp4"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp5"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp6"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp7"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp8"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp9"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp10"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp11"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp12"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp13"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp14"... D [16/Mar/2007:11:54:34 +1100] LoadDevices: Added device "usb:/dev/usb/lp15"... I [16/Mar/2007:11:54:34 +1100] LoadPPDs: Read "/etc/cups/ppds.dat", 3040 PPDs... I [16/Mar/2007:11:54:35 +1100] LoadPPDs: No new or changed PPDs... D [16/Mar/2007:11:54:35 +1100] LoadAllJobs: Scanning /var/spool/cups... D [16/Mar/2007:11:54:35 +1100] LoadAllJobs: Loading attributes for job 1... D [16/Mar/2007:11:54:35 +1100] LoadAllJobs: Loading attributes for job 2... D [16/Mar/2007:11:54:35 +1100] LoadAllJobs: Loading attributes for job 3... D [16/Mar/2007:11:54:35 +1100] LoadAllJobs: Loading attributes for job 4... D [16/Mar/2007:11:54:35 +1100] LoadAllJobs: Loading attributes for job 5... D [16/Mar/2007:11:54:35 +1100] LoadAllJobs: Loading attributes for job 6... I [16/Mar/2007:11:54:35 +1100] Full reload complete. D [16/Mar/2007:11:54:35 +1100] StartListening: NumListeners=1 D [16/Mar/2007:11:54:35 +1100] StartListening: address=00000000 port=631 D [16/Mar/2007:11:54:35 +1100] ResumeListening: setting input bits... D [16/Mar/2007:11:55:09 +1100] AcceptClient: 5 from 10.0.0.99:631. D [16/Mar/2007:11:55:09 +1100] ReadClient: 5 GET /printers HTTP/1.1 D [16/Mar/2007:11:55:09 +1100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 2860 I [16/Mar/2007:11:55:09 +1100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2860) D [16/Mar/2007:11:55:09 +1100] SendCommand: 5 file=7 D [16/Mar/2007:11:55:09 +1100] AcceptClient: 8 from localhost:631. D [16/Mar/2007:11:55:09 +1100] ReadClient: 8 POST / HTTP/1.1 D [16/Mar/2007:11:55:09 +1100] ProcessIPPRequest: 8 status_code=1 D [16/Mar/2007:11:55:09 +1100] ReadClient: 8 POST / HTTP/1.1 D [16/Mar/2007:11:55:09 +1100] ProcessIPPRequest: 8 status_code=1 D [16/Mar/2007:11:55:09 +1100] CloseClient: 8 D [16/Mar/2007:11:55:15 +1100] ReadClient: 5 GET /printers/tp0?op=print-test-page HTTP/1.1 D [16/Mar/2007:11:55:15 +1100] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 2862 I [16/Mar/2007:11:55:15 +1100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=2862) D [16/Mar/2007:11:55:15 +1100] SendCommand: 5 file=8 D [16/Mar/2007:11:55:15 +1100] AcceptClient: 7 from localhost:631. D [16/Mar/2007:11:55:15 +1100] ReadClient: 7 POST /printers/tp0 HTTP/1.1 D [16/Mar/2007:11:55:15 +1100] print_job: request file type is application/postscript. D [16/Mar/2007:11:55:15 +1100] check_quotas: requesting-user-name = 'root' D [16/Mar/2007:11:55:15 +1100] print_job: requesting-user-name = 'root' D [16/Mar/2007:11:55:15 +1100] Adding default job-sheets values "none,none"... I [16/Mar/2007:11:55:15 +1100] Adding start banner page "none" to job 7. I [16/Mar/2007:11:55:15 +1100] Adding end banner page "none" to job 7. I [16/Mar/2007:11:55:15 +1100] Job 7 queued on 'tp0' by 'root'. D [16/Mar/2007:11:55:15 +1100] Job 7 hold_until = 0 D [16/Mar/2007:11:55:15 +1100] StartJob(7, 0x80b0220) D [16/Mar/2007:11:55:15 +1100] StartJob() id = 7, file = 0/1 D [16/Mar/2007:11:55:15 +1100] job-sheets=none,none D [16/Mar/2007:11:55:15 +1100] banner_page = 0 D [16/Mar/2007:11:55:15 +1100] StartJob: argv = "tp0","7","root","Test Page","1","","/var/spool/cups/d00007-001" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[1]="SOFTWARE=CUPS/1.1" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[2]="USER=root" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[3]="CHARSET=iso-8859-1" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[4]="LANG=en_US" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[5]="TZ=Australia/Hobart" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[6]="PPD=/etc/cups/ppd/tp0.ppd" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[8]="RIP_MAX_CACHE=8m" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[10]="CONTENT_TYPE=application/postscript" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[11]="DEVICE_URI=usb://Canon/MP780" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[12]="PRINTER=tp0" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[15]="CUPS_SERVER=localhost" D [16/Mar/2007:11:55:15 +1100] StartJob: envp[16]="IPP_PORT=631" D [16/Mar/2007:11:55:15 +1100] StartJob: statusfds = [ 9 10 ] D [16/Mar/2007:11:55:15 +1100] StartJob: filterfds[1] = [ 11 -1 ] D [16/Mar/2007:11:55:15 +1100] StartJob: filter = "/usr/lib/cups/filter/pstops" D [16/Mar/2007:11:55:15 +1100] StartJob: filterfds[0] = [ 12 13 ] D [16/Mar/2007:11:55:15 +1100] start_process("/usr/lib/cups/filter/pstops", 0xbfff0130, 0xbffef4a0, 11, 13, 10) I [16/Mar/2007:11:55:15 +1100] Started filter /usr/lib/cups/filter/pstops (PID 2863) for job 7. D [16/Mar/2007:11:55:15 +1100] StartJob: filter = "/usr/lib/cups/filter/pstoturboprint" D [16/Mar/2007:11:55:15 +1100] StartJob: filterfds[1] = [ 11 14 ] D [16/Mar/2007:11:55:15 +1100] start_process("/usr/lib/cups/filter/pstoturboprint", 0xbfff0130, 0xbffef4a0, 12, 14, 10) I [16/Mar/2007:11:55:15 +1100] Started filter /usr/lib/cups/filter/pstoturboprint (PID 2864) for job 7. D [16/Mar/2007:11:55:15 +1100] StartJob: backend = "/usr/lib/cups/backend/usb" D [16/Mar/2007:11:55:15 +1100] StartJob: filterfds[0] = [ -1 12 ] D [16/Mar/2007:11:55:15 +1100] start_process("/usr/lib/cups/backend/usb", 0xbfff0130, 0xbffef4a0, 11, 12, 10) I [16/Mar/2007:11:55:15 +1100] Started backend /usr/lib/cups/backend/usb (PID 2866) for job 7. D [16/Mar/2007:11:55:15 +1100] ProcessIPPRequest: 7 status_code=0 D [16/Mar/2007:11:55:15 +1100] [Job 7] Page = 595x842; 10,20 to 586,833 D [16/Mar/2007:11:55:15 +1100] [Job 7] slowcollate=0, slowduplex=0, sloworder=0 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%BoundingBox: 0 0 612 792 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%Pages: 1 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%LanguageLevel: 1 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%DocumentData: Clean7Bit D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%DocumentSuppliedResources: procset testprint/1.1 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%Creator: Michael Sweet, Easy Software Products D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%CreationDate: May 11, 1999 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%Title: Test Page D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%EndComments D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%BeginProlog D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%BeginResource procset testprint 1.1 0 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%EndResource D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%EndProlog D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%Page: 1 1 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%Page: 1 1 D [16/Mar/2007:11:55:15 +1100] [Job 7] pw = 576.0, pl = 813.0 D [16/Mar/2007:11:55:15 +1100] [Job 7] PageLeft = 10.0, PageRight = 586.0 D [16/Mar/2007:11:55:15 +1100] [Job 7] PageTop = 833.0, PageBottom = 20.0 D [16/Mar/2007:11:55:15 +1100] [Job 7] PageWidth = 595.0, PageLength = 842.0 D [16/Mar/2007:11:55:15 +1100] [Job 7] 0 %%EOF D [16/Mar/2007:11:55:15 +1100] [Job 7] Saw EOF! D [16/Mar/2007:11:55:15 +1100] CloseClient: 7 D [16/Mar/2007:11:55:15 +1100] [Job 7] Printer using device file "/dev/usb/lp0"... D [16/Mar/2007:11:55:15 +1100] [Job 7] LPGETSTATUS returned a port status of 18... D [16/Mar/2007:11:55:15 +1100] [Job 7] tpprint: cannot read -sx D [16/Mar/2007:11:55:15 +1100] [Job 7] /usr/lib/cups/filter/pstoturboprint: line 42: kill: (2877) - No such process D [16/Mar/2007:11:55:16 +1100] [Job 7] [2]+ Terminated /usr/share/turboprint/lib/tpstdin --paste /tmp/pstoturboprint2864.chunk | gs -sDEVICE=pcx24b -rx -gx -dSAFER -dNOPAUSE -dBATCH -sOutputFile=/tmp/pstoturboprint2864.fifo - >>/var/log/turboprint_cups.log D [16/Mar/2007:11:55:16 +1100] [Job 7] /usr/lib/cups/filter/pstoturboprint: line 58: kill: (2877) - No such process D [16/Mar/2007:11:55:16 +1100] UpdateJob: job 7, file 0 is complete. D [16/Mar/2007:11:55:16 +1100] CancelJob: id = 7 D [16/Mar/2007:11:55:16 +1100] StopJob: id = 7, force = 0 D [16/Mar/2007:11:55:16 +1100] StopJob: printer state is 3 D [16/Mar/2007:11:56:17 +1100] CloseClient: 5