Daily Usenet report: Nov 30 15:00:03

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Nov 30 15:00:47 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Dec 1 08:21:58 colo-sc-1 pgpverify[10550]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	13383	75.0%	1.6 MB	72.7%
innd	2536	14.2%	354.6 KB	15.5%
innfeed	1233	6.9%	175.8 KB	7.7%
cnfsstat	576	3.2%	79.2 KB	3.5%
nnrpd	94	0.5%	9.2 KB	0.4%
controlchan	13	0.1%	2.5 KB	0.1%
overchan	1	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 8	17837	100.0%	2.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	34365	42.8%
Positive hits	33089	41.2%
Do not exist	12890	16.0%
Cache misses	41	0.1%
TOTAL: 4	80385	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.629	0.0%	50	0.000	92.580	1162.667
article cleanup	00:00:00.085	0.0%	12994	0.000	0.007	0.033
article logging	00:00:00.370	0.0%	13331	0.000	0.028	0.074
article parse	00:00:00.198	0.0%	23045	0.000	0.009	0.029
article write	00:00:00.674	0.0%	12074	0.000	0.056	1.217
artlog/artcncl	00:00:00.003	0.0%	50	0.000	0.060	0.250
data move	00:00:00.039	0.0%	89273	0.000	0.000	0.005
hisgrep/artcncl	00:00:00.002	0.0%	49	0.000	0.041	1.000
hishave/artcncl	00:00:01.447	0.0%	50	0.000	28.940	435.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:50.314	0.1%	81033	0.001	0.621	12.855
history sync	00:00:00.016	0.0%	406	0.000	0.039	0.500
history write	00:00:00.669	0.0%	12994	0.000	0.051	1.521
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:49:47.902	99.9%	88717	503.299	966.984	3107.865
nntp read	00:00:00.580	0.0%	88838	0.000	0.007	0.017
overview write	00:00:00.037	0.0%	12074	0.000	0.003	0.029
perl filter	00:00:13.059	0.0%	12994	0.667	1.005	1.364
python filter	00:00:00.380	0.0%	12994	0.000	0.029	0.085
site send	00:00:02.423	0.0%	36223	0.010	0.067	0.118
TOTAL: 23:51:10.877	23:51:02.827	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	12028	0.000	0.002	0.030
article prepare	00:00:00.000	0.0%	796	0.000	0.000	0.000
article read	00:00:00.018	0.0%	454	0.000	0.040	0.500
backlog stats	00:00:00.038	0.0%	68595	0.000	0.001	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.345	0.0%	41409	0.000	0.008	0.028
data write	00:00:00.843	0.0%	29455	0.000	0.029	0.070
idle	23:47:27.232	100.0%	68595	736.977	1248.593	2819.869
status file	00:00:00.081	0.0%	247	0.000	0.328	7.250
TOTAL: 23:47:38.871	23:47:28.581	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.003	0.5%	19	0.000	0.158	1.000
TOTAL: 00:00:00.647	00:00:00.003	0.5%	-	-	-	-

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	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	7	21413	7261	13943	209	33%	45:45:31
2	news-out.aioe.org	5	12202	3784	8415	3	31%	24:57:59
3	news.nntp4.net	2	10635	754	9195	686	7%	18:46:22
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		15	44250	11799	31553	898	26%	89:29:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	23.1 MB	3.1 KB	441.1 KB	23.5 MB	98%	3.2 KB
2	news-out.aioe.org	9.7 MB	0.0 KB	3.3 KB	9.7 MB	99%	2.6 KB
3	news.nntp4.net	2.4 MB	0.0 KB	2.4 MB	4.8 MB	50%	3.4 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		35.2 MB	3.1 KB	2.8 MB	38.0 MB	92%	3.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 30 15:00:03 - 15:59:59	667	5.5%	0.19	2.0 MB	5.4%	0.56
Nov 30 16:00:00 - 16:59:59	617	5.1%	0.17	1.8 MB	4.9%	0.50
Nov 30 17:00:00 - 17:59:59	679	5.6%	0.19	1.9 MB	5.2%	0.53
Nov 30 18:00:00 - 18:59:59	711	5.9%	0.20	2.0 MB	5.6%	0.57
Nov 30 19:00:00 - 19:59:59	584	4.8%	0.16	1.5 MB	4.2%	0.44
Nov 30 20:00:00 - 20:59:59	528	4.4%	0.15	1.6 MB	4.4%	0.45
Nov 30 21:00:00 - 21:59:59	521	4.3%	0.14	1.7 MB	4.8%	0.49
Nov 30 22:00:00 - 22:59:59	479	4.0%	0.13	1.6 MB	4.4%	0.45
Nov 30 23:00:00 - 23:59:59	384	3.2%	0.11	1.5 MB	4.2%	0.43
Dec 1 00:00:00 - 00:59:59	318	2.6%	0.09	1017.8 KB	2.8%	0.28
Dec 1 01:00:00 - 01:59:59	268	2.2%	0.07	907.4 KB	2.5%	0.25
Dec 1 02:00:00 - 02:59:59	210	1.7%	0.06	643.1 KB	1.7%	0.18
Dec 1 03:00:00 - 03:59:59	212	1.8%	0.06	581.6 KB	1.6%	0.16
Dec 1 04:00:00 - 04:59:59	242	2.0%	0.07	794.0 KB	2.1%	0.22
Dec 1 05:00:00 - 05:59:59	329	2.7%	0.09	1.1 MB	3.0%	0.30
Dec 1 06:00:00 - 06:59:59	370	3.1%	0.10	1.1 MB	3.1%	0.31
Dec 1 07:00:00 - 07:59:59	411	3.4%	0.11	1.3 MB	3.6%	0.37
Dec 1 08:00:00 - 08:59:59	524	4.3%	0.15	1.6 MB	4.3%	0.45
Dec 1 09:00:00 - 09:59:59	657	5.4%	0.18	1.7 MB	4.7%	0.48
Dec 1 10:00:00 - 10:59:59	668	5.5%	0.19	1.7 MB	4.8%	0.49
Dec 1 11:00:00 - 11:59:59	747	6.2%	0.21	2.4 MB	6.6%	0.68
Dec 1 12:00:00 - 12:59:59	686	5.7%	0.19	2.5 MB	6.9%	0.70
Dec 1 13:00:00 - 13:59:59	637	5.3%	0.18	1.6 MB	4.5%	0.46
Dec 1 14:00:00 - 15:00:02	636	5.3%	0.18	1.8 MB	4.9%	0.51
TOTAL: 23:59:59	12085	100.0%	0.14	36.1 MB	100.0%	0.43

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	779.8 MB	76.1%	9	0.18	67.82
ONE	DEFAULT	1024.0 MB	957.6 MB	93.5%	11	0.18	66.65
THREE	DEFAULT	1024.0 MB	792.8 MB	77.4%	9	0.18	65.87
TWO	DEFAULT	1024.0 MB	784.7 MB	76.6%	9	0.18	66.17
TOTAL: 4	-	4.0 GB	3.2 GB	80.9%	38	0.18	-

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	699	26	673
2	hasname-out.news.weretis.net	458	3	455
3	news-out.aioe.org	91	0	91
TOTAL: 3		1248	29	1219

Unwanted newsgroups [Top 20]:

Newsgroup	Count
panix.chat.politics	15
aol.neighborhood.ca.fresno.marketplace	7
chico.forsale	2
alt.business.business	1
fr.misc.domotique	1
alt.penthouse.sex.femdom	1
bburg.forsale	1
adobe.illustrator.windows	1
TOTAL: 8	29

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	370
EMP (md5)	343
EMP (phl)	91
Binary: misplaced binary	70
HTML Multipart	5
Scoring filter	1
TOTAL: 6	880

INND no permission servers:

System	Conn
162.142.125.211	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	250
	news-out.aioe.org	88
	news.nntp4.net	1
	TOTAL: 3	339
TOTAL: 1		339

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	%Took	Elapsed
1	bbsgslincom	340	340	0	0	100%	20:08:41
2	aioe	10277	71	8105	13	0%	23:47:03
3	nntp4	13824	0	11176	0	0%	23:47:03
4	weretis	4573	0	4453	31	0%	23:47:03
TOTAL: 4		29014	411	23734	44	1%	91:29:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.3 MB	0.0 KB	1.3 MB	4.0 KB	20:08:41
2	aioe	254.9 KB	42.4 KB	297.4 KB	3.5 KB	23:47:03
3	nntp4	0.0 KB	0.0 KB	0.0 KB	0	23:47:03
4	weretis	0.0 KB	153.6 KB	153.6 KB	5.0 KB	23:47:03
TOTAL: 4		1.6 MB	196.1 KB	1.8 MB	4.0 KB	91:29:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
147.182.247.10	0.002	0.004	00:00:00
147.182.202.179	0.001	0.003	00:00:00
164.92.106.15	0.003	0.003	00:00:00
147.182.225.86	0.002	0.002	00:00:00
138.197.4.162	0.002	0.014	00:00:00
192.241.202.134	0.001	0.002	00:00:00
scanner-05.ch1.censys-scanner.com	0.002	0.001	00:00:00
92.255.85.183	0.000	0.002	00:00:00
TOTAL: 8	0.013	0.031	00:00:00

NNRP no permission clients:

System	Conn
138.197.4.162	8
147.182.202.179	2
147.182.247.10	2
164.92.106.15	2
147.182.225.86	2
192.241.202.134	1
92.255.85.183	1
scanner-05.ch1.censys-scanner.com	1
TOTAL: 8	19

Daily Usenet report

Nov 30 15:00:03 -- Dec 1 15:00:02