D [16/Nov/2012:18:31:48 +1000] cupsdAcceptClient: skipping getpeercon()
D [16/Nov/2012:18:31:48 +1000] [Client 15] Accepted from 10.33.0.147:631 (IPv4)
D [16/Nov/2012:18:31:48 +1000] Report: clients=1
D [16/Nov/2012:18:31:48 +1000] Report: jobs=235
D [16/Nov/2012:18:31:48 +1000] Report: jobs-active=0
D [16/Nov/2012:18:31:48 +1000] Report: printers=143
D [16/Nov/2012:18:31:48 +1000] Report: printers-implicit=0
D [16/Nov/2012:18:31:48 +1000] Report: stringpool-string-count=1799673
D [16/Nov/2012:18:31:48 +1000] Report: stringpool-alloc-bytes=104776
D [16/Nov/2012:18:31:48 +1000] Report: stringpool-total-bytes=29767296
D [16/Nov/2012:18:31:48 +1000] [Client 15] GET /classes/djZC328.ppd HTTP/1.1
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Nov/2012:18:31:48 +1000] [Client 15] No authentication data provided.
D [16/Nov/2012:18:31:48 +1000] cupsdIsAuthorized: Need upgrade to TLS...
D [16/Nov/2012:18:31:48 +1000] [Client 15] Closing connection.
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] cupsdAcceptClient: skipping getpeercon()
D [16/Nov/2012:18:31:48 +1000] [Client 15] Accepted from 10.33.0.147:631 (IPv4)
D [16/Nov/2012:18:31:48 +1000] [Client 15] OPTIONS * HTTP/1.1
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Nov/2012:18:31:48 +1000] [Client 15] No authentication data provided.
D [16/Nov/2012:18:31:48 +1000] [Client 15] Encrypting connection.
D [16/Nov/2012:18:31:48 +1000] Connection from 10.33.0.147 now encrypted.
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [Client 15] GET /classes/djZC328.ppd HTTP/1.1
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [16/Nov/2012:18:31:48 +1000] [Client 15] No authentication data provided.
D [16/Nov/2012:18:31:48 +1000] [CGI] argv[0] = "/usr/lib/cups/cgi-bin/classes.cgi"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/www"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[10] = "SERVER_ADMIN=root@ipp.eait.uq.edu.au"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[11] = "SOFTWARE=CUPS/1.6.1"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[13] = "USER=root"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[17] = "IPP_PORT=631"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[18] = "LANG=en.UTF8"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[21] = "SERVER_NAME=ipp.eait.uq.edu.au"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[22] = "SERVER_PORT=631"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[23] = "REMOTE_ADDR=10.33.0.147"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[24] = "REMOTE_HOST=10.33.0.147"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[25] = "SCRIPT_NAME=/classes/djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/www/classes/djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[27] = "PATH_INFO=/djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/1.5.4"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[30] = "REQUEST_METHOD=GET"
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[31] = "QUERY_STRING="
D [16/Nov/2012:18:31:48 +1000] [CGI] envp[32] = "HTTPS=ON"
D [16/Nov/2012:18:31:48 +1000] [CGI] Started /usr/lib/cups/cgi-bin/classes.cgi (PID 12275)
I [16/Nov/2012:18:31:48 +1000] [Client 15] Started "/usr/lib/cups/cgi-bin/classes.cgi" (pid=12275)
D [16/Nov/2012:18:31:48 +1000] [Client 15] file=16
D [16/Nov/2012:18:31:48 +1000] [CGI] org.cups.sid cookie not found, initializing!
D [16/Nov/2012:18:31:48 +1000] [CGI] org.cups.sid cookie is "2286dfae7430cf892cf26d71ffa96305"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: SECTION="classes"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: REFRESH_PAGE=""
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: PRINTER_NAME="djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] cupsdAcceptClient: skipping getpeercon()
D [16/Nov/2012:18:31:48 +1000] [Client 17] Accepted from localhost (Domain)
D [16/Nov/2012:18:31:48 +1000] [Client 17] POST / HTTP/1.1
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [Client 17] No authentication data provided.
D [16/Nov/2012:18:31:48 +1000] [Client 17] 2.0 CUPS-Get-Default 1
D [16/Nov/2012:18:31:48 +1000] CUPS-Get-Default
D [16/Nov/2012:18:31:48 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: DEFAULT_NAME="djZC328-a"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: DEFAULT_URI="/printers/djZC328-a"
D [16/Nov/2012:18:31:48 +1000] [Client 17] POST / HTTP/1.1
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [Client 17] No authentication data provided.
D [16/Nov/2012:18:31:48 +1000] [Client 17] 2.0 Get-Printer-Attributes 2
D [16/Nov/2012:18:31:48 +1000] Get-Printer-Attributes ipp://localhost/classes/djZC328.ppd
D [16/Nov/2012:18:31:48 +1000] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [16/Nov/2012:18:31:48 +1000] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost/classes/djZC328.ppd) from localhost
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: SERVER_NAME="ipp.eait.uq.edu.au"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.6.1"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: TITLE="djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: SERVER_NAME="ipp.eait.uq.edu.au"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: CUPS_VERSION="CUPS v1.6.1"
D [16/Nov/2012:18:31:48 +1000] Script header: Set-Cookie: org.cups.sid=2286dfae7430cf892cf26d71ffa96305; path=/;
D [16/Nov/2012:18:31:48 +1000] Script header: Content-Type: text/html;charset=utf-8
D [16/Nov/2012:18:31:48 +1000] Script header: 
D [16/Nov/2012:18:31:48 +1000] [Client 17] POST / HTTP/1.1
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [Client 17] No authentication data provided.
D [16/Nov/2012:18:31:48 +1000] [Client 17] 2.0 Get-Jobs 3
D [16/Nov/2012:18:31:48 +1000] Get-Jobs ipp://localhost:631/printers/djZC328.ppd
D [16/Nov/2012:18:31:48 +1000] Get-Jobs client-error-not-found: The printer or class does not exist.
D [16/Nov/2012:18:31:48 +1000] Returning IPP client-error-not-found for Get-Jobs (ipp://localhost:631/printers/djZC328.ppd) from localhost
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [Client 17] HTTP_WAITING Closing on EOF
D [16/Nov/2012:18:31:48 +1000] [Client 17] Closing connection.
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiClearVariables called.
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: ORDER="asc"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: SECTION="classes"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: TOTAL="0"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: PRINTER_NAME="djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: PRINTER_URI_SUPPORTED="/classes/djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: THISURL="/classes/djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] [CGI] cgiSetVariable: SEARCH_DEST="djZC328.ppd"
D [16/Nov/2012:18:31:48 +1000] PID 12275 (/usr/lib/cups/cgi-bin/classes.cgi) exited with no errors.
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [16/Nov/2012:18:31:48 +1000] [Client 15] HTTP_WAITING Closing on EOF
D [16/Nov/2012:18:31:48 +1000] [Client 15] Closing connection.
D [16/Nov/2012:18:31:48 +1000] SSL shutdown successful!
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [16/Nov/2012:18:31:48 +1000] [Client 15] HTTP_WAITING Closing on EOF
D [16/Nov/2012:18:31:48 +1000] [Client 15] Closing connection.
D [16/Nov/2012:18:31:48 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
I [16/Nov/2012:18:33:45 +1000] Scheduler shutting down normally.
D [16/Nov/2012:18:33:45 +1000] Discarding unused server-stopped event...
I [16/Nov/2012:18:33:45 +1000] Saving job.cache...