Daily Usenet report for news.niel.me

Jun 25 04:15:01 -- Jun 26 04:15:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 25 10:36:39 rpi-studio innd: filter: No Cancel-Key[sha1:VyQF41+Kgow0ifRVWPxqIeZXY/0=] matches Cancel-Lock[sha1:ZAByutNVJfkya23EQuckeoDuXns=] target=<sb44hv$7of$1@dont-email.me>
Jun 25 10:49:16 rpi-studio innd: filter: No Cancel-Key[sha1:Ab/fhulyep9eHyqjPFBwUFEhKQo=] matches Cancel-Lock[sha1:flfqlUtPAMd/eAY+yx0wdFWcwpo=] target=<sb459p$lke$1@dont-email.me>

Log entries by program:

Program nameLines%LinesSize%Size
inn 4782 45.2%837.0 KB 49.1%
innd 2363 22.4%395.4 KB 23.2%
nnrpd 1855 17.6%209.3 KB 12.3%
innfeed 1565 14.8%261.4 KB 15.3%
nocem 2 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 6 10568 100.0%1.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 25285 67.5%
Negative hits 7469 19.9%
Do not exist 4714 12.6%
Cache misses 3 0.0%
TOTAL: 4 37471 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.042 0.0%26 0.750 1.615 10.000
article cancel00:00:00.005 0.0%30 0.000 0.167 1.000
article cleanup00:00:00.104 0.0%4732 0.000 0.022 0.160
article logging00:00:00.386 0.0%4737 0.000 0.081 0.286
article parse00:00:00.783 0.0%7516 0.000 0.104 0.353
article write00:00:02.847 0.0%4176 0.190 0.682 1.903
artlog/artcncl00:00:00.001 0.0%29 0.000 0.034 0.333
artlog/artcncl/perl00:00:00.001 0.0%26 0.000 0.038 1.000
data move00:00:00.101 0.0%40015 0.000 0.003 0.020
hisgrep/artcncl00:00:00.000 0.0%30 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%26 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%29 0.000 0.034 0.500
hishave/artcncl00:00:00.000 0.0%30 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%26 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.514 0.0%37562 0.000 0.014 0.041
history sync00:00:00.060 0.0%409 0.000 0.147 1.000
history write00:00:01.005 0.0%4732 0.000 0.212 0.522
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:31.559 99.9%40453 900.533 2136.592 10008.933
nntp read00:00:01.248 0.0%40102 0.008 0.031 0.070
overview write00:00:02.714 0.0%4176 0.111 0.650 4.889
perl filter00:00:21.442 0.0%4732 3.154 4.531 8.069
python filter00:00:00.021 0.0%8200 0.000 0.003 0.044
site send00:00:01.162 0.0%8357 0.000 0.139 0.478
TOTAL: 24:01:16.95424:01:03.996100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.046 0.0%4217 0.000 0.011 0.125
article prepare00:00:00.003 0.0%1596 0.000 0.002 0.250
article read00:00:00.044 0.0%313 0.000 0.141 8.000
backlog stats00:00:00.133 0.0%66073 0.000 0.002 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.854 0.0%36938 0.000 0.023 0.076
data write00:00:02.543 0.0%32703 0.033 0.078 0.145
idle23:58:50.636100.0%66073 563.389 1306.595 3594.557
status file00:00:00.397 0.0%169 1.000 2.349 8.000
TOTAL: 23:58:58.17223:58:54.656100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:12.076 84.5%1513 6.400 47.638 1705.486
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.098 0.1%3439 0.000 0.028 0.250
readart00:00:00.004 0.0%4 1.000 1.000 1.000
TOTAL: 00:01:25.32100:01:12.178 84.6%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 3894 1748 2106 40 44%21:28:47
2usenet.pasdenom.info 4 6503 888 5613 2 13%48:08:37
3feed-out.gegeweb.org 2 2847 570 2210 67 20%23:59:36
4nntp.terraraq.uk 2 3967 533 3133 301 13%25:28:37
5news.nntp4.net 3 2649 177 2443 29 6%16:00:45
6gegeweb-out.news.tnetconsulting.net 2 3719 10 3696 13 0%23:58:53
7i2pn.org 2 482 6 470 6 1%03:42:44
8localhost 29 3 3 0 0 100%00:00:00
TOTAL: 8 46 24064 3935 19671 458 16%162:47:59
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org5.4 MB0.0 KB151.9 KB5.6 MB 97%3.2 KB
2nntp.terraraq.uk4.1 MB0.0 KB1.2 MB5.3 MB 77%6.5 KB
3usenet.pasdenom.info2.4 MB0.0 KB3.7 KB2.4 MB 99%2.8 KB
4news.nntp4.net1.4 MB0.0 KB62.9 KB1.5 MB 95%7.2 KB
5feed-out.gegeweb.org1.3 MB0.0 KB74.5 KB1.4 MB 94%2.2 KB
6i2pn.org42.0 KB0.0 KB16.3 KB58.3 KB 72%4.9 KB
7gegeweb-out.news.tnetconsulting.net30.9 KB0.0 KB45.5 KB76.4 KB 40%3.3 KB
8localhost4.1 KB0.0 KB0.0 KB4.1 KB100%1.4 KB
TOTAL: 814.7 MB0.0 KB1.6 MB16.2 MB 90%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 25 04:15:01 - 04:59:59 89 2.1% 0.03280.9 KB 1.8% 0.10
Jun 25 05:00:00 - 05:59:59 81 1.9% 0.02251.5 KB 1.6% 0.07
Jun 25 06:00:00 - 06:59:59 88 2.1% 0.02496.6 KB 3.2% 0.14
Jun 25 07:00:00 - 07:59:59 124 3.0% 0.03483.3 KB 3.1% 0.13
Jun 25 08:00:00 - 08:59:59 115 2.8% 0.03332.8 KB 2.2% 0.09
Jun 25 09:00:00 - 09:59:59 157 3.8% 0.04721.0 KB 4.7% 0.20
Jun 25 10:00:00 - 10:59:59 136 3.3% 0.04499.8 KB 3.2% 0.14
Jun 25 11:00:00 - 11:59:59 153 3.7% 0.04552.0 KB 3.6% 0.15
Jun 25 12:00:00 - 12:59:59 138 3.3% 0.04881.8 KB 5.7% 0.24
Jun 25 13:00:00 - 13:59:59 122 2.9% 0.03383.4 KB 2.5% 0.11
Jun 25 14:00:00 - 14:59:59 221 5.3% 0.06955.1 KB 6.2% 0.27
Jun 25 15:00:00 - 15:59:59 183 4.4% 0.05636.9 KB 4.1% 0.18
Jun 25 16:00:00 - 16:59:59 261 6.3% 0.07699.7 KB 4.5% 0.19
Jun 25 17:00:00 - 17:59:59 241 5.8% 0.07781.2 KB 5.1% 0.22
Jun 25 18:00:00 - 18:59:59 341 8.2% 0.091.5 MB 10.0% 0.43
Jun 25 19:00:00 - 19:59:59 214 5.1% 0.06699.5 KB 4.5% 0.19
Jun 25 20:00:00 - 20:59:59 260 6.2% 0.07787.0 KB 5.1% 0.22
Jun 25 21:00:00 - 21:59:59 201 4.8% 0.06516.1 KB 3.3% 0.14
Jun 25 22:00:00 - 22:59:59 234 5.6% 0.07759.8 KB 4.9% 0.21
Jun 25 23:00:00 - 23:59:59 241 5.8% 0.071.1 MB 7.0% 0.30
Jun 26 00:00:00 - 00:59:59 177 4.2% 0.05619.8 KB 4.0% 0.17
Jun 26 01:00:00 - 01:59:59 132 3.2% 0.04414.9 KB 2.7% 0.12
Jun 26 02:00:00 - 02:59:59 133 3.2% 0.04583.0 KB 3.8% 0.16
Jun 26 03:00:00 - 03:59:59 97 2.3% 0.03360.3 KB 2.3% 0.10
Jun 26 04:00:00 - 04:15:01 30 0.7% 0.03142.0 KB 0.9% 0.16
TOTAL: 24:00:00 4169 100.0% 0.0515.1 MB 100.0% 0.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1nntp.terraraq.uk 286 0 0 0 0 0 0 0 286
2i2pn.org 94 0 0 0 0 0 0 0 94
3feed-out.gegeweb.org 67 0 0 0 0 0 0 0 67
4news-out.aioe.org 53 2 0 0 0 0 0 0 51
5news.nntp4.net 44 3 0 0 0 0 0 0 41
6gegeweb-out.news.tnetconsulting.net 13 0 0 0 0 0 0 0 13
7usenet.pasdenom.info 1 0 0 0 0 0 0 0 1
TOTAL: 7 558 5 0 0 0 0 0 0 553

Unwanted newsgroups [Top 20]:

NewsgroupCount
fr.petites-annonces.informatique.materiel 2
fr.petites-annonces.immobilier 2
news.test 1
TOTAL: 3 5

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 257
Bad path (news.giganews.com.POSTED) 86
EMP (phn nph) 85
User-issued spam cancel 75
EMP (phl) 28
EMP (md5) 14
Cancel of non-existing ID <sb247n$js8$1@shakotay.alphanet.ch> 1
No Cancel-Key matches Cancel-Lock. target=<sb44hv$7of$1@dont-email.me> 1
No Cancel-Key matches Cancel-Lock. target=<sb459p$lke$1@dont-email.me> 1
TOTAL: 9 548

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com1011
TOTAL: 11011

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 4
news.nntp4.net 1
TOTAL: 2 5
TOTAL: 1 5

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 7718 103 3839 151 0 10 1%23:59:48
2news.tnetconsulting.net 7581 55 4067 46 0 9 0%23:59:57
3news.gegeweb.org 3672 13 3576 0 0 0 0%23:59:57
4news.pasdenom.info 4630 12 3055 5 0 31 0%23:59:57
5nntp.terraraq.uk 2878 5 2520 0 0 0 0%23:59:44
6news.nntp4.net 4042 4 3900 0 0 0 0%23:59:57
7news.aioe.org 1986 2 1821 3 0 0 0%23:57:37
TOTAL: 7 32507 194 22778 205 0 50 0%167:56:57
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org227.3 KB302.6 KB529.9 KB0.0 KB/s2.1 KB23:59:48
2news.tnetconsulting.net131.8 KB110.2 KB241.9 KB0.0 KB/s2.4 KB23:59:57
3news.gegeweb.org46.7 KB0.0 KB46.7 KB0.0 KB/s3.6 KB23:59:57
4news.pasdenom.info27.0 KB7.4 KB34.4 KB0.0 KB/s2.0 KB23:59:57
5nntp.terraraq.uk19.7 KB0.0 KB19.7 KB0.0 KB/s3.9 KB23:59:44
6news.nntp4.net5.6 KB0.0 KB5.6 KB0.0 KB/s1.4 KB23:59:57
7news.aioe.org2.6 KB4.1 KB6.8 KB0.0 KB/s1.4 KB23:57:37
TOTAL: 7460.7 KB424.3 KB885.0 KB0.0 KB/s2.2 KB167:56:57
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mbp-gniel.niel.me 1 412.8 KB 4 3 000:00:59
TOTAL: 1 1 412.8 KB 4 3 000:01:24

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.niel.me 1 412.8 KB 4 3 000:00:59
2unresolved 11 00.0 KB 0 0 000:00:03
3*.gegeweb.eu 289 00.0 KB 0 0 000:00:21
TOTAL: 3 301 412.8 KB 4 3 000:01:24

NNRP auth users [Top 20]:

UserConn
mailman 289
TOTAL: 1 289

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mbp-gniel.niel.me 0.099 0.041 0.00000:00:59
mail.gegeweb.eu 8.233 9.029 3.14100:00:21
88.165.5.23 0.352 0.297 0.00000:00:03
TOTAL: 3 8.684 9.367 3.14100:01:24

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 289
88.165.5.23 11
TOTAL: 2 300

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 4100.0%
TOTAL: 1 4100.0%

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

NewsgroupCount
1grisbi.user.french 1
2grisbi.user 1
3grisbi.devel 1
4grisbi.test 1
TOTAL: 4 4
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me