ようこそNICのホームページへ(なが〜い試運転中 from 2004年)
  • @nifty atnifty-ddns ddo.jp dip.jp   HP パソコン 健康 落語 海外 思うまま 電子工作
  • ubuntuのバージョンアップをしようとして、
    logwatchがおかしくなった??、dovecotが動いていなかった

  • Last update:2020/07/12    Build:2020/07/12
  •  
    7月7日にubuntu18.04LTSを20にバージョンアップしようとした。
     結果LTSの正式リリースがなくて、そのままとなった。
    翌日、logwatchからのメールが届いていない。これは??

      四苦八苦したので備忘録として記載

    1.状況は以下のとおり

    2.結論は以下のとおり

    Dovecotがバージョンアップ(v2.2.22 (fe789d2)→v2.2.33.2 (d6601f4ec))された時に confファイルが変更になり、ssl関連のキー配置場所とファイル名が違っていた。
    /etc/dovecot/conf.d/10-ssl.conf の比較
    アップデート前
    ssl_cert =</etc/dovecot/dovecot.pem      <==こちらがcertkey
    ssl_key  =</etc/dovecot/private/dovecot.pem <==こちらがprivatekey
    
    アップデート後
    ssl_cert =</etc/dovecot/private/dovecot.pem  <==こちらがcertkey
    ssl_key  =</etc/dovecot/private/dovecot.key <==こちらがprivatekey
    

    ファイル名と配置場所を変更したら、無事メールが受信できるようになった。

    以下は3日間の四苦八苦です。興味のある方はご覧ください。


    3.logwatchが動いてない??

    logwatchを動かすクローンをどこで設定したか思い出せない。 調べてみると、
    logwatchのcronは自動設定で /etc/cron.daily <== ここにlogwatchのcron設定があった。
    drwxr-xr-x   2 root root  4096  7月  7 10:24 ./
    drwxr-xr-x 110 root root 12288  7月  7 10:24 ../
    -rw-r--r--   1 root root   102  2月  9  2013 .placeholder
    -rwxr-xr-x   1 root root   268  7月 10  2013 00logwatch*   <== ここにlogwatchのcron設定がある。
    -rwxr-xr-x   1 root root   539  4月  6  2016 apache2*
    -rwxr-xr-x   1 root root   376  4月  4  2014 apport*
    -rwxr-xr-x   1 root root  1478 10月  9  2018 apt-compat*
    -rwxr-xr-x   1 root root   314  2月 18  2014 aptitude*
    -rwxr-xr-x   1 root root   355  6月  4  2013 bsdmainutils*
    -rwxr-xr-x   1 root root  1176 11月  3  2017 dpkg*
    -rwxr-xr-x   1 root root   372  1月 23  2014 logrotate*
    -rwxr-xr-x   1 root root  1065  4月  7  2018 man-db*
    -rwxr-xr-x   1 root root   538  3月  2  2018 mlocate*
    -rwxr-xr-x   1 root root  1387 12月 13  2017 ntp*
    -rwxr-xr-x   1 root root   249  2月 17  2014 passwd*
    -rwxr-xr-x   1 root root  3477  2月 21  2018 popularity-contest*
    -rwxr-xr-x   1 root root   246  3月 22  2018 ubuntu-advantage-tools*
    -rwxr-xr-x   1 root root   214 10月  7  2014 update-notifier-common*
    -rwxr-xr-x   1 root root  1046  5月 19  2016 upstart*
    
    う〜ん??! logwatchは毎日動くようになっているようだ。

    4.Postfixがいている??

    あれ、偶然Postfixが動いていることを見つけた
    root# ps aux | grep postfix
    root       996  0.0  0.0  67376  3532 ?        Ss    7月07   0:00 /usr/lib/postfix/sbin/master -w
    postfix    998  0.0  0.1  69480  4872 ?        S     7月07   0:00 qmgr -l -t unix -u
    postfix   4968  0.0  0.1  69432  4824 ?        S    08:39   0:00 pickup -l -t unix -u -c
    root      5358  0.0  0.0  15452  1148 pts/0    S+   09:51   0:00 grep --color=auto postfix
    
    一方Dovecotは??
    root# ps aux | grep dovecot
    root      5416  0.0  0.0  15452  1004 pts/0    S+   10:06   0:00 grep --color=auto dovecot
    root# 
    
    動いていない!!!
    logwatchではなく、Dovecotが動いていないのか??
    logwatchのメールが置かれるディレクトリーを見てみると
    root:/home/nic/Maildir# ll
    drwxrwxr-x  5 nic nic  4096  7月  7 08:37 ./
    drwxr-xr-x 16 nic nic  4096  7月  7 18:40 ../
    drwx------  2 nic nic  4096  7月  7 08:37 cur/
    -rw-rw-r--  1 nic nic  1137  7月  7 08:37 dovecot-uidlist
    -rw-rw-r--  1 nic nic     8 11月  7  2015 dovecot-uidvalidity
    -r--r--r--  1 nic nic     0 11月  7  2015 dovecot-uidvalidity.563deb76
    -rw-rw-r--  1 nic nic   388  7月  5 13:10 dovecot.index
    -rw-rw-r--  1 nic nic 17268  7月  7 08:37 dovecot.index.cache
    -rw-rw-r--  1 nic nic 26492  7月  7 08:37 dovecot.index.log
    drwx------  2 nic nic  4096  7月  8 06:25 new/  <== ここ
    drwx------  2 nic nic  4096  7月  8 06:25 tmp/
    
    newのディレクトリーを覗くと
    root:/home/nic/Maildir# cd new
    root:/home/nic/Maildir/new# ll
    drwx------ 2 nic nic  4096  7月  8 06:25 ./
    drwxrwxr-x 5 nic nic  4096  7月  7 08:37 ../
    -rw------- 1 nic nic 45488  7月  8 06:25 1594157107.Vfd00I2803beM948091  <== ここ
    
    8日朝のファイルがある。 logwatchは動いてメールを送っているが、dovecotが動いていなくて、pop3でメールが受け取れない可能性がある。

    5.原因はDovecotが動いていない

    さっそくcatで新規メールの中身を見てみると
    root:/home/nic/Maildir/new# cat 1594157107.Vfd00I2803beM948091
    Return-Path: 
    (略)
     ################### Logwatch 7.4.3 (12/07/16) #################### 
            Processing Initiated: Wed Jul  8 06:25:05 2020  <== logwatchが動いている
            Date Range Processed: yesterday
                                  ( 2020-Jul-07 )
                                  Period is day.
            Detail Level of Output: 0
            Type of Output/Format: mail / text
            Logfiles for Host: ubuntu-server-nic
     ################################################################## 
    (以下略)
    
    logwatchは動いてメールを送っている。メールがthunderbirdで取り出せないだけのようだ。 Dovecotが動いていないようだ。
    バージョンアップしたことで何か変化があるのか??
    Dovecotのディレクトリーを覗いてみると バージョンアップしたことで日付が新しくなっているファイルがある。 日付が2019年8月になっている (前回のバージョンアップは2018年の夏頃)
    root@ubuntu-server:/etc/dovecot/conf.d# ll
    drwxr-xr-x 2 root root  4096  7月  8 13:38 ./
    drwxr-xr-x 4 root root  4096  7月  8 13:09 ../
    -rw-r--r-- 1 root root  5324 11月  7  2015 10-auth.conf
    -rw-r--r-- 1 root root  1893  5月 15  2014 10-director.conf
    -rw-r--r-- 1 root root  3062 10月  6  2017 10-logging.conf
    -rw-r--r-- 1 root root 15819 11月  7  2015 10-mail.conf
    -rw-r--r-- 1 root root 17244  8月 29  2019 10-mail.conf.ucf-dist   <==ここ
    -rw-r--r-- 1 root root  3383  5月 15  2014 10-master.conf
    -rw-r--r-- 1 root root  2329  8月 29  2019 10-ssl.conf     <==ここ エラーが出た
    -rw-r--r-- 1 root root   291  5月 15  2014 10-tcpwrapper.conf
    -rw-r--r-- 1 root root  1668  5月 15  2014 15-lda.conf
    -rw-r--r-- 1 root root  2808  3月 16  2016 15-mailboxes.conf
    -rw-r--r-- 1 root root  4235 10月  6  2017 20-imap.conf
    -rw-r--r-- 1 root root  4065  3月 16  2016 20-pop3.conf
    -rw-r--r-- 1 root root   676  5月 15  2014 90-acl.conf
    -rw-r--r-- 1 root root   292  5月 15  2014 90-plugin.conf
    -rw-r--r-- 1 root root  2597  6月 23  2017 90-quota.conf
    -rw-r--r-- 1 root root   499  5月 15  2014 auth-checkpassword.conf.ext
    -rw-r--r-- 1 root root   489  5月 15  2014 auth-deny.conf.ext
    -rw-r--r-- 1 root root   343  3月 16  2016 auth-dict.conf.ext
    -rw-r--r-- 1 root root   561  5月 15  2014 auth-master.conf.ext
    -rw-r--r-- 1 root root   515  5月 15  2014 auth-passwdfile.conf.ext
    -rw-r--r-- 1 root root   788  5月 15  2014 auth-sql.conf.ext
    -rw-r--r-- 1 root root   611  5月 15  2014 auth-static.conf.ext
    -rw-r--r-- 1 root root  2185  5月 15  2014 auth-system.conf.ext
    -rw-r--r-- 1 root root   330  5月 15  2014 auth-vpopmail.conf.ext
    
    pop3を使うのだからsslはなくてもいいだろうと判断し、 10-ssl.confを10-ssl.conf-orgにリネームしてリブート、 稼働状態をチェック
    root@ubuntu-server:~# systemctl status dovecot >dmy
    root@ubuntu-server:~# cat dmy
    ● dovecot.service - Dovecot IMAP/POP3 email server
       Loaded: loaded (/lib/systemd/system/dovecot.service; enabled; vendor preset: enabled)
       Active: active (running) since Wed 2020-07-08 13:46:22 JST; 4min 28s ago <== 稼働している
         Docs: man:dovecot(1)
               http://wiki2.dovecot.org/
     Main PID: 760 (dovecot)
        Tasks: 4 (limit: 4207)
       CGroup: /system.slice/dovecot.service
               ├─760 /usr/sbin/dovecot -F
               ├─794 dovecot/anvil
               ├─795 dovecot/log
               └─800 dovecot/config
    
     7月 08 13:46:22 ubuntu-server systemd[1]: Started Dovecot IMAP/POP3 email server.
     7月 08 13:46:23 ubuntu-server dovecot[760]: master: Dovecot v2.2.33.2 (d6601f4ec) starting up for imap, pop3 (core dumps disabled)
    root@ubuntu-server-nic:~# 
    
    
    psでチェック 動いている
    root@ubuntu-server:~# ps aux |grep dovecot
    root       760  0.0  0.0  18520  3184 ?        Ss   13:46   0:00 /usr/sbin/dovecot -F
    dovecot    794  0.0  0.0   9872  1100 ?        S    13:46   0:00 dovecot/anvil
    root       795  0.0  0.0  10004  2620 ?        S    13:46   0:00 dovecot/log
    root       800  0.0  0.1  21452  3940 ?        S    13:46   0:00 dovecot/config
    root      1179  0.0  0.0  15452  1008 pts/0    S+   13:53   0:00 grep --color=auto dovecot
    root@ubuntu-server:~# 
    
    Dovecotが立ち上がるようになったが、やはりだめ。 今まではthunderbirdでメールにアクセスすると「拒否された」とのエラーがでた。 このエラーはなくなったが、メールの受信ができないのは変わらない(2020/7/8 13:55)

    /var/log/mail.logにエラーがでるようになった。
    Jul  8 13:54:02 ubuntu-server-nic dovecot: pop3-login: Fatal: Couldn't parse private ssl_key: error:0909006C:
      継続行 PEM routines:get_name:no start line: Expecting: ANY PRIVATE KEY
    Jul  8 13:54:02 ubuntu-server-nic dovecot: master: Error: 
      継続行 service(pop3-login): command startup failed, throttling for 2 secs
    
    しかしわからない。

    telnetでpop3にログインすると
    root@ubuntu-server:/etc/dovecot# telnet localhost 110
    Trying 127.0.0.1...
    Connected to localhost.
    Escape character is '^]'.
    Connection closed by foreign host.   <== ここでキャンセルされている
    
    キャンセルされて入れない、 エラーを見ると
    root@ubuntu-server:/etc/dovecot# cd /var/log
    root@ubuntu-server:/var/log# tail mail.err
    Jul 10 16:38:55 ubuntu-server-nic dovecot: pop3-login: Fatal: Couldn't parse private ssl_key: 
     継続行 error:0909006C:PEM routines:get_name:no start line: Expecting: ANY PRIVATE KEY
    Jul 10 16:38:55 ubuntu-server-nic dovecot: master: Error: service(pop3-login): 
     継続行 command startup failed, throttling for 4 secs
    
    ssl-keyが無いとのこと。  pop3で何故だ???

    6.ssl−keyの位置と名前が違う

    Dovecotは設定でpop3s、IMAPsも使う設定になっているようだ。 この設定を止める方法もあるが、へたに触ってさらに複雑にするよりも、 バージョンアップ前後の10-ssl.confを比較して対応するようにした。
    Dovecotがバージョンアップ(v2.2.22 (fe789d2)→v2.2.33.2 (d6601f4ec))された時に confファイルが変更になり、ssl関連のキー配置場所とファイル名が違っていた。

    /etc/dovecot/conf.d/10-ssl.conf の比較
    アップデート前
    ssl_cert =</etc/dovecot/dovecot.pem      <==こちらがcertkey
    ssl_key  =</etc/dovecot/private/dovecot.pem <==こちらがprivatekey
    
    アップデート後
    ssl_cert =</etc/dovecot/private/dovecot.pem  <==こちらがcertkey
    ssl_key  =</etc/dovecot/private/dovecot.key <==こちらがprivatekey
    

    新しい/etc/dovecot/conf.d/10-ssl.conf に合わせて ファイル名と配置場所を変更したら、無事メールが受信できるようになった。


    パソコンへ     HomePage     このページのトップ