IMAP Account setup error

  • 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:

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
  • Click continue
  • Get undefined method '[]' for nil:NilClass
    image

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

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

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)

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

Additional information

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

I hope this helps

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

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

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