Daily Usenet Report

Aug 21 00:01:02 -- Aug 22 00:01:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 21 08:14:18 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Aug 21 11:52:58 newsfeed nnrpd[2245142]: 47.189.156.68 auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
inn 9437 30.9%3.8 MB 56.8%
nnrpd 8336 27.3%851.6 KB 12.5%
innd 8126 26.6%1.1 MB 17.1%
innfeed 4093 13.4%829.6 KB 12.2%
cnfsstat 432 1.4%63.6 KB 0.9%
nocem 114 0.4%27.1 KB 0.4%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 7 30539 100.0%6.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 119768 59.6%
Negative hits 72035 35.9%
Do not exist 8945 4.5%
Cache misses 45 0.0%
TOTAL: 4 200793 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.593 0.0%443 0.000 1.339 12.875
article cleanup00:00:00.124 0.0%8971 0.000 0.014 0.200
article logging00:00:00.414 0.0%9080 0.000 0.046 0.160
article parse00:00:00.210 0.0%13912 0.000 0.015 0.091
article write00:00:02.433 0.0%7718 0.000 0.315 14.833
artlog/artcncl00:00:00.010 0.0%354 0.000 0.028 0.167
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:01.872 0.0%202214 0.000 0.009 0.262
hisgrep/artcncl00:00:00.253 0.0%424 0.000 0.597 7.000
hishave/artcncl00:00:00.050 0.0%387 0.000 0.129 1.833
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:41.658 0.2%201581 0.099 0.802 7.163
history sync00:00:00.074 0.0%399 0.000 0.185 1.000
history write00:02:16.447 0.2%8971 3.121 15.210 126.417
hiswrite/artcncl00:00:00.073 0.0%24 3.000 3.042 3.043
idle23:50:15.160 99.2%200949 122.184 427.049 1348.402
nntp read00:00:04.188 0.0%202546 0.000 0.021 0.746
overview write00:04:04.618 0.3%7718 0.690 31.694 1970.327
perl filter00:00:32.588 0.0%8971 1.591 3.633 29.241
python filter00:00:03.267 0.0%8971 0.000 0.364 7.750
site send00:00:01.029 0.0%15497 0.000 0.066 0.169
TOTAL: 24:01:05.52624:00:05.061 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:04.477 0.0%4779675 0.000 0.001 0.002
article prepare00:00:00.009 0.0%46540 0.000 0.000 0.007
article read00:00:00.719 0.0%4294 0.000 0.167 1.935
backlog stats00:00:02.061 0.0%2094853 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:13.135 0.0%1280217 0.005 0.010 0.032
data write00:00:13.370 0.0%1275591 0.006 0.010 0.021
idle23:47:37.558 99.8%2094853 30.196 40.890 54.815
status file00:00:00.303 0.0%252 1.000 1.202 13.000
TOTAL: 23:50:13.03423:48:11.632 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:32:02.320 78.8%8153 14.000 235.781 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.265 0.0%16359 0.000 0.016 2.333
TOTAL: 00:40:38.80000:32:02.585 78.8%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 98
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 120

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1outgoing.news.lysator.liu.se 2 6882 1654 5177 51 24%24:02:56
2endofthelinebbs-out.news.weretis.net 3 14042 1263 12604 175 8%48:33:32
3usenet.blueworldhosting.com 720 8711 870 7365 476 9%23:51:33
4nntp.comgw.net 1 8019 825 6947 247 10%23:59:56
5usenet.goja.nl.eu.org 11 8088 655 7322 111 8%24:13:15
6news.hispagatos.org 5 7796 526 7255 15 6%24:26:19
7news.corradoroberto.it 5 1628 491 1137 0 30%25:02:41
8usenet.network 2 6235 406 5826 3 6%23:59:52
9endoftheline-out.news.chmurka.net 1 4062 304 3756 2 7%23:59:56
10i2pn.org 2 7669 219 7435 15 2%23:58:52
11newsfeed.bofh.team 1 8678 213 8450 15 2%23:59:56
12endofthelinebbs.peers.news.panix.com 25 8231 148 8066 17 1%23:54:31
13newsfeed.xs3.de 1 6384 98 6282 4 1%23:59:56
14news.samoylyk.net 2 6946 86 6857 3 1%23:59:20
15glockenspiel.complete.org 2 6018 22 5995 1 0%23:59:09
16news.bbs.nz 2 6764 8 6756 0 0%23:59:10
17news.furie.org.uk 1 4439 8 4430 1 0%23:01:14
18news.netfront.net 1 5021 2 4956 63 0%21:53:57
19photonic.trudheim.com 2 2386 1 2385 0 0%26:43:01
20eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:07
21localhost 8 0 0 0 0 0%00:00:00
22news.nk.ca 3 0 0 0 0 0%00:23:49
23news.nntp4.net 1 0 0 0 0 0%00:10:01
TOTAL: 23 802 127999 7799 119001 1199 6%482:23:03
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1outgoing.news.lysator.liu.se5.4 MB0.0 KB67.2 KB5.4 MB 98%3.3 KB
2endofthelinebbs-out.news.weretis.net4.7 MB0.0 KB338.7 KB5.0 MB 93%3.6 KB
3usenet.network4.6 MB0.0 KB3.9 KB4.6 MB 99%11.4 KB
4nntp.comgw.net2.7 MB0.0 KB648.4 KB3.4 MB 81%3.2 KB
5usenet.blueworldhosting.com2.7 MB0.0 KB1.1 MB3.8 MB 71%2.9 KB
6news.corradoroberto.it2.7 MB0.0 KB0.0 KB2.7 MB100%5.6 KB
7usenet.goja.nl.eu.org2.1 MB0.0 KB170.0 KB2.2 MB 92%3.0 KB
8news.hispagatos.org1.6 MB0.0 KB46.8 KB1.6 MB 97%3.0 KB
9endoftheline-out.news.chmurka.net1.1 MB0.0 KB1.4 KB1.1 MB 99%3.7 KB
10i2pn.org815.9 KB0.0 KB24.6 KB840.5 KB 97%3.6 KB
11newsfeed.bofh.team591.7 KB0.0 KB28.8 KB620.5 KB 95%2.7 KB
12endofthelinebbs.peers.news.panix.com450.7 KB0.0 KB28.3 KB479.0 KB 94%2.9 KB
13news.samoylyk.net360.2 KB0.0 KB3.2 KB363.4 KB 99%4.1 KB
14newsfeed.xs3.de358.7 KB0.0 KB26.9 KB385.6 KB 93%3.8 KB
15glockenspiel.complete.org112.8 KB0.0 KB0.7 KB113.4 KB 99%4.9 KB
16news.furie.org.uk27.1 KB0.0 KB0.7 KB27.8 KB 97%3.1 KB
17news.bbs.nz21.8 KB0.0 KB0.0 KB21.8 KB100%2.7 KB
18photonic.trudheim.com11.7 KB0.0 KB0.0 KB11.7 KB100%11.7 KB
19news.netfront.net3.2 KB0.0 KB636.1 KB639.3 KB 0%9.8 KB
20eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
21localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
23news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2330.2 MB0.0 KB3.1 MB33.3 MB 90%3.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 21 00:01:02 - 00:59:59 160 2.1% 0.05603.4 KB 2.0% 0.17
Aug 21 01:00:00 - 01:59:59 303 3.9% 0.081.1 MB 3.7% 0.31
Aug 21 02:00:00 - 02:59:59 396 5.1% 0.111.4 MB 4.7% 0.40
Aug 21 03:00:00 - 03:59:59 384 5.0% 0.111.3 MB 4.4% 0.37
Aug 21 04:00:00 - 04:59:59 347 4.5% 0.101019.7 KB 3.3% 0.28
Aug 21 05:00:00 - 05:59:59 438 5.7% 0.121.7 MB 5.6% 0.48
Aug 21 06:00:00 - 06:59:59 386 5.0% 0.111.5 MB 5.0% 0.42
Aug 21 07:00:00 - 07:59:59 457 5.9% 0.132.0 MB 6.8% 0.57
Aug 21 08:00:00 - 08:59:59 411 5.3% 0.111.7 MB 5.7% 0.49
Aug 21 09:00:00 - 09:59:59 472 6.1% 0.132.0 MB 6.8% 0.58
Aug 21 10:00:00 - 10:59:59 469 6.1% 0.132.1 MB 7.0% 0.60
Aug 21 11:00:00 - 11:59:59 465 6.0% 0.131.9 MB 6.4% 0.55
Aug 21 12:00:00 - 12:59:59 434 5.6% 0.121.3 MB 4.3% 0.37
Aug 21 13:00:00 - 13:59:59 373 4.8% 0.101.1 MB 3.7% 0.32
Aug 21 14:00:00 - 14:59:59 353 4.6% 0.101.1 MB 3.9% 0.33
Aug 21 15:00:00 - 15:59:59 292 3.8% 0.081.4 MB 4.7% 0.40
Aug 21 16:00:00 - 16:59:59 318 4.1% 0.091.3 MB 4.4% 0.37
Aug 21 17:00:00 - 17:59:59 329 4.3% 0.091.5 MB 5.0% 0.43
Aug 21 18:00:00 - 18:59:59 201 2.6% 0.06827.8 KB 2.7% 0.23
Aug 21 19:00:00 - 19:59:59 186 2.4% 0.051000.3 KB 3.3% 0.28
Aug 21 20:00:00 - 20:59:59 152 2.0% 0.04706.9 KB 2.3% 0.20
Aug 21 21:00:00 - 21:59:59 108 1.4% 0.03312.4 KB 1.0% 0.09
Aug 21 22:00:00 - 22:59:59 107 1.4% 0.03348.5 KB 1.1% 0.10
Aug 21 23:00:00 - 23:59:59 172 2.2% 0.05490.7 KB 1.6% 0.14
Aug 22 00:00:00 - 00:01:02 8 0.1% 0.13130.9 KB 0.4% 2.11
TOTAL: 24:00:00 7721 100.0% 0.0929.8 MB 100.0% 0.35
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
CYCNGS1NGS1536.0 GB48.0 MB 0.0% 1 0.00 0.00
CYCNGS2NGS1536.0 GB96.5 GB 6.3% 0 0.5434731.06
CYCTXT1TEXT5.0 GB3.8 GB 76.4% 0 0.03 2133.54
TOTAL: 3-3077.0 GB100.4 GB 3.3% 1 0.38-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 520 0 0 0 0 0 0 0 520
2nntp.comgw.net 258 0 0 0 0 0 0 0 258
3endofthelinebbs-out.news.weretis.net 203 0 0 0 0 0 0 0 203
4usenet.goja.nl.eu.org 124 0 0 0 0 0 0 0 124
5news.netfront.net 121 0 0 0 0 0 0 0 121
6outgoing.news.lysator.liu.se 52 0 0 0 0 0 0 0 52
7endofthelinebbs.peers.news.panix.com 19 0 0 0 0 0 0 0 19
8newsfeed.bofh.team 17 0 0 0 0 0 0 0 17
9news.hispagatos.org 16 0 0 0 0 0 0 0 16
10i2pn.org 15 0 0 0 0 0 0 0 15
11newsfeed.xs3.de 6 0 0 0 0 0 0 0 6
12news.samoylyk.net 4 0 0 0 0 0 0 0 4
13usenet.network 3 0 0 0 0 0 0 0 3
14endoftheline-out.news.chmurka.net 2 0 0 0 0 0 0 0 2
15glockenspiel.complete.org 1 0 0 0 0 0 0 0 1
16news.furie.org.uk 1 0 0 0 0 0 0 0 1
TOTAL: 16 1362 0 0 0 0 0 0 0 1362

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 409
EMP (phr path) 365
EMP (md5) 340
Too many newsgroups 87
EMP (phn nph) 30
Too many newsgroups (meow) 8
User-issued cancel 8
EMP (phl) 3
Too many newsgroups (low_xpost) 2
HTML Multipart 1
TOTAL: 10 1253

NoCeM on Spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)5203820382
robot@pasdenom.info (spam3)50099
robot@pasdenom.info (spam2)40044
TOTAL: 361038213395

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 44
usenet.blueworldhosting.com 44
usenet.goja.nl.eu.org 11
nntp.comgw.net 6
endofthelinebbs.peers.news.panix.com 2
newsfeed.bofh.team 1
outgoing.news.lysator.liu.se 1
TOTAL: 7 109
TOTAL: 1 109

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 11874 2911 4808 0 0 31 24%23:59:26
2nyheter.lysator.liu.se 6274 1177 4424 465 0 0 18%23:59:11
3news.furie.org.uk 5195 622 3578 3 0 8 11%23:59:13
4photonic.trudheim.com 8300 503 6463 746 0 0 6%23:59:53
5endofthelinebbs.peers.news.panix.com 8003 437 7273 8 0 0 5%23:59:02
6news.hispagatos.org 8413 419 6615 175 0 11 4%23:59:53
7usenet.network 7798 338 6448 26 0 13 4%23:59:53
8news.bbs.nz 14246 255 7049 412 0 127 1%23:59:53
9usenet.goja.nl.eu.org 7978 209 6697 142 0 7 2%23:59:53
10i2pn.org 8666 189 7239 49 0 83 2%23:59:53
11newsfeed.bofh.team 8332 178 7291 0 0 1 2%23:59:53
12news.nk.ca 11069 171 6827 637 0 67 1%23:59:53
13news.tnetconsulting.net 8710 127 7394 197 0 14 1%23:59:53
14news.corradoroberto.it 1098 101 817 5 0 0 9%23:10:48
15news.weretis.net 8014 95 7512 114 0 0 1%23:59:49
16nntp.comgw.net 7730 92 6681 87 0 6 1%23:59:42
17news.quux.org 8248 84 7200 407 0 7 1%23:59:53
18newsfeed.xs3.de 8742 52 7420 122 0 11 0%23:59:42
19news.chmurka.net 4191 51 3521 0 0 8 1%23:59:53
20news.samoylyk.net 8985 41 7511 45 0 38 0%23:59:53
21usenet.blueworldhosting.com 6973 1 6606 0 0 0 0%23:59:50
22news.nntp4.net4811659 0 0 0 04811516 0%23:59:53
23feed.opticnetworks.net 0 0 0 0 0 7721 0%23:59:53
TOTAL: 234980498 8053 129374 3640 04819669 0%551:05:05
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net11.8 MB0.0 KB11.8 MB0.1 KB/s4.2 KB23:59:26
2nyheter.lysator.liu.se5.3 MB4.5 MB9.8 MB0.1 KB/s6.1 KB23:59:11
3news.furie.org.uk4.0 MB9.4 KB4.0 MB0.0 KB/s6.6 KB23:59:13
4photonic.trudheim.com3.3 MB3.1 MB6.5 MB0.1 KB/s5.3 KB23:59:53
5endofthelinebbs.peers.news.panix.com2.1 MB11.0 KB2.1 MB0.0 KB/s4.9 KB23:59:02
6news.hispagatos.org1.6 MB1.9 MB3.5 MB0.0 KB/s6.0 KB23:59:53
7newsfeed.bofh.team1.6 MB0.0 KB1.6 MB0.0 KB/s9.1 KB23:59:53
8usenet.network1.2 MB50.5 KB1.3 MB0.0 KB/s3.6 KB23:59:53
9news.quux.org881.1 KB2.8 MB3.7 MB0.0 KB/s7.7 KB23:59:53
10news.bbs.nz838.3 KB3.1 MB3.9 MB0.0 KB/s6.0 KB23:59:53
11i2pn.org837.3 KB414.2 KB1.2 MB0.0 KB/s5.3 KB23:59:53
12news.tnetconsulting.net638.5 KB1.4 MB2.0 MB0.0 KB/s6.5 KB23:59:53
13news.samoylyk.net627.7 KB258.9 KB886.6 KB0.0 KB/s10.3 KB23:59:53
14news.weretis.net501.9 KB1.5 MB2.0 MB0.0 KB/s9.7 KB23:59:49
15usenet.goja.nl.eu.org499.1 KB1.4 MB1.9 MB0.0 KB/s5.5 KB23:59:53
16news.nk.ca386.4 KB4.0 MB4.4 MB0.1 KB/s5.5 KB23:59:53
17nntp.comgw.net366.4 KB1.3 MB1.6 MB0.0 KB/s9.2 KB23:59:42
18news.corradoroberto.it280.9 KB12.6 KB293.5 KB0.0 KB/s2.8 KB23:10:48
19newsfeed.xs3.de233.8 KB1.4 MB1.6 MB0.0 KB/s9.6 KB23:59:42
20news.chmurka.net196.2 KB0.0 KB196.2 KB0.0 KB/s3.8 KB23:59:53
21usenet.blueworldhosting.com2.7 KB0.0 KB2.7 KB0.0 KB/s2.7 KB23:59:50
22feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
23news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2337.2 MB27.1 MB64.3 MB0.0 KB/s5.6 KB551:05:05
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.189.156.68 1 00.0 KB 2 0 000:00:00
TOTAL: 1 1 00.0 KB 2 0 000:40:37

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.demos.su2714 00.0 KB 0 0 000:39:08
2unresolved 13 00.0 KB 2 0 000:01:18
3*.stretchoid.com 3 00.0 KB 0 0 000:00:10
TOTAL: 32730 00.0 KB 2 0 000:40:37

NNRP auth users [Top 20]:

UserConn
nelgin 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
ddt.demos.su 9.480 36.256 0.00000:39:08
194.87.241.33 0.022 0.070 0.00000:00:53
147.185.132.109 0.000 0.016 0.00000:00:10
198.235.24.54 0.000 0.016 0.00000:00:10
azpdssb28.stretchoid.com 0.006 0.011 0.00000:00:09
199.45.154.118 0.018 0.046 0.00000:00:04
88.214.25.64 0.004 0.012 0.00000:00:00
azpdeg20.stretchoid.com 0.003 0.013 0.00000:00:00
azpdsgb71.stretchoid.com 0.003 0.013 0.00000:00:00
179.60.149.238 0.005 0.011 0.00000:00:00
TOTAL: 10 9.541 36.464 0.00000:40:37

Curious NNRP server explorers [Top 20]:

SystemConn
ddt.demos.su 2714
194.87.241.33 5
199.45.154.118 3
147.185.132.109 1
179.60.149.238 1
198.235.24.54 1
88.214.25.64 1
azpdeg20.stretchoid.com 1
azpdsgb71.stretchoid.com 1
azpdssb28.stretchoid.com 1
TOTAL: 10 2729

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
179.60.149.238 1
199.45.154.118 1
88.214.25.64 1
azpdeg20.stretchoid.com 1
azpdsgb71.stretchoid.com 1
TOTAL: 5 5

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
#003 2
MGLNDD_144.172.126.95_119 2
EHLO www.censys.io 1
TOTAL: 3 5

NNRP client timeouts [Top 20]:

SystemConnPeer
179.60.149.238 1 1
199.45.154.118 1 2
88.214.25.64 1 1
TOTAL: 3 3 4

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1alt 2 66.7%
2news 1 33.3%
TOTAL: 2 3100.0%

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

NewsgroupCount
1alt.genealogy 2
2news.software.nntp 1
TOTAL: 2 3