Originally posted by: rc015116
Thanks for looking into this issue. https is still not working for me but I'm getting a different error now:
d [16/May/2018:14:56:54 +0600] cupsdAcceptClient(lis=30052528(4)) Clients=1
D [16/May/2018:14:56:54 +0600] [Client 158] Server address is "10.162.7.50".
D [16/May/2018:14:56:54 +0600] [Client 158] Accepted from 10.132.37.125:58778 (IPv4)
d [16/May/2018:14:56:54 +0600] cupsdAddSelect(fd=13, read_cb=300013f4, write_cb=0, data=30492228)
D [16/May/2018:14:56:54 +0600] [Client 158] Waiting for request.
d [16/May/2018:14:56:54 +0600] cupsdCheckJobs: 5 active jobs, sleeping=0, ac-power=-1, reload=0, curtime=1526500614
d [16/May/2018:14:56:54 +0600] cupsdCheckJobs: Job 175 - dest="General_Printer", printer=0, state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
d [16/May/2018:14:56:54 +0600] cupsdCheckJobs: Job 176 - dest="General_Printer", printer=0, state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
d [16/May/2018:14:56:54 +0600] cupsdCheckJobs: Job 177 - dest="General_Printer", printer=0, state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
d [16/May/2018:14:56:54 +0600] cupsdCheckJobs: Job 82887 - dest="jamesprint", printer=0, state=6, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
d [16/May/2018:14:56:54 +0600] cupsdCheckJobs: Job 82800 - dest="rica50", printer=0, state=3, cancel_time=0, hold_until=0, kill_time=0, pending_cost=0, pending_timeout=0
d [16/May/2018:14:56:54 +0600] select_timeout: JobHistoryUpdate=0
d [16/May/2018:14:56:54 +0600] [Client 158] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
d [16/May/2018:14:56:54 +0600] [Client 158] Saw first byte 16, auto-negotiating SSL/TLS session.
E [16/May/2018:14:56:54 +0600] [Client 158] Unable to encrypt connection: The request is invalid.
D [16/May/2018:14:56:54 +0600] [Client 158] Closing connection.
D [16/May/2018:14:56:54 +0600] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
d [16/May/2018:14:56:54 +0600] cupsdRemoveSelect(fd=13)
d [16/May/2018:14:56:54 +0600] cupsdRemoveSelect(fd=-1)
d [16/May/2018:14:56:54 +0600] select_timeout: JobHistoryUpdate=0
d [16/May/2018:14:56:55 +0600] select_timeout: JobHistoryUpdate=0
d [16/May/2018:14:56:55 +0600] select_timeout(0): 11 seconds to start pending jobs
d [16/May/2018:14:56:55 +0600] [Client 157] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=0(), file=-1
D [16/May/2018:14:56:55 +0600] [Client 157] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [16/May/2018:14:56:55 +0600] [Client 157] Closing connection.
D [16/May/2018:14:56:55 +0600] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
d [16/May/2018:14:56:55 +0600] cupsdRemoveSelect(fd=12)
d [16/May/2018:14:56:55 +0600] cupsdRemoveSelect(fd=-1)
d [16/May/2018:14:56:55 +0600] select_timeout: JobHistoryUpdate=0
d [16/May/2018:14:56:56 +0600] select_timeout: JobHistoryUpdate=0
d [16/May/2018:14:56:56 +0600] select_timeout(0): 11 seconds to start pending jobs
cups-2.2.3-3
gnutls-3.5.14-1
AIX 6.1 (same error on AIX 7.1 as well)
From what I can figure out, "The request is invalid" is a gnutls error (GNUTLS_E_INVALID_REQUEST). "Unable to encrypt conection:" is from cupsd_start_tls (in client.c) when calling httpEncryption (in http.c). Within httpEncryption I'm guessing the error happened when calling _httpTLSStart (in tls-gnutls.c). _httpTLSStart is a large method so I'm not sure which part of it failed.