Ticket #143: error_log-2017-09-26-socket

File error_log-2017-09-26-socket, 177.0 KB (added by Lewis Rosenthal, 7 years ago)

Error log (same configuration, but using socket backend)

Line 
1D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
2D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
3D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
4D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
5D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
6D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
7D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
8D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
9D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
10D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
11D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
12D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
13D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
14D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
15D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
16D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
17D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
18D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
19D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
20D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
21D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
22D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
23D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
24D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
25D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
26D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
27D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
28D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
29D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
30D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
31D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
32D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
33D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
34D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
35D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
36D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
37D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
38D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
39D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
40D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
41D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
42D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
43D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
44D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
45D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
46D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
47D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
48D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
49D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
50D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
51D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
52D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
53D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
54D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
55D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
56D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
57D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
58D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
59D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
60D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
61D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
62D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
63D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
64D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
65D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
66D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
67D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
68D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
69D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
70D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
71D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
72D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
73D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
74D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
75D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
76D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
77D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
78D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
79D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
80D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
81D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
82D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
83D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
84D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
85D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
86D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
87D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
88D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
89D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
90D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
91D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
92D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
93D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
94D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
95D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
96D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
97D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
98D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
99D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
100D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
101D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
102D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
103D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
104D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
105D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
106D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
107D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
108D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
109D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
110D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
111D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
112D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
113D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
114D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
115D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
116D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
117D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
118D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
119D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
120D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
121D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
122D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
123D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
124D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
125D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
126D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
127D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
128D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
129D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
130D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
131D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
132D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
133D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
134D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
135D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
136D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
137D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
138D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
139D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
140D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
141D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
142D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
143D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
144D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
145D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
146D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
147D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
148D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
149D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
150D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
151D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
152D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
153D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
154D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
155D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
156D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
157D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
158D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
159D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
160D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
161D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
162D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
163D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
164D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
165D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
166D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
167D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
168D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
169D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
170D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
171D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
172D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
173D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
174D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
175D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
176D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
177D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
178D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
179D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
180D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
181D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
182D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
183D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
184D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
185D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
186D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
187D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
188D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
189D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
190D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
191D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
192D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
193D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
194D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
195D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
196D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
197D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
198D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
199D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
200D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
201D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
202D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
203D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
204D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
205D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
206D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
207D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
208D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
209D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
210D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
211D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
212D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
213D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
214D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
215D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
216D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
217D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
218D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
219D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
220D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
221D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
222D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
223D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
224D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
225D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
226D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
227D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
228D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
229D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
230D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
231D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
232D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
233D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
234D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
235D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
236D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
237D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
238D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
239D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
240D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
241D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
242D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
243D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
244D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
245D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
246D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
247D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
248D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
249D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
250D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
251D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
252D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
253D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
254D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
255D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
256D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
257D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
258D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
259D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
260D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
261D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
262D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
263D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
264D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
265D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
266D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
267D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
268D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
269D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
270D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
271D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
272D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
273D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
274D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
275D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
276D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
277D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
278D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
279D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
280D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
281D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
282D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
283D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
284D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
285D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
286D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
287D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
288D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
289D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
290D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
291D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
292D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
293D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
294D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
295D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
296D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
297D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
298D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
299D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
300D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
301D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
302D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
303D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
304D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
305D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
306D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
307D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
308D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
309D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
310D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
311D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
312D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
313D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
314D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
315D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
316D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
317D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
318D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
319D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
320D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
321D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
322D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
323D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
324D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
325D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
326D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
327D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
328D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
329D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
330D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
331D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
332D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
333D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
334D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
335D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
336D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
337D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
338D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
339D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
340D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
341D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
342D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
343D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
344D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
345D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
346D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
347D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
348D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
349D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
350D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
351D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
352D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
353D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
354D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
355D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
356D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
357D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
358D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
359D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
360D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
361D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
362D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
363D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
364D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
365D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
366D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
367D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
368D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
369D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
370D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
371D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
372D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
373D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
374D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
375D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
376D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
377D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
378D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
379D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
380D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
381D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
382D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
383D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
384D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
385D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
386D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
387D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
388D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
389D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
390D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
391D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
392D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
393D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
394D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
395D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
396D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
397D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
398D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
399D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
400D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
401D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
402D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
403D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
404D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
405D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
406D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
407D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
408D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
409D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
410D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
411D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
412D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
413D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
414D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
415D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
416D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
417D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
418D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
419D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
420D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
421D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
422D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
423D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
424D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
425D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
426D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
427D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
428D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
429D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
430D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
431D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
432D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
433D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
434D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
435D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
436D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
437D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
438D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
439D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
440D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
441D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
442D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
443D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
444D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
445D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
446D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
447D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
448D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
449D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
450D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
451D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
452D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
453D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
454D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
455D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
456D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
457D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
458D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
459D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
460D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
461D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
462D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
463D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
464D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
465D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
466D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
467D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
468D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
469D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
470D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
471D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
472D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
473D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
474D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
475D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
476D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
477D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
478D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
479D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
480D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
481D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
482D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
483D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
484D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
485D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
486D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
487D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
488D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
489D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
490D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
491D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
492D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
493D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
494D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
495D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
496D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
497D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
498D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
499D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
500D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
501D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
502D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
503D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
504D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
505D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
506D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
507D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
508D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
509D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
510D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
511D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
512D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
513D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
514D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
515D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
516D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
517D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
518D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
519D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
520D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
521D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
522D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
523D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
524D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
525D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
526D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
527D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
528D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
529D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
530D [26/Sep/2017:14:08:30 +0500] [Client 24] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
531D [26/Sep/2017:14:08:30 +0500] [Client 24] Closing connection.
532D [26/Sep/2017:14:08:30 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
533D [26/Sep/2017:14:08:30 +0500] [Client 23] CGI data ready to be sent.
534D [26/Sep/2017:14:08:30 +0500] [Client 23] con->http=0x200fbdc0
535D [26/Sep/2017:14:08:30 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=132, file=11
536D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for CGI data.
537D [26/Sep/2017:14:08:30 +0500] [Client 23] Sending 0-length chunk.
538D [26/Sep/2017:14:08:30 +0500] [Client 23] Flushing write buffer.
539D [26/Sep/2017:14:08:30 +0500] [Client 23] New state is HTTP_STATE_WAITING
540D [26/Sep/2017:14:08:30 +0500] [Client 23] Waiting for request.
541D [26/Sep/2017:14:08:30 +0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
542D [26/Sep/2017:14:08:30 +0500] PID 132 (/@unixroot/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
543D [26/Sep/2017:14:08:39 +0500] [Client 23] POST /admin HTTP/1.1
544D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
545D [26/Sep/2017:14:08:39 +0500] [Client 23] Read: status=200
546D [26/Sep/2017:14:08:39 +0500] [Client 23] No authentication data provided.
547D [26/Sep/2017:14:08:39 +0500] Report: clients=2
548D [26/Sep/2017:14:08:39 +0500] Report: jobs=8
549D [26/Sep/2017:14:08:39 +0500] Report: jobs-active=1
550D [26/Sep/2017:14:08:39 +0500] Report: printers=2
551D [26/Sep/2017:14:08:39 +0500] Report: stringpool-string-count=5808
552D [26/Sep/2017:14:08:39 +0500] Report: stringpool-alloc-bytes=12880
553D [26/Sep/2017:14:08:39 +0500] Report: stringpool-total-bytes=112776
554D [26/Sep/2017:14:08:39 +0500] [Client 23] Read: status=100
555D [26/Sep/2017:14:08:39 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/admin.cgi"
556D [26/Sep/2017:14:08:39 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
557D [26/Sep/2017:14:08:39 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
558D [26/Sep/2017:14:08:39 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
559D [26/Sep/2017:14:08:39 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
560D [26/Sep/2017:14:08:39 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
561D [26/Sep/2017:14:08:39 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
562D [26/Sep/2017:14:08:39 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
563D [26/Sep/2017:14:08:39 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
564D [26/Sep/2017:14:08:39 +0500] [CGI] envp[8] = "HOME=H:\\var\\temp"
565D [26/Sep/2017:14:08:39 +0500] [CGI] envp[9] = "NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
566D [26/Sep/2017:14:08:39 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
567D [26/Sep/2017:14:08:39 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@ARCAOS-01D8CC3"
568D [26/Sep/2017:14:08:39 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
569D [26/Sep/2017:14:08:39 +0500] [CGI] envp[13] = "TMPDIR=H:\\var\\temp"
570D [26/Sep/2017:14:08:39 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
571D [26/Sep/2017:14:08:39 +0500] [CGI] envp[15] = "USER=root"
572D [26/Sep/2017:14:08:39 +0500] [CGI] envp[16] = "UNIXROOT=H:"
573D [26/Sep/2017:14:08:39 +0500] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
574D [26/Sep/2017:14:08:39 +0500] [CGI] envp[18] = "CUPS_SERVER=localhost"
575D [26/Sep/2017:14:08:39 +0500] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
576D [26/Sep/2017:14:08:39 +0500] [CGI] envp[20] = "IPP_PORT=631"
577D [26/Sep/2017:14:08:39 +0500] [CGI] envp[21] = "LANG=en_US.UTF8"
578D [26/Sep/2017:14:08:39 +0500] [CGI] envp[22] = "REDIRECT_STATUS=1"
579D [26/Sep/2017:14:08:39 +0500] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
580D [26/Sep/2017:14:08:39 +0500] [CGI] envp[24] = "SERVER_NAME=localhost"
581D [26/Sep/2017:14:08:39 +0500] [CGI] envp[25] = "SERVER_PORT=631"
582D [26/Sep/2017:14:08:39 +0500] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
583D [26/Sep/2017:14:08:39 +0500] [CGI] envp[27] = "REMOTE_HOST=localhost"
584D [26/Sep/2017:14:08:39 +0500] [CGI] envp[28] = "SCRIPT_NAME=/admin"
585D [26/Sep/2017:14:08:39 +0500] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/admin"
586D [26/Sep/2017:14:08:39 +0500] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
587D [26/Sep/2017:14:08:39 +0500] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=ba119267d82f0c1688613ada42d0a797"
588D [26/Sep/2017:14:08:39 +0500] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
589D [26/Sep/2017:14:08:39 +0500] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/admin"
590D [26/Sep/2017:14:08:39 +0500] [CGI] envp[34] = "REQUEST_METHOD=POST"
591D [26/Sep/2017:14:08:39 +0500] [CGI] envp[35] = "CONTENT_LENGTH=1864"
592D [26/Sep/2017:14:08:39 +0500] [CGI] envp[36] = "CONTENT_TYPE=multipart/form-data; boundary=---------------------------197140508620275744861173875606"
593D [26/Sep/2017:14:08:39 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/admin.cgi (PID 138)
594I [26/Sep/2017:14:08:39 +0500] [Client 23] Started "/@unixroot/usr/lib/cups/cgi-bin/admin.cgi" (pid=138, file=11)
595D [26/Sep/2017:14:08:39 +0500] [Client 23] Waiting for CGI data.
596D [26/Sep/2017:14:08:39 +0500] [Client 25] Accepted from localhost:50036 (IPv4)
597D [26/Sep/2017:14:08:39 +0500] [Client 25] Waiting for request.
598D [26/Sep/2017:14:08:39 +0500] [CGI] admin.cgi started...
599D [26/Sep/2017:14:08:39 +0500] [CGI] http=0x200360a0
600D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: SECTION=\"admin\"
601D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
602D [26/Sep/2017:14:08:39 +0500] [CGI] org.cups.sid cookie is \"ba119267d82f0c1688613ada42d0a797\"
603D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: org.cups.sid=\"ba119267d82f0c1688613ada42d0a797\"
604D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: OP=\"modify-printer\"
605D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: printer_name=\"HP_Color_LaserJet_3800\"
606D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: BAUDRATE=\"\"
607D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: BITS=\"\"
608D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: PARITY=\"\"
609D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: FLOW=\"\"
610D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: PRINTER_INFO=\"HP Color LaserJet 3800\"
611D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: PRINTER_LOCATION=\"\"
612D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: DEVICE_URI=\"socket://192.168.100.16\"
613D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: PRINTER_IS_SHARED=\"\"
614D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: PPD_NAME=\"__no_change__\"
615D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: MAX_FILE_SIZE=\"262144\"
616D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: PPD_FILE=\"\"
617D [26/Sep/2017:14:08:39 +0500] [CGI] op=\"modify-printer\"...
618D [26/Sep/2017:14:08:39 +0500] [CGI] do_am_printer: DEVICE_URI=\"socket://192.168.100.16\"
619D [26/Sep/2017:14:08:39 +0500] [Client 25] POST / HTTP/1.1
620D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
621D [26/Sep/2017:14:08:39 +0500] [Client 25] Read: status=200
622D [26/Sep/2017:14:08:39 +0500] [Client 25] No authentication data provided.
623D [26/Sep/2017:14:08:39 +0500] [Client 25] 2.0 Get-Printer-Attributes 1
624D [26/Sep/2017:14:08:39 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_LaserJet_3800
625D [26/Sep/2017:14:08:39 +0500] [Client 25] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_LaserJet_3800) from localhost
626D [26/Sep/2017:14:08:39 +0500] [Client 25] Content-Length: 10062
627D [26/Sep/2017:14:08:39 +0500] [Client 25] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
628D [26/Sep/2017:14:08:39 +0500] [Client 25] con->http=0x200f3c40
629D [26/Sep/2017:14:08:39 +0500] [Client 25] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10062, response=0x200af700(IPP_STATE_DATA), pipe_pid=0, file=-1
630D [26/Sep/2017:14:08:39 +0500] [Client 25] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
631D [26/Sep/2017:14:08:39 +0500] [Client 25] bytes=0, http_state=0, data_remaining=10062
632D [26/Sep/2017:14:08:39 +0500] [Client 25] Flushing write buffer.
633D [26/Sep/2017:14:08:39 +0500] [Client 25] New state is HTTP_STATE_WAITING
634D [26/Sep/2017:14:08:39 +0500] [Client 25] Waiting for request.
635D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
636D [26/Sep/2017:14:08:39 +0500] [Client 25] POST /admin/ HTTP/1.1
637D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
638D [26/Sep/2017:14:08:39 +0500] [Client 25] Read: status=200
639D [26/Sep/2017:14:08:39 +0500] [Client 25] No authentication data provided.
640D [26/Sep/2017:14:08:39 +0500] [Client 25] 2.0 CUPS-Add-Modify-Printer 2
641D [26/Sep/2017:14:08:39 +0500] CUPS-Add-Modify-Printer ipp://localhost/printers/HP_Color_LaserJet_3800
642D [26/Sep/2017:14:08:39 +0500] cupsdIsAuthorized: username=""
643D [26/Sep/2017:14:08:39 +0500] [Client 25] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost/printers/HP_Color_LaserJet_3800) from localhost
644D [26/Sep/2017:14:08:39 +0500] [Client 25] cupsdSendHeader: code=401, type="text/html", auth_type=1
645D [26/Sep/2017:14:08:39 +0500] [Client 25] WWW-Authenticate: Basic realm=\"CUPS\", trc=\"y\"
646D [26/Sep/2017:14:08:39 +0500] [Client 26] Accepted from localhost:50037 (IPv4)
647D [26/Sep/2017:14:08:39 +0500] [Client 26] Waiting for request.
648D [26/Sep/2017:14:08:39 +0500] [Client 25] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
649D [26/Sep/2017:14:08:39 +0500] [Client 25] Closing connection.
650D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
651D [26/Sep/2017:14:08:39 +0500] [Client 27] Accepted from localhost:50038 (IPv4)
652D [26/Sep/2017:14:08:39 +0500] [Client 27] Waiting for request.
653D [26/Sep/2017:14:08:39 +0500] [Client 26] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
654D [26/Sep/2017:14:08:39 +0500] [Client 26] Closing connection.
655D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
656D [26/Sep/2017:14:08:39 +0500] [Client 28] Accepted from localhost:50039 (IPv4)
657D [26/Sep/2017:14:08:39 +0500] [Client 28] Waiting for request.
658D [26/Sep/2017:14:08:39 +0500] [Client 27] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
659D [26/Sep/2017:14:08:39 +0500] [Client 27] Closing connection.
660D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
661D [26/Sep/2017:14:08:39 +0500] [Client 28] POST /admin/ HTTP/1.1
662D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
663D [26/Sep/2017:14:08:39 +0500] [Client 28] Read: status=200
664D [26/Sep/2017:14:08:39 +0500] [Client 28] Authorized as root using Local.
665D [26/Sep/2017:14:08:39 +0500] [Client 28] 2.0 CUPS-Add-Modify-Printer 2
666D [26/Sep/2017:14:08:39 +0500] CUPS-Add-Modify-Printer ipp://localhost/printers/HP_Color_LaserJet_3800
667D [26/Sep/2017:14:08:39 +0500] cupsdIsAuthorized: username="root"
668D [26/Sep/2017:14:08:39 +0500] HP_Color_LaserJet_3800 device-uri: Missing resource path.
669I [26/Sep/2017:14:08:39 +0500] Setting HP_Color_LaserJet_3800 device-uri to "socket://192.168.100.16" (was "usb://HP/Color%20LaserJet%203800?serial=CNDBB03809".)
670I [26/Sep/2017:14:08:39 +0500] Setting HP_Color_LaserJet_3800 printer-is-shared to 0 (was 0.)
671I [26/Sep/2017:14:08:39 +0500] Setting HP_Color_LaserJet_3800 printer-state to 3 (was 3.)
672D [26/Sep/2017:14:08:39 +0500] load_ppd: Loading /@unixroot/var/cache/cups/HP_Color_LaserJet_3800.data...
673D [26/Sep/2017:14:08:39 +0500] cupsdRegisterPrinter(p=0x200587e0(HP_Color_LaserJet_3800))
674D [26/Sep/2017:14:08:39 +0500] cupsdMarkDirty(P----)
675D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
676D [26/Sep/2017:14:08:39 +0500] cupsdMarkDirty(--p--)
677D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
678D [26/Sep/2017:14:08:39 +0500] Discarding unused printer-modified event...
679I [26/Sep/2017:14:08:39 +0500] Printer "HP_Color_LaserJet_3800" modified by "root".
680D [26/Sep/2017:14:08:39 +0500] [Client 28] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost/printers/HP_Color_LaserJet_3800) from localhost
681D [26/Sep/2017:14:08:39 +0500] [Client 28] Content-Length: 75
682D [26/Sep/2017:14:08:39 +0500] [Client 28] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
683D [26/Sep/2017:14:08:39 +0500] [Client 28] con->http=0x200fee20
684D [26/Sep/2017:14:08:39 +0500] [Client 28] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x200af7a0(IPP_STATE_DATA), pipe_pid=0, file=-1
685D [26/Sep/2017:14:08:39 +0500] [Client 28] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
686D [26/Sep/2017:14:08:39 +0500] [Client 28] bytes=0, http_state=0, data_remaining=75
687D [26/Sep/2017:14:08:39 +0500] [Client 28] Flushing write buffer.
688D [26/Sep/2017:14:08:39 +0500] [Client 28] New state is HTTP_STATE_WAITING
689D [26/Sep/2017:14:08:39 +0500] [Client 28] Waiting for request.
690D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
691D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: refresh_page=\"5;/admin/?OP=redirect&URL=/printers/HP_Color_LaserJet_3800\"
692D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: TITLE=\"Modify Printer\"
693D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
694D [26/Sep/2017:14:08:39 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
695D [26/Sep/2017:14:08:39 +0500] [Client 23] CGI data ready to be sent.
696D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
697D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=138, file=11
698D [26/Sep/2017:14:08:39 +0500] [Client 23] Waiting for CGI data.
699D [26/Sep/2017:14:08:39 +0500] [Client 23] Script header: Content-Type: text/html;charset=utf-8
700D [26/Sep/2017:14:08:39 +0500] [Client 23] Script header:
701D [26/Sep/2017:14:08:39 +0500] [Client 23] Sending status 200 for CGI.
702D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdSendHeader: code=200, type="(null)", auth_type=0
703D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
704D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=138, file=11
705D [26/Sep/2017:14:08:39 +0500] [Client 23] Waiting for CGI data.
706D [26/Sep/2017:14:08:39 +0500] [Client 23] CGI data ready to be sent.
707D [26/Sep/2017:14:08:39 +0500] [Client 28] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
708D [26/Sep/2017:14:08:39 +0500] [Client 28] Closing connection.
709D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
710D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
711D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=138, file=11
712D [26/Sep/2017:14:08:39 +0500] [Client 23] Waiting for CGI data.
713D [26/Sep/2017:14:08:39 +0500] [Client 23] CGI data ready to be sent.
714D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
715D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=138, file=11
716D [26/Sep/2017:14:08:39 +0500] [Client 23] Waiting for CGI data.
717D [26/Sep/2017:14:08:39 +0500] [Client 23] Sending 0-length chunk.
718D [26/Sep/2017:14:08:39 +0500] [Client 23] Flushing write buffer.
719D [26/Sep/2017:14:08:39 +0500] [Client 23] New state is HTTP_STATE_WAITING
720D [26/Sep/2017:14:08:39 +0500] [Client 23] Waiting for request.
721D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
722D [26/Sep/2017:14:08:39 +0500] PID 138 (/@unixroot/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
723D [26/Sep/2017:14:08:39 +0500] [Client 23] GET /cups.css HTTP/1.1
724D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
725D [26/Sep/2017:14:08:39 +0500] [Client 23] Read: status=200
726D [26/Sep/2017:14:08:39 +0500] [Client 23] No authentication data provided.
727D [26/Sep/2017:14:08:39 +0500] [Client 23] Processing GET /cups.css
728D [26/Sep/2017:14:08:39 +0500] [Client 23] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
729D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdSendHeader: code=200, type="text/css", auth_type=0
730D [26/Sep/2017:14:08:39 +0500] [Client 23] Sending file.
731D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
732D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=10
733D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
734D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=10
735D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
736D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=10
737D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
738D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=10
739D [26/Sep/2017:14:08:39 +0500] [Client 23] con->http=0x200fbdc0
740D [26/Sep/2017:14:08:39 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=10
741D [26/Sep/2017:14:08:39 +0500] [Client 23] Flushing write buffer.
742D [26/Sep/2017:14:08:39 +0500] [Client 23] New state is HTTP_STATE_WAITING
743D [26/Sep/2017:14:08:39 +0500] [Client 23] Waiting for request.
744D [26/Sep/2017:14:08:39 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
745D [26/Sep/2017:14:08:40 +0500] [Client 21] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
746D [26/Sep/2017:14:08:40 +0500] [Client 21] Closing connection.
747D [26/Sep/2017:14:08:40 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
748D [26/Sep/2017:14:08:44 +0500] [Client 23] GET /admin/?OP=redirect&URL=/printers/HP_Color_LaserJet_3800 HTTP/1.1
749D [26/Sep/2017:14:08:44 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
750D [26/Sep/2017:14:08:44 +0500] [Client 23] Read: status=200
751D [26/Sep/2017:14:08:44 +0500] [Client 23] No authentication data provided.
752D [26/Sep/2017:14:08:44 +0500] [Client 23] Processing GET /admin/?OP=redirect&URL=/printers/HP_Color_LaserJet_3800
753D [26/Sep/2017:14:08:44 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/admin.cgi"
754D [26/Sep/2017:14:08:44 +0500] [CGI] argv[1] = "OP=redirect&URL=/printers/HP_Color_LaserJet_3800"
755D [26/Sep/2017:14:08:44 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
756D [26/Sep/2017:14:08:44 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
757D [26/Sep/2017:14:08:44 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
758D [26/Sep/2017:14:08:44 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
759D [26/Sep/2017:14:08:44 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
760D [26/Sep/2017:14:08:44 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
761D [26/Sep/2017:14:08:44 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
762D [26/Sep/2017:14:08:44 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
763D [26/Sep/2017:14:08:44 +0500] [CGI] envp[8] = "HOME=H:\\var\\temp"
764D [26/Sep/2017:14:08:44 +0500] [CGI] envp[9] = "NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
765D [26/Sep/2017:14:08:44 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
766D [26/Sep/2017:14:08:44 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@ARCAOS-01D8CC3"
767D [26/Sep/2017:14:08:44 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
768D [26/Sep/2017:14:08:44 +0500] [CGI] envp[13] = "TMPDIR=H:\\var\\temp"
769D [26/Sep/2017:14:08:44 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
770D [26/Sep/2017:14:08:44 +0500] [CGI] envp[15] = "USER=root"
771D [26/Sep/2017:14:08:44 +0500] [CGI] envp[16] = "UNIXROOT=H:"
772D [26/Sep/2017:14:08:44 +0500] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
773D [26/Sep/2017:14:08:44 +0500] [CGI] envp[18] = "CUPS_SERVER=localhost"
774D [26/Sep/2017:14:08:44 +0500] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
775D [26/Sep/2017:14:08:44 +0500] [CGI] envp[20] = "IPP_PORT=631"
776D [26/Sep/2017:14:08:44 +0500] [CGI] envp[21] = "LANG=en_US.UTF8"
777D [26/Sep/2017:14:08:44 +0500] [CGI] envp[22] = "REDIRECT_STATUS=1"
778D [26/Sep/2017:14:08:44 +0500] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
779D [26/Sep/2017:14:08:44 +0500] [CGI] envp[24] = "SERVER_NAME=localhost"
780D [26/Sep/2017:14:08:44 +0500] [CGI] envp[25] = "SERVER_PORT=631"
781D [26/Sep/2017:14:08:44 +0500] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
782D [26/Sep/2017:14:08:44 +0500] [CGI] envp[27] = "REMOTE_HOST=localhost"
783D [26/Sep/2017:14:08:44 +0500] [CGI] envp[28] = "SCRIPT_NAME=/admin/"
784D [26/Sep/2017:14:08:44 +0500] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/admin/"
785D [26/Sep/2017:14:08:44 +0500] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
786D [26/Sep/2017:14:08:44 +0500] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=ba119267d82f0c1688613ada42d0a797"
787D [26/Sep/2017:14:08:44 +0500] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
788D [26/Sep/2017:14:08:44 +0500] [CGI] envp[33] = "REQUEST_METHOD=GET"
789D [26/Sep/2017:14:08:44 +0500] [CGI] envp[34] = "QUERY_STRING=OP=redirect&URL=/printers/HP_Color_LaserJet_3800"
790D [26/Sep/2017:14:08:44 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/admin.cgi (PID 140)
791I [26/Sep/2017:14:08:44 +0500] [Client 23] Started "/@unixroot/usr/lib/cups/cgi-bin/admin.cgi" (pid=140, file=9)
792D [26/Sep/2017:14:08:44 +0500] [Client 23] Waiting for CGI data.
793D [26/Sep/2017:14:08:44 +0500] [Job 8] Unloading...
794D [26/Sep/2017:14:08:44 +0500] [Client 29] Accepted from localhost:50040 (IPv4)
795D [26/Sep/2017:14:08:44 +0500] [Client 29] Waiting for request.
796D [26/Sep/2017:14:08:44 +0500] [CGI] admin.cgi started...
797D [26/Sep/2017:14:08:44 +0500] [CGI] http=0x20036040
798D [26/Sep/2017:14:08:44 +0500] [CGI] cgiSetVariable: SECTION=\"admin\"
799D [26/Sep/2017:14:08:44 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
800D [26/Sep/2017:14:08:44 +0500] [CGI] org.cups.sid cookie is \"ba119267d82f0c1688613ada42d0a797\"
801D [26/Sep/2017:14:08:44 +0500] [CGI] cgiSetVariable: OP=\"redirect\"
802D [26/Sep/2017:14:08:44 +0500] [CGI] cgiSetVariable: URL=\"/printers/HP_Color_LaserJet_3800\"
803D [26/Sep/2017:14:08:44 +0500] [CGI] redirecting with prefix http://localhost:631!
804D [26/Sep/2017:14:08:44 +0500] [Client 23] CGI data ready to be sent.
805D [26/Sep/2017:14:08:44 +0500] [Client 29] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
806D [26/Sep/2017:14:08:44 +0500] [Client 29] Closing connection.
807D [26/Sep/2017:14:08:44 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
808D [26/Sep/2017:14:08:44 +0500] [Client 23] con->http=0x200fbdc0
809D [26/Sep/2017:14:08:44 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=140, file=9
810D [26/Sep/2017:14:08:44 +0500] [Client 23] Waiting for CGI data.
811D [26/Sep/2017:14:08:44 +0500] [Client 23] Script header: Location: http://localhost:631/printers/HP_Color_LaserJet_3800
812D [26/Sep/2017:14:08:44 +0500] [Client 23] Script header:
813D [26/Sep/2017:14:08:44 +0500] [Client 23] Sending status 303 for CGI.
814D [26/Sep/2017:14:08:44 +0500] [Client 23] cupsdSendHeader: code=303, type="(null)", auth_type=0
815D [26/Sep/2017:14:08:44 +0500] [Client 23] Flushing write buffer.
816D [26/Sep/2017:14:08:44 +0500] [Client 23] New state is HTTP_STATE_WAITING
817D [26/Sep/2017:14:08:44 +0500] [Client 23] Waiting for request.
818D [26/Sep/2017:14:08:44 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
819W [26/Sep/2017:14:08:44 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
820D [26/Sep/2017:14:08:45 +0500] [Client 23] GET /printers/HP_Color_LaserJet_3800 HTTP/1.1
821D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
822D [26/Sep/2017:14:08:45 +0500] [Client 23] Read: status=200
823D [26/Sep/2017:14:08:45 +0500] [Client 23] No authentication data provided.
824D [26/Sep/2017:14:08:45 +0500] [Client 23] Processing GET /printers/HP_Color_LaserJet_3800
825D [26/Sep/2017:14:08:45 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
826D [26/Sep/2017:14:08:45 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
827D [26/Sep/2017:14:08:45 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
828D [26/Sep/2017:14:08:45 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
829D [26/Sep/2017:14:08:45 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
830D [26/Sep/2017:14:08:45 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
831D [26/Sep/2017:14:08:45 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
832D [26/Sep/2017:14:08:45 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
833D [26/Sep/2017:14:08:45 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
834D [26/Sep/2017:14:08:45 +0500] [CGI] envp[8] = "HOME=H:\\var\\temp"
835D [26/Sep/2017:14:08:45 +0500] [CGI] envp[9] = "NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
836D [26/Sep/2017:14:08:45 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
837D [26/Sep/2017:14:08:45 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@ARCAOS-01D8CC3"
838D [26/Sep/2017:14:08:45 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
839D [26/Sep/2017:14:08:45 +0500] [CGI] envp[13] = "TMPDIR=H:\\var\\temp"
840D [26/Sep/2017:14:08:45 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
841D [26/Sep/2017:14:08:45 +0500] [CGI] envp[15] = "USER=root"
842D [26/Sep/2017:14:08:45 +0500] [CGI] envp[16] = "UNIXROOT=H:"
843D [26/Sep/2017:14:08:45 +0500] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
844D [26/Sep/2017:14:08:45 +0500] [CGI] envp[18] = "CUPS_SERVER=localhost"
845D [26/Sep/2017:14:08:45 +0500] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
846D [26/Sep/2017:14:08:45 +0500] [CGI] envp[20] = "IPP_PORT=631"
847D [26/Sep/2017:14:08:45 +0500] [CGI] envp[21] = "LANG=en_US.UTF8"
848D [26/Sep/2017:14:08:45 +0500] [CGI] envp[22] = "REDIRECT_STATUS=1"
849D [26/Sep/2017:14:08:45 +0500] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
850D [26/Sep/2017:14:08:45 +0500] [CGI] envp[24] = "SERVER_NAME=localhost"
851D [26/Sep/2017:14:08:45 +0500] [CGI] envp[25] = "SERVER_PORT=631"
852D [26/Sep/2017:14:08:45 +0500] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
853D [26/Sep/2017:14:08:45 +0500] [CGI] envp[27] = "REMOTE_HOST=localhost"
854D [26/Sep/2017:14:08:45 +0500] [CGI] envp[28] = "SCRIPT_NAME=/printers/HP_Color_LaserJet_3800"
855D [26/Sep/2017:14:08:45 +0500] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_LaserJet_3800"
856D [26/Sep/2017:14:08:45 +0500] [CGI] envp[30] = "PATH_INFO=/HP_Color_LaserJet_3800"
857D [26/Sep/2017:14:08:45 +0500] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
858D [26/Sep/2017:14:08:45 +0500] [CGI] envp[32] = "HTTP_COOKIE=org.cups.sid=ba119267d82f0c1688613ada42d0a797"
859D [26/Sep/2017:14:08:45 +0500] [CGI] envp[33] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
860D [26/Sep/2017:14:08:45 +0500] [CGI] envp[34] = "REQUEST_METHOD=GET"
861D [26/Sep/2017:14:08:45 +0500] [CGI] envp[35] = "QUERY_STRING="
862D [26/Sep/2017:14:08:45 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 142)
863I [26/Sep/2017:14:08:45 +0500] [Client 23] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=142, file=9)
864D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
865D [26/Sep/2017:14:08:45 +0500] PID 140 (/@unixroot/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
866D [26/Sep/2017:14:08:45 +0500] [Client 30] Accepted from localhost:50041 (IPv4)
867D [26/Sep/2017:14:08:45 +0500] [Client 30] Waiting for request.
868D [26/Sep/2017:14:08:45 +0500] [CGI] org.cups.sid cookie is \"ba119267d82f0c1688613ada42d0a797\"
869D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
870D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
871D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
872D [26/Sep/2017:14:08:45 +0500] [Client 30] POST / HTTP/1.1
873D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
874D [26/Sep/2017:14:08:45 +0500] [Client 30] Read: status=200
875D [26/Sep/2017:14:08:45 +0500] [Client 30] No authentication data provided.
876D [26/Sep/2017:14:08:45 +0500] [Client 30] 2.0 CUPS-Get-Default 1
877D [26/Sep/2017:14:08:45 +0500] CUPS-Get-Default
878D [26/Sep/2017:14:08:45 +0500] CUPS-Get-Default client-error-not-found: No default printer.
879D [26/Sep/2017:14:08:45 +0500] [Client 30] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
880D [26/Sep/2017:14:08:45 +0500] [Client 30] Content-Length: 113
881D [26/Sep/2017:14:08:45 +0500] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
882D [26/Sep/2017:14:08:45 +0500] [Client 30] con->http=0x200f0000
883D [26/Sep/2017:14:08:45 +0500] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x200f75c0(IPP_STATE_DATA), pipe_pid=0, file=-1
884D [26/Sep/2017:14:08:45 +0500] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
885D [26/Sep/2017:14:08:45 +0500] [Client 30] bytes=0, http_state=0, data_remaining=113
886D [26/Sep/2017:14:08:45 +0500] [Client 30] Flushing write buffer.
887D [26/Sep/2017:14:08:45 +0500] [Client 30] New state is HTTP_STATE_WAITING
888D [26/Sep/2017:14:08:45 +0500] [Client 30] Waiting for request.
889D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
890D [26/Sep/2017:14:08:45 +0500] [CGI] show_printer(http=0x2003c220, printer=\"HP_Color_LaserJet_3800\")
891D [26/Sep/2017:14:08:45 +0500] [Client 30] POST / HTTP/1.1
892D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
893D [26/Sep/2017:14:08:45 +0500] [Client 30] Read: status=200
894D [26/Sep/2017:14:08:45 +0500] [Client 30] No authentication data provided.
895D [26/Sep/2017:14:08:45 +0500] [Client 30] 2.0 Get-Printer-Attributes 2
896D [26/Sep/2017:14:08:45 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_LaserJet_3800
897D [26/Sep/2017:14:08:45 +0500] [Client 30] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_LaserJet_3800) from localhost
898D [26/Sep/2017:14:08:45 +0500] [Client 30] Content-Length: 730
899D [26/Sep/2017:14:08:45 +0500] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
900D [26/Sep/2017:14:08:45 +0500] [Client 30] con->http=0x200f0000
901D [26/Sep/2017:14:08:45 +0500] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=730, response=0x200f77c0(IPP_STATE_DATA), pipe_pid=0, file=-1
902D [26/Sep/2017:14:08:45 +0500] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
903D [26/Sep/2017:14:08:45 +0500] [Client 30] bytes=0, http_state=0, data_remaining=730
904D [26/Sep/2017:14:08:45 +0500] [Client 30] Flushing write buffer.
905D [26/Sep/2017:14:08:45 +0500] [Client 30] New state is HTTP_STATE_WAITING
906D [26/Sep/2017:14:08:45 +0500] [Client 30] Waiting for request.
907D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
908D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
909D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
910D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
911D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
912D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
913D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"0\"
914D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
915D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
916D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800\"
917D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800\"
918D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_location[0]=\"\"
919D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800\"
920D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
921D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
922D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
923D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.5\"
924D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
925D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
926D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
927D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
928D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
929D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
930D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_LaserJet_3800\"
931D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
932D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
933D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
934D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=142, file=9
935D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
936D [26/Sep/2017:14:08:45 +0500] [Client 23] Script header: Content-Type: text/html;charset=utf-8
937D [26/Sep/2017:14:08:45 +0500] [Client 23] Script header:
938D [26/Sep/2017:14:08:45 +0500] [Client 23] Sending status 200 for CGI.
939D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdSendHeader: code=200, type="(null)", auth_type=0
940D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
941D [26/Sep/2017:14:08:45 +0500] [Client 30] POST / HTTP/1.1
942D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
943D [26/Sep/2017:14:08:45 +0500] [Client 30] Read: status=200
944D [26/Sep/2017:14:08:45 +0500] [Client 30] No authentication data provided.
945D [26/Sep/2017:14:08:45 +0500] [Client 30] 2.0 Get-Jobs 3
946D [26/Sep/2017:14:08:45 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_LaserJet_3800
947D [26/Sep/2017:14:08:45 +0500] [Job 8] Loading attributes...
948D [26/Sep/2017:14:08:45 +0500] [Client 30] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_LaserJet_3800) from localhost
949D [26/Sep/2017:14:08:45 +0500] [Client 30] Content-Length: 433
950D [26/Sep/2017:14:08:45 +0500] [Client 30] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
951D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
952D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
953D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
954D [26/Sep/2017:14:08:45 +0500] [CGI] Regular expression \".*Clean.*\"
955D [26/Sep/2017:14:08:45 +0500] [CGI] matches[0].rm_so=0
956D [26/Sep/2017:14:08:45 +0500] [CGI] matches[1].rm_so=-1
957D [26/Sep/2017:14:08:45 +0500] [Client 30] con->http=0x200f0000
958D [26/Sep/2017:14:08:45 +0500] [Client 30] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=433, response=0x200f8c20(IPP_STATE_DATA), pipe_pid=0, file=-1
959D [26/Sep/2017:14:08:45 +0500] [Client 30] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
960D [26/Sep/2017:14:08:45 +0500] [Client 30] bytes=0, http_state=0, data_remaining=433
961D [26/Sep/2017:14:08:45 +0500] [Client 30] Flushing write buffer.
962D [26/Sep/2017:14:08:45 +0500] [Client 30] New state is HTTP_STATE_WAITING
963D [26/Sep/2017:14:08:45 +0500] [Client 30] Waiting for request.
964D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
965D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
966D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
967D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
968D [26/Sep/2017:14:08:45 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
969D [26/Sep/2017:14:08:45 +0500] [CGI] matches[0].rm_so=0
970D [26/Sep/2017:14:08:45 +0500] [CGI] matches[1].rm_so=-1
971D [26/Sep/2017:14:08:45 +0500] [CGI] cgiClearVariables called.
972D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
973D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
974D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: TOTAL=\"1\"
975D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
976D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
977D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_preserved[0]=\"1\"
978D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_printer_name[0]=\"HP_Color_LaserJet_3800\"
979D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/HP_Color_LaserJet_3800\"
980D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_name[0]=\"Test Page\"
981D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\"
982D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\"
983D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: time_at_creation[0]=\"Tue Sep 26 13:56:19 EDT 2017\"
984D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: time_at_processing[0]=\"Tue Sep 26 13:56:19 EDT 2017\"
985D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_id[0]=\"8\"
986D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_state[0]=\"6\"
987D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_media_sheets_completed[0]=\"0\"
988D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\"
989D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\"
990D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
991D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_LaserJet_3800\"
992D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_LaserJet_3800\"
993D [26/Sep/2017:14:08:45 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_LaserJet_3800\"
994D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
995D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
996D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
997D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
998D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
999D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1000D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
1001D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
1002D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
1003D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1004D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
1005D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
1006D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
1007D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1008D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
1009D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
1010D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
1011D [26/Sep/2017:14:08:45 +0500] [Client 30] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1012D [26/Sep/2017:14:08:45 +0500] [Client 30] Closing connection.
1013D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1014D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1015D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
1016D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
1017D [26/Sep/2017:14:08:45 +0500] PID 142 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
1018D [26/Sep/2017:14:08:45 +0500] [Client 23] CGI data ready to be sent.
1019D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1020D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=142, file=9
1021D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for CGI data.
1022D [26/Sep/2017:14:08:45 +0500] [Client 23] Sending 0-length chunk.
1023D [26/Sep/2017:14:08:45 +0500] [Client 23] Flushing write buffer.
1024D [26/Sep/2017:14:08:45 +0500] [Client 23] New state is HTTP_STATE_WAITING
1025D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for request.
1026D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1027D [26/Sep/2017:14:08:45 +0500] [Client 23] GET /cups.css HTTP/1.1
1028D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1029D [26/Sep/2017:14:08:45 +0500] [Client 23] Read: status=200
1030D [26/Sep/2017:14:08:45 +0500] [Client 23] No authentication data provided.
1031D [26/Sep/2017:14:08:45 +0500] [Client 23] Processing GET /cups.css
1032D [26/Sep/2017:14:08:45 +0500] [Client 23] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
1033D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdSendHeader: code=200, type="text/css", auth_type=0
1034D [26/Sep/2017:14:08:45 +0500] [Client 23] Sending file.
1035D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1036D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1037D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1038D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1039D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1040D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1041D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1042D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1043D [26/Sep/2017:14:08:45 +0500] [Client 23] con->http=0x200fbdc0
1044D [26/Sep/2017:14:08:45 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1045D [26/Sep/2017:14:08:45 +0500] [Client 23] Flushing write buffer.
1046D [26/Sep/2017:14:08:45 +0500] [Client 23] New state is HTTP_STATE_WAITING
1047D [26/Sep/2017:14:08:45 +0500] [Client 23] Waiting for request.
1048D [26/Sep/2017:14:08:45 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1049D [26/Sep/2017:14:08:48 +0500] [Client 23] POST /jobs/ HTTP/1.1
1050D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1051D [26/Sep/2017:14:08:48 +0500] [Client 23] Read: status=200
1052D [26/Sep/2017:14:08:48 +0500] [Client 23] No authentication data provided.
1053D [26/Sep/2017:14:08:48 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/jobs.cgi"
1054D [26/Sep/2017:14:08:48 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
1055D [26/Sep/2017:14:08:48 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
1056D [26/Sep/2017:14:08:48 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
1057D [26/Sep/2017:14:08:48 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
1058D [26/Sep/2017:14:08:48 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
1059D [26/Sep/2017:14:08:48 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
1060D [26/Sep/2017:14:08:48 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
1061D [26/Sep/2017:14:08:48 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
1062D [26/Sep/2017:14:08:48 +0500] [CGI] envp[8] = "HOME=H:\\var\\temp"
1063D [26/Sep/2017:14:08:48 +0500] [CGI] envp[9] = "NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
1064D [26/Sep/2017:14:08:48 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
1065D [26/Sep/2017:14:08:48 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@ARCAOS-01D8CC3"
1066D [26/Sep/2017:14:08:48 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
1067D [26/Sep/2017:14:08:48 +0500] [CGI] envp[13] = "TMPDIR=H:\\var\\temp"
1068D [26/Sep/2017:14:08:48 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
1069D [26/Sep/2017:14:08:48 +0500] [CGI] envp[15] = "USER=root"
1070D [26/Sep/2017:14:08:48 +0500] [CGI] envp[16] = "UNIXROOT=H:"
1071D [26/Sep/2017:14:08:48 +0500] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
1072D [26/Sep/2017:14:08:48 +0500] [CGI] envp[18] = "CUPS_SERVER=localhost"
1073D [26/Sep/2017:14:08:48 +0500] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
1074D [26/Sep/2017:14:08:48 +0500] [CGI] envp[20] = "IPP_PORT=631"
1075D [26/Sep/2017:14:08:48 +0500] [CGI] envp[21] = "LANG=en_US.UTF8"
1076D [26/Sep/2017:14:08:48 +0500] [CGI] envp[22] = "REDIRECT_STATUS=1"
1077D [26/Sep/2017:14:08:48 +0500] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
1078D [26/Sep/2017:14:08:48 +0500] [CGI] envp[24] = "SERVER_NAME=localhost"
1079D [26/Sep/2017:14:08:48 +0500] [CGI] envp[25] = "SERVER_PORT=631"
1080D [26/Sep/2017:14:08:48 +0500] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
1081D [26/Sep/2017:14:08:48 +0500] [CGI] envp[27] = "REMOTE_HOST=localhost"
1082D [26/Sep/2017:14:08:48 +0500] [CGI] envp[28] = "SCRIPT_NAME=/jobs/"
1083D [26/Sep/2017:14:08:48 +0500] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/jobs/"
1084D [26/Sep/2017:14:08:48 +0500] [CGI] envp[30] = "SERVER_PROTOCOL=HTTP/1.1"
1085D [26/Sep/2017:14:08:48 +0500] [CGI] envp[31] = "HTTP_COOKIE=org.cups.sid=ba119267d82f0c1688613ada42d0a797"
1086D [26/Sep/2017:14:08:48 +0500] [CGI] envp[32] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
1087D [26/Sep/2017:14:08:48 +0500] [CGI] envp[33] = "HTTP_REFERER=http://localhost:631/printers/HP_Color_LaserJet_3800"
1088D [26/Sep/2017:14:08:48 +0500] [CGI] envp[34] = "REQUEST_METHOD=POST"
1089D [26/Sep/2017:14:08:48 +0500] [CGI] envp[35] = "CONTENT_LENGTH=121"
1090D [26/Sep/2017:14:08:48 +0500] [CGI] envp[36] = "CONTENT_TYPE=application/x-www-form-urlencoded"
1091D [26/Sep/2017:14:08:48 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/jobs.cgi (PID 144)
1092I [26/Sep/2017:14:08:48 +0500] [Client 23] Started "/@unixroot/usr/lib/cups/cgi-bin/jobs.cgi" (pid=144, file=10)
1093D [26/Sep/2017:14:08:48 +0500] [Client 23] Waiting for CGI data.
1094D [26/Sep/2017:14:08:48 +0500] [Client 31] Accepted from localhost:50042 (IPv4)
1095D [26/Sep/2017:14:08:48 +0500] [Client 31] Waiting for request.
1096D [26/Sep/2017:14:08:48 +0500] [CGI] org.cups.sid cookie is \"ba119267d82f0c1688613ada42d0a797\"
1097D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: org.cups.sid=\"ba119267d82f0c1688613ada42d0a797\"
1098D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: OP=\"cancel-job\"
1099D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: job_id=\"8\"
1100D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: job_printer_uri=\"/printers/HP_Color_LaserJet_3800\"
1101D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: SECTION=\"jobs\"
1102D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
1103D [26/Sep/2017:14:08:48 +0500] [Client 31] POST /jobs HTTP/1.1
1104D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1105D [26/Sep/2017:14:08:48 +0500] [Client 31] Read: status=200
1106D [26/Sep/2017:14:08:48 +0500] [Client 31] No authentication data provided.
1107D [26/Sep/2017:14:08:48 +0500] [Client 31] 2.0 Cancel-Job 1
1108D [26/Sep/2017:14:08:48 +0500] Cancel-Job ipp://localhost/jobs/8
1109D [26/Sep/2017:14:08:48 +0500] cupsdIsAuthorized: requesting-user-name="guest"
1110D [26/Sep/2017:14:08:48 +0500] [Client 31] Returning HTTP Unauthorized for Cancel-Job (ipp://localhost/jobs/8) from localhost
1111D [26/Sep/2017:14:08:48 +0500] [Client 31] cupsdSendHeader: code=401, type="text/html", auth_type=0
1112D [26/Sep/2017:14:08:48 +0500] [Client 31] WWW-Authenticate: Basic realm=\"CUPS\", trc=\"y\"
1113D [26/Sep/2017:14:08:48 +0500] [Client 32] Accepted from localhost:50043 (IPv4)
1114D [26/Sep/2017:14:08:48 +0500] [Client 32] Waiting for request.
1115D [26/Sep/2017:14:08:48 +0500] [Client 31] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1116D [26/Sep/2017:14:08:48 +0500] [Client 31] Closing connection.
1117D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1118D [26/Sep/2017:14:08:48 +0500] [Client 33] Accepted from localhost:50044 (IPv4)
1119D [26/Sep/2017:14:08:48 +0500] [Client 33] Waiting for request.
1120D [26/Sep/2017:14:08:48 +0500] [Client 32] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1121D [26/Sep/2017:14:08:48 +0500] [Client 32] Closing connection.
1122D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1123D [26/Sep/2017:14:08:48 +0500] [Client 34] Accepted from localhost:50045 (IPv4)
1124D [26/Sep/2017:14:08:48 +0500] [Client 34] Waiting for request.
1125D [26/Sep/2017:14:08:48 +0500] [Client 33] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1126D [26/Sep/2017:14:08:48 +0500] [Client 33] Closing connection.
1127D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1128D [26/Sep/2017:14:08:48 +0500] [Client 34] POST /jobs HTTP/1.1
1129D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1130D [26/Sep/2017:14:08:48 +0500] [Client 34] Read: status=200
1131D [26/Sep/2017:14:08:48 +0500] [Client 34] Authorized as root using Local.
1132D [26/Sep/2017:14:08:48 +0500] [Client 34] 2.0 Cancel-Job 1
1133D [26/Sep/2017:14:08:48 +0500] Cancel-Job ipp://localhost/jobs/8
1134D [26/Sep/2017:14:08:48 +0500] cupsdIsAuthorized: username="root"
1135D [26/Sep/2017:14:08:48 +0500] [Job 8] time-at-completed=1506449328
1136D [26/Sep/2017:14:08:48 +0500] Discarding unused job-completed event...
1137I [26/Sep/2017:14:08:48 +0500] [Job 8] Job canceled by \"root\"
1138I [26/Sep/2017:14:08:48 +0500] Expiring subscriptions...
1139D [26/Sep/2017:14:08:48 +0500] cupsdMarkDirty(---J-)
1140D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1141D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1142I [26/Sep/2017:14:08:48 +0500] [Job 8] Canceled by "root".
1143D [26/Sep/2017:14:08:48 +0500] [Client 34] Returning IPP successful-ok for Cancel-Job (ipp://localhost/jobs/8) from localhost
1144D [26/Sep/2017:14:08:48 +0500] [Client 34] Content-Length: 75
1145D [26/Sep/2017:14:08:48 +0500] [Client 34] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1146D [26/Sep/2017:14:08:48 +0500] [Job 8] Removing document files.
1147D [26/Sep/2017:14:08:48 +0500] cupsdMarkDirty(---J-)
1148D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1149D [26/Sep/2017:14:08:48 +0500] [Client 34] con->http=0x200fee20
1150D [26/Sep/2017:14:08:48 +0500] [Client 34] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x200f31c0(IPP_STATE_DATA), pipe_pid=0, file=-1
1151D [26/Sep/2017:14:08:48 +0500] [Client 34] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1152D [26/Sep/2017:14:08:48 +0500] [Client 34] bytes=0, http_state=0, data_remaining=75
1153D [26/Sep/2017:14:08:48 +0500] [Client 34] Flushing write buffer.
1154D [26/Sep/2017:14:08:48 +0500] [Client 34] New state is HTTP_STATE_WAITING
1155D [26/Sep/2017:14:08:48 +0500] [Client 34] Waiting for request.
1156D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1157D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: refresh_page=\"5;URL=http://localhost:631/printers/HP_Color_LaserJet_3800\"
1158D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: TITLE=\"Jobs\"
1159D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1160D [26/Sep/2017:14:08:48 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1161D [26/Sep/2017:14:08:48 +0500] [Client 23] CGI data ready to be sent.
1162D [26/Sep/2017:14:08:48 +0500] [Client 34] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1163D [26/Sep/2017:14:08:48 +0500] [Client 34] Closing connection.
1164D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1165D [26/Sep/2017:14:08:48 +0500] [Client 23] con->http=0x200fbdc0
1166D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=144, file=10
1167D [26/Sep/2017:14:08:48 +0500] [Client 23] Waiting for CGI data.
1168D [26/Sep/2017:14:08:48 +0500] [Client 23] Script header: Content-Type: text/html;charset=utf-8
1169D [26/Sep/2017:14:08:48 +0500] [Client 23] Script header:
1170D [26/Sep/2017:14:08:48 +0500] [Client 23] Sending status 200 for CGI.
1171D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdSendHeader: code=200, type="(null)", auth_type=0
1172D [26/Sep/2017:14:08:48 +0500] [Client 23] CGI data ready to be sent.
1173D [26/Sep/2017:14:08:48 +0500] [Client 23] con->http=0x200fbdc0
1174D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=144, file=10
1175D [26/Sep/2017:14:08:48 +0500] [Client 23] Waiting for CGI data.
1176D [26/Sep/2017:14:08:48 +0500] [Client 23] Sending 0-length chunk.
1177D [26/Sep/2017:14:08:48 +0500] [Client 23] Flushing write buffer.
1178D [26/Sep/2017:14:08:48 +0500] [Client 23] New state is HTTP_STATE_WAITING
1179D [26/Sep/2017:14:08:48 +0500] [Client 23] Waiting for request.
1180D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1181D [26/Sep/2017:14:08:48 +0500] PID 144 (/@unixroot/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors.
1182D [26/Sep/2017:14:08:48 +0500] [Client 23] GET /cups.css HTTP/1.1
1183D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1184D [26/Sep/2017:14:08:48 +0500] [Client 23] Read: status=200
1185D [26/Sep/2017:14:08:48 +0500] [Client 23] No authentication data provided.
1186D [26/Sep/2017:14:08:48 +0500] [Client 23] Processing GET /cups.css
1187D [26/Sep/2017:14:08:48 +0500] [Client 23] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
1188D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdSendHeader: code=200, type="text/css", auth_type=0
1189D [26/Sep/2017:14:08:48 +0500] [Client 23] Sending file.
1190D [26/Sep/2017:14:08:48 +0500] [Client 23] con->http=0x200fbdc0
1191D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1192D [26/Sep/2017:14:08:48 +0500] [Client 23] con->http=0x200fbdc0
1193D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1194D [26/Sep/2017:14:08:48 +0500] [Client 23] con->http=0x200fbdc0
1195D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1196D [26/Sep/2017:14:08:48 +0500] [Client 23] con->http=0x200fbdc0
1197D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1198D [26/Sep/2017:14:08:48 +0500] [Client 23] con->http=0x200fbdc0
1199D [26/Sep/2017:14:08:48 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1200D [26/Sep/2017:14:08:48 +0500] [Client 23] Flushing write buffer.
1201D [26/Sep/2017:14:08:48 +0500] [Client 23] New state is HTTP_STATE_WAITING
1202D [26/Sep/2017:14:08:48 +0500] [Client 23] Waiting for request.
1203D [26/Sep/2017:14:08:48 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1204D [26/Sep/2017:14:08:53 +0500] [Client 23] GET /printers/HP_Color_LaserJet_3800 HTTP/1.1
1205D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1206D [26/Sep/2017:14:08:53 +0500] [Client 23] Read: status=200
1207D [26/Sep/2017:14:08:53 +0500] [Client 23] No authentication data provided.
1208D [26/Sep/2017:14:08:53 +0500] [Client 23] Processing GET /printers/HP_Color_LaserJet_3800
1209D [26/Sep/2017:14:08:53 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
1210D [26/Sep/2017:14:08:53 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
1211D [26/Sep/2017:14:08:53 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
1212D [26/Sep/2017:14:08:53 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
1213D [26/Sep/2017:14:08:53 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
1214D [26/Sep/2017:14:08:53 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
1215D [26/Sep/2017:14:08:53 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
1216D [26/Sep/2017:14:08:53 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
1217D [26/Sep/2017:14:08:53 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
1218D [26/Sep/2017:14:08:53 +0500] [CGI] envp[8] = "HOME=H:\\var\\temp"
1219D [26/Sep/2017:14:08:53 +0500] [CGI] envp[9] = "NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
1220D [26/Sep/2017:14:08:53 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
1221D [26/Sep/2017:14:08:53 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@ARCAOS-01D8CC3"
1222D [26/Sep/2017:14:08:53 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
1223D [26/Sep/2017:14:08:53 +0500] [CGI] envp[13] = "TMPDIR=H:\\var\\temp"
1224D [26/Sep/2017:14:08:53 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
1225D [26/Sep/2017:14:08:53 +0500] [CGI] envp[15] = "USER=root"
1226D [26/Sep/2017:14:08:53 +0500] [CGI] envp[16] = "UNIXROOT=H:"
1227D [26/Sep/2017:14:08:53 +0500] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
1228D [26/Sep/2017:14:08:53 +0500] [CGI] envp[18] = "CUPS_SERVER=localhost"
1229D [26/Sep/2017:14:08:53 +0500] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
1230D [26/Sep/2017:14:08:53 +0500] [CGI] envp[20] = "IPP_PORT=631"
1231D [26/Sep/2017:14:08:53 +0500] [CGI] envp[21] = "LANG=en_US.UTF8"
1232D [26/Sep/2017:14:08:53 +0500] [CGI] envp[22] = "REDIRECT_STATUS=1"
1233D [26/Sep/2017:14:08:53 +0500] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
1234D [26/Sep/2017:14:08:53 +0500] [CGI] envp[24] = "SERVER_NAME=localhost"
1235D [26/Sep/2017:14:08:53 +0500] [CGI] envp[25] = "SERVER_PORT=631"
1236D [26/Sep/2017:14:08:53 +0500] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
1237D [26/Sep/2017:14:08:53 +0500] [CGI] envp[27] = "REMOTE_HOST=localhost"
1238D [26/Sep/2017:14:08:53 +0500] [CGI] envp[28] = "SCRIPT_NAME=/printers/HP_Color_LaserJet_3800"
1239D [26/Sep/2017:14:08:53 +0500] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_LaserJet_3800"
1240D [26/Sep/2017:14:08:53 +0500] [CGI] envp[30] = "PATH_INFO=/HP_Color_LaserJet_3800"
1241D [26/Sep/2017:14:08:53 +0500] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
1242D [26/Sep/2017:14:08:53 +0500] [CGI] envp[32] = "HTTP_COOKIE=org.cups.sid=ba119267d82f0c1688613ada42d0a797"
1243D [26/Sep/2017:14:08:53 +0500] [CGI] envp[33] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
1244D [26/Sep/2017:14:08:53 +0500] [CGI] envp[34] = "REQUEST_METHOD=GET"
1245D [26/Sep/2017:14:08:53 +0500] [CGI] envp[35] = "QUERY_STRING="
1246D [26/Sep/2017:14:08:53 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 146)
1247I [26/Sep/2017:14:08:53 +0500] [Client 23] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=146, file=9)
1248D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1249D [26/Sep/2017:14:08:53 +0500] [CGI] org.cups.sid cookie is \"ba119267d82f0c1688613ada42d0a797\"
1250D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
1251D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
1252D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
1253D [26/Sep/2017:14:08:53 +0500] [Client 35] Accepted from localhost:50046 (IPv4)
1254D [26/Sep/2017:14:08:53 +0500] [Client 35] Waiting for request.
1255D [26/Sep/2017:14:08:53 +0500] [Client 35] POST / HTTP/1.1
1256D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1257D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=200
1258D [26/Sep/2017:14:08:53 +0500] [Client 35] No authentication data provided.
1259D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=100
1260D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=100
1261D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=100
1262D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=100
1263D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=100
1264D [26/Sep/2017:14:08:53 +0500] [Client 35] 2.0 CUPS-Get-Default 1
1265D [26/Sep/2017:14:08:53 +0500] CUPS-Get-Default
1266D [26/Sep/2017:14:08:53 +0500] CUPS-Get-Default client-error-not-found: No default printer.
1267D [26/Sep/2017:14:08:53 +0500] [Client 35] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
1268D [26/Sep/2017:14:08:53 +0500] [Client 35] Content-Length: 113
1269D [26/Sep/2017:14:08:53 +0500] [Client 35] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1270D [26/Sep/2017:14:08:53 +0500] [Client 35] con->http=0x200fee20
1271D [26/Sep/2017:14:08:53 +0500] [Client 35] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x200af6e0(IPP_STATE_DATA), pipe_pid=0, file=-1
1272D [26/Sep/2017:14:08:53 +0500] [Client 35] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1273D [26/Sep/2017:14:08:53 +0500] [Client 35] bytes=0, http_state=0, data_remaining=113
1274D [26/Sep/2017:14:08:53 +0500] [Client 35] Flushing write buffer.
1275D [26/Sep/2017:14:08:53 +0500] [Client 35] New state is HTTP_STATE_WAITING
1276D [26/Sep/2017:14:08:53 +0500] [Client 35] Waiting for request.
1277D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1278D [26/Sep/2017:14:08:53 +0500] [CGI] show_printer(http=0x2003c220, printer=\"HP_Color_LaserJet_3800\")
1279D [26/Sep/2017:14:08:53 +0500] [Client 35] POST / HTTP/1.1
1280D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1281D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=200
1282D [26/Sep/2017:14:08:53 +0500] [Client 35] No authentication data provided.
1283D [26/Sep/2017:14:08:53 +0500] [Client 35] 2.0 Get-Printer-Attributes 2
1284D [26/Sep/2017:14:08:53 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_LaserJet_3800
1285D [26/Sep/2017:14:08:53 +0500] [Client 35] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_LaserJet_3800) from localhost
1286D [26/Sep/2017:14:08:53 +0500] [Client 35] Content-Length: 730
1287D [26/Sep/2017:14:08:53 +0500] [Client 35] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1288D [26/Sep/2017:14:08:53 +0500] [Client 35] con->http=0x200fee20
1289D [26/Sep/2017:14:08:53 +0500] [Client 35] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=730, response=0x20059a80(IPP_STATE_DATA), pipe_pid=0, file=-1
1290D [26/Sep/2017:14:08:53 +0500] [Client 35] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1291D [26/Sep/2017:14:08:53 +0500] [Client 35] bytes=0, http_state=0, data_remaining=730
1292D [26/Sep/2017:14:08:53 +0500] [Client 35] Flushing write buffer.
1293D [26/Sep/2017:14:08:53 +0500] [Client 35] New state is HTTP_STATE_WAITING
1294D [26/Sep/2017:14:08:53 +0500] [Client 35] Waiting for request.
1295D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1296D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1297D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1298D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1299D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1300D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
1301D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"0\"
1302D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
1303D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
1304D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800\"
1305D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800\"
1306D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_location[0]=\"\"
1307D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800\"
1308D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
1309D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
1310D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
1311D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.5\"
1312D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
1313D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
1314D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
1315D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
1316D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
1317D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_LaserJet_3800\"
1318D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1319D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1320D [26/Sep/2017:14:08:53 +0500] [Client 23] CGI data ready to be sent.
1321D [26/Sep/2017:14:08:53 +0500] [Client 35] POST / HTTP/1.1
1322D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1323D [26/Sep/2017:14:08:53 +0500] [Client 35] Read: status=200
1324D [26/Sep/2017:14:08:53 +0500] [Client 35] No authentication data provided.
1325D [26/Sep/2017:14:08:53 +0500] [Client 35] 2.0 Get-Jobs 3
1326D [26/Sep/2017:14:08:53 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_LaserJet_3800
1327D [26/Sep/2017:14:08:53 +0500] [Client 35] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_LaserJet_3800) from localhost
1328D [26/Sep/2017:14:08:53 +0500] [Client 35] Content-Length: 75
1329D [26/Sep/2017:14:08:53 +0500] [Client 35] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1330D [26/Sep/2017:14:08:53 +0500] [Client 35] con->http=0x200fee20
1331D [26/Sep/2017:14:08:53 +0500] [Client 35] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x20060f40(IPP_STATE_DATA), pipe_pid=0, file=-1
1332D [26/Sep/2017:14:08:53 +0500] [Client 35] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1333D [26/Sep/2017:14:08:53 +0500] [Client 35] bytes=0, http_state=0, data_remaining=75
1334D [26/Sep/2017:14:08:53 +0500] [Client 35] Flushing write buffer.
1335D [26/Sep/2017:14:08:53 +0500] [Client 35] New state is HTTP_STATE_WAITING
1336D [26/Sep/2017:14:08:53 +0500] [Client 35] Waiting for request.
1337D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1338D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1339D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=146, file=9
1340D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1341D [26/Sep/2017:14:08:53 +0500] [Client 23] Script header: Content-Type: text/html;charset=utf-8
1342D [26/Sep/2017:14:08:53 +0500] [Client 23] Script header:
1343D [26/Sep/2017:14:08:53 +0500] [Client 23] Sending status 200 for CGI.
1344D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdSendHeader: code=200, type="(null)", auth_type=0
1345D [26/Sep/2017:14:08:53 +0500] [CGI] Regular expression \".*Clean.*\"
1346D [26/Sep/2017:14:08:53 +0500] [CGI] matches[0].rm_so=0
1347D [26/Sep/2017:14:08:53 +0500] [CGI] matches[1].rm_so=-1
1348D [26/Sep/2017:14:08:53 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
1349D [26/Sep/2017:14:08:53 +0500] [CGI] matches[0].rm_so=0
1350D [26/Sep/2017:14:08:53 +0500] [CGI] matches[1].rm_so=-1
1351D [26/Sep/2017:14:08:53 +0500] [Client 23] CGI data ready to be sent.
1352D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1353D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=146, file=9
1354D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1355D [26/Sep/2017:14:08:53 +0500] [Client 23] CGI data ready to be sent.
1356D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1357D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=146, file=9
1358D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1359D [26/Sep/2017:14:08:53 +0500] [CGI] cgiClearVariables called.
1360D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
1361D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
1362D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: TOTAL=\"0\"
1363D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
1364D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_LaserJet_3800\"
1365D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_LaserJet_3800\"
1366D [26/Sep/2017:14:08:53 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_LaserJet_3800\"
1367D [26/Sep/2017:14:08:53 +0500] [Client 23] CGI data ready to be sent.
1368D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1369D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=146, file=9
1370D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1371D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1372D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=146, file=9
1373D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1374D [26/Sep/2017:14:08:53 +0500] [Client 23] CGI data ready to be sent.
1375D [26/Sep/2017:14:08:53 +0500] [Client 35] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1376D [26/Sep/2017:14:08:53 +0500] [Client 35] Closing connection.
1377D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1378D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1379D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=146, file=9
1380D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1381D [26/Sep/2017:14:08:53 +0500] [Client 23] CGI data ready to be sent.
1382D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1383D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=146, file=9
1384D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for CGI data.
1385D [26/Sep/2017:14:08:53 +0500] [Client 23] Sending 0-length chunk.
1386D [26/Sep/2017:14:08:53 +0500] [Client 23] Flushing write buffer.
1387D [26/Sep/2017:14:08:53 +0500] [Client 23] New state is HTTP_STATE_WAITING
1388D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for request.
1389D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1390D [26/Sep/2017:14:08:53 +0500] PID 146 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
1391D [26/Sep/2017:14:08:53 +0500] [Client 23] GET /cups.css HTTP/1.1
1392D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1393D [26/Sep/2017:14:08:53 +0500] [Client 23] Read: status=200
1394D [26/Sep/2017:14:08:53 +0500] [Client 23] No authentication data provided.
1395D [26/Sep/2017:14:08:53 +0500] [Client 23] Processing GET /cups.css
1396D [26/Sep/2017:14:08:53 +0500] [Client 23] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
1397D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdSendHeader: code=200, type="text/css", auth_type=0
1398D [26/Sep/2017:14:08:53 +0500] [Client 23] Sending file.
1399D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1400D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1401D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1402D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1403D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1404D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1405D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1406D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1407D [26/Sep/2017:14:08:53 +0500] [Client 23] con->http=0x200fbdc0
1408D [26/Sep/2017:14:08:53 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1409D [26/Sep/2017:14:08:53 +0500] [Client 23] Flushing write buffer.
1410D [26/Sep/2017:14:08:53 +0500] [Client 23] New state is HTTP_STATE_WAITING
1411D [26/Sep/2017:14:08:53 +0500] [Client 23] Waiting for request.
1412D [26/Sep/2017:14:08:53 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1413D [26/Sep/2017:14:08:57 +0500] [Client 23] POST /printers/HP_Color_LaserJet_3800 HTTP/1.1
1414D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1415D [26/Sep/2017:14:08:57 +0500] [Client 23] Read: status=200
1416D [26/Sep/2017:14:08:57 +0500] [Client 23] No authentication data provided.
1417D [26/Sep/2017:14:08:57 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
1418D [26/Sep/2017:14:08:57 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
1419D [26/Sep/2017:14:08:57 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
1420D [26/Sep/2017:14:08:57 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
1421D [26/Sep/2017:14:08:57 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
1422D [26/Sep/2017:14:08:57 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
1423D [26/Sep/2017:14:08:57 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
1424D [26/Sep/2017:14:08:57 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
1425D [26/Sep/2017:14:08:57 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
1426D [26/Sep/2017:14:08:57 +0500] [CGI] envp[8] = "HOME=H:\\var\\temp"
1427D [26/Sep/2017:14:08:57 +0500] [CGI] envp[9] = "NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
1428D [26/Sep/2017:14:08:57 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
1429D [26/Sep/2017:14:08:57 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@ARCAOS-01D8CC3"
1430D [26/Sep/2017:14:08:57 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
1431D [26/Sep/2017:14:08:57 +0500] [CGI] envp[13] = "TMPDIR=H:\\var\\temp"
1432D [26/Sep/2017:14:08:57 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
1433D [26/Sep/2017:14:08:57 +0500] [CGI] envp[15] = "USER=root"
1434D [26/Sep/2017:14:08:57 +0500] [CGI] envp[16] = "UNIXROOT=H:"
1435D [26/Sep/2017:14:08:57 +0500] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
1436D [26/Sep/2017:14:08:57 +0500] [CGI] envp[18] = "CUPS_SERVER=localhost"
1437D [26/Sep/2017:14:08:57 +0500] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
1438D [26/Sep/2017:14:08:57 +0500] [CGI] envp[20] = "IPP_PORT=631"
1439D [26/Sep/2017:14:08:57 +0500] [CGI] envp[21] = "LANG=en_US.UTF8"
1440D [26/Sep/2017:14:08:57 +0500] [CGI] envp[22] = "REDIRECT_STATUS=1"
1441D [26/Sep/2017:14:08:57 +0500] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
1442D [26/Sep/2017:14:08:57 +0500] [CGI] envp[24] = "SERVER_NAME=localhost"
1443D [26/Sep/2017:14:08:57 +0500] [CGI] envp[25] = "SERVER_PORT=631"
1444D [26/Sep/2017:14:08:57 +0500] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
1445D [26/Sep/2017:14:08:57 +0500] [CGI] envp[27] = "REMOTE_HOST=localhost"
1446D [26/Sep/2017:14:08:57 +0500] [CGI] envp[28] = "SCRIPT_NAME=/printers/HP_Color_LaserJet_3800"
1447D [26/Sep/2017:14:08:57 +0500] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_LaserJet_3800"
1448D [26/Sep/2017:14:08:57 +0500] [CGI] envp[30] = "PATH_INFO=/HP_Color_LaserJet_3800"
1449D [26/Sep/2017:14:08:57 +0500] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
1450D [26/Sep/2017:14:08:57 +0500] [CGI] envp[32] = "HTTP_COOKIE=org.cups.sid=ba119267d82f0c1688613ada42d0a797"
1451D [26/Sep/2017:14:08:57 +0500] [CGI] envp[33] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
1452D [26/Sep/2017:14:08:57 +0500] [CGI] envp[34] = "HTTP_REFERER=http://localhost:631/printers/HP_Color_LaserJet_3800"
1453D [26/Sep/2017:14:08:57 +0500] [CGI] envp[35] = "REQUEST_METHOD=POST"
1454D [26/Sep/2017:14:08:57 +0500] [CGI] envp[36] = "CONTENT_LENGTH=64"
1455D [26/Sep/2017:14:08:57 +0500] [CGI] envp[37] = "CONTENT_TYPE=application/x-www-form-urlencoded"
1456D [26/Sep/2017:14:08:57 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 148)
1457I [26/Sep/2017:14:08:57 +0500] [Client 23] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=148, file=10)
1458D [26/Sep/2017:14:08:57 +0500] [Client 23] Waiting for CGI data.
1459D [26/Sep/2017:14:08:57 +0500] [Client 36] Accepted from localhost:50047 (IPv4)
1460D [26/Sep/2017:14:08:57 +0500] [Client 36] Waiting for request.
1461D [26/Sep/2017:14:08:57 +0500] [CGI] org.cups.sid cookie is \"ba119267d82f0c1688613ada42d0a797\"
1462D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: org.cups.sid=\"ba119267d82f0c1688613ada42d0a797\"
1463D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: OP=\"print-test-page\"
1464D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
1465D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
1466D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
1467D [26/Sep/2017:14:08:57 +0500] [Client 36] POST /printers/HP_Color_LaserJet_3800 HTTP/1.1
1468D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1469D [26/Sep/2017:14:08:57 +0500] [Client 36] Read: status=200
1470D [26/Sep/2017:14:08:57 +0500] [Client 36] No authentication data provided.
1471D [26/Sep/2017:14:08:57 +0500] [Client 36] 2.0 Print-Job 1
1472D [26/Sep/2017:14:08:57 +0500] Print-Job ipp://localhost:631/printers/HP_Color_LaserJet_3800
1473D [26/Sep/2017:14:08:57 +0500] [Job ???] Auto-typing file...
1474I [26/Sep/2017:14:08:57 +0500] [Job ???] Request file type is application/vnd.cups-pdf-banner.
1475D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(---J-)
1476D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1477D [26/Sep/2017:14:08:57 +0500] Adding default job-sheets values "none,none"...
1478I [26/Sep/2017:14:08:57 +0500] [Job 9] Adding start banner page "none".
1479D [26/Sep/2017:14:08:57 +0500] Discarding unused job-created event...
1480D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(---J-)
1481D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1482I [26/Sep/2017:14:08:57 +0500] [Job 9] Adding end banner page "none".
1483I [26/Sep/2017:14:08:57 +0500] [Job 9] File of type application/vnd.cups-pdf-banner queued by "anonymous".
1484D [26/Sep/2017:14:08:57 +0500] [Job 9] hold_until=0
1485I [26/Sep/2017:14:08:57 +0500] [Job 9] Queued on "HP_Color_LaserJet_3800" by "anonymous".
1486D [26/Sep/2017:14:08:57 +0500] [Job 9] time-at-processing=1506449337
1487D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(---J-)
1488D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1489D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1490D [26/Sep/2017:14:08:57 +0500] Discarding unused printer-state-changed event...
1491D [26/Sep/2017:14:08:57 +0500] [Job 9] 3 filters for job:
1492D [26/Sep/2017:14:08:57 +0500] [Job 9] bannertopdf.exe (application/vnd.cups-pdf-banner to application/pdf, cost 32)
1493D [26/Sep/2017:14:08:57 +0500] [Job 9] pdftopdf.exe (application/pdf to application/vnd.cups-pdf, cost 66)
1494D [26/Sep/2017:14:08:57 +0500] [Job 9] foomatic-rip (application/vnd.cups-pdf to printer/HP_Color_LaserJet_3800, cost 0)
1495D [26/Sep/2017:14:08:57 +0500] [Job 9] job-sheets=none,none
1496D [26/Sep/2017:14:08:57 +0500] [Job 9] argv[0]="HP_Color_LaserJet_3800"
1497D [26/Sep/2017:14:08:57 +0500] [Job 9] argv[1]="9"
1498D [26/Sep/2017:14:08:57 +0500] [Job 9] argv[2]="anonymous"
1499D [26/Sep/2017:14:08:57 +0500] [Job 9] argv[3]="Test Page"
1500D [26/Sep/2017:14:08:57 +0500] [Job 9] argv[4]="1"
1501D [26/Sep/2017:14:08:57 +0500] [Job 9] argv[5]="job-uuid=urn:uuid:02e1822f-4f0b-3342-574b-42cec0e28b50 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1506449337 time-at-processing=1506449337"
1502D [26/Sep/2017:14:08:57 +0500] [Job 9] argv[6]="/@unixroot/var/spool/cups/d00009-001"
1503D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[0]="CUPS_CACHEDIR=/@unixroot/var/cache/cups"
1504D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[1]="CUPS_DATADIR=/@unixroot/usr/share/cups"
1505D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[2]="CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
1506D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[3]="CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
1507D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[4]="CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
1508D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[5]="CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
1509D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[6]="CUPS_SERVERROOT=/@unixroot/etc/cups"
1510D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[7]="CUPS_STATEDIR=/@unixroot/var/run/cups"
1511D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[8]="HOME=H:\\var\\temp"
1512D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[9]="NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
1513D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[10]="PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
1514D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[11]="SERVER_ADMIN=root@ARCAOS-01D8CC3"
1515D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[12]="SOFTWARE=CUPS/2.1.3"
1516D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[13]="TMPDIR=H:\\var\\temp"
1517D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[14]="TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
1518D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[15]="USER=root"
1519D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[16]="UNIXROOT=H:"
1520D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[17]="CUPS_MAX_MESSAGE=2047"
1521D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[18]="CUPS_SERVER=localhost"
1522D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[19]="CUPS_ENCRYPTION=IfRequested"
1523D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[20]="IPP_PORT=631"
1524D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[21]="CHARSET=utf-8"
1525D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[22]="LANG=en_US.UTF-8"
1526D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[23]="PPD=/@unixroot/etc/cups/ppd/HP_Color_LaserJet_3800.ppd"
1527D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[24]="RIP_MAX_CACHE=128m"
1528D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[25]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
1529D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[26]="DEVICE_URI=socket://192.168.100.16"
1530D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[27]="PRINTER_INFO=HP Color LaserJet 3800"
1531D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[28]="PRINTER_LOCATION="
1532D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[29]="PRINTER=HP_Color_LaserJet_3800"
1533D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[30]="PRINTER_STATE_REASONS=none"
1534D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[31]="CUPS_FILETYPE=document"
1535D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[32]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf"
1536D [26/Sep/2017:14:08:57 +0500] [Job 9] envp[33]="AUTH_I****"
1537I [26/Sep/2017:14:08:57 +0500] [Job 9] Started filter /@unixroot/usr/lib/cups/filter/bannertopdf.exe (PID 150)
1538I [26/Sep/2017:14:08:57 +0500] [Job 9] Started filter /@unixroot/usr/lib/cups/filter/pdftopdf.exe (PID 152)
1539I [26/Sep/2017:14:08:57 +0500] [Job 9] Started filter /@unixroot/usr/lib/cups/filter/foomatic-rip.exe (PID 154)
1540I [26/Sep/2017:14:08:57 +0500] [Job 9] Started backend /@unixroot/usr/lib/cups/backend/socket.exe (PID 156)
1541D [26/Sep/2017:14:08:57 +0500] Discarding unused job-state-changed event...
1542D [26/Sep/2017:14:08:57 +0500] [Client 36] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP_Color_LaserJet_3800) from localhost
1543D [26/Sep/2017:14:08:57 +0500] [Client 36] Content-Length: 195
1544D [26/Sep/2017:14:08:57 +0500] [Client 36] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1545D [26/Sep/2017:14:08:57 +0500] [Client 36] con->http=0x200fee20
1546D [26/Sep/2017:14:08:57 +0500] [Client 36] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=195, response=0x20060ec0(IPP_STATE_IDLE), pipe_pid=0, file=-1
1547D [26/Sep/2017:14:08:57 +0500] [Client 36] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1548D [26/Sep/2017:14:08:57 +0500] [Client 36] bytes=0, http_state=0, data_remaining=195
1549D [26/Sep/2017:14:08:57 +0500] [Client 36] Flushing write buffer.
1550D [26/Sep/2017:14:08:57 +0500] [Client 36] New state is HTTP_STATE_WAITING
1551D [26/Sep/2017:14:08:57 +0500] [Client 36] Waiting for request.
1552D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
1553D [26/Sep/2017:14:08:57 +0500] [Job 9] WARN: not compiled with DBus support
1554D [26/Sep/2017:14:08:57 +0500] [Job 9] \'CM Color Calibration\' Mode in SPOOLER-LESS: Off
1555D [26/Sep/2017:14:08:57 +0500] [Job 9] Getting input from file
1556D [26/Sep/2017:14:08:57 +0500] [Job 9] foomatic-rip version 1.8.2 running...
1557D [26/Sep/2017:14:08:57 +0500] [Job 9] Parsing PPD file ...
1558D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option ColorSpace
1559D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option Resolution
1560D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option PageSize
1561D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option Model
1562D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option PrintoutMode
1563D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option InputSlot
1564D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option Duplex
1565D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option Quality
1566D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option ImageableArea
1567D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option PaperDimension
1568D [26/Sep/2017:14:08:57 +0500] [Job 9] Added option Font
1569D [26/Sep/2017:14:08:57 +0500] [Job 9] Parameter Summary
1570D [26/Sep/2017:14:08:57 +0500] [Job 9] -----------------
1571D [26/Sep/2017:14:08:57 +0500] [Job 9] Spooler: cups
1572D [26/Sep/2017:14:08:57 +0500] [Job 9] Printer: HP_Color_LaserJet_3800
1573D [26/Sep/2017:14:08:57 +0500] [Job 9] Shell: /bin/bash
1574D [26/Sep/2017:14:08:57 +0500] [Job 9] PPD file: /@unixroot/etc/cups/ppd/HP_Color_LaserJet_3800.ppd
1575D [26/Sep/2017:14:08:57 +0500] [Job 9] ATTR file:
1576D [26/Sep/2017:14:08:57 +0500] [Job 9] Printer model: HP Color LaserJet 3800 hpijs pcl3, 3.16.5
1577D [26/Sep/2017:14:08:57 +0500] [Job 9] Job title: Test Page
1578D [26/Sep/2017:14:08:57 +0500] [Job 9] File(s) to be printed:
1579D [26/Sep/2017:14:08:57 +0500] [Job 9] <STDIN>
1580D [26/Sep/2017:14:08:57 +0500] [Job 9] Ghostscript extra search path (\'GS_LIB\'): /@unixroot/usr/share/cups/fonts
1581D [26/Sep/2017:14:08:57 +0500] [Job 9] Printing system options:
1582D [26/Sep/2017:14:08:57 +0500] [Job 9] Pondering option \'job-uuid=urn:uuid:02e1822f-4f0b-3342-574b-42cec0e28b50\'
1583D [26/Sep/2017:14:08:57 +0500] [Job 9] Unknown option job-uuid=urn:uuid:02e1822f-4f0b-3342-574b-42cec0e28b50.
1584D [26/Sep/2017:14:08:57 +0500] [Job 9] Pondering option \'job-originating-host-name=localhost\'
1585D [26/Sep/2017:14:08:57 +0500] [Job 9] Unknown option job-originating-host-name=localhost.
1586D [26/Sep/2017:14:08:57 +0500] [Job 9] Pondering option \'date-time-at-creation=\'
1587D [26/Sep/2017:14:08:57 +0500] [Job 9] Unknown option date-time-at-creation=.
1588D [26/Sep/2017:14:08:57 +0500] [Job 9] Pondering option \'date-time-at-processing=\'
1589D [26/Sep/2017:14:08:57 +0500] [Job 9] Unknown option date-time-at-processing=.
1590D [26/Sep/2017:14:08:57 +0500] [Job 9] Pondering option \'time-at-creation=1506449337\'
1591D [26/Sep/2017:14:08:57 +0500] [Job 9] Unknown option time-at-creation=1506449337.
1592D [26/Sep/2017:14:08:57 +0500] [Job 9] Pondering option \'time-at-processing=1506449337\'
1593D [26/Sep/2017:14:08:57 +0500] [Job 9] Unknown option time-at-processing=1506449337.
1594D [26/Sep/2017:14:08:57 +0500] [Job 9] CM Color Calibration Mode in CUPS: Off
1595D [26/Sep/2017:14:08:57 +0500] [Job 9] Options from the PPD file:
1596D [26/Sep/2017:14:08:57 +0500] [Job 9] ================================================
1597D [26/Sep/2017:14:08:57 +0500] [Job 9] File: <STDIN>
1598D [26/Sep/2017:14:08:57 +0500] [Job 9] ================================================
1599D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1600D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1601D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1602D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1603D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetArray: job_uri[0]=\"/jobs/9\"
1604D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetArray: job_id[0]=\"9\"
1605D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetArray: job_state[0]=\"3\"
1606D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetArray: job_state_message[0]=\"\"
1607D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetArray: job_state_reasons[0]=\"none\"
1608D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/HP_Color_LaserJet_3800\"
1609D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: TITLE=\"Print Test Page\"
1610D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1611D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1612D [26/Sep/2017:14:08:57 +0500] [Client 36] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1613D [26/Sep/2017:14:08:57 +0500] [Client 36] Closing connection.
1614D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
1615D [26/Sep/2017:14:08:57 +0500] [Client 23] CGI data ready to be sent.
1616D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: +connecting-to-device
1617D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(---J-)
1618D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files"
1619D [26/Sep/2017:14:08:57 +0500] [Job 9] Looking up \"192.168.100.16\"...
1620D [26/Sep/2017:14:08:57 +0500] Discarding unused printer-state-changed event...
1621D [26/Sep/2017:14:08:57 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
1622D [26/Sep/2017:14:08:57 +0500] [Client 23] con->http=0x200fbdc0
1623D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=148, file=10
1624D [26/Sep/2017:14:08:57 +0500] [Client 23] Waiting for CGI data.
1625D [26/Sep/2017:14:08:57 +0500] [Client 23] Script header: Content-Type: text/html;charset=utf-8
1626D [26/Sep/2017:14:08:57 +0500] [Client 23] Script header:
1627D [26/Sep/2017:14:08:57 +0500] [Client 23] Sending status 200 for CGI.
1628D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdSendHeader: code=200, type="(null)", auth_type=0
1629D [26/Sep/2017:14:08:57 +0500] PID 148 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
1630D [26/Sep/2017:14:08:57 +0500] [Client 23] CGI data ready to be sent.
1631D [26/Sep/2017:14:08:57 +0500] [Client 23] con->http=0x200fbdc0
1632D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=148, file=10
1633D [26/Sep/2017:14:08:57 +0500] [Client 23] Waiting for CGI data.
1634D [26/Sep/2017:14:08:57 +0500] [Client 23] Sending 0-length chunk.
1635D [26/Sep/2017:14:08:57 +0500] [Client 23] Flushing write buffer.
1636D [26/Sep/2017:14:08:57 +0500] [Client 23] New state is HTTP_STATE_WAITING
1637D [26/Sep/2017:14:08:57 +0500] [Client 23] Waiting for request.
1638D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1639D [26/Sep/2017:14:08:57 +0500] [Client 23] GET /cups.css HTTP/1.1
1640D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1641D [26/Sep/2017:14:08:57 +0500] [Client 23] Read: status=200
1642D [26/Sep/2017:14:08:57 +0500] [Client 23] No authentication data provided.
1643D [26/Sep/2017:14:08:57 +0500] [Client 23] Processing GET /cups.css
1644D [26/Sep/2017:14:08:57 +0500] [Client 23] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
1645D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdSendHeader: code=200, type="text/css", auth_type=0
1646D [26/Sep/2017:14:08:57 +0500] [Client 23] Sending file.
1647D [26/Sep/2017:14:08:57 +0500] [Client 23] con->http=0x200fbdc0
1648D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1649D [26/Sep/2017:14:08:57 +0500] [Client 23] con->http=0x200fbdc0
1650D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1651D [26/Sep/2017:14:08:57 +0500] [Client 23] con->http=0x200fbdc0
1652D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1653D [26/Sep/2017:14:08:57 +0500] [Client 23] con->http=0x200fbdc0
1654D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1655D [26/Sep/2017:14:08:57 +0500] [Client 23] con->http=0x200fbdc0
1656D [26/Sep/2017:14:08:57 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1657D [26/Sep/2017:14:08:57 +0500] [Client 23] Flushing write buffer.
1658D [26/Sep/2017:14:08:57 +0500] [Client 23] New state is HTTP_STATE_WAITING
1659D [26/Sep/2017:14:08:57 +0500] [Client 23] Waiting for request.
1660D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1661D [26/Sep/2017:14:08:57 +0500] [Job 9] PDF template file doesn\'t have form. It\'s okay.
1662W [26/Sep/2017:14:08:57 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
1663D [26/Sep/2017:14:08:57 +0500] [Job 9] Filetype: PDF
1664D [26/Sep/2017:14:08:57 +0500] [Job 9] Storing temporary files in H:\\var\\temp
1665D [26/Sep/2017:14:08:57 +0500] [Job 9] Process is dying with \"Failed to execute ghostscript to determine number of input pages!
1666D [26/Sep/2017:14:08:57 +0500] [Job 9] \", exit stat 6
1667D [26/Sep/2017:14:08:57 +0500] [Job 9] Cleaning up...
1668D [26/Sep/2017:14:08:57 +0500] [Job 9] PID 150 (/@unixroot/usr/lib/cups/filter/bannertopdf.exe) exited with no errors.
1669D [26/Sep/2017:14:08:57 +0500] [Job 9] PID 152 (/@unixroot/usr/lib/cups/filter/pdftopdf.exe) exited with no errors.
1670W [26/Sep/2017:14:08:57 +0500] cupsdDoSelect: select() returned -1... pause and retry select()
1671D [26/Sep/2017:14:08:57 +0500] [Job 9] ATTR: marker-colors=#000000,#00FFFF,#FF00FF,#FFFF00
1672D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(P----)
1673D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1674D [26/Sep/2017:14:08:57 +0500] [Job 9] ATTR: marker-names=\'\"Black Cartridge HP Q6470A\"\',\'\"Cyan Cartridge HP Q7581A\"\',\'\"Magenta Cartridge HP Q7583A\"\',\'\"Yellow Cartridge HP Q7582A\"\'
1675D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(P----)
1676D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1677D [26/Sep/2017:14:08:57 +0500] [Job 9] ATTR: marker-types=toner-cartridge,toner-cartridge,toner-cartridge,toner-cartridge
1678D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(P----)
1679D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1680D [26/Sep/2017:14:08:57 +0500] [Job 9] ATTR: marker-levels=16,67,67,13
1681D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(P----)
1682D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
1683D [26/Sep/2017:14:08:57 +0500] [Job 9] new_supply_state=0, change_state=ffff
1684D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -developer-low-report
1685D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -developer-empty-warning
1686D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -marker-supply-low-report
1687D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -marker-supply-empty-warning
1688D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -opc-near-eol-report
1689D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -opc-life-over-warning
1690D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -toner-low-report
1691D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -toner-empty-warning
1692D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -waste-receptacle-almost-full-report
1693D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -waste-receptacle-full-warning
1694D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -cleaner-life-almost-over-report
1695D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -cleaner-life-over-warning
1696D [26/Sep/2017:14:08:57 +0500] [Job 9] new_state=2000, change_state=ffff
1697D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -media-empty-warning
1698D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -door-open-report
1699D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -media-jam-warning
1700D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -input-tray-missing-warning
1701D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -output-tray-missing-warning
1702D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -marker-supply-missing-warning
1703D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -output-area-almost-full-report
1704D [26/Sep/2017:14:08:57 +0500] [Job 9] STATE: -output-area-full-warning
1705D [26/Sep/2017:14:08:57 +0500] [Job 9] backendWaitLoop(snmp_fd=5, addr=0x20037c84, side_cb=0x110f0)
1706D [26/Sep/2017:14:08:57 +0500] Discarding unused printer-state-changed event...
1707D [26/Sep/2017:14:08:57 +0500] [Job 9] PID 154 (/@unixroot/usr/lib/cups/filter/foomatic-rip.exe) stopped with status 6.
1708D [26/Sep/2017:14:08:57 +0500] [Job 9] PID 156 (/@unixroot/usr/lib/cups/backend/socket.exe) exited with no errors.
1709D [26/Sep/2017:14:08:57 +0500] Discarding unused job-state-changed event...
1710E [26/Sep/2017:14:08:57 +0500] [Job 9] Job stopped due to filter errors; please consult the error_log file for details.
1711D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(---J-)
1712D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
1713D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
1714D [26/Sep/2017:14:08:57 +0500] Discarding unused printer-state-changed event...
1715D [26/Sep/2017:14:08:57 +0500] cupsdMarkDirty(---J-)
1716D [26/Sep/2017:14:08:57 +0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
1717D [26/Sep/2017:14:08:57 +0500] [Job 9] The following messages were recorded from 02:08:57 pm to 02:08:57 pm
1718D [26/Sep/2017:14:08:57 +0500] [Job 9] hrDeviceDesc=\"HP Color LaserJet 3800\"
1719D [26/Sep/2017:14:08:57 +0500] [Job 9] prtGeneralCurrentLocalization=1
1720D [26/Sep/2017:14:08:57 +0500] [Job 9] prtLocalizationCharacterSet=2004
1721D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesColorantIndex.1.1 = 1
1722D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesColorantIndex.1.2 = 2
1723D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesColorantIndex.1.3 = 3
1724D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesColorantIndex.1.4 = 4
1725D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesClass.1.1 = 3
1726D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesClass.1.2 = 3
1727D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesClass.1.3 = 3
1728D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesClass.1.4 = 3
1729D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesType.1.1 = 21
1730D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesType.1.2 = 21
1731D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesType.1.3 = 21
1732D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesType.1.4 = 21
1733D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesDescription.1.1 = \"Black Cartridge HP Q6470A\"
1734D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesDescription.1.2 = \"Cyan Cartridge HP Q7581A\"
1735D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesDescription.1.3 = \"Magenta Cartridge HP Q7583A\"
1736D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesDescription.1.4 = \"Yellow Cartridge HP Q7582A\"
1737D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesSupplyUnit.1.1 = 7
1738D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesSupplyUnit.1.2 = 7
1739D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesSupplyUnit.1.3 = 7
1740D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesSupplyUnit.1.4 = 7
1741D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesMaxCapacity.1.1 = 6000
1742D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesMaxCapacity.1.2 = 6000
1743D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesMaxCapacity.1.3 = 6000
1744D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesMaxCapacity.1.4 = 6000
1745D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesLevel.1.1 = 960
1746D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesLevel.1.2 = 4020
1747D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesLevel.1.3 = 4020
1748D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerSuppliesLevel.1.4 = 780
1749D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerColorantValue.1.1 = \"black\"
1750D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerColorantValue.1.2 = \"cyan\"
1751D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerColorantValue.1.3 = \"magenta\"
1752D [26/Sep/2017:14:08:57 +0500] [Job 9] prtMarkerColorantValue.1.4 = \"yellow\"
1753D [26/Sep/2017:14:08:57 +0500] [Job 9] End of messages
1754D [26/Sep/2017:14:08:57 +0500] [Job 9] printer-state=3(idle)
1755D [26/Sep/2017:14:08:57 +0500] [Job 9] printer-state-message="Filter failed"
1756D [26/Sep/2017:14:08:57 +0500] [Job 9] printer-state-reasons=none
1757D [26/Sep/2017:14:08:58 +0500] [Job 9] Unloading...
1758D [26/Sep/2017:14:08:59 +0500] [Client 23] GET /printers/HP_Color_LaserJet_3800 HTTP/1.1
1759D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
1760D [26/Sep/2017:14:08:59 +0500] [Client 23] Read: status=200
1761D [26/Sep/2017:14:08:59 +0500] [Client 23] No authentication data provided.
1762D [26/Sep/2017:14:08:59 +0500] [Client 23] Processing GET /printers/HP_Color_LaserJet_3800
1763D [26/Sep/2017:14:08:59 +0500] [CGI] argv[0] = "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi"
1764D [26/Sep/2017:14:08:59 +0500] [CGI] envp[0] = "CUPS_CACHEDIR=/@unixroot/var/cache/cups"
1765D [26/Sep/2017:14:08:59 +0500] [CGI] envp[1] = "CUPS_DATADIR=/@unixroot/usr/share/cups"
1766D [26/Sep/2017:14:08:59 +0500] [CGI] envp[2] = "CUPS_DOCROOT=/@unixroot/usr/share/doc/cups"
1767D [26/Sep/2017:14:08:59 +0500] [CGI] envp[3] = "CUPS_FONTPATH=/@unixroot/usr/share/cups/fonts"
1768D [26/Sep/2017:14:08:59 +0500] [CGI] envp[4] = "CUPS_REQUESTROOT=/@unixroot/var/spool/cups"
1769D [26/Sep/2017:14:08:59 +0500] [CGI] envp[5] = "CUPS_SERVERBIN=/@unixroot/usr/lib/cups"
1770D [26/Sep/2017:14:08:59 +0500] [CGI] envp[6] = "CUPS_SERVERROOT=/@unixroot/etc/cups"
1771D [26/Sep/2017:14:08:59 +0500] [CGI] envp[7] = "CUPS_STATEDIR=/@unixroot/var/run/cups"
1772D [26/Sep/2017:14:08:59 +0500] [CGI] envp[8] = "HOME=H:\\var\\temp"
1773D [26/Sep/2017:14:08:59 +0500] [CGI] envp[9] = "NLSPATH=H:\\MPTN\\MSG\\NLS\\%N;H:\\TCPIP\\msg\\ENUS850\\%N;"
1774D [26/Sep/2017:14:08:59 +0500] [CGI] envp[10] = "PATH=/@unixroot/usr/lib/cups/filter;/@unixroot/usr/bin;/@unixroot/usr/sbin;/bin;/usr/bin"
1775D [26/Sep/2017:14:08:59 +0500] [CGI] envp[11] = "SERVER_ADMIN=root@ARCAOS-01D8CC3"
1776D [26/Sep/2017:14:08:59 +0500] [CGI] envp[12] = "SOFTWARE=CUPS/2.1.3"
1777D [26/Sep/2017:14:08:59 +0500] [CGI] envp[13] = "TMPDIR=H:\\var\\temp"
1778D [26/Sep/2017:14:08:59 +0500] [CGI] envp[14] = "TZ=EST5EDT,3,2,0,7200,11,1,0,7200,3600"
1779D [26/Sep/2017:14:08:59 +0500] [CGI] envp[15] = "USER=root"
1780D [26/Sep/2017:14:08:59 +0500] [CGI] envp[16] = "UNIXROOT=H:"
1781D [26/Sep/2017:14:08:59 +0500] [CGI] envp[17] = "CUPS_MAX_MESSAGE=2047"
1782D [26/Sep/2017:14:08:59 +0500] [CGI] envp[18] = "CUPS_SERVER=localhost"
1783D [26/Sep/2017:14:08:59 +0500] [CGI] envp[19] = "CUPS_ENCRYPTION=IfRequested"
1784D [26/Sep/2017:14:08:59 +0500] [CGI] envp[20] = "IPP_PORT=631"
1785D [26/Sep/2017:14:08:59 +0500] [CGI] envp[21] = "LANG=en_US.UTF8"
1786D [26/Sep/2017:14:08:59 +0500] [CGI] envp[22] = "REDIRECT_STATUS=1"
1787D [26/Sep/2017:14:08:59 +0500] [CGI] envp[23] = "GATEWAY_INTERFACE=CGI/1.1"
1788D [26/Sep/2017:14:08:59 +0500] [CGI] envp[24] = "SERVER_NAME=localhost"
1789D [26/Sep/2017:14:08:59 +0500] [CGI] envp[25] = "SERVER_PORT=631"
1790D [26/Sep/2017:14:08:59 +0500] [CGI] envp[26] = "REMOTE_ADDR=127.0.0.1"
1791D [26/Sep/2017:14:08:59 +0500] [CGI] envp[27] = "REMOTE_HOST=localhost"
1792D [26/Sep/2017:14:08:59 +0500] [CGI] envp[28] = "SCRIPT_NAME=/printers/HP_Color_LaserJet_3800"
1793D [26/Sep/2017:14:08:59 +0500] [CGI] envp[29] = "SCRIPT_FILENAME=/@unixroot/usr/share/doc/cups/printers/HP_Color_LaserJet_3800"
1794D [26/Sep/2017:14:08:59 +0500] [CGI] envp[30] = "PATH_INFO=/HP_Color_LaserJet_3800"
1795D [26/Sep/2017:14:08:59 +0500] [CGI] envp[31] = "SERVER_PROTOCOL=HTTP/1.1"
1796D [26/Sep/2017:14:08:59 +0500] [CGI] envp[32] = "HTTP_COOKIE=org.cups.sid=ba119267d82f0c1688613ada42d0a797"
1797D [26/Sep/2017:14:08:59 +0500] [CGI] envp[33] = "HTTP_USER_AGENT=Mozilla/5.0 (OS/2; Warp 4.5; rv:45.0) Gecko/20100101 Firefox/45.0"
1798D [26/Sep/2017:14:08:59 +0500] [CGI] envp[34] = "REQUEST_METHOD=GET"
1799D [26/Sep/2017:14:08:59 +0500] [CGI] envp[35] = "QUERY_STRING="
1800D [26/Sep/2017:14:08:59 +0500] [CGI] Started /@unixroot/usr/lib/cups/cgi-bin/printers.cgi (PID 158)
1801I [26/Sep/2017:14:08:59 +0500] [Client 23] Started "/@unixroot/usr/lib/cups/cgi-bin/printers.cgi" (pid=158, file=9)
1802D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1803D [26/Sep/2017:14:08:59 +0500] [Client 37] Accepted from localhost:50048 (IPv4)
1804D [26/Sep/2017:14:08:59 +0500] [Client 37] Waiting for request.
1805D [26/Sep/2017:14:08:59 +0500] [CGI] org.cups.sid cookie is \"ba119267d82f0c1688613ada42d0a797\"
1806D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
1807D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: REFRESH_PAGE=\"\"
1808D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
1809D [26/Sep/2017:14:08:59 +0500] [Client 37] POST / HTTP/1.1
1810D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1811D [26/Sep/2017:14:08:59 +0500] [Client 37] Read: status=200
1812D [26/Sep/2017:14:08:59 +0500] [Client 37] No authentication data provided.
1813D [26/Sep/2017:14:08:59 +0500] [Client 37] 2.0 CUPS-Get-Default 1
1814D [26/Sep/2017:14:08:59 +0500] CUPS-Get-Default
1815D [26/Sep/2017:14:08:59 +0500] CUPS-Get-Default client-error-not-found: No default printer.
1816D [26/Sep/2017:14:08:59 +0500] [Client 37] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
1817D [26/Sep/2017:14:08:59 +0500] [Client 37] Content-Length: 113
1818D [26/Sep/2017:14:08:59 +0500] [Client 37] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1819D [26/Sep/2017:14:08:59 +0500] [Client 37] con->http=0x200fee20
1820D [26/Sep/2017:14:08:59 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=113, response=0x200fbd80(IPP_STATE_DATA), pipe_pid=0, file=-1
1821D [26/Sep/2017:14:08:59 +0500] [Client 37] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1822D [26/Sep/2017:14:08:59 +0500] [Client 37] bytes=0, http_state=0, data_remaining=113
1823D [26/Sep/2017:14:08:59 +0500] [Client 37] Flushing write buffer.
1824D [26/Sep/2017:14:08:59 +0500] [Client 37] New state is HTTP_STATE_WAITING
1825D [26/Sep/2017:14:08:59 +0500] [Client 37] Waiting for request.
1826D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1827D [26/Sep/2017:14:08:59 +0500] [CGI] show_printer(http=0x2003c220, printer=\"HP_Color_LaserJet_3800\")
1828D [26/Sep/2017:14:08:59 +0500] [Client 37] POST / HTTP/1.1
1829D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1830D [26/Sep/2017:14:08:59 +0500] [Client 37] Read: status=200
1831D [26/Sep/2017:14:08:59 +0500] [Client 37] No authentication data provided.
1832D [26/Sep/2017:14:08:59 +0500] [Client 37] 2.0 Get-Printer-Attributes 2
1833D [26/Sep/2017:14:08:59 +0500] Get-Printer-Attributes ipp://localhost/printers/HP_Color_LaserJet_3800
1834D [26/Sep/2017:14:08:59 +0500] [Client 37] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/HP_Color_LaserJet_3800) from localhost
1835D [26/Sep/2017:14:08:59 +0500] [Client 37] Content-Length: 730
1836D [26/Sep/2017:14:08:59 +0500] [Client 37] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1837D [26/Sep/2017:14:08:59 +0500] [Client 37] con->http=0x200fee20
1838D [26/Sep/2017:14:08:59 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=730, response=0x200f6f40(IPP_STATE_DATA), pipe_pid=0, file=-1
1839D [26/Sep/2017:14:08:59 +0500] [Client 37] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1840D [26/Sep/2017:14:08:59 +0500] [Client 37] bytes=0, http_state=0, data_remaining=730
1841D [26/Sep/2017:14:08:59 +0500] [Client 37] Flushing write buffer.
1842D [26/Sep/2017:14:08:59 +0500] [Client 37] New state is HTTP_STATE_WAITING
1843D [26/Sep/2017:14:08:59 +0500] [Client 37] Waiting for request.
1844D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1845D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1846D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1847D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1848D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1849D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_is_accepting_jobs[0]=\"1\"
1850D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_is_shared[0]=\"0\"
1851D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_state[0]=\"3\"
1852D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: admin_uri[0]=\"/admin/\"
1853D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_uri_supported[0]=\"/printers/HP_Color_LaserJet_3800\"
1854D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_name[0]=\"HP_Color_LaserJet_3800\"
1855D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_location[0]=\"\"
1856D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_info[0]=\"HP Color LaserJet 3800\"
1857D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_sheets_default[0]=\"none, none\"
1858D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: device_uri[0]=\"socket://192.168.100.16\"
1859D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: color_supported[0]=\"1\"
1860D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_make_and_model[0]=\"HP Color LaserJet 3800 hpijs pcl3, 3.16.5\"
1861D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: media_default[0]=\"na_letter_8.5x11in\"
1862D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: sides_supported[0]=\"one-sided, two-sided-long-edge, two-sided-short-edge\"
1863D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: sides_default[0]=\"one-sided\"
1864D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: printer_commands[0]=\"AutoConfigure, Clean, PrintSelfTestPage\"
1865D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1866D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: server_is_sharing_printers[0]=\"0\"
1867D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: TITLE=\"HP_Color_LaserJet_3800\"
1868D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1869D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1870D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1871D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=0(), pipe_pid=158, file=9
1872D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1873D [26/Sep/2017:14:08:59 +0500] [Client 23] Script header: Content-Type: text/html;charset=utf-8
1874D [26/Sep/2017:14:08:59 +0500] [Client 23] Script header:
1875D [26/Sep/2017:14:08:59 +0500] [Client 23] Sending status 200 for CGI.
1876D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdSendHeader: code=200, type="(null)", auth_type=0
1877D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1878D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1879D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1880D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1881D [26/Sep/2017:14:08:59 +0500] [CGI] Regular expression \".*Clean.*\"
1882D [26/Sep/2017:14:08:59 +0500] [CGI] matches[0].rm_so=0
1883D [26/Sep/2017:14:08:59 +0500] [CGI] matches[1].rm_so=-1
1884D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1885D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1886D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1887D [26/Sep/2017:14:08:59 +0500] [CGI] Regular expression \".*PrintSelfTestPage.*\"
1888D [26/Sep/2017:14:08:59 +0500] [CGI] matches[0].rm_so=0
1889D [26/Sep/2017:14:08:59 +0500] [CGI] matches[1].rm_so=-1
1890D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1891D [26/Sep/2017:14:08:59 +0500] [Client 37] POST / HTTP/1.1
1892D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1893D [26/Sep/2017:14:08:59 +0500] [Client 37] Read: status=200
1894D [26/Sep/2017:14:08:59 +0500] [Client 37] No authentication data provided.
1895D [26/Sep/2017:14:08:59 +0500] [Client 37] 2.0 Get-Jobs 3
1896D [26/Sep/2017:14:08:59 +0500] Get-Jobs ipp://localhost:631/printers/HP_Color_LaserJet_3800
1897D [26/Sep/2017:14:08:59 +0500] [Job 9] Loading attributes...
1898D [26/Sep/2017:14:08:59 +0500] [Client 37] Returning IPP successful-ok for Get-Jobs (ipp://localhost:631/printers/HP_Color_LaserJet_3800) from localhost
1899D [26/Sep/2017:14:08:59 +0500] [Client 37] Content-Length: 433
1900D [26/Sep/2017:14:08:59 +0500] [Client 37] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
1901D [26/Sep/2017:14:08:59 +0500] [Client 37] con->http=0x200fee20
1902D [26/Sep/2017:14:08:59 +0500] [Client 37] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=433, response=0x20060ec0(IPP_STATE_DATA), pipe_pid=0, file=-1
1903D [26/Sep/2017:14:08:59 +0500] [Client 37] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
1904D [26/Sep/2017:14:08:59 +0500] [Client 37] bytes=0, http_state=0, data_remaining=433
1905D [26/Sep/2017:14:08:59 +0500] [Client 37] Flushing write buffer.
1906D [26/Sep/2017:14:08:59 +0500] [Client 37] New state is HTTP_STATE_WAITING
1907D [26/Sep/2017:14:08:59 +0500] [Client 37] Waiting for request.
1908D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1909D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1910D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1911D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1912D [26/Sep/2017:14:08:59 +0500] [CGI] cgiClearVariables called.
1913D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: ORDER=\"asc\"
1914D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: SECTION=\"printers\"
1915D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: TOTAL=\"1\"
1916D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
1917D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.1.3\"
1918D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_preserved[0]=\"1\"
1919D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_printer_name[0]=\"HP_Color_LaserJet_3800\"
1920D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_printer_uri[0]=\"/printers/HP_Color_LaserJet_3800\"
1921D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_name[0]=\"Test Page\"
1922D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_originating_user_name[0]=\"anonymous\"
1923D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: time_at_completed[0]=\"novalue\"
1924D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: time_at_creation[0]=\"Tue Sep 26 14:08:57 EDT 2017\"
1925D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: time_at_processing[0]=\"Tue Sep 26 14:08:57 EDT 2017\"
1926D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_id[0]=\"9\"
1927D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_state[0]=\"6\"
1928D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_media_sheets_completed[0]=\"0\"
1929D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_k_octets[0]=\"1\"
1930D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetArray: job_printer_state_message[0]=\"Filter failed\"
1931D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: PRINTER_NAME=\"HP_Color_LaserJet_3800\"
1932D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED=\"/printers/HP_Color_LaserJet_3800\"
1933D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: THISURL=\"/printers/HP_Color_LaserJet_3800\"
1934D [26/Sep/2017:14:08:59 +0500] [CGI] cgiSetVariable: SEARCH_DEST=\"HP_Color_LaserJet_3800\"
1935D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1936D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1937D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1938D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1939D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1940D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1941D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1942D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1943D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1944D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1945D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1946D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1947D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1948D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1949D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1950D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1951D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1952D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1953D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1954D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1955D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1956D [26/Sep/2017:14:08:59 +0500] [Client 37] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
1957D [26/Sep/2017:14:08:59 +0500] [Client 37] Closing connection.
1958D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
1959D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1960D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1961D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1962D [26/Sep/2017:14:08:59 +0500] PID 158 (/@unixroot/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
1963D [26/Sep/2017:14:08:59 +0500] [Client 23] CGI data ready to be sent.
1964D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1965D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=0(), pipe_pid=158, file=9
1966D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for CGI data.
1967D [26/Sep/2017:14:08:59 +0500] [Client 23] Sending 0-length chunk.
1968D [26/Sep/2017:14:08:59 +0500] [Client 23] Flushing write buffer.
1969D [26/Sep/2017:14:08:59 +0500] [Client 23] New state is HTTP_STATE_WAITING
1970D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for request.
1971D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
1972D [26/Sep/2017:14:08:59 +0500] [Client 23] GET /cups.css HTTP/1.1
1973D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
1974D [26/Sep/2017:14:08:59 +0500] [Client 23] Read: status=200
1975D [26/Sep/2017:14:08:59 +0500] [Client 23] No authentication data provided.
1976D [26/Sep/2017:14:08:59 +0500] [Client 23] Processing GET /cups.css
1977D [26/Sep/2017:14:08:59 +0500] [Client 23] filename="/@unixroot/usr/share/doc/cups/cups.css", type=text/css
1978D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdSendHeader: code=200, type="text/css", auth_type=0
1979D [26/Sep/2017:14:08:59 +0500] [Client 23] Sending file.
1980D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1981D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1982D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1983D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1984D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1985D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1986D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1987D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1988D [26/Sep/2017:14:08:59 +0500] [Client 23] con->http=0x200fbdc0
1989D [26/Sep/2017:14:08:59 +0500] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9961, response=0(), pipe_pid=0, file=9
1990D [26/Sep/2017:14:08:59 +0500] [Client 23] Flushing write buffer.
1991D [26/Sep/2017:14:08:59 +0500] [Client 23] New state is HTTP_STATE_WAITING
1992D [26/Sep/2017:14:08:59 +0500] [Client 23] Waiting for request.
1993D [26/Sep/2017:14:08:59 +0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"