Daily Usenet Report

May 6 00:01:01 -- May 7 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 6 08:14:53 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
May 7 00:00:01 newsfeed nnrpd[625079]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 25231 51.9%3.0 MB 34.0%
innd 9299 19.1%1.3 MB 15.0%
inn 8724 17.9%3.5 MB 40.1%
innfeed 4710 9.7%877.3 KB 9.8%
cnfsstat 576 1.2%88.0 KB 1.0%
nocem 88 0.2%11.0 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 48629 100.0%8.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 118457 62.0%
Negative hits 60234 31.5%
Do not exist 8652 4.5%
Cache misses 3792 2.0%
TOTAL: 4 191135 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.116 0.0%33 0.000 3.515 7.000
article cleanup00:00:00.084 0.0%8683 0.000 0.010 0.065
article logging00:00:00.546 0.0%8721 0.000 0.063 0.908
article parse00:00:00.222 0.0%14595 0.000 0.015 0.107
article write00:00:02.441 0.0%6657 0.000 0.367 7.000
artlog/artcncl00:00:00.000 0.0%12 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.765 0.0%193072 0.000 0.004 0.202
hisgrep/artcncl00:00:00.094 0.0%37 0.000 2.541 5.500
hishave/artcncl00:00:00.000 0.0%12 0.000 0.000 0.000
hishave/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:33.927 0.2%191718 0.088 0.803 5.003
history sync00:00:00.087 0.0%400 0.000 0.217 1.000
history write00:02:07.894 0.1%8676 3.225 14.741 152.348
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.012 0.0%1 12.000 12.000 12.000
idle23:46:02.573 99.0%196620 73.003 435.167 1319.620
nntp read00:00:04.235 0.0%193597 0.004 0.022 0.518
overview write00:08:43.381 0.6%6657 0.417 78.621 6573.026
perl filter00:00:24.494 0.0%8676 1.118 2.823 23.769
python filter00:00:01.572 0.0%8676 0.017 0.181 5.475
site send00:00:01.122 0.0%13389 0.000 0.084 0.233
TOTAL: 2324:00:03.565 99.9%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.024 0.0%13405 0.000 0.002 0.059
article prepare00:00:00.007 0.0%69667 0.000 0.000 0.007
article read00:00:01.639 0.0%5991 0.000 0.274 1.870
backlog stats00:00:00.403 0.0%270177 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.481 0.0%187124 0.002 0.013 0.041
data write00:00:03.989 0.0%181897 0.008 0.022 0.181
idle23:57:42.694100.0%270177 124.892 319.282 1590.164
status file00:00:00.860 0.0%413 0.000 2.082 62.800
TOTAL: 923:57:52.097100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:55:17.613 99.9%16659 51.500 199.148165716.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.295 0.1%112219 0.000 0.038 0.667
TOTAL: 300:55:21.908100.0%----

Control commands to innd:

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

Incoming feeds (innd):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 2 12024 2233 9294 497 18%48:39:25
2news.dne3.net 2 5159 1122 4030 7 21%18:50:01
3usenet.blueworldhosting.com 718 8191 712 6788 691 8%23:59:16
4usenet.network 2 5617 649 4959 9 11%23:59:41
5nntp.comgw.net 2 7300 243 6864 193 3%23:59:56
6i2pn.org 1 7074 231 6801 42 3%23:59:54
7newsfeed.bofh.team 1 8165 228 7853 84 2%23:59:56
8news.corradoroberto.it 2 1828 221 1607 0 12%23:59:40
9endoftheline-out.news.chmurka.net 1 2992 215 2777 0 7%23:59:58
10194.87.241.33 50 274 133 94 47 48%15:36:17
11endofthelinebbs.peers.news.panix.com 27 7942 112 7762 68 1%23:54:16
12news.hispagatos.org 6 7312 107 7184 21 1%24:26:21
13news.samoylyk.net 2 7006 53 6853 100 0%09:16:44
14usenet.goja.nl.eu.org 10 7109 50 7037 22 0%23:53:05
15peering.usenet.ovh 1 7124 41 7080 3 0%23:59:57
16newsfeed.xs3.de 2 5291 30 5259 2 0%23:59:44
17news.furie.org.uk 1 3446 27 3419 0 0%23:59:57
18news.netfront.net 1 6165 24 6108 33 0%25:10:44
19glockenspiel.complete.org 2 5196 24 5172 0 0%23:58:37
20news-out.tcpreset.net 2 5871 22 5847 2 0%23:59:52
21news.bbs.nz 2 6184 20 6164 0 0%23:58:27
22outgoing.news.lysator.liu.se 2 2931 9 2702 220 0%21:41:39
23eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:06
24localhost 27 0 0 0 0 0%00:00:00
25news.nk.ca 2 0 0 0 0 0%00:20:11
TOTAL: 25 869 130201 6506 121654 2041 4%523:53:44
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.network7.0 MB0.0 KB1.3 MB8.3 MB 84%13.0 KB
2endofthelinebbs-out.news.weretis.net6.4 MB0.0 KB808.3 KB7.2 MB 88%2.7 KB
3news.dne3.net2.9 MB0.0 KB6.2 KB2.9 MB 99%2.6 KB
4nntp.comgw.net2.7 MB0.0 KB570.5 KB3.3 MB 83%7.7 KB
5i2pn.org1.9 MB0.0 KB55.1 KB1.9 MB 97%7.2 KB
6usenet.blueworldhosting.com1.6 MB5.6 KB1.4 MB3.1 MB 53%2.2 KB
7news.corradoroberto.it1.5 MB0.0 KB0.0 KB1.5 MB100%7.0 KB
8endoftheline-out.news.chmurka.net888.6 KB0.0 KB0.0 KB888.6 KB100%4.1 KB
9newsfeed.bofh.team660.7 KB1.2 KB121.5 KB783.4 KB 84%2.5 KB
10194.87.241.33556.5 KB0.0 KB58.4 KB614.9 KB 90%3.4 KB
11glockenspiel.complete.org350.7 KB0.0 KB0.0 KB350.7 KB100%14.6 KB
12news.hispagatos.org271.7 KB0.0 KB41.4 KB313.1 KB 86%2.4 KB
13endofthelinebbs.peers.news.panix.com251.2 KB0.0 KB113.3 KB364.4 KB 68%2.0 KB
14peering.usenet.ovh188.9 KB0.0 KB3.6 KB192.6 KB 98%4.4 KB
15news.samoylyk.net187.9 KB0.0 KB99.8 KB287.6 KB 65%1.9 KB
16usenet.goja.nl.eu.org145.1 KB28.3 KB38.7 KB212.1 KB 68%2.9 KB
17newsfeed.xs3.de127.7 KB0.0 KB6.8 KB134.5 KB 94%4.2 KB
18news.furie.org.uk104.5 KB0.0 KB0.0 KB104.5 KB100%3.9 KB
19news.netfront.net101.2 KB0.0 KB337.0 KB438.2 KB 23%7.7 KB
20news.bbs.nz61.5 KB0.0 KB0.0 KB61.5 KB100%3.1 KB
21news-out.tcpreset.net48.1 KB0.0 KB2.7 KB50.8 KB 94%2.1 KB
22outgoing.news.lysator.liu.se26.1 KB0.0 KB238.4 KB264.5 KB 9%1.2 KB
23eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
24localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
25news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2528.0 MB35.1 KB5.1 MB33.1 MB 84%4.0 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
May 6 00:01:01 - 00:59:59 184 2.8% 0.05807.7 KB 2.8% 0.23
May 6 01:00:00 - 01:59:59 316 4.8% 0.091.6 MB 5.5% 0.44
May 6 02:00:00 - 02:59:59 263 4.0% 0.071.2 MB 4.3% 0.34
May 6 03:00:00 - 03:59:59 353 5.3% 0.101.7 MB 6.1% 0.49
May 6 04:00:00 - 04:59:59 356 5.4% 0.101.4 MB 5.1% 0.41
May 6 05:00:00 - 05:59:59 335 5.0% 0.091.0 MB 3.7% 0.30
May 6 06:00:00 - 06:59:59 388 5.8% 0.111.2 MB 4.2% 0.34
May 6 07:00:00 - 07:59:59 298 4.5% 0.081.5 MB 5.4% 0.44
May 6 08:00:00 - 08:59:59 333 5.0% 0.091.1 MB 4.1% 0.33
May 6 09:00:00 - 09:59:59 481 7.2% 0.131.5 MB 5.3% 0.43
May 6 10:00:00 - 10:59:59 335 5.0% 0.091.2 MB 4.4% 0.36
May 6 11:00:00 - 11:59:59 317 4.8% 0.091.7 MB 6.2% 0.50
May 6 12:00:00 - 12:59:59 346 5.2% 0.101.5 MB 5.2% 0.41
May 6 13:00:00 - 13:59:59 310 4.7% 0.091.5 MB 5.5% 0.44
May 6 14:00:00 - 14:59:59 358 5.4% 0.101.5 MB 5.3% 0.42
May 6 15:00:00 - 15:59:59 336 5.1% 0.091.3 MB 4.7% 0.38
May 6 16:00:00 - 16:59:59 247 3.7% 0.07771.0 KB 2.7% 0.21
May 6 17:00:00 - 17:59:59 228 3.4% 0.06952.8 KB 3.3% 0.26
May 6 18:00:00 - 18:59:59 140 2.1% 0.04604.7 KB 2.1% 0.17
May 6 19:00:00 - 19:59:59 120 1.8% 0.031.0 MB 3.7% 0.30
May 6 20:00:00 - 20:59:59 120 1.8% 0.03607.4 KB 2.1% 0.17
May 6 21:00:00 - 21:59:59 179 2.7% 0.05750.0 KB 2.6% 0.21
May 6 22:00:00 - 22:59:59 116 1.7% 0.03957.1 KB 3.3% 0.27
May 6 23:00:00 - 23:59:59 183 2.8% 0.05713.4 KB 2.5% 0.20
May 7 00:00:00 - 00:01:01 6 0.1% 0.1018.8 KB 0.1% 0.31
TOTAL: 24:00:00 6648 100.0% 0.0828.2 MB 100.0% 0.33
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 GB107.0 GB 7.0% 0 0.5037649.95
CYCNGS3NGS1536.0 GB48.0 MB 0.0% 0 0.00 0.00
CYCTXT1TEXT5.0 GB4.2 GB 84.4% 0 0.03 2149.04
TOTAL: 4-4613.0 GB111.3 GB 2.4% 1 0.36-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 699 0 0 1 0 0 0 0 698
2endofthelinebbs-out.news.weretis.net 471 0 0 0 0 0 0 0 471
3outgoing.news.lysator.liu.se 220 0 0 0 0 0 0 0 220
4nntp.comgw.net 209 0 0 0 0 0 0 0 209
5news.samoylyk.net 100 0 0 0 0 0 0 0 100
6newsfeed.bofh.team 86 0 0 1 0 0 0 0 85
7endofthelinebbs.peers.news.panix.com 69 1 0 0 0 0 0 0 68
8194.87.241.33 47 0 0 0 0 0 0 0 47
9i2pn.org 42 0 0 0 0 0 0 0 42
10news.hispagatos.org 37 0 0 0 0 0 0 0 37
11news.netfront.net 34 0 0 0 0 0 0 0 34
12usenet.goja.nl.eu.org 22 0 0 5 0 0 0 0 17
13usenet.network 9 0 0 0 0 0 0 0 9
14news.dne3.net 8 0 0 0 0 0 0 0 8
15glockenspiel.complete.org 3 0 0 0 0 0 0 0 3
16news-out.tcpreset.net 3 0 0 0 0 0 0 0 3
17peering.usenet.ovh 3 0 0 0 0 0 0 0 3
18newsfeed.xs3.de 2 0 0 0 0 0 0 0 2
TOTAL: 18 2064 1 0 7 0 0 0 0 2056

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.ebook 1
TOTAL: 1 1

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 1066
EMP (phr path) 499
Too many newsgroups 286
EMP (md5) 115
Angle-bracket bot 19
User-issued cancel 12
Too many newsgroups (meow) 10
HTML Multipart 8
Subject (lsdorpervlakte) 2
TOTAL: 9 2017

NoCeM on spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
robot@pasdenom.info (spam)80808
robot@pasdenom.info (spam2)28004040
i2pn2-nocem@i2pn2.org (multipost)33003939
i2pn2-nocem@i2pn2.org (rmp)20077
robot@pasdenom.info (spam3)40077
robot@pasdenom.info (spam4)40066
TOTAL: 6790899107

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
usenet.network 1
TOTAL: 1 1
Including strange strings
nntp.comgw.net 12
endofthelinebbs-out.news.weretis.net 8
usenet.blueworldhosting.com 8
news.netfront.net 4
newsfeed.bofh.team 2
i2pn.org 2
news.dne3.net 1
usenet.network 1
TOTAL: 8 38
TOTAL: 2 39

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 6867 3665 2388 581 0 0 53%23:58:03
2news.netfront.net 10586 2064 4554 0 0 0 19%23:59:50
3news.furie.org.uk 3965 1243 1843 31 0 0 31%23:58:55
4photonic.trudheim.com 7815 709 4254 1671 0 662 9%23:59:53
5peering.usenet.ovh 7275 657 5920 43 0 0 9%23:59:53
6endofthelinebbs.peers.news.panix.com 6937 519 6106 2 0 2 7%23:59:53
7news.dne3.net 5689 496 4584 285 0 0 8%23:59:52
8usenet.goja.nl.eu.org 7666 378 6060 48 0 0 4%23:59:32
9news.nntp4.net 7227 357 6158 6 0 13 4%23:58:56
10newsfeed.bofh.team 7519 135 6263 0 0 0 1%23:59:52
11news.tcpreset.net 7994 118 5366 1095 0 5 1%23:59:53
12news.hispagatos.org 8123 107 6319 74 0 0 1%23:59:09
13nntp.comgw.net 6875 94 6226 54 0 0 1%23:59:53
14usenet.network 7866 93 5737 14 0 0 1%23:59:41
15news.nk.ca 8860 91 5770 633 0 11 1%23:59:53
16i2pn.org 7061 87 6283 20 0 0 1%23:59:53
17news.quux.org 6652 31 5666 532 0 0 0%23:59:38
18news.weretis.net 6925 24 6217 401 0 0 0%23:59:53
19newsfeed.xs3.de 8299 22 6337 233 0 0 0%23:59:53
20news.tnetconsulting.net 9263 19 6186 421 0 1 0%23:59:53
21usenet.blueworldhosting.com 6362 18 5717 0 0 0 0%23:59:19
22news.chmurka.net 3817 17 2695 0 0 0 0%23:59:42
23news.samoylyk.net 9554 16 8056 591 0 3983 0%23:59:53
24news.corradoroberto.it 1079 11 869 0 0 0 1%22:24:48
25ddt.demos.su 44 5 38 0 0 0 11%05:49:46
26news.bbs.nz 0 0 0 0 0 6628 0%23:59:53
TOTAL: 26 170320 10976 125612 6735 0 11305 6%604:05:39
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nyheter.lysator.liu.se13.9 MB5.7 MB19.6 MB0.2 KB/s4.7 KB23:58:03
2news.netfront.net13.8 MB0.0 KB13.8 MB0.2 KB/s6.8 KB23:59:50
3news.furie.org.uk9.5 MB136.5 KB9.6 MB0.1 KB/s7.8 KB23:58:55
4peering.usenet.ovh5.4 MB156.3 KB5.5 MB0.1 KB/s8.1 KB23:59:53
5endofthelinebbs.peers.news.panix.com4.2 MB2.3 KB4.2 MB0.0 KB/s8.2 KB23:59:53
6photonic.trudheim.com3.3 MB4.8 MB8.1 MB0.1 KB/s3.5 KB23:59:53
7news.dne3.net1.9 MB3.4 MB5.3 MB0.1 KB/s7.0 KB23:59:52
8news.quux.org1.9 MB3.1 MB5.0 MB0.1 KB/s9.1 KB23:59:38
9news.nntp4.net1.1 MB20.6 KB1.1 MB0.0 KB/s3.2 KB23:58:56
10newsfeed.bofh.team1.1 MB0.0 KB1.1 MB0.0 KB/s8.0 KB23:59:52
11news.nk.ca1.0 MB3.9 MB4.9 MB0.1 KB/s6.9 KB23:59:53
12ddt.demos.su841.7 KB0.0 KB841.7 KB0.0 KB/s168.3 KB05:49:46
13usenet.goja.nl.eu.org697.3 KB539.7 KB1.2 MB0.0 KB/s2.9 KB23:59:32
14nntp.comgw.net675.3 KB1.3 MB1.9 MB0.0 KB/s13.4 KB23:59:53
15newsfeed.xs3.de578.9 KB2.0 MB2.6 MB0.0 KB/s10.5 KB23:59:53
16usenet.network185.0 KB36.4 KB221.4 KB0.0 KB/s2.1 KB23:59:41
17news.tcpreset.net182.0 KB6.3 MB6.5 MB0.1 KB/s5.4 KB23:59:53
18news.hispagatos.org176.3 KB1.0 MB1.2 MB0.0 KB/s6.7 KB23:59:09
19i2pn.org171.5 KB79.8 KB251.3 KB0.0 KB/s2.3 KB23:59:53
20news.chmurka.net62.4 KB0.0 KB62.4 KB0.0 KB/s3.7 KB23:59:42
21news.weretis.net61.7 KB4.8 MB4.9 MB0.1 KB/s11.7 KB23:59:53
22news.samoylyk.net40.5 KB5.4 MB5.4 MB0.1 KB/s9.1 KB23:59:53
23news.tnetconsulting.net39.9 KB3.7 MB3.7 MB0.0 KB/s8.6 KB23:59:53
24usenet.blueworldhosting.com31.8 KB0.0 KB31.8 KB0.0 KB/s1.8 KB23:59:19
25news.corradoroberto.it15.8 KB0.0 KB15.8 KB0.0 KB/s1.4 KB22:24:48
26news.bbs.nz0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
TOTAL: 2660.7 MB46.3 MB107.0 MB0.1 KB/s6.2 KB604:05:39
Outgoing feeds (innfeed) by volume

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved2788 00.0 KB 2 0 000:21:21
2*.nntp4.net2777 00.0 KB 0 0 000:22:51
3*localhost 1 00.0 KB 0 0 000:11:06
TOTAL: 35566 00.0 KB 2 0 000:55:19

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.438 29.608 0.00000:22:51
2a0e:73c2:8000:802c::2 10.534 29.682 0.00000:20:56
localhost 184.816 372.866 0.07600:11:06
206.168.34.221 0.008 0.019 0.00000:00:07
199.45.154.137 0.020 0.034 0.00000:00:06
162.142.125.212 0.017 0.043 0.00000:00:05
205.210.31.155 0.004 0.009 0.00000:00:02
205.210.31.70 0.003 0.011 0.00000:00:02
194.164.107.6 0.000 0.013 0.00000:00:00
91.238.181.91 0.009 0.004 0.00000:00:00
13.89.120.212 0.000 0.013 0.00000:00:00
TOTAL: 11 205.849 432.302 0.07600:55:19

Curious NNRP explorers [Top 20]:

SystemConn
news.nntp4.net 2777
2a0e:73c2:8000:802c::2 2776
162.142.125.212 3
199.45.154.137 3
13.89.120.212 1
194.164.107.6 1
205.210.31.155 1
205.210.31.70 1
206.168.34.221 1
91.238.181.91 1
localhost 1
TOTAL: 11 5566

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

SystemConn
13.89.120.212 1
162.142.125.212 1
199.45.154.137 1
206.168.34.221 1
91.238.181.91 1
TOTAL: 5 5

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

CommandCount
EHLO www.censys.io 3
#003 1
MGLNDD_144.172.126.95_119 1
TOTAL: 3 5

NNRP client timeouts [Top 20]:

SystemConnPeer
162.142.125.212 1 2
199.45.154.137 1 2
91.238.181.91 1 1
TOTAL: 3 3 7

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

HierarchyCountPct
1news 4100.0%
TOTAL: 1 4100.0%

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

NewsgroupCount
1news.admin.peering 4
TOTAL: 1 4