IBM i Global

 View Only
Expand all | Collapse all

Getting the SMTP server talking to a Google Relay

  • 1.  Getting the SMTP server talking to a Google Relay

    Posted Wed October 19, 2022 09:17 AM
    I'm in the process of moving some applications onto a cloud box, running V7R5, and I'm running into problems with sending email from that box.

    The CHGSMTPA on the box currently looks like this:

    Autostart server . . . . . . . . AUTOSTART *NO
    Clear e-mail on start-up . . . . COLDSTART *NO
    E-mail directory type . . . . . DIRTYPE *SMTP
    Retries by minute: RTYMIN
    Number of retries . . . . . . 3
    Time interval . . . . . . . . 30
    Retries by day: RTYDAY
    Number of retries . . . . . . 0
    Time interval . . . . . . . . 0
    Retries by hour: RTYHOUR
    Number of retries . . . . . . 0
    Time interval . . . . . . . . 0
    Retry remote name server . . . . RTYRMTSVR *NO
    Coded character set identifier CCSID 819
    Journal . . . . . . . . . . . . JOURNAL *NO
    Percent routing character . . . PCTRTGCHR *NO
    Support ETRN for server . . . . ETRNSVR *NO
    Support 8-bit MIME . . . . . . . MIME8BIT *NO
    Delivery status notification: NFYDLVRY
    Responsible person . . . . . . *NONE
    Subsystem description . . . . . SBSD QSYSWRK
    Library . . . . . . . . . . . QSYS
    Realtime Blackhole List . . . . RBLSVR *NONE
    Allow relayed mail . . . . . . . ALWRLY *NONE
    + for more values
    POP send mail window . . . . . . POPWDW *NONE
    Interface/domain association . . IFCDMN *NONE
    Filter mail for virus . . . . . FTRACN *NONE
    Override reject connect list . . OVRRJTCNNL *NO
    Allow bare line feed . . . . . . ALWBARELF *YES
    Verify identification . . . . . VFYID *NO
    Allow authentication . . . . . . ALWAUTH *NONE
    Verify MSF messages . . . . . . VFYMSFMSG *YES
    Verify from user . . . . . . . . VFYFROMUSR *ALL
    Forwarding mailhub server . . . FWDHUBSVR 'smtp-relay.gmail.com'
    Automatic registration . . . . . AUTOADD *NO
    User ID prefix . . . . . . . . USRIDPFX QSM
    Address . . . . . . . . . . . ADDRESS QSMRMTAD
    System name . . . . . . . . . SYSNAME TCPIP
    Alias table type . . . . . . . TBLTYPE *SYSTEM
    User ID delimiter . . . . . . . USRIDDELIM '?'
    Mail router . . . . . . . . . . MAILROUTER *SAME
    Outgoing EBCDIC/ASCII table: TBLSMTPOUT
    Outgoing EBCDIC/ASCII table . *CCSID
    Library . . . . . . . . . .
    Incoming ASCII/EBCDIC table: TBLSMTPIN
    Incoming ASCII/EBCDIC table . *CCSID
    Library . . . . . . . . . .
    Firewall . . . . . . . . . . . . FIREWALL *NO
    Process all mail through MSF . . ALLMAILMSF *NO
    Dial-up scheduler: DIALSCD
    Start with SMTP server . . . . *NO
    Configuration profile . . . .
    Connection time interval . . .
    Support ETRN for client: ETRNCLT
    Enable client ETRN . . . . . . *NO
    Incoming mail server address

    Mail domain name . . . . . . .
    Allow e-mail over implicit SSL ALWIMPSSL *NO
    Max incoming e-mail messages . . MAXINEMM *DFT
    Max outgoing e-mail messages . . MAXOUTEMM *DFT
    Keep until: KEEPUNTIL
    Successful final state . . . . *DFT
    Unsuccessful final state . . . *DFT
    Maximum e-mail message size . . MAXEMMSIZE *DFT
    SMTP domain alias . . . . . . . ALIASDMN *NONE

    The Google Relay was created to accept anything from the public IP address of the box, with TLS active. Following instructions, I set a QIBM_SMTP_SERVER_PORT environment variable at the system level; in WRKENVVAR, it looks like:

    QIBM_SMTP_SERVER_PORT '587'

    Am I missing anything?

    Incidentally, for some reason, if I attempt to open a case, whether for this or for another issue, under this IBM ID, all I get after signing on is a 503 page. Others don't have that problem. Could there be something wrong with my IBM ID?

    --
    James H. H. Lampert

    ------------------------------
    James H H Lampert
    ------------------------------


  • 2.  RE: Getting the SMTP server talking to a Google Relay

    IBM Champion
    Posted Wed October 19, 2022 08:55 PM
    Dear James

    Have you reached out to the cloud support team on this yet?  It is likely that the cloud environment may have certain network security in place which needs additional action to be taken on either party.

    ------------------------------
    Right action is better than knowledge; but in order to do what is right, we must know what is right.
    -- Charlemagne

    Satid Singkorapoom
    ------------------------------

    Original Message


  • 3.  RE: Getting the SMTP server talking to a Google Relay

    Posted Thu October 20, 2022 09:18 AM
    James,
    Here are some resources to help you. Did you download the CA certificates from Google and add them to your IBM i LPAR?

    Configuration of the IBM i SMTP Client to Relay Email to Office365 and Gmail: https://www.ibm.com/support/pages/node/959599
    How To Configure the SMTP Client To Use SMTP Authentication with a SMTP Relay: https://www.ibm.com/support/pages/node/643091
    Sending Email from IBM i Applications: http://rd.radile.com/rdweb/info2/smtp.html

    You can use this process to see where the breakdown is:
    1. End the SMTP Server: ENDTCPSVR SERVER(*SMTP)
    2. Turn SMTP tracing on: TRCTCPAPP APP(*SMTPCLT) SET(*ON )
    3. Start SMTP: STRTCPSVR SERVER(*SMTP)
    4. Send a test email using SNDSMTPEMM
    5. Wait for 1-2 minutes and then end the trace: TRCTCPAPP APP(*SMTPCLT) SET(*OFF)
    6. View the spool file for information on what is failing: WRFKSPLF (it's usually the larger spool file that is generated)


    You may need to add this additional system value (I believe you said you already had 587 added):
    ADDENVVAR ENVVAR(QIBM_SMTP_RLY_TLS_FIRST) VALUE('YES') LEVEL(*SYS)
    ADDENVVAR ENVVAR(QIBM_SMTP_SERVER_PORT) VALUE('587') LEVEL(*SYS)

    I noticed in your SMTP settings that you do not have it allowing email relay over SSL. You will need to turn that on as well.
    CHGSMTPA FWDHUBSVR('smtp.gmail.com') MAILROUTER('smtp.gmail.com') FIREWALL(*NO) ALWIMPSSL(*YES)

    Every time I have set this up I have had to add authentication as well but you might not need to. This is what that would look like:
    ADDSMTPLE TYPE(*HOSTAUTH) HOSTNAME('smtp.gmail.com') USERNAME(xxxxx@gmail.com) PASSWORD('password')   



    ------------------------------
    Matt Seeberger
    Power i Engineer
    CMA Technology Solutions
    ------------------------------

    Original Message


  • 4.  RE: Getting the SMTP server talking to a Google Relay

    Posted Thu October 20, 2022 03:47 PM
    It took a bit of doing just to get QMGTOOLS/GETSSL running, and when I did get it running, I got error messages every time.

    The most recent iteration still gave me error messages (see joblog excerpts below) but it did give me a couple of .cer files in IFS /tmp.

    QMGTOOLS/GETSSL IP('smtp-relay.gmail.com') PORT(587) STRTLS(Y) SERVICE(SMTP) AUTOIMP(Y) STOREPWD()
    Printer device PRT01 not found. Output queue changed to QPRINT in library QGPL.
    Object SSLRESULTS in QTEMP type *FILE not found.
    Object SSL_LOG in QTEMP type *FILE deleted.
    File SSLRESULTS created in library QTEMP.
    Member SSLRESULTS added to file SSLRESULTS in QTEMP.
    32 records copied from member SSL.
    Object not found. Object is /tmp/RABBIT_sslchain01.cer.
    Object copied.
    Object SSLRESULTS in QTEMP type *FILE deleted.
    Object SSL_LOG in QTEMP type *FILE not found.
    File SSLRESULTS created in library QTEMP.
    Member SSLRESULTS added to file SSLRESULTS in QTEMP.
    31 records copied from member SSL.
    Object not found. Object is /tmp/RABBIT_sslchain02.cer.
    Object copied.
    End of file detected for file SSL in QTEMP.
    Object SSL in QTEMP type *FILE deleted.
    Object SSLCHAIN in QTEMP type *FILE deleted.
    Object SSLRESULTS in QTEMP type *FILE deleted.
    Object CRTFILE in library QTEMP not found.
    Data area CRTFILE created in library QTEMP.
    Object CHK_DBCS in library QTEMP not found.
    Data area CHK_DBCS created in library QTEMP.
    File TMPFILE created in library QTEMP.
    Member TMPFILE added to file TMPFILE in QTEMP.
    Object QSHSTS in library QTEMP not found.
    Data area QSHSTS created in library QTEMP.
    Stream file copied to object.
    An error occurred during certificate validation. The issuer of the certificate may not be in the certificate store or the issuer may not be enabled. RC34..

    I tried manually importing the first cert from DCM, and got more-or-less the same error message as what's at the bottom of the file. Ditto for the second file.
    I then downloaded the two .cer files to my Mac, and inspecting them with Keystore Explorer. They're signed by "CN=GTS Root R1,O=Google Trust Services LLC,C=US" and at this time the only certs in *SYSTEM that I see in DCM are the local CA I created, and a cert generated from it in order to bring up secured TN5250. And I am importing the cert as a CA. How do I get it to accept it?

    ------------------------------
    James H H Lampert
    ------------------------------

    Original Message


  • 5.  RE: Getting the SMTP server talking to a Google Relay

    Posted Fri October 21, 2022 12:18 PM

    The problem is that the *SYSTEM certificate store isn't accepting the certs from Google, because it doesn't recognize the CA that signed them.

    Currently, the only CA cert in the *SYSTEM cert store is the one for the local CA.

    The first cert file contains "GTS CA 1C3" with a subject of "CN=GTS CA 1C3,O=Google Trust Services LLC,C=US" and an issuer of "CN=GTS Root R1,O=Google Trust Services LLC,C=US"

    The second cert file contains "GTS Root R1" with a subject of "CN=GTS Root R1,O=Google Trust Services LLC,C=US" and an issuer of "CN=GlobalSign Root CA,OU=Root CA,O=GlobalSign nv-sa,C=BE"

    And the IBM help page shows an older version of DCM, and the behavior I'm seeing is radically different from what's shown.

    How do I get it to accept the certs? Do I need to sign either or both with the local CA? If so, how do I do that?




    ------------------------------
    James H H Lampert
    ------------------------------

    Original Message


  • 6.  RE: Getting the SMTP server talking to a Google Relay

    IBM Champion
    Posted Mon October 24, 2022 04:28 AM
    Dear James

    Perhaps, you may need to apply some PTFs as discussed in this thread that was about problem in IBM i server certificate : https://community.ibm.com/community/user/power/discussion/import-certificate-store-api-qykmimportkeystore

    ------------------------------
    Right action is better than knowledge; but in order to do what is right, we must know what is right.
    -- Charlemagne

    Satid Singkorapoom
    ------------------------------

    Original Message


  • 7.  RE: Getting the SMTP server talking to a Google Relay

    IBM Champion
    Posted Mon October 24, 2022 07:10 AM
    Simple solution.
    Go into New Navigator.
    Bookmarks
    IBM Digital Certificate Manager for i
    Open or Create the *SYSTEM store
    "Populate with CAs"
    Simple as pie!

    ------------------------------
    Robert Berendt
    ------------------------------

    Original Message


  • 8.  RE: Getting the SMTP server talking to a Google Relay

    Posted Mon October 24, 2022 12:07 PM
    Simple as pie, but I'd never noticed "Populate with CAs" there before.

    Hi there. Small world, isn't it. Or at least, small Midrange community.

    ------------------------------
    James H H Lampert
    ------------------------------

    Original Message


  • 9.  RE: Getting the SMTP server talking to a Google Relay

    IBM Champion
    Posted Mon October 24, 2022 07:42 AM
    You need to extract all certs in the cert chain. You can do that from the cert you pulled down.

    Then you import them in the system store in the order of the chain omitting the actual server cert you downloaded. You're simply looking to establish trust.

    ------------------------------
    Steve Pitcher
    ------------------------------

    Original Message


  • 10.  RE: Getting the SMTP server talking to a Google Relay

    Posted Mon October 24, 2022 12:32 PM
    Ye vish, but this board is cranky. After I posted my reply to Rob, it expected me to post another reply to him, and wouldn't let me post a reply here until I canceled the one above. Not nearly as user-friendly as the BBS software that Fountain Pen Network uses, or what TrekBBS uses.

    Be that as it may, once I pulled in all of the CA certs as per Rob, I tried importing the certs from Google, as CA certs. That didn't work until I tried reverse numeric order. That's weird.

    Still no joy with sending email. I will hopefully be able to check what's happening at the Google end later today.

    One thing: last week, I added "IBM i TCP/IP SMTP Server" and "IBM i TCP/IP SMTP Client" to the application definitions for the local-CA-signed cert I have for TN5250 connections. Could that be interfering? I don't see a way to remove an assignment, other than deleting either the cert or the application definition.

    ------------------------------
    James H H Lampert
    ------------------------------

    Original Message


  • 11.  RE: Getting the SMTP server talking to a Google Relay

    Posted Tue October 25, 2022 12:48 PM
    Looking at DCM with a different browser, I was able to remove the cert assignments, but it didn't make any difference; I get the same results either way.

    On the Google side, I typically get this:
    Typical result of a test message sent using MMAIL/EMLPTUMSG
    and on the Midrange box, I typically get this:
    ID: 004B5A-Q60-002393000000298C20 Time Received: 10/25/22 12:04:00.922
    Recipient:

    E-mail LOG:
    10/25/22 12:04:00 0029 Recipient jamesl@redacted.com transfered to serve
    10/25/22 12:04:00 0029 r directly
    10/25/22 12:04:00 0029 Recipient jhhl@redacted.net transfered to server directly
    10/25/22 12:04:02 003B Marking jamesl@redacted.com as WRK
    10/25/22 12:04:02 003B Marking jhhl@redacted.net as WRK
    10/25/22 12:04:02 003B Connected to Server SMTP-RELAY.GMAIL.COM(142.251.12.28
    10/25/22 12:04:02 003B ):587
    10/25/22 12:04:02 0040 Relay already in processing, skip it
    10/25/22 12:04:04 003F Marking jamesl@redacted.com as NDL
    10/25/22 12:04:04 003F Marking jhhl@redacted.net as NDL

    Anybody have any insights at all?

    ------------------------------
    James H H Lampert
    ------------------------------

    Original Message


  • 12.  RE: Getting the SMTP server talking to a Google Relay

    Posted Wed October 26, 2022 12:33 PM
    I finally got around to trying the TRCTCPAPP that Mr. Seeberger suggested. Here is what I got, sending from the EMLPTUMSG command in the MMAIL package. I have not yet tried ADDENVVAR ENVVAR(QIBM_SMTP_RLY_TLS_FIRST) VALUE('YES') LEVEL(*SYS); as to ALWIMPSSL(*YES), everything I've read on that configuration parameter says it's for accepting incoming secured connections from other email clients, not for sending out SSL.

    Application..................SMTP Client Daemon
    Buffer size (KB).............4096
    Trace full action............*WRAP
    Filter....................... None
    Job id.......................002940/QTCP /QTSMTPCLTD
    Start time...................Wed Oct 26 2022 16:12:13 UTC
    End time.....................Wed Oct 26 2022 16:17:29 UTC
    Trace wrapped................No
    Configuration Changed........No
    SMTP CONFIGURATION PARAMETERS
    IPV6 Capable.....................................: *YES
    FIRST LEVEL RETRY INTERVAL (MINUTES) ............: 30
    FIRST LEVEL RETRY ATTEMPTS(0-99).................: 3
    SECOND LEVEL RETRY INTERVAL (HOURS)..............: 0
    SECOND LEVEL RETRY ATTEMPTS(0-99)................: 0
    RETRY REMOTE NAME SERVER (Y/N)...................: N
    AUTOSTART SMTP (Y/N).............................: N
    INBOUND XLATE TABLE (Y/N)........................: N
    OUTBOUND XLATE TABLE (Y/N).......................: N
    CFG CCSID........................................: 819
    HOST NAME (CP 500)...............................: TOUCHI75
    HOST DOMAIN NAME (CP 500)........................: <redacted cloud provider>.COM
    MAIL ROUTER (CP 500).............................:
    INBOUND XLATE TABLE..............................:
    OUTBOUND XLATE TABLE.............................:
    CONFIGURED DELIMITER CHAR........................: ?
    FIREWALL (Y/N)...................................: N
    PROCESS ALL MAIL (Y/N)...........................: N
    PERCENT ROUTE CHARACTER (Y/N) ...................: N
    ETRN SERVER ENABLED (Y/N) .......................: N
    ETRN CLIENT ENABLED (Y/N) .......................: N
    ETRN CLIENT IPADDR ............................:
    ETRN CLIENT HOST/DOMAIN .......................:
    8BITMIME (Y/N)...................................: N
    DSN (Y/N) .......................................: N
    DSN RESPONSIBLE PERSON ........................: *NONE
    SUBSYSTEM LIBRARY ...............................: QSYS
    NAME ..................................: QSYSWRK
    RBL SITE ADDRESS.................................: *NONE
    ALLOW RELAY......................................: *NONE
    POP BEFORE MAIL WINDOW...........................: 0
    FILTER ACTION....................................: *NONE
    INTERFACE DOMAIN.................................: *NONE
    AUTOREGISTRATION (Y/N)...........................: N
    JOURNAL..........................................: *NO
    Override reject connect list (Y/N)...............: N
    Allow bare line feed (Y/N).......................: Y
    Verify Id (Y/N)..................................: N
    Verify MSF Message (Y/N).........................: Y
    Allow authentication.............................: *NONE
    Verify from user.................................: *ALL
    Forwarding mailhub domain server.................: smtp-relay.gmail.com
    DATE TIME(UTC) J TRANS THREAD MODULE STMT TEXT
    ---------- ------------ - ----- ---------- ----- -----------------------------------------------------------------------------
    10/26/2022 16:12:13.907 0B 00000 033 qtmssmtpc.C 02478 Application role..................SMTP relay Job, num: 002940
    10/26/2022 16:12:13.911 0B 00000 033 qtmssmtpc.C 02481 Call: main()
    10/26/2022 16:12:13.919 0B 00000 033 qtmssmtpc.C 02308 Call: RegCltJOb sd = 3
    10/26/2022 16:12:13.919 0B 00000 033 qtmssmtpc.C 02270 Call: RptCltJob sd = 3 data =?REGRLY*
    10/26/2022 16:12:13.919 0B 00000 033 qtmssmtpc.C 02297 Return: RptWorkerJob ret = 1
    10/26/2022 16:12:13.919 0B 00000 033 qtmssmtpc.C 02322 Return: RegCltJOb ret = 1
    10/26/2022 16:12:13.919 0B 00000 033 qtmsdir72.C 02413 Call: recoverDataDirectories
    10/26/2022 16:12:13.920 0B 00000 033 qtmsdir72.C 02430 Return: recoverDataDirectories
    10/26/2022 16:12:13.924 0B 00000 033 qtmssmtpc.C 02516 Starting Infinte Loop
    10/26/2022 16:12:13.924 0B 00000 033 qtmssmtpc.C 02533 New Maximum Number of File Descriptors = 65536
    10/26/2022 16:12:13.924 0B 00000 035 qtmssmtpc.C 01588 Call: CheckpollThread 53
    10/26/2022 16:12:13.924 0B 00000 036 qtmssmtpc.C 01074 Call: CltIDRcvThread 54
    10/26/2022 16:12:13.924 0B 00000 037 qtmssmtpc.C 01699 Call: workerThread 55
    10/26/2022 16:12:13.924 0B 00000 038 qtmssmtpc.C 01699 Call: workerThread 56
    10/26/2022 16:12:13.924 0B 00000 034 qtmssmtpc.C 01883 Call: ControlThread 52
    10/26/2022 16:12:13.924 0B 00000 039 qtmssmtpc.C 01699 Call: workerThread 57
    10/26/2022 16:12:13.925 0B 00000 03A qtmssmtpc.C 01699 Call: workerThread 58
    10/26/2022 16:12:20.206 0B 00000 034 qtmsemq.C 00628 Call: getNextIDKeyedQueue(q=2)
    10/26/2022 16:12:20.208 0B 00000 034 qtmsemq.C 00742 Return: getNextIDKeyedQueue return 0
    10/26/2022 16:12:38.957 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:12:38.961 0B 00000 033 qtmsusr.C 02333 Open User File /QTCPTMM/CONFIG/USERS.DAT for read failed, errno=3025
    10/26/2022 16:12:38.963 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:12:38.963 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:12:38.966 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:12:38.966 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:12:38.968 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:12:38.968 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:13:03.987 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:13:03.987 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:13:03.987 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:13:03.990 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:13:03.990 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:13:03.992 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:13:03.992 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:13:29.017 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:13:29.017 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:13:29.017 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:13:29.020 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:13:29.020 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:13:29.023 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:13:29.023 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:13:36.696 0B 00000 036 qtmssmtpc.C 01124 Received request E-mail ID: 004B5B-QA0-0029410000004C6DF0
    10/26/2022 16:13:36.697 0B 00000 036 qtmssmtpc.C 01135 Total request waiting relay: 1
    10/26/2022 16:13:36.697 0B 00000 037 qtmssmtpc.C 01742 Processing Status, Waiting=1, Live=0
    10/26/2022 16:13:36.697 0B 00000 037 qtmssmtpc.C 01753 Processing E-mail ID: 004B5B-QA0-0029410000004C6DF0
    10/26/2022 16:13:36.697 0B 00000 036 qtmssmtpc.C 01124 Received request E-mail ID: 004B5B-QA0-0029410000004C6DF0
    10/26/2022 16:13:36.697 0B 00000 036 qtmssmtpc.C 01135 Total request waiting relay: 1
    10/26/2022 16:13:36.697 0B 00000 038 qtmssmtpc.C 01742 Processing Status, Waiting=1, Live=0
    10/26/2022 16:13:36.697 0B 00000 038 qtmssmtpc.C 01753 Processing E-mail ID: 004B5B-QA0-0029410000004C6DF0
    10/26/2022 16:13:37.497 0B 00000 038 qtmscltcnn.C 00726 Call: createClientConnection(id = 004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:37.497 0B 00000 037 qtmscltcnn.C 00726 Call: createClientConnection(id = 004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:37.497 0B 00000 038 qtmsemm.C 02833 Call: TryEmailStateUpdLock(id=004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:37.498 0B 00000 037 qtmsemm.C 02833 Call: TryEmailStateUpdLock(id=004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:37.500 0B 00000 037 qtmsemm.C 02988 Call: loadEmail(id=004B5B-QA0-0029410000004C6DF0, mode=0)
    10/26/2022 16:13:37.501 0B 00000 037 qtmsemm.C 03692 Return: loadEmail return SPP:0000 :1aefQTSMTPCLTDQTCP 002940 :301a0:0
    10/26/2022 16:13:37.501 0B 00000 037 qtmsemm.C 03692 :13
    10/26/2022 16:13:37.501 0B 00000 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:37.501 0B 00000 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:37.502 0B 00000 038 qtmsemm.C 02895 Return: TryEmailStateUpdLock return NULL
    10/26/2022 16:13:37.502 0B 00000 037 qtmsemm.C 02946 Return: TryEmailStateUpdLock return SPP:0000 :1aefQTSMTPCLTDQTCP 0029
    10/26/2022 16:13:37.502 0B 00000 037 qtmsemm.C 02946 40 :301a0:0:13
    10/26/2022 16:13:37.502 0B 00000 037 qtmscltcnn.C 05329 Call: setWorkingStatusAll
    10/26/2022 16:13:37.502 0B 00000 037 qtmsemm.C 04077 Call: addLogEntry(004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:37.502 0B 00000 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:37.502 0B 00000 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 04171 Return: addLogEntry
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 04077 Call: addLogEntry(004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 04171 Return: addLogEntry
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 02062 Call: updateStatus
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:37.503 0B 00000 037 qtmsemm.C 02154 Return: updateStatus return 1
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 05354 Return: setWorkingStatusAll
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00837 F4241: use mailhub: smtp-relay.gmail.com
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00882 F4241: Process Email: 004B5B-QA0-0029410000004C6DF0
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00888 F4241: Pipeline on
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00906 F4241: IPV6 off
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00919 F4241: RTYMIN(3 30)
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00923 F4241: RTYHR(0 0)
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00927 F4241: RTYDAY(0 0)
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00949 F4241: DSN off
    10/26/2022 16:13:37.503 0B 00000 037 qtmscltcnn.C 00954 F4241: CCSID = 819
    10/26/2022 16:13:37.504 0B 00000 037 qtmscltcnn.C 00968 F4241: client bnd off use ::
    10/26/2022 16:13:37.504 0B 00000 037 qtmscltcnn.C 00993 F4241: EHLO domain is (TOUCHI75.<redacted cloud provider>.COM)
    10/26/2022 16:13:37.504 0B 00000 037 qtmscltcnn.C 00995 Return: createClientConnection return
    10/26/2022 16:13:37.504 0B 00000 037 qtmssmtpc.C 01742 Processing Status, Waiting=0, Live=1
    10/26/2022 16:13:37.504 0B F4241 037 qtmscltcnn.C 02963 Call: handleNextForClient(state=1)
    10/26/2022 16:13:37.504 0B F4241 037 qtmscltaut.C 00692 Call: getAuthParm
    10/26/2022 16:13:37.504 0B F4241 037 qtmscltaut.C 00705 using mailhub
    10/26/2022 16:13:37.504 0B F4241 037 qtmscltaut.C 00714 getAuthParm: domain num=-1
    10/26/2022 16:13:37.504 0B F4241 037 qtmscltaut.C 00716 getAuthParm: domain=smtp-relay.gmail.com
    10/26/2022 16:13:37.505 0B F4241 037 qtmscltaut.C 00774 Cannot not find key QUSRSYS/QATMSCLTUS/SMTPCLTUSR.
    10/26/2022 16:13:37.505 0B F4241 037 qtmscltaut.C 00779 id:CPF5006
    10/26/2022 16:13:37.505 0B F4241 037 qtmscltaut.C 00793 Return: getAuthParm
    10/26/2022 16:13:37.505 0B F4241 037 qtmsmxrsv.C 00664 Call: getmxhostnames(name=smtp-relay.gmail.com,lht_first=TRUE,mxrslv=TRUE
    10/26/2022 16:13:37.505 0B F4241 037 qtmsmxrsv.C 00664 )
    10/26/2022 16:13:37.506 0B F4241 037 qtmsmxrsv.C 00564 host SMTP-RELAY.GMAIL.COM was not found in LHT
    10/26/2022 16:13:37.506 0B F4241 037 qtmsmxrsv.C 00710 Resolving MX for SMTP-RELAY.GMAIL.COM
    10/26/2022 16:13:37.568 0B F4241 037 qtmsmxrsv.C 00270 Header Info
    10/26/2022 16:13:37.568 0B F4241 037 qtmsmxrsv.C 00271 ID=51070, QR=1, OPCODE = 0
    10/26/2022 16:13:37.568 0B F4241 037 qtmsmxrsv.C 00273 AA=0,TC=0,RD=1,RA=1,Z=0,RCODE=0
    10/26/2022 16:13:37.568 0B F4241 037 qtmsmxrsv.C 00275 Question Section: Count =1
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00323 SMTP-RELAY.GMAIL.COM IN A
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00324 Answer Section: Count =1
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00126 RR NAME=SMTP-RELAY.GMAIL.COM
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00142 RR TYPE=1(A)
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00150 RR CLASS=1
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00157 TTL =300
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00164 RDLEN =4
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00224 rr 0 was type 1(A) this was not the requested type it was not added
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00225 The DNS is responding in the answer section with records that do not belo
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00225 ng in the answer.
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00226 This information was supposed to be in the additional information section
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00227 It will be ignored as per RFC 2821 and corresponding DNS RFCs
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00228 A and AAAA records will be picked up later by an explicit query
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00333 Valid answers: 0
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00342 didn't get answer but server did recursion, query done
    10/26/2022 16:13:37.569 0B F4241 037 qtmsmxrsv.C 00745 No MX records found, checking CNAME for SMTP-RELAY.GMAIL.COM
    10/26/2022 16:13:37.719 0B F4241 037 qtmsmxrsv.C 00406 DNS server responded as host not found
    10/26/2022 16:13:37.719 0B F4241 037 qtmsmxrsv.C 00805 name SMTP-RELAY.GMAIL.COM doesn't resolve, treat as Address Record, addin
    10/26/2022 16:13:37.719 0B F4241 037 qtmsmxrsv.C 00805 g to mx with priorty 0
    10/26/2022 16:13:37.741 0B F4241 037 qtmsmxrsv.C 00875 Return: getmxhostnames return 1
    10/26/2022 16:13:37.741 0B F4241 037 qtmscltcnn.C 03087 Initialized connection socket description: 4
    10/26/2022 16:13:37.741 0B F4241 037 qtmscltcnn.C 03118 No need to bind.
    10/26/2022 16:13:37.741 0B F4241 037 qtmscltcnn.C 06204 Call: establishConnectionCurMX
    10/26/2022 16:13:37.741 0B F4241 037 qtmscltcnn.C 06222 Environment variable QIBM_SMTP_SERVER_PORT set = 587
    10/26/2022 16:13:37.741 0B F4241 037 qtmscltcnn.C 05880 Call: establishConnection
    10/26/2022 16:13:37.742 0B F4241 037 qtmscltcnn.C 05883 Attempting connection to SMTP-RELAY.GMAIL.COM 142.251.12.28(587)
    10/26/2022 16:13:37.742 0B F4241 037 qtmscltcnn.C 05901 connect rc =-1 errno = 3430
    10/26/2022 16:13:37.956 0B F4241 037 qtmscltcnn.C 05929 Return: establishConnection return true
    10/26/2022 16:13:37.956 0B F4241 037 qtmsemm.C 04077 Call: addLogEntry(004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:37.956 0B F4241 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=5)
    10/26/2022 16:13:37.956 0B F4241 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:37.957 0B F4241 037 qtmsemm.C 04171 Return: addLogEntry
    10/26/2022 16:13:37.957 0B F4241 037 qtmscltcnn.C 06416 Connected to Server SMTP-RELAY.GMAIL.COM(142.251.12.28):587
    10/26/2022 16:13:37.957 0B F4241 037 qtmscltcnn.C 06433 Return: establishConnectionCurMX return 0
    10/26/2022 16:13:37.957 0B F4241 037 qtmscltcnn.C 03004 Return: handleNextForClient return true
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 02833 Call: TryEmailStateUpdLock(id=004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 02383 Call: CheckEmailWrkState(id=004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=5)
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 02463 Return: CheckEmailWrkState return 1
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 02469 Call: EnterEmailWorkLock(id=004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=5)
    10/26/2022 16:13:38.032 0B 00000 038 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 02544 Return: EnterEmailWorkLock return 2
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 02988 Call: loadEmail(id=004B5B-QA0-0029410000004C6DF0, mode=0)
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 03692 Return: loadEmail return SPP:0000 :1aefQTSMTPCLTDQTCP 002940 :30420:1
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 03692 :13
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 02549 Call: LeaveEmailWorkLock(id=004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=5)
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 02644 Return: LeaveEmailWorkLock return 1
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 04077 Call: addLogEntry(004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=5)
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:38.033 0B 00000 038 qtmsemm.C 04171 Return: addLogEntry
    10/26/2022 16:13:38.033 0B 00000 038 qtmscltcnn.C 00765 Already in processing
    10/26/2022 16:13:38.033 0B 00000 038 qtmscltcnn.C 00766 Return: createClientConnection return NULL
    10/26/2022 16:13:38.034 0B 00000 038 qtmssmtpc.C 01769 Can not create connection for 004B5B-QA0-0029410000004C6DF0, may be alrea
    10/26/2022 16:13:38.034 0B 00000 038 qtmssmtpc.C 01769 dy done
    10/26/2022 16:13:38.994 0B 00000 03A qtmssmtpc.C 01742 Processing Status, Waiting=0, Live=1
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 02963 Call: handleNextForClient(state=4)
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03333 Call: receiveReplies
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03352 recvBuffer rc = 90
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03399 Return: receiveReplies
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 01649 CLT_CONN_INIT 1 lines replies received
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 01677 220 smtp-relay.gmail.com ESMTP y6-20020a170902700600b0018661f77ab0sm2433
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 01677 93plk.35 - gsmtp
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03269 Call: sendReply
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03274 qtms_convert_string rc = 32
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03281 sending:EHLO TOUCHI75.<redacted cloud provider>.COM
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03309 Return: sendReply return true
    10/26/2022 16:13:38.994 0B F4241 03A qtmscltcnn.C 03004 Return: handleNextForClient return true
    10/26/2022 16:13:39.376 0B 00000 039 qtmssmtpc.C 01742 Processing Status, Waiting=0, Live=1
    10/26/2022 16:13:39.376 0B F4241 039 qtmscltcnn.C 02963 Call: handleNextForClient(state=8)
    10/26/2022 16:13:39.376 0B F4241 039 qtmscltcnn.C 03333 Call: receiveReplies
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03352 recvBuffer rc = 176
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03399 Return: receiveReplies
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03687 handleEHLO 8 lines replies received
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03704 EHLO domain (smtp-relay.gmail.com)
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03832 SIZE 157286400 supported by remote
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03749 8 bit mime supported on remote
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03767 STARTTLS supported on remote
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03758 PIPELINING supported on remote
    10/26/2022 16:13:39.377 0B F4241 039 qtmscltcnn.C 03953 Call: sendPipeline
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03993 try sendPipeline total message length = 132
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03269 Call: sendReply
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03274 qtms_convert_string rc = 132
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03281 sending:MAIL FROM: <info@<redacted domain>.com> BODY=8BITMIME SIZE=1455
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03281 RCPT TO: <jamesl@<redacted domain>.com>
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03281 RCPT TO: <jhhl@<redacted isp>.net>
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03281 DATA
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03309 Return: sendReply return true
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03996 Return: sendPipeline
    10/26/2022 16:13:39.379 0B F4241 039 qtmscltcnn.C 03004 Return: handleNextForClient return true
    10/26/2022 16:13:39.773 0B F4241 037 qtmssmtpc.C 01742 Processing Status, Waiting=0, Live=1
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 02963 Call: handleNextForClient(state=28)
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 03449 Call: receiveReplyPipeline
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 03472 recvBuffer rc = 713
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 01049 recv failed, errno 3430(Operation in progress.)
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 03463 recvBuffer failed
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 03464 Return: receiveReplyPipeline
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 03915 handlePipline 9 lines replies received
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 05237 Call: setNonDelStatus
    10/26/2022 16:13:39.773 0B F4241 037 qtmscltcnn.C 05252 Marking jamesl@<redacted domain>.com as NDL
    10/26/2022 16:13:39.773 0B F4241 037 qtmsemm.C 04077 Call: addLogEntry(004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:39.773 0B F4241 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:39.773 0B F4241 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 04171 Return: addLogEntry
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 02062 Call: updateStatus
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 02154 Return: updateStatus return 1
    10/26/2022 16:13:39.774 0B F4241 037 qtmscltcnn.C 05267 Return: setNonDelStatus
    10/26/2022 16:13:39.774 0B F4241 037 qtmscltcnn.C 05237 Call: setNonDelStatus
    10/26/2022 16:13:39.774 0B F4241 037 qtmscltcnn.C 05252 Marking jhhl@<redacted isp>.net as NDL
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 04077 Call: addLogEntry(004B5B-QA0-0029410000004C6DF0)
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 04171 Return: addLogEntry
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 02062 Call: updateStatus
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 00996 Call: LockFileUpdate(int file_descriptor=4)
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 01004 Return: LockFileUpdate return 1
    10/26/2022 16:13:39.774 0B F4241 037 qtmsemm.C 02154 Return: updateStatus return 1
    10/26/2022 16:13:39.774 0B F4241 037 qtmscltcnn.C 05267 Return: setNonDelStatus
    10/26/2022 16:13:39.774 0B F4241 037 qtmscltcnn.C 02183 handlePipline, return 5
    10/26/2022 16:13:39.774 0B F4241 037 qtmscltcnn.C 03269 Call: sendReply
    10/26/2022 16:13:39.775 0B F4241 037 qtmscltcnn.C 03274 qtms_convert_string rc = 7
    10/26/2022 16:13:39.775 0B F4241 037 qtmscltcnn.C 03281 sending:QUIT
    10/26/2022 16:13:54.047 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:13:54.047 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:13:54.047 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:13:54.049 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:13:54.049 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:13:54.051 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:13:54.051 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:14:19.077 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:14:19.077 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:14:19.077 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:14:19.080 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:14:19.080 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:14:19.089 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:14:19.089 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:14:44.107 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:14:44.107 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:14:44.107 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:14:44.110 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:14:44.110 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:14:44.117 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:14:44.117 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:15:09.137 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:15:09.137 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:15:09.137 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:15:09.165 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:15:09.165 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:15:09.167 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:15:09.167 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:15:34.200 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:15:34.200 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:15:34.200 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:15:34.205 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:15:34.205 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:15:34.211 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:15:34.211 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:15:59.230 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:15:59.230 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:15:59.230 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:15:59.258 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:15:59.258 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:15:59.260 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:15:59.260 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:16:24.291 0B 00000 033 qtmssmtpc.C 02328 Call: RptCltJob sd = 3 data =*OK=965,2,4,1
    10/26/2022 16:16:24.291 0B 00000 033 qtmssmtpc.C 02270 Call: RptCltJob sd = 3 data =?RPTCLT*
    10/26/2022 16:16:24.291 0B 00000 033 qtmssmtpc.C 02297 Return: RptWorkerJob ret = 1
    10/26/2022 16:16:24.291 0B 00000 033 qtmssmtpc.C 02341 Return: RptCltJob ret = 1
    10/26/2022 16:16:24.294 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:16:24.294 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:16:24.294 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:16:24.398 0B 00000 033 qtmsusr.C 00683 Lock SMTP user data file failed, errno = 3029
    10/26/2022 16:16:24.405 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:16:24.405 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:16:24.410 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:16:24.410 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:16:49.443 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:16:49.443 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:16:49.443 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:16:49.557 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:16:49.557 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:16:49.581 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:16:49.582 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile
    10/26/2022 16:17:14.614 0B 00000 033 qtmsusr.C 04141 Call: qtms_reLoadUserFile
    10/26/2022 16:17:14.615 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:17:14.615 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:17:14.617 0B 00000 033 qtmsusr.C 00407 getConfigModifyTime call QlgStat failed path=/QTCPTMM/CONFIG/USERS.DAT err
    10/26/2022 16:17:14.617 0B 00000 033 qtmsusr.C 00407 no= 3025
    10/26/2022 16:17:14.620 0B 00000 033 qtmsusr.C 02197 SMTP users total 0 load successfully
    10/26/2022 16:17:14.620 0B 00000 033 qtmsusr.C 04147 Return: qtms_reLoadUserFile

    ------------------------------
    James H H Lampert
    ------------------------------

    Original Message


  • 13.  RE: Getting the SMTP server talking to a Google Relay

    Posted Fri October 28, 2022 12:27 PM
    Progress on this has been slow, because it's not the only thing I'm working on, and I've been waiting for suggestions here, on the Midrange List, and on the Google support forum. I finally realized that I'd missed one of Mr. Seeberger's suggestions that I'd been meaning to try.

    ADDENVVAR ENVVAR(QIBM_SMTP_RLY_TLS_FIRST) VALUE('YES') LEVEL(*SYS)     was the last piece of the puzzle. I also had to cycle the SMTP server; it obviously picks up the environment variables at launch, rather than "on the fly."

    A test message just went through!

    So to summarize:
    1. Make sure both environment variables mentioned by Mr. Seeberger are set, and that the SMTP server was launched after setting them.
    2. Make sure the *SYSTEM cert store trusts all the standard certs, and the certs returned by QMGTOOLS/GETSSL as well.
    3. Make sure the Google Relay is accepting connections from the IP address whence it sees your traffic originating.

    ------------------------------
    James H H Lampert
    ------------------------------

    Original Message