Daily Usenet Report

Mar 29 00:01:01 -- Mar 30 00:01:01

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Mar 29 00:02:14 www innd: message repeated 2 times: [ ctlinnd command s]
Mar 29 15:00:03 www pgpverify[1573975]: pgpverify: verification failed
Mar 29 15:00:11 www pgpverify[1573983]: pgpverify: verification failed
Mar 29 15:01:08 www pgpverify[1573994]: pgpverify: verification failed
Mar 29 15:01:08 www pgpverify[1574002]: pgpverify: verification failed
Mar 29 18:10:29 www nnrpd[1578909]: unable to get certificates from '/news/etc/cert.pem'
Mar 29 18:10:29 www nnrpd[1578909]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]
Mar 29 21:28:35 www nnrpd[1584257]: unable to get certificates from '/news/etc/cert.pem'
Mar 29 21:28:35 www nnrpd[1584257]: 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 15905 72.9%3.4 MB 80.4%
innd 3899 17.9%540.8 KB 12.5%
innfeed 1701 7.8%285.4 KB 6.6%
nnrpd 289 1.3%22.6 KB 0.5%
controlchan 5 0.0%0.3 KB 0.0%
pgpverify 4 0.0%0.4 KB 0.0%
TOTAL: 6 21803 100.0%4.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 89708 50.8%
Negative hits 71047 40.3%
Do not exist 15648 8.9%
Cache misses 34 0.0%
TOTAL: 4 176437 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.037 0.0%65 0.000 0.569 7.000
article cleanup00:00:00.286 0.0%15647 0.000 0.018 0.150
article logging00:00:00.777 0.0%15830 0.000 0.049 0.144
article parse00:00:00.471 0.0%22129 0.000 0.021 0.212
article write00:00:08.736 0.0%13650 0.333 0.640 3.423
artlog/artcncl00:00:00.000 0.0%65 0.000 0.000 0.000
data move00:00:00.267 0.0%177529 0.000 0.002 0.014
hisgrep/artcncl00:00:00.006 0.0%43 0.000 0.140 5.000
hishave/artcncl00:00:00.002 0.0%65 0.000 0.031 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:02.441 0.0%176601 0.003 0.014 0.096
history sync00:00:00.048 0.0%326 0.000 0.147 1.000
history write00:00:02.862 0.0%15640 0.052 0.183 0.766
hiswrite/artcncl00:00:00.001 0.0%22 0.000 0.045 1.000
idle23:58:10.407 99.8%174533 178.927 494.407 2553.021
nntp read00:00:05.745 0.0%175565 0.019 0.033 0.062
overview write00:00:07.978 0.0%13650 0.281 0.584 2.962
perl filter00:00:43.517 0.1%15640 1.867 2.782 6.906
python filter00:00:02.306 0.0%15642 0.062 0.147 0.440
site send00:00:04.637 0.0%27304 0.032 0.170 0.625
TOTAL: 24:00:38.83823:59:30.524 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.222 0.0%18010 0.000 0.012 0.218
article prepare00:00:00.004 0.0%13272 0.000 0.000 0.031
article read00:00:00.712 0.0%2148 0.000 0.331 20.667
backlog stats00:00:00.226 0.0%191376 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:04.142 0.0%137565 0.013 0.030 0.054
data write00:00:16.061 0.0%124884 0.068 0.129 0.288
idle23:57:38.213100.0%191376 169.193 450.726 1912.965
status file00:00:00.183 0.0%178 0.000 1.028 8.000
TOTAL: 23:58:07.92323:57:59.763100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:00.303 45.8%7 42.000 43.286 44.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.2%14 0.000 0.071 0.167
TOTAL: 00:00:00.66200:00:00.304 45.9%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000400
TOTAL000400

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 13 25632 11580 13689 363 45%47:34:53
2peer.alt119.net 1 15556 928 13327 1301 5%23:59:57
3newsfeed.xs3.de 4 12044 689 11348 7 5%24:02:48
4usenet.blueworldhosting.com 2 15265 218 14731 316 1%24:34:07
5glockenspiel.complete.org 1 12753 150 12603 0 1%23:59:55
6news.samoylyk.net 1 12473 134 12337 2 1%23:59:12
7news.bbs.nz 9 21826 28 21797 1 0%45:11:05
8localhost 27 2 2 0 0 100%00:00:00
9news.nk.ca 2 0 0 0 0 0%00:20:01
TOTAL: 9 60 115551 13729 99832 1990 11%213:41:58
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net43.7 MB4.6 KB551.0 KB44.3 MB 98%3.8 KB
2newsfeed.xs3.de2.8 MB2.7 KB21.8 KB2.8 MB 99%4.1 KB
3peer.alt119.net2.6 MB36.8 KB2.9 MB5.5 MB 46%2.5 KB
4news.samoylyk.net2.6 MB0.0 KB3.0 KB2.6 MB 99%19.4 KB
5glockenspiel.complete.org585.5 KB0.0 KB0.0 KB585.5 KB100%3.9 KB
6usenet.blueworldhosting.com520.1 KB1.8 KB909.4 KB1.4 MB 36%2.7 KB
7news.bbs.nz56.5 KB0.0 KB1.8 KB58.3 KB 96%2.0 KB
8localhost1.9 KB0.0 KB0.0 KB1.9 KB100%0.9 KB
9news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 952.8 MB45.9 KB4.4 MB57.2 MB 92%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 29 00:01:01 - 00:59:59 278 2.0% 0.081.0 MB 2.0% 0.30
Mar 29 01:00:00 - 01:59:59 346 2.5% 0.102.0 MB 3.8% 0.56
Mar 29 02:00:00 - 02:59:59 529 3.9% 0.151.7 MB 3.3% 0.49
Mar 29 03:00:00 - 03:59:59 703 5.1% 0.202.0 MB 3.9% 0.57
Mar 29 04:00:00 - 04:59:59 671 4.9% 0.191.9 MB 3.6% 0.53
Mar 29 05:00:00 - 05:59:59 646 4.7% 0.183.2 MB 6.2% 0.92
Mar 29 06:00:00 - 06:59:59 690 5.1% 0.192.7 MB 5.3% 0.77
Mar 29 07:00:00 - 07:59:59 783 5.7% 0.222.6 MB 5.0% 0.74
Mar 29 08:00:00 - 08:59:59 948 6.9% 0.263.0 MB 5.9% 0.86
Mar 29 09:00:00 - 09:59:59 822 6.0% 0.233.1 MB 6.0% 0.89
Mar 29 10:00:00 - 10:59:59 843 6.2% 0.232.4 MB 4.7% 0.69
Mar 29 11:00:00 - 11:59:59 791 5.8% 0.223.0 MB 5.8% 0.86
Mar 29 12:00:00 - 12:59:59 832 6.1% 0.233.3 MB 6.3% 0.92
Mar 29 13:00:00 - 13:59:59 662 4.8% 0.182.1 MB 4.1% 0.61
Mar 29 14:00:00 - 14:59:59 723 5.3% 0.203.2 MB 6.1% 0.90
Mar 29 15:00:00 - 15:59:59 709 5.2% 0.203.1 MB 5.9% 0.87
Mar 29 16:00:00 - 16:59:59 580 4.2% 0.161.9 MB 3.7% 0.54
Mar 29 17:00:00 - 17:59:59 499 3.7% 0.141.9 MB 3.6% 0.53
Mar 29 18:00:00 - 18:59:59 362 2.7% 0.101.5 MB 2.9% 0.43
Mar 29 19:00:00 - 19:59:59 254 1.9% 0.071010.2 KB 1.9% 0.28
Mar 29 20:00:00 - 20:59:59 264 1.9% 0.072.2 MB 4.3% 0.63
Mar 29 21:00:00 - 21:59:59 186 1.4% 0.05643.3 KB 1.2% 0.18
Mar 29 22:00:00 - 22:59:59 214 1.6% 0.06734.9 KB 1.4% 0.20
Mar 29 23:00:00 - 23:59:59 312 2.3% 0.091.5 MB 2.9% 0.43
Mar 30 00:00:00 - 00:01:01 13 0.1% 0.2141.0 KB 0.1% 0.67
TOTAL: 24:00:00 13660 100.0% 0.1651.7 MB 100.0% 0.61
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1peer.alt119.net 1332 34 0 1 0 0 0 0 1297
2endofthelinebbs-out.news.weretis.net 526 4 0 3 0 0 0 0 519
3usenet.blueworldhosting.com 297 13 0 1 0 0 0 0 283
4newsfeed.xs3.de 20 1 0 1 0 0 0 0 18
5glockenspiel.complete.org 2 0 0 1 0 0 0 0 1
6news.samoylyk.net 2 0 0 0 0 0 0 0 2
7news.bbs.nz 1 0 0 0 0 0 0 0 1
TOTAL: 7 2180 52 0 7 0 0 0 0 2121

Unwanted newsgroups [Top 20]:

NewsgroupCount
at.jobs 13
att.jobs 9
aus.jobs 8
0.0ssptest 4
us.test 4
trollnet.cinema 2
alt.sex.girl 2
rocksolid.feeds.news 2
alt.sex.fetish.feet.toes 1
alt.personals.sex 1
soc.culture.ukraine 1
panix.chat.politics 1
alt.bestjobsusa.philly.jobs 1
bigweek.general 1
grisbi.test 1
to 1
TOTAL: 16 52

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 751
EMP (phn path) 615
Too many newsgroups 397
EMP (phn nph) 155
EMP (phl) 6
Scoring filter 5
User-issued spam cancel 4
HTML Multipart 3
Too many newsgroups (meow) 2
Binary: misplaced binary 1
TOTAL: 10 1939

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 164
newsfeed.xs3.de 13
usenet.blueworldhosting.com 2
peer.alt119.net 2
glockenspiel.complete.org 1
TOTAL: 5 182
TOTAL: 1 182

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1peer.alt119.net 13552 1595 11060 4 0 0 11%23:59:30
2usenet.blueworldhosting.com 13831 1592 11813 38 0 3846 11%23:59:56
3news.bbs.nz 26519 14 13602 24 0 407 0%23:59:39
4news.nk.ca 14681 7 13629 10 0 6 0%23:59:56
5news.quux.org 13844 6 13520 1 0 0 0%23:59:56
6newsfeed.xs3.de 13172 4 12957 0 0 0 0%23:59:56
7news.weretis.net 13737 2 13640 18 0 0 0%23:59:56
8news.samoylyk.net 15795 1 13536 3 0 0 0%23:59:55
TOTAL: 8 125131 3221 103757 98 0 4259 2%191:58:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1peer.alt119.net9.0 MB11.7 KB9.1 MB0.1 KB/s5.8 KB23:59:30
2usenet.blueworldhosting.com6.4 MB90.4 KB6.5 MB0.1 KB/s4.1 KB23:59:56
3news.bbs.nz46.2 KB175.5 KB221.6 KB0.0 KB/s5.8 KB23:59:39
4news.quux.org18.6 KB6.3 KB24.8 KB0.0 KB/s3.5 KB23:59:56
5news.nk.ca11.0 KB30.1 KB41.1 KB0.0 KB/s2.4 KB23:59:56
6newsfeed.xs3.de6.0 KB0.0 KB6.0 KB0.0 KB/s1.5 KB23:59:56
7news.weretis.net3.4 KB45.3 KB48.7 KB0.0 KB/s2.4 KB23:59:56
8news.samoylyk.net1.1 KB3.9 KB5.0 KB0.0 KB/s1.2 KB23:59:55
TOTAL: 815.5 MB363.2 KB15.9 MB0.0 KB/s4.9 KB191:58:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.186.38.89 1 00.0 KB 1 0 000:00:00
TOTAL: 1 1 00.0 KB 1 0 000:00:00

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 1 00.0 KB 1 0 000:00:00
2*.ch1.censys-scanner.com 3 00.0 KB 0 0 000:00:00
TOTAL: 2 4 00.0 KB 1 0 000:00:00

NNRP auth users [Top 20]:

UserConn
nelgin 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
scanner-05.ch1.censys-scanner.com 0.117 0.055 0.00000:00:00
scanner-26.ch1.censys-scanner.com 0.123 0.044 0.00000:00:00
TOTAL: 2 0.240 0.099 0.00000:00:00

Curious NNRP server explorers [Top 20]:

SystemConn
scanner-05.ch1.censys-scanner.com 2
scanner-26.ch1.censys-scanner.com 1
TOTAL: 2 3

NNRP unrecognized commands (by host):

SystemConn
scanner-05.ch1.censys-scanner.com 1
scanner-26.ch1.censys-scanner.com 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 2
TOTAL: 1 2

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