Daily Usenet report: Aug 15 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Aug 15 08:44:15 newsfeed overchan[8147]: input line too long, skipping
Aug 15 17:00:05 newsfeed pgpverify[4811]: pgpverify: verification failed
Aug 15 17:10:18 newsfeed overchan[8147]: input line too long, skipping
Aug 15 18:27:31 newsfeed overchan[8147]: input line too long, skipping
Aug 15 19:33:40 newsfeed overchan[8147]: input line too long, skipping
Aug 15 19:44:24 newsfeed overchan[8147]: input line too long, skipping
Aug 15 19:46:47 newsfeed overchan[8147]: input line too long, skipping
Aug 15 20:31:42 newsfeed overchan[8147]: input line too long, skipping
Aug 15 20:58:50 newsfeed overchan[8147]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2316010	97.2%	259.2 MB	96.8%
innfeed	43559	1.8%	5.1 MB	1.9%
innd	19351	0.8%	3.1 MB	1.1%
nnrpd	4720	0.2%	499.2 KB	0.2%
overchan	9	0.0%	1.7 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2383654	100.0%	267.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2302504	89.8%
Positive hits	185633	7.2%
Negative hits	75250	2.9%
Cache misses	1037	0.0%
TOTAL: 4	2564424	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.901	0.0%	183	0.000	26.781	178.462
article cleanup	00:00:29.878	0.0%	2315253	0.010	0.013	0.019
article logging	00:01:39.940	0.1%	2315789	0.032	0.043	0.089
article parse	00:39:06.841	2.7%	83561453	0.015	0.028	0.044
article write	00:16:59.065	1.2%	2308103	0.243	0.442	1.155
artlog/artcncl	00:00:00.004	0.0%	172	0.000	0.023	0.333
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:38.743	0.2%	96398670	0.001	0.002	0.003
hisgrep/artcncl	00:00:01.683	0.0%	179	0.000	9.402	105.143
hishave/artcncl	00:00:00.110	0.0%	183	0.000	0.601	14.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:40:43.524	2.8%	2573158	0.209	0.950	6.195
history sync	00:00:00.015	0.0%	290	0.000	0.052	0.500
history write	01:12:03.804	5.0%	2310267	0.383	1.872	7.455
hiswrite/artcncl	00:00:00.001	0.0%	4	0.000	0.250	1.000
idle	15:34:37.226	64.9%	68732455	0.523	0.816	1.636
nntp read	00:55:58.780	3.9%	82050885	0.027	0.041	0.067
overview write	00:00:12.028	0.0%	2308103	0.003	0.005	0.007
perl filter	04:05:34.371	17.1%	2310267	4.135	6.378	10.679
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:31.699	0.3%	4881681	0.042	0.056	0.075
TOTAL: 24:00:01.425	23:34:42.613	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.411	0.0%	540131	0.001	0.003	0.006
article prepare	00:00:00.081	0.0%	321522	0.000	0.000	0.003
article read	00:01:28.585	0.1%	77417	0.182	1.144	18.725
backlog stats	00:00:01.877	0.0%	2120997	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:17.337	0.0%	1011034	0.012	0.017	0.026
data write	00:04:42.231	0.3%	1116948	0.050	0.253	0.439
idle	23:42:29.138	99.5%	2120997	22.223	40.240	219.955
status file	00:00:16.117	0.0%	17127	0.560	0.941	5.925
TOTAL: 23:50:16.933	23:49:16.777	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:14.767	56.5%	2989	0.000	4.940	1856.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.316	1.2%	6578	0.000	0.048	2.684
readart	00:00:00.001	0.0%	12	0.000	0.083	0.500
TOTAL: 00:00:26.141	00:00:15.084	57.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1300	2309432	2304447	2787	2198	99%	367:36:32
2	news.nask.pl	2	187757	4942	177880	4935	2%	23:53:58
3	news.icm.edu.pl	41	267	150	103	14	56%	16:40:46
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		1344	2497456	2309539	180770	7147	92%	408:11:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	599.1 GB	101.8 MB	117.8 MB	599.3 GB	99%	272.4 KB
2	news.nask.pl	143.2 MB	709.6 MB	724.8 MB	1.5 GB	9%	163.6 KB
3	news.icm.edu.pl	3.2 MB	13.0 KB	649.0 KB	3.8 MB	83%	24.0 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		599.2 GB	811.4 MB	843.3 MB	600.8 GB	99%	271.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 15 04:15:02 - 04:59:59	51823	2.2%	19.21	15.6 GB	2.6%	6054.96
Aug 15 05:00:00 - 05:59:59	89185	3.9%	24.77	26.7 GB	4.4%	7770.39
Aug 15 06:00:00 - 06:59:59	92050	4.0%	25.57	27.3 GB	4.6%	7957.53
Aug 15 07:00:00 - 07:59:59	70918	3.1%	19.70	22.9 GB	3.8%	6665.71
Aug 15 08:00:00 - 08:59:59	86007	3.7%	23.89	26.5 GB	4.4%	7732.32
Aug 15 09:00:00 - 09:59:59	127830	5.5%	35.51	32.3 GB	5.4%	9400.12
Aug 15 10:00:00 - 10:59:59	53360	2.3%	14.82	17.8 GB	3.0%	5189.63
Aug 15 11:00:00 - 11:59:59	73709	3.2%	20.47	20.9 GB	3.5%	6101.20
Aug 15 12:00:00 - 12:59:59	99673	4.3%	27.69	23.5 GB	3.9%	6836.50
Aug 15 13:00:00 - 13:59:59	59499	2.6%	16.53	17.2 GB	2.9%	5008.48
Aug 15 14:00:00 - 14:59:59	83210	3.6%	23.11	23.0 GB	3.8%	6691.88
Aug 15 15:00:00 - 15:59:59	171339	7.4%	47.59	35.2 GB	5.9%	10256.26
Aug 15 16:00:00 - 16:59:59	144557	6.3%	40.15	30.8 GB	5.1%	8981.83
Aug 15 17:00:00 - 17:59:59	123461	5.3%	34.29	22.8 GB	3.8%	6652.09
Aug 15 18:00:00 - 18:59:59	138589	6.0%	38.50	24.8 GB	4.1%	7221.91
Aug 15 19:00:00 - 19:59:59	102138	4.4%	28.37	21.5 GB	3.6%	6265.09
Aug 15 20:00:00 - 20:59:59	63185	2.7%	17.55	21.5 GB	3.6%	6276.76
Aug 15 21:00:00 - 21:59:59	103672	4.5%	28.80	29.5 GB	4.9%	8592.23
Aug 15 22:00:00 - 22:59:59	110372	4.8%	30.66	29.3 GB	4.9%	8524.12
Aug 15 23:00:00 - 23:59:59	112155	4.9%	31.15	28.5 GB	4.8%	8306.85
Aug 16 00:00:00 - 00:59:59	75255	3.3%	20.90	20.9 GB	3.5%	6091.56
Aug 16 01:00:00 - 01:59:59	75307	3.3%	20.92	21.9 GB	3.7%	6392.02
Aug 16 02:00:00 - 02:59:59	90519	3.9%	25.14	26.7 GB	4.4%	7769.47
Aug 16 03:00:00 - 03:59:59	85256	3.7%	23.68	26.5 GB	4.4%	7704.99
Aug 16 04:00:00 - 04:15:03	25080	1.1%	27.77	6.2 GB	1.0%	7151.44
TOTAL: 24:00:01	2308149	100.0%	26.71	599.9 GB	100.0%	7279.94

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5059	9	3084	8	1958
2	newsfeed.pionier.net.pl	2601	180	1904	8	509
3	news.icm.edu.pl	29	0	1	0	28
TOTAL: 3		7689	189	4989	16	2495

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	122
uk.rec.cycling.moderated	15
it.tlc.cellulari.android	14
de.comp.office-pakete.ms-office.excel	8
de.rec.sf.startrek	8
comp.mobile.android	7
hr.ponuda.hardver.komponente	5
it.comp.os.win.windows8	4
de.comp.lang.vbclassic	4
it.comp.os.win.windows10	1
comp.sys.raspberry-pi	1
TOTAL: 11	189

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

Groups	Count
pl.praca.oferowana	8
pl.sci.matematyka	7
bit.listserv.catholic	1
TOTAL: 3	16

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1741
Binary in non-binary group	158
Scoring filter (8)	16
EMP (phl)	13
Scoring filter (16)	7
EMP (fsl)	5
Scoring filter (9)	5
HTML post	3
Scoring filter (11)	3
Too many newsgroups	3
Scoring filter (10)	2
Scoring filter (18)	1
Scoring filter (14)	1
Scoring filter (13)	1
TOTAL: 14	1959

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	401
	news.nask.pl	128
	news.icm.edu.pl	7
	TOTAL: 3	536
TOTAL: 1		536

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	377967	67659	184439	60	0	2053	17%	23:59:59
2	panorama	290956	1230	261094	87	3	277293	0%	23:59:59
3	poznan	6560	124	4621	18	0	0	1%	23:59:59
4	icm	338846	19	253663	11340	0	7	0%	23:59:59
5	okapi	0	0	0	0	0	1030	0%	23:57:52
TOTAL: 5		1014329	69032	703817	11505	3	280383	6%	119:57:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	38.1 GB	34.1 MB	38.2 GB	463.3 KB/s	591.2 KB	23:59:59
2	panorama	516.5 MB	28.9 MB	545.4 MB	6.5 KB/s	424.1 KB	23:59:59
3	icm	3.5 MB	5.3 GB	5.3 GB	64.1 KB/s	487.3 KB	23:59:59
4	poznan	558.3 KB	60.4 KB	618.8 KB	0.0 KB/s	4.4 KB	23:59:59
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:52
TOTAL: 5		38.7 GB	5.3 GB	44.0 GB	106.8 KB/s	572.7 KB	119:57:48

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	12	8.9 KB	6	0	0	00:00:19
TOTAL: 1		288	12	8.9 KB	6	0	0	00:00:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	672	12	8.9 KB	6	00:00:23
2	*.census.shodan.io	1	0	0.0 KB	0	00:00:01
TOTAL: 2		673	12	8.9 KB	6	00:00:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.656	5.152	00:00:19
mon.man.lodz.pl	1.276	4.320	00:00:03
ninja.census.shodan.io	0.000	0.020	00:00:01
david.man.lodz.pl	0.424	1.596	00:00:01
TOTAL: 4	3.356	11.088	00:00:25

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
ninja.census.shodan.io	1
TOTAL: 3	385

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	12	100.0%
TOTAL: 1		12	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	pl.comp.pecet	10
2	pl.comp.os.ms-windows.winnt	2
TOTAL: 2		12

Daily Usenet report

Aug 15 04:15:02 -- Aug 16 04:15:03