Daily Usenet report: Sep 26 15:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 26 15:01:17 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Sep 27 08:36:28 colo-sc-1 pgpverify[27948]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	15239	75.3%	1.8 MB	73.0%
innd	3185	15.7%	436.8 KB	17.0%
innfeed	1224	6.0%	169.7 KB	6.6%
cnfsstat	576	2.8%	84.7 KB	3.3%
controlchan	12	0.1%	2.8 KB	0.1%
nnrpd	8	0.0%	0.9 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	20245	100.0%	2.5 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	40727	43.5%
Positive hits	38031	40.6%
Do not exist	14799	15.8%
Cache misses	29	0.0%
TOTAL: 4	93586	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.028	0.0%	44	0.000	0.636	4.000
article cleanup	00:00:00.091	0.0%	14909	0.000	0.006	0.043
article logging	00:00:00.413	0.0%	15218	0.000	0.027	0.071
article parse	00:00:00.250	0.0%	26327	0.000	0.009	0.036
article write	00:00:01.043	0.0%	13292	0.000	0.078	9.538
artlog/artcncl	00:00:00.001	0.0%	44	0.000	0.023	0.500
data move	00:00:00.058	0.0%	103062	0.000	0.001	0.007
hisgrep/artcncl	00:00:00.000	0.0%	44	0.000	0.000	0.000
hishave/artcncl	00:00:00.008	0.0%	44	0.000	0.182	1.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:12.341	0.1%	94243	0.001	0.768	39.622
history sync	00:00:00.009	0.0%	404	0.000	0.022	0.667
history write	00:00:02.321	0.0%	14909	0.000	0.156	9.870
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:59:14.325	99.9%	103235	417.758	836.483	2531.983
nntp read	00:00:00.791	0.0%	103246	0.000	0.008	0.020
overview write	00:00:00.047	0.0%	13292	0.000	0.004	0.041
perl filter	00:00:16.470	0.0%	14909	0.870	1.105	1.602
python filter	00:00:00.446	0.0%	14909	0.000	0.030	0.091
site send	00:00:02.549	0.0%	39877	0.026	0.064	0.113
TOTAL: 24:01:05.980	24:00:51.191	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	13256	0.000	0.003	0.026
article prepare	00:00:00.000	0.0%	563	0.000	0.000	0.000
article read	00:00:00.544	0.0%	425	0.000	1.280	131.000
backlog stats	00:00:00.035	0.0%	75864	0.000	0.000	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.353	0.0%	45841	0.000	0.008	0.042
data write	00:00:00.921	0.0%	32714	0.000	0.028	0.073
idle	23:56:55.605	100.0%	75864	709.563	1136.450	2540.176
status file	00:00:00.092	0.0%	251	0.000	0.367	2.000
TOTAL: 23:56:59.616	23:56:57.584	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.000	0.0%	2	0.000	0.000	0.000
TOTAL: 00:00:00.564	00:00:00.000	0.0%	-	-	-	-

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
control@usenet-fr.news.eu.org	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	5	25872	9464	15862	546	36%	48:36:02
2	news-out.aioe.org	2	13008	3415	9593	0	26%	24:24:48
3	news.nntp4.net	1	15760	531	14145	1084	3%	25:41:18
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		9	54640	13410	39600	1630	24%	98:42:08

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	28.3 MB	896.5 KB	29.2 MB	97%	3.0 KB
2	news-out.aioe.org	8.3 MB	0.0 KB	8.3 MB	100%	2.5 KB
3	news.nntp4.net	1.5 MB	8.3 MB	9.8 MB	15%	6.2 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		38.1 MB	9.1 MB	47.3 MB	80%	3.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 26 15:00:02 - 15:59:59	608	4.6%	0.17	1.8 MB	4.9%	0.52
Sep 26 16:00:00 - 16:59:59	627	4.7%	0.17	1.8 MB	4.8%	0.51
Sep 26 17:00:00 - 17:59:59	664	5.0%	0.18	1.8 MB	4.7%	0.50
Sep 26 18:00:00 - 18:59:59	634	4.8%	0.18	1.7 MB	4.6%	0.49
Sep 26 19:00:00 - 19:59:59	705	5.3%	0.20	2.0 MB	5.3%	0.56
Sep 26 20:00:00 - 20:59:59	646	4.9%	0.18	1.7 MB	4.5%	0.49
Sep 26 21:00:00 - 21:59:59	508	3.8%	0.14	1.7 MB	4.5%	0.48
Sep 26 22:00:00 - 22:59:59	481	3.6%	0.13	1.6 MB	4.2%	0.45
Sep 26 23:00:00 - 23:59:59	350	2.6%	0.10	1.1 MB	2.9%	0.31
Sep 27 00:00:00 - 00:59:59	303	2.3%	0.08	849.3 KB	2.2%	0.24
Sep 27 01:00:00 - 01:59:59	299	2.3%	0.08	995.0 KB	2.6%	0.28
Sep 27 02:00:00 - 02:59:59	312	2.3%	0.09	952.0 KB	2.5%	0.26
Sep 27 03:00:00 - 03:59:59	248	1.9%	0.07	705.0 KB	1.8%	0.20
Sep 27 04:00:00 - 04:59:59	330	2.5%	0.09	1.2 MB	3.1%	0.33
Sep 27 05:00:00 - 05:59:59	327	2.5%	0.09	1.0 MB	2.8%	0.29
Sep 27 06:00:00 - 06:59:59	428	3.2%	0.12	1.3 MB	3.4%	0.36
Sep 27 07:00:00 - 07:59:59	594	4.5%	0.17	1.4 MB	3.7%	0.40
Sep 27 08:00:00 - 08:59:59	730	5.5%	0.20	2.0 MB	5.2%	0.56
Sep 27 09:00:00 - 09:59:59	745	5.6%	0.21	1.9 MB	5.0%	0.54
Sep 27 10:00:00 - 10:59:59	738	5.6%	0.20	2.0 MB	5.3%	0.57
Sep 27 11:00:00 - 11:59:59	627	4.7%	0.17	1.8 MB	4.7%	0.50
Sep 27 12:00:00 - 12:59:59	804	6.1%	0.22	2.2 MB	6.0%	0.64
Sep 27 13:00:00 - 13:59:59	773	5.8%	0.21	2.1 MB	5.5%	0.58
Sep 27 14:00:00 - 14:59:59	801	6.0%	0.22	2.2 MB	5.9%	0.63
Sep 27 15:00:00 - 15:00:01	1	0.0%	1.00	2.5 KB	0.0%	2.53
TOTAL: 23:59:59	13283	100.0%	0.15	37.6 MB	100.0%	0.45

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	182.5 MB	17.8%	3	0.19	63.20
ONE	DEFAULT	1024.0 MB	344.3 MB	33.6%	5	0.19	62.97
THREE	DEFAULT	1024.0 MB	185.7 MB	18.1%	3	0.20	61.89
TWO	DEFAULT	1024.0 MB	180.1 MB	17.6%	3	0.19	63.76
TOTAL: 4	-	4.0 GB	892.6 MB	21.8%	14	0.19	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	1073	16	2	1055
2	hasname-out.news.weretis.net	798	1	0	797
3	news-out.aioe.org	41	0	0	41
TOTAL: 3		1912	17	2	1893

Unwanted newsgroups [Top 20]:

Newsgroup	Count
panix.chat.politics	8
newsreader.test	5
alt.math	1
panix.test	1
panix.chat	1
clari.local.chicago	1
TOTAL: 6	17

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	991
EMP (md5)	374
EMP (phn nph)	198
EMP (phl)	11
Binary: misplaced binary	6
User-issued spam cancel	4
HTML Multipart	4
Too many newsgroups	1
TOTAL: 8	1589

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	263
	news-out.aioe.org	41
	TOTAL: 2	304
TOTAL: 1		304

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	379	379	0	0	0	100%	20:00:32
2	aioe	12342	29	9680	0	0	0%	23:50:00
3	nntp4	15431	3	12769	0	2	0%	23:58:55
4	weretis	3982	0	3821	14	0	0%	23:54:37
TOTAL: 4		32134	411	26270	14	2	1%	91:44:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.5 MB	0.0 KB	1.5 MB	3.9 KB	20:00:32
2	aioe	118.8 KB	0.0 KB	118.8 KB	4.1 KB	23:50:00
3	nntp4	5.9 KB	0.0 KB	5.9 KB	2.0 KB	23:58:55
4	weretis	0.0 KB	45.8 KB	45.8 KB	3.3 KB	23:54:37
TOTAL: 4		1.6 MB	45.8 KB	1.6 MB	3.9 KB	91:44:04

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
news.nntp4.net	0.001	0.002	00:00:00
scanner-21.ch1.censys-scanner.com	0.000	0.003	00:00:00
TOTAL: 2	0.001	0.005	00:00:00

NNRP no permission clients:

System	Conn
news.nntp4.net	1
scanner-21.ch1.censys-scanner.com	1
TOTAL: 2	2

Daily Usenet report

Sep 26 15:00:02 -- Sep 27 15:00:01