Daily Usenet Report

Aug 15 00:01:01 -- Aug 16 00:01:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 15 00:07:01 newsfeed innfeed[1734587]: ME tape invalid messageID in /news/spool/innfeed/endofthelinebbs.peers.news.panix.com.input: <lfc1u7FkskrU1@mid.individual.@03024359434E47533200017B4F2900000001@
Aug 15 10:00:07 newsfeed pgpverify[2119107]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 9011 29.8%3.7 MB 56.2%
nnrpd 8430 27.8%885.3 KB 13.1%
innd 8103 26.8%1.2 MB 17.7%
innfeed 4167 13.8%775.4 KB 11.5%
cnfsstat 432 1.4%65.6 KB 1.0%
nocem 115 0.4%27.4 KB 0.4%
controlchan 23 0.1%5.5 KB 0.1%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 8 30282 100.0%6.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 110091 58.2%
Negative hits 67115 35.5%
Do not exist 8625 4.6%
Cache misses 3479 1.8%
TOTAL: 4 189310 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.690 0.0%438 0.000 1.575 17.500
article cleanup00:00:00.108 0.0%8666 0.000 0.012 0.067
article logging00:00:00.506 0.0%8691 0.000 0.058 1.464
article parse00:00:00.225 0.0%13806 0.000 0.016 0.078
article write00:00:02.109 0.0%7572 0.000 0.279 2.841
artlog/artcncl00:00:00.011 0.0%337 0.000 0.033 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:01.288 0.0%186109 0.000 0.007 0.209
hisgrep/artcncl00:00:00.304 0.0%416 0.000 0.731 12.500
hishave/artcncl00:00:00.070 0.0%388 0.000 0.180 3.389
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:33.710 0.2%190182 0.103 0.808 6.025
history sync00:00:00.070 0.0%401 0.000 0.175 0.667
history write00:02:08.274 0.1%8662 3.033 14.809 124.929
hiswrite/artcncl00:00:00.110 0.0%23 2.000 4.783 5.444
idle23:47:37.821 99.1%185774 95.127 461.086 1658.508
nntp read00:00:02.916 0.0%185885 0.003 0.016 0.153
overview write00:07:15.062 0.5%7572 0.647 57.457 7090.459
perl filter00:00:31.400 0.0%8662 1.402 3.625 18.509
python filter00:00:02.422 0.0%8662 0.000 0.280 4.189
site send00:00:00.918 0.0%15204 0.000 0.060 0.229
TOTAL: 24:01:08.49224:00:18.014 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:05.032 0.0%5093650 0.000 0.001 0.003
article prepare00:00:00.023 0.0%109396 0.000 0.000 0.003
article read00:00:11.458 0.0%19888 0.000 0.576 1.821
backlog stats00:00:02.430 0.0%2378331 0.000 0.001 0.004
callbacks00:00:00.003 0.0%225 0.000 0.013 0.019
data read00:00:14.817 0.0%1524874 0.005 0.010 0.031
data write00:00:15.038 0.0%1522423 0.006 0.010 0.017
idle23:46:18.428 99.7%2378331 5.733 35.983 54.772
status file00:00:16.343 0.0%298 0.879 54.842 9487.000
TOTAL: 23:50:08.09023:47:23.572 99.8%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:22:27.565 68.2%8287 20.500 162.612 10051.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.253 0.0%16706 0.000 0.015 0.500
TOTAL: 00:32:55.75600:22:27.818 68.2%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002112
TOTAL002112

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 4 13194 2308 10521 365 17%46:18:00
2outgoing.news.lysator.liu.se 2 6453 1634 4729 90 25%23:58:52
3usenet.blueworldhosting.com 718 8290 636 7428 226 7%23:59:26
4news.corradoroberto.it 4 1196 516 679 1 43%23:41:48
5usenet.network 2 5812 444 5366 2 7%23:59:38
6news.hispagatos.org 6 7183 419 6748 16 5%24:24:22
7nntp.comgw.net 1 7496 407 6958 131 5%23:59:57
8i2pn.org 2 7202 262 6929 11 3%23:59:12
9newsfeed.bofh.team 2 8159 156 7998 5 1%23:59:52
10endoftheline-out.news.chmurka.net 2 3982 147 3834 1 3%23:59:44
11newsfeed.xs3.de 2 5955 141 5784 30 2%23:59:53
12endofthelinebbs.peers.news.panix.com 28 8733 115 8595 23 1%24:27:46
13usenet.goja.nl.eu.org 11 7410 105 7293 12 1%23:38:33
14glockenspiel.complete.org 2 5842 88 5753 1 1%23:58:35
15news.netfront.net 1 2736 33 2641 62 1%14:48:47
16news.furie.org.uk 4 4393 27 4364 2 0%25:11:22
17news.samoylyk.net 19 6904 11 6808 85 0%20:20:18
18news.bbs.nz 1 6632 7 6625 0 0%23:59:24
19photonic.trudheim.com 3 2200 3 2196 1 0%30:38:02
20eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:04
21localhost 8 0 0 0 0 0%00:00:01
22news.nk.ca 2 0 0 0 0 0%00:20:11
23news.nntp4.net 1 0 0 0 0 0%00:10:00
TOTAL: 23 826 119772 7459 111249 1064 6%474:03:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net9.0 MB0.0 KB600.4 KB9.6 MB 93%3.7 KB
2outgoing.news.lysator.liu.se6.1 MB0.0 KB406.3 KB6.5 MB 93%3.8 KB
3usenet.network4.2 MB0.0 KB3.1 KB4.2 MB 99%9.7 KB
4news.corradoroberto.it3.1 MB0.0 KB2.3 KB3.1 MB 99%6.1 KB
5usenet.blueworldhosting.com1.9 MB12.5 KB667.7 KB2.5 MB 73%3.0 KB
6nntp.comgw.net1.5 MB0.0 KB204.1 KB1.7 MB 88%3.3 KB
7news.hispagatos.org1.3 MB0.0 KB50.0 KB1.4 MB 96%3.3 KB
8i2pn.org920.6 KB0.0 KB17.3 KB937.9 KB 98%3.4 KB
9newsfeed.xs3.de672.0 KB0.0 KB124.8 KB796.8 KB 84%4.7 KB
10glockenspiel.complete.org654.5 KB0.0 KB2.4 KB656.9 KB 99%7.4 KB
11newsfeed.bofh.team536.2 KB0.0 KB7.2 KB543.4 KB 98%3.4 KB
12endoftheline-out.news.chmurka.net467.1 KB0.0 KB0.7 KB467.8 KB 99%3.2 KB
13usenet.goja.nl.eu.org381.1 KB0.0 KB8.8 KB389.9 KB 97%3.3 KB
14endofthelinebbs.peers.news.panix.com350.0 KB0.0 KB55.5 KB405.5 KB 86%2.9 KB
15news.netfront.net132.4 KB0.0 KB197.7 KB330.1 KB 40%3.5 KB
16news.furie.org.uk114.7 KB0.0 KB1.9 KB116.6 KB 98%4.0 KB
17news.samoylyk.net41.3 KB0.0 KB93.3 KB134.6 KB 30%1.4 KB
18photonic.trudheim.com23.5 KB0.0 KB11.1 KB34.7 KB 67%8.7 KB
19news.bbs.nz21.9 KB0.0 KB0.0 KB21.9 KB100%3.1 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: 2331.4 MB12.5 KB2.4 MB33.8 MB 92%4.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 15 00:01:01 - 00:59:59 353 4.7% 0.101.4 MB 4.3% 0.39
Aug 15 01:00:00 - 01:59:59 227 3.0% 0.06712.9 KB 2.2% 0.20
Aug 15 02:00:00 - 02:59:59 330 4.4% 0.091.1 MB 3.6% 0.32
Aug 15 03:00:00 - 03:59:59 343 4.5% 0.101.0 MB 3.2% 0.29
Aug 15 04:00:00 - 04:59:59 310 4.1% 0.09944.2 KB 2.9% 0.26
Aug 15 05:00:00 - 05:59:59 385 5.1% 0.111.4 MB 4.5% 0.40
Aug 15 06:00:00 - 06:59:59 360 4.8% 0.101.3 MB 4.3% 0.38
Aug 15 07:00:00 - 07:59:59 348 4.6% 0.101.4 MB 4.4% 0.39
Aug 15 08:00:00 - 08:59:59 377 5.0% 0.101.5 MB 4.8% 0.43
Aug 15 09:00:00 - 09:59:59 352 4.7% 0.101.3 MB 4.3% 0.38
Aug 15 10:00:00 - 10:59:59 449 5.9% 0.122.5 MB 8.1% 0.72
Aug 15 11:00:00 - 11:59:59 479 6.3% 0.132.1 MB 6.8% 0.60
Aug 15 12:00:00 - 12:59:59 487 6.4% 0.142.0 MB 6.5% 0.58
Aug 15 13:00:00 - 13:59:59 335 4.4% 0.091.1 MB 3.7% 0.33
Aug 15 14:00:00 - 14:59:59 395 5.2% 0.111.2 MB 3.7% 0.33
Aug 15 15:00:00 - 15:59:59 345 4.6% 0.101.4 MB 4.4% 0.39
Aug 15 16:00:00 - 16:59:59 390 5.2% 0.111.9 MB 6.1% 0.54
Aug 15 17:00:00 - 17:59:59 315 4.2% 0.091.7 MB 5.5% 0.49
Aug 15 18:00:00 - 18:59:59 188 2.5% 0.051.8 MB 5.8% 0.52
Aug 15 19:00:00 - 19:59:59 200 2.6% 0.06834.4 KB 2.6% 0.23
Aug 15 20:00:00 - 20:59:59 123 1.6% 0.03532.9 KB 1.7% 0.15
Aug 15 21:00:00 - 21:59:59 155 2.1% 0.04879.1 KB 2.7% 0.24
Aug 15 22:00:00 - 22:59:59 139 1.8% 0.04554.8 KB 1.7% 0.15
Aug 15 23:00:00 - 23:59:59 169 2.2% 0.05608.7 KB 1.9% 0.17
Aug 16 00:00:00 - 00:01:02 1 0.0% 0.0228.8 KB 0.1% 0.46
TOTAL: 24:00:01 7555 100.0% 0.0931.3 MB 100.0% 0.37
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.3 GB 6.3% 0 0.5236174.89
CYCTXT1TEXT5.0 GB3.8 GB 76.3% 0 0.08 769.88
TOTAL: 3-3077.0 GB100.1 GB 3.3% 1 0.36-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs-out.news.weretis.net 383 0 0 0 0 0 0 0 383
2usenet.blueworldhosting.com 230 1 0 4 0 0 0 0 225
3nntp.comgw.net 134 0 0 0 0 0 0 0 134
4outgoing.news.lysator.liu.se 93 0 0 0 0 0 0 0 93
5news.netfront.net 86 0 0 0 0 0 0 0 86
6news.samoylyk.net 85 0 0 0 0 0 0 0 85
7newsfeed.xs3.de 31 0 0 0 0 0 0 0 31
8endofthelinebbs.peers.news.panix.com 24 0 0 0 0 0 0 0 24
9news.hispagatos.org 17 0 0 0 0 0 0 0 17
10usenet.goja.nl.eu.org 13 0 0 0 0 0 0 0 13
11i2pn.org 11 0 0 0 0 0 0 0 11
12newsfeed.bofh.team 5 0 0 0 0 0 0 0 5
13news.furie.org.uk 2 0 0 0 0 0 0 0 2
14endoftheline-out.news.chmurka.net 1 0 0 0 0 0 0 0 1
15glockenspiel.complete.org 1 0 0 0 0 0 0 0 1
16news.corradoroberto.it 1 0 0 0 0 0 0 0 1
17photonic.trudheim.com 1 0 0 0 0 0 0 0 1
18usenet.network 1 0 0 0 0 0 0 0 1
TOTAL: 18 1119 1 0 4 0 0 0 0 1114

Unwanted newsgroups [Top 20]:

NewsgroupCount
news.test_messages 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (phr path) 460
EMP (phn path) 295
EMP (md5) 194
Too many newsgroups 59
EMP (phl) 31
EMP (phn nph) 28
User-issued cancel 21
Too many newsgroups (meow) 1
TOTAL: 8 1089

NoCeM on Spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)5603870387
robot@pasdenom.info (spam3)20022
TOTAL: 25803872389

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 15
usenet.blueworldhosting.com 4
outgoing.news.lysator.liu.se 3
endofthelinebbs.peers.news.panix.com 1
newsfeed.xs3.de 1
nntp.comgw.net 1
TOTAL: 6 25
TOTAL: 1 25

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 11047 3317 4203 0 0 144 30%23:59:54
2nyheter.lysator.liu.se 6107 1269 4057 600 0 41 20%23:59:54
3endofthelinebbs.peers.news.panix.com 345192 1074 329262 10890 3706 0 0%23:59:54
4news.furie.org.uk 4973 805 3237 12 0 53 16%23:59:54
5photonic.trudheim.com 8026 710 6159 680 0 23 8%23:59:19
6news.hispagatos.org 8213 492 6353 282 0 13 5%23:59:27
7usenet.network 7395 376 6188 35 0 4 5%23:59:46
8newsfeed.bofh.team 8214 292 7001 0 0 14 3%23:59:54
9news.bbs.nz 13727 263 6829 453 0 181 1%23:59:46
10usenet.goja.nl.eu.org 8271 248 6818 257 0 8 2%23:59:49
11news.tnetconsulting.net 8399 239 7057 245 0 16 2%23:59:53
12news.corradoroberto.it 1025 217 488 2 0 0 21%22:50:34
13news.weretis.net 8034 203 7154 193 0 0 2%23:59:22
14news.quux.org 7945 198 6835 468 0 7 2%23:59:22
15i2pn.org 8365 162 7008 82 0 106 1%23:59:54
16nntp.comgw.net 7996 135 6848 153 0 5 1%23:59:32
17news.nk.ca 11230 129 6701 634 0 46 1%23:59:54
18newsfeed.xs3.de 8663 115 7117 170 0 22 1%23:59:54
19news.chmurka.net 4379 33 3577 1 0 1 0%23:59:54
20news.samoylyk.net 8633 11 8248 90 0 6324 0%23:54:54
21usenet.blueworldhosting.com 7124 2 6741 0 0 7 0%23:59:54
22news.nntp4.net4779666 0 0 0 04779655 0%23:59:54
23feed.opticnetworks.net 0 0 0 0 0 7555 0%23:59:54
TOTAL: 235282624 10290 447881 15247 37064794225 0%550:40:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net15.7 MB0.0 KB15.7 MB0.2 KB/s4.8 KB23:59:54
2endofthelinebbs.peers.news.panix.com7.5 MB34.1 MB41.6 MB0.5 KB/s3.6 KB23:59:54
3nyheter.lysator.liu.se6.9 MB4.4 MB11.3 MB0.1 KB/s6.2 KB23:59:54
4news.furie.org.uk6.8 MB118.1 KB6.9 MB0.1 KB/s8.7 KB23:59:54
5photonic.trudheim.com4.6 MB3.6 MB8.2 MB0.1 KB/s6.0 KB23:59:19
6news.hispagatos.org3.1 MB2.0 MB5.1 MB0.1 KB/s6.8 KB23:59:27
7newsfeed.bofh.team2.6 MB0.0 KB2.6 MB0.0 KB/s9.0 KB23:59:54
8news.quux.org2.0 MB3.1 MB5.0 MB0.1 KB/s7.7 KB23:59:22
9usenet.network1.7 MB90.4 KB1.7 MB0.0 KB/s4.4 KB23:59:46
10news.bbs.nz1.3 MB3.7 MB5.0 MB0.1 KB/s7.1 KB23:59:46
11news.weretis.net1.3 MB2.3 MB3.6 MB0.0 KB/s9.3 KB23:59:22
12nntp.comgw.net1.2 MB1.8 MB3.0 MB0.0 KB/s10.7 KB23:59:32
13newsfeed.xs3.de1.2 MB1.9 MB3.0 MB0.0 KB/s10.9 KB23:59:54
14news.tnetconsulting.net1.2 MB2.2 MB3.3 MB0.0 KB/s7.0 KB23:59:53
15usenet.goja.nl.eu.org937.0 KB2.3 MB3.2 MB0.0 KB/s6.4 KB23:59:49
16news.corradoroberto.it929.6 KB23.5 KB953.1 KB0.0 KB/s4.4 KB22:50:34
17i2pn.org774.7 KB339.9 KB1.1 MB0.0 KB/s4.6 KB23:59:54
18news.samoylyk.net343.9 KB767.0 KB1.1 MB0.0 KB/s11.0 KB23:54:54
19news.nk.ca267.2 KB4.2 MB4.5 MB0.1 KB/s6.0 KB23:59:54
20news.chmurka.net113.9 KB45.5 KB159.4 KB0.0 KB/s4.7 KB23:59:54
21usenet.blueworldhosting.com7.9 KB0.0 KB7.9 KB0.0 KB/s3.9 KB23:59:54
22feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
23news.nntp4.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
TOTAL: 2360.2 MB66.8 MB127.0 MB0.1 KB/s5.1 KB550:40:32
Outgoing feeds (innfeed) by Volume

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.demos.su2756 00.0 KB 0 0 000:31:49
2unresolved 14 00.0 KB 1 0 000:00:43
3*.ip.linodeusercontent.com 2 00.0 KB 0 0 000:00:18
4*.census.shodan.io 1 00.0 KB 0 0 000:00:00
5*.stretchoid.com 1 00.0 KB 0 0 000:00:00
6? 1 00.0 KB 0 0 000:00:02
TOTAL: 62775 00.0 KB 1 0 000:32:54

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
ddt.demos.su 9.724 36.842 0.00000:31:49
172-232-217-148.ip.linodeusercontent.com 0.008 0.024 0.00000:00:18
104.152.52.145 0.004 0.012 0.00000:00:10
147.185.132.228 0.005 0.010 0.00000:00:10
147.185.132.129 0.003 0.013 0.00000:00:10
206.168.34.112 0.024 0.036 0.00000:00:06
167.94.146.52 0.030 0.030 0.00000:00:03
2607:ff10:c8:594::d 0.004 0.012 0.00000:00:02
167.94.138.51 0.014 0.014 0.00000:00:01
194.165.16.72 0.000 0.017 0.00000:00:00
198.235.24.111 0.004 0.012 0.00000:00:00
sky.census.shodan.io 0.005 0.011 0.00000:00:00
91.238.181.21 0.003 0.013 0.00000:00:00
azpdwgc40.stretchoid.com 0.007 0.010 0.00000:00:00
104.152.52.199 0.000 0.017 0.00000:00:00
? 0.004 0.011 0.00000:00:00
TOTAL: 16 9.839 37.084 0.00000:32:54

Curious NNRP server explorers [Top 20]:

SystemConn
ddt.demos.su 2756
167.94.146.52 3
206.168.34.112 3
172-232-217-148.ip.linodeusercontent.com 2
104.152.52.145 1
104.152.52.199 1
147.185.132.129 1
147.185.132.228 1
167.94.138.51 1
194.165.16.72 1
198.235.24.111 1
2607:ff10:c8:594::d 1
91.238.181.21 1
azpdwgc40.stretchoid.com 1
sky.census.shodan.io 1
TOTAL: 15 2775

NNRP gethostbyaddr failures [Top 20]:

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

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

SystemConn
167.94.138.51 1
167.94.146.52 1
194.165.16.72 1
206.168.34.112 1
91.238.181.21 1
azpdwgc40.stretchoid.com 1
TOTAL: 6 6

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

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

NNRP client timeouts [Top 20]:

SystemConnPeer
167.94.146.52 1 2
194.165.16.72 1 1
206.168.34.112 1 2
91.238.181.21 1 1
TOTAL: 4 4 7

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1comp 2100.0%
TOTAL: 1 2100.0%

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

NewsgroupCount
1comp.sys.acorn.announce 2
TOTAL: 1 2