Daily Usenet report: Sep 20 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Sep 20 11:43:12 newsfeed nnrpd[10010]: SERVER perl filter not defined
Sep 20 12:56:04 newsfeed nnrpd[12638]: SERVER perl filter not defined
Sep 20 13:10:08 newsfeed overchan[25437]: input line too long, skipping
Sep 20 13:10:42 newsfeed overchan[25437]: input line too long, skipping
Sep 20 16:47:15 newsfeed nnrpd[21250]: SERVER perl filter not defined
Sep 20 16:48:16 newsfeed nnrpd[21261]: SERVER perl filter not defined
Sep 20 16:52:15 newsfeed nnrpd[21357]: SERVER perl filter not defined
Sep 20 17:38:34 newsfeed nnrpd[23094]: SERVER perl filter not defined
Sep 20 17:45:31 newsfeed nnrpd[23412]: SERVER perl filter not defined
Sep 20 17:48:12 newsfeed nnrpd[23444]: SERVER perl filter not defined
Sep 20 20:20:52 newsfeed overchan[25437]: input line too long, skipping
Sep 20 21:05:44 newsfeed overchan[25437]: input line too long, skipping
Sep 20 21:56:37 newsfeed overchan[25437]: input line too long, skipping
Sep 20 21:59:55 newsfeed nnrpd[32668]: SERVER perl filter not defined
Sep 20 22:00:57 newsfeed overchan[25437]: input line too long, skipping
Sep 21 00:35:48 newsfeed nnrpd[6066]: SERVER perl filter not defined
Sep 21 00:36:19 newsfeed nnrpd[6072]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2527722	95.6%	293.1 MB	95.2%
innd	67243	2.5%	9.1 MB	2.9%
innfeed	45103	1.7%	5.3 MB	1.7%
nnrpd	4864	0.2%	502.6 KB	0.2%
overchan	7	0.0%	1.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2644940	100.0%	307.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2470794	83.5%
Negative hits	434995	14.7%
Positive hits	54287	1.8%
Cache misses	213	0.0%
TOTAL: 4	2960289	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.467	0.0%	195	0.000	12.651	47.172
article cleanup	00:00:43.786	0.1%	2515201	0.014	0.017	0.024
article logging	00:01:54.516	0.1%	2515816	0.035	0.046	0.210
article parse	01:14:24.563	5.2%	138068488	0.018	0.032	0.045
article write	00:27:47.039	1.9%	2432989	0.461	0.685	1.654
artlog/artcncl	00:00:00.005	0.0%	192	0.000	0.026	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:05:42.482	0.4%	153450666	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.463	0.0%	190	0.000	2.437	13.125
hishave/artcncl	00:00:00.146	0.0%	195	0.000	0.749	4.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:33:23.506	2.3%	2974139	0.097	0.674	4.214
history sync	00:00:00.020	0.0%	290	0.000	0.069	1.000
history write	01:39:00.267	6.9%	2482823	0.278	2.393	10.494
hiswrite/artcncl	00:00:00.010	0.0%	5	0.000	2.000	9.000
idle	11:42:36.201	48.8%	91323717	0.352	0.462	0.908
nntp read	01:39:18.892	6.9%	136114552	0.029	0.044	0.057
overview write	00:00:16.185	0.0%	2432989	0.005	0.007	0.009
perl filter	05:43:51.026	23.9%	2482822	7.191	8.310	9.869
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:30.767	0.3%	4909683	0.043	0.055	0.080
TOTAL: 24:00:02.737	23:13:32.341	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.332	0.0%	106245	0.000	0.003	0.014
article prepare	00:00:00.025	0.0%	36490	0.000	0.001	0.011
article read	00:00:56.355	0.1%	9087	0.000	6.202	126.167
backlog stats	00:00:00.326	0.0%	277505	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:03.900	0.0%	157144	0.005	0.025	0.054
data write	00:00:22.806	0.0%	127099	0.015	0.179	0.529
idle	23:51:47.102	99.9%	277505	50.881	309.570	753.628
status file	00:00:17.875	0.0%	16382	0.650	1.091	7.879
TOTAL: 23:53:50.879	23:53:28.721	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.099	40.9%	3148	0.000	6.067	243.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.203	0.4%	6938	0.000	0.029	0.667
readart	00:00:00.030	0.1%	72	0.000	0.417	7.000
TOTAL: 00:00:46.706	00:00:19.332	41.4%	-	-	-	-

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	1043	2489315	2425971	16252	47092	97%	416:34:06
2	news.nask.pl	3	58946	18009	5493	35444	30%	24:10:48
3	news.icm.edu.pl	34	353	46	293	14	13%	18:49:45
4	localhost	12	11	11	0	0	100%	00:00:00
TOTAL: 4		1092	2548625	2444037	22038	82550	95%	459:34:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	770.6 GB	6.6 GB	9.6 GB	786.7 GB	97%	333.6 KB
2	news.nask.pl	2.6 GB	252.0 MB	6.0 GB	8.9 GB	29%	173.8 KB
3	news.icm.edu.pl	1022.3 KB	0.0 KB	589.2 KB	1.6 MB	63%	26.9 KB
4	localhost	20.9 KB	0.0 KB	0.0 KB	20.9 KB	100%	1.9 KB
TOTAL: 4		773.2 GB	6.8 GB	15.5 GB	795.6 GB	97%	330.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 20 04:15:02 - 04:59:59	43183	1.8%	16.01	14.5 GB	1.9%	5654.47
Sep 20 05:00:00 - 05:59:59	48756	2.0%	13.54	18.1 GB	2.3%	5262.27
Sep 20 06:00:00 - 06:59:59	46490	1.9%	12.91	16.7 GB	2.2%	4870.33
Sep 20 07:00:00 - 07:59:59	42368	1.7%	11.77	15.7 GB	2.0%	4564.03
Sep 20 08:00:00 - 08:59:59	68185	2.8%	18.94	22.0 GB	2.8%	6402.74
Sep 20 09:00:00 - 09:59:59	87917	3.6%	24.42	28.0 GB	3.6%	8160.34
Sep 20 10:00:00 - 10:59:59	91656	3.7%	25.46	28.4 GB	3.7%	8273.94
Sep 20 11:00:00 - 11:59:59	106780	4.4%	29.66	32.5 GB	4.2%	9480.42
Sep 20 12:00:00 - 12:59:59	132087	5.4%	36.69	39.7 GB	5.1%	11564.59
Sep 20 13:00:00 - 13:59:59	126992	5.2%	35.28	40.3 GB	5.2%	11736.96
Sep 20 14:00:00 - 14:59:59	126313	5.2%	35.09	39.5 GB	5.1%	11518.92
Sep 20 15:00:00 - 15:59:59	119173	4.9%	33.10	37.0 GB	4.8%	10787.90
Sep 20 16:00:00 - 16:59:59	109306	4.5%	30.36	35.0 GB	4.5%	10190.11
Sep 20 17:00:00 - 17:59:59	121282	5.0%	33.69	36.3 GB	4.7%	10586.59
Sep 20 18:00:00 - 18:59:59	124380	5.1%	34.55	37.9 GB	4.9%	11053.50
Sep 20 19:00:00 - 19:59:59	118005	4.8%	32.78	36.9 GB	4.8%	10760.96
Sep 20 20:00:00 - 20:59:59	127331	5.2%	35.37	38.4 GB	5.0%	11197.97
Sep 20 21:00:00 - 21:59:59	134903	5.5%	37.47	39.4 GB	5.1%	11475.23
Sep 20 22:00:00 - 22:59:59	117893	4.8%	32.75	37.3 GB	4.8%	10855.85
Sep 20 23:00:00 - 23:59:59	115403	4.7%	32.06	38.2 GB	4.9%	11136.60
Sep 21 00:00:00 - 00:59:59	103260	4.2%	28.68	33.9 GB	4.4%	9872.84
Sep 21 01:00:00 - 01:59:59	108359	4.4%	30.10	35.7 GB	4.6%	10388.46
Sep 21 02:00:00 - 02:59:59	105731	4.3%	29.37	34.4 GB	4.4%	10005.55
Sep 21 03:00:00 - 03:59:59	91829	3.8%	25.51	28.5 GB	3.7%	8291.70
Sep 21 04:00:00 - 04:15:02	26746	1.1%	29.65	8.2 GB	1.1%	9490.13
TOTAL: 24:00:00	2444328	100.0%	28.29	772.7 GB	100.0%	9377.22

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	47501	225	31187	0	16089
2	news.nask.pl	35686	47	1340	5	34294
3	news.icm.edu.pl	15	0	0	0	15
TOTAL: 3		83202	272	32527	5	50398

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	120
it.comp.os.win.windows10	52
comp.mobile.android	41
it.comp.os.win.windows7	22
it.tlc.cellulari.android	21
comp.sys.raspberry-pi	7
de.comm.provider.telefonie	2
de.comp.lang.php	2
de.rec.sf.startrek	2
de.comp.lang.vbclassic	1
nl.comp.os.linux.techniek	1
alt.sex.telephones	1
TOTAL: 12	272

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	45838
EMP (md5)	3856
Scoring filter (8)	35
Scoring filter (9)	20
Poison newsgroup	11
Scoring filter (11)	7
Scoring filter (10)	6
HTML post	3
Scoring filter (14)	2
Scoring filter (12)	2
TOTAL: 10	49780

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	398
	news.nask.pl	218
	news.icm.edu.pl	1
	TOTAL: 3	617
TOTAL: 1		617

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	69171	920	40776	253	5	64529	1%	23:59:57
2	poznan	15748	477	9654	38	0	0	3%	23:59:31
3	nask	11858	342	9989	1	0	0	2%	23:58:55
4	icm	44414	81	35102	6534	0	0	0%	23:59:52
5	okapi	0	0	0	0	0	2280	0%	23:57:50
TOTAL: 5		141191	1820	95521	6826	5	66809	1%	119:56:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	115.6 MB	2.0 KB	115.6 MB	1.4 KB/s	345.2 KB	23:58:55
2	icm	6.6 MB	2.4 GB	2.4 GB	29.1 KB/s	379.4 KB	23:59:52
3	panorama	4.1 MB	1.4 MB	5.4 MB	0.1 KB/s	4.8 KB	23:59:57
4	poznan	2.0 MB	393.0 KB	2.4 MB	0.0 KB/s	4.7 KB	23:59:31
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		128.3 MB	2.4 GB	2.5 GB	6.1 KB/s	304.9 KB	119:56:05

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	72	70.9 KB	32	0	00:00:28
2	isvcs01.man.lodz.pl	11	0	0.0 KB	0	11	00:00:01
TOTAL: 2		299	72	70.9 KB	32	11	00:00:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	683	72	70.9 KB	32	11	00:00:43
2	*.dhcp.chtrptr.net	2	0	0.0 KB	0	0	00:00:02
TOTAL: 2		685	72	70.9 KB	32	11	00:00:45

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.512	6.032	00:00:28
mon.man.lodz.pl	1.292	6.392	00:00:09
david.man.lodz.pl	0.408	1.920	00:00:03
172-221-045-126.dhcp.chtrptr.net	0.008	0.048	00:00:02
isvcs01.man.lodz.pl	0.064	0.312	00:00:01
TOTAL: 5	3.284	14.704	00:00:45

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
172-221-045-126.dhcp.chtrptr.net	2
TOTAL: 3	386

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Sep 20 04:15:02 -- Sep 21 04:15:02