Bug 1051492
Summary: | [notifier] When a mail is resent after a SMTP issue, there's no info about it in logs | ||||||
---|---|---|---|---|---|---|---|
Product: | Red Hat Enterprise Virtualization Manager | Reporter: | Jiri Belka <jbelka> | ||||
Component: | ovirt-engine-notification-service | Assignee: | Mooli Tayer <mtayer> | ||||
Status: | CLOSED CURRENTRELEASE | QA Contact: | Jiri Belka <jbelka> | ||||
Severity: | low | Docs Contact: | |||||
Priority: | unspecified | ||||||
Version: | 3.4.0 | CC: | aberezin, acathrow, bazulay, gklein, iheim, pstehlik, Rhev-m-bugs, yeylon | ||||
Target Milestone: | --- | ||||||
Target Release: | 3.4.0 | ||||||
Hardware: | Unspecified | ||||||
OS: | Unspecified | ||||||
Whiteboard: | infra | ||||||
Fixed In Version: | Doc Type: | Bug Fix | |||||
Doc Text: | Story Points: | --- | |||||
Clone Of: | Environment: | ||||||
Last Closed: | Type: | Bug | |||||
Regression: | --- | Mount Type: | --- | ||||
Documentation: | --- | CRM: | |||||
Verified Versions: | Category: | --- | |||||
oVirt Team: | Infra | RHEL 7.3 requirements from Atomic Host: | |||||
Cloudforms Team: | --- | Target Upstream Version: | |||||
Embargoed: | |||||||
Bug Depends On: | 1071536 | ||||||
Bug Blocks: | |||||||
Attachments: |
|
Description
Jiri Belka
2014-01-10 12:33:48 UTC
Created attachment 848157 [details]
notifier.log
We should change logging to log the event When we attempt to send it and not only before The first attempt. ok, av3, tested with local stopped postfix which then was started. This works but in strange way in case of logging. From notifier.log it is not very understandable: 1. first discover of event 2014-04-28 10:34:59,673 INFO [org.ovirt.engine.core.notifier.transport.smtp.Smtp] Send email to [jbelka] subject: [alertMessage (jb-rhevm34.rhev.lab.eng.brq.redhat.com), [user admin initiated console session for VM jb-w8-x86]] 2014-04-28 10:34:59,674 DEBUG [org.ovirt.engine.core.notifier.transport.smtp.Smtp] body: [<b>Time:</b> 2014-04-28 10:34:46.172<br><b>Message:</b> user admin initiated console session for VM jb-w8-x86<br><b>Severity:</b> NORMAL<p><b>User Name:</b> admin<br><b>VM Name:</b> jb-w8-x86<br><b>Host Name:</b> dell-r210ii-04<br><b>Template Name:</b> Blank<br><b>Data Center Name:</b> Default<br>] 2. probably another try to send 2014-04-28 10:36:22,791 INFO [org.ovirt.engine.core.notifier.transport.smtp.Smtp] Send email to [jbelka] subject: [alertMessage (jb-rhevm34.rhev.lab.eng.brq.redhat.com), [user admin initiated console session for VM jb-w8-x86]] 2014-04-28 10:36:22,792 DEBUG [org.ovirt.engine.core.notifier.transport.smtp.Smtp] body: [<b>Time:</b> 2014-04-28 10:34:46.172<br><b>Message:</b> user admin initiated console session for VM jb-w8-x86<br><b>Severity:</b> NORMAL<p><b>User Name:</b> admin<br><b>VM Name:</b> jb-w8-x86<br><b>Host Name:</b> dell-r210ii-04<br><b>Template Name:</b> Blank<br><b>Data Center Name:</b> Default<br>] 3. timeout for sending mails 2014-04-28 10:37:25,795 ERROR [org.ovirt.engine.core.notifier.transport.smtp.Smtp] Failed to send message to jbelka with subject alertMessage (jb-rhevm34.rhev.lab.eng.brq.redhat.com), [user admin initiated console session for VM jb-w8-x86] due to to error: Could not connect to SMTP host: 10.4.122.10, port: 587 javax.mail.MessagingException: Could not connect to SMTP host: 10.4.122.10, port: 587; nested exception is: java.net.ConnectException: Connection timed out 4. unblocked connection and another try 2014-04-28 10:37:45,799 INFO [org.ovirt.engine.core.notifier.transport.smtp.Smtp] Send email to [jbelka] subject: [alertMessage (jb-rhevm34.rhev.lab.eng.brq.redhat.com), [user admin initiated console session for VM jb-w8-x86]] 2014-04-28 10:37:45,800 DEBUG [org.ovirt.engine.core.notifier.transport.smtp.Smtp] body: [<b>Time:</b> 2014-04-28 10:34:46.172<br><b>Message:</b> user admin initiated console session for VM jb-w8-x86<br><b>Severity:</b> NORMAL<p><b>User Name:</b> admin<br><b>VM Name:</b> jb-w8-x86<br><b>Host Name:</b> dell-r210ii-04<br><b>Template Name:</b> Blank<br><b>Data Center Name:</b> Default<br>] so... 1. "Send email..." 2. "Send email..." 3. timeout 4. "Send email..." Sorry but this is confusing. You inform in the log that something is 'send' (ok, this is not 'sent') but anyway. This is why I wronte in the description "1. change from 'Send email' to 'Submitting for send'". So for me the flow for easy troubleshooting should be like this: 1a. "Queued email..." 1b. "Sending queued mails..." 2a. "Sending queued mails..." 3. timeout, sending of queue mails fails, will try again... 4. "Sending queued mails..." 5. "Queued mails sent...", mail queue now is empty Thus putting it back to ASSIGNED as there was almost nothing done to change logging part to make it more understandable. Hello Jiri, Currently we do not log when adding to mail queue: "Queued email..." Or when the queue is empty: "Queued mails sent...", mail queue now is empty Or anything related to the queue. That can change if there is any benefit to users to know about it (I'm not sure). However it is unrelated to this bug named: "When a mail is resent after a SMTP issue, there's no info about it in logs" so I think it should be closed. Currently we print the same message (same one from before this feature) each time an email is sent. PS from my point of view what is mostly missing here is after "Send email..." to have "Email sent successfully" OR "Sending email failed" nit fix I see we do have a: "Failed to send message" + explanation message in case of failure. ok av7. after discussion things got clarified: each mail notification gets separate 'Failed to send' error in the log, then each mail is sent. Closing as part of 3.4.0 |