☰

CUPSエラーログがすばやく入力されます。

tag-icon • tag-icon linux postfix
CUPSエラーログがすばやく入力されます。

CUPSエラーログがすばやく入力されます。数日で1.8GBまで満たされました。

ログがなぜそんなに早くいっぱいになるのかを理解するのに役立ちます。以下はエラーログの一部です。

D [15/Jul/2022:09:08:05 +1000] [Job 1741429] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:87f14fd8-e2c1-3c85-660b-2ac816cdd376 job-originating-host-name=localhost time-at-creation=1657840033 time-at-processing=1657840075 time-at-completed=time-at-completed"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] argv[6]="/var/spool/cups/d1741429-001"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[8]="HOME=/var/spool/cups/tmp"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[10]="[email protected]"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[11]="SOFTWARE=CUPS/1.4.2"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[13]="USER=root"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[16]="IPP_PORT=631"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[17]="CHARSET=utf-8"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[18]="LANG=en_US.UTF-8"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[19]="PPD=/etc/cups/ppd/mzpay1.ppd"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[20]="RIP_MAX_CACHE=128m"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[21]="CONTENT_TYPE=text/plain"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[22]="DEVICE_URI=lpd://mzpay1/raw?timeout=10"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[23]="PRINTER_INFO=mzpay1"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[24]="PRINTER_LOCATION="
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[25]="PRINTER=mzpay1"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] envp[26]="CUPS_FILETYPE=document"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Started backend /usr/lib/cups/backend/lpd (PID 5903) 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] STATE: +connecting-to-device 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Looking up "mzpay1"...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Connecting to mzpay1:515 for printer raw 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Connecting to printer...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] STATE: -connecting-to-device 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Connected to printer...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Connected to 172.29.51.44:515 (IPv4) (local port 1020)...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] lpd_command 02 raw 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Sending command string (5 bytes)...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Reading command status...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] lpd_command returning 0 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Control file is:
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Hcms10.rockhold.local 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Ppick 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] J_stdin_ 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] ldfA903cms10.macarth 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] UdfA903cms10.macarth 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] N_stdin_ 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] lpd_command 02 95 cfA903cms10.macarth 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Sending command string (26 bytes)...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Reading command status...
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Set job-printer-state-message to "Remote host did not respond with command status byte after 10 seconds!", current level=WARN 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] lpd_command returning 4 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Backend returned status 1 (failed) 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] Retrying job on same printer.
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] End of messages 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] printer-state=3(idle) 
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] printer-state-message="/usr/lib/cups/backend/lpd failed"
D [15/Jul/2022:09:08:05 +1000] [Job 1741429] printer-state-reasons=none

答え1

コードフラグメントでは、CUPSはmzpay1LPDプロトコルを使用してプリンタ（IP 172.29.51.44）に印刷ジョブを送信しようとします。つまり、プリンタのTCPポート515に接続されます。

プリンタが接続を受け入れたように見えますが、10秒以内にコマンドステータスバイトに応答しません。したがって、CUPSはこれを失敗と見なし、印刷ジョブを再試行します。たぶんそのサイクルが繰り返されますか？

プリンタが応答しないような理由を調べる必要があります。他のホストから送信された他の印刷ジョブのために作業している可能性はありますか？

私の経験では、複数のホストがプリンタに直接ジョブを送信すると、一部のネットワークプリンタが正しく動作しません。また、LPDプロトコルにはLPDサーバーに十分な記憶スペースがあり、実際の印刷メカニズムが使用されているときに合理的な数の印刷ジョブをスプールできるという仮定が組み込まれているようです。

多くの小規模ネットワークプリンタではそうではありません。プリンタがジョブを印刷している間、最初のジョブが完了するまで他のジョブを受け入れることはできません。しかし、LPDには「しばらくお待ちください」と言える方法がないようです。クライアントは、印刷ジョブの送信を直ちに開始するか、まったく送信しません。したがって、クライアントは、LPDサーバーとして機能するプリンターが口頭で、最初のジョブがすぐに完了することを望むことに不満を抱きます。

これが問題だと思われる場合は、別のネットワーク印刷プロトコルに切り替えてみてください（プリンタがサポートしている場合）。または、すべてのクライアントシステムに印刷ジョブスプーリングに十分なディスク容量があり、そのプロトコルのみを持つコンピュータにジョブを送信するようにしてください。サーバーはジョブをプリンタに直接送信します。

答え2

cupd.confのLogLevelオプションを見てください。https://www.suse.com/support/kb/doc/?id=000016621 私は「警告」に設定すれば十分だろうと思いましたが、私は「デバッグ」に設定し、30秒以内に2MBをコミットしました...！

関連情報