Daily Usenet report: Oct 8 15:00:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 8 15:01:10 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	44155	66.4%	6.4 MB	66.7%
innd	19840	29.8%	2.8 MB	29.5%
innfeed	1244	1.9%	188.9 KB	1.9%
cnfsstat	1152	1.7%	177.5 KB	1.8%
nnrpd	80	0.1%	9.0 KB	0.1%
controlchan	2	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	66474	100.0%	9.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	94776	41.6%
Positive hits	89070	39.1%
Do not exist	43915	19.3%
Cache misses	62	0.0%
TOTAL: 4	227823	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.031	0.0%	59	0.000	0.525	1.778
article cleanup	00:00:00.282	0.0%	44142	0.000	0.006	0.030
article logging	00:00:01.304	0.0%	44148	0.006	0.030	0.195
article parse	00:00:00.900	0.0%	111403	0.001	0.008	0.021
article write	00:00:01.421	0.0%	27452	0.009	0.052	0.142
artlog/artcncl	00:00:00.000	0.0%	58	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:00.206	0.0%	307316	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.000	0.0%	58	0.000	0.000	0.000
hishave/artcncl	00:00:00.001	0.0%	59	0.000	0.017	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.720	0.0%	229008	0.000	0.008	0.101
history sync	00:00:00.009	0.0%	314	0.000	0.029	0.500
history write	00:00:03.503	0.0%	44143	0.012	0.079	5.195
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:59:18.146	99.9%	286855	164.597	301.052	916.157
nntp read	00:00:02.120	0.0%	286098	0.001	0.007	0.014
overview write	00:00:00.108	0.0%	27452	0.000	0.004	0.020
perl filter	00:00:42.167	0.0%	44141	0.593	0.955	1.264
python filter	00:00:00.951	0.0%	44141	0.000	0.022	0.055
site send	00:00:04.049	0.0%	82356	0.024	0.049	0.077
TOTAL: 24:00:39.656	24:00:16.918	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.059	0.0%	27307	0.000	0.002	0.022
article prepare	00:00:00.007	0.0%	20568	0.000	0.000	0.010
article read	00:00:00.328	0.0%	9211	0.000	0.036	0.127
backlog stats	00:00:00.109	0.0%	171019	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.752	0.0%	107821	0.000	0.007	0.015
data write	00:00:02.331	0.0%	80680	0.000	0.029	0.109
idle	23:54:37.064	100.0%	171019	246.838	503.319	1442.514
status file	00:00:00.078	0.0%	161	0.000	0.484	3.077
TOTAL: 23:54:45.550	23:54:40.728	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:06.906	95.5%	532	255.000	464.109	10737.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.052	0.0%	2113	0.000	0.025	0.091
readart	00:00:05.471	2.1%	501	0.000	10.920	19.930
TOTAL: 00:04:18.523	00:04:12.429	97.6%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	147
name	2
pause	2
paused	2
reserve	2
TOTAL: 10	167

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	7	73627	20817	42769	10041	28%	49:26:35
2	usenet.blueworldhosting.com	720	22493	3686	14498	4309	16%	23:46:30
3	news.nntp4.net	4	38899	3038	33537	2324	7%	24:25:12
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		732	135019	27541	90804	16674	20%	97:38:17

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	117.4 MB	56.3 MB	173.7 MB	67%	5.8 KB
2	usenet.blueworldhosting.com	21.1 MB	27.9 MB	49.0 MB	43%	6.3 KB
3	news.nntp4.net	18.0 MB	11.5 MB	29.5 MB	60%	5.6 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		156.5 MB	95.7 MB	252.3 MB	62%	5.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 8 15:00:01 - 15:59:59	2082	7.6%	0.58	13.7 MB	8.8%	3.89
Oct 8 16:00:00 - 16:59:59	1995	7.3%	0.55	12.4 MB	8.0%	3.53
Oct 8 17:00:00 - 17:59:59	1521	5.6%	0.42	9.6 MB	6.1%	2.73
Oct 8 18:00:00 - 18:59:59	1296	4.7%	0.36	8.3 MB	5.3%	2.37
Oct 8 19:00:00 - 19:59:59	1636	6.0%	0.45	11.0 MB	7.1%	3.14
Oct 8 20:00:00 - 20:59:59	2079	7.6%	0.58	11.9 MB	7.6%	3.38
Oct 8 21:00:00 - 21:59:59	1362	5.0%	0.38	8.6 MB	5.5%	2.45
Oct 8 22:00:00 - 22:59:59	901	3.3%	0.25	5.4 MB	3.4%	1.53
Oct 8 23:00:00 - 23:59:59	1346	4.9%	0.37	9.9 MB	6.3%	2.81
Oct 9 00:00:00 - 00:59:59	1405	5.1%	0.39	9.5 MB	6.1%	2.72
Oct 9 01:00:00 - 01:59:59	521	1.9%	0.14	3.2 MB	2.1%	0.91
Oct 9 02:00:00 - 02:59:59	550	2.0%	0.15	2.6 MB	1.7%	0.74
Oct 9 03:00:00 - 03:59:59	502	1.8%	0.14	2.3 MB	1.5%	0.65
Oct 9 04:00:00 - 04:59:59	696	2.5%	0.19	3.1 MB	2.0%	0.88
Oct 9 05:00:00 - 05:59:59	573	2.1%	0.16	2.9 MB	1.8%	0.81
Oct 9 06:00:00 - 06:59:59	671	2.5%	0.19	2.4 MB	1.5%	0.68
Oct 9 07:00:00 - 07:59:59	1036	3.8%	0.29	4.7 MB	3.0%	1.32
Oct 9 08:00:00 - 08:59:59	928	3.4%	0.26	4.4 MB	2.8%	1.26
Oct 9 09:00:00 - 09:59:59	971	3.5%	0.27	4.0 MB	2.6%	1.13
Oct 9 10:00:00 - 10:59:59	1163	4.2%	0.32	6.0 MB	3.8%	1.70
Oct 9 11:00:00 - 11:59:59	1051	3.8%	0.29	4.7 MB	3.0%	1.35
Oct 9 12:00:00 - 12:59:59	1078	3.9%	0.30	5.3 MB	3.4%	1.52
Oct 9 13:00:00 - 13:59:59	1001	3.7%	0.28	5.4 MB	3.4%	1.53
Oct 9 14:00:00 - 14:59:59	1022	3.7%	0.28	4.6 MB	2.9%	1.30
Oct 9 15:00:00 - 15:00:02	1	0.0%	0.50	2.5 KB	0.0%	1.24
TOTAL: 24:00:01	27387	100.0%	0.32	155.8 MB	100.0%	1.85

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
EIGHT	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
FIVE	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
FOUR	DEFAULT	1024.0 MB	154.7 MB	15.1%	24	0.63	19.38
ONE	DEFAULT	1024.0 MB	317.2 MB	31.0%	26	0.62	19.53
SEVEN	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
SIX	BIG	4.0 GB	2.9 GB	72.8%	1	0.00	0.00
THREE	DEFAULT	1024.0 MB	161.3 MB	15.8%	24	0.63	19.36
TWO	DEFAULT	1024.0 MB	150.1 MB	14.7%	24	0.63	19.28
TOTAL: 8	-	20.0 GB	12.4 GB	61.9%	102	0.63	-

Sites sending bad articles:

Server		Total	Group	Other
1	hasname-out.news.weretis.net	10059	18	10041
2	usenet.blueworldhosting.com	4313	1097	3216
3	news.nntp4.net	2338	19	2319
TOTAL: 3		16710	1134	15576

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	221
kraft.politics	131
kraft.vehicles	98
de.sci.announce	98
fido7.pushkin.local	82
de.sci.medizin	77
fido7.fidonet.online	71
kraft.talks	52
kraft.competent	46
linux.kernel.git	43
fido7.ukrnews	37
fido7.starper.limited	32
odessa.news	26
local.general	21
perl.cpan	17
fido7.lorapvt.horo	12
adobe.illustrator.macintosh	10
fido7.f715.techinfo	6
fido.pushkin.local	4
rocksolid.feeds.news	3
TOTAL: 51	1134

INND Perl filter [Top 20]:

Reason	Count
Reject Google Groups posting to comp.lang.c	7372
Reject Google Groups posting to sci.crypt	6589
EMP (md5)	1079
Too many newsgroups	382
EMP (phl)	60
Reject Google Groups posting to comp.arch	43
Too many newsgroups (meow)	14
Scoring filter	13
HTML Multipart	9
EMP (phn path)	6
TOTAL: 10	15567

INND no permission servers:

System	Conn
194.165.16.10	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	7
	TOTAL: 1	7
No colon-space in header
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 2		8

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	6888	6888	0	0	0	100%	23:59:57
2	nntp4	27591	2414	21962	39	0	8%	23:59:56
3	weretis	9445	302	5488	685	0	3%	23:59:57
4	aioe	0	0	0	0	27213	0%	23:59:57
5	blueworldhosting	26730	0	22913	0	0	0%	23:59:57
TOTAL: 5		70654	9604	50363	724	27213	13%	119:59:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	bbsgslincom	42.0 MB	0.0 KB	42.0 MB	0.5 KB/s	6.2 KB	23:59:57
2	nntp4	14.2 MB	110.4 KB	14.4 MB	0.2 KB/s	6.0 KB	23:59:56
3	weretis	1.7 MB	4.5 MB	6.2 MB	0.1 KB/s	6.4 KB	23:59:57
4	aioe	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
5	blueworldhosting	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
TOTAL: 5		58.0 MB	4.6 MB	62.6 MB	0.1 KB/s	6.2 KB	119:59:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	220-134-66-225.hinet-ip.hinet.net	7	501	5.3 MB	6	0	0	00:03:45
TOTAL: 1		7	501	5.3 MB	6	0	0	00:04:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.hinet-ip.hinet.net	8	501	5.3 MB	6	0	0	00:04:17
TOTAL: 1		8	501	5.3 MB	6	0	0	00:04:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
220-134-66-225.hinet-ip.hinet.net	0.042	0.167	00:03:45
114-34-121-114.hinet-ip.hinet.net	0.001	0.003	00:00:32
118.194.250.31	0.003	0.004	00:00:00
142.170.104.150	0.003	0.000	00:00:00
107.170.232.50	0.000	0.003	00:00:00
192.241.231.10	0.002	0.001	00:00:00
107.170.252.18	0.001	0.003	00:00:00
TOTAL: 7	0.052	0.181	00:04:18

Curious NNRP server explorers [Top 20]:

System	Conn
114-34-121-114.hinet-ip.hinet.net	1
TOTAL: 1	1

NNRP no permission clients:

System	Conn
118.194.250.31	3
107.170.232.50	1
142.170.104.150	1
107.170.252.18	1
192.241.231.10	1
TOTAL: 5	7

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	501	100.0%
TOTAL: 1		501	100.0%

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

Newsgroup		Count
1	comp.arch	501
TOTAL: 1		501

Daily Usenet report

Oct 8 15:00:01 -- Oct 9 15:00:02