Daily Usenet report: Apr 12 04:15:03

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Apr 12 08:08:12 newsfeed nnrpd[544]: SERVER perl filter not defined
Apr 12 08:09:16 newsfeed nnrpd[559]: SERVER perl filter not defined
Apr 12 08:12:19 newsfeed overchan[23633]: input line too long, skipping
Apr 12 11:07:47 newsfeed nnrpd[7328]: SERVER perl filter not defined
Apr 12 11:22:23 newsfeed nnrpd[7798]: SERVER perl filter not defined
Apr 12 13:59:09 newsfeed nnrpd[13471]: SERVER perl filter not defined
Apr 12 13:59:57 newsfeed nnrpd[13491]: SERVER perl filter not defined
Apr 12 14:01:06 newsfeed nnrpd[13583]: SERVER perl filter not defined
Apr 12 14:02:52 newsfeed nnrpd[13599]: SERVER perl filter not defined
Apr 12 14:05:19 newsfeed nnrpd[13812]: SERVER perl filter not defined
Apr 12 14:10:28 newsfeed nnrpd[13940]: SERVER perl filter not defined
Apr 12 14:45:06 newsfeed overchan[23633]: input line too long, skipping
Apr 12 15:29:31 newsfeed nnrpd[16772]: SERVER perl filter not defined
Apr 12 21:31:00 newsfeed nnrpd[30102]: SERVER perl filter not defined
Apr 12 22:57:36 newsfeed nnrpd[818]: SERVER perl filter not defined
Apr 13 00:06:02 newsfeed nnrpd[3396]: SERVER perl filter not defined
Apr 13 00:15:34 newsfeed nnrpd[3752]: SERVER perl filter not defined
Apr 13 02:57:05 newsfeed overchan[23633]: input line too long, skipping
Apr 13 02:57:25 newsfeed overchan[23633]: input line too long, skipping
Apr 13 03:00:40 newsfeed overchan[23633]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3019479	97.6%	331.1 MB	97.1%
innfeed	47497	1.5%	5.6 MB	1.7%
innd	23323	0.8%	3.8 MB	1.1%
nnrpd	4855	0.2%	514.2 KB	0.1%
overchan	6	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3095161	100.0%	341.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3001960	95.8%
Negative hits	119403	3.8%
Positive hits	10747	0.3%
Cache misses	143	0.0%
TOTAL: 4	3132253	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.815	0.0%	199	0.000	19.171	504.000
article cleanup	00:00:26.318	0.0%	3017848	0.006	0.009	0.014
article logging	00:01:38.572	0.1%	3018484	0.022	0.033	0.082
article parse	00:28:24.956	2.0%	86051539	0.012	0.020	0.038
article write	00:12:20.791	0.9%	3014407	0.149	0.246	0.466
artlog/artcncl	00:00:00.011	0.0%	190	0.000	0.058	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:38.557	0.2%	101173854	0.001	0.002	0.003
hisgrep/artcncl	00:00:02.375	0.0%	195	0.000	12.179	241.000
hishave/artcncl	00:00:00.165	0.0%	199	0.000	0.829	11.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:19:18.987	1.3%	3147892	0.042	0.368	2.139
history sync	00:00:00.018	0.0%	290	0.000	0.062	1.000
history write	02:15:51.689	9.4%	3016584	0.178	2.702	12.717
hiswrite/artcncl	00:00:00.002	0.0%	4	0.000	0.500	0.667
idle	16:14:55.139	67.7%	71967055	0.492	0.813	2.286
nntp read	00:41:13.452	2.9%	83838452	0.019	0.030	0.047
overview write	00:00:11.589	0.0%	3014407	0.002	0.004	0.006
perl filter	03:17:07.811	13.7%	3016583	2.581	3.921	6.310
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:27.568	0.3%	6047583	0.034	0.044	0.064
TOTAL: 24:00:03.896	23:38:41.815	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.123	0.0%	58227	0.000	0.002	0.013
article prepare	00:00:00.004	0.0%	7600	0.000	0.001	0.031
article read	00:00:43.228	0.1%	1893	0.000	22.836	425.500
backlog stats	00:00:00.101	0.0%	166617	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.068	0.0%	87230	0.002	0.012	0.028
data write	00:00:02.967	0.0%	68716	0.021	0.043	0.101
idle	23:53:03.662	99.9%	166617	334.860	516.056	966.446
status file	00:00:10.317	0.0%	16505	0.422	0.625	1.282
TOTAL: 23:54:15.898	23:54:01.470	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:27.605	73.1%	3120	0.000	8.848	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.171	0.5%	6782	0.000	0.025	0.500
readart	00:00:00.000	0.0%	26	0.000	0.000	0.000
TOTAL: 00:00:37.779	00:00:27.776	73.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1418	3015874	3007355	6986	1533	99%	440:02:01
2	news.nask.pl	3	12837	8629	2293	1915	67%	24:02:31
3	localhost	16	15	15	0	0	100%	00:00:00
4	news.icm.edu.pl	42	254	12	230	12	4%	15:44:17
TOTAL: 4		1479	3028980	3016011	9509	3460	99%	479:48:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	609.6 GB	9.9 MB	43.8 MB	609.7 GB	99%	212.5 KB
2	news.nask.pl	32.0 MB	524.8 KB	17.8 MB	50.3 MB	63%	4.9 KB
3	news.icm.edu.pl	229.9 KB	0.0 KB	469.0 KB	698.9 KB	32%	29.1 KB
4	localhost	23.9 KB	0.0 KB	0.0 KB	23.9 KB	100%	1.6 KB
TOTAL: 4		609.6 GB	10.4 MB	62.1 MB	609.7 GB	99%	211.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 12 04:15:03 - 04:59:59	82741	2.7%	30.68	20.7 GB	3.4%	8039.81
Apr 12 05:00:00 - 05:59:59	79153	2.6%	21.99	22.8 GB	3.7%	6634.61
Apr 12 06:00:00 - 06:59:59	94838	3.1%	26.34	25.7 GB	4.2%	7480.37
Apr 12 07:00:00 - 07:59:59	89531	3.0%	24.87	22.3 GB	3.6%	6490.05
Apr 12 08:00:00 - 08:59:59	109430	3.6%	30.40	24.3 GB	4.0%	7071.34
Apr 12 09:00:00 - 09:59:59	77204	2.6%	21.45	16.6 GB	2.7%	4847.31
Apr 12 10:00:00 - 10:59:59	115372	3.8%	32.05	22.3 GB	3.7%	6497.90
Apr 12 11:00:00 - 11:59:59	136658	4.5%	37.96	26.5 GB	4.3%	7731.59
Apr 12 12:00:00 - 12:59:59	148505	4.9%	41.25	28.8 GB	4.7%	8376.60
Apr 12 13:00:00 - 13:59:59	137761	4.6%	38.27	24.3 GB	4.0%	7075.11
Apr 12 14:00:00 - 14:59:59	125879	4.2%	34.97	22.6 GB	3.7%	6579.13
Apr 12 15:00:00 - 15:59:59	112400	3.7%	31.22	21.1 GB	3.5%	6140.61
Apr 12 16:00:00 - 16:59:59	168526	5.6%	46.81	29.3 GB	4.8%	8525.31
Apr 12 17:00:00 - 17:59:59	159838	5.3%	44.40	27.5 GB	4.5%	8008.84
Apr 12 18:00:00 - 18:59:59	217729	7.2%	60.48	36.0 GB	5.9%	10480.77
Apr 12 19:00:00 - 19:59:59	137260	4.6%	38.13	27.4 GB	4.5%	7972.97
Apr 12 20:00:00 - 20:59:59	105758	3.5%	29.38	24.9 GB	4.1%	7254.27
Apr 12 21:00:00 - 21:59:59	122756	4.1%	34.10	28.2 GB	4.6%	8223.93
Apr 12 22:00:00 - 22:59:59	117514	3.9%	32.64	29.9 GB	4.9%	8697.13
Apr 12 23:00:00 - 23:59:59	102531	3.4%	28.48	21.4 GB	3.5%	6234.82
Apr 13 00:00:00 - 00:59:59	115813	3.8%	32.17	21.2 GB	3.5%	6175.25
Apr 13 01:00:00 - 01:59:59	156042	5.2%	43.34	28.5 GB	4.7%	8306.03
Apr 13 02:00:00 - 02:59:59	109531	3.6%	30.43	23.5 GB	3.8%	6835.25
Apr 13 03:00:00 - 03:59:59	159616	5.3%	44.34	27.8 GB	4.6%	8104.05
Apr 13 04:00:00 - 04:15:02	32807	1.1%	36.37	7.4 GB	1.2%	8613.35
TOTAL: 23:59:59	3015193	100.0%	34.90	610.9 GB	100.0%	7413.61

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	2045	13	141	1	1890
2	newsfeed.pionier.net.pl	2039	167	1139	0	733
3	news.icm.edu.pl	12	0	0	0	12
TOTAL: 3		4096	180	1280	1	2635

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	103
it.tlc.cellulari.android	27
it.comp.os.win.windows10	21
comp.mobile.android	12
de.rec.sf.startrek	6
uk.rec.cycling.moderated	5
comp.sys.raspberry-pi	3
it.politica.m5s	1
de.comp.lang.php	1
it.comp.os.win.windows7	1
TOTAL: 10	180

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 (md5)	1592
Binary in non-binary group	325
EMP (phl)	18
Scoring filter (8)	11
EMP (fsl)	11
Scoring filter (10)	8
Too many newsgroups	6
Scoring filter (18)	6
Scoring filter (9)	5
HTML post	5
Scoring filter (11)	3
Scoring filter (16)	3
Poison newsgroup	2
Scoring filter (12)	2
Scoring filter (13)	1
TOTAL: 15	1998

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	506
	news.nask.pl	130
	TOTAL: 2	636
TOTAL: 1		636

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	43104	556	14949	108	6	42805	1%	23:59:57
2	poznan	13969	303	8288	22	0	0	2%	23:59:57
3	icm	25721	71	18458	143	0	1	0%	23:59:53
4	nask	4693	6	3437	0	0	0	0%	23:59:22
5	okapi	0	0	0	0	0	1083	0%	23:57:50
TOTAL: 5		87487	936	45132	273	6	43889	1%	119:56:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	4.1 MB	48.5 MB	52.6 MB	0.6 KB/s	251.5 KB	23:59:53
2	panorama	2.9 MB	344.4 KB	3.2 MB	0.0 KB/s	5.0 KB	23:59:57
3	poznan	997.4 KB	163.6 KB	1.1 MB	0.0 KB/s	3.6 KB	23:59:57
4	nask	13.5 KB	0.0 KB	13.5 KB	0.0 KB/s	2.3 KB	23:59:22
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		7.9 MB	49.0 MB	56.9 MB	0.1 KB/s	48.2 KB	119:56:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	26	32.0 KB	13	0	00:00:20
2	isvcs01.man.lodz.pl	15	0	0.0 KB	0	15	00:00:02
TOTAL: 2		303	26	32.0 KB	13	15	00:00:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	686	26	32.0 KB	13	15	00:00:27
2	unresolved	2	0	0.0 KB	0	0	00:00:10
TOTAL: 2		688	26	32.0 KB	13	15	00:00:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.052	3.912	00:00:20
185.48.193.90	0.004	0.008	00:00:10
mon.man.lodz.pl	1.008	3.760	00:00:02
isvcs01.man.lodz.pl	0.096	0.264	00:00:02
david.man.lodz.pl	0.340	1.224	00:00:00
TOTAL: 5	2.500	9.168	00:00:37

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
185.48.193.90	2
TOTAL: 3	385

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Apr 12 04:15:03 -- Apr 13 04:15:02