Daily Usenet Report

Apr 7 00:01:01 -- Apr 8 00:01:02

Unknown entries from news log file:

First 24 / 24 lines (100.0%)

Apr 7 00:08:10 www innd: message repeated 2 times: [ ctlinnd command s]
Apr 7 10:12:40 www innd: tradspool: could not symlink /news/spool/articles/soc/culture/pakistan/132 to /news/spool/articles/soc/culture/iran/1: File exists
Apr 7 10:12:42 www innd: tradspool: could not symlink /news/spool/articles/soc/culture/pakistan/133 to /news/spool/articles/soc/culture/iran/2: File exists
Apr 7 10:12:46 www innd: tradspool: could not symlink /news/spool/articles/soc/culture/pakistan/134 to /news/spool/articles/soc/culture/iran/3: File exists
Apr 7 10:12:50 www innd: tradspool: could not symlink /news/spool/articles/soc/culture/pakistan/135 to /news/spool/articles/soc/culture/iran/4: File exists
Apr 7 10:12:51 www innd: tradspool: could not symlink /news/spool/articles/soc/culture/pakistan/136 to /news/spool/articles/soc/culture/iran/5: File exists
Apr 7 10:12:56 www innd: tradspool: could not symlink /news/spool/articles/soc/culture/pakistan/137 to /news/spool/articles/soc/culture/iran/6: File exists
Apr 7 10:15:01 www innd: tradspool: could not symlink /news/spool/articles/soc/culture/pakistan/138 to /news/spool/articles/soc/culture/iran/7: File exists
Apr 7 13:04:15 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/789 to /news/spool/articles/talk/politics/usa/1: File exists
Apr 7 13:04:15 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/790 to /news/spool/articles/talk/politics/usa/2: File exists
Apr 7 13:04:15 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/791 to /news/spool/articles/talk/politics/usa/3: File exists
Apr 7 13:04:17 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/792 to /news/spool/articles/talk/politics/usa/4: File exists
Apr 7 13:04:20 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/793 to /news/spool/articles/talk/politics/usa/5: File exists
Apr 7 13:15:17 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/794 to /news/spool/articles/talk/politics/usa/6: File exists
Apr 7 13:15:18 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/795 to /news/spool/articles/talk/politics/usa/7: File exists
Apr 7 13:15:18 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/796 to /news/spool/articles/talk/politics/usa/8: File exists
Apr 7 13:15:19 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/797 to /news/spool/articles/talk/politics/usa/9: File exists
Apr 7 13:15:22 www innd: tradspool: could not symlink /news/spool/articles/alt/radio/talk/798 to /news/spool/articles/talk/politics/usa/10: File exists
Apr 7 19:35:02 www nnrpd[3863885]: unable to get certificates from '/news/etc/cert.pem'
Apr 7 19:35:02 www nnrpd[3863885]: error initializing TLS: [CA_file: ] [CA_path: /news/etc] [cert_file: /news/etc/cert.pem] [key_file: /news/etc/key.pem]
Apr 7 21:59:03 www innd: tradspool: could not open /news/spool/articles/news/test/21: File exists
Apr 7 21:59:03 www innd: tradspool: could not open /news/spool/articles/news/test/22: File exists
Apr 7 21:59:03 www innd: tradspool: could not open /news/spool/articles/news/test/23: File exists
Apr 7 21:59:08 www innd: tradspool: could not open /news/spool/articles/news/test/24: File exists

Log entries by program:

Program nameLines%LinesSize%Size
inn 249407 95.0%38.8 MB 95.2%
innd 10167 3.9%1.4 MB 3.5%
innfeed 2686 1.0%513.8 KB 1.2%
nnrpd 130 0.0%11.1 KB 0.0%
controlchan 22 0.0%5.3 KB 0.0%
rnews 2 0.0%0.2 KB 0.0%
TOTAL: 6 262414 100.0%40.7 MB100.0%

History cache:

ReasonCount%Count
Negative hits 308076 45.2%
Do not exist 247548 36.3%
Positive hits 125891 18.5%
Cache misses 148 0.0%
TOTAL: 4 681663 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.027 0.0%49 0.000 0.551 8.000
article cleanup00:00:03.347 0.0%252813 0.000 0.013 0.097
article logging00:00:11.242 0.0%253065 0.000 0.044 0.115
article parse00:00:34.586 0.0%3232048 0.000 0.011 0.059
article write00:00:10.509 0.0%10874 0.359 0.966 2.684
artlog/artcncl00:00:00.000 0.0%3 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%21 0.000 0.000 0.000
data move00:00:03.856 0.0%2626235 0.000 0.001 0.006
hisgrep/artcncl00:00:00.006 0.0%52 0.000 0.115 2.000
hishave/artcncl00:00:00.000 0.0%3 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:23.898 0.0%695346 0.006 0.034 0.223
history sync00:00:00.044 0.0%340 0.000 0.129 1.000
history write00:01:01.780 0.1%252792 0.091 0.244 0.809
idle23:37:04.489 98.3%2571078 2.485 33.070 9244.154
nntp read00:04:50.648 0.3%3222847 0.021 0.090 0.110
overview write00:00:08.692 0.0%10853 0.186 0.801 8.526
perl filter00:12:25.071 0.9%252813 2.066 2.947 11.651
python filter00:00:21.467 0.0%252814 0.066 0.085 0.509
site send00:00:04.646 0.0%21708 0.077 0.214 0.542
TOTAL: 24:00:55.18723:57:24.308 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.121 0.0%12122 0.000 0.010 0.221
article prepare00:00:00.006 0.0%33204 0.000 0.000 0.007
article read00:00:01.315 0.0%5123 0.061 0.257 2.318
backlog stats00:00:00.745 0.0%269260 0.000 0.003 0.107
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:05.442 0.0%188877 0.000 0.029 0.059
data write00:00:22.561 0.0%178841 0.000 0.126 0.256
idle23:55:35.889100.0%269260 167.365 319.899 21486.357
status file00:00:00.258 0.0%242 0.000 1.066 4.000
TOTAL: 23:56:18.17323:56:06.337100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:03.670 77.3%8 20.667 458.750 1697.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.0%16 0.000 0.062 0.167
TOTAL: 00:00:04.75000:00:03.671 77.3%----

Control commands to INND:

CommandNumber
flush 4
flushlogs 1
go 2
logmode 2
lowmark 1
mode 147
name 2
pause 2
paused 1
reload 2
reserve 2
throttle 2
TOTAL: 12 168

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
us-control@lists.killfile.org000100
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 4 22054 6206 15316 532 28%52:02:39
2usenet.blueworldhosting.com 401 12643 2719 9151 773 21%21:50:58
3outgoing.news.lysator.liu.se 0 12578 645 11390 543 5%23:59:05
4usenet.network 2 12437 478 11915 44 3%25:44:01
5newsfeed.xs3.de 0 9462 377 9078 7 3%22:44:02
6i2pn.org 0 11528 240 11233 55 2%22:43:55
7endoftheline-out.news.chmurka.net 2 5857 227 5603 27 3%25:58:31
8endofthelinebbs.peers.news.panix.com 104 246218 161 10271 235786 0%42:08:25
9glockenspiel.complete.org 2 17667 63 17543 61 0%38:22:09
10news.samoylyk.net 3 10233 27 10202 4 0%24:42:35
11news.bbs.nz 1 11611 4 11603 4 0%28:39:39
12news.netfront.net 1 14057 2 13416 639 0%25:55:17
13localhost 24 1 1 0 0 100%00:00:01
14news.furie.org.uk 1 5261 1 5258 2 0%23:43:45
15eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:17
16news.nk.ca 2 0 0 0 0 0%00:20:00
TOTAL: 16 548 391607 11151 141979 238477 2%379:05:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net23.3 MB0.0 KB1.4 MB24.8 MB 94%3.8 KB
2usenet.blueworldhosting.com16.4 MB0.0 KB3.3 MB19.7 MB 83%5.8 KB
3usenet.network4.6 MB0.0 KB95.7 KB4.6 MB 97%9.1 KB
4newsfeed.xs3.de1.5 MB0.0 KB39.4 KB1.5 MB 97%4.0 KB
5outgoing.news.lysator.liu.se1013.4 KB0.0 KB1.2 MB2.2 MB 45%1.9 KB
6endoftheline-out.news.chmurka.net794.7 KB0.0 KB226.9 KB1021.6 KB 77%4.0 KB
7i2pn.org777.8 KB0.0 KB102.7 KB880.5 KB 88%3.0 KB
8endofthelinebbs.peers.news.panix.com550.3 KB0.0 KB14.8 GB14.8 GB 0%65.6 KB
9glockenspiel.complete.org418.5 KB3.1 KB133.2 KB554.9 KB 75%4.5 KB
10news.samoylyk.net249.9 KB0.0 KB8.1 KB258.0 KB 96%8.3 KB
11news.netfront.net8.8 KB0.0 KB192.8 MB192.8 MB 0%308.0 KB
12news.bbs.nz5.3 KB0.0 KB28.4 KB33.7 KB 15%4.2 KB
13localhost1.9 KB0.0 KB0.0 KB1.9 KB100%1.9 KB
14news.furie.org.uk1.0 KB0.0 KB27.5 KB28.5 KB 3%9.5 KB
15eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
16news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1649.5 MB3.1 KB14.9 GB15.0 GB 0%63.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 7 00:01:01 - 00:59:59 271 2.5% 0.08846.4 KB 1.7% 0.24
Apr 7 01:00:00 - 01:59:59 457 4.2% 0.131.9 MB 3.9% 0.53
Apr 7 02:00:00 - 02:59:59 495 4.6% 0.141.9 MB 4.0% 0.55
Apr 7 03:00:00 - 03:59:59 592 5.5% 0.162.7 MB 5.6% 0.75
Apr 7 04:00:00 - 04:59:59 606 5.6% 0.172.2 MB 4.6% 0.62
Apr 7 05:00:00 - 05:59:59 622 5.8% 0.172.0 MB 4.3% 0.58
Apr 7 06:00:00 - 06:59:59 574 5.3% 0.162.6 MB 5.4% 0.73
Apr 7 07:00:00 - 07:59:59 610 5.6% 0.172.7 MB 5.6% 0.76
Apr 7 08:00:00 - 08:59:59 567 5.2% 0.163.6 MB 7.6% 1.03
Apr 7 09:00:00 - 09:59:59 580 5.4% 0.162.1 MB 4.5% 0.61
Apr 7 10:00:00 - 10:59:59 599 5.5% 0.172.8 MB 5.9% 0.81
Apr 7 11:00:00 - 11:59:59 600 5.6% 0.172.8 MB 5.9% 0.80
Apr 7 12:00:00 - 12:59:59 631 5.8% 0.182.9 MB 6.1% 0.83
Apr 7 13:00:00 - 13:59:59 516 4.8% 0.142.9 MB 6.1% 0.83
Apr 7 14:00:00 - 14:59:59 492 4.6% 0.142.1 MB 4.3% 0.59
Apr 7 15:00:00 - 15:59:59 551 5.1% 0.152.0 MB 4.1% 0.56
Apr 7 16:00:00 - 16:59:59 440 4.1% 0.121.6 MB 3.4% 0.46
Apr 7 17:00:00 - 17:59:59 399 3.7% 0.112.8 MB 6.0% 0.81
Apr 7 18:00:00 - 18:59:59 342 3.2% 0.101.5 MB 3.2% 0.44
Apr 7 19:00:00 - 19:59:59 345 3.2% 0.101.4 MB 2.9% 0.39
Apr 7 20:00:00 - 20:59:59 292 2.7% 0.081.5 MB 3.2% 0.44
Apr 7 21:00:00 - 00:01:02 228 2.1% 0.06807.9 KB 1.7% 0.22
TOTAL: 22:00:01 10809 100.0% 0.1447.7 MB 100.0% 0.62
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1endofthelinebbs.peers.news.panix.com235732235724 0 0 0 0 0 0 8
2endofthelinebbs-out.news.weretis.net 783 0 0 0 0 0 0 0 783
3usenet.blueworldhosting.com 773 2 0 0 1 0 0 0 770
4news.netfront.net 585 0 0 0 0 0 0 0 585
5outgoing.news.lysator.liu.se 544 0 0 0 0 0 0 0 544
6i2pn.org 54 0 0 0 0 0 0 0 54
7usenet.network 43 0 0 0 0 0 0 0 43
8glockenspiel.complete.org 30 0 0 0 0 0 0 0 30
9endoftheline-out.news.chmurka.net 26 0 0 0 0 0 0 0 26
10newsfeed.xs3.de 16 0 0 0 0 0 0 0 16
11news.samoylyk.net 6 0 0 0 0 0 0 0 6
12news.bbs.nz 2 0 0 0 0 0 0 0 2
13news.furie.org.uk 1 0 0 0 0 0 0 0 1
TOTAL: 13238595235726 0 0 1 0 0 0 2868

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.binaries.usenet2day 134592
alt.binaries.games.encrypted 25501
alt.binaries.bloaf 4879
alt.binaries.frogs 3907
alt.binaries.backup 3020
alt.binaries.boneless 2869
alt.binaries.warez 2847
alt.binaries.ath 2646
alt.binaries.etc 2560
alt.binaries.ftn 1962
alt.binaries.multimedia.csi 1775
alt.binaries.roger 1761
alt.binaries.dvd.midnightmovies 1750
alt.binaries.hunters 1731
alt.binaries.svcd 1722
alt.binaries.comp 1643
alt.binaries.erotica 1608
alt.binaries.lou 1595
alt.binaries.encrypted 1577
alt.binaries.dream 1556
TOTAL: 212 235726

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

GroupsCount
soc.politics 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 923
EMP (md5) 793
EMP (phl) 219
Binary: misplaced binary fx03.ams4.POSTED 79
Binary: misplaced binary fx10.ams4.POSTED 72
Too many newsgroups 71
Binary: misplaced binary fx01.ams4.POSTED 70
Binary: misplaced binary fx12.ams4.POSTED 50
Binary: misplaced binary fx11.ams4.POSTED 43
Scoring filter 32
Binary: misplaced binary fx13.ams4.POSTED 29
Binary: misplaced binary fx05.ams4.POSTED 29
Binary: misplaced binary fx15.ams4.POSTED 25
Binary: misplaced binary fx04.ams4.POSTED 25
Binary: misplaced binary fx09.ams4.POSTED 24
Binary: misplaced binary fx14.ams4.POSTED 23
Binary: misplaced binary fx02.ams4.POSTED 21
Binary: misplaced binary fx08.ams4.POSTED 17
Binary: misplaced binary fx06.ams4.POSTED 13
hierarchy violation - crosspost outside sdnet 5
TOTAL: 23 2574

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
usenet.blueworldhosting.com 3
TOTAL: 1 3
Including strange strings
endofthelinebbs-out.news.weretis.net 254
newsfeed.xs3.de 9
outgoing.news.lysator.liu.se 4
endoftheline-out.news.chmurka.net 3
news.samoylyk.net 2
usenet.network 1
TOTAL: 6 273
TOTAL: 2 276

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 14231 4022 6770 12 0 110 28%22:07:21
2nyheter.lysator.liu.se 10459 2435 7399 344 0 3 23%22:07:21
3endofthelinebbs.peers.news.panix.com 11128 410 10313 83 0 0 3%22:07:20
4news.nk.ca 14255 100 10575 64 0 98 0%22:07:20
5news.bbs.nz 21100 68 10639 92 0 186 0%22:04:26
6news.furie.org.uk 7257 45 5402 108 0 72 0%22:07:25
7i2pn.org 10680 34 10418 2 0 0 0%22:07:20
8news.tnetconsulting.net 12209 29 10747 28 0 58 0%22:07:20
9news.quux.org 11408 28 10645 101 0 6 0%22:07:20
10news.samoylyk.net 15234 22 10753 3 0 488 0%22:07:21
11usenet.network 12694 21 9982 16 0 64 0%22:07:21
12news.weretis.net 11011 16 10725 59 0 0 0%22:07:20
13usenet.blueworldhosting.com 8511 3 7550 0 0 187 0%22:07:25
14newsfeed.xs3.de 12754 3 10269 153 0 40 0%22:07:21
15peer.alt119.net 0 0 0 0 0 10806 0%23:57:47
16endoftheline-in.news.chmurka.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 16 172931 7236 132187 1065 0 12118 4%333:47:48
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net18.1 MB50.3 KB18.1 MB0.2 KB/s4.6 KB22:07:21
2nyheter.lysator.liu.se11.1 MB3.5 MB14.5 MB0.2 KB/s5.4 KB22:07:21
3endofthelinebbs.peers.news.panix.com1.4 MB114.7 KB1.5 MB0.0 KB/s3.2 KB22:07:20
4news.furie.org.uk1.2 MB1.1 MB2.3 MB0.0 KB/s15.6 KB22:07:25
5news.quux.org842.0 KB1.3 MB2.1 MB0.0 KB/s16.7 KB22:07:20
6news.nk.ca398.6 KB1.4 MB1.8 MB0.0 KB/s11.5 KB22:07:20
7news.bbs.nz279.8 KB1.8 MB2.1 MB0.0 KB/s13.4 KB22:04:26
8news.tnetconsulting.net139.4 KB266.8 KB406.2 KB0.0 KB/s7.1 KB22:07:20
9i2pn.org139.1 KB9.1 KB148.2 KB0.0 KB/s4.1 KB22:07:20
10usenet.network88.1 KB81.9 KB170.0 KB0.0 KB/s4.6 KB22:07:21
11news.samoylyk.net68.3 KB17.8 KB86.1 KB0.0 KB/s3.4 KB22:07:21
12news.weretis.net48.1 KB1.6 MB1.6 MB0.0 KB/s21.8 KB22:07:20
13newsfeed.xs3.de17.1 KB1.4 MB1.4 MB0.0 KB/s9.2 KB22:07:21
14usenet.blueworldhosting.com4.2 KB0.0 KB4.2 KB0.0 KB/s1.4 KB22:07:25
15peer.alt119.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
16endoftheline-in.news.chmurka.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 1633.7 MB12.6 MB46.3 MB0.0 KB/s5.7 KB333:47:48
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

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

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 1 00.0 KB 3 0 000:00:00
2*.census.shodan.io 1 00.0 KB 0 0 000:00:00
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4*.monitoring.internet-measurement.com 2 00.0 KB 0 0 000:00:03
TOTAL: 4 5 00.0 KB 3 0 000:00:04

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
jovial.monitoring.internet-measurement.com 0.101 0.040 0.00000:00:01
dexterous.monitoring.internet-measurement.com 0.108 0.043 0.00000:00:01
red.census.shodan.io 0.118 0.031 0.00000:00:00
scanner-08.ch1.censys-scanner.com 0.101 0.045 0.00000:00:00
192.241.224.16 0.064 0.050 0.00000:00:00
TOTAL: 5 0.492 0.209 0.00000:00:04

Curious NNRP server explorers [Top 20]:

SystemConn
jovial.monitoring.internet-measurement.com 1
192.241.224.16 1
dexterous.monitoring.internet-measurement.com 1
scanner-08.ch1.censys-scanner.com 1
red.census.shodan.io 1
TOTAL: 5 5

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

SystemConn
192.241.224.16 1
scanner-08.ch1.censys-scanner.com 1
TOTAL: 2 2

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

CommandCount
EHLO www.censys.io 1
MGLNDD_192.138.210.162_119 1
TOTAL: 2 2

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1comp 3 75.0%
2news 1 25.0%
TOTAL: 2 4100.0%

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

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