1320n printing problems with Mint 19

Asked by Gil Weber on 2018-10-04

Subject: Claws Mail & HP laser printer 1320n on Linux Mint 19

My HP printer is not behaving nicely with Claws Mail and Linux Mint 19 (Cinnamon). Every time I look at an e-mail in the "print preview" it's a jumble with letters reversed in words, large spaces inserted into lines, etc. Simply a mess. But if I look a second time with "print preview" all looks perfect.

The same thing happens if I go to print. The first printing is a jumbled mess, but printing a second time and it looks just fine.

Additionally, though they may appear when viewed in "print preview," headers print from only a very small number of e-mails. Most won't print the headers. I don't see a pattern as to which will and won't print the headers.

The breakages looks like random whitespace / special characters/ reversed characters have been inserted into the header or body text lines. For example in a header I'll see something like this. (Sorry that this forum's construction compressed the spaces that I tried to enter, so I had to show the spacing and text problems between parentheses marks.)

> oT (large space here and reversed characters) : <myname@mydoma (space here) i.n (space here after reversed characters) com>
> Date: Mon 21, (large space) A (large space) ug 2017 14:02:17 -0500

Or in the body text I'll get this sort of thing.

> Use Shift+Alt+ (large space) aT (reversed characters and large space) b to highlight and 'jump (large space) to'

In the current printer settings I see the following.
Description: HP LaserJet 1320n, hpcups 3.18.9
Make and Model: HP LaserJet 1320 hpijs, 3.17.10
Device URI: usb://HP/LaserJet%201320%20series?serial=00CNHC5DP0ZQ

I have tried changing the Device URI to: hp:/usb/hp_LaserJet_1320_series?serial=00CNHC5DP0ZQ

This made no difference.

I have tried all of the drivers for 1320 and 1320n but the problems persist.

Other documents view and print just fine. It's only Claws-Mail e-mails that are problems. And these printing problems did not exist when I had the printer connected to another computer using Linux SuSE 42.3.

My printer's Firmware Datecode is 20041024. I cannot find any information on a newer Firmware version, or if such exists how to get it into my USB-connected printer.

What other information can I provide that might help get to a solution?

Thank you.
bearzlos

Question information

Language:
English Edit question
Status:
Open
For:
HPLIP Edit question
Assignee:
No assignee Edit question
Last query:
2018-11-28
Last reply:
2018-11-28
srinivas (srinivas5) said : #1

Hi,
Run "sudo hp-logcapture" command and then print the e-mail from hp-toolbox. It will generate the logs. Please attach the logs for our analysis.
Also capture the hp-check -t command output and share it for our analysis.

Thanks,
Srinivas Teja

Gil Weber (bearzlos) said : #2

Thanks for this reply but I do not understand how to do what you want. Please give me steps to perform.

When I run "sudo hp-logcapture" I get this return:

gil@phred:~$ sudo hp-logcapture
[sudo] password for gil:
-Archiving system logs (i.e. syslog, message, error_log). Press (y=yes*, n=no, q=quit):y
Existing /var/log/syslog log file copied to /var/log/syslog.11.gz
Existing /var/log/cups/error_log log file copied to /var/log/cups/error_log.11.gz

Please perform the tasks (Print, scan, fax) for which you need to collect the logs.
Are you done with tasks?. Press (y=yes*, q=quit):

OK, so after this what do I do now? How do I perform the tasks to collect the logs before pressing "y" to be done or "q" to quit?

There are two .gz files in /var/log... How do I print the e-mail (WHAT e-mail?) from hp-toolbox that will generate the logs?

Do I copy and paste those logs here?

Once I understand the above I can capture the hp-check -t command output for you.

Thanks for explaining very clearly what you need and how I get it for you.

Thanks!
bearzlos

srinivas (srinivas5) said : #3

Hi,

Please share the claws e-mail input file you are trying to print on Linuxmint 19 and also logs.

Please follow the below instruction to generate the log file.
1: open a terminal and run the command "sudo hp-logcapture"
2: open another terminal and give a document print.
3: come to the previous terminal and quit the log capture.
This will generate the logs tar file.

Regards,
Srinivas Teja.

Gil Weber (bearzlos) said : #4

Thanks again, but once again I don't understand. Your commands are not
specific enough.

1) this is clear, but I get the following output:

gil@phred:~$ sudo hp-logcapture
[sudo] password for gil:
-Archiving system logs (i.e. syslog, message, error_log). Press
(y=yes*, n=no, q=quit):

That's all I get.

2) This command makes no sense. What do you mean open another terminal
and "...give a document print?" How do I "...give a document print?"
Please be specific with the steps to do this.

3) Do you mean close the first terminal and that will generate logs
tar file(s)? If yes, where will they appear? In the second terminal?

I appreciate your help but the instructions just are not clear.

Thanks.
Gil Weber

On Mon, 08 Oct 2018 10:02:24 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Hi,
>
> Please share the claws e-mail input file you are trying to print on
> Linuxmint 19 and also logs.
>
> Please follow the below instruction to generate the log file.
> 1: open a terminal and run the command "sudo hp-logcapture"
> 2: open another terminal and give a document print.
> 3: come to the previous terminal and quit the log capture.
> This will generate the logs tar file.
>
> Regards,
> Srinivas Teja.
>

srinivas (srinivas5) said : #5

Hi,

gil@phred:~$ sudo hp-logcapture
[sudo] password for gil:
-Archiving system logs (i.e. syslog, message, error_log). Press
(y=yes*, n=no, q=quit):

Press "yes" here
Then it will prompt to do a (print/scan/fax) (do it in some other terminal).
Then comeback to this terminal , it will ask to press "yes" once again if you are done with printing/scanning.
Do that, then logs will be generated as a ".tar" file.

Regards,
Srinivas Teja.

Gil Weber (bearzlos) said : #6

Srinivas, I really appreciate your help, but you simply do not
understand that I don't understand your instructions. Please be much
more specific.

> gil@phred:~$ sudo hp-logcapture
> [sudo] password for gil:
> -Archiving system logs (i.e. syslog, message, error_log). Press
> (y=yes*, n=no, q=quit):
>
> Press "yes" here

[Gil] OK, so I press "yes. But then....

> Then it will prompt to do a (print/scan/fax) (do it in some other
> terminal).

[Gil] What do you mean by this? How do I print/scan/fax? And
how do I do this print/scan/fax in a second terminal? If yes, then how
do I print/scan/fax from that second terminal? What command do I use
in the second terminal to print?

> Then comeback to this terminal , it will ask to press
> "yes" once again if you are done with printing/scanning. Do that,
> then logs will be generated as a ".tar" file.

[Gil] Assuming I get back to the first terminal and press "yes" will
that then generate the logs? And if yes, where will they be so I can
send them to you?

I really need **specific** instructions. Please do not assume I know.

Thank you.
Gil Weber

On Wed, 10 Oct 2018 08:43:15 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Hi,
>
> gil@phred:~$ sudo hp-logcapture
> [sudo] password for gil:
> -Archiving system logs (i.e. syslog, message, error_log). Press
> (y=yes*, n=no, q=quit):
>
> Press "yes" here
> Then it will prompt to do a (print/scan/fax) (do it in some other
> terminal). Then comeback to this terminal , it will ask to press
> "yes" once again if you are done with printing/scanning. Do that,
> then logs will be generated as a ".tar" file.
>
>
> Regards,
> Srinivas Teja.
>

srinivas (srinivas5) said : #7

Hi,
As mentioned above the command is self explanatory. Please follow the instructions as suggested by the command.

Thanks,
Srinivas

Gil Weber (bearzlos) said : #8

Srinivas, This time I just entered "print" in the second terminal, then
"y" in the first terminal.

Attached are the resulting files. You'll note that one of them (.txt)
is empty.

Please note that I got the following message in the console while the
log was being generated:

Logs are saved as /home/gil/hplip_troubleshoot_logs.tar.gz
Please create a bug @https://bugs.launchpad.net/hplip/+filebug and
upload this log file.

Do I need to do this or is sending the log file to you sufficient?

Additionally the following appeared in a separate window:

HPLIP cannot detect devices on your network. This may be due to
existing firewall settings blocking the required ports lid (5353/udp).
When you are in a trusted network environment, you may open the ports
for network services like mdns and slp in the firewall. For detailed
steps follow the link.
http://hplipopensource.com/node/375

This printer is connected only to my computer, so do I need to open
ports? I entered both mdns and slp in the search bar at this page and
the results are way over my head. :o( I am not a Linux guru.

Gil Weber

On Mon, 15 Oct 2018 12:37:38 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Hi,
> As mentioned above the command is self explanatory. Please follow the
> instructions as suggested by the command.
>
> Thanks,
> Srinivas
>

srinivas (srinivas5) said : #9

Hi,

Do a print job in other terminal using hp-toolbox and not "print".
Then it will generate the logs.tar properly.
You can attach here.

Thanks,
Srinivas Teja.

Gil Weber (bearzlos) said : #10

OK, this time in the second terminal I used the hp-toolbox window to
print a test page. Once that was done I quit the log-capture and closed
the terminal.

Attached is the tar file. I hope this is correct.
Thanks again.
Gil Weber

On Tue, 16 Oct 2018 12:08:18 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Hi,
>
> Do a print job in other terminal using hp-toolbox and not "print".
> Then it will generate the logs.tar properly.
> You can attach here.
>
> Thanks,
> Srinivas Teja.
>

srinivas (srinivas5) said : #11

Hi,
The log file didn't get uploaded properly. Can you copy paste the contents from the log file instead?

Gil Weber (bearzlos) said : #12

On Thu, 18 Oct 2018 09:03:07 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Hi,
> The log file didn't get uploaded properly. Can you copy paste the
> contents from the log file instead?
>

I have attached three files extracted from
hplip_troubleshoot_logs.tar.gz

Hope this works.
Gil Weber

srinivas (srinivas5) said : #13

Hi,
Can you copy paste the log file contents in the query? Looks the attachment is getting blocked.

Gil Weber (bearzlos) said : #14

On Tue, 23 Oct 2018 11:32:32 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Hi,
> Can you copy paste the log file contents in the query? Looks the
> attachment is getting blocked.
>

Attachments are being blocked? Sounds as if there is something wrong
with the e-mail system at your end. :o(

OK, here are the three files.

Each is very long, and the line breaks pasted into this e-mail are not
the same as I see on my computer screen when I look at the logs. Good
luck! :o)

Would you like for me to FAX you two pages showing the printing
problems? Then you'll see exactly what I'm talking about. If yes, please
give me a FAX number.
Thx.
Gil Weber

This is hp-check.log

hp-check[16711]: info: :
hp-check[16711]: info: :[01mHP Linux Imaging and Printing System (ver.
3.18.9)[0m hp-check[16711]: info: :[01mDependency/Version Check Utility
ver. 15.1[0m hp-check[16711]: info: :
hp-check[16711]: info: :Copyright (c) 2001-15 HP Development Company, LP
hp-check[16711]: info: :This software comes with ABSOLUTELY NO WARRANTY.
hp-check[16711]: info: :This is free software, and you are welcome to
distribute it hp-check[16711]: info: :under certain conditions. See
COPYING file for more details. hp-check[16711]: info: :
hp-check[16711]: info: :[01mNote: hp-check can be run in three modes:[0m
hp-check[16711]: info: :1. Compile-time check mode (-c or --compile):
Use this mode before compiling the hp-check[16711]: info: :HPLIP
supplied tarball (.tar.gz or .run) to determine if the proper
dependencies hp-check[16711]: info: :are installed to successfully
compile HPLIP. hp-check[16711]: info: :2. Run-time check mode (-r or
--run): Use this mode to determine if a distro hp-check[16711]:
info: :supplied package (.deb, .rpm, etc) or an already built HPLIP
supplied tarball hp-check[16711]: info: :has the proper dependencies
installed to successfully run. hp-check[16711]: info: :3. Both compile-
and run-time check mode (-b or --both) (Default): This mode
hp-check[16711]: info: :will check both of the above cases (both
compile- and run-time dependencies). hp-check[16711]: info: :
hp-check[16711]: info: :Check types: hp-check[16711]: info: :a.
EXTERNALDEP - External Dependencies hp-check[16711]: info: :b.
GENERALDEP - General Dependencies (required both at compile and run
time) hp-check[16711]: info: :c. COMPILEDEP - Compile time Dependencies
hp-check[16711]: info: :d. [All are run-time checks] hp-check[16711]:
info: :PYEXT SCANCONF QUEUES PERMISSION hp-check[16711]: info: :
hp-check[16711]: info: :Status Types: hp-check[16711]: info: : OK
hp-check[16711]: info: : MISSING - Missing Dependency or
Permission or Plug-in hp-check[16711]: info: : INCOMPAT -
Incompatible dependency-version or Plugin-version hp-check[16711]:
info: : hp-check[16711]: info: : hp-check[16711]: info: :---------------
hp-check[16711]: info: :| SYSTEM INFO |
hp-check[16711]: info: :---------------
hp-check[16711]: info: :
hp-check[16711]: info: : Kernel: 4.15.0-36-generic #39-Ubuntu SMP Mon
Sep 24 16:19:09 UTC 2018 GNU/Linux Host: phred
 Proc: 4.15.0-36-generic #39-Ubuntu SMP Mon Sep 24 16:19:09 UTC 2018
GNU/Linux Distribution: 22 19
hp-check[16711]: info: : Bitness: 64 bit

hp-check[16711]: info: :
hp-check[16711]: info: :-----------------------
hp-check[16711]: info: :| HPLIP CONFIGURATION |
hp-check[16711]: info: :-----------------------
hp-check[16711]: info: :
hp-check[16711]: info: :HPLIP-Version: HPLIP 3.18.9
hp-check[16711]: info: :HPLIP-Home: /usr/share/hplip
hp-check[16711]: info: :HPLIP-Installation: Auto installation is
supported for linuxmint distro 19 version hp-check[16711]: info: :
hp-check[16711]: info: :[01mCurrent contents of '/etc/hp/hplip.conf'
file:[0m hp-check[16711]: info: :# hplip.conf. Generated from
hplip.conf.in by configure.

[hplip]
version=3.18.9

[dirs]
home=/usr/share/hplip
run=/var/run
ppd=/usr/share/ppd/HP
ppdbase=/usr/share/ppd
doc=/usr/share/doc/hplip-3.18.9
html=/usr/share/doc/hplip-3.18.9
icon=/usr/share/applications
cupsbackend=/usr/lib/cups/backend
cupsfilter=/usr/lib/cups/filter
drv=/usr/share/cups/drv/hp
bin=/usr/bin
apparmor=/etc/apparmor.d
# Following values are determined at configure time and cannot be
changed. [configure]
network-build=yes
libusb01-build=no
pp-build=no
gui-build=yes
scanner-build=yes
fax-build=yes
dbus-build=yes
cups11-build=no
doc-build=yes
shadow-build=no
hpijs-install=no
foomatic-drv-install=no
foomatic-ppd-install=no
foomatic-rip-hplip-install=no
hpcups-install=yes
cups-drv-install=yes
cups-ppd-install=no
internal-tag=3.18.9
restricted-build=no
ui-toolkit=qt4
qt3=no
qt4=yes
qt5=no
policy-kit=no
lite-build=no
udev_sysfs_rules=no
hpcups-only-build=no
hpijs-only-build=no
apparmor_build=yes
class-driver=no

hp-check[16711]: info: :
hp-check[16711]: info: :[01mCurrent contents of
'/var/lib/hp/hplip.state' file:[0m hp-check[16711]: info: :Plugins are
not installed. Could not access file: No such file or directory
hp-check[16711]: info: : hp-check[16711]: info: :[01mCurrent contents
of '~/.hplip/hplip.conf' file:[0m hp-check[16711]: info: :[upgrade]
notify_upgrade=true
last_upgraded_time=1538071677
pending_upgrade_time=0
latest_available_version=3.17.10

[last_used]
device_uri="hp:/usb/hp_LaserJet_1320_series?serial=00CNHC5DP0ZQ"
printer_name=hp_LaserJet_1320
working_dir=.

[settings]
systray_visible=0
systray_messages=0

[commands]
scan=/usr/bin/xsane -V %SANE_URI%

[refresh]
rate=30
enable=true
type=1

[polling]
enable=false
interval=5
device_list=

[fax]
voice_phone=
email_address=

[installation]
date_time=10/05/2018 11:21:29
version=3.18.9

hp-check[16711]: info: : <Package-name> <Package-Desc>
<Required/Optional> <Min-Version> <Installed-Version> <Status>
<Comment> hp-check[16711]: info: : hp-check[16711]:
info: :-------------- hp-check[16711]: info: :| COMPILEDEP |
hp-check[16711]: info: :--------------
hp-check[16711]: info: :
hp-check[16711]: info: : gcc gcc - GNU Project C and
C++ Compiler REQUIRED -
7.3.0 OK - hp-check[16711]: info: :
make make - GNU make utility to maintain groups of
programs REQUIRED 3.0 4.1
OK - hp-check[16711]: info: : libtool libtool -
Library building support services REQUIRED
- 2.4.6 OK - hp-check[16711]: info: :
hp-check[16711]: info: :------------------------ hp-check[16711]:
info: :| General Dependencies | hp-check[16711]:
info: :------------------------ hp-check[16711]: info: :
hp-check[16711]: info: : libcrypto libcrypto - OpenSSL
cryptographic library REQUIRED
- 1.1.0 OK - hp-check[16711]: info: :
python-xml Python XML
libraries REQUIRED
- 2.2.5 OK - hp-check[16711]: info: :
libnetsnmp-devel libnetsnmp-devel - SNMP networking library
development files REQUIRED 5.0.9 5.7.3
OK - hp-check[16711]: info: : sane-devel SANE -
Scanning library development files REQUIRED
- - OK - hp-check[16711]: info: :
pil PIL - Python Imaging Library (required for
commandline scanning with hp-scan) OPTIONAL -
1.1.7 OK - hp-check[16711]: info: :
pyqt4-dbus PyQt 4 DBus - DBus Support for
PyQt4 REQUIRED 4.0
4.12.1 OK - hp-check[16711]: info: :
libpthread libpthread - POSIX threads
library REQUIRED -
2.27 OK - hp-check[16711]: info: :
python-devel Python devel - Python development
files REQUIRED 2.2
2.7.15 OK - hp-check[16711]: info: :
cups-devel CUPS devel- Common Unix Printing System
development files REQUIRED - 2.2.7
OK - hp-check[16711]: info: : python-dbus Python DBus
- Python bindings for DBus REQUIRED
0.80.0 1.2.6 OK - hp-check[16711]: info: :
cups-ddk CUPS DDK - CUPS driver development
kit OPTIONAL -
- OK - hp-check[16711]: info: :
reportlab Reportlab - PDF library for
Python OPTIONAL 2.0
3.4.0 OK - hp-check[16711]: info: :
pyqt4 PyQt 4- Qt interface for Python (for Qt version
4.x) REQUIRED 4.0 4.12.1 OK
- hp-check[16711]: info: : libusb libusb - USB
library REQUIRED
- 1.0 OK - hp-check[16711]: info: :
cups-image CUPS image - CUPS image development
files REQUIRED -
2.2.7 OK - hp-check[16711]: info: :
python2X Python 2.2 or greater - Python programming
language REQUIRED 2.2 2.7.15
OK - hp-check[16711]: info: : python-notify Python
libnotify - Python bindings for the libnotify Desktop notifications
OPTIONAL - - OK -
hp-check[16711]: info: : libjpeg libjpeg - JPEG
library REQUIRED
- - OK - hp-check[16711]: info: :
sane SANE - Scanning
library REQUIRED
- - OK - hp-check[16711]: info: :
hp-check[16711]: info: :---------------------- hp-check[16711]:
info: :| Scan Configuration | hp-check[16711]:
info: :---------------------- hp-check[16711]: info: : hp-check[16711]:
info: : scanext
Scan-SANE-Extension
REQUIRED - 3.18.9 OK -
hp-check[16711]: info: : hpaio
HPLIP-SANE-Backend
REQUIRED - 3.18.9 OK 'hpaio found
in /etc/sane.d/dll.conf' hp-check[16711]: info: : hp-check[16711]:
info: :------------------------- hp-check[16711]: info: :| External
Dependencies | hp-check[16711]: info: :-------------------------
hp-check[16711]: info: : hp-check[16711]: info: : gs
GhostScript - PostScript and PDF language interpreter and previewer
REQUIRED 7.05 9.25 OK -
hp-check[16711]: info: : scanimage scanimage - Shell
scanning program OPTIONAL
1.0 1.0.27 OK - hp-check[16711]: info: :
cups CUPS - Common Unix Printing
System REQUIRED 1.1
2.2.7 OK 'CUPS Scheduler is running' hp-check[16711]:
info: : network network
-wget OPTIONAL
- 1.19.4 OK - hp-check[16711]: info: :
policykit PolicyKit - Administrative policy
framework OPTIONAL -
0.105 OK - hp-check[16711]: info: :
xsane xsane - Graphical scanner frontend for
SANE OPTIONAL 0.9 0.999
OK - hp-check[16711]: info: : dbus DBus -
Message bus system REQUIRED
- 1.12.2 OK - hp-check[16711]: info: :
avahi-utils
avahi-utils
OPTIONAL - 0.7 OK -
hp-check[16711]: info: : hp-check[16711]: info: :---------------------
hp-check[16711]: info: :| Python Extentions | hp-check[16711]:
info: :--------------------- hp-check[16711]: info: : hp-check[16711]:
info: : hpmudext
IO-Extension
REQUIRED - 3.18.9 OK -
hp-check[16711]: info: : cupsext
CUPS-Extension
REQUIRED - 3.18.9 OK -
hp-check[16711]: info: : hp-check[16711]:
info: :------------------------------ hp-check[16711]: info: :|
DISCOVERED SCANNER DEVICES | hp-check[16711]:
info: :------------------------------ hp-check[16711]: info: :
hp-check[16711]: info: :No Scanner found. hp-check[16711]: info: :
hp-check[16711]: info: :-------------------------- hp-check[16711]:
info: :| DISCOVERED USB DEVICES | hp-check[16711]:
info: :-------------------------- hp-check[16711]: info: :
hp-check[16711]: info: : Device URI Model
hp-check[16711]: info: : --------------------------------
----------------------- hp-check[16711]: info: :
hp:/usb/hp_LaserJet_1320_series? HP LaserJet 1320 series
serial=00CNHC5DP0ZQ hp-check[16711]: info: : hp-check[16711]:
info: :--------------------------------- hp-check[16711]: info: :|
INSTALLED CUPS PRINTER QUEUES | hp-check[16711]:
info: :--------------------------------- hp-check[16711]: info: :
hp-check[16711]: info: : hp-check[16711]:
info: :[01mhp_LaserJet_1320[0m hp-check[16711]:
info: :[01m----------------[0m hp-check[16711]: info: :Type: Printer
hp-check[16711]: info: :Device URI:
hp:/usb/hp_LaserJet_1320_series?serial=00CNHC5DP0ZQ hp-check[16711]:
info: :PPD: /etc/cups/ppd/hp_LaserJet_1320.ppd hp-check[16711]:
info: :PPD Description: HP LaserJet 1320 hpijs, 3.17.10
hp-check[16711]: info: :Printer status: printer hp_LaserJet_1320 is
idle. enabled since Tue 16 Oct 2018 10:07:58 AM EDT hp-check[16711]:
info: :Communication status: Good hp-check[16711]: info: :
hp-check[16711]: info: : hp-check[16711]: info: :--------------
hp-check[16711]: info: :| PERMISSION | hp-check[16711]:
info: :-------------- hp-check[16711]: info: : hp-check[16711]:
info: :USB hp_LaserJet_1320 Required
- - OK Node:'/dev/bus/usb/001/003' Perm:' root lp
rw- rw- rw- rw- r--' hp-check[16711]: info: :[32;01mNo errors or
warnings.[0m hp-check[16711]: info: : hp-check[16711]: info: :Done.

This is syslog.log

Oct 16 10:07:19 phred kernel: [85958.207794] audit: type=1400
audit(1539698839.563:32): apparmor="DENIED" operation="file_lock"
profile="/usr/share/hplip/toolbox.py" name="/etc/xdg/Trolltech.conf"
pid=16467 comm="python" requested_mask="k" denied_mask="k" fsuid=1000
ouid=0 Oct 16 10:07:19 phred python: io/hpmud/musb.c 427: Found
interface conf=0, iface=0, altset=1, index=1 Oct 16 10:07:19 phred
python: io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0
Oct 16 10:07:19 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:19 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:19 phred python: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:07:19 phred
python: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:07:19
phred python: io/hpmud/musb.c 427: Found interface conf=0, iface=0,
altset=1, index=1 Oct 16 10:07:19 phred python: io/hpmud/musb.c 389:
Active kernel driver on interface=0 ret=0 Oct 16 10:07:19 phred
upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:19 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:19 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:20 phred python: io/hpmud/musb.c 780: read actual
device_id successfully fd=1 len=170 Oct 16 10:07:20 phred python:
io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:07:20 phred
python: io/hpmud/musb.c 960: new HP-MESSAGE channel=1 clientCnt=1
channelCnt=1 Oct 16 10:07:20 phred python: io/hpmud/musb.c 427: Found
interface conf=0, iface=0, altset=0, index=2 Oct 16 10:07:20 phred
python: io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0
Oct 16 10:07:20 phred python: io/hpmud/musb.c 535: claimed 7/1/3
interface Oct 16 10:07:20 phred upowerd[1092]: unhandled action
'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:20 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:20 phred python: io/hpmud/musb.c 561: released 7/1/3
interface Oct 16 10:07:20 phred upowerd[1092]: unhandled action
'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:21 phred python: io/hpmud/musb.c 975: removed HP-MESSAGE
channel=1 clientCnt=0 channelCnt=0 Oct 16 10:07:36 phred kernel:
[85975.258875] [UFW BLOCK] IN=enp4s0 OUT=
MAC=01:00:5e:00:00:01:e0:22:02:05:dd:8d:08:00 SRC=192.168.1.254
DST=224.0.0.1 LEN=36 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 Oct 16
10:07:40 phred kernel: [85979.338084] [UFW BLOCK] IN=enp4s0 OUT=
MAC=01:00:5e:00:00:fb:08:ea:40:39:35:52:08:00 SRC=192.168.1.69
DST=224.0.0.251 LEN=32 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 Oct 16
10:07:43 phred python: io/hpmud/musb.c 427: Found interface conf=0,
iface=0, altset=1, index=1 Oct 16 10:07:43 phred python:
io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0 Oct 16
10:07:43 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:43 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:43 phred python: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:07:43 phred
python: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:07:43
phred upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:43 phred python: io/hpmud/musb.c 427: Found interface
conf=0, iface=0, altset=1, index=1 Oct 16 10:07:43 phred python:
io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0 Oct 16
10:07:43 phred python: io/hpmud/musb.c 535: claimed 7/1/2 interface Oct
16 10:07:43 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:43 phred python: io/hpmud/musb.c 780: read actual
device_id successfully fd=1 len=170 Oct 16 10:07:43 phred python:
io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:07:43 phred
upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:43 phred python: io/hpmud/musb.c 960: new HP-MESSAGE
channel=1 clientCnt=1 channelCnt=1 Oct 16 10:07:43 phred python:
io/hpmud/musb.c 427: Found interface conf=0, iface=0, altset=0, index=2
Oct 16 10:07:43 phred python: io/hpmud/musb.c 389: Active kernel driver
on interface=0 ret=0 Oct 16 10:07:43 phred python: io/hpmud/musb.c 535:
claimed 7/1/3 interface Oct 16 10:07:43 phred upowerd[1092]: unhandled
action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:43 phred python: io/hpmud/musb.c 561: released 7/1/3
interface Oct 16 10:07:43 phred upowerd[1092]: unhandled action
'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:44 phred python: io/hpmud/musb.c 975: removed HP-MESSAGE
channel=1 clientCnt=0 channelCnt=0 Oct 16 10:07:45 phred python:
io/hpmud/musb.c 427: Found interface conf=0, iface=0, altset=1, index=1
Oct 16 10:07:45 phred python: io/hpmud/musb.c 389: Active kernel driver
on interface=0 ret=0 Oct 16 10:07:45 phred upowerd[1092]: unhandled
action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:45 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:45 phred python: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:07:45 phred
python: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:07:45
phred python: io/hpmud/musb.c 427: Found interface conf=0, iface=0,
altset=1, index=1 Oct 16 10:07:45 phred python: io/hpmud/musb.c 389:
Active kernel driver on interface=0 ret=0 Oct 16 10:07:45 phred
upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:45 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:45 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:45 phred python: io/hpmud/musb.c 780: read actual
device_id successfully fd=1 len=170 Oct 16 10:07:45 phred python:
io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:07:45 phred
python: io/hpmud/musb.c 960: new HP-MESSAGE channel=1 clientCnt=1
channelCnt=1 Oct 16 10:07:45 phred python: io/hpmud/musb.c 427: Found
interface conf=0, iface=0, altset=0, index=2 Oct 16 10:07:45 phred
python: io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0
Oct 16 10:07:45 phred python: io/hpmud/musb.c 535: claimed 7/1/3
interface Oct 16 10:07:45 phred upowerd[1092]: unhandled action
'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:45 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:45 phred python: io/hpmud/musb.c 561: released 7/1/3
interface Oct 16 10:07:45 phred upowerd[1092]: unhandled action
'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:45 phred kernel: [85984.605660] audit: type=1400
audit(1539698865.960:33): apparmor="DENIED" operation="capable"
profile="/usr/sbin/cupsd" pid=16173 comm="cupsd" capability=12
capname="net_admin" Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c
427: Found interface conf=0, iface=0, altset=1, index=1 Oct 16 10:07:46
phred hpijs[16640]: io/hpmud/musb.c 389: Active kernel driver on
interface=0 ret=0 Oct 16 10:07:46 phred upowerd[1092]: unhandled action
'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:07:46 phred
hpijs[16640]: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16
10:07:46 phred upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 427: Found
interface conf=0, iface=0, altset=1, index=1 Oct 16 10:07:46 phred
hpijs[16640]: io/hpmud/musb.c 389: Active kernel driver on interface=0
ret=0 Oct 16 10:07:46 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:07:46 phred
hpijs[16640]: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16
10:07:46 phred hpijs[16640]: io/hpmud/musb.c 427: Found interface
conf=0, iface=0, altset=1, index=1 Oct 16 10:07:46 phred hpijs[16640]:
io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0 Oct 16
10:07:46 phred upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:46 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 817: read actual
device_status successfully fd=1 Oct 16 10:07:46 phred hpijs[16640]:
io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:07:46 phred
hpijs[16640]: io/hpmud/musb.c 427: Found interface conf=0, iface=0,
altset=1, index=1 Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c
389: Active kernel driver on interface=0 ret=0 Oct 16 10:07:46 phred
upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:46 phred hpijs[16640]: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:07:46 phred
hpijs[16640]: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16
10:07:46 phred upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hp[16625]: io/hpmud/musb.c 427: Found interface
conf=0, iface=0, altset=1, index=1 Oct 16 10:07:46 phred hp[16625]:
io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0 Oct 16
10:07:46 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hp[16625]: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:46 phred hp[16625]: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:07:46 phred
hp[16625]: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16
10:07:46 phred hp[16625]: io/hpmud/musb.c 960: new PRINT channel=2
clientCnt=1 channelCnt=1 Oct 16 10:07:46 phred hp[16625]:
io/hpmud/musb.c 427: Found interface conf=0, iface=0, altset=1, index=1
Oct 16 10:07:46 phred hp[16625]: io/hpmud/musb.c 389: Active kernel
driver on interface=0 ret=0 Oct 16 10:07:46 phred upowerd[1092]:
unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:07:46 phred hp[16625]: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:07:46 phred python: io/hpmud/musb.c 975: removed
HP-MESSAGE channel=1 clientCnt=0 channelCnt=0 Oct 16 10:07:58 phred
hp[16625]: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16
10:07:58 phred hp[16625]: io/hpmud/musb.c 975: removed PRINT channel=2
clientCnt=0 channelCnt=0 Oct 16 10:07:58 phred upowerd[1092]: unhandled
action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:08:28 phred scanimage: io/hpmud/model.c 532: no
hp_hp_LaserJet_1320_series attributes found
in /usr/share/hplip/data/models/models.dat Oct 16 10:08:28 phred
scanimage: io/hpmud/model.c 543: no hp_hp_LaserJet_1320_series
attributes found
in /usr/share/hplip/data/models/unreleased/unreleased.dat Oct 16
10:08:29 phred kernel: [86028.293144] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:0000:0000:0000:06c1
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=893902 PROTO=UDP SPT=8612 DPT=8612 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.293165] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:0000:0000:0000:06c1
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=319415 PROTO=UDP SPT=8612 DPT=8610 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.293181] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:3c47:7580:bb72:d637
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=422437 PROTO=UDP SPT=8612 DPT=8612 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.293196] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:3c47:7580:bb72:d637
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=660603 PROTO=UDP SPT=8612 DPT=8610 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.293211] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:91d1:ba45:ed1e:79a7
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=479669 PROTO=UDP SPT=8612 DPT=8612 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.293226] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:91d1:ba45:ed1e:79a7
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=559394 PROTO=UDP SPT=8612 DPT=8610 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.293240] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=fe80:0000:0000:0000:c8e7:6844:d2a4:7e82
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=877512 PROTO=UDP SPT=8612 DPT=8612 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.293255] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=fe80:0000:0000:0000:c8e7:6844:d2a4:7e82
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=721635 PROTO=UDP SPT=8612 DPT=8610 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.303421] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:0000:0000:0000:06c1
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=893902 PROTO=UDP SPT=8612 DPT=8612 LEN=24 Oct 16 10:08:29 phred
kernel: [86028.303441] [UFW BLOCK] IN=enp4s0 OUT= MAC=
SRC=2600:1700:4010:91d0:0000:0000:0000:06c1
DST=ff02:0000:0000:0000:0000:0000:0000:0001 LEN=64 TC=0 HOPLIMIT=1
FLOWLBL=319415 PROTO=UDP SPT=8612 DPT=8610 LEN=24 Oct 16 10:08:34 phred
kernel: [86032.974475] [UFW BLOCK] IN=enp4s0 OUT=
MAC=01:00:5e:00:00:fb:08:ea:40:39:35:52:08:00 SRC=192.168.1.69
DST=224.0.0.251 LEN=32 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 Oct 16
10:08:35 phred python: io/hpmud/musb.c 427: Found interface conf=0,
iface=0, altset=1, index=1 Oct 16 10:08:35 phred python:
io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0 Oct 16
10:08:35 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:08:35 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:08:35 phred python: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:08:35 phred
python: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:08:35
phred upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:08:35 phred python: io/hpmud/musb.c 427: Found interface
conf=0, iface=0, altset=1, index=1 Oct 16 10:08:35 phred python:
io/hpmud/musb.c 389: Active kernel driver on interface=0 ret=0 Oct 16
10:08:35 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:08:35 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:08:35 phred python: io/hpmud/musb.c 780: read
actual device_id successfully fd=1 len=170 Oct 16 10:08:35 phred
python: io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:08:35
phred python: io/hpmud/musb.c 427: Found interface conf=0, iface=0,
altset=1, index=1 Oct 16 10:08:35 phred python: io/hpmud/musb.c 389:
Active kernel driver on interface=0 ret=0 Oct 16 10:08:35 phred
upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:08:35 phred python: io/hpmud/musb.c 535: claimed 7/1/2
interface Oct 16 10:08:35 phred upowerd[1092]: unhandled action 'bind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:08:35 phred python: io/hpmud/musb.c 780: read actual
device_id successfully fd=1 len=170 Oct 16 10:08:35 phred python:
io/hpmud/musb.c 561: released 7/1/2 interface Oct 16 10:08:35 phred
upowerd[1092]: unhandled action 'unbind'
on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0
Oct 16 10:08:35 phred python: io/hpmud/musb.c 2185: [16711]
hpmud_make_usb_uri() bus=001 dev=003 Oct 16 10:08:35 phred python:
io/hpmud/musb.c 2282: hpmud_make_usb_uri()
uri=hp:/usb/hp_LaserJet_1320_series?serial=00CNHC5DP0ZQ bytes_read=51

And this is cups_error_log.log

D [16/Oct/2018:10:07:19 -0400] cupsdSetBusyState: newbusy="Active
clients", busy="Not busy" D [16/Oct/2018:10:07:19 -0400] [Client 3]
Server address is "/run/cups/cups.sock". D [16/Oct/2018:10:07:19 -0400]
[Client 3] Accepted from localhost (Domain) D [16/Oct/2018:10:07:19
-0400] [Client 3] Waiting for request. I [16/Oct/2018:10:07:19 -0400]
Expiring subscriptions... D [16/Oct/2018:10:07:19 -0400] Report:
clients=3 D [16/Oct/2018:10:07:19 -0400] Report: jobs=93
D [16/Oct/2018:10:07:19 -0400] Report: jobs-active=0
D [16/Oct/2018:10:07:19 -0400] Report: printers=1
D [16/Oct/2018:10:07:19 -0400] Report: stringpool-string-count=2777
D [16/Oct/2018:10:07:19 -0400] Report: stringpool-alloc-bytes=10328
D [16/Oct/2018:10:07:19 -0400] Report: stringpool-total-bytes=52920
D [16/Oct/2018:10:07:19 -0400] [Client 3] POST / HTTP/1.1
D [16/Oct/2018:10:07:19 -0400] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients" D [16/Oct/2018:10:07:19 -0400] [Client
3] Read: status=200, state=6 D [16/Oct/2018:10:07:19 -0400] [Client 3]
No authentication data provided. D [16/Oct/2018:10:07:19 -0400] [Client
3] 2.0 CUPS-Get-Printers 1 D [16/Oct/2018:10:07:19 -0400]
CUPS-Get-Printers D [16/Oct/2018:10:07:19 -0400] [Client 3] Returning
IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D
[16/Oct/2018:10:07:19 -0400] [Client 3] Content-Length: 407 D
[16/Oct/2018:10:07:19 -0400] [Client 3] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:19 -0400]
[Client 3] con->http=0x55c52aa96310 D [16/Oct/2018:10:07:19 -0400]
[Client 3] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aa6f290(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:19 -0400] [Client 3] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:19 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:19 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:19 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:19 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:19 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:19
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:19 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:19 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:19 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:19 -0400] [Client 3] 2.0
CUPS-Get-Printers 2 D [16/Oct/2018:10:07:19 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:19 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:19
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:19 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:19 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:19 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa73840(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:19 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:19 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:19 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:19 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:19 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:19 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:19
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:19 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:19 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:19 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:19 -0400] [Client 3] 2.0
CUPS-Get-Printers 3 D [16/Oct/2018:10:07:19 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:19 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:19
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:19 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:19 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:19 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa6f290(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:19 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:19 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:19 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:19 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:19 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:19 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:19
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:19 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:19 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:19 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:19 -0400] [Client 3] 2.0
CUPS-Get-Printers 4 D [16/Oct/2018:10:07:19 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:19 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:19
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:19 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:19 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:19 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa73840(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:19 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:19 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:19 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:19 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:19 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:19 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:19
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:19 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:19 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:19 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:19 -0400] [Client 3] 2.0
CUPS-Get-Printers 5 D [16/Oct/2018:10:07:19 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:19 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:19
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:19 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:19 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:19 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa6f290(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:19 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:19 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:19 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:19 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:19 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:19 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" I [16/Oct/2018:10:07:20
-0400] Expiring subscriptions... D [16/Oct/2018:10:07:21 -0400] [Client
3] POST / HTTP/1.1 D [16/Oct/2018:10:07:21 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:07:21
-0400] [Client 3] Read: status=200, state=6 D [16/Oct/2018:10:07:21
-0400] [Client 3] No authentication data provided. D
[16/Oct/2018:10:07:21 -0400] [Client 3] 2.0 CUPS-Get-Printers 6 D
[16/Oct/2018:10:07:21 -0400] CUPS-Get-Printers D [16/Oct/2018:10:07:21
-0400] [Client 3] Returning IPP successful-ok for CUPS-Get-Printers (no
URI) from localhost D [16/Oct/2018:10:07:21 -0400] [Client 3]
Content-Length: 407 D [16/Oct/2018:10:07:21 -0400] [Client 3]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 I
[16/Oct/2018:10:07:21 -0400] Expiring subscriptions... D
[16/Oct/2018:10:07:21 -0400] [Client 3] con->http=0x55c52aa96310 D
[16/Oct/2018:10:07:21 -0400] [Client 3] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aa73840(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:21 -0400] [Client 3] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:21 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:21 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:21 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:21 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:21 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:21
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:21 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:21 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:21 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:21 -0400] [Client 3] 2.0
CUPS-Get-Printers 7 D [16/Oct/2018:10:07:21 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:21 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:21
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:21 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:21 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:21 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa6f290(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:21 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:21 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:21 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:21 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:21 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:21 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" I [16/Oct/2018:10:07:22
-0400] Expiring subscriptions... D [16/Oct/2018:10:07:43 -0400] [Client
3] POST / HTTP/1.1 D [16/Oct/2018:10:07:43 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:07:43
-0400] [Client 3] Read: status=200, state=6 D [16/Oct/2018:10:07:43
-0400] [Client 3] No authentication data provided. D
[16/Oct/2018:10:07:43 -0400] [Client 3] 2.0 CUPS-Get-Printers 8 D
[16/Oct/2018:10:07:43 -0400] CUPS-Get-Printers D [16/Oct/2018:10:07:43
-0400] [Client 3] Returning IPP successful-ok for CUPS-Get-Printers (no
URI) from localhost D [16/Oct/2018:10:07:43 -0400] [Client 3]
Content-Length: 407 D [16/Oct/2018:10:07:43 -0400] [Client 3]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 I
[16/Oct/2018:10:07:43 -0400] Expiring subscriptions... D
[16/Oct/2018:10:07:43 -0400] [Client 3] con->http=0x55c52aa96310 D
[16/Oct/2018:10:07:43 -0400] [Client 3] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aa73840(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:43 -0400] [Client 3] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:43 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:43 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:43 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:43 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:43 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:43
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:43 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:43 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:43 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:43 -0400] [Client 3] 2.0
CUPS-Get-Printers 9 D [16/Oct/2018:10:07:43 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:43 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:43
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:43 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:43 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:43 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa6f290(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:43 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:43 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:43 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:43 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:43 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:43 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:43
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:43 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:43 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:43 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:43 -0400] [Client 3] 2.0
CUPS-Get-Printers 10 D [16/Oct/2018:10:07:43 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:43 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:43
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:43 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:43 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:43 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa73840(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:43 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:43 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:43 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:43 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:43 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:43 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" I [16/Oct/2018:10:07:44
-0400] Expiring subscriptions... D [16/Oct/2018:10:07:44 -0400] [Client
3] POST / HTTP/1.1 D [16/Oct/2018:10:07:44 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:07:44
-0400] [Client 3] Read: status=200, state=6 D [16/Oct/2018:10:07:44
-0400] [Client 3] No authentication data provided. D
[16/Oct/2018:10:07:44 -0400] [Client 3] 2.0 CUPS-Get-Printers 11 D
[16/Oct/2018:10:07:44 -0400] CUPS-Get-Printers D [16/Oct/2018:10:07:44
-0400] [Client 3] Returning IPP successful-ok for CUPS-Get-Printers (no
URI) from localhost D [16/Oct/2018:10:07:44 -0400] [Client 3]
Content-Length: 407 D [16/Oct/2018:10:07:44 -0400] [Client 3]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:44 -0400] [Client 3] con->http=0x55c52aa96310 D
[16/Oct/2018:10:07:44 -0400] [Client 3] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aa6f290(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:44 -0400] [Client 3] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:44 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:44 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:44 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:44 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:44 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" I [16/Oct/2018:10:07:45
-0400] Expiring subscriptions... D [16/Oct/2018:10:07:45 -0400] [Client
3] POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:07:45
-0400] [Client 3] Read: status=200, state=6 D [16/Oct/2018:10:07:45
-0400] [Client 3] No authentication data provided. D
[16/Oct/2018:10:07:45 -0400] [Client 3] 2.0 CUPS-Get-Printers 12 D
[16/Oct/2018:10:07:45 -0400] CUPS-Get-Printers D [16/Oct/2018:10:07:45
-0400] [Client 3] Returning IPP successful-ok for CUPS-Get-Printers (no
URI) from localhost D [16/Oct/2018:10:07:45 -0400] [Client 3]
Content-Length: 407 D [16/Oct/2018:10:07:45 -0400] [Client 3]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 3] con->http=0x55c52aa96310 D
[16/Oct/2018:10:07:45 -0400] [Client 3] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aa73840(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 3] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:45 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:45
-0400] [Client 3] POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:45 -0400] [Client 3] Read: status=200, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 3] No authentication data
provided. D [16/Oct/2018:10:07:45 -0400] [Client 3] 2.0
CUPS-Get-Printers 13 D [16/Oct/2018:10:07:45 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:45 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:45
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:45 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:45 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:45 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aa6f290(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:45 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:45 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:45
-0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:45 -0400] [Client 4] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 4]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
4] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 4]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Active clients" D [16/Oct/2018:10:07:45
-0400] [Client 4] Read: status=200, state=6 D [16/Oct/2018:10:07:45
-0400] [Client 4] No authentication data provided. D
[16/Oct/2018:10:07:45 -0400] [Client 4] 2.0 Get-Printer-Attributes 1 D
[16/Oct/2018:10:07:45 -0400] Get-Printer-Attributes
ipp://localhost:631/printers/hp_LaserJet_1320 D [16/Oct/2018:10:07:45
-0400] [Client 4] Returning IPP successful-ok for
Get-Printer-Attributes (ipp://localhost:631/printers/hp_LaserJet_1320)
from localhost D [16/Oct/2018:10:07:45 -0400] [Client 4]
Content-Length: 1289 D [16/Oct/2018:10:07:45 -0400] [Client 4]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] con->http=0x55c52aa9a6e0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1289, response=0x55c52aa79eb0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 4] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] bytes=0, http_state=0,
data_remaining=1289 D [16/Oct/2018:10:07:45 -0400] [Client 4] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 4] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 4] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:45
-0400] [Client 4] POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=200, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] No authentication data
provided. D [16/Oct/2018:10:07:45 -0400] [Client 4] 2.0
CUPS-Get-Printers 2 D [16/Oct/2018:10:07:45 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:45 -0400] [Client 4] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:45
-0400] [Client 4] Content-Length: 1289 D [16/Oct/2018:10:07:45 -0400]
[Client 4] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:45 -0400] [Client 4]
con->http=0x55c52aa9a6e0 D [16/Oct/2018:10:07:45 -0400] [Client 4]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1289,
response=0x55c52aa72de0(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] bytes=0, http_state=0,
data_remaining=1289 D [16/Oct/2018:10:07:45 -0400] [Client 4] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 4] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 4] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:45
-0400] [Client 4] POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=200, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] No authentication data
provided. D [16/Oct/2018:10:07:45 -0400] [Client 4] 2.0
CUPS-Get-Default 3 D [16/Oct/2018:10:07:45 -0400] CUPS-Get-Default D
[16/Oct/2018:10:07:45 -0400] [Client 4] Returning IPP successful-ok for
CUPS-Get-Default (no URI) from localhost D [16/Oct/2018:10:07:45 -0400]
[Client 4] Content-Length: 10550 D [16/Oct/2018:10:07:45 -0400] [Client
4] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] con->http=0x55c52aa9a6e0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=10550, response=0x55c52aa79eb0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 4] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] bytes=0, http_state=0,
data_remaining=10550 D [16/Oct/2018:10:07:45 -0400] [Client 4] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 4] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 4] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:45
-0400] [Client 4] POST /printers/hp_LaserJet_1320 HTTP/1.1 D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients", busy="Not busy" D [16/Oct/2018:10:07:45 -0400] [Client 4]
Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400] [Client 4] No
authentication data provided. D [16/Oct/2018:10:07:45 -0400] [Client 4]
2.0 Get-Printer-Attributes 4 D [16/Oct/2018:10:07:45 -0400]
Get-Printer-Attributes ipp://localhost/printers/hp_LaserJet_1320 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Returning IPP successful-ok for
Get-Printer-Attributes (ipp://localhost/printers/hp_LaserJet_1320) from
localhost D [16/Oct/2018:10:07:45 -0400] [Client 4] Content-Length:
66379 D [16/Oct/2018:10:07:45 -0400] [Client 4] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45
-0400] [Client 4] con->http=0x55c52aa9a6e0 D [16/Oct/2018:10:07:45
-0400] [Client 4] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=66379, response=0x55c52aa72de0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 4] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] bytes=0, http_state=0,
data_remaining=66379 D [16/Oct/2018:10:07:45 -0400] [Client 4] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 4] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 4] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:07:45
-0400] [Client 4] POST /printers/hp_LaserJet_1320 HTTP/1.1 D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients", busy="Not busy" D [16/Oct/2018:10:07:45 -0400] [Client 4]
Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400] [Client 4] No
authentication data provided. D [16/Oct/2018:10:07:45 -0400] [Client 4]
2.0 Create-Job 5 D [16/Oct/2018:10:07:45 -0400] Create-Job
ipp://localhost/printers/hp_LaserJet_1320 D [16/Oct/2018:10:07:45
-0400] cupsdMarkDirty(---J-) D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients and dirty files",
busy="Active clients" D [16/Oct/2018:10:07:45 -0400] [Job 341] Applying
default options... D [16/Oct/2018:10:07:45 -0400] add_job:
requesting-user-name="gil" I [16/Oct/2018:10:07:45 -0400] [Job 341]
Adding start banner page "none". D [16/Oct/2018:10:07:45 -0400]
Notifier dbus started - PID = 16620 D [16/Oct/2018:10:07:45 -0400]
Notifier dbus started - PID = 16621 D [16/Oct/2018:10:07:45 -0400]
cupsdMarkDirty(----S) D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients and dirty files", busy="Active clients and
dirty files" I [16/Oct/2018:10:07:45 -0400] [Job 341] Queued on
"hp_LaserJet_1320" by "gil". D [16/Oct/2018:10:07:45 -0400] [Client 4]
Returning IPP successful-ok for Create-Job
(ipp://localhost/printers/hp_LaserJet_1320) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 4] Content-Length: 201 D
[16/Oct/2018:10:07:45 -0400] [Client 4] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 4] con->http=0x55c52aa9a6e0 D [16/Oct/2018:10:07:45 -0400]
[Client 4] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=201, response=0x55c52aa8b940(IPP_STATE_IDLE),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 4] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] bytes=0, http_state=0,
data_remaining=201 D [16/Oct/2018:10:07:45 -0400] [Client 4] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 4] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 4] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:45 -0400] [Client 4] POST /printers/hp_LaserJet_1320
HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients and dirty files", busy="Dirty files" D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=200, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] No authentication data
provided. D [16/Oct/2018:10:07:45 -0400] [Client 4] 2.0 Send-Document 6
D [16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] [Client 4] Read: status=100, state=6 D
[16/Oct/2018:10:07:45 -0400] Send-Document
ipp://localhost:631/printers/hp_LaserJet_1320 D [16/Oct/2018:10:07:45
-0400] cupsdIsAuthorized: requesting-user-name="gil" D
[16/Oct/2018:10:07:45 -0400] [Job 341] Auto-typing file... D
[16/Oct/2018:10:07:45 -0400] [Job 341] Request file type is
application/postscript. D [16/Oct/2018:10:07:45 -0400]
cupsdMarkDirty(---J-) D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients and dirty files", busy="Active clients and
dirty files" I [16/Oct/2018:10:07:45 -0400] [Job 341] File of type
application/postscript queued by "gil". I [16/Oct/2018:10:07:45 -0400]
[Job 341] Adding end banner page "none". D [16/Oct/2018:10:07:45 -0400]
cupsdMarkDirty(---J-) D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients and dirty files", busy="Active clients and
dirty files" D [16/Oct/2018:10:07:45 -0400] [Job 341]
time-at-processing=1539698865 D [16/Oct/2018:10:07:45 -0400]
cupsdMarkDirty(---J-) D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients and dirty files", busy="Active clients and
dirty files" D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients and dirty files", busy="Active clients and
dirty files" D [16/Oct/2018:10:07:45 -0400] cupsdMarkDirty(----S) D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:45 -0400] [Job 341] 3 filters for job: D
[16/Oct/2018:10:07:45 -0400] [Job 341] gstopdf (application/postscript
to application/pdf, cost 0) D [16/Oct/2018:10:07:45 -0400] [Job 341]
pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D
[16/Oct/2018:10:07:45 -0400] [Job 341] foomatic-rip
(application/vnd.cups-pdf to printer/hp_LaserJet_1320, cost 0) D
[16/Oct/2018:10:07:45 -0400] [Job 341] job-sheets=none,none D
[16/Oct/2018:10:07:45 -0400] [Job 341] argv[0]="hp_LaserJet_1320" D
[16/Oct/2018:10:07:45 -0400] [Job 341] argv[1]="341" D
[16/Oct/2018:10:07:45 -0400] [Job 341] argv[2]="gil" D
[16/Oct/2018:10:07:45 -0400] [Job 341] argv[3]="tmpti0PhB" D
[16/Oct/2018:10:07:45 -0400] [Job 341] argv[4]="1" D
[16/Oct/2018:10:07:45 -0400] [Job 341] argv[5]="finishings=3
number-up=1 Resolution=600x600dpi
job-uuid=urn:uuid:a5e47739-6a22-392f-6d51-a0cedbfb5cb8
job-originating-host-name=localhost date-time-at-creation=
date-time-at-processing= time-at-creation=1539698865
time-at-processing=1539698865 document-name-supplied=tmpti0PhB" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
argv[6]="/var/spool/cups/d00341-001" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[1]="CUPS_DATADIR=/usr/share/cups" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[6]="CUPS_SERVERROOT=/etc/cups" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[7]="CUPS_STATEDIR=/run/cups" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[8]="HOME=/var/spool/cups/tmp" D [16/Oct/2018:10:07:45 -0400] [Job
341]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[10]="SERVER_ADMIN=root@phred" D [16/Oct/2018:10:07:45 -0400] [Job
341] envp[11]="SOFTWARE=CUPS/2.2.7" D [16/Oct/2018:10:07:45 -0400] [Job
341] envp[12]="TMPDIR=/var/spool/cups/tmp" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[13]="USER=root" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[14]="CUPS_MAX_MESSAGE=2047" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[16]="CUPS_ENCRYPTION=IfRequested" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[17]="IPP_PORT=631" D [16/Oct/2018:10:07:45 -0400] [Job
341] envp[18]="CHARSET=utf-8" D [16/Oct/2018:10:07:45 -0400] [Job 341]
envp[19]="LANG=en_US.UTF-8" D [16/Oct/2018:10:07:45 -0400] [Job 341]
envp[20]="PPD=/etc/cups/ppd/hp_LaserJet_1320.ppd" D
[16/Oct/2018:10:07:45 -0400] [Job 341] envp[21]="RIP_MAX_CACHE=128m" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[22]="CONTENT_TYPE=application/postscript" D [16/Oct/2018:10:07:45
-0400] [Job 341]
envp[23]="DEVICE_URI=hp:/usb/hp_LaserJet_1320_series?serial=00CNHC5DP0ZQ"
D [16/Oct/2018:10:07:45 -0400] [Job 341]
envp[24]="PRINTER_INFO=hp_LaserJet_1320" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[25]="PRINTER_LOCATION=" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[26]="PRINTER=hp_LaserJet_1320" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[27]="PRINTER_STATE_REASONS=none" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[28]="CUPS_FILETYPE=document" D [16/Oct/2018:10:07:45 -0400] [Job
341] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf" D
[16/Oct/2018:10:07:45 -0400] [Job 341] envp[30]="AUTH_I****" I
[16/Oct/2018:10:07:45 -0400] [Job 341] Started
filter /usr/lib/cups/filter/gstopdf (PID 16622) I [16/Oct/2018:10:07:45
-0400] [Job 341] Started filter /usr/lib/cups/filter/pdftopdf (PID
16623) I [16/Oct/2018:10:07:45 -0400] [Job 341] Started
filter /usr/lib/cups/filter/foomatic-rip (PID 16624) I
[16/Oct/2018:10:07:45 -0400] [Job 341] Started
backend /usr/lib/cups/backend/hp (PID 16625) D [16/Oct/2018:10:07:45
-0400] cupsdMarkDirty(----S) D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Active clients and dirty files" D [16/Oct/2018:10:07:45
-0400] [Client 4] Returning IPP successful-ok for Send-Document
(ipp://localhost:631/printers/hp_LaserJet_1320) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 4] Content-Length: 171 D
[16/Oct/2018:10:07:45 -0400] [Client 4] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 4] con->http=0x55c52aa9a6e0 D [16/Oct/2018:10:07:45 -0400]
[Client 4] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=171, response=0x55c52aa7fa80(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 4] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 4] bytes=0, http_state=0,
data_remaining=171 D [16/Oct/2018:10:07:45 -0400] [Client 4] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 4] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 4] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Notifier]
state=3 D [16/Oct/2018:10:07:45 -0400] [Notifier] state=3 D
[16/Oct/2018:10:07:45 -0400] [Notifier] Connected to D-BUS D
[16/Oct/2018:10:07:45 -0400] [Notifier] JobCreated D
[16/Oct/2018:10:07:45 -0400] [Job 341] gstopdf argv[6] = 341 gil
tmpti0PhB 1 finishings=3 number-up=1 Resolution=600x600dpi
job-uuid=urn:uuid:a5e47739-6a22-392f-6d51-a0cedbfb5cb8
job-originating-host-name=localhost date-time-at-creation=
date-time-at-processing= time-at-creation=1539698865
time-at-processing=1539698865
document-name-supplied=tmpti0PhB /var/spool/cups/d00341-001 D
[16/Oct/2018:10:07:45 -0400] [Job 341]
PPD: /etc/cups/ppd/hp_LaserJet_1320.ppd D [16/Oct/2018:10:07:45 -0400]
[Notifier] Connected to D-BUS D [16/Oct/2018:10:07:45 -0400] [Notifier]
state=3 D [16/Oct/2018:10:07:45 -0400] [Notifier] state=3 D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 5] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 5]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
5] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 5]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 5] 2.0 Get-Notifications 49 D [16/Oct/2018:10:07:45
-0400] Get-Notifications /printers/ D [16/Oct/2018:10:07:45 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:45
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:45 -0400] [Client 5]
Content-Length: 1636 D [16/Oct/2018:10:07:45 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:45 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1636, response=0x55c52aa83260(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=1636 D [16/Oct/2018:10:07:45 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Notifier]
state=3 D [16/Oct/2018:10:07:45 -0400] [Notifier] PrinterStateChanged D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 6] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 6]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
6] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 6]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 6] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 6] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 6] 2.0 Get-Job-Attributes 50 D [16/Oct/2018:10:07:45
-0400] Get-Job-Attributes ipp://localhost/jobs/341 D
[16/Oct/2018:10:07:45 -0400] [Client 6] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/341) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 6] Content-Length: 109 D
[16/Oct/2018:10:07:45 -0400] [Client 6] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 6] con->http=0x55c52aaa6c40 D [16/Oct/2018:10:07:45 -0400]
[Client 6] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=109, response=0x55c52aa83200(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 6] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 6] bytes=0, http_state=0,
data_remaining=109 D [16/Oct/2018:10:07:45 -0400] [Client 6] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 6] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 6] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 6]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:45 -0400] [Client 6] Closing connection. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] [Notifier] state=3 D [16/Oct/2018:10:07:45
-0400] [Notifier] JobState D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:45
-0400] [Client 7] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:45 -0400] [Client 7] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:45 -0400] [Client 7] Waiting for request.
D [16/Oct/2018:10:07:45 -0400] [Client 7] POST / HTTP/1.1 D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Active clients,
printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client
7] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400] [Client 7]
No authentication data provided. D [16/Oct/2018:10:07:45 -0400] [Client
7] 2.0 Get-Job-Attributes 51 D [16/Oct/2018:10:07:45 -0400]
Get-Job-Attributes ipp://localhost/jobs/341 D [16/Oct/2018:10:07:45
-0400] [Client 7] Returning IPP successful-ok for Get-Job-Attributes
(ipp://localhost/jobs/341) from localhost D [16/Oct/2018:10:07:45
-0400] [Client 7] Content-Length: 109 D [16/Oct/2018:10:07:45 -0400]
[Client 7] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:45 -0400] [Client 7]
con->http=0x55c52aaa6c40 D [16/Oct/2018:10:07:45 -0400] [Client 7]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=109,
response=0x55c52aa83200(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:45 -0400] [Client 7] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 7] bytes=0, http_state=0,
data_remaining=109 D [16/Oct/2018:10:07:45 -0400] [Client 7] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 7] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 7] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 7]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:45 -0400] [Client 7] Closing connection. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] [Client 4] HTTP_STATE_WAITING Closing for
error 32 (Broken pipe) D [16/Oct/2018:10:07:45 -0400] [Client 4]
Closing connection. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 5] POST / HTTP/1.1 D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 5] Read: status=200,
state=6 D [16/Oct/2018:10:07:45 -0400] [Client 5] No authentication
data provided. D [16/Oct/2018:10:07:45 -0400] [Client 5] 2.0
Get-Notifications 52 D [16/Oct/2018:10:07:45 -0400]
Get-Notifications /printers/ D [16/Oct/2018:10:07:45 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:45
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:45 -0400] [Client 5]
Content-Length: 127 D [16/Oct/2018:10:07:45 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:45 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=127, response=0x55c52aa83240(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=127 D [16/Oct/2018:10:07:45 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 5] POST /
HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 5] 2.0 Get-Notifications 53 D [16/Oct/2018:10:07:45
-0400] Get-Notifications /printers/ D [16/Oct/2018:10:07:45 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:45
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:45 -0400] [Client 5]
Content-Length: 127 D [16/Oct/2018:10:07:45 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:45 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=127, response=0x55c52aa7fa80(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=127 D [16/Oct/2018:10:07:45 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Job 341]
pdftopdf: Last filter determined by the PPD: foomatic-rip;
FINAL_CONTENT_TYPE: application/vnd.cups-pdf => pdftopdf will log pages
in page_log. D [16/Oct/2018:10:07:45 -0400] [Job 341]
OUTFORMAT=\"PDF\", so output format will be PDF D [16/Oct/2018:10:07:45
-0400] [Job 341] Color Manager: Calibration Mode/Off D
[16/Oct/2018:10:07:45 -0400] [Job 341] Calling
FindDeviceById(cups-hp_LaserJet_1320) D [16/Oct/2018:10:07:45 -0400]
[Job 341] Calling FindDeviceById(cups-hp_LaserJet_1320) D
[16/Oct/2018:10:07:45 -0400] [Job 341] Found
device /org/freedesktop/ColorManager/devices/cups_hp_LaserJet_1320 D
[16/Oct/2018:10:07:45 -0400] [Job 341] Calling
org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D
[16/Oct/2018:10:07:45 -0400] [Job 341] Found
device /org/freedesktop/ColorManager/devices/cups_hp_LaserJet_1320 D
[16/Oct/2018:10:07:45 -0400] [Job 341] Calling
org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D
[16/Oct/2018:10:07:45 -0400] [Job 341] Calling
FindDeviceById(cups-hp_LaserJet_1320) D [16/Oct/2018:10:07:45 -0400]
[Job 341] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off D
[16/Oct/2018:10:07:45 -0400] [Job 341] Getting input from file D
[16/Oct/2018:10:07:45 -0400] [Job 341] foomatic-rip version 1.20.2
running... D [16/Oct/2018:10:07:45 -0400] [Job 341] Found
device /org/freedesktop/ColorManager/devices/cups_hp_LaserJet_1320 D
[16/Oct/2018:10:07:45 -0400] [Job 341] Parsing PPD file ... D
[16/Oct/2018:10:07:45 -0400] [Job 341] Calling
GetProfileForQualifiers(RGB..600dpi...) D [16/Oct/2018:10:07:45 -0400]
[Job 341] Added option ColorSpace D [16/Oct/2018:10:07:45 -0400] [Job
341] Added option Resolution D [16/Oct/2018:10:07:45 -0400] [Job 341]
Added option PageSize D [16/Oct/2018:10:07:45 -0400] [Job 341] Added
option Model D [16/Oct/2018:10:07:45 -0400] [Job 341] Added option
PrintoutMode D [16/Oct/2018:10:07:45 -0400] [Job 341] Added option
InputSlot D [16/Oct/2018:10:07:45 -0400] [Job 341] Found
profile /org/freedesktop/ColorManager/profiles/hp_LaserJet_1320_RGB__ D
[16/Oct/2018:10:07:45 -0400] [Job 341] Calling
org.freedesktop.ColorManager.Profile.Get(Filename) D
[16/Oct/2018:10:07:45 -0400] [Job 341] Use profile filename: \'\' D
[16/Oct/2018:10:07:45 -0400] [Job 341] Color Manager: ICC Profile: D
[16/Oct/2018:10:07:45 -0400] [Job 341] Ghostscript using
Any-Part-of-Pixel method to fill paths. D [16/Oct/2018:10:07:45 -0400]
[Job 341] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER
-dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -sstdout=%stderr
-sOutputFile=%stdout -sDEVICE=pdfwrite -dCompatibilityLevel=1.3
-dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS
-dColorImageFilter=/FlateEncode -dPDFSETTINGS=/printer
-dColorConversionStrategy=/LeaveColorUnchanged -r600x600
-dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792
-I/usr/share/cups/fonts -c \'<</.HWMargins[18.000000 14.400000
18.000000 14.400024] /Margins[0 0]>>setpagedevice\' -f -_ D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[0]=\"CONTENT_TYPE=application/postscript\" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[1]=\"PRINTER_INFO=hp_LaserJet_1320\" D
[16/Oct/2018:10:07:45 -0400] [Job 341] envp[2]=\"USER=root\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[3]=\"CUPS_SERVER=/run/cups/cups.sock\" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[4]=\"HOME=/var/spool/cups/tmp\" D
[16/Oct/2018:10:07:45 -0400] [Job 341] envp[5]=\"RIP_MAX_CACHE=128m\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[6]=\"CUPS_SERVERROOT=/etc/cups\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[7]=\"CUPS_CACHEDIR=/var/cache/cups\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[8]=\"PRINTER_STATE_REASONS=none\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[9]=\"CUPS_REQUESTROOT=/var/spool/cups\" D
[16/Oct/2018:10:07:45 -0400] [Job 341] envp[10]=\"OUTFORMAT=PDF\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[11]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin\"
D [16/Oct/2018:10:07:45 -0400] [Job 341]
envp[12]=\"DEVICE_URI=hp:/usb/hp_LaserJet_1320_series?serial=00CNHC5DP0ZQ\"
D [16/Oct/2018:10:07:45 -0400] [Job 341]
envp[13]=\"CUPS_STATEDIR=/run/cups\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[14]=\"CUPS_MAX_MESSAGE=2047\" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[15]=\"LANG=en_US.UTF-8\" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[16]=\"CUPS_SERVERBIN=/usr/lib/cups\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[17]=\"CUPS_FILETYPE=document\" D [16/Oct/2018:10:07:45 -0400] [Job
341] envp[18]=\"PPD=/etc/cups/ppd/hp_LaserJet_1320.ppd\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[19]=\"CUPS_FONTPATH=/usr/share/cups/fonts\" D
[16/Oct/2018:10:07:45 -0400] [Job 341] envp[20]=\"IPP_PORT=631\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[21]=\"AUTH_INFO_REQUIRED=none\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[22]=\"PRINTER_LOCATION=\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[23]=\"CUPS_DOCROOT=/usr/share/cups/doc-root\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[24]=\"SERVER_ADMIN=root@phred\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[25]=\"PWD=/\" D [16/Oct/2018:10:07:45 -0400] [Job 341]
envp[26]=\"CHARSET=utf-8\" D [16/Oct/2018:10:07:45 -0400] [Job 341]
envp[27]=\"PRINTER=hp_LaserJet_1320\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] envp[28]=\"SOFTWARE=CUPS/2.2.7\" D [16/Oct/2018:10:07:45
-0400] [Job 341] envp[29]=\"CUPS_DATADIR=/usr/share/cups\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[30]=\"FINAL_CONTENT_TYPE=application/vnd.cups-pdf\" D
[16/Oct/2018:10:07:45 -0400] [Job 341]
envp[31]=\"CUPS_ENCRYPTION=IfRequested\" D [16/Oct/2018:10:07:45 -0400]
[Job 341] Added option Duplex D [16/Oct/2018:10:07:45 -0400] [Job 341]
Added option Quality D [16/Oct/2018:10:07:45 -0400] [Job 341] Added
option ImageableArea D [16/Oct/2018:10:07:45 -0400] [Job 341] Added
option PaperDimension D [16/Oct/2018:10:07:45 -0400] [Job 341] Added
option Font D [16/Oct/2018:10:07:45 -0400] [Job 341] Parameter Summary
D [16/Oct/2018:10:07:45 -0400] [Job 341] ----------------- D
[16/Oct/2018:10:07:45 -0400] [Job 341] Spooler: cups D
[16/Oct/2018:10:07:45 -0400] [Job 341] Printer: hp_LaserJet_1320 D
[16/Oct/2018:10:07:45 -0400] [Job 341] Shell: /bin/sh D
[16/Oct/2018:10:07:45 -0400] [Job 341] PPD
file: /etc/cups/ppd/hp_LaserJet_1320.ppd D [16/Oct/2018:10:07:45 -0400]
[Job 341] ATTR file: D [16/Oct/2018:10:07:45 -0400] [Job 341] Printer
model: HP LaserJet 1320 hpijs, 3.17.10 D [16/Oct/2018:10:07:45 -0400]
[Job 341] Job title: tmpti0PhB D [16/Oct/2018:10:07:45 -0400] [Job 341]
File(s) to be printed: D [16/Oct/2018:10:07:45 -0400] [Job 341] <STDIN>
D [16/Oct/2018:10:07:45 -0400] [Job 341] Ghostscript extra search path
(\'GS_LIB\'): /usr/share/cups/fonts D [16/Oct/2018:10:07:45 -0400] [Job
341] Printing system options: D [16/Oct/2018:10:07:45 -0400] [Job 341]
Pondering option \'finishings=3\' D [16/Oct/2018:10:07:45 -0400] [Job
341] Unknown option finishings=3. D [16/Oct/2018:10:07:45 -0400] [Job
341] Pondering option \'number-up=1\' D [16/Oct/2018:10:07:45 -0400]
[Job 341] Unknown option number-up=1. D [16/Oct/2018:10:07:45 -0400]
[Job 341] Pondering option
\'job-uuid=urn:uuid:a5e47739-6a22-392f-6d51-a0cedbfb5cb8\' D
[16/Oct/2018:10:07:45 -0400] [Job 341] Unknown option
job-uuid=urn:uuid:a5e47739-6a22-392f-6d51-a0cedbfb5cb8. D
[16/Oct/2018:10:07:45 -0400] [Job 341] Pondering option
\'job-originating-host-name=localhost\' D [16/Oct/2018:10:07:45 -0400]
[Job 341] Unknown option job-originating-host-name=localhost. D
[16/Oct/2018:10:07:45 -0400] [Job 341] Pondering option
\'date-time-at-creation=\' D [16/Oct/2018:10:07:45 -0400] [Job 341]
Unknown option date-time-at-creation=. D [16/Oct/2018:10:07:45 -0400]
[Job 341] Pondering option \'date-time-at-processing=\' D
[16/Oct/2018:10:07:45 -0400] [Job 341] Unknown option
date-time-at-processing=. D [16/Oct/2018:10:07:45 -0400] [Job 341]
Pondering option \'time-at-creation=1539698865\' D
[16/Oct/2018:10:07:45 -0400] [Job 341] Unknown option
time-at-creation=1539698865. D [16/Oct/2018:10:07:45 -0400] [Job 341]
Pondering option \'time-at-processing=1539698865\' D
[16/Oct/2018:10:07:45 -0400] [Job 341] Unknown option
time-at-processing=1539698865. D [16/Oct/2018:10:07:45 -0400] [Job 341]
Pondering option \'document-name-supplied=tmpti0PhB\' D
[16/Oct/2018:10:07:45 -0400] [Job 341] Unknown option
document-name-supplied=tmpti0PhB. D [16/Oct/2018:10:07:45 -0400] [Job
341] CM Color Calibration Mode in CUPS: Off D [16/Oct/2018:10:07:45
-0400] [Job 341] Options from the PPD file: D [16/Oct/2018:10:07:45
-0400] [Job 341] Pondering option \'Resolution=600x600dpi\' D
[16/Oct/2018:10:07:45 -0400] [Job 341] invalid choice \"600x600dpi\",
using \"(null)\" instead D [16/Oct/2018:10:07:45 -0400] [Job 341]
================================================ D
[16/Oct/2018:10:07:45 -0400] [Job 341] File: <STDIN> D
[16/Oct/2018:10:07:45 -0400] [Job 341]
================================================ D
[16/Oct/2018:10:07:45 -0400] [Job 341] PID 16622
(/usr/lib/cups/filter/gstopdf) exited with no errors. D
[16/Oct/2018:10:07:45 -0400] [Job 341] PAGE: 1 1 D
[16/Oct/2018:10:07:45 -0400] cupsdMarkDirty(---J-) D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] cupsdMarkDirty(---J-) D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] cupsdMarkDirty(----S) D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] [Notifier] state=3 D [16/Oct/2018:10:07:45
-0400] [Job 341] Filetype: PDF D [16/Oct/2018:10:07:45 -0400] [Job 341]
Storing temporary files in /tmp D [16/Oct/2018:10:07:45 -0400] [Job
341] PID 16623 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 8] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 8]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
8] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 8]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 8] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 8] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 8] 2.0 Get-Notifications 14 D [16/Oct/2018:10:07:45
-0400] Get-Notifications / D [16/Oct/2018:10:07:45 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:45
-0400] [Client 8] Returning IPP successful-ok for Get-Notifications (/)
from localhost D [16/Oct/2018:10:07:45 -0400] [Client 8]
Content-Length: 2164 D [16/Oct/2018:10:07:45 -0400] [Client 8]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 8] con->http=0x55c52aaa5a80 D
[16/Oct/2018:10:07:45 -0400] [Client 8] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=2164, response=0x55c52aa9adf0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 8] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 8] bytes=0, http_state=0,
data_remaining=2164 D [16/Oct/2018:10:07:45 -0400] [Client 8] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 8] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 8] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 8] POST /
HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 8] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 8] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 8] 2.0 Get-Job-Attributes 15 D [16/Oct/2018:10:07:45
-0400] Get-Job-Attributes ipp://localhost/jobs/341 D
[16/Oct/2018:10:07:45 -0400] [Client 8] Returning IPP successful-ok for
Get-Job-Attributes (ipp://localhost/jobs/341) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 8] Content-Length: 1107 D
[16/Oct/2018:10:07:45 -0400] [Client 8] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 8] con->http=0x55c52aaa5a80 D [16/Oct/2018:10:07:45 -0400]
[Client 8] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1107, response=0x55c52aa9aca0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 8] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 8] bytes=0, http_state=0,
data_remaining=1107 D [16/Oct/2018:10:07:45 -0400] [Client 8] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 8] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 8] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:45
-0400] [Client 9] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:45 -0400] [Client 9] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:45 -0400] [Client 9] Waiting for request.
D [16/Oct/2018:10:07:45 -0400] [Client 9] POST / HTTP/1.1 D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Active clients,
printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client
9] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400] [Client 9]
No authentication data provided. D [16/Oct/2018:10:07:45 -0400] [Client
9] 2.0 Get-Printer-Attributes 16 D [16/Oct/2018:10:07:45 -0400]
Get-Printer-Attributes D [16/Oct/2018:10:07:45 -0400]
Get-Printer-Attributes client-error-not-found: The printer or class
does not exist. D [16/Oct/2018:10:07:45 -0400] [Client 9] Returning IPP
client-error-not-found for Get-Printer-Attributes () from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 9] Content-Length: 130 D
[16/Oct/2018:10:07:45 -0400] [Client 9] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 9] con->http=0x55c52aaab970 D [16/Oct/2018:10:07:45 -0400]
[Client 9] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=130, response=0x55c52a9f0690(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 9] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 9] bytes=0, http_state=0,
data_remaining=130 D [16/Oct/2018:10:07:45 -0400] [Client 9] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 9] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 9] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 9]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:45 -0400] [Client 9] Closing connection. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 10] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 10]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
10] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 10]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 10] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 10] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 10] 2.0 Get-Job-Attributes 17 D [16/Oct/2018:10:07:45
-0400] Get-Job-Attributes ipp://localhost/jobs/341 D
[16/Oct/2018:10:07:45 -0400] [Client 10] Returning IPP successful-ok
for Get-Job-Attributes (ipp://localhost/jobs/341) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 10] Content-Length: 180 D
[16/Oct/2018:10:07:45 -0400] [Client 10] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 10] con->http=0x55c52aaab970 D [16/Oct/2018:10:07:45 -0400]
[Client 10] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=180, response=0x55c52a9f0690(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 10] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 10] bytes=0, http_state=0,
data_remaining=180 D [16/Oct/2018:10:07:45 -0400] [Client 10] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 10] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 10] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 10]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:45 -0400] [Client 10] Closing connection. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 11] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 11]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
11] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 11]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 11] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 11] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 11] 2.0 Get-Job-Attributes 18 D [16/Oct/2018:10:07:45
-0400] Get-Job-Attributes ipp://localhost/jobs/341 D
[16/Oct/2018:10:07:45 -0400] [Client 11] Returning IPP successful-ok
for Get-Job-Attributes (ipp://localhost/jobs/341) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 11] Content-Length: 101 D
[16/Oct/2018:10:07:45 -0400] [Client 11] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 11] con->http=0x55c52aaab970 D [16/Oct/2018:10:07:45 -0400]
[Client 11] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=101, response=0x55c52a9ebbe0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 11] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 11] bytes=0, http_state=0,
data_remaining=101 D [16/Oct/2018:10:07:45 -0400] [Client 11] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 11] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 11] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:45
-0400] [Client 12] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:45 -0400] [Client 12] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:45 -0400] [Client 12] Waiting for
request. D [16/Oct/2018:10:07:45 -0400] [Client 12] POST / HTTP/1.1 D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Active clients,
printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client
12] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400] [Client
12] No authentication data provided. D [16/Oct/2018:10:07:45 -0400]
[Client 12] 2.0 Get-Printer-Attributes 19 D [16/Oct/2018:10:07:45
-0400] Get-Printer-Attributes ipp://phred:631/printers/hp_LaserJet_1320
D [16/Oct/2018:10:07:45 -0400] [Client 12] Returning IPP successful-ok
for Get-Printer-Attributes (ipp://phred:631/printers/hp_LaserJet_1320)
from localhost D [16/Oct/2018:10:07:45 -0400] [Client 12]
Content-Length: 142 D [16/Oct/2018:10:07:45 -0400] [Client 12]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:45 -0400] [Client 12] con->http=0x55c52aaafd40 D
[16/Oct/2018:10:07:45 -0400] [Client 12] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=142, response=0x55c52a9ebb20(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 12] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 12] bytes=0, http_state=0,
data_remaining=142 D [16/Oct/2018:10:07:45 -0400] [Client 12] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 12] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 12] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 12]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 12] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 12] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 12] 2.0 Get-Job-Attributes 20 D [16/Oct/2018:10:07:45
-0400] Get-Job-Attributes ipp://localhost/jobs/341 D
[16/Oct/2018:10:07:45 -0400] [Client 12] Returning IPP successful-ok
for Get-Job-Attributes (ipp://localhost/jobs/341) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 12] Content-Length: 181 D
[16/Oct/2018:10:07:45 -0400] [Client 12] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 12] con->http=0x55c52aaafd40 D [16/Oct/2018:10:07:45 -0400]
[Client 12] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=181, response=0x55c52a9f2dc0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 12] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 12] bytes=0, http_state=0,
data_remaining=181 D [16/Oct/2018:10:07:45 -0400] [Client 12] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 12] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 12] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 11]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:45 -0400] [Client 11] Closing connection. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 13] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 13]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
13] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 13]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 13] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 13] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 13] 2.0 Get-Job-Attributes 21 D [16/Oct/2018:10:07:45
-0400] Get-Job-Attributes ipp://localhost/jobs/341 D
[16/Oct/2018:10:07:45 -0400] [Client 13] Returning IPP successful-ok
for Get-Job-Attributes (ipp://localhost/jobs/341) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 13] Content-Length: 101 D
[16/Oct/2018:10:07:45 -0400] [Client 13] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 13] con->http=0x55c52aaab970 D [16/Oct/2018:10:07:45 -0400]
[Client 13] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=101, response=0x55c52a9f0600(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 13] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 13] bytes=0, http_state=0,
data_remaining=101 D [16/Oct/2018:10:07:45 -0400] [Client 13] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 13] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 13] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 13]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:45 -0400] [Client 13] Closing connection. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Client 14] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:45 -0400] [Client 14]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:45 -0400] [Client
14] Waiting for request. D [16/Oct/2018:10:07:45 -0400] [Client 14]
POST / HTTP/1.1 D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400]
[Client 14] Read: status=200, state=6 D [16/Oct/2018:10:07:45 -0400]
[Client 14] No authentication data provided. D [16/Oct/2018:10:07:45
-0400] [Client 14] 2.0 Get-Job-Attributes 22 D [16/Oct/2018:10:07:45
-0400] Get-Job-Attributes ipp://localhost/jobs/341 D
[16/Oct/2018:10:07:45 -0400] [Client 14] Returning IPP successful-ok
for Get-Job-Attributes (ipp://localhost/jobs/341) from localhost D
[16/Oct/2018:10:07:45 -0400] [Client 14] Content-Length: 101 D
[16/Oct/2018:10:07:45 -0400] [Client 14] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:45 -0400]
[Client 14] con->http=0x55c52aaab970 D [16/Oct/2018:10:07:45 -0400]
[Client 14] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=101, response=0x55c52a9ebb50(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:45 -0400] [Client 14] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:45 -0400] [Client 14] bytes=0, http_state=0,
data_remaining=101 D [16/Oct/2018:10:07:45 -0400] [Client 14] Flushing
write buffer. D [16/Oct/2018:10:07:45 -0400] [Client 14] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:45 -0400] [Client 14] Waiting
for request. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:45 -0400] [Client 14]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:45 -0400] [Client 14] Closing connection. D
[16/Oct/2018:10:07:45 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:45 -0400] [Client 8] HTTP_STATE_WAITING Closing for
error 32 (Broken pipe) D [16/Oct/2018:10:07:45 -0400] [Client 8]
Closing connection. D [16/Oct/2018:10:07:45 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:45 -0400] [Job 341] File contains 1 pages D
[16/Oct/2018:10:07:45 -0400] [Job 341] Starting renderer with command:
gs -dShowAcroForm -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE
-dNOINTERPOLATE -sDEVICE=ijs -sIjsServer=hpijs -dDEVICEWIDTHPOINTS=612
-dDEVICEHEIGHTPOINTS=792 -sDeviceManufacturer=\"HEWLETT-PACKARD\"
-sDeviceModel=\"HP LaserJet\" -dDuplex=false -r300
-sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:MediaType=0,Quality:PenSet=0,PS:MediaPosition=7
-dIjsUseOutputFD -sOutputFile=- /tmp/foomatic-DqKOpc D
[16/Oct/2018:10:07:45 -0400] [Job 341] Starting process
\"kid3\" (generation 1) D [16/Oct/2018:10:07:45 -0400] [Job 341]
Starting process \"kid4\" (generation 2) D [16/Oct/2018:10:07:45 -0400]
[Job 341] Starting process \"renderer\" (generation 2) D
[16/Oct/2018:10:07:45 -0400] [Job 341] JCL: \033%-12345X@PJL D
[16/Oct/2018:10:07:45 -0400] [Job 341] <job data> D
[16/Oct/2018:10:07:46 -0400] [Job 341] STATE: +connecting-to-device D
[16/Oct/2018:10:07:46 -0400] cupsdMarkDirty(---J-) D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46 -0400]
cupsdMarkDirty(----S) D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Dirty files" I [16/Oct/2018:10:07:46
-0400] Expiring subscriptions... D [16/Oct/2018:10:07:46 -0400]
[Notifier] state=3 D [16/Oct/2018:10:07:46 -0400] [Notifier]
PrinterStateChanged D [16/Oct/2018:10:07:46 -0400] [Notifier] state=3 D
[16/Oct/2018:10:07:46 -0400] [Client 5] POST / HTTP/1.1 D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 Get-Notifications 54 D [16/Oct/2018:10:07:46
-0400] Get-Notifications /printers/ D [16/Oct/2018:10:07:46 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:46
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:46 -0400] [Client 5]
Content-Length: 573 D [16/Oct/2018:10:07:46 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:46 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=573, response=0x55c52aa9bb70(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=573 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:46 -0400] [Client 5] POST / HTTP/1.1 D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 CUPS-Get-Printers 55 D [16/Oct/2018:10:07:46
-0400] CUPS-Get-Printers D [16/Oct/2018:10:07:46 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Printers (no URI) from
localhost D [16/Oct/2018:10:07:46 -0400] [Client 5] Content-Length:
1305 D [16/Oct/2018:10:07:46 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:46
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1305, response=0x55c52aa9b900(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=1305 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:46 -0400] [Client 5] POST / HTTP/1.1 D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 CUPS-Get-Default 56 D [16/Oct/2018:10:07:46
-0400] CUPS-Get-Default D [16/Oct/2018:10:07:46 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Default (no URI) from
localhost D [16/Oct/2018:10:07:46 -0400] [Client 5] Content-Length:
10566 D [16/Oct/2018:10:07:46 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:46
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=10566, response=0x55c52aa9bb70(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=10566 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:46 -0400] [Job 341] STATE: -connecting-to-device D
[16/Oct/2018:10:07:46 -0400] cupsdMarkDirty(---J-) D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:46 -0400]
[Job 341] STATE:
-media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
D [16/Oct/2018:10:07:46 -0400] cupsdMarkDirty(----S) D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:46 -0400] [Notifier] state=3 D [16/Oct/2018:10:07:46
-0400] [Notifier] PrinterStateChanged D [16/Oct/2018:10:07:46 -0400]
[Notifier] state=3 D [16/Oct/2018:10:07:46 -0400] [Client 5] POST /
HTTP/1.1 D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 CUPS-Get-Default 57 D [16/Oct/2018:10:07:46
-0400] CUPS-Get-Default D [16/Oct/2018:10:07:46 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Default (no URI) from
localhost D [16/Oct/2018:10:07:46 -0400] [Client 5] Content-Length:
10550 D [16/Oct/2018:10:07:46 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:46
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=10550, response=0x55c52aa9a840(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=10550 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400] [Client 5] POST /
HTTP/1.1 D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 Get-Notifications 58 D [16/Oct/2018:10:07:46
-0400] Get-Notifications /printers/ D [16/Oct/2018:10:07:46 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:46
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:46 -0400] [Client 5]
Content-Length: 557 D [16/Oct/2018:10:07:46 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:46 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=557, response=0x55c52aaa65e0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=557 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400] [Client 5] POST /
HTTP/1.1 D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 CUPS-Get-Printers 59 D [16/Oct/2018:10:07:46
-0400] CUPS-Get-Printers D [16/Oct/2018:10:07:46 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Printers (no URI) from
localhost D [16/Oct/2018:10:07:46 -0400] [Client 5] Content-Length:
1289 D [16/Oct/2018:10:07:46 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:46
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1289, response=0x55c52aaa7dd0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=1289 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400] [Client 5] POST /
HTTP/1.1 D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 CUPS-Get-Default 60 D [16/Oct/2018:10:07:46
-0400] CUPS-Get-Default D [16/Oct/2018:10:07:46 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Default (no URI) from
localhost D [16/Oct/2018:10:07:46 -0400] [Client 5] Content-Length:
10550 D [16/Oct/2018:10:07:46 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:46
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=10550, response=0x55c52aaa65e0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=10550 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400] [Client 5] POST /
HTTP/1.1 D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 5] 2.0 CUPS-Get-Default 61 D [16/Oct/2018:10:07:46
-0400] CUPS-Get-Default D [16/Oct/2018:10:07:46 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Default (no URI) from
localhost D [16/Oct/2018:10:07:46 -0400] [Client 5] Content-Length:
10550 D [16/Oct/2018:10:07:46 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:46
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=10550, response=0x55c52aaa7dd0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=10550 D [16/Oct/2018:10:07:46 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46
-0400] [Client 15] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:46 -0400] [Client 15] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:46 -0400] [Client 15] Waiting for
request. D [16/Oct/2018:10:07:46 -0400] [Client 15] POST / HTTP/1.1 D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Active clients,
printing jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400] [Client
15] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400] [Client
15] No authentication data provided. D [16/Oct/2018:10:07:46 -0400]
[Client 15] 2.0 Get-Notifications 23 D [16/Oct/2018:10:07:46 -0400]
Get-Notifications / D [16/Oct/2018:10:07:46 -0400] cupsdIsAuthorized:
requesting-user-name="gil" D [16/Oct/2018:10:07:46 -0400] [Client 15]
Returning IPP successful-ok for Get-Notifications (/) from localhost D
[16/Oct/2018:10:07:46 -0400] [Client 15] Content-Length: 1003 D
[16/Oct/2018:10:07:46 -0400] [Client 15] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46 -0400]
[Client 15] con->http=0x55c52aaab970 D [16/Oct/2018:10:07:46 -0400]
[Client 15] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1003, response=0x55c52aaa6730(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 15] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 15] bytes=0, http_state=0,
data_remaining=1003 D [16/Oct/2018:10:07:46 -0400] [Client 15] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 15] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 15] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400] [Client 15]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:07:46 -0400] [Client 15] Closing connection. D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:46 -0400] [Client 16] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:07:46 -0400] [Client 16]
Accepted from localhost (Domain) D [16/Oct/2018:10:07:46 -0400] [Client
16] Waiting for request. D [16/Oct/2018:10:07:46 -0400] [Client 16]
POST /printers/hp_LaserJet_1320 HTTP/1.1 D [16/Oct/2018:10:07:46 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Active clients, printing jobs, and dirty files" D
[16/Oct/2018:10:07:46 -0400] [Client 16] Read: status=200, state=6 D
[16/Oct/2018:10:07:46 -0400] [Client 16] No authentication data
provided. D [16/Oct/2018:10:07:46 -0400] [Client 16] 2.0
Get-Printer-Attributes 24 D [16/Oct/2018:10:07:46 -0400]
Get-Printer-Attributes ipp://localhost/printers/hp_LaserJet_1320 D
[16/Oct/2018:10:07:46 -0400] [Client 16] Returning IPP successful-ok
for Get-Printer-Attributes (ipp://localhost/printers/hp_LaserJet_1320)
from localhost D [16/Oct/2018:10:07:46 -0400] [Client 16]
Content-Length: 230 D [16/Oct/2018:10:07:46 -0400] [Client 16]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:46 -0400] [Client 16] con->http=0x55c52aaab970 D
[16/Oct/2018:10:07:46 -0400] [Client 16] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=230, response=0x55c52aaa6820(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 16] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 16] bytes=0, http_state=0,
data_remaining=230 D [16/Oct/2018:10:07:46 -0400] [Client 16] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 16] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 16] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46
-0400] [Client 17] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:46 -0400] [Client 17] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:46 -0400] [Client 17] Waiting for
request. D [16/Oct/2018:10:07:46 -0400] [Client 17]
GET /printers/hp_LaserJet_1320.ppd HTTP/1.1 D [16/Oct/2018:10:07:46
-0400] cupsdSetBusyState: newbusy="Active clients, printing jobs, and
dirty files", busy="Active clients, printing jobs, and dirty files" D
[16/Oct/2018:10:07:46 -0400] [Client 17] Read: status=200, state=3 D
[16/Oct/2018:10:07:46 -0400] [Client 17] No authentication data
provided. D [16/Oct/2018:10:07:46 -0400] [Client 17] Processing
GET /printers/hp_LaserJet_1320.ppd D [16/Oct/2018:10:07:46 -0400]
[Client 17] filename="/etc/cups/ppd/hp_LaserJet_1320.ppd",
type=application/vnd.cups-ppd D [16/Oct/2018:10:07:46 -0400] [Client
17] cupsdSendHeader: code=304, type="(null)", auth_type=0 D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Active clients, printing jobs, and dirty files"
D [16/Oct/2018:10:07:46 -0400] [Client 17] HTTP_STATE_WAITING Closing
for error 32 (Broken pipe) D [16/Oct/2018:10:07:46 -0400] [Client 17]
Closing connection. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:46 -0400] [Client 3] POST / HTTP/1.1 D
[16/Oct/2018:10:07:46 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:46 -0400] [Client 3] Read: status=200,
state=6 D [16/Oct/2018:10:07:46 -0400] [Client 3] No authentication
data provided. D [16/Oct/2018:10:07:46 -0400] [Client 3] 2.0
CUPS-Get-Printers 14 D [16/Oct/2018:10:07:46 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:07:46 -0400] [Client 3] Returning IPP successful-ok for
CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:07:46
-0400] [Client 3] Content-Length: 407 D [16/Oct/2018:10:07:46 -0400]
[Client 3] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:46 -0400] [Client 3]
con->http=0x55c52aa96310 D [16/Oct/2018:10:07:46 -0400] [Client 3]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aaa6760(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:46 -0400] [Client 3] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:46 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:46 -0400] [Client 3] POST /
HTTP/1.1 D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:46 -0400]
[Client 3] Read: status=200, state=6 D [16/Oct/2018:10:07:46 -0400]
[Client 3] No authentication data provided. D [16/Oct/2018:10:07:46
-0400] [Client 3] 2.0 CUPS-Get-Printers 15 D [16/Oct/2018:10:07:46
-0400] CUPS-Get-Printers D [16/Oct/2018:10:07:46 -0400] [Client 3]
Returning IPP successful-ok for CUPS-Get-Printers (no URI) from
localhost D [16/Oct/2018:10:07:46 -0400] [Client 3] Content-Length: 407
D [16/Oct/2018:10:07:46 -0400] [Client 3] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:46 -0400]
[Client 3] con->http=0x55c52aa96310 D [16/Oct/2018:10:07:46 -0400]
[Client 3] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aaa5a80(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:46 -0400] [Client 3] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:46 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:46 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:46 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:46 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:46 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" I [16/Oct/2018:10:07:47 -0400] Expiring
subscriptions... D [16/Oct/2018:10:07:49 -0400] [Job 341] renderer
exited with status 0 I [16/Oct/2018:10:07:49 -0400] Expiring
subscriptions... D [16/Oct/2018:10:07:49 -0400] [Job 341] kid4 exited
with status 0 D [16/Oct/2018:10:07:49 -0400] [Job 341] kid3 finished D
[16/Oct/2018:10:07:49 -0400] [Job 341] Kid3 exit status: 0 D
[16/Oct/2018:10:07:49 -0400] [Job 341] Closing foomatic-rip. D
[16/Oct/2018:10:07:49 -0400] [Job 341] PID 16624
(/usr/lib/cups/filter/foomatic-rip) exited with no errors. D
[16/Oct/2018:10:07:58 -0400] [Job 341] ready to print D
[16/Oct/2018:10:07:58 -0400] cupsdMarkDirty(---J-) D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Job 341] Set job-printer-state-message to
"ready to print", current level=INFO D [16/Oct/2018:10:07:58 -0400]
cupsdMarkDirty(----S) D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:58 -0400] cupsdMarkDirty(----S) D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" I
[16/Oct/2018:10:07:58 -0400] Expiring subscriptions... D
[16/Oct/2018:10:07:58 -0400] [Notifier] state=3 D [16/Oct/2018:10:07:58
-0400] [Notifier] state=3 D [16/Oct/2018:10:07:58 -0400] [Notifier]
state=3 D [16/Oct/2018:10:07:58 -0400] [Notifier] PrinterStateChanged D
[16/Oct/2018:10:07:58 -0400] [Client 5] POST / HTTP/1.1 D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active
clients, printing jobs, and dirty files", busy="Printing jobs and dirty
files" D [16/Oct/2018:10:07:58 -0400] [Client 5] Read: status=200,
state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] No authentication
data provided. D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=100, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] 2.0
Get-Notifications 62 D [16/Oct/2018:10:07:58 -0400]
Get-Notifications /printers/ D [16/Oct/2018:10:07:58 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:58
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:58 -0400] [Client 5]
Content-Length: 557 D [16/Oct/2018:10:07:58 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:58 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=557, response=0x55c52aa99b20(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=557 D [16/Oct/2018:10:07:58 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:58 -0400] [Client 5] POST /
HTTP/1.1 D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:58 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:58
-0400] [Client 5] 2.0 CUPS-Get-Printers 63 D [16/Oct/2018:10:07:58
-0400] CUPS-Get-Printers D [16/Oct/2018:10:07:58 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Printers (no URI) from
localhost D [16/Oct/2018:10:07:58 -0400] [Client 5] Content-Length:
1289 D [16/Oct/2018:10:07:58 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:58
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:58
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1289, response=0x55c52aaa7c30(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=1289 D [16/Oct/2018:10:07:58 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:58 -0400] [Client 3] POST /
HTTP/1.1 D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 3] Read: status=200, state=6 D [16/Oct/2018:10:07:58 -0400]
[Client 3] No authentication data provided. D [16/Oct/2018:10:07:58
-0400] [Client 3] 2.0 CUPS-Get-Printers 16 D [16/Oct/2018:10:07:58
-0400] CUPS-Get-Printers D [16/Oct/2018:10:07:58 -0400] [Client 3]
Returning IPP successful-ok for CUPS-Get-Printers (no URI) from
localhost D [16/Oct/2018:10:07:58 -0400] [Client 3] Content-Length: 407
D [16/Oct/2018:10:07:58 -0400] [Client 3] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:58 -0400]
[Client 5] POST / HTTP/1.1 D [16/Oct/2018:10:07:58 -0400]
cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty
files", busy="Active clients, printing jobs, and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 5] Read: status=200, state=6 D
[16/Oct/2018:10:07:58 -0400] [Client 5] No authentication data
provided. D [16/Oct/2018:10:07:58 -0400] [Client 5] 2.0
CUPS-Get-Default 64 D [16/Oct/2018:10:07:58 -0400] CUPS-Get-Default D
[16/Oct/2018:10:07:58 -0400] [Client 5] Returning IPP successful-ok for
CUPS-Get-Default (no URI) from localhost D [16/Oct/2018:10:07:58 -0400]
[Client 5] Content-Length: 10564 D [16/Oct/2018:10:07:58 -0400] [Client
5] cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:58 -0400] [Client 3] con->http=0x55c52aa96310 D
[16/Oct/2018:10:07:58 -0400] [Client 3] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aa99b20(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 3] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 3] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:07:58 -0400] [Client 3] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 3] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 3] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Active clients, printing jobs, and dirty files", busy="Active
clients, printing jobs, and dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:58 -0400]
[Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=10564, response=0x55c52aaa5eb0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=10564 D [16/Oct/2018:10:07:58 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Printing jobs and dirty files", busy="Active clients, printing
jobs, and dirty files" D [16/Oct/2018:10:07:58 -0400] [Job 341] PID
16625 (/usr/lib/cups/backend/hp) exited with no errors. D
[16/Oct/2018:10:07:58 -0400] [Job 341] time-at-completed=1539698878 D
[16/Oct/2018:10:07:58 -0400] cupsdMarkDirty(----S) D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" I
[16/Oct/2018:10:07:58 -0400] [Job 341] Job completed. I
[16/Oct/2018:10:07:58 -0400] Expiring subscriptions... D
[16/Oct/2018:10:07:58 -0400] cupsdMarkDirty(---J-) D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:58 -0400] cupsdMarkDirty(----S) D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:58 -0400] cupsdMarkDirty(---J-) D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Printing jobs
and dirty files", busy="Printing jobs and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Notifier] state=3 D [16/Oct/2018:10:07:58
-0400] [Notifier] JobCompleted D [16/Oct/2018:10:07:58 -0400]
[Notifier] state=3 D [16/Oct/2018:10:07:58 -0400] [Notifier] state=3 D
[16/Oct/2018:10:07:58 -0400] [Notifier] PrinterStateChanged D
[16/Oct/2018:10:07:58 -0400] [Notifier] state=3 D [16/Oct/2018:10:07:58
-0400] [Client 5] POST / HTTP/1.1 D [16/Oct/2018:10:07:58 -0400]
cupsdSetBusyState: newbusy="Active clients and dirty files",
busy="Printing jobs and dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:58 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:58
-0400] [Client 5] 2.0 CUPS-Get-Default 65 D [16/Oct/2018:10:07:58
-0400] CUPS-Get-Default D [16/Oct/2018:10:07:58 -0400] [Client 5]
Returning IPP successful-ok for CUPS-Get-Default (no URI) from
localhost D [16/Oct/2018:10:07:58 -0400] [Client 5] Content-Length:
10550 D [16/Oct/2018:10:07:58 -0400] [Client 5] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:58
-0400] [Client 5] con->http=0x55c52aaa2870 D [16/Oct/2018:10:07:58
-0400] [Client 5] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=10550, response=0x55c52aaa8670(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=10550 D [16/Oct/2018:10:07:58 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 5] POST / HTTP/1.1 D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 5] Read: status=200, state=6 D [16/Oct/2018:10:07:58 -0400]
[Client 5] No authentication data provided. D [16/Oct/2018:10:07:58
-0400] [Client 5] 2.0 Get-Notifications 66 D [16/Oct/2018:10:07:58
-0400] Get-Notifications /printers/ D [16/Oct/2018:10:07:58 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:58
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:58 -0400] [Client 5]
Content-Length: 1104 D [16/Oct/2018:10:07:58 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:58 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1104, response=0x55c52aab4470(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=1104 D [16/Oct/2018:10:07:58 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 18] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:58 -0400] [Client 18] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:58 -0400] [Client 18] Waiting for
request. D [16/Oct/2018:10:07:58 -0400] [Client 18] POST / HTTP/1.1 D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 18] Read: status=200, state=6 D
[16/Oct/2018:10:07:58 -0400] [Client 18] No authentication data
provided. D [16/Oct/2018:10:07:58 -0400] [Client 18] 2.0
Get-Job-Attributes 67 D [16/Oct/2018:10:07:58 -0400] Get-Job-Attributes
ipp://localhost/jobs/341 D [16/Oct/2018:10:07:58 -0400] [Client 18]
Returning IPP successful-ok for Get-Job-Attributes
(ipp://localhost/jobs/341) from localhost D [16/Oct/2018:10:07:58
-0400] [Client 18] Content-Length: 109 D [16/Oct/2018:10:07:58 -0400]
[Client 18] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:58 -0400] [Client 18]
con->http=0x55c52aab7390 D [16/Oct/2018:10:07:58 -0400] [Client 18]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=109,
response=0x55c52aab55e0(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:58 -0400] [Client 18] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 18] bytes=0, http_state=0,
data_remaining=109 D [16/Oct/2018:10:07:58 -0400] [Client 18] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 18] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 18] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 18] HTTP_STATE_WAITING Closing for
error 32 (Broken pipe) D [16/Oct/2018:10:07:58 -0400] [Client 18]
Closing connection. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Dirty files" D [16/Oct/2018:10:07:58
-0400] [Client 5] POST / HTTP/1.1 D [16/Oct/2018:10:07:58 -0400]
cupsdSetBusyState: newbusy="Active clients and dirty files",
busy="Dirty files" D [16/Oct/2018:10:07:58 -0400] [Client 5] Read:
status=200, state=6 D [16/Oct/2018:10:07:58 -0400] [Client 5] No
authentication data provided. D [16/Oct/2018:10:07:58 -0400] [Client 5]
2.0 Get-Notifications 68 D [16/Oct/2018:10:07:58 -0400]
Get-Notifications /printers/ D [16/Oct/2018:10:07:58 -0400]
cupsdIsAuthorized: requesting-user-name="gil" D [16/Oct/2018:10:07:58
-0400] [Client 5] Returning IPP successful-ok for Get-Notifications
(/printers/) from localhost D [16/Oct/2018:10:07:58 -0400] [Client 5]
Content-Length: 127 D [16/Oct/2018:10:07:58 -0400] [Client 5]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] con->http=0x55c52aaa2870 D
[16/Oct/2018:10:07:58 -0400] [Client 5] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=127, response=0x55c52aab5f60(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 5] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 5] bytes=0, http_state=0,
data_remaining=127 D [16/Oct/2018:10:07:58 -0400] [Client 5] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 5] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 5] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 19] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:58 -0400] [Client 19] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:58 -0400] [Client 19] Waiting for
request. D [16/Oct/2018:10:07:58 -0400] [Client 19] POST / HTTP/1.1 D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 19] Read: status=200, state=6 D
[16/Oct/2018:10:07:58 -0400] [Client 19] No authentication data
provided. D [16/Oct/2018:10:07:58 -0400] [Client 19] 2.0
Get-Notifications 25 D [16/Oct/2018:10:07:58 -0400] Get-Notifications /
D [16/Oct/2018:10:07:58 -0400] cupsdIsAuthorized:
requesting-user-name="gil" D [16/Oct/2018:10:07:58 -0400] [Client 19]
Returning IPP successful-ok for Get-Notifications (/) from localhost D
[16/Oct/2018:10:07:58 -0400] [Client 19] Content-Length: 2058 D
[16/Oct/2018:10:07:58 -0400] [Client 19] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:58 -0400]
[Client 19] con->http=0x55c52aab7390 D [16/Oct/2018:10:07:58 -0400]
[Client 19] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=2058, response=0x55c52aab4470(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 19] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 19] bytes=0, http_state=0,
data_remaining=2058 D [16/Oct/2018:10:07:58 -0400] [Client 19] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 19] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 19] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 20] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:07:58 -0400] [Client 20] Accepted from localhost
(Domain) D [16/Oct/2018:10:07:58 -0400] [Client 20] Waiting for
request. D [16/Oct/2018:10:07:58 -0400] [Client 20] POST / HTTP/1.1 D
[16/Oct/2018:10:07:58 -0400] cupsdSetBusyState: newbusy="Active clients
and dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 20] Read: status=200, state=6 D
[16/Oct/2018:10:07:58 -0400] [Client 20] No authentication data
provided. D [16/Oct/2018:10:07:58 -0400] [Client 20] 2.0
Get-Job-Attributes 26 D [16/Oct/2018:10:07:58 -0400] Get-Job-Attributes
ipp://localhost/jobs/341 D [16/Oct/2018:10:07:58 -0400] [Client 20]
Returning IPP successful-ok for Get-Job-Attributes
(ipp://localhost/jobs/341) from localhost D [16/Oct/2018:10:07:58
-0400] [Client 20] Content-Length: 120 D [16/Oct/2018:10:07:58 -0400]
[Client 20] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:07:58 -0400] [Client 20]
con->http=0x55c52aabb760 D [16/Oct/2018:10:07:58 -0400] [Client 20]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=120,
response=0x55c52aab4c50(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:07:58 -0400] [Client 20] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 20] bytes=0, http_state=0,
data_remaining=120 D [16/Oct/2018:10:07:58 -0400] [Client 20] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 20] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 20] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 20] HTTP_STATE_WAITING Closing for
error 32 (Broken pipe) D [16/Oct/2018:10:07:58 -0400] [Client 20]
Closing connection. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Dirty files" D [16/Oct/2018:10:07:58
-0400] cupsdSetBusyState: newbusy="Active clients and dirty files",
busy="Dirty files" D [16/Oct/2018:10:07:58 -0400] [Client 21] Server
address is "/run/cups/cups.sock". D [16/Oct/2018:10:07:58 -0400]
[Client 21] Accepted from localhost (Domain) D [16/Oct/2018:10:07:58
-0400] [Client 21] Waiting for request. D [16/Oct/2018:10:07:58 -0400]
[Client 21] POST / HTTP/1.1 D [16/Oct/2018:10:07:58 -0400]
cupsdSetBusyState: newbusy="Active clients and dirty files",
busy="Active clients and dirty files" D [16/Oct/2018:10:07:58 -0400]
[Client 21] Read: status=200, state=6 D [16/Oct/2018:10:07:58 -0400]
[Client 21] No authentication data provided. D [16/Oct/2018:10:07:58
-0400] [Client 21] 2.0 Get-Printer-Attributes 27 D
[16/Oct/2018:10:07:58 -0400] Get-Printer-Attributes
ipp://phred:631/printers/hp_LaserJet_1320 D [16/Oct/2018:10:07:58
-0400] [Client 21] Returning IPP successful-ok for
Get-Printer-Attributes (ipp://phred:631/printers/hp_LaserJet_1320) from
localhost D [16/Oct/2018:10:07:58 -0400] [Client 21] Content-Length:
142 D [16/Oct/2018:10:07:58 -0400] [Client 21] cupsdSendHeader:
code=200, type="application/ipp", auth_type=0 D [16/Oct/2018:10:07:58
-0400] [Client 21] con->http=0x55c52aabb760 D [16/Oct/2018:10:07:58
-0400] [Client 21] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=142, response=0x55c52aab4800(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:07:58 -0400] [Client 21] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:07:58 -0400] [Client 21] bytes=0, http_state=0,
data_remaining=142 D [16/Oct/2018:10:07:58 -0400] [Client 21] Flushing
write buffer. D [16/Oct/2018:10:07:58 -0400] [Client 21] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:07:58 -0400] [Client 21] Waiting
for request. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Active clients and dirty files" D
[16/Oct/2018:10:07:58 -0400] [Client 19] HTTP_STATE_WAITING Closing for
error 32 (Broken pipe) D [16/Oct/2018:10:07:58 -0400] [Client 19]
Closing connection. D [16/Oct/2018:10:07:58 -0400] cupsdSetBusyState:
newbusy="Dirty files", busy="Dirty files" I [16/Oct/2018:10:07:59
-0400] Expiring subscriptions... I [16/Oct/2018:10:08:16 -0400] Saving
job.cache... I [16/Oct/2018:10:08:16 -0400] Saving
subscriptions.conf... D [16/Oct/2018:10:08:16 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Dirty files" I [16/Oct/2018:10:08:16 -0400]
Expiring subscriptions... D [16/Oct/2018:10:08:23 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:23 -0400] [Client 22] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:08:23 -0400] [Client 22]
Accepted from localhost (Domain) D [16/Oct/2018:10:08:23 -0400] [Client
22] Waiting for request. I [16/Oct/2018:10:08:23 -0400] Expiring
subscriptions... D [16/Oct/2018:10:08:23 -0400] Report: clients=8 D
[16/Oct/2018:10:08:23 -0400] Report: jobs=94 D [16/Oct/2018:10:08:23
-0400] Report: jobs-active=0 D [16/Oct/2018:10:08:23 -0400] Report:
printers=1 D [16/Oct/2018:10:08:23 -0400] Report:
stringpool-string-count=3559 D [16/Oct/2018:10:08:23 -0400] Report:
stringpool-alloc-bytes=12624 D [16/Oct/2018:10:08:23 -0400] Report:
stringpool-total-bytes=69856 D [16/Oct/2018:10:08:23 -0400] [Client 22]
HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:08:23 -0400] [Client 22] Closing connection. D
[16/Oct/2018:10:08:23 -0400] cupsdSetBusyState: newbusy="Not busy",
busy="Active clients" I [16/Oct/2018:10:08:24 -0400] Expiring
subscriptions... D [16/Oct/2018:10:08:27 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:08:27
-0400] [Client 23] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:08:27 -0400] [Client 23] Accepted from localhost
(Domain) D [16/Oct/2018:10:08:27 -0400] [Client 23] Waiting for
request. I [16/Oct/2018:10:08:27 -0400] Expiring subscriptions... D
[16/Oct/2018:10:08:27 -0400] [Client 23] HTTP_STATE_WAITING Closing for
error 32 (Broken pipe) D [16/Oct/2018:10:08:27 -0400] [Client 23]
Closing connection. D [16/Oct/2018:10:08:27 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:28
-0400] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:28 -0400] [Client 24] Server address is
"/run/cups/cups.sock". D [16/Oct/2018:10:08:28 -0400] [Client 24]
Accepted from localhost (Domain) D [16/Oct/2018:10:08:28 -0400] [Client
24] Waiting for request. I [16/Oct/2018:10:08:28 -0400] Expiring
subscriptions... D [16/Oct/2018:10:08:28 -0400] cupsdAddCert: Adding
certificate for PID 0 D [16/Oct/2018:10:08:28 -0400] [Client 24] POST /
HTTP/1.1 D [16/Oct/2018:10:08:28 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Active clients" D [16/Oct/2018:10:08:28
-0400] [Client 24] Read: status=200, state=6 D [16/Oct/2018:10:08:28
-0400] [Client 24] No authentication data provided. D
[16/Oct/2018:10:08:28 -0400] [Client 24] 2.0 CUPS-Get-Printers 1 D
[16/Oct/2018:10:08:28 -0400] CUPS-Get-Printers D [16/Oct/2018:10:08:28
-0400] [Client 24] Returning IPP successful-ok for CUPS-Get-Printers
(no URI) from localhost D [16/Oct/2018:10:08:28 -0400] [Client 24]
Content-Length: 139 D [16/Oct/2018:10:08:28 -0400] [Client 24]
cupsdSendHeader: code=200, type="application/ipp", auth_type=0 D
[16/Oct/2018:10:08:28 -0400] [Client 24] con->http=0x55c52aab7390 D
[16/Oct/2018:10:08:28 -0400] [Client 24] cupsdWriteClient error=0,
used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=139, response=0x55c52aaa5de0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:08:28 -0400] [Client 24] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:28 -0400] [Client 24] bytes=0, http_state=0,
data_remaining=139 D [16/Oct/2018:10:08:28 -0400] [Client 24] Flushing
write buffer. D [16/Oct/2018:10:08:28 -0400] [Client 24] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:28 -0400] [Client 24] Waiting
for request. D [16/Oct/2018:10:08:28 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" I [16/Oct/2018:10:08:29
-0400] Expiring subscriptions... D [16/Oct/2018:10:08:29 -0400] Closing
client 1 after 300 seconds of inactivity. D [16/Oct/2018:10:08:29
-0400] [Client 1] Closing connection. D [16/Oct/2018:10:08:29 -0400]
cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D
[16/Oct/2018:10:08:29 -0400] Closing client 2 after 300 seconds of
inactivity. D [16/Oct/2018:10:08:29 -0400] [Client 2] Closing
connection. D [16/Oct/2018:10:08:29 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Not busy" D [16/Oct/2018:10:08:35 -0400]
[Client 24] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:08:35 -0400] [Client 24] Closing connection. D
[16/Oct/2018:10:08:35 -0400] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy" I [16/Oct/2018:10:08:35 -0400] Expiring
subscriptions... D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:08:35
-0400] [Client 25] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:08:35 -0400] [Client 25] Accepted from localhost
(Domain) D [16/Oct/2018:10:08:35 -0400] [Client 25] Waiting for
request. D [16/Oct/2018:10:08:35 -0400] [Client 25] POST / HTTP/1.1 D
[16/Oct/2018:10:08:35 -0400] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients" D [16/Oct/2018:10:08:35 -0400] [Client
25] Read: status=200, state=6 D [16/Oct/2018:10:08:35 -0400] [Client
25] No authentication data provided. D [16/Oct/2018:10:08:35 -0400]
[Client 25] 2.0 CUPS-Get-Printers 1 D [16/Oct/2018:10:08:35 -0400]
CUPS-Get-Printers D [16/Oct/2018:10:08:35 -0400] [Client 25] Returning
IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D
[16/Oct/2018:10:08:35 -0400] [Client 25] Content-Length: 1289 D
[16/Oct/2018:10:08:35 -0400] [Client 25] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:08:35 -0400]
[Client 25] con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400]
[Client 25] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1289, response=0x55c52aab55a0(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:08:35 -0400] [Client 25] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 25] bytes=0, http_state=0,
data_remaining=1289 D [16/Oct/2018:10:08:35 -0400] [Client 25] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 25] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 25] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 25] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 25] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 25] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 25] 2.0
CUPS-Get-Default 2 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Default D
[16/Oct/2018:10:08:35 -0400] [Client 25] Returning IPP successful-ok
for CUPS-Get-Default (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 25] Content-Length: 10550 D [16/Oct/2018:10:08:35 -0400]
[Client 25] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 25]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 25]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10550,
response=0x55c52aaa8b20(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 25] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 25] bytes=0, http_state=0,
data_remaining=10550 D [16/Oct/2018:10:08:35 -0400] [Client 25]
Flushing write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 25] New
state is HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 25]
Waiting for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 25] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 25] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 25] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 25] 2.0
CUPS-Get-Default 3 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Default D
[16/Oct/2018:10:08:35 -0400] [Client 25] Returning IPP successful-ok
for CUPS-Get-Default (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 25] Content-Length: 10550 D [16/Oct/2018:10:08:35 -0400]
[Client 25] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 25]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 25]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10550,
response=0x55c52aaa88d0(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 25] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 25] bytes=0, http_state=0,
data_remaining=10550 D [16/Oct/2018:10:08:35 -0400] [Client 25]
Flushing write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 25] New
state is HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 25]
Waiting for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 25] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 25] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 25] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 25] 2.0
CUPS-Get-Printers 4 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:08:35 -0400] [Client 25] Returning IPP successful-ok
for CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 25] Content-Length: 242 D [16/Oct/2018:10:08:35 -0400]
[Client 25] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 25]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 25]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=242,
response=0x55c52aaa8b20(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 25] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 25] bytes=0, http_state=0,
data_remaining=242 D [16/Oct/2018:10:08:35 -0400] [Client 25] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 25] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 25] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 25] HTTP_STATE_WAITING Closing for error 32 (Broken
pipe) D [16/Oct/2018:10:08:35 -0400] [Client 25] Closing connection. D
[16/Oct/2018:10:08:35 -0400] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy" D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:08:35
-0400] [Client 26] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:08:35 -0400] [Client 26] Accepted from localhost
(Domain) D [16/Oct/2018:10:08:35 -0400] [Client 26] Waiting for
request. D [16/Oct/2018:10:08:35 -0400] [Client 26] POST / HTTP/1.1 D
[16/Oct/2018:10:08:35 -0400] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients" D [16/Oct/2018:10:08:35 -0400] [Client
26] Read: status=200, state=6 D [16/Oct/2018:10:08:35 -0400] [Client
26] No authentication data provided. D [16/Oct/2018:10:08:35 -0400]
[Client 26] 2.0 Get-Printer-Attributes 1 D [16/Oct/2018:10:08:35 -0400]
Get-Printer-Attributes ipp://localhost:631/printers/hp_LaserJet_1320 D
[16/Oct/2018:10:08:35 -0400] [Client 26] Returning IPP successful-ok
for Get-Printer-Attributes
(ipp://localhost:631/printers/hp_LaserJet_1320) from localhost D
[16/Oct/2018:10:08:35 -0400] [Client 26] Content-Length: 1289 D
[16/Oct/2018:10:08:35 -0400] [Client 26] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:08:35 -0400]
[Client 26] con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400]
[Client 26] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=1289, response=0x55c52aab4800(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:08:35 -0400] [Client 26] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 26] bytes=0, http_state=0,
data_remaining=1289 D [16/Oct/2018:10:08:35 -0400] [Client 26] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 26] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 26] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 26] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 26] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 26] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 26] 2.0
CUPS-Get-Printers 2 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:08:35 -0400] [Client 26] Returning IPP successful-ok
for CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 26] Content-Length: 421 D [16/Oct/2018:10:08:35 -0400]
[Client 26] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 26]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 26]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=421,
response=0x55c52aab5450(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 26] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 26] bytes=0, http_state=0,
data_remaining=421 D [16/Oct/2018:10:08:35 -0400] [Client 26] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 26] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 26] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 26] HTTP_STATE_WAITING Closing for error 32 (Broken
pipe) D [16/Oct/2018:10:08:35 -0400] [Client 26] Closing connection. D
[16/Oct/2018:10:08:35 -0400] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy" D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Active clients", busy="Not busy" D [16/Oct/2018:10:08:35
-0400] [Client 27] Server address is "/run/cups/cups.sock". D
[16/Oct/2018:10:08:35 -0400] [Client 27] Accepted from localhost
(Domain) D [16/Oct/2018:10:08:35 -0400] [Client 27] Waiting for
request. D [16/Oct/2018:10:08:35 -0400] [Client 27] POST / HTTP/1.1 D
[16/Oct/2018:10:08:35 -0400] cupsdSetBusyState: newbusy="Active
clients", busy="Active clients" D [16/Oct/2018:10:08:35 -0400] [Client
27] Read: status=200, state=6 D [16/Oct/2018:10:08:35 -0400] [Client
27] No authentication data provided. D [16/Oct/2018:10:08:35 -0400]
[Client 27] 2.0 CUPS-Get-Printers 1 D [16/Oct/2018:10:08:35 -0400]
CUPS-Get-Printers D [16/Oct/2018:10:08:35 -0400] [Client 27] Returning
IPP successful-ok for CUPS-Get-Printers (no URI) from localhost D
[16/Oct/2018:10:08:35 -0400] [Client 27] Content-Length: 407 D
[16/Oct/2018:10:08:35 -0400] [Client 27] cupsdSendHeader: code=200,
type="application/ipp", auth_type=0 D [16/Oct/2018:10:08:35 -0400]
[Client 27] con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400]
[Client 27] cupsdWriteClient error=0, used=0,
state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=407, response=0x55c52aab3550(IPP_STATE_DATA),
pipe_pid=0, file=-1 D [16/Oct/2018:10:08:35 -0400] [Client 27] Writing
IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 27] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:08:35 -0400] [Client 27] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 27] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 27] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 27] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 27] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 27] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 27] 2.0
CUPS-Get-Printers 2 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:08:35 -0400] [Client 27] Returning IPP successful-ok
for CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 27] Content-Length: 407 D [16/Oct/2018:10:08:35 -0400]
[Client 27] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aab5450(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 27] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 27] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:08:35 -0400] [Client 27] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 27] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 27] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 27] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 27] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 27] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 27] 2.0
CUPS-Get-Printers 3 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:08:35 -0400] [Client 27] Returning IPP successful-ok
for CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 27] Content-Length: 407 D [16/Oct/2018:10:08:35 -0400]
[Client 27] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aaa8b20(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 27] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 27] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:08:35 -0400] [Client 27] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 27] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 27] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 27] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 27] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 27] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 27] 2.0
CUPS-Get-Printers 4 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:08:35 -0400] [Client 27] Returning IPP successful-ok
for CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 27] Content-Length: 407 D [16/Oct/2018:10:08:35 -0400]
[Client 27] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aab5450(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 27] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 27] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:08:35 -0400] [Client 27] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 27] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 27] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" D [16/Oct/2018:10:08:35
-0400] [Client 27] POST / HTTP/1.1 D [16/Oct/2018:10:08:35 -0400]
cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D
[16/Oct/2018:10:08:35 -0400] [Client 27] Read: status=200, state=6 D
[16/Oct/2018:10:08:35 -0400] [Client 27] No authentication data
provided. D [16/Oct/2018:10:08:35 -0400] [Client 27] 2.0
CUPS-Get-Printers 5 D [16/Oct/2018:10:08:35 -0400] CUPS-Get-Printers D
[16/Oct/2018:10:08:35 -0400] [Client 27] Returning IPP successful-ok
for CUPS-Get-Printers (no URI) from localhost D [16/Oct/2018:10:08:35
-0400] [Client 27] Content-Length: 407 D [16/Oct/2018:10:08:35 -0400]
[Client 27] cupsdSendHeader: code=200, type="application/ipp",
auth_type=0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
con->http=0x55c52aa8bab0 D [16/Oct/2018:10:08:35 -0400] [Client 27]
cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND,
data_encoding=HTTP_ENCODING_LENGTH, data_remaining=407,
response=0x55c52aaa8b20(IPP_STATE_DATA), pipe_pid=0, file=-1 D
[16/Oct/2018:10:08:35 -0400] [Client 27] Writing IPP response,
ipp_state=IPP_STATE_DATA, old wused=0, new wused=0 D
[16/Oct/2018:10:08:35 -0400] [Client 27] bytes=0, http_state=0,
data_remaining=407 D [16/Oct/2018:10:08:35 -0400] [Client 27] Flushing
write buffer. D [16/Oct/2018:10:08:35 -0400] [Client 27] New state is
HTTP_STATE_WAITING D [16/Oct/2018:10:08:35 -0400] [Client 27] Waiting
for request. D [16/Oct/2018:10:08:35 -0400] cupsdSetBusyState:
newbusy="Not busy", busy="Active clients" I [16/Oct/2018:10:08:36
-0400] Expiring subscriptions... D [16/Oct/2018:10:08:37 -0400] [Client
27] HTTP_STATE_WAITING Closing for error 32 (Broken pipe) D
[16/Oct/2018:10:08:37 -0400] [Client 27] Closing connection. D
[16/Oct/2018:10:08:37 -0400] cupsdSetBusyState: newbusy="Not busy",
busy="Not busy" I [16/Oct/2018:10:08:37 -0400] Expiring subscriptions...

srinivas (srinivas5) said : #15

Can you try installing the plugin and check if everything works fine?
command to install the plugin: hp-plugin

Gil Weber (bearzlos) said : #16

On Sat, 27 Oct 2018 08:37:57 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Can you try installing the plugin and check if everything works fine?
> command to install the plugin: hp-plugin
>

I installed the plug-in. It did not make any difference.

1) First print preview is messed up, second print preview is fine.
2) First printing is messed up, second printing is fine.

I ask again, would you like me to FAX you examples of what my printer
is putting out so you really understand the problem? If yes, please
give me a fax number. (Assuming you are in the USA or can supply a USA
phone number.)

Thanks.
Gil Weber

srinivas (srinivas5) said : #17

Thanks for the updates.
So the second print onward you don't see any issue? If you send more prints, does it print properly?
We don't have a USA phone number to receive fax.

Gil Weber (bearzlos) said : #18

On Mon, 29 Oct 2018 07:32:53 -0000
srinivas <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Answered
>
> srinivas proposed the following answer:
> Thanks for the updates.
> So the second print onward you don't see any issue? If you send more
> prints, does it print properly? We don't have a USA phone number to
> receive fax.
>

No. Problem persists from e-mail to e-mail.

First printing of e-mail displays problems.
Second printing of **same** e-mail looks fine.

Then immediately switch to another e-mail.

First printing of the second e-mail displays problems.
Second printing of second e-mail looks fine.

Gil Weber

Gil Weber (bearzlos) said : #19

Hi. Still hoping you have an answer for this printing problem with
e-mails using Mint 19 and Claws-Mail.

Please advise.
Thanks.
Gil Weber

On Mon, 29 Oct 2018 11:19:14 -0400
Gil Weber <email address hidden> wrote:

> On Mon, 29 Oct 2018 07:32:53 -0000
> srinivas <email address hidden> wrote:
>
> > Your question #674679 on HPLIP changed:
> > https://answers.launchpad.net/hplip/+question/674679
> >
> > Status: Open => Answered
> >
> > srinivas proposed the following answer:
> > Thanks for the updates.
> > So the second print onward you don't see any issue? If you send more
> > prints, does it print properly? We don't have a USA phone number to
> > receive fax.
> >
>
> No. Problem persists from e-mail to e-mail.
>
> First printing of e-mail displays problems.
> Second printing of **same** e-mail looks fine.
>
> Then immediately switch to another e-mail.
>
> First printing of the second e-mail displays problems.
> Second printing of second e-mail looks fine.
>
> Gil Weber

Launchpad Janitor (janitor) said : #20

This question was expired because it remained in the 'Open' state without activity for the last 15 days.

Gil Weber (bearzlos) said : #21

Please note that this question should NOT have expired. I replied on
November 12th but have not received a reply. Reply to:
srinivas <email address hidden>

Pleases reopen and help me with a solution.
Thank you.
Gil Weber

On Wed, 28 Nov 2018 09:14:39 -0000
Launchpad Janitor <email address hidden> wrote:

> Your question #674679 on HPLIP changed:
> https://answers.launchpad.net/hplip/+question/674679
>
> Status: Open => Expired
>
> Launchpad Janitor expired the question:
> This question was expired because it remained in the 'Open' state
> without activity for the last 15 days.
>

Can you help with this problem?

Provide an answer of your own, or ask Gil Weber for more information if necessary.

To post a message you must log in.