Daily Usenet report: Sep 30 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 30 07:58:55 newsfeed nnrpd[17703]: SERVER perl filter not defined
Sep 30 08:01:57 newsfeed nnrpd[17814]: SERVER perl filter not defined
Sep 30 08:03:14 newsfeed nnrpd[17816]: SERVER perl filter not defined
Sep 30 08:42:04 newsfeed nnrpd[19182]: SERVER perl filter not defined
Sep 30 10:05:19 newsfeed nnrpd[22209]: SERVER perl filter not defined
Sep 30 10:12:03 newsfeed nnrpd[22329]: SERVER perl filter not defined
Sep 30 11:32:25 newsfeed nnrpd[25059]: SERVER perl filter not defined
Sep 30 17:25:03 newsfeed nnrpd[14074]: SERVER perl filter not defined
Sep 30 18:32:13 newsfeed nnrpd[16403]: SERVER perl filter not defined
Sep 30 22:34:21 newsfeed overchan[2605]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2696152	98.5%	310.9 MB	98.2%
innd	30924	1.1%	4.5 MB	1.4%
innfeed	4887	0.2%	685.1 KB	0.2%
nnrpd	4854	0.2%	501.9 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2736819	100.0%	316.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2688996	96.1%
Negative hits	94952	3.4%
Positive hits	14790	0.5%
Cache misses	90	0.0%
TOTAL: 4	2798828	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.039	0.0%	177	0.000	5.870	98.000
article cleanup	00:00:39.512	0.0%	2694046	0.006	0.015	0.031
article logging	00:01:19.911	0.1%	2694597	0.013	0.030	0.737
article parse	01:06:47.455	4.6%	207607230	0.007	0.019	0.041
article write	00:22:55.629	1.6%	2680639	0.226	0.513	2.569
artlog/artcncl	00:00:00.005	0.0%	177	0.000	0.028	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:40.675	0.2%	223955017	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.275	0.0%	148	0.000	1.858	35.000
hishave/artcncl	00:00:00.112	0.0%	177	0.000	0.633	20.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:26:52.697	1.9%	2799653	0.037	0.576	11.692
history sync	00:00:00.018	0.0%	293	0.000	0.061	2.000
history write	00:37:21.872	2.6%	2689907	0.118	0.833	13.290
hiswrite/artcncl	00:00:00.127	0.0%	29	0.000	4.379	51.000
idle	14:27:22.946	60.3%	157162957	0.207	0.331	6.106
nntp read	01:04:06.202	4.5%	205513166	0.008	0.019	0.030
overview write	00:00:14.087	0.0%	2680639	0.000	0.005	0.018
perl filter	05:05:54.311	21.3%	2689907	3.803	6.823	10.046
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:21.098	0.3%	5397105	0.033	0.048	0.101
TOTAL: 23:59:18.892	23:20:37.971	97.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.144	0.0%	51190	0.000	0.003	0.100
article prepare	00:00:00.015	0.0%	59736	0.000	0.000	0.250
article read	00:01:12.380	0.1%	14932	0.000	4.847	523.750
backlog stats	00:00:00.186	0.0%	221561	0.000	0.001	0.028
callbacks	00:00:00.000	0.0%	11	0.000	0.000	0.000
data read	00:00:01.474	0.0%	122305	0.000	0.012	0.080
data write	00:00:07.915	0.0%	99330	0.000	0.080	0.472
idle	23:57:21.684	99.9%	221561	49.887	389.246	1920.812
status file	00:00:00.689	0.0%	909	0.000	0.758	5.000
TOTAL: 23:58:51.151	23:58:44.487	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:27.090	86.7%	3137	0.000	27.762	22339.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.162	0.2%	6948	0.000	0.023	0.500
readart	00:00:00.018	0.0%	90	0.000	0.200	1.400
TOTAL: 00:01:40.468	00:01:27.270	86.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1318	2679438	2668398	6272	4768	99%	315:07:31
2	news.nask.pl	2	25546	12850	4026	8670	50%	24:11:38
3	news.icm.edu.pl	41	330	16	307	7	4%	18:26:36
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		1371	2705323	2681273	10605	13445	99%	357:45:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	746.0 GB	43.4 MB	147.8 MB	746.2 GB	99%	292.7 KB
2	news.nask.pl	80.4 MB	1.4 MB	116.9 MB	198.7 MB	40%	9.5 KB
3	news.icm.edu.pl	340.1 KB	21.6 KB	135.4 KB	497.1 KB	68%	21.6 KB
4	localhost	17.6 KB	0.0 KB	0.0 KB	17.6 KB	100%	2.0 KB
TOTAL: 4		746.1 GB	44.8 MB	264.8 MB	746.4 GB	99%	290.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 30 04:15:02 - 04:59:59	32913	1.2%	12.20	11.8 GB	1.6%	4574.63
Sep 30 05:00:00 - 05:59:59	39152	1.5%	10.88	13.9 GB	1.9%	4053.35
Sep 30 06:00:00 - 06:59:59	40485	1.5%	11.25	14.6 GB	2.0%	4251.09
Sep 30 07:00:00 - 07:59:59	44065	1.6%	12.24	14.8 GB	2.0%	4299.82
Sep 30 08:00:00 - 08:59:59	47354	1.8%	13.15	14.7 GB	2.0%	4273.20
Sep 30 09:00:00 - 09:59:59	70035	2.6%	19.45	20.9 GB	2.8%	6092.92
Sep 30 10:00:00 - 10:59:59	63819	2.4%	17.73	21.0 GB	2.8%	6120.26
Sep 30 11:00:00 - 11:59:59	69752	2.6%	19.38	21.7 GB	2.9%	6331.51
Sep 30 12:00:00 - 12:59:59	77942	2.9%	21.65	24.8 GB	3.3%	7222.33
Sep 30 13:00:00 - 13:59:59	91906	3.4%	25.53	28.1 GB	3.8%	8198.05
Sep 30 14:00:00 - 14:59:59	121683	4.5%	33.80	34.5 GB	4.6%	10048.63
Sep 30 15:00:00 - 15:59:59	142706	5.3%	39.64	39.3 GB	5.3%	11435.73
Sep 30 16:00:00 - 16:59:59	155799	5.8%	43.28	41.6 GB	5.6%	12122.64
Sep 30 17:00:00 - 17:59:59	161001	6.0%	44.72	42.0 GB	5.6%	12232.21
Sep 30 18:00:00 - 18:59:59	171975	6.4%	47.77	42.1 GB	5.6%	12255.46
Sep 30 19:00:00 - 19:59:59	157986	5.9%	43.88	42.4 GB	5.7%	12352.41
Sep 30 20:00:00 - 20:59:59	160040	6.0%	44.46	41.9 GB	5.6%	12198.19
Sep 30 21:00:00 - 21:59:59	156589	5.8%	43.50	41.4 GB	5.6%	12059.84
Sep 30 22:00:00 - 22:59:59	160545	6.0%	44.60	41.0 GB	5.5%	11956.37
Sep 30 23:00:00 - 23:59:59	152241	5.7%	42.29	40.3 GB	5.4%	11747.06
Oct 1 00:00:00 - 00:59:59	115735	4.3%	32.15	29.9 GB	4.0%	8711.28
Oct 1 01:00:00 - 01:59:59	129360	4.8%	35.93	35.1 GB	4.7%	10233.14
Oct 1 02:00:00 - 02:59:59	148063	5.5%	41.13	40.7 GB	5.5%	11840.81
Oct 1 03:00:00 - 03:59:59	135306	5.0%	37.59	37.4 GB	5.0%	10882.41
Oct 1 04:00:00 - 04:15:02	35550	1.3%	39.41	9.8 GB	1.3%	11360.34
TOTAL: 24:00:00	2682002	100.0%	31.04	745.7 GB	100.0%	9049.73

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	8865	23	437	6	8399
2	newsfeed.pionier.net.pl	5101	343	3702	2	1054
3	news.icm.edu.pl	7	0	1	0	6
TOTAL: 3		13973	366	4140	8	9459

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	230
it.comp.os.win.windows10	47
it.tlc.cellulari.android	39
comp.mobile.ipad	21
comp.mobile.android	7
it.comp.os.win.windows7	6
hr.alt.cellular.gsm.android	5
de.comp.office-pakete.ms-office.excel	4
de.comp.lang.php	3
comp.sys.raspberry-pi	2
uk.rec.cycling.moderated	1
de.rec.sf.startrek	1
TOTAL: 12	366

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7555
Binary in non-binary group	1036
Scoring filter (8)	136
Scoring filter (9)	61
Scoring filter (10)	32
EMP (phl)	23
EMP (fsl)	17
Scoring filter (13)	14
Scoring filter (11)	13
Scoring filter (12)	5
Scoring filter (19)	4
Scoring filter (17)	2
Scoring filter (20)	2
Scoring filter (16)	2
Scoring filter (15)	2
Scoring filter (18)	1
Scoring filter (14)	1
HTML post	1
Too many newsgroups	1
TOTAL: 19	8908

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	15759	3748	9466	1158	30	36743	23%	23:58:25
2	icm	40995	487	24545	8981	0	0	1%	23:50:00
3	poznan	18888	183	12451	37	0	0	0%	23:47:18
4	nask	7877	28	5200	3	0	0	0%	23:48:28
5	okapi	0	0	0	0	0	1769	0%	23:50:00
TOTAL: 5		83519	4446	51662	10179	30	38512	5%	119:14:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	14.4 MB	4.7 MB	19.2 MB	0.2 KB/s	4.0 KB	23:58:25
2	icm	6.5 MB	2.0 GB	2.0 GB	24.1 KB/s	218.2 KB	23:50:00
3	poznan	958.3 KB	309.9 KB	1.2 MB	0.0 KB/s	5.8 KB	23:47:18
4	nask	94.7 KB	653.5 KB	748.2 KB	0.0 KB/s	24.1 KB	23:48:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 5		22.0 MB	2.0 GB	2.0 GB	4.9 KB/s	142.8 KB	119:14:11

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	287	80	76.3 KB	35	0	00:00:22
2	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:00
TOTAL: 2		296	80	76.3 KB	35	9	00:01:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	680	80	76.3 KB	35	9	00:00:27
2	unresolved	2	0	0.0 KB	0	0	00:00:03
3	*.ostnet.pl	1	0	0.0 KB	0	0	00:01:07
4	*.members.linode.com	1	0	0.0 KB	0	0	00:00:01
TOTAL: 4		684	80	76.3 KB	35	9	00:01:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
clif140239.ostnet.pl	0.034	0.013	00:01:07
web.man.lodz.pl	0.967	4.108	00:00:22
mon.man.lodz.pl	0.906	3.681	00:00:03
196.52.22.56	0.007	0.007	00:00:03
li97-228.members.linode.com	0.003	0.007	00:00:01
david.man.lodz.pl	0.293	1.248	00:00:01
isvcs01.man.lodz.pl	0.048	0.165	00:00:00
TOTAL: 7	2.258	9.229	00:01:40

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
196.52.22.56	1
clif140239.ostnet.pl	1
li97-228.members.linode.com	1
185.48.195.142	1
TOTAL: 6	388

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	74
2	pl.comp.pecet	6
TOTAL: 2		80

Daily Usenet report

Sep 30 04:15:02 -- Oct 1 04:15:02