Unnamed

D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=6", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=5", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=4", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=3", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_preserved>0", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=4", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=3", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state<7", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{?job_name=", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=6", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=5", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=4", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=3", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_preserved>0", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=4", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=3", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state<7", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{?job_name=", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=6", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=5", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=4", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=3", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_preserved>0", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=4", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state=3", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{job_state<7", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished looping on "job_id"...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1785 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{#job_id=0", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 1786 on EOF...
D [13/Nov/2007:17:34:02 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [13/Nov/2007:17:34:02 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:02 +0100] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{PREVURL?" at 87, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 87...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{PREVURL}" at 105...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 191 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 191...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 198 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{PREVURL?", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 247...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{THISURL}" at 265...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 367 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 367...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{THISURL}" at 385...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 489 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{ORDER=dec", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 535...
D [13/Nov/2007:17:34:02 +0100] [CGI] "{NEXTURL}" at 553...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 631 on character ':'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 631...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 638 on character '}'...
D [13/Nov/2007:17:34:02 +0100] [CGI] Finished "{NEXTURL?", out=0xb7d8b4c0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 659 on EOF...
D [13/Nov/2007:17:34:02 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [13/Nov/2007:17:34:02 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:02 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [13/Nov/2007:17:34:02 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:02 +0100] [CGI] Returning at file position 614 on EOF...
D [13/Nov/2007:17:34:09 +0100] cupsdReadClient: 8 GET /jobs/?op=restart-job&job_id=4&job_printer_uri=/printers/HP_DESKJET_845C_USB_1 HTTP/1.1
D [13/Nov/2007:17:34:09 +0100] cupsdReadClient: 8 Browser asked for language "en.utf-8"...
D [13/Nov/2007:17:34:09 +0100] cupsdAuthorize: username="root"
I [13/Nov/2007:17:34:09 +0100] commptr="?op=restart-job&job_id=4&job_printer_uri=/printers/HP_DESKJET_845C_USB_1"
D [13/Nov/2007:17:34:09 +0100] CGI /usr/libexec/cups/cgi-bin/jobs.cgi started - PID = 5899
I [13/Nov/2007:17:34:09 +0100] Started "/usr/libexec/cups/cgi-bin/jobs.cgi" (pid=5899)
D [13/Nov/2007:17:34:09 +0100] cupsdSendCommand: 8 file=12
D [13/Nov/2007:17:34:09 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [13/Nov/2007:17:34:09 +0100] cupsdReadClient: 16 POST /jobs HTTP/1.1
D [13/Nov/2007:17:34:09 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:17:34:09 +0100] Restart-Job ipp://localhost/jobs/4
D [13/Nov/2007:17:34:09 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:17:34:09 +0100] job-sheets=none,none
D [13/Nov/2007:17:34:09 +0100] banner_page = 0
D [13/Nov/2007:17:34:09 +0100] [Job 4] argv[0]="HP_DESKJET_845C_USB_1"
D [13/Nov/2007:17:34:09 +0100] [Job 4] argv[1]="4"
D [13/Nov/2007:17:34:09 +0100] [Job 4] argv[2]="user"
D [13/Nov/2007:17:34:09 +0100] [Job 4] argv[3]="(stdin)"
D [13/Nov/2007:17:34:09 +0100] [Job 4] argv[4]="1"
D [13/Nov/2007:17:34:09 +0100] [Job 4] argv[5]="A4 media=A4 job-uuid=urn:uuid:333b95cf-21df-3770-4138-36805863d06b"
D [13/Nov/2007:17:34:09 +0100] [Job 4] argv[6]="/var/spool/cups/d00004-001"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[8]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[9]="SERVER_ADMIN=root@fr33z.no-ip.org"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[10]="SOFTWARE=CUPS/1.2.10"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[12]="USER=root"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[15]="IPP_PORT=631"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[16]="CHARSET=utf-8"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[17]="LANG=en_US"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[18]="PPD=/etc/cups/ppd/HP_DESKJET_845C_USB_1.ppd"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[19]="RIP_MAX_CACHE=8m"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[20]="CONTENT_TYPE=application/postscript"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[21]="DEVICE_URI=usb://HP/DESKJET%20845C?serial=TH1CD1615CSX"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[22]="PRINTER=HP_DESKJET_845C_USB_1"
D [13/Nov/2007:17:34:09 +0100] [Job 4] envp[23]="FINAL_CONTENT_TYPE=printer/HP_DESKJET_845C_USB_1"
I [13/Nov/2007:17:34:09 +0100] Started filter /usr/libexec/cups/filter/pstops (PID 5900) for job 4.
I [13/Nov/2007:17:34:09 +0100] Started filter /usr/libexec/cups/filter/foomatic-rip (PID 5901) for job 4.
I [13/Nov/2007:17:34:09 +0100] Started backend /usr/libexec/cups/backend/usb (PID 5902) for job 4.
D [13/Nov/2007:17:34:09 +0100] Discarding unused job-state event...
I [13/Nov/2007:17:34:09 +0100] Job 4 was restarted by "root".
D [13/Nov/2007:17:34:09 +0100] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [13/Nov/2007:17:34:09 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:17:34:09 +0100] PID 5899 (/usr/libexec/cups/cgi-bin/jobs.cgi) exited with no errors.
D [13/Nov/2007:17:34:09 +0100] [Job 4] Page = 595x842; 0,0 to 595,842
D [13/Nov/2007:17:34:09 +0100] [Job 4] slow_collate=0, slow_duplex=0, slow_order=0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Before copy_comments - %!PS-Adobe-1.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] %!PS-Adobe-1.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] %%BoundingBox: 0 0 595 842
D [13/Nov/2007:17:34:09 +0100] [Job 4] %%Creator: Qt 3.3.8
D [13/Nov/2007:17:34:09 +0100] [Job 4] %%CreationDate: Thu Jul 19 19:32:12 2007
D [13/Nov/2007:17:34:09 +0100] [Job 4] %%Orientation: Portrait
D [13/Nov/2007:17:34:09 +0100] [Job 4] %%Pages: 1
D [13/Nov/2007:17:34:09 +0100] [Job 4] %%DocumentFonts: TimesNewRomanPSMT Arial-BoldMT ArialMT
D [13/Nov/2007:17:34:09 +0100] [Job 4] Before copy_prolog - 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Before copy_setup - %%BeginSetup
D [13/Nov/2007:17:34:09 +0100] [Job 4] foomatic-rip version $Revision$ running...
D [13/Nov/2007:17:34:09 +0100] [Job 4] Parsing PPD file ...
D [13/Nov/2007:17:34:09 +0100] [Job 4] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option ColorSpace
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option PageSize
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option PageRegion
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option ImageableArea
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option PaperDimension
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option MediaType
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option Quality
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option RET
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option GammaGeneral
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option GammaCyan
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option GammaMagenta
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option GammaYellow
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option GammaBlack
D [13/Nov/2007:17:34:09 +0100] [Job 4] Added option Font
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Parameter Summary
D [13/Nov/2007:17:34:09 +0100] [Job 4] -----------------
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Spooler: cups
D [13/Nov/2007:17:34:09 +0100] [Job 4] Printer: HP_DESKJET_845C_USB_1
D [13/Nov/2007:17:34:09 +0100] [Job 4] Shell: /bin/sh
D [13/Nov/2007:17:34:09 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [13/Nov/2007:17:34:09 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:09 +0100] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:09 +0100] [CGI] "{title}" at 205...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting "{refresh_page?" at 375, result=0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 375...
D [13/Nov/2007:17:34:09 +0100] [CGI] "{refresh_page}" at 425...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 428 on character ':'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 428...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 429 on character '}'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Finished "{refresh_page?", out=0xb7d5f4c0...
D [13/Nov/2007:17:34:09 +0100] [CGI] "{title}" at 672...
D [13/Nov/2007:17:34:09 +0100] [CGI] "{title}" at 953...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting "{SECTION=admin" at 1412, result=0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 1412...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 1413 on character ':'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 1413...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 1416 on character '}'...
D [13/Nov/2007:17:34:09 +0100] cupsdCloseClient: 16
D [13/Nov/2007:17:34:09 +0100] [Job 4] PPD file: /etc/cups/ppd/HP_DESKJET_845C_USB_1.ppd
D [13/Nov/2007:17:34:09 +0100] [Job 4] ATTR file: 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Printer model: HP DeskJet 845C Foomatic/cdj670
D [13/Nov/2007:17:34:09 +0100] [Job 4] Job title: stdin
D [13/Nov/2007:17:34:09 +0100] [Job 4] File(s) to be printed: 
D [13/Nov/2007:17:34:09 +0100] [Job 4] <STDIN>
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [13/Nov/2007:17:34:09 +0100] [Job 4] Pondering option 'A4'
D [13/Nov/2007:17:34:09 +0100] [Job 4] Unknown boolean option "A4".
D [13/Nov/2007:17:34:09 +0100] [Job 4] Pondering option 'media=A4'
D [13/Nov/2007:17:34:09 +0100] [Job 4] Pondering option 'job-uuid=urn:uuid:333b95cf-21df-3770-4138-36805863d06b'
D [13/Nov/2007:17:34:09 +0100] [Job 4] Unknown option job-uuid=urn:uuid:333b95cf-21df-3770-4138-36805863d06b.
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] ================================================
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] File: <STDIN>
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] ================================================
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Reading PostScript input ...
D [13/Nov/2007:17:34:09 +0100] [Job 4] --> This document is DSC-conforming!
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] -----------
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginProlog
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%EndProlog
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] -----------
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginSetup
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *PageSize A4
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: PageSize=A4 --> Option will be set by PostScript interpreter
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *MediaType plain
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: MediaType=plain --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: MediaType=plain
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: MediaType=plain --> Setting option
D [13/Nov/2007:17:34:09 +0100] [CGI] Finished "{SECTION=admin", out=0xb7d5f4c0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting "{SECTION=classes" at 1679, result=0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 1679...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 1680 on character ':'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 1680...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 1683 on character '}'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Finished "{SECTION=classes", out=0xb7d5f4c0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting "{SECTION=help" at 1939, result=0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 1939...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 1940 on character ':'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 1940...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 1943 on character '}'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Finished "{SECTION=help", out=0xb7d5f4c0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting "{SECTION=jobs" at 2207, result=1...
D [13/Nov/2007:17:34:09 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 2207...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 2208 on character ':'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *Quality normal
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: Quality=normal --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: Quality=normal
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: Quality=normal --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *RET on
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: RET=on --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: RET=on
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: RET=on --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *GammaGeneral 1.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaGeneral=1.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: GammaGeneral=1.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaGeneral=1.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *GammaCyan 0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaCyan=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: GammaCyan=0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaCyan=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *GammaMagenta 0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaMagenta=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: GammaMagenta=0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaMagenta=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *GammaYellow 0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaYellow=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: GammaYellow=0.0
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 2208...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 2211 on character '}'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7d5f4c0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting "{SECTION=printers" at 2465, result=0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 2465...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 2466 on character ':'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 2466...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 2469 on character '}'...
D [13/Nov/2007:17:34:09 +0100] [CGI] Finished "{SECTION=printers", out=0xb7d5f4c0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 2831 on EOF...
D [13/Nov/2007:17:34:09 +0100] [CGI] cgiCopyTemplateLang(tmpl="job-restart.tmpl")
D [13/Nov/2007:17:34:09 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:09 +0100] [CGI] Template file is "/usr/share/cups/templates/job-restart.tmpl"...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:09 +0100] [CGI] "{job_printer_uri}" at 29...
D [13/Nov/2007:17:34:09 +0100] [CGI] "{job_id}" at 43...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 68 on EOF...
D [13/Nov/2007:17:34:09 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [13/Nov/2007:17:34:09 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:09 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaYellow=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginFeature: *GammaBlack 0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaBlack=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %% FoomaticRIPOptionSetting: GammaBlack=0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] Option: GammaBlack=0.0 --> Setting option
D [13/Nov/2007:17:34:09 +0100] [Job 4] Printer using device file "/dev/usb/lp0"...
D [13/Nov/2007:17:34:09 +0100] [Job 4] backendRunLoop(print_fd=0, device_fd=4, use_bc=1)
D [13/Nov/2007:17:34:09 +0100] [Job 4] Before page loop - %%Page: 1 1
D [13/Nov/2007:17:34:09 +0100] [Job 4] Copying page 1...
D [13/Nov/2007:17:34:09 +0100] [Job 4] pagew = 595.0, pagel = 842.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] bboxw = 595, bboxl = 842
D [13/Nov/2007:17:34:09 +0100] [Job 4] PageLeft = 0.0, PageRight = 595.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] PageTop = 842.0, PageBottom = 0.0
D [13/Nov/2007:17:34:09 +0100] [Job 4] PageWidth = 595.0, PageLength = 842.0
D [13/Nov/2007:17:34:09 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:17:34:09 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:09 +0100] [CGI] Returning at file position 614 on EOF...
D [13/Nov/2007:17:34:09 +0100] [Job 4] Wrote 1 pages...
D [13/Nov/2007:17:34:09 +0100] PID 5900 (/usr/libexec/cups/filter/pstops) exited with no errors.
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%EndSetup
D [13/Nov/2007:17:34:09 +0100] [Job 4] Inserting PostScript code for CUPS' page accounting
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] -----------
D [13/Nov/2007:17:34:09 +0100] [Job 4] New page:  1 1
D [13/Nov/2007:17:34:09 +0100] [Job 4] Inserting option code into "PageSetup" section.
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%BeginPageSetup
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found: %%EndPageSetup
D [13/Nov/2007:17:34:09 +0100] [Job 4] End of page header
D [13/Nov/2007:17:34:09 +0100] [Job 4] Stopping search for page header options
D [13/Nov/2007:17:34:09 +0100] [Job 4] Found:
D [13/Nov/2007:17:34:09 +0100] [Job 4] ²õXÂA/*üA*Ú¿¾É5*
D [13/Nov/2007:17:34:09 +0100] [Job 4] --> Output goes directly to the renderer now.
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Starting renderer
D [13/Nov/2007:17:34:09 +0100] [Job 4] JCL: <job data> 
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] renderer PID kid4=5907
D [13/Nov/2007:17:34:09 +0100] [Job 4] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=cdj670 -dPapertype=0 -dQuality=0 -dRetStatus=1 -dMasterGamma=1.000000 -dGammaValC=0.000000 -dGammaValM=0.000000 -dGammaValY=0.000000 -dGammaValK=0.000000 -sOutputFile=- -
D [13/Nov/2007:17:34:09 +0100] [Job 4] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=cdj670' '-dPapertype=0' '-dQuality=0' '-dRetStatus=1' '-dMasterGamma=1.000000' '-dGammaValC=0.000000' '-dGammaValM=0.000000' '-dGammaValY=0.000000' '-dGammaValK=0.000000' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
D [13/Nov/2007:17:34:09 +0100] [Job 4] Unknown device: cdj670
D [13/Nov/2007:17:34:09 +0100] [Job 4] Unrecoverable error: undefined in .uninstallpagedevice
D [13/Nov/2007:17:34:09 +0100] [Job 4] Operand stack:
D [13/Nov/2007:17:34:09 +0100] [Job 4] defaultdevice
D [13/Nov/2007:17:34:09 +0100] [Job 4] renderer return value: 1
D [13/Nov/2007:17:34:09 +0100] [Job 4] renderer received signal: 1
D [13/Nov/2007:17:34:09 +0100] [Job 4] tail process done writing data to STDOUT
D [13/Nov/2007:17:34:09 +0100] [Job 4] KID4 finished
D [13/Nov/2007:17:34:09 +0100] [Job 4] 
D [13/Nov/2007:17:34:09 +0100] [Job 4] Closing renderer
D [13/Nov/2007:17:34:09 +0100] [Job 4] KID4 exited with status 0
D [13/Nov/2007:17:34:09 +0100] [Job 4] KID3 exited with status 3
D [13/Nov/2007:17:34:09 +0100] [Job 4] Renderer exit stat: 3
D [13/Nov/2007:17:34:09 +0100] [Job 4] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [13/Nov/2007:17:34:09 +0100] [Job 4] error: Illegal seek (29)
D [13/Nov/2007:17:34:09 +0100] [Job 4] Possible error on renderer command line or PostScript error. Check options.
D [13/Nov/2007:17:34:09 +0100] [Job 4] Renderer process finished
D [13/Nov/2007:17:34:09 +0100] [Job 4] Killing process 5906 (KID3)
D [13/Nov/2007:17:34:09 +0100] [Job 4] Process dying with "Error closing renderer", exit stat: 3
D [13/Nov/2007:17:34:09 +0100] [Job 4] error: Bad file descriptor (9)
D [13/Nov/2007:17:34:09 +0100] [Job 4] Error closing renderer
E [13/Nov/2007:17:34:09 +0100] PID 5901 (/usr/libexec/cups/filter/foomatic-rip) stopped with status 3!
D [13/Nov/2007:17:34:09 +0100] PID 5902 (/usr/libexec/cups/backend/usb) exited with no errors.
D [13/Nov/2007:17:34:09 +0100] [Job 4] File 0 is complete.
D [13/Nov/2007:17:34:09 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:17:34:09 +0100] Discarding unused job-stopped event...
D [13/Nov/2007:17:34:12 +0100] cupsdReadClient: 7 GET /jobs/ HTTP/1.1
D [13/Nov/2007:17:34:12 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:17:34:12 +0100] cupsdAuthorize: username="root"
I [13/Nov/2007:17:34:12 +0100] commptr=""
D [13/Nov/2007:17:34:12 +0100] CGI /usr/libexec/cups/cgi-bin/jobs.cgi started - PID = 5910
I [13/Nov/2007:17:34:12 +0100] Started "/usr/libexec/cups/cgi-bin/jobs.cgi" (pid=5910)
D [13/Nov/2007:17:34:12 +0100] cupsdSendCommand: 7 file=12
D [13/Nov/2007:17:34:12 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [13/Nov/2007:17:34:12 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{title}" at 205...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{refresh_page?" at 375, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 375...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{refresh_page}" at 425...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 428 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 428...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 429 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{refresh_page?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{title}" at 672...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{title}" at 953...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SECTION=admin" at 1412, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1412...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1413 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1413...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1416 on character '}'...
D [13/Nov/2007:17:34:12 +0100] cupsdAcceptClient: 16 from localhost (Domain)
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SECTION=admin", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SECTION=classes" at 1679, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1679...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1680 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1680...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1683 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SECTION=classes", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SECTION=help" at 1939, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1939...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1940 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1940...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1943 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SECTION=help", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SECTION=jobs" at 2207, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 2207...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 2208 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] cupsdReadClient: 16 POST / HTTP/1.1
D [13/Nov/2007:17:34:12 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:17:34:12 +0100] Get-Jobs ipp://localhost/jobs
D [13/Nov/2007:17:34:12 +0100] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 2208...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 2211 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SECTION=printers" at 2465, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 2465...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 2466 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 2466...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 2469 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SECTION=printers", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 2831 on EOF...
D [13/Nov/2007:17:34:12 +0100] cupsdCloseClient: 16
D [13/Nov/2007:17:34:12 +0100] PID 5910 (/usr/libexec/cups/cgi-bin/jobs.cgi) exited with no errors.
D [13/Nov/2007:17:34:12 +0100] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl")
D [13/Nov/2007:17:34:12 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{SECTION}" at 24...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{?SEARCH_DEST}" at 39...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{WHICH_JOBS?" at 67, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 67...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{WHICH_JOBS}" at 125...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 128 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 128...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 129 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{WHICH_JOBS?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{ORDER?" at 137, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 137...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{ORDER}" at 185...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 188 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 188...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 189 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{ORDER?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SEARCH_DEST?" at 235, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 235...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{SEARCH_DEST}" at 248...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 249 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 249...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SECTION=classes" at 266, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 266...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 274 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 274...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{SECTION=jobs" at 288, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 288...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 293 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 293...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 302 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 303 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SECTION=classes", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 304 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{SEARCH_DEST?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{?QUERY}" at 357...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{SECTION}" at 455...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{?SEARCH_DEST}" at 470...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{WHICH_JOBS?" at 482, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 482...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{WHICH_JOBS}" at 506...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{ORDER?" at 513, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 513...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{ORDER}" at 531...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 532 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 532...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 533 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{ORDER?", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 534 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 534...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{ORDER?" at 541, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 541...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{ORDER}" at 555...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 556 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 556...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 557 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{ORDER?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 558 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{WHICH_JOBS?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 641 on EOF...
D [13/Nov/2007:17:34:12 +0100] [CGI] cgiCopyTemplateLang(tmpl="jobs-header.tmpl")
D [13/Nov/2007:17:34:12 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Template file is "/usr/share/cups/templates/jobs-header.tmpl"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?which_jobs=" at 17, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 17...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?printer_name=" at 42, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 42...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 48 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 48...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{printer_uri_supported}" at 71...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 72 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?printer_name=", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?printer_name=" at 211, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 211...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 217 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 217...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{printer_uri_supported}" at 240...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 241 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?printer_name=", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 333 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 333...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{which_jobs=all" at 349, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 349...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?printer_name=" at 374, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 374...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 380 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 380...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{printer_uri_supported}" at 403...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 404 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?printer_name=" at 543, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 543...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 549 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 549...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{printer_uri_supported}" at 572...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 573 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 656 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 656...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?printer_name=" at 681, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 681...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 687 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 687...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{printer_uri_supported}" at 710...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 711 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?printer_name=" at 823, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 823...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 829 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 829...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{printer_uri_supported}" at 852...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 853 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 945 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{which_jobs=all", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 946 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?which_jobs=", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{total=0" at 983, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 983...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 991 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 991...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{#job_id}" at 1008...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{total}" at 1019...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?which_jobs=" at 1034, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1034...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1041 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1041...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{which_jobs=all" at 1057, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1057...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1058 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1058...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1068 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{which_jobs=all", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1069 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?which_jobs=", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{total=1" at 1082, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1082...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1083 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1083...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1085 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{total=1", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1086 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{total=0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1092 on EOF...
D [13/Nov/2007:17:34:12 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [13/Nov/2007:17:34:12 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{PREVURL?" at 87, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 87...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{PREVURL}" at 105...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 191 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 191...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 198 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{PREVURL?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 247...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{THISURL}" at 265...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 367 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 367...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{THISURL}" at 385...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 489 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{ORDER=dec", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 535...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{NEXTURL}" at 553...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 631 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 631...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 638 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{NEXTURL?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 659 on EOF...
D [13/Nov/2007:17:34:12 +0100] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl")
D [13/Nov/2007:17:34:12 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Template file is "/usr/share/cups/templates/jobs.tmpl"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{#job_id=0" at 11, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 11...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 12 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 12...
D [13/Nov/2007:17:34:12 +0100] [CGI] Looping on "job_id" at 277, count=3...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?job_name=", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=6", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=5", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=4", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=3", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_preserved>0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=4", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=3", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state<7", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?job_name=", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=6", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=5", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=4", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=3", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_preserved>0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=4", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=3", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state<7", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{?job_name=", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=6", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=5", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=4", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=3", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_preserved>0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=4", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state=3", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{job_state<7", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished looping on "job_id"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1785 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{#job_id=0", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 1786 on EOF...
D [13/Nov/2007:17:34:12 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [13/Nov/2007:17:34:12 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{PREVURL?" at 87, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 87...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{PREVURL}" at 105...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 191 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 191...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 198 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{PREVURL?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 247...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{THISURL}" at 265...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 367 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 367...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{THISURL}" at 385...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 489 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{ORDER=dec", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Skip first part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 535...
D [13/Nov/2007:17:34:12 +0100] [CGI] "{NEXTURL}" at 553...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 631 on character ':'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Output second part...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 631...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 638 on character '}'...
D [13/Nov/2007:17:34:12 +0100] [CGI] Finished "{NEXTURL?", out=0xb7e2c4c0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 659 on EOF...
D [13/Nov/2007:17:34:12 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [13/Nov/2007:17:34:12 +0100] [CGI] locale="en"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [13/Nov/2007:17:34:12 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:17:34:12 +0100] [CGI] Returning at file position 614 on EOF...
D [13/Nov/2007:17:34:41 +0100] Unloading job 4...
D [13/Nov/2007:17:34:41 +0100] Unloading job 5...
D [13/Nov/2007:17:35:12 +0100] Unloading job 6...
D [13/Nov/2007:17:35:20 +0100] cupsdCloseClient: 7
D [13/Nov/2007:17:35:20 +0100] cupsdCloseClient: 8
D [13/Nov/2007:17:35:20 +0100] cupsdCloseClient: 15
D [13/Nov/2007:17:35:20 +0100] cupsdCloseClient: 10
D [13/Nov/2007:17:35:20 +0100] cupsdCloseClient: 11
D [13/Nov/2007:17:35:20 +0100] cupsdCloseClient: 13
D [13/Nov/2007:17:35:20 +0100] cupsdCloseClient: 14
D [13/Nov/2007:18:37:48 +0100] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [13/Nov/2007:18:37:48 +0100] cupsdReadClient: 7 GET /jobs/ HTTP/1.1
D [13/Nov/2007:18:37:48 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:48 +0100] cupsdAuthorize: No authentication data provided.
I [13/Nov/2007:18:37:48 +0100] commptr=""
D [13/Nov/2007:18:37:48 +0100] CGI /usr/libexec/cups/cgi-bin/jobs.cgi started - PID = 6210
I [13/Nov/2007:18:37:48 +0100] Started "/usr/libexec/cups/cgi-bin/jobs.cgi" (pid=6210)
D [13/Nov/2007:18:37:48 +0100] cupsdSendCommand: 7 file=8
D [13/Nov/2007:18:37:48 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [13/Nov/2007:18:37:48 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{title}" at 205...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{refresh_page?" at 375, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 375...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{refresh_page}" at 425...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 428 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 428...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 429 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{refresh_page?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{title}" at 672...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{title}" at 953...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SECTION=admin" at 1412, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1412...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1413 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1413...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1416 on character '}'...
D [13/Nov/2007:18:37:48 +0100] cupsdAcceptClient: 10 from localhost (Domain)
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SECTION=admin", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SECTION=classes" at 1679, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1679...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1680 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1680...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1683 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SECTION=classes", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SECTION=help" at 1939, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1939...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1940 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1940...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1943 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SECTION=help", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SECTION=jobs" at 2207, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 2207...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 2208 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] cupsdReadClient: 10 POST / HTTP/1.1
D [13/Nov/2007:18:37:48 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:48 +0100] Get-Jobs ipp://localhost/jobs
D [13/Nov/2007:18:37:48 +0100] Loading attributes for job 4...
D [13/Nov/2007:18:37:48 +0100] Loading attributes for job 5...
D [13/Nov/2007:18:37:48 +0100] Loading attributes for job 6...
D [13/Nov/2007:18:37:48 +0100] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 2208...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 2211 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SECTION=printers" at 2465, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 2465...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 2466 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 2466...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 2469 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SECTION=printers", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 2831 on EOF...
D [13/Nov/2007:18:37:48 +0100] cupsdCloseClient: 10
D [13/Nov/2007:18:37:48 +0100] PID 6210 (/usr/libexec/cups/cgi-bin/jobs.cgi) exited with no errors.
D [13/Nov/2007:18:37:48 +0100] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl")
D [13/Nov/2007:18:37:48 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{SECTION}" at 24...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{?SEARCH_DEST}" at 39...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{WHICH_JOBS?" at 67, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 67...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{WHICH_JOBS}" at 125...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 128 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 128...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 129 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{WHICH_JOBS?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{ORDER?" at 137, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 137...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{ORDER}" at 185...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 188 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 188...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 189 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{ORDER?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SEARCH_DEST?" at 235, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 235...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{SEARCH_DEST}" at 248...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 249 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 249...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SECTION=classes" at 266, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 266...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 274 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 274...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{SECTION=jobs" at 288, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 288...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 293 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 293...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 302 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 303 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SECTION=classes", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 304 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{SEARCH_DEST?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{?QUERY}" at 357...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{SECTION}" at 455...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{?SEARCH_DEST}" at 470...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{WHICH_JOBS?" at 482, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 482...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{WHICH_JOBS}" at 506...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{ORDER?" at 513, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 513...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{ORDER}" at 531...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 532 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 532...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 533 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{ORDER?", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 534 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 534...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{ORDER?" at 541, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 541...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{ORDER}" at 555...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 556 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 556...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 557 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{ORDER?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 558 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{WHICH_JOBS?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 641 on EOF...
D [13/Nov/2007:18:37:48 +0100] [CGI] cgiCopyTemplateLang(tmpl="jobs-header.tmpl")
D [13/Nov/2007:18:37:48 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Template file is "/usr/share/cups/templates/jobs-header.tmpl"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?which_jobs=" at 17, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 17...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?printer_name=" at 42, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 42...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 48 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 48...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{printer_uri_supported}" at 71...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 72 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?printer_name=", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?printer_name=" at 211, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 211...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 217 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 217...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{printer_uri_supported}" at 240...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 241 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?printer_name=", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 333 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 333...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{which_jobs=all" at 349, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 349...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?printer_name=" at 374, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 374...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 380 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 380...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{printer_uri_supported}" at 403...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 404 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?printer_name=" at 543, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 543...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 549 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 549...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{printer_uri_supported}" at 572...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 573 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 656 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 656...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?printer_name=" at 681, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 681...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 687 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 687...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{printer_uri_supported}" at 710...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 711 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?printer_name=" at 823, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 823...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 829 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 829...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{printer_uri_supported}" at 852...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 853 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 945 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{which_jobs=all", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 946 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?which_jobs=", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{total=0" at 983, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 983...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 991 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 991...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{#job_id}" at 1008...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{total}" at 1019...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?which_jobs=" at 1034, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1034...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1041 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1041...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{which_jobs=all" at 1057, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1057...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1058 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1058...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1068 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{which_jobs=all", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1069 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?which_jobs=", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{total=1" at 1082, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1082...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1083 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1083...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1085 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{total=1", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1086 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{total=0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1092 on EOF...
D [13/Nov/2007:18:37:48 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [13/Nov/2007:18:37:48 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{PREVURL?" at 87, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 87...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{PREVURL}" at 105...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 191 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 191...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 198 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{PREVURL?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 247...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{THISURL}" at 265...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 367 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 367...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{THISURL}" at 385...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 489 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{ORDER=dec", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 535...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{NEXTURL}" at 553...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 631 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 631...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 638 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{NEXTURL?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 659 on EOF...
D [13/Nov/2007:18:37:48 +0100] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl")
D [13/Nov/2007:18:37:48 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Template file is "/usr/share/cups/templates/jobs.tmpl"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{#job_id=0" at 11, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 11...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 12 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 12...
D [13/Nov/2007:18:37:48 +0100] [CGI] Looping on "job_id" at 277, count=3...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?job_name=", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=6", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=5", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=4", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=3", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_preserved>0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=4", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=3", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state<7", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?job_name=", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=6", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=5", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=4", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=3", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_preserved>0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=4", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=3", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state<7", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 277...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 339...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_name}" at 359...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 372...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{?job_name=" at 400, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 400...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 408 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 408...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_name}" at 418...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 419 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{?job_name=", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_originating_user_name}" at 462...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_k_octets}" at 492...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 539...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 547 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 547...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{?job_media_sheets_completed}" at 576...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 577 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=3" at 606, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 606...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_creation}" at 641...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 642 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 642...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=4" at 655, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 655...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_creation}" at 687...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 688 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 688...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=5" at 702, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 702...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_processing}" at 742...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 743 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 743...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=6" at 756, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 756...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 764 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 764...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=7" at 778, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 778...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_completed}" at 812...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 813 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 813...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=8" at 826, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 826...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 834 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 834...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{time_at_completed}" at 869...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 870 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 871 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 872 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=6", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 873 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=5", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 874 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=4", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 875 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=3", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_preserved>0" at 909, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 909...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 960...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 998...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1081 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1081...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1082 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_preserved>0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=4" at 1096, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1096...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1147...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1185...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1268 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1268...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1269 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=4", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state=3" at 1283, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1283...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1331...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1369...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1446 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1446...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1447 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state=3", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{job_state<7" at 1461, result=1...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1461...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1511...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_printer_uri}" at 1549...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{job_id}" at 1677...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1753 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 1753...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1754 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{job_state<7", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1774 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished looping on "job_id"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1785 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{#job_id=0", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 1786 on EOF...
D [13/Nov/2007:18:37:48 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [13/Nov/2007:18:37:48 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{PREVURL?" at 87, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 87...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{PREVURL}" at 105...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 191 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 191...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 198 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{PREVURL?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 247...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{THISURL}" at 265...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 367 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 367...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{THISURL}" at 385...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 489 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{ORDER=dec", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 535...
D [13/Nov/2007:18:37:48 +0100] [CGI] "{NEXTURL}" at 553...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 631 on character ':'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Output second part...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 631...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 638 on character '}'...
D [13/Nov/2007:18:37:48 +0100] [CGI] Finished "{NEXTURL?", out=0xb7e204c0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 659 on EOF...
D [13/Nov/2007:18:37:48 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [13/Nov/2007:18:37:48 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [13/Nov/2007:18:37:48 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:37:48 +0100] [CGI] Returning at file position 614 on EOF...
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 7 GET /images/button-search.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 7 file=8
D [13/Nov/2007:18:37:49 +0100] cupsdAcceptClient: 8 from localhost:631 (IPv4)
D [13/Nov/2007:18:37:49 +0100] cupsdAcceptClient: 10 from localhost:631 (IPv4)
D [13/Nov/2007:18:37:49 +0100] cupsdAcceptClient: 11 from localhost:631 (IPv4)
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 7 GET /images/button-sort-descending.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 7 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 8 GET /images/button-clear.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 8 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 8 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 8 GET /images/button-restart-job.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 8 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 8 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 7 GET /images/button-show-completed.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 7 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 10 GET /images/button-cancel-job.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 10 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 10 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 11 GET /images/button-show-all.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 11 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 11 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 11 GET /images/button-move-job.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 11 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 11 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 11 GET /images/button-restart-job.gif HTTP/1.1
D [13/Nov/2007:18:37:49 +0100] cupsdReadClient: 11 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:37:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:37:49 +0100] write_file: 11 file=12
D [13/Nov/2007:18:37:49 +0100] cupsdCloseClient: 8
D [13/Nov/2007:18:38:42 +0100] cupsdAcceptClient: 8 from localhost (Domain)
D [13/Nov/2007:18:38:42 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [13/Nov/2007:18:38:42 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:42 +0100] CUPS-Get-Printers
D [13/Nov/2007:18:38:42 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [13/Nov/2007:18:38:42 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [13/Nov/2007:18:38:42 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:42 +0100] CUPS-Get-Classes
D [13/Nov/2007:18:38:42 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [13/Nov/2007:18:38:42 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [13/Nov/2007:18:38:42 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:42 +0100] CUPS-Get-Default
D [13/Nov/2007:18:38:42 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [13/Nov/2007:18:38:42 +0100] cupsdCloseClient: 8
D [13/Nov/2007:18:38:49 +0100] Unloading job 4...
D [13/Nov/2007:18:38:49 +0100] Unloading job 5...
D [13/Nov/2007:18:38:49 +0100] Unloading job 6...
D [13/Nov/2007:18:38:49 +0100] cupsdAcceptClient: 8 from localhost (Domain)
D [13/Nov/2007:18:38:49 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [13/Nov/2007:18:38:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:49 +0100] CUPS-Get-Printers
D [13/Nov/2007:18:38:49 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [13/Nov/2007:18:38:49 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [13/Nov/2007:18:38:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:49 +0100] CUPS-Get-Classes
D [13/Nov/2007:18:38:49 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [13/Nov/2007:18:38:49 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [13/Nov/2007:18:38:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:49 +0100] CUPS-Get-Default
D [13/Nov/2007:18:38:49 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [13/Nov/2007:18:38:49 +0100] cupsdCloseClient: 8
D [13/Nov/2007:18:38:49 +0100] cupsdAcceptClient: 8 from localhost (Domain)
D [13/Nov/2007:18:38:49 +0100] cupsdReadClient: 8 POST /printers/HP_DESKJET_845C_USB_1 HTTP/1.1
D [13/Nov/2007:18:38:49 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:49 +0100] Print-Job ipp://localhost/printers/HP_DESKJET_845C_USB_1
D [13/Nov/2007:18:38:49 +0100] print_job: auto-typing file...
D [13/Nov/2007:18:38:49 +0100] print_job: request file type is application/postscript.
D [13/Nov/2007:18:38:49 +0100] add_job: requesting-user-name="user"
I [13/Nov/2007:18:38:49 +0100] Adding start banner page "none" to job 7.
D [13/Nov/2007:18:38:49 +0100] Discarding unused job-created event...
I [13/Nov/2007:18:38:49 +0100] Adding end banner page "none" to job 7.
I [13/Nov/2007:18:38:49 +0100] Job 7 queued on "HP_DESKJET_845C_USB_1" by "user".
D [13/Nov/2007:18:38:49 +0100] Job 7 hold_until = 0
D [13/Nov/2007:18:38:49 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:18:38:49 +0100] job-sheets=none,none
D [13/Nov/2007:18:38:49 +0100] banner_page = 0
D [13/Nov/2007:18:38:49 +0100] [Job 7] argv[0]="HP_DESKJET_845C_USB_1"
D [13/Nov/2007:18:38:49 +0100] [Job 7] argv[1]="7"
D [13/Nov/2007:18:38:49 +0100] [Job 7] argv[2]="user"
D [13/Nov/2007:18:38:49 +0100] [Job 7] argv[3]="(stdin)"
D [13/Nov/2007:18:38:49 +0100] [Job 7] argv[4]="1"
D [13/Nov/2007:18:38:49 +0100] [Job 7] argv[5]="A4 media=A4 job-uuid=urn:uuid:1ed45fd0-0f3e-350b-6862-d83b3d1258c2"
D [13/Nov/2007:18:38:49 +0100] [Job 7] argv[6]="/var/spool/cups/d00007-001"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[2]="CUPS_DOCROOT=/usr/share/cups/html"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[5]="CUPS_SERVERBIN=/usr/libexec/cups"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[8]="PATH=/usr/libexec/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[9]="SERVER_ADMIN=root@fr33z.no-ip.org"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[10]="SOFTWARE=CUPS/1.2.10"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[12]="USER=root"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[15]="IPP_PORT=631"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[16]="CHARSET=utf-8"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[17]="LANG=en_US"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[18]="PPD=/etc/cups/ppd/HP_DESKJET_845C_USB_1.ppd"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[19]="RIP_MAX_CACHE=8m"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[20]="CONTENT_TYPE=application/postscript"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[21]="DEVICE_URI=usb://HP/DESKJET%20845C?serial=TH1CD1615CSX"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[22]="PRINTER=HP_DESKJET_845C_USB_1"
D [13/Nov/2007:18:38:49 +0100] [Job 7] envp[23]="FINAL_CONTENT_TYPE=printer/HP_DESKJET_845C_USB_1"
I [13/Nov/2007:18:38:49 +0100] Started filter /usr/libexec/cups/filter/pstops (PID 6230) for job 7.
I [13/Nov/2007:18:38:49 +0100] Started filter /usr/libexec/cups/filter/foomatic-rip (PID 6231) for job 7.
I [13/Nov/2007:18:38:49 +0100] Started backend /usr/libexec/cups/backend/usb (PID 6232) for job 7.
D [13/Nov/2007:18:38:49 +0100] Discarding unused job-state event...
D [13/Nov/2007:18:38:49 +0100] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [13/Nov/2007:18:38:49 +0100] [Job 7] Page = 595x842; 0,0 to 595,842
D [13/Nov/2007:18:38:49 +0100] [Job 7] slow_collate=0, slow_duplex=0, slow_order=0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Before copy_comments - %!PS-Adobe-1.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] %!PS-Adobe-1.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] %%BoundingBox: 0 0 594 842
D [13/Nov/2007:18:38:49 +0100] [Job 7] %%Creator: Qt 3.3.8
D [13/Nov/2007:18:38:49 +0100] [Job 7] %%CreationDate: Tue Nov 13 18:38:49 2007
D [13/Nov/2007:18:38:49 +0100] [Job 7] %%Orientation: Portrait
D [13/Nov/2007:18:38:49 +0100] [Job 7] %%Pages: 1
D [13/Nov/2007:18:38:49 +0100] [Job 7] %%DocumentFonts: TimesNewRomanPSMT CourierNewPSMT CourierNewPS-BoldMT Verdana-Bold Verdana
D [13/Nov/2007:18:38:49 +0100] [Job 7] Before copy_prolog - 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Before copy_setup - %%BeginSetup
D [13/Nov/2007:18:38:49 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:18:38:49 +0100] cupsdCloseClient: 8
D [13/Nov/2007:18:38:49 +0100] [Job 7] foomatic-rip version $Revision$ running...
D [13/Nov/2007:18:38:49 +0100] [Job 7] Parsing PPD file ...
D [13/Nov/2007:18:38:49 +0100] [Job 7] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option ColorSpace
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option PageSize
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option PageRegion
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option ImageableArea
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option PaperDimension
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option MediaType
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option Quality
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option RET
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option GammaGeneral
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option GammaCyan
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option GammaMagenta
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option GammaYellow
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option GammaBlack
D [13/Nov/2007:18:38:49 +0100] [Job 7] Added option Font
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Parameter Summary
D [13/Nov/2007:18:38:49 +0100] [Job 7] -----------------
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Spooler: cups
D [13/Nov/2007:18:38:49 +0100] [Job 7] Printer: HP_DESKJET_845C_USB_1
D [13/Nov/2007:18:38:49 +0100] [Job 7] Shell: /bin/sh
D [13/Nov/2007:18:38:49 +0100] [Job 7] PPD file: /etc/cups/ppd/HP_DESKJET_845C_USB_1.ppd
D [13/Nov/2007:18:38:49 +0100] [Job 7] ATTR file: 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Printer model: HP DeskJet 845C Foomatic/cdj670
D [13/Nov/2007:18:38:49 +0100] [Job 7] Job title: stdin
D [13/Nov/2007:18:38:49 +0100] [Job 7] File(s) to be printed: 
D [13/Nov/2007:18:38:49 +0100] [Job 7] <STDIN>
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [13/Nov/2007:18:38:49 +0100] [Job 7] Pondering option 'A4'
D [13/Nov/2007:18:38:49 +0100] [Job 7] Unknown boolean option "A4".
D [13/Nov/2007:18:38:49 +0100] [Job 7] Pondering option 'media=A4'
D [13/Nov/2007:18:38:49 +0100] [Job 7] Pondering option 'job-uuid=urn:uuid:1ed45fd0-0f3e-350b-6862-d83b3d1258c2'
D [13/Nov/2007:18:38:49 +0100] [Job 7] Unknown option job-uuid=urn:uuid:1ed45fd0-0f3e-350b-6862-d83b3d1258c2.
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] ================================================
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] File: <STDIN>
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] ================================================
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Reading PostScript input ...
D [13/Nov/2007:18:38:49 +0100] [Job 7] --> This document is DSC-conforming!
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] -----------
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginProlog
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%EndProlog
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] -----------
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginSetup
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *PageSize A4
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: PageSize=A4 --> Option will be set by PostScript interpreter
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *MediaType plain
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: MediaType=plain --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: MediaType=plain
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: MediaType=plain --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *Quality normal
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: Quality=normal --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: Quality=normal
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: Quality=normal --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *RET on
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: RET=on --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: RET=on
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: RET=on --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *GammaGeneral 1.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaGeneral=1.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: GammaGeneral=1.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaGeneral=1.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *GammaCyan 0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaCyan=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: GammaCyan=0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaCyan=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *GammaMagenta 0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaMagenta=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: GammaMagenta=0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaMagenta=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *GammaYellow 0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaYellow=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: GammaYellow=0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaYellow=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginFeature: *GammaBlack 0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaBlack=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %% FoomaticRIPOptionSetting: GammaBlack=0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Option: GammaBlack=0.0 --> Setting option
D [13/Nov/2007:18:38:49 +0100] [Job 7] Printer using device file "/dev/usb/lp0"...
D [13/Nov/2007:18:38:49 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:18:38:49 +0100] [Job 7] backendRunLoop(print_fd=0, device_fd=4, use_bc=1)
D [13/Nov/2007:18:38:49 +0100] [Job 7] Before page loop - %%Page: 1 1
D [13/Nov/2007:18:38:49 +0100] [Job 7] Copying page 1...
D [13/Nov/2007:18:38:49 +0100] [Job 7] pagew = 595.0, pagel = 842.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] bboxw = 595, bboxl = 842
D [13/Nov/2007:18:38:49 +0100] [Job 7] PageLeft = 0.0, PageRight = 595.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] PageTop = 842.0, PageBottom = 0.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] PageWidth = 595.0, PageLength = 842.0
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%EndSetup
D [13/Nov/2007:18:38:49 +0100] [Job 7] Inserting PostScript code for CUPS' page accounting
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] -----------
D [13/Nov/2007:18:38:49 +0100] [Job 7] New page:  1 1
D [13/Nov/2007:18:38:49 +0100] [Job 7] Inserting option code into "PageSetup" section.
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%BeginPageSetup
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found: %%EndPageSetup
D [13/Nov/2007:18:38:49 +0100] [Job 7] End of page header
D [13/Nov/2007:18:38:49 +0100] [Job 7] Stopping search for page header options
D [13/Nov/2007:18:38:49 +0100] [Job 7] Found:
D [13/Nov/2007:18:38:49 +0100] [Job 7] ì*ß¿SFñ¾±°¶º1+.AÓðPFÐÖÄùï¶Ç8õûîJ+¾ÂU*î=PÊRÏL/5@Ê/4>RÂ/âL*¿/µÞððAÌ7â×àÚ¯ÀÉò¶J
D [13/Nov/2007:18:38:49 +0100] [Job 7] --> Output goes directly to the renderer now.
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Starting renderer
D [13/Nov/2007:18:38:49 +0100] [Job 7] JCL: <job data> 
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] renderer PID kid4=6237
D [13/Nov/2007:18:38:49 +0100] [Job 7] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=cdj670 -dPapertype=0 -dQuality=0 -dRetStatus=1 -dMasterGamma=1.000000 -dGammaValC=0.000000 -dGammaValM=0.000000 -dGammaValY=0.000000 -dGammaValK=0.000000 -sOutputFile=- -
D [13/Nov/2007:18:38:49 +0100] [Job 7] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=cdj670' '-dPapertype=0' '-dQuality=0' '-dRetStatus=1' '-dMasterGamma=1.000000' '-dGammaValC=0.000000' '-dGammaValM=0.000000' '-dGammaValY=0.000000' '-dGammaValK=0.000000' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
D [13/Nov/2007:18:38:49 +0100] [Job 7] Unknown device: cdj670
D [13/Nov/2007:18:38:49 +0100] [Job 7] Unrecoverable error: undefined in .uninstallpagedevice
D [13/Nov/2007:18:38:49 +0100] [Job 7] Operand stack:
D [13/Nov/2007:18:38:49 +0100] [Job 7] defaultdevice
D [13/Nov/2007:18:38:49 +0100] [Job 7] renderer return value: 1
D [13/Nov/2007:18:38:49 +0100] [Job 7] renderer received signal: 1
D [13/Nov/2007:18:38:49 +0100] [Job 7] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [13/Nov/2007:18:38:49 +0100] [Job 7] error: Illegal seek (29)
D [13/Nov/2007:18:38:49 +0100] [Job 7] Possible error on renderer command line or PostScript error. Check options.
D [13/Nov/2007:18:38:49 +0100] [Job 7] tail process done writing data to STDOUT
D [13/Nov/2007:18:38:49 +0100] [Job 7] KID4 finished
D [13/Nov/2007:18:38:49 +0100] [Job 7] Wrote 1 pages...
D [13/Nov/2007:18:38:49 +0100] PID 6230 (/usr/libexec/cups/filter/pstops) exited with no errors.
D [13/Nov/2007:18:38:49 +0100] [Job 7] 
D [13/Nov/2007:18:38:49 +0100] [Job 7] Closing renderer
D [13/Nov/2007:18:38:49 +0100] [Job 7] KID3 exited with status 3
D [13/Nov/2007:18:38:49 +0100] [Job 7] Renderer exit stat: 3
D [13/Nov/2007:18:38:49 +0100] [Job 7] Renderer process finished
D [13/Nov/2007:18:38:49 +0100] [Job 7] Killing process 6236 (KID3)
D [13/Nov/2007:18:38:49 +0100] [Job 7] Process dying with "Error closing renderer", exit stat: 3
D [13/Nov/2007:18:38:49 +0100] [Job 7] error: Illegal seek (29)
D [13/Nov/2007:18:38:49 +0100] [Job 7] Error closing renderer
D [13/Nov/2007:18:38:49 +0100] PID 6232 (/usr/libexec/cups/backend/usb) exited with no errors.
E [13/Nov/2007:18:38:49 +0100] PID 6231 (/usr/libexec/cups/filter/foomatic-rip) stopped with status 3!
D [13/Nov/2007:18:38:49 +0100] [Job 7] File 0 is complete.
D [13/Nov/2007:18:38:49 +0100] Discarding unused printer-state-changed event...
D [13/Nov/2007:18:38:49 +0100] Discarding unused job-stopped event...
D [13/Nov/2007:18:38:50 +0100] Unloading job 7...
D [13/Nov/2007:18:38:58 +0100] cupsdReadClient: 7 GET / HTTP/1.1
D [13/Nov/2007:18:38:58 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:38:58 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:38:58 +0100] cupsdSendError: 7 code=304 (Not Modified)
D [13/Nov/2007:18:39:02 +0100] cupsdReadClient: 10 GET /admin HTTP/1.1
D [13/Nov/2007:18:39:02 +0100] cupsdReadClient: 10 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:02 +0100] cupsdAuthorize: No authentication data provided.
I [13/Nov/2007:18:39:02 +0100] commptr=""
D [13/Nov/2007:18:39:02 +0100] CGI /usr/libexec/cups/cgi-bin/admin.cgi started - PID = 6240
I [13/Nov/2007:18:39:02 +0100] Started "/usr/libexec/cups/cgi-bin/admin.cgi" (pid=6240)
D [13/Nov/2007:18:39:02 +0100] cupsdSendCommand: 10 file=8
D [13/Nov/2007:18:39:02 +0100] [CGI] admin.cgi started...
D [13/Nov/2007:18:39:02 +0100] [CGI] http=0x8076028
D [13/Nov/2007:18:39:02 +0100] [CGI] No form data, showing main menu...
D [13/Nov/2007:18:39:02 +0100] cupsdAcceptClient: 12 from localhost (Domain)
D [13/Nov/2007:18:39:02 +0100] cupsdReadClient: 12 POST / HTTP/1.1
D [13/Nov/2007:18:39:02 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:02 +0100] CUPS-Get-Printers
D [13/Nov/2007:18:39:02 +0100] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [13/Nov/2007:18:39:02 +0100] cupsdReadClient: 12 POST / HTTP/1.1
D [13/Nov/2007:18:39:02 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:02 +0100] CUPS-Get-Devices
I [13/Nov/2007:18:39:02 +0100] commptr="1+0+4+requested-attributes=all"
D [13/Nov/2007:18:39:02 +0100] CGI /usr/libexec/cups/daemon/cups-deviced started - PID = 6241
I [13/Nov/2007:18:39:02 +0100] Started "/usr/libexec/cups/daemon/cups-deviced" (pid=6241)
D [13/Nov/2007:18:39:02 +0100] cupsdSendCommand: 12 file=13
D [13/Nov/2007:18:39:02 +0100] [cups-deviced] Added device "ipp"...
D [13/Nov/2007:18:39:02 +0100] [cups-deviced] Added device "lpd"...
D [13/Nov/2007:18:39:02 +0100] [cups-deviced] Added device "scsi"...
D [13/Nov/2007:18:39:03 +0100] [cups-deviced] Added device "socket"...
D [13/Nov/2007:18:39:03 +0100] PID 6241 (/usr/libexec/cups/daemon/cups-deviced) exited with no errors.
D [13/Nov/2007:18:39:03 +0100] [cups-deviced] Added device "usb://HP/DESKJET%20845C?serial=TH1CD1615CSX"...
D [13/Nov/2007:18:39:03 +0100] [cups-deviced] Added device "http"...
D [13/Nov/2007:18:39:03 +0100] PID 6240 (/usr/libexec/cups/cgi-bin/admin.cgi) exited with no errors.
D [13/Nov/2007:18:39:03 +0100] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [13/Nov/2007:18:39:03 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [13/Nov/2007:18:39:03 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:39:03 +0100] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{title}" at 205...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{refresh_page?" at 375, result=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 375...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{refresh_page}" at 425...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 428 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 428...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 429 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{refresh_page?", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{title}" at 672...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{title}" at 953...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{SECTION=admin" at 1412, result=1...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1412...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 1413 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1413...
D [13/Nov/2007:18:39:03 +0100] cupsdCloseClient: 12
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 1416 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{SECTION=admin", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{SECTION=classes" at 1679, result=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1679...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 1680 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1680...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 1683 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{SECTION=classes", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{SECTION=help" at 1939, result=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1939...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 1940 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1940...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 1943 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{SECTION=help", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{SECTION=jobs" at 2207, result=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 2207...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 2208 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 2208...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 2211 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{SECTION=jobs", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{SECTION=printers" at 2465, result=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 2465...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 2466 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 2466...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 2469 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{SECTION=printers", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 2831 on EOF...
D [13/Nov/2007:18:39:03 +0100] [CGI] cgiCopyTemplateLang(tmpl="admin.tmpl")
D [13/Nov/2007:18:39:03 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:39:03 +0100] [CGI] Template file is "/usr/share/cups/templates/admin.tmpl"...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{have_samba?" at 384, result=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 384...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 512 on character ':'...
D [13/Nov/2007:18:39:03 +0100] cupsdAcceptClient: 8 from localhost:631 (IPv4)
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 7 GET /images/button-view-access-log.gif HTTP/1.1
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:03 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:03 +0100] write_file: 7 file=12
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 10 GET /images/button-view-error-log.gif HTTP/1.1
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 10 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:03 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:03 +0100] write_file: 10 file=13
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 11 GET /images/button-edit-configuration-file.gif HTTP/1.1
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 11 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:03 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:03 +0100] write_file: 11 file=14
D [13/Nov/2007:18:39:03 +0100] [CGI] Output second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 512...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 513 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{have_samba?", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{#device_uri=0" at 535, result=1...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 535...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 536 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 536...
D [13/Nov/2007:18:39:03 +0100] [CGI] Looping on "device_uri" at 586, count=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 586...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{device_options}" at 642...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{device_make_and_model}" at 772...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{device_info}" at 787...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 795 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished looping on "device_uri"...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 801 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{#device_uri=0", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting "{SETTINGS_ERROR?" at 1850, result=0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Skip first part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1850...
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 8 GET /images/button-view-page-log.gif HTTP/1.1
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 8 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:03 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:03 +0100] write_file: 8 file=12
D [13/Nov/2007:18:39:03 +0100] [CGI] "{SETTINGS_MESSAGE}" at 1871...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{SETTINGS_ERROR}" at 1904...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 1918 on character ':'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Output second part...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 1918...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{?remote_printers}" at 2117...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{?share_printers}" at 2223...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{?remote_admin}" at 2337...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{?user_cancel_any}" at 2435...
D [13/Nov/2007:18:39:03 +0100] [CGI] "{?debug_logging}" at 2552...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 2706 on character '}'...
D [13/Nov/2007:18:39:03 +0100] [CGI] Finished "{SETTINGS_ERROR?", out=0xb7e364c0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 2728 on EOF...
D [13/Nov/2007:18:39:03 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [13/Nov/2007:18:39:03 +0100] [CGI] locale="en"...
D [13/Nov/2007:18:39:03 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [13/Nov/2007:18:39:03 +0100] [CGI] Starting at file position 0...
D [13/Nov/2007:18:39:03 +0100] [CGI] Returning at file position 614 on EOF...
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 7 GET /images/button-change-settings.gif HTTP/1.1
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:03 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:03 +0100] write_file: 7 file=13
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 11 GET /images/button-change-settings.gif HTTP/1.1
D [13/Nov/2007:18:39:03 +0100] cupsdReadClient: 11 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:03 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:03 +0100] write_file: 11 file=14
D [13/Nov/2007:18:39:03 +0100] cupsdCloseClient: 11
D [13/Nov/2007:18:39:13 +0100] cupsdReadClient: 7 GET /admin/log/access_log HTTP/1.1
D [13/Nov/2007:18:39:13 +0100] cupsdReadClient: 7 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:13 +0100] cupsdAuthorize: No authentication data provided.
D [13/Nov/2007:18:39:13 +0100] write_file: 7 file=11
D [13/Nov/2007:18:39:17 +0100] cupsdReadClient: 10 GET /admin/log/error_log HTTP/1.1
D [13/Nov/2007:18:39:17 +0100] cupsdReadClient: 10 Browser asked for language "en.utf-8"...
D [13/Nov/2007:18:39:17 +0100] cupsdAuthorize: No authentication data provided.