[opensuse] cups refuses to print a certain page.

-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 Hi, I was printing a 300 page job, page by page, from a script (that adds a label), but it get stuck at page 163: psselect -p$PAGE $FILE /tmp/pagina.ps echo "Sending page $PAGE to default printer" lp /tmp/pagina.ps -o page-label="$PAGE" lpq shows the pages as "printing": tp0 is ready and printing Rank Owner Job File(s) Total Size active cer 1409 pagina.ps 57344 bytes It seems to be printing, looking at the process list: 18564 ? Ss 0:00 /usr/sbin/cupsd -c /etc/cups/cupsd.conf 18570 ? S 0:00 \_ /bin/bash /usr/lib/cups/filter/pstoturboprint 1409 cer pagina.ps 1 page-label=163 cpi=10 lpi=6 InkType=Black_0 MediaType=Plainpaper_0 _kde-filters zedoColorModel=Gray PageSize=A4 Resolution=360x360dpi_1 zedoDithering=ErrorDiffusion InputSlot=AutomaticFeed zedoLicensedTo=LicenseOwner zedoColorCorrection=1 zedoUserColor=0 zedoMirror=0 zedoDuplexAdjust=0 zedoBrightness=0 zedoContrast=0 zedoGamut=0 zedoIntensity=0 zedoGamma=180 zedoColorY=0 zedoColorC=0 zedoColorM=0 zedoColorK=0 job-uuid=urn:uuid:3b273496-4625-3ca4-6b68-6805247ec89b 18588 ? S 0:00 | \_ /usr/bin/tpprint -a0 -e1 -s8268x11693 -v2 -l/var/log/turboprint_cups.log ---page-label=163 ---cpi=10 ---lpi=6 ---InkType=Black_0 ---MediaType=Plainpaper_0 ---_kde-filters ---zedoColorModel=Gray ---PageSize=A4 ---Resolution=360x360dpi_1 ---zedoDithering=ErrorDiffusion ---InputSlot=AutomaticFeed ---zedoLicensedTo=LicenseOwner ---zedoColorCorrection=1 ---zedoUserColor=0 ---zedoMirror=0 ---zedoDuplexAdjust=0 ---zedoBrightness=0 ---zedoContrast=0 ---zedoGamut=0 ---zedoIntensity=0 ---zedoGamma=180 ---zedoColorY=0 ---zedoColorC=0 ---zedoColorM=0 ---zedoColorK=0 ---job-uuid=urn:uuid:3b273496-4625-3ca4-6b68-6805247ec89b --ppdfile=/etc/cups/ppd/tp0.ppd --psheader=/tmp/pstoturboprint18570.chunk --jobid=1409 --username=cer --title=pagina.ps /tmp/pstoturboprint18570.fifo - 18571 ? S 0:00 \_ parallel:/dev/lp0 1409 cer pagina.ps 1 page-label=163 cpi=10 lpi=6 InkType=Black_0 MediaType=Plainpaper_0 _kde-filters zedoColorModel=Gray PageSize=A4 Resolution=360x360dpi_1 zedoDithering=ErrorDiffusion InputSlot=AutomaticFeed zedoLicensedTo=LicenseOwner zedoColorCorrection=1 zedoUserColor=0 zedoMirror=0 zedoDuplexAdjust=0 zedoBrightness=0 zedoContrast=0 zedoGamut=0 zedoIntensity=0 zedoGamma=180 zedoColorY=0 zedoColorC=0 zedoColorM=0 zedoColorK=0 job-uuid=urn:uuid:3b273496-4625-3ca4-6b68-6805247ec89b but the page 163 gets stuck and never prints. Looking at the /tmp/pagina.ps file with "gv", it is blank; it appears that I tried to print over the end of the book, but I can't understand why cups gets stuck. I have restarted cups in debug mode: D [24/Aug/2007:16:56:22 +0200] add_job: requesting-user-name="cer" I [24/Aug/2007:16:56:22 +0200] Adding start banner page "none" to job 1409. d [24/Aug/2007:16:56:22 +0200] copy_banner(0x800841f8[5], 0x8008a708[1409], none) d [24/Aug/2007:16:56:22 +0200] add_job_state_reasons(0x800841f8[5], 1409) d [24/Aug/2007:16:56:22 +0200] cupsdAddEvent(event=job-created, dest=0x80073528(tp0), job=0x8008a708(1409), text="Job created.", ...) D [24/Aug/2007:16:56:22 +0200] Discarding unused job-created event... d [24/Aug/2007:16:56:22 +0200] add_file(con=0x800841f8[5], job=1409, filetype=application/postscript, compression=0) I [24/Aug/2007:16:56:22 +0200] Adding end banner page "none" to job 1409. d [24/Aug/2007:16:56:22 +0200] copy_banner(0x800841f8[5], 0x8008a708[1409], none) I [24/Aug/2007:16:56:22 +0200] Job 1409 queued on "tp0" by "cer". D [24/Aug/2007:16:56:22 +0200] Job 1409 hold_until = 0 d [24/Aug/2007:16:56:22 +0200] cupsdSaveJob(job=0x8008a708(1409)): job->attrs=0x80087a00 d [24/Aug/2007:16:56:22 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:56:22 +0200] cupsdCheckJobs: Job 1409: state_value=3, loaded=yes d [24/Aug/2007:16:56:22 +0200] start_job: id = 1409, file = 0/1 d [24/Aug/2007:16:56:22 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80073528(tp0), job=(nil)(0), text="%s "%s" state changed.", ...) D [24/Aug/2007:16:56:22 +0200] Discarding unused printer-state-changed event... D [24/Aug/2007:16:56:22 +0200] job-sheets=none,none D [24/Aug/2007:16:56:22 +0200] banner_page = 0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] argv[0]="tp0" D [24/Aug/2007:16:56:22 +0200] [Job 1409] argv[1]="1409" D [24/Aug/2007:16:56:22 +0200] [Job 1409] argv[2]="cer" D [24/Aug/2007:16:56:22 +0200] [Job 1409] argv[3]="pagina.ps" D [24/Aug/2007:16:56:22 +0200] [Job 1409] argv[4]="1" D [24/Aug/2007:16:56:22 +0200] [Job 1409] argv[5]="page-label=163 cpi=10 lpi=6 InkType=Black_0 MediaType=Plainpaper_0 _kde-filters zedoColorModel=Gray PageSize=A4 Resolution=360x360dpi_1 zedoDithering=ErrorDiffusion InputSlot=AutomaticFeed zedoLicensedTo=LicenseOwner zedoColorCorrection=1 zedoUserColor=0 zedoMirror=0 zedoDuplexAdjust=0 zedoBrightness=0 zedoContrast=0 zedoGamut=0 zedoIntensity=0 zedoGamma=180 zedoColorY=0 zedoColorC=0 zedoColorM=0 zedoColorK=0 job-uuid=urn:uuid:3b273496-4625-3ca4-6b68-6805247ec89b" D [24/Aug/2007:16:56:22 +0200] [Job 1409] argv[6]="/var/spool/cups/d01409-001" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[1]="CUPS_DATADIR=/usr/share/cups" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[2]="CUPS_DOCROOT=/usr/share/doc/packages/cups" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[6]="CUPS_SERVERROOT=/etc/cups" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[7]="CUPS_STATEDIR=/var/run/cups" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[9]="SERVER_ADMIN=root@nimrodel.valinor" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[10]="SOFTWARE=CUPS/1.2.7" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[11]="TMPDIR=/var/spool/cups/tmp" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[12]="USER=root" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[13]="CUPS_SERVER=localhost" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[14]="CUPS_ENCRYPTION=IfRequested" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[15]="IPP_PORT=631" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[16]="CHARSET=utf-8" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[17]="LANG=en_US" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[18]="PPD=/etc/cups/ppd/tp0.ppd" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[19]="RIP_MAX_CACHE=8m" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[20]="CONTENT_TYPE=application/postscript" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[21]="DEVICE_URI=parallel:/dev/lp0" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[22]="PRINTER=tp0" D [24/Aug/2007:16:56:22 +0200] [Job 1409] envp[23]="FINAL_CONTENT_TYPE=printer/tp0" d [24/Aug/2007:16:56:22 +0200] start_job: status_pipes = [ 9 10 ] d [24/Aug/2007:16:56:22 +0200] start_job: filter="/usr/lib/cups/filter/pstops" d [24/Aug/2007:16:56:22 +0200] start_job: filterfds[0]=[ 11 12 ] d [24/Aug/2007:16:56:22 +0200] cupsdStartProcess("/usr/lib/cups/filter/pstops", 0x8008a628, 0xbfc16ef8, -1, 12, 10) d [24/Aug/2007:16:56:22 +0200] start_job: Closing filter pipes for slot 1 [ -1 -1 ]... I [24/Aug/2007:16:56:22 +0200] Started filter /usr/lib/cups/filter/pstops (PID 18569) for job 1409. d [24/Aug/2007:16:56:22 +0200] start_job: print_pipes = [ 13 14 ] d [24/Aug/2007:16:56:22 +0200] start_job: filter="/usr/lib/cups/filter/pstoturboprint" d [24/Aug/2007:16:56:22 +0200] start_job: filterfds[1]=[ 13 14 ] d [24/Aug/2007:16:56:22 +0200] cupsdStartProcess("/usr/lib/cups/filter/pstoturboprint", 0x8008a628, 0xbfc16ef8, 11, 14, 10) d [24/Aug/2007:16:56:22 +0200] start_job: Closing filter pipes for slot 0 [ 11 12 ]... I [24/Aug/2007:16:56:22 +0200] Started filter /usr/lib/cups/filter/pstoturboprint (PID 18570) for job 1409. d [24/Aug/2007:16:56:22 +0200] start_job: backend="/usr/lib/cups/backend/parallel" d [24/Aug/2007:16:56:22 +0200] start_job: filterfds[0] = [ -1 -1 ] d [24/Aug/2007:16:56:22 +0200] cupsdStartProcess("/usr/lib/cups/backend/parallel", 0x8008a628, 0xbfc16ef8, 13, -1, 10) I [24/Aug/2007:16:56:22 +0200] Started backend /usr/lib/cups/backend/parallel (PID 18571) for job 1409. d [24/Aug/2007:16:56:22 +0200] start_job: Closing print pipes [ 13 14 ]... d [24/Aug/2007:16:56:22 +0200] start_job: Closing back pipes [ 7 8 ]... d [24/Aug/2007:16:56:22 +0200] start_job: Closing status output pipe 10... d [24/Aug/2007:16:56:22 +0200] start_job: Closing filter pipes for slot 0 [ -1 -1 ]... d [24/Aug/2007:16:56:22 +0200] start_job: Adding fd 9 to InputSet... d [24/Aug/2007:16:56:22 +0200] cupsdAddEvent(event=job-state, dest=0x80073528(tp0), job=0x8008a708(1409), text="Job #%d started.", ...) D [24/Aug/2007:16:56:22 +0200] Discarding unused job-state event... D [24/Aug/2007:16:56:22 +0200] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) d [24/Aug/2007:16:56:22 +0200] cupsdProcessIPPRequest: Adding fd 5 to OutputSet... d [24/Aug/2007:16:56:22 +0200] process_children() D [24/Aug/2007:16:56:22 +0200] PID 18569 (/usr/lib/cups/filter/pstops) exited with no errors. D [24/Aug/2007:16:56:22 +0200] [Job 1409] Page = 595x842; 10,20 to 586,833 D [24/Aug/2007:16:56:22 +0200] [Job 1409] slow_collate=0, slow_duplex=0, slow_order=0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] Before copy_comments - %!PS-Adobe-3.0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %!PS-Adobe-3.0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%Title: (Microsoft Word - ... ) D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%Version: 1 3 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%CreationDate: 11:59:55 AM 08/24/2007 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%For: (Netrufo) D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%DocumentData: Clean7Bit D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%LanguageLevel: 3 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%BoundingBox: 0 0 595 842 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%HiResBoundingBox: 0.0 0.0 595.0 842.0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%Pages: 0 0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%DocumentProcessColors: (atend) D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%DocumentSuppliedResources: D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%+ procset (Adobe Acrobat - PDF operators) 1.2 0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%+ procset (Adobe Acrobat - type operators) 1.2 0 D [24/Aug/2007:16:56:22 +0200] [Job 1409] %%EndComments D [24/Aug/2007:16:56:22 +0200] [Job 1409] Before copy_prolog - %%BeginDefaults D [24/Aug/2007:16:56:22 +0200] [Job 1409] Before copy_setup - %%BeginSetup D [24/Aug/2007:16:56:22 +0200] [Job 1409] Before page loop - %%Trailer D [24/Aug/2007:16:56:22 +0200] [Job 1409] Wrote 0 pages... d [24/Aug/2007:16:56:22 +0200] cupsdWriteClient: 5 bytes < 0 d [24/Aug/2007:16:56:22 +0200] cupsdWriteClient: Removing fd 5 from OutputSet... d [24/Aug/2007:16:56:22 +0200] cupsdReadClient: 5, used=0, file=-1 state=0 d [24/Aug/2007:16:56:22 +0200] cupsdReadClient: httpGets returned EOF... D [24/Aug/2007:16:56:22 +0200] cupsdCloseClient: 5 d [24/Aug/2007:16:56:22 +0200] cupsdCloseClient: Removing fd 5 from InputSet and OutputSet... d [24/Aug/2007:16:56:22 +0200] [Job 1409] STATE: +connecting-to-device d [24/Aug/2007:16:56:22 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80073528(tp0), job=(nil)(0), text="Printer "%s" state changed.", ...) D [24/Aug/2007:16:56:22 +0200] Discarding unused printer-state-changed event... d [24/Aug/2007:16:56:22 +0200] [Job 1409] STATE: -connecting-to-device d [24/Aug/2007:16:56:22 +0200] cupsdAddEvent(event=printer-state-changed, dest=0x80073528(tp0), job=(nil)(0), text="Printer "%s" state changed.", ...) D [24/Aug/2007:16:56:22 +0200] Discarding unused printer-state-changed event... D [24/Aug/2007:16:56:22 +0200] [Job 1409] backendRunLoop(print_fd=0, device_fd=4, use_bc=0) d [24/Aug/2007:16:56:23 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:27 +0200] cupsdAcceptClient(lis=0x80060640) 2 Clients = 0 D [24/Aug/2007:16:56:27 +0200] cupsdAcceptClient: 5 from localhost:631 (IPv4) d [24/Aug/2007:16:56:27 +0200] cupsdAcceptClient: 5 connected to server on localhost:631 d [24/Aug/2007:16:56:27 +0200] cupsdAcceptClient: Adding fd 5 to InputSet... d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=0, file=-1 state=0 D [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 POST / HTTP/1.1 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: uri = "/"... d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location /admin Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location / Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: best = / d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: Authorization="" D [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: No authentication data provided. d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] POST / d [24/Aug/2007:16:56:27 +0200] CONTENT_TYPE = application/ipp d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=350, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=342, file=-1 state=6 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=342, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest(0x8008b998[5]): operation_id = 4002 D [24/Aug/2007:16:56:27 +0200] CUPS-Get-Printers d [24/Aug/2007:16:56:27 +0200] get_printers(0x8008b998[5], 0) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp(p=0x8005a4e0, op=4002(CUPS-Get-Printers)) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp: Found wildcard match... d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8005b6a8((null)) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: op=0(unknown-0000) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] add_printer_state_reasons(0x8008b998[5], 0x80060868[lp]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x8006be70, ra=0x80084438, group=0, quickcopy=0) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006bf80[printer-name,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006c020[printer-info,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006c1a8[job-sheets-default,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006d190[printer-make-and-model,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x800610b8, ra=0x80084438, group=0, quickcopy=-2147483648) d [24/Aug/2007:16:56:27 +0200] add_printer_state_reasons(0x8008b998[5], 0x8006ca28[lpg]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x8006c460, ra=0x80084438, group=0, quickcopy=0) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006cfd0[printer-name,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006d830[printer-info,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006d8f8[job-sheets-default,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8006d978[printer-make-and-model,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x800610b8, ra=0x80084438, group=0, quickcopy=-2147483648) d [24/Aug/2007:16:56:27 +0200] add_printer_state_reasons(0x8008b998[5], 0x8009e3e8[tf0]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x8009ea30, ra=0x80084438, group=0, quickcopy=0) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8009eaa8[printer-name,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8009eaf8[printer-info,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8009e0a0[job-sheets-default,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x80074b48[printer-make-and-model,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x800610b8, ra=0x80084438, group=0, quickcopy=-2147483648) d [24/Aug/2007:16:56:27 +0200] add_printer_state_reasons(0x8008b998[5], 0x80073528[tp0]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x8009e308, ra=0x80084438, group=0, quickcopy=0) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8009e380[printer-name,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8009e0f8[printer-info,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x8009e1c0[job-sheets-default,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x8008a628, 0x80081660[printer-make-and-model,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x8008a628, from=0x800610b8, ra=0x80084438, group=0, quickcopy=-2147483648) D [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: Adding fd 5 to OutputSet... d [24/Aug/2007:16:56:27 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: 5 bytes < 0 d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: Removing fd 5 from OutputSet... d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=0, file=-1 state=0 D [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 POST / HTTP/1.1 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: uri = "/"... d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location /admin Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location / Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: best = / d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: Authorization="" D [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: No authentication data provided. d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] POST / d [24/Aug/2007:16:56:27 +0200] CONTENT_TYPE = application/ipp d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=350, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=342, file=-1 state=6 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=342, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest(0x8008b998[5]): operation_id = 4005 D [24/Aug/2007:16:56:27 +0200] CUPS-Get-Classes d [24/Aug/2007:16:56:27 +0200] get_printers(0x8008b998[5], 1) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp(p=0x8005a4e0, op=4005(CUPS-Get-Classes)) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp: Found wildcard match... d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8005b6a8((null)) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: op=0(unknown-0000) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... D [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: Adding fd 5 to OutputSet... d [24/Aug/2007:16:56:27 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: 5 bytes < 0 d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: Removing fd 5 from OutputSet... d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=0, file=-1 state=0 D [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 POST / HTTP/1.1 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: uri = "/"... d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location /admin Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location / Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: best = / d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: Authorization="" D [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: No authentication data provided. d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] POST / d [24/Aug/2007:16:56:27 +0200] CONTENT_TYPE = application/ipp d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=75, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=67, file=-1 state=6 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=67, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest(0x8008b998[5]): operation_id = 4001 D [24/Aug/2007:16:56:27 +0200] CUPS-Get-Default d [24/Aug/2007:16:56:27 +0200] get_default(0x8008b998[5]) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp(p=0x8005a4e0, op=4001(CUPS-Get-Default)) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp: Found wildcard match... d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8005b6a8((null)) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: op=0(unknown-0000) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] add_printer_state_reasons(0x8008b998[5], 0x80073528[tp0]) d [24/Aug/2007:16:56:27 +0200] printer-uri-supported="ipp://localhost:631/printers/tp0" d [24/Aug/2007:16:56:27 +0200] add_queued_job_count(0x8008b998[5], 0x80073528[tp0]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x80084ba8, from=0x8009e308, ra=(nil), group=0, quickcopy=0) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e330[uri-authentication-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e358[uri-security-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e380[printer-name,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e3a8[printer-location,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e0f8[printer-info,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e120[printer-more-info,4,45]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e148[job-quota-period,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e170[job-k-limit,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e198[job-page-limit,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e1c0[job-sheets-default,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8009e1f0[device-uri,4,45]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80081218[color-supported,4,22]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80081608[pages-per-minute,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80081660[printer-make-and-model,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80076b88[media-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80076e40[media-default,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80077088[port-monitor,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800770b0[port-monitor-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80073a50[finishings-supported,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800770d8[finishings-default,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80073af8[document-format-supported,4,49]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80073c60[copies-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800771a8[job-hold-until-default,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800771d0[job-priority-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800771f8[number-up-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80077220[orientation-requested-default,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x80084ba8, from=0x800610b8, ra=(nil), group=0, quickcopy=-2147483648) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006be18[charset-configured,4,47]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006d080[charset-supported,4,47]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061738[compression-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bdb0[copies-supported,4,33]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bce0[document-format-default,4,49]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bc88[generated-natural-language-supported,4,48]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800617b8[ipp-versions-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800613c0[job-hold-until-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bc60[job-priority-supported,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800771f8[number-up-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80077220[orientation-requested-default,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x80084ba8, from=0x800610b8, ra=(nil), group=0, quickcopy=-2147483648) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006be18[charset-configured,4,47]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006d080[charset-supported,4,47]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061738[compression-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bdb0[copies-supported,4,33]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bce0[document-format-default,4,49]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bc88[generated-natural-language-supported,4,48]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800617b8[ipp-versions-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800613c0[job-hold-until-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bc60[job-priority-supported,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800615c8[job-sheets-supported,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061320[multiple-document-handling-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006b978[multiple-document-jobs-supported,4,22]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006b1f0[multiple-operation-time-out,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006b190[natural-language-configured,4,48]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006bd60[notify-attributes-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006b130[notify-lease-duration-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006b0d8[notify-lease-duration-supported,4,33]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061880[notify-max-events-supported,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061820[notify-events-default,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006b2c8[notify-events-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061858[notify-pull-method-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006d018[notify-schemes-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006b9c0[number-up-supported,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x8006ba48[operations-supported,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800732e0[orientation-requested-supported,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061350[page-ranges-supported,4,22]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061398[pdl-override-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x80061260[printer-error-policy-supported,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ba8, 0x800610e0[printer-op-policy-supported,4,42]) D [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: Adding fd 5 to OutputSet... d [24/Aug/2007:16:56:27 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: 5 bytes < 0 d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: Removing fd 5 from OutputSet... d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=0, file=-1 state=0 D [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 POST / HTTP/1.1 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: uri = "/"... d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location /admin Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location / Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: best = / d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: Authorization="" D [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: No authentication data provided. d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] POST / d [24/Aug/2007:16:56:27 +0200] CONTENT_TYPE = application/ipp d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=119, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=111, file=-1 state=6 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=111, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest(0x8008b998[5]): operation_id = 000b D [24/Aug/2007:16:56:27 +0200] Get-Printer-Attributes ipp://localhost/printers/tp0 d [24/Aug/2007:16:56:27 +0200] get_printer_attrs(0x8008b998[5], ipp://localhost/printers/tp0) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp(p=0x8005a4e0, op=b(Get-Printer-Attributes)) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp: Found wildcard match... d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8005b6a8((null)) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: op=0(unknown-0000) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] add_printer_state_reasons(0x8008b998[5], 0x80073528[tp0]) d [24/Aug/2007:16:56:27 +0200] printer-uri-supported="ipp://localhost:631/printers/tp0" d [24/Aug/2007:16:56:27 +0200] add_queued_job_count(0x8008b998[5], 0x80073528[tp0]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x800851a8, from=0x8009e308, ra=(nil), group=0, quickcopy=0) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e330[uri-authentication-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e358[uri-security-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e380[printer-name,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e3a8[printer-location,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e0f8[printer-info,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e120[printer-more-info,4,45]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e148[job-quota-period,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e170[job-k-limit,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e198[job-page-limit,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e1c0[job-sheets-default,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8009e1f0[device-uri,4,45]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80081218[color-supported,4,22]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80081608[pages-per-minute,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80081660[printer-make-and-model,4,41]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80076b88[media-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80076e40[media-default,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80077088[port-monitor,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800770b0[port-monitor-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80073a50[finishings-supported,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800770d8[finishings-default,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80073af8[document-format-supported,4,49]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80073c60[copies-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800771a8[job-hold-until-default,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800771d0[job-priority-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800771f8[number-up-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80077220[orientation-requested-default,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x800851a8, from=0x800610b8, ra=(nil), group=0, quickcopy=-2147483648) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006be18[charset-configured,4,47]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006d080[charset-supported,4,47]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061738[compression-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006bdb0[copies-supported,4,33]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006bce0[document-format-default,4,49]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006bc88[generated-natural-language-supported,4,48]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800617b8[ipp-versions-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800613c0[job-hold-until-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006bc60[job-priority-supported,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800615c8[job-sheets-supported,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061320[multiple-document-handling-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006b978[multiple-document-jobs-supported,4,22]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006b1f0[multiple-operation-time-out,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006b190[natural-language-configured,4,48]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006bd60[notify-attributes-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006b130[notify-lease-duration-default,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006b0d8[notify-lease-duration-supported,4,33]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061880[notify-max-events-supported,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061820[notify-events-default,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006b2c8[notify-events-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061858[notify-pull-method-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006d018[notify-schemes-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006b9c0[number-up-supported,4,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x8006ba48[operations-supported,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800732e0[orientation-requested-supported,4,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061350[page-ranges-supported,4,22]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061398[pdl-override-supported,4,44]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x80061260[printer-error-policy-supported,4,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x800851a8, 0x800610e0[printer-op-policy-supported,4,42]) D [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: Adding fd 5 to OutputSet... d [24/Aug/2007:16:56:27 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: 5 bytes < 0 d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: Removing fd 5 from OutputSet... d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=0, file=-1 state=0 D [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 POST / HTTP/1.1 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: uri = "/"... d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location CUPS_INTERNAL_BROWSE_ACL Limit 0 d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location /admin Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: Location / Limit 7f d [24/Aug/2007:16:56:27 +0200] cupsdFindBest: best = / d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: Authorization="" D [24/Aug/2007:16:56:27 +0200] cupsdAuthorize: No authentication data provided. d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x800603a0(/) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] POST / d [24/Aug/2007:16:56:27 +0200] CONTENT_TYPE = application/ipp d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=119, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=111, file=-1 state=6 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5 con->data_encoding=HTTP_ENCODE_LENGTH, con->data_remaining=111, con->file=-1 d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest(0x8008b998[5]): operation_id = 000a D [24/Aug/2007:16:56:27 +0200] Get-Jobs ipp://localhost/printers/tp0 d [24/Aug/2007:16:56:27 +0200] get_jobs(0x8008b998[5], ipp://localhost/printers/tp0) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp(p=0x8005a4e0, op=a(Get-Jobs)) d [24/Aug/2007:16:56:27 +0200] cupsdFindPolicyOp: Found wildcard match... d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: con->uri="/", con->best=0x8005b6a8((null)) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: level=AUTH_ANON, type=AUTH_NONE, satisfy=AUTH_SATISFY_ALL, num_names=0 d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: op=0(unknown-0000) d [24/Aug/2007:16:56:27 +0200] cupsdIsAuthorized: auth=AUTH_ALLOW... d [24/Aug/2007:16:56:27 +0200] get_jobs: job->id = 1409 d [24/Aug/2007:16:56:27 +0200] get_jobs: count = 1 d [24/Aug/2007:16:56:27 +0200] add_job_state_reasons(0x8008b998[5], 1409) d [24/Aug/2007:16:56:27 +0200] copy_attrs(to=0x80084ad8, from=0x80087a00, ra=(nil), group=2, quickcopy=0) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087ae0[printer-uri,2,45]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087b60[job-originating-user-name,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087b88[job-name,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087bf0[document-format,2,49]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087c40[page-label,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087cf0[job-sheets,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087ca8[cpi,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087db8[lpi,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087e20[InkType,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087e88[MediaType,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087ed0[_kde-filters,2,22]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087f20[zedoColorModel,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087f90[PageSize,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80087fd8[Resolution,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80088048[zedoDithering,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x800880c0[InputSlot,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80088108[zedoLicensedTo,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80088180[zedoColorCorrection,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x800881f0[zedoUserColor,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80088260[zedoMirror,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x800882a8[zedoDuplexAdjust,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x800882f8[zedoBrightness,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80088348[zedoContrast,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x80088398[zedoGamut,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x800883e0[zedoIntensity,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a4a0[zedoGamma,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a508[zedoColorY,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a550[zedoColorC,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a598[zedoColorM,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a5e0[zedoColorK,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a6d0[job-priority,2,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a890[job-uuid,2,45]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a950[job-originating-host-name,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008a9c8[time-at-creation,2,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008aa18[time-at-processing,2,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008aa68[time-at-completed,2,13]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008aab8[job-id,2,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008ab00[job-state,2,23]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008ab48[job-media-sheets-completed,2,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008aba0[job-printer-uri,2,45]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008abf0[job-name,2,42]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008ac18[job-k-octets,2,21]) d [24/Aug/2007:16:56:27 +0200] copy_attribute(0x80084ad8, 0x8008ac68[job-hold-until,2,44]) D [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: 5 status_code=0 (successful-ok) d [24/Aug/2007:16:56:27 +0200] cupsdProcessIPPRequest: Adding fd 5 to OutputSet... d [24/Aug/2007:16:56:27 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: 5 bytes < 0 d [24/Aug/2007:16:56:27 +0200] cupsdWriteClient: Removing fd 5 from OutputSet... d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: 5, used=0, file=-1 state=0 d [24/Aug/2007:16:56:27 +0200] cupsdReadClient: httpGets returned EOF... D [24/Aug/2007:16:56:27 +0200] cupsdCloseClient: 5 d [24/Aug/2007:16:56:27 +0200] cupsdCloseClient: Removing fd 5 from InputSet and OutputSet... d [24/Aug/2007:16:56:28 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:39 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:56:39 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:56:39 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:56:50 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:56:50 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:56:50 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:57:01 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:57:01 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:57:01 +0200] stringpool: 1231 strings, 10328 allocated, 18160 total bytes d [24/Aug/2007:16:57:01 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:57:12 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:57:12 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:57:12 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:57:23 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:57:23 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:57:23 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:57:34 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:57:34 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:57:34 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:57:45 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:57:45 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:57:45 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:57:56 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:57:56 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:57:56 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:58:07 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 d [24/Aug/2007:16:58:07 +0200] cupsdCheckJobs: Job 1409: state_value=5, loaded=yes d [24/Aug/2007:16:58:07 +0200] stringpool: 1231 strings, 10328 allocated, 18160 total bytes d [24/Aug/2007:16:58:07 +0200] select_timeout: 11 seconds to process active jobs d [24/Aug/2007:16:58:18 +0200] cupsdCheckJobs: 1 active jobs, sleeping=0, reload=0 And it doesn't print anything. The page is blank, but it should do something, complain of an error, something... but it just stays there, silent. Of course, the error is mine, trying to print a non existing page, but... is it also a bug? :-? - -- Cheers, Carlos Robinson -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.5 (GNU/Linux) Comment: Made with pgp4pine 1.76 iD8DBQFGzvdptTMYHG2NR9URAnFPAJ4i84/tZljf+ZeAg+1xhYuDAZ5dcACfSCep dLvbo063oAMy3/sPnjPIfcQ= =DT22 -----END PGP SIGNATURE----- -- To unsubscribe, e-mail: opensuse+unsubscribe@opensuse.org For additional commands, e-mail: opensuse+help@opensuse.org