☰

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를 커밋했습니다...!

관련 정보