Daily Usenet report

Jun 25 00:01:01 -- Jun 26 00:01:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 25 00:27:11 www innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program nameLines%LinesSize%Size
inn 21060 52.2%5.7 MB 68.2%
innd 14315 35.5%1.8 MB 21.6%
innfeed 3682 9.1%735.9 KB 8.6%
nnrpd 1280 3.2%137.0 KB 1.6%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 5 40338 100.0%8.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 154516 53.9%
Negative hits 111370 38.8%
Do not exist 19188 6.7%
Cache misses 1645 0.6%
TOTAL: 4 286719 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.158 0.0%382 0.182 0.414 6.000
article cleanup00:00:00.387 0.0%20801 0.000 0.019 0.125
article logging00:00:01.055 0.0%21058 0.000 0.050 0.181
article parse00:00:05.875 0.0%284770 0.000 0.021 0.060
article write00:00:16.333 0.0%11593 0.785 1.409 6.292
artlog/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:00:01.729 0.0%549208 0.000 0.003 0.006
hisgrep/artcncl00:00:00.084 0.0%385 0.000 0.218 1.250
hishave/artcncl00:00:00.000 0.0%3 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:00:10.843 0.0%288021 0.010 0.038 0.158
history sync00:00:00.106 0.0%386 0.000 0.275 3.000
history write00:00:11.421 0.0%19223 0.310 0.594 2.286
hiswrite/artparse00:00:00.002 0.0%1 2.000 2.000 2.000
idle23:56:18.947 99.7%511110 17.976 168.611 838.066
nntp read00:00:44.959 0.1%521321 0.016 0.086 0.170
overview write00:00:45.290 0.1%11593 0.417 3.907 553.562
perl filter00:00:31.712 0.0%19222 1.033 1.650 5.274
python filter00:00:02.239 0.0%19222 0.030 0.116 0.597
site send00:00:05.135 0.0%23186 0.020 0.221 0.688
TOTAL: 24:01:02.51323:59:16.275 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.124 0.0%11860 0.000 0.010 0.200
article prepare00:00:00.010 0.0%89116 0.000 0.000 0.004
article read00:00:03.220 0.0%11498 0.048 0.280 1.491
backlog stats00:00:00.659 0.0%336465 0.000 0.002 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:06.446 0.0%250340 0.014 0.026 0.042
data write00:00:31.938 0.0%242530 0.056 0.132 0.302
idle23:55:23.120 99.9%336465 87.955 255.965 659.159
status file00:00:00.250 0.0%288 0.000 0.868 6.000
TOTAL: 23:56:19.99223:56:05.767100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:03:41.446 85.8%869 52.000 254.829 9786.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.309 0.1%1744 0.000 0.177 2.667
TOTAL: 00:04:18.06000:03:41.755 85.9%----

Control commands to INND:

CommandNumber
begin 2
drop 1
feedinfo 2
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 145
name 3
pause 2
paused 2
reload 2
reserve 2
TOTAL: 14 172

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 3 22361 4312 17777 272 19%53:22:41
2usenet.blueworldhosting.com 720 12622 2155 9762 705 17%23:46:05
3outgoing.news.lysator.liu.se 4 14090 1282 9875 2933 9%24:44:48
4usenet.network 2 11270 1051 9834 385 9%25:41:14
5usenet.goja.nl.eu.org 10 11515 870 10550 95 7%23:06:48
6paganini.bofh.team 1 11918 508 11283 127 4%23:58:57
7endofthelinebbs.peers.news.panix.com 23 10716 337 10361 18 3%23:56:27
8newsfeed.xs3.de 1 8961 188 8770 3 2%23:59:52
9i2pn.org 1 10489 185 10298 6 1%23:59:53
10news.samoylyk.net 1 9956 159 9795 2 1%23:59:47
11endoftheline-out.news.chmurka.net 2 3978 148 3829 1 3%23:59:37
12glockenspiel.complete.org 2 3828 122 3706 0 3%13:46:51
13nntp.alphanet.ch 25 8396 114 8271 11 1%24:01:37
14194.87.241.33 30 121 50 71 0 41%08:02:47
15news.nntp4.net 2 9408 43 9361 4 0%23:59:54
16news.furie.org.uk 2 5002 5 4996 1 0%23:59:17
17news.bbs.nz 4 8437 1 8366 70 0%14:49:54
18news.netfront.net 48 4904 0 430 4474 0%16:26:53
19news.nk.ca 2 0 0 0 0 0%00:20:02
20localhost 25 0 0 0 0 0%00:00:00
21eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:16
TOTAL: 21 909 167972 11530 147335 9107 6%420:13:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1usenet.blueworldhosting.com19.3 MB2.8 KB3.3 MB22.6 MB 85%8.1 KB
2endofthelinebbs-out.news.weretis.net18.0 MB172.2 KB426.9 KB18.5 MB 96%4.1 KB
3usenet.network5.8 MB86.1 KB2.1 MB8.0 MB 73%5.7 KB
4outgoing.news.lysator.liu.se3.3 MB116.8 MB953.4 MB1.0 GB 0%260.8 KB
5usenet.goja.nl.eu.org2.8 MB89.2 KB156.7 KB3.0 MB 91%3.2 KB
6nntp.alphanet.ch1.6 MB8.8 KB13.5 KB1.7 MB 98%13.5 KB
7paganini.bofh.team1.2 MB82.9 KB756.1 KB2.0 MB 59%3.2 KB
8endofthelinebbs.peers.news.panix.com1.2 MB91.9 KB38.1 KB1.3 MB 90%3.7 KB
9i2pn.org813.8 KB86.0 KB11.4 KB911.2 KB 89%4.8 KB
10news.samoylyk.net694.6 KB86.1 KB0.0 KB780.7 KB 88%4.8 KB
11newsfeed.xs3.de576.3 KB86.1 KB0.0 KB662.5 KB 86%3.5 KB
12glockenspiel.complete.org545.8 KB0.0 KB0.0 KB545.8 KB100%4.5 KB
13endoftheline-out.news.chmurka.net501.4 KB4.5 KB0.0 KB505.8 KB 99%3.4 KB
14194.87.241.33102.5 KB0.0 KB0.0 KB102.5 KB100%2.1 KB
15news.nntp4.net101.2 KB87.4 KB0.7 KB189.4 KB 53%4.0 KB
16news.furie.org.uk11.9 KB4.5 KB0.0 KB16.4 KB 72%2.7 KB
17news.bbs.nz2.2 KB81.6 KB51.7 KB135.5 KB 1%1.9 KB
18news.netfront.net0.0 KB465.8 MB1.1 GB1.6 GB 0%373.3 KB
19news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
20localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
21eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2156.4 MB583.6 MB2.1 GB2.7 GB 2%137.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 25 00:01:01 - 00:59:59 317 2.7% 0.091.1 MB 1.9% 0.31
Jun 25 01:00:00 - 01:59:59 336 2.9% 0.092.6 MB 4.6% 0.75
Jun 25 02:00:00 - 02:59:59 329 2.8% 0.091.1 MB 2.0% 0.32
Jun 25 03:00:00 - 03:59:59 458 4.0% 0.131.4 MB 2.4% 0.39
Jun 25 04:00:00 - 04:59:59 485 4.2% 0.131.7 MB 3.0% 0.49
Jun 25 05:00:00 - 05:59:59 519 4.5% 0.141.7 MB 2.9% 0.47
Jun 25 06:00:00 - 06:59:59 557 4.8% 0.152.4 MB 4.3% 0.69
Jun 25 07:00:00 - 07:59:59 526 4.5% 0.152.7 MB 4.7% 0.76
Jun 25 08:00:00 - 08:59:59 558 4.8% 0.152.0 MB 3.5% 0.57
Jun 25 09:00:00 - 09:59:59 587 5.1% 0.162.4 MB 4.2% 0.68
Jun 25 10:00:00 - 10:59:59 531 4.6% 0.152.1 MB 3.7% 0.60
Jun 25 11:00:00 - 11:59:59 660 5.7% 0.182.4 MB 4.3% 0.69
Jun 25 12:00:00 - 12:59:59 630 5.4% 0.174.0 MB 7.0% 1.13
Jun 25 13:00:00 - 13:59:59 981 8.5% 0.273.8 MB 6.6% 1.07
Jun 25 14:00:00 - 14:59:59 498 4.3% 0.141.8 MB 3.2% 0.52
Jun 25 15:00:00 - 15:59:59 544 4.7% 0.151.9 MB 3.4% 0.54
Jun 25 16:00:00 - 16:59:59 443 3.8% 0.123.4 MB 5.9% 0.96
Jun 25 17:00:00 - 17:59:59 438 3.8% 0.122.3 MB 4.0% 0.64
Jun 25 18:00:00 - 18:59:59 323 2.8% 0.091.6 MB 2.9% 0.46
Jun 25 19:00:00 - 19:59:59 320 2.8% 0.092.1 MB 3.7% 0.60
Jun 25 20:00:00 - 20:59:59 523 4.5% 0.154.4 MB 7.7% 1.24
Jun 25 21:00:00 - 21:59:59 417 3.6% 0.125.0 MB 8.7% 1.41
Jun 25 22:00:00 - 22:59:59 234 2.0% 0.071.2 MB 2.1% 0.34
Jun 25 23:00:00 - 23:59:59 367 3.2% 0.101.8 MB 3.3% 0.53
Jun 26 00:00:00 - 00:01:02 9 0.1% 0.1524.8 KB 0.0% 0.40
TOTAL: 24:00:01 11590 100.0% 0.1356.8 MB 100.0% 0.67
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 4474 0 0 1231 0 0 0 0 3243
2outgoing.news.lysator.liu.se 2947 0 0 311 0 0 0 0 2636
3usenet.blueworldhosting.com 708 7 0 1 0 0 0 0 700
4endofthelinebbs-out.news.weretis.net 466 0 0 5 0 0 0 0 461
5usenet.network 420 56 0 2 0 0 0 0 362
6paganini.bofh.team 165 0 0 2 0 0 0 0 163
7usenet.goja.nl.eu.org 127 0 0 3 0 0 0 0 124
8news.bbs.nz 70 0 0 1 0 0 0 0 69
9glockenspiel.complete.org 21 0 0 3 0 0 0 0 18
10endofthelinebbs.peers.news.panix.com 19 7 0 4 0 0 0 0 8
11nntp.alphanet.ch 11 0 0 3 0 0 0 0 8
12news.samoylyk.net 11 0 0 2 0 0 0 0 9
13newsfeed.xs3.de 7 0 0 3 0 0 0 0 4
14i2pn.org 7 0 0 3 0 0 0 0 4
15news.nntp4.net 7 0 0 3 0 0 0 0 4
16endoftheline-out.news.chmurka.net 6 0 0 1 0 0 0 0 5
17news.furie.org.uk 1 0 0 1 0 0 0 0 0
TOTAL: 17 9467 70 0 1579 0 0 0 0 7818

Unwanted newsgroups [Top 20]:

NewsgroupCount
perl.cpan 29
odessa.news 18
alfanet.nocem 10
kraft.binary 4
alt.energy 4
alt.penthouse.sex.first-time 1
alt.rss.hackernews 1
alt.text.rss.hackernews 1
alt.comp.lang 1
alt.maryland 1
TOTAL: 10 70

INND Perl filter [Top 20]:

ReasonCount
Too many newsgroups 4585
Binary: misplaced binary 1284
EMP (md5) 793
EMP (phr path) 374
EMP (phn path) 361
EMP (phl) 99
User-issued cancel 36
EMP (phn nph) 12
HTML Multipart 10
EMP (fsl) 5
TOTAL: 10 7559

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Huge articles
usenet.network 1
TOTAL: 1 1
Including strange strings
endofthelinebbs-out.news.weretis.net 213
usenet.goja.nl.eu.org 11
news.samoylyk.net 9
endoftheline-out.news.chmurka.net 5
outgoing.news.lysator.liu.se 4
newsfeed.xs3.de 4
news.nntp4.net 3
usenet.blueworldhosting.com 3
paganini.bofh.team 2
endofthelinebbs.peers.news.panix.com 1
TOTAL: 12 257
No colon-space in header field
usenet.blueworldhosting.com 1
TOTAL: 1 1
TOTAL: 3 259

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 11588 11393 164 32 0 42 98%23:59:55
2nyheter.lysator.liu.se 10856 2083 7358 865 0 23 19%23:59:55
3paganini.bofh.team 13152 866 10719 2 0 17 6%23:59:55
4endofthelinebbs.peers.news.panix.com 11799 564 10910 114 0 0 4%23:59:46
5usenet.blueworldhosting.com 9022 238 8209 1 0 0 2%23:59:55
6ddt.demos.su 638 216 420 0 0 0 33%21:43:02
7news.weretis.net 11757 176 11063 348 0 3 1%23:59:46
8news.nk.ca 14430 165 10450 792 0 5 1%23:59:55
9i2pn.org 11508 111 10893 224 0 1 0%23:59:55
10usenet.network 12078 108 9520 33 0 0 0%23:59:49
11news.bbs.nz 21947 103 10969 480 0 130 0%23:59:55
12news.furie.org.uk 7895 82 5518 253 0 0 1%23:59:51
13alphanet.ch 13712 72 10899 498 0 30 0%23:59:35
14news.tnetconsulting.net 12344 70 11127 389 0 0 0%23:59:56
15news.chmurka.net 5401 49 4229 56 0 0 0%23:59:51
16news.quux.org 11676 48 10707 618 0 0 0%23:59:55
17news.nntp4.net 13015 32 11081 220 0 3 0%23:59:55
18news.samoylyk.net 13570 27 11167 219 0 11 0%23:59:55
19usenet.goja.nl.eu.org 12860 14 10275 378 0 18 0%23:59:55
20newsfeed.xs3.de 13569 5 10937 408 0 9 0%23:59:55
TOTAL: 20 232817 16422 176615 5930 0 292 7%477:40:36
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net56.3 MB100.9 KB56.4 MB0.7 KB/s5.1 KB23:59:55
2nyheter.lysator.liu.se15.8 MB6.6 MB22.5 MB0.3 KB/s7.8 KB23:59:55
3paganini.bofh.team6.5 MB6.9 KB6.5 MB0.1 KB/s7.6 KB23:59:55
4endofthelinebbs.peers.news.panix.com2.4 MB306.4 KB2.7 MB0.0 KB/s4.0 KB23:59:46
5news.furie.org.uk1.4 MB1.8 MB3.2 MB0.0 KB/s9.8 KB23:59:51
6news.nk.ca641.8 KB5.8 MB6.4 MB0.1 KB/s6.9 KB23:59:55
7news.weretis.net521.9 KB1.7 MB2.2 MB0.0 KB/s4.2 KB23:59:46
8news.bbs.nz516.2 KB3.0 MB3.5 MB0.0 KB/s6.2 KB23:59:55
9usenet.blueworldhosting.com438.1 KB3.3 KB441.4 KB0.0 KB/s1.8 KB23:59:55
10ddt.demos.su385.1 KB0.0 KB385.1 KB0.0 KB/s1.8 KB21:43:02
11usenet.network357.2 KB77.4 KB434.6 KB0.0 KB/s3.1 KB23:59:49
12i2pn.org346.4 KB418.7 KB765.2 KB0.0 KB/s2.3 KB23:59:55
13news.tnetconsulting.net341.9 KB1.9 MB2.2 MB0.0 KB/s4.9 KB23:59:56
14alphanet.ch191.0 KB3.4 MB3.6 MB0.0 KB/s6.4 KB23:59:35
15news.nntp4.net189.3 KB605.1 KB794.4 KB0.0 KB/s3.2 KB23:59:55
16news.samoylyk.net169.2 KB505.3 KB674.5 KB0.0 KB/s2.7 KB23:59:55
17news.quux.org163.2 KB3.9 MB4.1 MB0.0 KB/s6.2 KB23:59:55
18news.chmurka.net127.3 KB136.4 KB263.7 KB0.0 KB/s2.5 KB23:59:51
19usenet.goja.nl.eu.org41.1 KB2.0 MB2.0 MB0.0 KB/s5.3 KB23:59:55
20newsfeed.xs3.de13.9 KB2.2 MB2.2 MB0.0 KB/s5.6 KB23:59:55
TOTAL: 2086.6 MB34.4 MB121.0 MB0.1 KB/s5.5 KB477:40:36
Outgoing feeds (innfeed) by Volume

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 289 00.0 KB 0 0 000:04:04
2*.210.203.35.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
4unresolved 1 00.0 KB 4 0 000:00:09
TOTAL: 4 292 00.0 KB 4 0 000:04:16

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2001:470:d:3a4::100 24.922 10.472 0.00000:04:04
192.241.195.6 0.105 0.035 0.00000:00:09
sunny.monitoring.internet-measurement.com 0.107 0.041 0.00000:00:01
2605:aa80:0:9::3 0.102 0.065 0.00000:00:00
165.210.203.35.bc.googleusercontent.com 0.100 0.044 0.00000:00:00
TOTAL: 5 25.336 10.657 0.00000:04:16

Curious NNRP server explorers [Top 20]:

SystemConn
2001:470:d:3a4::100 288
sunny.monitoring.internet-measurement.com 1
2605:aa80:0:9::3 1
192.241.195.6 1
165.210.203.35.bc.googleusercontent.com 1
TOTAL: 5 292

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

SystemConn
165.210.203.35.bc.googleusercontent.com 3
TOTAL: 1 3

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

CommandCount
User-Agent: Expanse, a Palo Alto Network... 1
Host: 192.138.210.162:119 1
GET / HTTP/1.1 1
TOTAL: 3 3

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1news 2 40.0%
2alt 2 40.0%
3comp 1 20.0%
TOTAL: 3 5100.0%

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

NewsgroupCount
1alt.music.lyrics 2
2news.admin.net-abuse.usenet 1
3news.admin.peering 1
4comp.sys.acorn.announce 1
TOTAL: 4 5