Daily Usenet report for news.niel.me

Feb 13 04:15:02 -- Feb 14 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Feb 13 10:34:53 rpi-studio innd: filter: No Cancel-Key[sha1:ls1bR0O4Q8vogKdXUUUHB2VYETg=] matches Cancel-Lock[sha1:UQiOXnw+fcZ8/Wc8W86wzSYq+Fw=] target=<s085k6$o1m$1@pasdenom.info>
Feb 13 12:34:39 rpi-studio innd: filter: Valid Cancel-Key /9OpPdf5FPeVDaIXZOZrUYwVH4M= found. target=<i8prpiFov1eU1@mid.individual.net>
Feb 13 14:35:56 rpi-studio innd: filter: No Cancel-Key[sha256:BRPLmKHIVR7zNcl1ToRLCr3mfV2WCTFdDNcpb/IGYOA=] matches Cancel-Lock[sha256:2sRIT/6ksCcrurFDk6ttSwqAvP9iqXmtnu7cOLPsJ7Y=] target=<s05qq5$2ud1$1@news.gegeweb.eu>
Feb 13 14:36:13 rpi-studio innd: filter: Valid Cancel-Key Xi3FNeU40fGUbKqAQdDA8EZn+NHYQiM+SlChjwqUqMU= found. target=<slrns2fh24.uj.gerald.niel+spam@home.niel.me>
Feb 13 14:41:09 rpi-studio innd: filter: Invalid Cancel-Key syntax '=?UTF-8?Q?sha512=3AvvXyd3Yx50Asneq=2FJ5SydGo7BHtIkGDdIfpzG5l?='. target=<slrns2fled.785.gerald.niel+spam@home.niel.me>
Feb 13 14:41:09 rpi-studio innd: filter: Invalid Cancel-Key syntax '=?UTF-8?Q?q9mpbdsQeu=2FzX1Ct7YXQAaDBAGWEYyVaKLmh1r68VzZwSNQ=3D=3D?='. target=<slrns2fled.785.gerald.niel+spam@home.niel.me>
Feb 13 14:41:09 rpi-studio innd: filter: Valid Cancel-Key nfJpm2sqF7+ar0FoeCIBGmEIOrggzvE5aZDrHpMB4gE= found. target=<slrns2fled.785.gerald.niel+spam@home.niel.me>
Feb 13 14:42:46 rpi-studio innd: filter: No Cancel-Key[sha1:JKN34CgAw4yn7zInRKJ+00Ia7hY= sha256:BRPLmKHIVR7zNcl1ToRLCr3mfV2WCTFdDNcpb/IGYOA=] matches Cancel-Lock[sha256:2sRIT/6ksCcrurFDk6ttSwqAvP9iqXmtnu7cOLPsJ7Y=] target=<s05qq5$2ud1$1@news.gegeweb.eu>
Feb 13 14:43:12 rpi-studio innd: filter: No Cancel-Key[sha1:JKN34CgAw4yn7zInRKJ+00Ia7hY= sha256:BRPLmKHIVR7zNcl1ToRLCr3mfV2WCTFdDNcpb/IGYOA=] matches Cancel-Lock[sha256:2sRIT/6ksCcrurFDk6ttSwqAvP9iqXmtnu7cOLPsJ7Y=] target=<s05qq5$2ud1$1@news.gegeweb.eu>
Feb 13 14:43:30 rpi-studio innd: filter: Valid Cancel-Key 14duFneoWqTlIOWCuzfR9cfmYotLKa6llmMQtmS9ZGI= found. target=<slrns2flnl.79e.gerald.niel+spam@home.niel.me>
Feb 13 14:45:34 rpi-studio innd: filter: Valid Cancel-Key qZN3XYEjldJthG7f/ruwpF6OOp28dRNo1bvc/U4w5sE= found. target=<slrns2flth.79n.gerald.niel+spam@home.niel.me>
Feb 13 14:45:46 rpi-studio innd: filter: No Cancel-Key[sha256:Anbz1dtrXhCG1Jkel1TfbtB0ZVXE7XTZ1J7q2dIsyWU= sha256:BRPLmKHIVR7zNcl1ToRLCr3mfV2WCTFdDNcpb/IGYOA=] matches Cancel-Lock[sha256:2sRIT/6ksCcrurFDk6ttSwqAvP9iqXmtnu7cOLPsJ7Y=] target=<s05qq5$2ud1$1@news.gegeweb.eu>
Feb 14 00:55:20 rpi-studio innd: filter: Valid Cancel-Key jhHFWPtCnM57n7zYHNaDk8Umzbw= found. target=<2568145.mvXUDI8C0e@PointedEars.de>

Log entries by program:

Program nameLines%LinesSize%Size
inn 5635 40.5%918.7 KB 46.4%
nnrpd 4418 31.7%424.9 KB 21.5%
innd 2400 17.2%393.1 KB 19.8%
innfeed 1421 10.2%237.9 KB 12.0%
nocem 55 0.4%5.9 KB 0.3%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 6 13930 100.0%1.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 21014 61.9%
Negative hits 7454 22.0%
Do not exist 5468 16.1%
Cache misses 5 0.0%
TOTAL: 4 33941 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.043 0.0%45 0.000 0.956 4.000
article cancel00:00:00.021 0.0%95 0.000 0.221 1.000
article cleanup00:00:00.110 0.0%5463 0.000 0.020 0.098
article logging00:00:00.436 0.0%5499 0.000 0.079 0.192
article parse00:00:01.021 0.0%11455 0.000 0.089 0.263
article write00:00:03.361 0.0%4865 0.231 0.691 2.000
artlog/artcncl00:00:00.006 0.0%95 0.000 0.063 1.000
artlog/artcncl/perl00:00:00.002 0.0%45 0.000 0.044 1.000
data move00:00:00.136 0.0%40566 0.000 0.003 0.018
hisgrep/artcncl00:00:00.003 0.0%75 0.000 0.040 0.200
hisgrep/artcncl/perl00:00:00.002 0.0%45 0.000 0.044 1.000
hisgrep/perl00:00:00.003 0.0%51 0.000 0.059 0.500
hishave/artcncl00:00:00.000 0.0%95 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%45 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.511 0.0%33870 0.000 0.015 0.038
history sync00:00:00.042 0.0%413 0.000 0.102 0.667
history write00:00:01.165 0.0%5463 0.000 0.213 0.690
hiswrite/artcncl00:00:00.002 0.0%20 0.000 0.100 1.000
idle24:00:26.644 99.9%40730 583.804 2121.941 6978.953
nntp read00:00:01.357 0.0%39836 0.009 0.034 0.090
overview write00:00:02.933 0.0%4865 0.033 0.603 4.714
perl filter00:00:24.770 0.0%5463 2.620 4.534 10.737
python filter00:00:00.016 0.0%5481 0.000 0.003 0.062
site send00:00:01.796 0.0%9782 0.012 0.184 0.438
TOTAL: 24:01:16.04524:01:04.380100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.060 0.0%4939 0.000 0.012 0.095
article prepare00:00:00.002 0.0%696 0.000 0.003 0.125
article read00:00:00.021 0.0%115 0.000 0.183 7.000
backlog stats00:00:00.134 0.0%67572 0.000 0.002 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.833 0.0%38049 0.000 0.022 0.047
data write00:00:02.565 0.0%33050 0.027 0.078 0.125
idle23:58:16.927100.0%67572 603.599 1277.111 3182.032
status file00:00:00.388 0.0%175 1.000 2.217 6.000
TOTAL: 23:58:24.06423:58:20.930100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%1 0.000 0.000 0.000
idle01:38:09.867 99.7%2584 19.400 2279.360 21042.548
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.483 0.0%10273 0.000 0.047 1.308
readart00:00:00.097 0.0%234 0.000 0.415 2.050
TOTAL: 01:38:27.70301:38:10.447 99.7%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 4642 3016 1512 114 64%22:39:23
2news.nntp4.net 1 5367 952 4073 342 17%24:52:25
3feed-out.gegeweb.org 3 3721 469 3251 1 12%23:59:52
4usenet.pasdenom.info 2 2575 140 2430 5 5%23:59:18
5gegeweb-out.news.tnetconsulting.net 2 4498 48 4438 12 1%23:59:47
6localhost 49 18 14 0 4 77%00:00:01
7i2pn.org 1 332 9 322 1 2%02:06:04
TOTAL: 7 60 21153 4648 16026 479 21%121:36:50
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org10.0 MB0.0 KB418.1 KB10.4 MB 96%3.4 KB
2news.nntp4.net6.4 MB0.0 KB4.0 MB10.4 MB 61%8.3 KB
3feed-out.gegeweb.org1.5 MB0.0 KB2.9 KB1.5 MB 99%3.3 KB
4gegeweb-out.news.tnetconsulting.net340.3 KB0.0 KB49.1 KB389.5 KB 87%6.5 KB
5usenet.pasdenom.info299.5 KB0.0 KB11.0 KB310.6 KB 96%2.1 KB
6localhost23.7 KB0.0 KB3.1 KB26.8 KB 88%1.5 KB
7i2pn.org19.7 KB0.0 KB4.3 KB24.0 KB 81%2.4 KB
TOTAL: 718.6 MB0.0 KB4.5 MB23.1 MB 80%4.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 13 04:15:02 - 04:59:59 123 2.5% 0.05391.2 KB 2.0% 0.15
Feb 13 05:00:00 - 05:59:59 128 2.6% 0.04478.5 KB 2.5% 0.13
Feb 13 06:00:00 - 06:59:59 152 3.1% 0.04586.8 KB 3.0% 0.16
Feb 13 07:00:00 - 07:59:59 89 1.8% 0.02293.9 KB 1.5% 0.08
Feb 13 08:00:00 - 08:59:59 109 2.2% 0.03328.1 KB 1.7% 0.09
Feb 13 09:00:00 - 09:59:59 129 2.7% 0.04376.5 KB 1.9% 0.10
Feb 13 10:00:00 - 10:59:59 215 4.4% 0.061.1 MB 5.9% 0.32
Feb 13 11:00:00 - 11:59:59 211 4.3% 0.06930.2 KB 4.8% 0.26
Feb 13 12:00:00 - 12:59:59 161 3.3% 0.04439.2 KB 2.3% 0.12
Feb 13 13:00:00 - 13:59:59 178 3.7% 0.05438.9 KB 2.3% 0.12
Feb 13 14:00:00 - 14:59:59 232 4.8% 0.06781.8 KB 4.0% 0.22
Feb 13 15:00:00 - 15:59:59 222 4.6% 0.06896.1 KB 4.6% 0.25
Feb 13 16:00:00 - 16:59:59 193 4.0% 0.05897.5 KB 4.6% 0.25
Feb 13 17:00:00 - 17:59:59 248 5.1% 0.07825.5 KB 4.3% 0.23
Feb 13 18:00:00 - 18:59:59 353 7.3% 0.102.0 MB 10.6% 0.57
Feb 13 19:00:00 - 19:59:59 293 6.0% 0.08930.9 KB 4.8% 0.26
Feb 13 20:00:00 - 20:59:59 289 5.9% 0.081.1 MB 5.7% 0.31
Feb 13 21:00:00 - 21:59:59 216 4.4% 0.06935.6 KB 4.8% 0.26
Feb 13 22:00:00 - 22:59:59 294 6.0% 0.081.3 MB 6.9% 0.37
Feb 13 23:00:00 - 23:59:59 275 5.7% 0.081009.3 KB 5.2% 0.28
Feb 14 00:00:00 - 00:59:59 229 4.7% 0.061.3 MB 6.8% 0.37
Feb 14 01:00:00 - 01:59:59 184 3.8% 0.05767.4 KB 4.0% 0.21
Feb 14 02:00:00 - 02:59:59 174 3.6% 0.05626.2 KB 3.2% 0.17
Feb 14 03:00:00 - 03:59:59 134 2.8% 0.04413.5 KB 2.1% 0.11
Feb 14 04:00:00 - 04:15:02 33 0.7% 0.0482.6 KB 0.4% 0.09
TOTAL: 24:00:00 4864 100.0% 0.0618.9 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 365 0 0 0 0 0 0 0 365
2news-out.aioe.org 151 1 0 0 0 0 0 0 150
3i2pn.org 81 0 0 0 0 0 0 0 81
4gegeweb-out.news.tnetconsulting.net 23 0 0 0 0 0 0 0 23
5usenet.pasdenom.info 5 0 0 0 0 0 0 0 5
6localhost 4 0 0 0 0 0 0 0 4
7feed-out.gegeweb.org 2 0 0 0 0 0 0 0 2
TOTAL: 7 631 1 0 0 0 0 0 0 630

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Bad path (news.giganews.com.POSTED) 166
Too many newsgroups 155
EMP (md5) 106
EMP (phl) 79
EMP (phn nph) 79
No Cancel-Key matches Cancel-Lock. target=<s05qq5$2ud1$1@news.gegeweb.eu> 4
HTML Multipart 3
Too many newsgroups (meow) 1
Cancel of non-existing ID <d24b9148-40ac-4e27-89c8-4bfad3bb1bfdn@googlegroups.com> 1
TOTAL: 9 594

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com4904949
TOTAL: 14904949

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 32
news.nntp4.net 3
feed-out.gegeweb.org 1
TOTAL: 3 36
TOTAL: 1 36

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 9019 62 4674 7 0 35 0%23:59:29
2news.tnetconsulting.net 8516 34 4818 2 0 40 0%23:59:40
3news.gegeweb.org 4453 29 4256 1 0 0 0%23:59:46
4news.pasdenom.info 5245 16 4211 1 0 2 0%23:59:58
5news.nntp4.net 4244 13 3874 1 0 1 0%23:59:37
6news.aioe.org 1783 8 1438 0 0 0 0%23:53:32
TOTAL: 6 33260 162 23271 12 0 78 0%143:52:02
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org189.8 KB13.2 KB203.0 KB0.0 KB/s2.9 KB23:59:29
2news.tnetconsulting.net149.6 KB15.4 KB165.0 KB0.0 KB/s4.6 KB23:59:40
3news.gegeweb.org66.8 KB4.5 KB71.2 KB0.0 KB/s2.4 KB23:59:46
4news.nntp4.net51.9 KB1.9 KB53.9 KB0.0 KB/s3.8 KB23:59:37
5news.pasdenom.info45.2 KB2.3 KB47.5 KB0.0 KB/s2.8 KB23:59:58
6news.aioe.org17.3 KB0.0 KB17.3 KB0.0 KB/s2.2 KB23:53:32
TOTAL: 6520.6 KB37.4 KB558.0 KB0.0 KB/s3.2 KB143:52:02
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mbp-gniel.niel.me 48 216480.5 KB 70 14 801:37:34
TOTAL: 1 48 216480.5 KB 70 14 801:38:27

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.niel.me 48 216480.5 KB 70 14 801:37:34
2*.gegeweb.eu 288 00.0 KB 0 0 000:00:40
3unresolved 5 00.0 KB 0 0 000:00:01
4*.w90-2.abo.wanadoo.fr 3 00.0 KB 0 0 000:00:10
TOTAL: 4 344 216480.5 KB 70 14 801:38:27

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 2.431 1.801 0.00001:37:34
mail.gegeweb.eu 7.776 8.508 2.54500:00:40
amontsouris-654-1-96-85.w90-2.abo.wanadoo.fr 0.094 0.068 0.00000:00:10
88.165.5.23 0.128 0.167 0.00000:00:01
TOTAL: 4 10.429 10.544 2.54501:38:27

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
88.165.5.23 5
amontsouris-654-1-96-85.w90-2.abo.wanadoo.fr 3
TOTAL: 3 296

Newsgroup request counts (by category):

CategoryCountPct
1fr 140 64.8%
2news 43 19.9%
3local 19 8.8%
4control 12 5.6%
5grisbi 2 0.9%
TOTAL: 5 216100.0%

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

NewsgroupCount
1fr.usenet.forums.evolution 99
2news.software.nntp 39
3fr.comp.os.mac-os.x 23
4local.test 19
5control.cancel 12
6fr.comp.os.linux.configuration 9
7fr.comp.mail 6
8fr.comp.sys.mac.materiel 3
9news.software.readers 3
10grisbi.test 1
11news.answers 1
12grisbi.user.french 1
TOTAL: 12 216
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me