S
SharpEye
Using Outlook 2002 SP3 to connect to a SMTP server to send messages.
Outlook set to Authenticate Outgoing server without SPA
Here is the debug log. I can't see Outlook sending an AUTH command at all.
Can anyone help?
Log follows....
2005.02.25 08:16:27 <<<< Logging Started (level is LTF_TRACE) >>>>
2005.02.25 08:16:27 Successfully initialized the resource manager
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Created
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Synchronize called
(flags = 00000031)
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Synch operation
started (flags = 00000031)
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: StartImport(flags =
00000000, max msg = ffffffff): full items
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Trying to read
remote URL off the resource
2005.02.25 08:16:27 Looking up handler for POP
2005.02.25 08:16:27 Creating proto handler
{848F8363-04C9-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:27 Initializing proto handler
{848F8363-04C9-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: StartImport:
Retrieving Blob
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: StartImport:
pCallback->StartImport
2005.02.25 08:16:27 Callback::ExecuteThread[THIS: 001e3a98] (00001000)
returns 00000000
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "CPOPManifestTask: Begin execution")
2005.02.25 08:16:27 Callback::ExecuteThread[THIS: 001e3a98] (00001000)
returns 00000000
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Finding host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Connecting to host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Connected to host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK comms-server2.rh.com
BusinessMail v4.50.6.00 - POP3 service ready")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Authorizing to server")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] USER COLIN")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK User [COLIN] is
allowed to get mail here...")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] PASS *****")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK mailbox locked and
ready")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Authorized to host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Connected to host")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/3
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 2/4
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] STAT")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 3/5
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] UIDL")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> -ERR non-existant command")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "Server does not support UIDL.
Falling back on TOP.")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 4/7
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 5/8
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] STAT")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 6/9
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 7/9
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "CPOPManifestTask: Comparing server
and local blobs")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 8/9
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: AddDeferredCall:
pCallback->RegisterDeferredCall
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "CPOPManifestTask: Checking for
messages to delete")
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: DeleteNukedItems -
Nothing to nuke
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: UploadItems: 0
messages to send
2005.02.25 08:16:27 ResourceObject [THIS: 001b2d58]: Created
2005.02.25 08:16:27 ResourceObject [THIS: 001b2d58]: Synchronize called
(flags = 00000031)
2005.02.25 08:16:28 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: End execution")
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: Synch operation
started (flags = 00000031)
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001cfe98] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: StartImport(flags =
00000000, max msg = ffffffff): full items
2005.02.25 08:16:28 Callback::LogText[THIS: 001cfe98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPDeferredCallTask: Begin execution")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cfe98]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] 2005.02.25
08:16:28 ResourceObject [THIS: 001b2d58]: 2005.02.25 08:16:28
Callback::ExecuteThreadTrying to read remote URL off the resource
[THIS: 001cf4c0] 2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]:
(00001000) returns 00000000
StartImport: Retrieving Blob
(flags = LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPQuitTask: Begin execution")
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: StartImport:
pCallback->StartImport
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: Begin execution")
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001ad998] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001ad998] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnecting from host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] QUIT")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Finding host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Connected to host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK POP3 server connection
terminated")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnected from host")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 1/1
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 00158480]: Synch operation
completed
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK comms-server2.rh.com
BusinessMail v4.50.6.00 - POP3 service ready")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Authorizing to server")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] USER root")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK User [root] is allowed
to get mail here...")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] PASS *****")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK mailbox locked and
ready")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Authorized to host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Connected to host")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/3
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 2/4
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] STAT")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 3/5
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] UIDL")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> -ERR non-existant command")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "Server does not support UIDL.
Falling back on TOP.")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 4/7
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 5/8
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] STAT")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 6/9
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 7/9
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: Comparing server
and local blobs")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 8/9
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: AddDeferredCall:
pCallback->RegisterDeferredCall
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: Checking for
messages to delete")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: End execution")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPDeferredCallTask: Begin execution")
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001cf4c0] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: 2005.02.25 08:16:28
Callback::ReportStatus[THIS: 001cf4c0] (ulAsynchPhase = RSF_COMPLETED,
hrStatus = 0x00000000)
DeleteNukedItems - Nothing to nuke
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPQuitTask: Begin execution")
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001cf4c0] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnecting from host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] QUIT")
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: UploadItems: 0
messages to send
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK POP3 server connection
terminated")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnected from host")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 1/1
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: Synch operation
completed
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Synchronize called
(flags = 00000001)
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Synch operation
started (flags = 00000001)
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: UploadItems: 1
messages to send
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Trying to read
remote URL off the resource
2005.02.25 08:16:55 Looking up handler for SMTP
2005.02.25 08:16:55 Creating proto handler
{8D2595E0-07C3-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:55 Initializing proto handler
{8D2595E0-07C3-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: SendItem:
pCallback->Put
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "CSMTPPutTask: Begin execution")
2005.02.25 08:16:55 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP PH: Connecting to SMTP server")
2005.02.25 08:16:55 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Finding host")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Connected to host")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: <rx> 220 [comms-server2.rh.com]
BusinessMail SMTP server 4.51.00, SMTP service ready")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: [tx] EHLO Surveying")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: <rx> 250 Welcome [Surveying],
pleased to meet you.")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Authorized to host")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Connected to host")
2005.02.25 08:16:55 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/4
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: [tx] MAIL FROM:
<[email protected]>")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: <rx> 535 Authentication
required")
2005.02.25 08:16:55 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x800ccc78)
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Synch operation
completed
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: Synchronize called
(flags = 00000001)
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: Synch operation
started (flags = 00000001)
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: UploadItems: 1
messages to send
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: SendItem:
pCallback->Put
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "CSMTPPutTask: Begin execution")
2005.02.25 08:17:28 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP PH: Connecting to SMTP server")
2005.02.25 08:17:28 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Finding host")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Connected to host")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: <rx> 220 [comms-server2.rh.com]
BusinessMail SMTP server 4.51.00, SMTP service ready")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: [tx] EHLO Surveying")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: <rx> 250 Welcome [Surveying],
pleased to meet you.")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Authorized to host")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Connected to host")
2005.02.25 08:17:28 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/4
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: [tx] MAIL FROM:
<[email protected]>")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: <rx> 535 Authentication
required")
2005.02.25 08:17:28 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x800ccc78)
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: Synch operation
completed
2005.02.25 08:17:58 ResourceObject [THIS: 00158480]: Destroyed
2005.02.25 08:17:58 ResourceObject [THIS: 001b2d58]: Destroyed
2005.02.25 08:17:58 Resource manager terminated
Outlook set to Authenticate Outgoing server without SPA
Here is the debug log. I can't see Outlook sending an AUTH command at all.
Can anyone help?
Log follows....
2005.02.25 08:16:27 <<<< Logging Started (level is LTF_TRACE) >>>>
2005.02.25 08:16:27 Successfully initialized the resource manager
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Created
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Synchronize called
(flags = 00000031)
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Synch operation
started (flags = 00000031)
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: StartImport(flags =
00000000, max msg = ffffffff): full items
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: Trying to read
remote URL off the resource
2005.02.25 08:16:27 Looking up handler for POP
2005.02.25 08:16:27 Creating proto handler
{848F8363-04C9-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:27 Initializing proto handler
{848F8363-04C9-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: StartImport:
Retrieving Blob
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: StartImport:
pCallback->StartImport
2005.02.25 08:16:27 Callback::ExecuteThread[THIS: 001e3a98] (00001000)
returns 00000000
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "CPOPManifestTask: Begin execution")
2005.02.25 08:16:27 Callback::ExecuteThread[THIS: 001e3a98] (00001000)
returns 00000000
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Finding host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Connecting to host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Connected to host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK comms-server2.rh.com
BusinessMail v4.50.6.00 - POP3 service ready")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Authorizing to server")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] USER COLIN")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK User [COLIN] is
allowed to get mail here...")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] PASS *****")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK mailbox locked and
ready")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Authorized to host")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: Connected to host")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/3
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 2/4
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] STAT")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 3/5
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] UIDL")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> -ERR non-existant command")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "Server does not support UIDL.
Falling back on TOP.")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 4/7
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_CONNECTED) Progress = 5/8
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: [tx] STAT")
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 6/9
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 7/9
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "CPOPManifestTask: Comparing server
and local blobs")
2005.02.25 08:16:27 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 8/9
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: AddDeferredCall:
pCallback->RegisterDeferredCall
2005.02.25 08:16:27 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:27 "CPOPManifestTask: Checking for
messages to delete")
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: DeleteNukedItems -
Nothing to nuke
2005.02.25 08:16:27 ResourceObject [THIS: 00158480]: UploadItems: 0
messages to send
2005.02.25 08:16:27 ResourceObject [THIS: 001b2d58]: Created
2005.02.25 08:16:27 ResourceObject [THIS: 001b2d58]: Synchronize called
(flags = 00000031)
2005.02.25 08:16:28 Callback::LogText[THIS: 001e3a98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: End execution")
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: Synch operation
started (flags = 00000031)
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001cfe98] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001e3a98]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: StartImport(flags =
00000000, max msg = ffffffff): full items
2005.02.25 08:16:28 Callback::LogText[THIS: 001cfe98] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPDeferredCallTask: Begin execution")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cfe98]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] 2005.02.25
08:16:28 ResourceObject [THIS: 001b2d58]: 2005.02.25 08:16:28
Callback::ExecuteThreadTrying to read remote URL off the resource
[THIS: 001cf4c0] 2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]:
(00001000) returns 00000000
StartImport: Retrieving Blob
(flags = LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPQuitTask: Begin execution")
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: StartImport:
pCallback->StartImport
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: Begin execution")
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001ad998] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001ad998] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnecting from host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] QUIT")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Finding host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Connected to host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK POP3 server connection
terminated")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnected from host")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 1/1
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 00158480]: Synch operation
completed
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK comms-server2.rh.com
BusinessMail v4.50.6.00 - POP3 service ready")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Authorizing to server")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] USER root")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK User [root] is allowed
to get mail here...")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] PASS *****")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK mailbox locked and
ready")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Authorized to host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Connected to host")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/3
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 2/4
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] STAT")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 3/5
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] UIDL")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> -ERR non-existant command")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "Server does not support UIDL.
Falling back on TOP.")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 4/7
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_CONNECTED) Progress = 5/8
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] STAT")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK 0 0")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 6/9
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 7/9
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: Comparing server
and local blobs")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 8/9
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: AddDeferredCall:
pCallback->RegisterDeferredCall
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: Checking for
messages to delete")
2005.02.25 08:16:28 Callback::LogText[THIS: 001ad998] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPManifestTask: End execution")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPDeferredCallTask: Begin execution")
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001cf4c0] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001ad998]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: 2005.02.25 08:16:28
Callback::ReportStatus[THIS: 001cf4c0] (ulAsynchPhase = RSF_COMPLETED,
hrStatus = 0x00000000)
DeleteNukedItems - Nothing to nuke
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "CPOPQuitTask: Begin execution")
2005.02.25 08:16:28 Callback::ExecuteThread[THIS: 001cf4c0] (00001000)
returns 00000000
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnecting from host")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: [tx] QUIT")
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: UploadItems: 0
messages to send
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: <rx> +OK POP3 server connection
terminated")
2005.02.25 08:16:28 Callback::LogText[THIS: 001cf4c0] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:28 "POP3: Disconnected from host")
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_INPROGRESS) Progress = 1/1
2005.02.25 08:16:28 Callback::ReportStatus[THIS: 001cf4c0]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x00000000)
2005.02.25 08:16:28 ResourceObject [THIS: 001b2d58]: Synch operation
completed
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Synchronize called
(flags = 00000001)
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Synch operation
started (flags = 00000001)
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: UploadItems: 1
messages to send
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Trying to read
remote URL off the resource
2005.02.25 08:16:55 Looking up handler for SMTP
2005.02.25 08:16:55 Creating proto handler
{8D2595E0-07C3-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:55 Initializing proto handler
{8D2595E0-07C3-11D3-B8AF-00105A19CDC6}
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: SendItem:
pCallback->Put
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "CSMTPPutTask: Begin execution")
2005.02.25 08:16:55 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP PH: Connecting to SMTP server")
2005.02.25 08:16:55 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Finding host")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Connected to host")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: <rx> 220 [comms-server2.rh.com]
BusinessMail SMTP server 4.51.00, SMTP service ready")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: [tx] EHLO Surveying")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: <rx> 250 Welcome [Surveying],
pleased to meet you.")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Authorized to host")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: Connected to host")
2005.02.25 08:16:55 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/4
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: [tx] MAIL FROM:
<[email protected]>")
2005.02.25 08:16:55 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:16:55 "SMTP: <rx> 535 Authentication
required")
2005.02.25 08:16:55 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x800ccc78)
2005.02.25 08:16:55 ResourceObject [THIS: 00158480]: Synch operation
completed
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: Synchronize called
(flags = 00000001)
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: Synch operation
started (flags = 00000001)
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: UploadItems: 1
messages to send
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: SendItem:
pCallback->Put
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "CSMTPPutTask: Begin execution")
2005.02.25 08:17:28 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP PH: Connecting to SMTP server")
2005.02.25 08:17:28 Callback::ExecuteThread[THIS: 0393ce48] (00001000)
returns 00000000
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Finding host")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Connected to host")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: <rx> 220 [comms-server2.rh.com]
BusinessMail SMTP server 4.51.00, SMTP service ready")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: [tx] EHLO Surveying")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: <rx> 250 Welcome [Surveying],
pleased to meet you.")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Authorized to host")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: Connected to host")
2005.02.25 08:17:28 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_CONNECTED) Progress = 1/4
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: [tx] MAIL FROM:
<[email protected]>")
2005.02.25 08:17:28 Callback::LogText[THIS: 0393ce48] (flags =
LTF_TRACE, hr = 00000000, code = 00000000,
2005.02.25 08:17:28 "SMTP: <rx> 535 Authentication
required")
2005.02.25 08:17:28 Callback::ReportStatus[THIS: 0393ce48]
(ulAsynchPhase = RSF_COMPLETED, hrStatus = 0x800ccc78)
2005.02.25 08:17:28 ResourceObject [THIS: 00158480]: Synch operation
completed
2005.02.25 08:17:58 ResourceObject [THIS: 00158480]: Destroyed
2005.02.25 08:17:58 ResourceObject [THIS: 001b2d58]: Destroyed
2005.02.25 08:17:58 Resource manager terminated