Daily Usenet report

Aug 27 00:01:02 -- Aug 28 00:01:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Aug 27 00:37:40 www innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program nameLines%LinesSize%Size
inn 18455 45.5%5.8 MB 67.9%
innd 10008 24.7%1.2 MB 14.3%
nnrpd 8883 21.9%940.1 KB 10.7%
innfeed 3246 8.0%633.0 KB 7.2%
rnews 5 0.0%0.4 KB 0.0%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 6 40599 100.0%8.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 159323 59.2%
Negative hits 84279 31.3%
Do not exist 18292 6.8%
Cache misses 7222 2.7%
TOTAL: 4 269116 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.138 0.0%34 0.000 4.059 10.167
article cleanup00:00:00.339 0.0%18343 0.000 0.018 0.099
article logging00:00:00.834 0.0%18393 0.000 0.045 0.231
article parse00:00:03.174 0.0%159489 0.000 0.020 0.063
article write00:00:20.209 0.0%12602 0.894 1.604 5.231
artlog/artcncl00:00:00.001 0.0%4 0.000 0.250 1.000
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:01.212 0.0%363849 0.000 0.003 0.029
hisgrep/artcncl00:00:00.024 0.0%38 0.000 0.632 2.000
hishave/artcncl00:00:00.000 0.0%4 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:00:09.055 0.0%270692 0.007 0.033 0.125
history sync00:00:00.078 0.0%306 0.000 0.255 1.000
history write00:00:10.211 0.0%18281 0.328 0.559 2.415
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle18:46:52.074 99.6%339099 18.768 199.387 771.082
nntp read00:00:29.247 0.0%348317 0.021 0.084 0.164
overview write00:00:18.050 0.0%12602 0.725 1.432 11.404
perl filter00:00:32.606 0.0%18279 1.036 1.784 5.848
python filter00:00:02.528 0.0%18281 0.050 0.138 0.730
site send00:00:04.742 0.0%25204 0.053 0.188 0.810
TOTAL: 18:50:52.93718:49:04.522 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.200 0.0%19950 0.000 0.010 0.481
article prepare00:00:00.013 0.0%88752 0.000 0.000 0.015
article read00:00:03.239 0.0%11552 0.133 0.280 1.850
backlog stats00:00:00.817 0.0%360647 0.001 0.002 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:07.963 0.0%263861 0.021 0.030 0.056
data write00:00:31.629 0.0%265807 0.034 0.119 0.381
idle18:44:43.545 99.9%360647 15.580 187.118 569.490
status file00:00:00.258 0.0%280 0.000 0.921 4.000
TOTAL: 18:45:53.50318:45:27.664100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:13:52.803 29.9%6561 25.000 126.932 1620.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.886 0.1%13122 0.000 0.144 5.667
TOTAL: 00:46:26.88900:13:54.689 30.0%----

Control commands to INND:

CommandNumber
flush 4
flushlogs 2
go 2
logmode 2
lowmark 1
mode 113
name 2
pause 2
paused 2
reload 2
reserve 2
shutdown 3
TOTAL: 12 137

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 7 28427 3627 24555 245 12%50:25:09
2usenet.network 4 10840 3174 7096 570 29%19:03:58
3usenet.blueworldhosting.com 266 12737 2841 9412 484 22%18:31:42
4outgoing.news.lysator.liu.se 2 11521 1364 9937 220 11%17:56:22
5usenet.goja.nl.eu.org 11 9124 436 8648 40 4%17:59:40
6endoftheline-out.news.chmurka.net 3 4990 254 4735 1 5%18:05:31
7194.87.241.33 25 481 249 181 51 51%13:19:40
8i2pn.org 3 12418 187 12221 10 1%18:01:20
9paganini.bofh.team 2 13374 187 13110 77 1%17:55:22
10endofthelinebbs.peers.news.panix.com 23 11976 164 11793 19 1%18:34:51
11newsfeed.xs3.de 2 10255 138 10116 1 1%17:56:25
12glockenspiel.complete.org 3 9819 115 9674 30 1%22:40:26
13nntp.alphanet.ch 17 9385 68 9311 6 0%16:44:28
14news.bbs.nz 1 10739 32 10704 3 0%23:17:48
15news.netfront.net 36 6350 27 2344 3979 0%22:15:41
16news.nntp4.net 2 10679 18 10660 1 0%17:56:25
17news.samoylyk.net 3 11210 13 11196 1 0%18:37:17
18news.furie.org.uk 2 5982 6 5970 6 0%17:56:24
19localhost 22 2 2 0 0 100%00:00:01
20eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:07
21news.nk.ca 2 0 0 0 0 0%00:20:00
TOTAL: 21 437 190309 12902 171663 5744 6%367:48:37
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net18.9 MB5.2 KB337.0 KB19.2 MB 98%5.1 KB
2usenet.network18.1 MB0.0 KB1.3 MB19.4 MB 93%5.3 KB
3usenet.blueworldhosting.com16.7 MB0.0 KB4.6 MB21.2 MB 78%6.5 KB
4outgoing.news.lysator.liu.se3.6 MB1.5 MB29.5 MB34.6 MB 10%22.4 KB
5usenet.goja.nl.eu.org2.3 MB5.2 KB205.3 KB2.5 MB 91%5.4 KB
6nntp.alphanet.ch1.5 MB5.3 KB75.5 KB1.6 MB 94%21.5 KB
7194.87.241.33844.0 KB0.0 KB71.8 KB915.8 KB 92%3.1 KB
8endoftheline-out.news.chmurka.net703.6 KB5.2 KB0.0 KB708.8 KB 99%2.8 KB
9endofthelinebbs.peers.news.panix.com684.8 KB5.2 KB94.7 KB784.7 KB 87%4.3 KB
10paganini.bofh.team649.7 KB5.2 KB303.8 KB958.8 KB 67%3.6 KB
11i2pn.org609.2 KB5.2 KB79.9 KB694.3 KB 87%3.5 KB
12newsfeed.xs3.de539.7 KB5.2 KB0.0 KB544.9 KB 99%3.9 KB
13glockenspiel.complete.org436.7 KB5.2 KB124.3 KB566.2 KB 77%3.9 KB
14news.netfront.net111.7 KB15.9 MB1.3 GB1.3 GB 0%348.1 KB
15news.samoylyk.net87.3 KB5.2 KB0.0 KB92.4 KB 94%6.6 KB
16news.bbs.nz87.1 KB5.2 KB16.8 KB109.1 KB 79%3.1 KB
17news.nntp4.net67.5 KB5.2 KB0.0 KB72.7 KB 92%3.8 KB
18news.furie.org.uk19.5 KB5.2 KB25.3 KB50.0 KB 39%4.2 KB
19localhost4.5 KB0.0 KB0.0 KB4.5 KB100%2.3 KB
20eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
21news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 2165.7 MB17.5 MB1.4 GB1.4 GB 4%80.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 27 00:01:02 - 00:59:59 329 2.6% 0.091.5 MB 0.0% 0.43
Aug 27 01:00:00 - 01:59:59 474 3.8% 0.134.0 MB 0.0% 1.13
Aug 27 02:00:00 - 02:59:59 483 3.8% 0.131.9 MB 0.0% 0.53
Aug 27 03:00:00 - 03:59:59 519 4.1% 0.141.5 MB 0.0% 0.43
Aug 27 04:00:00 - 04:59:59 549 4.3% 0.152.4 MB 4.9% 0.68
Aug 27 05:00:00 - 05:59:59 662 5.2% 0.183.2 MB 6.5% 0.90
Aug 27 06:00:00 - 06:59:59 174 1.4% 0.05482.8 KB 1.0% 0.13
Aug 27 07:00:00 - 07:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Aug 27 08:00:00 - 08:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Aug 27 09:00:00 - 09:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Aug 27 10:00:00 - 10:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Aug 27 11:00:00 - 11:59:59 3653 28.9% 1.0117.0 MB 34.9% 4.83
Aug 27 12:00:00 - 12:59:59 739 5.9% 0.212.5 MB 5.2% 0.72
Aug 27 13:00:00 - 13:59:59 651 5.2% 0.184.0 MB 8.2% 1.14
Aug 27 14:00:00 - 14:59:59 612 4.8% 0.173.3 MB 6.8% 0.94
Aug 27 15:00:00 - 15:59:59 761 6.0% 0.214.7 MB 9.7% 1.35
Aug 27 16:00:00 - 16:59:59 640 5.1% 0.182.8 MB 5.9% 0.81
Aug 27 17:00:00 - 17:59:59 504 4.0% 0.142.2 MB 4.5% 0.63
Aug 27 18:00:00 - 18:59:59 377 3.0% 0.102.1 MB 4.4% 0.60
Aug 27 19:00:00 - 19:59:59 377 3.0% 0.103.6 MB 7.4% 1.03
Aug 27 20:00:00 - 20:59:59 335 2.7% 0.092.4 MB 5.0% 0.69
Aug 27 21:00:00 - 21:59:59 255 2.0% 0.071.5 MB 3.1% 0.43
Aug 27 22:00:00 - 22:59:59 233 1.8% 0.061.6 MB 3.3% 0.46
Aug 27 23:00:00 - 23:59:59 295 2.3% 0.087.9 MB 16.2% 2.23
Aug 28 00:00:00 - 00:01:01 9 0.1% 0.1547.8 KB 0.1% 0.78
TOTAL: 23:59:59 12631 100.0% 0.1548.6 MB 100.0% 0.58
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 4012 0 0 44 0 0 0 0 3968
2usenet.network 615 0 0 0 0 0 0 0 615
3usenet.blueworldhosting.com 492 8 0 0 1 0 0 0 483
4endofthelinebbs-out.news.weretis.net 249 0 0 1 0 0 0 0 248
5outgoing.news.lysator.liu.se 224 0 0 5 0 0 0 0 219
6paganini.bofh.team 75 0 0 1 0 0 0 0 74
7194.87.241.33 51 0 0 0 0 0 0 0 51
8usenet.goja.nl.eu.org 41 0 0 1 0 0 0 0 40
9endofthelinebbs.peers.news.panix.com 21 0 0 1 0 0 0 0 20
10i2pn.org 13 0 0 2 0 0 0 0 11
11glockenspiel.complete.org 9 0 0 1 0 0 0 0 8
12news.furie.org.uk 6 0 0 1 0 0 0 0 5
13nntp.alphanet.ch 6 0 0 1 0 0 0 0 5
14news.samoylyk.net 2 0 0 2 0 0 0 0 0
15news.bbs.nz 1 0 0 1 0 0 0 0 0
16endoftheline-out.news.chmurka.net 1 0 0 1 0 0 0 0 0
17news.nntp4.net 1 0 0 1 0 0 0 0 0
18newsfeed.xs3.de 1 0 0 1 0 0 0 0 0
TOTAL: 18 5820 8 0 64 1 0 0 0 5747

Unwanted newsgroups [Top 20]:

NewsgroupCount
nsysu.rec.netgame 4
nsysu.rec.tarot-card 4
TOTAL: 2 8

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

GroupsCount
uk.religion.christian 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Binary: misplaced binary 2647
Too many newsgroups 1468
EMP (phn path) 603
EMP (md5) 494
EMP (phr path) 318
Binary Image: misplaced jpg 80
EMP (phl) 38
User-issued cancel 18
HTML Multipart 13
EMP (phr nph) 6
hierarchy violation - crosspost outside sdnet 4
Too many newsgroups (meow) 1
HTML post 1
TOTAL: 13 5691

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
usenet.network 27
endofthelinebbs-out.news.weretis.net 14
usenet.blueworldhosting.com 8
outgoing.news.lysator.liu.se 2
endofthelinebbs.peers.news.panix.com 1
i2pn.org 1
TOTAL: 6 53
No colon-space in header field
usenet.blueworldhosting.com 3
TOTAL: 1 3
TOTAL: 2 56

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 12916 11227 1168 209 0 871 86%18:49:08
2nyheter.lysator.liu.se 11506 2475 7936 825 0 511 21%18:49:08
3paganini.bofh.team 14786 789 11860 0 0 447 5%18:59:03
4endofthelinebbs.peers.news.panix.com 12768 438 12104 70 0 152 3%18:50:10
5news.nk.ca 17066 210 11652 632 0 259 1%18:49:09
6nntp.comgw.net 15266 188 11808 659 0 312 1%18:57:43
7usenet.blueworldhosting.com 8682 160 8105 0 0 83 1%18:49:09
8ddt.demos.su 827 148 676 1 0 6 17%16:10:19
9news.chmurka.net 6477 95 4930 19 0 49 1%18:59:03
10news.furie.org.uk 8432 50 6033 224 0 67 0%18:49:08
11usenet.network 11738 48 9031 3 0 1 0%18:55:12
12i2pn.org 12516 48 11863 248 0 166 0%18:59:03
13news.tnetconsulting.net 13043 32 12187 389 0 147 0%18:49:09
14usenet.goja.nl.eu.org 14568 32 11702 415 0 472 0%18:49:39
15news.bbs.nz 21076 29 12062 514 0 1162 0%18:49:08
16news.quux.org 12844 26 11963 500 0 152 0%18:49:09
17news.nntp4.net 14817 24 12361 179 0 396 0%18:59:03
18news.weretis.net 12800 21 12234 375 0 190 0%18:49:08
19alphanet.ch 15628 14 12208 333 0 674 0%18:59:03
20news.samoylyk.net 15214 7 12420 163 0 759 0%18:49:08
21newsfeed.xs3.de 15076 5 12039 436 0 468 0%18:49:08
TOTAL: 21 268046 16066 206342 6194 0 7344 5%393:38:50
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net55.7 MB7.3 MB63.0 MB1.0 KB/s5.6 KB18:49:08
2nyheter.lysator.liu.se15.4 MB7.3 MB22.7 MB0.3 KB/s7.1 KB18:49:08
3paganini.bofh.team5.3 MB0.0 KB5.3 MB0.1 KB/s6.9 KB18:59:03
4endofthelinebbs.peers.news.panix.com2.4 MB220.1 KB2.6 MB0.0 KB/s5.3 KB18:50:10
5nntp.comgw.net1.5 MB7.4 MB8.9 MB0.1 KB/s10.8 KB18:57:43
6news.furie.org.uk1.0 MB3.8 MB4.8 MB0.1 KB/s17.9 KB18:49:08
7news.nk.ca482.2 KB12.4 MB12.9 MB0.2 KB/s15.7 KB18:49:09
8news.weretis.net444.3 KB10.0 MB10.5 MB0.2 KB/s27.1 KB18:49:08
9news.chmurka.net384.2 KB32.8 KB417.0 KB0.0 KB/s3.7 KB18:59:03
10usenet.blueworldhosting.com310.5 KB0.0 KB310.5 KB0.0 KB/s1.9 KB18:49:09
11ddt.demos.su287.7 KB3.9 KB291.6 KB0.0 KB/s2.0 KB16:10:19
12news.quux.org240.6 KB5.0 MB5.3 MB0.1 KB/s10.2 KB18:49:09
13news.nntp4.net134.3 KB781.3 KB915.6 KB0.0 KB/s4.5 KB18:59:03
14usenet.network120.7 KB6.2 KB126.9 KB0.0 KB/s2.5 KB18:55:12
15news.bbs.nz113.3 KB11.5 MB11.6 MB0.2 KB/s21.8 KB18:49:08
16news.tnetconsulting.net109.8 KB2.3 MB2.4 MB0.0 KB/s5.9 KB18:49:09
17i2pn.org108.4 KB590.4 KB698.8 KB0.0 KB/s2.4 KB18:59:03
18usenet.goja.nl.eu.org75.7 KB2.8 MB2.9 MB0.0 KB/s6.7 KB18:49:39
19alphanet.ch29.2 KB3.8 MB3.8 MB0.1 KB/s11.2 KB18:59:03
20news.samoylyk.net21.7 KB2.2 MB2.2 MB0.0 KB/s13.3 KB18:49:08
21newsfeed.xs3.de9.7 KB3.1 MB3.1 MB0.0 KB/s7.3 KB18:49:08
TOTAL: 2184.1 MB80.6 MB164.8 MB0.1 KB/s7.6 KB393:38:50
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

ReasonConn
What server? (server parameter unset in inn.conf 5
TOTAL: 1 5

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
147.186.47.220 1 00.0 KB 4 0 000:00:00
TOTAL: 1 1 00.0 KB 4 0 000:46:15

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
2?2186 00.0 KB 0 0 000:46:13
3unresolved 2 00.0 KB 4 0 000:00:00
TOTAL: 32189 00.0 KB 4 0 000:46:15

NNRP auth users [Top 20]:

UserConn
nelgin 1
TOTAL: 1 1

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2a0a:bb07::8b 222.220 98.250 0.00000:46:13
thrifty.monitoring.internet-measurement.com 0.094 0.053 0.00000:00:01
192.241.224.38 0.109 0.030 0.00000:00:00
TOTAL: 3 222.423 98.333 0.00000:46:15

Curious NNRP server explorers [Top 20]:

SystemConn
2a0a:bb07::8b 2186
thrifty.monitoring.internet-measurement.com 1
192.241.224.38 1
TOTAL: 3 2188

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

SystemConn
192.241.224.38 1
TOTAL: 1 1

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

CommandCount
MGLNDD_192.138.210.162_119 1
TOTAL: 1 1

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1news 6 66.7%
2comp 1 11.1%
3alt 1 11.1%
4soc 1 11.1%
TOTAL: 4 9100.0%

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

NewsgroupCount
1news.admin.peering 6
2comp.sys.acorn.apps 1
3alt.bbs.doors 1
4soc.genealogy.britain 1
TOTAL: 4 9