Bug #388

problem with CUPS generating 10GB /var/adm/messages file

Added by r a over 3 years ago. Updated over 2 years ago.

Status:Resolved Start date:2010-10-31
Priority:Normal Due date:2011-09-14
Assignee:OI Userland % Done:

100%

Category:SFW (Solaris Freeware)
Target version:oi_151_stable Estimated time:8.00 hours
Difficulty:Medium Tags:cups

Description

I have an installation of OpenIndiana b147 and have configured my default network printer to be my HP Photosmart C6280.
I turn on the printer when I need print something, then leave it on until I shutdown my computer. I noticed a message that OpenIndiana reported indicating the HP printer was unavailable but ignored it as I there was no print job in the queue. However, I noticed my computer becoming slower to respond but though it was Adobe Flash causing a problem.
However, this evening my computer was very slow and upon investigating found that my /var/adm/messages file was over 10GB in size from a couple of hundred megabytes a couple of days ago.
The details below are from my /var/adm/messages file, the hpcups messages make up 99.99% of the 10.4GB

Oct 30 14:28:59 fred pseudo: [ID 129642 kern.info] pseudo-device: nsmb0
Oct 30 14:28:59 fred genunix: [ID 936769 kern.info] nsmb0 is /pseudo/nsmb@0
Oct 30 14:29:00 fred mac: [ID 469746 kern.info] NOTICE: nge1 registered
Oct 30 14:29:00 fred pcplusmp: [ID 805372 kern.info] pcplusmp: ide (ata) instance 0 irq 0xe vector 0x43 ioapic 0x4 intin 0xe is bound to cpu 2
Oct 30 14:29:00 fred pcplusmp: [ID 805372 kern.info] pcplusmp: ide (ata) instance 1 irq 0xf vector 0x43 ioapic 0x4 intin 0xf is bound to cpu 3
Oct 30 14:29:00 fred usba: [ID 912658 kern.info] USB 1.10 interface (usbif46a,4.config1.1) operating at full speed (USB 1.x) on USB 1.10 external hub: input@1, hid2 at bus address 4
Oct 30 14:29:00 fred usba: [ID 349649 kern.info] Cherry USB-CyBo@rd
Oct 30 14:29:00 fred genunix: [ID 936769 kern.info] hid2 is /pci@0,0/pci15d9,1411@2/hub@1/device@3/input@1
Oct 30 14:29:00 fred usba: [ID 912658 kern.info] USB 2.0 interface-association (usbia46d,9a4.config1.0) operating at hi speed (USB 2.x) on USB 2.0 root hub: video@0, usbvc1 at bus address 2
Oct 30 14:29:00 fred usba: [ID 349649 kern.info] E17D8031
Oct 30 14:29:00 fred genunix: [ID 936769 kern.info] usbvc1 is /pci@0,0/pci15d9,1411@2,1/miscellaneous@4/video@0
Oct 30 14:29:06 fred console-kit-daemon502: [ID 702911 daemon.warning] GLib-GObject-WARNING: g_object_set_property: construct property "seat-id" for object `CkSession' can't be set after construction
Oct 30 14:29:06 fred gdm-simple-slave751: [ID 702911 daemon.warning] WARNING: GdmWelcomeSession: Error setting owner of run time directory: No such file or directory
Oct 30 14:29:07 fred gdm-simple-slave758: [ID 702911 daemon.warning] WARNING: GdmWelcomeSession: Error setting owner of run time directory: No such file or directory
Oct 30 14:29:07 fred gnome-session758: [ID 702911 daemon.warning] WARNING: IceListenForConnections returned 2 non-local listeners: inet/fred:57027,inet6/fred:53476
Oct 30 14:29:10 fred syslog780: [ID 702911 daemon.warning] Gtk-WARNING: gtkwidget.c:5628: widget not within a GtkWindow
Oct 30 14:30:06 fred syslog780: [ID 702911 daemon.warning] WARNING: Failed to send buffer
Oct 30 14:30:17 fred gnome-session789: [ID 702911 daemon.warning] WARNING: IceListenForConnections returned 2 non-local listeners: inet/fred:63919,inet6/fred:56658
Oct 30 14:58:04 fred hpcups415: [ID 491277 user.error] prnt/hpijs/services.cpp 386: unable to write to output, fd=-2147483648, count=32: Broken pipe
Oct 30 14:58:04 fred hpcups415: [ID 491277 user.error] prnt/hpijs/services.cpp 386: unable to write to output, fd=-2147483647, count=32: Broken pipe
Oct 30 14:58:04 fred hpcups415: [ID 491277 user.error] prnt/hpijs/services.cpp 386: unable to write to output, fd=-2147483646, count=32: Broken pipe
Oct 30 14:58:04 fred hpcups415: [ID 491277 user.error] prnt/hpijs/services.cpp 386: unable to write to output, fd=-2147483645, count=32: Broken pipe
Oct 30 14:58:04 fred hpcups415: [ID 491277 user.error] prnt/hpijs/services.cpp 386: unable to write to output, fd=-2147483644, count=32: Broken pipe
Oct 30 14:58:04 fred hpcups415: [ID 491277 user.error] prnt/hpijs/services.cpp 386: unable to write to output, fd=-2147483643, count=32: Broken pipe

While the initial process was 415, the process was restarted on powerup and assigned the id of 422. By killing process 422, the printer started printing a page which did not appear in the queue and the excessive number of log entries was stopped.
Why was 10GB of error messages generated by a document failing to print?

History

Updated by Matt Lewandowsky over 3 years ago

  • Project changed from site to OpenIndiana Distribution

Updated by Julian Wiesener almost 3 years ago

  • Category set to SFW (Solaris Freeware)
  • Assignee set to OI SFW

fd=-2147483648
fd=-2147483647
...
fd=-2147483643

looks like an integer overflow in a loop

Updated by Ken Mays over 2 years ago

  • Status changed from New to Feedback
  • Target version set to oi_151_stable
  • % Done changed from 0 to 90
  • Difficulty set to Medium
  • Tags set to needs-triage

Updated by Ken Mays over 2 years ago

  • Due date set to 2011-09-14
  • Assignee changed from OI SFW to OI Userland
  • % Done changed from 90 to 0
  • Estimated time set to 8.00
  • Tags changed from needs-triage to cups

Updated by Ken Mays over 2 years ago

  • Status changed from Feedback to Resolved
  • % Done changed from 0 to 100

Set the CUPS printer property to lpd://<Printer IP>

Also available in: Atom PDF