Daily Usenet report: Apr 5 04:15:03

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Apr 5 08:10:42 newsfeed nnrpd[5491]: SERVER perl filter not defined
Apr 5 08:12:54 newsfeed nnrpd[5502]: SERVER perl filter not defined
Apr 5 10:43:37 newsfeed nnrpd[11098]: SERVER perl filter not defined
Apr 5 11:59:39 newsfeed overchan[14691]: input line too long, skipping
Apr 5 12:06:57 newsfeed nnrpd[14109]: SERVER perl filter not defined
Apr 5 12:07:12 newsfeed nnrpd[14110]: SERVER perl filter not defined
Apr 5 12:10:23 newsfeed nnrpd[14333]: SERVER perl filter not defined
Apr 5 15:09:00 newsfeed overchan[14691]: input line too long, skipping
Apr 5 15:09:59 newsfeed overchan[14691]: input line too long, skipping
Apr 5 15:15:23 newsfeed overchan[14691]: input line too long, skipping
Apr 5 15:29:05 newsfeed nnrpd[21409]: SERVER perl filter not defined
Apr 5 15:31:42 newsfeed nnrpd[21628]: SERVER perl filter not defined
Apr 5 16:06:37 newsfeed overchan[14691]: input line too long, skipping
Apr 5 19:57:45 newsfeed overchan[14691]: input line too long, skipping
Apr 5 22:30:03 newsfeed nnrpd[4384]: SERVER perl filter not defined
Apr 5 23:39:40 newsfeed overchan[14691]: input line too long, skipping
Apr 6 03:45:36 newsfeed overchan[14691]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3001814	97.6%	333.2 MB	97.2%
innfeed	45488	1.5%	5.4 MB	1.6%
innd	22916	0.7%	3.7 MB	1.1%
nnrpd	4828	0.2%	508.9 KB	0.1%
overchan	9	0.0%	1.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3075056	100.0%	342.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2991004	96.3%
Negative hits	104905	3.4%
Positive hits	10491	0.3%
Cache misses	102	0.0%
TOTAL: 4	3106502	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.909	0.0%	170	0.000	17.112	181.000
article cleanup	00:00:42.479	0.0%	3001501	0.010	0.014	0.019
article logging	00:02:25.409	0.2%	3002108	0.030	0.048	0.184
article parse	00:54:00.021	3.7%	94808270	0.020	0.034	0.043
article write	00:21:41.209	1.5%	2996819	0.258	0.434	1.059
artlog/artcncl	00:00:00.005	0.0%	161	0.000	0.031	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:17.929	0.2%	109761992	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.260	0.0%	164	0.000	1.585	23.000
hishave/artcncl	00:00:00.098	0.0%	170	0.000	0.576	6.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:41:00.073	2.8%	3115576	0.055	0.790	5.334
history sync	00:00:00.019	0.0%	290	0.000	0.066	1.000
history write	01:56:56.052	8.1%	2999643	0.263	2.339	8.157
hiswrite/artcncl	00:00:00.322	0.0%	6	0.000	53.667	321.000
idle	12:34:15.720	52.4%	70166913	0.339	0.645	1.375
nntp read	01:10:51.852	4.9%	92434039	0.029	0.046	0.064
overview write	00:00:18.038	0.0%	2996819	0.004	0.006	0.008
perl filter	05:31:26.759	23.0%	2999643	4.226	6.630	8.507
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:27.086	0.4%	6013802	0.046	0.064	0.083
TOTAL: 24:00:01.953	23:22:26.240	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.152	0.0%	57927	0.000	0.003	0.010
article prepare	00:00:00.007	0.0%	18404	0.000	0.000	0.019
article read	00:01:12.958	0.1%	4659	0.333	15.660	286.000
backlog stats	00:00:00.174	0.0%	175704	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	50	0.000	0.000	0.000
data read	00:00:02.082	0.0%	90604	0.007	0.023	0.054
data write	00:00:08.312	0.0%	75242	0.026	0.110	0.380
idle	23:52:25.573	99.9%	175704	147.472	489.150	1020.516
status file	00:00:16.204	0.0%	16322	0.625	0.993	5.136
TOTAL: 23:54:25.573	23:54:05.462	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:16.699	51.5%	3107	0.000	5.375	21.778
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.332	1.0%	6858	0.000	0.048	1.667
readart	00:00:00.014	0.0%	60	0.000	0.233	1.167
TOTAL: 00:00:32.400	00:00:17.045	52.6%	-	-	-	-

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	1162	2996320	2987349	6948	2023	99%	429:30:41
2	news.nask.pl	2	13017	8935	1417	2665	68%	23:54:37
3	news.icm.edu.pl	49	218	16	201	1	7%	16:00:51
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		1223	3009564	2996309	8566	4689	99%	469:26:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	694.8 GB	17.1 MB	21.9 MB	694.8 GB	99%	243.7 KB
2	news.nask.pl	35.9 MB	253.6 KB	16.4 MB	52.5 MB	68%	4.6 KB
3	news.icm.edu.pl	366.8 KB	0.0 KB	156.8 KB	523.6 KB	70%	30.8 KB
4	localhost	14.2 KB	0.0 KB	0.0 KB	14.2 KB	100%	1.6 KB
TOTAL: 4		694.8 GB	17.4 MB	38.4 MB	694.9 GB	99%	242.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 5 04:15:03 - 04:59:59	110303	3.7%	40.90	24.3 GB	3.5%	9444.58
Apr 5 05:00:00 - 05:59:59	130934	4.4%	36.37	34.0 GB	4.9%	9896.33
Apr 5 06:00:00 - 06:59:59	122830	4.1%	34.12	30.9 GB	4.4%	8999.57
Apr 5 07:00:00 - 07:59:59	124329	4.1%	34.54	30.7 GB	4.4%	8950.36
Apr 5 08:00:00 - 08:59:59	154263	5.1%	42.85	35.9 GB	5.2%	10465.31
Apr 5 09:00:00 - 09:59:59	98041	3.3%	27.23	25.8 GB	3.7%	7520.36
Apr 5 10:00:00 - 10:59:59	152284	5.1%	42.30	34.2 GB	4.9%	9965.37
Apr 5 11:00:00 - 11:59:59	146709	4.9%	40.75	37.1 GB	5.3%	10803.32
Apr 5 12:00:00 - 12:59:59	136319	4.5%	37.87	36.0 GB	5.2%	10487.46
Apr 5 13:00:00 - 13:59:59	120354	4.0%	33.43	32.4 GB	4.7%	9423.84
Apr 5 14:00:00 - 14:59:59	116878	3.9%	32.47	26.4 GB	3.8%	7688.19
Apr 5 15:00:00 - 15:59:59	88059	2.9%	24.46	18.9 GB	2.7%	5514.12
Apr 5 16:00:00 - 16:59:59	142030	4.7%	39.45	27.1 GB	3.9%	7900.99
Apr 5 17:00:00 - 17:59:59	117398	3.9%	32.61	28.0 GB	4.0%	8143.67
Apr 5 18:00:00 - 18:59:59	100375	3.3%	27.88	21.2 GB	3.1%	6180.89
Apr 5 19:00:00 - 19:59:59	102639	3.4%	28.51	28.9 GB	4.2%	8425.49
Apr 5 20:00:00 - 20:59:59	114496	3.8%	31.80	30.7 GB	4.4%	8932.74
Apr 5 21:00:00 - 21:59:59	136347	4.6%	37.87	32.0 GB	4.6%	9330.42
Apr 5 22:00:00 - 22:59:59	121569	4.1%	33.77	22.9 GB	3.3%	6671.77
Apr 5 23:00:00 - 23:59:59	125255	4.2%	34.79	24.4 GB	3.5%	7097.96
Apr 6 00:00:00 - 00:59:59	117109	3.9%	32.53	21.2 GB	3.0%	6169.31
Apr 6 01:00:00 - 01:59:59	128117	4.3%	35.59	25.8 GB	3.7%	7500.53
Apr 6 02:00:00 - 02:59:59	153450	5.1%	42.62	31.7 GB	4.6%	9227.22
Apr 6 03:00:00 - 03:59:59	104956	3.5%	29.15	27.3 GB	3.9%	7937.76
Apr 6 04:00:00 - 04:15:03	31307	1.0%	34.67	7.6 GB	1.1%	8872.34
TOTAL: 24:00:00	2996351	100.0%	34.68	695.3 GB	100.0%	8438.92

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	2845	10	106	13	2716
2	newsfeed.pionier.net.pl	2451	198	1763	3	487
3	news.icm.edu.pl	2	0	0	0	2
TOTAL: 3		5298	208	1869	16	3205

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	133
it.tlc.cellulari.android	42
it.comp.os.win.windows10	17
de.rec.sf.startrek	8
comp.mobile.android	4
de.comp.office-pakete.ms-office.word	2
it.comp.os.linux.ubuntu	1
openwatcom.users.assembler	1
TOTAL: 8	208

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2338
Binary in non-binary group	122
EMP (phl)	71
Scoring filter (8)	24
Too many newsgroups	18
Scoring filter (9)	8
Scoring filter (16)	5
Scoring filter (12)	3
HTML post	2
Scoring filter (10)	2
Scoring filter (18)	2
Scoring filter (11)	1
Scoring filter (19)	1
Scoring filter (247)	1
TOTAL: 14	2598

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	428
	news.nask.pl	178
	news.icm.edu.pl	1
	TOTAL: 3	607
TOTAL: 1		607

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	42351	943	13797	313	144	42845	2%	23:59:58
2	poznan	14919	155	8824	8	0	0	1%	23:59:57
3	icm	25937	35	17571	2271	0	0	0%	23:59:58
4	nask	3411	1	2603	0	0	0	0%	23:59:58
5	okapi	0	0	0	0	0	1227	0%	23:57:51
TOTAL: 5		86618	1134	42795	2592	144	44072	1%	119:57:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	5.3 MB	860.9 MB	866.2 MB	10.3 KB/s	384.6 KB	23:59:58
2	panorama	2.8 MB	2.7 MB	5.5 MB	0.1 KB/s	4.5 KB	23:59:58
3	poznan	497.3 KB	35.0 KB	532.3 KB	0.0 KB/s	3.3 KB	23:59:57
4	nask	2.2 KB	0.0 KB	2.2 KB	0.0 KB/s	2.2 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		8.6 MB	863.6 MB	872.3 MB	2.1 KB/s	239.7 KB	119:57:42

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
panorama	488.4 KB
TOTAL: 2	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	60	54.1 KB	28	0	00:00:23
2	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:02
TOTAL: 2		297	60	54.1 KB	28	9	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	681	60	54.1 KB	28	9	0	00:00:31
TOTAL: 1		681	60	54.1 KB	28	9	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.884	6.340	00:00:23
mon.man.lodz.pl	1.672	5.968	00:00:03
isvcs01.man.lodz.pl	0.088	0.168	00:00:02
david.man.lodz.pl	0.480	1.732	00:00:01
TOTAL: 4	4.124	14.208	00:00:31

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	60	100.0%
TOTAL: 1		60	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	46
2	pl.comp.pecet	14
TOTAL: 2		60

Daily Usenet report

Apr 5 04:15:03 -- Apr 6 04:15:03