[Userland-team] [OpenIndiana Distribution - Bug #388] (Resolved) problem with CUPS generating 10GB /var/adm/messages file

[illumos project]

Issue #388 has been updated by Ken Mays.

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

Set the CUPS printer property to lpd://<Printer IP>  
----------------------------------------
Bug #388: problem with CUPS generating 10GB /var/adm/messages file
https://www.illumos.org/issues/388

Author: r a
Status: Resolved
Priority: Normal
Assignee: OI Userland
Category: SFW (Solaris Freeware)
Target version: oi_151_stable
Difficulty: Medium
Tags: cups


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 at 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 at 1, hid2 at bus address 4
Oct 30 14:29:00 fred usba: [ID 349649 kern.info]        Cherry USB-CyBo at rd
Oct 30 14:29:00 fred genunix: [ID 936769 kern.info] hid2 is /pci at 0,0/pci15d9,1411 at 2/hub at 1/device at 3/input at 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 at 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 at 0,0/pci15d9,1411 at 2,1/miscellaneous at 4/video at 0
Oct 30 14:29:06 fred console-kit-daemon[502]: [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-slave[751]: [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-slave[758]: [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-session[758]: [ID 702911 daemon.warning] WARNING: IceListenForConnections returned 2 non-local listeners: inet/fred:57027,inet6/fred:53476
Oct 30 14:29:10 fred syslog[780]: [ID 702911 daemon.warning] Gtk-WARNING: gtkwidget.c:5628: widget not within a GtkWindow
Oct 30 14:30:06 fred syslog[780]: [ID 702911 daemon.warning] WARNING: Failed to send buffer
Oct 30 14:30:17 fred gnome-session[789]: [ID 702911 daemon.warning] WARNING: IceListenForConnections returned 2 non-local listeners: inet/fred:63919,inet6/fred:56658
Oct 30 14:58:04 fred hpcups[415]: [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 hpcups[415]: [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 hpcups[415]: [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 hpcups[415]: [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 hpcups[415]: [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 hpcups[415]: [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?



-- 
You have received this notification because you have either subscribed to it, or are involved in it.
To change your notification preferences, please click here: http://www.illumos.org/my/account