IMAP Account setup error

skip · June 4, 2024, 2:46pm

This is Zammad version 6.3.1-1717069273.466794ba.jammy
Used Zammad installation type: package
Operating system: Ubuntu 22.04
Browser + version: Firefox 126.0.1 (64-bit)

Expected behavior:

Test triggers to send emails on action changes
Successfully set up mock Email account
Given that no IMAP account is set up expected fallback to notification email according to Zammad won't send e-mail notifications via triggers unless inbound e-mail is set up · Issue #2352 · zammad/zammad · GitHub

Actual behavior:

Not able to set up Email account with IMAP in Email Channels (not notification)

Steps to reproduce the behavior:

Register a mock email with an account at https://imitate.email/
Go to Channels - Email
Click on New Account
Click on Experts to get more configuration and copy details from imitate.email

image1397×2702 273 KB
Click continue
Get undefined method '[]' for nil:NilClass

Log file section:

I, [2024-06-04T14:41:51.927561#13174-149220]  INFO -- : Started POST "/api/v1/channels_email_inbound" for 79.44.108.83 at 2024-06-04 14:41:51 +0000
I, [2024-06-04T14:41:51.936325#13174-149220]  INFO -- : Processing by ChannelsEmailController#inbound as JSON
I, [2024-06-04T14:41:51.936458#13174-149220]  INFO -- :   Parameters: {"adapter"=>"imap", "options"=>{"realname"=>"TEST TEST", "email"=>"test@notreal.com", "group_id"=>"191", "host"=>"imap.imitate.email", "user"=>"Nb[REDACTED]", "password"=>"[FILTERED]", "ssl"=>"ssl", "ssl_verify"=>true, "port"=>"993", "folder"=>"", "keep_on_server"=>true}}
I, [2024-06-04T14:41:51.972990#13174-149220]  INFO -- : fetching imap (imap.imitate.email/Nb[REDACTED]port=993,ssl=true,starttls=false,folder=INBOX,keep_on_server=true,auth_type=LOGIN)
I, [2024-06-04T14:41:52.453365#13174-149220]  INFO -- : check only mode, fetch no emails

Any help is appreciated

fliebe92 · June 5, 2024, 7:29pm

Hi @skip. The interesting part of the log is missing/not posted.

skip · June 6, 2024, 6:58am

Hello @fliebe92 and thanks for your reply

Here is the logfile before and a little after

I, [2024-06-06T06:54:10.794972#1217-149500]  INFO -- :   Rendered inline template (Duration: 0.6ms | Allocations: 286)
I, [2024-06-06T06:54:10.797152#1217-149500]  INFO -- : Completed 404 Not Found in 12ms (Views: 1.1ms | ActiveRecord: 3.8ms | Allocations: 2419)
I, [2024-06-06T06:54:10.840349#1031-149060]  INFO -- : Running job thread for 'Generate 'Session' data.' (Sessions.jobs) status is: sleep
I, [2024-06-06T06:54:10.840447#1031-149060]  INFO -- : Running job thread for 'Check 'Channel' streams.' (Channel.stream) status is: sleep
I, [2024-06-06T06:54:10.840653#1031-149060]  INFO -- : Running job thread for 'Check channels.' (Channel.fetch) status is: sleep
I, [2024-06-06T06:54:10.880166#1031-150220]  INFO -- : execute Job.run (try_count 0)...
I, [2024-06-06T06:54:10.883075#1031-150220]  INFO -- : ended Job.run took: 0.039689456 seconds.
I, [2024-06-06T06:54:11.137206#1212-148920]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:11 +0000
I, [2024-06-06T06:54:11.144021#1212-148920]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:11.144142#1212-148920]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:11.153463#1212-148920] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:11.155077#1212-148920]  INFO -- :   Rendered inline template (Duration: 0.9ms | Allocations: 285)
I, [2024-06-06T06:54:11.157287#1212-148920]  INFO -- : Completed 404 Not Found in 13ms (Views: 1.4ms | ActiveRecord: 4.0ms | Allocations: 2418)
I, [2024-06-06T06:54:15.497665#1247-149500]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:15 +0000
I, [2024-06-06T06:54:15.504257#1247-149500]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:15.504316#1247-149500]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:15.510686#1247-149500] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:15.511737#1247-149500]  INFO -- :   Rendered inline template (Duration: 0.6ms | Allocations: 285)
I, [2024-06-06T06:54:15.513079#1247-149500]  INFO -- : Completed 404 Not Found in 9ms (Views: 0.9ms | ActiveRecord: 3.1ms | Allocations: 2418)
I, [2024-06-06T06:54:17.530382#1247-148920]  INFO -- : Started POST "/AutoDiscover/autodiscover.xml" for 172.22.22.252 at 2024-06-06 06:54:17 +0000
I, [2024-06-06T06:54:17.535355#1247-148920]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:17.535510#1247-148920]  INFO -- :   Parameters: {"a"=>"AutoDiscover/autodiscover"}
E, [2024-06-06T06:54:17.548916#1247-148920] ERROR -- : No route matches [POST] /AutoDiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:17.550320#1247-148920]  INFO -- :   Rendered inline template (Duration: 0.7ms | Allocations: 285)
I, [2024-06-06T06:54:17.552312#1247-148920]  INFO -- : Completed 404 Not Found in 17ms (Views: 1.2ms | ActiveRecord: 6.1ms | Allocations: 2766)
I, [2024-06-06T06:54:18.417744#1270-149380]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:18 +0000
I, [2024-06-06T06:54:18.444251#1270-149380]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:18.444342#1270-149380]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:18.466754#1270-149380] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:18.474585#1270-149380]  INFO -- :   Rendered inline template (Duration: 4.0ms | Allocations: 285)
I, [2024-06-06T06:54:18.476474#1270-149380]  INFO -- : Completed 404 Not Found in 32ms (Views: 7.4ms | ActiveRecord: 3.7ms | Allocations: 2418)
I, [2024-06-06T06:54:18.481274#1212-149320]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:18 +0000
I, [2024-06-06T06:54:18.489128#1212-149320]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:18.489225#1212-149320]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:18.498092#1212-149320] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:18.499573#1212-149320]  INFO -- :   Rendered inline template (Duration: 0.8ms | Allocations: 285)
I, [2024-06-06T06:54:18.501800#1212-149320]  INFO -- : Completed 404 Not Found in 12ms (Views: 1.2ms | ActiveRecord: 4.0ms | Allocations: 2418)
I, [2024-06-06T06:54:18.854915#1031-149320]  INFO -- : execute Channel.fetch (try_count 0)...
I, [2024-06-06T06:54:18.856651#1031-149320]  INFO -- : ended Channel.fetch took: 0.008896135 seconds.
I, [2024-06-06T06:54:20.085898#1247-149320]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:20 +0000
I, [2024-06-06T06:54:20.093685#1247-149320]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:20.093779#1247-149320]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:20.101960#1247-149320] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:20.103462#1247-149320]  INFO -- :   Rendered inline template (Duration: 0.7ms | Allocations: 285)
I, [2024-06-06T06:54:20.105407#1247-149320]  INFO -- : Completed 404 Not Found in 12ms (Views: 1.2ms | ActiveRecord: 3.3ms | Allocations: 2418)
I, [2024-06-06T06:54:21.123914#1214-149360]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 93.70.67.64 at 2024-06-06 06:54:21 +0000
I, [2024-06-06T06:54:21.131646#1214-149360]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:21.131729#1214-149360]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:21.139862#1214-149360] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:21.141840#1214-149360]  INFO -- :   Rendered inline template (Duration: 1.0ms | Allocations: 285)
I, [2024-06-06T06:54:21.143998#1214-149360]  INFO -- : Completed 404 Not Found in 12ms (Views: 1.8ms | ActiveRecord: 3.5ms | Allocations: 2418)
I, [2024-06-06T06:54:21.842951#1031-149060]  INFO -- : ProcessScheduledJobs running...
I, [2024-06-06T06:54:21.845861#1031-149060]  INFO -- : Running job thread for 'Generate 'Session' data.' (Sessions.jobs) status is: sleep
I, [2024-06-06T06:54:21.845960#1031-149060]  INFO -- : Running job thread for 'Check 'Channel' streams.' (Channel.stream) status is: sleep
I, [2024-06-06T06:54:21.846010#1031-149060]  INFO -- : Running job thread for 'Process ticket escalations.' (Ticket.process_escalation) status is: sleep
I, [2024-06-06T06:54:21.846268#1031-149060]  INFO -- : Running job thread for 'Check channels.' (Channel.fetch) status is: sleep
I, [2024-06-06T06:54:21.848622#1031-149060]  INFO -- : Running job thread for 'Execute planned jobs.' (Job.run) status is: sleep
I, [2024-06-06T06:54:22.962222#1222-149500]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:22 +0000
I, [2024-06-06T06:54:22.968185#1222-149500]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:22.968232#1222-149500]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
I, [2024-06-06T06:54:22.973129#1240-149600]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:22 +0000
E, [2024-06-06T06:54:22.977041#1222-149500] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:22.979168#1222-149500]  INFO -- :   Rendered inline template (Duration: 1.2ms | Allocations: 285)
I, [2024-06-06T06:54:22.981487#1222-149500]  INFO -- : Completed 404 Not Found in 13ms (Views: 1.9ms | ActiveRecord: 3.6ms | Allocations: 2429)
I, [2024-06-06T06:54:22.981484#1240-149600]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:22.981578#1240-149600]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:22.988880#1240-149600] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:22.990138#1240-149600]  INFO -- :   Rendered inline template (Duration: 0.7ms | Allocations: 285)
I, [2024-06-06T06:54:22.991998#1240-149600]  INFO -- : Completed 404 Not Found in 10ms (Views: 1.0ms | ActiveRecord: 2.8ms | Allocations: 2418)
I, [2024-06-06T06:54:27.819767#1240-148920]  INFO -- : Started POST "/api/v1/channels_email_inbound" for 79.44.108.83 at 2024-06-06 06:54:27 +0000
I, [2024-06-06T06:54:27.847824#1240-148920]  INFO -- : Processing by ChannelsEmailController#inbound as JSON
I, [2024-06-06T06:54:27.847959#1240-148920]  INFO -- :   Parameters: {"adapter"=>"imap", "options"=>{"realname"=>"Test", "email"=>"test@nonexistent.lan", "group_id"=>"186", "host"=>"imap.imitate.email", "user"=>"Nb[REDACTED]", "password"=>"[FILTERED]", "ssl"=>"ssl", "ssl_verify"=>true, "port"=>"993", "folder"=>"", "keep_on_server"=>true}}
I, [2024-06-06T06:54:27.894136#1240-148920]  INFO -- : fetching imap (imap.imitate.email/Nb[REDACTED] port=993,ssl=true,starttls=false,folder=INBOX,keep_on_server=true,auth_type=LOGIN)
I, [2024-06-06T06:54:28.470298#1240-148920]  INFO -- : check only mode, fetch no emails
I, [2024-06-06T06:54:28.569293#1240-148920]  INFO -- : Completed 200 OK in 721ms (Views: 0.8ms | ActiveRecord: 23.3ms | Allocations: 17685)
^C
root@zammad-web:~# tail -f /var/log/zammad/production.log
I, [2024-06-06T06:54:36.224895#1222-149380]  INFO -- : Processing by ChannelsEmailController#index as JSON
I, [2024-06-06T06:54:36.224956#1222-149380]  INFO -- :   Parameters: {"_"=>"1717656533116"}
I, [2024-06-06T06:54:36.585702#1227-149520]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.70 at 2024-06-06 06:54:36 +0000
I, [2024-06-06T06:54:36.625151#1227-149520]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:36.625218#1227-149520]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:36.652086#1227-149520] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:36.658199#1227-149520]  INFO -- :   Rendered inline template (Duration: 5.4ms | Allocations: 286)
I, [2024-06-06T06:54:36.660144#1227-149520]  INFO -- : Completed 404 Not Found in 35ms (Views: 5.9ms | ActiveRecord: 4.1ms | Allocations: 2439)
I, [2024-06-06T06:54:37.240171#1222-149380]  INFO -- : Completed 200 OK in 1015ms (Views: 111.5ms | ActiveRecord: 363.8ms | Allocations: 276379)
I, [2024-06-06T06:54:42.854785#1031-149060]  INFO -- : ProcessScheduledJobs running...
I, [2024-06-06T06:54:42.865136#1031-150420]  INFO -- : execute ExternalCredential::Exchange.refresh_token (try_count 0)...
I, [2024-06-06T06:54:42.867069#1031-150420]  INFO -- : ended ExternalCredential::Exchange.refresh_token took: 0.008056306 seconds.
I, [2024-06-06T06:54:43.007445#1247-149380]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:43 +0000
I, [2024-06-06T06:54:43.015537#1247-149380]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:43.015758#1247-149380]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:43.024220#1247-149380] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:43.025775#1247-149380]  INFO -- :   Rendered inline template (Duration: 0.6ms | Allocations: 285)
I, [2024-06-06T06:54:43.027870#1247-149380]  INFO -- : Completed 404 Not Found in 12ms (Views: 1.3ms | ActiveRecord: 3.4ms | Allocations: 2418)
I, [2024-06-06T06:54:43.063907#1247-149440]  INFO -- : Started POST "/autodiscover/autodiscover.xml" for 62.101.2.71 at 2024-06-06 06:54:43 +0000
I, [2024-06-06T06:54:43.069531#1247-149440]  INFO -- : Processing by ErrorsController#routing as XML
I, [2024-06-06T06:54:43.069646#1247-149440]  INFO -- :   Parameters: {"a"=>"autodiscover/autodiscover"}
E, [2024-06-06T06:54:43.078721#1247-149440] ERROR -- : No route matches [POST] /autodiscover/autodiscover.xml (ActionController::RoutingError)

I, [2024-06-06T06:54:43.080055#1247-149440]  INFO -- :   Rendered inline template (Duration: 0.7ms | Allocations: 285)
I, [2024-06-06T06:54:43.081891#1247-149440]  INFO -- : Completed 404 Not Found in 12ms (Views: 1.1ms | ActiveRecord: 3.1ms | Allocations: 2418)

skip · June 6, 2024, 10:38am

As info, I set up a completely new server instance from scratch and am getting the same error there.

skip · June 6, 2024, 1:40pm

Additional information

I set it to POP and it worked fine with the parameters from the same provider.

I hope this helps

fliebe92 · June 13, 2024, 11:32am

Hi @skip. Sorry, too much on my desk right now

The provided log is still not the correct one, there are only some missing routes in there, but no other error.

system · June 8, 2025, 11:32am

This topic was automatically closed 360 days after the last reply. New replies are no longer allowed.