Daily Usenet Report

Apr 9 00:01:02 -- Apr 10 00:01:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Apr 9 08:17:17 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Apr 10 00:00:01 newsfeed nnrpd[1286860]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 25155 51.9%3.0 MB 33.0%
innd 8906 18.4%1.3 MB 13.9%
inn 8754 18.1%3.8 MB 41.9%
innfeed 4961 10.2%944.7 KB 10.2%
cnfsstat 576 1.2%89.0 KB 1.0%
nocem 79 0.2%8.5 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 48432 100.0%9.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 131179 63.0%
Negative hits 68485 32.9%
Do not exist 8641 4.1%
Cache misses 36 0.0%
TOTAL: 4 208341 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.168 0.0%33 0.000 5.091 20.000
article cleanup00:00:00.122 0.0%8703 0.000 0.014 0.253
article logging00:00:00.583 0.0%8742 0.000 0.067 1.044
article parse00:00:00.253 0.0%20264 0.000 0.012 0.091
article write00:00:02.342 0.0%7171 0.000 0.327 3.806
artlog/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.246 0.0%217421 0.000 0.001 0.014
hisgrep/artcncl00:00:00.118 0.0%36 0.000 3.278 4.455
hishave/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:25.142 0.2%209713 0.099 0.692 4.793
history sync00:00:00.079 0.0%393 0.000 0.201 1.000
history write00:02:08.624 0.1%8699 3.164 14.786 116.542
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:47:57.256 99.0%218444 71.814 392.216 1454.148
nntp read00:00:02.786 0.0%218077 0.004 0.013 0.070
overview write00:08:06.493 0.6%7171 0.528 67.842 19115.529
perl filter00:00:21.470 0.0%8699 1.368 2.468 11.015
python filter00:00:01.202 0.0%8699 0.000 0.138 4.853
site send00:00:01.147 0.0%14412 0.000 0.080 0.240
TOTAL: 2224:01:08.031 99.9%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.026 0.0%8433 0.000 0.003 0.083
article prepare00:00:00.006 0.0%56999 0.000 0.000 0.003
article read00:00:00.714 0.0%4976 0.000 0.143 0.605
backlog stats00:00:00.447 0.0%306301 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.837 0.0%210033 0.003 0.014 0.056
data write00:00:04.078 0.0%206012 0.010 0.020 0.045
idle23:58:11.429100.0%306301 116.897 281.721 865.663
status file00:00:01.234 0.0%433 1.000 2.850 164.500
TOTAL: 923:58:20.771100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:54:47.631 99.9%16681 39.000 197.088180953.750
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.383 0.1%112217 0.000 0.039 0.333
TOTAL: 300:54:52.014100.0%----

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 (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.dne3.net 3 6968 1243 5713 12 17%24:53:53
2endofthelinebbs-out.news.weretis.net 3 13668 917 12619 132 6%48:55:34
3usenet.blueworldhosting.com 723 8360 569 7244 547 6%23:51:53
4usenet.goja.nl.eu.org 11 7182 517 6557 108 7%22:10:47
5nntp.comgw.net 1 7696 491 7044 161 6%23:59:57
6news.hispagatos.org 6 7820 456 7229 135 5%24:25:09
7usenet.network 2 5962 438 5515 9 7%23:56:56
8news.corradoroberto.it 2 2007 435 1571 1 21%23:59:22
9newsfeed.bofh.team 2 8477 304 8106 67 3%23:59:30
10i2pn.org 1 7302 254 6986 62 3%23:59:58
11194.87.241.33 39 491 241 159 91 49%18:58:36
12news-out.tcpreset.net 3 6431 236 6154 41 3%23:59:41
13glockenspiel.complete.org 2 2699 195 2504 0 7%15:02:14
14newsfeed.xs3.de 2 5934 180 5744 10 3%23:57:52
15endoftheline-out.news.chmurka.net 1 3338 173 3165 0 5%23:59:57
16news.furie.org.uk 1 3956 118 3838 0 2%23:59:56
17news.samoylyk.net 1 6544 95 6447 2 1%23:59:50
18endofthelinebbs.peers.news.panix.com 26 7483 89 7383 11 1%23:58:09
19peering.usenet.ovh 1 7331 82 7237 12 1%23:59:57
20news.netfront.net 2 9156 68 8985 103 0%34:05:06
21news.bbs.nz 1 6227 10 6214 3 0%23:59:34
22outgoing.news.lysator.liu.se 1 5308 0 5308 0 0%23:59:57
23eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:07
24localhost 29 0 0 0 0 0%00:00:00
25news.nk.ca 2 0 0 0 0 0%00:20:18
TOTAL: 25 866 140340 7111 131722 1507 5%548:44:13
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net13.1 MB1.3 KB522.5 KB13.7 MB 96%13.3 KB
2usenet.network4.6 MB0.0 KB10.5 KB4.6 MB 99%10.7 KB
3news.dne3.net3.5 MB0.0 KB11.7 KB3.5 MB 99%2.9 KB
4news.corradoroberto.it2.5 MB0.0 KB0.6 KB2.5 MB 99%6.0 KB
5usenet.goja.nl.eu.org1.6 MB0.0 KB148.8 KB1.7 MB 91%2.8 KB
6usenet.blueworldhosting.com1.4 MB0.0 KB1.5 MB2.9 MB 48%2.7 KB
7nntp.comgw.net1.3 MB1.3 KB542.8 KB1.9 MB 71%2.9 KB
8news.hispagatos.org1.2 MB0.0 KB193.9 KB1.4 MB 86%2.4 KB
9newsfeed.bofh.team983.9 KB1.3 KB120.1 KB1.1 MB 89%3.0 KB
10glockenspiel.complete.org933.0 KB0.0 KB0.0 KB933.0 KB100%4.8 KB
11news.samoylyk.net773.8 KB0.0 KB2.1 KB775.8 KB 99%8.0 KB
12endoftheline-out.news.chmurka.net668.8 KB0.0 KB0.0 KB668.8 KB100%3.9 KB
13i2pn.org607.9 KB0.0 KB80.3 KB688.2 KB 88%2.2 KB
14194.87.241.33562.4 KB0.0 KB119.9 KB682.3 KB 82%2.1 KB
15newsfeed.xs3.de502.8 KB0.0 KB12.7 KB515.5 KB 97%2.7 KB
16news-out.tcpreset.net489.0 KB0.0 KB45.4 KB534.5 KB 91%1.9 KB
17news.furie.org.uk355.4 KB0.0 KB0.0 KB355.4 KB100%3.0 KB
18peering.usenet.ovh274.8 KB0.0 KB15.1 KB289.9 KB 94%3.1 KB
19endofthelinebbs.peers.news.panix.com256.1 KB0.0 KB12.4 KB268.5 KB 95%2.7 KB
20news.netfront.net121.0 KB2.6 KB1.3 MB1.4 MB 8%8.3 KB
21news.bbs.nz25.0 KB2.9 KB1.3 KB29.2 KB 85%2.2 KB
22eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
23localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
24news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
25outgoing.news.lysator.liu.se0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2535.7 MB9.3 KB4.5 MB40.3 MB 88%4.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 9 00:01:02 - 00:59:59 169 2.4% 0.05604.0 KB 1.3% 0.17
Apr 9 01:00:00 - 01:59:59 244 3.4% 0.07592.6 KB 1.3% 0.16
Apr 9 02:00:00 - 02:59:59 279 3.9% 0.08882.9 KB 1.9% 0.25
Apr 9 03:00:00 - 03:59:59 441 6.1% 0.121.3 MB 3.0% 0.38
Apr 9 04:00:00 - 04:59:59 338 4.7% 0.091.7 MB 3.8% 0.48
Apr 9 05:00:00 - 05:59:59 363 5.1% 0.101.2 MB 2.8% 0.35
Apr 9 06:00:00 - 06:59:59 331 4.6% 0.091.1 MB 2.4% 0.30
Apr 9 07:00:00 - 07:59:59 429 6.0% 0.121.7 MB 3.7% 0.47
Apr 9 08:00:00 - 08:59:59 384 5.3% 0.111007.9 KB 2.2% 0.28
Apr 9 09:00:00 - 09:59:59 374 5.2% 0.101.7 MB 3.9% 0.50
Apr 9 10:00:00 - 10:59:59 361 5.0% 0.101.3 MB 2.9% 0.37
Apr 9 11:00:00 - 11:59:59 353 4.9% 0.101.2 MB 2.8% 0.35
Apr 9 12:00:00 - 12:59:59 363 5.1% 0.101.1 MB 2.5% 0.32
Apr 9 13:00:00 - 13:59:59 369 5.1% 0.101.5 MB 3.3% 0.42
Apr 9 14:00:00 - 14:59:59 365 5.1% 0.101.2 MB 2.8% 0.35
Apr 9 15:00:00 - 15:59:59 425 5.9% 0.1219.5 MB 44.1% 5.55
Apr 9 16:00:00 - 16:59:59 373 5.2% 0.101.6 MB 3.5% 0.44
Apr 9 17:00:00 - 17:59:59 261 3.6% 0.071.2 MB 2.6% 0.33
Apr 9 18:00:00 - 18:59:59 176 2.5% 0.05608.8 KB 1.3% 0.17
Apr 9 19:00:00 - 19:59:59 149 2.1% 0.04533.8 KB 1.2% 0.15
Apr 9 20:00:00 - 20:59:59 132 1.8% 0.04721.9 KB 1.6% 0.20
Apr 9 21:00:00 - 21:59:59 138 1.9% 0.04834.3 KB 1.8% 0.23
Apr 9 22:00:00 - 22:59:59 164 2.3% 0.05615.1 KB 1.4% 0.17
Apr 9 23:00:00 - 23:59:59 178 2.5% 0.05717.7 KB 1.6% 0.20
Apr 10 00:00:00 - 00:01:02 19 0.3% 0.3154.2 KB 0.1% 0.87
TOTAL: 24:00:00 7178 100.0% 0.0844.3 MB 100.0% 0.53
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 GB106.0 GB 6.9% 0 0.7026775.61
CYCNGS3NGS1536.0 GB48.0 MB 0.0% 0 0.00 0.00
CYCTXT1TEXT5.0 GB4.2 GB 83.7% 0 0.03 2095.55
TOTAL: 4-4613.0 GB110.3 GB 2.4% 1 0.48-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 551 2 0 0 0 0 0 0 549
2nntp.comgw.net 171 0 0 1 0 0 0 0 170
3endofthelinebbs-out.news.weretis.net 152 0 0 1 0 0 0 0 151
4news.hispagatos.org 127 15 0 0 0 0 0 0 112
5news.netfront.net 124 0 0 1 0 0 0 0 123
6usenet.goja.nl.eu.org 122 0 0 0 0 0 0 0 122
7194.87.241.33 90 0 0 0 0 0 0 0 90
8newsfeed.bofh.team 67 4 0 1 0 0 0 0 62
9i2pn.org 64 0 0 0 0 0 0 0 64
10news-out.tcpreset.net 40 0 0 0 0 0 0 0 40
11peering.usenet.ovh 13 0 0 0 0 0 0 0 13
12endofthelinebbs.peers.news.panix.com 12 1 0 0 0 0 0 0 11
13news.dne3.net 12 0 0 0 0 0 0 0 12
14newsfeed.xs3.de 11 0 0 0 0 0 0 0 11
15usenet.network 11 0 0 0 0 0 0 0 11
16news.samoylyk.net 2 0 0 0 0 0 0 0 2
17news.corradoroberto.it 1 0 0 0 0 0 0 0 1
TOTAL: 17 1570 22 0 4 0 0 0 0 1544

Unwanted newsgroups [Top 20]:

NewsgroupCount
hispagatos.mastodon.tag.hacking 13
alt.fan.trainspotting 4
hispagatos.mastodon.tag.hackers 3
alt.org.startrek.ufpi 2
TOTAL: 4 22

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 608
Too many newsgroups 339
EMP (phr path) 288
EMP (md5) 137
Too many newsgroups (meow) 36
Angle-bracket bot 34
Binary: misplaced binary 32
User-issued cancel 23
HTML Multipart 6
HTML post 2
hierarchy violation - crosspost outside sdnet 1
TOTAL: 11 1506

NoCeM on spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
robot@pasdenom.info (spam)30303
robot@pasdenom.info (spam2)34004545
i2pn2-nocem@i2pn2.org (multipost)25002828
robot@pasdenom.info (spam3)5002626
robot@pasdenom.info (spam4)5001414
i2pn2-nocem@i2pn2.org (rmp)30099
TOTAL: 67503122125

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
usenet.blueworldhosting.com 1
TOTAL: 1 1
Including strange strings
nntp.comgw.net 13
usenet.goja.nl.eu.org 6
usenet.blueworldhosting.com 4
i2pn.org 2
news.hispagatos.org 2
news.netfront.net 2
usenet.network 2
newsfeed.bofh.team 2
endofthelinebbs-out.news.weretis.net 1
news-out.tcpreset.net 1
TOTAL: 13 38
TOTAL: 2 39

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 10948 2696 4402 31 0 518 24%23:59:53
2nyheter.lysator.liu.se 8277 1753 4960 460 0 67 21%23:59:53
3news.furie.org.uk 4591 1157 2405 20 0 0 25%23:59:53
4photonic.trudheim.com 8001 743 4934 1495 0 50 9%23:59:53
5peering.usenet.ovh 7746 519 6477 46 0 0 6%23:59:53
6endofthelinebbs.peers.news.panix.com 7506 444 6628 4 0 0 5%23:59:53
7news.dne3.net 6206 297 5265 329 0 5 4%23:59:53
8news.nntp4.net 7981 231 6527 8 0 83 2%23:59:53
9usenet.goja.nl.eu.org 7542 224 6021 36 0 0 2%23:59:53
10newsfeed.bofh.team 8166 128 6730 0 0 30 1%23:59:53
11news.tcpreset.net 8306 93 5848 848 0 11 1%23:59:53
12nntp.comgw.net 7187 89 6566 34 0 31 1%23:59:53
13news.hispagatos.org 7763 86 6575 69 0 45 1%23:59:38
14news.bbs.nz 13196 85 6549 529 0 168 0%23:58:57
15news.nk.ca 10159 83 6561 434 0 112 0%23:59:53
16i2pn.org 7807 57 6686 22 0 50 0%23:59:38
17usenet.network 7874 52 6077 12 0 0 0%23:59:53
18newsfeed.xs3.de 8642 37 6737 198 0 36 0%23:59:50
19news.samoylyk.net 8754 33 6938 32 0 46 0%23:59:53
20news.weretis.net 7721 31 6852 289 0 0 0%23:59:14
21news.quux.org 7174 28 6339 364 0 0 0%23:59:53
22news.tnetconsulting.net 10510 23 6747 305 0 30 0%23:59:52
23usenet.blueworldhosting.com 7108 18 6319 1 0 3 0%23:59:06
24news.chmurka.net 4276 14 2996 0 0 0 0%23:58:47
25ddt.demos.su 94 11 79 0 0 0 11%09:01:47
26news.corradoroberto.it 1027 8 708 0 0 0 0%22:06:05
27feed.opticnetworks.net 0 0 0 0 0 7173 0%23:59:53
TOTAL: 27 194562 8940 142926 5566 0 8458 4%631:00:55
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net29.4 MB105.3 KB29.5 MB0.3 KB/s11.1 KB23:59:53
2nyheter.lysator.liu.se10.1 MB5.9 MB16.0 MB0.2 KB/s7.4 KB23:59:53
3news.furie.org.uk6.9 MB75.6 KB6.9 MB0.1 KB/s6.0 KB23:59:53
4newsfeed.bofh.team5.4 MB0.0 KB5.4 MB0.1 KB/s43.2 KB23:59:53
5peering.usenet.ovh4.4 MB181.1 KB4.6 MB0.1 KB/s8.3 KB23:59:53
6nntp.comgw.net4.3 MB686.0 KB5.0 MB0.1 KB/s41.5 KB23:59:53
7news.bbs.nz3.8 MB4.1 MB7.9 MB0.1 KB/s13.2 KB23:58:57
8photonic.trudheim.com3.5 MB11.5 MB15.0 MB0.2 KB/s6.8 KB23:59:53
9endofthelinebbs.peers.news.panix.com3.4 MB6.7 KB3.4 MB0.0 KB/s7.8 KB23:59:53
10news.hispagatos.org3.1 MB1.7 MB4.7 MB0.1 KB/s31.2 KB23:59:38
11newsfeed.xs3.de2.7 MB1.5 MB4.2 MB0.0 KB/s18.2 KB23:59:50
12news.samoylyk.net2.2 MB155.3 KB2.3 MB0.0 KB/s36.8 KB23:59:53
13news.nk.ca1.6 MB3.4 MB5.0 MB0.1 KB/s9.8 KB23:59:53
14usenet.goja.nl.eu.org674.0 KB563.4 KB1.2 MB0.0 KB/s4.8 KB23:59:53
15news.nntp4.net635.7 KB1.3 MB1.9 MB0.0 KB/s8.1 KB23:59:53
16news.dne3.net532.9 KB16.8 MB17.3 MB0.2 KB/s28.4 KB23:59:53
17news.quux.org223.2 KB2.7 MB2.9 MB0.0 KB/s7.6 KB23:59:53
18news.tcpreset.net159.6 KB5.2 MB5.3 MB0.1 KB/s5.8 KB23:59:53
19i2pn.org135.2 KB144.9 KB280.1 KB0.0 KB/s3.5 KB23:59:38
20usenet.network128.8 KB64.0 KB192.8 KB0.0 KB/s3.0 KB23:59:53
21news.tnetconsulting.net120.7 KB3.0 MB3.1 MB0.0 KB/s9.8 KB23:59:52
22news.weretis.net68.0 KB3.4 MB3.5 MB0.0 KB/s11.0 KB23:59:14
23news.chmurka.net63.5 KB0.0 KB63.5 KB0.0 KB/s4.5 KB23:58:47
24ddt.demos.su35.7 KB0.0 KB35.7 KB0.0 KB/s3.2 KB09:01:47
25usenet.blueworldhosting.com25.1 KB1.4 KB26.5 KB0.0 KB/s1.4 KB23:59:06
26news.corradoroberto.it10.9 KB0.0 KB10.9 KB0.0 KB/s1.4 KB22:06:05
27feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2783.5 MB62.4 MB145.8 MB0.1 KB/s10.3 KB631:00:55
Outgoing feeds (innfeed) by volume

NNRP readership statistics [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
12a0e:73c2:8000:802c::22782 00.0 KB 0 0 000:20:05
2news.nntp4.net2782 00.0 KB 0 0 000:22:05
3167.94.138.186 3 00.0 KB 0 0 000:00:08
4147.185.132.66 1 00.0 KB 0 0 000:00:00
5147.185.132.78 1 00.0 KB 0 0 000:00:02
6162.142.125.32 1 00.0 KB 0 0 000:00:06
7194.165.16.164 1 00.0 KB 0 0 000:00:00
82a06:4882:d000::dd 1 00.0 KB 0 0 000:00:01
9azpdssrvaql5.stretchoid.com 1 00.0 KB 0 0 000:00:09
10localhost 1 00.0 KB 0 0 000:12:07
TOTAL: 105574 00.0 KB 0 0 000:54:49

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved2790 00.0 KB 0 0 000:20:26
2*.nntp4.net2782 00.0 KB 0 0 000:22:05
3*.stretchoid.com 1 00.0 KB 0 0 000:00:09
4*localhost 1 00.0 KB 0 0 000:12:07
TOTAL: 45574 00.0 KB 0 0 000:54:49

NNRP auth users [Top 20]:

UserConn
getlist 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
news.nntp4.net 10.011 29.826 0.00000:22:05
2a0e:73c2:8000:802c::2 10.569 29.389 0.00000:20:05
localhost 186.921 373.368 0.07700:12:07
azpdssrvaql5.stretchoid.com 0.007 0.007 0.00000:00:09
167.94.138.186 0.028 0.025 0.00000:00:08
162.142.125.32 0.013 0.019 0.00000:00:06
147.185.132.78 0.007 0.007 0.00000:00:02
2a06:4882:d000::dd 0.000 0.014 0.00000:00:01
147.185.132.66 0.000 0.013 0.00000:00:00
194.165.16.164 0.000 0.014 0.00000:00:00
? 0.004 0.012 0.00000:00:00
TOTAL: 11 207.560 432.694 0.07700:54:49

NNRP gethostbyaddr failures [Top 20]:

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

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

SystemConn
162.142.125.32 1
167.94.138.186 1
194.165.16.164 1
TOTAL: 3 3

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

CommandCount
EHLO www.censys.io 2
#003 1
TOTAL: 2 3

NNRP client timeouts [Top 20]:

SystemConnPeer
167.94.138.186 1 2
194.165.16.164 1 1
TOTAL: 2 2 5