Daily Usenet report

Jun 4 00:00:00 -- Jun 6 00:00:00

Unknown entries from news log file:

First 50 / 2055 lines (2.4%)

Jun 4 00:43:46 spool1 nnrpd[1093]: 10.1.1.20 noperm ihave without permission
Jun 4 00:44:16 spool1 nnrpd[74888]: 10.1.1.20 noperm ihave without permission
Jun 4 00:44:47 spool1 nnrpd[77440]: 10.1.1.20 noperm ihave without permission
Jun 4 00:45:18 spool1 nnrpd[79192]: 10.1.1.20 noperm ihave without permission
Jun 4 00:45:48 spool1 nnrpd[79835]: 10.1.1.20 noperm ihave without permission
Jun 4 00:46:18 spool1 nnrpd[80178]: 10.1.1.20 noperm ihave without permission
Jun 4 00:46:48 spool1 nnrpd[80809]: 10.1.1.20 noperm ihave without permission
Jun 4 00:47:18 spool1 nnrpd[83571]: 10.1.1.20 noperm ihave without permission
Jun 4 00:47:48 spool1 nnrpd[83645]: 10.1.1.20 noperm ihave without permission
Jun 4 00:48:19 spool1 nnrpd[84300]: 10.1.1.20 noperm ihave without permission
Jun 4 00:48:49 spool1 nnrpd[90393]: 10.1.1.20 noperm ihave without permission
Jun 4 00:49:20 spool1 nnrpd[91966]: 10.1.1.20 noperm ihave without permission
Jun 4 00:49:50 spool1 nnrpd[92983]: 10.1.1.20 noperm ihave without permission
Jun 4 00:50:20 spool1 nnrpd[93863]: 10.1.1.20 noperm ihave without permission
Jun 4 00:50:50 spool1 nnrpd[94503]: 10.1.1.20 noperm ihave without permission
Jun 4 00:51:20 spool1 nnrpd[94831]: 10.1.1.20 noperm ihave without permission
Jun 4 00:51:50 spool1 nnrpd[96194]: 10.1.1.20 noperm ihave without permission
Jun 4 00:52:20 spool1 nnrpd[5019]: 10.1.1.20 noperm ihave without permission
Jun 4 00:52:51 spool1 nnrpd[5641]: 10.1.1.20 noperm ihave without permission
Jun 4 00:53:21 spool1 nnrpd[8913]: 10.1.1.20 noperm ihave without permission
Jun 4 00:53:51 spool1 nnrpd[13887]: 10.1.1.20 noperm ihave without permission
Jun 4 00:54:21 spool1 nnrpd[74282]: 10.1.1.20 noperm ihave without permission
Jun 4 00:54:51 spool1 nnrpd[74910]: 10.1.1.20 noperm ihave without permission
Jun 4 00:55:21 spool1 nnrpd[77674]: 10.1.1.20 noperm ihave without permission
Jun 4 00:55:51 spool1 nnrpd[78290]: 10.1.1.20 noperm ihave without permission
Jun 4 00:56:21 spool1 nnrpd[78890]: 10.1.1.20 noperm ihave without permission
Jun 4 00:56:51 spool1 nnrpd[78982]: 10.1.1.20 noperm ihave without permission
Jun 4 00:57:22 spool1 nnrpd[79646]: 10.1.1.20 noperm ihave without permission
Jun 4 00:57:52 spool1 nnrpd[79728]: 10.1.1.20 noperm ihave without permission
Jun 4 00:58:22 spool1 nnrpd[80228]: 10.1.1.20 noperm ihave without permission
Jun 4 00:58:52 spool1 nnrpd[80511]: 10.1.1.20 noperm ihave without permission
Jun 4 00:59:22 spool1 nnrpd[83324]: 10.1.1.20 noperm ihave without permission
Jun 4 00:59:52 spool1 nnrpd[83980]: 10.1.1.20 noperm ihave without permission
Jun 4 01:00:23 spool1 nnrpd[89623]: 10.1.1.20 noperm ihave without permission
Jun 4 01:00:53 spool1 nnrpd[90310]: 10.1.1.20 noperm ihave without permission
Jun 4 01:01:24 spool1 nnrpd[91578]: 10.1.1.20 noperm ihave without permission
Jun 4 01:01:54 spool1 nnrpd[92173]: 10.1.1.20 noperm ihave without permission
Jun 4 01:02:25 spool1 nnrpd[92424]: 10.1.1.20 noperm ihave without permission
Jun 4 01:02:55 spool1 nnrpd[92629]: 10.1.1.20 noperm ihave without permission
Jun 4 01:03:25 spool1 nnrpd[93190]: 10.1.1.20 noperm ihave without permission
Jun 4 01:03:55 spool1 nnrpd[93553]: 10.1.1.20 noperm ihave without permission
Jun 4 01:04:25 spool1 nnrpd[75853]: 10.1.1.20 noperm ihave without permission
Jun 4 01:04:56 spool1 nnrpd[76119]: 10.1.1.20 noperm ihave without permission
Jun 4 01:05:27 spool1 nnrpd[77424]: 10.1.1.20 noperm ihave without permission
Jun 4 01:05:57 spool1 nnrpd[78065]: 10.1.1.20 noperm ihave without permission
Jun 4 01:06:27 spool1 nnrpd[78881]: 10.1.1.20 noperm ihave without permission
Jun 4 01:06:57 spool1 nnrpd[78901]: 10.1.1.20 noperm ihave without permission
Jun 4 01:07:27 spool1 nnrpd[79394]: 10.1.1.20 noperm ihave without permission
Jun 4 01:07:57 spool1 nnrpd[80126]: 10.1.1.20 noperm ihave without permission
Jun 4 01:08:28 spool1 nnrpd[80148]: 10.1.1.20 noperm ihave without permission

Log entries by program:

Program nameLines%LinesSize%Size
innd 628516 57.9%69.5 MB 53.7%
inn 233165 21.5%28.2 MB 21.8%
nnrpd 223729 20.6%31.7 MB 24.5%
innfeed 822 0.1%91.1 KB 0.1%
controlchan 28 0.0%4.2 KB 0.0%
nocem 11 0.0%0.8 KB 0.0%
innbind 2 0.0%0.2 KB 0.0%
rnews 1 0.0%0.1 KB 0.0%
TOTAL: 81086274 100.0%129.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 230405 51.0%
Negative hits 220458 48.8%
Cache misses 396 0.1%
Positive hits 121 0.0%
TOTAL: 4 451380 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.527 0.0%108 0.000 14.139 117.000
article cleanup00:00:01.400 0.0%230619 0.000 0.006 0.049
article logging00:00:11.611 0.0%230811 0.000 0.050 2.857
article parse00:01:13.602 0.1%4720589 0.000 0.016 0.031
article write00:08:28.152 0.4%227876 0.135 2.230 96.062
artlog/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
data move00:00:09.929 0.0%4937137 0.000 0.002 0.022
hisgrep/artcncl00:00:00.283 0.0%126 0.000 2.246 20.000
hishave/artcncl00:00:00.000 0.0%18 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:03:57.665 0.2%451834 0.000 0.526 7.564
history sync00:00:15.017 0.0%637 0.000 23.575 142.333
history write00:19:02.255 0.8%230619 0.584 4.953 15.607
idle37:10:58.971 96.1%4819909 1.840 27.772 9816.016
nntp read00:00:51.501 0.0%4932899 0.000 0.010 0.050
overview write00:05:35.194 0.2%227876 0.145 1.471 80.000
perl filter00:00:12.384 0.0%228591 0.000 0.054 0.270
python filter00:48:31.690 2.1%438114 0.522 6.646 36.073
site send00:00:07.819 0.0%433940 0.000 0.018 0.070
TOTAL: 38:42:03.21138:39:39.000 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.424 0.0%206621 0.000 0.002 0.013
article prepare00:00:00.169 0.0%823672 0.000 0.000 0.001
article read00:00:22.134 0.0%205918 0.068 0.107 0.334
backlog stats00:00:00.673 0.0%2375700 0.000 0.000 0.038
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:04.401 0.0%410895 0.000 0.011 0.024
data write00:05:10.317 0.2%1962420 0.000 0.158 0.236
idle38:25:04.378 99.7%2375700 3.397 58.216 25070.792
status file00:00:00.205 0.0%129 0.000 1.589 10.000
TOTAL: 38:30:59.36038:30:42.701100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:42.314 0.0%5310 0.000 7.969 61.000
idle00:30:35.194 0.7%8301653 0.000 0.221 12102.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite06:36:29.923 9.7%754100 0.000 31.547 3409.012
readart00:00:13.482 0.0%5355 0.000 2.518 71.000
TOTAL: 68:02:02.05307:08:00.913 10.5%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
mode 239
name 2
newgroup 1
pause 2
paused 2
reload 10
reserve 2
rmgroup 5
shutdown 9
TOTAL: 13 283

Newsgroups created:

GroupMode
alt.binaries.multimediay
TOTAL1

Newsgroups removed:

Group
fr.comp.lang.basic
fr.comp.lang.pascal
fr.comp.sys.next
fr.comp.sys.sun
fr.usenet.8bits
TOTAL: 5

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@usenet-fr.news.eu.org051066
TOTAL051066

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1localhost208301 209742 209532 0 210 99%02:11:31
2feed1.usenet.blueworldhosting.com 15 24150 20757 856 2537 85%42:41:25
TOTAL: 2208316 233892 230289 856 2747 98%44:52:56
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1localhost32.2 GB0.0 KB1.5 MB32.2 GB 99%160.9 KB
2feed1.usenet.blueworldhosting.com76.9 MB0.0 KB24.1 MB101.1 MB 76%4.4 KB
TOTAL: 232.3 GB0.0 KB25.7 MB32.3 GB 99%145.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 4 00:00:00 - 00:59:59 4065 1.8% 1.13864.5 MB 0.0% 245.90
Jun 4 01:00:00 - 01:59:59 21666 9.4% 6.021.7 GB 0.0% 500.61
Jun 4 02:00:00 - 02:59:59 22197 9.6% 6.172.5 GB 0.0% 738.58
Jun 4 03:00:00 - 03:59:59 388 0.2% 0.111.1 MB 0.0% 0.33
Jun 4 04:00:00 - 04:59:59 499 0.2% 0.141.3 MB 0.0% 0.37
Jun 4 05:00:00 - 05:59:59 539 0.2% 0.151.6 MB 0.0% 0.46
Jun 4 06:00:00 - 06:59:59 489 0.2% 0.141.7 MB 0.0% 0.47
Jun 4 07:00:00 - 07:59:59 489 0.2% 0.141.4 MB 0.0% 0.40
Jun 4 08:00:00 - 08:59:59 496 0.2% 0.141.4 MB 0.1% 0.40
Jun 4 09:00:00 - 09:59:59 608 0.3% 0.173.1 MB 0.2% 0.88
Jun 4 10:00:00 - 10:59:59 532 0.2% 0.151.6 MB 0.1% 0.44
Jun 4 11:00:00 - 11:59:59 590 0.3% 0.161.8 MB 0.1% 0.51
Jun 4 12:00:00 - 12:59:59 548 0.2% 0.151.5 MB 0.1% 0.43
Jun 4 13:00:00 - 13:59:59 527 0.2% 0.151.5 MB 0.1% 0.42
Jun 4 14:00:00 - 14:59:59 603 0.3% 0.172.5 MB 0.1% 0.71
Jun 4 15:00:00 - 15:59:59 517 0.2% 0.141.5 MB 0.1% 0.43
Jun 4 16:00:00 - 16:59:59 413 0.2% 0.112.9 MB 0.1% 0.82
Jun 4 17:00:00 - 17:59:59 366 0.2% 0.101.5 MB 0.1% 0.44
Jun 4 18:00:00 - 18:59:59 6448 2.8% 1.794.3 GB 214.4%1238.46
Jun 4 19:00:00 - 19:59:59 6898 3.0% 1.924.6 GB 232.5%1342.66
Jun 4 20:00:00 - 20:59:59 1915 0.8% 0.531.0 GB 51.4% 296.80
Jun 4 21:00:00 - 21:59:59 4977 2.2% 1.383.2 GB 163.4% 943.76
Jun 4 22:00:00 - 22:59:59 3398 1.5% 0.942.2 GB 109.7% 633.42
Jun 4 23:00:00 - 23:59:59 3065 1.3% 0.852.0 GB 103.1% 595.23
Jun 5 00:00:00 - 00:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 01:00:00 - 01:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 02:00:00 - 02:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 03:00:00 - 03:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 04:00:00 - 04:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 05:00:00 - 05:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 06:00:00 - 06:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 07:00:00 - 07:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jun 5 08:00:00 - 08:59:59 4230 1.8% 1.1814.6 MB 0.7% 4.16
Jun 5 09:00:00 - 09:59:59 529 0.2% 0.152.0 MB 0.1% 0.56
Jun 5 10:00:00 - 10:59:59 650 0.3% 0.181.9 MB 0.1% 0.55
Jun 5 11:00:00 - 11:59:59 632 0.3% 0.183.6 MB 0.2% 1.03
Jun 5 12:00:00 - 12:59:59 589 0.3% 0.162.2 MB 0.1% 0.62
Jun 5 13:00:00 - 13:59:59 590 0.3% 0.162.0 MB 0.1% 0.58
Jun 5 14:00:00 - 14:59:59 533 0.2% 0.151.9 MB 0.1% 0.55
Jun 5 15:00:00 - 15:59:59 529 0.2% 0.151.7 MB 0.1% 0.47
Jun 5 16:00:00 - 16:59:59 499 0.2% 0.141.4 MB 0.1% 0.41
Jun 5 17:00:00 - 17:59:59 382 0.2% 0.111.5 MB 0.1% 0.43
Jun 5 18:00:00 - 18:59:59 279 0.1% 0.081.5 MB 0.1% 0.44
Jun 5 19:00:00 - 19:59:59 231 0.1% 0.06772.8 KB 0.0% 0.21
Jun 5 20:00:00 - 20:59:59 234 0.1% 0.071.4 MB 0.1% 0.39
Jun 5 21:00:00 - 21:59:59 28610 12.4% 7.952.0 GB 100.0% 577.55
Jun 5 22:00:00 - 22:59:59 62323 27.1% 17.312.9 GB 143.8% 830.38
Jun 5 23:00:00 - 23:59:59 47136 20.5% 13.094.9 GB 248.5%1435.35
Jun 6 00:00:00 - 00:00:00 1 0.0% 0.0033.8 KB 0.0% 0.00
TOTAL: 48:00:00 230210 100.0% 1.332.0 GB 100.0% 12.03
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1feed1.usenet.blueworldhosting.com 2728 721 0 0 2 0 0 0 2005
2localhost 209 0 0 0 0 0 0 0 209
TOTAL: 2 2937 721 0 0 2 0 0 0 2214

Unwanted newsgroups [Top 20]:

NewsgroupCount
talk.ukrainian.tg 472
talk.english.tg 122
talk.english.tw 46
talk.generic.ua.ws 45
ee.arvutid 6
talk.from.diary 4
talk.from.war.diary 4
news.oriens-occasus.ru 2
free.it.satellite.dreambox 2
alt.sex.fetish.feet.toes 1
ncku.env 1
trial.soc.culture.czechoslovak 1
Trump-targeted 1
gun.general 1
alt.culture.ny 1
"alt.fan.rush-limbaurgh" 1
wolfsburg.test 1
soc.culture.russia 1
alt.culture.australia 1
a.b.classic.tv.shows 1
TOTAL: 27 721

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

GroupsCount
bit.listserv.catholic 2
TOTAL: 1 2

INND Python filter [Top 20]:

ReasonCount
EMP Body Reject 944
Bad From (Test User <test-user@nospam.example>) 576
EMP PHN Reject 265
EMP PHL Reject 187
Bad Subject (echolot ping - ignore) 21
Binary (yEnc) 11
Bad Subject (MA_=23FININVEST=21_=C3=89_NAZI=E5=8D=8DP) 3
Bad Subject (Microsofts Merkmale =?ISO-8859-1?Q?f=FCr?= P) 3
Bad Subject (M8J+YjPCfmIzwn5iM8J+YjPCfmIzwn5iM8J+YjP) 2
Bad Subject (Ministero delle Finanze NON FUNZIONA =?iso-8859-1?q?P) 2
HTML Misplaced 1
Bad Subject (MjM45Y+w5Lit5aSn6YeM5Y2A5Y+r5bCP5aeQ6YCi?= =?UTF-8?B?55Sy5a2455Sf5aa56bq76LGG5bCP5qih5YW86IG35Y+w5Lit5aSn6YeM5Y+r5bCP5aeQ5Y+w5Lit5aSn?= =?UTF-8?B?6YeM5Y2A5aSW6YCB6Iy26I6K6LO0ZmIyMjM45Y+w5 1
Crosspost Low Limit Exceeded 1
Bad Subject (MCUgVU5ERVRFQ1RBQkxFIENPVU5URVJGRUlUIE1P) 1
Binary (base64) 1
Bad Subject (M5rS75Yqo57qq5b+14oCc5YWt5Zub4oCd5q276Zq+6ICF5Lul5Y+K4oCc5pu+57uP) 1
Binary (uuEnc) 1
Bad Subject (MINALISSIMA_=23FININVEST=21_=C3=89_NAZI=E5=8D=8DP) 1
TOTAL: 18 2022

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
feed1.usenet.blueworldhosting.com 192
TOTAL: 1 192
TOTAL: 1 192

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1feed1.usenet.blueworldhosting.com 208309 208290 0 0 0 703 99%08:21:27
TOTAL: 1 208309 208290 0 0 0 703 99%08:21:27
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1feed1.usenet.blueworldhosting.com32.2 GB0.0 KB32.2 GB1.1 MB/s162.0 KB08:21:27
TOTAL: 132.2 GB0.0 KB32.2 GB1.1 MB/s162.0 KB08:21:27
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

ReasonConn
What server? 1
TOTAL: 1 1

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1023-084-030-207.res.spectrum.com 288 53553.6 GB 2936718 052:16:04
2localhost 6 00.0 KB 028000 000:38:41
3192.168.1.146 296 00.0 KB 0142783 1614:58:02
TOTAL: 3 590 53553.6 GB 29207501 1668:01:46

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 288 53553.6 GB 2936718 052:16:04
2*.stehlik.co.uk 7 00.0 KB 0 0 000:03:59
3unresolved2330 00.0 KB 0142783 1615:02:59
4? 6 00.0 KB 028000 000:38:41
TOTAL: 42631 53553.6 GB 29207501 1668:01:46

NNRP auth users [Top 20]:

UserConn
jesse 581
0.052 system 0.007 idle 0.000 elapsed 0.060 1
0.044 system 0.015 idle 0.000 elapsed 0.059 1
0.061 system 0.000 idle 0.000 elapsed 0.191 1
TOTAL: 4 584

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
023-084-030-207.res.spectrum.com 563.889 422.982 7.69352:16:04
192.168.1.146 503.302 392.537 10.69514:58:02
localhost 38.252 30.155 0.00000:38:41
toma-03.stehlik.co.uk 0.440 0.106 0.00000:03:59
10.1.1.20 114.796 37.232 0.00000:03:01
50.83.128.198 0.647 0.194 0.00000:01:54
104.156.155.25 0.073 0.015 0.00000:00:01
104.156.155.22 0.063 0.028 0.00000:00:00
TOTAL: 8 1221.462 883.249 18.38868:01:46

Curious NNRP server explorers [Top 20]:

SystemConn
10.1.1.20 2028
toma-03.stehlik.co.uk 7
50.83.128.198 4
104.156.155.22 1
104.156.155.25 1
TOTAL: 5 2041

NNRP client timeouts [Top 20]:

SystemConnPeer
192.168.1.146 257 257
023-084-030-207.res.spectrum.com 117 104
localhost 2 2
TOTAL: 3 376 364

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1alt 106 93.0%
2muc 4 3.5%
3news 4 3.5%
TOTAL: 3 114100.0%

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

NewsgroupCount
1alt.binaries.multimedia 85
2alt.test 14
3alt.folklore.computers 7
4muc.lists.freebsd.bugs 4
5news.answers 3
6news.groups 1
TOTAL: 6 114
innreport from INN 2.7.0 (rc1 version) based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then