Daily Usenet report: Feb 20 04:15:03

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Feb 20 10:03:28 newsfeed nnrpd[15776]: SERVER perl filter not defined
Feb 20 10:05:10 newsfeed nnrpd[15847]: SERVER perl filter not defined
Feb 20 10:22:33 newsfeed nnrpd[16462]: SERVER perl filter not defined
Feb 20 11:06:01 newsfeed nnrpd[17867]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.groupsex 1]
Feb 20 13:00:57 newsfeed nnrpd[21946]: message repeated 8 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur 1]
Feb 20 13:34:19 newsfeed nnrpd[23001]: SERVER perl filter not defined
Feb 20 13:36:23 newsfeed nnrpd[23088]: SERVER perl filter not defined
Feb 20 15:05:58 newsfeed nnrpd[26184]: SERVER perl filter not defined
Feb 20 16:35:51 newsfeed nnrpd[29273]: SERVER perl filter not defined
Feb 20 18:36:51 newsfeed nnrpd[972]: SERVER perl filter not defined
Feb 20 20:43:58 newsfeed nnrpd[5430]: SERVER perl filter not defined
Feb 20 21:25:47 newsfeed nnrpd[6902]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3009118	98.5%	350.4 MB	98.3%
innd	35771	1.2%	5.1 MB	1.4%
nnrpd	4909	0.2%	499.4 KB	0.1%
innfeed	4849	0.2%	680.9 KB	0.2%
TOTAL: 4	3054647	100.0%	356.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3001520	96.3%
Negative hits	102285	3.3%
Positive hits	14113	0.5%
Cache misses	131	0.0%
TOTAL: 4	3118049	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.810	0.0%	178	0.000	4.551	134.000
article cleanup	00:00:44.836	0.1%	3006575	0.006	0.015	0.025
article logging	00:01:43.797	0.1%	3007615	0.018	0.035	0.858
article parse	01:30:21.166	6.3%	202882673	0.014	0.027	0.080
article write	00:41:41.493	2.9%	2991090	0.332	0.836	5.484
artlog/artcncl	00:00:00.005	0.0%	178	0.000	0.028	1.000
artlog/artparse	00:00:00.002	0.0%	68	0.000	0.029	1.000
data move	00:03:23.605	0.2%	222036015	0.000	0.001	0.006
hisgrep/artcncl	00:00:00.229	0.0%	163	0.000	1.405	16.000
hishave/artcncl	00:00:00.186	0.0%	178	0.000	1.045	133.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:28.673	2.3%	3120474	0.109	0.624	7.888
history sync	00:00:00.015	0.0%	293	0.000	0.051	1.000
history write	00:48:50.626	3.4%	3003832	0.225	0.976	9.903
hiswrite/artcncl	00:00:00.013	0.0%	15	0.000	0.867	12.000
idle	11:34:01.695	48.2%	132155547	0.156	0.315	0.656
nntp read	01:21:43.074	5.7%	200517877	0.016	0.024	0.048
overview write	00:00:18.501	0.0%	2991090	0.001	0.006	0.013
perl filter	06:46:27.440	28.2%	3003764	5.614	8.119	10.285
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:20.951	0.4%	6026616	0.038	0.053	0.102
TOTAL: 23:59:39.608	23:27:07.117	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.219	0.0%	51585	0.000	0.004	0.143
article prepare	00:00:00.025	0.0%	63460	0.000	0.000	0.050
article read	00:00:58.933	0.1%	15867	0.000	3.714	224.500
backlog stats	00:00:00.254	0.0%	237407	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:02.024	0.0%	125442	0.000	0.016	0.111
data write	00:00:13.520	0.0%	109872	0.000	0.123	0.323
idle	23:57:54.190	99.9%	237407	110.057	363.402	2245.968
status file	00:00:00.703	0.0%	854	0.000	0.823	16.000
TOTAL: 23:59:17.309	23:59:09.868	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:23:54.575	99.8%	84933	0.000	186.436	150254.917
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.013	0.0%	170515	0.000	0.035	23.829
readart	00:00:06.276	0.0%	90	0.000	69.733	356.400
TOTAL: 04:24:29.738	04:24:06.864	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1290	2993179	2982601	7244	3334	99%	404:55:23
2	news.nask.pl	2	25991	10055	3728	12208	38%	23:58:59
3	localhost	11	10	10	0	0	100%	00:00:00
4	news.icm.edu.pl	49	329	9	309	11	2%	17:53:57
TOTAL: 4		1352	3019509	2992675	11281	15553	99%	446:48:19

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	976.2 GB	53.6 MB	161.1 MB	976.4 GB	99%	342.9 KB
2	news.nask.pl	48.7 MB	580.2 KB	162.9 MB	212.1 MB	22%	9.8 KB
3	news.icm.edu.pl	104.3 KB	0.0 KB	448.8 KB	553.1 KB	18%	27.7 KB
4	localhost	26.3 KB	0.0 KB	0.0 KB	26.3 KB	100%	2.6 KB
TOTAL: 4		976.3 GB	54.2 MB	324.5 MB	976.6 GB	99%	340.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 20 04:15:03 - 04:59:59	86356	2.9%	32.02	31.4 GB	3.2%	12214.72
Feb 20 05:00:00 - 05:59:59	118568	4.0%	32.94	42.7 GB	4.4%	12424.06
Feb 20 06:00:00 - 06:59:59	116029	3.9%	32.23	43.0 GB	4.4%	12529.36
Feb 20 07:00:00 - 07:59:59	122123	4.1%	33.92	42.6 GB	4.4%	12401.34
Feb 20 08:00:00 - 08:59:59	117176	3.9%	32.55	41.3 GB	4.2%	12017.84
Feb 20 09:00:00 - 09:59:59	125242	4.2%	34.79	42.9 GB	4.4%	12482.24
Feb 20 10:00:00 - 10:59:59	117663	3.9%	32.68	41.8 GB	4.3%	12165.61
Feb 20 11:00:00 - 11:59:59	122537	4.1%	34.04	40.5 GB	4.2%	11788.80
Feb 20 12:00:00 - 12:59:59	114228	3.8%	31.73	38.5 GB	4.0%	11215.40
Feb 20 13:00:00 - 13:59:59	109562	3.7%	30.43	35.3 GB	3.6%	10274.86
Feb 20 14:00:00 - 14:59:59	106451	3.6%	29.57	37.1 GB	3.8%	10814.66
Feb 20 15:00:00 - 15:59:59	111910	3.7%	31.09	41.7 GB	4.3%	12153.45
Feb 20 16:00:00 - 16:59:59	115316	3.9%	32.03	40.9 GB	4.2%	11905.58
Feb 20 17:00:00 - 17:59:59	116165	3.9%	32.27	39.1 GB	4.0%	11391.97
Feb 20 18:00:00 - 18:59:59	109230	3.7%	30.34	38.6 GB	4.0%	11246.64
Feb 20 19:00:00 - 19:59:59	114216	3.8%	31.73	38.6 GB	4.0%	11231.21
Feb 20 20:00:00 - 20:59:59	134240	4.5%	37.29	41.0 GB	4.2%	11933.41
Feb 20 21:00:00 - 21:59:59	144020	4.8%	40.01	43.7 GB	4.5%	12719.99
Feb 20 22:00:00 - 22:59:59	150437	5.0%	41.79	43.1 GB	4.4%	12555.68
Feb 20 23:00:00 - 23:59:59	147713	4.9%	41.03	42.5 GB	4.4%	12387.31
Feb 21 00:00:00 - 00:59:59	114742	3.8%	31.87	34.9 GB	3.6%	10177.19
Feb 21 01:00:00 - 01:59:59	169558	5.7%	47.10	41.9 GB	4.3%	12206.51
Feb 21 02:00:00 - 02:59:59	147155	4.9%	40.88	43.4 GB	4.5%	12652.09
Feb 21 03:00:00 - 03:59:59	127958	4.3%	35.54	37.8 GB	3.9%	11007.58
Feb 21 04:00:00 - 04:15:05	33738	1.1%	37.28	9.8 GB	1.0%	11345.60
TOTAL: 24:00:02	2992333	100.0%	34.63	974.0 GB	100.0%	11819.97

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12497	112	203	13	12169
2	newsfeed.pionier.net.pl	4098	293	2611	3	1191
3	news.icm.edu.pl	12	0	0	0	12
TOTAL: 3		16607	405	2814	16	13372

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	128
comp.mobile.android	105
it.tlc.cellulari.android	69
it.comp.os.win.windows10	58
comp.sys.raspberry-pi	13
comp.mobile.ipad	12
alt.sex.telephones	5
uk.rec.cycling.moderated	5
nl.comp.sys.android	4
it.comp.os.win.windows7	2
it.comp.os.linux.ubuntu	2
de.comm.provider.telefonie	2
TOTAL: 12	405

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11404
Binary in non-binary group	563
EMP (phl)	114
Scoring filter (8)	66
Scoring filter (10)	41
Scoring filter (9)	37
Scoring filter (11)	16
Scoring filter (12)	6
HTML post	5
Scoring filter (19)	4
EMP (fsl)	3
Scoring filter (17)	2
Scoring filter (18)	1
Scoring filter (14)	1
Too many newsgroups	1
TOTAL: 15	12264

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	7296	1607	3637	710	18	45424	22%	24:02:41
2	poznan	15784	239	9795	10	0	0	1%	24:03:04
3	icm	40229	192	22840	12767	0	0	0%	24:04:36
4	nask	7596	120	5185	0	0	0	1%	24:07:48
5	okapi	0	0	0	0	0	1932	0%	24:00:02
TOTAL: 5		70905	2158	41457	13487	18	47356	3%	120:18:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	75.6 MB	3.2 GB	3.2 GB	39.0 KB/s	261.1 KB	24:04:36
2	panorama	23.7 MB	43.8 MB	67.6 MB	0.8 KB/s	29.9 KB	24:02:41
3	poznan	1.1 MB	31.1 KB	1.1 MB	0.0 KB/s	4.5 KB	24:03:04
4	nask	399.0 KB	0.0 KB	399.0 KB	0.0 KB/s	3.3 KB	24:07:48
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		100.8 MB	3.2 GB	3.3 GB	8.0 KB/s	220.8 KB	120:18:11

Backlog files shrunk by innfeed:

Server	Size
panorama	2.9 MB
TOTAL: 1	2.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	291	57	70.2 KB	25	3	00:00:22
2	pc-tom.man.lodz.pl	4	13	2.6 MB	3	0	01:02:20
3	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:02
TOTAL: 3		302	70	2.7 MB	28	10	04:24:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	686	70	2.7 MB	28	10	01:02:50
2	unresolved	3	0	0.0 KB	0	0	03:21:38
TOTAL: 2		689	70	2.7 MB	28	10	04:24:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.24	4.848	7.511	03:21:32
pc-tom.man.lodz.pl	0.198	0.176	01:02:20
web.man.lodz.pl	1.141	4.944	00:00:22
196.52.19.16	0.004	0.022	00:00:05
mon.man.lodz.pl	1.055	3.964	00:00:04
isvcs01.man.lodz.pl	0.061	0.105	00:00:02
david.man.lodz.pl	0.294	1.259	00:00:01
TOTAL: 7	7.601	17.981	04:24:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.192.24	2
196.52.19.16	1
TOTAL: 4	387

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	57	95.0%
2	alt	3	5.0%
TOTAL: 2		60	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	48
2	pl.comp.os.ms-windows.winnt	6
3	pl.rec.mineraly	3
4	alt.binaries.pictures.erotica.amateur	2
5	alt.binaries.pictures.erotica.groupsex	1
TOTAL: 5		60

Daily Usenet report

Feb 20 04:15:03 -- Feb 21 04:15:05