Bug smtp-filter & Syslog.pm

[FGRO]

Hi,

ich bekomme folgende Fehlermeldungen in den Logs:

pmg-smtp-filter[7199]: WARNING: Use of uninitialized value in concatenation (.) or string at /usr/bin/pmg-smtp-filter line 646.

$msginfo->{fqdn} = "$msginfo->{hostname}.$msginfo->{domain}";

und

pmg-smtp-filter[7657]: WARNING: Use of uninitialized value $args[2] in sprintf at /usr/lib/x86_64-linux-gnu/perl/5.24/Sys/Syslog.pm line 423.

$message = @args ? sprintf($mask, @args) : $mask;

wie kann man das lösen?

proxmox-mailgateway: 5.2-1 (API: 5.2-3/26df5d99, running kernel: 4.15.18-15-pve)
pmg-api: 5.2-3
pmg-gui: 1.0-45
pve-kernel-4.15: 5.4-3
pve-kernel-4.15.18-15-pve: 4.15.18-40
pve-kernel-4.15.18-7-pve: 4.15.18-27
libarchive-perl: 3.2.1-1
libpve-apiclient-perl: 2.0-5
libpve-common-perl: 5.0-52
libpve-http-server-perl: 2.0-12
libxdgmime-perl: 0.01-3
lvm2: 2.02.168-2
pmg-docs: 5.2-3
proxmox-spamassassin: 3.4.2-2
proxmox-widget-toolkit: 1.0-28
pve-firmware: 2.0-5
pve-xtermjs: 3.10.1-2
zfsutils-linux: 0.7.13-pve1~bpo1

 

[fluxX04]

Hi,

zur Fehlermeldung #2 gibt es schon einen bug report:
https://bugzilla.proxmox.com/show_bug.cgi?id=2232

Dazu wurde schon ein Patch veröffentlicht und sollte in der nächsten Version behoben sein.

Zur Fehlermeldung #1 wären ein paar mehr Zeilen vom Log von Vorteil, um das Problem einzugrenzen zu können (können auch anonymisiert sein).

Greetz

 

[FGRO]

ok, weiss zwar nicht welche log da ich nehmen soll, hab mal die mail.log genommen

Jun 7 14:53:56 pmg postfix/postscreen[8929]: CONNECT from [98.137.65.204]:42784 to [192.168.11.4]:25
Jun 7 14:53:56 pmg pmg-smtp-filter[913]: starting database maintainance
Jun 7 14:53:56 pmg pmg-smtp-filter[913]: end database maintainance (58 ms)
Jun 7 14:54:02 pmg pmgpolicy[907]: starting policy database maintainance (greylist, rbl)
Jun 7 14:54:02 pmg pmgpolicy[907]: end policy database maintainance (7 ms, 2 ms)
Jun 7 14:54:02 pmg postfix/postscreen[8929]: PASS NEW [98.137.65.204]:42784
Jun 7 14:54:02 pmg postfix/smtpd[8943]: connect from sonic311-23.consmr.mail.gq1.yahoo.com[98.137.65.204]
Jun 7 14:54:04 pmg pmgpolicy[8370]: SPF says pass
Jun 7 14:54:04 pmg postfix/smtpd[8943]: 11469406E4: client=sonic311-23.consmr.mail.gq1.yahoo.com[98.137.65.204]
Jun 7 14:54:04 pmg postfix/cleanup[8946]: 11469406E4: message-id=<2028731271.177481.1559912032710@mail.yahoo.com>
Jun 7 14:54:04 pmg postfix/qmgr[7554]: 11469406E4: from=<*****@yahoo.com>, size=3722, nrcpt=1 (queue active)
Jun 7 14:54:04 pmg pmg-smtp-filter[8727]: 2019/06/07-14:54:04 CONNECT TCP Peer: "[127.0.0.1]:39002" Local: "[127.0.0.1]:10024"
Jun 7 14:54:04 pmg pmg-smtp-filter[8727]: WARNING: Use of uninitialized value in concatenation (.) or string at /usr/bin/pmg-smtp-filter line 646.
Jun 7 14:54:04 pmg pmg-smtp-filter[8727]: 406E65CFA5E6C6EE2D: new mail message-id=<2028731271.177481.1559912032710@mail.yahoo.com>
Jun 7 14:54:04 pmg postfix/smtpd[8943]: disconnect from sonic311-23.consmr.mail.gq1.yahoo.com[98.137.65.204] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7
Jun 7 14:54:05 pmg pmg-smtp-filter[8727]: 406E65CFA5E6C6EE2D: SA score=0/5 time=0.720 bayes=0 autolearn=no autolearn_force=no hits=AWL(0.236),BAYES_00(-1.9),DKIM_SIGNED(0.1),DKIM_VALID(-0.1),DKIM_VALID_AU(-0.1),DKIM_VALID_EF(-0.1),FREEMAIL_FROM(0.001),HTML_MESSAGE(0.001),KAM_NUMSUBJECT(0.5),MPART_ALT_DIFF(0.79),SPF_HELO_NONE(0.001),SPF_PASS(-0.001)
Jun 7 14:54:05 pmg postfix/smtpd[8951]: connect from localhost.localdomain[127.0.0.1]
Jun 7 14:54:05 pmg postfix/smtpd[8951]: 3745B40978: client=localhost.localdomain[127.0.0.1], orig_client=sonic311-23.consmr.mail.gq1.yahoo.com[98.137.65.204]
Jun 7 14:54:05 pmg postfix/cleanup[8946]: 3745B40978: message-id=<2028731271.177481.1559912032710@mail.yahoo.com>
Jun 7 14:54:05 pmg postfix/qmgr[7554]: 3745B40978: from=<*****@yahoo.com>, size=4943, nrcpt=1 (queue active)
Jun 7 14:54:05 pmg pmg-smtp-filter[8727]: WARNING: Use of uninitialized value $args[2] in sprintf at /usr/lib/x86_64-linux-gnu/perl/5.24/Sys/Syslog.pm line 423.
Jun 7 14:54:05 pmg pmg-smtp-filter[8727]: 406E65CFA5E6C6EE2D: accept mail to <****@*****> (rule: , 3745B40978)
Jun 7 14:54:05 pmg postfix/smtpd[8951]: disconnect from localhost.localdomain[127.0.0.1] ehlo=1 xforward=1 mail=1 rcpt=1 data=1 commands=5
Jun 7 14:54:05 pmg pmg-smtp-filter[8727]: 406E65CFA5E6C6EE2D: processing time: 0.791 seconds (0.72, 0.023, 0)
Jun 7 14:54:05 pmg postfix/lmtp[8947]: 11469406E4: to=<*****@*****>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.4, delays=0.54/0.01/0.04/0.8, dsn=2.5.0, status=sent (250 2.5.0 OK (406E65CFA5E6C6EE2D))
Jun 7 14:54:05 pmg postfix/qmgr[7554]: 11469406E4: removed

ist der Patch das hier? Wenn ja, dann funktioniert das bei mir nicht. Habe die Zeile entsprechend abgeändert und pmgproxy gestartet. Nach wie vor die Meldung.

 bin/pmg-smtp-filter | 2 +-
 1 file changed, 1 insertion(+), 1 deletion(-)

diff --git a/bin/pmg-smtp-filter b/bin/pmg-smtp-filter
index 31fddd5..d198239 100755
--- a/bin/pmg-smtp-filter
+++ b/bin/pmg-smtp-filter
@@ -353,7 +353,7 @@ sub apply_rules {
     if ($unmatched) {
     my $accept = PMG::RuleDB::Accept->new ();
     $accept->execute ($queue, $self->{ruledb}, $mod_group, $unmatched,
-              $msginfo, undef, undef, undef);
+              $msginfo, { RULE => 'default-accept' }, undef);
     }
 
     return $matching_rules;

 

[fluxX04]

Hi,

das ist einer von den Patches, die Änderungen sollten auch noch übernommen werden (in 7 Dateien):
https://git.proxmox.com/?p=pmg-api.git;a=commit;h=6c65ab40fb93878f4497daa543be6768dcc63719

Habs zwar noch nicht selbst getestet, da trotz dem Fehler alles noch funktioniert.

Greetz

 

[mira]

Will take a look, thank you for reporting this. If possible, could you open a new bug for this? (https://bugzilla.proxmox.com/enter_bug.cgi) If possible with the steps to reproduce it.

 

[mira]

Ahh, und wieder Englisch im deutschen Forum geschrieben ...
Es gibt bereits einen vorläufigen Patch auf der pmg-devel Liste. Nochmals vielen Dank für die Meldung.