Daily Usenet report: Feb 6 15:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 6 15:01:31 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Feb 7 06:39:20 colo-sc-1 pgpverify[31262]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	15459	71.9%	1.8 MB	69.6%
innd	4251	19.8%	568.2 KB	21.0%
innfeed	1175	5.5%	166.9 KB	6.2%
cnfsstat	576	2.7%	80.5 KB	3.0%
nnrpd	32	0.1%	3.3 KB	0.1%
controlchan	12	0.1%	2.7 KB	0.1%
overchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 8	21507	100.0%	2.6 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	40913	43.6%
Positive hits	37747	40.3%
Do not exist	15069	16.1%
Cache misses	32	0.0%
TOTAL: 4	93761	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.128	0.0%	59	0.000	2.169	28.667
article cleanup	00:00:00.072	0.0%	15178	0.000	0.005	0.057
article logging	00:00:00.395	0.0%	15392	0.000	0.026	0.093
article parse	00:00:00.226	0.0%	33174	0.000	0.007	0.027
article write	00:00:02.686	0.0%	12536	0.000	0.214	9.241
artlog/artcncl	00:00:00.000	0.0%	58	0.000	0.000	0.000
data move	00:00:00.040	0.0%	108738	0.000	0.000	0.006
hisgrep/artcncl	00:00:00.000	0.0%	57	0.000	0.000	0.000
hishave/artcncl	00:00:00.003	0.0%	59	0.000	0.051	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:19.146	0.0%	94385	0.000	0.203	4.441
history sync	00:00:00.006	0.0%	407	0.000	0.015	0.333
history write	00:00:10.065	0.0%	15178	0.000	0.663	26.095
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	23:59:48.204	99.9%	110173	322.508	784.114	2619.904
nntp read	00:00:00.685	0.0%	110055	0.000	0.006	0.024
overview write	00:00:00.042	0.0%	12536	0.000	0.003	0.057
perl filter	00:00:25.662	0.0%	15178	0.830	1.691	52.588
python filter	00:00:00.380	0.0%	15178	0.000	0.025	0.065
site send	00:00:02.584	0.0%	37607	0.021	0.069	0.155
TOTAL: 24:01:06.173	24:00:50.324	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.027	0.0%	12523	0.000	0.002	0.059
article prepare	00:00:00.000	0.0%	791	0.000	0.000	0.000
article read	00:00:00.026	0.0%	508	0.000	0.051	0.333
backlog stats	00:00:00.048	0.0%	68367	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.261	0.0%	42046	0.000	0.006	0.029
data write	00:00:00.755	0.0%	29776	0.000	0.025	0.093
idle	23:57:22.095	100.0%	68367	588.252	1261.458	3186.952
status file	00:00:00.232	0.0%	223	0.000	1.040	88.000
TOTAL: 23:57:29.154	23:57:23.444	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%	7	0.000	0.000	0.000
TOTAL: 00:00:00.658	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
grisbi-control@grisbi.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	25442	7268	17142	1032	28%	48:02:53
2	news-out.aioe.org	3	12352	4958	7384	10	40%	24:05:13
3	news.nntp4.net	1	13299	151	11699	1449	1%	21:42:35
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		10	51093	12377	36225	2491	24%	93:50:41

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	23.8 MB	2.0 MB	25.9 MB	92%	3.2 KB
2	news-out.aioe.org	13.1 MB	21.0 KB	13.1 MB	99%	2.7 KB
3	news.nntp4.net	672.7 KB	8.9 MB	9.6 MB	6%	6.1 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		37.6 MB	11.0 MB	48.6 MB	77%	3.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 6 15:00:02 - 15:59:59	654	5.2%	0.18	1.9 MB	5.0%	0.54
Feb 6 16:00:00 - 16:59:59	666	5.3%	0.18	2.0 MB	5.3%	0.57
Feb 6 17:00:00 - 17:59:59	568	4.5%	0.16	1.6 MB	4.2%	0.46
Feb 6 18:00:00 - 18:59:59	675	5.4%	0.19	2.3 MB	6.1%	0.66
Feb 6 19:00:00 - 19:59:59	571	4.5%	0.16	1.8 MB	4.8%	0.52
Feb 6 20:00:00 - 20:59:59	638	5.1%	0.18	2.0 MB	5.4%	0.58
Feb 6 21:00:00 - 21:59:59	649	5.2%	0.18	1.9 MB	5.0%	0.54
Feb 6 22:00:00 - 22:59:59	554	4.4%	0.15	1.6 MB	4.2%	0.45
Feb 6 23:00:00 - 23:59:59	414	3.3%	0.12	1.2 MB	3.0%	0.33
Feb 7 00:00:00 - 00:59:59	398	3.2%	0.11	1.4 MB	3.6%	0.39
Feb 7 01:00:00 - 01:59:59	450	3.6%	0.12	1.3 MB	3.5%	0.37
Feb 7 02:00:00 - 02:59:59	326	2.6%	0.09	1.0 MB	2.8%	0.30
Feb 7 03:00:00 - 03:59:59	329	2.6%	0.09	1.2 MB	3.0%	0.33
Feb 7 04:00:00 - 04:59:59	324	2.6%	0.09	1.1 MB	2.9%	0.31
Feb 7 05:00:00 - 05:59:59	296	2.4%	0.08	1.2 MB	3.3%	0.35
Feb 7 06:00:00 - 06:59:59	238	1.9%	0.07	972.7 KB	2.5%	0.27
Feb 7 07:00:00 - 07:59:59	386	3.1%	0.11	1.3 MB	3.3%	0.36
Feb 7 08:00:00 - 08:59:59	513	4.1%	0.14	1.7 MB	4.4%	0.47
Feb 7 09:00:00 - 09:59:59	604	4.8%	0.17	1.6 MB	4.2%	0.46
Feb 7 10:00:00 - 10:59:59	661	5.3%	0.18	1.7 MB	4.6%	0.49
Feb 7 11:00:00 - 11:59:59	607	4.8%	0.17	1.7 MB	4.5%	0.48
Feb 7 12:00:00 - 12:59:59	591	4.7%	0.16	1.6 MB	4.1%	0.45
Feb 7 13:00:00 - 13:59:59	642	5.1%	0.18	1.7 MB	4.4%	0.47
Feb 7 14:00:00 - 14:59:59	799	6.4%	0.22	2.2 MB	5.8%	0.63
Feb 7 15:00:00 - 15:00:01	1	0.0%	1.00	2.1 KB	0.0%	2.06
TOTAL: 23:59:59	12554	100.0%	0.15	37.9 MB	100.0%	0.45

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
FOUR	DEFAULT	1024.0 MB	389.4 MB	38.0%	5	0.19	65.06
ONE	DEFAULT	1024.0 MB	557.4 MB	54.4%	7	0.19	63.84
THREE	DEFAULT	1024.0 MB	398.0 MB	38.9%	5	0.18	66.20
TWO	DEFAULT	1024.0 MB	390.0 MB	38.1%	5	0.19	64.28
TOTAL: 4	-	4.0 GB	1.7 GB	42.4%	22	0.19	-

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	news.nntp4.net	1574	2	1	1571
2	hasname-out.news.weretis.net	1209	4	0	1205
3	news-out.aioe.org	64	7	0	57
TOTAL: 3		2847	13	1	2833

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.infosystems.gemini	7
wolfsburg.test	2
alt.education.university	1
alt.exploding.barney	1
alt.sex.telephone.alt	1
mozilla.dev.tech.js-engine	1
TOTAL: 6	13

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)	2002
EMP (md5)	506
EMP (phn nph)	52
EMP (phl)	23
HTML Multipart	17
User-issued spam cancel	8
Too many newsgroups	6
Too many newsgroups (meow)	2
Binary: misplaced binary	1
HTML post	1
U2 violation - invalid distribution	1
Scoring filter	1
TOTAL: 12	2620

INND no permission servers:

System	Conn
89.248.165.105	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	159
	news-out.aioe.org	53
	news.nntp4.net	1
	TOTAL: 3	213
TOTAL: 1		213

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	422	422	0	0	0	100%	20:40:10
2	aioe	9160	57	7344	3	1	0%	23:59:52
3	nntp4	14336	2	12372	0	10	0%	23:59:50
4	weretis	5412	0	5111	31	0	0%	23:59:52
TOTAL: 4		29330	481	24827	34	11	1%	92:39:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	bbsgslincom	1.5 MB	0.0 KB	1.5 MB	3.5 KB	20:40:10
2	aioe	215.6 KB	36.6 KB	252.1 KB	4.2 KB	23:59:52
3	nntp4	5.0 KB	0.0 KB	5.0 KB	2.5 KB	23:59:50
4	weretis	0.0 KB	202.4 KB	202.4 KB	6.5 KB	23:59:52
TOTAL: 4		1.7 MB	239.0 KB	1.9 MB	3.8 KB	92:39:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
45.227.254.52	0.002	0.002	00:00:00
jerry-se-do-eu-west-scanners-24.do.binaryedge.ninja	0.001	0.002	00:00:00
143.110.151.117	0.000	0.002	00:00:00
89.248.165.105	0.000	0.003	00:00:00
23.129.64.250	0.002	0.000	00:00:00
?	0.001	0.000	00:00:00
TOTAL: 6	0.006	0.009	00:00:00

NNRP no permission clients:

System	Conn
45.227.254.52	2
89.248.165.105	1
143.110.151.117	1
23.129.64.250	1
jerry-se-do-eu-west-scanners-24.do.binaryedge.ninja	1
TOTAL: 5	6

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

Daily Usenet report

Feb 6 15:00:02 -- Feb 7 15:00:01