🐛 Can't verify email / email not sent

[anarion80]

Describe the bug After registering an account, the profile page shows "Email not verified". After clicking on Resend email confirmation we immediately get Email confirmation resent successfully notification, but no email is sent to SMTP server.

To Reproduce Steps to reproduce the behavior:

1. Go to Manage Profile
2. Click on Resend email confirmation
3. Check email server logs, email is not sent

Expected behavior Email is sent.

Screenshots

Desktop (please complete the following information):

• OS: Windows 10
• Browser: Edge
• Version: 126.0.2592.61

Hi.Events Version and platform This is in Docker, with separate backend, frontend, database, redis and nginx. Here are relevant env variables for the backend, with a local postfix server with no username, password or SSL/TLS. Works fine for many other services.

MAIL_FROM_ADDRESS: hievents@my_domain.com
MAIL_FROM_NAME: Hi.Events
MAIL_HOST: 192.168.2.9
MAIL_MAILER:  smtp
MAIL_PASSWORD: ""
MAIL_PORT: 25
MAIL_USERNAME: ""

Logs Nothing really in the logs. Only the following in the backed logs:

192.168.2.10 - - [19/Jun/2024:14:52:43 +0000] "OPTIONS /users/1/resend-email-confirmation HTTP/1.1" 204 0 "http://192.168.2.100:8167/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Edg/126.0.0.0" "-"
127.0.0.1 -  19/Jun/2024:14:52:43 +0000 "OPTIONS /index.php" 204

[daveearley]

Thanks for the report @anarion80! There was a bug fix released recently that may have fixed this. Are you using the latest release?

[anarion80]

Yes, the latest docker images from 4 days ago I think.

[anarion80]

Just checked on today's version and no change.

[daveearley]

Is it only this email that isn't sending? If it's all emails it may be a configuration issue.

[anarion80]

The only other email sending event is forgotten password, and in here the same: Success, no errors, email not sent.

So yes, it might be a config issue, but it's not clear where. I also added MAIL_ENCRYPTION='' (empty) to env variables but no change.

I enabled debug, but it is not helping much:

[21-Jun-2024 06:06:46.266327] DEBUG: pid 125, fpm_event_loop(), line 435: event module triggered 1 events
2024/06/21 06:06:46 [info] 157#157: *25 client ::1 closed keepalive connection
[21-Jun-2024 06:06:46.506678] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 0 active children, 2 spare children, 2 running children. Spawning rate 1
[21-Jun-2024 06:06:47.507787] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 0 active children, 2 spare children, 2 running children. Spawning rate 1
[21-Jun-2024 06:06:48.508898] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 0 active children, 2 spare children, 2 running children. Spawning rate 1
[21-Jun-2024 06:06:49.510019] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 0 active children, 2 spare children, 2 running children. Spawning rate 1
192.168.2.10 - - [21/Jun/2024:06:06:50 +0000] "OPTIONS /users/1/resend-email-confirmation HTTP/1.1" 204 0 "http://192.168.2.100:8167/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Edg/126.0.0.0" "-"
127.0.0.1 -  21/Jun/2024:06:06:50 +0000 "OPTIONS /index.php" 204
[21-Jun-2024 06:06:50.414956] DEBUG: pid 125, fpm_event_loop(), line 435: event module triggered 1 events
[21-Jun-2024 06:06:50.510189] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 1 active children, 1 spare children, 2 running children. Spawning rate 1
192.168.2.10 - - [21/Jun/2024:06:06:50 +0000] "POST /users/1/resend-email-confirmation HTTP/1.1" 204 0 "http://192.168.2.100:8167/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Edg/126.0.0.0" "-"
127.0.0.1 -  21/Jun/2024:06:06:50 +0000 "POST /index.php" 204
[21-Jun-2024 06:06:50.516994] DEBUG: pid 125, fpm_event_loop(), line 435: event module triggered 1 events
127.0.0.1 -  21/Jun/2024:06:06:51 +0000 "GET /healthcheck" 200
[21-Jun-2024 06:06:51.326677] DEBUG: pid 125, fpm_event_loop(), line 435: event module triggered 1 events
2024/06/21 06:06:51 [info] 158#158: *29 client ::1 closed keepalive connection
[21-Jun-2024 06:06:51.510983] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 0 active children, 2 spare children, 2 running children. Spawning rate 1
[21-Jun-2024 06:06:52.512142] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 0 active children, 2 spare children, 2 running children. Spawning rate 1
[21-Jun-2024 06:06:53.513280] DEBUG: pid 125, fpm_pctl_perform_idle_server_maintenance(), line 398: [pool www] currently 0 active children, 2 spare children, 2 running children. Spawning rate 1