Daily Usenet Report

May 2 00:01:02 -- May 3 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 2 08:16:41 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
May 3 00:00:01 newsfeed nnrpd[2154610]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 25309 53.8%3.0 MB 35.1%
inn 8394 17.8%3.4 MB 40.1%
innd 8061 17.1%1.1 MB 13.4%
innfeed 4587 9.7%888.8 KB 10.2%
cnfsstat 576 1.2%97.0 KB 1.1%
nocem 127 0.3%14.2 KB 0.2%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 47055 100.0%8.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 120852 60.7%
Negative hits 63962 32.1%
Do not exist 8277 4.2%
Cache misses 5969 3.0%
TOTAL: 4 199060 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.118 0.0%23 0.000 5.130 10.000
article cleanup00:00:00.185 0.0%8326 0.000 0.022 1.194
article logging00:00:00.687 0.0%8392 0.000 0.082 4.088
article parse00:00:00.197 0.0%13766 0.000 0.014 0.070
article write00:00:02.402 0.0%6651 0.000 0.361 3.943
artlog/artcncl00:00:00.001 0.0%12 0.000 0.083 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.402 0.0%197442 0.000 0.002 0.041
hisgrep/artcncl00:00:00.071 0.0%25 0.000 2.840 7.000
hishave/artcncl00:00:00.009 0.0%12 0.000 0.750 4.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:25.804 0.2%199985 0.094 0.729 6.036
history sync00:00:00.084 0.0%399 0.000 0.211 1.000
history write00:02:02.577 0.1%8313 3.225 14.745 138.800
hiswrite/artcncl00:00:00.008 0.0%2 4.000 4.000 4.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:51:40.029 99.3%200658 57.262 428.092 2345.906
nntp read00:00:02.774 0.0%198600 0.000 0.014 0.061
overview write00:03:31.736 0.2%6651 0.450 31.835 1295.380
perl filter00:00:20.541 0.0%8313 1.300 2.471 13.655
python filter00:00:01.247 0.0%8313 0.000 0.150 5.159
site send00:00:01.259 0.0%13419 0.000 0.094 0.248
TOTAL: 2324:00:10.131 99.9%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.027 0.0%9894 0.000 0.003 0.100
article prepare00:00:00.007 0.0%51252 0.000 0.000 0.005
article read00:00:00.728 0.0%4437 0.000 0.164 1.829
backlog stats00:00:00.400 0.0%260977 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.323 0.0%182147 0.000 0.013 0.044
data write00:00:03.474 0.0%177658 0.010 0.020 0.032
idle23:58:46.257100.0%260977 158.426 330.781 2050.823
status file00:00:00.510 0.0%343 1.000 1.487 11.000
TOTAL: 923:58:53.726100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:58:12.807 99.9%16733 116.000 208.738191318.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.875 0.1%112417 0.000 0.043 1.000
TOTAL: 300:58:17.682100.0%----

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 (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.dne3.net 2 6654 1298 5345 11 19%25:56:05
2endofthelinebbs-out.news.weretis.net 2 12556 1129 11260 167 8%48:26:42
3usenet.goja.nl.eu.org 11 7599 976 6365 258 12%24:14:22
4nntp.comgw.net 1 7552 458 6934 160 6%23:59:56
5i2pn.org 1 7122 434 6548 140 6%23:59:51
6usenet.blueworldhosting.com 493 8034 362 7196 476 4%17:25:13
7news.corradoroberto.it 2 1791 346 1445 0 19%23:59:25
8usenet.network 3 5587 259 5319 9 4%23:59:06
9news.hispagatos.org 10 7596 253 7259 84 3%24:50:01
10newsfeed.bofh.team 2 8145 229 7858 58 2%23:59:21
11endoftheline-out.news.chmurka.net 1 3430 215 3215 0 6%23:59:57
12newsfeed.xs3.de 1 5583 141 5430 12 2%23:59:56
13glockenspiel.complete.org 2 5050 136 4912 2 2%24:02:12
14news.furie.org.uk 1 3396 99 3295 2 2%23:59:58
15endofthelinebbs.peers.news.panix.com 28 7055 91 6954 10 1%24:21:48
16194.87.241.33 45 246 79 91 76 32%13:51:38
17news.netfront.net 1 7603 76 7328 199 0%27:38:21
18peering.usenet.ovh 1 6980 75 6904 1 1%23:59:57
19news-out.tcpreset.net 2 6073 58 5999 16 0%23:59:49
20news.samoylyk.net 1 5984 49 5935 0 0%23:59:49
21outgoing.news.lysator.liu.se 2 4022 9 4012 1 0%23:59:10
22news.bbs.nz 2 2573 0 2573 0 0%00:03:00
23eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:06
24localhost 31 0 0 0 0 0%00:00:00
25news.nk.ca 1 0 0 0 0 0%00:10:06
TOTAL: 25 647 130631 6772 122177 1682 5%519:05:49
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net3.5 MB464.8 KB263.1 KB4.2 MB 83%3.3 KB
2news.dne3.net3.5 MB0.0 KB10.5 KB3.5 MB 99%2.7 KB
3usenet.network2.8 MB5.7 KB10.6 KB2.9 MB 99%10.9 KB
4nntp.comgw.net2.6 MB459.0 KB511.2 KB3.6 MB 73%5.9 KB
5usenet.goja.nl.eu.org2.6 MB0.0 KB361.7 KB2.9 MB 87%2.4 KB
6news.corradoroberto.it2.2 MB0.0 KB0.0 KB2.2 MB100%6.5 KB
7i2pn.org1.4 MB5.7 KB183.0 KB1.6 MB 88%2.8 KB
8glockenspiel.complete.org870.3 KB5.7 KB1.8 KB877.8 KB 99%6.4 KB
9endoftheline-out.news.chmurka.net867.8 KB0.0 KB0.0 KB867.8 KB100%4.0 KB
10usenet.blueworldhosting.com792.1 KB0.0 KB1.1 MB1.8 MB 42%2.2 KB
11news.samoylyk.net674.3 KB0.0 KB0.0 KB674.3 KB100%13.8 KB
12newsfeed.bofh.team648.9 KB464.7 KB73.0 KB1.2 MB 54%4.1 KB
13news.hispagatos.org638.7 KB0.0 KB115.0 KB753.6 KB 84%2.2 KB
14newsfeed.xs3.de462.7 KB5.7 KB23.4 KB491.8 KB 94%3.2 KB
15news.furie.org.uk329.6 KB464.7 KB0.0 KB794.3 KB 41%7.9 KB
16peering.usenet.ovh268.7 KB0.0 KB1.3 KB270.0 KB 99%3.6 KB
17194.87.241.33248.7 KB0.0 KB94.0 KB342.8 KB 72%2.2 KB
18news.netfront.net248.1 KB0.0 KB1.5 MB1.7 MB 13%6.5 KB
19endofthelinebbs.peers.news.panix.com214.5 KB0.0 KB8.7 KB223.3 KB 96%2.2 KB
20news-out.tcpreset.net195.3 KB5.7 KB15.8 KB216.8 KB 90%2.9 KB
21outgoing.news.lysator.liu.se31.0 KB459.0 KB0.0 KB490.0 KB 6%49.0 KB
22eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
23localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
24news.bbs.nz0.0 KB0.0 KB0.0 KB0.0 KB 0%0
25news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2524.9 MB2.3 MB4.2 MB31.4 MB 79%3.8 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 2 00:01:02 - 00:59:59 186 2.8% 0.05768.4 KB 3.1% 0.22
May 2 01:00:00 - 01:59:59 319 4.8% 0.09883.7 KB 3.5% 0.25
May 2 02:00:00 - 02:59:59 360 5.4% 0.101.1 MB 4.5% 0.31
May 2 03:00:00 - 03:59:59 359 5.4% 0.101.1 MB 4.6% 0.32
May 2 04:00:00 - 04:59:59 359 5.4% 0.10923.5 KB 3.7% 0.26
May 2 05:00:00 - 05:59:59 349 5.3% 0.101013.0 KB 4.0% 0.28
May 2 06:00:00 - 06:59:59 368 5.5% 0.101.4 MB 5.7% 0.40
May 2 07:00:00 - 07:59:59 320 4.8% 0.091.1 MB 4.3% 0.30
May 2 08:00:00 - 08:59:59 437 6.6% 0.121.7 MB 6.9% 0.48
May 2 09:00:00 - 09:59:59 390 5.9% 0.111.8 MB 7.4% 0.51
May 2 10:00:00 - 10:59:59 408 6.1% 0.111.9 MB 7.8% 0.54
May 2 11:00:00 - 11:59:59 398 6.0% 0.111.3 MB 5.4% 0.38
May 2 12:00:00 - 12:59:59 334 5.0% 0.091.1 MB 4.4% 0.31
May 2 13:00:00 - 13:59:59 288 4.3% 0.08799.8 KB 3.2% 0.22
May 2 14:00:00 - 14:59:59 276 4.2% 0.08782.4 KB 3.1% 0.22
May 2 15:00:00 - 15:59:59 239 3.6% 0.07905.9 KB 3.6% 0.25
May 2 16:00:00 - 16:59:59 254 3.8% 0.071.3 MB 5.1% 0.36
May 2 17:00:00 - 17:59:59 243 3.7% 0.07777.3 KB 3.1% 0.22
May 2 18:00:00 - 18:59:59 158 2.4% 0.04946.4 KB 3.8% 0.26
May 2 19:00:00 - 19:59:59 109 1.6% 0.03490.2 KB 2.0% 0.14
May 2 20:00:00 - 20:59:59 126 1.9% 0.04902.4 KB 3.6% 0.25
May 2 21:00:00 - 21:59:59 98 1.5% 0.03727.8 KB 2.9% 0.20
May 2 22:00:00 - 22:59:59 117 1.8% 0.03484.4 KB 1.9% 0.13
May 2 23:00:00 - 23:59:59 148 2.2% 0.04618.1 KB 2.5% 0.17
May 3 00:00:00 - 00:01:01 3 0.0% 0.0520.1 KB 0.1% 0.33
TOTAL: 23:59:59 6646 100.0% 0.0824.5 MB 100.0% 0.29
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 GB106.9 GB 7.0% 0 0.4541258.89
CYCNGS3NGS1536.0 GB48.0 MB 0.0% 0 0.00 0.00
CYCTXT1TEXT5.0 GB4.2 GB 84.3% 0 0.02 3211.81
TOTAL: 4-4613.0 GB111.2 GB 2.4% 1 0.38-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 490 1 0 0 0 0 0 0 489
2usenet.goja.nl.eu.org 273 0 0 0 0 0 0 0 273
3news.netfront.net 203 0 0 0 0 0 0 0 203
4nntp.comgw.net 184 0 0 1 0 0 0 0 183
5endofthelinebbs-out.news.weretis.net 155 0 0 2 0 0 0 0 153
6i2pn.org 130 0 0 1 0 0 0 0 129
7news.hispagatos.org 93 0 0 0 0 0 0 0 93
8194.87.241.33 76 0 0 0 0 0 0 0 76
9newsfeed.bofh.team 60 1 0 2 0 0 0 0 57
10news-out.tcpreset.net 16 0 0 1 0 0 0 0 15
11endofthelinebbs.peers.news.panix.com 13 2 0 0 0 0 0 0 11
12newsfeed.xs3.de 12 0 0 1 0 0 0 0 11
13usenet.network 12 0 0 1 0 0 0 0 11
14news.dne3.net 11 0 0 0 0 0 0 0 11
15news.bbs.nz 3 0 0 0 0 0 0 0 3
16news.furie.org.uk 2 0 0 2 0 0 0 0 0
17glockenspiel.complete.org 1 0 0 1 0 0 0 0 0
18outgoing.news.lysator.liu.se 1 0 0 1 0 0 0 0 0
19peering.usenet.ovh 1 0 0 0 0 0 0 0 1
TOTAL: 19 1736 4 0 13 0 0 0 0 1719

Unwanted newsgroups [Top 20]:

NewsgroupCount
infomaster01c.at 2
alt.magazines 1
soc.culture.russia 1
TOTAL: 3 4

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 786
Too many newsgroups 327
EMP (phr path) 320
EMP (md5) 131
Too many newsgroups (meow) 33
Angle-bracket bot 30
User-issued cancel 19
Binary: misplaced binary 4
HTML Multipart 3
TOTAL: 9 1653

NoCeM on spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
robot@pasdenom.info (spam)50505
i2pn2-nocem@i2pn2.org (spam)20303
robot@pasdenom.info (spam2)7000106106
i2pn2-nocem@i2pn2.org (multipost)33003838
i2pn2-nocem@i2pn2.org (rmp)20077
robot@pasdenom.info (spam3)30055
robot@pasdenom.info (spam4)40055
TOTAL: 711908161169

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
nntp.comgw.net 24
usenet.blueworldhosting.com 14
endofthelinebbs-out.news.weretis.net 6
news.netfront.net 5
usenet.goja.nl.eu.org 4
i2pn.org 4
usenet.network 3
endofthelinebbs.peers.news.panix.com 3
newsfeed.bofh.team 3
TOTAL: 9 66
TOTAL: 1 66

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 7081 2833 3511 290 0 0 40%23:58:55
2news.netfront.net 10766 1750 4804 3 0 6 16%23:59:51
3news.furie.org.uk 3761 1144 1862 46 0 0 30%23:59:53
4endofthelinebbs.peers.news.panix.com 6944 686 5947 3 0 0 9%23:58:54
5peering.usenet.ovh 7084 643 5960 29 0 0 9%23:59:21
6photonic.trudheim.com 7828 526 4716 1397 0 2 6%23:59:49
7news.dne3.net 5669 255 5064 118 0 0 4%23:59:46
8usenet.goja.nl.eu.org 6766 208 5192 27 0 0 3%23:59:53
9news.nntp4.net 7242 205 6183 3 0 21 2%23:59:53
10news.hispagatos.org 7295 141 6203 39 0 20 1%23:59:53
11i2pn.org 6914 96 6084 12 0 10 1%23:59:53
12newsfeed.bofh.team 7293 88 6327 0 0 0 1%23:59:53
13news.tcpreset.net 8120 83 5644 816 0 2 1%23:59:52
14nntp.comgw.net 6493 57 6098 26 0 0 0%23:59:53
15usenet.network 8035 50 6052 16 0 2 0%23:59:49
16news.nk.ca 9216 31 6175 320 0 16 0%23:59:50
17news.chmurka.net 4290 25 2974 0 0 0 0%23:59:47
18news.samoylyk.net 8620 21 6487 48 0 9 0%23:59:51
19news.weretis.net 6967 21 6457 164 0 0 0%23:59:41
20news.quux.org 6475 21 5878 241 0 0 0%23:59:52
21news.tnetconsulting.net 9350 18 6416 201 0 11 0%23:59:53
22newsfeed.xs3.de 8088 17 6234 235 0 0 0%23:59:53
23usenet.blueworldhosting.com 6606 12 6155 0 0 3153 0%23:59:53
24ddt.demos.su 16 3 13 0 0 0 18%02:16:37
25news.corradoroberto.it 862 1 626 0 0 0 0%22:58:01
26news.bbs.nz 0 0 0 0 0 6633 0%23:59:53
TOTAL: 26 167781 8935 127062 4034 0 9885 5%601:08:39
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net9.5 MB43.6 KB9.6 MB0.1 KB/s5.6 KB23:59:51
2nyheter.lysator.liu.se8.9 MB3.1 MB12.0 MB0.1 KB/s3.9 KB23:58:55
3news.furie.org.uk6.6 MB195.6 KB6.8 MB0.1 KB/s5.9 KB23:59:53
4peering.usenet.ovh4.1 MB177.7 KB4.2 MB0.1 KB/s6.5 KB23:59:21
5endofthelinebbs.peers.news.panix.com3.7 MB5.5 KB3.7 MB0.0 KB/s5.6 KB23:58:54
6photonic.trudheim.com2.9 MB3.8 MB6.7 MB0.1 KB/s3.6 KB23:59:49
7newsfeed.bofh.team767.0 KB0.0 KB767.0 KB0.0 KB/s8.7 KB23:59:53
8news.nntp4.net632.4 KB45.1 KB677.6 KB0.0 KB/s3.3 KB23:59:53
9news.tcpreset.net574.7 KB3.7 MB4.3 MB0.1 KB/s4.9 KB23:59:52
10news.quux.org558.9 KB1.6 MB2.2 MB0.0 KB/s8.4 KB23:59:52
11news.dne3.net471.9 KB1.5 MB2.0 MB0.0 KB/s5.4 KB23:59:46
12usenet.goja.nl.eu.org441.3 KB598.7 KB1.0 MB0.0 KB/s4.4 KB23:59:53
13news.samoylyk.net381.7 KB345.1 KB726.9 KB0.0 KB/s10.5 KB23:59:51
14i2pn.org296.4 KB124.0 KB420.4 KB0.0 KB/s3.9 KB23:59:53
15news.hispagatos.org278.7 KB702.0 KB980.7 KB0.0 KB/s5.4 KB23:59:53
16newsfeed.xs3.de160.1 KB1.2 MB1.3 MB0.0 KB/s5.4 KB23:59:53
17usenet.network158.2 KB106.2 KB264.3 KB0.0 KB/s4.0 KB23:59:49
18nntp.comgw.net134.0 KB558.8 KB692.8 KB0.0 KB/s8.3 KB23:59:53
19ddt.demos.su126.7 KB0.0 KB126.7 KB0.0 KB/s42.2 KB02:16:37
20news.chmurka.net126.4 KB0.0 KB126.4 KB0.0 KB/s5.1 KB23:59:47
21news.tnetconsulting.net104.2 KB2.1 MB2.2 MB0.0 KB/s10.3 KB23:59:53
22news.nk.ca64.0 KB1.8 MB1.9 MB0.0 KB/s5.4 KB23:59:50
23news.weretis.net52.7 KB1.9 MB1.9 MB0.0 KB/s10.8 KB23:59:41
24usenet.blueworldhosting.com31.1 KB0.0 KB31.1 KB0.0 KB/s2.6 KB23:59:53
25news.corradoroberto.it2.7 KB0.0 KB2.7 KB0.0 KB/s2.7 KB22:58:01
26news.bbs.nz0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2641.1 MB23.5 MB64.6 MB0.0 KB/s5.1 KB601:08:39
Outgoing feeds (innfeed) by volume

NNRP connection statistics (by domain) [Top 100]:

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved2804 00.0 KB 2 0 000:21:52
2*.nntp4.net2787 00.0 KB 0 0 000:23:30
3*.ip.linodeusercontent.com 1 00.0 KB 0 0 000:00:00
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:02
5*localhost 1 00.0 KB 0 0 000:12:49
TOTAL: 55594 00.0 KB 2 0 000:58:15

NNRP auth users [Top 20]:

UserConn
getlist 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
news.nntp4.net 10.668 29.291 0.00000:23:30
2a0e:73c2:8000:802c::2 10.268 30.042 0.00000:21:17
localhost 182.394 371.511 0.07500:12:49
206.168.34.124 0.013 0.042 0.00000:00:13
162.142.125.219 0.023 0.038 0.00000:00:06
167.94.145.108 0.025 0.029 0.00000:00:04
162.142.125.196 0.016 0.012 0.00000:00:03
198.235.24.200 0.003 0.010 0.00000:00:02
tenable.monitoring.internet-measurement.com 0.004 0.009 0.00000:00:02
2a06:4882:7000::8e 0.009 0.004 0.00000:00:01
147.185.132.85 0.004 0.009 0.00000:00:01
194.165.16.161 0.010 0.003 0.00000:00:00
69-164-217-245.ip.linodeusercontent.com 0.009 0.004 0.00000:00:00
47.90.150.71 0.003 0.014 0.00000:00:00
TOTAL: 14 203.449 431.018 0.07500:58:15

Curious NNRP explorers [Top 20]:

SystemConn
2a0e:73c2:8000:802c::2 2789
news.nntp4.net 2787
162.142.125.219 3
167.94.145.108 3
206.168.34.124 3
147.185.132.85 1
162.142.125.196 1
194.165.16.161 1
198.235.24.200 1
2a06:4882:7000::8e 1
47.90.150.71 1
69-164-217-245.ip.linodeusercontent.com 1
localhost 1
tenable.monitoring.internet-measurement.com 1
TOTAL: 14 5594

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

SystemConn
162.142.125.196 1
162.142.125.219 1
167.94.145.108 1
194.165.16.161 1
206.168.34.124 1
TOTAL: 5 5

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

CommandCount
EHLO www.censys.io 4
#003 1
TOTAL: 2 5

NNRP client timeouts [Top 20]:

SystemConnPeer
162.142.125.219 1 2
167.94.145.108 1 2
194.165.16.161 1 1
206.168.34.124 1 2
TOTAL: 4 4 10

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

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

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

NewsgroupCount
1alt.bbs.doors 1
2news.answers 1
TOTAL: 2 2