Daily Usenet report: May 25 15:00:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

May 25 15:01:18 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
May 25 18:15:03 colo-sc-1 pgpverify[18532]: pgpverify: verification failed
May 25 18:15:07 colo-sc-1 pgpverify[18547]: pgpverify: verification failed
May 25 18:15:07 colo-sc-1 pgpverify[18555]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13951	76.3%	1.7 MB	73.5%
innd	2372	13.0%	345.4 KB	14.8%
innfeed	1221	6.7%	177.7 KB	7.6%
cnfsstat	576	3.1%	78.0 KB	3.3%
nnrpd	163	0.9%	17.1 KB	0.7%
controlchan	4	0.0%	0.3 KB	0.0%
pgpverify	3	0.0%	0.5 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 8	18291	100.0%	2.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	36639	43.0%
Negative hits	35008	41.1%
Do not exist	13538	15.9%
Cache misses	44	0.1%
TOTAL: 4	85229	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.611	0.0%	52	0.000	11.750	50.500
article cleanup	00:00:00.097	0.0%	13604	0.000	0.007	0.062
article logging	00:00:00.418	0.0%	13903	0.000	0.030	0.091
article parse	00:00:00.226	0.0%	26306	0.000	0.009	0.032
article write	00:00:00.855	0.0%	12684	0.000	0.067	2.589
artlog/artcncl	00:00:00.001	0.0%	51	0.000	0.020	1.000
data move	00:00:00.039	0.0%	92768	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.000	0.0%	52	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	52	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.435	0.0%	85637	0.000	0.017	0.992
history sync	00:00:00.018	0.0%	366	0.000	0.049	0.500
history write	00:00:02.117	0.0%	13602	0.000	0.156	4.492
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:18.627	100.0%	92753	530.688	931.707	3001.960
nntp read	00:00:00.625	0.0%	92590	0.000	0.007	0.019
overview write	00:00:00.043	0.0%	12684	0.000	0.003	0.021
perl filter	00:00:16.239	0.0%	13602	0.631	1.194	12.452
python filter	00:00:00.375	0.0%	13602	0.000	0.028	0.105
site send	00:00:02.174	0.0%	38050	0.024	0.057	0.135
TOTAL: 24:00:53.689	24:00:43.900	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	12645	0.000	0.002	0.025
article prepare	00:00:00.000	0.0%	625	0.000	0.000	0.000
article read	00:00:00.015	0.0%	387	0.000	0.039	0.500
backlog stats	00:00:00.041	0.0%	72521	0.000	0.001	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.315	0.0%	42856	0.000	0.007	0.022
data write	00:00:00.824	0.0%	30759	0.000	0.027	0.087
idle	23:57:06.230	100.0%	72521	661.103	1188.983	2917.976
status file	00:00:00.124	0.0%	243	0.000	0.510	14.750
TOTAL: 23:57:13.343	23:57:07.570	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:00.000	0.0%	0	0.000	0.000	0.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.001	0.1%	38	0.000	0.026	1.000
TOTAL: 00:00:01.506	00:00:00.001	0.1%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	3	0	0
TOTAL	0	0	0	3	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	11	24799	7758	16778	263	31%	49:03:21
2	news-out.aioe.org	3	12649	4932	7685	32	38%	24:15:41
3	news.nntp4.net	1	16504	254	15533	717	1%	28:43:39
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		16	53952	12944	39996	1012	23%	102:02:41

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	27.6 MB	1.2 KB	460.5 KB	28.0 MB	98%	3.6 KB
2	news-out.aioe.org	13.5 MB	0.0 KB	54.9 KB	13.5 MB	99%	2.8 KB
3	news.nntp4.net	1.5 MB	2.1 KB	2.6 MB	4.2 MB	36%	4.4 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		42.5 MB	3.3 KB	3.1 MB	45.7 MB	93%	3.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 25 15:00:01 - 15:59:59	812	6.4%	0.23	2.9 MB	6.9%	0.81
May 25 16:00:00 - 16:59:59	666	5.3%	0.18	1.9 MB	4.6%	0.54
May 25 17:00:00 - 17:59:59	541	4.3%	0.15	1.6 MB	3.7%	0.44
May 25 18:00:00 - 18:59:59	615	4.8%	0.17	2.4 MB	5.8%	0.68
May 25 19:00:00 - 19:59:59	666	5.3%	0.18	2.4 MB	5.7%	0.68
May 25 20:00:00 - 20:59:59	489	3.9%	0.14	1.8 MB	4.3%	0.51
May 25 21:00:00 - 21:59:59	471	3.7%	0.13	1.4 MB	3.5%	0.41
May 25 22:00:00 - 22:59:59	422	3.3%	0.12	1.7 MB	4.0%	0.48
May 25 23:00:00 - 23:59:59	373	2.9%	0.10	1.6 MB	3.8%	0.45
May 26 00:00:00 - 00:59:59	323	2.5%	0.09	2.1 MB	5.0%	0.59
May 26 01:00:00 - 01:59:59	260	2.0%	0.07	1.2 MB	2.8%	0.34
May 26 02:00:00 - 02:59:59	248	2.0%	0.07	1006.8 KB	2.4%	0.28
May 26 03:00:00 - 03:59:59	229	1.8%	0.06	1.1 MB	2.7%	0.32
May 26 04:00:00 - 04:59:59	269	2.1%	0.07	1.1 MB	2.6%	0.31
May 26 05:00:00 - 05:59:59	390	3.1%	0.11	1.2 MB	2.9%	0.35
May 26 06:00:00 - 06:59:59	489	3.9%	0.14	1.3 MB	3.0%	0.36
May 26 07:00:00 - 07:59:59	491	3.9%	0.14	2.0 MB	4.7%	0.56
May 26 08:00:00 - 08:59:59	618	4.9%	0.17	1.9 MB	4.5%	0.53
May 26 09:00:00 - 09:59:59	673	5.3%	0.19	1.8 MB	4.2%	0.50
May 26 10:00:00 - 10:59:59	706	5.6%	0.20	1.8 MB	4.4%	0.53
May 26 11:00:00 - 11:59:59	612	4.8%	0.17	1.5 MB	3.7%	0.44
May 26 12:00:00 - 12:59:59	813	6.4%	0.23	2.2 MB	5.3%	0.63
May 26 13:00:00 - 13:59:59	767	6.0%	0.21	2.1 MB	5.0%	0.59
May 26 14:00:00 - 14:59:59	729	5.7%	0.20	1.8 MB	4.4%	0.52
May 26 15:00:00 - 15:00:03	13	0.1%	4.33	48.0 KB	0.1%	16.01
TOTAL: 24:00:02	12685	100.0%	0.15	41.7 MB	100.0%	0.49

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	108.7 MB	10.6%	7	0.20	59.37
ONE	DEFAULT	1024.0 MB	283.5 MB	27.7%	9	0.20	59.50
THREE	DEFAULT	1024.0 MB	119.0 MB	11.6%	7	0.20	61.86
TWO	DEFAULT	1024.0 MB	109.0 MB	10.6%	7	0.20	60.97
TOTAL: 4	-	4.0 GB	620.2 MB	15.1%	30	0.20	-

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nntp4.net	629	16	1	1	611
2	hasname-out.news.weretis.net	481	12	1	0	468
3	news-out.aioe.org	105	1	0	0	104
TOTAL: 3		1215	29	2	1	1183

Unwanted newsgroups [Top 20]:

Newsgroup	Count
bofh.transport.public	8
fr.misc.domotique	8
gun.general	5
comp.infosystems.gemini	3
alt.ads	1
ga.jobs	1
fr.comp.os.mac-os.serveurs	1
alt.business.import-export.biz.marketplace.international	1
alt.biz.misc	1
TOTAL: 9	29

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	417
EMP (md5)	270
EMP (phn nph)	147
EMP (phl)	42
Scoring filter	5
HTML Multipart	4
TOTAL: 6	885

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	220
	news-out.aioe.org	73
	news.nntp4.net	5
	TOTAL: 3	298
TOTAL: 1		298

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	315	315	0	0	0	100%	17:58:21
2	aioe	9165	43	6825	15	4	0%	23:59:52
3	nntp4	14869	3	12458	0	1	0%	23:59:52
4	weretis	6086	1	5041	16	1	0%	23:59:54
TOTAL: 4		30435	362	24324	31	6	1%	89:57:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	3.0 MB	0.0 KB	3.0 MB	9.9 KB	17:58:21
2	aioe	149.1 KB	75.8 KB	224.8 KB	3.9 KB	23:59:52
3	nntp4	4.2 KB	0.0 KB	4.2 KB	1.4 KB	23:59:52
4	weretis	1.0 KB	131.7 KB	132.7 KB	7.8 KB	23:59:54
TOTAL: 4		3.2 MB	207.5 KB	3.4 MB	8.8 KB	89:57:59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
178-79-148-229.ip.linodeusercontent.com	0.029	0.030	00:00:01
165.22.16.163	0.009	0.011	00:00:00
192.241.209.59	0.000	0.002	00:00:00
192.241.209.73	0.002	0.001	00:00:00
tor-exit3.riverside.rocks	0.002	0.001	00:00:00
175.198.181.162	0.002	0.000	00:00:00
TOTAL: 6	0.044	0.045	00:00:01

NNRP no permission clients:

System	Conn
178-79-148-229.ip.linodeusercontent.com	26
165.22.16.163	8
tor-exit3.riverside.rocks	1
175.198.181.162	1
192.241.209.59	1
192.241.209.73	1
TOTAL: 6	38

Daily Usenet report

May 25 15:00:01 -- May 26 15:00:03