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] 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: 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: 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] 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: 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: 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.