Daily Usenet Report

Apr 8 00:01:02 -- Apr 9 00:01:02

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Apr 8 00:09:06 www innd: message repeated 2 times: [ ctlinnd command s]
Apr 8 00:10:00 www innd: tradspool: could not open /news/spool/articles/news/test/25: File exists
Apr 8 02:29:45 www innd: tradspool: could not open /news/spool/articles/news/test/26: File exists
Apr 8 03:02:38 www innd: tradspool: could not open /news/spool/articles/news/test/27: File exists
Apr 8 03:02:40 www innd: tradspool: could not open /news/spool/articles/news/test/28: File exists
Apr 8 03:03:36 www innd: tradspool: could not open /news/spool/articles/news/test/29: File exists
Apr 8 03:18:39 www innd: tradspool: could not open /news/spool/articles/news/test/30: File exists
Apr 8 03:18:39 www innd: tradspool: could not open /news/spool/articles/news/test/31: File exists
Apr 8 03:33:28 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21686 to /news/spool/articles/soc/culture/russia/1: File exists
Apr 8 03:33:30 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21687 to /news/spool/articles/soc/culture/russia/2: File exists
Apr 8 03:33:38 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21688 to /news/spool/articles/soc/culture/russia/3: File exists
Apr 8 03:33:38 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21689 to /news/spool/articles/soc/culture/russia/4: File exists
Apr 8 03:33:38 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21690 to /news/spool/articles/soc/culture/russia/5: File exists
Apr 8 03:33:39 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21691 to /news/spool/articles/soc/culture/russia/6: File exists
Apr 8 03:33:39 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21692 to /news/spool/articles/soc/culture/russia/7: File exists
Apr 8 03:33:39 www innd: tradspool: could not symlink /news/spool/articles/aus/politics/21693 to /news/spool/articles/soc/culture/russia/8: File exists
Apr 8 17:35:13 www nnrpd[4041056]: unable to get certificates from '/news/etc/cert.pem'
Apr 8 17:35:13 www nnrpd[4041056]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]

Log entries by program:

Program nameLines%LinesSize%Size
inn 15702 49.7%4.6 MB 67.3%
innd 12618 39.9%1.7 MB 24.6%
innfeed 2908 9.2%531.4 KB 7.6%
nnrpd 352 1.1%27.5 KB 0.4%
rnews 3 0.0%0.4 KB 0.0%
controlchan 3 0.0%0.4 KB 0.0%
TOTAL: 6 31586 100.0%6.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 128311 57.9%
Negative hits 76372 34.5%
Do not exist 15365 6.9%
Cache misses 1549 0.7%
TOTAL: 4 221597 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.052 0.0%78 0.000 0.667 4.000
article cleanup00:00:00.284 0.0%15382 0.000 0.018 0.074
article logging00:00:00.715 0.0%15633 0.000 0.046 0.128
article parse00:00:01.169 0.0%61744 0.000 0.019 0.076
article write00:00:08.130 0.0%10736 0.400 0.757 2.929
artlog/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%15 0.000 0.000 0.000
data move00:00:00.383 0.0%242368 0.000 0.002 0.010
hisgrep/artcncl00:00:00.011 0.0%87 0.000 0.126 0.750
hishave/artcncl00:00:00.000 0.0%9 0.000 0.000 0.000
hissync/artwrite00: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:08.943 0.0%221684 0.005 0.040 0.165
history sync00:00:00.051 0.0%305 0.000 0.167 1.000
history write00:00:10.227 0.0%15365 0.067 0.666 1.064
idle23:57:41.297 99.8%241579 59.032 357.073 10355.172
nntp read00:00:12.002 0.0%243862 0.000 0.049 0.119
overview write00:00:07.673 0.0%10721 0.200 0.716 13.000
perl filter00:00:49.635 0.1%15380 1.992 3.227 7.500
python filter00:00:02.688 0.0%15382 0.078 0.175 0.441
site send00:00:04.163 0.0%21442 0.034 0.194 0.534
TOTAL: 24:00:52.66723:59:27.423 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.092 0.0%15445 0.000 0.006 0.077
article prepare00:00:00.006 0.0%25116 0.000 0.000 0.005
article read00:00:01.080 0.0%4151 0.056 0.260 1.484
backlog stats00:00:00.548 0.0%236936 0.000 0.002 0.069
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.244 0.0%170284 0.015 0.031 0.067
data write00:00:19.967 0.0%163172 0.000 0.122 0.412
idle23:46:39.110100.0%236936 60.000 361.275 21489.000
status file00:00:00.312 0.0%286 0.000 1.091 6.000
TOTAL: 23:47:19.24823:47:06.359100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:22.566 95.8%12 26.000 1880.500 5419.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.004 0.0%52 0.000 0.077 0.800
TOTAL: 00:00:23.54900:00:22.570 95.8%----

Control commands to INND:

CommandNumber
flush 4
flushlogs 2
go 5
logmode 2
lowmark 1
mode 148
name 2
newgroup 1
pause 1
paused 1
reload 2
rmgroup 1
throttle 2
TOTAL: 13 172

Newsgroups created:

GroupMode
news.testy
TOTAL1

Newsgroups removed:

Group
news.test
TOTAL: 1

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 9 19135 6021 12614 500 31%37:16:16
2usenet.blueworldhosting.com 1432 12473 2426 9375 672 19%21:19:28
3newsfeed.xs3.de 3 9637 466 9164 7 4%19:59:23
4outgoing.news.lysator.liu.se 3 11713 447 10813 453 3%18:57:25
5endofthelinebbs.peers.news.panix.com 45 12387 327 9991 2069 2%22:45:55
6i2pn.org 3 11345 265 11026 54 2%19:59:22
7endoftheline-out.news.chmurka.net 7 4963 149 4776 38 3%17:24:35
8usenet.network 2 10311 146 10122 43 1%17:12:22
9news.samoylyk.net 3 9498 49 9447 2 0%18:43:52
10glockenspiel.complete.org 3 3483 10 3465 8 0%04:32:31
11news.furie.org.uk 2 5242 2 5240 0 0%18:56:21
12localhost 22 2 2 0 0 100%00:00:02
13news.netfront.net 2 11852 1 11226 625 0%16:27:31
14eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:13
15news.bbs.nz 4 3052 0 3052 0 0%05:41:33
16news.nk.ca 2 0 0 0 0 0%00:20:00
TOTAL: 16 1543 125093 10311 110311 4471 8%239:46:49
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net21.8 MB1.7 KB1.2 MB23.0 MB 94%3.6 KB
2usenet.blueworldhosting.com16.1 MB0.0 KB2.7 MB18.8 MB 85%6.2 KB
3newsfeed.xs3.de1.6 MB0.0 KB156.8 KB1.8 MB 91%3.9 KB
4usenet.network1.6 MB0.0 KB245.3 KB1.9 MB 87%10.2 KB
5endofthelinebbs.peers.news.panix.com1.2 MB0.0 KB121.7 MB122.9 MB 0%52.5 KB
6i2pn.org906.0 KB0.0 KB243.3 KB1.1 MB 78%3.6 KB
7outgoing.news.lysator.liu.se761.0 KB149.4 KB860.4 KB1.7 MB 42%2.0 KB
8endoftheline-out.news.chmurka.net546.7 KB0.0 KB267.0 KB813.6 KB 67%4.4 KB
9news.samoylyk.net297.2 KB0.0 KB150.5 KB447.7 KB 66%8.8 KB
10news.furie.org.uk151.8 KB0.0 KB0.0 KB151.8 KB100%75.9 KB
11glockenspiel.complete.org144.7 KB0.0 KB161.3 KB306.0 KB 47%17.0 KB
12localhost4.1 KB0.0 KB0.0 KB4.1 KB100%2.1 KB
13news.netfront.net1.0 KB0.0 KB188.2 MB188.2 MB 0%307.9 KB
14eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
15news.bbs.nz0.0 KB0.0 KB0.0 KB0.0 KB 0%0
16news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1645.2 MB151.1 KB315.9 MB361.2 MB 12%25.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 8 00:01:02 - 00:59:59 13 0.1% 0.0025.0 KB 0.0% 0.01
Apr 8 01:00:00 - 01:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Apr 8 02:00:00 - 02:59:59 1428 13.3% 0.407.0 MB 40.9% 2.00
Apr 8 03:00:00 - 03:59:59 500 4.6% 0.142.4 MB 14.0% 0.69
Apr 8 04:00:00 - 04:59:59 514 4.8% 0.141.4 MB 8.3% 0.41
Apr 8 05:00:00 - 05:59:59 520 4.8% 0.141.4 MB 8.1% 0.40
Apr 8 06:00:00 - 06:59:59 454 4.2% 0.132.3 MB 13.5% 0.66
Apr 8 07:00:00 - 07:59:59 421 3.9% 0.122.1 MB 12.3% 0.60
Apr 8 08:00:00 - 08:59:59 561 5.2% 0.162.6 MB 15.3% 0.75
Apr 8 09:00:00 - 09:59:59 627 5.8% 0.173.4 MB 19.8% 0.97
Apr 8 10:00:00 - 10:59:59 613 5.7% 0.171.8 MB 10.2% 0.50
Apr 8 11:00:00 - 11:59:59 503 4.7% 0.141.9 MB 11.0% 0.54
Apr 8 12:00:00 - 12:59:59 604 5.6% 0.173.0 MB 17.2% 0.84
Apr 8 13:00:00 - 13:59:59 523 4.9% 0.152.1 MB 12.3% 0.60
Apr 8 14:00:00 - 14:59:59 552 5.1% 0.152.4 MB 13.8% 0.68
Apr 8 15:00:00 - 15:59:59 443 4.1% 0.121.5 MB 8.5% 0.41
Apr 8 16:00:00 - 16:59:59 486 4.5% 0.141.9 MB 11.0% 0.54
Apr 8 17:00:00 - 17:59:59 431 4.0% 0.121.6 MB 9.5% 0.46
Apr 8 18:00:00 - 18:59:59 349 3.2% 0.102.8 MB 16.5% 0.81
Apr 8 19:00:00 - 19:59:59 260 2.4% 0.07893.3 KB 5.1% 0.25
Apr 8 20:00:00 - 20:59:59 229 2.1% 0.06907.0 KB 5.1% 0.25
Apr 8 21:00:00 - 21:59:59 219 2.0% 0.06850.9 KB 4.8% 0.24
Apr 8 22:00:00 - 22:59:59 256 2.4% 0.071.3 MB 7.4% 0.36
Apr 8 23:00:00 - 23:59:59 248 2.3% 0.072.7 MB 15.4% 0.76
Apr 9 00:00:00 - 00:01:02 8 0.1% 0.1341.6 KB 0.2% 0.67
TOTAL: 24:00:00 10762 100.0% 0.1217.2 MB 100.0% 0.20
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs.peers.news.panix.com 2069 2067 0 0 0 0 0 0 2
2endofthelinebbs-out.news.weretis.net 751 0 0 1 0 0 0 0 750
3news.netfront.net 739 0 0 0 0 0 0 0 739
4usenet.blueworldhosting.com 672 2 0 0 0 0 0 0 670
5outgoing.news.lysator.liu.se 455 0 0 1 0 0 0 0 454
6glockenspiel.complete.org 86 0 0 0 0 0 0 0 86
7i2pn.org 56 0 0 0 0 0 0 0 56
8endoftheline-out.news.chmurka.net 45 0 0 0 0 0 0 0 45
9usenet.network 43 0 0 0 0 0 0 0 43
10newsfeed.xs3.de 11 0 0 0 0 0 0 0 11
11news.samoylyk.net 3 0 0 0 0 0 0 0 3
12news.furie.org.uk 1 0 0 0 0 0 0 0 1
TOTAL: 12 4931 2069 0 2 0 0 0 0 2860

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.backup 1461
alt.binaries.ftd 390
alt.binaries.bloaf 60
finet.binaries.keskustelu 34
alt.binaries.nospam.female.bodyhair 20
alt.binaries.pictures.earlmiller 14
alt.binaries.nospam.female.bodyhair.pubes 11
alt.binaries.pictures.nude.celebrities.fake 10
alt.binaries.pictures.wallpaper 7
alt.binaries.pictures.scenic 6
alt.binaries.comics.dcp 6
alt.binaries.test.yenc 5
alt.binaries.pictures.purebeauty 5
alt.binaries.alt5 4
alt.binaries.sounds.lossless 4
alt.binaries.movies.thelostmovies 3
alt.binaries.sounds.lossless.blues 2
alt.binaries.sounds.lossless.1970s 2
alt.binaries.pictures.tall-ships 2
alt.binaries.pictures.nylons 2
TOTAL: 39 2069

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 890
EMP (md5) 628
EMP (phl) 223
Binary: misplaced binary fx15.ams4.POSTED 105
Too many newsgroups 93
Binary: misplaced binary fx04.ams4.POSTED 82
Binary: misplaced binary fx01.ams4.POSTED 80
Binary: misplaced binary fx02.ams4.POSTED 68
Binary: misplaced binary fx05.ams4.POSTED 67
Binary: misplaced binary fx07.ams4.POSTED 54
Binary: misplaced binary fx12.ams4.POSTED 48
EMP (phn nph) 46
Binary: misplaced binary fx03.ams4.POSTED 40
Binary: misplaced binary fx14.ams4.POSTED 39
Binary: misplaced binary fx10.ams4.POSTED 32
Binary: misplaced binary fx09.ams4.POSTED 25
Too many newsgroups (meow) 15
Binary: misplaced binary fx08.ams4.POSTED 12
Binary: misplaced binary fx06.ams4.POSTED 11
Binary: misplaced binary fx13.ams4.POSTED 8
TOTAL: 22 2579

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
endofthelinebbs-out.news.weretis.net 245
newsfeed.xs3.de 4
i2pn.org 2
endoftheline-out.news.chmurka.net 2
outgoing.news.lysator.liu.se 1
news.samoylyk.net 1
news.furie.org.uk 1
TOTAL: 7 256
TOTAL: 1 256

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 13867 3515 7240 6 0 728 25%21:54:47
2nyheter.lysator.liu.se 10680 1622 8536 148 0 631 15%21:54:47
3endofthelinebbs.peers.news.panix.com 10950 236 10462 59 0 93 2%21:54:46
4news.furie.org.uk 7636 107 5711 50 0 250 1%21:54:47
5news.nk.ca 12415 63 10606 52 0 281 0%21:54:46
6news.bbs.nz 19751 47 10622 80 0 849 0%21:54:47
7news.tnetconsulting.net 11528 40 10684 31 0 0 0%21:54:39
8i2pn.org 10490 35 10310 2 0 80 0%21:54:46
9usenet.network 12267 27 10056 5 0 89 0%21:54:47
10news.quux.org 10904 27 10590 50 0 116 0%21:54:46
11news.weretis.net 10850 9 10721 26 0 86 0%21:54:46
12newsfeed.xs3.de 11423 5 10212 65 0 641 0%21:54:47
13usenet.blueworldhosting.com 8265 2 7027 0 0 41 0%21:41:05
14news.samoylyk.net 13433 2 10703 4 0 870 0%21:54:47
15endoftheline-in.news.chmurka.net 0 0 0 0 0 0 0%00:20:22
16peer.alt119.net 0 0 0 0 0 10755 0%23:55:06
TOTAL: 16 164459 5737 133480 578 0 15510 3%330:48:31
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net15.3 MB25.8 KB15.3 MB0.2 KB/s4.4 KB21:54:47
2nyheter.lysator.liu.se8.1 MB1.8 MB9.9 MB0.1 KB/s5.7 KB21:54:47
3news.furie.org.uk1.1 MB1023.2 KB2.1 MB0.0 KB/s14.0 KB21:54:47
4endofthelinebbs.peers.news.panix.com790.8 KB78.6 KB869.4 KB0.0 KB/s2.9 KB21:54:46
5news.quux.org337.4 KB1011.3 KB1.3 MB0.0 KB/s17.5 KB21:54:46
6news.nk.ca250.5 KB1.2 MB1.4 MB0.0 KB/s12.7 KB21:54:46
7news.bbs.nz185.1 KB1.1 MB1.2 MB0.0 KB/s10.0 KB21:54:47
8i2pn.org151.5 KB1.6 KB153.1 KB0.0 KB/s4.1 KB21:54:46
9news.tnetconsulting.net144.7 KB183.5 KB328.2 KB0.0 KB/s4.6 KB21:54:39
10usenet.network113.4 KB26.7 KB140.1 KB0.0 KB/s4.4 KB21:54:47
11news.weretis.net33.4 KB773.4 KB806.8 KB0.0 KB/s23.1 KB21:54:46
12newsfeed.xs3.de19.2 KB502.1 KB521.4 KB0.0 KB/s7.4 KB21:54:47
13usenet.blueworldhosting.com4.1 KB0.0 KB4.1 KB0.0 KB/s2.1 KB21:41:05
14news.samoylyk.net4.1 KB553.7 KB557.8 KB0.0 KB/s93.0 KB21:54:47
15endoftheline-in.news.chmurka.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:20:22
16peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:06
TOTAL: 1626.5 MB8.1 MB34.6 MB0.0 KB/s5.6 KB330:48:31
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

ReasonConn
400 File exists writing SMstore file -- throttling 3
TOTAL: 1 3

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 7 00.0 KB 5 0 000:00:23
TOTAL: 1 7 00.0 KB 5 0 000:00:23

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
104.156.155.30 0.592 0.186 0.00000:00:23
192.241.226.30 0.090 0.063 0.00000:00:00
TOTAL: 2 0.682 0.249 0.00000:00:23

Curious NNRP server explorers [Top 20]:

SystemConn
104.156.155.30 6
192.241.226.30 1
TOTAL: 2 7

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

SystemConn
192.241.226.30 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 4 66.7%
2comp 2 33.3%
TOTAL: 2 6100.0%

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

NewsgroupCount
1news.software.nntp 4
2comp.sys.acorn.misc 2
TOTAL: 2 6