Daily Usenet Report

Feb 23 00:01:02 -- Feb 24 00:01:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Feb 23 00:07:00 www innd: message repeated 2 times: [ ctlinnd command s]
Feb 23 15:16:00 www nnrpd[3857567]: unable to get certificates from '/news/etc/cert.pem'
Feb 23 15:16:00 www nnrpd[3857567]: 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 15233 67.6%4.0 MB 78.2%
innd 4950 22.0%709.2 KB 13.6%
innfeed 2329 10.3%423.9 KB 8.2%
nnrpd 23 0.1%2.7 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 22536 100.0%5.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 100793 57.4%
Negative hits 59718 34.0%
Do not exist 14918 8.5%
Cache misses 28 0.0%
TOTAL: 4 175457 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.099 0.0%65 0.000 1.523 15.000
article cleanup00:00:00.307 0.0%14956 0.000 0.021 0.136
article logging00:00:00.669 0.0%15180 0.000 0.044 0.129
article parse00:00:01.529 0.0%83473 0.000 0.018 0.105
article write00:00:12.744 0.0%12174 0.359 1.047 2.292
artlog/artcncl00:00:00.003 0.0%64 0.000 0.047 1.000
data move00:00:00.418 0.0%245488 0.000 0.002 0.004
hisgrep/artcncl00:00:00.020 0.0%63 0.000 0.317 10.000
hishave/artcncl00:00:00.010 0.0%65 0.000 0.154 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:07.951 0.0%175912 0.004 0.045 0.098
history sync00:00:00.043 0.0%387 0.000 0.111 1.000
history write00:00:07.584 0.0%14954 0.076 0.507 0.780
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:58:06.175 99.8%237129 82.629 363.879 2420.157
nntp read00:00:14.727 0.0%238087 0.018 0.062 0.129
overview write00:00:06.898 0.0%12174 0.184 0.567 4.533
perl filter00:00:38.227 0.0%14954 1.505 2.556 4.426
python filter00:00:02.486 0.0%14954 0.089 0.166 0.417
site send00:00:03.558 0.0%24348 0.041 0.146 0.417
TOTAL: 24:01:00.49223:59:43.448 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.143 0.0%12384 0.000 0.012 0.067
article prepare00:00:00.005 0.0%39152 0.000 0.000 0.005
article read00:00:01.521 0.0%5904 0.067 0.258 2.368
backlog stats00:00:00.516 0.0%268358 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.527 0.0%175988 0.016 0.031 0.047
data write00:00:22.872 0.0%164866 0.089 0.139 0.216
idle23:56:32.354100.0%268358 176.080 321.184 1170.688
status file00:00:00.176 0.0%194 0.000 0.907 4.000
TOTAL: 23:57:13.86323:57:03.114100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:30:10.031100.0%8 26.500226253.875900024.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.000 0.0%21 0.000 0.000 0.000
TOTAL: 00:30:10.87000:30:10.031100.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 5 22453 6816 15337 300 30%46:51:36
2usenet.blueworldhosting.com 5 13281 4196 8473 612 31%23:54:20
3outgoing.news.lysator.liu.se 2 13089 614 12243 232 4%23:59:48
4newsfeed.xs3.de 2 11114 416 10697 1 3%23:59:44
5news.samoylyk.net 2 11216 80 11133 3 0%23:59:47
6glockenspiel.complete.org 2 11031 23 11005 3 0%23:59:45
7news.furie.org.uk 2 6699 7 6692 0 0%23:59:52
8news.netfront.net 1 13743 6 12225 1512 0%20:59:41
9news.bbs.nz 3 18919 4 18915 0 0%39:29:17
10eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:14
11news.nk.ca 2 0 0 0 0 0%00:21:11
12localhost 25 0 0 0 0 0%00:00:00
TOTAL: 12 52 121545 12162 106720 2663 10%251:45:15
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.blueworldhosting.com25.4 MB0.0 KB2.9 MB28.2 MB 89%6.0 KB
2endofthelinebbs-out.news.weretis.net22.1 MB0.0 KB515.2 KB22.6 MB 97%3.3 KB
3newsfeed.xs3.de1.5 MB0.0 KB1.3 KB1.5 MB 99%3.7 KB
4outgoing.news.lysator.liu.se1.1 MB0.0 KB628.8 KB1.7 MB 64%2.1 KB
5news.samoylyk.net370.9 KB0.0 KB6.4 KB377.2 KB 98%4.5 KB
6glockenspiel.complete.org111.0 KB0.0 KB4.3 KB115.3 KB 96%4.4 KB
7news.furie.org.uk24.3 KB0.0 KB0.0 KB24.3 KB100%3.5 KB
8news.netfront.net19.8 KB0.0 KB500.8 MB500.8 MB 0%337.8 KB
9news.bbs.nz7.2 KB0.0 KB0.0 KB7.2 KB100%1.8 KB
10eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
11news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1250.6 MB0.0 KB504.8 MB555.4 MB 9%38.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 23 00:01:02 - 00:59:59 258 2.1% 0.071.6 MB 3.2% 0.47
Feb 23 01:00:00 - 01:59:59 385 3.2% 0.111.3 MB 2.6% 0.38
Feb 23 02:00:00 - 02:59:59 502 4.1% 0.141.9 MB 3.7% 0.53
Feb 23 03:00:00 - 03:59:59 645 5.3% 0.182.7 MB 5.3% 0.76
Feb 23 04:00:00 - 04:59:59 547 4.5% 0.151.8 MB 3.5% 0.51
Feb 23 05:00:00 - 05:59:59 616 5.1% 0.172.1 MB 4.2% 0.60
Feb 23 06:00:00 - 06:59:59 583 4.8% 0.162.3 MB 4.5% 0.65
Feb 23 07:00:00 - 07:59:59 580 4.8% 0.163.1 MB 6.1% 0.88
Feb 23 08:00:00 - 08:59:59 643 5.3% 0.182.4 MB 4.7% 0.68
Feb 23 09:00:00 - 09:59:59 782 6.4% 0.222.8 MB 5.5% 0.80
Feb 23 10:00:00 - 10:59:59 716 5.9% 0.202.9 MB 5.7% 0.82
Feb 23 11:00:00 - 11:59:59 619 5.1% 0.171.9 MB 3.8% 0.55
Feb 23 12:00:00 - 12:59:59 567 4.7% 0.162.0 MB 4.0% 0.57
Feb 23 13:00:00 - 13:59:59 677 5.6% 0.194.1 MB 8.1% 1.17
Feb 23 14:00:00 - 14:59:59 596 4.9% 0.172.1 MB 4.2% 0.60
Feb 23 15:00:00 - 15:59:59 626 5.1% 0.172.0 MB 3.9% 0.56
Feb 23 16:00:00 - 16:59:59 562 4.6% 0.162.3 MB 4.6% 0.67
Feb 23 17:00:00 - 17:59:59 493 4.1% 0.142.4 MB 4.8% 0.69
Feb 23 18:00:00 - 18:59:59 400 3.3% 0.112.1 MB 4.2% 0.60
Feb 23 19:00:00 - 19:59:59 348 2.9% 0.101.3 MB 2.6% 0.37
Feb 23 20:00:00 - 20:59:59 250 2.1% 0.07863.9 KB 1.7% 0.24
Feb 23 21:00:00 - 21:59:59 328 2.7% 0.092.6 MB 5.1% 0.73
Feb 23 22:00:00 - 22:59:59 208 1.7% 0.061.1 MB 2.1% 0.30
Feb 23 23:00:00 - 23:59:59 224 1.8% 0.061.1 MB 2.1% 0.30
Feb 24 00:00:00 - 00:01:02 9 0.1% 0.1527.6 KB 0.1% 0.44
TOTAL: 24:00:00 12164 100.0% 0.1450.8 MB 100.0% 0.60
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 1569 0 0 0 0 0 0 0 1569
2usenet.blueworldhosting.com 618 11 0 0 0 0 0 0 607
3endofthelinebbs-out.news.weretis.net 509 0 0 1 0 0 0 0 508
4outgoing.news.lysator.liu.se 289 0 0 0 0 0 0 0 289
5newsfeed.xs3.de 12 0 0 0 0 0 0 0 12
6glockenspiel.complete.org 5 0 0 1 0 0 0 0 4
7news.samoylyk.net 3 0 0 0 0 0 0 0 3
TOTAL: 7 3005 11 0 2 0 0 0 0 2992

Unwanted newsgroups [Top 20]:

NewsgroupCount
rocksolid.feeds.news 5
test.news 2
angelugnergmail.com 1
alt.penthouse.sex.first-time 1
alt.bestjobsusa.portland.jobs 1
alt.support.chronic.pain 1
TOTAL: 6 11

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 555
EMP (phn path) 452
Binary: misplaced binary fx04.ams4.POSTED 374
Binary: misplaced binary fx03.ams4.POSTED 240
Binary: misplaced binary fx11.ams4.POSTED 142
Binary: misplaced binary fx15.ams4.POSTED 135
Binary: misplaced binary fx05.ams4.POSTED 132
Binary: misplaced binary fx14.ams4.POSTED 104
Too many newsgroups 86
Binary: misplaced binary fx07.ams4.POSTED 80
EMP (phl) 75
Binary: misplaced binary fx13.ams4.POSTED 71
Binary: misplaced binary fx06.ams4.POSTED 68
Binary: misplaced binary fx10.ams4.POSTED 58
Binary: misplaced binary fx01.ams4.POSTED 42
Binary: misplaced binary fx09.ams4.POSTED 33
Binary: misplaced binary fx12.ams4.POSTED 30
Binary: misplaced binary fx08.ams4.POSTED 30
Scoring filter 28
Binary: misplaced binary fx02.ams4.POSTED 20
TOTAL: 23 2767

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 203
newsfeed.xs3.de 11
usenet.blueworldhosting.com 6
outgoing.news.lysator.liu.se 3
news.samoylyk.net 1
glockenspiel.complete.org 1
TOTAL: 6 225
TOTAL: 1 225

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 17114 5556 6587 16 0 10 32%23:59:56
2nyheter.lysator.liu.se 11779 3618 7917 3 0 0 30%23:59:55
3news.nk.ca 16081 198 11799 91 0 161 1%23:59:56
4usenet.blueworldhosting.com 9311 40 7896 0 0 11 0%23:59:57
5news.bbs.nz 23703 36 12058 67 0 48 0%23:59:55
6news.quux.org 12592 25 11987 125 0 0 0%23:59:52
7news.furie.org.uk 9072 11 6833 1 0 0 0%23:59:56
8news.weretis.net 12256 5 12157 0 0 0 0%23:59:57
9news.tnetconsulting.net 12949 3 12156 0 0 0 0%23:59:41
10peer.alt119.net 0 0 0 0 0 12160 0%23:57:48
11newsfeed.xs3.de 15330 0 11745 2 0 0 0%23:59:56
12news.samoylyk.net 15656 0 12080 0 0 0 0%23:59:56
TOTAL: 12 155843 9492 113215 305 0 12390 6%287:56:45
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net22.9 MB82.4 KB23.0 MB0.3 KB/s4.2 KB23:59:56
2nyheter.lysator.liu.se16.2 MB6.3 KB16.2 MB0.2 KB/s4.6 KB23:59:55
3news.nk.ca991.9 KB360.4 KB1.3 MB0.0 KB/s4.7 KB23:59:56
4news.bbs.nz219.7 KB372.3 KB592.1 KB0.0 KB/s5.7 KB23:59:55
5news.quux.org157.9 KB775.8 KB933.8 KB0.0 KB/s6.2 KB23:59:52
6usenet.blueworldhosting.com131.5 KB0.0 KB131.5 KB0.0 KB/s3.3 KB23:59:57
7news.furie.org.uk107.6 KB126.8 KB234.5 KB0.0 KB/s19.5 KB23:59:56
8news.weretis.net16.5 KB0.0 KB16.5 KB0.0 KB/s3.3 KB23:59:57
9news.tnetconsulting.net8.0 KB0.0 KB8.0 KB0.0 KB/s2.7 KB23:59:41
10peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
11newsfeed.xs3.de0.0 KB5.5 KB5.5 KB0.0 KB/s2.8 KB23:59:56
12news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
TOTAL: 1240.7 MB1.7 MB42.4 MB0.0 KB/s4.4 KB287:56:45
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1192.241.200.18 1 00.0 KB 0 0 000:00:00
2111.7.96.150 1 00.0 KB 0 0 000:30:00
3198.199.95.14 1 00.0 KB 0 0 000:00:09
4scanner-04.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 4 00.0 KB 0 0 000:30:10

NNRP readership statistics (by domain):

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

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
111.7.96.150 0.087 0.021 0.00000:30:00
198.199.95.14 0.095 0.017 0.00000:00:09
scanner-04.ch1.censys-scanner.com 0.086 0.046 0.00000:00:00
192.241.200.18 0.082 0.030 0.00000:00:00
TOTAL: 4 0.350 0.114 0.00000:30:10

NNRP unrecognized commands (by host):

SystemConn
111.7.96.150 3
192.241.200.18 1
scanner-04.ch1.censys-scanner.com 1
TOTAL: 3 5

NNRP unrecognized commands (by command):

CommandCount
À#024 1
EHLO www.censys.io 1
#023#001#023#002#023#003#001 1
#026#003#001 1
MGLNDD_192.138.210.162_119 1
TOTAL: 5 5

NNRP client timeouts [Top 20]:

SystemConnPeer
111.7.96.150 1 0
TOTAL: 1 1 0