Ticket #171: cups-error_log.txt

File cups-error_log.txt, 155.5 KB (added by Anchieri, 7 years ago)
Line 
1I [01/Sep/2017:18:04:28 -0100] Listening to 0.0.0.0:631 (IPv4)
2d [01/Sep/2017:18:04:28 -0100] cupsdAddLocation: Added location "/"
3d [01/Sep/2017:18:04:28 -0100] cupsdAddIPMask(masks=0x200356e4(0), address=0:0:0:0, netmask=0:0:0:0)
4d [01/Sep/2017:18:04:28 -0100] cupsdAddLocation: Added location "/admin"
5d [01/Sep/2017:18:04:28 -0100] cupsdAddIPMask(masks=0x20036184(0), address=0:0:0:0, netmask=0:0:0:0)
6d [01/Sep/2017:18:04:28 -0100] cupsdAddLocation: Added location "/admin/conf"
7d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20036320, name="@SYSTEM")
8d [01/Sep/2017:18:04:28 -0100] cupsdAddIPMask(masks=0x20036344(0), address=0:0:0:0, netmask=0:0:0:0)
9d [01/Sep/2017:18:04:28 -0100] cupsdAddLocation: Added location "/admin/log"
10d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20036620, name="@SYSTEM")
11d [01/Sep/2017:18:04:28 -0100] cupsdAddIPMask(masks=0x20036644(0), address=0:0:0:0, netmask=0:0:0:0)
12d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0, op=5(Create-Job))
13d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044940, op=2(Print-Job))
14d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044940, op=3(Print-URI))
15d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044940, op=4(Validate-Job))
16d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0, op=6(Send-Document))
17d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20044aa0, name="@OWNER")
18d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20044aa0, name="@SYSTEM")
19d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=7(Send-URI))
20d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=c(Hold-Job))
21d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=d(Release-Job))
22d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=e(Restart-Job))
23d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=12(Purge-Jobs))
24d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=14(Set-Job-Attributes))
25d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=17(Create-Job-Subscriptions))
26d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=1a(Renew-Subscription))
27d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=1b(Cancel-Subscription))
28d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=1c(Get-Notifications))
29d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=2c(Reprocess-Job))
30d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=2d(Cancel-Current-Job))
31d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=2e(Suspend-Current-Job))
32d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=2f(Resume-Job))
33d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=39(Cancel-My-Jobs))
34d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=3b(Close-Job))
35d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=400d(CUPS-Move-Job))
36d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x20044aa0, op=4027(CUPS-Get-Document))
37d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0, op=4003(CUPS-Add-Modify-Printer))
38d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x200460c0, name="@SYSTEM")
39d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200460c0, op=4004(CUPS-Delete-Printer))
40d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200460c0, op=4006(CUPS-Add-Modify-Class))
41d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200460c0, op=4007(CUPS-Delete-Class))
42d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200460c0, op=400a(CUPS-Set-Default))
43d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200460c0, op=400b(CUPS-Get-Devices))
44d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0, op=10(Pause-Printer))
45d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x200467e0, name="@SYSTEM")
46d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=11(Resume-Printer))
47d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=22(Enable-Printer))
48d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=23(Disable-Printer))
49d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=24(Pause-Printer-After-Current-Job))
50d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=25(Hold-New-Jobs))
51d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=26(Release-Held-New-Jobs))
52d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=27(Deactivate-Printer))
53d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=28(Activate-Printer))
54d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=29(Restart-Printer))
55d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=2a(Shutdown-Printer))
56d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=2b(Startup-Printer))
57d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=30(Promote-Job))
58d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=31(Schedule-Job-After))
59d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=38(Cancel-Jobs))
60d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=4008(CUPS-Accept-Jobs))
61d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0x200467e0, op=4009(CUPS-Reject-Jobs))
62d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0, op=400e(CUPS-Authenticate-Job))
63d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20047c00, name="@OWNER")
64d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20047c00, name="@SYSTEM")
65d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20043fa0, po=0, op=0(0x0000))
66d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=4(Validate-Job))
67d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
68d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=38(Cancel-Jobs))
69d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
70d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=39(Cancel-My-Jobs))
71d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
72d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=3b(Close-Job))
73d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
74d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=4027(CUPS-Get-Document))
75d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
76d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0, op=5(Create-Job))
77d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048700, op=2(Print-Job))
78d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048700, op=3(Print-URI))
79d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048700, op=4(Validate-Job))
80d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0, op=6(Send-Document))
81d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20048860, name="@OWNER")
82d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20048860, name="@SYSTEM")
83d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=7(Send-URI))
84d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=c(Hold-Job))
85d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=d(Release-Job))
86d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=e(Restart-Job))
87d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=12(Purge-Jobs))
88d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=14(Set-Job-Attributes))
89d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=17(Create-Job-Subscriptions))
90d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=1a(Renew-Subscription))
91d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=1b(Cancel-Subscription))
92d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=1c(Get-Notifications))
93d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=2c(Reprocess-Job))
94d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=2d(Cancel-Current-Job))
95d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=2e(Suspend-Current-Job))
96d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=2f(Resume-Job))
97d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=39(Cancel-My-Jobs))
98d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=3b(Close-Job))
99d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=400d(CUPS-Move-Job))
100d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20048860, op=4027(CUPS-Get-Document))
101d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0, op=4003(CUPS-Add-Modify-Printer))
102d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x20049e80, name="@SYSTEM")
103d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20049e80, op=4004(CUPS-Delete-Printer))
104d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20049e80, op=4006(CUPS-Add-Modify-Class))
105d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20049e80, op=4007(CUPS-Delete-Class))
106d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x20049e80, op=400a(CUPS-Set-Default))
107d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0, op=10(Pause-Printer))
108d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x2004a480, name="@SYSTEM")
109d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=11(Resume-Printer))
110d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=22(Enable-Printer))
111d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=23(Disable-Printer))
112d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=24(Pause-Printer-After-Current-Job))
113d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=25(Hold-New-Jobs))
114d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=26(Release-Held-New-Jobs))
115d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=27(Deactivate-Printer))
116d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=28(Activate-Printer))
117d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=29(Restart-Printer))
118d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=2a(Shutdown-Printer))
119d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=2b(Startup-Printer))
120d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=30(Promote-Job))
121d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=31(Schedule-Job-After))
122d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=38(Cancel-Jobs))
123d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=4008(CUPS-Accept-Jobs))
124d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004a480, op=4009(CUPS-Reject-Jobs))
125d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0, op=8(Cancel-Job))
126d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x2004b8a0, name="@OWNER")
127d [01/Sep/2017:18:04:28 -0100] cupsdAddName(loc=0x2004b8a0, name="@SYSTEM")
128d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0x2004b8a0, op=400e(CUPS-Authenticate-Job))
129d [01/Sep/2017:18:04:28 -0100] cupsdAddPolicyOp(p=0x20047dc0, po=0, op=0(0x0000))
130d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20047dc0, op=4(Validate-Job))
131d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
132d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20047dc0, op=38(Cancel-Jobs))
133d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
134d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20047dc0, op=39(Cancel-My-Jobs))
135d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
136d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20047dc0, op=3b(Close-Job))
137d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
138d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20047dc0, op=4027(CUPS-Get-Document))
139d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
140I [01/Sep/2017:18:04:28 -0100] Remote access is enabled.
141D [01/Sep/2017:18:04:28 -0100] Added auto ServerAlias ECSGIULIO
142I [01/Sep/2017:18:04:28 -0100] Loaded configuration file "/@unixroot/etc/cups/cupsd.conf"
143d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_CACHEDIR=/@unixroot/var/cache/cups
144d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_DATADIR=/@unixroot/usr/share/cups
145d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_DOCROOT=/@unixroot/usr/share/doc/cups
146d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts
147d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_REQUESTROOT=/@unixroot/var/spool/cups
148d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_SERVERBIN=/@unixroot/usr/lib/cups
149d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_SERVERROOT=/@unixroot/etc/cups
150d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_STATEDIR=/@unixroot/var/run/cups
151d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: HOME=/@unixroot/var/spool/cups/tmp
152d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;
153d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin
154d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: SERVER_ADMIN=root@ECSGIULIO
155d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: SOFTWARE=CUPS/2.1.3
156d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: TMPDIR=/@unixroot/var/spool/cups/tmp
157d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: TZ=CET-1CES,3,-1,0,7200,10,-1,0,3600,3600
158d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: USER=root
159d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: UNIXROOT=N:
160d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_MAX_MESSAGE=2047
161I [01/Sep/2017:18:04:28 -0100] Configured for up to 100 clients.
162I [01/Sep/2017:18:04:28 -0100] Allowing up to 100 client connections per host.
163I [01/Sep/2017:18:04:28 -0100] Using policy "default" as the default.
164d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: NumPolicies=2
165d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: Policies[0]="authenticated"
166d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: Policies[1]="default"
167I [01/Sep/2017:18:04:28 -0100] Full reload is required.
168I [01/Sep/2017:18:04:28 -0100] Loaded MIME database from "/@unixroot/usr/share/cups/mime" and "/@unixroot/etc/cups": 44 types, 53 filters...
169d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/g-code
170d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/octet-stream
171d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/pdf
172d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/postscript
173d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/rss+xml
174d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/sla
175d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.adobe-reader-postscript
176d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-banner
177d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-command
178d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-pdf
179d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-pdf-banner
180d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-postscript
181d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-ppd
182d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-raster
183d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.cups-raw
184d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/vnd.makerbot-s3g
185d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/x-cshell
186d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/x-csource
187d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/x-perl
188d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type application/x-shell
189d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/gif
190d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/jpeg
191d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/png
192d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/pwg-raster
193d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/tiff
194d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/urf
195d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-alias
196d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-bitmap
197d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-icon
198d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-photocd
199d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-portable-anymap
200d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-portable-bitmap
201d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-portable-graymap
202d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-portable-pixmap
203d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-sgi-rgb
204d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-sun-raster
205d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-xbitmap
206d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-xpixmap
207d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type image/x-xwindowdump
208d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type model/amf
209d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type model/vnd.collada+xml
210d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type text/css
211d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type text/html
212d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: type text/plain
213d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/octet-stream to application/vnd.cups-raw 0 -
214d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/pdf to application/vnd.cups-pdf 66 pdftopdf.exe
215d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/postscript to application/pdf 0 gstopdf
216d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/postscript to application/vnd.cups-postscript 66 pstops
217d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/vnd.adobe-reader-postscript to application/vnd.cups-postscript 66 pstops.exe
218d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/vnd.cups-pdf to application/vnd.cups-postscript 100 pdftops.exe
219d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/vnd.cups-pdf to application/vnd.cups-raster 99 gstoraster.exe
220d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/vnd.cups-pdf to image/pwg-raster 99 gstoraster
221d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/vnd.cups-pdf-banner to application/pdf 32 bannertopdf.exe
222d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/vnd.cups-postscript to application/vnd.cups-raster 175 gstoraster
223d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/vnd.cups-raster to image/pwg-raster 100 rastertopwg.exe
224d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-cshell to application/pdf 32 texttopdf.exe
225d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-cshell to text/plain 100 -
226d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-csource to application/pdf 32 texttopdf
227d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-csource to text/plain 100 -
228d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-perl to application/pdf 32 texttopdf
229d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-perl to text/plain 100 -
230d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-shell to application/pdf 32 texttopdf
231d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter application/x-shell to text/plain 100 -
232d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/gif to application/vnd.cups-pdf 65 imagetopdf.exe
233d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/gif to application/vnd.cups-raster 100 imagetoraster.exe
234d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-pdf 65 imagetopdf
235d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/jpeg to application/vnd.cups-raster 100 imagetoraster
236d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/png to application/vnd.cups-pdf 65 imagetopdf
237d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/png to application/vnd.cups-raster 100 imagetoraster
238d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/pwg-raster to application/pdf 32 rastertopdf.exe
239d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-pdf 65 imagetopdf
240d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/tiff to application/vnd.cups-raster 100 imagetoraster
241d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-pdf 65 imagetopdf
242d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-bitmap to application/vnd.cups-raster 100 imagetoraster
243d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-pdf 65 imagetopdf
244d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-photocd to application/vnd.cups-raster 100 imagetoraster
245d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-pdf 65 imagetopdf
246d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-anymap to application/vnd.cups-raster 100 imagetoraster
247d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-pdf 65 imagetopdf
248d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-bitmap to application/vnd.cups-raster 100 imagetoraster
249d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-pdf 65 imagetopdf
250d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-graymap to application/vnd.cups-raster 100 imagetoraster
251d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-pdf 65 imagetopdf
252d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-portable-pixmap to application/vnd.cups-raster 100 imagetoraster
253d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-pdf 65 imagetopdf
254d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-sgi-rgb to application/vnd.cups-raster 100 imagetoraster
255d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-pdf 65 imagetopdf
256d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-sun-raster to application/vnd.cups-raster 100 imagetoraster
257d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-pdf 65 imagetopdf
258d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-xbitmap to application/vnd.cups-raster 100 imagetoraster
259d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-pdf 65 imagetopdf
260d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-xpixmap to application/vnd.cups-raster 100 imagetoraster
261d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter image/x-xwindowdump to application/vnd.cups-pdf 65 imagetopdf
262d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter text/css to text/plain 100 -
263d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter text/html to application/pdf 32 texttopdf
264d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter text/html to text/plain 100 -
265d [01/Sep/2017:18:04:28 -0100] cupsdReadConfiguration: filter text/plain to application/pdf 32 texttopdf
266D [01/Sep/2017:18:04:28 -0100] Loading printer HPOFFICE...
267d [01/Sep/2017:18:04:28 -0100] cupsdAddPrinter("HPOFFICE")
268d [01/Sep/2017:18:04:28 -0100] cupsdAddPrinter: Adding HPOFFICE to Printers
269d [01/Sep/2017:18:04:28 -0100] cupsdSetPrinterReasons(p=0x2005f360(HPOFFICE),s="-cups-insecure-filter-warning,cups-missing-filter-warning"
270d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: uri="/printers/HPOFFICE", limit=10...
271d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
272d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
273d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin(6) Limit 7f
274d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /(1) Limit 7f
275d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: best=/
276d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=2(Print-Job))
277d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
278D [01/Sep/2017:18:04:28 -0100] load_ppd: Loading /@unixroot/var/cache/cups/HPOFFICE.data...
279d [01/Sep/2017:18:04:28 -0100] cupsdSetPrinterReasons(p=0x2005f360(HPOFFICE),s="-cups-missing-filter-warning,cups-insecure-filter-warning"
280d [01/Sep/2017:18:04:28 -0100] add_printer_filter(p=0x2005f360(HPOFFICE), filtertype=0x2003cee0(printer/HPOFFICE), filter="application/vnd.cups-raw application/octet-stream 0 -")
281d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPOFFICE: adding filter application/vnd.cups-raw printer/HPOFFICE/application/octet-stream 0 -
282d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPOFFICE: adding filter printer/HPOFFICE/application/octet-stream printer/HPOFFICE 0 -
283d [01/Sep/2017:18:04:28 -0100] add_printer_filter(p=0x2005f360(HPOFFICE), filtertype=0x2003cee0(printer/HPOFFICE), filter="application/vnd.cups-raster 0 hpcups")
284d [01/Sep/2017:18:04:28 -0100] HPOFFICE: File \"/@unixroot/usr/lib/cups/filter/hpcups.exe\" permessi OK (040755/uid=0/gid=0).
285d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPOFFICE: adding filter application/vnd.cups-raster printer/HPOFFICE 0 hpcups
286d [01/Sep/2017:18:04:28 -0100] add_printer_formats: 46 types, 56 filters
287d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/g-code not supported
288d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/octet-stream needs 2 filters
289d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/pdf needs 2 filters
290d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/postscript needs 3 filters
291d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/rss+xml not supported
292d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/sla not supported
293d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.adobe-reader-postscript needs 2 filters
294d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-banner not supported
295d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-command not supported
296d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-pdf needs 2 filters
297d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-pdf-banner needs 3 filters
298d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-postscript needs 1 filters
299d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-ppd not supported
300d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-raster needs 1 filters
301d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-raw needs 1 filters
302d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.makerbot-s3g not supported
303d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-cshell needs 3 filters
304d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-csource needs 3 filters
305d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-perl needs 3 filters
306d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-shell needs 3 filters
307d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/gif needs 2 filters
308d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/jpeg needs 2 filters
309d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/png needs 2 filters
310d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/pwg-raster needs 3 filters
311d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/tiff needs 2 filters
312d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/urf not supported
313d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-alias not supported
314d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-bitmap needs 2 filters
315d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-icon not supported
316d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-photocd needs 2 filters
317d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-anymap needs 2 filters
318d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-bitmap needs 2 filters
319d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-graymap needs 2 filters
320d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-pixmap needs 2 filters
321d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-sgi-rgb needs 2 filters
322d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-sun-raster needs 2 filters
323d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-xbitmap needs 2 filters
324d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-xpixmap needs 2 filters
325d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-xwindowdump needs 2 filters
326d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: model/amf not supported
327d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: model/vnd.collada+xml not supported
328d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: text/css needs 4 filters
329d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: text/html needs 3 filters
330d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: text/plain needs 3 filters
331d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: 32 supported types
332D [01/Sep/2017:18:04:28 -0100] cupsdRegisterPrinter(p=0x2005f360(HPOFFICE))
333d [01/Sep/2017:18:04:28 -0100] cupsdSetPrinterReasons(p=0x2005f360(HPOFFICE),s="-cups-insecure-filter-warning,cups-missing-filter-warning"
334d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: uri="/printers/HPOFFICE", limit=10...
335d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
336d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
337d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin(6) Limit 7f
338d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /(1) Limit 7f
339d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: best=/
340d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=2(Print-Job))
341d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
342D [01/Sep/2017:18:04:28 -0100] load_ppd: Loading /@unixroot/var/cache/cups/HPOFFICE.data...
343d [01/Sep/2017:18:04:28 -0100] cupsdSetPrinterReasons(p=0x2005f360(HPOFFICE),s="-cups-missing-filter-warning,cups-insecure-filter-warning"
344d [01/Sep/2017:18:04:28 -0100] add_printer_filter(p=0x2005f360(HPOFFICE), filtertype=0x2003cee0(printer/HPOFFICE), filter="application/vnd.cups-raw application/octet-stream 0 -")
345d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPOFFICE: adding filter application/vnd.cups-raw printer/HPOFFICE/application/octet-stream 0 -
346d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPOFFICE: adding filter printer/HPOFFICE/application/octet-stream printer/HPOFFICE 0 -
347d [01/Sep/2017:18:04:28 -0100] add_printer_filter(p=0x2005f360(HPOFFICE), filtertype=0x2003cee0(printer/HPOFFICE), filter="application/vnd.cups-raster 0 hpcups")
348d [01/Sep/2017:18:04:28 -0100] HPOFFICE: File \"/@unixroot/usr/lib/cups/filter/hpcups.exe\" permessi OK (040755/uid=0/gid=0).
349d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPOFFICE: adding filter application/vnd.cups-raster printer/HPOFFICE 0 hpcups
350d [01/Sep/2017:18:04:28 -0100] add_printer_formats: 46 types, 56 filters
351d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/g-code not supported
352d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/octet-stream needs 2 filters
353d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/pdf needs 2 filters
354d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/postscript needs 3 filters
355d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/rss+xml not supported
356d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/sla not supported
357d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.adobe-reader-postscript needs 2 filters
358d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-banner not supported
359d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-command not supported
360d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-pdf needs 2 filters
361d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-pdf-banner needs 3 filters
362d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-postscript needs 1 filters
363d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-ppd not supported
364d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-raster needs 1 filters
365d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.cups-raw needs 1 filters
366d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/vnd.makerbot-s3g not supported
367d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-cshell needs 3 filters
368d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-csource needs 3 filters
369d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-perl needs 3 filters
370d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: application/x-shell needs 3 filters
371d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/gif needs 2 filters
372d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/jpeg needs 2 filters
373d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/png needs 2 filters
374d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/pwg-raster needs 3 filters
375d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/tiff needs 2 filters
376d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/urf not supported
377d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-alias not supported
378d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-bitmap needs 2 filters
379d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-icon not supported
380d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-photocd needs 2 filters
381d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-anymap needs 2 filters
382d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-bitmap needs 2 filters
383d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-graymap needs 2 filters
384d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-portable-pixmap needs 2 filters
385d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-sgi-rgb needs 2 filters
386d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-sun-raster needs 2 filters
387d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-xbitmap needs 2 filters
388d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-xpixmap needs 2 filters
389d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: image/x-xwindowdump needs 2 filters
390d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: model/amf not supported
391d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: model/vnd.collada+xml not supported
392d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: text/css needs 4 filters
393d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: text/html needs 3 filters
394d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: text/plain needs 3 filters
395d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPOFFICE: 32 supported types
396D [01/Sep/2017:18:04:28 -0100] cupsdRegisterPrinter(p=0x2005f360(HPOFFICE))
397D [01/Sep/2017:18:04:28 -0100] Loading printer HPPSC1610...
398d [01/Sep/2017:18:04:28 -0100] cupsdAddPrinter("HPPSC1610")
399d [01/Sep/2017:18:04:28 -0100] cupsdAddPrinter: Adding HPPSC1610 to Printers
400d [01/Sep/2017:18:04:28 -0100] cupsdSetPrinterReasons(p=0x20086260(HPPSC1610),s="-cups-insecure-filter-warning,cups-missing-filter-warning"
401d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: uri="/printers/HPPSC1610", limit=10...
402d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
403d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
404d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /admin(6) Limit 7f
405d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: Location /(1) Limit 7f
406d [01/Sep/2017:18:04:28 -0100] cupsdFindBest: best=/
407d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=2(Print-Job))
408d [01/Sep/2017:18:04:28 -0100] cupsdFindPolicyOp: Found exact match...
409D [01/Sep/2017:18:04:28 -0100] load_ppd: Loading /@unixroot/var/cache/cups/HPPSC1610.data...
410d [01/Sep/2017:18:04:28 -0100] cupsdSetPrinterReasons(p=0x20086260(HPPSC1610),s="-cups-missing-filter-warning,cups-insecure-filter-warning"
411d [01/Sep/2017:18:04:28 -0100] add_printer_filter(p=0x20086260(HPPSC1610), filtertype=0x200600a0(printer/HPPSC1610), filter="application/vnd.cups-raw application/octet-stream 0 -")
412d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPPSC1610: adding filter application/vnd.cups-raw printer/HPPSC1610/application/octet-stream 0 -
413d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPPSC1610: adding filter printer/HPPSC1610/application/octet-stream printer/HPPSC1610 0 -
414d [01/Sep/2017:18:04:28 -0100] add_printer_filter(p=0x20086260(HPPSC1610), filtertype=0x200600a0(printer/HPPSC1610), filter="application/vnd.cups-raster 0 hpcups")
415d [01/Sep/2017:18:04:28 -0100] HPPSC1610: File \"/@unixroot/usr/lib/cups/filter/hpcups.exe\" permessi OK (040755/uid=0/gid=0).
416d [01/Sep/2017:18:04:28 -0100] add_printer_filter: HPPSC1610: adding filter application/vnd.cups-raster printer/HPPSC1610 0 hpcups
417d [01/Sep/2017:18:04:28 -0100] add_printer_formats: 48 types, 59 filters
418d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/g-code not supported
419d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/octet-stream needs 2 filters
420d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/pdf needs 2 filters
421d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/postscript needs 3 filters
422d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/rss+xml not supported
423d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/sla not supported
424d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.adobe-reader-postscript needs 2 filters
425d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-banner not supported
426d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-command not supported
427d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-pdf needs 2 filters
428d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-pdf-banner needs 3 filters
429d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-postscript needs 1 filters
430d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-ppd not supported
431d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-raster needs 1 filters
432d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.cups-raw needs 1 filters
433d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/vnd.makerbot-s3g not supported
434d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/x-cshell needs 3 filters
435d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/x-csource needs 3 filters
436d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/x-perl needs 3 filters
437d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: application/x-shell needs 3 filters
438d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/gif needs 2 filters
439d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/jpeg needs 2 filters
440d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/png needs 2 filters
441d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/pwg-raster needs 3 filters
442d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/tiff needs 2 filters
443d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/urf not supported
444d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-alias not supported
445d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-bitmap needs 2 filters
446d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-icon not supported
447d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-photocd needs 2 filters
448d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-portable-anymap needs 2 filters
449d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-portable-bitmap needs 2 filters
450d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-portable-graymap needs 2 filters
451d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-portable-pixmap needs 2 filters
452d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-sgi-rgb needs 2 filters
453d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-sun-raster needs 2 filters
454d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-xbitmap needs 2 filters
455d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-xpixmap needs 2 filters
456d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: image/x-xwindowdump needs 2 filters
457d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: model/amf not supported
458d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: model/vnd.collada+xml not supported
459d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: text/css needs 4 filters
460d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: text/html needs 3 filters
461d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: text/plain needs 3 filters
462d [01/Sep/2017:18:04:28 -0100] add_printer_formats: HPPSC1610: 32 supported types
463D [01/Sep/2017:18:04:28 -0100] cupsdRegisterPrinter(p=0x20086260(HPPSC1610))
464I [01/Sep/2017:18:04:28 -0100] Loading job cache file "/@unixroot/var/cache/cups/job.cache"...
465D [01/Sep/2017:18:04:28 -0100] [Job 1] Loading from cache...
466D [01/Sep/2017:18:04:28 -0100] [Job 2] Loading from cache...
467D [01/Sep/2017:18:04:28 -0100] [Job 3] Loading from cache...
468D [01/Sep/2017:18:04:28 -0100] [Job 4] Loading from cache...
469D [01/Sep/2017:18:04:28 -0100] [Job 4] Loading attributes...
470d [01/Sep/2017:18:04:28 -0100] cupsdLoadJob: JobHistoryUpdate=1503998653
471D [01/Sep/2017:18:04:28 -0100] [Job 4] Unloading...
472D [01/Sep/2017:18:04:28 -0100] [Job 5] Loading from cache...
473D [01/Sep/2017:18:04:28 -0100] [Job 6] Loading from cache...
474D [01/Sep/2017:18:04:28 -0100] [Job 7] Loading from cache...
475D [01/Sep/2017:18:04:28 -0100] [Job 8] Loading from cache...
476D [01/Sep/2017:18:04:28 -0100] [Job 9] Loading from cache...
477D [01/Sep/2017:18:04:28 -0100] [Job 10] Loading from cache...
478D [01/Sep/2017:18:04:28 -0100] [Job 11] Loading from cache...
479D [01/Sep/2017:18:04:28 -0100] [Job 12] Loading from cache...
480D [01/Sep/2017:18:04:28 -0100] [Job 13] Loading from cache...
481D [01/Sep/2017:18:04:28 -0100] [Job 14] Loading from cache...
482D [01/Sep/2017:18:04:28 -0100] [Job 15] Loading from cache...
483D [01/Sep/2017:18:04:28 -0100] [Job 16] Loading from cache...
484D [01/Sep/2017:18:04:28 -0100] [Job 16] Loading attributes...
485d [01/Sep/2017:18:04:28 -0100] cupsdLoadJob: JobHistoryUpdate=1503998653
486D [01/Sep/2017:18:04:28 -0100] [Job 16] Unloading...
487D [01/Sep/2017:18:04:28 -0100] [Job 17] Loading from cache...
488D [01/Sep/2017:18:04:28 -0100] [Job 18] Loading from cache...
489D [01/Sep/2017:18:04:28 -0100] [Job 19] Loading from cache...
490D [01/Sep/2017:18:04:28 -0100] [Job 20] Loading from cache...
491D [01/Sep/2017:18:04:28 -0100] [Job 21] Loading from cache...
492D [01/Sep/2017:18:04:28 -0100] [Job 22] Loading from cache...
493D [01/Sep/2017:18:04:28 -0100] [Job 22] Loading attributes...
494d [01/Sep/2017:18:04:28 -0100] cupsdLoadJob: JobHistoryUpdate=1503998653
495D [01/Sep/2017:18:04:28 -0100] [Job 22] Unloading...
496D [01/Sep/2017:18:04:28 -0100] [Job 23] Loading from cache...
497D [01/Sep/2017:18:04:28 -0100] [Job 24] Loading from cache...
498D [01/Sep/2017:18:04:28 -0100] [Job 25] Loading from cache...
499D [01/Sep/2017:18:04:28 -0100] [Job 26] Loading from cache...
500D [01/Sep/2017:18:04:28 -0100] [Job 27] Loading from cache...
501D [01/Sep/2017:18:04:28 -0100] [Job 28] Loading from cache...
502D [01/Sep/2017:18:04:28 -0100] [Job 29] Loading from cache...
503D [01/Sep/2017:18:04:28 -0100] [Job 30] Loading from cache...
504D [01/Sep/2017:18:04:28 -0100] [Job 31] Loading from cache...
505D [01/Sep/2017:18:04:28 -0100] [Job 32] Loading from cache...
506I [01/Sep/2017:18:04:28 -0100] Full reload complete.
507D [01/Sep/2017:18:04:28 -0100] cupsdCleanFiles(path="/@unixroot/var/spool/cups/tmp", pattern="(null)")
508I [01/Sep/2017:18:04:28 -0100] Cleaning out old files in "/@unixroot/var/spool/cups/tmp".
509D [01/Sep/2017:18:04:28 -0100] cupsdCleanFiles(path="/@unixroot/var/cache/cups", pattern="*.ipp")
510I [01/Sep/2017:18:04:28 -0100] Cleaning out old files in "/@unixroot/var/cache/cups".
511d [01/Sep/2017:18:04:28 -0100] cupsdCreateProfile(job_id=0, allow_networking=1) = NULL
512d [01/Sep/2017:18:04:28 -0100] cupsdStartListening: 1 Listeners
513I [01/Sep/2017:18:04:28 -0100] Listening to 0.0.0.0:631 on fd 6...
514d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_SERVER=localhost
515d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: CUPS_ENCRYPTION=IfRequested
516d [01/Sep/2017:18:04:28 -0100] cupsdSetEnv: IPP_PORT=631
517I [01/Sep/2017:18:04:28 -0100] Resuming new connection processing...
518d [01/Sep/2017:18:04:28 -0100] cupsdResumeListening: Setting input bits...
519d [01/Sep/2017:18:04:28 -0100] cupsdAddSelect(fd=6, read_cb=0x1691e, write_cb=0, data=0x200358c0)
520d [01/Sep/2017:18:04:28 -0100] cupsdAddSelect(fd=7, read_cb=0x1c2b7, write_cb=0, data=0)
521D [01/Sep/2017:18:04:28 -0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
522D [01/Sep/2017:18:04:28 -0100] cupsdAddCert: Adding certificate for PID 0
523d [01/Sep/2017:18:04:28 -0100] cupsdAddCert: NumSystemGroups=1
524d [01/Sep/2017:18:04:28 -0100] cupsdAddEvent(event=server-started, dest=0(), job=0(0), text="Scheduler started in foreground.", ...)
525D [01/Sep/2017:18:04:28 -0100] Discarding unused server-started event...
526d [01/Sep/2017:18:04:28 -0100] cupsdCheckJobs: 0 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1504281868
527d [01/Sep/2017:18:04:28 -0100] select_timeout: JobHistoryUpdate=1503998653
528d [01/Sep/2017:18:04:29 -0100] cupsdCleanJobs: MaxJobs=500, JobHistory=2147483647, JobFiles=86400
529D [01/Sep/2017:18:04:29 -0100] [Job 4] Removing document files.
530D [01/Sep/2017:18:04:29 -0100] cupsdMarkDirty(---J-)
531D [01/Sep/2017:18:04:29 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
532D [01/Sep/2017:18:04:29 -0100] [Job 16] Removing document files.
533D [01/Sep/2017:18:04:29 -0100] cupsdMarkDirty(---J-)
534D [01/Sep/2017:18:04:29 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
535D [01/Sep/2017:18:04:29 -0100] [Job 22] Removing document files.
536D [01/Sep/2017:18:04:29 -0100] cupsdMarkDirty(---J-)
537D [01/Sep/2017:18:04:29 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
538d [01/Sep/2017:18:04:29 -0100] cupsdCleanJobs: JobHistoryUpdate=0
539D [01/Sep/2017:18:04:29 -0100] Report: clients=0
540D [01/Sep/2017:18:04:29 -0100] Report: jobs=32
541D [01/Sep/2017:18:04:29 -0100] Report: jobs-active=0
542D [01/Sep/2017:18:04:29 -0100] Report: printers=2
543D [01/Sep/2017:18:04:29 -0100] Report: stringpool-string-count=27732
544D [01/Sep/2017:18:04:29 -0100] Report: stringpool-alloc-bytes=11208
545D [01/Sep/2017:18:04:29 -0100] Report: stringpool-total-bytes=513904
546d [01/Sep/2017:18:04:29 -0100] select_timeout: JobHistoryUpdate=0
547d [01/Sep/2017:18:04:29 -0100] select_timeout(0): 31 seconds to write dirty config/state files
548d [01/Sep/2017:18:04:35 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=0
549D [01/Sep/2017:18:04:35 -0100] [Client 1] Accepted from localhost:53873 (IPv4)
550d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=9, read_cb=0x1915c, write_cb=0, data=0x202765a0)
551D [01/Sep/2017:18:04:35 -0100] [Client 1] Waiting for request.
552d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
553d [01/Sep/2017:18:04:35 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=1
554D [01/Sep/2017:18:04:35 -0100] [Client 2] Accepted from localhost:53874 (IPv4)
555d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=10, read_cb=0x1915c, write_cb=0, data=0x2005d400)
556D [01/Sep/2017:18:04:35 -0100] [Client 2] Waiting for request.
557d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
558d [01/Sep/2017:18:04:35 -0100] [Client 2] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
559D [01/Sep/2017:18:04:35 -0100] [Client 2] POST /printers/HPPSC1610 HTTP/1.1
560D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
561D [01/Sep/2017:18:04:35 -0100] [Client 2] Read: status=200
562d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: uri="/printers/HPPSC1610", limit=10...
563d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
564d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
565d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin(6) Limit 7f
566d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /(1) Limit 7f
567d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: best=/
568d [01/Sep/2017:18:04:35 -0100] [Client 2] con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
569D [01/Sep/2017:18:04:35 -0100] [Client 2] No authentication data provided.
570d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
571d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
572d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
573d [01/Sep/2017:18:04:35 -0100] [Client 2] pipe_command: infile=11, outfile=0x2005da3c, command="/@unixroot/usr/lib/cups/cgi-bin/printers.cgi", options="/HPPSC1610", root=0
574D [01/Sep/2017:18:04:35 -0100] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
575D [01/Sep/2017:18:04:35 -0100] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
576D [01/Sep/2017:18:04:35 -0100] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
577D [01/Sep/2017:18:04:35 -0100] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
578D [01/Sep/2017:18:04:35 -0100] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
579D [01/Sep/2017:18:04:35 -0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
580D [01/Sep/2017:18:04:35 -0100] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
581D [01/Sep/2017:18:04:35 -0100] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
582D [01/Sep/2017:18:04:35 -0100] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
583D [01/Sep/2017:18:04:35 -0100] [CGI] envp[8] = "HOME=/@unixroot/var/spool/cups/tmp"
584D [01/Sep/2017:18:04:35 -0100] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
585D [01/Sep/2017:18:04:35 -0100] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
586D [01/Sep/2017:18:04:35 -0100] [CGI] envp[11] = "SERVER_ADMIN=root@ECSGIULIO"
587D [01/Sep/2017:18:04:35 -0100] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
588D [01/Sep/2017:18:04:35 -0100] [CGI] envp[13] = "TMPDIR=/@unixroot/var/spool/cups/tmp"
589D [01/Sep/2017:18:04:35 -0100] [CGI] envp[14] = "TZ=CET-1CES,3,-1,0,7200,10,-1,0,3600,3600"
590D [01/Sep/2017:18:04:35 -0100] [CGI] envp[15] = "USER=root"
591D [01/Sep/2017:18:04:35 -0100] [CGI] envp[16] = "UNIXROOT=N:"
592D [01/Sep/2017:18:04:35 -0100] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
593D [01/Sep/2017:18:04:35 -0100] [CGI] envp[18] = "CUPS_SERVER=localhost"
594D [01/Sep/2017:18:04:35 -0100] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
595D [01/Sep/2017:18:04:35 -0100] [CGI] envp[20] = "IPP_PORT=631"
596D [01/Sep/2017:18:04:35 -0100] [CGI] envp[21] = "LANG=en_US.UTF8"
597D [01/Sep/2017:18:04:35 -0100] [CGI] envp[22] = "REDIRECT_STATUS=1"
598D [01/Sep/2017:18:04:35 -0100] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
599D [01/Sep/2017:18:04:35 -0100] [CGI] envp[24] = "SERVER_NAME=localhost"
600D [01/Sep/2017:18:04:35 -0100] [CGI] envp[25] = "SERVER_PORT=631"
601D [01/Sep/2017:18:04:35 -0100] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
602D [01/Sep/2017:18:04:35 -0100] [CGI] envp[27] = "REMOTE_HOST=localhost"
603D [01/Sep/2017:18:04:35 -0100] [CGI] envp[28] = "SCRIPT_NAME=/printers/HPPSC1610"
604D [01/Sep/2017:18:04:35 -0100] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HPPSC1610"
605D [01/Sep/2017:18:04:35 -0100] [CGI] envp[30] = "PATH_INFO=/HPPSC1610"
606D [01/Sep/2017:18:04:35 -0100] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
607D [01/Sep/2017:18:04:35 -0100] [CGI] envp[32] = "HTTP_COOKIE=org.cups.sid=3a182577254f961f9d2266e4b01b552a"
608D [01/Sep/2017:18:04:35 -0100] [CGI] envp[33] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
609D [01/Sep/2017:18:04:35 -0100] [CGI] envp[34] = "HTTP_REFERER=http://localhost:631/printers/HPPSC1610?which_jobs=all"
610D [01/Sep/2017:18:04:35 -0100] [CGI] envp[35] = "REQUEST_METHOD=POST"
611D [01/Sep/2017:18:04:35 -0100] [CGI] envp[36] = "CONTENT_LENGTH=60"
612D [01/Sep/2017:18:04:35 -0100] [CGI] envp[37] = "CONTENT_TYPE=application/x-www-form-urlencoded"
613d [01/Sep/2017:18:04:35 -0100] File \"/@unixroot/usr/lib/cups/cgi-bin/printers.cgi\" permessi OK (040755/uid=0/gid=0).
614d [01/Sep/2017:18:04:35 -0100] cupsdStartProcess: Preparing to start "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi", arguments:
615d [01/Sep/2017:18:04:35 -0100] cupsdStartProcess: argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
616d [01/Sep/2017:18:04:35 -0100] cupsdStartProcess(command="/@unixroot/usr/lib/cups/cgi-bin/printers.cgi", argv=0x1655a0, envp=0x165730, infd=11, outfd=13, errfd=8, backfd=-1, sidefd=-1, root=0, profile=0, job=0(0), pid=0x165494) = 332
617D [01/Sep/2017:18:04:35 -0100] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 332)
618I [01/Sep/2017:18:04:35 -0100] [Client 2] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=332, file=12)
619d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=12, read_cb=0x146e4, write_cb=0, data=0x2005d400)
620D [01/Sep/2017:18:04:35 -0100] [Client 2] Waiting for CGI data.
621d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
622d [01/Sep/2017:18:04:35 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=2
623D [01/Sep/2017:18:04:35 -0100] [Client 3] Accepted from localhost:53875 (IPv4)
624d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0, data=0x2028ca40)
625D [01/Sep/2017:18:04:35 -0100] [Client 3] Waiting for request.
626D [01/Sep/2017:18:04:35 -0100] [CGI] org.cups.sid cookie is \"3a182577254f961f9d2266e4b01b552a\"
627D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: org.cups.sid=\"3a182577254f961f9d2266e4b01b552a\"
628D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: OP=\"cancel-jobs\"
629D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: SECTION=\"printers\"
630D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
631D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: PRINTER_NAME=\"HPPSC1610\"
632d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
633d [01/Sep/2017:18:04:35 -0100] [Client 3] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
634D [01/Sep/2017:18:04:35 -0100] [Client 3] POST /printers/HPPSC1610 HTTP/1.1
635D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
636D [01/Sep/2017:18:04:35 -0100] [Client 3] Read: status=200
637d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: uri="/printers/HPPSC1610", limit=10...
638d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
639d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
640d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin(6) Limit 7f
641d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /(1) Limit 7f
642d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: best=/
643d [01/Sep/2017:18:04:35 -0100] [Client 3] con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
644D [01/Sep/2017:18:04:35 -0100] [Client 3] No authentication data provided.
645d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
646d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
647d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
648D [01/Sep/2017:18:04:35 -0100] [Client 3] 2.0 Cancel-Jobs 1
649d [01/Sep/2017:18:04:35 -0100] cupsdProcessIPPRequest(0x2028ca40[3]): operation_id = 0038
650D [01/Sep/2017:18:04:35 -0100] Cancel-Jobs ipp://localhost/printers/HPPSC1610
651d [01/Sep/2017:18:04:35 -0100] cancel_all_jobs(0x2028ca40[3], ipp://localhost/printers/HPPSC1610)
652d [01/Sep/2017:18:04:35 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=38(Cancel-Jobs))
653d [01/Sep/2017:18:04:35 -0100] cupsdFindPolicyOp: Found exact match...
654d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: con->uri="/printers/HPPSC1610", con->best=0x200478a0((null))
655d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
656d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: op=38(Cancel-Jobs)
657d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
658D [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: username=""
659D [01/Sep/2017:18:04:35 -0100] [Client 3] Returning HTTP Non autorizzato for Cancel-Jobs (ipp://localhost/printers/HPPSC1610) from localhost
660d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: uri="/printers/HPPSC1610", limit=10...
661d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
662d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
663d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin(6) Limit 7f
664d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /(1) Limit 7f
665d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: best=/
666d [01/Sep/2017:18:04:35 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=38(Cancel-Jobs))
667d [01/Sep/2017:18:04:35 -0100] cupsdFindPolicyOp: Found exact match...
668d [01/Sep/2017:18:04:35 -0100] [Client 3] cupsdSendError code=401, auth_type=1
669D [01/Sep/2017:18:04:35 -0100] [Client 3] cupsdSendHeader: code=401, type="text/html", auth_type=1
670D [01/Sep/2017:18:04:35 -0100] [Client 3] WWW-Authenticate: Basic realm=\"CUPS\", trc=\"y\"
671d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
672d [01/Sep/2017:18:04:35 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=3
673D [01/Sep/2017:18:04:35 -0100] [Client 4] Accepted from localhost:53876 (IPv4)
674d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=13, read_cb=0x1915c, write_cb=0, data=0x20290c60)
675D [01/Sep/2017:18:04:35 -0100] [Client 4] Waiting for request.
676d [01/Sep/2017:18:04:35 -0100] [Client 3] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0x2005e900(IPP_STATE_DATA), file=-1
677D [01/Sep/2017:18:04:35 -0100] [Client 3] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
678D [01/Sep/2017:18:04:35 -0100] [Client 3] Closing connection.
679D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
680d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=11)
681d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=-1)
682d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
683d [01/Sep/2017:18:04:35 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=3
684D [01/Sep/2017:18:04:35 -0100] [Client 5] Accepted from localhost:53877 (IPv4)
685d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0, data=0x2028ca40)
686D [01/Sep/2017:18:04:35 -0100] [Client 5] Waiting for request.
687d [01/Sep/2017:18:04:35 -0100] [Client 4] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
688D [01/Sep/2017:18:04:35 -0100] [Client 4] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
689D [01/Sep/2017:18:04:35 -0100] [Client 4] Closing connection.
690D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
691d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=13)
692d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=-1)
693d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
694d [01/Sep/2017:18:04:35 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=3
695D [01/Sep/2017:18:04:35 -0100] [Client 6] Accepted from localhost:53878 (IPv4)
696d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=13, read_cb=0x1915c, write_cb=0, data=0x20290c60)
697D [01/Sep/2017:18:04:35 -0100] [Client 6] Waiting for request.
698d [01/Sep/2017:18:04:35 -0100] [Client 5] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
699D [01/Sep/2017:18:04:35 -0100] [Client 5] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
700D [01/Sep/2017:18:04:35 -0100] [Client 5] Closing connection.
701D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
702d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=11)
703d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=-1)
704d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
705d [01/Sep/2017:18:04:35 -0100] [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
706D [01/Sep/2017:18:04:35 -0100] [Client 6] POST /printers/HPPSC1610 HTTP/1.1
707D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
708D [01/Sep/2017:18:04:35 -0100] [Client 6] Read: status=200
709d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: uri="/printers/HPPSC1610", limit=10...
710d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
711d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
712d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /admin(6) Limit 7f
713d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: Location /(1) Limit 7f
714d [01/Sep/2017:18:04:35 -0100] cupsdFindBest: best=/
715d [01/Sep/2017:18:04:35 -0100] [Client 6] con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
716d [01/Sep/2017:18:04:35 -0100] cupsdFindCert(certificate=E20107173395C6084DB98874DEC804BC)
717d [01/Sep/2017:18:04:35 -0100] cupsdFindCert: Returning "root".
718D [01/Sep/2017:18:04:35 -0100] [Client 6] Authorized as root using Local.
719d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
720d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
721d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
722D [01/Sep/2017:18:04:35 -0100] [Client 6] 2.0 Cancel-Jobs 1
723d [01/Sep/2017:18:04:35 -0100] cupsdProcessIPPRequest(0x20290c60[6]): operation_id = 0038
724D [01/Sep/2017:18:04:35 -0100] Cancel-Jobs ipp://localhost/printers/HPPSC1610
725d [01/Sep/2017:18:04:35 -0100] cancel_all_jobs(0x20290c60[6], ipp://localhost/printers/HPPSC1610)
726d [01/Sep/2017:18:04:35 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=38(Cancel-Jobs))
727d [01/Sep/2017:18:04:35 -0100] cupsdFindPolicyOp: Found exact match...
728d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: con->uri="/printers/HPPSC1610", con->best=0x200478a0((null))
729d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=1
730d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: op=38(Cancel-Jobs)
731d [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
732D [01/Sep/2017:18:04:35 -0100] cupsdIsAuthorized: username="root"
733I [01/Sep/2017:18:04:35 -0100] All jobs on "HPPSC1610" were canceled by "root".
734d [01/Sep/2017:18:04:35 -0100] cupsdCheckJobs: 0 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1504281875
735D [01/Sep/2017:18:04:35 -0100] [Client 6] Returning IPP successful-ok for Cancel-Jobs (ipp://localhost/printers/HPPSC1610) from localhost
736D [01/Sep/2017:18:04:35 -0100] [Client 6] Content-Length: 75
737D [01/Sep/2017:18:04:35 -0100] [Client 6] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
738d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=13, read_cb=0x1915c, write_cb=0x18138, data=0x20290c60)
739d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
740D [01/Sep/2017:18:04:35 -0100] [Client 6] con->http=0x20291de0
741D [01/Sep/2017:18:04:35 -0100] [Client 6] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x2005ede0(IPP_STATE_DATA), pipe_pid=0, file=-1
742D [01/Sep/2017:18:04:35 -0100] [Client 6] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
743D [01/Sep/2017:18:04:35 -0100] [Client 6] bytes=0, http_state=0, data_remaining=75
744D [01/Sep/2017:18:04:35 -0100] [Client 6] Flushing write buffer.
745D [01/Sep/2017:18:04:35 -0100] [Client 6] New state is HTTP_STATE_WAITING
746d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=13, read_cb=0x1915c, write_cb=0, data=0x20290c60)
747D [01/Sep/2017:18:04:35 -0100] [Client 6] Waiting for request.
748D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
749d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
750D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: refresh_page=\"5;URL=/printers/HPPSC1610\"
751D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: TITLE=\"Cancel Jobs\"
752D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
753D [01/Sep/2017:18:04:35 -0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
754d [01/Sep/2017:18:04:35 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"header.tmpl\")
755d [01/Sep/2017:18:04:35 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
756d [01/Sep/2017:18:04:35 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/header.tmpl\"...
757d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 0...
758d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{refresh_page?\" at 294, result=1...
759d [01/Sep/2017:18:04:35 -0100] [CGI] Output first part...
760d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 294...
761d [01/Sep/2017:18:04:35 -0100] [CGI] \"{refresh_page}\" at 344...
762d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 347 on character \':\'...
763d [01/Sep/2017:18:04:35 -0100] [CGI] Skip second part...
764d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 347...
765d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 348 on character \'}\'...
766d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{refresh_page?\", out=0x18228ffc...
767d [01/Sep/2017:18:04:35 -0100] [CGI] \"{display:none;}\" at 493...
768d [01/Sep/2017:18:04:35 -0100] [CGI] Lone { at 633...
769d [01/Sep/2017:18:04:35 -0100] [CGI] Lone { at 708...
770d [01/Sep/2017:18:04:35 -0100] [CGI] Lone { at 843...
771d [01/Sep/2017:18:04:35 -0100] [CGI] Lone { at 877...
772d [01/Sep/2017:18:04:35 -0100] [CGI] \"{title}\" at 1081...
773d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{SECTION=admin\" at 1315, result=0...
774d [01/Sep/2017:18:04:35 -0100] [CGI] Skip first part...
775d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1315...
776d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1331 on character \':\'...
777d [01/Sep/2017:18:04:35 -0100] [CGI] Output second part...
778d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1331...
779d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1332 on character \'}\'...
780d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{SECTION=admin\", out=0x18228ffc...
781d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{SECTION=classes\" at 1396, result=0...
782d [01/Sep/2017:18:04:35 -0100] [CGI] Skip first part...
783d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1396...
784d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1412 on character \':\'...
785d [01/Sep/2017:18:04:35 -0100] [CGI] Output second part...
786d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1412...
787d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1413 on character \'}\'...
788d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{SECTION=classes\", out=0x18228ffc...
789d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{SECTION=help\" at 1470, result=0...
790d [01/Sep/2017:18:04:35 -0100] [CGI] Skip first part...
791d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1470...
792d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1486 on character \':\'...
793d [01/Sep/2017:18:04:35 -0100] [CGI] Output second part...
794d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1486...
795d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1487 on character \'}\'...
796d [01/Sep/2017:18:04:35 -0100] [Client 2] write_pipe: CGI output on fd 12.
797d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=12)
798d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
799D [01/Sep/2017:18:04:35 -0100] [Client 2] CGI data ready to be sent.
800d [01/Sep/2017:18:04:35 -0100] [Client 6] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
801D [01/Sep/2017:18:04:35 -0100] [Client 6] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
802D [01/Sep/2017:18:04:35 -0100] [Client 6] Closing connection.
803D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
804d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=13)
805d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=-1)
806d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
807d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{SECTION=help\", out=0x18228ffc...
808d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{SECTION=jobs\" at 1538, result=0...
809d [01/Sep/2017:18:04:35 -0100] [CGI] Skip first part...
810d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1538...
811d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1554 on character \':\'...
812d [01/Sep/2017:18:04:35 -0100] [CGI] Output second part...
813d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1554...
814d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1555 on character \'}\'...
815d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{SECTION=jobs\", out=0x18228ffc...
816d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{SECTION=printers\" at 1610, result=1...
817d [01/Sep/2017:18:04:35 -0100] [CGI] Output first part...
818d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1610...
819d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1626 on character \':\'...
820d [01/Sep/2017:18:04:35 -0100] [CGI] Skip second part...
821d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 1626...
822d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1627 on character \'}\'...
823d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{SECTION=printers\", out=0x18228ffc...
824d [01/Sep/2017:18:04:35 -0100] [CGI] \"{title}\" at 1750...
825d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 1757 on EOF...
826d [01/Sep/2017:18:04:35 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"printer-cancel-jobs.tmpl\")
827d [01/Sep/2017:18:04:35 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
828d [01/Sep/2017:18:04:35 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/printer-cancel-jobs.tmpl\"...
829d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 0...
830d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{is_class?\" at 43, result=0...
831d [01/Sep/2017:18:04:35 -0100] [CGI] Skip first part...
832d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 43...
833d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 49 on character \':\'...
834d [01/Sep/2017:18:04:35 -0100] [CGI] Output second part...
835d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 49...
836d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 57 on character \'}\'...
837d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{is_class?\", out=0x18228ffc...
838d [01/Sep/2017:18:04:35 -0100] [CGI] \"{printer_name}\" at 72...
839d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{is_class?\" at 104, result=0...
840d [01/Sep/2017:18:04:35 -0100] [CGI] Skip first part...
841d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 104...
842d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 110 on character \':\'...
843d [01/Sep/2017:18:04:35 -0100] [CGI] Output second part...
844d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 110...
845d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 118 on character \'}\'...
846d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{is_class?\", out=0x18228ffc...
847d [01/Sep/2017:18:04:35 -0100] [CGI] Starting \"{is_class?\" at 139, result=0...
848d [01/Sep/2017:18:04:35 -0100] [CGI] Skip first part...
849d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 139...
850D [01/Sep/2017:18:04:35 -0100] [Client 2] con->http=0x20288ec0
851D [01/Sep/2017:18:04:35 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=332, file=12
852d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=12, read_cb=0x146e4, write_cb=0, data=0x2005d400)
853D [01/Sep/2017:18:04:35 -0100] [Client 2] Waiting for CGI data.
854D [01/Sep/2017:18:04:35 -0100] [Client 2] Script header: Content-Type: text/html;charset=utf-8
855D [01/Sep/2017:18:04:35 -0100] [Client 2] Script header:
856D [01/Sep/2017:18:04:35 -0100] [Client 2] Sending status 200 for CGI.
857D [01/Sep/2017:18:04:35 -0100] [Client 2] cupsdSendHeader: code=200, type="(null)", auth_type=0
858d [01/Sep/2017:18:04:35 -0100] process_children()
859d [01/Sep/2017:18:04:35 -0100] cupsdFinishProcess(pid=332, name=0x17f6d0, namelen=1024, job_id=0x17f6cc(0)) = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
860D [01/Sep/2017:18:04:35 -0100] PID 332 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
861d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
862d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 147 on character \':\'...
863d [01/Sep/2017:18:04:35 -0100] [CGI] Output second part...
864d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 147...
865d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 156 on character \'}\'...
866d [01/Sep/2017:18:04:35 -0100] [CGI] Finished \"{is_class?\", out=0x18228ffc...
867d [01/Sep/2017:18:04:35 -0100] [CGI] \"{printer_name}\" at 171...
868d [01/Sep/2017:18:04:35 -0100] [CGI] \"{printer_name}\" at 187...
869d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 216 on EOF...
870d [01/Sep/2017:18:04:35 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"trailer.tmpl\")
871d [01/Sep/2017:18:04:35 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
872d [01/Sep/2017:18:04:35 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/trailer.tmpl\"...
873d [01/Sep/2017:18:04:35 -0100] [CGI] Starting at file position 0...
874d [01/Sep/2017:18:04:35 -0100] [CGI] Returning at file position 218 on EOF...
875D [01/Sep/2017:18:04:35 -0100] [Client 2] con->http=0x20288ec0
876D [01/Sep/2017:18:04:35 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=332, file=12
877d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=12, read_cb=0x146e4, write_cb=0, data=0x2005d400)
878D [01/Sep/2017:18:04:35 -0100] [Client 2] Waiting for CGI data.
879d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=10)
880d [01/Sep/2017:18:04:35 -0100] [Client 2] write_pipe: CGI output on fd 12.
881d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=12)
882d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
883D [01/Sep/2017:18:04:35 -0100] [Client 2] CGI data ready to be sent.
884d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
885D [01/Sep/2017:18:04:35 -0100] [Client 2] con->http=0x20288ec0
886D [01/Sep/2017:18:04:35 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=332, file=12
887d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=12, read_cb=0x146e4, write_cb=0, data=0x2005d400)
888D [01/Sep/2017:18:04:35 -0100] [Client 2] Waiting for CGI data.
889D [01/Sep/2017:18:04:35 -0100] [Client 2] Sending 0-length chunk.
890D [01/Sep/2017:18:04:35 -0100] [Client 2] Flushing write buffer.
891D [01/Sep/2017:18:04:35 -0100] [Client 2] New state is HTTP_STATE_WAITING
892d [01/Sep/2017:18:04:35 -0100] cupsdAddSelect(fd=10, read_cb=0x1915c, write_cb=0, data=0x2005d400)
893D [01/Sep/2017:18:04:35 -0100] [Client 2] Waiting for request.
894d [01/Sep/2017:18:04:35 -0100] cupsdRemoveSelect(fd=12)
895d [01/Sep/2017:18:04:35 -0100] cupsdEndProcess(pid=332, force=0)
896D [01/Sep/2017:18:04:35 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
897d [01/Sep/2017:18:04:35 -0100] select_timeout: JobHistoryUpdate=0
898d [01/Sep/2017:18:04:37 -0100] select_timeout: JobHistoryUpdate=0
899d [01/Sep/2017:18:04:37 -0100] select_timeout(0): 23 seconds to write dirty config/state files
900d [01/Sep/2017:18:04:40 -0100] [Client 1] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
901D [01/Sep/2017:18:04:40 -0100] [Client 1] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
902D [01/Sep/2017:18:04:40 -0100] [Client 1] Closing connection.
903D [01/Sep/2017:18:04:40 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
904d [01/Sep/2017:18:04:40 -0100] cupsdRemoveSelect(fd=9)
905d [01/Sep/2017:18:04:40 -0100] cupsdRemoveSelect(fd=-1)
906d [01/Sep/2017:18:04:40 -0100] cupsdCheckJobs: 0 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1504281880
907d [01/Sep/2017:18:04:40 -0100] select_timeout: JobHistoryUpdate=0
908d [01/Sep/2017:18:04:40 -0100] [Client 2] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
909D [01/Sep/2017:18:04:40 -0100] [Client 2] GET /printers/HPPSC1610 HTTP/1.1
910D [01/Sep/2017:18:04:40 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
911D [01/Sep/2017:18:04:40 -0100] [Client 2] Read: status=200
912d [01/Sep/2017:18:04:40 -0100] cupsdFindBest: uri="/printers/HPPSC1610", limit=2...
913d [01/Sep/2017:18:04:40 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
914d [01/Sep/2017:18:04:40 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
915d [01/Sep/2017:18:04:40 -0100] cupsdFindBest: Location /admin(6) Limit 7f
916d [01/Sep/2017:18:04:40 -0100] cupsdFindBest: Location /(1) Limit 7f
917d [01/Sep/2017:18:04:40 -0100] cupsdFindBest: best=/
918d [01/Sep/2017:18:04:40 -0100] [Client 2] con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
919D [01/Sep/2017:18:04:40 -0100] [Client 2] No authentication data provided.
920d [01/Sep/2017:18:04:40 -0100] cupsdIsAuthorized: con->uri="/printers/HPPSC1610", con->best=0x200356c0(/)
921d [01/Sep/2017:18:04:40 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
922d [01/Sep/2017:18:04:40 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
923D [01/Sep/2017:18:04:40 -0100] [Client 2] Processing GET /printers/HPPSC1610
924d [01/Sep/2017:18:04:40 -0100] [Client 2] pipe_command: infile=-1, outfile=0x2005da3c, command="/@unixroot/usr/lib/cups/cgi-bin/printers.cgi", options="/HPPSC1610", root=0
925D [01/Sep/2017:18:04:40 -0100] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
926D [01/Sep/2017:18:04:40 -0100] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
927D [01/Sep/2017:18:04:40 -0100] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
928D [01/Sep/2017:18:04:40 -0100] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
929D [01/Sep/2017:18:04:40 -0100] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
930D [01/Sep/2017:18:04:40 -0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
931D [01/Sep/2017:18:04:40 -0100] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
932D [01/Sep/2017:18:04:40 -0100] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
933D [01/Sep/2017:18:04:40 -0100] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
934D [01/Sep/2017:18:04:40 -0100] [CGI] envp[8] = "HOME=/@unixroot/var/spool/cups/tmp"
935D [01/Sep/2017:18:04:40 -0100] [CGI] envp[9] = "NLSPATH=C:\\MPTN\\MSG\\NLS\\%N;C:\\TCPIP\\msg\\ENUS850\\%N;"
936D [01/Sep/2017:18:04:40 -0100] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
937D [01/Sep/2017:18:04:40 -0100] [CGI] envp[11] = "SERVER_ADMIN=root@ECSGIULIO"
938D [01/Sep/2017:18:04:40 -0100] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
939D [01/Sep/2017:18:04:40 -0100] [CGI] envp[13] = "TMPDIR=/@unixroot/var/spool/cups/tmp"
940D [01/Sep/2017:18:04:40 -0100] [CGI] envp[14] = "TZ=CET-1CES,3,-1,0,7200,10,-1,0,3600,3600"
941D [01/Sep/2017:18:04:40 -0100] [CGI] envp[15] = "USER=root"
942D [01/Sep/2017:18:04:40 -0100] [CGI] envp[16] = "UNIXROOT=N:"
943D [01/Sep/2017:18:04:40 -0100] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
944D [01/Sep/2017:18:04:40 -0100] [CGI] envp[18] = "CUPS_SERVER=localhost"
945D [01/Sep/2017:18:04:40 -0100] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
946D [01/Sep/2017:18:04:40 -0100] [CGI] envp[20] = "IPP_PORT=631"
947D [01/Sep/2017:18:04:40 -0100] [CGI] envp[21] = "LANG=en_US.UTF8"
948D [01/Sep/2017:18:04:40 -0100] [CGI] envp[22] = "REDIRECT_STATUS=1"
949D [01/Sep/2017:18:04:40 -0100] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
950D [01/Sep/2017:18:04:40 -0100] [CGI] envp[24] = "SERVER_NAME=localhost"
951D [01/Sep/2017:18:04:40 -0100] [CGI] envp[25] = "SERVER_PORT=631"
952D [01/Sep/2017:18:04:40 -0100] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
953D [01/Sep/2017:18:04:40 -0100] [CGI] envp[27] = "REMOTE_HOST=localhost"
954D [01/Sep/2017:18:04:40 -0100] [CGI] envp[28] = "SCRIPT_NAME=/printers/HPPSC1610"
955D [01/Sep/2017:18:04:40 -0100] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HPPSC1610"
956D [01/Sep/2017:18:04:40 -0100] [CGI] envp[30] = "PATH_INFO=/HPPSC1610"
957D [01/Sep/2017:18:04:40 -0100] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
958D [01/Sep/2017:18:04:40 -0100] [CGI] envp[32] = "HTTP_COOKIE=org.cups.sid=3a182577254f961f9d2266e4b01b552a"
959D [01/Sep/2017:18:04:40 -0100] [CGI] envp[33] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
960D [01/Sep/2017:18:04:40 -0100] [CGI] envp[34] = "REQUEST_METHOD=GET"
961D [01/Sep/2017:18:04:40 -0100] [CGI] envp[35] = "QUERY_STRING="
962d [01/Sep/2017:18:04:40 -0100] File \"/@unixroot/usr/lib/cups/cgi-bin/printers.cgi\" permessi OK (040755/uid=0/gid=0).
963d [01/Sep/2017:18:04:40 -0100] cupsdStartProcess: Preparing to start "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi", arguments:
964d [01/Sep/2017:18:04:40 -0100] cupsdStartProcess: argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
965d [01/Sep/2017:18:04:41 -0100] cupsdStartProcess(command="/@unixroot/usr/lib/cups/cgi-bin/printers.cgi", argv=0x1655a0, envp=0x165730, infd=-1, outfd=11, errfd=8, backfd=-1, sidefd=-1, root=0, profile=0, job=0(0), pid=0x165494) = 334
966D [01/Sep/2017:18:04:41 -0100] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 334)
967I [01/Sep/2017:18:04:41 -0100] [Client 2] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=334, file=9)
968d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
969D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
970d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
971D [01/Sep/2017:18:04:41 -0100] [CGI] org.cups.sid cookie is \"3a182577254f961f9d2266e4b01b552a\"
972D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: SECTION=\"printers\"
973D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
974D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: PRINTER_NAME=\"HPPSC1610\"
975d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
976d [01/Sep/2017:18:04:41 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=1
977D [01/Sep/2017:18:04:41 -0100] [Client 7] Accepted from localhost:53879 (IPv4)
978d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0, data=0x202765a0)
979D [01/Sep/2017:18:04:41 -0100] [Client 7] Waiting for request.
980d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
981d [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
982D [01/Sep/2017:18:04:41 -0100] [Client 7] POST / HTTP/1.1
983D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
984D [01/Sep/2017:18:04:41 -0100] [Client 7] Read: status=200
985d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: uri="/", limit=10...
986d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
987d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
988d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin(6) Limit 7f
989d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /(1) Limit 7f
990d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: best=/
991d [01/Sep/2017:18:04:41 -0100] [Client 7] con->uri="/", con->best=0x200356c0(/)
992D [01/Sep/2017:18:04:41 -0100] [Client 7] No authentication data provided.
993d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: con->uri="/", con->best=0x200356c0(/)
994d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
995d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
996D [01/Sep/2017:18:04:41 -0100] [Client 7] 2.0 CUPS-Get-Default 1
997d [01/Sep/2017:18:04:41 -0100] cupsdProcessIPPRequest(0x202765a0[7]): operation_id = 4001
998D [01/Sep/2017:18:04:41 -0100] CUPS-Get-Default
999d [01/Sep/2017:18:04:41 -0100] get_default(0x202765a0[7])
1000d [01/Sep/2017:18:04:41 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=4001(CUPS-Get-Default))
1001d [01/Sep/2017:18:04:41 -0100] cupsdFindPolicyOp: Found wildcard match...
1002d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: con->uri="/", con->best=0x20047d80((null))
1003d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
1004d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: op=0(0x0000)
1005d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
1006d [01/Sep/2017:18:04:41 -0100] printer-uri-supported="ipp://localhost:631/printers/HPPSC1610"
1007d [01/Sep/2017:18:04:41 -0100] copy_attrs(to=0x2005ece0, from=0x200607c0, ra=0x2028ca40, group=0, quickcopy=0)
1008d [01/Sep/2017:18:04:41 -0100] copy_attrs(to=0x2005ece0, from=0x20087b80, ra=0x2028ca40, group=0, quickcopy=0)
1009d [01/Sep/2017:18:04:41 -0100] copy_attrs(to=0x2005ece0, from=0x2003ce20, ra=0x2028ca40, group=0, quickcopy=-2147483648)
1010D [01/Sep/2017:18:04:41 -0100] [Client 7] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
1011D [01/Sep/2017:18:04:41 -0100] [Client 7] Content-Length: 166
1012D [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1013d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0x18138, data=0x202765a0)
1014d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1015D [01/Sep/2017:18:04:41 -0100] [Client 7] con->http=0x20285e60
1016D [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=166, response=0x2005ece0(IPP_STATE_DATA), pipe_pid=0, file=-1
1017D [01/Sep/2017:18:04:41 -0100] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1018D [01/Sep/2017:18:04:41 -0100] [Client 7] bytes=0, http_state=0, data_remaining=166
1019D [01/Sep/2017:18:04:41 -0100] [Client 7] Flushing write buffer.
1020D [01/Sep/2017:18:04:41 -0100] [Client 7] New state is HTTP_STATE_WAITING
1021d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0, data=0x202765a0)
1022D [01/Sep/2017:18:04:41 -0100] [Client 7] Waiting for request.
1023D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1024d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1025D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: DEFAULT_NAME=\"HPPSC1610\"
1026d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1027D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: DEFAULT_URI=\"/printers/HPPSC1610\"
1028D [01/Sep/2017:18:04:41 -0100] [CGI] show_printer(http=0x2003c300, printer=\"HPPSC1610\")
1029d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1030d [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
1031D [01/Sep/2017:18:04:41 -0100] [Client 7] POST / HTTP/1.1
1032D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1033D [01/Sep/2017:18:04:41 -0100] [Client 7] Read: status=200
1034d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: uri="/", limit=10...
1035d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
1036d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
1037d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin(6) Limit 7f
1038d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /(1) Limit 7f
1039d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: best=/
1040d [01/Sep/2017:18:04:41 -0100] [Client 7] con->uri="/", con->best=0x200356c0(/)
1041D [01/Sep/2017:18:04:41 -0100] [Client 7] No authentication data provided.
1042d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: con->uri="/", con->best=0x200356c0(/)
1043d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
1044d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
1045D [01/Sep/2017:18:04:41 -0100] [Client 7] 2.0 Get-Printer-Attributes 2
1046d [01/Sep/2017:18:04:41 -0100] cupsdProcessIPPRequest(0x202765a0[7]): operation_id = 000b
1047D [01/Sep/2017:18:04:41 -0100] Get-Printer-Attributes ipp://localhost/printers/HPPSC1610
1048d [01/Sep/2017:18:04:41 -0100] get_printer_attrs(0x202765a0[7], ipp://localhost/printers/HPPSC1610)
1049d [01/Sep/2017:18:04:41 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=b(Get-Printer-Attributes))
1050d [01/Sep/2017:18:04:41 -0100] cupsdFindPolicyOp: Found wildcard match...
1051d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: con->uri="/", con->best=0x20047d80((null))
1052d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
1053d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: op=0(0x0000)
1054d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
1055d [01/Sep/2017:18:04:41 -0100] printer-uri-supported="ipp://localhost:631/printers/HPPSC1610"
1056d [01/Sep/2017:18:04:41 -0100] copy_attrs(to=0x2005ed20, from=0x200607c0, ra=0x2028cc00, group=0, quickcopy=0)
1057d [01/Sep/2017:18:04:41 -0100] copy_attrs(to=0x2005ed20, from=0x20087b80, ra=0x2028cc00, group=0, quickcopy=0)
1058d [01/Sep/2017:18:04:41 -0100] copy_attrs(to=0x2005ed20, from=0x2003ce20, ra=0x2028cc00, group=0, quickcopy=-2147483648)
1059D [01/Sep/2017:18:04:41 -0100] [Client 7] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HPPSC1610) from localhost
1060D [01/Sep/2017:18:04:41 -0100] [Client 7] Content-Length: 732
1061D [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1062d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0x18138, data=0x202765a0)
1063d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1064D [01/Sep/2017:18:04:41 -0100] [Client 7] con->http=0x20285e60
1065D [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=732, response=0x2005ed20(IPP_STATE_DATA), pipe_pid=0, file=-1
1066D [01/Sep/2017:18:04:41 -0100] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1067D [01/Sep/2017:18:04:41 -0100] [Client 7] bytes=0, http_state=0, data_remaining=732
1068D [01/Sep/2017:18:04:41 -0100] [Client 7] Flushing write buffer.
1069D [01/Sep/2017:18:04:41 -0100] [Client 7] New state is HTTP_STATE_WAITING
1070d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0, data=0x202765a0)
1071D [01/Sep/2017:18:04:41 -0100] [Client 7] Waiting for request.
1072D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1073d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1074d [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetIPPVars(response=0x20047820, filter_name=\"(null)\", filter_value=\"(null)\", prefix=\"(null)\", parent_el=0)
1075D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1076D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1077d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1078d [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetIPPObjectVars(obj=0x2003f520, prefix=\"(null)\", element=0)
1079D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1080D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1081d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1082D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
1083d [01/Sep/2017:18:04:41 -0100] [CGI] printer_is_accepting_jobs[0]=\"1\"
1084d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1085D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_is_shared[0]=\"1\"
1086d [01/Sep/2017:18:04:41 -0100] [CGI] printer_is_shared[0]=\"1\"
1087D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_state[0]=\"3\"
1088d [01/Sep/2017:18:04:41 -0100] [CGI] printer_state[0]=\"3\"
1089D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
1090D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HPPSC1610\"
1091d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1092d [01/Sep/2017:18:04:41 -0100] [CGI] printer_uri_supported[0]=\"/printers/HPPSC1610\"
1093D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_name[0]=\"HPPSC1610\"
1094d [01/Sep/2017:18:04:41 -0100] [CGI] printer_name[0]=\"HPPSC1610\"
1095D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_location[0]=\"CASA\"
1096d [01/Sep/2017:18:04:41 -0100] [CGI] printer_location[0]=\"CASA\"
1097D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_info[0]=\"HP PSC 1600 series\"
1098d [01/Sep/2017:18:04:41 -0100] [CGI] printer_info[0]=\"HP PSC 1600 series\"
1099D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
1100d [01/Sep/2017:18:04:41 -0100] [CGI] job_sheets_default[0]=\"none, none\"
1101D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: device_uri[0]=\"usb://HP/PSC%201600%20series?serial=MY517D30GML0&interface=1\"
1102d [01/Sep/2017:18:04:41 -0100] [CGI] device_uri[0]=\"usb://HP/PSC%201600%20series?serial=MY517D30GML0&interface=1\"
1103D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: color_supported[0]=\"1\"
1104d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1105d [01/Sep/2017:18:04:41 -0100] [CGI] color_supported[0]=\"1\"
1106D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP PSC 1600 Series, hpcups 3.16.11\"
1107d [01/Sep/2017:18:04:41 -0100] [CGI] printer_make_and_model[0]=\"HP PSC 1600 Series, hpcups 3.16.11\"
1108D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: media_default[0]=\"iso_a4_210x297mm\"
1109d [01/Sep/2017:18:04:41 -0100] [CGI] media_default[0]=\"iso_a4_210x297mm\"
1110D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
1111d [01/Sep/2017:18:04:41 -0100] [CGI] sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
1112D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
1113d [01/Sep/2017:18:04:41 -0100] [CGI] sides_default[0]=\"one-sided\"
1114D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
1115d [01/Sep/2017:18:04:41 -0100] [CGI] printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
1116D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
1117d [01/Sep/2017:18:04:41 -0100] [CGI] server_is_sharing_printers[0]=\"0\"
1118d [01/Sep/2017:18:04:41 -0100] [CGI] Returing 1 from cgiSetIPPVars()...
1119d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1120D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: TITLE=\"HPPSC1610\"
1121D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1122D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1123d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1124d [01/Sep/2017:18:04:41 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"header.tmpl\")
1125d [01/Sep/2017:18:04:41 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
1126d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1127d [01/Sep/2017:18:04:41 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/header.tmpl\"...
1128d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 0...
1129d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1130d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{refresh_page?\" at 294, result=0...
1131d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1132d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1133d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 294...
1134d [01/Sep/2017:18:04:41 -0100] [CGI] \"{refresh_page}\" at 344...
1135d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 347 on character \':\'...
1136d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1137d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1138d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 347...
1139d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 348 on character \'}\'...
1140d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{refresh_page?\", out=0x18228ffc...
1141d [01/Sep/2017:18:04:41 -0100] [Client 2] write_pipe: CGI output on fd 9.
1142d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1143d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
1144D [01/Sep/2017:18:04:41 -0100] [Client 2] CGI data ready to be sent.
1145d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1146d [01/Sep/2017:18:04:41 -0100] [CGI] \"{display:none;}\" at 493...
1147d [01/Sep/2017:18:04:41 -0100] [CGI] Lone { at 633...
1148d [01/Sep/2017:18:04:41 -0100] [CGI] Lone { at 708...
1149D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1150D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=334, file=9
1151d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1152D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1153D [01/Sep/2017:18:04:41 -0100] [Client 2] Script header: Content-Type: text/html;charset=utf-8
1154D [01/Sep/2017:18:04:41 -0100] [Client 2] Script header:
1155D [01/Sep/2017:18:04:41 -0100] [Client 2] Sending status 200 for CGI.
1156D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdSendHeader: code=200, type="(null)", auth_type=0
1157d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1158d [01/Sep/2017:18:04:41 -0100] [CGI] Lone { at 843...
1159d [01/Sep/2017:18:04:41 -0100] [CGI] Lone { at 877...
1160d [01/Sep/2017:18:04:41 -0100] [CGI] \"{title}\" at 1081...
1161d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SECTION=admin\" at 1315, result=0...
1162d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1163d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1315...
1164d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1331 on character \':\'...
1165d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1166d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1331...
1167d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1332 on character \'}\'...
1168d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SECTION=admin\", out=0x18228ffc...
1169d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SECTION=classes\" at 1396, result=0...
1170d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1171d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1396...
1172d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1412 on character \':\'...
1173d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1174d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1412...
1175d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1413 on character \'}\'...
1176d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SECTION=classes\", out=0x18228ffc...
1177d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SECTION=help\" at 1470, result=0...
1178d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1179d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1470...
1180d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1486 on character \':\'...
1181d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1182d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1486...
1183d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1487 on character \'}\'...
1184d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SECTION=help\", out=0x18228ffc...
1185d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SECTION=jobs\" at 1538, result=0...
1186d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1187d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1538...
1188d [01/Sep/2017:18:04:41 -0100] [Client 2] write_pipe: CGI output on fd 9.
1189d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1190d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
1191D [01/Sep/2017:18:04:41 -0100] [Client 2] CGI data ready to be sent.
1192D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1193D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1194d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1195D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1196d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1197d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1554 on character \':\'...
1198d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1199d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1554...
1200d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1555 on character \'}\'...
1201d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SECTION=jobs\", out=0x18228ffc...
1202d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SECTION=printers\" at 1610, result=1...
1203d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1204d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1610...
1205d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1626 on character \':\'...
1206d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1207d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1626...
1208d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1627 on character \'}\'...
1209d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SECTION=printers\", out=0x18228ffc...
1210d [01/Sep/2017:18:04:41 -0100] [CGI] \"{title}\" at 1750...
1211d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1757 on EOF...
1212d [01/Sep/2017:18:04:41 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"printer.tmpl\")
1213d [01/Sep/2017:18:04:41 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
1214d [01/Sep/2017:18:04:41 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/printer.tmpl\"...
1215d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 0...
1216d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_uri_supported}\" at 50...
1217d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_name}\" at 66...
1218d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_state=3\" at 89, result=1...
1219d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1220d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 89...
1221d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 94 on character \':\'...
1222d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1223d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 94...
1224d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_state=4\" at 111, result=0...
1225d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1226d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 111...
1227d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 122 on character \':\'...
1228d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1229d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 122...
1230d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 129 on character \'}\'...
1231d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_state=4\", out=0...
1232d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 130 on character \'}\'...
1233d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_state=3\", out=0x18228ffc...
1234d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_is_accepting_jobs=0\" at 161, result=0...
1235d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1236d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 161...
1237d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 176 on character \':\'...
1238d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1239d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 176...
1240d [01/Sep/2017:18:04:41 -0100] [Client 2] write_pipe: CGI output on fd 9.
1241d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1242d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
1243D [01/Sep/2017:18:04:41 -0100] [Client 2] CGI data ready to be sent.
1244D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1245D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1246d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1247D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1248d [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
1249D [01/Sep/2017:18:04:41 -0100] [Client 7] POST / HTTP/1.1
1250D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1251D [01/Sep/2017:18:04:41 -0100] [Client 7] Read: status=200
1252d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: uri="/", limit=10...
1253d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
1254d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
1255d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin(6) Limit 7f
1256d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /(1) Limit 7f
1257d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: best=/
1258d [01/Sep/2017:18:04:41 -0100] [Client 7] con->uri="/", con->best=0x200356c0(/)
1259D [01/Sep/2017:18:04:41 -0100] [Client 7] No authentication data provided.
1260d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: con->uri="/", con->best=0x200356c0(/)
1261d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
1262d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
1263D [01/Sep/2017:18:04:41 -0100] [Client 7] 2.0 Get-Jobs 3
1264d [01/Sep/2017:18:04:41 -0100] cupsdProcessIPPRequest(0x202765a0[7]): operation_id = 000a
1265D [01/Sep/2017:18:04:41 -0100] Get-Jobs ipp://localhost:631/printers/HPPSC1610
1266d [01/Sep/2017:18:04:41 -0100] get_jobs(0x202765a0[7], ipp://localhost:631/printers/HPPSC1610)
1267d [01/Sep/2017:18:04:41 -0100] cupsdFindPolicyOp(p=0x20043fa0, op=a(Get-Jobs))
1268d [01/Sep/2017:18:04:41 -0100] cupsdFindPolicyOp: Found wildcard match...
1269d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: con->uri="/", con->best=0x20047d80((null))
1270d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
1271d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: op=0(0x0000)
1272d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
1273d [01/Sep/2017:18:04:41 -0100] get_jobs: count=0
1274D [01/Sep/2017:18:04:41 -0100] [Client 7] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HPPSC1610) from localhost
1275D [01/Sep/2017:18:04:41 -0100] [Client 7] Content-Length: 75
1276D [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1277d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0x18138, data=0x202765a0)
1278d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1279d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 191 on character \'}\'...
1280d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_is_accepting_jobs=0\", out=0x18228ffc...
1281d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{server_is_sharing_printers=0\" at 223, result=1...
1282d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1283d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 223...
1284d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 227 on character \':\'...
1285d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1286d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 227...
1287d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_is_shared=0\" at 248, result=0...
1288d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1289d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 248...
1290d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 252 on character \':\'...
1291d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1292d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 252...
1293d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 253 on character \'}\'...
1294d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_is_shared=0\", out=0...
1295d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 254 on character \'}\'...
1296d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{server_is_sharing_printers=0\", out=0x18228ffc...
1297d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{default_name=HPPSC1610\" at 290, result=1...
1298d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1299d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 290...
1300d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 307 on character \':\'...
1301d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1302d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 307...
1303d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 308 on character \'}\'...
1304d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{default_name=HPPSC1610\", out=0x18228ffc...
1305d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_uri_supported}\" at 367...
1306d [01/Sep/2017:18:04:41 -0100] [CGI] \"{$org.cups.sid}\" at 452...
1307D [01/Sep/2017:18:04:41 -0100] [CGI] Regular expression \".*Clean.*\"
1308D [01/Sep/2017:18:04:41 -0100] [CGI] matches[0].rm_so=0
1309D [01/Sep/2017:18:04:41 -0100] [CGI] matches[1].rm_so=-1
1310d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_commands~.*Clean.*\" at 639, result=1...
1311d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1312d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 639...
1313d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 700 on character \':\'...
1314d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1315d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 700...
1316d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 701 on character \'}\'...
1317d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_commands~.*Clean.*\", out=0x18228ffc...
1318D [01/Sep/2017:18:04:41 -0100] [CGI] Regular expression \".*PrintSelfTestPage.*\"
1319D [01/Sep/2017:18:04:41 -0100] [CGI] matches[0].rm_so=0
1320D [01/Sep/2017:18:04:41 -0100] [CGI] matches[1].rm_so=-1
1321d [01/Sep/2017:18:04:41 -0100] [Client 2] write_pipe: CGI output on fd 9.
1322d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1323d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
1324D [01/Sep/2017:18:04:41 -0100] [Client 2] CGI data ready to be sent.
1325D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1326D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1327d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1328D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1329D [01/Sep/2017:18:04:41 -0100] [Client 7] con->http=0x20285e60
1330D [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x2005ed40(IPP_STATE_DATA), pipe_pid=0, file=-1
1331D [01/Sep/2017:18:04:41 -0100] [Client 7] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1332D [01/Sep/2017:18:04:41 -0100] [Client 7] bytes=0, http_state=0, data_remaining=75
1333D [01/Sep/2017:18:04:41 -0100] [Client 7] Flushing write buffer.
1334D [01/Sep/2017:18:04:41 -0100] [Client 7] New state is HTTP_STATE_WAITING
1335d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=11, read_cb=0x1915c, write_cb=0, data=0x202765a0)
1336D [01/Sep/2017:18:04:41 -0100] [Client 7] Waiting for request.
1337D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1338d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1339d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_commands~.*PrintSelfTestPage.*\" at 742, result=1...
1340d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1341d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 742...
1342d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 809 on character \':\'...
1343d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1344d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 809...
1345d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 810 on character \'}\'...
1346d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_commands~.*PrintSelfTestPage.*\", out=0x18228ffc...
1347d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_state=5\" at 828, result=0...
1348d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1349d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 828...
1350d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 882 on character \':\'...
1351d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1352d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 882...
1353d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 934 on character \'}\'...
1354d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_state=5\", out=0x18228ffc...
1355d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{printer_is_accepting_jobs=0\" at 964, result=0...
1356d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1357d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 964...
1358d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1013 on character \':\'...
1359d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1360d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1013...
1361d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1062 on character \'}\'...
1362d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{printer_is_accepting_jobs=0\", out=0x18228ffc...
1363d [01/Sep/2017:18:04:41 -0100] [CGI] \"{admin_uri}\" at 1277...
1364d [01/Sep/2017:18:04:41 -0100] [CGI] \"{$org.cups.sid}\" at 1365...
1365d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_name}\" at 1430...
1366d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_name}\" at 1939...
1367d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_info}\" at 2012...
1368d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_location}\" at 2094...
1369d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_make_and_model}\" at 2180...
1370d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{color_supported=1\" at 2201, result=1...
1371d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1372d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2201...
1373d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2207 on character \':\'...
1374d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1375d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2207...
1376d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2217 on character \'}\'...
1377d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{color_supported=1\", out=0x18228ffc...
1378d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{sides_supported=one-sided\" at 2244, result=0...
1379d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1380d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2244...
1381D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1382D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1383d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1384D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1385d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=10)
1386d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1387d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2245 on character \':\'...
1388d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1389d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2245...
1390d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2264 on character \'}\'...
1391d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{sides_supported=one-sided\", out=0x18228ffc...
1392d [01/Sep/2017:18:04:41 -0100] [CGI] \"{device_uri}\" at 2337...
1393d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_sheets_default}\" at 2432...
1394d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{media_default?\" at 2454, result=1...
1395d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1396d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2454...
1397d [01/Sep/2017:18:04:41 -0100] [CGI] \"{media_default}\" at 2469...
1398d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2470 on character \':\'...
1399d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1400d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2470...
1401d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2478 on character \'}\'...
1402d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{media_default?\", out=0x18228ffc...
1403d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{sides_default?\" at 2494, result=1...
1404d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1405d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2494...
1406d [01/Sep/2017:18:04:41 -0100] [CGI] \"{sides_default}\" at 2515...
1407d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2516 on character \':\'...
1408d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1409d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2516...
1410d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2517 on character \'}\'...
1411d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{sides_default?\", out=0x18228ffc...
1412d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2537 on EOF...
1413d [01/Sep/2017:18:04:41 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"printer-jobs-header.tmpl\")
1414d [01/Sep/2017:18:04:41 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
1415d [01/Sep/2017:18:04:41 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/printer-jobs-header.tmpl\"...
1416d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 0...
1417d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 28 on EOF...
1418d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1419D [01/Sep/2017:18:04:41 -0100] [CGI] cgiClearVariables called.
1420D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: ORDER=\"asc\"
1421D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: SECTION=\"printers\"
1422D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: TOTAL=\"0\"
1423D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: PRINTER_NAME=\"HPPSC1610\"
1424D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HPPSC1610\"
1425D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: THISURL=\"/printers/HPPSC1610\"
1426D [01/Sep/2017:18:04:41 -0100] [CGI] cgiSetVariable: SEARCH_DEST=\"HPPSC1610\"
1427d [01/Sep/2017:18:04:41 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"search.tmpl\")
1428d [01/Sep/2017:18:04:41 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
1429d [01/Sep/2017:18:04:41 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/search.tmpl\"...
1430d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 0...
1431d [01/Sep/2017:18:04:41 -0100] [CGI] \"{SECTION}\" at 24...
1432d [01/Sep/2017:18:04:41 -0100] [CGI] \"{?SEARCH_DEST}\" at 39...
1433d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{WHICH_JOBS?\" at 67, result=0...
1434d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1435d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 67...
1436d [01/Sep/2017:18:04:41 -0100] [CGI] \"{WHICH_JOBS}\" at 125...
1437d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 128 on character \':\'...
1438d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1439d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 128...
1440d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 129 on character \'}\'...
1441d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{WHICH_JOBS?\", out=0x18228ffc...
1442d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{ORDER?\" at 137, result=1...
1443d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1444d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 137...
1445d [01/Sep/2017:18:04:41 -0100] [CGI] \"{ORDER}\" at 185...
1446d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 188 on character \':\'...
1447d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1448d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 188...
1449d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 189 on character \'}\'...
1450d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{ORDER?\", out=0x18228ffc...
1451d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SEARCH_DEST?\" at 235, result=1...
1452d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1453d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 235...
1454d [01/Sep/2017:18:04:41 -0100] [CGI] \"{SEARCH_DEST}\" at 248...
1455d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 249 on character \':\'...
1456d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1457d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 249...
1458d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SECTION=classes\" at 266, result=0...
1459d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1460d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 266...
1461d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 274 on character \':\'...
1462d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1463d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 274...
1464d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{SECTION=jobs\" at 288, result=0...
1465d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1466d [01/Sep/2017:18:04:41 -0100] [Client 2] write_pipe: CGI output on fd 9.
1467d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1468d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
1469D [01/Sep/2017:18:04:41 -0100] [Client 2] CGI data ready to be sent.
1470d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1471d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 288...
1472d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 293 on character \':\'...
1473d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1474d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 293...
1475d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 302 on character \'}\'...
1476d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SECTION=jobs\", out=0...
1477d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 303 on character \'}\'...
1478d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SECTION=classes\", out=0...
1479d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 304 on character \'}\'...
1480d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{SEARCH_DEST?\", out=0x18228ffc...
1481d [01/Sep/2017:18:04:41 -0100] [CGI] \"{?QUERY}\" at 359...
1482d [01/Sep/2017:18:04:41 -0100] [CGI] \"{SECTION}\" at 415...
1483d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 530 on EOF...
1484d [01/Sep/2017:18:04:41 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"jobs-header.tmpl\")
1485d [01/Sep/2017:18:04:41 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
1486d [01/Sep/2017:18:04:41 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/jobs-header.tmpl\"...
1487d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 0...
1488d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?which_jobs=\" at 14, result=1...
1489d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1490d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 14...
1491d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 15 on character \':\'...
1492d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1493d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 15...
1494d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?printer_name=\" at 45, result=0...
1495d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1496d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 45...
1497d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 51 on character \':\'...
1498d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1499d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 51...
1500d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_uri_supported}\" at 74...
1501d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 75 on character \'}\'...
1502d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?printer_name=\", out=0...
1503d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 144 on character \'}\'...
1504d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?which_jobs=\", out=0x18228ffc...
1505d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?which_jobs=completed\" at 168, result=0...
1506d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1507d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 168...
1508d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 169 on character \':\'...
1509d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1510d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 169...
1511d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?printer_name=\" at 199, result=0...
1512d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1513d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 199...
1514D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1515D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1516d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1517D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1518d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1519d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 205 on character \':\'...
1520d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1521d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 205...
1522d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_uri_supported}\" at 228...
1523d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 229 on character \'}\'...
1524d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?printer_name=\", out=0x18228ffc...
1525d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 358 on character \'}\'...
1526d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?which_jobs=completed\", out=0x18228ffc...
1527d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?which_jobs=all\" at 376, result=0...
1528d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1529d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 376...
1530d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 377 on character \':\'...
1531d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1532d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 377...
1533d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?printer_name=\" at 407, result=0...
1534d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1535d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 407...
1536d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 413 on character \':\'...
1537d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1538d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 413...
1539d [01/Sep/2017:18:04:41 -0100] [CGI] \"{printer_uri_supported}\" at 436...
1540d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 437 on character \'}\'...
1541d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?printer_name=\", out=0x18228ffc...
1542d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 554 on character \'}\'...
1543d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?which_jobs=all\", out=0x18228ffc...
1544d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{total=0\" at 583, result=1...
1545d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1546d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 583...
1547d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 591 on character \':\'...
1548d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1549d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 591...
1550d [01/Sep/2017:18:04:41 -0100] [CGI] \"{#job_id}\" at 608...
1551d [01/Sep/2017:18:04:41 -0100] [CGI] \"{total}\" at 619...
1552d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?which_jobs=\" at 634, result=1...
1553d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1554d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 634...
1555d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 641 on character \':\'...
1556d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1557d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 641...
1558d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{which_jobs=all\" at 657, result=0...
1559d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1560d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 657...
1561d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 658 on character \':\'...
1562D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1563D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1564d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1565D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1566d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=10)
1567d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1568d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1569d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 658...
1570d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 668 on character \'}\'...
1571d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{which_jobs=all\", out=0...
1572d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 669 on character \'}\'...
1573d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?which_jobs=\", out=0...
1574d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{total=1\" at 682, result=0...
1575d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1576d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 682...
1577d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 683 on character \':\'...
1578d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1579d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 683...
1580d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 685 on character \'}\'...
1581d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{total=1\", out=0...
1582d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 686 on character \'}\'...
1583d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{total=0\", out=0x18228ffc...
1584d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 692 on EOF...
1585d [01/Sep/2017:18:04:41 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"jobs.tmpl\")
1586d [01/Sep/2017:18:04:41 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
1587d [01/Sep/2017:18:04:41 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/jobs.tmpl\"...
1588d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 0...
1589d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{#job_id=0\" at 11, result=1...
1590d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1591d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 11...
1592d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 12 on character \':\'...
1593d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1594d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 12...
1595d [01/Sep/2017:18:04:41 -0100] [CGI] \"{THISURL}\" at 87...
1596d [01/Sep/2017:18:04:41 -0100] [CGI] \"{?QUERY}\" at 102...
1597d [01/Sep/2017:18:04:41 -0100] [CGI] \"{?WHICH_JOBS}\" at 131...
1598d [01/Sep/2017:18:04:41 -0100] [CGI] \"{FIRST}\" at 149...
1599d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{ORDER=dec\" at 171, result=0...
1600d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1601d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 171...
1602d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 175 on character \':\'...
1603d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1604d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 175...
1605d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 179 on character \'}\'...
1606d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{ORDER=dec\", out=0...
1607d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{ORDER=dec\" at 192, result=0...
1608d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1609d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 192...
1610d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 243 on character \':\'...
1611d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1612d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1613d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 243...
1614d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 294 on character \'}\'...
1615d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{ORDER=dec\", out=0...
1616d [01/Sep/2017:18:04:41 -0100] [CGI] Looping on \"job_id\" at 418, count=0...
1617d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 418...
1618d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_printer_uri}\" at 467...
1619d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_printer_name}\" at 487...
1620d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_id}\" at 500...
1621d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?phone?\" at 508, result=0...
1622d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1623d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 508...
1624d [01/Sep/2017:18:04:41 -0100] [CGI] \"{phone}\" at 517...
1625d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 519 on character \':\'...
1626d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1627d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 519...
1628d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 520 on character \'}\'...
1629d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?phone?\", out=0...
1630d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?job_name=\" at 548, result=1...
1631d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1632d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 548...
1633d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 556 on character \':\'...
1634d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1635d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 556...
1636d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_name}\" at 566...
1637d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 567 on character \'}\'...
1638d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?job_name=\", out=0...
1639d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{?job_originating_user_name=\" at 612, result=1...
1640d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1641d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 612...
1642d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 621 on character \':\'...
1643d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1644d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 621...
1645d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_originating_user_name}\" at 648...
1646d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 649 on character \'}\'...
1647d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{?job_originating_user_name=\", out=0...
1648d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_k_octets}\" at 679...
1649d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_media_sheets_completed=0\" at 726, result=0...
1650d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1651d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 726...
1652d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 734 on character \':\'...
1653d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1654d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 734...
1655d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1656d [01/Sep/2017:18:04:41 -0100] [CGI] \"{?job_media_sheets_completed}\" at 763...
1657d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 764 on character \'}\'...
1658d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_media_sheets_completed=0\", out=0...
1659d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=3\" at 793, result=0...
1660d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1661d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 793...
1662d [01/Sep/2017:18:04:41 -0100] [CGI] \"{time_at_creation}\" at 828...
1663d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 829 on character \':\'...
1664d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1665d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 829...
1666d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=4\" at 842, result=0...
1667d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1668d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 842...
1669d [01/Sep/2017:18:04:41 -0100] [CGI] \"{time_at_creation}\" at 874...
1670d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 875 on character \':\'...
1671d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1672d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 875...
1673d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=5\" at 889, result=0...
1674d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1675d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 889...
1676d [01/Sep/2017:18:04:41 -0100] [CGI] \"{time_at_processing}\" at 929...
1677d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 930 on character \':\'...
1678d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1679d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 930...
1680d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=6\" at 943, result=0...
1681d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1682d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 943...
1683d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 951 on character \':\'...
1684d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1685d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 951...
1686d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=7\" at 965, result=0...
1687d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1688d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 965...
1689d [01/Sep/2017:18:04:41 -0100] [CGI] \"{time_at_completed}\" at 999...
1690d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1000 on character \':\'...
1691d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1692d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1000...
1693d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=8\" at 1013, result=0...
1694d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1695d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1696d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1013...
1697d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1021 on character \':\'...
1698d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1699d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1021...
1700d [01/Sep/2017:18:04:41 -0100] [CGI] \"{time_at_completed}\" at 1056...
1701d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1057 on character \'}\'...
1702d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=8\", out=0...
1703d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1058 on character \'}\'...
1704d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=7\", out=0...
1705d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1059 on character \'}\'...
1706d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=6\", out=0...
1707d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1060 on character \'}\'...
1708d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=5\", out=0...
1709d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1061 on character \'}\'...
1710d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=4\", out=0...
1711d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1062 on character \'}\'...
1712d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=3\", out=0...
1713d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_printer_state_message?\" at 1095, result=0...
1714d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1715d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1095...
1716d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_printer_state_message}\" at 1132...
1717d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1139 on character \':\'...
1718d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1719d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1139...
1720d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1140 on character \'}\'...
1721d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_printer_state_message?\", out=0...
1722d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_preserved>0\" at 1168, result=1...
1723d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1724d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1168...
1725d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state>5\" at 1181, result=1...
1726d [01/Sep/2017:18:04:41 -0100] [CGI] Output first part...
1727d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1181...
1728d [01/Sep/2017:18:04:41 -0100] [CGI] \"{$org.cups.sid}\" at 1281...
1729d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_id}\" at 1384...
1730d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_printer_uri}\" at 1454...
1731d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1505 on character \':\'...
1732d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1733d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1734d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1505...
1735d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1506 on character \'}\'...
1736d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1737d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state>5\", out=0...
1738d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1507 on character \':\'...
1739d [01/Sep/2017:18:04:41 -0100] [CGI] Skip second part...
1740d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1507...
1741d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1508 on character \'}\'...
1742d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_preserved>0\", out=0...
1743d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=4\" at 1522, result=0...
1744d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1745d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1522...
1746d [01/Sep/2017:18:04:41 -0100] [CGI] \"{$org.cups.sid}\" at 1622...
1747d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_id}\" at 1725...
1748d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_printer_uri}\" at 1795...
1749d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1847 on character \':\'...
1750d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1751d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1847...
1752d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 1848 on character \'}\'...
1753d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=4\", out=0...
1754d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state=3\" at 1862, result=0...
1755d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1756d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 1862...
1757d [01/Sep/2017:18:04:41 -0100] [CGI] \"{$org.cups.sid}\" at 1962...
1758d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_id}\" at 2062...
1759d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_printer_uri}\" at 2132...
1760d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2181 on character \':\'...
1761d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1762d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2181...
1763d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2182 on character \'}\'...
1764d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state=3\", out=0...
1765d [01/Sep/2017:18:04:41 -0100] [CGI] Starting \"{job_state<7\" at 2196, result=0...
1766d [01/Sep/2017:18:04:41 -0100] [CGI] Skip first part...
1767d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2196...
1768d [01/Sep/2017:18:04:41 -0100] [CGI] \"{$org.cups.sid}\" at 2296...
1769d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_id}\" at 2398...
1770d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_printer_uri}\" at 2468...
1771d [01/Sep/2017:18:04:41 -0100] [CGI] \"{$org.cups.sid}\" at 2618...
1772d [01/Sep/2017:18:04:41 -0100] [CGI] \"{job_id}\" at 2718...
1773d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2766 on character \':\'...
1774d [01/Sep/2017:18:04:41 -0100] [CGI] Output second part...
1775d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 2766...
1776d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2767 on character \'}\'...
1777d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{job_state<7\", out=0...
1778d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2787 on character \'}\'...
1779d [01/Sep/2017:18:04:41 -0100] [Client 2] write_pipe: CGI output on fd 9.
1780d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1781d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
1782D [01/Sep/2017:18:04:41 -0100] [Client 2] CGI data ready to be sent.
1783d [01/Sep/2017:18:04:41 -0100] [Client 7] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
1784D [01/Sep/2017:18:04:41 -0100] [Client 7] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1785D [01/Sep/2017:18:04:41 -0100] [Client 7] Closing connection.
1786D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1787d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=11)
1788d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=-1)
1789d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1790d [01/Sep/2017:18:04:41 -0100] [CGI] Finished looping on \"job_id\"...
1791d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2807 on character \'}\'...
1792d [01/Sep/2017:18:04:41 -0100] [CGI] Finished \"{#job_id=0\", out=0x18228ffc...
1793d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 2808 on EOF...
1794d [01/Sep/2017:18:04:41 -0100] [CGI] cgiCopyTemplateLang(tmpl=\"trailer.tmpl\")
1795d [01/Sep/2017:18:04:41 -0100] [CGI] lang=\"en_US.UTF8\", locale=\"/en_US\"...
1796d [01/Sep/2017:18:04:41 -0100] [CGI] Template file is \"/@unixroot/usr/share/cups/templates/trailer.tmpl\"...
1797d [01/Sep/2017:18:04:41 -0100] [CGI] Starting at file position 0...
1798d [01/Sep/2017:18:04:41 -0100] [CGI] Returning at file position 218 on EOF...
1799D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1800D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1801d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1802D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1803d [01/Sep/2017:18:04:41 -0100] process_children()
1804d [01/Sep/2017:18:04:41 -0100] cupsdFinishProcess(pid=334, name=0x17f6d0, namelen=1024, job_id=0x17f6cc(0)) = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
1805D [01/Sep/2017:18:04:41 -0100] PID 334 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
1806d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1807d [01/Sep/2017:18:04:41 -0100] [Client 2] write_pipe: CGI output on fd 9.
1808d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1809d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0, write_cb=0x18138, data=0x2005d400)
1810D [01/Sep/2017:18:04:41 -0100] [Client 2] CGI data ready to be sent.
1811D [01/Sep/2017:18:04:41 -0100] [Client 2] con->http=0x20288ec0
1812D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=334, file=9
1813d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=9, read_cb=0x146e4, write_cb=0, data=0x2005d400)
1814D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for CGI data.
1815D [01/Sep/2017:18:04:41 -0100] [Client 2] Sending 0-length chunk.
1816D [01/Sep/2017:18:04:41 -0100] [Client 2] Flushing write buffer.
1817D [01/Sep/2017:18:04:41 -0100] [Client 2] New state is HTTP_STATE_WAITING
1818d [01/Sep/2017:18:04:41 -0100] cupsdAddSelect(fd=10, read_cb=0x1915c, write_cb=0, data=0x2005d400)
1819D [01/Sep/2017:18:04:41 -0100] [Client 2] Waiting for request.
1820d [01/Sep/2017:18:04:41 -0100] cupsdRemoveSelect(fd=9)
1821d [01/Sep/2017:18:04:41 -0100] cupsdEndProcess(pid=334, force=0)
1822D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1823d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1824d [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
1825D [01/Sep/2017:18:04:41 -0100] [Client 2] GET /cups.css HTTP/1.1
1826D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1827D [01/Sep/2017:18:04:41 -0100] [Client 2] Read: status=200
1828d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: uri="/cups.css", limit=2...
1829d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/log(10) Limit 7f
1830d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
1831d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /admin(6) Limit 7f
1832d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: Location /(1) Limit 7f
1833d [01/Sep/2017:18:04:41 -0100] cupsdFindBest: best=/
1834d [01/Sep/2017:18:04:41 -0100] [Client 2] con->uri="/cups.css", con->best=0x200356c0(/)
1835D [01/Sep/2017:18:04:41 -0100] [Client 2] No authentication data provided.
1836d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: con->uri="/cups.css", con->best=0x200356c0(/)
1837d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
1838d [01/Sep/2017:18:04:41 -0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
1839D [01/Sep/2017:18:04:41 -0100] [Client 2] Processing GET /cups.css
1840d [01/Sep/2017:18:04:41 -0100] [Client 2] get_file: filestats=0x173604, filename=0x173670, len=1024, returning "/@unixroot/usr/share/doc/cups/cups.css".
1841D [01/Sep/2017:18:04:41 -0100] [Client 2] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
1842d [01/Sep/2017:18:04:41 -0100] [Client 2] is_cgi: filename="/@unixroot/usr/share/doc/cups/cups.css", filestats=0x173604, type=text/css, returning 0.
1843d [01/Sep/2017:18:04:41 -0100] [Client 2] check_if_modified: filestats=0x173604(9961, 1503410768)) If-Modified-Since="Tue, 22 Aug 2017 14:06:08 GMT"
1844d [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdSendError code=304, auth_type=0
1845D [01/Sep/2017:18:04:41 -0100] [Client 2] cupsdSendHeader: code=304, type="(null)", auth_type=0
1846D [01/Sep/2017:18:04:41 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1847d [01/Sep/2017:18:04:41 -0100] select_timeout: JobHistoryUpdate=0
1848d [01/Sep/2017:18:04:42 -0100] select_timeout: JobHistoryUpdate=0
1849d [01/Sep/2017:18:04:42 -0100] select_timeout(0): 18 seconds to write dirty config/state files
1850d [01/Sep/2017:18:04:51 -0100] [Client 2] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
1851D [01/Sep/2017:18:04:51 -0100] [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1852D [01/Sep/2017:18:04:51 -0100] [Client 2] Closing connection.
1853D [01/Sep/2017:18:04:51 -0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1854d [01/Sep/2017:18:04:51 -0100] cupsdRemoveSelect(fd=10)
1855d [01/Sep/2017:18:04:51 -0100] cupsdRemoveSelect(fd=-1)
1856d [01/Sep/2017:18:04:51 -0100] cupsdCheckJobs: 0 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1504281891
1857d [01/Sep/2017:18:04:51 -0100] select_timeout: JobHistoryUpdate=0
1858d [01/Sep/2017:18:04:52 -0100] select_timeout: JobHistoryUpdate=0
1859d [01/Sep/2017:18:04:52 -0100] select_timeout(0): 8 seconds to write dirty config/state files
1860I [01/Sep/2017:18:05:00 -0100] Saving job.cache...
1861D [01/Sep/2017:18:05:00 -0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
1862d [01/Sep/2017:18:05:00 -0100] select_timeout: JobHistoryUpdate=0
1863d [01/Sep/2017:18:05:00 -0100] select_timeout(0): 86400 seconds to do nothing
1864W [01/Sep/2017:18:05:05 -0100] cupsdDoSelect: select() returned -1... pause and retry select()
1865d [01/Sep/2017:18:05:06 -0100] cupsdAcceptClient(lis=0x200358c0(6)) Clients=0
1866D [01/Sep/2017:18:05:06 -0100] [Client 8] Accepted from localhost:53881 (IPv4)
1867d [01/Sep/2017:18:05:06 -0100] cupsdAddSelect(fd=9, read_cb=0x1915c, write_cb=0, data=0x2005d2e0)
1868D [01/Sep/2017:18:05:06 -0100] [Client 8] Waiting for request.
1869d [01/Sep/2017:18:05:06 -0100] cupsdCheckJobs: 0 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1504281906
1870I [01/Sep/2017:18:05:06 -0100] Scheduler shutting down normally.
1871d [01/Sep/2017:18:05:06 -0100] cupsdAddEvent(event=server-stopped, dest=0(), job=0(0), text="Scheduler shutting down normally.", ...)
1872D [01/Sep/2017:18:05:06 -0100] Discarding unused server-stopped event...
1873d [01/Sep/2017:18:05:06 -0100] cupsdCloseAllClients() Clients=1
1874D [01/Sep/2017:18:05:06 -0100] [Client 8] Closing connection.
1875D [01/Sep/2017:18:05:06 -0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
1876d [01/Sep/2017:18:05:06 -0100] cupsdRemoveSelect(fd=9)
1877d [01/Sep/2017:18:05:06 -0100] cupsdRemoveSelect(fd=-1)
1878d [01/Sep/2017:18:05:06 -0100] cupsdStopListening: closing all listen sockets.
1879d [01/Sep/2017:18:05:06 -0100] cupsdPauseListening: Clearing input bits...
1880d [01/Sep/2017:18:05:06 -0100] cupsdRemoveSelect(fd=6)
1881d [01/Sep/2017:18:05:06 -0100] cupsdRemoveSelect(fd=7)
1882d [01/Sep/2017:18:05:06 -0100] cupsdDeleteProfile(profile="(null)")
1883I [01/Sep/2017:18:05:06 -0100] Saving job.cache...
1884D [01/Sep/2017:18:05:06 -0100] cupsdStopSelect()