Daily Usenet report for news.niel.me

Feb 15 04:15:01 -- Feb 16 04:15:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Feb 15 10:30:00 rpi-studio innd: filter: No Cancel-Key[sha256:31ttXzDMe7vT0vyvJZbg8djdC+xfJdhiOBSHBbg+TQE=] matches Cancel-Lock[sha1:d4w0OdZ0pNDEUkf2GT+aiAEQ+TA=] target=<conventions_gestions-faq-1612171800$91264@sys.gegeweb.eu>
Feb 15 10:30:00 rpi-studio innd: filter: No Cancel-Key[sha256:h3/p9Fi/4bX8lqvk7AYxO2xOgAR6iXz9rLKVC+5yF0s=] matches Cancel-Lock[sha1:xD2bE5V7LRKuVPJTt2cQpR/Kv2A=] target=<cu_fufa-faq-1612171800$91264@sys.gegeweb.eu>
Feb 15 17:00:04 rpi-studio pgpverify[17381]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 5442 43.4%950.2 KB 49.9%
nnrpd 3141 25.1%316.9 KB 16.6%
innd 2295 18.3%366.3 KB 19.2%
innfeed 1597 12.8%265.1 KB 13.9%
nocem 42 0.3%4.6 KB 0.2%
controlchan 7 0.1%1.1 KB 0.1%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 12525 100.0%1.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 20664 62.0%
Negative hits 7315 22.0%
Do not exist 5335 16.0%
Cache misses 6 0.0%
TOTAL: 4 33320 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.039 0.0%20 0.500 1.950 4.500
article cancel00:00:00.021 0.0%54 0.000 0.389 1.000
article cleanup00:00:00.113 0.0%5322 0.000 0.021 0.154
article logging00:00:00.414 0.0%5373 0.000 0.077 0.227
article parse00:00:00.968 0.0%11158 0.000 0.087 0.211
article write00:00:03.194 0.0%4847 0.231 0.659 1.381
artlog/artcncl00:00:00.004 0.0%54 0.000 0.074 1.000
artlog/artcncl/perl00:00:00.003 0.0%20 0.000 0.150 1.000
data move00:00:00.119 0.0%39555 0.000 0.003 0.016
hisgrep/artcncl00:00:00.000 0.0%24 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.003 0.0%20 0.000 0.150 1.000
hisgrep/perl00:00:00.002 0.0%24 0.000 0.083 1.000
hishave/artcncl00:00:00.003 0.0%54 0.000 0.056 1.000
hishave/artcncl/perl00:00:00.000 0.0%20 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.518 0.0%33338 0.000 0.016 0.051
history sync00:00:00.038 0.0%404 0.000 0.094 1.000
history write00:00:01.114 0.0%5320 0.000 0.209 0.538
hiswrite/artcncl00:00:00.006 0.0%30 0.000 0.200 1.000
idle24:00:21.395 99.9%39611 751.099 2181.752 17654.471
nntp read00:00:01.363 0.0%38812 0.000 0.035 0.084
overview write00:00:02.973 0.0%4847 0.105 0.613 6.583
perl filter00:00:23.698 0.0%5320 2.941 4.455 7.846
python filter00:00:00.012 0.0%5328 0.000 0.002 0.048
site send00:00:01.374 0.0%9731 0.000 0.141 0.375
TOTAL: 24:01:09.44824:00:57.374100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.063 0.0%5183 0.000 0.012 0.125
article prepare00:00:00.000 0.0%732 0.000 0.000 0.000
article read00:00:00.028 0.0%130 0.000 0.215 8.000
backlog stats00:00:00.164 0.0%65414 0.000 0.003 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.902 0.0%36913 0.000 0.024 0.048
data write00:00:02.379 0.0%32162 0.025 0.074 0.120
idle23:49:55.830100.0%65414 617.595 1311.582 6400.936
status file00:00:00.427 0.0%187 0.000 2.283 8.000
TOTAL: 23:50:02.69923:49:59.793100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.001 0.0%3 0.000 0.333 0.500
idle01:43:31.425 99.7%2052 13.500 3027.010 66729.353
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.170 0.0%6786 0.000 0.025 0.250
readart00:00:00.113 0.0%293 0.000 0.386 2.000
TOTAL: 01:43:48.60701:43:31.709 99.7%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 170

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 4808 3378 1325 105 70%24:53:29
2news.nntp4.net 2 5093 841 3990 262 16%23:37:10
3feed-out.gegeweb.org 2 3632 429 3203 0 11%23:59:35
4usenet.pasdenom.info 3 2508 151 2353 4 6%23:51:31
5i2pn.org 1 4710 69 4592 49 1%23:59:59
6gegeweb-out.news.tnetconsulting.net 1 3198 33 3135 30 1%17:31:46
7localhost 43 8 8 0 0 100%00:00:00
TOTAL: 7 54 23957 4909 18598 450 20%137:53:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org10.7 MB0.0 KB332.9 KB11.0 MB 97%3.2 KB
2news.nntp4.net8.4 MB0.0 KB1.0 MB9.5 MB 89%8.8 KB
3feed-out.gegeweb.org1.7 MB0.0 KB0.0 KB1.7 MB100%4.0 KB
4usenet.pasdenom.info355.6 KB0.0 KB7.4 KB363.0 KB 97%2.3 KB
5gegeweb-out.news.tnetconsulting.net282.0 KB0.0 KB88.0 KB370.0 KB 76%5.9 KB
6i2pn.org152.1 KB0.0 KB92.4 KB244.5 KB 62%2.1 KB
7localhost16.5 KB0.0 KB0.0 KB16.5 KB100%2.1 KB
TOTAL: 721.6 MB0.0 KB1.5 MB23.1 MB 93%4.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 15 04:15:01 - 04:59:59 101 2.1% 0.04325.0 KB 1.5% 0.12
Feb 15 05:00:00 - 05:59:59 77 1.6% 0.02286.3 KB 1.3% 0.08
Feb 15 06:00:00 - 06:59:59 106 2.2% 0.03623.4 KB 2.8% 0.17
Feb 15 07:00:00 - 07:59:59 82 1.7% 0.02263.2 KB 1.2% 0.07
Feb 15 08:00:00 - 08:59:59 103 2.1% 0.03348.0 KB 1.6% 0.10
Feb 15 09:00:00 - 09:59:59 142 2.9% 0.04594.3 KB 2.7% 0.17
Feb 15 10:00:00 - 10:59:59 147 3.0% 0.04643.8 KB 2.9% 0.18
Feb 15 11:00:00 - 11:59:59 142 2.9% 0.04449.5 KB 2.0% 0.12
Feb 15 12:00:00 - 12:59:59 227 4.7% 0.061.0 MB 4.9% 0.30
Feb 15 13:00:00 - 13:59:59 158 3.3% 0.04481.2 KB 2.2% 0.13
Feb 15 14:00:00 - 14:59:59 166 3.4% 0.05532.8 KB 2.4% 0.15
Feb 15 15:00:00 - 15:59:59 206 4.3% 0.06733.7 KB 3.3% 0.20
Feb 15 16:00:00 - 16:59:59 306 6.3% 0.09980.7 KB 4.5% 0.27
Feb 15 17:00:00 - 17:59:59 264 5.5% 0.071.7 MB 8.1% 0.49
Feb 15 18:00:00 - 18:59:59 317 6.5% 0.091.1 MB 5.0% 0.31
Feb 15 19:00:00 - 19:59:59 324 6.7% 0.091.0 MB 4.9% 0.30
Feb 15 20:00:00 - 20:59:59 313 6.5% 0.091.8 MB 8.2% 0.50
Feb 15 21:00:00 - 21:59:59 356 7.4% 0.101.6 MB 7.3% 0.44
Feb 15 22:00:00 - 22:59:59 242 5.0% 0.07818.8 KB 3.7% 0.23
Feb 15 23:00:00 - 23:59:59 244 5.0% 0.07733.9 KB 3.3% 0.20
Feb 16 00:00:00 - 00:59:59 330 6.8% 0.092.3 MB 10.6% 0.65
Feb 16 01:00:00 - 01:59:59 174 3.6% 0.05943.3 KB 4.3% 0.26
Feb 16 02:00:00 - 02:59:59 140 2.9% 0.041.5 MB 7.2% 0.44
Feb 16 03:00:00 - 03:59:59 136 2.8% 0.04734.1 KB 3.3% 0.20
Feb 16 04:00:00 - 04:15:01 39 0.8% 0.04119.5 KB 0.5% 0.13
TOTAL: 24:00:00 4842 100.0% 0.0621.4 MB 100.0% 0.25
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 267 0 0 0 0 0 0 0 267
2news-out.aioe.org 149 5 0 0 0 0 0 0 144
3i2pn.org 94 0 0 2 0 0 0 0 92
4gegeweb-out.news.tnetconsulting.net 11 0 0 0 0 0 0 0 11
5usenet.pasdenom.info 5 0 0 0 0 0 0 0 5
TOTAL: 5 526 5 0 2 0 0 0 0 519

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.fan.rush-limbaugh 2
alt.atheism 1
news.test 1
comp.os.linux 1
TOTAL: 4 5

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 132
Bad path (news.giganews.com.POSTED) 125
EMP (phn nph) 83
EMP (phl) 79
EMP (md5) 44
Cancels abusifs 2
Cancel of non-existing ID <3LGWH.84217$Fx6.58071@fx47.iad> 1
HTML Multipart 1
Cancel of non-existing ID <10BWH.47068$th1.39569@fx18.iad> 1
TOTAL: 9 468

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com2102121
nocem@aioe.org1101111
TOTAL: 23203232

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 45
news.nntp4.net 5
usenet.pasdenom.info 1
TOTAL: 3 51
TOTAL: 1 51

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 8989 59 4665 7 0 109 0%23:59:57
2news.tnetconsulting.net 8458 52 4787 1 0 108 0%23:59:57
3news.pasdenom.info 5114 17 4156 7 0 80 0%23:59:57
4news.nntp4.net 4249 17 3978 0 0 67 0%23:59:57
5news.gegeweb.org 4467 15 4286 0 0 4 0%23:59:57
6news.aioe.org 1441 8 1222 0 0 0 0%23:07:24
7nntp.terraraq.uk 0 0 0 0 0 3394 0%23:59:57
TOTAL: 7 32718 168 23094 15 0 3762 0%167:07:06
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org229.2 KB13.1 KB242.3 KB0.0 KB/s3.7 KB23:59:57
2news.tnetconsulting.net216.8 KB1.9 KB218.7 KB0.0 KB/s4.1 KB23:59:57
3news.pasdenom.info125.6 KB40.5 KB166.1 KB0.0 KB/s6.9 KB23:59:57
4news.nntp4.net86.3 KB0.0 KB86.3 KB0.0 KB/s5.1 KB23:59:57
5news.gegeweb.org44.4 KB0.0 KB44.4 KB0.0 KB/s3.0 KB23:59:57
6news.aioe.org16.5 KB0.0 KB16.5 KB0.0 KB/s2.1 KB23:07:24
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
TOTAL: 7718.9 KB55.4 KB774.3 KB0.0 KB/s4.2 KB167:07:06
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mbp-gniel.niel.me 24 83428.7 KB 46 8 001:43:01
TOTAL: 1 24 83428.7 KB 46 8 001:43:47

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.niel.me 24 83428.7 KB 46 8 001:43:01
2unresolved 7 00.0 KB 0 0 000:00:02
3*.w90-2.abo.wanadoo.fr 1 00.0 KB 0 0 000:00:00
4*.security.ipip.net 1 00.0 KB 0 0 000:00:01
5*.105.67.207.li.binaryedge.ninja 1 00.0 KB 0 0 000:00:02
6*.gegeweb.eu 288 00.0 KB 0 0 000:00:39
TOTAL: 6 322 83428.7 KB 46 8 001:43:47

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mbp-gniel.niel.me 1.207 0.964 0.00001:43:01
mail.gegeweb.eu 8.144 8.184 2.58200:00:39
172.105.67.207.li.binaryedge.ninja 0.035 0.017 0.00000:00:02
88.165.5.23 0.114 0.174 0.00000:00:01
scan-141.security.ipip.net 0.019 0.039 0.00000:00:01
80.82.68.219 0.058 0.055 0.00000:00:00
amontsouris-654-1-124-228.w90-2.abo.wanadoo.fr 0.039 0.020 0.00000:00:00
TOTAL: 7 9.616 9.453 2.58201:43:47

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
88.165.5.23 5
80.82.68.219 2
amontsouris-654-1-124-228.w90-2.abo.wanadoo.fr 1
scan-141.security.ipip.net 1
172.105.67.207.li.binaryedge.ninja 1
TOTAL: 6 298

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
80.82.68.219 2
TOTAL: 2 6

NNRP unrecognized commands (by command):

CommandCount
#003 2
Connection: close 1
GET / HTTP/1.1 1
User-Agent: HTTP Banner Detection (https... 1
Host: 185.73.232.189:119 1
TOTAL: 5 6

NNRP client timeouts [Top 20]:

SystemConnPeer
80.82.68.219 2 2
TOTAL: 1 2 2

Newsgroup request counts (by category):

CategoryCountPct
1fr 60 72.3%
2news 18 21.7%
3grisbi 5 6.0%
TOTAL: 3 83100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1fr.usenet.forums.evolution 14
2fr.usenet.forums.annonces 14
3fr.comp.usenet.serveurs 10
4fr.misc.bavardages.linux 10
5news.admin.peering 9
6fr.usenet.documents 6
7news.software.readers 5
8grisbi.bugsreports 4
9fr.comp.sys.mac.materiel 3
10fr.comp.os.linux.configuration 3
11news.answers 2
12news.announce.newgroups 1
13grisbi.test 1
14news.admin.hierarchies 1
TOTAL: 14 83
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me