Sanjay, I did my best on this: Hope you can make something of it. ===Robert Armstrong=== ================================================== Running hp-check. The program loops. It is necessary to kill it with ctrl-c This is hpcheck.log after hp-check -t > /tmp/hpcheck.log ========================================================= HP Linux Imaging and Printing System (ver. 3.11.3a) Dependency/Version Check Utility ver. 14.3 Copyright (c) 2001-9 Hewlett-Packard Development Company, LP This software comes with ABSOLUTELY NO WARRANTY. This is free software, and you are welcome to distribute it under certain conditions. See COPYING file for more details. Note: hp-check can be run in three modes: 1. Compile-time check mode (-c or --compile): Use this mode before compiling the HPLIP supplied tarball (.tar.gz or  .run) to determine if the proper dependencies are installed to successfully compile HPLIP.                           2. Run-time check mode (-r or --run): Use this mode to determine if a distro supplied package (.deb, .rpm, etc) or an already built HPLIP supplied tarball has the proper dependencies installed to successfully run.                      3. Both compile- and run-time check mode (-b or --both) (Default): This mode will check both of the above cases (both compile- and run-time dependencies).                                                                                 Saving output in log file: hp-check.log Initializing. Please wait... Done. ================================= hp-check.log: ================================= hp-check[2547]: info: : Initializing. Please wait... error: User exit hp-check[2547]: info: : hp-check[2547]: info: :Done. ============================================ Command "lpr ctest",100 lines. Result is good first page of 63 lines, as always, followed by a second page beginning with line 64, but with each group of three lines overstruck by other (earlier?) lines. The print job finishes cleanly when the end of the second page is ejected. /var/log/syslog for date ============================================== Oct  7 07:25:06 susa5 kernel: [    0.463442] highmem bounce pool size: 64 pages Oct  7 07:25:06 susa5 kernel: [    0.472422] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Oct  7 07:25:06 susa5 kernel: [    0.473268] DLM (built Apr  9 2011 23:32:52) installed Oct  7 07:25:06 susa5 kernel: [    0.486177] OCFS2 User DLM kernel interface loaded Oct  7 07:25:06 susa5 kernel: [    0.488754] GFS2 (built Apr  9 2011 23:33:12) installed Oct  7 07:25:06 susa5 kernel: [    3.947366] Compaq SMART2 Driver (v 2.6.0) Oct  7 07:25:06 susa5 kernel: [    3.956609] scsi: Detection failed (no card) Oct  7 07:25:06 susa5 kernel: [    3.957989] Emulex LightPulse Fibre Channel SCSI driver 8.3.18 Oct  7 07:25:06 susa5 kernel: [    3.958116] Copyright(c) 2004-2009 Emulex.  All rights reserved. Oct  7 07:25:06 susa5 kernel: [    3.980055] Failed initialization of WD-7000 SCSI card! Oct  7 07:25:06 susa5 kernel: [    4.072255] GDT-HA: Storage RAID Controller Driver. Version: 3.05 Oct  7 07:25:06 susa5 kernel: [    4.072686] 3ware Storage Controller device driver for Linux v1.26.02.003. Oct  7 07:25:06 susa5 kernel: [    4.072902] 3ware 9000 Storage Controller device driver for Linux v2.26.02.014. Oct  7 07:25:06 susa5 kernel: [    4.764594] synth name on entry is: (null) Oct  7 07:25:06 susa5 kernel: [    4.788035] registered taskstats version 1 Oct  7 07:25:06 susa5 kernel: [    4.976998] VFS: Mounted root (ext2 filesystem) readonly on device 8:5. Oct  7 07:25:06 susa5 kernel: [   10.743078] Console: switching to colour frame buffer device 128x37 Oct  7 07:25:06 susa5 kernel: [   12.765803] EXT2-fs (sda5): warning: maximal mount count reached, running e2fsck is recommended Oct  7 07:25:06 susa5 udevd[1691]: bind failed: Address already in use Oct  7 07:25:06 susa5 udevd[1691]: error binding control socket, seems udevd is already running Oct  7 07:25:18 susa5 dhcpcd[1717]: timed out Oct  7 07:25:18 susa5 dhcpcd[1717]: allowing 8 seconds for IPv4LL timeout Oct  7 07:25:26 susa5 dhcpcd[1717]: timed out Oct  7 07:25:32 susa5 console-kit-daemon[1908]: WARNING: Failed to acquire org.freedesktop.ConsoleKit Oct  7 07:25:32 susa5 console-kit-daemon[1908]: WARNING: Could not acquire name; bailing out Oct  7 07:25:58 susa5 kernel: [   71.081709] start_kdeinit (2217): /proc/2217/oom_adj is deprecated, please use /proc/2217/oom_score_adj instead. Oct  7 07:26:33 susa5 python: hp-systray[2349]: error: option -s not recognized Oct  7 07:26:57 susa5 dhcpcd[2460]: dhcpcd not running Oct  7 07:26:58 susa5 dhcpcd[2466]: dhcpcd not running Oct  7 07:26:58 susa5 dhcpcd[2473]: dhcpcd not running Oct  7 07:44:57 susa5 dhcpcd[3338]: dhcpcd not running Oct  7 19:28:45 susa5 udevd[1728]: bind failed: Address already in use Oct  7 19:28:45 susa5 udevd[1728]: error binding control socket, seems udevd is already running Oct  7 19:28:45 susa5 kernel: [    0.498884] highmem bounce pool size: 64 pages Oct  7 19:28:45 susa5 kernel: [    0.507949] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Oct  7 19:28:45 susa5 kernel: [    0.508777] DLM (built Apr  9 2011 23:32:52) installed Oct  7 19:28:45 susa5 kernel: [    0.521697] OCFS2 User DLM kernel interface loaded Oct  7 19:28:45 susa5 kernel: [    0.524267] GFS2 (built Apr  9 2011 23:33:12) installed Oct  7 19:28:45 susa5 kernel: [    3.988269] Compaq SMART2 Driver (v 2.6.0) Oct  7 19:28:45 susa5 kernel: [    3.997525] scsi: Detection failed (no card) Oct  7 19:28:45 susa5 kernel: [    3.998842] Emulex LightPulse Fibre Channel SCSI driver 8.3.18 Oct  7 19:28:45 susa5 kernel: [    3.998945] Copyright(c) 2004-2009 Emulex.  All rights reserved. Oct  7 19:28:45 susa5 kernel: [    4.021078] Failed initialization of WD-7000 SCSI card! Oct  7 19:28:45 susa5 kernel: [    4.113231] GDT-HA: Storage RAID Controller Driver. Version: 3.05 Oct  7 19:28:45 susa5 kernel: [    4.114646] 3ware Storage Controller device driver for Linux v1.26.02.003. Oct  7 19:28:45 susa5 kernel: [    4.114865] 3ware 9000 Storage Controller device driver for Linux v2.26.02.014. Oct  7 19:28:45 susa5 kernel: [    5.474202] synth name on entry is: (null) Oct  7 19:28:45 susa5 kernel: [    5.497447] registered taskstats version 1 Oct  7 19:28:45 susa5 kernel: [    5.681592] VFS: Mounted root (ext2 filesystem) readonly on device 8:5. Oct  7 19:28:45 susa5 kernel: [   12.747939] Console: switching to colour frame buffer device 128x37 Oct  7 19:28:45 susa5 kernel: [   13.437455] EXT2-fs (sda5): warning: maximal mount count reached, running e2fsck is recommended Oct  7 19:28:56 susa5 dhcpcd[1754]: timed out Oct  7 19:28:56 susa5 dhcpcd[1754]: allowing 8 seconds for IPv4LL timeout Oct  7 19:29:04 susa5 dhcpcd[1754]: timed out Oct  7 19:29:11 susa5 console-kit-daemon[1872]: WARNING: Failed to acquire org.freedesktop.ConsoleKit Oct  7 19:29:11 susa5 console-kit-daemon[1872]: WARNING: Could not acquire name; bailing out Oct  7 19:29:32 susa5 kernel: [   66.897527] start_kdeinit (2242): /proc/2242/oom_adj is deprecated, please use /proc/2242/oom_score_adj instead. Oct  7 19:30:07 susa5 python: hp-systray[2383]: error: option -s not recognized Oct  7 19:33:59 susa5 python: hp-check[2547]: error: User exit Oct  7 19:44:12 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 482: argv[0] = Officejet_4500_G510a-f Oct  7 19:44:12 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 482: argv[1] = 109 Oct  7 19:44:12 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 482: argv[2] = root Oct  7 19:44:12 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 482: argv[3] = ctest Oct  7 19:44:12 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 482: argv[4] = 1 Oct  7 19:44:12 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 482: argv[5] = finishings=3 number-up=1 job-uuid=urn:uuid:e1d69049-22c9-3b1b-5c51-19fe2dbf9a3c job-originating-host-name=localhost time-at-creation=1318041852 time-at-processing=1318041852 AP_D_InputSlot= Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 729: DEBUG: startPage... Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 730: DEBUG: MediaClass = "" Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 731: DEBUG: MediaColor = "" Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 732: DEBUG: MediaType = "Plain" Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 733: DEBUG: OutputType = "0" Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 734: DEBUG: AdvanceDistance = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 735: DEBUG: AdvanceMedia = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 736: DEBUG: Collate = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 737: DEBUG: CutMedia = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 738: DEBUG: Duplex = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 739: DEBUG: HWResolution = [ 600 600 ] Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 742: DEBUG: ImagingBoundingBox = [ 9 9 603 783 ] Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 743: DEBUG: InsertSheet = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 744: DEBUG: Jog = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 745: DEBUG: LeadingEdge = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 746: DEBUG: Margins = [ 9 9 ] Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 747: DEBUG: ManualFeed = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 748: DEBUG: MediaPosition = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 749: DEBUG: MediaWeight = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 750: DEBUG: MirrorPrint = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 751: DEBUG: NegativePrint = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 752: DEBUG: NumCopies = 1 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 753: DEBUG: Orientation = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 754: DEBUG: OutputFaceUp = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 755: DEBUG: PageSize = [ 612 792 ] Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 756: DEBUG: Separations = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 757: DEBUG: TraySwitch = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 758: DEBUG: Tumble = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 759: DEBUG: cupsWidth = 4950 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 760: DEBUG: cupsHeight = 6450 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 761: DEBUG: cupsMediaType = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 762: DEBUG: cupsRowStep = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 763: DEBUG: cupsBitsPerColor = 8 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 764: DEBUG: cupsBitsPerPixel = 32 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 765: DEBUG: cupsBytesPerLine = 19800 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 766: DEBUG: cupsColorOrder = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 767: DEBUG: cupsColorSpace = 17 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 768: DEBUG: cupsCompression = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 769: DEBUG: cupsPageSizeName =  Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 770: DEBUG: cupsInteger0 = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 771: DEBUG: cupsInteger1 = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 772: DEBUG: cupsInteger2 = 0 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 773: DEBUG: cupsString0 =  Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 774: DEBUG: cupsReal0 = 0.000000 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 775: DEBUG: cupsReal1 = 0.000000 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 375: HPCUPS: found Printer Language, it is - pcl3gui2 Oct  7 19:44:14 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 427: HPCUPS: returning NO_ERROR from startPage Oct  7 19:44:48 susa5 hpcups[2848]: prnt/hpcups/HPCupsFilter.cpp 535: HPCUPS: StartPrintJob end of job, calling closeFilter() ========================================================== Here again is /var/log/syslog from lpr request at 19:58 ========================================================== Oct  7 19:58:49 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 482: argv[0] = Officejet_4500_G510a-f Oct  7 19:58:49 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 482: argv[1] = 110 Oct  7 19:58:49 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 482: argv[2] = root Oct  7 19:58:49 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 482: argv[3] = ctest Oct  7 19:58:49 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 482: argv[4] = 1 Oct  7 19:58:49 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 482: argv[5] = finishings=3 number-up=1 job-uuid=urn:uuid:0cc1ec6d-f17f-32d7-63ba-ed079a27f952 job-originating-host-name=localhost time-at-creation=1318042729 time-at-processing=1318042729 AP_D_InputSlot= Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 729: DEBUG: startPage... Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 730: DEBUG: MediaClass = "" Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 731: DEBUG: MediaColor = "" Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 732: DEBUG: MediaType = "Plain" Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 733: DEBUG: OutputType = "0" Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 734: DEBUG: AdvanceDistance = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 735: DEBUG: AdvanceMedia = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 736: DEBUG: Collate = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 737: DEBUG: CutMedia = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 738: DEBUG: Duplex = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 739: DEBUG: HWResolution = [ 600 600 ] Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 742: DEBUG: ImagingBoundingBox = [ 9 9 603 783 ] Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 743: DEBUG: InsertSheet = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 744: DEBUG: Jog = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 745: DEBUG: LeadingEdge = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 746: DEBUG: Margins = [ 9 9 ] Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 747: DEBUG: ManualFeed = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 748: DEBUG: MediaPosition = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 749: DEBUG: MediaWeight = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 750: DEBUG: MirrorPrint = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 751: DEBUG: NegativePrint = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 752: DEBUG: NumCopies = 1 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 753: DEBUG: Orientation = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 754: DEBUG: OutputFaceUp = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 755: DEBUG: PageSize = [ 612 792 ] Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 756: DEBUG: Separations = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 757: DEBUG: TraySwitch = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 758: DEBUG: Tumble = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 759: DEBUG: cupsWidth = 4950 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 760: DEBUG: cupsHeight = 6450 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 761: DEBUG: cupsMediaType = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 762: DEBUG: cupsRowStep = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 763: DEBUG: cupsBitsPerColor = 8 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 764: DEBUG: cupsBitsPerPixel = 32 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 765: DEBUG: cupsBytesPerLine = 19800 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 766: DEBUG: cupsColorOrder = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 767: DEBUG: cupsColorSpace = 17 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 768: DEBUG: cupsCompression = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 769: DEBUG: cupsPageSizeName =  Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 770: DEBUG: cupsInteger0 = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 771: DEBUG: cupsInteger1 = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 772: DEBUG: cupsInteger2 = 0 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 773: DEBUG: cupsString0 =  Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 774: DEBUG: cupsReal0 = 0.000000 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 775: DEBUG: cupsReal1 = 0.000000 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 375: HPCUPS: found Printer Language, it is - pcl3gui2 Oct  7 19:58:50 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 427: HPCUPS: returning NO_ERROR from startPage Oct  7 19:59:20 susa5 hpcups[3282]: prnt/hpcups/HPCupsFilter.cpp 535: HPCUPS: StartPrintJob end of job, calling closeFilter() ======================================================= /var/log/messages---no data for this time ========================================================= ========================================================== /var/log/cups/error_log: ============================================================== D [07/Oct/2011:19:58:49 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:49 -0700] Report: clients=1 D [07/Oct/2011:19:58:49 -0700] Report: jobs=6 D [07/Oct/2011:19:58:49 -0700] Report: jobs-active=0 D [07/Oct/2011:19:58:49 -0700] Report: printers=2 D [07/Oct/2011:19:58:49 -0700] Report: printers-implicit=0 D [07/Oct/2011:19:58:49 -0700] Report: stringpool-string-count=4283 D [07/Oct/2011:19:58:49 -0700] Report: stringpool-alloc-bytes=10192 D [07/Oct/2011:19:58:49 -0700] Report: stringpool-total-bytes=83512 D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 CUPS-Get-Default 1 D [07/Oct/2011:19:58:49 -0700] CUPS-Get-Default D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Not busy D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST /printers/Officejet_4500_G510a-f HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 Create-Job 1 D [07/Oct/2011:19:58:49 -0700] Create-Job ipp://localhost:631/printers/Officejet_4500_G510a-f D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(----J-) D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients and dirty files D [07/Oct/2011:19:58:49 -0700] add_job: requesting-user-name="root" I [07/Oct/2011:19:58:49 -0700] [Job 110] Adding start banner page "none". D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(-----S) I [07/Oct/2011:19:58:49 -0700] [Job 110] Queued on "Officejet_4500_G510a-f" by "root". D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/Officejet_4500_G510a-f) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Dirty files D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST /printers/Officejet_4500_G510a-f HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 Send-Document 1 D [07/Oct/2011:19:58:49 -0700] Send-Document ipp://localhost:631/printers/Officejet_4500_G510a-f D [07/Oct/2011:19:58:49 -0700] cupsdIsAuthorized: requesting-user-name="root" D [07/Oct/2011:19:58:49 -0700] [Job 110] Auto-typing file... D [07/Oct/2011:19:58:49 -0700] [Job 110] Request file type is text/plain. D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(----J-) I [07/Oct/2011:19:58:49 -0700] [Job 110] File of type text/plain queued by "root". I [07/Oct/2011:19:58:49 -0700] [Job 110] Adding end banner page "none". D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(----J-) D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(----J-) D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:49 -0700] [Job 110] job-sheets=none,none D [07/Oct/2011:19:58:49 -0700] [Job 110] argv[0]="Officejet_4500_G510a-f" D [07/Oct/2011:19:58:49 -0700] [Job 110] argv[1]="110" D [07/Oct/2011:19:58:49 -0700] [Job 110] argv[2]="root" D [07/Oct/2011:19:58:49 -0700] [Job 110] argv[3]="ctest" D [07/Oct/2011:19:58:49 -0700] [Job 110] argv[4]="1" D [07/Oct/2011:19:58:49 -0700] [Job 110] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:0cc1ec6d-f17f-32d7-63ba-ed079a27f952 job-originating-host-name=localhost time-at-creation=1318042729 time-at-processing=1318042729 AP_D_InputSlot=" D [07/Oct/2011:19:58:49 -0700] [Job 110] argv[6]="/var/spool/cups/d00110-001" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[1]="CUPS_DATADIR=/usr/share/cups" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[6]="CUPS_SERVERROOT=/etc/cups" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[7]="CUPS_STATEDIR=/var/run/cups" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[8]="HOME=/var/spool/cups/tmp" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[10]="SERVER_ADMIN=root@susa5" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[11]="SOFTWARE=CUPS/1.4.6" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[12]="TMPDIR=/var/spool/cups/tmp" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[13]="USER=root" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[15]="CUPS_ENCRYPTION=IfRequested" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[16]="IPP_PORT=631" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[17]="CHARSET=utf-8" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[18]="LANG=en_US.UTF-8" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[19]="PPD=/etc/cups/ppd/Officejet_4500_G510a-f.ppd" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[20]="RIP_MAX_CACHE=8m" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[21]="CONTENT_TYPE=text/plain" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[22]="DEVICE_URI=hp:/usb/Officejet_4500_G510a-f?serial=CN16MF32RD05H2" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[23]="PRINTER_INFO=Officejet_4500_G510a-f" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[24]="PRINTER_LOCATION=" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[25]="PRINTER=Officejet_4500_G510a-f" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[26]="CUPS_FILETYPE=document" D [07/Oct/2011:19:58:49 -0700] [Job 110] envp[27]="FINAL_CONTENT_TYPE=printer/Officejet_4500_G510a-f" I [07/Oct/2011:19:58:49 -0700] [Job 110] Started filter /usr/lib/cups/filter/texttops (PID 3279) I [07/Oct/2011:19:58:49 -0700] [Job 110] Started filter /usr/lib/cups/filter/pstops (PID 3280) I [07/Oct/2011:19:58:49 -0700] [Job 110] Started filter /usr/lib/cups/filter/pstoraster (PID 3281) I [07/Oct/2011:19:58:49 -0700] [Job 110] Started filter /usr/lib/cups/filter/hpcups (PID 3282) I [07/Oct/2011:19:58:49 -0700] [Job 110] Started backend /usr/lib/cups/backend/hp (PID 3283) D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/Officejet_4500_G510a-f) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:49 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:58:49 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 482: argv[0] = Officejet_4500_G510a-f D [07/Oct/2011:19:58:49 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 482: argv[1] = 110 D [07/Oct/2011:19:58:49 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 482: argv[2] = root D [07/Oct/2011:19:58:49 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 482: argv[3] = ctest D [07/Oct/2011:19:58:49 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 482: argv[4] = 1 D [07/Oct/2011:19:58:49 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 482: argv[5] = finishings=3 number-up=1 job-uuid=urn:uuid:0cc1ec6d-f17f-32d7-63ba-ed079a27f952 job-originating-host-name=localhost time-at-creation=1318042729 time-at-processing=1318042729 AP_D_InputSlot= D [07/Oct/2011:19:58:49 -0700] [Job 110] Page = 612x792; 9,9 to 603,783 D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->num_fonts = 35 D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[0] = AvantGarde-Book D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[1] = AvantGarde-BookOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[2] = AvantGarde-Demi D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[3] = AvantGarde-DemiOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[4] = Bookman-Demi D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[5] = Bookman-DemiItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[6] = Bookman-Light D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[7] = Bookman-LightItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[8] = Courier D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[9] = Courier-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[10] = Courier-BoldOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[11] = Courier-Oblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[12] = Helvetica D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[13] = Helvetica-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[14] = Helvetica-BoldOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[15] = Helvetica-Narrow D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[16] = Helvetica-Narrow-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[17] = Helvetica-Narrow-BoldOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[18] = Helvetica-Narrow-Oblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[19] = Helvetica-Oblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[20] = NewCenturySchlbk-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[21] = NewCenturySchlbk-BoldItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[22] = NewCenturySchlbk-Italic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[23] = NewCenturySchlbk-Roman D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[24] = Palatino-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[25] = Palatino-BoldItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[26] = Palatino-Italic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[27] = Palatino-Roman D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[28] = Symbol D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[29] = Times-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[30] = Times-BoldItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[31] = Times-Italic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[32] = Times-Roman D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[33] = ZapfChancery-MediumItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[34] = ZapfDingbats D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->num_fonts = 35 D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[0] = AvantGarde-Book D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[1] = AvantGarde-BookOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[2] = AvantGarde-Demi D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[3] = AvantGarde-DemiOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[4] = Bookman-Demi D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[5] = Bookman-DemiItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[6] = Bookman-Light D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[7] = Bookman-LightItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[8] = Courier D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[9] = Courier-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[10] = Courier-BoldOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[11] = Courier-Oblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[12] = Helvetica D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[13] = Helvetica-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[14] = Helvetica-BoldOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[15] = Helvetica-Narrow D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[16] = Helvetica-Narrow-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[17] = Helvetica-Narrow-BoldOblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[18] = Helvetica-Narrow-Oblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[19] = Helvetica-Oblique D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[20] = NewCenturySchlbk-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[21] = NewCenturySchlbk-BoldItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[22] = NewCenturySchlbk-Italic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[23] = NewCenturySchlbk-Roman D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[24] = Palatino-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[25] = Palatino-BoldItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[26] = Palatino-Italic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[27] = Palatino-Roman D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[28] = Symbol D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[29] = Times-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[30] = Times-BoldItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[31] = Times-Italic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[32] = Times-Roman D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[33] = ZapfChancery-MediumItalic D [07/Oct/2011:19:58:49 -0700] [Job 110] ppd->fonts[34] = ZapfDingbats D [07/Oct/2011:19:58:49 -0700] [Job 110] Page = 612x792; 9,9 to 603,783 D [07/Oct/2011:19:58:49 -0700] [Job 110] slow_collate=0, slow_duplex=0, slow_order=0 D [07/Oct/2011:19:58:49 -0700] [Job 110] Before copy_comments - %!PS-Adobe-3.0 D [07/Oct/2011:19:58:49 -0700] [Job 110] %!PS-Adobe-3.0 D [07/Oct/2011:19:58:49 -0700] [Job 110] %%BoundingBox: 0 0 612 792 D [07/Oct/2011:19:58:49 -0700] [Job 110] %cupsRotation: 0 D [07/Oct/2011:19:58:49 -0700] [Job 110] %%Creator: texttops/CUPS v1.4.6 D [07/Oct/2011:19:58:49 -0700] [Job 110] %%CreationDate: Fri 07 Oct 2011 07:58:49 PM PDT D [07/Oct/2011:19:58:49 -0700] [Job 110] %%Title: (ctest) D [07/Oct/2011:19:58:49 -0700] [Job 110] %%For: (root) D [07/Oct/2011:19:58:49 -0700] [Job 110] %%Pages: (atend) D [07/Oct/2011:19:58:49 -0700] [Job 110] %%DocumentNeededResources: font Monospace-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] %%+ font Monospace D [07/Oct/2011:19:58:49 -0700] [Job 110] %%DocumentSuppliedResources: procset texttops 1.1 0 D [07/Oct/2011:19:58:49 -0700] [Job 110] %%+ font Monospace-Bold D [07/Oct/2011:19:58:49 -0700] [Job 110] %%+ font Monospace D [07/Oct/2011:19:58:49 -0700] [Job 110] %%EndComments D [07/Oct/2011:19:58:49 -0700] [Job 110] Before copy_prolog - %%BeginProlog I [07/Oct/2011:19:58:49 -0700] [Job 110] Starting GPL Ghostscript 9.00... D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:49 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:49 -0700] [Job 110] Running /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOMEDIAATTRS -sDEVICE=cups -sstdout=%stderr -sOUTPUTFILE=%stdout -c  -f -_ D [07/Oct/2011:19:58:49 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:58:49 -0700] Get-Notifications / D [07/Oct/2011:19:58:49 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 Get-Job-Attributes 1 D [07/Oct/2011:19:58:49 -0700] Get-Job-Attributes ipp://localhost/jobs/110 D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/110) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 Get-Job-Attributes 1 D [07/Oct/2011:19:58:49 -0700] Get-Job-Attributes ipp://localhost/jobs/110 D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/110) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:49 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:58:49 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:58:49 -0700] Get-Notifications / D [07/Oct/2011:19:58:49 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:49 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:58:49 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:58:49 -0700] Get-Notifications / D [07/Oct/2011:19:58:49 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:58:49 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:58:49 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:49 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:49 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:58:50 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:50 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:50 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:58:50 -0700] Get-Notifications / D [07/Oct/2011:19:58:50 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:58:50 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:58:50 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:50 -0700] cupsdCloseClient: 13 I [07/Oct/2011:19:58:50 -0700] [Job 110] Start rendering... D [07/Oct/2011:19:58:50 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:50 -0700] cupsdMarkDirty(-----S) I [07/Oct/2011:19:58:50 -0700] [Job 110] Processing page 1... D [07/Oct/2011:19:58:50 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:50 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:50 -0700] PID 3279 (/usr/lib/cups/filter/texttops) exited with no errors. D [07/Oct/2011:19:58:50 -0700] [Job 110] Before copy_setup - %%Page: 1 1 D [07/Oct/2011:19:58:50 -0700] [Job 110] Before page loop - %%Page: 1 1 D [07/Oct/2011:19:58:50 -0700] [Job 110] Copying page 1... D [07/Oct/2011:19:58:50 -0700] [Job 110] pagew = 594.0, pagel = 774.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792 D [07/Oct/2011:19:58:50 -0700] [Job 110] PageLeft = 9.0, PageRight = 603.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] PageTop = 783.0, PageBottom = 9.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] PageWidth = 612.0, PageLength = 792.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] Copying page 2... D [07/Oct/2011:19:58:50 -0700] [Job 110] pagew = 594.0, pagel = 774.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792 D [07/Oct/2011:19:58:50 -0700] [Job 110] PageLeft = 9.0, PageRight = 603.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] PageTop = 783.0, PageBottom = 9.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] PageWidth = 612.0, PageLength = 792.0 D [07/Oct/2011:19:58:50 -0700] [Job 110] Wrote 2 pages... D [07/Oct/2011:19:58:50 -0700] PID 3280 (/usr/lib/cups/filter/pstops) exited with no errors. D [07/Oct/2011:19:58:50 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:50 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:50 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:58:50 -0700] Get-Notifications / D [07/Oct/2011:19:58:50 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:58:50 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:58:50 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:50 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:58:50 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:50 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:50 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:58:50 -0700] Get-Notifications / D [07/Oct/2011:19:58:50 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:58:50 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:58:50 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:50 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:50 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 729: DEBUG: startPage... D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 730: DEBUG: MediaClass = "" D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 731: DEBUG: MediaColor = "" D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 732: DEBUG: MediaType = "Plain" D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 733: DEBUG: OutputType = "0" D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 734: DEBUG: AdvanceDistance = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 735: DEBUG: AdvanceMedia = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 736: DEBUG: Collate = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 737: DEBUG: CutMedia = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 738: DEBUG: Duplex = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 739: DEBUG: HWResolution = [ 600 600 ] D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 742: DEBUG: ImagingBoundingBox = [ 9 9 603 783 ] D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 743: DEBUG: InsertSheet = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 744: DEBUG: Jog = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 745: DEBUG: LeadingEdge = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 746: DEBUG: Margins = [ 9 9 ] D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 747: DEBUG: ManualFeed = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 748: DEBUG: MediaPosition = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 749: DEBUG: MediaWeight = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 750: DEBUG: MirrorPrint = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 751: DEBUG: NegativePrint = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 752: DEBUG: NumCopies = 1 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 753: DEBUG: Orientation = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 754: DEBUG: OutputFaceUp = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 755: DEBUG: PageSize = [ 612 792 ] D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 756: DEBUG: Separations = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 757: DEBUG: TraySwitch = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 758: DEBUG: Tumble = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 759: DEBUG: cupsWidth = 4950 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 760: DEBUG: cupsHeight = 6450 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 761: DEBUG: cupsMediaType = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 762: DEBUG: cupsRowStep = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 763: DEBUG: cupsBitsPerColor = 8 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 764: DEBUG: cupsBitsPerPixel = 32 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 765: DEBUG: cupsBytesPerLine = 19800 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 766: DEBUG: cupsColorOrder = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 767: DEBUG: cupsColorSpace = 17 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 768: DEBUG: cupsCompression = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 769: DEBUG: cupsPageSizeName = D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 770: DEBUG: cupsInteger0 = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 771: DEBUG: cupsInteger1 = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 772: DEBUG: cupsInteger2 = 0 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 773: DEBUG: cupsString0 = D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 774: DEBUG: cupsReal0 = 0.000000 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 775: DEBUG: cupsReal1 = 0.000000 D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 375: HPCUPS: found Printer Language, it is - pcl3gui2STATE: +connecting-to-device D [07/Oct/2011:19:58:50 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 427: HPCUPS: returning NO_ERROR from startPagePAGE: 1 1STATE: -connecting-to-device D [07/Oct/2011:19:58:50 -0700] [Job 110] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other I [07/Oct/2011:19:58:54 -0700] [Job 110] Processing page 2... D [07/Oct/2011:19:58:54 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:54 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:58:55 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:58:55 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:58:55 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:58:55 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:58:55 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:58:55 -0700] Get-Notifications / D [07/Oct/2011:19:58:55 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:58:55 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:58:55 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:58:55 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:58:55 -0700] cupsdCloseClient: 13 I [07/Oct/2011:19:59:20 -0700] Saving job cache file "/var/cache/cups/job.cache"... I [07/Oct/2011:19:59:20 -0700] Saving subscriptions.conf... D [07/Oct/2011:19:59:20 -0700] cupsdSetBusyState: Printing jobs D [07/Oct/2011:19:59:20 -0700] [Job 110] PAGE: 2 1INFO: Processing page 3... D [07/Oct/2011:19:59:20 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:59:20 -0700] cupsdSetBusyState: Printing jobs and dirty files I [07/Oct/2011:19:59:20 -0700] [Job 110] Rendering completed D [07/Oct/2011:19:59:20 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:59:20 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:59:20 -0700] PID 3282 (/usr/lib/cups/filter/hpcups) exited with no errors. D [07/Oct/2011:19:59:20 -0700] PID 3281 (/usr/lib/cups/filter/pstoraster) exited with no errors. D [07/Oct/2011:19:59:21 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:59:21 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:59:21 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files D [07/Oct/2011:19:59:21 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:59:21 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:59:21 -0700] Get-Notifications / D [07/Oct/2011:19:59:21 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:59:21 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:59:21 -0700] cupsdSetBusyState: Printing jobs and dirty files D [07/Oct/2011:19:59:21 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:59:21 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:59:38 -0700] [Job 110] prnt/hpcups/HPCupsFilter.cpp 535: HPCUPS: StartPrintJob end of job, calling closeFilter()INFO: ready to print D [07/Oct/2011:19:59:38 -0700] PID 3283 (/usr/lib/cups/backend/hp) exited with no errors. D [07/Oct/2011:19:59:38 -0700] cupsdMarkDirty(-----S) I [07/Oct/2011:19:59:38 -0700] [Job 110] Job completed. D [07/Oct/2011:19:59:38 -0700] cupsdMarkDirty(----J-) D [07/Oct/2011:19:59:38 -0700] cupsdMarkDirty(-----S) D [07/Oct/2011:19:59:38 -0700] cupsdAcceptClient: 13 from localhost (Domain) D [07/Oct/2011:19:59:38 -0700] cupsdReadClient: 13 POST / HTTP/1.1 D [07/Oct/2011:19:59:38 -0700] cupsdSetBusyState: Active clients and dirty files D [07/Oct/2011:19:59:38 -0700] cupsdAuthorize: No authentication data provided. D [07/Oct/2011:19:59:38 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1 D [07/Oct/2011:19:59:38 -0700] Get-Notifications / D [07/Oct/2011:19:59:38 -0700] cupsdIsAuthorized: requesting-user-name="s5" D [07/Oct/2011:19:59:38 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost D [07/Oct/2011:19:59:38 -0700] cupsdSetBusyState: Dirty files D [07/Oct/2011:19:59:38 -0700] cupsdReadClient: 13 WAITING Closing on EOF D [07/Oct/2011:19:59:38 -0700] cupsdCloseClient: 13 D [07/Oct/2011:19:59:39 -0700] [Job 110] Unloading... I [07/Oct/2011:19:59:51 -0700] Saving job cache file "/var/cache/cups/job.cache"... I [07/Oct/2011:19:59:51 -0700] Saving subscriptions.conf... D [07/Oct/2011:19:59:51 -0700] cupsdSetBusyState: Not busy D [07/Oct/2011:19:59:51 -0700] Report: clients=0 D [07/Oct/2011:19:59:51 -0700] Report: jobs=7 D [07/Oct/2011:19:59:51 -0700] Report: jobs-active=0 D [07/Oct/2011:19:59:51 -0700] Report: printers=2 D [07/Oct/2011:19:59:51 -0700] Report: printers-implicit=0 D [07/Oct/2011:19:59:51 -0700] Report: stringpool-string-count=5019 D [07/Oct/2011:19:59:51 -0700] Report: stringpool-alloc-bytes=10256 D [07/Oct/2011:19:59:51 -0700] Report: stringpool-total-bytes=99240 ================================================================ The bmp files were non-text data -rw-r--r-- 1 lp lp 95821254 Oct  7 19:59 hpcupsfilterc_1.bmp -rw-r--r-- 1 lp lp 95821254 Oct  7 19:59 hpcupsfilterc_2.bmp -rw-r--r-- 1 lp lp  3999062 Oct  7 19:59 hpcupsfilterk_1.bmp -rw-r--r-- 1 lp lp  3999062 Oct  7 19:59 hpcupsfilterk_2.bmp =================================================================== ============================================================== rw-r--r-- 1 lp lp 388747 Oct  7 19:59 hpcups_job110.out  This looked like a mess. Due to limitations of yahoo mail, not sent. =============================================================== ================================================================ /tmp/hpcheck.log does not exist ================================================================ ________________________________ From: Sanjay Kumar