Hi Roberto,
thanks, for the quick reply.
Am Samstag, 31. August 2019, 00:29:53 CEST schrieb Roberto C. Sánchez:
> What happened here is that the pre-removal script of the prior version
> was called. Here is what that script contains:
>
> #!/bin/sh
> set -e
>
> if [ -x "/etc/init.d/dovecot" ]; then
> if [ -x /usr/sbin/invoke-rc.d ] ; then
> invoke-rc.d dovecot stop
> else
> /etc/init.d/dovecot stop
> fi
> fi
>
root@netcup:~# test -x "/etc/init.d/dovecot"
root@netcup:~# echo $?
0
root@netcup:~# test -x /usr/sbin/invoke-rc.d
root@netcup:~# echo $?
0
root@netcup:~# invoke-rc.d dovecot stop
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
root@netcup:~# echo $?
1
root@netcup:~# systemctl status dovecot
● dovecot.service - Dovecot IMAP/POP3 email server
Loaded: loaded (/lib/systemd/system/dovecot.service; enabled)
Active: active (running) since Sat 2019-08-31 09:47:19 CEST; 22s ago
Main PID: 12106 (dovecot)
CGroup: /system.slice/dovecot.service
├─12106 /usr/sbin/dovecot -F
├─12109 dovecot/anvil
├─12110 dovecot/log
├─12111 dovecot/ssl-params
├─12112 dovecot/imap-login
├─12113 dovecot/config
├─12115 dovecot/auth
└─12116 dovecot/imap
Aug 31 09:47:19 netcup systemd[1]: Starting Dovecot IMAP/POP3 email server...
Aug 31 09:47:19 netcup systemd[1]: Started Dovecot IMAP/POP3 email server.
Aug 31 09:47:21 netcup dovecot[12106]: master: Dovecot v2.2.13 starting up for
imap, sieve (core dumps disabled)
Aug 31 09:47:21 netcup dovecot[12110]: imap-login: Login: user=<rd>,
method=PLAIN, rip=2a02:8070:898f:e400:1a31:bfff:fe52:1b1c,
lip=2a03:4000:6:52b6::, mpid=12116, TLS, session=<wUjn8mSRVAAqAoBwiY/kABoxv//
+Uhsc>
root@netcup:~# /etc/init.d/dovecot stop
[....] Stopping dovecot (via systemctl): dovecot.serviceWarning: Stopping
dovecot.service, but it can still be activated by:
dovecot.socket
. ok
root@netcup:~# systemctl status dovecot
● dovecot.service - Dovecot IMAP/POP3 email server
Loaded: loaded (/lib/systemd/system/dovecot.service; enabled)
Active: active (running) since Sat 2019-08-31 09:48:54 CEST; 5s ago
Main PID: 12162 (dovecot)
CGroup: /system.slice/dovecot.service
├─12162 /usr/sbin/dovecot -F
├─12164 dovecot/anvil
├─12165 dovecot/log
├─12166 dovecot/ssl-params
├─12167 dovecot/imap-login
├─12168 dovecot/config
├─12170 dovecot/auth
├─12171 dovecot/imap-login
├─12172 dovecot/imap
└─12173 dovecot/imap
Aug 31 09:48:54 netcup systemd[1]: Started Dovecot IMAP/POP3 email server.
Aug 31 09:48:54 netcup dovecot[12162]: master: Dovecot v2.2.13 starting up for
imap, sieve (core dumps disabled)
Aug 31 09:48:55 netcup dovecot[12165]: imap-login: Login: user=<kathrin>,
method=PLAIN, rip=2a02:8070:898f:e400:1a31:bfff:fe52:1b1c,
lip=2a03:4000:6:52b6::, mpid=12172, TLS, session=<PI16+GSRfAAqAoBwiY/kABoxv//
+Uhsc>
Aug 31 09:48:55 netcup dovecot[12165]: imap-login: Login: user=<rd>,
method=PLAIN, rip=2a02:8070:898f:e400:1a31:bfff:fe52:1b1c,
lip=2a03:4000:6:52b6::, mpid=12173, TLS, session=<h6B6+GSRfgAqAoBwiY/kABoxv//
+Uhsc>
root@netcup:~# systemctl stop dovecot
Warning: Stopping dovecot.service, but it can still be activated by:
dovecot.socket
root@netcup:~# systemctl status dovecot
● dovecot.service - Dovecot IMAP/POP3 email server
Loaded: loaded (/lib/systemd/system/dovecot.service; enabled)
Active: active (running) since Sat 2019-08-31 09:49:13 CEST; 6s ago
Main PID: 12180 (dovecot)
CGroup: /system.slice/dovecot.service
├─12180 /usr/sbin/dovecot -F
├─12182 dovecot/anvil
├─12183 dovecot/log
├─12184 dovecot/ssl-params
├─12185 dovecot/imap-login
├─12186 dovecot/config
├─12188 dovecot/auth
├─12189 dovecot/imap-login
├─12190 dovecot/imap
└─12191 dovecot/imap
Aug 31 09:49:13 netcup systemd[1]: Started Dovecot IMAP/POP3 email server.
Aug 31 09:49:13 netcup dovecot[12180]: master: Dovecot v2.2.13 starting up for
imap, sieve (core dumps disabled)
Aug 31 09:49:14 netcup dovecot[12183]: imap-login: Login: user=<kathrin>,
method=PLAIN, rip=2a02:8070:898f:e400:1a31:bfff:fe52:1b1c,
lip=2a03:4000:6:52b6::, mpid=12190, TLS, session=<nwGc+WSRjAAqAoBwiY/kABoxv//
+Uhsc>
Aug 31 09:49:14 netcup dovecot[12183]: imap-login: Login: user=<rd>,
method=PLAIN, rip=2a02:8070:898f:e400:1a31:bfff:fe52:1b1c,
lip=2a03:4000:6:52b6::, mpid=12191, TLS, session=<nxac+WSRjgAqAoBwiY/kABoxv//
+Uhsc>
root@netcup:~#
It almost looks to me that dovecot restarts itself (?)
Aug 31 09:49:13 netcup systemd[1]: Stopping Dovecot IMAP/POP3 email server...
Aug 31 09:49:13 netcup dovecot[12165]: anvil: Warning: Killed with signal 15
(by pid=1 uid=0 code=kill)
Aug 31 09:49:13 netcup dovecot[12165]: auth: Error: read(anvil-auth-penalty)
failed: EOF
Aug 31 09:49:13 netcup dovecot[12165]: auth: Error: net_connect_unix(anvil-
auth-penalty) failed: Permission denied
Aug 31 09:49:13 netcup dovecot[12165]: imap-login: Error: read(anvil) failed:
EOF
Aug 31 09:49:13 netcup dovecot[12165]: imap-login: Error: read(anvil) failed:
EOF
Aug 31 09:49:13 netcup dovecot[12165]: imap: Server shutting down. in=160
out=105576
Aug 31 09:49:13 netcup dovecot[12165]: imap: Server shutting down. in=141
out=1696
Aug 31 09:49:13 netcup systemd[1]: Stopped Dovecot IMAP/POP3 email server.
Aug 31 09:49:13 netcup systemd[1]: Starting Dovecot IMAP/POP3 email server...
Aug 31 09:49:13 netcup systemd[1]: Started Dovecot IMAP/POP3 email server.
Aug 31 09:49:13 netcup dovecot[12180]: master: Dovecot v2.2.13 starting up for
imap, sieve (core dumps disabled)
> The service has only been up for a few minutes, but the upgrade failed
> to stop it, which makes me think it would not have such a recent start
> time. Was there something else going on with this machine?
The recent start time is probably since this was the output of apt-get install
-f, and the apt-get upgrade failed shortly before (with the same error
message). Fortunately, I still had the entire log in the terminal history:
root@netcup:~# apt-get upgrade
Reading package lists... Done
Building dependency tree
Reading state information... Done
Calculating upgrade... Done
The following packages will be upgraded:
dovecot-core dovecot-imapd dovecot-lucene dovecot-managesieved dovecot-sieve
libsvn1 subversion
7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Need to get 0 B/7,198 kB of archives.
After this operation, 216 kB of additional disk space will be used.
Do you want to continue? [Y/n]
Retrieving bug reports... Done
Parsing Found/Fixed information... Done
Reading changelogs... Done
(Reading database ... 93995 files and directories currently installed.)
Preparing to unpack .../subversion_1.8.10-6+deb8u7_amd64.deb ...
Unpacking subversion (1.8.10-6+deb8u7) over (1.8.10-6+deb8u6) ...
Preparing to unpack .../libsvn1_1.8.10-6+deb8u7_amd64.deb ...
Unpacking libsvn1:amd64 (1.8.10-6+deb8u7) over (1.8.10-6+deb8u6) ...
Preparing to unpack .../dovecot-imapd_1%3a2.2.13-12~deb8u7_amd64.deb ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: warning: subprocess old pre-removal script returned error exit status 1
dpkg: trying script from the new package instead ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: error processing archive /var/cache/apt/archives/dovecot-
imapd_1%3a2.2.13-12~deb8u7_amd64.deb (--unpack):
subprocess new pre-removal script returned error exit status 1
Preparing to unpack .../dovecot-managesieved_1%3a2.2.13-12~deb8u7_amd64.deb
...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: warning: subprocess old pre-removal script returned error exit status 1
dpkg: trying script from the new package instead ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: error processing archive /var/cache/apt/archives/dovecot-
managesieved_1%3a2.2.13-12~deb8u7_amd64.deb (--unpack):
subprocess new pre-removal script returned error exit status 1
Preparing to unpack .../dovecot-sieve_1%3a2.2.13-12~deb8u7_amd64.deb ...
Unpacking dovecot-sieve (1:2.2.13-12~deb8u7) over (1:2.2.13-12~deb8u6) ...
Preparing to unpack .../dovecot-lucene_1%3a2.2.13-12~deb8u7_amd64.deb ...
Unpacking dovecot-lucene (1:2.2.13-12~deb8u7) over (1:2.2.13-12~deb8u6) ...
Preparing to unpack .../dovecot-core_1%3a2.2.13-12~deb8u7_amd64.deb ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: warning: subprocess old pre-removal script returned error exit status 1
dpkg: trying script from the new package instead ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: error processing archive /var/cache/apt/archives/dovecot-
core_1%3a2.2.13-12~deb8u7_amd64.deb (--unpack):
subprocess new pre-removal script returned error exit status 1
Processing triggers for man-db (2.7.0.2-5) ...
Errors were encountered while processing:
/var/cache/apt/archives/dovecot-imapd_1%3a2.2.13-12~deb8u7_amd64.deb
/var/cache/apt/archives/dovecot-managesieved_1%3a2.2.13-12~deb8u7_amd64.deb
/var/cache/apt/archives/dovecot-core_1%3a2.2.13-12~deb8u7_amd64.deb
E: Sub-process /usr/bin/dpkg returned an error code (1)
root@netcup:~# apt-get upgrade
Reading package lists... Done
Building dependency tree
Reading state information... Done
You might want to run 'apt-get -f install' to correct these.
The following packages have unmet dependencies:
dovecot-lucene : Depends: dovecot-core (= 1:2.2.13-12~deb8u7) but
1:2.2.13-12~deb8u6 is installed
dovecot-managesieved : Depends: dovecot-sieve (= 1:2.2.13-12~deb8u6) but
1:2.2.13-12~deb8u7 is installed
dovecot-sieve : Depends: dovecot-core (= 1:2.2.13-12~deb8u7) but
1:2.2.13-12~deb8u6 is installed
E: Unmet dependencies. Try using -f.
root@netcup:~# apt-get -f install
Reading package lists... Done
Building dependency tree
Reading state information... Done
Correcting dependencies... Done
The following extra packages will be installed:
dovecot-core dovecot-imapd dovecot-managesieved
Suggested packages:
dovecot-gssapi dovecot-pgsql dovecot-mysql dovecot-sqlite dovecot-ldap
dovecot-pop3d dovecot-lmtpd dovecot-solr
The following packages will be upgraded:
dovecot-core dovecot-imapd dovecot-managesieved
3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
4 not fully installed or removed.
Need to get 0 B/3,884 kB of archives.
After this operation, 0 B of additional disk space will be used.
Do you want to continue? [Y/n]
Retrieving bug reports... Done
Parsing Found/Fixed information... Done
Reading changelogs... Done
(Reading database ... 93995 files and directories currently installed.)
Preparing to unpack .../dovecot-imapd_1%3a2.2.13-12~deb8u7_amd64.deb ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: warning: subprocess old pre-removal script returned error exit status 1
dpkg: trying script from the new package instead ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: error processing archive /var/cache/apt/archives/dovecot-
imapd_1%3a2.2.13-12~deb8u7_amd64.deb (--unpack):
subprocess new pre-removal script returned error exit status 1
Preparing to unpack .../dovecot-managesieved_1%3a2.2.13-12~deb8u7_amd64.deb
...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: warning: subprocess old pre-removal script returned error exit status 1
dpkg: trying script from the new package instead ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: error processing archive /var/cache/apt/archives/dovecot-
managesieved_1%3a2.2.13-12~deb8u7_amd64.deb (--unpack):
subprocess new pre-removal script returned error exit status 1
Preparing to unpack .../dovecot-core_1%3a2.2.13-12~deb8u7_amd64.deb ...
Job for dovecot.service canceled.
invoke-rc.d: initscript dovecot, action "stop" failed.
dpkg: warning: subprocess old pre-removal script returned error exit status 1
dpkg: trying script from the new package instead ...
Warning: Stopping dovecot.service, but it can still be activated by:
dovecot.socket
dpkg: ... it looks like that went OK
Unpacking dovecot-core (1:2.2.13-12~deb8u7) over (1:2.2.13-12~deb8u6) ...
Processing triggers for systemd (215-17+deb8u13) ...
Processing triggers for man-db (2.7.0.2-5) ...
Errors were encountered while processing:
/var/cache/apt/archives/dovecot-imapd_1%3a2.2.13-12~deb8u7_amd64.deb
/var/cache/apt/archives/dovecot-managesieved_1%3a2.2.13-12~deb8u7_amd64.deb
E: Sub-process /usr/bin/dpkg returned an error code (1)
root@netcup:~# df
Filesystem 1K-blocks Used Available Use% Mounted on
udev 10240 0 10240 0% /dev
tmpfs 203900 21964 181936 11% /run
/dev/vda2 55884132 50992956 2029304 97% /
tmpfs 509748 0 509748 0% /dev/shm
tmpfs 5120 0 5120 0% /run/lock
tmpfs 509748 0 509748 0% /sys/fs/cgroup
tmpfs 101952 0 101952 0% /run/user/2809
root@netcup:~# systemctl status dovecot
● dovecot.service - Dovecot IMAP/POP3 email server
Loaded: loaded (/lib/systemd/system/dovecot.service; enabled)
Active: active (running) since Fri 2019-08-30 23:42:26 CEST; 3min 16s ago
Main PID: 1802 (dovecot)
CGroup: /system.slice/dovecot.service
├─1802 /usr/sbin/dovecot -F
├─1805 dovecot/anvil
├─1806 dovecot/log
├─1808 dovecot/imap-login
├─1809 dovecot/config
├─1814 dovecot/imap
├─1844 dovecot/imap-login
├─1845 dovecot/imap-login
├─1846 dovecot/imap-login
├─1847 dovecot/imap-login
├─1848 dovecot/imap-login
├─1849 dovecot/imap-login
├─1850 dovecot/imap-login
├─1851 dovecot/imap
├─1852 dovecot/imap-login
├─1853 dovecot/imap-login
├─1854 dovecot/imap
├─1855 dovecot/imap-login
├─1856 dovecot/imap
├─1857 dovecot/imap
├─1858 dovecot/imap
├─1859 dovecot/imap
├─1860 dovecot/imap
├─1861 dovecot/imap
├─1862 dovecot/imap
└─1863 dovecot/imap
Aug 30 23:42:26 netcup systemd[1]: Started Dovecot IMAP/POP3 email server.
Aug 30 23:42:26 netcup dovecot[1802]: master: Dovecot v2.2.13 starting up for
imap, sieve (core dumps disabled)
Aug 30 23:42:26 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=2a02:8070:898f:e400:1a31:bfff:fe52:1b1c,
lip=2a03:4000:6:52b6::, mpid=1814, TLS, session=<0fqMf1yR8AAqAoBwiY/kABoxv//
+Uhsc>
Aug 30 23:42:31 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1851, TLS,
session=<M2rff1yRZQBOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1854, TLS,
session=<vUngf1yRZgBOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1856, TLS,
session=<9xrhf1yRhABOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1857, TLS,
session=<nhrhf1yRTQBOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1858, TLS,
session=<Rxrhf1yRlwBOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1859, TLS,
session=<F+7hf1yRmQBOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1860, TLS,
session=<MWvif1yRIgBOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1861, TLS,
session=<uvXif1yR3ABOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1862, TLS,
session=<eUzjf1yRyQBOKygI>
Aug 30 23:42:32 netcup dovecot[1806]: imap-login: Login: user=<rd>,
method=PLAIN, rip=78.43.40.8, lip=37.120.169.230, mpid=1863, TLS,
session=<fgHlf1yRJgBOKygI>
root@netcup:~#
Kind regards
Rainer
--
Rainer Dorsch
http://bokomoko.de/
