[cups] cups 2.0.2 on OpenWRT - no error but no print either

Gerrit Einhoff gerein at gmx.de
Sun Apr 12 06:25:06 PDT 2015


Hi,

I am relatively new to cups, so apologies if I'm missing something basic.
I'm trying to get a simple setup working, with cups 2.0.2 installed on my
router (under OpenWRT) with just one network printer (Brother MFC7820n)
connected via AppSocket on a fixed IP (socket://192.168.1.206:9100).

cups is setup correctly and I installed the printer via the web-interface
without any problem. The printer is also reachable/working, I can print out
PS-files if I just netcat them to port 9100.

However, if I try to print via cups (e.g., "lp -d BiS-socket letter.ps"),
the job is received and completes without errors but also without a
printout. A "tcpdump host 192.168.1.206" shows a bunch of UDP-packets with
SNMP requests being sent and answered but no TCP-data stream being opened
to port 9100.

I've set LogLevel to debug2 but the error.log doesn't seem to show anything
unusual. Any hints what I could be missing?

Below the abbreviated log-output (just the [Job x] part because it's very
long otherwise). I have also tried using the IPP-backend with identical
results.

Many thanks!
-Gerrit


root at OpenWrt:~# lp -d BiS-socket letter.ps
request id is BiS-socket-9 (1 file(s))
root at OpenWrt:~# grep "\[Job" /var/log/cups/error_log
I [12/Apr/2015:13:17:21 +0000] [Job 9] Adding start banner page "none".
I [12/Apr/2015:13:17:21 +0000] [Job 9] Queued on "BiS-socket" by "root".
D [12/Apr/2015:13:17:21 +0000] [Job 9] Auto-typing file...
D [12/Apr/2015:13:17:21 +0000] [Job 9] Request file type is
application/postscript.
I [12/Apr/2015:13:17:21 +0000] [Job 9] File of type application/postscript
queued by "root".
I [12/Apr/2015:13:17:21 +0000] [Job 9] Adding end banner page "none".
D [12/Apr/2015:13:17:21 +0000] [Job 9] time-at-processing=1428844641
D [12/Apr/2015:13:17:21 +0000] [Job 9] 2 filters for job:
D [12/Apr/2015:13:17:21 +0000] [Job 9] pstops (application/postscript to
application/vnd.cups-postscript, cost 66)
D [12/Apr/2015:13:17:21 +0000] [Job 9] brlpdwrapperMFC7820N
(application/vnd.cups-postscript to printer/BiS-socket, cost 0)
D [12/Apr/2015:13:17:21 +0000] [Job 9] job-sheets=none,none
D [12/Apr/2015:13:17:21 +0000] [Job 9] argv[0]="BiS-socket"
D [12/Apr/2015:13:17:21 +0000] [Job 9] argv[1]="9"
D [12/Apr/2015:13:17:21 +0000] [Job 9] argv[2]="root"
D [12/Apr/2015:13:17:21 +0000] [Job 9] argv[3]="letter.ps"
D [12/Apr/2015:13:17:21 +0000] [Job 9] argv[4]="1"
D [12/Apr/2015:13:17:21 +0000] [Job 9] argv[5]="finishings=3 number-up=1
job-uuid=urn:uuid:3540c722-5b95-3a0b-55b4-9ecf47843567
job-originating-host-name=localhost time-at-creation=1428844641
time-at-processing=1428844641"
D [12/Apr/2015:13:17:21 +0000] [Job 9] argv[6]="/var/spool/cups/d00009-001"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[2]="CUPS_DOCROOT=/usr/share/doc/cups"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[8]="HOME=/var/spool/cups/tmp"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[10]="SERVER_ADMIN=root at OpenWrt"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[11]="SOFTWARE=CUPS/2.0.2"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[13]="USER=root"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[14]="CUPS_MAX_MESSAGE=2047"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[15]="CUPS_SERVER=localhost"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[16]="CUPS_ENCRYPTION=IfRequested"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[17]="IPP_PORT=631"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[18]="CHARSET=utf-8"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[19]="LANG=en_US.UTF-8"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[20]="PPD=/etc/cups/ppd/BiS-socket.ppd"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[21]="RIP_MAX_CACHE=auto"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[22]="CONTENT_TYPE=application/postscript"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[23]="DEVICE_URI=socket://
192.168.1.206:9100"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[24]="PRINTER_INFO=BiS-socket"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[25]="PRINTER_LOCATION="
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[26]="PRINTER=BiS-socket"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[27]="PRINTER_STATE_REASONS=none"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[28]="CUPS_FILETYPE=document"
D [12/Apr/2015:13:17:21 +0000] [Job 9]
envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
D [12/Apr/2015:13:17:21 +0000] [Job 9] envp[30]="AUTH_I****"
I [12/Apr/2015:13:17:21 +0000] [Job 9] Started filter
/usr/lib/cups/filter/pstops (PID 12706)
I [12/Apr/2015:13:17:21 +0000] [Job 9] Started filter
/usr/lib/cups/filter/brlpdwrapperMFC7820N (PID 12707)
I [12/Apr/2015:13:17:21 +0000] [Job 9] Started backend
/usr/lib/cups/backend/socket (PID 12708)
D [12/Apr/2015:13:17:21 +0000] [Job 9] Page = 595x842; 18,12 to 577,830
D [12/Apr/2015:13:17:21 +0000] [Job 9] slow_collate=0, slow_duplex=0,
slow_order=0
D [12/Apr/2015:13:17:21 +0000] [Job 9] Before copy_comments - %!PS-Adobe-2.0
D [12/Apr/2015:13:17:21 +0000] [Job 9] %!PS-Adobe-2.0
D [12/Apr/2015:13:17:21 +0000] [Job 9] %%Creator: dvipsk 5.58f Copyright
1986, 1994 XXX
D [12/Apr/2015:13:17:21 +0000] [Job 9] %%Title: letter.dvi
D [12/Apr/2015:13:17:21 +0000] [Job 9] %%Pages: 1
D [12/Apr/2015:13:17:21 +0000] [Job 9] %%PageOrder: Ascend
D [12/Apr/2015:13:17:21 +0000] [Job 9] %%BoundingBox: 0 0 612 792
D [12/Apr/2015:13:17:21 +0000] [Job 9] %%DocumentPaperSizes: Letter
D [12/Apr/2015:13:17:21 +0000] [Job 9] %%EndComments
D [12/Apr/2015:13:17:21 +0000] [Job 9] Before copy_prolog -
%DVIPSCommandLine: dvips -o letter.ps letter.dvi
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: +connecting-to-device
D [12/Apr/2015:13:17:21 +0000] [Job 9] Looking up "192.168.1.206"...
d [12/Apr/2015:13:17:21 +0000] [Job 9] hrDeviceDesc="Brother MFC-7820N"
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtGeneralCurrentLocalization=1
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtLocalizationCharacterSet=4
D [12/Apr/2015:13:17:21 +0000] [Job 9] Before copy_setup - %%BeginSetup
D [12/Apr/2015:13:17:21 +0000] [Job 9] Before page loop - %%Page: 53 1
D [12/Apr/2015:13:17:21 +0000] [Job 9] Copying page 1...
D [12/Apr/2015:13:17:21 +0000] [Job 9] pagew = 559.0, pagel = 818.0
D [12/Apr/2015:13:17:21 +0000] [Job 9] bboxx = 0, bboxy = 0, bboxw = 595,
bboxl = 842
D [12/Apr/2015:13:17:21 +0000] [Job 9] PageLeft = 18.0, PageRight = 577.0
D [12/Apr/2015:13:17:21 +0000] [Job 9] PageTop = 830.0, PageBottom = 12.0
D [12/Apr/2015:13:17:21 +0000] [Job 9] PageWidth = 595.0, PageLength = 842.0
D [12/Apr/2015:13:17:21 +0000] [Job 9] Wrote 1 pages...
D [12/Apr/2015:13:17:21 +0000] [Job 9] PID 12706
(/usr/lib/cups/filter/pstops) exited with no errors.
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesColorantIndex.1.1 =
1
D [12/Apr/2015:13:17:21 +0000] [Job 9] PID 12707
(/usr/lib/cups/filter/brlpdwrapperMFC7820N) exited with no errors.
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesColorantIndex.1.2 =
1
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesClass.1.1 = 3
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesClass.1.2 = 3
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesType.1.1 = 3
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesType.1.2 = 9
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesDescription.1.1 =
"TN-350/TN-2000/TN-2025/TN-2075"
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesDescription.1.2 =
"DR-350/DR-2000/DR-2025/DR-2075"
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesSupplyUnit.1.1 = 13
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesSupplyUnit.1.2 = 7
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesMaxCapacity.1.1 = -2
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesMaxCapacity.1.2 = -2
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesLevel.1.1 = -3
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerSuppliesLevel.1.2 = -3
d [12/Apr/2015:13:17:21 +0000] [Job 9] prtMarkerColorantValue.1.1 = "black"
D [12/Apr/2015:13:17:21 +0000] [Job 9] ATTR: marker-colors=#000000,#000000
D [12/Apr/2015:13:17:21 +0000] [Job 9] ATTR:
marker-names='"TN-350/TN-2000/TN-2025/TN-2075"','"DR-350/DR-2000/DR-2025/DR-2075"'
D [12/Apr/2015:13:17:21 +0000] [Job 9] ATTR: marker-types=toner,opc
D [12/Apr/2015:13:17:21 +0000] [Job 9] ATTR: marker-levels=-1,-1
D [12/Apr/2015:13:17:21 +0000] [Job 9] new_supply_state=0, change_state=ffff
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -developer-low-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -developer-empty-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -marker-supply-low-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -marker-supply-empty-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -opc-near-eol-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -opc-life-over-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -toner-low-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -toner-empty-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE:
-waste-receptacle-almost-full-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -waste-receptacle-full-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE:
-cleaner-life-almost-over-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -cleaner-life-over-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] new_state=0, change_state=ffff
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -media-empty-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -door-open-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -media-jam-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -input-tray-missing-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -output-tray-missing-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -marker-supply-missing-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE:
-output-area-almost-full-report
D [12/Apr/2015:13:17:21 +0000] [Job 9] STATE: -output-area-full-warning
D [12/Apr/2015:13:17:21 +0000] [Job 9] backendWaitLoop(snmp_fd=5,
addr=0xb7172a84, side_cb=0xb6f37520)
D [12/Apr/2015:13:17:21 +0000] [Job 9] PID 12708
(/usr/lib/cups/backend/socket) exited with no errors.
D [12/Apr/2015:13:17:21 +0000] [Job 9] time-at-completed=1428844641
I [12/Apr/2015:13:17:21 +0000] [Job 9] Job completed.
D [12/Apr/2015:13:17:21 +0000] [Job 9] Removing from history.
D [12/Apr/2015:13:17:21 +0000] [Job 9] Unloading...



More information about the cups mailing list