How debug email setup in wekan


#1

I have 3 dockers for wekan app:

  • wekan-app
  • wekan-db
  • mailserver

I got below logs of mailserver when invite one person to one board:

Jul 1 00:49:08 04518caefb02 postfix/smtpd[192]: connect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 1 00:49:08 04518caefb02 postfix/smtpd[192]: lost connection after STARTTLS from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 1 00:49:08 04518caefb02 postfix/smtpd[192]: disconnect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]

I got details from mailserver by add “debug_peer_list = 172.0.0.0/8” to /etc/postfix/main.cf and still got no clue:

Jul 3 01:11:20 04518caefb02 postfix/smtpd[232]: connect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:11:20 04518caefb02 postfix/smtpd[232]: warning: non-null host address bits in “172.19.0.0/8”, perhaps you should use “172.0.0.0/8” instead
Jul 3 01:11:20 04518caefb02 postfix/smtpd[232]: lost connection after STARTTLS from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:11:20 04518caefb02 postfix/smtpd[232]: disconnect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:12:32 04518caefb02 postfix/smtpd[232]: connect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:12:32 04518caefb02 postfix/smtpd[232]: warning: non-null host address bits in “172.19.0.0/8”, perhaps you should use “172.0.0.0/8” instead
Jul 3 01:12:32 04518caefb02 postfix/smtpd[232]: lost connection after STARTTLS from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:12:32 04518caefb02 postfix/smtpd[232]: disconnect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:12:41 04518caefb02 postfix/postfix-script[244]: refreshing the Postfix mail system
Jul 3 01:12:41 04518caefb02 postfix/anvil[235]: statistics: max connection rate 1/60s for (smtp:172.19.0.4) at Jul 3 01:11:20
Jul 3 01:12:41 04518caefb02 postfix/anvil[235]: statistics: max connection count 1 for (smtp:172.19.0.4) at Jul 3 01:11:20
Jul 3 01:12:41 04518caefb02 postfix/anvil[235]: statistics: max cache size 1 at Jul 3 01:11:20
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: connect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: smtp_stream_setup: maxtime=300 enable_deadline=0
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostname: wekan-app.wekanmongodb_wekan-tier ~? 127.0.0.0/8
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostaddr: 172.19.0.4 ~? 127.0.0.0/8
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostname: wekan-app.wekanmongodb_wekan-tier ~? [::ffff:127.0.0.0]/104
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostaddr: 172.19.0.4 ~? [::ffff:127.0.0.0]/104
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostname: wekan-app.wekanmongodb_wekan-tier ~? [::1]/128
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostaddr: 172.19.0.4 ~? [::1]/128
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_list_match: wekan-app.wekanmongodb_wekan-tier: no match
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_list_match: 172.19.0.4: no match
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: auto_clnt_open: connected to private/anvil
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr request = connect
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr ident = smtp:172.19.0.4
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/anvil: wanted attribute: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute value: 0
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/anvil: wanted attribute: count
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: count
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute value: 1
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/anvil: wanted attribute: rate
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: rate
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute value: 1
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/anvil: wanted attribute: (list terminator)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: (end)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: report connect to all milters
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "j"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: result "wekan.com"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "{daemon_name}"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: result "wekan.com"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "v"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: result "Postfix 2.11.0"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: non-protocol events for protocol version 2: SMFIP_NOUNKNOWN SMFIP_NODATA
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: transport=inet endpoint=localhost:8891
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: trying… [127.0.0.1]
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: vstream_tweak_tcp: TCP_MAXSEG 21845
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: fd=19: stream buffer size old=0 new=43690
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: my_version=0x2
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: my_actions=0x1ff SMFIF_ADDHDRS SMFIF_CHGBODY SMFIF_ADDRCPT SMFIF_DELRCPT SMFIF_CHGHDRS SMFIF_QUARANTINE SMFIF_CHGFROM SMFIF_ADDRCPT_PAR SMFIF_SETSYMLIST
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: my_events=0x7f SMFIP_NOCONNECT SMFIP_NOHELO SMFIP_NOMAIL SMFIP_NORCPT SMFIP_NOBODY SMFIP_NOHDRS SMFIP_NOEOH
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: milter inet:localhost:8891 version 2
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: events SMFIP_NOHELO
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_connect: requests SMFIF_ADDHDRS SMFIF_CHGHDRS SMFIF_SETSYMLIST
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_conn_event: milter inet:localhost:8891: connect wekan-app.wekanmongodb_wekan-tier/172.19.0.4
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: event: SMFIC_CONNECT; macros: j=wekan.com {daemon_name}=wekan.com v=Postfix 2.11.0
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: reply: SMFIR_CONTINUE data 0 bytes
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 220 wekan.com ESMTP
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: xsasl_cyrus_server_create: SASL service=smtp, realm=(null)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: name_mask: noanonymous
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: watchdog_pat: 0x556bf7f9ac00
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: < wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: EHLO [127.0.0.1]
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: report helo to all milters
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "{tls_version}"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "{cipher}"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "{cipher_bits}"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "{cert_subject}"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter_macro_lookup: "{cert_issuer}"
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_helo_event: milter inet:localhost:8891: helo [127.0.0.1]
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: event: SMFIC_HELO; macros: (none)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: skipping event SMFIC_HELO for milter inet:localhost:8891
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_list_match: wekan-app.wekanmongodb_wekan-tier: no match
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_list_match: 172.19.0.4: no match
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-wekan.com
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-PIPELINING
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-SIZE 10240000
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-VRFY
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-ETRN
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-STARTTLS
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-AUTH PLAIN LOGIN
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-ENHANCEDSTATUSCODES
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250-8BITMIME
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 250 DSN
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: watchdog_pat: 0x556bf7f9ac00
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: < wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: STARTTLS
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: query milter states for other event
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_other_event: milter inet:localhost:8891
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: > wekan-app.wekanmongodb_wekan-tier[172.19.0.4]: 220 2.0.0 Ready to start TLS
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: abort all milters
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_abort: abort milter inet:localhost:8891
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr request = seed
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr size = 32
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/tlsmgr: wanted attribute: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute value: 0
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/tlsmgr: wanted attribute: seed
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: seed
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute value: K6wvDZaYx2Of+4noAwsIV6UD99bivkD4NFUP6XhocVc=
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/tlsmgr: wanted attribute: (list terminator)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: (end)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr request = update
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr cache_type = smtpd
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr cache_id = B9D50557B91AD9A10197ECFAB34C2A437AE2375D1EB787226F984165010503ED&s=smtp&l=268439663
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr session = [data 127 bytes]
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/tlsmgr: wanted attribute: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute value: 0
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/tlsmgr: wanted attribute: (list terminator)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: (end)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: xsasl_cyrus_server_create: SASL service=smtp, realm=(null)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: name_mask: noanonymous
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: watchdog_pat: 0x556bf7f9ac00
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: smtp_get: EOF
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostname: wekan-app.wekanmongodb_wekan-tier ~? 127.0.0.0/8
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostaddr: 172.19.0.4 ~? 127.0.0.0/8
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostname: wekan-app.wekanmongodb_wekan-tier ~? [::ffff:127.0.0.0]/104
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostaddr: 172.19.0.4 ~? [::ffff:127.0.0.0]/104
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostname: wekan-app.wekanmongodb_wekan-tier ~? [::1]/128
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_hostaddr: 172.19.0.4 ~? [::1]/128
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_list_match: wekan-app.wekanmongodb_wekan-tier: no match
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: match_list_match: 172.19.0.4: no match
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr request = disconnect
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: send attr ident = smtp:172.19.0.4
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/anvil: wanted attribute: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: status
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute value: 0
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: private/anvil: wanted attribute: (list terminator)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: input attribute name: (end)
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: lost connection after STARTTLS from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: disconnect event to all milters
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: milter8_disc_event: quit milter inet:localhost:8891
Jul 3 01:12:54 04518caefb02 postfix/smtpd[250]: disconnect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]

I got no log from wekan-app although wekan reports mail send failed. So anyone can help me

  1. how got detail fail log in wekan app?
  2. any clue from mailserver fail logs above?

Thanks a lot.


#2

#3

Thanks @xet7!I have read that wiki and got no clue about my problem.
I wonder why I got nothing by “docker logs wekan-app” when error reported in chrome when invite people?
Is there any switch I missed?


#4

Jul 5 07:43:15 22b8147d71f2 postfix/smtpd[209]: connect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 5 07:43:15 22b8147d71f2 postfix/smtpd[209]: lost connection after UNKNOWN from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]
Jul 5 07:43:15 22b8147d71f2 postfix/smtpd[209]: disconnect from wekan-app.wekanmongodb_wekan-tier[172.19.0.4]

I got above logs from mailserver when checking “Enable TLS support for SMTP Server”.


#5

I have the same problem since Wekan started using Meteor 1.4. With the TLS checkbox unchecked I still see STARTTLS being sent to my mail server and then it rejects the connection.


#6

Please continue discussion at:
https://github.com/wekan/wekan/issues/1120