This conversation is getting a bit long and unwieldy, so I'll just recap
without quoting:
Delivery from quarantine via dspam command tries to deliver to a rcpt-to
with a corrupt local-part.
One more piece to the puzzle, the dspam.debug log. Turning on a lot of
logging, and releasing a message for the user firmapost gives me the log
below (at the bottom). Worth noting is that dspam is set up to set the
user from the signature, and that I've hacked the mysql table so that
local recipient "firmapost" and "hakon" are one and the same. Also worth
noting is that the dspam debug log seems to show nothing bad, but I
still get an entry in mail.log saying :
---/var/log/mail.log:---
Dec 16 12:29:54 garbo dspam.cgi: Piping into |/usr/bin/dspam
--deliver=innocent --class=innocent --source=error --user
firmap...@alstadheim.priv.no -d %u
Dec 16 12:29:54 garbo postfix/smtpd[6855]: connect from localhost[127.0.0.1]
Dec 16 12:29:54 garbo postfix/smtpd[6855]: NOQUEUE: reject: RCPT from
localhost[127.0.0.1]: 550 5.1.1 <` n...@alstadheim.priv.no>: Recipient
address rejected: User unknown in local recipient table; from=<>
to=<`???n...@alstadheim.priv.no> proto=SMTP helo=<localhost>
Dec 16 12:29:54 garbo dspam[6854]: Got error 550 in response to RCPT TO:
550 5.1.1 <` n...@alstadheim.priv.no>: Recipient address rejected: User
unknown in local recipient table
-----/var/log/dspam/dspam.debug:-----
6854: [12/16/2011 12:29:54] DSPAM Instance Startup
6854: [12/16/2011 12:29:54] input args: /usr/bin/dspam
--deliver=innocent --class=innocent --source=error --user
firmap...@alstadheim.priv.no -d %u
6854: [12/16/2011 12:29:54] pass-thru args: /usr/bin/procmail -d %u
6854: [12/16/2011 12:29:54] processing user firmap...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] uid = 109, euid = 109, gid = 113, egid = 113
6854: [12/16/2011 12:29:54] loading preferences for user
firmap...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam: successful returning
struct for name: firmap...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] Loading preferences for uid 1003
6854: [12/16/2011 12:29:54] Loading preferences for uid 0
6854: [12/16/2011 12:29:54] default preferences empty. reverting to
dspam.conf preferences.
6854: [12/16/2011 12:29:54] Loading preferences from dspam.conf
6854: [12/16/2011 12:29:54] using
/var/spool/dspam/opt-in/alstadheim.priv.no/firmapost.dspam as path
6854: [12/16/2011 12:29:54] using
/var/spool/dspam/opt-out/alstadheim.priv.no/firmapost.nodspam as path
6854: [12/16/2011 12:29:54] sedation level set to: 5
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam: successful returning
struct for name: firmap...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] found signature '1003,4ee8d87a230351905817711'
6854: [12/16/2011 12:29:54] found signature '1003,4ee8d87a230351905817711'
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam returning cached name
firmap...@alstadheim.priv.no.
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam returning cached name
firmap...@alstadheim.priv.no.
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam: successful returning
struct for name: ha...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] loading preferences for user
ha...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam: successful returning
struct for name: ha...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] Loading preferences for uid 1003
6854: [12/16/2011 12:29:54] Loading preferences for uid 0
6854: [12/16/2011 12:29:54] default preferences empty. reverting to
dspam.conf preferences.
6854: [12/16/2011 12:29:54] Loading preferences from dspam.conf
6854: [12/16/2011 12:29:54] processing signature. length: 31224
6854: [12/16/2011 12:29:54] Reversing 2602 tokens
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam returning cached name
ha...@alstadheim.priv.no.
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam returning cached name
ha...@alstadheim.priv.no.
6854: [12/16/2011 12:29:54] Control: [10 10] [10 11] Delta: [0 1]
6854: [12/16/2011 12:29:54] Message classification/result: INNOCENT
6854: [12/16/2011 12:29:54] reclassifying iteration 1 result: 0
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam: successful returning
struct for name: ha...@alstadheim.priv.no
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam returning cached name
ha...@alstadheim.priv.no.
6854: [12/16/2011 12:29:54] [graham] [0.010005] E: 15748588381744953279
(1frq, 185s, 148057i)
6854: [12/16/2011 12:29:54] [burton] [0.010005] E: 15748588381744953279
(1frq, 185s, 148057i)
6854: [12/16/2011 12:29:54] [graham] [0.010193] E: 9263404936849617100
(1frq, 131s, 102883i)
6854: [12/16/2011 12:29:54] [burton] [0.010193] E: 9263404936849617100
(1frq, 131s, 102883i)
6854: [12/16/2011 12:29:54] [graham] [0.016993] E: 7966368225648734409
(1frq, 30s, 14036i)
6854: [12/16/2011 12:29:54] [burton] [0.016993] E: 7966368225648734409
(1frq, 30s, 14036i)
6854: [12/16/2011 12:29:54] [graham] [0.981008] E: 15746851927764203258
(1frq, 281s, 44i)
6854: [12/16/2011 12:29:54] [burton] [0.981008] E: 15746851927764203258
(1frq, 281s, 44i)
6854: [12/16/2011 12:29:54] [graham] [0.020701] E: 14355574306758424511
(1frq, 121s, 46298i)
6854: [12/16/2011 12:29:54] [burton] [0.020701] E: 14355574306758424511
(1frq, 121s, 46298i)
6854: [12/16/2011 12:29:54] [graham] [0.023897] E: 3425356359147417806
(1frq, 44s, 14536i)
6854: [12/16/2011 12:29:54] [burton] [0.023897] E: 3425356359147417806
(1frq, 44s, 14536i)
6854: [12/16/2011 12:29:54] [graham] [0.024829] E: 15748589637089327039
(1frq, 217s, 68932i)
6854: [12/16/2011 12:29:54] [burton] [0.024829] E: 15748589637089327039
(1frq, 217s, 68932i)
6854: [12/16/2011 12:29:54] [graham] [0.974981] E: 18256419115993565442
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [burton] [0.974981] E: 18256419115993565442
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [burton] [0.974981] E: 18256419115993565442
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [graham] [0.974981] E: 4782332562467524645
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [burton] [0.974981] E: 4782332562467524645
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [burton] [0.974981] E: 4782332562467524645
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [graham] [0.974981] E: 12676106563463409350
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [burton] [0.974981] E: 12676106563463409350
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [burton] [0.974981] E: 12676106563463409350
(2frq, 53s, 11i)
6854: [12/16/2011 12:29:54] [graham] [0.025419] E: 7966369481076209593
(1frq, 220s, 68222i)
6854: [12/16/2011 12:29:54] [burton] [0.025419] E: 7966369481076209593
(1frq, 220s, 68222i)
6854: [12/16/2011 12:29:54] [graham] [0.029158] E: 14355568740397709247
(1frq, 1221s, 328818i)
6854: [12/16/2011 12:29:54] [burton] [0.029158] E: 14355568740397709247
(1frq, 1221s, 328818i)
6854: [12/16/2011 12:29:54] [graham] [0.029428] E: 8469387941885472973
(1frq, 41s, 10937i)
6854: [12/16/2011 12:29:54] [burton] [0.029428] E: 8469387941885472973
(1frq, 41s, 10937i)
6854: [12/16/2011 12:29:54] [graham] [0.030111] E: 8470771402308024253
(1frq, 423s, 110201i)
6854: [12/16/2011 12:29:54] [burton] [0.030111] E: 8470771402308024253
(1frq, 423s, 110201i)
6854: [12/16/2011 12:29:54] [graham] [0.030215] E: 15748594722442082239
(1frq, 432s, 112147i)
6854: [12/16/2011 12:29:54] [burton] [0.030215] E: 15748594722442082239
(1frq, 432s, 112147i)
6854: [12/16/2011 12:29:54] [burton] [0.964235] E: 4770807341199130624
(14frq, 40s, 12i)
6854: [12/16/2011 12:29:54] [burton] [0.964235] E: 4770807341199130624
(14frq, 40s, 12i)
6854: [12/16/2011 12:29:54] [burton] [0.036303] E: 7966368225648736185
(1frq, 371s, 79656i)
6854: [12/16/2011 12:29:54] [burton] [0.038443] E: 9671831450207352760
(1frq, 152s, 30750i)
6854: [12/16/2011 12:29:54] [burton] [0.961468] E: 8470765355077169544
(1frq, 145s, 47i)
6854: [12/16/2011 12:29:54] [burton] [0.038699] E: 8304161374642075577
(1frq, 91s, 18283i)
6854: [12/16/2011 12:29:54] [burton] [0.039636] E: 15748594703936812991
(1frq, 411s, 80543i)
6854: [12/16/2011 12:29:54] [burton] [0.959232] E: 4531472238005545143
(1frq, 32s, 11i)
6854: [12/16/2011 12:29:54] [burton] [0.959232] E: 1503626691403100726
(1frq, 32s, 11i)
6854: [12/16/2011 12:29:54] Graham-Bayesian Probability: 0.000000
Samples: 15
6854: [12/16/2011 12:29:54] Burton-Bayesian Probability: 0.000002
Samples: 27
6854: [12/16/2011 12:29:54] no factors specified; using default
6854: [12/16/2011 12:29:54] Result Confidence: 0.54
6854: [12/16/2011 12:29:54] total processing time: 0.05359s
6854: [12/16/2011 12:29:54] libdspam returned probability of 0.000000
6854: [12/16/2011 12:29:54] message result: NOT SPAM
6854: [12/16/2011 12:29:54] _mysql_drv_getpwnam returning cached name
ha...@alstadheim.priv.no.
6854: [12/16/2011 12:29:54] delivering message
6854: [12/16/2011 12:29:54] Establishing connection to 127.0.0.1:10025
6854: [12/16/2011 12:29:54] Connection established
6854: [12/16/2011 12:29:54] DSPAM Instance Shutdown. Exit Code: 0
------------------------------------------------------------------------------
Learn Windows Azure Live! Tuesday, Dec 13, 2011
Microsoft is holding a special Learn Windows Azure training event for
developers. It will provide a great way to learn Windows Azure and what it
provides. You can attend the event by watching it streamed LIVE online.
Learn more at http://p.sf.net/sfu/ms-windowsazure
_______________________________________________
Dspam-user mailing list
Dspam-user@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/dspam-user
