Daily Usenet report for news.niel.me

Dec 1 04:15:01 -- Dec 2 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 1 20:37:00 rpi-studio innd: filter: No Cancel-Key[sha1:iogTmmOX7+b4g5VuA9EXMQEIbW4= sha256:QYl7YA0819TaXsCWJrCy6/IwrLX+Dlnwtlq+KOXKQ/8=] matches Cancel-Lock[sha1:ahzveLnkqlODHlroaWmt1Faru3o= sha256:JArLPKo7VWQpvTA9W8CwleTUoKG3khjSQl3938WMGrQ=] target=<vhpp7q$s79$1@rasp.pasdenom.info>

Log entries by program:

Program nameLines%LinesSize%Size
inn 2405 27.9%473.2 KB 34.1%
nnrpd 2275 26.4%262.9 KB 18.9%
innd 2164 25.1%369.0 KB 26.6%
innfeed 1764 20.5%283.3 KB 20.4%
controlchan 1 0.0%0.0 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 6 8610 100.0%1.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 13386 56.1%
Negative hits 8084 33.9%
Do not exist 2370 9.9%
Cache misses 12 0.1%
TOTAL: 4 23852 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.016 0.0%12 0.500 1.333 6.000
article cancel00:00:00.001 0.0%15 0.000 0.067 1.000
article cleanup00:00:00.048 0.0%2374 0.000 0.020 0.333
article logging00:00:00.143 0.0%2378 0.000 0.060 0.250
article parse00:00:00.325 0.0%5026 0.000 0.065 0.250
article write00:00:00.992 0.0%2180 0.000 0.455 1.250
artlog/artcncl00:00:00.000 0.0%15 0.000 0.000 0.000
artlog/artcncl/perl00:00:00.001 0.0%12 0.000 0.083 1.000
data move00:00:00.104 0.0%24954 0.000 0.004 0.034
hisgrep/artcncl00:00:00.001 0.0%13 0.000 0.077 1.000
hisgrep/artcncl/perl00:00:00.000 0.0%12 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%15 0.000 0.067 1.000
hishave/artcncl00:00:00.000 0.0%15 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%12 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.318 0.0%23903 0.000 0.013 0.050
history sync00:00:00.052 0.0%395 0.000 0.132 1.000
history write00:00:00.414 0.0%2374 0.000 0.174 0.750
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle24:00:46.638100.0%27321 1001.586 3164.110 30020.300
nntp read00:00:00.633 0.0%26262 0.000 0.024 0.079
overview write00:00:00.656 0.0%2180 0.000 0.301 3.250
perl filter00:00:09.475 0.0%2374 2.000 3.991 12.600
python filter00:00:00.015 0.0%2374 0.000 0.006 0.200
site send00:00:00.789 0.0%4380 0.000 0.180 0.625
TOTAL: 24:01:09.28024:01:00.622100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.016 0.0%2210 0.000 0.007 0.200
article prepare00:00:00.003 0.0%580 0.000 0.005 0.125
article read00:00:00.025 0.0%132 0.000 0.189 4.000
backlog stats00:00:00.151 0.0%30067 0.000 0.005 0.030
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.380 0.0%16840 0.000 0.023 0.071
data write00:00:00.783 0.0%15045 0.000 0.052 0.111
idle23:50:13.191100.0%30067 1089.412 2854.066 9123.394
status file00:00:01.427 0.0%208 0.000 6.861 31.000
TOTAL: 23:50:20.44723:50:15.976100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:07:36.624 97.5%13254 4.500 306.068 10014.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.852 0.0%49479 0.000 0.037 0.500
readart00:00:01.580 0.0%11273 0.139 0.140 3.000
TOTAL: 01:09:20.56301:07:40.056 97.6%----

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
1gegeweb-out.news.tnetconsulting.net 2 2019 1054 917 48 52%25:40:55
2feed-out.gegeweb.org 3 2089 486 1603 0 23%23:59:28
3i2pn.org 2 4149 404 3745 0 9%46:27:01
4news.nntp4.net 3 1732 163 1525 44 9%18:49:29
5nntp.terraraq.uk 6 3421 89 3332 0 2%41:38:29
6usenet.pasdenom.info 7 1281 74 1197 10 5%22:48:38
7news.glou.org 2 1787 64 1651 72 3%23:59:07
8news.samoylyk.net 2 195 12 182 1 6%04:27:12
9localhost 25 0 0 0 0 0%00:00:00
TOTAL: 9 52 16673 2346 14152 175 14%207:50:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1gegeweb-out.news.tnetconsulting.net3.9 MB0.0 KB195.9 KB4.1 MB 95%3.8 KB
2feed-out.gegeweb.org1.8 MB0.0 KB0.0 KB1.8 MB100%3.8 KB
3news.nntp4.net979.1 KB0.0 KB122.8 KB1.1 MB 88%5.3 KB
4i2pn.org916.4 KB0.0 KB0.0 KB916.4 KB100%2.3 KB
5nntp.terraraq.uk354.6 KB0.0 KB0.0 KB354.6 KB100%4.0 KB
6news.glou.org223.8 KB0.0 KB322.6 KB546.4 KB 40%4.0 KB
7usenet.pasdenom.info160.7 KB0.0 KB12.4 KB173.1 KB 92%2.1 KB
8news.samoylyk.net51.9 KB0.0 KB29.0 KB80.9 KB 64%6.2 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 98.3 MB0.0 KB682.7 KB9.0 MB 92%3.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 1 04:15:01 - 04:59:59 39 1.8% 0.01112.9 KB 1.3% 0.04
Dec 1 05:00:00 - 05:59:59 36 1.7% 0.0193.4 KB 1.1% 0.03
Dec 1 06:00:00 - 06:59:59 32 1.5% 0.01103.8 KB 1.2% 0.03
Dec 1 07:00:00 - 07:59:59 35 1.6% 0.01143.0 KB 1.7% 0.04
Dec 1 08:00:00 - 08:59:59 23 1.1% 0.0177.2 KB 0.9% 0.02
Dec 1 09:00:00 - 09:59:59 46 2.1% 0.01186.2 KB 2.2% 0.05
Dec 1 10:00:00 - 10:59:59 75 3.4% 0.02268.6 KB 3.1% 0.07
Dec 1 11:00:00 - 11:59:59 111 5.1% 0.03448.3 KB 5.2% 0.12
Dec 1 12:00:00 - 12:59:59 115 5.3% 0.03686.4 KB 8.0% 0.19
Dec 1 13:00:00 - 13:59:59 128 5.9% 0.04529.0 KB 6.1% 0.15
Dec 1 14:00:00 - 14:59:59 109 5.0% 0.03364.9 KB 4.2% 0.10
Dec 1 15:00:00 - 15:59:59 82 3.8% 0.02316.4 KB 3.7% 0.09
Dec 1 16:00:00 - 16:59:59 116 5.3% 0.03363.0 KB 4.2% 0.10
Dec 1 17:00:00 - 17:59:59 119 5.5% 0.03420.6 KB 4.9% 0.12
Dec 1 18:00:00 - 18:59:59 136 6.2% 0.04590.2 KB 6.9% 0.16
Dec 1 19:00:00 - 19:59:59 143 6.6% 0.04444.4 KB 5.2% 0.12
Dec 1 20:00:00 - 20:59:59 125 5.7% 0.03401.1 KB 4.7% 0.11
Dec 1 21:00:00 - 21:59:59 135 6.2% 0.04580.5 KB 6.7% 0.16
Dec 1 22:00:00 - 22:59:59 141 6.5% 0.04490.3 KB 5.7% 0.14
Dec 1 23:00:00 - 23:59:59 141 6.5% 0.04477.6 KB 5.6% 0.13
Dec 2 00:00:00 - 00:59:59 73 3.3% 0.02273.9 KB 3.2% 0.08
Dec 2 01:00:00 - 01:59:59 51 2.3% 0.01198.0 KB 2.3% 0.06
Dec 2 02:00:00 - 02:59:59 74 3.4% 0.02231.6 KB 2.7% 0.06
Dec 2 03:00:00 - 03:59:59 78 3.6% 0.02332.2 KB 3.9% 0.09
Dec 2 04:00:00 - 04:15:01 17 0.8% 0.02467.7 KB 5.4% 0.52
TOTAL: 24:00:00 2180 100.0% 0.038.4 MB 100.0% 0.10
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.glou.org 72 0 0 0 0 0 0 0 72
2news.nntp4.net 62 0 0 0 0 0 0 0 62
3gegeweb-out.news.tnetconsulting.net 43 0 0 0 0 0 0 0 43
4usenet.pasdenom.info 11 0 0 0 0 0 0 0 11
5nntp.terraraq.uk 6 0 0 0 0 0 0 0 6
6feed-out.gegeweb.org 3 0 0 0 0 0 0 0 3
7news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 7 198 0 0 0 0 0 0 0 198

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 81
Bad path (news.giganews.com.POSTED) 56
Too many newsgroups 48
EMP (md5) 5
Cancels abusifs 4
TOTAL: 5 194

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed-out.gegeweb.org 3
nntp.terraraq.uk 1
TOTAL: 2 4
TOTAL: 1 4

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.gegeweb.org 1836 50 1629 0 0 0 2%23:53:29
2i2pn.org 2762 31 1915 0 0 12 1%23:55:58
3news.tnetconsulting.net 2887 7 2165 4 0 13 0%23:59:33
4news.pasdenom.info 1738 4 1659 1 0 0 0%23:59:57
5news.nntp4.net 2153 2 1993 0 0 0 0%23:56:22
6news.samoylyk.net 2794 1 2037 45 0 16 0%23:59:45
7nntp.terraraq.uk 1326 0 1287 0 0 1 0%23:56:25
8news.aioe.org 0 0 0 0 0 1827 0%24:00:00
TOTAL: 8 15496 95 12685 50 0 1869 0%191:41:29
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.gegeweb.org184.2 KB0.0 KB184.2 KB0.0 KB/s3.7 KB23:53:29
2i2pn.org102.4 KB0.0 KB102.4 KB0.0 KB/s3.3 KB23:55:58
3news.nntp4.net26.4 KB0.0 KB26.4 KB0.0 KB/s13.2 KB23:56:22
4news.tnetconsulting.net24.6 KB16.7 KB41.4 KB0.0 KB/s3.8 KB23:59:33
5news.pasdenom.info14.9 KB10.8 KB25.7 KB0.0 KB/s5.1 KB23:59:57
6news.samoylyk.net4.2 KB195.5 KB199.7 KB0.0 KB/s4.3 KB23:59:45
7nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:56:25
8news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
TOTAL: 8356.7 KB223.1 KB579.8 KB0.0 KB/s4.0 KB191:41:29
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1162.250.122.68 12 1127118.1 MB 11 0 001:06:50
TOTAL: 1 12 1127118.1 MB 11 0 001:09:19

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 18 1127118.1 MB 11 0 001:07:13
2*.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
3*.subs.proxad.net 1 00.0 KB 0 0 000:00:00
4? 2 00.0 KB 0 0 000:00:01
5*.ip.linodeusercontent.com 115 00.0 KB 0 0 000:00:26
6*.stretchoid.com 2 00.0 KB 0 0 000:00:10
7*.150.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
8*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
9*.gegeweb.eu 288 00.0 KB 0 0 000:01:22
TOTAL: 9 429 1127118.1 MB 11 0 001:09:19

NNRP auth users [Top 20]:

UserConn
mailman 288
TOTAL: 1 288

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
162.250.122.68 1.857 4.586 0.00001:06:50
mail.gegeweb.eu 4.370 3.596 1.50700:01:22
172-105-246-139.ip.linodeusercontent.com 2.840 1.331 0.00000:00:25
205.210.31.89 0.013 0.009 0.00000:00:11
azpdws54.stretchoid.com 0.034 0.017 0.00000:00:09
198.235.24.254 0.007 0.015 0.00000:00:06
91.238.181.21 0.023 0.027 0.00000:00:03
94.103.125.138 0.012 0.016 0.00000:00:02
shiny.monitoring.internet-measurement.com 0.017 0.007 0.00000:00:01
240e:108:49a1:fffe:1::1 0.038 0.030 0.00000:00:01
45-56-66-228.ip.linodeusercontent.com 0.240 0.132 0.00000:00:01
azpdcgc62.stretchoid.com 0.030 0.021 0.00000:00:01
214.150.216.162.bc.googleusercontent.com 0.044 0.032 0.00000:00:00
88-165-5-23.subs.proxad.net 0.031 0.022 0.00000:00:00
206.210.203.35.bc.googleusercontent.com 0.036 0.004 0.00000:00:00
147.45.112.158 0.035 0.013 0.00000:00:00
TOTAL: 16 9.627 9.858 1.50701:09:19

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 288
172-105-246-139.ip.linodeusercontent.com 105
45-56-66-228.ip.linodeusercontent.com 10
91.238.181.21 2
240e:108:49a1:fffe:1::1 2
azpdws54.stretchoid.com 1
shiny.monitoring.internet-measurement.com 1
88-165-5-23.subs.proxad.net 1
205.210.31.89 1
147.45.112.158 1
214.150.216.162.bc.googleusercontent.com 1
azpdcgc62.stretchoid.com 1
206.210.203.35.bc.googleusercontent.com 1
94.103.125.138 1
198.235.24.254 1
TOTAL: 15 417

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
206.210.203.35.bc.googleusercontent.com 4
214.150.216.162.bc.googleusercontent.com 4
147.45.112.158 1
91.238.181.21 1
azpdcgc62.stretchoid.com 1
TOTAL: 5 11

NNRP unrecognized commands (by command):

CommandCount
Accept-Encoding: gzip 2
GET / HTTP/1.1 2
#003 2
Host: 45.13.105.36:563 2
User-Agent: Expanse, a Palo Alto Network... 2
MGLNDD_45.13.105.36_119 1
TOTAL: 6 11

NNRP client timeouts [Top 20]:

SystemConnPeer
91.238.181.21 1 1
147.45.112.158 1 1
TOTAL: 2 2 3

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 10662 94.6%
2local 609 5.4%
TOTAL: 2 11271100.0%

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

NewsgroupCount
1grisbi.bugsreports 4632
2grisbi.test 3337
3grisbi.user.french 1622
4grisbi.devel 922
5local.test 547
6grisbi.cvs 96
7local.gegeweb.support 62
8grisbi.user 51
9grisbi.admin 2
TOTAL: 9 11271