1 | # PyKota |
---|
2 | # -*- coding: ISO-8859-15 -*- |
---|
3 | # |
---|
4 | # PyKota - Print Quotas for CUPS and LPRng |
---|
5 | # |
---|
6 | # (c) 2003, 2004, 2005, 2006, 2007 Jerome Alet <alet@librelogiciel.com> |
---|
7 | # This program is free software; you can redistribute it and/or modify |
---|
8 | # it under the terms of the GNU General Public License as published by |
---|
9 | # the Free Software Foundation; either version 2 of the License, or |
---|
10 | # (at your option) any later version. |
---|
11 | # |
---|
12 | # This program is distributed in the hope that it will be useful, |
---|
13 | # but WITHOUT ANY WARRANTY; without even the implied warranty of |
---|
14 | # MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the |
---|
15 | # GNU General Public License for more details. |
---|
16 | # |
---|
17 | # You should have received a copy of the GNU General Public License |
---|
18 | # along with this program; if not, write to the Free Software |
---|
19 | # Foundation, Inc., 51 Franklin Street, Fifth Floor, Boston, MA 02110-1301, USA. |
---|
20 | # |
---|
21 | # $Id$ |
---|
22 | # |
---|
23 | # |
---|
24 | |
---|
25 | import sys |
---|
26 | import os |
---|
27 | import socket |
---|
28 | import errno |
---|
29 | import time |
---|
30 | import threading |
---|
31 | import Queue |
---|
32 | |
---|
33 | from pykota import constants |
---|
34 | |
---|
35 | FORMFEEDCHAR = chr(0x0c) # Form Feed character, ends PJL answers. |
---|
36 | |
---|
37 | # Old method : pjlMessage = "\033%-12345X@PJL USTATUSOFF\r\n@PJL INFO STATUS\r\n@PJL INFO PAGECOUNT\r\n\033%-12345X" |
---|
38 | # Here's a new method, which seems to work fine on my HP2300N, while the |
---|
39 | # previous one didn't. |
---|
40 | # TODO : We could also experiment with USTATUS JOB=ON and we would know for sure |
---|
41 | # when the job is finished, without having to poll the printer repeatedly. |
---|
42 | pjlMessage = "\033%-12345X@PJL USTATUS DEVICE=ON\r\n@PJL INFO STATUS\r\n@PJL INFO PAGECOUNT\r\n@PJL USTATUS DEVICE=OFF\033%-12345X" |
---|
43 | pjlStatusValues = { |
---|
44 | "10000" : "Powersave Mode", |
---|
45 | "10001" : "Ready Online", |
---|
46 | "10002" : "Ready Offline", |
---|
47 | "10003" : "Warming Up", |
---|
48 | "10004" : "Self Test", |
---|
49 | "10005" : "Reset", |
---|
50 | "10023" : "Printing", |
---|
51 | "35078" : "Powersave Mode", # 10000 is ALSO powersave !!! |
---|
52 | "40000" : "Sleep Mode", # Standby |
---|
53 | } |
---|
54 | |
---|
55 | class Handler : |
---|
56 | """A class for PJL print accounting.""" |
---|
57 | def __init__(self, parent, printerhostname, skipinitialwait=False) : |
---|
58 | self.parent = parent |
---|
59 | self.printerHostname = printerhostname |
---|
60 | self.skipinitialwait = skipinitialwait |
---|
61 | try : |
---|
62 | self.port = int(self.parent.arguments.split(":")[1].strip()) |
---|
63 | except (IndexError, ValueError) : |
---|
64 | self.port = 9100 |
---|
65 | self.printerInternalPageCounter = self.printerStatus = None |
---|
66 | self.closed = False |
---|
67 | self.sock = None |
---|
68 | self.queue = None |
---|
69 | self.readthread = None |
---|
70 | self.quitEvent = threading.Event() |
---|
71 | |
---|
72 | def __del__(self) : |
---|
73 | """Ensures the network connection is closed at object deletion time.""" |
---|
74 | self.close() |
---|
75 | |
---|
76 | def open(self) : |
---|
77 | """Opens the network connection.""" |
---|
78 | sock = socket.socket(socket.AF_INET, socket.SOCK_STREAM) |
---|
79 | try : |
---|
80 | sock.connect((self.printerHostname, self.port)) |
---|
81 | except socket.error, msg : |
---|
82 | self.parent.filter.printInfo(_("Problem during connection to %s:%s : %s") % (self.printerHostname, self.port, str(msg)), "warn") |
---|
83 | return False |
---|
84 | else : |
---|
85 | sock.setblocking(False) |
---|
86 | self.sock = sock |
---|
87 | self.closed = False |
---|
88 | self.quitEvent.clear() |
---|
89 | self.queue = Queue.Queue(0) |
---|
90 | self.readthread = threading.Thread(target=self.readloop) |
---|
91 | self.readthread.start() |
---|
92 | self.parent.filter.logdebug("Connected to printer %s:%s" % (self.printerHostname, self.port)) |
---|
93 | return True |
---|
94 | |
---|
95 | def close(self) : |
---|
96 | """Closes the network connection.""" |
---|
97 | if not self.closed : |
---|
98 | self.quitEvent.set() |
---|
99 | if self.readthread is not None : |
---|
100 | self.readthread.join() |
---|
101 | self.readthread = None |
---|
102 | if self.sock is not None : |
---|
103 | self.sock.close() |
---|
104 | self.sock = None |
---|
105 | self.parent.filter.logdebug("Connection to %s:%s is now closed." % (self.printerHostname, self.port)) |
---|
106 | self.queue = None |
---|
107 | self.closed = True |
---|
108 | |
---|
109 | def readloop(self) : |
---|
110 | """Reading loop thread.""" |
---|
111 | self.parent.filter.logdebug("Reading thread started.") |
---|
112 | buffer = [] |
---|
113 | while not self.quitEvent.isSet() : |
---|
114 | try : |
---|
115 | answer = self.sock.recv(4096) |
---|
116 | except socket.error, (err, msg) : |
---|
117 | time.sleep(0.1) # We will try again later in all cases |
---|
118 | if err != errno.EAGAIN : |
---|
119 | self.parent.filter.printInfo(_("Problem while receiving PJL answer from %s:%s : %s") % (self.printerHostname, self.port, str(msg)), "warn") |
---|
120 | else : |
---|
121 | buffer.append(answer) |
---|
122 | if answer.endswith(FORMFEEDCHAR) : |
---|
123 | self.queue.put("".join(buffer)) |
---|
124 | buffer = [] |
---|
125 | if buffer : |
---|
126 | self.queue.put("".join(buffer)) |
---|
127 | self.parent.filter.logdebug("Reading thread ended.") |
---|
128 | |
---|
129 | def retrievePJLValues(self) : |
---|
130 | """Retrieves a printer's internal page counter and status via PJL.""" |
---|
131 | try : |
---|
132 | self.sock.send(pjlMessage) |
---|
133 | except socket.error, msg : |
---|
134 | self.parent.filter.printInfo(_("Problem while sending PJL query to %s:%s : %s") % (self.printerHostname, self.port, str(msg)), "warn") |
---|
135 | else : |
---|
136 | self.parent.filter.logdebug("Query sent to %s : %s" % (self.printerHostname, repr(pjlMessage))) |
---|
137 | actualpagecount = self.printerStatus = None |
---|
138 | while (actualpagecount is None) or (self.printerStatus is None) : |
---|
139 | try : |
---|
140 | answer = self.queue.get(True, 5) |
---|
141 | except Queue.Empty : |
---|
142 | self.parent.filter.logdebug("Timeout when reading printer's answer from %s:%s" % (self.printerHostname, self.port)) |
---|
143 | else : |
---|
144 | readnext = False |
---|
145 | self.parent.filter.logdebug("PJL answer : %s" % repr(answer)) |
---|
146 | for line in [l.strip() for l in answer.split()] : |
---|
147 | if line.startswith("CODE=") : |
---|
148 | self.printerStatus = line.split("=")[1] |
---|
149 | self.parent.filter.logdebug("Found status : %s" % self.printerStatus) |
---|
150 | elif line.startswith("PAGECOUNT=") : |
---|
151 | try : |
---|
152 | actualpagecount = int(line.split('=')[1].strip()) |
---|
153 | except ValueError : |
---|
154 | self.parent.filter.logdebug("Received incorrect datas : [%s]" % line.strip()) |
---|
155 | else : |
---|
156 | self.parent.filter.logdebug("Found pages counter : %s" % actualpagecount) |
---|
157 | elif line.startswith("PAGECOUNT") : |
---|
158 | readnext = True # page counter is on next line |
---|
159 | elif readnext : |
---|
160 | try : |
---|
161 | actualpagecount = int(line.strip()) |
---|
162 | except ValueError : |
---|
163 | self.parent.filter.logdebug("Received incorrect datas : [%s]" % line.strip()) |
---|
164 | else : |
---|
165 | self.parent.filter.logdebug("Found pages counter : %s" % actualpagecount) |
---|
166 | readnext = False |
---|
167 | self.printerInternalPageCounter = max(actualpagecount, self.printerInternalPageCounter) |
---|
168 | |
---|
169 | def waitPrinting(self) : |
---|
170 | """Waits for printer status being 'printing'.""" |
---|
171 | try : |
---|
172 | noprintingmaxdelay = int(self.parent.filter.config.getNoPrintingMaxDelay(self.parent.filter.PrinterName)) |
---|
173 | except (TypeError, AttributeError) : # NB : AttributeError in testing mode because I'm lazy ! |
---|
174 | noprintingmaxdelay = constants.NOPRINTINGMAXDELAY |
---|
175 | self.parent.filter.logdebug("No max delay defined for printer %s, using %i seconds." % (self.parent.filter.PrinterName, noprintingmaxdelay)) |
---|
176 | if not noprintingmaxdelay : |
---|
177 | self.parent.filter.logdebug("Will wait indefinitely until printer %s is in 'printing' state." % self.parent.filter.PrinterName) |
---|
178 | else : |
---|
179 | self.parent.filter.logdebug("Will wait until printer %s is in 'printing' state or %i seconds have elapsed." % (self.parent.filter.PrinterName, noprintingmaxdelay)) |
---|
180 | previousValue = self.parent.getLastPageCounter() |
---|
181 | timebefore = time.time() |
---|
182 | firstvalue = None |
---|
183 | while True : |
---|
184 | self.retrievePJLValues() |
---|
185 | if self.printerStatus in ('10023', '10003') : |
---|
186 | break |
---|
187 | if self.printerInternalPageCounter is not None : |
---|
188 | if firstvalue is None : |
---|
189 | # first time we retrieved a page counter, save it |
---|
190 | firstvalue = self.printerInternalPageCounter |
---|
191 | else : |
---|
192 | # second time (or later) |
---|
193 | if firstvalue < self.printerInternalPageCounter : |
---|
194 | # Here we have a printer which lies : |
---|
195 | # it says it is not printing or warming up |
---|
196 | # BUT the page counter increases !!! |
---|
197 | # So we can probably quit being sure it is printing. |
---|
198 | self.parent.filter.printInfo("Printer %s is lying to us !!!" % self.parent.filter.PrinterName, "warn") |
---|
199 | break |
---|
200 | elif noprintingmaxdelay and ((time.time() - timebefore) > noprintingmaxdelay) : |
---|
201 | # More than X seconds without the printer being in 'printing' mode |
---|
202 | # We can safely assume this won't change if printer is now 'idle' |
---|
203 | if self.printerStatus in ('10000', '10001', '35078', '40000') : |
---|
204 | if self.printerInternalPageCounter == previousValue : |
---|
205 | # Here the job won't be printed, because probably |
---|
206 | # the printer rejected it for some reason. |
---|
207 | self.parent.filter.printInfo("Printer %s probably won't print this job !!!" % self.parent.filter.PrinterName, "warn") |
---|
208 | else : |
---|
209 | # Here the job has already been entirely printed, and |
---|
210 | # the printer has already passed from 'idle' to 'printing' to 'idle' again. |
---|
211 | self.parent.filter.printInfo("Printer %s has probably already printed this job !!!" % self.parent.filter.PrinterName, "warn") |
---|
212 | break |
---|
213 | self.parent.filter.logdebug(_("Waiting for printer %s to be printing...") % self.parent.filter.PrinterName) |
---|
214 | time.sleep(constants.ITERATIONDELAY) |
---|
215 | |
---|
216 | def waitIdle(self) : |
---|
217 | """Waits for printer status being 'idle'.""" |
---|
218 | idle_num = 0 |
---|
219 | while True : |
---|
220 | self.retrievePJLValues() |
---|
221 | if self.printerStatus in ('10000', '10001', '35078', '40000') : |
---|
222 | if (self.printerInternalPageCounter is not None) \ |
---|
223 | and self.skipinitialwait \ |
---|
224 | and (os.environ.get("PYKOTAPHASE") == "BEFORE") : |
---|
225 | self.parent.filter.logdebug("No need to wait for the printer to be idle, it is the case already.") |
---|
226 | return |
---|
227 | idle_num += 1 |
---|
228 | if idle_num >= constants.STABILIZATIONDELAY : |
---|
229 | # printer status is stable, we can exit |
---|
230 | break |
---|
231 | else : |
---|
232 | idle_num = 0 |
---|
233 | self.parent.filter.logdebug(_("Waiting for printer %s's idle status to stabilize...") % self.parent.filter.PrinterName) |
---|
234 | time.sleep(constants.ITERATIONDELAY) |
---|
235 | |
---|
236 | def retrieveInternalPageCounter(self) : |
---|
237 | """Returns the page counter from the printer via internal PJL handling.""" |
---|
238 | while not self.open() : |
---|
239 | self.parent.filter.logdebug("Will retry in 1 second.") |
---|
240 | time.sleep(1) |
---|
241 | try : |
---|
242 | try : |
---|
243 | if (os.environ.get("PYKOTASTATUS") != "CANCELLED") and \ |
---|
244 | (os.environ.get("PYKOTAACTION") == "ALLOW") and \ |
---|
245 | (os.environ.get("PYKOTAPHASE") == "AFTER") and \ |
---|
246 | self.parent.filter.JobSizeBytes : |
---|
247 | self.waitPrinting() |
---|
248 | self.waitIdle() |
---|
249 | except : |
---|
250 | self.parent.filter.printInfo(_("PJL querying stage interrupted. Using latest value seen for internal page counter (%s) on printer %s.") % (self.printerInternalPageCounter, self.parent.filter.PrinterName), "warn") |
---|
251 | raise |
---|
252 | else : |
---|
253 | return self.printerInternalPageCounter |
---|
254 | finally : |
---|
255 | self.close() |
---|
256 | |
---|
257 | def main(hostname) : |
---|
258 | """Tries PJL accounting for a printer host.""" |
---|
259 | class fakeFilter : |
---|
260 | """Fakes a filter for testing purposes.""" |
---|
261 | def __init__(self) : |
---|
262 | """Initializes the fake filter.""" |
---|
263 | self.PrinterName = "FakePrintQueue" |
---|
264 | self.JobSizeBytes = 1 |
---|
265 | |
---|
266 | def printInfo(self, msg, level="info") : |
---|
267 | """Prints informational message.""" |
---|
268 | sys.stderr.write("%s : %s\n" % (level.upper(), msg)) |
---|
269 | sys.stderr.flush() |
---|
270 | |
---|
271 | def logdebug(self, msg) : |
---|
272 | """Prints debug message.""" |
---|
273 | self.printInfo(msg, "debug") |
---|
274 | |
---|
275 | class fakeAccounter : |
---|
276 | """Fakes an accounter for testing purposes.""" |
---|
277 | def __init__(self) : |
---|
278 | """Initializes fake accounter.""" |
---|
279 | self.arguments = "pjl:9100" |
---|
280 | self.filter = fakeFilter() |
---|
281 | self.protocolHandler = Handler(self, sys.argv[1]) |
---|
282 | |
---|
283 | def getLastPageCounter(self) : |
---|
284 | """Fakes the return of a page counter.""" |
---|
285 | return 0 |
---|
286 | |
---|
287 | acc = fakeAccounter() |
---|
288 | return acc.protocolHandler.retrieveInternalPageCounter() |
---|
289 | |
---|
290 | if __name__ == "__main__" : |
---|
291 | if len(sys.argv) != 2 : |
---|
292 | sys.stderr.write("Usage : python %s printer_ip_address\n" % sys.argv[0]) |
---|
293 | else : |
---|
294 | def _(msg) : |
---|
295 | return msg |
---|
296 | |
---|
297 | pagecounter = main(sys.argv[1]) |
---|
298 | print "Internal page counter's value is : %s" % pagecounter |
---|
299 | |
---|