Daily Usenet Report

Feb 9 00:01:01 -- Feb 10 00:01:01

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Feb 9 00:06:37 www innd: message repeated 2 times: [ ctlinnd command s]
Feb 9 06:06:19 www nnrpd[1083500]: unable to get certificates from '/news/etc/cert.pem'
Feb 9 06:06:19 www nnrpd[1083500]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]
Feb 9 10:21:51 www nnrpd[1226442]: unable to get certificates from '/news/etc/cert.pem'
Feb 9 10:21:51 www nnrpd[1226442]: 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 15448 65.6%3.9 MB 76.5%
innd 5723 24.3%788.4 KB 15.2%
innfeed 2325 9.9%423.5 KB 8.2%
nnrpd 40 0.2%4.3 KB 0.1%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 5 23537 100.0%5.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 94900 57.0%
Negative hits 56464 33.9%
Do not exist 15163 9.1%
Cache misses 35 0.0%
TOTAL: 4 166562 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.089 0.0%58 0.000 1.534 6.000
article cleanup00:00:00.345 0.0%15184 0.000 0.023 0.122
article logging00:00:00.702 0.0%15368 0.000 0.046 0.170
article parse00:00:02.114 0.0%108650 0.000 0.019 0.063
article write00:00:09.831 0.0%11515 0.347 0.854 2.975
artlog/artcncl00:00:00.001 0.0%58 0.000 0.017 0.500
data move00:00:00.812 0.0%262359 0.000 0.003 0.010
hisgrep/artcncl00:00:00.015 0.0%58 0.000 0.259 2.000
hishave/artcncl00:00:00.007 0.0%58 0.000 0.121 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.061 0.0%166813 0.004 0.048 0.101
history sync00:00:00.062 0.0%337 0.000 0.184 2.500
history write00:00:09.136 0.0%15183 0.102 0.602 0.868
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:57:34.032 99.8%249973 24.975 345.053 1470.029
nntp read00:00:20.808 0.0%251908 0.022 0.083 0.201
overview write00:00:07.518 0.0%11515 0.243 0.653 5.370
perl filter00:00:41.974 0.0%15183 1.669 2.765 6.755
python filter00:00:03.649 0.0%15183 0.091 0.240 1.214
site send00:00:04.237 0.0%23030 0.032 0.184 0.442
TOTAL: 24:00:46.52723:59:23.393 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.150 0.0%11541 0.000 0.013 0.130
article prepare00:00:00.014 0.0%37292 0.000 0.000 0.011
article read00:00:01.571 0.0%5775 0.071 0.272 3.538
backlog stats00:00:00.470 0.0%234599 0.000 0.002 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.553 0.0%154258 0.021 0.036 0.060
data write00:00:20.969 0.0%143872 0.100 0.146 0.239
idle23:55:12.420100.0%234599 201.551 367.062 992.460
status file00:00:00.166 0.0%183 0.000 0.907 4.000
TOTAL: 23:55:52.47723:55:41.313100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:30:04.231 99.9%14 20.667128873.643900000.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%33 0.000 0.030 0.500
TOTAL: 00:30:06.12300:30:04.232 99.9%----

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 3 20605 6073 14210 322 29%44:42:40
2usenet.blueworldhosting.com 5 13004 4280 8036 688 32%25:02:52
3outgoing.news.lysator.liu.se 2 12274 655 11343 276 5%23:59:48
4newsfeed.xs3.de 1 10387 355 10031 1 3%23:59:55
5news.samoylyk.net 2 10539 75 10461 3 0%23:59:31
6glockenspiel.complete.org 1 10827 42 10781 4 0%23:59:54
7news.bbs.nz 2 4249 2 4247 0 0%13:10:06
8news.furie.org.uk 1 6072 1 6071 0 0%23:59:54
9news.netfront.net 3 14340 1 12101 2238 0%23:01:29
10localhost 25 0 0 0 0 0%00:00:00
11eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:05
12news.nk.ca 2 0 0 0 0 0%00:20:02
TOTAL: 12 48 102297 11484 87281 3532 11%226:26:16
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.blueworldhosting.com29.5 MB0.0 KB2.8 MB32.3 MB 91%6.7 KB
2endofthelinebbs-out.news.weretis.net18.7 MB0.0 KB573.3 KB19.3 MB 97%3.1 KB
3outgoing.news.lysator.liu.se1.7 MB0.0 KB786.3 KB2.5 MB 69%2.7 KB
4newsfeed.xs3.de1.3 MB0.0 KB1.2 KB1.3 MB 99%3.7 KB
5glockenspiel.complete.org314.5 KB19.3 KB33.0 KB366.7 KB 85%8.0 KB
6news.samoylyk.net259.0 KB0.0 KB4.3 KB263.3 KB 98%3.4 KB
7news.bbs.nz12.7 KB0.0 KB0.0 KB12.7 KB100%6.4 KB
8news.netfront.net3.0 KB0.0 KB746.2 MB746.2 MB 0%341.3 KB
9news.furie.org.uk0.5 KB0.0 KB0.0 KB0.5 KB100%0.5 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
11eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1251.8 MB19.3 KB750.3 MB802.1 MB 6%54.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 9 00:01:01 - 00:59:59 243 2.1% 0.071.8 MB 3.5% 0.53
Feb 9 01:00:00 - 01:59:59 415 3.6% 0.121.5 MB 2.8% 0.42
Feb 9 02:00:00 - 02:59:59 522 4.5% 0.142.6 MB 5.0% 0.74
Feb 9 03:00:00 - 03:59:59 593 5.1% 0.161.7 MB 3.3% 0.48
Feb 9 04:00:00 - 04:59:59 526 4.6% 0.152.1 MB 4.1% 0.61
Feb 9 05:00:00 - 05:59:59 554 4.8% 0.152.0 MB 3.9% 0.57
Feb 9 06:00:00 - 06:59:59 583 5.1% 0.161.5 MB 2.9% 0.44
Feb 9 07:00:00 - 07:59:59 618 5.4% 0.173.6 MB 6.9% 1.02
Feb 9 08:00:00 - 08:59:59 621 5.4% 0.171.9 MB 3.6% 0.53
Feb 9 09:00:00 - 09:59:59 607 5.3% 0.171.9 MB 3.6% 0.53
Feb 9 10:00:00 - 10:59:59 689 6.0% 0.192.4 MB 4.6% 0.68
Feb 9 11:00:00 - 11:59:59 660 5.7% 0.183.4 MB 6.5% 0.96
Feb 9 12:00:00 - 12:59:59 682 5.9% 0.193.1 MB 6.1% 0.90
Feb 9 13:00:00 - 13:59:59 603 5.2% 0.171.9 MB 3.7% 0.55
Feb 9 14:00:00 - 14:59:59 525 4.6% 0.152.8 MB 5.3% 0.79
Feb 9 15:00:00 - 15:59:59 541 4.7% 0.152.3 MB 4.5% 0.66
Feb 9 16:00:00 - 16:59:59 422 3.7% 0.122.5 MB 4.7% 0.70
Feb 9 17:00:00 - 17:59:59 421 3.6% 0.122.2 MB 4.2% 0.62
Feb 9 18:00:00 - 18:59:59 313 2.7% 0.093.5 MB 6.7% 0.99
Feb 9 19:00:00 - 19:59:59 320 2.8% 0.091.2 MB 2.2% 0.33
Feb 9 20:00:00 - 20:59:59 271 2.3% 0.081.1 MB 2.1% 0.31
Feb 9 21:00:00 - 21:59:59 314 2.7% 0.091.3 MB 2.6% 0.38
Feb 9 22:00:00 - 22:59:59 206 1.8% 0.062.5 MB 4.8% 0.71
Feb 9 23:00:00 - 23:59:59 277 2.4% 0.081.3 MB 2.4% 0.36
Feb 10 00:00:00 - 00:01:01 9 0.1% 0.1522.8 KB 0.0% 0.37
TOTAL: 24:00:00 11535 100.0% 0.1352.0 MB 100.0% 0.62
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 2381 0 0 0 0 0 0 0 2381
2usenet.blueworldhosting.com 697 110 0 0 1 0 0 0 586
3endofthelinebbs-out.news.weretis.net 508 0 0 0 0 0 0 0 508
4outgoing.news.lysator.liu.se 251 4 0 0 0 0 0 0 247
5newsfeed.xs3.de 10 0 0 0 0 0 0 0 10
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 3855 114 0 1 1 0 0 0 3739

Unwanted newsgroups [Top 20]:

NewsgroupCount
panix.test 49
panix.staff.general 25
panix.chat 14
rocksolid.feeds.news 5
panix.questions 4
panix.staff.netbsd.current-users 4
de.org.politik.misc 4
panix.staff.tech-staff 2
mailbundestag.de 1
alt.culture.underwear 1
panix.staff.netbsd.users 1
panix.staff.netbsd.announce 1
panix.upgrade 1
rocksolid.feeds.tech 1
panix.user.movies 1
TOTAL: 15 114

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
misc.taxes.moderated 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Binary: misplaced binary fx15.ams4.POSTED 1301
EMP (md5) 573
EMP (phn path) 461
Binary: misplaced binary fx03.ams4.POSTED 209
Binary: misplaced binary fx08.ams4.POSTED 153
Binary: misplaced binary fx11.ams4.POSTED 141
Binary: misplaced binary fx06.ams4.POSTED 138
Binary: misplaced binary fx05.ams4.POSTED 128
Binary: misplaced binary fx01.ams4.POSTED 92
Binary: misplaced binary fx04.ams4.POSTED 55
Too many newsgroups 50
EMP (phl) 46
Binary: misplaced binary fx09.ams4.POSTED 41
Binary: misplaced binary fx10.ams4.POSTED 39
EMP (phn nph) 37
Binary: misplaced binary fx14.ams4.POSTED 36
Binary: misplaced binary fx02.ams4.POSTED 33
Binary: misplaced binary fx12.ams4.POSTED 12
Scoring filter 4
HTML Multipart 4
TOTAL: 21 3555

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 170
newsfeed.xs3.de 9
usenet.blueworldhosting.com 5
TOTAL: 3 184
TOTAL: 1 184

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 15482 5458 6068 8 0 4 35%23:59:49
2nyheter.lysator.liu.se 11272 3440 7436 5 0 0 30%23:59:56
3news.quux.org 11860 69 11238 157 0 0 0%23:59:56
4news.bbs.nz 22474 38 11370 123 0 28 0%23:59:56
5usenet.blueworldhosting.com 8466 17 7277 0 0 0 0%23:59:56
6news.furie.org.uk 7948 11 6320 0 0 0 0%23:59:56
7news.nk.ca 3763 4 2838 2 0 8616 0%23:59:56
8news.tnetconsulting.net 12700 1 11519 2 0 0 0%23:59:56
9news.weretis.net 11610 1 11529 3 0 0 0%23:59:56
10news.samoylyk.net 15099 0 11452 0 0 0 0%23:59:56
11newsfeed.xs3.de 14656 0 11175 3 0 0 0%23:59:56
12peer.alt119.net 0 0 0 0 0 11528 0%23:57:47
TOTAL: 12 135330 9039 98222 303 0 20176 6%287:56:56
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net27.5 MB121.6 KB27.6 MB0.3 KB/s5.2 KB23:59:49
2nyheter.lysator.liu.se21.4 MB51.6 KB21.5 MB0.3 KB/s6.4 KB23:59:56
3news.quux.org1.8 MB1.2 MB3.0 MB0.0 KB/s13.6 KB23:59:56
4news.bbs.nz371.3 KB3.1 MB3.5 MB0.0 KB/s22.2 KB23:59:56
5news.furie.org.uk176.4 KB0.0 KB176.4 KB0.0 KB/s16.0 KB23:59:56
6usenet.blueworldhosting.com41.2 KB0.0 KB41.2 KB0.0 KB/s2.4 KB23:59:56
7news.nk.ca13.3 KB11.1 KB24.4 KB0.0 KB/s4.1 KB23:59:56
8news.tnetconsulting.net4.0 KB11.2 KB15.2 KB0.0 KB/s5.1 KB23:59:56
9news.weretis.net2.8 KB649.2 KB652.0 KB0.0 KB/s163.0 KB23:59:56
10news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
11newsfeed.xs3.de0.0 KB17.0 KB17.0 KB0.0 KB/s5.7 KB23:59:56
12peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 1251.3 MB5.2 MB56.5 MB0.1 KB/s6.2 KB287:56:56
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1107.150.107.66 1 00.0 KB 0 0 000:00:00
2scanner-04.ch1.censys-scanner.com 2 00.0 KB 0 0 000:00:00
3123.160.221.17 1 00.0 KB 0 0 000:30:00
4certain.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
5198.199.111.154 1 00.0 KB 0 0 000:00:00
6magnanimous.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
7scanner-08.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 7 8 00.0 KB 0 0 000:30:06

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 0 0 000:30:01
2*.ch1.censys-scanner.com 3 00.0 KB 0 0 000:00:00
3*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
TOTAL: 3 8 00.0 KB 0 0 000:30:06

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
123.160.221.17 0.089 0.034 0.00000:30:00
magnanimous.monitoring.internet-measurement.com 0.091 0.012 0.00000:00:01
certain.monitoring.internet-measurement.com 0.088 0.033 0.00000:00:01
107.150.107.66 0.072 0.028 0.00000:00:00
scanner-04.ch1.censys-scanner.com 0.172 0.053 0.00000:00:00
198.199.111.154 0.098 0.027 0.00000:00:00
scanner-08.ch1.censys-scanner.com 0.098 0.046 0.00000:00:00
TOTAL: 7 0.708 0.233 0.00000:30:06

NNRP unrecognized commands (by host):

SystemConn
123.160.221.17 3
scanner-04.ch1.censys-scanner.com 1
198.199.111.154 1
scanner-08.ch1.censys-scanner.com 1
TOTAL: 4 6

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

SystemConnPeer
123.160.221.17 1 0
TOTAL: 1 1 1