Daily Usenet report for news.niel.me

May 15 04:15:02 -- May 16 04:15:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

May 15 09:59:22 rpi-studio innd: filter: No Cancel-Key[sha1:OmP6H4lsrz0lgyTXR/3k+7Wsu6U=] matches Cancel-Lock[sha1:DUq03jE9tEiLXK1ik/b2jy4t6Yc=] target=<6280b25a$0$18016$426a74cc@news.free.fr>
May 15 17:00:05 rpi-studio pgpverify[3428]: pgpverify: verification failed
May 15 17:00:06 rpi-studio pgpverify[3445]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 4367 42.2%829.3 KB 47.9%
innd 2410 23.3%411.6 KB 23.8%
nnrpd 1850 17.9%185.4 KB 10.7%
innfeed 1716 16.6%303.0 KB 17.5%
controlchan 13 0.1%2.3 KB 0.1%
pgpverify 2 0.0%0.3 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 7 10359 100.0%1.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 24777 63.7%
Negative hits 9851 25.3%
Do not exist 4285 11.0%
Cache misses 6 0.0%
TOTAL: 4 38919 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.035 0.0%27 0.000 1.296 4.000
article cancel00:00:00.010 0.0%31 0.000 0.323 1.667
article cleanup00:00:00.091 0.0%4293 0.000 0.021 0.182
article logging00:00:00.332 0.0%4308 0.000 0.077 0.273
article parse00:00:00.992 0.0%10634 0.000 0.093 0.267
article write00:00:06.645 0.0%3821 0.238 1.739 109.917
artlog/artcncl00:00:00.001 0.0%31 0.000 0.032 0.500
artlog/artcncl/perl00:00:00.004 0.0%27 0.000 0.148 1.000
data move00:00:00.119 0.0%45153 0.000 0.003 0.015
hisgrep/artcncl00:00:00.003 0.0%30 0.000 0.100 1.000
hisgrep/artcncl/perl00:00:00.004 0.0%27 0.000 0.148 1.000
hisgrep/perl00:00:00.000 0.0%33 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%31 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.491 0.0%38910 0.000 0.013 0.042
history sync00:00:00.085 0.0%414 0.000 0.205 9.333
history write00:00:00.944 0.0%4289 0.000 0.220 0.600
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle24:00:24.827 99.9%45194 528.739 1912.308 8229.247
nntp read00:00:01.476 0.0%44713 0.006 0.033 0.068
overview write00:00:02.959 0.0%3821 0.061 0.774 5.040
perl filter00:00:20.485 0.0%4289 2.485 4.776 10.348
python filter00:00:00.004 0.0%4292 0.000 0.001 0.053
site send00:00:01.176 0.0%7724 0.000 0.152 0.457
TOTAL: 24:01:15.49524:01:00.683100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.037 0.0%3886 0.000 0.010 0.071
article prepare00:00:00.000 0.0%252 0.000 0.000 0.000
article read00:00:00.013 0.0%41 0.000 0.317 8.000
backlog stats00:00:00.121 0.0%53660 0.000 0.002 0.011
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.716 0.0%33520 0.000 0.021 0.047
data write00:00:01.934 0.0%29810 0.016 0.065 0.120
idle23:50:26.386100.0%53660 911.580 1599.448 6981.698
status file00:00:00.461 0.0%181 1.000 2.547 15.000
TOTAL: 23:50:35.79923:50:29.668100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:22.759 30.2%1492 0.000 55.468 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.152 0.1%3830 0.000 0.040 0.333
readart00:00:00.027 0.0%15 0.000 1.800 1.929
TOTAL: 00:04:33.79900:01:22.938 30.3%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002202
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 2 4345 2011 2202 132 46%27:24:50
2i2pn.org 1 3986 1205 2693 88 30%24:41:21
3feed-out.gegeweb.org 3 2525 284 2241 0 11%23:58:15
4usenet.pasdenom.info 1 2949 249 2697 3 8%24:35:09
5nntp.terraraq.uk 4 6194 114 5931 149 1%41:14:47
6news.samoylyk.net 1 3499 45 3448 6 1%23:59:53
7gegeweb-out.news.tnetconsulting.net 2 3238 43 3186 9 1%23:59:29
8news.nntp4.net 1 2276 29 2184 63 1%13:35:24
9localhost 28 3 3 0 0 100%00:00:00
TOTAL: 9 43 29015 3983 24582 450 13%203:29:08
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org7.8 MB0.0 KB412.7 KB8.2 MB 95%3.9 KB
2i2pn.org4.2 MB0.0 KB580.8 KB4.8 MB 88%3.8 KB
3news.samoylyk.net1.5 MB0.0 KB19.4 KB1.6 MB 98%31.3 KB
4news.nntp4.net1.1 MB0.0 KB4.1 MB5.2 MB 21%58.0 KB
5feed-out.gegeweb.org1.0 MB0.0 KB0.0 KB1.0 MB100%3.8 KB
6nntp.terraraq.uk947.9 KB0.0 KB521.3 KB1.4 MB 64%5.6 KB
7usenet.pasdenom.info771.1 KB0.0 KB11.9 KB783.0 KB 98%3.1 KB
8gegeweb-out.news.tnetconsulting.net750.8 KB0.0 KB34.4 KB785.1 KB 95%15.1 KB
9localhost4.2 KB0.0 KB0.0 KB4.2 KB100%1.4 KB
TOTAL: 918.1 MB0.0 KB5.6 MB23.8 MB 76%5.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 15 04:15:02 - 04:59:59 69 1.8% 0.03211.0 KB 1.1% 0.08
May 15 05:00:00 - 05:59:59 85 2.2% 0.02970.6 KB 4.9% 0.27
May 15 06:00:00 - 06:59:59 96 2.5% 0.03413.6 KB 2.1% 0.11
May 15 07:00:00 - 07:59:59 127 3.3% 0.04497.6 KB 2.5% 0.14
May 15 08:00:00 - 08:59:59 122 3.2% 0.03437.8 KB 2.2% 0.12
May 15 09:00:00 - 09:59:59 126 3.3% 0.04496.8 KB 2.5% 0.14
May 15 10:00:00 - 10:59:59 111 2.9% 0.03543.3 KB 2.7% 0.15
May 15 11:00:00 - 11:59:59 155 4.1% 0.04511.6 KB 2.6% 0.14
May 15 12:00:00 - 12:59:59 180 4.7% 0.051.2 MB 6.1% 0.34
May 15 13:00:00 - 13:59:59 165 4.3% 0.05676.0 KB 3.4% 0.19
May 15 14:00:00 - 14:59:59 164 4.3% 0.051.3 MB 6.9% 0.38
May 15 15:00:00 - 15:59:59 172 4.5% 0.05531.0 KB 2.7% 0.15
May 15 16:00:00 - 16:59:59 145 3.8% 0.04570.7 KB 2.9% 0.16
May 15 17:00:00 - 17:59:59 246 6.4% 0.071.3 MB 6.5% 0.36
May 15 18:00:00 - 18:59:59 199 5.2% 0.06677.2 KB 3.4% 0.19
May 15 19:00:00 - 19:59:59 232 6.1% 0.061.2 MB 6.0% 0.33
May 15 20:00:00 - 20:59:59 171 4.5% 0.05596.3 KB 3.0% 0.17
May 15 21:00:00 - 21:59:59 199 5.2% 0.06934.6 KB 4.7% 0.26
May 15 22:00:00 - 22:59:59 175 4.6% 0.05568.9 KB 2.9% 0.16
May 15 23:00:00 - 23:59:59 252 6.6% 0.071.6 MB 8.5% 0.47
May 16 00:00:00 - 00:59:59 223 5.8% 0.062.2 MB 11.3% 0.62
May 16 01:00:00 - 01:59:59 159 4.2% 0.04638.2 KB 3.2% 0.18
May 16 02:00:00 - 02:59:59 120 3.1% 0.03718.2 KB 3.6% 0.20
May 16 03:00:00 - 03:59:59 99 2.6% 0.03609.0 KB 3.1% 0.17
May 16 04:00:00 - 04:15:02 29 0.8% 0.03230.1 KB 1.2% 0.26
TOTAL: 24:00:00 3821 100.0% 0.0419.3 MB 100.0% 0.23
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 143 0 0 0 0 0 0 0 143
2news-out.aioe.org 130 1 0 0 0 0 0 0 129
3nntp.terraraq.uk 118 0 0 0 0 0 0 0 118
4i2pn.org 82 0 0 0 0 0 0 0 82
5news.samoylyk.net 10 0 0 2 0 0 0 0 8
6gegeweb-out.news.tnetconsulting.net 2 0 0 2 0 0 0 0 0
7usenet.pasdenom.info 2 0 0 0 0 0 0 0 2
8feed-out.gegeweb.org 1 0 0 0 0 0 0 0 1
TOTAL: 8 488 1 0 4 0 0 0 0 483

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 195
EMP (phn nph) 93
Bad path (news.giganews.com.POSTED) 77
EMP (phl) 68
Too many newsgroups 30
Cancel of <mn.7a717e651803ab5c.145333@ffz.invalid.eu> without Cancel-Key 1
Cancel of non-existing ID <t5qjdc$ga1$1@cabale.usenet-fr.net> 1
Cancel of <mn.7aba7e651f773bb1.145333@zorro.eu.invalid> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<6280b25a$0$18016$426a74cc@news.free.fr> 1
Cancel of <mn.7a797e65cdb957ff.145333@zorro.eu.invalid> without Cancel-Key 1
TOTAL: 10 468

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 10
news.samoylyk.net 3
i2pn.org 1
feed-out.gegeweb.org 1
TOTAL: 4 15
TOTAL: 1 15

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.nntp4.net 4006 12 3757 0 0 5 0%23:59:54
2i2pn.org 4959 11 2583 5 0 37 0%23:56:03
3news.gegeweb.org 3567 6 3517 5 0 0 0%23:59:37
4news.tnetconsulting.net 6195 6 3809 5 0 37 0%23:59:40
5news.aioe.org 1475 3 1433 0 0 0 0%23:58:07
6nntp.terraraq.uk 2671 3 2615 0 0 4 0%23:59:54
7news.pasdenom.info 3427 0 3393 3 0 0 0%23:59:54
8news.samoylyk.net 3933 0 3784 4 0 0 0%23:59:31
TOTAL: 8 30233 41 24891 22 0 83 0%191:52:40
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.nntp4.net43.7 KB0.0 KB43.7 KB0.0 KB/s3.6 KB23:59:54
2i2pn.org35.3 KB13.7 KB49.0 KB0.0 KB/s3.1 KB23:56:03
3news.tnetconsulting.net16.3 KB11.9 KB28.2 KB0.0 KB/s2.6 KB23:59:40
4news.gegeweb.org15.2 KB17.0 KB32.1 KB0.0 KB/s2.9 KB23:59:37
5nntp.terraraq.uk9.1 KB0.0 KB9.1 KB0.0 KB/s3.0 KB23:59:54
6news.aioe.org4.2 KB0.0 KB4.2 KB0.0 KB/s1.4 KB23:58:07
7news.pasdenom.info0.0 KB4.2 KB4.2 KB0.0 KB/s1.4 KB23:59:54
8news.samoylyk.net0.0 KB7.4 KB7.4 KB0.0 KB/s1.9 KB23:59:31
TOTAL: 8123.7 KB54.1 KB177.8 KB0.0 KB/s2.8 KB191:52:40
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12a0e:e701:1154:0:b521:8a38:3c1c:eb 3 12188.5 KB 7 3 000:02:09
TOTAL: 1 3 12188.5 KB 7 3 000:04:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 3 12188.5 KB 7 3 000:02:09
2*.ip.linodeusercontent.com 44 00.0 KB 0 0 000:00:44
3*.biz.spectrum.com 1 00.0 KB 0 0 000:00:10
4*.gegeweb.eu 288 00.0 KB 0 0 000:01:06
5unresolved 3 00.0 KB 0 0 000:00:22
TOTAL: 5 339 12188.5 KB 7 3 000:04:33

NNRP auth users [Top 20]:

UserConn
mailman 288
gerald 3
TOTAL: 2 291

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2a0e:e701:1154:0:b521:8a38:3c1c:eb 0.323 0.125 0.01800:02:09
mail.gegeweb.eu 7.658 8.293 2.23600:01:06
172-104-140-107.ip.linodeusercontent.com 1.619 0.738 0.00000:00:44
27.124.32.167 0.056 0.056 0.00000:00:19
071-013-068-078.biz.spectrum.com 0.021 0.032 0.00000:00:10
23.129.64.132 0.020 0.030 0.00000:00:03
TOTAL: 6 9.697 9.274 2.25400:04:33

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
172-104-140-107.ip.linodeusercontent.com 44
27.124.32.167 2
23.129.64.132 1
071-013-068-078.biz.spectrum.com 1
TOTAL: 5 336

NNRP unrecognized commands (by host):

SystemConn
27.124.32.167 2
071-013-068-078.biz.spectrum.com 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

CommandCount
#026#003#001 1
#001 1
#003 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
27.124.32.167 1 1
TOTAL: 1 1 2

Newsgroup request counts (by category):

CategoryCountPct
1comp 5 41.7%
2grisbi 4 33.3%
3fr 2 16.7%
4news 1 8.3%
TOTAL: 4 12100.0%

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

NewsgroupCount
1comp.sys.raspberry-pi 3
2fr.usenet.forums.evolution 2
3grisbi.user 2
4comp.infosystems.gemini 2
5grisbi.user.french 1
6grisbi.test 1
7news.admin.hierarchies 1
TOTAL: 7 12