Daily Usenet Report: Feb 21 00:01:01

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 21 08:14:01 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Feb 22 00:00:01 newsfeed nnrpd[948130]: localhost auth: program exited with status 8

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	16292	47.4%	4.9 MB	65.2%
innd	13048	38.0%	1.7 MB	22.7%
innfeed	4411	12.8%	844.3 KB	10.9%
cnfsstat	432	1.3%	73.6 KB	0.9%
nocem	101	0.3%	12.5 KB	0.2%
nnrpd	67	0.2%	7.1 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	34352	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	150705	60.0%
Negative hits	75088	29.9%
Do not exist	16052	6.4%
Cache misses	9458	3.8%
TOTAL: 4	251303	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.173	0.0%	59	0.000	2.932	12.000
article cleanup	00:00:00.093	0.0%	12429	0.000	0.007	0.067
article logging	00:00:00.537	0.0%	12466	0.000	0.043	0.352
article parse	00:01:29.777	0.1%	1400554	0.000	0.064	0.539
article write	00:00:02.590	0.0%	8612	0.000	0.301	2.739
artlog/artcncl	00:00:00.001	0.0%	31	0.000	0.032	1.000
artlog/artparse	00:00:00.156	0.0%	3769	0.000	0.041	0.167
data move	00:00:05.460	0.0%	1647464	0.000	0.003	0.724
hisgrep/artcncl	00:00:00.120	0.0%	83	0.000	1.446	9.000
hishave/artcncl	00:00:00.000	0.0%	31	0.000	0.000	0.000
hishave/artparse	00:00:00.023	0.0%	3769	0.000	0.006	0.048
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:39.974	0.3%	248674	0.107	0.885	12.599
history sync	00:00:00.062	0.0%	361	0.000	0.172	1.000
history write	00:02:45.861	0.2%	12405	3.154	13.370	120.313
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:01:16.234	0.1%	3645	2.000	20.915	258.500
idle	23:44:34.966	98.8%	1582732	7.971	54.005	1256.220
nntp read	00:00:28.244	0.0%	1595725	0.002	0.018	0.606
overview write	00:06:13.871	0.4%	8612	0.437	43.413	3368.644
perl filter	00:00:53.287	0.1%	12405	1.093	4.296	35.605
python filter	00:00:01.741	0.0%	12405	0.000	0.140	4.882
site send	00:00:01.514	0.0%	17318	0.000	0.087	0.233
TOTAL: 24:01:31.836	24:01:34.684	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.063	0.0%	21289	0.000	0.003	0.025
article prepare	00:00:00.013	0.0%	57547	0.000	0.000	0.007
article read	00:00:00.784	0.0%	4922	0.000	0.159	0.500
backlog stats	00:00:00.554	0.0%	331567	0.000	0.002	0.007
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.063	0.0%	222097	0.002	0.014	0.047
data write	00:00:05.988	0.0%	221608	0.008	0.027	0.095
idle	23:56:41.086	100.0%	331567	60.389	259.981	1165.203
status file	00:00:00.488	0.0%	405	0.667	1.205	16.000
TOTAL: 23:57:06.110	23:56:52.039	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:46.708	6.4%	20	0.500	2335.400	10047.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.862	0.5%	78877	0.000	0.049	0.500
TOTAL: 00:12:06.591	00:00:50.570	7.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	98
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	120

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	endofthelinebbs-out.news.weretis.net	3	16659	2477	13866	316	14%	47:04:54
2	usenet.blueworldhosting.com	288	10374	1002	8495	877	9%	23:48:00
3	newsfeed.bofh.team	9	16910	733	12543	3634	4%	26:52:52
4	usenet.goja.nl.eu.org	10	9541	731	8535	275	7%	23:40:38
5	glockenspiel.complete.org	2	6532	654	5833	45	10%	20:41:08
6	news.corradoroberto.it	11	1645	526	1119	0	31%	23:14:27
7	usenet.network	1	6837	450	6361	26	6%	23:59:57
8	i2pn.org	1	9535	424	9072	39	4%	23:59:56
9	endoftheline-out.news.chmurka.net	1	4033	255	3774	4	6%	23:59:56
10	news.samoylyk.net	2	8222	233	7932	57	2%	23:55:39
11	nntp.comgw.net	1	9861	180	9547	134	1%	23:59:58
12	news.nntp4.net	18	11882	139	10590	1153	1%	08:28:18
13	news.hispagatos.org	6	9844	127	9631	86	1%	24:26:05
14	endofthelinebbs.peers.news.panix.com	27	9311	119	9167	25	1%	24:20:39
15	newsfeed.xs3.de	1	7346	115	7071	160	1%	23:59:55
16	194.87.241.33	42	661	115	431	115	17%	14:20:10
17	news.furie.org.uk	1	4599	38	4559	2	0%	23:59:58
18	outgoing.news.lysator.liu.se	1	6543	10	6528	5	0%	23:59:57
19	news.bbs.nz	3	8039	9	8030	0	0%	24:03:38
20	photonic.trudheim.com	4	2118	3	2114	1	0%	24:15:12
21	localhost	30	0	0	0	0	0%	00:00:01
22	news.nk.ca	2	0	0	0	0	0%	00:20:17
TOTAL: 22		464	160492	8340	145198	6954	5%	477:31:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.bofh.team	143.1 MB	6.0 MB	3.3 GB	3.4 GB	4%	821.1 KB
2	i2pn.org	25.3 MB	633.1 KB	61.4 KB	26.0 MB	97%	57.4 KB
3	nntp.comgw.net	14.2 MB	0.0 KB	579.5 KB	14.8 MB	96%	48.3 KB
4	endofthelinebbs-out.news.weretis.net	6.7 MB	0.0 KB	685.3 KB	7.4 MB	90%	2.7 KB
5	news.samoylyk.net	6.1 MB	0.0 KB	159.2 KB	6.3 MB	97%	22.2 KB
6	news.corradoroberto.it	4.6 MB	0.0 KB	0.0 KB	4.6 MB	100%	9.0 KB
7	usenet.network	4.3 MB	3.0 KB	42.8 KB	4.4 MB	98%	9.4 KB
8	usenet.blueworldhosting.com	2.6 MB	0.0 KB	1.7 MB	4.3 MB	61%	2.4 KB
9	usenet.goja.nl.eu.org	1.9 MB	2.8 KB	531.5 KB	2.5 MB	78%	2.5 KB
10	glockenspiel.complete.org	1.7 MB	0.0 KB	96.9 KB	1.8 MB	94%	2.6 KB
11	news.hispagatos.org	1.2 MB	405.3 KB	265.1 KB	1.9 MB	64%	9.0 KB
12	endoftheline-out.news.chmurka.net	1.1 MB	0.0 KB	7.2 KB	1.1 MB	99%	4.3 KB
13	newsfeed.xs3.de	504.7 KB	0.0 KB	632.8 KB	1.1 MB	44%	4.1 KB
14	news.nntp4.net	422.1 KB	0.0 KB	160.0 MB	160.4 MB	0%	127.1 KB
15	endofthelinebbs.peers.news.panix.com	327.4 KB	3.0 KB	43.0 KB	373.4 KB	87%	2.6 KB
16	194.87.241.33	321.6 KB	0.0 KB	302.5 KB	624.0 KB	51%	2.7 KB
17	news.furie.org.uk	145.0 KB	0.0 KB	7.4 KB	152.4 KB	95%	3.8 KB
18	outgoing.news.lysator.liu.se	45.4 KB	571.8 KB	6.9 KB	624.0 KB	7%	41.6 KB
19	photonic.trudheim.com	16.1 KB	0.0 KB	232.0 KB	248.1 KB	6%	62.0 KB
20	news.bbs.nz	13.2 KB	0.0 KB	0.0 KB	13.2 KB	100%	1.5 KB
21	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
22	news.nk.ca	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 22		214.8 MB	7.6 MB	3.4 GB	3.7 GB	5%	250.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 21 00:01:01 - 00:59:59	192	2.2%	0.05	563.9 KB	0.2%	0.16
Feb 21 01:00:00 - 01:59:59	251	2.9%	0.07	707.9 KB	0.3%	0.20
Feb 21 02:00:00 - 02:59:59	372	4.3%	0.10	1.1 MB	0.5%	0.31
Feb 21 03:00:00 - 03:59:59	479	5.6%	0.13	1.5 MB	0.7%	0.44
Feb 21 04:00:00 - 04:59:59	506	5.9%	0.14	1.8 MB	0.8%	0.50
Feb 21 05:00:00 - 05:59:59	421	4.9%	0.12	1.7 MB	0.7%	0.48
Feb 21 06:00:00 - 06:59:59	425	4.9%	0.12	1.6 MB	0.7%	0.47
Feb 21 07:00:00 - 07:59:59	510	5.9%	0.14	2.8 MB	1.2%	0.79
Feb 21 08:00:00 - 08:59:59	531	6.2%	0.15	7.1 MB	3.1%	2.02
Feb 21 09:00:00 - 09:59:59	487	5.7%	0.14	6.6 MB	2.9%	1.88
Feb 21 10:00:00 - 10:59:59	438	5.1%	0.12	1.5 MB	0.7%	0.43
Feb 21 11:00:00 - 11:59:59	407	4.7%	0.11	13.3 MB	5.8%	3.77
Feb 21 12:00:00 - 12:59:59	409	4.8%	0.11	6.4 MB	2.8%	1.81
Feb 21 13:00:00 - 13:59:59	460	5.3%	0.13	29.8 MB	13.0%	8.47
Feb 21 14:00:00 - 14:59:59	323	3.8%	0.09	1.2 MB	0.5%	0.35
Feb 21 15:00:00 - 15:59:59	388	4.5%	0.11	12.0 MB	5.2%	3.41
Feb 21 16:00:00 - 16:59:59	388	4.5%	0.11	48.2 MB	21.0%	13.72
Feb 21 17:00:00 - 17:59:59	411	4.8%	0.11	12.8 MB	5.6%	3.63
Feb 21 18:00:00 - 18:59:59	226	2.6%	0.06	10.4 MB	4.5%	2.97
Feb 21 19:00:00 - 19:59:59	151	1.8%	0.04	582.8 KB	0.2%	0.16
Feb 21 20:00:00 - 20:59:59	189	2.2%	0.05	32.1 MB	14.0%	9.14
Feb 21 21:00:00 - 21:59:59	213	2.5%	0.06	11.3 MB	4.9%	3.21
Feb 21 22:00:00 - 22:59:59	204	2.4%	0.06	13.7 MB	6.0%	3.89
Feb 21 23:00:00 - 23:59:59	214	2.5%	0.06	10.6 MB	4.6%	3.01
Feb 22 00:00:00 - 00:01:01	8	0.1%	0.13	24.1 KB	0.0%	0.40
TOTAL: 24:00:00	8603	100.0%	0.10	229.3 MB	100.0%	2.72

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
CYCNGS1	NGS	1536.0 GB	48.0 MB	0.0%	1	0.00	0.00
CYCNGS2	NGS	1536.0 GB	104.0 GB	6.8%	0	2.96	6302.72
CYCTXT1	TEXT	5.0 GB	4.1 GB	82.4%	0	0.04	1677.25
TOTAL: 3	-	3077.0 GB	108.2 GB	3.5%	1	2.20	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.bofh.team	4162	42	13	4107
2	news.nntp4.net	1230	5	0	1225
3	usenet.blueworldhosting.com	891	4	0	887
4	endofthelinebbs-out.news.weretis.net	354	0	0	354
5	usenet.goja.nl.eu.org	279	0	1	278
6	newsfeed.xs3.de	160	0	0	160
7	nntp.comgw.net	132	0	0	132
8	194.87.241.33	115	0	0	115
9	news.hispagatos.org	95	20	5	70
10	news.samoylyk.net	57	0	0	57
11	glockenspiel.complete.org	46	0	0	46
12	i2pn.org	39	0	1	38
13	news.netfront.net	33	0	0	33
14	usenet.network	27	0	1	26
15	endofthelinebbs.peers.news.panix.com	23	1	1	21
16	outgoing.news.lysator.liu.se	5	0	2	3
17	endoftheline-out.news.chmurka.net	4	0	0	4
18	news.bbs.nz	3	0	0	3
19	news.furie.org.uk	2	0	0	2
20	photonic.trudheim.com	1	0	0	1
TOTAL: 20		7658	72	24	7562

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.sex.strip_clubs	17
hispagatos.mastodon.tag.hacking	15
alt.sex.beer-bottle1	13
alt.sex.boredoma	10
hispagatos.hacking.news	8
alt.discuss.test	2
soc.culture.russia	2
alt.online	1
de.alt.tv	1
hispagatos.mastodon.tag.hackers	1
news.test_messages	1
ravenvearlygmail.com	1
TOTAL: 12	72

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	1951
EMP (phr path)	857
EMP (phn nph)	177
Binary: misplaced binary	163
Too many newsgroups	162
EMP (md5)	149
EMP (phl)	73
Too many newsgroups (low_xpost)	51
Poison newsgroup	47
Angle-bracket bot	25
Too many newsgroups (meow)	17
User-issued cancel	15
Excessive Supersedes (thangorodrim.ancalagon.de:127.0.0.1)	10
Excessive Supersedes (803b122fd3835df49eaab7d78f06a651)	4
HTML Multipart	4
Subject (lsdmVyPw==?= (was: Lohnt Ersatz des Netzteils fÃ¼r PowerMac G4 Qui cksilver)	2
hierarchy violation - crosspost outside sdnet	2
MID-Bot	1
Subject (LSDRgdCw0LzRi9C5INC90LjQutGH0ZHQvN)	1
TOTAL: 19	3711

NoCeM on Spool:

Issuer (type)	Notices	Cancel	Skip	Total
i2pn2-nocem@i2pn2.org (spam)	3	4	0	4
robot@pasdenom.info (spam)	3	3	0	3
robot@pasdenom.info (spam2)	37	0	45	45
robot@pasdenom.info (spam3)	8	0	39	39
i2pn2-nocem@i2pn2.org (multipost)	33	0	37	37
robot@pasdenom.info (spam4)	7	0	20	20
i2pn2-nocem@i2pn2.org (rmp)	3	0	9	9
TOTAL: 7	94	7	150	157

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	newsfeed.bofh.team	3661
	news.nntp4.net	153
	TOTAL: 2	3814
Including strange strings
	usenet.blueworldhosting.com	14
	endofthelinebbs-out.news.weretis.net	8
	usenet.goja.nl.eu.org	6
	nntp.comgw.net	3
	news.nntp4.net	2
	newsfeed.xs3.de	1
	i2pn.org	1
	news.bbs.nz	1
	glockenspiel.complete.org	1
	TOTAL: 9	37
TOTAL: 2		3851

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nyheter.lysator.liu.se	9778	2233	5526	834	9	22%	23:58:56
2	news.netfront.net	13827	2169	6275	144	72	15%	23:59:52
3	news.furie.org.uk	5859	610	3424	363	0	10%	23:59:52
4	photonic.trudheim.com	9953	544	6409	1294	0	5%	23:59:50
5	usenet.goja.nl.eu.org	8079	363	6980	85	0	4%	23:59:52
6	news.chmurka.net	4559	350	3554	0	0	7%	23:59:01
7	endofthelinebbs.peers.news.panix.com	8593	308	7902	23	0	3%	23:59:41
8	news.nk.ca	10477	296	7511	617	64	2%	23:59:52
9	news.hispagatos.org	9157	151	7868	134	0	1%	23:59:52
10	news.corradoroberto.it	1569	149	858	2	32	9%	22:18:57
11	i2pn.org	8826	142	7659	17	117	1%	23:59:18
12	usenet.network	8262	138	7054	109	0	1%	23:59:16
13	newsfeed.bofh.team	8555	119	7548	0	0	1%	23:59:52
14	news.bbs.nz	15814	113	7785	693	213	0%	23:59:30
15	news.nntp4.net	15768	106	15403	22	4538	0%	23:59:52
16	news.weretis.net	9025	71	7881	649	3	0%	23:59:52
17	nntp.comgw.net	8899	67	8299	45	0	0%	23:59:28
18	news.quux.org	7038	59	5548	881	0	0%	23:59:52
19	newsfeed.xs3.de	9262	41	7986	146	0	0%	23:59:52
20	news.tnetconsulting.net	9794	35	7860	339	67	0%	23:59:52
21	news.samoylyk.net	9523	22	8227	24	50	0%	23:59:52
22	ddt.demos.su	74	12	57	0	0	16%	06:57:36
23	usenet.blueworldhosting.com	7598	0	7085	0	14	0%	23:59:13
24	feed.opticnetworks.net	0	0	0	0	8603	0%	23:59:52
TOTAL: 24		200289	8098	154699	6421	13782	4%	557:09:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.chmurka.net	180.2 MB	0.0 KB	180.2 MB	2.1 KB/s	527.1 KB	23:59:01
2	news.netfront.net	129.9 MB	77.5 MB	207.4 MB	2.5 KB/s	91.8 KB	23:59:52
3	news.nk.ca	108.5 MB	68.5 MB	176.9 MB	2.1 KB/s	198.5 KB	23:59:52
4	news.furie.org.uk	24.4 MB	176.9 MB	201.3 MB	2.4 KB/s	211.9 KB	23:59:52
5	nyheter.lysator.liu.se	7.5 MB	184.0 MB	191.5 MB	2.3 KB/s	64.0 KB	23:58:56
6	news.bbs.nz	4.5 MB	146.0 MB	150.5 MB	1.8 KB/s	191.2 KB	23:59:30
7	photonic.trudheim.com	2.9 MB	4.6 MB	7.5 MB	0.1 KB/s	4.2 KB	23:59:50
8	endofthelinebbs.peers.news.panix.com	2.6 MB	576.5 KB	3.1 MB	0.0 KB/s	9.7 KB	23:59:41
9	news.hispagatos.org	2.0 MB	2.4 MB	4.4 MB	0.1 KB/s	15.7 KB	23:59:52
10	i2pn.org	815.3 KB	234.5 KB	1.0 MB	0.0 KB/s	6.6 KB	23:59:18
11	news.quux.org	806.7 KB	184.5 MB	185.3 MB	2.2 KB/s	201.9 KB	23:59:52
12	newsfeed.bofh.team	781.8 KB	0.0 KB	781.8 KB	0.0 KB/s	6.6 KB	23:59:52
13	usenet.goja.nl.eu.org	716.2 KB	1.0 MB	1.7 MB	0.0 KB/s	4.0 KB	23:59:52
14	usenet.network	464.3 KB	605.8 KB	1.0 MB	0.0 KB/s	4.3 KB	23:59:16
15	news.corradoroberto.it	363.4 KB	5.7 KB	369.2 KB	0.0 KB/s	2.4 KB	22:18:57
16	news.weretis.net	299.3 KB	197.8 MB	198.1 MB	2.3 KB/s	281.8 KB	23:59:52
17	news.nntp4.net	236.6 KB	23.9 KB	260.4 KB	0.0 KB/s	2.0 KB	23:59:52
18	nntp.comgw.net	188.4 KB	536.6 KB	725.0 KB	0.0 KB/s	6.5 KB	23:59:28
19	newsfeed.xs3.de	177.6 KB	1.1 MB	1.3 MB	0.0 KB/s	7.2 KB	23:59:52
20	news.tnetconsulting.net	133.4 KB	2.7 MB	2.8 MB	0.0 KB/s	7.7 KB	23:59:52
21	news.samoylyk.net	103.7 KB	2.2 MB	2.3 MB	0.0 KB/s	50.7 KB	23:59:52
22	ddt.demos.su	42.3 KB	0.0 KB	42.3 KB	0.0 KB/s	3.5 KB	06:57:36
23	feed.opticnetworks.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:52
24	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:13
TOTAL: 24		467.5 MB	1.0 GB	1.5 GB	0.8 KB/s	107.1 KB	557:09:02

NNRP readership statistics:

System		Conn	Elapsed
1	162.142.125.201	3	00:00:10
2	167.94.146.61	3	00:00:04
3	104.152.52.151	1	00:00:10
4	104.152.52.199	1	00:00:00
5	205.210.31.158	1	00:00:10
6	205.210.31.174	1	00:00:10
7	205.210.31.239	1	00:00:00
8	localhost	1	00:11:19
9	treasured.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 9		13	00:12:06

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	unresolved	11	00:00:45
2	*.monitoring.internet-measurement.com	1	00:00:01
3	?	1	00:11:19
TOTAL: 3		13	00:12:06

NNRP auth users [Top 20]:

User	Conn
getlist	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
localhost	184.824	372.352	0.076	00:11:19
162.142.125.201	0.020	0.047	0.000	00:00:10
205.210.31.174	0.004	0.038	0.000	00:00:10
205.210.31.158	0.006	0.012	0.000	00:00:10
104.152.52.151	0.000	0.026	0.000	00:00:10
167.94.146.61	0.024	0.042	0.000	00:00:04
treasured.monitoring.internet-measurement.com	0.000	0.017	0.000	00:00:01
205.210.31.239	0.003	0.014	0.000	00:00:00
104.152.52.199	0.000	0.017	0.000	00:00:00
TOTAL: 9	184.881	372.565	0.076	00:12:06

NNRP unrecognized commands (by host) [Top 20]:

System	Conn
162.142.125.201	1
167.94.146.61	1
TOTAL: 2	2

NNRP unrecognized commands (by command) [Top 20]:

Command	Count
EHLO www.censys.io	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
162.142.125.201	1	2
167.94.146.61	1	2
TOTAL: 2	2	5

Daily Usenet Report

Feb 21 00:01:01 -- Feb 22 00:01:01