Daily Usenet report for news.niel.me

Sep 10 04:15:01 -- Sep 11 04:15:01

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Sep 10 12:52:47 rpi-studio innd: filter: No Cancel-Key[sha256:7xgfvPxMjJupGwCPWoHuIkNB7hicPu5t58+r3lpmgaE=] matches Cancel-Lock[sha256:rc2h3wsNcoDYiW44t2y2qfOm44oZxwhN1h6+x7dThpc=] target=<tfhq43$1gt$6@rasp.pasdenom.info>
Sep 10 13:05:39 rpi-studio innd: filter: No Cancel-Key[sha256:pmCHtT6lzy1XKvpm2vuEySZO/gf4bok0CkkafDTz9d0=] matches Cancel-Lock[sha256:L/0WaDlfUAli/ht8HXZ6lfp0wyhATSeiL7lJRk28qUg=] target=<tfhr0b$4or$1@rasp.pasdenom.info>
Sep 10 13:10:27 rpi-studio innd: filter: No Cancel-Key[sha256:Sa+EoYLtx9KFVggCZUEzCzJSePazVutpDDiYswymYNI=] matches Cancel-Lock[sha256:A0GUX8JOlLB2E/MOdD15N2XXDnMkErYdshUeNqJKr/c=] target=<tfhr8h$5fd$1@rasp.pasdenom.info>
Sep 10 17:51:04 rpi-studio innd: filter: No Cancel-Key[sha256:ZPTuvjOmmXl+roPkmRm52zRtZCdkyxkcOQIVeg8+Dno=] matches Cancel-Lock[sha256:M7PTwSlU0+V+FzLisVkPKArO4jkzLFC+gLXP3MAhI+M=] target=<1py2urk.7jgfnb1q47q7yN%mv@gmail.com.invalid>
Sep 10 18:23:56 rpi-studio innd: filter: No Cancel-Key[sha256:QFkNEL1+BGlFC6rl/iPrSixE+2nGUPGt1cY8orQmjbw=] matches Cancel-Lock[sha256:69hSg4FPPHtDTQjHK8uKpiwrFk0nZtKKDWSbLDl9Hsc=] target=<1py2wd3.1c8nak5rcgurzN%mv@gmail.com.invalid>
Sep 10 20:35:16 rpi-studio innd: filter: No Cancel-Key[sha256:JW6MeqwtJWZBLPGWBF2zZzTaoszqpaU6akhyXVyI4sc=] matches Cancel-Lock[sha256:Wf0IpOE9ZfQNNNPjEHMaXXuBFYKtNF1CzAijNjprKX8=] target=<tfilb8$5q9$1@rasp.pasdenom.info>
Sep 10 20:52:33 rpi-studio innd: filter: No Cancel-Key[sha256:iKGPGPucV856MGXg3s6cBHogupU9aLIatrkWLXpDluE=] matches Cancel-Lock[sha256:jPBXM93KN/fEDSX4uHglXEsurNaBdoylHF9Zv4rVFAY=] target=<tfimbo$8fr$1@rasp.pasdenom.info>
Sep 10 20:55:35 rpi-studio innd: filter: No Cancel-Key[sha256:2WKpwReYNmuPeHrM6M1JtSxanbwdK8nN8mLbFLDqCrc=] matches Cancel-Lock[sha256:JkIR5KJ86tZAVGNEwNBSBg/mmVQRkd6nmySa0aJoGtk=] target=<tfimic$905$1@rasp.pasdenom.info>
Sep 10 20:58:39 rpi-studio innd: filter: No Cancel-Key[sha256:Zs+toAL9HWo9RZd0uOPMJ4Yg1BdigDLvphJGCPfarkk=] matches Cancel-Lock[sha256:5vW2IwdSkrPnTFLY5DNM149IUc+GD8PFQjV9QTUvij4=] target=<tfimnn$9au$1@rasp.pasdenom.info>
Sep 10 21:00:52 rpi-studio innd: filter: No Cancel-Key[sha256:NOwmcHOySVnhPNpucIVyB77GX64OxLBaR9m+bZLzxOg=] matches Cancel-Lock[sha256:2tx215VfgbS1b21heHZryoqlqGlV9W+5GLSwtwsf69g=] target=<tfims3$9p3$1@rasp.pasdenom.info>
Sep 10 21:24:34 rpi-studio innd: filter: No Cancel-Key[sha256:G7gMfCRjUJK0NX6WTpsEudrtA+JlTp/OxAfE75QS4pE=] matches Cancel-Lock[sha256:n65w6vCRndVJAxNcIqkvnIdagHh6aOgyiyl51v7EKR4=] target=<tfio7r$co7$1@rasp.pasdenom.info>
Sep 10 21:43:43 rpi-studio innd: filter: No Cancel-Key[sha256:vi7RNucRaafwadGBvH7/HQv/mqIHQXpTCMSumjePqLo=] matches Cancel-Lock[sha256:ONnd+ySumUcPX3K/gt4KF9R04uC2B1VGrUvl9XsUTiE=] target=<tfipcn$g11$1@rasp.pasdenom.info>

Log entries by program:

Program nameLines%LinesSize%Size
inn 4152 42.4%798.2 KB 48.0%
innd 2398 24.5%420.4 KB 25.3%
innfeed 1720 17.6%273.9 KB 16.5%
nnrpd 1522 15.5%170.8 KB 10.3%
nocem 4 0.0%0.4 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 6 9797 100.0%1.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 26607 67.6%
Negative hits 8695 22.1%
Do not exist 4046 10.3%
Cache misses 7 0.0%
TOTAL: 4 39355 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.043 0.0%27 0.000 1.593 11.000
article cancel00:00:00.011 0.0%43 0.000 0.256 1.000
article cleanup00:00:00.070 0.0%4050 0.000 0.017 0.143
article logging00:00:00.293 0.0%4062 0.000 0.072 0.267
article parse00:00:00.789 0.0%9746 0.000 0.081 0.267
article write00:00:02.334 0.0%3766 0.077 0.620 2.286
artlog/artcncl00:00:00.003 0.0%43 0.000 0.070 1.000
artlog/artcncl/perl00:00:00.003 0.0%27 0.000 0.111 1.000
data move00:00:00.118 0.0%44544 0.000 0.003 0.013
hisgrep/artcncl00:00:00.000 0.0%40 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.001 0.0%27 0.000 0.037 0.500
hisgrep/perl00:00:00.002 0.0%41 0.000 0.049 0.500
hishave/artcncl00:00:00.000 0.0%43 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%27 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.489 0.0%39367 0.000 0.012 0.035
history sync00:00:00.036 0.0%415 0.000 0.087 0.667
history write00:00:00.832 0.0%4050 0.000 0.205 0.500
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle24:00:32.919100.0%44799 334.214 1929.349 6900.966
nntp read00:00:01.441 0.0%44283 0.005 0.033 0.073
overview write00:00:02.662 0.0%3766 0.000 0.707 4.176
perl filter00:00:17.315 0.0%4050 1.958 4.275 9.086
python filter00:00:00.010 0.0%4050 0.000 0.002 0.071
site send00:00:01.643 0.0%7585 0.000 0.217 0.591
TOTAL: 24:01:14.88924:01:01.014100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.039 0.0%4741 0.000 0.008 0.080
article prepare00:00:00.001 0.0%1376 0.000 0.001 0.015
article read00:00:00.030 0.0%282 0.000 0.106 1.333
backlog stats00:00:00.135 0.0%63375 0.000 0.002 0.016
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.918 0.0%37287 0.000 0.025 0.063
data write00:00:02.119 0.0%32932 0.010 0.064 0.128
idle23:51:06.381100.0%63375 323.655 1354.894 3973.589
status file00:00:00.462 0.0%176 1.000 2.625 8.000
TOTAL: 23:51:13.85123:51:10.085100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:22.628 18.1%1447 11.400 15.638 83.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.089 0.1%3352 0.000 0.027 0.273
readart00:00:00.016 0.0%20 0.800 0.800 0.800
TOTAL: 00:02:04.84600:00:22.733 18.2%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 4
reserve 2
TOTAL: 11 173

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 4 3837 1667 2133 37 43%25:17:17
2i2pn.org 1 3591 1278 2190 123 35%23:31:33
3feed-out.gegeweb.org 3 2480 160 2318 2 6%23:56:35
4usenet.pasdenom.info 4 1575 110 1463 2 6%15:06:16
5nntp.terraraq.uk 2 3648 68 3546 34 1%25:03:50
6news.glou.org 5 1003 59 942 2 5%06:34:34
7news.samoylyk.net 2 7313 46 7267 0 0%46:22:46
8news.nntp4.net 4 5948 34 5739 175 0%36:06:25
9gegeweb-out.news.tnetconsulting.net 3 688 12 676 0 1%06:49:56
10localhost 26 0 0 0 0 0%00:00:00
TOTAL: 10 54 30083 3434 26274 375 11%208:49:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org7.5 MB0.0 KB76.7 KB7.5 MB 99%4.5 KB
2i2pn.org4.0 MB0.0 KB299.3 KB4.3 MB 93%3.1 KB
3news.samoylyk.net2.1 MB0.0 KB0.0 KB2.1 MB100%47.7 KB
4news.nntp4.net1.7 MB0.0 KB1.0 MB2.8 MB 62%13.6 KB
5news.glou.org1.0 MB0.0 KB6.2 KB1.0 MB 99%17.4 KB
6feed-out.gegeweb.org650.4 KB0.0 KB7.0 KB657.4 KB 98%4.1 KB
7nntp.terraraq.uk356.1 KB0.0 KB135.6 KB491.7 KB 72%4.8 KB
8gegeweb-out.news.tnetconsulting.net317.6 KB0.0 KB0.0 KB317.6 KB100%26.5 KB
9usenet.pasdenom.info270.0 KB0.0 KB3.1 KB273.1 KB 98%2.4 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1017.9 MB0.0 KB1.5 MB19.4 MB 92%5.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 10 04:15:01 - 04:59:59 66 1.7% 0.02268.7 KB 1.3% 0.10
Sep 10 05:00:00 - 05:59:59 90 2.4% 0.03284.6 KB 1.4% 0.08
Sep 10 06:00:00 - 06:59:59 125 3.3% 0.03466.3 KB 2.3% 0.13
Sep 10 07:00:00 - 07:59:59 91 2.4% 0.03360.0 KB 1.8% 0.10
Sep 10 08:00:00 - 08:59:59 68 1.8% 0.02226.4 KB 1.1% 0.06
Sep 10 09:00:00 - 09:59:59 83 2.2% 0.02366.3 KB 1.8% 0.10
Sep 10 10:00:00 - 10:59:59 117 3.1% 0.03710.3 KB 3.5% 0.20
Sep 10 11:00:00 - 11:59:59 160 4.2% 0.04668.9 KB 3.3% 0.19
Sep 10 12:00:00 - 12:59:59 167 4.4% 0.05497.4 KB 2.4% 0.14
Sep 10 13:00:00 - 13:59:59 132 3.5% 0.04434.7 KB 2.1% 0.12
Sep 10 14:00:00 - 14:59:59 293 7.7% 0.082.0 MB 10.2% 0.58
Sep 10 15:00:00 - 15:59:59 247 6.5% 0.073.3 MB 16.3% 0.93
Sep 10 16:00:00 - 16:59:59 162 4.3% 0.04889.5 KB 4.3% 0.25
Sep 10 17:00:00 - 17:59:59 194 5.1% 0.05685.0 KB 3.3% 0.19
Sep 10 18:00:00 - 18:59:59 197 5.2% 0.05882.7 KB 4.3% 0.25
Sep 10 19:00:00 - 19:59:59 174 4.6% 0.05648.4 KB 3.2% 0.18
Sep 10 20:00:00 - 20:59:59 212 5.6% 0.061.0 MB 5.1% 0.29
Sep 10 21:00:00 - 21:59:59 227 6.0% 0.061.3 MB 6.6% 0.37
Sep 10 22:00:00 - 22:59:59 171 4.5% 0.051.3 MB 6.6% 0.37
Sep 10 23:00:00 - 23:59:59 175 4.6% 0.05595.4 KB 2.9% 0.17
Sep 11 00:00:00 - 00:59:59 218 5.8% 0.06746.5 KB 3.6% 0.21
Sep 11 01:00:00 - 01:59:59 150 4.0% 0.041.4 MB 6.9% 0.39
Sep 11 02:00:00 - 02:59:59 111 2.9% 0.03458.6 KB 2.2% 0.13
Sep 11 03:00:00 - 03:59:59 117 3.1% 0.03545.3 KB 2.7% 0.15
Sep 11 04:00:00 - 04:15:01 34 0.9% 0.04144.2 KB 0.7% 0.16
TOTAL: 24:00:00 3781 100.0% 0.0420.0 MB 100.0% 0.24
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1i2pn.org 124 0 0 0 0 0 0 0 124
2news.nntp4.net 97 0 0 0 0 0 0 0 97
3news-out.aioe.org 43 1 0 0 0 0 0 0 42
4news.glou.org 16 0 0 0 0 0 0 0 16
5usenet.pasdenom.info 13 1 0 0 0 0 0 0 12
6nntp.terraraq.uk 5 0 0 0 0 0 0 0 5
7feed-out.gegeweb.org 2 0 0 0 0 0 0 0 2
8news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 8 301 2 0 0 0 0 0 0 299

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
france.haute-normandie.rouen 1
TOTAL: 2 2

INND Perl filter [Top 20]:

ReasonCount
EMP (phl) 110
EMP (phn nph) 82
Bad path (news.giganews.com.POSTED) 57
Too many newsgroups 33
EMP (md5) 4
Cancel of <tfiuee$bgj$1@ns507557.dodin.fr.nf> without Cancel-Key 1
TOTAL: 6 287

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com3033
TOTAL: 13033

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 8
i2pn.org 3
news.samoylyk.net 1
TOTAL: 3 12
TOTAL: 1 12

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 4332 180 2210 8 0 130 4%23:58:42
2news.tnetconsulting.net 11090 45 3726 6 0 808 0%23:59:56
3news.nntp4.net 4466 28 3723 0 0 43 0%23:59:56
4nntp.terraraq.uk 2838 24 2500 15 0 0 0%23:59:56
5news.gegeweb.org 3766 9 3600 12 0 0 0%23:59:44
6news.samoylyk.net 4314 8 3747 2 0 2 0%23:59:56
7news.aioe.org 2348 5 1636 1 0 1 0%23:59:56
8news.pasdenom.info 3340 1 3206 0 0 0 0%23:59:56
TOTAL: 8 36494 300 24348 44 0 984 0%191:58:02
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.nntp4.net1.1 MB0.0 KB1.1 MB0.0 KB/s41.3 KB23:59:56
2nntp.terraraq.uk1.0 MB1.4 MB2.4 MB0.0 KB/s64.3 KB23:59:56
3news.tnetconsulting.net890.0 KB13.8 KB903.7 KB0.0 KB/s17.7 KB23:59:56
4i2pn.org628.8 KB8.9 KB637.7 KB0.0 KB/s3.4 KB23:58:42
5news.samoylyk.net599.3 KB2.8 KB602.2 KB0.0 KB/s60.2 KB23:59:56
6news.pasdenom.info92.7 KB0.0 KB92.7 KB0.0 KB/s92.7 KB23:59:56
7news.gegeweb.org43.3 KB33.3 KB76.6 KB0.0 KB/s3.6 KB23:59:44
8news.aioe.org13.8 KB1.6 KB15.5 KB0.0 KB/s2.6 KB23:59:56
TOTAL: 84.4 MB1.5 MB5.9 MB0.0 KB/s17.4 KB191:58:02
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12a0e:e701:1154:0:89e0:71bd:ccc9:4e4f 1 210.6 KB 1 0 000:00:59
TOTAL: 1 1 210.6 KB 1 0 000:02:04

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 1 210.6 KB 1 0 000:00:59
2*.gegeweb.eu 289 00.0 KB 0 0 000:01:03
3unresolved 2 00.0 KB 0 0 000:00:01
TOTAL: 3 292 210.6 KB 1 0 000:02:04

NNRP auth users [Top 20]:

UserConn
mailman 289
gerald 1
TOTAL: 2 290

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 8.022 8.244 2.41500:01:03
2a0e:e701:1154:0:89e0:71bd:ccc9:4e4f 0.080 0.034 0.00600:00:59
192.241.213.87 0.020 0.031 0.00000:00:00
179.60.150.88 0.038 0.000 0.00000:00:00
TOTAL: 4 8.160 8.309 2.42100:02:04

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 289
192.241.213.87 1
179.60.150.88 1
TOTAL: 3 291

NNRP unrecognized commands (by host):

SystemConn
192.241.213.87 1
TOTAL: 1 1

NNRP unrecognized commands (by command):

CommandCount
MGLNDD_45.13.105.36_119 1
TOTAL: 1 1

Newsgroup request counts (by category):

CategoryCountPct
1fr 2100.0%
TOTAL: 1 2100.0%

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

NewsgroupCount
1fr.usenet.forums.annonces 2
TOTAL: 1 2
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me