Daily Usenet report for news.niel.me

Jun 22 04:15:02 -- Jun 23 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 22 09:56:47 rpi-studio innd: filter: No Cancel-Key[sha1:+5AvFtFMe5BCUgw7b0tcUJYRyyU=] matches Cancel-Lock[sha256:s8daoZhDJNgSjvfEsR3wkprohfVh0H+m8HLlu/ecno0=] target=<sas50k$kmb$1@shakotay.alphanet.ch>

Log entries by program:

Program nameLines%LinesSize%Size
inn 4369 43.4%760.1 KB 47.1%
innd 2292 22.8%384.7 KB 23.8%
nnrpd 1840 18.3%197.6 KB 12.2%
innfeed 1561 15.5%271.4 KB 16.8%
nocem 1 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 6 10064 100.0%1.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 22407 67.1%
Negative hits 6695 20.0%
Do not exist 4294 12.9%
Cache misses 6 0.0%
TOTAL: 4 33402 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.039 0.0%16 1.000 2.438 6.667
article cancel00:00:00.003 0.0%20 0.000 0.150 1.000
article cleanup00:00:00.093 0.0%4320 0.000 0.022 0.125
article logging00:00:00.339 0.0%4338 0.000 0.078 0.759
article parse00:00:00.855 0.0%8352 0.000 0.102 0.250
article write00:00:02.414 0.0%3814 0.154 0.633 2.059
artlog/artcncl00:00:00.000 0.0%20 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
data move00:00:00.098 0.0%37789 0.000 0.003 0.028
hisgrep/artcncl00:00:00.000 0.0%20 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.002 0.0%16 0.000 0.125 1.000
hisgrep/perl00:00:00.002 0.0%23 0.000 0.087 0.500
hishave/artcncl00:00:00.000 0.0%20 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.002 0.0%16 0.000 0.125 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.499 0.0%33574 0.000 0.015 0.055
history sync00:00:00.048 0.0%411 0.000 0.117 1.000
history write00:00:00.904 0.0%4320 0.000 0.209 0.923
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:27.239100.0%37884 675.943 2281.365 12517.958
nntp read00:00:01.165 0.0%37389 0.000 0.031 0.071
overview write00:00:02.544 0.0%3814 0.000 0.667 5.000
perl filter00:00:21.130 0.0%4320 3.171 4.891 11.438
python filter00:00:00.017 0.0%7083 0.000 0.002 0.071
site send00:00:01.064 0.0%7632 0.000 0.139 0.441
TOTAL: 24:01:09.93824:00:58.457100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.053 0.0%3805 0.000 0.014 0.125
article prepare00:00:00.000 0.0%1268 0.000 0.000 0.000
article read00:00:00.039 0.0%270 0.000 0.144 8.000
backlog stats00:00:00.145 0.0%61331 0.000 0.002 0.013
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.852 0.0%33828 0.000 0.025 0.047
data write00:00:02.334 0.0%29936 0.027 0.078 0.151
idle23:59:06.708100.0%61331 413.668 1407.880 4551.826
status file00:00:00.409 0.0%174 1.000 2.351 8.000
TOTAL: 23:59:13.63423:59:10.540100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:03:24.352 91.3%1502 6.400 136.053 4017.417
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.123 0.1%3475 0.000 0.035 0.273
readart00:00:00.028 0.0%33 0.848 0.848 0.848
TOTAL: 00:03:43.73300:03:24.503 91.4%----

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 1 3784 2014 1701 69 53%24:56:00
2usenet.pasdenom.info 4 7707 579 7124 4 7%62:45:12
3feed-out.gegeweb.org 2 2635 514 2119 2 19%23:59:35
4nntp.terraraq.uk 1 3212 446 2498 268 13%23:12:46
5news.nntp4.net 3 2561 135 2360 66 5%17:23:11
6i2pn.org 2 3744 47 3656 41 1%23:58:59
7gegeweb-out.news.tnetconsulting.net 1 3598 20 3560 18 0%23:59:57
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 39 27241 3755 23018 468 13%200:15:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news-out.aioe.org6.0 MB0.0 KB260.6 KB6.2 MB 95%3.1 KB
2nntp.terraraq.uk3.7 MB0.0 KB868.3 KB4.5 MB 81%6.5 KB
3feed-out.gegeweb.org2.7 MB0.0 KB3.5 KB2.7 MB 99%5.3 KB
4news.nntp4.net1.7 MB0.0 KB187.2 KB1.8 MB 90%9.4 KB
5usenet.pasdenom.info1.4 MB0.0 KB17.7 KB1.4 MB 98%2.5 KB
6i2pn.org201.2 KB0.0 KB119.7 KB320.9 KB 62%3.6 KB
7gegeweb-out.news.tnetconsulting.net109.1 KB0.0 KB65.0 KB174.1 KB 62%4.6 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 815.7 MB0.0 KB1.5 MB17.2 MB 91%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 22 04:15:02 - 04:59:59 80 2.1% 0.03314.7 KB 1.7% 0.12
Jun 22 05:00:00 - 05:59:59 65 1.7% 0.02352.4 KB 1.9% 0.10
Jun 22 06:00:00 - 06:59:59 87 2.3% 0.02247.5 KB 1.3% 0.07
Jun 22 07:00:00 - 07:59:59 123 3.2% 0.03390.9 KB 2.1% 0.11
Jun 22 08:00:00 - 08:59:59 94 2.5% 0.03329.2 KB 1.7% 0.09
Jun 22 09:00:00 - 09:59:59 90 2.4% 0.03723.4 KB 3.8% 0.20
Jun 22 10:00:00 - 10:59:59 95 2.5% 0.03278.4 KB 1.5% 0.08
Jun 22 11:00:00 - 11:59:59 91 2.4% 0.03239.1 KB 1.3% 0.07
Jun 22 12:00:00 - 12:59:59 143 3.8% 0.04527.2 KB 2.8% 0.15
Jun 22 13:00:00 - 13:59:59 168 4.4% 0.05455.4 KB 2.4% 0.13
Jun 22 14:00:00 - 14:59:59 124 3.3% 0.03346.0 KB 1.8% 0.10
Jun 22 15:00:00 - 15:59:59 178 4.7% 0.05492.4 KB 2.6% 0.14
Jun 22 16:00:00 - 16:59:59 188 4.9% 0.05540.9 KB 2.9% 0.15
Jun 22 17:00:00 - 17:59:59 232 6.1% 0.06817.5 KB 4.3% 0.23
Jun 22 18:00:00 - 18:59:59 227 6.0% 0.06802.1 KB 4.3% 0.22
Jun 22 19:00:00 - 19:59:59 247 6.5% 0.072.2 MB 11.9% 0.62
Jun 22 20:00:00 - 20:59:59 296 7.8% 0.081.3 MB 7.0% 0.37
Jun 22 21:00:00 - 21:59:59 270 7.1% 0.071.1 MB 6.2% 0.32
Jun 22 22:00:00 - 22:59:59 244 6.4% 0.071.3 MB 7.0% 0.37
Jun 22 23:00:00 - 23:59:59 194 5.1% 0.051.2 MB 6.4% 0.33
Jun 23 00:00:00 - 00:59:59 153 4.0% 0.041.1 MB 6.2% 0.32
Jun 23 01:00:00 - 01:59:59 142 3.7% 0.041.5 MB 8.0% 0.42
Jun 23 02:00:00 - 02:59:59 126 3.3% 0.041.6 MB 8.7% 0.45
Jun 23 03:00:00 - 03:59:59 121 3.2% 0.03355.8 KB 1.9% 0.10
Jun 23 04:00:00 - 04:15:02 27 0.7% 0.0379.7 KB 0.4% 0.09
TOTAL: 24:00:00 3805 100.0% 0.0418.4 MB 100.0% 0.22
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1nntp.terraraq.uk 288 0 0 0 0 0 0 0 288
2news.nntp4.net 116 0 0 0 0 0 0 0 116
3news-out.aioe.org 79 1 0 0 0 0 0 0 78
4gegeweb-out.news.tnetconsulting.net 21 0 0 0 0 0 0 0 21
5i2pn.org 17 0 0 0 0 0 0 0 17
6usenet.pasdenom.info 4 0 0 0 0 0 0 0 4
7feed-out.gegeweb.org 3 0 0 0 0 0 0 0 3
TOTAL: 7 528 1 0 0 0 0 0 0 527

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 220
Bad path (news.giganews.com.POSTED) 102
EMP (phn nph) 102
EMP (md5) 34
EMP (phl) 31
User-issued spam cancel 16
Too many newsgroups (low_xpost) 1
Cancel of <sasr7q$d94p$1@pasdenom.info> without Cancel-Key 1
Cancel of <sas4nc$je8$1@shakotay.alphanet.ch> without Cancel-Key 1
Cancel of non-existing ID <ije8rgFronsU1@mid.individual.net> 1
TOTAL: 10 509

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 10
nntp.terraraq.uk 7
feed-out.gegeweb.org 1
TOTAL: 3 18
TOTAL: 1 18

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 7118 154 3524 68 0 0 2%23:59:57
2news.tnetconsulting.net 6932 62 3729 9 0 0 0%23:59:35
3news.pasdenom.info 4616 15 2966 1 0 2 0%23:59:57
4news.gegeweb.org 3358 4 3290 0 0 0 0%23:59:01
5nntp.terraraq.uk 2458 3 2259 0 0 0 0%23:59:28
6news.nntp4.net 3814 1 3568 0 0 0 0%23:59:57
7news.aioe.org 1495 0 1383 0 0 0 0%23:51:08
TOTAL: 7 29791 239 20719 78 0 2 0%167:49:03
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org380.8 KB146.5 KB527.3 KB0.0 KB/s2.4 KB23:59:57
2news.tnetconsulting.net165.2 KB25.0 KB190.2 KB0.0 KB/s2.7 KB23:59:35
3news.pasdenom.info46.5 KB3.2 KB49.8 KB0.0 KB/s3.1 KB23:59:57
4news.gegeweb.org41.3 KB0.0 KB41.3 KB0.0 KB/s10.3 KB23:59:01
5nntp.terraraq.uk10.4 KB0.0 KB10.4 KB0.0 KB/s3.5 KB23:59:28
6news.nntp4.net2.6 KB0.0 KB2.6 KB0.0 KB/s2.6 KB23:59:57
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:51:08
TOTAL: 7646.7 KB174.7 KB821.4 KB0.0 KB/s2.6 KB167:49:03
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mbp-gniel.niel.me 1 1946.6 KB 5 0 000:03:12
TOTAL: 1 1 1946.6 KB 5 0 000:03:43

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.niel.me 1 1946.6 KB 5 0 000:03:12
2*.gegeweb.eu 288 00.0 KB 0 0 000:00:22
3unresolved 5 00.0 KB 0 0 000:00:04
4*.security.ipip.net 1 00.0 KB 0 0 000:00:02
5*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 5 296 1946.6 KB 5 0 000:03:43

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 0.050 0.050 0.00000:03:12
mail.gegeweb.eu 8.567 8.701 3.10200:00:22
218.94.99.102 0.069 0.023 0.00000:00:02
scan-141.security.ipip.net 0.033 0.022 0.00000:00:02
88.165.5.23 0.090 0.030 0.00000:00:00
scanner-05.ch1.censys-scanner.com 0.027 0.045 0.00000:00:00
185.202.2.139 0.043 0.043 0.00000:00:00
? 0.033 0.022 0.00000:00:00
TOTAL: 8 8.912 8.936 3.10200:03:43

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
88.165.5.23 2
185.202.2.139 2
218.94.99.102 1
scan-141.security.ipip.net 1
scanner-05.ch1.censys-scanner.com 1
TOTAL: 6 295

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 1
TOTAL: 1 1

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
185.202.2.139 2
scanner-05.ch1.censys-scanner.com 1
TOTAL: 3 7

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
185.202.2.139 2 2
TOTAL: 1 2 2

Newsgroup request counts (by category):

CategoryCountPct
1fr 15 78.9%
2grisbi 4 21.1%
TOTAL: 2 19100.0%

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

NewsgroupCount
1fr.usenet.forums.evolution 12
2grisbi.devel 2
3grisbi.user.french 2
4fr.comp.usenet.serveurs 2
5fr.comp.sys.raspberry-pi 1
TOTAL: 5 19