Daily Usenet Report

Feb 11 00:01:02 -- Feb 12 00:01:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Feb 11 00:07:12 www innd: message repeated 2 times: [ ctlinnd command s]
Feb 11 12:13:51 www nnrpd[1600502]: unable to get certificates from '/news/etc/cert.pem'
Feb 11 12:13:51 www nnrpd[1600502]: 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 24839 57.1%4.8 MB 65.8%
innd 16314 37.5%2.1 MB 28.5%
innfeed 2353 5.4%429.3 KB 5.7%
nnrpd 21 0.0%2.3 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 43528 100.0%7.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 85101 49.3%
Negative hits 62675 36.3%
Do not exist 24607 14.3%
Cache misses 90 0.1%
TOTAL: 4 172473 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.068 0.0%59 0.000 1.153 5.000
article cleanup00:00:00.559 0.0%24563 0.000 0.023 0.091
article logging00:00:01.082 0.0%24805 0.000 0.044 0.136
article parse00:00:09.829 0.0%508036 0.000 0.019 0.116
article write00:00:08.659 0.0%10433 0.366 0.830 2.543
artlog/artcncl00:00:00.002 0.0%56 0.000 0.036 0.333
data move00:00:01.858 0.0%668943 0.000 0.003 0.010
hisgrep/artcncl00:00:00.014 0.0%54 0.000 0.259 2.500
hishave/artcncl00:00:00.006 0.0%59 0.000 0.102 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.497 0.0%172818 0.006 0.049 0.132
history sync00:00:00.045 0.0%351 0.000 0.128 1.000
history write00:00:14.018 0.0%24558 0.083 0.571 0.978
hiswrite/artcncl00:00:00.002 0.0%5 0.000 0.400 0.500
idle23:55:53.570 99.7%615226 23.386 140.036 1830.058
nntp read00:01:15.551 0.1%624970 0.023 0.121 0.189
overview write00:00:07.132 0.0%10433 0.158 0.684 4.784
perl filter00:00:53.609 0.1%24558 1.208 2.183 5.000
python filter00:00:04.821 0.0%24558 0.075 0.196 0.826
site send00:00:03.848 0.0%20866 0.051 0.184 0.842
TOTAL: 24:00:44.93723:59:03.170 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.207 0.0%10480 0.000 0.020 0.176
article prepare00:00:00.003 0.0%30780 0.000 0.000 0.006
article read00:00:01.447 0.0%4990 0.077 0.290 2.435
backlog stats00:00:00.520 0.0%228209 0.000 0.002 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.420 0.0%149201 0.016 0.036 0.072
data write00:00:19.980 0.0%139940 0.102 0.143 0.202
idle23:57:15.989100.0%228209 186.124 377.882 1172.208
status file00:00:00.201 0.0%206 0.000 0.976 4.000
TOTAL: 23:57:55.27023:57:43.767100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:30:10.224100.0%8 26.000226278.000900044.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.002 0.0%17 0.000 0.118 0.200
TOTAL: 00:30:11.07900:30:10.226100.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 20992 5576 15115 301 26%51:16:51
2usenet.blueworldhosting.com 5 11590 3545 7500 545 30%24:03:21
3outgoing.news.lysator.liu.se 1 11430 690 10436 304 6%23:59:57
4newsfeed.xs3.de 1 9288 376 8907 5 4%23:59:55
5news.samoylyk.net 1 9613 68 9544 1 0%23:59:50
6glockenspiel.complete.org 1 7964 28 7915 21 0%18:55:13
7news.bbs.nz 4 10500 13 10487 0 0%27:54:55
8news.furie.org.uk 2 5327 3 5324 0 0%23:59:38
9localhost 25 0 0 0 0 0%00:00:00
10news.nk.ca 2 0 0 0 0 0%00:20:01
11news.netfront.net 19 24095 0 11195 12900 0%29:13:01
12eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:05
TOTAL: 12 67 110799 10299 86423 14077 9%247:52:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.blueworldhosting.com24.9 MB1.9 KB3.5 MB28.4 MB 87%7.1 KB
2endofthelinebbs-out.news.weretis.net17.5 MB2.0 KB506.1 KB18.0 MB 97%3.1 KB
3newsfeed.xs3.de1.5 MB1.9 KB9.7 KB1.5 MB 99%4.0 KB
4outgoing.news.lysator.liu.se1.4 MB0.0 KB588.6 KB2.0 MB 70%2.0 KB
5news.samoylyk.net247.5 KB0.0 KB1.5 KB249.0 KB 99%3.6 KB
6glockenspiel.complete.org137.9 KB10.7 KB40.2 KB188.7 KB 73%3.9 KB
7news.bbs.nz30.8 KB0.0 KB0.0 KB30.8 KB100%2.4 KB
8news.furie.org.uk6.2 KB0.0 KB0.0 KB6.2 KB100%2.1 KB
9localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
10news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
11news.netfront.net0.0 KB0.0 KB4.2 GB4.2 GB 0%342.2 KB
12eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1245.6 MB16.5 KB4.2 GB4.3 GB 1%183.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 11 00:01:02 - 00:59:59 256 2.5% 0.07914.7 KB 1.9% 0.26
Feb 11 01:00:00 - 01:59:59 391 3.8% 0.111.5 MB 3.3% 0.43
Feb 11 02:00:00 - 02:59:59 454 4.4% 0.132.3 MB 4.9% 0.65
Feb 11 03:00:00 - 03:59:59 588 5.6% 0.161.7 MB 3.7% 0.49
Feb 11 04:00:00 - 04:59:59 534 5.1% 0.151.8 MB 3.8% 0.50
Feb 11 05:00:00 - 05:59:59 485 4.7% 0.131.5 MB 3.2% 0.42
Feb 11 06:00:00 - 06:59:59 569 5.5% 0.163.1 MB 6.8% 0.89
Feb 11 07:00:00 - 07:59:59 488 4.7% 0.141.7 MB 3.6% 0.47
Feb 11 08:00:00 - 08:59:59 555 5.3% 0.152.2 MB 4.8% 0.63
Feb 11 09:00:00 - 09:59:59 657 6.3% 0.182.1 MB 4.6% 0.60
Feb 11 10:00:00 - 10:59:59 644 6.2% 0.182.4 MB 5.3% 0.70
Feb 11 11:00:00 - 11:59:59 593 5.7% 0.162.7 MB 5.8% 0.76
Feb 11 12:00:00 - 12:59:59 537 5.2% 0.152.9 MB 6.2% 0.81
Feb 11 13:00:00 - 13:59:59 534 5.1% 0.151.7 MB 3.8% 0.49
Feb 11 14:00:00 - 14:59:59 506 4.9% 0.142.1 MB 4.5% 0.59
Feb 11 15:00:00 - 15:59:59 446 4.3% 0.121.6 MB 3.6% 0.47
Feb 11 16:00:00 - 16:59:59 385 3.7% 0.112.3 MB 5.1% 0.66
Feb 11 17:00:00 - 17:59:59 396 3.8% 0.112.9 MB 6.3% 0.83
Feb 11 18:00:00 - 18:59:59 303 2.9% 0.081.8 MB 3.9% 0.50
Feb 11 19:00:00 - 19:59:59 246 2.4% 0.071.4 MB 3.0% 0.39
Feb 11 20:00:00 - 20:59:59 205 2.0% 0.06712.5 KB 1.5% 0.20
Feb 11 21:00:00 - 21:59:59 270 2.6% 0.072.6 MB 5.7% 0.74
Feb 11 22:00:00 - 22:59:59 186 1.8% 0.051.2 MB 2.6% 0.34
Feb 11 23:00:00 - 23:59:59 194 1.9% 0.05857.4 KB 1.8% 0.24
Feb 12 00:00:00 - 00:01:02 4 0.0% 0.0621.1 KB 0.0% 0.34
TOTAL: 24:00:00 10426 100.0% 0.1246.0 MB 100.0% 0.54
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 12948 0 0 0 0 0 0 012948
2usenet.blueworldhosting.com 557 105 0 1 0 0 0 0 451
3endofthelinebbs-out.news.weretis.net 519 0 0 1 0 0 0 0 518
4outgoing.news.lysator.liu.se 310 0 0 0 0 0 0 0 310
5newsfeed.xs3.de 12 0 0 1 0 0 0 0 11
6glockenspiel.complete.org 10 0 0 2 0 0 0 0 8
7news.samoylyk.net 1 0 0 0 0 0 0 0 1
TOTAL: 7 14357 105 0 5 0 0 0 014247

Unwanted newsgroups [Top 20]:

NewsgroupCount
panix.questions 41
panix.chat 16
panix.test 15
sk.politics 12
panix.staff.general 10
rocksolid.feeds.news 5
panix.upgrade 1
panix.staff.tech-staff 1
alt.alt.alt 1
panix.staff.netbsd.announce 1
panix.staff.netbsd.users 1
ee.arvutid 1
TOTAL: 12 105

INND Perl filter [Top 20]:

ReasonCount
Binary: misplaced binary fx03.ams4.POSTED 2101
Binary: misplaced binary fx02.ams4.POSTED 1858
Binary: misplaced binary fx05.ams4.POSTED 1520
Binary: misplaced binary fx06.ams4.POSTED 1247
Binary: misplaced binary fx13.ams4.POSTED 1112
Binary: misplaced binary fx15.ams4.POSTED 1097
Binary: misplaced binary fx14.ams4.POSTED 1073
Binary: misplaced binary fx09.ams4.POSTED 1065
Binary: misplaced binary fx12.ams4.POSTED 603
EMP (phn path) 576
Binary: misplaced binary fx01.ams4.POSTED 534
EMP (md5) 358
Binary: misplaced binary fx08.ams4.POSTED 263
Binary: misplaced binary fx04.ams4.POSTED 169
Binary: misplaced binary fx07.ams4.POSTED 155
Binary: misplaced binary fx11.ams4.POSTED 150
EMP (phl) 54
Too many newsgroups 41
Scoring filter 13
EMP (phn nph) 12
TOTAL: 22 14006

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 219
usenet.blueworldhosting.com 14
newsfeed.xs3.de 7
outgoing.news.lysator.liu.se 1
TOTAL: 4 241
TOTAL: 1 241

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 14227 4388 6018 20 0 0 30%23:58:43
2nyheter.lysator.liu.se 10211 2885 6836 8 0 0 28%23:59:56
3news.quux.org 10765 64 10178 143 0 0 0%23:59:36
4news.bbs.nz 20189 37 10249 118 0 42 0%23:59:52
5news.furie.org.uk 7362 28 5540 0 0 0 0%23:59:24
6news.nk.ca 13572 4 10388 4 0 9 0%23:59:56
7news.tnetconsulting.net 11554 1 10421 0 0 0 0%23:58:41
8usenet.blueworldhosting.com 8087 1 6842 0 0 11 0%23:59:46
9news.samoylyk.net 13472 0 10357 0 0 0 0%23:59:50
10peer.alt119.net 0 0 0 0 0 10424 0%23:57:46
11newsfeed.xs3.de 13017 0 10047 1 0 0 0%23:59:41
12news.weretis.net 10466 0 10423 1 0 0 0%23:59:56
TOTAL: 12 132922 7408 97299 295 0 10486 5%287:53:07
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net20.1 MB239.9 KB20.4 MB0.2 KB/s4.7 KB23:58:43
2nyheter.lysator.liu.se17.8 MB24.3 KB17.9 MB0.2 KB/s6.3 KB23:59:56
3news.quux.org355.6 KB1.0 MB1.4 MB0.0 KB/s6.8 KB23:59:36
4news.bbs.nz341.4 KB711.1 KB1.0 MB0.0 KB/s6.8 KB23:59:52
5news.furie.org.uk219.5 KB0.0 KB219.5 KB0.0 KB/s7.8 KB23:59:24
6news.nk.ca5.4 KB8.7 KB14.1 KB0.0 KB/s1.8 KB23:59:56
7news.tnetconsulting.net1.8 KB0.0 KB1.8 KB0.0 KB/s1.8 KB23:58:41
8usenet.blueworldhosting.com1.6 KB0.0 KB1.6 KB0.0 KB/s1.6 KB23:59:46
9news.samoylyk.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
10peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:46
11newsfeed.xs3.de0.0 KB4.5 KB4.5 KB0.0 KB/s4.5 KB23:59:41
12news.weretis.net0.0 KB4.5 KB4.5 KB0.0 KB/s4.5 KB23:59:56
TOTAL: 1238.9 MB2.0 MB40.9 MB0.0 KB/s5.4 KB287:53:07
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1107.170.232.12 1 00.0 KB 0 0 000:00:09
2107.170.232.17 1 00.0 KB 0 0 000:00:00
3202.107.226.5 1 00.0 KB 0 0 000:30:00
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:11

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:11

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
202.107.226.5 0.084 0.063 0.00000:30:00
107.170.232.12 0.077 0.035 0.00000:00:09
scanner-04.ch1.censys-scanner.com 0.110 0.053 0.00000:00:00
107.170.232.17 0.086 0.024 0.00000:00:00
TOTAL: 4 0.357 0.175 0.00000:30:11

NNRP unrecognized commands (by host):

SystemConn
scanner-04.ch1.censys-scanner.com 1
107.170.232.17 1
202.107.226.5 1
TOTAL: 3 3

NNRP unrecognized commands (by command):

CommandCount
MGLNDD_192.138.210.162_119 1
EHLO www.censys.io 1
GET / HTTP/1.0 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
202.107.226.5 1 0
TOTAL: 1 1 0