Daily Usenet Report

Mar 12 00:01:02 -- Mar 13 00:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Mar 13 00:00:02 newsfeed nnrpd[60914]: localhost auth: program exited with status 8

Log entries by program:

Program nameLines%LinesSize%Size
nnrpd 14134 36.4%1.7 MB 22.0%
inn 9774 25.2%3.8 MB 48.2%
innd 9751 25.1%1.4 MB 17.6%
innfeed 4514 11.6%873.0 KB 10.9%
cnfsstat 576 1.5%92.7 KB 1.2%
nocem 88 0.2%10.2 KB 0.1%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 38838 100.0%7.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 136386 64.0%
Negative hits 66979 31.4%
Do not exist 9632 4.5%
Cache misses 39 0.0%
TOTAL: 4 213036 100.0%

innd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.156 0.0%26 0.000 6.000 11.000
article cleanup00:00:00.135 0.0%9733 0.000 0.014 0.349
article logging00:00:00.567 0.0%9784 0.000 0.058 0.687
article parse00:00:00.293 0.0%18662 0.000 0.016 0.209
article write00:00:02.290 0.0%7275 0.000 0.315 3.821
artlog/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:01.299 0.0%207504 0.000 0.006 0.185
hisgrep/artcncl00:00:00.129 0.0%28 0.000 4.607 9.400
hishave/artcncl00:00:00.000 0.0%2 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:47.276 0.2%214840 0.086 0.779 7.843
history sync00:00:00.076 0.0%405 0.000 0.188 1.333
history write00:02:16.824 0.2%9715 3.140 14.084 133.357
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:43:16.462 98.5%205078 96.397 416.410 1710.450
nntp read00:00:03.899 0.0%208833 0.002 0.019 0.781
overview write00:13:56.087 1.0%7275 0.750 114.926 11959.640
perl filter00:00:49.952 0.1%9714 1.619 5.142 97.988
python filter00:00:01.893 0.0%9714 0.000 0.195 5.048
site send00:00:01.153 0.0%14631 0.015 0.079 0.400
TOTAL: 2324:03:18.491 99.9%----

innfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.023 0.0%8499 0.000 0.003 0.111
article prepare00:00:00.010 0.0%56512 0.000 0.000 0.013
article read00:00:00.716 0.0%4730 0.000 0.151 1.828
backlog stats00:00:00.464 0.0%287483 0.000 0.002 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.509 0.0%195065 0.000 0.013 0.060
data write00:00:03.714 0.0%191179 0.002 0.019 0.054
idle23:57:39.325100.0%287483 98.736 300.050 1307.719
status file00:00:00.507 0.0%366 1.000 1.385 13.500
TOTAL: 923:57:47.268100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:31:07.555 99.7%8379 29.000 222.885179907.250
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.771 0.3%95608 0.000 0.050 0.500
TOTAL: 300:31:12.326100.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 3 14194 1998 11984 212 14%47:15:03
2glockenspiel.complete.org 2 7110 973 6081 56 13%29:33:50
3usenet.blueworldhosting.com 716 9200 630 7934 636 6%23:57:01
4usenet.goja.nl.eu.org 12 8756 624 7858 274 7%24:30:01
5usenet.network 1 6136 515 5600 21 8%23:59:57
6news.corradoroberto.it 5 1411 467 944 0 33%24:00:57
7nntp.comgw.net 2 8763 397 8099 267 4%23:57:30
8news.nntp4.net 1 7656 386 7134 136 5%23:59:55
9newsfeed.bofh.team 2 9441 336 8945 160 3%23:59:15
10i2pn.org 1 8193 258 7823 112 3%23:59:54
11endoftheline-out.news.chmurka.net 2 3427 258 3167 2 7%23:59:35
12194.87.241.33 40 770 181 380 209 23%14:38:31
13endofthelinebbs.peers.news.panix.com 27 7689 116 7566 7 1%24:12:39
14news.samoylyk.net 2 7236 113 7111 12 1%23:58:56
15newsfeed.xs3.de 1 6501 62 6418 21 0%23:59:55
16peering.usenet.ovh 2 8261 50 8190 21 0%23:59:48
17news.hispagatos.org 6 8957 49 8853 55 0%24:26:06
18news.netfront.net 1 6612 12 6336 264 0%22:17:53
19news.bbs.nz 1 6757 8 6748 1 0%23:59:37
20news.furie.org.uk 1 3863 7 3853 3 0%23:59:58
21outgoing.news.lysator.liu.se 2 5048 5 5041 2 0%23:59:15
22photonic.trudheim.com 3 1928 4 1923 1 0%24:54:57
23localhost 26 0 0 0 0 0%00:00:00
24news.nk.ca 2 0 0 0 0 0%00:20:18
TOTAL: 24 861 147909 7449 137988 2472 5%548:00:51
Articles received by server

Incoming volume (innd):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net6.1 MB5.0 KB397.2 KB6.5 MB 93%3.0 KB
2usenet.network5.8 MB2.5 KB28.1 KB5.8 MB 99%11.1 KB
3news.corradoroberto.it4.7 MB0.0 KB0.0 KB4.7 MB100%10.2 KB
4glockenspiel.complete.org3.9 MB2.5 KB144.7 KB4.0 MB 96%4.0 KB
5nntp.comgw.net2.1 MB2.5 KB1.5 MB3.6 MB 58%5.5 KB
6usenet.goja.nl.eu.org2.0 MB2.5 KB546.1 KB2.5 MB 78%2.9 KB
7usenet.blueworldhosting.com1.7 MB0.0 KB1.2 MB2.9 MB 57%2.4 KB
8news.nntp4.net1.1 MB2.5 KB270.7 KB1.3 MB 79%2.6 KB
9endoftheline-out.news.chmurka.net1.1 MB0.0 KB1.6 KB1.1 MB 99%4.2 KB
10newsfeed.bofh.team955.5 KB2.5 KB666.5 KB1.6 MB 58%3.3 KB
11i2pn.org751.7 KB2.5 KB157.7 KB911.9 KB 82%2.5 KB
12news.samoylyk.net609.5 KB2.5 KB25.1 KB637.0 KB 95%5.1 KB
13194.87.241.33555.1 KB0.0 KB525.7 KB1.1 MB 51%2.8 KB
14newsfeed.xs3.de336.3 KB2.5 KB49.8 KB388.6 KB 86%4.7 KB
15endofthelinebbs.peers.news.panix.com328.7 KB2.5 KB9.5 KB340.7 KB 96%2.8 KB
16news.hispagatos.org216.8 KB4.3 KB110.1 KB331.2 KB 65%3.2 KB
17peering.usenet.ovh139.6 KB2.5 KB32.8 KB174.9 KB 79%2.5 KB
18news.netfront.net73.1 KB0.0 KB5.3 MB5.4 MB 1%20.0 KB
19news.furie.org.uk33.4 KB2.5 KB2.9 KB38.9 KB 86%3.9 KB
20outgoing.news.lysator.liu.se20.5 KB2.5 KB3.1 KB26.1 KB 78%3.7 KB
21news.bbs.nz14.6 KB2.5 KB0.0 KB17.1 KB 85%1.9 KB
22photonic.trudheim.com10.0 KB2.5 KB0.0 KB12.5 KB 79%2.5 KB
23localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
24news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2432.3 MB46.5 KB10.9 MB43.3 MB 74%4.5 KB
Incoming volume received by server

Incoming articles (innd):

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 12 00:01:02 - 00:59:59 122 1.7% 0.03408.5 KB 1.3% 0.12
Mar 12 01:00:00 - 01:59:59 193 2.7% 0.051.1 MB 3.4% 0.31
Mar 12 02:00:00 - 02:59:59 225 3.1% 0.06910.8 KB 2.8% 0.25
Mar 12 03:00:00 - 03:59:59 269 3.7% 0.07969.8 KB 3.0% 0.27
Mar 12 04:00:00 - 04:59:59 353 4.9% 0.101.4 MB 4.4% 0.40
Mar 12 05:00:00 - 05:59:59 324 4.5% 0.091.2 MB 3.9% 0.35
Mar 12 06:00:00 - 06:59:59 379 5.2% 0.111.2 MB 3.9% 0.35
Mar 12 07:00:00 - 07:59:59 388 5.3% 0.111.4 MB 4.3% 0.39
Mar 12 08:00:00 - 08:59:59 480 6.6% 0.132.6 MB 8.3% 0.74
Mar 12 09:00:00 - 09:59:59 378 5.2% 0.101.3 MB 4.3% 0.38
Mar 12 10:00:00 - 10:59:59 438 6.0% 0.121.8 MB 5.8% 0.52
Mar 12 11:00:00 - 11:59:59 543 7.5% 0.153.5 MB 11.1% 1.00
Mar 12 12:00:00 - 12:59:59 440 6.1% 0.121.6 MB 5.0% 0.45
Mar 12 13:00:00 - 13:59:59 396 5.5% 0.111.3 MB 4.0% 0.36
Mar 12 14:00:00 - 14:59:59 377 5.2% 0.101.3 MB 4.2% 0.38
Mar 12 15:00:00 - 15:59:59 417 5.7% 0.121.7 MB 5.5% 0.49
Mar 12 16:00:00 - 16:59:59 333 4.6% 0.092.0 MB 6.2% 0.55
Mar 12 17:00:00 - 17:59:59 322 4.4% 0.091.4 MB 4.3% 0.39
Mar 12 18:00:00 - 18:59:59 235 3.2% 0.071.6 MB 5.1% 0.46
Mar 12 19:00:00 - 19:59:59 164 2.3% 0.05670.8 KB 2.1% 0.19
Mar 12 20:00:00 - 20:59:59 139 1.9% 0.04875.9 KB 2.7% 0.24
Mar 12 21:00:00 - 21:59:59 122 1.7% 0.03541.7 KB 1.7% 0.15
Mar 12 22:00:00 - 22:59:59 131 1.8% 0.04547.6 KB 1.7% 0.15
Mar 12 23:00:00 - 23:59:59 90 1.2% 0.03327.7 KB 1.0% 0.09
Mar 13 00:00:00 - 00:01:02 7 0.1% 0.1121.3 KB 0.1% 0.34
TOTAL: 24:00:00 7265 100.0% 0.0831.6 MB 100.0% 0.37
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 GB105.0 GB 6.8% 0 0.5534114.04
CYCNGS3NGS1536.0 GB48.0 MB 0.0% 0 0.00 0.00
CYCTXT1TEXT5.0 GB4.2 GB 83.0% 0 0.03 2370.51
TOTAL: 4-4613.0 GB109.2 GB 2.4% 1 0.40-

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1usenet.blueworldhosting.com 640 0 0 0 1 0 0 0 639
2nntp.comgw.net 293 0 0 1 0 0 0 0 292
3usenet.goja.nl.eu.org 265 0 0 1 0 0 0 0 264
4news.netfront.net 257 0 0 0 0 0 0 0 257
5endofthelinebbs-out.news.weretis.net 227 0 0 2 0 0 0 0 225
6194.87.241.33 209 0 0 0 0 0 0 0 209
7newsfeed.bofh.team 160 0 0 1 0 0 0 0 159
8news.nntp4.net 140 1 0 1 0 0 0 0 138
9i2pn.org 114 0 0 1 0 0 0 0 113
10news.hispagatos.org 56 21 0 2 0 0 0 0 33
11glockenspiel.complete.org 51 0 0 1 0 0 0 0 50
12usenet.network 25 0 0 1 0 0 0 0 24
13peering.usenet.ovh 23 0 0 1 0 0 0 0 22
14newsfeed.xs3.de 21 0 0 1 0 0 0 0 20
15news.samoylyk.net 12 0 0 1 0 0 0 0 11
16endofthelinebbs.peers.news.panix.com 5 1 0 1 0 0 0 0 3
17news.furie.org.uk 3 0 0 1 0 0 0 0 2
18endoftheline-out.news.chmurka.net 2 0 0 0 0 0 0 0 2
19outgoing.news.lysator.liu.se 2 0 0 1 0 0 0 0 1
20news.bbs.nz 1 0 0 1 0 0 0 0 0
21photonic.trudheim.com 1 0 0 1 0 0 0 0 0
TOTAL: 21 2507 23 0 19 1 0 0 0 2464

Unwanted newsgroups [Top 20]:

NewsgroupCount
hispagatos.mastodon.tag.hacking 20
atl.binaries.android 1
hispagatos.mastodon.tag.hackers 1
kiel.sex 1
TOTAL: 4 23

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

GroupsCount
talk.origins 1
TOTAL: 1 1

Perl filter (innd) [Top 20]:

ReasonCount
EMP (phn path) 1247
EMP (phr path) 361
Binary: misplaced binary 300
Too many newsgroups 229
EMP (md5) 162
EMP (phl) 50
Angle-bracket bot 44
User-issued cancel 11
HTML Multipart 4
EMP (phn nph) 3
Too many newsgroups (meow) 1
TOTAL: 11 2412

NoCeM on spool:

Issuer (type)NoticesBad PGPCancelSkipTotal
i2pn2-nocem@i2pn2.org (multipost)37003737
robot@pasdenom.info (spam2)31003434
robot@pasdenom.info (spam3)9003434
robot@pasdenom.info (spam4)8002727
i2pn2-nocem@i2pn2.org (rmp)20077
TOTAL: 58700139139

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
nntp.comgw.net 25
endofthelinebbs-out.news.weretis.net 6
usenet.goja.nl.eu.org 4
usenet.blueworldhosting.com 4
usenet.network 3
news.nntp4.net 2
news.netfront.net 2
i2pn.org 2
peering.usenet.ovh 2
newsfeed.bofh.team 1
TOTAL: 10 51
No colon-space in header field
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 2 52

Outgoing feeds (innfeed) by articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nyheter.lysator.liu.se 7858 2846 3915 493 0 26 36%23:59:52
2news.netfront.net 11492 2314 4932 4 0 76 20%23:59:53
3news.furie.org.uk 4521 1044 2529 26 0 37 23%23:59:54
4endofthelinebbs.peers.news.panix.com 7982 580 6670 6 0 31 7%23:59:54
5peering.usenet.ovh 8025 567 6618 47 0 43 7%23:59:54
6photonic.trudheim.com 8759 558 5597 1102 0 5 6%23:59:42
7usenet.goja.nl.eu.org 7542 318 6130 36 0 37 4%23:59:54
8news.nntp4.net 7820 285 6544 3 0 38 3%23:59:35
9news.corradoroberto.it 1214 261 604 0 0 3 21%23:05:01
10news.bbs.nz 13290 220 6501 523 0 201 1%23:59:54
11usenet.network 8901 210 6197 33 0 26 2%23:59:54
12newsfeed.bofh.team 7752 170 6748 0 0 57 2%23:59:54
13news.hispagatos.org 7853 123 7029 56 0 32 1%23:59:39
14nntp.comgw.net 7433 122 6700 38 0 45 1%23:59:53
15news.quux.org 5826 116 4810 416 0 9 1%23:59:54
16news.weretis.net 7828 114 6889 255 0 24 1%23:59:54
17news.nk.ca 9833 106 6604 449 0 161 1%23:59:54
18i2pn.org 8266 89 6851 32 0 144 1%23:59:52
19newsfeed.xs3.de 8752 73 6946 160 0 60 0%23:59:54
20news.samoylyk.net 8043 54 6979 56 0 105 0%23:59:54
21news.tnetconsulting.net 9562 50 6832 373 0 88 0%23:59:53
22news.chmurka.net 3919 16 2915 1 0 0 0%23:59:54
23ddt.demos.su 51 2 49 0 0 0 3%06:28:36
24usenet.blueworldhosting.com 7248 1 6454 0 0 0 0%23:59:52
25feed.opticnetworks.net 0 0 0 0 0 7259 0%23:59:54
TOTAL: 25 179770 10239 132043 4109 0 8507 5%581:30:24
Outgoing feeds (innfeed) by articles

Outgoing feeds (innfeed) by volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net13.3 MB92.2 KB13.3 MB0.2 KB/s5.9 KB23:59:53
2nyheter.lysator.liu.se10.9 MB5.5 MB16.4 MB0.2 KB/s5.0 KB23:59:52
3news.furie.org.uk7.8 MB195.8 KB8.0 MB0.1 KB/s7.6 KB23:59:54
4peering.usenet.ovh5.6 MB283.1 KB5.9 MB0.1 KB/s9.9 KB23:59:54
5endofthelinebbs.peers.news.panix.com4.6 MB32.1 KB4.6 MB0.1 KB/s8.1 KB23:59:54
6photonic.trudheim.com4.0 MB3.6 MB7.6 MB0.1 KB/s4.7 KB23:59:42
7usenet.network1.8 MB80.9 KB1.8 MB0.0 KB/s7.8 KB23:59:54
8newsfeed.bofh.team1.5 MB0.0 KB1.5 MB0.0 KB/s9.1 KB23:59:54
9news.bbs.nz1.3 MB3.4 MB4.7 MB0.1 KB/s6.5 KB23:59:54
10news.nntp4.net1.0 MB15.9 KB1.0 MB0.0 KB/s3.7 KB23:59:35
11usenet.goja.nl.eu.org1.0 MB689.2 KB1.7 MB0.0 KB/s4.9 KB23:59:54
12news.quux.org981.6 KB2.8 MB3.7 MB0.0 KB/s7.2 KB23:59:54
13news.hispagatos.org890.1 KB678.8 KB1.5 MB0.0 KB/s8.8 KB23:59:39
14news.weretis.net802.4 KB2.8 MB3.6 MB0.0 KB/s10.1 KB23:59:54
15news.corradoroberto.it714.6 KB0.0 KB714.6 KB0.0 KB/s2.7 KB23:05:01
16nntp.comgw.net681.5 KB743.7 KB1.4 MB0.0 KB/s8.9 KB23:59:53
17newsfeed.xs3.de638.4 KB1.3 MB2.0 MB0.0 KB/s8.6 KB23:59:54
18news.tnetconsulting.net379.7 KB3.5 MB3.9 MB0.0 KB/s9.5 KB23:59:53
19news.samoylyk.net349.1 KB746.2 KB1.1 MB0.0 KB/s10.0 KB23:59:54
20i2pn.org338.1 KB323.8 KB661.8 KB0.0 KB/s5.5 KB23:59:52
21news.nk.ca260.7 KB2.9 MB3.2 MB0.0 KB/s5.9 KB23:59:54
22ddt.demos.su95.6 KB0.0 KB95.6 KB0.0 KB/s47.8 KB06:28:36
23news.chmurka.net51.0 KB3.7 KB54.7 KB0.0 KB/s3.2 KB23:59:54
24usenet.blueworldhosting.com3.5 KB0.0 KB3.5 KB0.0 KB/s3.5 KB23:59:52
25feed.opticnetworks.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
TOTAL: 2558.8 MB29.7 MB88.6 MB0.0 KB/s6.3 KB581:30:24
Outgoing feeds (innfeed) by volume

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

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved2800 00.0 KB 1 0 000:18:44
2*.stretchoid.com 2 00.0 KB 0 0 000:00:10
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
4*.netvigator.com 1 00.0 KB 0 0 000:00:10
5*localhost 1 00.0 KB 0 0 000:12:04
TOTAL: 52805 00.0 KB 1 0 000:31:11

NNRP auth users [Top 20]:

UserConn
getlist 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2001:41d0:a:280::1 10.377 34.458 0.00000:16:37
localhost 183.076 372.790 0.08900:12:04
118.193.59.10 0.024 0.110 0.00000:01:46
n220246082169.netvigator.com 0.000 0.015 0.00000:00:10
205.210.31.102 0.004 0.011 0.00000:00:10
205.210.31.234 0.006 0.009 0.00000:00:10
azpdcs25jnoy.stretchoid.com 0.000 0.015 0.00000:00:09
invigorating.monitoring.internet-measurement.com 0.000 0.015 0.00000:00:01
196.251.112.87 0.000 0.030 0.00000:00:00
91.238.181.93 0.000 0.015 0.00000:00:00
194.0.234.59 0.000 0.015 0.00000:00:00
azpdsgc12.stretchoid.com 0.000 0.015 0.00000:00:00
TOTAL: 12 193.487 407.498 0.08900:31:11

Curious NNRP explorers [Top 20]:

SystemConn
2001:41d0:a:280::1 2785
118.193.59.10 9
196.251.112.87 2
194.0.234.59 1
205.210.31.102 1
205.210.31.234 1
91.238.181.93 1
azpdcs25jnoy.stretchoid.com 1
azpdsgc12.stretchoid.com 1
invigorating.monitoring.internet-measurement.com 1
localhost 1
n220246082169.netvigator.com 1
TOTAL: 12 2805

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

SystemConn
118.193.59.10 10
194.0.234.59 1
91.238.181.93 1
azpdsgc12.stretchoid.com 1
TOTAL: 4 13

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

CommandCount
#003 3
#005#004 1
#026#003#001#001#027#001 1
?##016?Y%? ?#014 3?P?I?#017?z}???v?... 1
?#024 1
Accept-Language: zh-CN,zh;q=0.9,en;q=0.8... 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 (Macintosh; Intel... 1
github.com 1
TOTAL: 11 13

NNRP client timeouts [Top 20]:

SystemConnPeer
194.0.234.59 1 1
91.238.181.93 1 1
TOTAL: 2 2 4

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

HierarchyCountPct
1news 2100.0%
TOTAL: 1 2100.0%

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

NewsgroupCount
1news.software.nntp 2
TOTAL: 1 2