IMAP Account setup error

Technical assistance
1

  • 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
    image
    image1397×2702 273 KB
  • 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

2

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

3

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)
4

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

5

Additional information

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

I hope this helps

6

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.