Daily Usenet Report

Dec 19 00:01:01 -- Dec 20 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Dec 19 00:06:20 www innd: message repeated 2 times: [ ctlinnd command s]
Dec 19 06:33:03 www nnrpd[668834]: unable to get certificates from '/news/etc/cert.pem'
Dec 19 06:33:03 www nnrpd[668834]: 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 18549 62.5%4.3 MB 73.3%
innd 8709 29.3%1.1 MB 19.3%
innfeed 2359 7.9%436.2 KB 7.3%
nnrpd 70 0.2%5.7 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 29688 100.0%5.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 97668 53.9%
Negative hits 65451 36.1%
Do not exist 18178 10.0%
Cache misses 39 0.0%
TOTAL: 4 181336 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.071 0.0%67 0.000 1.060 4.667
article cleanup00:00:00.382 0.0%18299 0.000 0.021 0.128
article logging00:00:00.941 0.0%18483 0.000 0.051 0.138
article parse00:00:04.400 0.0%222590 0.000 0.020 0.082
article write00:00:10.283 0.0%11816 0.439 0.870 1.865
artlog/artcncl00:00:00.000 0.0%55 0.000 0.000 0.000
data move00:00:00.762 0.0%388367 0.000 0.002 0.008
hisgrep/artcncl00:00:00.014 0.0%60 0.000 0.233 2.000
hishave/artcncl00:00:00.006 0.0%67 0.000 0.090 0.667
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.174 0.0%182269 0.004 0.045 0.144
history sync00:00:00.049 0.0%371 0.000 0.132 1.000
history write00:00:10.289 0.0%18299 0.091 0.562 0.866
hiswrite/artcncl00:00:00.002 0.0%7 0.000 0.286 0.500
idle23:57:09.476 99.7%364130 18.107 236.810 1909.799
nntp read00:00:35.521 0.0%369816 0.024 0.096 0.172
overview write00:00:07.477 0.0%11816 0.229 0.633 3.868
perl filter00:00:47.235 0.1%18299 1.759 2.581 4.462
python filter00:00:02.971 0.0%18299 0.087 0.162 0.487
site send00:00:04.918 0.0%23632 0.080 0.208 0.428
TOTAL: 24:00:51.83023:59:22.971 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.132 0.0%12153 0.000 0.011 0.092
article prepare00:00:00.003 0.0%34480 0.000 0.000 0.007
article read00:00:01.715 0.0%5715 0.115 0.300 3.150
backlog stats00:00:00.548 0.0%250138 0.000 0.002 0.008
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.830 0.0%166765 0.015 0.035 0.058
data write00:00:21.585 0.0%156848 0.100 0.138 0.199
idle23:56:51.244100.0%250138 165.175 344.655 1162.992
status file00:00:00.223 0.0%205 0.000 1.088 9.000
TOTAL: 23:57:32.10523:57:21.280100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:11.502 94.4%5 45.333 2300.400 9758.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.003 0.0%10 0.000 0.300 0.500
TOTAL: 00:00:12.18000:00:11.505 94.5%----

Control commands to INND:

CommandNumber
cancel 2
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 146
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 12 169

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 6 22198 6756 14974 468 30%48:04:30
2usenet.blueworldhosting.com 5 12876 3805 8679 392 29%24:20:55
3newsfeed.xs3.de 2 11008 583 10417 8 5%23:59:37
4outgoing.news.lysator.liu.se 1 13014 583 11894 537 4%23:59:57
5news.samoylyk.net 1 10694 76 10617 1 0%23:59:51
6glockenspiel.complete.org 1 10090 25 10063 2 0%23:59:54
7news.bbs.nz 4 15558 14 15544 0 0%36:47:16
8news.furie.org.uk 2 6316 3 6313 0 0%24:55:51
9news.netfront.net 8 17276 2 12340 4934 0%23:09:35
10localhost 25 0 0 0 0 0%00:00:00
11news.nk.ca 2 0 0 0 0 0%00:20:00
12eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:03
TOTAL: 12 58 119030 11847 100841 6342 9%253:47:29
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net22.7 MB0.0 KB952.6 KB23.6 MB 96%3.3 KB
2usenet.blueworldhosting.com18.1 MB0.0 KB1.2 MB19.3 MB 93%4.7 KB
3newsfeed.xs3.de3.1 MB0.0 KB12.0 KB3.1 MB 99%5.3 KB
4news.samoylyk.net1.5 MB0.0 KB1.2 KB1.5 MB 99%19.8 KB
5outgoing.news.lysator.liu.se1.2 MB0.0 KB1.6 MB2.8 MB 41%2.6 KB
6glockenspiel.complete.org428.9 KB4.6 KB4.6 KB438.2 KB 97%16.2 KB
7news.bbs.nz36.2 KB0.0 KB0.0 KB36.2 KB100%2.6 KB
8news.furie.org.uk7.3 KB0.0 KB0.0 KB7.3 KB100%2.4 KB
9news.netfront.net3.7 KB0.0 KB1.7 GB1.7 GB 0%351.9 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
11news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1247.0 MB4.6 KB1.7 GB1.7 GB 2%98.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 19 00:01:01 - 00:59:59 276 2.3% 0.081.7 MB 3.5% 0.49
Dec 19 01:00:00 - 01:59:59 328 2.8% 0.091.7 MB 3.5% 0.48
Dec 19 02:00:00 - 02:59:59 528 4.5% 0.151.8 MB 3.8% 0.52
Dec 19 03:00:00 - 03:59:59 615 5.2% 0.171.7 MB 3.5% 0.48
Dec 19 04:00:00 - 04:59:59 617 5.2% 0.172.0 MB 4.1% 0.57
Dec 19 05:00:00 - 05:59:59 593 5.0% 0.161.8 MB 3.8% 0.52
Dec 19 06:00:00 - 06:59:59 542 4.6% 0.151.8 MB 3.7% 0.51
Dec 19 07:00:00 - 07:59:59 565 4.8% 0.162.0 MB 4.0% 0.56
Dec 19 08:00:00 - 08:59:59 604 5.1% 0.172.0 MB 4.1% 0.56
Dec 19 09:00:00 - 09:59:59 807 6.8% 0.223.6 MB 7.5% 1.03
Dec 19 10:00:00 - 10:59:59 768 6.5% 0.212.1 MB 4.3% 0.59
Dec 19 11:00:00 - 11:59:59 723 6.1% 0.202.5 MB 5.1% 0.71
Dec 19 12:00:00 - 12:59:59 614 5.2% 0.172.5 MB 5.1% 0.70
Dec 19 13:00:00 - 13:59:59 582 4.9% 0.163.0 MB 6.1% 0.84
Dec 19 14:00:00 - 14:59:59 550 4.7% 0.152.2 MB 4.6% 0.63
Dec 19 15:00:00 - 15:59:59 670 5.7% 0.194.0 MB 8.3% 1.15
Dec 19 16:00:00 - 16:59:59 491 4.2% 0.141.6 MB 3.3% 0.46
Dec 19 17:00:00 - 17:59:59 432 3.7% 0.121.5 MB 3.2% 0.44
Dec 19 18:00:00 - 18:59:59 331 2.8% 0.092.5 MB 5.1% 0.70
Dec 19 19:00:00 - 19:59:59 244 2.1% 0.07819.7 KB 1.7% 0.23
Dec 19 20:00:00 - 20:59:59 229 1.9% 0.06875.7 KB 1.8% 0.24
Dec 19 21:00:00 - 21:59:59 236 2.0% 0.07996.2 KB 2.0% 0.28
Dec 19 22:00:00 - 22:59:59 176 1.5% 0.051.2 MB 2.5% 0.34
Dec 19 23:00:00 - 23:59:59 296 2.5% 0.082.6 MB 5.4% 0.75
Dec 20 00:00:00 - 00:01:01 9 0.1% 0.1541.1 KB 0.1% 0.67
TOTAL: 24:00:00 11826 100.0% 0.1448.4 MB 100.0% 0.57
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 5101 0 0 0 0 0 0 0 5101
2endofthelinebbs-out.news.weretis.net 606 0 0 0 0 0 0 0 606
3outgoing.news.lysator.liu.se 548 0 0 0 0 0 0 0 548
4usenet.blueworldhosting.com 394 14 0 0 1 0 0 0 379
5newsfeed.xs3.de 17 0 0 0 0 0 0 0 17
6news.samoylyk.net 2 0 0 0 0 0 0 0 2
TOTAL: 6 6668 14 0 0 1 0 0 0 6653

Unwanted newsgroups [Top 20]:

NewsgroupCount
panix.chat 5
rocksolid.feeds.news 5
panix.test 3
hawai.politics 1
TOTAL: 4 14

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

GroupsCount
sci.nanotech 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Binary: misplaced binary 3923
Too many newsgroups (low_xpost) 943
EMP (md5) 667
EMP (phn path) 571
EMP (phn nph) 136
Too many newsgroups 109
EMP (phl) 106
Scoring filter 10
HTML Multipart 3
TOTAL: 9 6468

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 153
usenet.blueworldhosting.com 21
newsfeed.xs3.de 9
news.samoylyk.net 1
outgoing.news.lysator.liu.se 1
TOTAL: 5 185
TOTAL: 1 185

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 15906 5478 6346 0 0 6 34%23:59:43
2nyheter.lysator.liu.se 11839 2716 8500 21 0 1 22%23:59:08
3news.furie.org.uk 8858 74 6477 4 0 1 0%23:59:45
4news.nk.ca 15569 58 11684 52 0 23 0%23:59:56
5news.bbs.nz 23173 34 11717 58 0 277 0%23:59:55
6news.quux.org 12247 16 11661 115 0 18 0%23:59:56
7usenet.blueworldhosting.com 8342 6 7944 0 0 0 0%23:59:29
8news.weretis.net 11849 4 11816 1 0 0 0%23:59:55
9news.samoylyk.net 14841 0 11740 3 0 2 0%23:58:58
10newsfeed.xs3.de 14197 0 11220 1 0 2 0%23:59:51
11news.tnetconsulting.net 12597 0 11819 0 0 24 0%23:59:56
12peer.alt119.net 0 0 0 0 0 11825 0%23:57:47
TOTAL: 12 149418 8386 110924 255 0 12179 5%287:54:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net24.8 MB0.0 KB24.8 MB0.3 KB/s4.6 KB23:59:43
2nyheter.lysator.liu.se15.1 MB97.8 KB15.2 MB0.2 KB/s5.7 KB23:59:08
3news.furie.org.uk6.6 MB62.1 KB6.7 MB0.1 KB/s88.0 KB23:59:45
4news.nk.ca1.1 MB178.8 KB1.2 MB0.0 KB/s11.5 KB23:59:56
5news.bbs.nz382.2 KB1.1 MB1.4 MB0.0 KB/s16.0 KB23:59:55
6news.quux.org128.2 KB361.5 KB489.8 KB0.0 KB/s3.7 KB23:59:56
7usenet.blueworldhosting.com15.3 KB0.0 KB15.3 KB0.0 KB/s2.5 KB23:59:29
8news.weretis.net5.6 KB522.4 KB528.1 KB0.0 KB/s105.6 KB23:59:55
9news.samoylyk.net0.0 KB2.9 KB2.9 KB0.0 KB/s1.0 KB23:58:58
10newsfeed.xs3.de0.0 KB2.5 KB2.5 KB0.0 KB/s2.5 KB23:59:51
11news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
12peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 1248.2 MB2.3 MB50.4 MB0.0 KB/s6.0 KB287:54:19
Outgoing feeds (innfeed) by Volume

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 1 00.0 KB 2 0 000:00:09
2*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 3 3 00.0 KB 2 0 000:00:12

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
192.241.209.99 0.083 0.054 0.00000:00:09
swanky.monitoring.internet-measurement.com 0.082 0.048 0.00000:00:01
scanner-05.ch1.censys-scanner.com 0.083 0.063 0.00000:00:00
TOTAL: 3 0.248 0.165 0.00000:00:12

Curious NNRP server explorers [Top 20]:

SystemConn
scanner-05.ch1.censys-scanner.com 1
192.241.209.99 1
swanky.monitoring.internet-measurement.com 1
TOTAL: 3 3

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

Newsgroup request counts (by category):

CategoryCountPct
1news 3 75.0%
2alt 1 25.0%
TOTAL: 2 4100.0%

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

NewsgroupCount
1news.software.nntp 3
2alt.genealogy 1
TOTAL: 2 4