Daily Usenet report: Apr 5 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Apr 5 08:45:49 newsfeed nnrpd[32111]: SERVER perl filter not defined
Apr 5 08:47:08 newsfeed nnrpd[32134]: SERVER perl filter not defined
Apr 5 19:04:04 newsfeed overchan[2643]: input line too long, skipping
Apr 6 00:40:08 newsfeed nnrpd[32167]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2788183	98.5%	329.0 MB	98.2%
innd	33508	1.2%	4.7 MB	1.4%
innfeed	4869	0.2%	684.4 KB	0.2%
nnrpd	4774	0.2%	494.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2831336	100.0%	334.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2781514	96.2%
Negative hits	95695	3.3%
Positive hits	13405	0.5%
Cache misses	211	0.0%
TOTAL: 4	2890825	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.519	0.0%	304	0.000	18.155	334.000
article cleanup	00:00:40.209	0.0%	2786163	0.000	0.014	0.029
article logging	00:01:38.496	0.1%	2786732	0.016	0.035	1.028
article parse	01:18:51.123	5.5%	211890126	0.012	0.022	0.067
article write	01:05:24.209	4.5%	2770411	0.358	1.416	5.215
artlog/artcncl	00:00:00.006	0.0%	296	0.000	0.020	1.000
artlog/artparse	00:00:00.020	0.0%	487	0.000	0.041	1.000
data move	00:04:32.020	0.3%	229892352	0.000	0.001	0.004
hisgrep/artcncl	00:00:02.790	0.0%	276	0.000	10.109	304.000
hishave/artcncl	00:00:00.067	0.0%	304	0.000	0.220	10.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:33:36.835	2.3%	2892396	0.106	0.697	5.562
history sync	00:00:00.012	0.0%	294	0.000	0.041	1.000
history write	00:50:40.179	3.5%	2783212	0.208	1.092	7.018
hiswrite/artcncl	00:00:00.207	0.0%	28	0.000	7.393	205.000
idle	12:03:10.652	50.3%	151877166	0.214	0.286	11.969
nntp read	01:19:12.751	5.5%	209666688	0.015	0.023	0.038
overview write	00:00:16.323	0.0%	2770411	0.000	0.006	0.012
perl filter	06:02:56.152	25.2%	2782725	5.770	7.825	10.477
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:13.341	0.4%	5771613	0.035	0.054	0.107
TOTAL: 23:58:47.489	23:26:20.911	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.842	0.0%	243536	0.000	0.003	0.250
article prepare	00:00:00.195	0.0%	863140	0.000	0.000	0.250
article read	00:04:50.740	0.3%	215575	0.000	1.349	212.000
backlog stats	00:00:01.183	0.0%	1153310	0.000	0.001	0.043
callbacks	00:00:00.000	0.0%	6	0.000	0.000	0.000
data read	00:00:06.712	0.0%	515697	0.000	0.013	0.370
data write	00:01:40.014	0.1%	648859	0.000	0.154	0.437
idle	23:51:38.902	99.5%	1153310	11.701	74.480	8661.143
status file	00:00:00.920	0.0%	1212	0.000	0.759	4.000
TOTAL: 23:58:48.617	23:58:19.508	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:12:17.827	99.6%	3044	0.000	1425.042	600054.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.306	0.0%	67203	0.000	0.019	1.000
readart	00:00:01.890	0.0%	20	0.000	94.500	315.000
TOTAL: 01:12:35.221	01:12:21.023	99.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	151
name	4
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	194

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1049	2769471	2758919	6718	3834	99%	427:55:35
2	news.nask.pl	3	26617	11309	2921	12387	42%	23:56:13
3	news.icm.edu.pl	42	375	42	279	54	11%	17:58:13
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1098	2796466	2770273	9918	16275	99%	469:50:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	912.1 GB	27.0 MB	217.5 MB	912.3 GB	99%	346.3 KB
2	news.nask.pl	59.9 MB	1.1 MB	124.2 MB	185.1 MB	32%	8.0 KB
3	news.icm.edu.pl	845.5 KB	0.0 KB	888.5 KB	1.7 MB	48%	18.1 KB
4	localhost	4.6 KB	0.0 KB	0.0 KB	4.6 KB	100%	1.5 KB
TOTAL: 4		912.1 GB	28.1 MB	342.6 MB	912.5 GB	99%	343.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 5 04:15:02 - 04:59:59	83346	3.0%	30.89	29.5 GB	3.2%	11460.19
Apr 5 05:00:00 - 05:59:59	103584	3.7%	28.77	39.3 GB	4.3%	11457.16
Apr 5 06:00:00 - 06:59:59	102709	3.7%	28.53	40.5 GB	4.4%	11800.86
Apr 5 07:00:00 - 07:59:59	111480	4.0%	30.97	41.8 GB	4.6%	12178.13
Apr 5 08:00:00 - 08:59:59	109631	4.0%	30.45	40.0 GB	4.4%	11663.57
Apr 5 09:00:00 - 09:59:59	110125	4.0%	30.59	40.2 GB	4.4%	11702.88
Apr 5 10:00:00 - 10:59:59	103749	3.7%	28.82	38.0 GB	4.2%	11067.55
Apr 5 11:00:00 - 11:59:59	109349	3.9%	30.37	39.2 GB	4.3%	11411.30
Apr 5 12:00:00 - 12:59:59	117336	4.2%	32.59	39.1 GB	4.3%	11378.86
Apr 5 13:00:00 - 13:59:59	128188	4.6%	35.61	40.0 GB	4.4%	11654.81
Apr 5 14:00:00 - 14:59:59	117246	4.2%	32.57	38.2 GB	4.2%	11132.16
Apr 5 15:00:00 - 15:59:59	110079	4.0%	30.58	36.2 GB	4.0%	10552.76
Apr 5 16:00:00 - 16:59:59	115335	4.2%	32.04	37.4 GB	4.1%	10905.98
Apr 5 17:00:00 - 17:59:59	123583	4.5%	34.33	39.0 GB	4.3%	11364.98
Apr 5 18:00:00 - 18:59:59	132634	4.8%	36.84	37.8 GB	4.2%	11021.79
Apr 5 19:00:00 - 19:59:59	129450	4.7%	35.96	37.0 GB	4.1%	10784.85
Apr 5 20:00:00 - 20:59:59	126619	4.6%	35.17	36.1 GB	4.0%	10528.42
Apr 5 21:00:00 - 21:59:59	121307	4.4%	33.70	34.8 GB	3.8%	10124.01
Apr 5 22:00:00 - 22:59:59	127673	4.6%	35.46	35.4 GB	3.9%	10318.85
Apr 5 23:00:00 - 23:59:59	119213	4.3%	33.11	35.8 GB	3.9%	10433.33
Apr 6 00:00:00 - 00:59:59	92282	3.3%	25.63	31.2 GB	3.4%	9082.08
Apr 6 01:00:00 - 01:59:59	116425	4.2%	32.34	40.0 GB	4.4%	11639.10
Apr 6 02:00:00 - 02:59:59	122407	4.4%	34.00	40.3 GB	4.4%	11730.52
Apr 6 03:00:00 - 03:59:59	113565	4.1%	31.55	36.4 GB	4.0%	10612.96
Apr 6 04:00:00 - 04:15:02	23768	0.9%	26.35	7.9 GB	0.9%	9173.63
TOTAL: 24:00:00	2771083	100.0%	32.07	911.3 GB	100.0%	11059.76

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12616	46	323	5	12242
2	newsfeed.pionier.net.pl	4144	158	3120	4	862
3	news.icm.edu.pl	44	0	0	0	44
TOTAL: 3		16804	204	3443	9	13148

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	72
comp.sys.raspberry-pi	53
it.tlc.cellulari.android	37
it.comp.os.win.windows10	24
it.comp.os.win.windows7	5
comp.mobile.android	4
de.comp.lang.php	3
nl.comp.os.linux.techniek	2
uk.net.providers.gradwell	1
uk.rec.cycling.moderated	1
alt.sex.telephones	1
hr.ponuda.hardver.komponente	1
TOTAL: 12	204

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

Groups	Count
pl.praca.oferowana	5
pl.sci.matematyka	3
bit.listserv.catholic	1
TOTAL: 3	9

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11599
Binary in non-binary group	180
Scoring filter (8)	146
Scoring filter (9)	98
Scoring filter (10)	39
Scoring filter (17)	7
Scoring filter (11)	6
Scoring filter (12)	6
Excessively crossposted test article	4
Scoring filter (14)	3
HTML post	3
Too many newsgroups	1
TOTAL: 12	12092

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	231438	200947	17021	9916	6	6981	86%	23:50:01
2	panorama	30489	1622	26270	645	20	207788	5%	24:01:52
3	poznan	17258	42	11316	5	0	0	0%	24:00:00
4	nask	8661	12	4424	2393	0	0	0%	24:00:00
5	okapi	0	0	0	0	0	2049	0%	23:50:01
TOTAL: 5		287846	202623	59031	12959	26	216818	70%	119:41:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	36.1 GB	1.7 GB	37.8 GB	461.4 KB/s	187.8 KB	23:50:01
2	panorama	6.8 MB	8.3 MB	15.1 MB	0.2 KB/s	6.8 KB	24:01:52
3	poznan	145.0 KB	35.4 KB	180.4 KB	0.0 KB/s	3.8 KB	24:00:00
4	nask	35.3 KB	907.5 MB	907.6 MB	10.8 KB/s	386.4 KB	24:00:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		36.1 GB	2.6 GB	38.7 GB	94.1 KB/s	188.0 KB	119:41:54

Backlog files shrunk by innfeed:

Server	Size
panorama	18.1 MB
okapi	488.3 KB
TOTAL: 2	18.6 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	14	13.9 KB	6	0	00:00:26
2	pc-tom.man.lodz.pl	4	6	8.7 KB	3	0	01:12:02
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		295	20	22.6 KB	9	3	01:12:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	678	20	22.6 KB	9	3	01:12:34
2	*.us-west-2.compute.amazonaws.com	2	0	0.0 KB	0	0	00:00:00
TOTAL: 2		680	20	22.6 KB	9	3	01:12:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-tom.man.lodz.pl	0.044	0.196	01:12:02
web.man.lodz.pl	1.045	3.868	00:00:26
mon.man.lodz.pl	0.923	3.079	00:00:03
david.man.lodz.pl	0.303	0.997	00:00:01
isvcs01.man.lodz.pl	0.008	0.046	00:00:00
ec2-35-163-84-238.us-west-2.compute.amazonaws.com	0.006	0.006	00:00:00
ec2-52-26-9-177.us-west-2.compute.amazonaws.com	0.000	0.016	00:00:00
TOTAL: 7	2.329	8.208	01:12:34

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	287
david.man.lodz.pl	96
ec2-52-26-9-177.us-west-2.compute.amazonaws.com	1
ec2-35-163-84-238.us-west-2.compute.amazonaws.com	1
TOTAL: 4	385

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-tom.man.lodz.pl	2	0
TOTAL: 1	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	18	90.0%
2	lodman	2	10.0%
TOTAL: 2		20	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	10
2	pl.comp.pecet	4
3	pl.internet.komunikaty	3
4	lodman.info	2
5	pl.internet.polip	1
TOTAL: 5		20

Daily Usenet report

Apr 5 04:15:02 -- Apr 6 04:15:02