Daily Usenet report for news.niel.me

Oct 1 04:15:01 -- Oct 2 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 1 16:57:40 rpi-studio innd: filter: No Cancel-Key[sha1:5R1s6f/XKNXz/fH1GX1+f4MqxJM=] matches Cancel-Lock[sha1:2lwvnsogkfTTzV2N9Y/EQV9IC/Y=] target=<66fc0cb2$0$3272$426a34cc@news.free.fr>

Log entries by program:

Program nameLines%LinesSize%Size
innd 2213 26.2%384.6 KB 28.7%
inn 2204 26.1%414.7 KB 30.9%
nnrpd 2164 25.6%252.3 KB 18.8%
innfeed 1876 22.2%289.5 KB 21.6%
controlchan 1 0.0%0.1 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 6 8459 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 12179 62.8%
Negative hits 5043 26.0%
Do not exist 2170 11.2%
Cache misses 7 0.0%
TOTAL: 4 19399 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.017 0.0%14 1.000 1.214 1.500
article cancel00:00:00.005 0.0%14 0.000 0.357 0.500
article cleanup00:00:00.040 0.0%2173 0.000 0.018 1.000
article logging00:00:00.176 0.0%2174 0.000 0.081 0.400
article parse00:00:00.302 0.0%4573 0.000 0.066 0.500
article write00:00:01.094 0.0%1950 0.000 0.561 1.545
artlog/artcncl00:00:00.000 0.0%14 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%14 0.000 0.071 0.500
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.116 0.0%20388 0.000 0.006 0.030
hisgrep/artcncl00:00:00.002 0.0%14 0.000 0.143 0.500
hisgrep/artcncl/perl00:00:00.001 0.0%14 0.000 0.071 0.250
hisgrep/perl00:00:00.002 0.0%24 0.000 0.083 1.000
hishave/artcncl00:00:00.000 0.0%14 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.001 0.0%14 0.000 0.071 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.372 0.0%19418 0.000 0.019 0.143
history sync00:00:00.053 0.0%399 0.000 0.133 1.000
history write00:00:00.389 0.0%2173 0.000 0.179 0.667
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:52.216100.0%22522 1035.808 3838.567 37543.812
nntp read00:00:00.544 0.0%21647 0.000 0.025 0.087
overview write00:00:00.719 0.0%1950 0.000 0.369 4.125
perl filter00:00:09.265 0.0%2173 2.286 4.264 12.000
python filter00:00:00.012 0.0%2173 0.000 0.006 0.118
site send00:00:00.922 0.0%3929 0.000 0.235 0.667
TOTAL: 24:01:13.30524:01:06.249100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.019 0.0%2035 0.000 0.009 0.091
article prepare00:00:00.002 0.0%756 0.000 0.003 0.250
article read00:00:00.033 0.0%181 0.000 0.182 7.000
backlog stats00:00:00.187 0.0%31015 0.000 0.006 0.097
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.404 0.0%16046 0.000 0.025 0.091
data write00:00:00.865 0.0%14306 0.000 0.060 0.133
idle23:59:16.509100.0%31015 1082.027 2784.347 9701.710
status file00:00:00.626 0.0%257 0.000 2.436 5.000
TOTAL: 23:59:23.89623:59:18.645100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:14:36.673 50.0%3016 7.500 290.674 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.403 0.0%10008 0.000 0.040 0.500
readart00:00:00.254 0.0%1496 0.148 0.170 0.400
TOTAL: 00:29:11.91000:14:37.330 50.1%----

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
1feed-out.gegeweb.org 4 1923 1128 781 14 58%24:55:47
2gegeweb-out.news.tnetconsulting.net 2 1640 248 1340 52 15%23:02:41
3news.samoylyk.net 3 3606 223 3380 3 6%40:34:13
4usenet.pasdenom.info 10 2362 220 2107 35 9%42:16:56
5i2pn.org 2 2039 207 1831 1 10%23:56:38
6news.glou.org 3 1737 67 1634 36 3%24:59:14
7nntp.terraraq.uk 3 1852 60 1791 1 3%24:44:42
8news.nntp4.net 5 858 47 771 40 5%13:12:41
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 57 16017 2200 13635 182 13%217:42:52
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org3.8 MB0.0 KB40.8 KB3.9 MB 98%3.5 KB
2news.samoylyk.net1.7 MB0.0 KB10.8 KB1.7 MB 99%7.6 KB
3gegeweb-out.news.tnetconsulting.net1.2 MB0.0 KB292.5 KB1.5 MB 80%5.0 KB
4i2pn.org489.3 KB0.0 KB1.2 KB490.5 KB 99%2.4 KB
5usenet.pasdenom.info455.7 KB0.0 KB56.3 KB512.0 KB 88%2.0 KB
6news.nntp4.net275.9 KB0.0 KB97.3 KB373.2 KB 73%4.3 KB
7news.glou.org247.0 KB0.0 KB99.9 KB346.9 KB 71%3.4 KB
8nntp.terraraq.uk140.4 KB0.0 KB1.7 KB142.1 KB 98%2.3 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 98.2 MB0.0 KB600.6 KB8.8 MB 93%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 1 04:15:01 - 04:59:59 33 1.7% 0.01123.4 KB 1.6% 0.05
Oct 1 05:00:00 - 05:59:59 50 2.6% 0.01129.8 KB 1.7% 0.04
Oct 1 06:00:00 - 06:59:59 18 0.9% 0.0164.3 KB 0.8% 0.02
Oct 1 07:00:00 - 07:59:59 27 1.4% 0.01102.6 KB 1.4% 0.03
Oct 1 08:00:00 - 08:59:59 58 3.0% 0.02316.2 KB 4.2% 0.09
Oct 1 09:00:00 - 09:59:59 53 2.7% 0.01652.9 KB 8.6% 0.18
Oct 1 10:00:00 - 10:59:59 61 3.1% 0.02308.7 KB 4.1% 0.09
Oct 1 11:00:00 - 11:59:59 73 3.7% 0.02307.5 KB 4.1% 0.09
Oct 1 12:00:00 - 12:59:59 63 3.2% 0.02163.7 KB 2.2% 0.05
Oct 1 13:00:00 - 13:59:59 92 4.7% 0.03293.0 KB 3.9% 0.08
Oct 1 14:00:00 - 14:59:59 91 4.7% 0.03257.8 KB 3.4% 0.07
Oct 1 15:00:00 - 15:59:59 93 4.8% 0.03287.3 KB 3.8% 0.08
Oct 1 16:00:00 - 16:59:59 76 3.9% 0.02202.5 KB 2.7% 0.06
Oct 1 17:00:00 - 17:59:59 116 5.9% 0.03494.6 KB 6.5% 0.14
Oct 1 18:00:00 - 18:59:59 123 6.3% 0.03443.7 KB 5.9% 0.12
Oct 1 19:00:00 - 19:59:59 190 9.7% 0.05683.2 KB 9.0% 0.19
Oct 1 20:00:00 - 20:59:59 120 6.1% 0.03403.2 KB 5.3% 0.11
Oct 1 21:00:00 - 21:59:59 119 6.1% 0.03503.1 KB 6.6% 0.14
Oct 1 22:00:00 - 22:59:59 87 4.5% 0.02435.0 KB 5.7% 0.12
Oct 1 23:00:00 - 23:59:59 99 5.1% 0.03299.7 KB 4.0% 0.08
Oct 2 00:00:00 - 00:59:59 118 6.0% 0.03428.3 KB 5.7% 0.12
Oct 2 01:00:00 - 01:59:59 80 4.1% 0.02264.0 KB 3.5% 0.07
Oct 2 02:00:00 - 02:59:59 53 2.7% 0.01168.2 KB 2.2% 0.05
Oct 2 03:00:00 - 03:59:59 51 2.6% 0.01216.2 KB 2.9% 0.06
Oct 2 04:00:00 - 04:15:01 8 0.4% 0.0128.6 KB 0.4% 0.03
TOTAL: 24:00:00 1952 100.0% 0.027.4 MB 100.0% 0.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 87 0 0 0 0 0 0 0 87
2gegeweb-out.news.tnetconsulting.net 54 0 0 0 0 0 0 0 54
3news.glou.org 49 0 0 0 0 0 0 0 49
4usenet.pasdenom.info 18 0 0 0 0 0 0 0 18
5feed-out.gegeweb.org 15 0 0 0 0 0 0 0 15
6nntp.terraraq.uk 1 0 0 0 0 0 0 0 1
TOTAL: 6 224 0 0 0 0 0 0 0 224

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 87
EMP (phn path) 68
Bad path (news.giganews.com.POSTED) 57
Cancels abusifs 1
Cancel of <09b3c2af0d3d73329d8669f8ad4540bf66d55a7b@i2pn2.org> without Cancel-Key 1
Cancel of <9cb20619aa989e06622f48686b701ed685bb9801@i2pn2.org> without Cancel-Key 1
Cancel of <91d40c74247a9f726d760408833801de1b5f8948@i2pn2.org> without Cancel-Key 1
Cancel of <f451feabe0b275fbd2127e412d64d5269b0783a7@i2pn2.org> without Cancel-Key 1
Cancel of <ad54795015781b3f1a7486ef814b674adcaf6993@i2pn2.org> without Cancel-Key 1
Cancel of <cf1df12faca49b524776e94c8dc0514d09002886@i2pn2.org> without Cancel-Key 1
Cancel of <d998553d92620048e5b007c7a90e65835a91d26a@i2pn2.org> without Cancel-Key 1
Cancel of <9d13dac1a04cd2ad2a84ddf02f30b72dea5077cd@i2pn2.org> without Cancel-Key 1
Cancel of <eb38fa88f8a1f5c1521ce4671ae31b6d9e2654f2@i2pn2.org> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<66fc0cb2$0$3272$426a34cc@news.free.fr> 1
TOTAL: 14 223

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed-out.gegeweb.org 1
TOTAL: 1 1
TOTAL: 1 1

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 2848 62 1698 4 0 25 2%23:56:38
2news.gegeweb.org 905 44 779 0 0 0 4%22:58:41
3news.samoylyk.net 2287 14 1786 51 0 18 0%23:52:56
4news.tnetconsulting.net 3162 3 1943 3 0 4 0%23:56:39
5news.nntp4.net 2155 3 1912 0 0 20 0%23:56:44
6news.pasdenom.info 1823 2 1621 3 0 16 0%23:42:06
7news.aioe.org 0 0 0 0 0 1475 0%24:00:00
8nntp.terraraq.uk 1191 0 1175 0 0 0 0%23:30:08
TOTAL: 8 14371 128 10914 61 0 1558 0%189:53:52
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.gegeweb.org151.1 KB0.0 KB151.1 KB0.0 KB/s3.4 KB22:58:41
2i2pn.org136.9 KB37.3 KB174.1 KB0.0 KB/s2.6 KB23:56:38
3news.samoylyk.net57.5 KB280.0 KB337.5 KB0.0 KB/s5.2 KB23:52:56
4news.nntp4.net44.0 KB0.0 KB44.0 KB0.0 KB/s14.7 KB23:56:44
5news.tnetconsulting.net9.6 KB5.5 KB15.1 KB0.0 KB/s2.5 KB23:56:39
6news.pasdenom.info9.0 KB23.5 KB32.5 KB0.0 KB/s6.5 KB23:42:06
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
8nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:30:08
TOTAL: 8408.2 KB346.1 KB754.3 KB0.0 KB/s4.0 KB189:53:52
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
169.10.48.174 10 14962.5 MB 23 0 000:13:45
TOTAL: 1 10 14962.5 MB 23 0 000:29:11

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 17 14962.5 MB 23 0 000:14:14
2*.gegeweb.eu 288 00.0 KB 0 0 000:01:54
3? 5 00.0 KB 0 0 000:12:51
4*.150.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
5*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:04
6*.survey.inspici.com 1 00.0 KB 0 0 000:00:04
7*.211.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:01
TOTAL: 7 315 14962.5 MB 23 0 000:29:11

NNRP auth users [Top 20]:

UserConn
mailman 288
gerald 5
TOTAL: 2 293

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
69.10.48.174 0.527 0.814 0.00000:13:45
2a0e:e701:1154:0:ade1:e632:7abc:9e8e 0.280 0.114 0.02300:12:51
mail.gegeweb.eu 5.229 4.469 1.75200:01:54
205.210.31.24 0.020 0.025 0.00000:00:14
198.235.24.88 0.028 0.024 0.00000:00:10
server-0-3.survey.inspici.com 0.018 0.005 0.00000:00:04
resounding.monitoring.internet-measurement.com 0.028 0.025 0.00000:00:02
acclaimed.monitoring.internet-measurement.com 0.032 0.020 0.00000:00:01
147.45.112.6 0.028 0.024 0.00000:00:01
91.238.181.20 0.080 0.033 0.00000:00:01
171.211.203.35.bc.googleusercontent.com 0.026 0.017 0.00000:00:01
139.150.216.162.bc.googleusercontent.com 0.063 0.028 0.00000:00:00
198.235.24.167 0.008 0.015 0.00000:00:00
206.168.34.33 0.044 0.024 0.00000:00:00
TOTAL: 14 6.411 5.637 1.77500:29:11

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
2a0e:e701:1154:0:ade1:e632:7abc:9e8e 5
91.238.181.20 2
147.45.112.6 1
198.235.24.167 1
206.168.34.33 1
139.150.216.162.bc.googleusercontent.com 1
171.211.203.35.bc.googleusercontent.com 1
acclaimed.monitoring.internet-measurement.com 1
198.235.24.88 1
server-0-3.survey.inspici.com 1
205.210.31.24 1
resounding.monitoring.internet-measurement.com 1
TOTAL: 13 305

NNRP unrecognized commands (by host):

SystemConn
171.211.203.35.bc.googleusercontent.com 4
139.150.216.162.bc.googleusercontent.com 4
147.45.112.6 1
206.168.34.33 1
TOTAL: 4 10

NNRP unrecognized commands (by command):

CommandCount
Accept-Encoding: gzip 2
User-Agent: Expanse, a Palo Alto Network... 2
GET / HTTP/1.1 2
Host: 45.13.105.36:563 2
EHLO www.censys.io 1
#003 1
TOTAL: 6 10

NNRP client timeouts [Top 20]:

SystemConnPeer
147.45.112.6 1 1
TOTAL: 1 1 2

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 1260 84.2%
2local 236 15.8%
TOTAL: 2 1496100.0%

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

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