Daily Usenet Report

Sep 12 00:01:01 -- Sep 13 00:01:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 12 08:12:46 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Sep 12 14:58:30 newsfeed nnrpd[2266973]: 47.186.47.228 auth: program exited with status 8
Sep 13 00:00:01 newsfeed nnrpd[2402387]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
inn 8958 29.8%3.7 MB 56.1%
nnrpd 8511 28.3%887.0 KB 13.2%
innd 8031 26.7%1.2 MB 17.6%
innfeed 4126 13.7%808.1 KB 12.1%
cnfsstat 432 1.4%63.4 KB 0.9%
nocem 10 0.0%1.8 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 30069 100.0%6.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 122304 59.9%
Negative hits 73342 35.9%
Do not exist 8625 4.2%
Cache misses 32 0.0%
TOTAL: 4 204303 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.130 0.0%42 0.000 3.095 9.000
article cleanup00:00:00.097 0.0%8871 0.000 0.011 0.080
article logging00:00:00.437 0.0%8972 0.000 0.049 0.136
article parse00:00:00.205 0.0%12587 0.000 0.016 0.078
article write00:00:02.442 0.0%7652 0.000 0.319 3.316
artlog/artcncl00:00:00.001 0.0%7 0.000 0.143 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:01.725 0.0%206667 0.000 0.008 0.499
hisgrep/artcncl00:00:00.103 0.0%47 0.000 2.191 7.000
hishave/artcncl00:00:00.000 0.0%7 0.000 0.000 0.000
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:02:37.339 0.2%207115 0.093 0.760 5.527
history sync00:00:00.068 0.0%398 0.000 0.171 1.000
history write00:02:03.484 0.1%8729 3.013 14.146 113.909
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:50:13.473 99.2%205452 65.600 417.681 1388.838
nntp read00:00:03.415 0.0%207555 0.002 0.016 0.654
overview write00:05:07.479 0.4%7652 0.671 40.183 2504.679
perl filter00:00:34.457 0.0%8728 1.476 3.948 81.733
python filter00:00:02.398 0.0%8728 0.000 0.275 10.140
site send00:00:01.233 0.0%15312 0.000 0.081 0.250
TOTAL: 24:01:38.18624:00:48.486 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.024 0.0%7909 0.000 0.003 0.059
article prepare00:00:00.004 0.0%48960 0.000 0.000 0.002
article read00:00:00.561 0.0%3720 0.000 0.151 4.583
backlog stats00:00:00.344 0.0%275603 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.397 0.0%192031 0.001 0.012 0.052
data write00:00:03.192 0.0%187475 0.006 0.017 0.041
idle23:58:20.631100.0%275603 47.730 313.134 945.343
status file00:00:00.309 0.0%277 0.871 1.116 16.000
TOTAL: 23:58:39.05623:58:27.462100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle49:19:50.314 99.4%11468 0.250 15485.727 56807.352
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:03.948 0.0%101837 0.000 0.039 0.500
readart00:00:00.017 0.0%32 0.000 0.531 0.607
TOTAL: 49:38:37.56949:19:54.279 99.4%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 97
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 119

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1outgoing.news.lysator.liu.se 1 6529 1791 4681 57 27%23:59:55
2endofthelinebbs-out.news.weretis.net 4 13150 1347 11584 219 10%45:14:38
3usenet.blueworldhosting.com 721 8496 950 7127 419 11%23:51:36
4nntp.comgw.net 3 7875 742 7016 117 9%24:08:28
5news.hispagatos.org 5 7738 511 7176 51 6%24:26:20
6usenet.goja.nl.eu.org 11 8059 470 7486 103 5%25:29:49
7news.corradoroberto.it 9 1562 378 1181 3 24%24:36:00
8usenet.network 2 5847 347 5499 1 5%23:59:54
9newsfeed.bofh.team 3 8461 232 8204 25 2%24:08:50
10i2pn.org 2 7816 190 7594 32 2%23:59:46
11endoftheline-out.news.chmurka.net 2 3667 176 3484 7 4%23:59:49
12endofthelinebbs.peers.news.panix.com 26 7973 142 7819 12 1%24:02:27
13news.samoylyk.net 1 6843 109 6724 10 1%23:59:49
14news.nntp4.net 1 6547 37 6502 8 0%23:59:53
15news.netfront.net 1 6296 36 6162 98 0%23:46:50
16newsfeed.xs3.de 2 6291 27 6257 7 0%23:59:25
17news.bbs.nz 2 6244 16 6220 8 0%23:59:28
18glockenspiel.complete.org 2 5621 12 5599 10 0%24:02:34
19news.furie.org.uk 2 3881 3 3869 9 0%23:59:23
20photonic.trudheim.com 3 2059 3 2050 6 0%30:40:37
21eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:04
22localhost 27 0 0 0 0 0%00:00:00
23news.nk.ca 2 0 0 0 0 0%00:20:12
TOTAL: 23 833 130955 7519 122234 1202 5%510:55:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1outgoing.news.lysator.liu.se4.7 MB39.2 KB88.6 KB4.8 MB 97%2.6 KB
2endofthelinebbs-out.news.weretis.net4.1 MB51.6 KB353.3 KB4.5 MB 91%2.9 KB
3usenet.network3.4 MB0.0 KB1.3 KB3.4 MB 99%10.1 KB
4usenet.blueworldhosting.com2.2 MB0.0 KB714.4 KB2.9 MB 75%2.2 KB
5news.corradoroberto.it2.1 MB7.6 KB0.0 KB2.1 MB 99%5.8 KB
6nntp.comgw.net2.0 MB40.8 KB444.4 KB2.5 MB 81%3.0 KB
7news.hispagatos.org1.5 MB46.9 KB65.8 KB1.6 MB 93%3.0 KB
8usenet.goja.nl.eu.org1.3 MB43.1 KB152.7 KB1.5 MB 87%2.7 KB
9newsfeed.bofh.team716.4 KB43.1 KB46.2 KB805.6 KB 88%3.1 KB
10i2pn.org702.1 KB19.0 KB56.6 KB777.7 KB 90%3.5 KB
11news.samoylyk.net697.6 KB37.1 KB0.6 KB735.4 KB 94%6.2 KB
12endoftheline-out.news.chmurka.net465.8 KB27.1 KB0.6 KB493.5 KB 94%2.7 KB
13endofthelinebbs.peers.news.panix.com353.7 KB13.4 KB14.3 KB381.4 KB 92%2.5 KB
14news.nntp4.net104.4 KB35.1 KB0.0 KB139.5 KB 74%3.1 KB
15news.netfront.net93.6 KB41.8 KB309.6 KB444.9 KB 21%3.3 KB
16newsfeed.xs3.de84.0 KB21.5 KB0.0 KB105.5 KB 79%3.1 KB
17photonic.trudheim.com48.8 KB19.6 KB0.0 KB68.4 KB 71%7.6 KB
18news.bbs.nz43.5 KB35.1 KB0.0 KB78.6 KB 55%3.3 KB
19glockenspiel.complete.org35.2 KB52.9 KB0.6 KB88.7 KB 39%4.0 KB
20news.furie.org.uk14.0 KB39.4 KB0.9 KB54.3 KB 25%4.5 KB
21eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
23news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2324.7 MB614.4 KB2.2 MB27.5 MB 89%3.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 12 00:01:01 - 00:59:59 177 2.3% 0.05771.2 KB 3.0% 0.22
Sep 12 01:00:00 - 01:59:59 227 3.0% 0.06919.4 KB 3.6% 0.26
Sep 12 02:00:00 - 02:59:59 326 4.3% 0.09972.5 KB 3.8% 0.27
Sep 12 03:00:00 - 03:59:59 371 4.9% 0.101.4 MB 5.6% 0.40
Sep 12 04:00:00 - 04:59:59 305 4.0% 0.08880.7 KB 3.4% 0.24
Sep 12 05:00:00 - 05:59:59 412 5.4% 0.111.3 MB 5.2% 0.37
Sep 12 06:00:00 - 06:59:59 381 5.0% 0.111.0 MB 4.2% 0.30
Sep 12 07:00:00 - 07:59:59 409 5.4% 0.111.2 MB 4.6% 0.33
Sep 12 08:00:00 - 08:59:59 424 5.6% 0.121.3 MB 5.2% 0.37
Sep 12 09:00:00 - 09:59:59 652 8.5% 0.182.1 MB 8.5% 0.60
Sep 12 10:00:00 - 10:59:59 415 5.4% 0.121.3 MB 5.4% 0.38
Sep 12 11:00:00 - 11:59:59 420 5.5% 0.121.3 MB 5.1% 0.36
Sep 12 12:00:00 - 12:59:59 444 5.8% 0.121.4 MB 5.7% 0.41
Sep 12 13:00:00 - 13:59:59 442 5.8% 0.121.3 MB 5.0% 0.36
Sep 12 14:00:00 - 14:59:59 348 4.6% 0.101.1 MB 4.3% 0.30
Sep 12 15:00:00 - 15:59:59 296 3.9% 0.081.1 MB 4.6% 0.32
Sep 12 16:00:00 - 16:59:59 325 4.3% 0.091.2 MB 4.7% 0.33
Sep 12 17:00:00 - 17:59:59 290 3.8% 0.081.0 MB 4.2% 0.30
Sep 12 18:00:00 - 18:59:59 212 2.8% 0.06771.0 KB 3.0% 0.21
Sep 12 19:00:00 - 19:59:59 148 1.9% 0.04569.3 KB 2.2% 0.16
Sep 12 20:00:00 - 20:59:59 150 2.0% 0.04519.6 KB 2.0% 0.14
Sep 12 21:00:00 - 21:59:59 131 1.7% 0.04447.9 KB 1.8% 0.12
Sep 12 22:00:00 - 22:59:59 137 1.8% 0.04573.0 KB 2.2% 0.16
Sep 12 23:00:00 - 23:59:59 178 2.3% 0.05614.5 KB 2.4% 0.17
Sep 13 00:00:00 - 00:01:01 12 0.2% 0.2047.2 KB 0.2% 0.77
TOTAL: 24:00:00 7632 100.0% 0.0924.9 MB 100.0% 0.30
Incoming articles
Incoming articles (size)

CNFS buffer status:

BufferClassSizeUsed%UsedCyclesKB/secDays
CYCNGS1NGS1536.0 GB48.0 MB 0.0% 1 0.00 0.00
CYCNGS2NGS1536.0 GB97.4 GB 6.3% 0 0.4739317.82
CYCTXT1TEXT5.0 GB3.9 GB 77.1% 0 0.03 1959.78
TOTAL: 3-3077.0 GB101.3 GB 3.3% 1 0.33-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 451 2 0 0 0 0 0 0 449
2endofthelinebbs-out.news.weretis.net 271 0 0 14 0 0 0 0 257
3nntp.comgw.net 133 0 0 9 0 0 0 0 124
4usenet.goja.nl.eu.org 114 0 0 10 0 0 0 0 104
5news.netfront.net 101 0 0 9 0 0 0 0 92
6outgoing.news.lysator.liu.se 59 0 0 9 0 0 0 0 50
7news.hispagatos.org 47 21 0 10 0 0 0 0 16
8i2pn.org 33 1 0 4 0 0 0 0 28
9newsfeed.bofh.team 24 0 0 10 0 0 0 0 14
10endofthelinebbs.peers.news.panix.com 17 0 0 4 0 0 0 0 13
11news.bbs.nz 9 0 0 9 0 0 0 0 0
12news.furie.org.uk 9 0 0 8 0 0 0 0 1
13news.samoylyk.net 9 0 0 9 0 0 0 0 0
14glockenspiel.complete.org 8 0 0 8 0 0 0 0 0
15news.nntp4.net 8 0 0 8 0 0 0 0 0
16newsfeed.xs3.de 8 0 0 7 0 0 0 0 1
17endoftheline-out.news.chmurka.net 7 0 0 6 0 0 0 0 1
18photonic.trudheim.com 6 0 0 6 0 0 0 0 0
19news.corradoroberto.it 4 0 0 3 0 0 0 0 1
20usenet.network 1 0 0 0 0 0 0 0 1
TOTAL: 20 1319 24 0 143 0 0 0 0 1152

Unwanted newsgroups [Top 20]:

NewsgroupCount
hispagatos.hacking.ctf 15
hispagatos.hacking.news 6
hispagatos.hacking.exploits 1
soc.culture.russia 1
sympatico.support 1
TOTAL: 5 24

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 482
EMP (phr path) 353
Too many newsgroups 101
EMP (md5) 94
User-issued cancel 11
HTML Multipart 6
Too many newsgroups (meow) 2
Too many newsgroups (low_xpost) 1
TOTAL: 8 1050

NoCeM on Spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (spam)10202
i2pn2-nocem@i2pn2.org (rmp)2001212
robot@pasdenom.info (spam3)50055
TOTAL: 38021719

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 38
usenet.blueworldhosting.com 31
usenet.goja.nl.eu.org 12
nntp.comgw.net 10
endofthelinebbs.peers.news.panix.com 5
outgoing.news.lysator.liu.se 2
news.hispagatos.org 1
news.corradoroberto.it 1
newsfeed.xs3.de 1
TOTAL: 9 101
TOTAL: 1 101

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 12148 2222 5373 1 0 22 18%23:59:15
2nyheter.lysator.liu.se 6030 1445 3946 449 0 0 23%23:59:52
3news.furie.org.uk 4248 914 2731 1 0 0 21%23:58:29
4photonic.trudheim.com 8029 622 5910 1089 0 0 7%23:59:51
5endofthelinebbs.peers.news.panix.com 7862 416 7206 5 0 6 5%23:59:52
6usenet.network 8143 394 6455 50 0 0 4%23:59:52
7news.hispagatos.org 8469 369 6537 206 0 2 4%23:59:52
8usenet.goja.nl.eu.org 8374 276 6711 162 0 0 3%23:58:28
9news.nk.ca 11303 205 6810 569 0 38 1%23:59:52
10newsfeed.bofh.team 8539 196 7014 0 0 1 2%23:59:42
11news.bbs.nz 14103 185 7043 390 0 96 1%23:59:52
12news.weretis.net 7885 124 7376 128 0 5 1%23:59:29
13i2pn.org 8585 120 7219 81 0 47 1%23:59:36
14news.tnetconsulting.net 8447 120 7264 239 0 14 1%23:59:52
15news.nntp4.net 8754 114 7397 33 0 0 1%23:59:37
16nntp.comgw.net 8008 88 6704 91 0 0 1%23:59:52
17news.quux.org 8103 81 7033 500 0 22 0%23:59:33
18news.corradoroberto.it 1102 56 929 1 0 0 5%23:13:04
19newsfeed.xs3.de 9086 46 7383 153 0 0 0%23:59:26
20news.chmurka.net 4078 40 3353 2 0 0 0%23:59:52
21news.samoylyk.net 9073 24 7429 50 0 29 0%23:59:38
22usenet.blueworldhosting.com 6645 1 6364 0 0 6 0%23:59:52
23feed.opticnetworks.net 0 0 0 0 0 7632 0%23:59:52
TOTAL: 23 177014 8058 134187 4200 0 7920 4%551:04:40
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net9.2 MB1.9 KB9.2 MB0.1 KB/s4.2 KB23:59:15
2news.furie.org.uk4.9 MB140.9 KB5.0 MB0.1 KB/s5.6 KB23:58:29
3nyheter.lysator.liu.se4.7 MB3.5 MB8.2 MB0.1 KB/s4.4 KB23:59:52
4photonic.trudheim.com3.4 MB3.4 MB6.8 MB0.1 KB/s4.1 KB23:59:51
5endofthelinebbs.peers.news.panix.com2.3 MB10.7 KB2.3 MB0.0 KB/s5.6 KB23:59:52
6newsfeed.bofh.team1.8 MB0.0 KB1.8 MB0.0 KB/s9.2 KB23:59:42
7usenet.network1.3 MB117.1 KB1.4 MB0.0 KB/s3.3 KB23:59:52
8news.hispagatos.org1.2 MB2.1 MB3.3 MB0.0 KB/s5.8 KB23:59:52
9news.quux.org944.0 KB2.6 MB3.5 MB0.0 KB/s6.2 KB23:59:33
10news.nntp4.net824.3 KB448.3 KB1.2 MB0.0 KB/s8.7 KB23:59:37
11news.weretis.net644.0 KB1.6 MB2.3 MB0.0 KB/s9.3 KB23:59:29
12usenet.goja.nl.eu.org621.3 KB1.5 MB2.1 MB0.0 KB/s5.0 KB23:58:28
13news.bbs.nz609.0 KB2.8 MB3.4 MB0.0 KB/s6.1 KB23:59:52
14news.samoylyk.net587.0 KB270.4 KB857.4 KB0.0 KB/s11.6 KB23:59:38
15news.tnetconsulting.net577.0 KB1.5 MB2.1 MB0.0 KB/s5.9 KB23:59:52
16news.nk.ca499.0 KB3.3 MB3.8 MB0.0 KB/s5.0 KB23:59:52
17i2pn.org424.7 KB466.8 KB891.4 KB0.0 KB/s4.4 KB23:59:36
18nntp.comgw.net292.6 KB1.4 MB1.7 MB0.0 KB/s9.9 KB23:59:52
19newsfeed.xs3.de216.7 KB1.6 MB1.8 MB0.0 KB/s9.4 KB23:59:26
20news.corradoroberto.it162.1 KB1.7 KB163.9 KB0.0 KB/s2.9 KB23:13:04
21news.chmurka.net92.4 KB3.0 KB95.3 KB0.0 KB/s2.3 KB23:59:52
22usenet.blueworldhosting.com3.4 KB0.0 KB3.4 KB0.0 KB/s3.4 KB23:59:52
23feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
TOTAL: 2335.0 MB26.9 MB61.9 MB0.0 KB/s5.2 KB551:04:40
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.186.47.228 1 514.7 KB 3 0 048:57:07
TOTAL: 1 1 514.7 KB 3 0 049:38:36

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 12 514.7 KB 3 0 048:57:37
2*.demos.su2766 00.0 KB 0 0 000:29:36
3*.stretchoid.com 2 00.0 KB 0 0 000:00:10
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
5*.survey.inspici.com 1 00.0 KB 0 0 000:00:03
6? 1 00.0 KB 0 0 000:11:05
TOTAL: 62783 514.7 KB 3 0 049:38:36

NNRP auth users [Top 20]:

UserConn
getlist 1
nelgin 1
TOTAL: 2 2

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47.186.47.228 0.118 0.348 0.77048:57:07
ddt.demos.su 9.432 36.759 0.00000:29:36
localhost 179.910 370.762 0.07500:11:05
198.235.24.237 0.006 0.009 0.00000:00:10
205.210.31.240 0.004 0.012 0.00000:00:10
azpdss71.stretchoid.com 0.004 0.012 0.00000:00:09
162.142.125.41 0.025 0.035 0.00000:00:04
199.45.154.113 0.012 0.052 0.00000:00:04
server-0-3.survey.inspici.com 0.005 0.014 0.00000:00:03
appealing.monitoring.internet-measurement.com 0.004 0.012 0.00000:00:02
92.118.39.205 0.003 0.017 0.00000:00:00
80.66.76.130 0.008 0.008 0.00000:00:00
185.242.226.41 0.000 0.017 0.00000:00:00
azpdsg22.stretchoid.com 0.006 0.011 0.00000:00:00
TOTAL: 14 189.537 408.068 0.84549:38:36

Curious NNRP server explorers [Top 20]:

SystemConn
ddt.demos.su 2766
162.142.125.41 3
199.45.154.113 3
185.242.226.41 1
198.235.24.237 1
205.210.31.240 1
80.66.76.130 1
92.118.39.205 1
appealing.monitoring.internet-measurement.com 1
azpdsg22.stretchoid.com 1
azpdss71.stretchoid.com 1
localhost 1
server-0-3.survey.inspici.com 1
TOTAL: 13 2782

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
185.242.226.41 5
162.142.125.41 1
199.45.154.113 1
80.66.76.130 1
azpdsg22.stretchoid.com 1
TOTAL: 5 9

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
EHLO www.censys.io 2
#003 1
Accept-Encoding: gzip 1
Accept: */* 1
GET / HTTP/1.1 1
Host: 144.172.126.95:119 1
MGLNDD_144.172.126.95_119 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
TOTAL: 8 9

NNRP client timeouts [Top 20]:

SystemConnPeer
162.142.125.41 1 2
199.45.154.113 1 2
47.186.47.228 1 0
80.66.76.130 1 1
TOTAL: 4 4 5

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1alt 2 50.0%
2news 2 50.0%
TOTAL: 2 4100.0%

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

NewsgroupCount
1news.software.nntp 2
2alt.fan.states.texas 1
3alt.music.lyrics 1
TOTAL: 3 4