Cups 1.2.6 SNMP discovery crashes HP LaserJet 2420DN

Tue Nov 28 23:59:45 PST 2006

At the exact moment that the "Administration" tab in cups-1.2.6 is clicked,  our HP LaserJet 2420DN network printer drops from the network and must be restarted.  The system is running gentoo linux.

The Administration page then displays the printer as an available ipp:// printer to be installed, and correctly identifies the model number.  However, the printer is already installed as a socket:// printer and works as such.  Also, the printer does not seem to respond to ipp:// in general,  but only to socket:// connections, as is shown by /usr/libexec/cups/backend/snmp below.

The printer works as long as the new SNMP discovery is not triggered.  Cups 1.1.23 and older work perfectly with the same printer.

Is it possible to disable SNMP discovery or to prevent it from crashing this printer?

Before discovery drops the printer from the network,  the cups snmp command reports the following:

# CUPS_DEBUG_LEVEL=2 /usr/libexec/cups/backend/snmp

INFO: Using default SNMP Address @LOCAL
INFO: Using default SNMP Community public
...
DEBUG: 0.012 Received 55 bytes from 192.168.0.248...
DEBUG: community="public"
DEBUG: request-id=1164777762
DEBUG: error-status=0
DEBUG: SEQUENCE 53 bytes
DEBUG:     INTEGER 1 bytes 0
DEBUG:     OCTET STRING 6 bytes "public"
DEBUG:     Get-Response-PDU 40 bytes
DEBUG:         INTEGER 4 bytes 1164777762
DEBUG:         INTEGER 1 bytes 0
DEBUG:         INTEGER 1 bytes 0
DEBUG:         SEQUENCE 26 bytes
DEBUG:             SEQUENCE 24 bytes
DEBUG:                 OID 11 bytes .1.3.6.1.2.1.25.3.2.1.2.1
DEBUG:                 OID 9 bytes .1.3.6.1.2.1.25.3.1.5
DEBUG: add_cache(addr=0x7fff239a0810, addrname="192.168.0.248", uri="(null)", id="(null)", make_and_model="(null)")
DEBUG: 0.012 Sending 46 bytes to 192.168.0.248...
DEBUG: SEQUENCE 44 bytes
DEBUG:     INTEGER 1 bytes 0
DEBUG:     OCTET STRING 6 bytes "public"
DEBUG:     Get-Request-PDU 31 bytes
DEBUG:         INTEGER 4 bytes 1164777763
DEBUG:         INTEGER 1 bytes 0
DEBUG:         INTEGER 1 bytes 0
DEBUG:         SEQUENCE 17 bytes
DEBUG:             SEQUENCE 15 bytes
DEBUG:                 OID 11 bytes .1.3.6.1.2.1.25.3.2.1.3.1
DEBUG:                 NULL VALUE 0 bytes
DEBUG: 0.019 Received 55 bytes from 192.168.0.248...
DEBUG: community="public"
DEBUG: request-id=1164777762
DEBUG: error-status=0
DEBUG: SEQUENCE 53 bytes
DEBUG:     INTEGER 1 bytes 0
DEBUG:     OCTET STRING 6 bytes "public"
DEBUG:     Get-Response-PDU 40 bytes
DEBUG:         INTEGER 4 bytes 1164777762
DEBUG:         INTEGER 1 bytes 0
DEBUG:         INTEGER 1 bytes 0
DEBUG:         SEQUENCE 26 bytes
DEBUG:             SEQUENCE 24 bytes
DEBUG:                 OID 11 bytes .1.3.6.1.2.1.25.3.2.1.2.1
DEBUG:                 OID 9 bytes .1.3.6.1.2.1.25.3.1.5
DEBUG: Discarding duplicate device type for "192.168.0.248"...
DEBUG: 0.029 Received 62 bytes from 192.168.0.248...
DEBUG: community="public"
DEBUG: request-id=1164777763
DEBUG: error-status=0
DEBUG: SEQUENCE 60 bytes
DEBUG:     INTEGER 1 bytes 0
DEBUG:     OCTET STRING 6 bytes "public"
DEBUG:     Get-Response-PDU 47 bytes
DEBUG:         INTEGER 4 bytes 1164777763
DEBUG:         INTEGER 1 bytes 0
DEBUG:         INTEGER 1 bytes 0
DEBUG:         SEQUENCE 33 bytes
DEBUG:             SEQUENCE 31 bytes
DEBUG:                 OID 11 bytes .1.3.6.1.2.1.25.3.2.1.3.1
DEBUG:                 OCTET STRING 16 bytes "hp LaserJet 2420"
DEBUG: 1.031 Probing 192.168.0.248...
DEBUG: 1.034 POST /ipp...
DEBUG: 1.034 ipp://192.168.0.248:631/ipp server-error-device-error (Unable to reconnect)
DEBUG: 1.034 POST /ipp/port2...
DEBUG: 1.034 ipp://192.168.0.248:631/ipp/port2 server-error-device-error (Unable to reconnect)
DEBUG: 1.034 POST /ipp/port3...
DEBUG: 1.034 ipp://192.168.0.248:631/ipp/port3 server-error-device-error (Unable to reconnect)
DEBUG: 1.035 POST /EPSON_IPP_Printer...
DEBUG: 1.035 ipp://192.168.0.248:631/EPSON_IPP_Printer server-error-device-error (Unable to reconnect)
DEBUG: 1.035 POST /LPT1...
DEBUG: 1.035 ipp://192.168.0.248:631/LPT1 server-error-device-error (Unable to reconnect)
DEBUG: 1.035 POST /LPT2...
DEBUG: 1.035 ipp://192.168.0.248:631/LPT2 server-error-device-error (Unable to reconnect)
DEBUG: 1.035 POST /COM1...
DEBUG: 1.035 ipp://192.168.0.248:631/COM1 server-error-device-error (Unable to reconnect)
DEBUG: 1.035 POST /...
DEBUG: 1.035 ipp://192.168.0.248:631/ server-error-device-error (Unable to reconnect)
DEBUG: 1.035 Trying socket://192.168.0.248:9100...
DEBUG: 192.168.0.248 supports AppSocket!
DEBUG: 1.035 Scan complete!
network socket://192.168.0.248 "hp LaserJet 2420" "hp LaserJet 2420 192.168.0.248" ""

After the printer drops from the network,  the above command no longer reports the printer at all.

No obvious errors appear in the error_log while the /admin page loads:

D [28/Nov/2006:23:23:01 -0800] cupsdReadClient: 8 GET /admin HTTP/1.1
D [28/Nov/2006:23:23:01 -0800] cupsdReadClient: 8 Browser asked for language "en-us.utf-8"...
D [28/Nov/2006:23:23:01 -0800] cupsdAuthorize: username="root"
D [28/Nov/2006:23:23:01 -0800] CGI /usr/libexec/cups/cgi-bin/admin.cgi started - PID = 30836
I [28/Nov/2006:23:23:01 -0800] Started "/usr/libexec/cups/cgi-bin/admin.cgi" (pid=30836)
D [28/Nov/2006:23:23:01 -0800] cupsdSendCommand: 8 file=9
D [28/Nov/2006:23:23:01 -0800] [CGI] admin.cgi started...
D [28/Nov/2006:23:23:01 -0800] [CGI] http=0x5455d0
D [28/Nov/2006:23:23:01 -0800] [CGI] No form data, showing main menu...
D [28/Nov/2006:23:23:01 -0800] cupsdAcceptClient: 11 from localhost (Domain)
D [28/Nov/2006:23:23:01 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [28/Nov/2006:23:23:01 -0800] cupsdAuthorize: No authentication data provided.
D [28/Nov/2006:23:23:01 -0800] CUPS-Get-Printers
D [28/Nov/2006:23:23:01 -0800] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [28/Nov/2006:23:23:02 -0800] cupsdReadClient: 11 POST / HTTP/1.1
D [28/Nov/2006:23:23:02 -0800] cupsdAuthorize: No authentication data provided.
D [28/Nov/2006:23:23:02 -0800] CUPS-Get-Devices
D [28/Nov/2006:23:23:02 -0800] CGI /usr/libexec/cups/daemon/cups-deviced started - PID = 30837
I [28/Nov/2006:23:23:02 -0800] Started "/usr/libexec/cups/daemon/cups-deviced" (pid=30837)
D [28/Nov/2006:23:23:02 -0800] cupsdSendCommand: 11 file=12
D [28/Nov/2006:23:23:02 -0800] [cups-deviced] Added device "ipp"...
D [28/Nov/2006:23:23:02 -0800] [cups-deviced] Added device "lpd"...
D [28/Nov/2006:23:23:02 -0800] [cups-deviced] Added device "scsi"...
D [28/Nov/2006:23:23:02 -0800] [cups-deviced] Added device "serial:/dev/ttyS0?baud=115200"...
D [28/Nov/2006:23:23:02 -0800] [cups-deviced] Added device "serial:/dev/ttyS1?baud=115200"...
D [28/Nov/2006:23:23:04 -0800] [cups-deviced] Added device "ipp://192.168.0.248:631/ipp"...
D [28/Nov/2006:23:23:04 -0800] [cups-deviced] Added device "socket"...
D [28/Nov/2006:23:23:04 -0800] [cups-deviced] Added device "http"...
D [28/Nov/2006:23:23:04 -0800] [cups-deviced] Added device "smb"...
D [28/Nov/2006:23:23:04 -0800] PID 30837 (/usr/libexec/cups/daemon/cups-deviced) exited with no errors.
D [28/Nov/2006:23:23:04 -0800] [cups-deviced] Added device "beh"...
D [28/Nov/2006:23:23:04 -0800] PID 30836 (/usr/libexec/cups/cgi-bin/admin.cgi) exited with no errors.
D [28/Nov/2006:23:23:04 -0800] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [28/Nov/2006:23:23:04 -0800] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [28/Nov/2006:23:23:04 -0800] [CGI] locale="en_us"...
D [28/Nov/2006:23:23:04 -0800] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 0...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{title}" at 205...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{refresh_page?" at 374, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 374...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{refresh_page}" at 424...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 427 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 427...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 428 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{refresh_page?", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{title}" at 671...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{title}" at 952...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{SECTION=admin" at 1411, result=1...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1411...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 1412 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1412...
D [28/Nov/2006:23:23:04 -0800] cupsdCloseClient: 11
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 1415 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{SECTION=admin", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1678...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 1679 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1679...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 1682 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{SECTION=classes", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1938...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 1939 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1939...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 1942 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{SECTION=help", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 2206...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 2207 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 2207...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 2210 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{SECTION=jobs", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{SECTION=printers" at 2464, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 2464...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 2465 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 2465...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 2468 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{SECTION=printers", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 2830 on EOF...
D [28/Nov/2006:23:23:04 -0800] [CGI] cgiCopyTemplateLang(tmpl="admin.tmpl")
D [28/Nov/2006:23:23:04 -0800] [CGI] locale="en_us"...
D [28/Nov/2006:23:23:04 -0800] [CGI] Template file is "/usr/share/cups/templates/admin.tmpl"...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{have_samba?" at 384, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 384...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 512 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 512...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 513 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{have_samba?", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{#device_uri=0" at 535, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 535...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 536 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 536...
D [28/Nov/2006:23:23:04 -0800] [CGI] Looping on "device_uri" at 586, count=1...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 586...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{device_options}" at 642...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{device_make_and_model}" at 772...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{device_info}" at 787...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 795 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished looping on "device_uri"...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 801 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{#device_uri=0", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting "{SETTINGS_ERROR?" at 1850, result=0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Skip first part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1850...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{SETTINGS_MESSAGE}" at 1871...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{SETTINGS_ERROR}" at 1904...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 1918 on character ':'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Output second part...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 1918...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{?remote_printers}" at 2117...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{?share_printers}" at 2223...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{?remote_admin}" at 2337...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{?user_cancel_any}" at 2435...
D [28/Nov/2006:23:23:04 -0800] [CGI] "{?debug_logging}" at 2552...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 2706 on character '}'...
D [28/Nov/2006:23:23:04 -0800] [CGI] Finished "{SETTINGS_ERROR?", out=0x2b28978cb780...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 2728 on EOF...
D [28/Nov/2006:23:23:04 -0800] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [28/Nov/2006:23:23:04 -0800] [CGI] locale="en_us"...
D [28/Nov/2006:23:23:04 -0800] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [28/Nov/2006:23:23:04 -0800] [CGI] Starting at file position 0...
D [28/Nov/2006:23:23:04 -0800] [CGI] Returning at file position 614 on EOF...

Thanks for looking at this.  Any advice is appreciated. -mieses