Daily Usenet report for news.niel.me

Sep 5 04:15:01 -- Sep 6 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 5 21:43:01 rpi-studio innd: filter: No Cancel-Key[sha1:fm1sYXic5EVTI2/vdcnYerllDZA=] matches Cancel-Lock[sha1:OMbfjd4M2MqJ2nBP54FH+QQQ7+Y=] target=<66da0429$0$1768$426a74cc@news.free.fr>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2489 29.3%462.4 KB 34.1%
innd 2235 26.3%393.9 KB 29.1%
nnrpd 1952 23.0%227.6 KB 16.8%
innfeed 1813 21.4%271.6 KB 20.0%
nocem 1 0.0%0.2 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 8491 100.0%1.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13102 58.7%
Negative hits 6736 30.2%
Do not exist 2464 11.0%
Cache misses 2 0.0%
TOTAL: 4 22304 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.012 0.0%11 1.000 1.091 2.000
article cancel00:00:00.005 0.0%11 0.000 0.455 1.000
article cleanup00:00:00.056 0.0%2468 0.000 0.023 0.250
article logging00:00:00.182 0.0%2469 0.000 0.074 0.500
article parse00:00:00.357 0.0%5587 0.000 0.064 0.333
article write00:00:01.212 0.0%2171 0.000 0.558 1.500
artlog/artcncl00:00:00.000 0.0%11 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.000 0.0%11 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.118 0.0%23631 0.000 0.005 0.043
hisgrep/artcncl00:00:00.000 0.0%11 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.001 0.0%11 0.000 0.091 1.000
hisgrep/perl00:00:00.000 0.0%17 0.000 0.000 0.000
hishave/artcncl00:00:00.000 0.0%11 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.001 0.0%11 0.000 0.091 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.337 0.0%22321 0.000 0.015 0.054
history sync00:00:00.062 0.0%387 0.000 0.160 1.000
history write00:00:00.442 0.0%2468 0.000 0.179 0.600
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle24:00:39.999100.0%25749 1178.603 3357.024 42888.857
nntp read00:00:00.695 0.0%25104 0.000 0.028 0.147
overview write00:00:00.743 0.0%2171 0.000 0.342 4.000
perl filter00:00:12.536 0.0%2468 2.438 5.079 16.000
python filter00:00:00.017 0.0%2468 0.000 0.007 0.071
site send00:00:01.023 0.0%4378 0.000 0.234 0.533
TOTAL: 24:01:06.87824:00:57.798100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.021 0.0%2172 0.000 0.010 0.200
article prepare00:00:00.005 0.0%1016 0.000 0.005 0.125
article read00:00:00.039 0.0%248 0.000 0.157 1.000
backlog stats00:00:00.193 0.0%32653 0.000 0.006 0.033
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.439 0.0%17561 0.000 0.025 0.133
data write00:00:00.949 0.0%15544 0.000 0.061 0.150
idle23:57:16.283100.0%32653 797.539 2640.991 9226.354
status file00:00:04.532 0.0%247 0.000 18.348 63.000
TOTAL: 23:57:27.59323:57:22.461100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:35:37.121 94.3%11350 3.000 188.293 10041.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.495 0.1%42044 0.000 0.036 0.500
readart00:00:01.336 0.1%9479 0.130 0.141 0.187
TOTAL: 00:37:46.83100:35:39.952 94.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
1feed-out.gegeweb.org 3 2047 1036 1010 1 50%23:52:59
2news.samoylyk.net 4 3285 371 2914 0 11%32:03:42
3gegeweb-out.news.tnetconsulting.net 2 1812 280 1469 63 15%22:40:55
4i2pn.org 3 2024 190 1821 13 9%20:52:23
5news.nntp4.net 2 2017 148 1693 176 7%21:00:44
6nntp.terraraq.uk 2 1523 29 1493 1 1%21:04:43
7news.glou.org 3 39 6 33 0 15%01:29:30
8usenet.pasdenom.info 4 78 1 76 1 1%04:12:16
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 48 12825 2061 10509 255 16%147:17:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org3.6 MB0.0 KB5.7 KB3.6 MB 99%3.5 KB
2news.samoylyk.net1.8 MB0.0 KB0.0 KB1.8 MB100%5.1 KB
3gegeweb-out.news.tnetconsulting.net917.2 KB0.0 KB208.5 KB1.1 MB 81%3.3 KB
4news.nntp4.net596.2 KB0.0 KB1.6 MB2.2 MB 26%7.0 KB
5i2pn.org537.5 KB0.0 KB146.7 KB684.1 KB 78%3.4 KB
6nntp.terraraq.uk191.2 KB0.0 KB11.5 KB202.7 KB 94%6.8 KB
7news.glou.org25.3 KB0.0 KB0.0 KB25.3 KB100%4.2 KB
8usenet.pasdenom.info2.7 KB0.0 KB1.5 KB4.2 KB 64%2.1 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 97.6 MB0.0 KB2.0 MB9.6 MB 79%4.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 5 04:15:01 - 04:59:59 32 1.5% 0.01142.5 KB 1.8% 0.05
Sep 5 05:00:00 - 05:59:59 24 1.1% 0.01155.4 KB 2.0% 0.04
Sep 5 06:00:00 - 06:59:59 76 3.5% 0.02338.3 KB 4.4% 0.09
Sep 5 07:00:00 - 07:59:59 32 1.5% 0.01113.9 KB 1.5% 0.03
Sep 5 08:00:00 - 08:59:59 61 2.8% 0.02260.9 KB 3.4% 0.07
Sep 5 09:00:00 - 09:59:59 66 3.0% 0.02223.6 KB 2.9% 0.06
Sep 5 10:00:00 - 10:59:59 96 4.4% 0.03318.6 KB 4.1% 0.09
Sep 5 11:00:00 - 11:59:59 86 4.0% 0.02260.6 KB 3.4% 0.07
Sep 5 12:00:00 - 12:59:59 84 3.9% 0.02271.0 KB 3.5% 0.08
Sep 5 13:00:00 - 13:59:59 77 3.6% 0.02235.5 KB 3.0% 0.07
Sep 5 14:00:00 - 14:59:59 91 4.2% 0.03351.4 KB 4.5% 0.10
Sep 5 15:00:00 - 15:59:59 92 4.2% 0.03318.9 KB 4.1% 0.09
Sep 5 16:00:00 - 16:59:59 112 5.2% 0.03302.1 KB 3.9% 0.08
Sep 5 17:00:00 - 17:59:59 147 6.8% 0.04574.2 KB 7.4% 0.16
Sep 5 18:00:00 - 18:59:59 110 5.1% 0.03405.4 KB 5.2% 0.11
Sep 5 19:00:00 - 19:59:59 126 5.8% 0.04464.4 KB 6.0% 0.13
Sep 5 20:00:00 - 20:59:59 144 6.6% 0.04498.7 KB 6.4% 0.14
Sep 5 21:00:00 - 21:59:59 124 5.7% 0.03393.8 KB 5.1% 0.11
Sep 5 22:00:00 - 22:59:59 112 5.2% 0.03483.0 KB 6.2% 0.13
Sep 5 23:00:00 - 23:59:59 117 5.4% 0.03360.4 KB 4.6% 0.10
Sep 6 00:00:00 - 00:59:59 125 5.8% 0.03501.9 KB 6.5% 0.14
Sep 6 01:00:00 - 01:59:59 91 4.2% 0.03321.9 KB 4.1% 0.09
Sep 6 02:00:00 - 02:59:59 65 3.0% 0.02204.4 KB 2.6% 0.06
Sep 6 03:00:00 - 03:59:59 67 3.1% 0.02247.0 KB 3.2% 0.07
Sep 6 04:00:00 - 04:15:01 12 0.6% 0.0125.0 KB 0.3% 0.03
TOTAL: 24:00:00 2169 100.0% 0.037.6 MB 100.0% 0.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nntp4.net 181 0 0 0 0 0 0 0 181
2gegeweb-out.news.tnetconsulting.net 50 0 0 0 0 0 0 0 50
3news.glou.org 47 0 0 0 0 0 0 0 47
4usenet.pasdenom.info 7 0 0 0 0 0 0 0 7
5i2pn.org 7 0 0 0 0 0 0 0 7
6nntp.terraraq.uk 4 0 0 0 0 0 0 0 4
7feed-out.gegeweb.org 2 0 0 0 0 0 0 0 2
TOTAL: 7 298 0 0 0 0 0 0 0 298

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 110
EMP (md5) 91
Bad path (news.giganews.com.POSTED) 52
EMP (phn path) 38
Cancel of <a28a73655594871c1715029148d5dca3fbf45362@i2pn2.org> without Cancel-Key 1
Cancel of non-existing ID <vbc14t$a4t4$1@dont-email.me> 1
Cancel of <bcc7ebd98aab5bcb5e5482071cfa819f556a7eac@i2pn2.org> without Cancel-Key 1
No Cancel-Key matches Cancel-Lock. target=<66da0429$0$1768$426a74cc@news.free.fr> 1
Cancel of <6faf6d28ad1307887cbed5e346bd275a1d9d5abf@i2pn2.org> without Cancel-Key 1
Cancel of <7d44cdf33a5463b5c8d86476a6a00eccdddd2182@i2pn2.org> without Cancel-Key 1
TOTAL: 10 297

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 3155 116 1892 1 0 1 3%23:54:53
2news.gegeweb.org 1169 94 1037 0 0 0 8%23:42:14
3news.samoylyk.net 2257 24 1853 14 0 3 1%23:54:52
4news.tnetconsulting.net 3522 1 2163 4 0 0 0%23:55:01
5news.nntp4.net 2133 0 1991 0 0 0 0%23:54:54
6news.pasdenom.info 1906 0 1732 0 0 0 0%23:45:45
7news.aioe.org 0 0 0 0 0 1778 0%24:00:00
8nntp.terraraq.uk 1420 0 1399 0 0 0 0%23:44:38
TOTAL: 8 15562 235 12067 19 0 1782 1%190:52:17
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org462.6 KB1.3 KB463.9 KB0.0 KB/s4.0 KB23:54:53
2news.gegeweb.org264.5 KB0.0 KB264.5 KB0.0 KB/s2.8 KB23:42:14
3news.samoylyk.net86.3 KB78.0 KB164.3 KB0.0 KB/s4.3 KB23:54:52
4news.tnetconsulting.net2.2 KB22.1 KB24.3 KB0.0 KB/s4.9 KB23:55:01
5news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:54:54
6news.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB/s023:45:45
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:44:38
TOTAL: 8815.7 KB101.4 KB917.1 KB0.0 KB/s3.6 KB190:52:17
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
169.10.48.174 8 947916.6 MB 20 0 000:34:39
TOTAL: 1 8 947916.6 MB 20 0 000:37:46

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 28 947916.6 MB 20 0 000:35:37
2*.survey.inspici.com 1 00.0 KB 0 0 000:00:05
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.gegeweb.eu 288 00.0 KB 0 0 000:01:50
5*.210.203.35.bc.googleusercontent.com 2 00.0 KB 0 0 000:00:01
TOTAL: 5 321 947916.6 MB 20 0 000:37:46

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
69.10.48.174 1.562 3.779 0.00000:34:39
mail.gegeweb.eu 5.166 4.574 1.71800:01:50
147.185.133.17 0.073 0.020 0.00000:00:12
104.152.52.214 0.026 0.026 0.00000:00:11
azpdss43.stretchoid.com 0.013 0.009 0.00000:00:09
206.168.34.38 0.045 0.021 0.00000:00:08
206.168.34.122 0.058 0.009 0.00000:00:07
server-0-3.survey.inspici.com 0.009 0.014 0.00000:00:05
198.235.24.179 0.023 0.019 0.00000:00:05
208.100.26.244 0.191 0.109 0.00000:00:04
198.235.24.13 0.029 0.020 0.00000:00:02
206.168.34.113 0.045 0.022 0.00000:00:01
244.210.203.35.bc.googleusercontent.com 0.068 0.021 0.00000:00:00
azpdsgc11.stretchoid.com 0.015 0.008 0.00000:00:00
206.168.34.217 0.041 0.020 0.00000:00:00
91.238.181.71 0.022 0.029 0.00000:00:00
217.210.203.35.bc.googleusercontent.com 0.040 0.011 0.00000:00:00
104.152.52.133 0.029 0.008 0.00000:00:00
TOTAL: 18 7.455 8.719 1.71800:37:46

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
208.100.26.244 10
198.235.24.179 1
azpdss43.stretchoid.com 1
198.235.24.13 1
217.210.203.35.bc.googleusercontent.com 1
206.168.34.113 1
206.168.34.122 1
206.168.34.217 1
244.210.203.35.bc.googleusercontent.com 1
104.152.52.133 1
206.168.34.38 1
91.238.181.71 1
104.152.52.214 1
server-0-3.survey.inspici.com 1
azpdsgc11.stretchoid.com 1
147.185.133.17 1
TOTAL: 17 313

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
147.185.133.17 4
244.210.203.35.bc.googleusercontent.com 4
217.210.203.35.bc.googleusercontent.com 4
azpdsgc11.stretchoid.com 1
206.168.34.122 1
206.168.34.217 1
206.168.34.113 1
91.238.181.71 1
206.168.34.38 1
TOTAL: 9 18

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
91.238.181.71 1 1
TOTAL: 1 1 3

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 8333 87.9%
2local 1146 12.1%
TOTAL: 2 9479100.0%

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

NewsgroupCount
1grisbi.bugsreports 2248
2grisbi.user.french 2000
3grisbi.test 1997
4grisbi.devel 1788
5local.test 1022
6grisbi.cvs 192
7local.gegeweb.support 124
8grisbi.user 102
9grisbi.admin 6
TOTAL: 9 9479
innreport from INN 2.6.4 based on work (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>
Local contact: usenet@niel.me