deano_ferrari:
I note that you’re using the gutenprint driver, which may (or may not work). You’ll have to examine the CUPS error log (/var/log/cups/error_log) to find out where it is gong wrong.
It might also be worth trying the vendor driver perhaps
PIXMA MG3650 - Support - Download drivers, software and manuals - Canon Europe
This uses it’s own ‘cnijbe’ backend, and the URI will look like ‘cnijbe://Canon/?port=net&serial=…’
My cups error log is clean.
i.e.
D [12/Mar/2021:04:52:51 -0600] Report: stringpool-alloc-bytes=20016
D [12/Mar/2021:04:52:51 -0600] Report: stringpool-total-bytes=703576
D [12/Mar/2021:05:19:31 -0600] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [12/Mar/2021:05:19:31 -0600] [Client 50] Server address is "/run/cups/cups.sock".
D [12/Mar/2021:05:19:31 -0600] [Client 50] Accepted from localhost (Domain)
D [12/Mar/2021:05:19:31 -0600] [Client 50] Waiting for request.
I [12/Mar/2021:05:19:31 -0600] Expiring subscriptions...
D [12/Mar/2021:05:19:31 -0600] cupsdAddCert: Adding certificate for PID 0
D [12/Mar/2021:05:19:31 -0600] Report: clients=1
D [12/Mar/2021:05:19:31 -0600] Report: jobs=9
D [12/Mar/2021:05:19:31 -0600] Report: jobs-active=0
D [12/Mar/2021:05:19:31 -0600] Report: printers=1
D [12/Mar/2021:05:19:31 -0600] Report: stringpool-string-count=36941
D [12/Mar/2021:05:19:31 -0600] Report: stringpool-alloc-bytes=20016
D [12/Mar/2021:05:19:31 -0600] Report: stringpool-total-bytes=703576
D [12/Mar/2021:05:19:31 -0600] [Client 50] POST / HTTP/1.1
D [12/Mar/2021:05:19:31 -0600] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [12/Mar/2021:05:19:31 -0600] [Client 50] Read: status=200, state=6
D [12/Mar/2021:05:19:31 -0600] [Client 50] No authentication data provided.
D [12/Mar/2021:05:19:31 -0600] [Client 50] 2.0 Renew-Subscription 4286
D [12/Mar/2021:05:19:31 -0600] Renew-Subscription /
D [12/Mar/2021:05:19:31 -0600] cupsdIsAuthorized: requesting-user-name="root"
D [12/Mar/2021:05:19:31 -0600] cupsdMarkDirty(----S)
D [12/Mar/2021:05:19:31 -0600] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [12/Mar/2021:05:19:31 -0600] [Client 50] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [12/Mar/2021:05:19:31 -0600] [Client 50] Content-Length: 106
D [12/Mar/2021:05:19:31 -0600] [Client 50] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [12/Mar/2021:05:19:31 -0600] [Client 50] con->http=0x5593592cd4c0
D [12/Mar/2021:05:19:31 -0600] [Client 50] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=106, response=0x5593592c7c30(IPP_STATE_DATA), pipe_pid=0, file=-1
D [12/Mar/2021:05:19:31 -0600] [Client 50] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [12/Mar/2021:05:19:31 -0600] [Client 50] bytes=0, http_state=0, data_remaining=106
D [12/Mar/2021:05:19:31 -0600] [Client 50] Flushing write buffer.
D [12/Mar/2021:05:19:31 -0600] [Client 50] New state is HTTP_STATE_WAITING
D [12/Mar/2021:05:19:31 -0600] [Client 50] Waiting for request.
D [12/Mar/2021:05:19:31 -0600] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [12/Mar/2021:05:19:32 -0600] Expiring subscriptions...
I [12/Mar/2021:05:20:02 -0600] Saving subscriptions.conf...
D [12/Mar/2021:05:20:02 -0600] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [12/Mar/2021:05:20:02 -0600] Expiring subscriptions...
I [12/Mar/2021:05:24:32 -0600] Expiring subscriptions...
D [12/Mar/2021:05:24:32 -0600] cupsdAddCert: Adding certificate for PID 0
D [12/Mar/2021:05:24:32 -0600] Closing client 50 after 300 seconds of inactivity.
D [12/Mar/2021:05:24:32 -0600] [Client 50] Closing connection.
D [12/Mar/2021:05:24:32 -0600] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [12/Mar/2021:05:24:32 -0600] Report: clients=0
D [12/Mar/2021:05:24:32 -0600] Report: jobs=9
D [12/Mar/2021:05:24:32 -0600] Report: jobs-active=0
D [12/Mar/2021:05:24:32 -0600] Report: printers=1
D [12/Mar/2021:05:24:32 -0600] Report: stringpool-string-count=36941
D [12/Mar/2021:05:24:32 -0600] Report: stringpool-alloc-bytes=20016
D [12/Mar/2021:05:24:32 -0600] Report: stringpool-total-bytes=703576
D [12/Mar/2021:05:40:57 -0600] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [12/Mar/2021:05:40:57 -0600] [Client 51] Server address is "/run/cups/cups.sock".
D [12/Mar/2021:05:40:57 -0600] [Client 51] Accepted from localhost (Domain)
D [12/Mar/2021:05:40:57 -0600] [Client 51] Waiting for request.
I [12/Mar/2021:05:40:57 -0600] Expiring subscriptions...
D [12/Mar/2021:05:40:57 -0600] cupsdAddCert: Adding certificate for PID 0
D [12/Mar/2021:05:40:57 -0600] Report: clients=1
D [12/Mar/2021:05:40:57 -0600] Report: jobs=9
D [12/Mar/2021:05:40:57 -0600] Report: jobs-active=0
D [12/Mar/2021:05:40:57 -0600] Report: printers=1
D [12/Mar/2021:05:40:57 -0600] Report: stringpool-string-count=36941
D [12/Mar/2021:05:40:57 -0600] Report: stringpool-alloc-bytes=20016
D [12/Mar/2021:05:40:57 -0600] Report: stringpool-total-bytes=703576
D [12/Mar/2021:05:40:57 -0600] [Client 51] POST / HTTP/1.1
D [12/Mar/2021:05:40:57 -0600] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [12/Mar/2021:05:40:57 -0600] [Client 51] Read: status=200, state=6
D [12/Mar/2021:05:40:57 -0600] [Client 51] No authentication data provided.
D [12/Mar/2021:05:40:57 -0600] [Client 51] 2.0 Renew-Subscription 2446
D [12/Mar/2021:05:40:57 -0600] Renew-Subscription /
D [12/Mar/2021:05:40:57 -0600] cupsdIsAuthorized: requesting-user-name="root"
D [12/Mar/2021:05:40:57 -0600] cupsdMarkDirty(----S)
D [12/Mar/2021:05:40:57 -0600] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [12/Mar/2021:05:40:57 -0600] [Client 51] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [12/Mar/2021:05:40:57 -0600] [Client 51] Content-Length: 106
D [12/Mar/2021:05:40:57 -0600] [Client 51] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [12/Mar/2021:05:40:57 -0600] [Client 51] con->http=0x5593592cd4c0
D [12/Mar/2021:05:40:57 -0600] [Client 51] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=106, response=0x5593590ea9e0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [12/Mar/2021:05:40:57 -0600] [Client 51] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [12/Mar/2021:05:40:57 -0600] [Client 51] bytes=0, http_state=0, data_remaining=106
D [12/Mar/2021:05:40:57 -0600] [Client 51] Flushing write buffer.
D [12/Mar/2021:05:40:57 -0600] [Client 51] New state is HTTP_STATE_WAITING
D [12/Mar/2021:05:40:57 -0600] [Client 51] Waiting for request.
D [12/Mar/2021:05:40:57 -0600] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [12/Mar/2021:05:40:58 -0600] Expiring subscriptions...
I [12/Mar/2021:05:41:28 -0600] Saving subscriptions.conf...
D [12/Mar/2021:05:41:28 -0600] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [12/Mar/2021:05:41:28 -0600] Expiring subscriptions...
I [12/Mar/2021:05:45:58 -0600] Expiring subscriptions...
D [12/Mar/2021:05:45:58 -0600] cupsdAddCert: Adding certificate for PID 0
D [12/Mar/2021:05:45:58 -0600] Closing client 51 after 300 seconds of inactivity.
D [12/Mar/2021:05:45:58 -0600] [Client 51] Closing connection.
D [12/Mar/2021:05:45:58 -0600] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [12/Mar/2021:05:45:58 -0600] Report: clients=0
D [12/Mar/2021:05:45:58 -0600] Report: jobs=9
D [12/Mar/2021:05:45:58 -0600] Report: jobs-active=0
D [12/Mar/2021:05:45:58 -0600] Report: printers=1
D [12/Mar/2021:05:45:58 -0600] Report: stringpool-string-count=36941
D [12/Mar/2021:05:45:58 -0600] Report: stringpool-alloc-bytes=20016
D [12/Mar/2021:05:45:58 -0600] Report: stringpool-total-bytes=703576
D [12/Mar/2021:05:46:10 -0600] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [12/Mar/2021:05:46:10 -0600] [Client 52] Server address is "/run/cups/cups.sock".
D [12/Mar/2021:05:46:10 -0600] [Client 52] Accepted from localhost (Domain)
D [12/Mar/2021:05:46:10 -0600] [Client 52] Waiting for request.
I [12/Mar/2021:05:46:10 -0600] Expiring subscriptions...
D [12/Mar/2021:05:46:10 -0600] [Client 52] POST / HTTP/1.1
D [12/Mar/2021:05:46:10 -0600] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [12/Mar/2021:05:46:10 -0600] [Client 52] Read: status=200, state=6
D [12/Mar/2021:05:46:10 -0600] [Client 52] No authentication data provided.
D [12/Mar/2021:05:46:10 -0600] [Client 52] 2.0 Renew-Subscription 3228
D [12/Mar/2021:05:46:10 -0600] Renew-Subscription /
D [12/Mar/2021:05:46:10 -0600] cupsdIsAuthorized: requesting-user-name="root"
D [12/Mar/2021:05:46:10 -0600] cupsdMarkDirty(----S)
D [12/Mar/2021:05:46:10 -0600] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [12/Mar/2021:05:46:10 -0600] [Client 52] Returning IPP successful-ok for Renew-Subscription (/) from localhost
D [12/Mar/2021:05:46:10 -0600] [Client 52] Content-Length: 106
D [12/Mar/2021:05:46:10 -0600] [Client 52] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [12/Mar/2021:05:46:10 -0600] [Client 52] con->http=0x5593592cd4c0
D [12/Mar/2021:05:46:10 -0600] [Client 52] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=106, response=0x5593592c8140(IPP_STATE_DATA), pipe_pid=0, file=-1
D [12/Mar/2021:05:46:10 -0600] [Client 52] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [12/Mar/2021:05:46:10 -0600] [Client 52] bytes=0, http_state=0, data_remaining=106
D [12/Mar/2021:05:46:10 -0600] [Client 52] Flushing write buffer.
D [12/Mar/2021:05:46:10 -0600] [Client 52] New state is HTTP_STATE_WAITING
D [12/Mar/2021:05:46:10 -0600] [Client 52] Waiting for request.
D [12/Mar/2021:05:46:10 -0600] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [12/Mar/2021:05:46:11 -0600] Expiring subscriptions...
I [12/Mar/2021:05:46:41 -0600] Saving subscriptions.conf...
D [12/Mar/2021:05:46:41 -0600] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
I [12/Mar/2021:05:46:41 -0600] Expiring subscriptions...
I [12/Mar/2021:05:51:11 -0600] Expiring subscriptions...
D [12/Mar/2021:05:51:11 -0600] cupsdAddCert: Adding certificate for PID 0
D [12/Mar/2021:05:51:11 -0600] Closing client 52 after 300 seconds of inactivity.
D [12/Mar/2021:05:51:11 -0600] [Client 52] Closing connection.
D [12/Mar/2021:05:51:11 -0600] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [12/Mar/2021:05:51:11 -0600] Report: clients=0
D [12/Mar/2021:05:51:11 -0600] Report: jobs=9
D [12/Mar/2021:05:51:11 -0600] Report: jobs-active=0
D [12/Mar/2021:05:51:11 -0600] Report: printers=1
D [12/Mar/2021:05:51:11 -0600] Report: stringpool-string-count=36941
D [12/Mar/2021:05:51:11 -0600] Report: stringpool-alloc-bytes=20016
D [12/Mar/2021:05:51:11 -0600] Report: stringpool-total-bytes=703576