[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

[debian-users:44346] can't print with CUPS

From: Ippei Tamura <ippei1@xxxxxxxxxxxx>
Subject: [debian-users:44346] can't print with CUPS
Date: Fri, 5 Aug 2005 16:25:27 +0900
List-help: <mailto:debian-users-ctl@debian.or.jp?body=help>
List-id: debian-users.debian.or.jp
List-owner: <mailto:debian-users-admin@debian.or.jp>
List-post: <mailto:debian-users@debian.or.jp>
List-software: fml [fml 4.0.3 release (20011202/4.0.3)]
List-unsubscribe: <mailto:debian-users-ctl@debian.or.jp?body=unsubscribe>
Organization: DDTP ja team coordinator
X-ml-info: If you have a question, send e-mail with the body "help" (without quotes) to the address debian-users-ctl@debian.or.jp; help=<mailto:debian-users-ctl@debian.or.jp?body=help>
X-ml-name: debian-users
X-mlserver: fml [fml 4.0.3 release (20011202/4.0.3)]; post only (only members can post)
X-original-to: debian-users@debian.or.jp
Message-id: <87br4c4zh8.wl%ippei1@xxxxxxxxxxxx>
X-mail-count: 44346
User-agent: Wanderlust/2.14.0 (Africa) SEMI/1.14.6 (Maruoka) FLIM/1.14.7 (Sanj�) APEL/10.6 Emacs/21.4 (i386-pc-linux-gnu) MULE/5.0 (SAKAKI)

田村です。

sid 環境にて CUPS 経由での印刷ができなくなりました。
自力解決を試みましたが断念し、お尋ねする次第です。

1. 環境
・sid を使用
・gs-esp & gs-common は hold 中。他は最新バージョン。
・プリンタはブラザー製 MFC-3820JN、ルータ経由でネットワーク接続。
・同社提供の LPR 用ドライバおよび CUPS 用ドライバを使用。
・同じくルータ経由でネットワーク接続している Win2K マシンからは
  問題なく印刷できる。よってプリンタの故障ではないと思われる。

2. 状況
・最後に印刷したのは先月中旬。その際は問題なし。
・その後、関連があると思われるパッケージとしては cupsys が
  1.1.23-11 にアップグレードされている。
・先日、久々に印刷しようとして不可能なのに気づいた。
・症状は、プリンタが一切応答しない。
・ブラウザにて CUPS の設定画面を開き、プリンタと対話 (ジョブ
  取消など) することは可能。

3. ログファイル
/var/log/cups/error_log の出力を以下に示します。
/etc/cups/cupsd.conf にて "LogLevel debug" と指定してあります。

D [05/Aug/2005:03:35:30 +0900] StartJob(27, 0x8093618)
D [05/Aug/2005:03:35:30 +0900] StartJob() id = 27, file = 0/1
D [05/Aug/2005:03:35:30 +0900] job-sheets=none,none
D [05/Aug/2005:03:35:30 +0900] banner_page = 0
D [05/Aug/2005:03:35:30 +0900] StartJob: argv = "MFC3820JN","27","root","Test Page","1","","/var/spool/cups/d00027-001"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[1]="SOFTWARE=CUPS/1.1"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[2]="USER=root"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[3]="CHARSET=utf-8"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[4]="LANG=ja"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[5]="TZ=Asia/Tokyo"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[6]="PPD=/etc/cups/ppd/MFC3820JN.ppd"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[8]="RIP_MAX_CACHE=8m"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[10]="CONTENT_TYPE=application/postscript"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[11]="DEVICE_URI=lpd://192.168.1.11/queue"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[12]="PRINTER=MFC3820JN"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[15]="CUPS_SERVER=localhost"
D [05/Aug/2005:03:35:30 +0900] StartJob: envp[16]="IPP_PORT=631"
D [05/Aug/2005:03:35:30 +0900] StartJob: statusfds = [ 9 10 ]
D [05/Aug/2005:03:35:30 +0900] StartJob: filterfds[1] = [ 11 -1 ]
D [05/Aug/2005:03:35:30 +0900] StartJob: filter = "/usr/lib/cups/filter/pstops"
D [05/Aug/2005:03:35:30 +0900] StartJob: filterfds[0] = [ 12 13 ]
D [05/Aug/2005:03:35:30 +0900] start_process("/usr/lib/cups/filter/pstops", 0xbf8de710, 0xbf8ddc88, 11, 13, 10)
I [05/Aug/2005:03:35:30 +0900] Started filter /usr/lib/cups/filter/pstops (PID 5753) for job 27.
D [05/Aug/2005:03:35:30 +0900] StartJob: filter = "/usr/lib/cups/filter/brlpdwrapperMFC3820JN"
D [05/Aug/2005:03:35:30 +0900] StartJob: filterfds[1] = [ 11 14 ]
D [05/Aug/2005:03:35:30 +0900] start_process("/usr/lib/cups/filter/brlpdwrapperMFC3820JN", 0xbf8de710, 0xbf8ddc88, 12, 14, 10)
I [05/Aug/2005:03:35:30 +0900] Started filter /usr/lib/cups/filter/brlpdwrapperMFC3820JN (PID 5754) for job 27.
D [05/Aug/2005:03:35:30 +0900] StartJob: backend = "/usr/lib/cups/backend/lpd"
D [05/Aug/2005:03:35:30 +0900] StartJob: filterfds[0] = [ -1 12 ]
D [05/Aug/2005:03:35:30 +0900] start_process("/usr/lib/cups/backend/lpd", 0xbf8de710, 0xbf8ddc88, 11, 12, 10)
I [05/Aug/2005:03:35:30 +0900] Started backend /usr/lib/cups/backend/lpd (PID 5757) for job 27.
D [05/Aug/2005:03:35:30 +0900] ProcessIPPRequest: 7 status_code=0
D [05/Aug/2005:03:35:30 +0900] [Job 27] Page = 595x842; 9,9 to 586,833
D [05/Aug/2005:03:35:30 +0900] [Job 27] slowcollate=0, slowduplex=0, sloworder=0
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%BoundingBox: 0 0 612 792
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%Pages: 1
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%LanguageLevel: 1
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%DocumentData: Clean7Bit
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%DocumentSuppliedResources: procset testprint/1.1
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%Creator: Michael Sweet, Easy Software Products
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%CreationDate: May 11, 1999
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%Title: Test Page
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%EndComments
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%BeginProlog
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%BeginResource procset testprint 1.1 0
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%EndResource
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%EndProlog
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%Page: 1 1
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%Page: 1 1
D [05/Aug/2005:03:35:30 +0900] [Job 27] pw = 577.0, pl = 824.0
D [05/Aug/2005:03:35:30 +0900] [Job 27] PageLeft = 9.0, PageRight = 586.0
D [05/Aug/2005:03:35:30 +0900] [Job 27] PageTop = 833.0, PageBottom = 9.0
D [05/Aug/2005:03:35:30 +0900] [Job 27] PageWidth = 595.0, PageLength = 842.0
D [05/Aug/2005:03:35:30 +0900] [Job 27] 0 %%EOF
D [05/Aug/2005:03:35:30 +0900] [Job 27] Saw EOF!
D [05/Aug/2005:03:35:30 +0900] CloseClient: 7
D [05/Aug/2005:03:35:30 +0900] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [05/Aug/2005:03:35:30 +0900] SendError: 5 code=404 (Not Found)
D [05/Aug/2005:03:35:30 +0900] CloseClient: 5
D [05/Aug/2005:03:35:34 +0900] AcceptClient: 5 from localhost:631.
D [05/Aug/2005:03:35:34 +0900] ReadClient: 5 GET /printers/MFC3820JN HTTP/1.1
D [05/Aug/2005:03:35:34 +0900] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 6571
I [05/Aug/2005:03:35:34 +0900] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=6571)
D [05/Aug/2005:03:35:34 +0900] SendCommand: 5 file=8
D [05/Aug/2005:03:35:34 +0900] AcceptClient: 7 from localhost:631.
D [05/Aug/2005:03:35:34 +0900] ReadClient: 7 POST / HTTP/1.1
D [05/Aug/2005:03:35:34 +0900] ProcessIPPRequest: 7 status_code=1
D [05/Aug/2005:03:35:34 +0900] ReadClient: 7 POST / HTTP/1.1
D [05/Aug/2005:03:35:34 +0900] ProcessIPPRequest: 7 status_code=1
D [05/Aug/2005:03:35:34 +0900] ReadClient: 7 POST / HTTP/1.1
D [05/Aug/2005:03:35:34 +0900] ProcessIPPRequest: 7 status_code=1
D [05/Aug/2005:03:35:34 +0900] CloseClient: 7
D [05/Aug/2005:03:35:34 +0900] ReadClient: 5 GET /favicon.ico HTTP/1.1
D [05/Aug/2005:03:35:34 +0900] SendError: 5 code=404 (Not Found)
D [05/Aug/2005:03:35:34 +0900] CloseClient: 5
D [05/Aug/2005:03:35:38 +0900] [Job 27] Connected on ports 515 (local 1023)...
D [05/Aug/2005:03:35:38 +0900] [Job 27] lpd_command 02 queue
D [05/Aug/2005:03:35:38 +0900] [Job 27] Sending command string (7 bytes)...
D [05/Aug/2005:03:35:38 +0900] [Job 27] Reading command status...
W [05/Aug/2005:03:40:38 +0900] [Job 27] Remote host did not respond with command status byte after 300 seconds!
D [05/Aug/2005:03:40:38 +0900] [Job 27] lpd_command returning 4
E [05/Aug/2005:03:40:38 +0900] PID 5757 stopped with status 1!
D [05/Aug/2005:03:40:38 +0900] UpdateJob: job 27, file 0 is complete.
D [05/Aug/2005:03:40:38 +0900] StopJob: id = 27, force = 0
I [05/Aug/2005:03:40:38 +0900] Saving printers.conf...
D [05/Aug/2005:03:40:38 +0900] StopJob: printer state is 5

4. ログを見て気づいた点
・バックエンドとして /usr/lib/cups/backend/lpd (PID 5757) が呼び
  出されている。
・リモートホスト (プリンタ) が応答しないという警告がある。
・その後、lpd_command がステータス  4 を返す。
・最終的に、バックエンドはステータス 1 を返して停止してしまう。
・途中のフィルタ処理などに問題があるようには見えない。
・"SendError: 5 code=404 (Not Found)" という表示が見られるのが、
  気になる。

以上です。皆様のアドバイスを頂戴したく、宜しくお願い致します。

--
田村 一平 <ippei1@xxxxxxxxxxxx>

Follow-Ups:
- [debian-users:44492] Re: can't print with CUPS
  - From: Kenshi Muto

Prev by Date: [debian-users:44345] Re: パッケージの保留に関して
Next by Date: [debian-users:44347] emacs on debian sarge
Previous by thread: [debian-users:44345] Re: パッケージの保留に関して
Next by thread: [debian-users:44492] Re: can't print with CUPS
Index(es):
- Date
- Thread