Random Error on Webmail login /usr/share/usermin/mailbox/folders-lib.pl line 2016.

http://virtualmin.com/node/35424

When I login as a user to access the webmail I get the following error randomly.

Sometimes the webmail panel work and sometimes no.

Error - Perl execution failed

Can't use an undefined value as a symbol reference at /usr/share/usermin/mailbox/folders-lib.pl line 2016.

Status: 
Active

Comments

Looks like an IMAP command failed. Can you try adding the line error_stack=1 to /etc/usermin/config , and then posting the full error message next time it happens?

I already have the error_stack=1

Ok, can you attach a screenshot or copy and paste the full error message? It should include the names and line numbers of several .cgi and .pl files, which will give me an idea of where exactly the error is being triggered.

The only error I see is this one. Now I cannot reproduce it so I can't attach a screenshot, but usually it happens often to more than one user.

Connecting to https://mydomain:20000 to check email I get only

Error - Perl execution failed

Can't use an undefined value as a symbol reference at /usr/share/usermin/mailbox/folders-lib.pl line 2016.

Are you sure it is /etc/usermin/config that has the error_stack=1 line, and not /etc/webmin/config ?

Yes I'm sure /etc/usermin/config

See attachment

Can you also attach a screenshot of the full browser window when this happens? I'd like to see where in the UI it occurs.

It's a random error and is difficult to catch.

I've done this

vim /etc/dovecot/20-imap.conf

and changed

mail_max_userip_connections = 100

to

mail_max_userip_connections = 250

I think that the random error is involved in some kind of limitation and I hope I'm right. The error is on the right panel and on the left panel.

Sometimes clicking on the usermin and then re-clicking on mail in the top left start working again.

I think that with the impa conf edit things are going better but I still have errors in the miniserv log that I attach.

It could be that you're hitting the IMAP connection limit. Did making that dovecot change help?

I see less errors, but there are still some.

Do you think it's related to that limit?

There are also other limits?

It may be worth checking the dovecot log to see if it reports any message when this limit is exceeded. The log is usually /var/log/maillog or mail.log

Which is the sintax of that limit excess in the log?

I don't know, but you could grep for lines that contain dovecot

Increased the amount but the same error randomically.

File attached

The error continue to go...

What can I do. The webmail is practically unasable.

Would it be possible for me to login to your system to debug the underlying cause here?

No it's not possible.

It's a production server.

Ok, I have a fix that should at least better identify what is really going wrong here.

Which Linux distribution are you running on there?

Operating system Debian Linux 7

Try replacing the file /usr/share/usermin/mailbox/mailbox-lib.pl with the file attached to this bug report, and see if the error message changes.

Sorry but I can't see any file here.

Where I can find it?

I have sent you the file via email instead.

Ok. Now I have a different error.

I sent you the screen via mail causa the uploader here doesn't work.

Ok, it looks like is at least closer to finding the cause of the error. The problem seems to be that the IMAP server is returning an error message, but in a format Usermin cannot parse.

However, I'm surprised that nothing gets logged to the mail log (like /var/log/mail.log) at the same time this happens..

The only things I can see looking the time lapse of the screenshot is the following:

Feb 17 13:49:19 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1085 out=42682
Feb 17 14:02:49 server3 spamd[3844]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 14:02:49 server3 spamd[3844]: spamd: processing message (unknown) for louise.XXXXXXXXXXXXXX:1387
Feb 17 14:02:49 server3 spamd[3844]: spamd: identified spam (8.9/5.0) for louise.XXXXXXXXXXXXXX:1387 in 0.3 seconds, 54890 bytes.
Feb 17 14:02:49 server3 spamd[3844]: spamd: result: Y 8 - BAYES_40,HTML_MESSAGE,KB_DATE_CONTAINS_TAB,MIME_HTML_ONLY,MISSING_MID,RCVD_IN_BL_SPAMCOP_NET,RCVD_IN_BRBL_LASTEXT,RCVD_IN_RP_RNBL,RDNS_NONE,TAB_IN_FROM scantime=0.3,size=54890,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=56552,mid=(unknown),bayes=0.206271,autolearn=no
Feb 17 14:02:49 server3 postfix/local[19843]: A156E44402B4: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=6.2, delays=5.5/0/0/0.77, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 14:26:57 server3 spamd[3843]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 14:26:57 server3 spamd[3843]: spamd: processing message (unknown) for louise.XXXXXXXXXXXXXX:1387
Feb 17 14:27:01 server3 spamd[3843]: spamd: identified spam (17.8/5.0) for louise.XXXXXXXXXXXXXX:1387 in 4.1 seconds, 854 bytes.
Feb 17 14:27:01 server3 spamd[3843]: spamd: result: Y 17 - BAYES_99,BAYES_999,BODY_URI_ONLY,DSN_NO_MIMEVERSION,MISSING_MID,RCVD_IN_BL_SPAMCOP_NET,RCVD_NUMERIC_HELO,RDNS_DYNAMIC,TVD_RCVD_IP,TVD_RCVD_IP4,T_FSL_HELO_BARE_IP_2,URIBL_BLOCKED,URIBL_DBL_SPAM,URIBL_JP_SURBL,URIBL_MW_SURBL,URIBL_SC_SURBL,URIBL_WS_SURBL scantime=4.1,size=854,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=57858,mid=(unknown),bayes=1.000000,autolearn=spam
Feb 17 14:27:01 server3 postfix/local[25379]: 5587D44402C6: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=6.2, delays=1.6/0/0/4.5, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 14:54:00 server3 spamd[3843]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 14:54:00 server3 spamd[3843]: spamd: processing message <2107184129.962815.1424181238088.JavaMail.app@ltx1-app7349.prod> for louise.XXXXXXXXXXXXXX:1387
Feb 17 14:54:01 server3 spamd[3843]: spamd: clean message (-1.9/5.0) for louise.XXXXXXXXXXXXXX:1387 in 0.6 seconds, 27459 bytes.
Feb 17 14:54:01 server3 spamd[3843]: spamd: result: . -1 - BAYES_00,HTML_MESSAGE,SPF_HELO_PASS,SPF_PASS,T_DKIM_INVALID,T_RP_MATCHES_RCVD,URIBL_BLOCKED scantime=0.6,size=27459,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=59596,mid=<2107184129.962815.1424181238088.JavaMail.app@ltx1-app7349.prod>,bayes=0.000000,autolearn=ham
Feb 17 14:54:01 server3 postfix/local[31925]: E85E044402BE: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=1.7, delays=0.67/0/0/0.98, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 15:06:01 server3 spamd[2525]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 15:06:01 server3 spamd[2525]: spamd: processing message (unknown) for louise.XXXXXXXXXXXXXX:1387
Feb 17 15:06:01 server3 spamd[2525]: spamd: identified spam (9.6/5.0) for louise.XXXXXXXXXXXXXX:1387 in 0.2 seconds, 54869 bytes.
Feb 17 15:06:01 server3 spamd[2525]: spamd: result: Y 9 - BAYES_40,HTML_MESSAGE,KB_DATE_CONTAINS_TAB,MIME_HTML_ONLY,MISSING_MID,RCVD_IN_BL_SPAMCOP_NET,RCVD_IN_BRBL_LASTEXT,RCVD_IN_RP_RNBL,RCVD_IN_SORBS_WEB,RDNS_NONE,TAB_IN_FROM scantime=0.2,size=54869,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=60255,mid=(unknown),bayes=0.364989,autolearn=no
Feb 17 15:06:01 server3 postfix/local[1609]: 0586244402B0: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=44, delays=43/0/0/0.64, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 15:17:25 server3 spamd[2525]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 15:17:25 server3 spamd[2525]: spamd: processing message <B4685F6E3131404D98B995915B7AF8F404AED168@AUDIINSX0350.audi.vwg> for louise.XXXXXXXXXXXXXX:1387
Feb 17 15:17:25 server3 spamd[2525]: spamd: clean message (-1.9/5.0) for louise.XXXXXXXXXXXXXX:1387 in 0.4 seconds, 1998 bytes.
Feb 17 15:17:25 server3 spamd[2525]: spamd: result: . -1 - BAYES_00,SPF_HELO_PASS,SPF_PASS scantime=0.4,size=1998,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=60885,mid=<B4685F6E3131404D98B995915B7AF8F404AED168@AUDIINSX0350.audi.vwg>,bayes=0.000000,autolearn=ham
Feb 17 15:17:25 server3 postfix/local[4947]: DA12444402B0: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=0.81, delays=0.02/0/0/0.79, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 16:32:53 server3 spamd[2525]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 16:32:53 server3 spamd[2525]: spamd: processing message (unknown) for louise.XXXXXXXXXXXXXX:1387
Feb 17 16:32:53 server3 spamd[2525]: spamd: identified spam (8.9/5.0) for louise.XXXXXXXXXXXXXX:1387 in 0.1 seconds, 54870 bytes.
Feb 17 16:32:53 server3 spamd[2525]: spamd: result: Y 8 - BAYES_20,HTML_MESSAGE,KB_DATE_CONTAINS_TAB,MIME_HTML_ONLY,MISSING_MID,RCVD_IN_BL_SPAMCOP_NET,RCVD_IN_BRBL_LASTEXT,RCVD_IN_RP_RNBL,RDNS_NONE,TAB_IN_FROM scantime=0.1,size=54870,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=36707,mid=(unknown),bayes=0.118640,autolearn=no
Feb 17 16:33:00 server3 postfix/local[21353]: DC72644402B0: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=8, delays=0.33/0.01/0/7.7, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 17:50:43 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=4842, secured, session=<saXmgEsP8AB/AAAB>
Feb 17 17:50:43 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=391 out=3333
Feb 17 17:50:43 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=4844, secured, session=<3hfpgEsP8gB/AAAB>
Feb 17 17:50:43 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1119 out=72372
Feb 17 17:50:49 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=4854, secured, session=<zaZMgUsPAwB/AAAB>

Sorry wrong time: 10:50 - 11:20

here is the log, but nothing at the time of the error.

Feb 17 10:47:23 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=12946, secured, session=<zLf9lkUPJQB/AAAB>
Feb 17 10:47:24 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=156 out=37585
Feb 17 10:47:30 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=12960, secured, session=<+FZhl0UPNwB/AAAB>
Feb 17 10:47:30 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=408 out=3238
Feb 17 10:47:39 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=12986, secured, session=<3ZLyl0UPQAB/AAAB>
Feb 17 10:47:41 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=160 out=1673
Feb 17 10:47:41 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=12996, secured, session=<bUYLmEUPQgB/AAAB>
Feb 17 10:47:41 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=408 out=3238
Feb 17 10:47:42 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=13007, secured, session=<kGYVmEUPQwB/AAAB>
Feb 17 10:47:42 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=408 out=3238
Feb 17 10:52:47 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=14156, secured, session=<QuJIqkUPSAB/AAAB>
Feb 17 10:52:47 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=115 out=1089
Feb 17 10:52:48 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=14164, secured, session=<AU1SqkUPSQB/AAAB>
Feb 17 10:52:48 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=408 out=3238
Feb 17 10:52:48 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=14171, secured, session=<nWVYqkUPSgB/AAAB>
Feb 17 10:52:48 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=408 out=3238
Feb 17 10:59:34 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=15355, secured, session=<yI+EwkUPbAB/AAAB>
Feb 17 10:59:34 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1085 out=43916
Feb 17 10:59:41 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=15411, secured, session=<fbHwwkUPdQB/AAAB>
Feb 17 10:59:41 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1086 out=17664
Feb 17 10:59:51 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=15421, secured, session=<HUyHw0UPfAB/AAAB>
Feb 17 10:59:51 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=581 out=8216
Feb 17 11:00:01 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=15459, secured, session=<a30sxEUPfgB/AAAB>
Feb 17 11:00:02 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1086 out=17664
Feb 17 11:00:08 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=15526, secured, session=<LjKLxEUPgQB/AAAB>
Feb 17 11:00:08 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=432 out=3609
Feb 17 11:00:14 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=15535, secured, session=<kDblxEUPgwB/AAAB>
Feb 17 11:00:14 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1085 out=43916
Feb 17 11:03:33 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=16408, secured, session=<rSjI0EUP5AB/AAAB>
Feb 17 11:03:33 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=16410, secured, session=<sZjI0EUP5QB/AAAB>
Feb 17 11:03:33 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=408 out=3238
Feb 17 11:03:33 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1085 out=43916
Feb 17 11:07:13 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=17267, secured, session=<M2Ho3UUPhgB/AAAB>
Feb 17 11:07:13 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=17269, secured, session=<NZbo3UUPhwB/AAAB>
Feb 17 11:07:13 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=71 out=741
Feb 17 11:07:13 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=156 out=37585
Feb 17 11:07:19 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=17280, secured, session=<+QxE3kUPmgB/AAAB>
Feb 17 11:07:19 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=586 out=14684
Feb 17 11:07:24 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=17302, secured, session=<G5+M3kUPnAB/AAAB>
Feb 17 11:07:24 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=180 out=4580
Feb 17 11:15:07 server3 spamd[5155]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 11:15:07 server3 spamd[5155]: spamd: processing message <54E314A9.2040405@XXXXXXXXXXXXXXogna.it> for louise.XXXXXXXXXXXXXX:1387
Feb 17 11:15:08 server3 spamd[5155]: spamd: clean message (-2.9/5.0) for louise.XXXXXXXXXXXXXX:1387 in 1.2 seconds, 5633 bytes.
Feb 17 11:15:08 server3 spamd[5155]: spamd: result: . -2 - ALL_TRUSTED,BAYES_00,HTML_MESSAGE,URIBL_BLOCKED scantime=1.2,size=5633,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=49113,mid=<54E314A9.2040405@XXXXXXXXXXXXXXogna.it>,bayes=0.000000,autolearn=ham
Feb 17 11:15:08 server3 postfix/local[18314]: BC9B044402AF: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=1.8, delays=0.21/0/0/1.6, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 11:31:44 server3 spamd[5155]: spamd: setuid to louise.XXXXXXXXXXXXXX succeeded
Feb 17 11:31:44 server3 spamd[5155]: spamd: processing message <DECE3CB1-A902-476B-B9A6-AAB9834DC4AE@XXXXXXXXXXXXXXogna.it> for louise.XXXXXXXXXXXXXX:1387
Feb 17 11:31:44 server3 spamd[5155]: spamd: clean message (-1.2/5.0) for louise.XXXXXXXXXXXXXX:1387 in 0.3 seconds, 122160 bytes.
Feb 17 11:31:44 server3 spamd[5155]: spamd: result: . -1 - ALL_TRUSTED,BAYES_00,HTML_IMAGE_ONLY_08,HTML_MESSAGE,URIBL_BLOCKED scantime=0.3,size=122160,user=louise.XXXXXXXXXXXXXX,uid=1387,required_score=5.0,rhost=localhost.localdomain,raddr=127.0.0.1,rport=49712,mid=<DECE3CB1-A902-476B-B9A6-AAB9834DC4AE@XXXXXXXXXXXXXXogna.it>,bayes=0.000000,autolearn=no
Feb 17 11:31:44 server3 postfix/local[21290]: 6A03A44402AF: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=2, delays=1.2/0/0/0.76, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 11:57:37 server3 postfix/qmgr[30197]: 6E71244402AF: from=<louise.dixon@alice.it>, size=697391, nrcpt=1 (queue active)
Feb 17 11:57:37 server3 postfix/local[23541]: 6E71244402AF: to=<louise.XXXXXXXXXXXXXX@server3.localdomain>, orig_to=<louise@XXXXXXXXXXXXXXogna.it>, relay=local, delay=1.4, delays=1/0/0/0.42, dsn=2.0.0, status=sent (delivered to command: /usr/bin/procmail-wrapper -o -a $DOMAIN -d $LOGNAME)
Feb 17 12:03:39 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=28575, secured, session=<Shm7p0YPiQB/AAAB>
Feb 17 12:03:39 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=408 out=3316
Feb 17 12:03:39 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=28577, secured, session=<+rW9p0YPigB/AAAB>
Feb 17 12:03:40 server3 dovecot: imap(louise.XXXXXXXXXXXXXX): Connection closed in=1169 out=869498
Feb 17 12:03:48 server3 dovecot: imap-login: Login: user=<louise.XXXXXXXXXXXXXX>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=28611, secured, session=<r9E/qEYPkAB/AAAB>

I don't see anything obviously wrong there - I'd expect to see a message from dovecot reporting that the mailbox cannot be opened for some reason.

If you monitor /var/log/mail.log, is there any message that gets logged at the exact time that you attempt to log into Usermin?

Also, are you able to use another webmail client such as RoundCube? Or does RoundCube throw an error too?

You can install RoundCube using the Install Scripts option on the left side of Virtualmin.

I'd like to solve the problem not circumvent it.

As you can see from my post above there are no error logs at the time of the error.

For troubleshooting purposes, it would be helpful to know if you're able to login via another app such as RoundCube.

I made an APT-GET Update/Upgrade.

It's the same?

That's a development package, so to install it, you would need to go to the link Jamie mentioned above, download the package named "usermin_1.651_all.deb", and then you can install that using either "dpkg", or using Webmin.

Any news here?

If I install the dev package then I can upgrade again it normally through APT?

Whenever a new Usermin version is put into the Virtualmin repository, you'll be able to upgrade to it with no problem.

Thanks for that screenshot -- is that what happens after upgrading to the dev version of Usermin?

I didn't upgrade yet.

It's a production env so I don't want to install dev packages.

Unfortunately, no one else has reported an issue like you're describing, so we're unlikely to be able to correct the issue you're having without seeing the output that Jamie enabled on that development Usermin version.

However, that development Usermin version will eventually be made into a new Usermin release and moved into the Virtualmin repository, probably within a few weeks, and you could always try it then.

Also, as a troubleshooting step, we'd still be interested to know if that user is able to log in via other means, such as RoundCube.

That's something you could try in the meantime, while waiting for the new Usermin version to hit the Virtualmin repository.

The 1.651 development version of Usermin is quite safe to install - the only change between it and 1.650 is a fix to improve the debugging of this problem.

My concern is...if I install this package then I can continue to use apt-get update to update usermin or I must uninstall and then reinstall?

Yes, you will be able to continue to upgrade once the next major version comes out.

No, this change is in the usermin package.