Daily Usenet Report

Apr 4 00:01:01 -- Apr 5 00:01:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Apr 4 00:02:22 www innd: message repeated 2 times: [ ctlinnd command s]
Apr 4 04:20:02 www innd: tradspool: could not symlink /news/spool/articles/talk/politics/guns/54689 to /news/spool/articles/us/politics/elections/14: File exists
Apr 4 04:20:02 www innd: tradspool: could not symlink /news/spool/articles/talk/politics/guns/54690 to /news/spool/articles/us/politics/elections/15: File exists
Apr 4 04:20:03 www innd: tradspool: could not symlink /news/spool/articles/talk/politics/guns/54691 to /news/spool/articles/us/politics/elections/16: File exists
Apr 4 04:20:03 www innd: tradspool: could not symlink /news/spool/articles/talk/politics/guns/54692 to /news/spool/articles/us/politics/elections/17: File exists
Apr 4 04:20:03 www innd: tradspool: could not symlink /news/spool/articles/talk/politics/guns/54693 to /news/spool/articles/us/politics/elections/18: File exists
Apr 4 04:20:04 www innd: tradspool: could not symlink /news/spool/articles/talk/politics/guns/54694 to /news/spool/articles/us/politics/elections/19: File exists
Apr 4 04:20:07 www innd: tradspool: could not symlink /news/spool/articles/talk/politics/guns/54695 to /news/spool/articles/us/politics/elections/20: File exists
Apr 4 04:52:54 www nnrpd[1851804]: unable to get certificates from '/news/etc/cert.pem'
Apr 4 04:52:54 www nnrpd[1851804]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]

Log entries by program:

Program nameLines%LinesSize%Size
inn 23794 65.9%4.6 MB 75.6%
innd 10280 28.5%1.2 MB 18.8%
innfeed 1821 5.0%332.3 KB 5.3%
nnrpd 237 0.7%18.4 KB 0.3%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 36133 100.0%6.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 105452 47.4%
Negative hits 93815 42.1%
Do not exist 23339 10.5%
Cache misses 23 0.0%
TOTAL: 4 222629 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.040 0.0%56 0.000 0.714 4.000
article cleanup00:00:00.447 0.0%23428 0.000 0.019 0.072
article logging00:00:01.146 0.0%23780 0.000 0.048 0.152
article parse00:00:00.718 0.0%30937 0.000 0.023 0.132
article write00:00:08.610 0.0%14906 0.235 0.578 2.733
artlog/artcncl00:00:00.002 0.0%56 0.000 0.036 0.250
artlog/artwrite00:00:00.000 0.0%7 0.000 0.000 0.000
data move00:00:00.313 0.0%222563 0.000 0.001 0.005
hisgrep/artcncl00:00:00.003 0.0%37 0.000 0.081 1.000
hishave/artcncl00:00:00.001 0.0%56 0.000 0.018 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:02.789 0.0%223303 0.002 0.012 0.102
history sync00:00:00.054 0.0%387 0.000 0.140 1.000
history write00:00:03.790 0.0%23421 0.023 0.162 0.750
hiswrite/artcncl00:00:00.002 0.0%19 0.000 0.105 0.143
idle23:57:36.657 99.8%218271 121.783 395.181 1743.913
nntp read00:00:07.566 0.0%220118 0.015 0.034 0.058
overview write00:00:08.580 0.0%14899 0.281 0.576 1.921
perl filter00:01:03.103 0.1%23428 1.907 2.693 5.966
python filter00:00:03.332 0.0%23428 0.062 0.142 0.517
site send00:00:06.354 0.0%29798 0.026 0.213 0.500
TOTAL: 24:00:57.05823:59:23.507 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.168 0.0%15675 0.000 0.011 0.126
article prepare00:00:00.002 0.0%4104 0.000 0.000 0.016
article read00:00:00.334 0.0%785 0.000 0.425 21.500
backlog stats00:00:00.332 0.0%222202 0.000 0.001 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.028 0.0%164037 0.016 0.031 0.060
data write00:00:20.201 0.0%150511 0.082 0.134 0.225
idle23:55:31.476100.0%222202 128.665 387.627 1478.875
status file00:00:00.173 0.0%166 0.000 1.042 4.000
TOTAL: 23:56:07.16323:55:57.714100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle19:37:16.373100.0%1388 42.000 50890.759 51111.489
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.404 0.0%2837 0.140 0.142 2.000
readart00:00:00.010 0.0%94 0.106 0.106 0.106
TOTAL: 19:37:17.80319:37:16.787100.0%----

Control commands to INND:

CommandNumber
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 146
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 167

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 8 30801 10708 18581 1512 34%48:48:28
2peer.alt119.net 2 20380 3186 13543 3651 15%24:23:20
3newsfeed.xs3.de 2 13865 621 13225 19 4%23:59:15
4usenet.blueworldhosting.com 2 19599 147 16022 3430 0%19:22:37
5glockenspiel.complete.org 2 12190 93 12095 2 0%23:59:53
6news.samoylyk.net 1 14057 53 14003 1 0%23:59:48
7news.bbs.nz 1 12096 27 12067 2 0%23:59:45
8localhost 25 0 0 0 0 0%00:00:00
9eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:04
10news.nk.ca 1 0 0 0 0 0%00:10:00
TOTAL: 10 45 122988 14835 99536 8617 12%188:53:10
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net40.6 MB0.0 KB2.3 MB42.9 MB 94%3.6 KB
2peer.alt119.net8.5 MB0.0 KB12.4 MB20.8 MB 40%3.1 KB
3newsfeed.xs3.de2.4 MB0.0 KB27.1 KB2.5 MB 98%3.9 KB
4news.samoylyk.net1.8 MB0.0 KB1.7 KB1.8 MB 99%33.8 KB
5usenet.blueworldhosting.com640.9 KB0.0 KB4.6 MB5.2 MB 12%1.5 KB
6glockenspiel.complete.org572.8 KB0.0 KB5.1 KB577.9 KB 99%6.1 KB
7news.bbs.nz51.2 KB0.0 KB5.7 KB56.8 KB 90%2.0 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
9eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
10news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1054.5 MB0.0 KB19.3 MB73.8 MB 73%3.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 4 00:01:01 - 00:59:59 242 1.6% 0.07854.9 KB 1.5% 0.24
Apr 4 01:00:00 - 01:59:59 518 3.5% 0.141.7 MB 3.2% 0.49
Apr 4 02:00:00 - 02:59:59 584 3.9% 0.162.6 MB 4.8% 0.74
Apr 4 03:00:00 - 03:59:59 721 4.8% 0.202.1 MB 3.9% 0.60
Apr 4 04:00:00 - 04:59:59 682 4.6% 0.192.5 MB 4.7% 0.72
Apr 4 05:00:00 - 05:59:59 577 3.9% 0.162.1 MB 3.9% 0.60
Apr 4 06:00:00 - 06:59:59 640 4.3% 0.183.6 MB 6.7% 1.04
Apr 4 07:00:00 - 07:59:59 897 6.0% 0.252.8 MB 5.2% 0.81
Apr 4 08:00:00 - 08:59:59 929 6.2% 0.263.2 MB 5.9% 0.91
Apr 4 09:00:00 - 09:59:59 820 5.5% 0.232.7 MB 4.9% 0.76
Apr 4 10:00:00 - 10:59:59 1110 7.4% 0.314.1 MB 7.6% 1.16
Apr 4 11:00:00 - 11:59:59 919 6.2% 0.263.9 MB 7.2% 1.11
Apr 4 12:00:00 - 12:59:59 675 4.5% 0.192.3 MB 4.3% 0.66
Apr 4 13:00:00 - 13:59:59 856 5.7% 0.242.7 MB 4.9% 0.76
Apr 4 14:00:00 - 14:59:59 713 4.8% 0.202.1 MB 3.9% 0.59
Apr 4 15:00:00 - 15:59:59 744 5.0% 0.212.2 MB 4.0% 0.62
Apr 4 16:00:00 - 16:59:59 695 4.7% 0.192.9 MB 5.4% 0.82
Apr 4 17:00:00 - 17:59:59 606 4.1% 0.172.5 MB 4.6% 0.71
Apr 4 18:00:00 - 18:59:59 623 4.2% 0.172.0 MB 3.8% 0.58
Apr 4 19:00:00 - 19:59:59 352 2.4% 0.101.2 MB 2.3% 0.35
Apr 4 20:00:00 - 20:59:59 283 1.9% 0.08892.1 KB 1.6% 0.25
Apr 4 21:00:00 - 21:59:59 231 1.5% 0.061.6 MB 2.9% 0.44
Apr 4 22:00:00 - 22:59:59 229 1.5% 0.06699.2 KB 1.3% 0.19
Apr 4 23:00:00 - 23:59:59 252 1.7% 0.07863.0 KB 1.6% 0.24
Apr 5 00:00:00 - 00:01:02 9 0.1% 0.1514.8 KB 0.0% 0.24
TOTAL: 24:00:01 14907 100.0% 0.1754.1 MB 100.0% 0.64
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1peer.alt119.net 3672 32 0 0 0 0 0 0 3640
2usenet.blueworldhosting.com 3284 66 0 0 0 0 0 0 3218
3endofthelinebbs-out.news.weretis.net 1835 3 0 0 0 0 0 0 1832
4newsfeed.xs3.de 31 0 0 0 0 0 0 0 31
5glockenspiel.complete.org 4 2 0 0 0 0 0 0 2
6news.samoylyk.net 3 0 0 0 0 0 0 0 3
7news.bbs.nz 2 0 0 0 0 0 0 0 2
TOTAL: 7 8831 103 0 0 0 0 0 0 8728

Unwanted newsgroups [Top 20]:

NewsgroupCount
talk.english.tg 36
at.jobs 13
att.jobs 9
0.test 9
aus.jobs 6
comp.hackers 3
al.christnet.bible 3
rocksolid.feeds.news 3
panix.chat.politics 2
alt.rock-n-roll.psychedelic 2
fr.petites-annonces.divers 2
grisbi.bugsreports 2
news.groups.music.classical 2
soc.culture.ukraine 1
mozilla.reps.general 1
alt.islam 1
talk.generic.ua.ws 1
panix.chat 1
0.0ssptest 1
soc.men.alt.feminism 1
TOTAL: 24 103

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 5238
EMP (md5) 1566
Too many newsgroups 1324
EMP (phn nph) 162
EMP (phl) 36
EMP (fsl) 13
Scoring filter 11
HTML Multipart 6
User-issued spam cancel 4
Subject (buy ketamine for depression) 1
Binary: misplaced binary 1
TOTAL: 11 8362

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 317
peer.alt119.net 28
newsfeed.xs3.de 12
news.samoylyk.net 2
TOTAL: 4 359
TOTAL: 1 359

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1peer.alt119.net 12539 609 11109 8 0 37 4%23:59:58
2usenet.blueworldhosting.com 15834 304 14334 0 0 15 1%23:59:56
3news.bbs.nz 29098 17 14840 22 0 646 0%23:59:58
4news.nk.ca 16417 13 14864 11 0 19 0%23:59:58
5news.weretis.net 15006 3 14864 36 0 1 0%23:59:58
6news.samoylyk.net 16310 0 14851 1 0 33 0%23:59:57
7news.quux.org 15241 0 14788 2 0 7 0%23:59:46
8newsfeed.xs3.de 14450 0 14272 5 0 15 0%23:59:58
9news.tnetconsulting.net 16361 0 14902 0 0 36 0%23:59:58
TOTAL: 9 151256 946 128824 85 0 809 0%215:59:27
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1peer.alt119.net3.8 MB24.7 KB3.8 MB0.0 KB/s6.3 KB23:59:58
2usenet.blueworldhosting.com1.6 MB0.0 KB1.6 MB0.0 KB/s5.5 KB23:59:56
3news.bbs.nz47.4 KB283.5 KB330.9 KB0.0 KB/s8.5 KB23:59:58
4news.nk.ca44.6 KB31.1 KB75.7 KB0.0 KB/s3.2 KB23:59:58
5news.weretis.net4.7 KB105.4 KB110.1 KB0.0 KB/s2.8 KB23:59:58
6news.samoylyk.net0.0 KB1.6 KB1.6 KB0.0 KB/s1.6 KB23:59:57
7news.quux.org0.0 KB3.0 KB3.0 KB0.0 KB/s1.5 KB23:59:46
8newsfeed.xs3.de0.0 KB8.8 KB8.8 KB0.0 KB/s1.8 KB23:59:58
9news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:58
TOTAL: 95.5 MB458.2 KB6.0 MB0.0 KB/s5.9 KB215:59:27
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.186.38.89 1 12.2 KB 1 0 019:37:17
TOTAL: 1 1 12.2 KB 1 0 019:37:17

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 2 12.2 KB 1 0 019:37:17
2*.ch1.censys-scanner.com 2 00.0 KB 0 0 000:00:00
TOTAL: 2 4 12.2 KB 1 0 019:37:17

NNRP auth users [Top 20]:

UserConn
nelgin 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.186.38.89 0.576 0.809 0.00619:37:17
scanner-08.ch1.censys-scanner.com 0.147 0.069 0.00000:00:00
94.102.61.41 0.061 0.050 0.00000:00:00
TOTAL: 3 0.784 0.928 0.00619:37:17

Curious NNRP server explorers [Top 20]:

SystemConn
scanner-08.ch1.censys-scanner.com 2
94.102.61.41 1
TOTAL: 2 3

NNRP unrecognized commands (by host):

SystemConn
94.102.61.41 5
scanner-08.ch1.censys-scanner.com 1
TOTAL: 2 6

NNRP unrecognized commands (by command):

CommandCount
Accept-Encoding: gzip 1
EHLO www.censys.io 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
Host: 192.138.210.162:119 1
Accept: */* 1
GET / HTTP/1.1 1
TOTAL: 6 6

Newsgroup request counts (by category):

CategoryCountPct
1news 1100.0%
TOTAL: 1 1100.0%

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

NewsgroupCount
1news.software.nntp 1
TOTAL: 1 1