Daily Usenet Report

Mar 3 00:01:01 -- Mar 4 00:01:01

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Mar 3 00:06:51 www innd: message repeated 2 times: [ ctlinnd command s]
Mar 3 08:13:30 www nnrpd[1210909]: unable to get certificates from '/news/etc/cert.pem'
Mar 3 08:13:30 www nnrpd[1210909]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]
Mar 3 08:44:03 www innd: tradspool: could not symlink /news/spool/articles/rec/arts/tv/122043 to /news/spool/articles/sci/med/diseases/1: File exists
Mar 3 08:44:03 www innd: tradspool: could not symlink /news/spool/articles/rec/arts/tv/122044 to /news/spool/articles/sci/med/diseases/2: File exists
Mar 3 08:44:04 www innd: tradspool: could not symlink /news/spool/articles/rec/arts/tv/122045 to /news/spool/articles/sci/med/diseases/3: File exists
Mar 3 13:42:00 www nnrpd[1221173]: 47.186.2.63 auth: program error: Use of uninitialized value $un in pattern match (m//) at /usr/local/bin/usenet-auth line 13, <STDIN> line 8.
Mar 3 13:42:00 www nnrpd[1221173]: 47.186.2.63 auth: program error: Use of uninitialized value $pw in pattern match (m//) at /usr/local/bin/usenet-auth line 14, <STDIN> line 8.
Mar 3 13:42:00 www nnrpd[1221173]: 47.186.2.63 auth: program error: Use of uninitialized value $un in pattern match (m//) at /usr/local/bin/usenet-auth line 13, <STDIN> line 8.
Mar 3 13:42:00 www nnrpd[1221173]: 47.186.2.63 auth: program error: Use of uninitialized value $pw in pattern match (m//) at /usr/local/bin/usenet-auth line 14, <STDIN> line 8.
Mar 3 13:42:00 www nnrpd[1221173]: 47.186.2.63 auth: program error: Use of uninitialized value $un in pattern match (m//) at /usr/local/bin/usenet-auth line 13, <STDIN> line 8.
Mar 3 13:42:00 www nnrpd[1221173]: 47.186.2.63 auth: program error: Use of uninitialized value $pw in pattern match (m//) at /usr/local/bin/usenet-auth line 14, <STDIN> line 8.

Log entries by program:

Program nameLines%LinesSize%Size
inn 18476 59.2%4.7 MB 73.6%
innd 5924 19.0%813.2 KB 12.4%
nnrpd 4173 13.4%423.3 KB 6.4%
innfeed 2646 8.5%499.4 KB 7.6%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 5 31221 100.0%6.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 107281 52.8%
Negative hits 74063 36.4%
Do not exist 17945 8.8%
Cache misses 4063 2.0%
TOTAL: 4 203352 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.141 0.0%111 0.000 1.270 46.000
article cleanup00:00:00.372 0.0%17923 0.000 0.021 0.070
article logging00:00:00.800 0.0%18269 0.000 0.044 0.132
article parse00:00:02.655 0.0%151164 0.000 0.018 0.081
article write00:00:11.239 0.0%11965 0.322 0.939 2.143
artlog/artcncl00:00:00.000 0.0%110 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:00:01.024 0.0%329306 0.000 0.003 0.008
hisgrep/artcncl00:00:00.010 0.0%81 0.000 0.123 1.000
hishave/artcncl00:00:00.008 0.0%111 0.000 0.072 0.333
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.533 0.0%203580 0.002 0.042 0.091
history sync00:00:00.054 0.0%381 0.000 0.142 1.500
history write00:00:10.005 0.0%17908 0.078 0.559 0.952
hiswrite/artcncl00:00:00.014 0.0%30 0.000 0.467 1.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:47:19.553 99.7%318925 33.122 268.526 1212.859
nntp read00:00:27.116 0.0%321459 0.025 0.084 0.152
overview write00:00:07.074 0.0%11962 0.148 0.591 4.500
perl filter00:00:48.287 0.1%17911 1.857 2.696 4.227
python filter00:00:02.824 0.0%17911 0.049 0.158 0.371
site send00:00:04.281 0.0%23924 0.056 0.179 0.413
TOTAL: 23:50:58.47423:49:23.990 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.128 0.0%12252 0.000 0.010 0.092
article prepare00:00:00.008 0.0%38844 0.000 0.000 0.010
article read00:00:01.410 0.0%4851 0.000 0.291 2.944
backlog stats00:00:00.620 0.0%269678 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:06.367 0.0%184875 0.019 0.034 0.063
data write00:00:26.128 0.0%174249 0.101 0.150 0.210
idle23:45:31.881 99.9%269678 168.188 317.163 1111.246
status file00:00:00.188 0.0%211 0.000 0.891 3.000
TOTAL: 23:46:19.76923:46:06.730100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:04:06.016 54.7%4256 20.333 57.805 10015.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.728 0.2%8293 0.000 0.088 1.333
TOTAL: 00:07:30.06700:04:06.744 54.8%----

Control commands to INND:

CommandNumber
begin 1
checkfile 1
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
shutdown 3
TOTAL: 14 173

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 6 20818 7109 13519 190 34%47:09:11
2usenet.blueworldhosting.com 6 13370 3150 9808 412 23%26:19:17
3outgoing.news.lysator.liu.se 3 12464 862 11372 230 6%24:08:31
4newsfeed.xs3.de 2 10375 516 9852 7 4%23:59:25
5usenet.network 2 10482 308 10163 11 2%23:44:05
6glockenspiel.complete.org 2 16573 179 16390 4 1%33:47:26
7news.samoylyk.net 2 10445 129 10312 4 1%23:59:34
8endofthelinebbs.peers.news.panix.com 23 9693 27 7208 2458 0%10:24:02
9news.furie.org.uk 2 5637 6 5630 1 0%23:59:24
10news.bbs.nz 2 16020 5 16014 1 0%33:04:05
11news.netfront.net 4 15412 4 12641 2767 0%25:36:21
12news.nk.ca 2 0 0 0 0 0%00:21:11
13localhost 26 0 0 0 0 0%00:00:04
14eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:14
TOTAL: 14 83 141289 12295 122909 6085 8%296:42:50
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net24.7 MB1.3 KB930.1 KB25.6 MB 96%3.6 KB
2usenet.blueworldhosting.com19.3 MB2.3 KB2.1 MB21.3 MB 90%6.1 KB
3usenet.network2.0 MB947.1 KB971.3 KB3.9 MB 51%12.4 KB
4outgoing.news.lysator.liu.se1.7 MB946.0 KB1.6 MB4.2 MB 40%4.0 KB
5newsfeed.xs3.de1.5 MB13.7 KB20.1 KB1.6 MB 97%3.1 KB
6glockenspiel.complete.org939.8 KB946.0 KB989.1 KB2.8 MB 32%15.7 KB
7news.samoylyk.net656.7 KB946.0 KB990.4 KB2.5 MB 25%19.5 KB
8endofthelinebbs.peers.news.panix.com76.6 KB0.0 KB100.2 MB100.3 MB 0%41.3 KB
9news.furie.org.uk14.4 KB0.0 KB1.2 KB15.6 KB 92%2.2 KB
10news.netfront.net11.3 KB0.0 KB955.4 MB955.4 MB 0%353.1 KB
11news.bbs.nz9.3 KB0.0 KB6.7 KB16.1 KB 58%2.7 KB
12news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
13localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
14eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1450.9 MB3.7 MB1.0 GB1.1 GB 4%62.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 3 00:01:01 - 00:59:59 322 2.7% 0.092.3 MB 4.6% 0.65
Mar 3 01:00:00 - 01:59:59 448 3.7% 0.121.2 MB 2.4% 0.34
Mar 3 02:00:00 - 02:59:59 493 4.1% 0.141.5 MB 3.0% 0.43
Mar 3 03:00:00 - 03:59:59 606 5.0% 0.172.6 MB 5.3% 0.75
Mar 3 04:00:00 - 04:59:59 565 4.7% 0.161.8 MB 3.7% 0.52
Mar 3 05:00:00 - 05:59:59 514 4.3% 0.141.6 MB 3.3% 0.46
Mar 3 06:00:00 - 06:59:59 527 4.4% 0.152.2 MB 4.5% 0.63
Mar 3 07:00:00 - 07:59:59 641 5.3% 0.183.1 MB 6.3% 0.89
Mar 3 08:00:00 - 08:59:59 634 5.3% 0.182.2 MB 4.4% 0.62
Mar 3 09:00:00 - 09:59:59 827 6.9% 0.232.7 MB 5.4% 0.76
Mar 3 10:00:00 - 10:59:59 755 6.3% 0.212.7 MB 5.4% 0.77
Mar 3 11:00:00 - 11:59:59 784 6.5% 0.222.8 MB 5.6% 0.80
Mar 3 12:00:00 - 12:59:59 683 5.7% 0.192.3 MB 4.7% 0.66
Mar 3 13:00:00 - 13:59:59 613 5.1% 0.173.5 MB 7.0% 0.99
Mar 3 14:00:00 - 14:59:59 561 4.7% 0.161.6 MB 3.3% 0.47
Mar 3 15:00:00 - 15:59:59 564 4.7% 0.162.0 MB 3.9% 0.56
Mar 3 16:00:00 - 16:59:59 512 4.3% 0.141.9 MB 3.9% 0.54
Mar 3 17:00:00 - 17:59:59 417 3.5% 0.122.9 MB 5.8% 0.82
Mar 3 18:00:00 - 18:59:59 288 2.4% 0.08903.3 KB 1.8% 0.25
Mar 3 19:00:00 - 19:59:59 348 2.9% 0.101.4 MB 2.8% 0.39
Mar 3 20:00:00 - 20:59:59 239 2.0% 0.071.0 MB 2.0% 0.29
Mar 3 21:00:00 - 21:59:59 250 2.1% 0.071.6 MB 3.3% 0.46
Mar 3 22:00:00 - 22:59:59 214 1.8% 0.061.5 MB 3.1% 0.43
Mar 3 23:00:00 - 23:59:59 233 1.9% 0.062.2 MB 4.5% 0.64
Mar 4 00:00:00 - 00:01:01 2 0.0% 0.033.6 KB 0.0% 0.06
TOTAL: 24:00:00 12040 100.0% 0.1449.6 MB 100.0% 0.59
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 2631 0 0 0 0 0 0 0 2631
2endofthelinebbs.peers.news.panix.com 2554 2552 0 0 0 0 0 0 2
3endofthelinebbs-out.news.weretis.net 479 0 0 1 0 0 0 0 478
4usenet.blueworldhosting.com 436 13 0 2 0 0 0 0 421
5outgoing.news.lysator.liu.se 186 0 0 1 0 0 0 0 185
6newsfeed.xs3.de 16 0 0 4 0 0 0 0 12
7usenet.network 9 0 0 2 0 0 0 0 7
8news.samoylyk.net 8 0 0 1 0 0 0 0 7
9glockenspiel.complete.org 3 0 0 1 0 0 0 0 2
10news.furie.org.uk 1 0 0 0 0 0 0 0 1
11news.bbs.nz 1 0 0 0 0 0 0 0 1
TOTAL: 11 6324 2565 0 12 0 0 0 0 3747

Unwanted newsgroups [Top 20]:

NewsgroupCount
ab.alt.binaries.bitburger 1519
alt.binaries.backup 427
ab.alt.binaries.boneless 306
4u.alt.binaries.4u 125
alt.binaries.bloaf 40
alt.binaries.ftd 31
finet.binaries.keskustelu 23
ab.alt.binaries.pizza 17
alt.binaries.nospam.female.bodyhair.pubes 8
alt.binaries.sounds.anime 7
alt.binaries.nospam.female.bodyhair 6
alt.binaries.test.yenc 5
rocksolid.feeds.news 5
alt.binaries.adan 4
alt.binaries.sounds.lossless 3
alt.binaries.multimedia.vintage-film.post-1960 3
alt.binaries.pictures.scenic 3
alt.binaries.pretty.models 3
alt.binaries.sounds.lossless.jazz 3
alt.binaries.sounds.whitburn.lossless 2
TOTAL: 40 2565

INND Perl filter [Top 20]:

ReasonCount
Poison newsgroup 422
EMP (md5) 341
Binary: misplaced binary fx17.iad.POSTED 290
Binary: misplaced binary fx15.ams4.POSTED 271
EMP (phn path) 268
Binary: misplaced binary fx01.ams4.POSTED 263
Binary: misplaced binary fx14.ams4.POSTED 256
Binary: misplaced binary fx04.ams4.POSTED 211
Binary: misplaced binary fx03.ams4.POSTED 209
Binary: misplaced binary fx16.iad.POSTED 172
Binary: misplaced binary fx06.ams4.POSTED 100
Binary: misplaced binary fx11.ams4.POSTED 97
Binary: misplaced binary fx02.ams4.POSTED 91
Binary: misplaced binary fx05.ams4.POSTED 67
Too many newsgroups 54
EMP (phn nph) 51
EMP (phl) 50
Binary: misplaced binary fx08.ams4.POSTED 49
Binary: misplaced binary fx13.ams4.POSTED 41
Binary: misplaced binary fx12.ams4.POSTED 29
TOTAL: 27 3394

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 292
usenet.blueworldhosting.com 37
newsfeed.xs3.de 9
outgoing.news.lysator.liu.se 5
news.samoylyk.net 4
endofthelinebbs.peers.news.panix.com 2
usenet.network 1
TOTAL: 7 350
TOTAL: 1 350

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 15832 4380 7646 10 0 7 27%23:59:44
2nyheter.lysator.liu.se 11440 2206 8719 252 0 0 19%23:59:44
3usenet.network 14186 333 11263 53 0 0 2%23:59:43
4usenet.blueworldhosting.com 11869 297 8696 1 0 18 2%23:59:44
5news.nk.ca 14615 130 11518 295 0 266 0%23:59:44
6endofthelinebbs.peers.news.panix.com 3720 83 3502 8 0 0 2%09:58:09
7news.furie.org.uk 8072 37 5988 196 0 2 0%23:59:44
8news.bbs.nz 22595 31 11716 285 0 38 0%23:59:44
9news.quux.org 12365 13 11663 259 0 0 0%23:59:44
10news.weretis.net 12172 5 11786 245 0 0 0%23:59:44
11news.tnetconsulting.net 12792 4 11787 244 0 0 0%23:59:44
12news.samoylyk.net 13783 1 11689 214 0 0 0%23:59:44
13peer.alt119.net 0 0 0 0 0 12039 0%23:55:25
14newsfeed.xs3.de 13358 0 11302 213 0 0 0%23:59:44
TOTAL: 14 166799 7520 127275 2275 0 12370 4%321:50:21
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net19.1 MB41.6 KB19.1 MB0.2 KB/s4.5 KB23:59:44
2nyheter.lysator.liu.se9.5 MB2.0 MB11.5 MB0.1 KB/s4.8 KB23:59:44
3usenet.blueworldhosting.com2.0 MB1.5 KB2.0 MB0.0 KB/s7.0 KB23:59:44
4usenet.network1.4 MB175.1 KB1.6 MB0.0 KB/s4.2 KB23:59:43
5news.furie.org.uk1.2 MB1.9 MB3.1 MB0.0 KB/s13.6 KB23:59:44
6news.nk.ca710.0 KB2.2 MB2.9 MB0.0 KB/s6.9 KB23:59:44
7endofthelinebbs.peers.news.panix.com636.6 KB12.9 KB649.4 KB0.0 KB/s7.1 KB09:58:09
8news.bbs.nz144.8 KB3.0 MB3.2 MB0.0 KB/s10.3 KB23:59:44
9news.quux.org52.8 KB2.0 MB2.1 MB0.0 KB/s7.8 KB23:59:44
10news.tnetconsulting.net20.7 KB1.9 MB2.0 MB0.0 KB/s8.1 KB23:59:44
11news.weretis.net16.4 KB2.9 MB2.9 MB0.0 KB/s11.8 KB23:59:44
12news.samoylyk.net1.5 KB1.8 MB1.8 MB0.0 KB/s8.4 KB23:59:44
13peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:25
14newsfeed.xs3.de0.0 KB1.7 MB1.7 MB0.0 KB/s8.4 KB23:59:44
TOTAL: 1434.7 MB19.7 MB54.4 MB0.0 KB/s5.7 KB321:50:21
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1news-xfer.access.net1377 00.0 KB 0 0 000:06:47
2107.170.243.26 1 00.0 KB 0 0 000:00:00
347.186.2.63 4 00.0 KB 0 0 000:00:36
4scanner-29.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 41383 00.0 KB 0 0 000:07:23

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
2unresolved 1 00.0 KB 0 0 000:00:00
3*.access.net1377 00.0 KB 0 0 000:06:47
TOTAL: 31379 00.0 KB 0 0 000:06:47

NNRP auth users [Top 20]:

UserConn
nelgin 6
TOTAL: 1 6

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
news-xfer.access.net 127.125 43.991 0.00000:06:47
47.186.2.63 0.266 0.115 2.26100:00:36
107.170.243.26 0.100 0.035 0.00000:00:00
scanner-29.ch1.censys-scanner.com 0.101 0.031 0.00000:00:00
TOTAL: 4 127.592 44.172 2.26100:07:23

NNRP no permission clients:

SystemConn
47.186.2.63 1
TOTAL: 1 1

NNRP unrecognized commands (by host):

SystemConn
107.170.243.26 1
47.186.2.63 1
scanner-29.ch1.censys-scanner.com 1
TOTAL: 3 3

NNRP unrecognized commands (by command):

CommandCount
authingo\x7f\x7f#010#010fo pass 1Prompt1 1
EHLO www.censys.io 1
MGLNDD_192.138.210.162_119 1
TOTAL: 3 3