> On Jul 17, 2017, at 10:28 AM, Tom Hendrikx <t...@whyscream.net> wrote: > > On 17-07-17 16:00, Robert Kudyba wrote: >> >>> On Jul 17, 2017, at 9:39 AM, Antony Stone >>> <antony.st...@spamassassin.open.source.it >>> <mailto:antony.st...@spamassassin.open.source.it>> wrote: >>> >>> On Monday 17 July 2017 at 14:25:17, Robert Kudyba wrote: >>> >>>>> On Jul 14, 2017, at 4:00 AM, Matus UHLAR - fantomas >>>>> <uh...@fantomas.sk <mailto:uh...@fantomas.sk>> >>> wrote: >>>>>> Robert Kudyba <rkud...@fordham.edu <mailto:rkud...@fordham.edu>> wrote: >>>>>>> Over the past few days sending mail via SquirrelMail has become >>>>>>> glacial. The load on the server is under 1. I've restarted the SA, >>>>>>> sendmail and dovecot processes several times. Here are some logs I can >>>>>>> provide any settings if desired. >>>>> >>>>> tried to run a message through "spamassassin -D" ? >>>>> that should give you debug/timing info. >>>> >>>> OK here is the pastebin of spamassassin -D < gtube.txt: >>>> https://urldefense.proofpoint.com/v2/url?u=https-3A__pastebin.com_iZtm2hhy&d=DwIFAw&c=aqMfXOEvEJQh2iQMCb7Wy8l0sPnURkcqADc2guUW8IM&r=X0jL9y0sL4r4iU_qVtR3lLNo4tOL1ry_m7-psV3GejY&m=wV3-oZ_3m8NtSuw_6UTtdU1WptL8Pl1vNOok-EXrcZo&s=802-414zeT59KVCIFVa_uxfSq0XezT7e4OVZibWbIwc&e= >>>> >>> >>> >>> Jul 16 09:01:42.796 [29903] dbg: dns: entering helper-app run mode >>> Jul 16 09:01:47.806 [29903] dbg: dns: leaving helper-app run mode >>> Jul 16 09:01:47.806 [29903] dbg: razor2: razor2 check timed out after 5 >>> seconds >> >> OK so I ran: /var/spool/amavisd/.razor >> >> ls -l /var/spool/amavisd/.razor >> total 100 >> -rw-r----- 1 amavis amavis 72420 Dec 22 2014 razor-agent.log >> -rw-r----- 1 amavis amavis 998 Jul 17 09:49 server.c301.cloudmark.com.conf >> -rw-r----- 1 amavis amavis 998 Jul 17 09:46 server.c302.cloudmark.com.conf >> -rw-r----- 1 amavis amavis 995 Dec 20 2014 server.c303.cloudmark.com.conf >> -rw-r----- 1 amavis amavis 57 Jul 17 09:49 servers.catalogue.lst >> -rw-r----- 1 amavis amavis 30 May 23 2013 servers.discovery.lst >> -rw-r----- 1 amavis amavis 76 Jul 17 09:49 servers.nomination.lst >> >> New pastebin: >> https://urldefense.proofpoint.com/v2/url?u=https-3A__pastebin.com_9RWEYuSt&d=DwIC-g&c=aqMfXOEvEJQh2iQMCb7Wy8l0sPnURkcqADc2guUW8IM&r=X0jL9y0sL4r4iU_qVtR3lLNo4tOL1ry_m7-psV3GejY&m=QspHQBi1X_n1ZQylsERsyborPsWRSy3cHQlXJ8FUf7c&s=DZ63JGDSr9nTI6HaajZtLRvUf0ao4tBA4dKtq_77Xlg&e= >> >> >> Still taking 15 seconds. >> >> Jul 17 09:55:28 storm spamd[28111]: spamd: clean message (-103.4/5.0) >> for spamd:1001 in 15.0 seconds, 1843 bytes. >> Jul 17 09:55:28 storm spamd[28111]: spamd: result: . -103 - >> ALL_TRUSTED,BAYES_00,FROM_IS_TO,USER_IN_WHITELIST >> scantime=15.0,size=1843,user=spamd,uid=1001,required_score=5.0,rhost=localhost,raddr=::1,rport=53074,mid=<32889a456ed9c9911ff0034513796858.squirrel@ourdomain>,bayes=0.000000,autolearn=no >> autolearn_force=no >> Jul 17 09:55:28 storm spamd[28041]: prefork: child states: II >> > > The error is still the same. Do you even have access to those cloudmark > razor servers? Does razor work outside of spamassassin/amavisd?
Is that supposed to be a paid service? This test seems successful. razor-check -d < /usr/share/doc/spamassassin/sample-spam.txt Razor-Log: Computed razorhome from env: /root/.razor Razor-Log: Found razorhome: /root/.razor Razor-Log: read_file: 15 items read from /root/.razor/razor-agent.conf Jul 17 10:38:14.467263 check[20932]: [ 2] [bootup] Logging initiated LogDebugLevel=9 to stdout Jul 17 10:38:14.467525 check[20932]: [ 5] computed razorhome=/root/.razor, conf=/root/.razor/razor-agent.conf, ident=/root/.razor/identity Jul 17 10:38:14.467584 check[20932]: [ 2] Razor-Agents v2.84 starting razor-check -d Jul 17 10:38:14.467707 check[20932]: [ 8] reading straight RFC822 mail from <stdin> Jul 17 10:38:14.467809 check[20932]: [ 6] read 1 mail Jul 17 10:38:14.467905 check[20932]: [ 8] Client supported_engines: 4 8 Jul 17 10:38:14.468110 check[20932]: [ 8] prep_mail done: mail 1 headers=293, mime0=616 Jul 17 10:38:14.468241 check[20932]: [ 6] skipping whitelist file (empty?): /root/.razor/razor-whitelist Jul 17 10:38:14.468383 check[20932]: [ 5] read_file: 1 items read from /root/.razor/servers.discovery.lst Jul 17 10:38:14.468528 check[20932]: [ 5] read_file: 0 items read from /root/.razor/servers.nomination.lst Jul 17 10:38:14.468671 check[20932]: [ 5] read_file: 3 items read from /root/.razor/servers.catalogue.lst Jul 17 10:38:14.468862 check[20932]: [ 9] Assigning defaults to c303.cloudmark.com Jul 17 10:38:14.468957 check[20932]: [ 9] Assigning defaults to c301.cloudmark.com Jul 17 10:38:14.469043 check[20932]: [ 9] Assigning defaults to c302.cloudmark.com Jul 17 10:38:14.469567 check[20932]: [ 5] read_file: 32 items read from /root/.razor/server.c303.cloudmark.com.conf Jul 17 10:38:14.469944 check[20932]: [ 5] read_file: 32 items read from /root/.razor/server.c303.cloudmark.com.conf Jul 17 10:38:14.470333 check[20932]: [ 5] read_file: 32 items read from /root/.razor/server.c302.cloudmark.com.conf Jul 17 10:38:14.470710 check[20932]: [ 5] read_file: 32 items read from /root/.razor/server.c302.cloudmark.com.conf Jul 17 10:38:14.471098 check[20932]: [ 5] read_file: 32 items read from /root/.razor/server.c301.cloudmark.com.conf Jul 17 10:38:14.471475 check[20932]: [ 5] read_file: 32 items read from /root/.razor/server.c301.cloudmark.com.conf Jul 17 10:38:14.471586 check[20932]: [ 5] server discovery overdue by 89981385 seconds Jul 17 10:38:14.471652 check[20932]: [ 8] already have 1 discovery servers Jul 17 10:38:14.471713 check[20932]: [ 8] Checking with Razor Discovery Server discovery.razor.cloudmark.com Jul 17 10:38:14.471780 check[20932]: [ 6] No port specified, using 2703 Jul 17 10:38:14.471827 check[20932]: [ 5] Connecting to discovery.razor.cloudmark.com ... Jul 17 10:38:24.488675 check[20932]: [ 8] Connection established Jul 17 10:38:24.488824 check[20932]: [ 4] discovery.razor.cloudmark.com >> 23 server greeting: sn=D&srl=668&a=l&a=cg Jul 17 10:38:24.488971 check[20932]: [ 4] discovery.razor.cloudmark.com << 12 Jul 17 10:38:24.489043 check[20932]: [ 6] a=g&pm=csl Jul 17 10:38:24.542170 check[20932]: [ 4] discovery.razor.cloudmark.com >> 71 Jul 17 10:38:24.542254 check[20932]: [ 6] response to sent.1 -csl=? c302.cloudmark.com c301.cloudmark.com c303.cloudmark.com . Jul 17 10:38:24.542394 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with csl=c302.cloudmark.com Jul 17 10:38:24.542496 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with csl=c301.cloudmark.com Jul 17 10:38:24.542556 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with csl=c303.cloudmark.com Jul 17 10:38:24.542661 check[20932]: [ 4] discovery.razor.cloudmark.com << 12 Jul 17 10:38:24.542724 check[20932]: [ 6] a=g&pm=nsl Jul 17 10:38:24.546362 check[20932]: [ 4] discovery.razor.cloudmark.com >> 91 Jul 17 10:38:24.546473 check[20932]: [ 6] response to sent.2 -nsl=? n001.cloudmark.com n003.cloudmark.com n004.cloudmark.com n002.cloudmark.com . Jul 17 10:38:24.546614 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n001.cloudmark.com Jul 17 10:38:24.546684 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n003.cloudmark.com Jul 17 10:38:24.546740 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n004.cloudmark.com Jul 17 10:38:24.546796 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n002.cloudmark.com Jul 17 10:38:24.546918 check[20932]: [ 5] disconnecting from server discovery.razor.cloudmark.com Jul 17 10:38:24.547020 check[20932]: [ 4] discovery.razor.cloudmark.com << 5 Jul 17 10:38:24.547081 check[20932]: [ 6] a=q Jul 17 10:38:24.554881 check[20932]: [ 5] writeservers skipping .lst file: /root/.razor/servers.catalogue.lst Jul 17 10:38:24.557938 check[20932]: [ 5] writeservers skipping .lst file: /root/.razor/servers.nomination.lst Jul 17 10:38:24.558095 check[20932]: [ 6] c303.cloudmark.com is a Catalogue Server srl 34517; computed min_cf=21, Server se: 2380 Jul 17 10:38:24.558227 check[20932]: [ 8] Computed supported_engines: 8 Jul 17 10:38:24.558344 check[20932]: [ 8] Using next closest server c303.cloudmark.com:2703, cached info srl 34517 Jul 17 10:38:24.558475 check[20932]: [ 8] mail 1 has no subject Jul 17 10:38:24.558735 check[20932]: [ 6] preproc: mail 1.0 went from 616 bytes to 503 Jul 17 10:38:24.558827 check[20932]: [ 6] computing sigs for mail 1.0, len 503 Jul 17 10:38:24.559334 check[20932]: [ 6] Engine (8) didn't produce a signature for mail 1.0 Jul 17 10:38:24.559502 check[20932]: [ 5] Connecting to c303.cloudmark.com ... Jul 17 10:38:29.572663 check[20932]: [ 8] Connection established Jul 17 10:38:29.572819 check[20932]: [ 4] c303.cloudmark.com >> 25 server greeting: sn=C&srl=35001&a=l&a=cg Jul 17 10:38:29.572994 check[20932]: [ 4] c303.cloudmark.com << 25 Jul 17 10:38:29.573051 check[20932]: [ 6] cn=razor-agents&cv=2.84 Jul 17 10:38:29.573175 check[20932]: [ 4] c303.cloudmark.com << 14 Jul 17 10:38:29.573230 check[20932]: [ 6] a=g&pm=state Jul 17 10:38:29.672515 check[20932]: [ 4] c303.cloudmark.com >> 279 Jul 17 10:38:29.672613 check[20932]: [ 6] response to sent.5 -sn=C sv=4.007 zone=razor2.cloudmark.com ac=21 srl=35001 lm=4 bql=50 bqs=329 dre=8 srf=FF sa=2FB se=2380 to=15 nmp=2 ep8=20 ep10=20 ep19=5 ep20=5 ep21=5 ep22=5 crt=90 immdi=1 imsio=0 lsp=3 lsp10=0 mps=300 mhs=15 mphs=4 cp=7 1 16 6 5 4 3 2 0 cs=1 . Jul 17 10:38:29.673088 check[20932]: [ 5] Updated to new server state srl 35001 for server c303.cloudmark.com Jul 17 10:38:29.673169 check[20932]: [ 6] c303.cloudmark.com is a Catalogue Server srl 35001; computed min_cf=21, Server se: 2380 Jul 17 10:38:29.673268 check[20932]: [ 8] Computed supported_engines: 8 Jul 17 10:38:29.699183 check[20932]: [ 5] wrote 32 HASH items to file: /root/.razor/server.c303.cloudmark.com.conf Jul 17 10:38:29.699282 check[20932]: [ 5] srl was updated, forcing discovery ... Jul 17 10:38:29.699370 check[20932]: [ 5] server discovery overdue by 89977211 seconds Jul 17 10:38:29.699455 check[20932]: [ 8] already have 1 discovery servers Jul 17 10:38:29.699534 check[20932]: [ 8] Checking with Razor Discovery Server discovery.razor.cloudmark.com Jul 17 10:38:29.699616 check[20932]: [ 6] losing old server connection, c303.cloudmark.com, for new server, discovery.razor.cloudmark.com Jul 17 10:38:29.699666 check[20932]: [ 5] disconnecting from server c303.cloudmark.com Jul 17 10:38:29.699762 check[20932]: [ 4] c303.cloudmark.com << 5 Jul 17 10:38:29.699811 check[20932]: [ 6] a=q Jul 17 10:38:29.699908 check[20932]: [ 5] Connecting to discovery.razor.cloudmark.com ... Jul 17 10:38:34.708679 check[20932]: [ 8] Connection established Jul 17 10:38:34.708871 check[20932]: [ 4] discovery.razor.cloudmark.com >> 23 server greeting: sn=D&srl=668&a=l&a=cg Jul 17 10:38:34.708993 check[20932]: [ 4] discovery.razor.cloudmark.com << 12 Jul 17 10:38:34.709047 check[20932]: [ 6] a=g&pm=csl Jul 17 10:38:34.784691 check[20932]: [ 4] discovery.razor.cloudmark.com >> 71 Jul 17 10:38:34.784821 check[20932]: [ 6] response to sent.7 -csl=? c301.cloudmark.com c302.cloudmark.com c303.cloudmark.com . Jul 17 10:38:34.784956 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with csl=c301.cloudmark.com Jul 17 10:38:34.785013 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with csl=c302.cloudmark.com Jul 17 10:38:34.785060 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with csl=c303.cloudmark.com Jul 17 10:38:34.785160 check[20932]: [ 4] discovery.razor.cloudmark.com << 12 Jul 17 10:38:34.785210 check[20932]: [ 6] a=g&pm=nsl Jul 17 10:38:34.788848 check[20932]: [ 4] discovery.razor.cloudmark.com >> 91 Jul 17 10:38:34.788976 check[20932]: [ 6] response to sent.8 -nsl=? n004.cloudmark.com n002.cloudmark.com n003.cloudmark.com n001.cloudmark.com . Jul 17 10:38:34.789115 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n004.cloudmark.com Jul 17 10:38:34.789171 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n002.cloudmark.com Jul 17 10:38:34.789242 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n003.cloudmark.com Jul 17 10:38:34.789290 check[20932]: [ 8] Discovery Server discovery.razor.cloudmark.com replying with nsl=n001.cloudmark.com Jul 17 10:38:34.789400 check[20932]: [ 5] disconnecting from server discovery.razor.cloudmark.com Jul 17 10:38:34.789570 check[20932]: [ 4] discovery.razor.cloudmark.com << 5 Jul 17 10:38:34.789623 check[20932]: [ 6] a=q Jul 17 10:38:34.789789 check[20932]: [ 5] writeservers skipping .lst file: /root/.razor/servers.catalogue.lst Jul 17 10:38:34.789865 check[20932]: [ 5] writeservers skipping .lst file: /root/.razor/servers.nomination.lst Jul 17 10:38:34.790058 check[20932]: [ 5] mail 1.0 e8 got no sig Jul 17 10:38:34.790118 check[20932]: [ 5] No queries, no spam Jul 17 10:38:34.790168 check[20932]: [ 5] already disconnected from server discovery.razor.cloudmark.com Jul 17 10:38:34.790243 check[20932]: [ 8] razor-check finished successfully.
