You are not logged in.
- Topics: Active | Unanswered
#1 2012-06-15 18:49:29
- Declan991
- Member
- Registered: 2012-06-15
- Posts: 3
Canon iP4700 and CUPS - CUPS thinks printed; nothing happens
So I've installed the Canon rpm using rpmextract and CUPS (1.5.3) using the Arch wiki. All my print jobs etc. are appearing on CUPS (including test pages), but nothing is happening at all on the printer. Goes through sending files to printer and then completion stages. CUPS tells me that it's idle, accepting jobs and not shared, "Defaults: job-sheets=none, none media=iso_a4_210x297mm sides=one-sided", connection = " usb://Canon/iP4700%20series?serial=92ED9C" which makes sense as it's a USB printer. The driver is "Canon iP4700 series Ver.3.20 (color, 2-sided printing)". I've no experience getting printers to work with Linux whatsoever, so I'm sorry if I left something obvious out!
Offline
#2 2012-06-15 18:52:17
- AndyRTR
- Developer
- From: Magdeburg/Germany
- Registered: 2005-10-07
- Posts: 1,642
Re: Canon iP4700 and CUPS - CUPS thinks printed; nothing happens
Check the cups log files (increas debug level).
Offline
#3 2012-06-15 19:20:46
- Declan991
- Member
- Registered: 2012-06-15
- Posts: 3
Re: Canon iP4700 and CUPS - CUPS thinks printed; nothing happens
Ok, I'm not really sure what's important, so here's the last few hundred lines:
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1
D [15/Jun/2012:20:11:03 +0100] Get-Printer-Attributes ipp://localhost/printers/iP4700
D [15/Jun/2012:20:11:03 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/iP4700) from localhost
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_is_accepting_jobs[0]="1"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_is_shared[0]="0"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_state[0]="4"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: admin_uri[0]="/admin/"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_uri_supported[0]="/printers/iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_name[0]="iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_location[0]=""
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_info[0]="Canon iP4700 series"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_sheets_default[0]="none, none"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: device_uri[0]="usb://Canon/iP4700%20series?serial=92ED9C"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: color_supported[0]="1"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_make_and_model[0]="Canon iP4700 series Ver.3.20"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: media_default[0]="iso_a4_210x297mm"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: sides_supported[0]="one-sided, two-sided-long-edge, two-sided-short-edge"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: sides_default[0]="one-sided"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: printer_commands[0]="AutoConfigure, Clean, PrintSelfTestPage"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: server_is_sharing_printers[0]="0"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: refresh_page="10;URL=/printers/iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: TITLE="iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [15/Jun/2012:20:11:03 +0100] Script header: Content-Type: text/html;charset=utf-8
D [15/Jun/2012:20:11:03 +0100] Script header:
D [15/Jun/2012:20:11:03 +0100] [CGI] Regular expression ".*Clean.*"
D [15/Jun/2012:20:11:03 +0100] [CGI] matches[0].rm_so=0
D [15/Jun/2012:20:11:03 +0100] [CGI] matches[1].rm_so=-1
D [15/Jun/2012:20:11:03 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*"
D [15/Jun/2012:20:11:03 +0100] [CGI] matches[0].rm_so=0
D [15/Jun/2012:20:11:03 +0100] [CGI] matches[1].rm_so=-1
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 18 POST / HTTP/1.1
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 18 1.1 Get-Jobs 1
D [15/Jun/2012:20:11:03 +0100] Get-Jobs ipp://localhost:631/printers/iP4700
D [15/Jun/2012:20:11:03 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/iP4700) from localhost
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiClearVariables called.
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: ORDER="asc"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: SECTION="printers"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: TOTAL="1"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_printer_name[0]="iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_printer_uri[0]="/printers/iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_name[0]="Test Page"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_originating_user_name[0]="anonymous"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: time_at_creation[0]="Fri Jun 15 20:10:40 2012"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: time_at_processing[0]="Fri Jun 15 20:10:40 2012"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: time_at_completed[0]="novalue"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_id[0]="12"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_state[0]="5"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_media_sheets_completed[0]="0"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_k_octets[0]="1"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetArray: job_printer_state_message[0]="Sending data to printer."
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: PRINTER_NAME="iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED="/printers/iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: THISURL="/printers/iP4700"
D [15/Jun/2012:20:11:03 +0100] [CGI] cgiSetVariable: SEARCH_DEST="iP4700"
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 18 WAITING Closing on EOF
D [15/Jun/2012:20:11:03 +0100] cupsdCloseClient: 18
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] PID 791 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 14 GET /cups.css HTTP/1.1
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 14 GET /images/left.gif HTTP/1.1
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 17 GET /images/right.gif HTTP/1.1
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdReadClient: 17 GET /images/sel.gif HTTP/1.1
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:03 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:03 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Jun/2012:20:11:03 +0100] [Job 12] Read 379 bytes of back-channel data...
D [15/Jun/2012:20:11:04 +0100] [Job 12] Read 512 bytes of back-channel data...
D [15/Jun/2012:20:11:05 +0100] [Job 12] Read 379 bytes of back-channel data...
D [15/Jun/2012:20:11:06 +0100] [Job 12] Read 512 bytes of back-channel data...
D [15/Jun/2012:20:11:07 +0100] [Job 12] Read 379 bytes of back-channel data...
D [15/Jun/2012:20:11:08 +0100] [Job 12] Read 512 bytes of back-channel data...
D [15/Jun/2012:20:11:09 +0100] [Job 12] Page = 595x842; 10,14 to 586,833
D [15/Jun/2012:20:11:09 +0100] [Job 12] slow_collate=0, slow_duplex=0, slow_order=0
D [15/Jun/2012:20:11:09 +0100] [Job 12] Before copy_comments - %!PS-Adobe-3.0
D [15/Jun/2012:20:11:09 +0100] [Job 12] %!PS-Adobe-3.0
D [15/Jun/2012:20:11:09 +0100] [Job 12] %%BoundingBox: 0 0 612 792
D [15/Jun/2012:20:11:09 +0100] [Job 12] %%Creator: GPL Ghostscript 905 (ps2write)
D [15/Jun/2012:20:11:09 +0100] [Job 12] %%LanguageLevel: 2
D [15/Jun/2012:20:11:09 +0100] [Job 12] %%CreationDate: D:20120615201041+01'00'
D [15/Jun/2012:20:11:09 +0100] [Job 12] %%Pages: 1
D [15/Jun/2012:20:11:09 +0100] [Job 12] %%EndComments
D [15/Jun/2012:20:11:09 +0100] [Job 12] Before copy_prolog - %%BeginProlog
D [15/Jun/2012:20:11:09 +0100] [Job 12] Read 379 bytes of back-channel data...
D [15/Jun/2012:20:11:09 +0100] [Job 12] Before copy_setup - %%Page: 1 1
D [15/Jun/2012:20:11:09 +0100] [Job 12] Before page loop - %%Page: 1 1
D [15/Jun/2012:20:11:09 +0100] [Job 12] Copying page 1...
D [15/Jun/2012:20:11:09 +0100] [Job 12] pagew = 576.0, pagel = 819.2
D [15/Jun/2012:20:11:09 +0100] [Job 12] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [15/Jun/2012:20:11:09 +0100] [Job 12] PageLeft = 9.6, PageRight = 585.6
D [15/Jun/2012:20:11:09 +0100] [Job 12] PageTop = 833.4, PageBottom = 14.2
D [15/Jun/2012:20:11:09 +0100] [Job 12] PageWidth = 595.0, PageLength = 842.0
D [15/Jun/2012:20:11:09 +0100] [Job 12] device_uri=(usb://Canon/iP4700%20series?serial=92ED9C)
D [15/Jun/2012:20:11:09 +0100] [Job 12] p_ppd->model_number=(361)
D [15/Jun/2012:20:11:09 +0100] [Job 12] # p_size->name=A4 , p_size_default->name = A4 #
D [15/Jun/2012:20:11:09 +0100] [Job 12] ### num_opt(lpr optins) = 4 ###
D [15/Jun/2012:20:11:09 +0100] [Job 12]
D [15/Jun/2012:20:11:09 +0100] [Job 12] # ppdPageSize width=595.000000 height=842.000000 ###
D [15/Jun/2012:20:11:09 +0100] [Job 12]
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : p_choice found in p_ppd (p_choice:A4 210.0x297.0mm / A4)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : choice=A4(p_table->ppd_key=PageSize)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : p_choice found in p_ppd (p_choice:Plain Paper / plain)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : choice=plain(p_table->ppd_key=MediaType)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : p_choice found in p_ppd (p_choice:Automatically Select / auto)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : choice=auto(p_table->ppd_key=InputSlot)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNCartridge is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNQuality is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNHalftoning is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNRenderIntent is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNGamma is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNBalanceC is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNBalanceM is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNBalanceY is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNDensity is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNGrayscale is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNCopies is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : p_choice found in p_ppd (p_choice:OFF / None)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNStapleSide is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : CNContrast is not found in p_ppd
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : p_choice found in p_ppd (p_choice:2 / 2)
D [15/Jun/2012:20:11:09 +0100] [Job 12] (3)Make cif options : choice=2(p_table->ppd_key=CNExtension)
D [15/Jun/2012:20:11:09 +0100] [Job 12] ### lpr optins(3) : job-originating-host-name=localhost ###
D [15/Jun/2012:20:11:09 +0100] [Job 12] ### lpr optins(2) : job-uuid=urn:uuid:18240d1b-91f2-3674-624c-0f1ed58e5665 ###
D [15/Jun/2012:20:11:09 +0100] [Job 12] ### lpr optins(1) : time-at-creation=1339787440 ###
D [15/Jun/2012:20:11:09 +0100] [Job 12] ### lpr optins(0) : time-at-processing=1339787440 ###
D [15/Jun/2012:20:11:09 +0100] [Job 12] make_filter_param_list: --fit
D [15/Jun/2012:20:11:09 +0100] [Job 12] make_filter_param_list: --bbox
D [15/Jun/2012:20:11:09 +0100] [Job 12] make_filter_param_list: --extension
D [15/Jun/2012:20:11:09 +0100] [Job 12] make_filter_param_list: --paperload
D [15/Jun/2012:20:11:09 +0100] [Job 12] make_filter_param_list: --media
D [15/Jun/2012:20:11:09 +0100] [Job 12] make_filter_param_list: --papersize
D [15/Jun/2012:20:11:09 +0100] [Job 12] pstocanonij: /usr/bin/gs -r600 -g4958x7016 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -| /usr/local/bin/cifip4700 --imageres 600 --papersize a4 --media plain --pa
perload auto --bbox 9,14,586,834 --fit
D [15/Jun/2012:20:11:10 +0100] [Job 12] /usr/local/bin/cifip4700: error while loading shared libraries: libcnbpcmcm361.so: cannot open shared object file: No such file or directory
I [15/Jun/2012:20:11:10 +0100] Saving job.cache...
D [15/Jun/2012:20:11:10 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:10 +0100] [Job 12] Read 512 bytes of back-channel data...
D [15/Jun/2012:20:11:11 +0100] [Job 12] Read 379 bytes of back-channel data...
D [15/Jun/2012:20:11:12 +0100] [Job 12] PID 785 (gs) exited with no errors.
D [15/Jun/2012:20:11:12 +0100] [Job 12] Wrote 1 pages...
D [15/Jun/2012:20:11:12 +0100] [Job 12] PID 786 (pstops) exited with no errors.
D [15/Jun/2012:20:11:12 +0100] PID 778 (/usr/lib/cups/filter/pdftops) exited with no errors.
D [15/Jun/2012:20:11:12 +0100] [Job 12] Sent 0 bytes...
D [15/Jun/2012:20:11:12 +0100] [Job 12] Waiting for read thread to exit...
D [15/Jun/2012:20:11:12 +0100] PID 779 (/usr/lib/cups/filter/pstocanonij) exited with no errors.
D [15/Jun/2012:20:11:12 +0100] PID 780 (/usr/lib/cups/backend/usb) exited with no errors.
D [15/Jun/2012:20:11:12 +0100] Discarding unused job-completed event...
I [15/Jun/2012:20:11:12 +0100] [Job 12] Job completed.
D [15/Jun/2012:20:11:12 +0100] cupsdMarkDirty(----J-)
D [15/Jun/2012:20:11:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs"
D [15/Jun/2012:20:11:12 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:12 +0100] Discarding unused printer-state-changed event...
D [15/Jun/2012:20:11:13 +0100] [Job 12] Unloading...
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 14 GET /printers/iP4700 HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/printers.cgi"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@(none)"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[13] = "USER=root"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[16] = "IPP_PORT=631"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[17] = "LANG=en_US.UTF8"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[18] = "REDIRECT_STATUS=1"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[19] = "GATEWAY_INTERFACE=CGI/1.1"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[20] = "SERVER_NAME=localhost"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[21] = "SERVER_PORT=631"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[22] = "REMOTE_ADDR=[v1.::1]"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[23] = "REMOTE_HOST=localhost"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[24] = "SCRIPT_NAME=/printers/iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[25] = "SCRIPT_FILENAME=/usr/share/cups/doc/printers/iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[26] = "PATH_INFO=/iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[28] = "HTTP_COOKIE=org.cups.sid=9755bce312cebd96f1a6de76e37baecb"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux i686; rv:13.0) Gecko/20100101 Firefox/13.0"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[30] = "REQUEST_METHOD=GET"
D [15/Jun/2012:20:11:13 +0100] [CGI] envp[31] = "QUERY_STRING="
D [15/Jun/2012:20:11:13 +0100] [CGI] Started /usr/lib/cups/cgi-bin/printers.cgi (PID 798)
I [15/Jun/2012:20:11:13 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=798)
D [15/Jun/2012:20:11:13 +0100] cupsdSendCommand: 14 file=16
D [15/Jun/2012:20:11:13 +0100] [CGI] org.cups.sid cookie is "9755bce312cebd96f1a6de76e37baecb"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: SECTION="printers"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: PRINTER_NAME="iP4700"
D [15/Jun/2012:20:11:13 +0100] cupsdAcceptClient: 18 from localhost (Domain)
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 18 POST / HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 18 1.1 CUPS-Get-Default 1
D [15/Jun/2012:20:11:13 +0100] CUPS-Get-Default
D [15/Jun/2012:20:11:13 +0100] CUPS-Get-Default client-error-not-found: No default printer.
D [15/Jun/2012:20:11:13 +0100] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] [CGI] show_printer(http=0xb8e5d780, printer="iP4700")
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 18 POST / HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 18 1.1 Get-Printer-Attributes 1
D [15/Jun/2012:20:11:13 +0100] Get-Printer-Attributes ipp://localhost/printers/iP4700
D [15/Jun/2012:20:11:13 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/iP4700) from localhost
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_is_accepting_jobs[0]="1"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_is_shared[0]="0"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_state[0]="3"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: admin_uri[0]="/admin/"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_uri_supported[0]="/printers/iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_name[0]="iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_location[0]=""
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_info[0]="Canon iP4700 series"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: job_sheets_default[0]="none, none"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: device_uri[0]="usb://Canon/iP4700%20series?serial=92ED9C"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: color_supported[0]="1"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_make_and_model[0]="Canon iP4700 series Ver.3.20"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: media_default[0]="iso_a4_210x297mm"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: sides_supported[0]="one-sided, two-sided-long-edge, two-sided-short-edge"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: sides_default[0]="one-sided"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: printer_commands[0]="AutoConfigure, Clean, PrintSelfTestPage"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetArray: server_is_sharing_printers[0]="0"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: TITLE="iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: SERVER_NAME="localhost"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.5.3"
D [15/Jun/2012:20:11:13 +0100] Script header: Content-Type: text/html;charset=utf-8
D [15/Jun/2012:20:11:13 +0100] Script header:
D [15/Jun/2012:20:11:13 +0100] [CGI] Regular expression ".*Clean.*"
D [15/Jun/2012:20:11:13 +0100] [CGI] matches[0].rm_so=0
D [15/Jun/2012:20:11:13 +0100] [CGI] matches[1].rm_so=-1
D [15/Jun/2012:20:11:13 +0100] [CGI] Regular expression ".*PrintSelfTestPage.*"
D [15/Jun/2012:20:11:13 +0100] [CGI] matches[0].rm_so=0
D [15/Jun/2012:20:11:13 +0100] [CGI] matches[1].rm_so=-1
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 18 POST / HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 18 1.1 Get-Jobs 1
D [15/Jun/2012:20:11:13 +0100] Get-Jobs ipp://localhost:631/printers/iP4700
D [15/Jun/2012:20:11:13 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/iP4700) from localhost
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiClearVariables called.
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: ORDER="asc"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: SECTION="printers"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: TOTAL="0"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: PRINTER_NAME="iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED="/printers/iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: THISURL="/printers/iP4700"
D [15/Jun/2012:20:11:13 +0100] [CGI] cgiSetVariable: SEARCH_DEST="iP4700"
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 18 WAITING Closing on EOF
D [15/Jun/2012:20:11:13 +0100] cupsdCloseClient: 18
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] PID 798 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 14 GET /cups.css HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 14 GET /images/left.gif HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 17 GET /images/right.gif HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 14 GET /images/unsel.gif HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdReadClient: 17 GET /images/sel.gif HTTP/1.1
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [15/Jun/2012:20:11:13 +0100] cupsdAuthorize: No authentication data provided.
D [15/Jun/2012:20:11:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [15/Jun/2012:20:11:43 +0100] Saving job.cache...
D [15/Jun/2012:20:11:43 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [15/Jun/2012:20:11:43 +0100] Report: clients=2
D [15/Jun/2012:20:11:43 +0100] Report: jobs=12
D [15/Jun/2012:20:11:43 +0100] Report: jobs-active=0
D [15/Jun/2012:20:11:43 +0100] Report: printers=1
D [15/Jun/2012:20:11:43 +0100] Report: printers-implicit=0
D [15/Jun/2012:20:11:43 +0100] Report: stringpool-string-count=8065
D [15/Jun/2012:20:11:43 +0100] Report: stringpool-alloc-bytes=9488
D [15/Jun/2012:20:11:43 +0100] Report: stringpool-total-bytes=148136
D [15/Jun/2012:20:11:44 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [15/Jun/2012:20:11:44 +0100] cupsdCloseClient: 17
D [15/Jun/2012:20:11:44 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [15/Jun/2012:20:11:44 +0100] cupsdReadClient: 14 WAITING Closing on EOF
D [15/Jun/2012:20:11:44 +0100] cupsdCloseClient: 14
D [15/Jun/2012:20:11:44 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"Offline
#4 2012-06-16 07:55:08
- AndyRTR
- Developer
- From: Magdeburg/Germany
- Registered: 2005-10-07
- Posts: 1,642
Re: Canon iP4700 and CUPS - CUPS thinks printed; nothing happens
D [15/Jun/2012:20:11:10 +0100] [Job 12] /usr/local/bin/cifip4700: error while loading shared libraries: libcnbpcmcm361.so: cannot open shared object file: No such file or directory
Your local custom driver pkg is broken. Maybe a recompile helps.
Offline
#5 2012-06-16 12:23:25
- David Batson
- Member
- Registered: 2011-10-13
- Posts: 640
Re: Canon iP4700 and CUPS - CUPS thinks printed; nothing happens
You probably have not created the necessary symbolic links yet. See following post for more into.
https://bbs.archlinux.org/viewtopic.php … 2#p1007702
You might also need older versions of libpng, libtiff, and libjpeg. See following for more info.
https://bbs.archlinux.org/viewtopic.php?id=136415
Offline