Daily Usenet report for news.niel.me

Apr 24 04:15:01 -- Apr 25 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Apr 24 11:12:25 rpi-studio innd: filter: No Cancel-Key[sha1:SJinDiH2k8nM/YX13B5KMXQ0Jsw=] matches Cancel-Lock[sha1:304FM5FKY+Iw7vpIl4J5TxVWjIQ=] target=<6809ffd9$0$28067$426a74cc@news.free.fr>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2353 27.9%414.5 KB 31.6%
innd 2166 25.7%371.6 KB 28.3%
innfeed 2017 23.9%308.2 KB 23.5%
nnrpd 1905 22.6%218.1 KB 16.6%
controlchan 1 0.0%0.0 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 6 8443 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 10323 55.6%
Negative hits 5900 31.8%
Do not exist 2336 12.6%
Cache misses 20 0.1%
TOTAL: 4 18579 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.008 0.0%6 1.000 1.333 2.000
article cancel00:00:00.000 0.0%6 0.000 0.000 0.000
article cleanup00:00:00.049 0.0%2355 0.000 0.021 0.333
article logging00:00:00.172 0.0%2355 0.000 0.073 0.267
article parse00:00:00.363 0.0%5937 0.000 0.061 0.333
article write00:00:00.925 0.0%1843 0.000 0.502 1.333
artlog/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%6 0.000 0.167 1.000
data move00:00:00.122 0.0%20896 0.000 0.006 0.054
hisgrep/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%9 0.000 0.111 1.000
hishave/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%6 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.333 0.0%18642 0.000 0.018 0.070
history sync00:00:00.052 0.0%362 0.000 0.144 1.000
history write00:00:00.469 0.0%2355 0.000 0.199 0.562
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:39.896100.0%22411 1361.102 3857.030 20003.333
nntp read00:00:00.620 0.0%21690 0.000 0.029 0.094
overview write00:00:00.568 0.0%1843 0.000 0.308 5.500
perl filter00:00:12.843 0.0%2355 1.895 5.454 19.833
python filter00:00:00.008 0.0%2355 0.000 0.003 0.333
site send00:00:00.572 0.0%3775 0.000 0.152 0.500
TOTAL: 24:01:05.93424:00:57.002100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.023 0.0%2507 0.000 0.009 0.133
article prepare00:00:00.001 0.0%256 0.000 0.004 0.250
article read00:00:00.017 0.0%64 0.000 0.266 8.000
backlog stats00:00:00.125 0.0%25475 0.000 0.005 0.056
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.382 0.0%13182 0.000 0.029 0.105
data write00:00:00.630 0.0%11486 0.000 0.055 0.214
idle23:50:31.653100.0%25475 1209.367 3369.250 10171.271
status file00:00:07.747 0.0%284 1.000 27.278 87.000
TOTAL: 23:50:44.14023:50:40.578100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:25.445 16.5%1461 9.600 17.416 2195.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.130 0.1%3269 0.000 0.040 1.000
readart00:00:00.000 0.0%1 0.000 0.000 0.000
TOTAL: 00:02:34.58500:00:25.575 16.5%----

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.nntp4.net 2 2286 727 1087 472 31%23:59:35
2feed-out.gegeweb.org 4 1800 479 1321 0 26%23:59:38
3news.glou.org 6 1381 117 1252 12 8%23:48:34
4gegeweb-out.news.tnetconsulting.net 1 273 25 245 3 9%07:55:00
5nntp.terraraq.uk 5 141 18 119 4 12%05:14:40
6news.samoylyk.net 2 160 14 146 0 8%04:16:18
7i2pn.org 1 156 9 147 0 5%03:15:38
8usenet.pasdenom.info 32 757 0 757 0 0%22:05:28
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 78 6954 1389 5074 491 19%114:34:51
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.nntp4.net3.5 MB0.0 KB4.0 MB7.5 MB 46%6.4 KB
2feed-out.gegeweb.org1.5 MB0.0 KB0.0 KB1.5 MB100%3.2 KB
3news.glou.org418.8 KB0.0 KB43.7 KB462.4 KB 90%3.6 KB
4news.samoylyk.net94.0 KB0.0 KB0.0 KB94.0 KB100%6.7 KB
5gegeweb-out.news.tnetconsulting.net81.4 KB0.0 KB6.4 KB87.9 KB 92%3.1 KB
6nntp.terraraq.uk32.1 KB0.0 KB8.6 KB40.7 KB 78%1.8 KB
7i2pn.org21.9 KB0.0 KB0.0 KB21.9 KB100%2.4 KB
8usenet.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 95.6 MB0.0 KB4.1 MB9.7 MB 58%5.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 24 04:15:01 - 04:59:59 36 2.0% 0.0198.2 KB 1.3% 0.04
Apr 24 05:00:00 - 05:59:59 37 2.0% 0.01104.8 KB 1.4% 0.03
Apr 24 06:00:00 - 06:59:59 26 1.4% 0.0171.9 KB 1.0% 0.02
Apr 24 07:00:00 - 07:59:59 35 1.9% 0.0197.1 KB 1.3% 0.03
Apr 24 08:00:00 - 08:59:59 26 1.4% 0.01101.4 KB 1.4% 0.03
Apr 24 09:00:00 - 09:59:59 61 3.3% 0.02229.8 KB 3.1% 0.06
Apr 24 10:00:00 - 10:59:59 68 3.7% 0.02252.4 KB 3.4% 0.07
Apr 24 11:00:00 - 11:59:59 45 2.4% 0.01150.6 KB 2.0% 0.04
Apr 24 12:00:00 - 12:59:59 94 5.1% 0.03378.2 KB 5.1% 0.11
Apr 24 13:00:00 - 13:59:59 80 4.3% 0.02260.9 KB 3.5% 0.07
Apr 24 14:00:00 - 14:59:59 118 6.4% 0.03611.1 KB 8.3% 0.17
Apr 24 15:00:00 - 15:59:59 107 5.8% 0.03741.9 KB 10.1% 0.21
Apr 24 16:00:00 - 16:59:59 83 4.5% 0.02297.9 KB 4.0% 0.08
Apr 24 17:00:00 - 17:59:59 89 4.8% 0.02448.0 KB 6.1% 0.12
Apr 24 18:00:00 - 18:59:59 77 4.2% 0.02246.0 KB 3.3% 0.07
Apr 24 19:00:00 - 19:59:59 95 5.2% 0.03323.2 KB 4.4% 0.09
Apr 24 20:00:00 - 20:59:59 123 6.7% 0.03443.5 KB 6.0% 0.12
Apr 24 21:00:00 - 21:59:59 121 6.6% 0.03460.3 KB 6.2% 0.13
Apr 24 22:00:00 - 22:59:59 98 5.3% 0.03349.8 KB 4.7% 0.10
Apr 24 23:00:00 - 23:59:59 110 6.0% 0.03378.4 KB 5.1% 0.11
Apr 25 00:00:00 - 00:59:59 58 3.1% 0.02255.4 KB 3.5% 0.07
Apr 25 01:00:00 - 01:59:59 82 4.5% 0.02309.3 KB 4.2% 0.09
Apr 25 02:00:00 - 02:59:59 84 4.6% 0.02416.0 KB 5.6% 0.12
Apr 25 03:00:00 - 03:59:59 77 4.2% 0.02312.5 KB 4.2% 0.09
Apr 25 04:00:00 - 04:15:02 12 0.7% 0.0127.7 KB 0.4% 0.03
TOTAL: 24:00:01 1842 100.0% 0.027.2 MB 100.0% 0.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 472 362 0 0 0 0 0 0 110
2news.glou.org 12 0 0 0 0 0 0 0 12
3nntp.terraraq.uk 6 0 0 0 0 0 0 0 6
4gegeweb-out.news.tnetconsulting.net 5 0 0 0 0 0 0 0 5
5i2pn.org 3 0 0 0 0 0 0 0 3
6news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 6 499 362 0 0 0 0 0 0 137

Unwanted newsgroups [Top 20]:

NewsgroupCount
linux.kernel.netdev 301
linux.kernel.git 57
soc.culture.russia 2
soc.culture.iran 1
news.test 1
TOTAL: 5 362

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 90
Bad path (news.giganews.com.POSTED) 43
Cancel of <vuduf8$5ll$2@news.chmurka.net> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<6809ffd9$0$28067$426a74cc@news.free.fr> 1
Cancel of non-existing ID <m6ugk2F7rnbU1@mid.individual.net> 1
EMP (md5) 1
TOTAL: 6 137

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 2526 24 1697 3 0 13 0%23:58:00
2news.gegeweb.org 1442 22 1333 0 0 0 1%23:45:12
3news.tnetconsulting.net 2692 12 1826 2 0 23 0%23:59:45
4news.samoylyk.net 1863 1 1708 0 0 633 0%23:57:21
5nntp.terraraq.uk 1072 0 964 0 0 0 0%23:44:26
6news.aioe.org 0 0 0 0 0 1606 0%24:00:01
7news.pasdenom.info 1375 0 1330 0 0 0 0%23:56:37
8news.nntp4.net 1162 0 1100 0 0 0 0%23:32:26
TOTAL: 8 12132 59 9958 5 0 2275 0%190:53:48
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org170.3 KB8.1 KB178.5 KB0.0 KB/s6.6 KB23:58:00
2news.gegeweb.org53.9 KB0.0 KB53.9 KB0.0 KB/s2.4 KB23:45:12
3news.tnetconsulting.net31.7 KB5.4 KB37.1 KB0.0 KB/s2.6 KB23:59:45
4news.samoylyk.net3.0 KB0.0 KB3.0 KB0.0 KB/s3.0 KB23:57:21
5nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:44:26
6news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
7news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:37
8news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:32:26
TOTAL: 8258.9 KB13.6 KB272.4 KB0.0 KB/s4.3 KB190:53:48
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 2 10.7 KB 1 0 000:00:02
TOTAL: 1 2 10.7 KB 1 0 000:02:34

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 2 10.7 KB 1 0 000:00:02
2? 6 00.0 KB 0 0 000:00:10
3*.211.203.35.bc.googleusercontent.com 2 00.0 KB 0 0 000:00:02
4*.stretchoid.com 1 00.0 KB 0 0 000:00:01
5*.gegeweb.eu 288 00.0 KB 0 0 000:01:55
6unresolved 10 00.0 KB 0 0 000:00:12
7*.150.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
8*.monitoring.internet-measurement.com 5 00.0 KB 0 0 000:00:08
TOTAL: 8 315 10.7 KB 1 0 000:02:34

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.gegeweb.eu 4.654 4.412 1.90100:01:55
2a06:4882:9000::ac 0.028 0.012 0.00000:00:04
reassuring.monitoring.internet-measurement.com 0.047 0.015 0.00000:00:03
206.168.34.115 0.043 0.017 0.00000:00:03
2a06:4882:9000::ae 0.048 0.000 0.00000:00:03
2a06:4882:1000::1f 0.019 0.004 0.00000:00:03
prosperous.monitoring.internet-measurement.com 0.055 0.035 0.00000:00:03
205.210.31.137 0.010 0.014 0.00000:00:02
205.210.31.34 0.011 0.011 0.00000:00:02
syn-066-061-096-239.res.spectrum.com 0.057 0.050 0.00000:00:02
206.168.34.82 0.067 0.021 0.00000:00:01
azpdsga8kzg1.stretchoid.com 0.019 0.028 0.00000:00:01
199.211.203.35.bc.googleusercontent.com 0.055 0.032 0.00000:00:01
94.211.203.35.bc.googleusercontent.com 0.012 0.024 0.00000:00:00
193.150.216.162.bc.googleusercontent.com 0.081 0.008 0.00000:00:00
194.165.16.166 0.015 0.007 0.00000:00:00
20.98.167.222 0.030 0.022 0.00000:00:00
untiring.monitoring.internet-measurement.com 0.038 0.021 0.00000:00:00
calm.monitoring.internet-measurement.com 0.030 0.026 0.00000:00:00
194.164.107.5 0.008 0.015 0.00000:00:00
TOTAL: 25 5.457 4.840 1.90100:02:34

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
206.168.34.82 3
prosperous.monitoring.internet-measurement.com 1
94.211.203.35.bc.googleusercontent.com 1
reassuring.monitoring.internet-measurement.com 1
2a06:4882:9000::ac 1
199.211.203.35.bc.googleusercontent.com 1
194.165.16.166 1
untiring.monitoring.internet-measurement.com 1
2a06:4882:1000::1f 1
194.164.107.5 1
205.210.31.137 1
azpdsga8kzg1.stretchoid.com 1
194.165.16.167 1
20.98.167.222 1
193.150.216.162.bc.googleusercontent.com 1
2a06:4882:3000::45 1
205.210.31.34 1
206.168.34.115 1
2a06:4882:3000::32 1
TOTAL: 24 313

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
94.211.203.35.bc.googleusercontent.com 4
193.150.216.162.bc.googleusercontent.com 4
199.211.203.35.bc.googleusercontent.com 4
20.98.167.222 1
azpdsga8kzg1.stretchoid.com 1
206.168.34.115 1
194.165.16.166 1
TOTAL: 7 16

NNRP unrecognized commands (by command):

CommandCount
User-Agent: Expanse, a Palo Alto Network... 3
GET / HTTP/1.1 3
Accept-Encoding: gzip 3
Host: 45.13.105.36:563 2
MGLNDD_45.13.105.36_119 2
Host: 45.13.105.36:119 1
EHLO www.censys.io 1
#003 1
TOTAL: 8 16

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.166 1 1
TOTAL: 1 1 3

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 1100.0%
TOTAL: 1 1100.0%

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

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