Daily Usenet report: Oct 2 04:15:03

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Oct 2 05:55:05 newsfeed pgpverify[26774]: pgpverify: verification failed
Oct 2 09:05:15 newsfeed nnrpd[1949]: SERVER perl filter not defined
Oct 2 10:20:45 newsfeed nnrpd[4694]: SERVER perl filter not defined
Oct 2 11:26:55 newsfeed nnrpd[7241]: SERVER perl filter not defined
Oct 2 13:37:59 newsfeed nnrpd[11949]: SERVER perl filter not defined
Oct 2 14:49:43 newsfeed nnrpd[14526]: SERVER perl filter not defined
Oct 2 14:52:16 newsfeed nnrpd[14589]: SERVER perl filter not defined
Oct 2 16:00:03 newsfeed overchan[22772]: input line too long, skipping
Oct 2 16:04:48 newsfeed overchan[22772]: input line too long, skipping
Oct 2 16:24:41 newsfeed overchan[22772]: input line too long, skipping
Oct 2 16:26:30 newsfeed overchan[22772]: input line too long, skipping
Oct 2 17:00:20 newsfeed overchan[22772]: input line too long, skipping
Oct 2 19:39:35 newsfeed overchan[22772]: input line too long, skipping
Oct 2 19:57:50 newsfeed overchan[22772]: input line too long, skipping
Oct 2 20:14:24 newsfeed overchan[22772]: input line too long, skipping
Oct 2 23:05:06 newsfeed nnrpd[32763]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3218588	97.1%	371.6 MB	96.7%
innd	47135	1.4%	6.8 MB	1.8%
innfeed	44455	1.3%	5.3 MB	1.4%
nnrpd	4768	0.1%	501.1 KB	0.1%
overchan	9	0.0%	1.6 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	3314960	100.0%	384.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3130008	87.4%
Negative hits	293273	8.2%
Positive hits	156894	4.4%
Cache misses	465	0.0%
TOTAL: 4	3580640	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.281	0.0%	485	0.000	8.827	76.000
article cleanup	00:00:38.341	0.0%	3218898	0.009	0.012	0.016
article logging	00:01:54.782	0.1%	3219641	0.022	0.036	0.210
article parse	01:01:19.899	4.3%	240929636	0.008	0.015	0.025
article write	00:19:58.199	1.4%	3133397	0.170	0.382	0.726
artlog/artcncl	00:00:00.015	0.0%	484	0.000	0.031	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:55.253	0.3%	260637851	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.483	0.0%	480	0.000	3.090	17.000
hishave/artcncl	00:00:00.657	0.0%	485	0.000	1.355	2.094
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:45.523	1.9%	3611015	0.048	0.461	4.325
history sync	00:00:00.007	0.0%	291	0.000	0.024	0.500
history write	01:18:38.538	5.5%	3158755	0.213	1.494	6.851
hiswrite/artcncl	00:00:00.002	0.0%	5	0.000	0.400	1.000
idle	13:16:41.227	55.3%	179338018	0.186	0.267	0.521
nntp read	01:30:36.204	6.3%	238707564	0.012	0.023	0.035
overview write	00:00:13.656	0.0%	3133397	0.003	0.004	0.007
perl filter	05:02:15.452	21.0%	3158755	3.206	5.741	7.887
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:25.577	0.3%	6458451	0.029	0.041	0.055
TOTAL: 24:00:01.093	23:09:29.096	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.784	0.0%	335492	0.000	0.002	0.012
article prepare	00:00:00.021	0.0%	77888	0.000	0.000	0.014
article read	00:01:47.230	0.1%	18510	0.000	5.793	298.000
backlog stats	00:00:00.679	0.0%	1186800	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:09.133	0.0%	715165	0.002	0.013	0.030
data write	00:00:36.740	0.0%	548750	0.014	0.067	0.107
idle	23:48:43.171	99.8%	1186800	27.891	72.231	771.051
status file	00:00:12.179	0.0%	16279	0.450	0.748	1.092
TOTAL: 23:51:58.160	23:51:29.937	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.074	61.7%	3035	0.000	6.285	95.556
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.180	0.6%	6630	0.000	0.027	1.500
readart	00:00:00.000	0.0%	8	0.000	0.000	0.000
TOTAL: 00:00:30.890	00:00:19.254	62.3%	-	-	-	-

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
hun-mnt@news.sztaki.hu	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	1114	3154007	3076627	7276	70104	97%	376:35:36
2	news.nask.pl	2	160847	53841	90633	16373	33%	23:56:53
3	news.icm.edu.pl	50	258	38	205	15	14%	17:33:53
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1174	3315119	3130513	98114	86492	94%	418:06:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	849.5 GB	16.7 GB	18.6 GB	884.7 GB	96%	294.8 KB
2	news.nask.pl	14.6 GB	133.8 MB	2.6 GB	17.3 GB	83%	259.0 KB
3	news.icm.edu.pl	1.1 MB	1.0 MB	1.3 MB	3.4 MB	31%	64.9 KB
4	localhost	10.0 KB	0.0 KB	0.0 KB	10.0 KB	100%	1.4 KB
TOTAL: 4		864.0 GB	16.8 GB	21.3 GB	902.1 GB	95%	294.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 2 04:15:03 - 04:59:59	81273	2.6%	30.13	25.7 GB	3.0%	9983.65
Oct 2 05:00:00 - 05:59:59	112269	3.6%	31.19	36.3 GB	4.2%	10559.60
Oct 2 06:00:00 - 06:59:59	126701	4.0%	35.19	40.2 GB	4.7%	11708.47
Oct 2 07:00:00 - 07:59:59	137653	4.4%	38.24	37.5 GB	4.3%	10922.77
Oct 2 08:00:00 - 08:59:59	131799	4.2%	36.61	39.2 GB	4.5%	11419.51
Oct 2 09:00:00 - 09:59:59	141798	4.5%	39.39	42.2 GB	4.9%	12291.72
Oct 2 10:00:00 - 10:59:59	143527	4.6%	39.87	43.7 GB	5.1%	12722.85
Oct 2 11:00:00 - 11:59:59	116975	3.7%	32.49	42.6 GB	4.9%	12413.69
Oct 2 12:00:00 - 12:59:59	111366	3.6%	30.93	35.5 GB	4.1%	10348.02
Oct 2 13:00:00 - 13:59:59	48892	1.6%	13.58	18.5 GB	2.1%	5386.35
Oct 2 14:00:00 - 14:59:59	119842	3.8%	33.29	22.0 GB	2.6%	6416.05
Oct 2 15:00:00 - 15:59:59	245811	7.9%	68.28	23.9 GB	2.8%	6972.63
Oct 2 16:00:00 - 16:59:59	142108	4.5%	39.47	40.1 GB	4.6%	11679.20
Oct 2 17:00:00 - 17:59:59	131180	4.2%	36.44	39.7 GB	4.6%	11575.56
Oct 2 18:00:00 - 18:59:59	133328	4.3%	37.04	41.4 GB	4.8%	12066.39
Oct 2 19:00:00 - 19:59:59	110918	3.5%	30.81	33.0 GB	3.8%	9615.06
Oct 2 20:00:00 - 20:59:59	138134	4.4%	38.37	40.5 GB	4.7%	11789.12
Oct 2 21:00:00 - 21:59:59	134727	4.3%	37.42	41.2 GB	4.8%	11994.30
Oct 2 22:00:00 - 22:59:59	117093	3.7%	32.53	36.9 GB	4.3%	10738.74
Oct 2 23:00:00 - 23:59:59	120608	3.9%	33.50	37.9 GB	4.4%	11035.00
Oct 3 00:00:00 - 00:59:59	147780	4.7%	41.05	33.1 GB	3.8%	9635.66
Oct 3 01:00:00 - 01:59:59	173429	5.5%	48.17	36.7 GB	4.3%	10683.91
Oct 3 02:00:00 - 02:59:59	151302	4.8%	42.03	36.3 GB	4.2%	10558.77
Oct 3 03:00:00 - 03:59:59	90484	2.9%	25.13	31.0 GB	3.6%	9026.38
Oct 3 04:00:00 - 04:15:02	21833	0.7%	24.21	7.6 GB	0.9%	8835.40
TOTAL: 23:59:59	3130830	100.0%	36.24	862.6 GB	100.0%	10468.99

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	70521	151	59604	2	10764
2	news.nask.pl	16738	30	850	1	15857
3	news.icm.edu.pl	15	0	4	0	11
TOTAL: 3		87274	181	60458	3	26632

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	98
de.rec.sf.startrek	30
it.comp.os.win.windows10	14
it.tlc.cellulari.android	13
it.comp.os.win.windows7	9
hr.ponuda.hardver.komponente	4
comp.sys.raspberry-pi	4
hr.rec.glazba.hifi	2
de.comp.lang.php	2
de.comp.office-pakete.ms-office.word	2
comp.mobile.android	1
alt.sex.telephones	1
rec.arts.tv.x-factor	1
TOTAL: 13	181

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

Groups	Count
pl.praca.oferowana	2
bit.listserv.catholic	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	24164
EMP (md5)	1573
EMP (phl)	62
Scoring filter (8)	56
Scoring filter (10)	17
Scoring filter (9)	12
Too many newsgroups	6
HTML post	1
TOTAL: 8	25891

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	412
	news.nask.pl	329
	TOTAL: 2	741
TOTAL: 1		741

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	211324	13797	116590	1	0	11	6%	23:59:57
2	panorama	160634	2586	130151	658	16	205010	1%	23:59:57
3	poznan	18181	294	10405	34	0	1	1%	23:59:46
4	icm	228842	79	191292	1672	0	27	0%	23:59:57
5	okapi	0	0	0	0	0	1634	0%	23:57:49
TOTAL: 5		618981	16756	448438	2365	16	206683	2%	119:57:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	4.6 GB	2.4 KB	4.6 GB	56.3 KB/s	352.6 KB	23:59:57
2	panorama	16.9 MB	6.0 MB	22.9 MB	0.3 KB/s	7.2 KB	23:59:57
3	icm	3.2 MB	562.9 MB	566.0 MB	6.7 KB/s	331.0 KB	23:59:57
4	poznan	1.3 MB	242.0 KB	1.5 MB	0.0 KB/s	4.8 KB	23:59:46
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
TOTAL: 5		4.7 GB	569.2 MB	5.2 GB	12.7 KB/s	286.1 KB	119:57:26

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
okapi	488.4 KB
TOTAL: 2	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	8	9.7 KB	4	0	00:00:24
2	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 2		295	8	9.7 KB	4	7	00:00:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	679	8	9.7 KB	4	7	0	00:00:30
TOTAL: 1		679	8	9.7 KB	4	7	0	00:00:30

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	0.960	4.232	00:00:24
mon.man.lodz.pl	1.000	4.492	00:00:04
david.man.lodz.pl	0.284	1.468	00:00:01
isvcs01.man.lodz.pl	0.032	0.116	00:00:00
TOTAL: 4	2.276	10.308	00:00:30

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	4
2	pl.comp.os.ms-windows.winnt	4
TOTAL: 2		8

Daily Usenet report

Oct 2 04:15:03 -- Oct 3 04:15:02