Daily Usenet report: Sep 2 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Sep 2 10:05:17 newsfeed nnrpd[5815]: SERVER perl filter not defined
Sep 2 10:18:20 newsfeed nnrpd[6330]: SERVER perl filter not defined
Sep 2 10:57:02 newsfeed nnrpd[7688]: SERVER perl filter not defined
Sep 2 13:25:57 newsfeed nnrpd[12792]: SERVER perl filter not defined
Sep 2 13:31:02 newsfeed nnrpd[13031]: SERVER perl filter not defined
Sep 2 16:47:18 newsfeed nnrpd[19926]: SERVER perl filter not defined
Sep 2 16:49:14 newsfeed nnrpd[19941]: SERVER perl filter not defined
Sep 2 17:42:22 newsfeed nnrpd[21761]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2583948	97.3%	303.0 MB	96.9%
innfeed	43819	1.7%	5.3 MB	1.7%
innd	22122	0.8%	3.8 MB	1.2%
nnrpd	5078	0.2%	544.9 KB	0.2%
TOTAL: 4	2654967	100.0%	312.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2577766	98.1%
Negative hits	31860	1.2%
Positive hits	16706	0.6%
Cache misses	135	0.0%
TOTAL: 4	2626467	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.116	0.0%	555	0.000	16.425	853.000
article cleanup	00:00:37.661	0.0%	2581951	0.004	0.015	0.027
article logging	00:01:26.286	0.1%	2582415	0.016	0.033	0.237
article parse	01:00:14.509	4.2%	105945972	0.026	0.034	0.069
article write	00:37:05.873	2.6%	2577847	0.371	0.863	3.677
artlog/artcncl	00:00:00.006	0.0%	551	0.000	0.011	1.000
artlog/artparse	00:00:00.000	0.0%	7	0.000	0.000	0.000
data move	00:01:55.229	0.1%	121493260	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.829	0.0%	208	0.000	8.793	198.333
hishave/artcncl	00:00:01.525	0.0%	555	0.000	2.748	104.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:24:55.639	1.7%	2627009	0.104	0.569	9.562
history sync	00:00:00.022	0.0%	292	0.000	0.075	1.000
history write	00:32:49.608	2.3%	2578422	0.169	0.764	12.182
hiswrite/artcncl	00:00:01.166	0.0%	347	0.000	3.360	10.000
idle	13:59:35.603	58.3%	77364072	0.144	0.651	5.875
nntp read	00:47:43.532	3.3%	103898760	0.021	0.028	0.036
overview write	00:00:17.220	0.0%	2577847	0.000	0.007	0.019
perl filter	05:51:39.619	24.4%	2578415	6.666	8.183	11.084
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:26.389	0.4%	5262773	0.031	0.062	0.134
TOTAL: 23:59:14.870	23:24:00.832	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.578	0.0%	179582	0.000	0.003	0.118
article prepare	00:00:00.042	0.0%	111064	0.000	0.000	0.250
article read	00:00:57.860	0.1%	27713	0.000	2.088	1442.000
backlog stats	00:00:00.342	0.0%	420046	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	13	0.000	0.000	0.000
data read	00:00:03.782	0.0%	249562	0.000	0.015	0.105
data write	00:00:20.201	0.0%	151731	0.000	0.133	0.357
idle	23:57:44.070	99.9%	420046	25.521	205.368	1693.378
status file	00:00:12.968	0.0%	15531	0.000	0.835	5.600
TOTAL: 23:59:36.609	23:59:19.843	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:35:23.512	99.3%	3294	0.000	644.661	90251.095
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.175	0.0%	7365	0.000	0.024	0.667
readart	00:00:00.104	0.0%	58	0.000	1.793	7.700
TOTAL: 00:35:37.935	00:35:23.791	99.3%	-	-	-	-

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	1391	2578526	2574446	430	3650	99%	324:07:02
2	news.nask.pl	2	17319	4398	12618	303	25%	24:15:48
3	news.icm.edu.pl	38	372	37	177	158	9%	17:45:00
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1440	2596225	2578889	13225	4111	99%	366:07:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	780.6 GB	16.6 MB	27.6 MB	780.7 GB	99%	317.5 KB
2	news.nask.pl	27.6 MB	755.8 KB	854.5 KB	29.1 MB	94%	6.3 KB
3	news.icm.edu.pl	948.7 KB	90.4 KB	9.9 MB	10.9 MB	8%	57.3 KB
4	localhost	11.5 KB	0.0 KB	0.0 KB	11.5 KB	100%	1.4 KB
TOTAL: 4		780.7 GB	17.4 MB	38.3 MB	780.7 GB	99%	316.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 2 04:15:02 - 04:59:59	75442	2.9%	27.96	24.1 GB	3.1%	9353.52
Sep 2 05:00:00 - 05:59:59	103883	4.0%	28.86	33.4 GB	4.3%	9729.69
Sep 2 06:00:00 - 06:59:59	104625	4.1%	29.06	34.1 GB	4.4%	9924.03
Sep 2 07:00:00 - 07:59:59	116655	4.5%	32.40	34.9 GB	4.5%	10172.11
Sep 2 08:00:00 - 08:59:59	84641	3.3%	23.51	27.7 GB	3.6%	8076.57
Sep 2 09:00:00 - 09:59:59	101297	3.9%	28.14	30.4 GB	3.9%	8840.33
Sep 2 10:00:00 - 10:59:59	126742	4.9%	35.21	31.8 GB	4.1%	9275.75
Sep 2 11:00:00 - 11:59:59	112208	4.4%	31.17	35.5 GB	4.6%	10341.27
Sep 2 12:00:00 - 12:59:59	101501	3.9%	28.19	30.9 GB	4.0%	9006.71
Sep 2 13:00:00 - 13:59:59	85759	3.3%	23.82	26.0 GB	3.3%	7559.60
Sep 2 14:00:00 - 14:59:59	111467	4.3%	30.96	33.0 GB	4.2%	9608.86
Sep 2 15:00:00 - 15:59:59	110795	4.3%	30.78	33.6 GB	4.3%	9798.00
Sep 2 16:00:00 - 16:59:59	93471	3.6%	25.96	28.4 GB	3.6%	8284.13
Sep 2 17:00:00 - 17:59:59	121154	4.7%	33.65	33.7 GB	4.3%	9805.33
Sep 2 18:00:00 - 18:59:59	125959	4.9%	34.99	34.2 GB	4.4%	9959.66
Sep 2 19:00:00 - 19:59:59	98082	3.8%	27.25	29.6 GB	3.8%	8631.58
Sep 2 20:00:00 - 20:59:59	110770	4.3%	30.77	32.3 GB	4.1%	9401.89
Sep 2 21:00:00 - 21:59:59	117451	4.6%	32.63	33.3 GB	4.3%	9711.78
Sep 2 22:00:00 - 22:59:59	130030	5.0%	36.12	36.2 GB	4.6%	10529.83
Sep 2 23:00:00 - 23:59:59	117766	4.6%	32.71	35.6 GB	4.6%	10357.56
Sep 3 00:00:00 - 00:59:59	91542	3.5%	25.43	29.4 GB	3.8%	8568.22
Sep 3 01:00:00 - 01:59:59	127261	4.9%	35.35	38.8 GB	5.0%	11314.76
Sep 3 02:00:00 - 02:59:59	116927	4.5%	32.48	38.3 GB	4.9%	11149.32
Sep 3 03:00:00 - 03:59:59	71748	2.8%	19.93	26.4 GB	3.4%	7687.43
Sep 3 04:00:00 - 04:15:02	21645	0.8%	24.00	7.9 GB	1.0%	9133.09
TOTAL: 24:00:00	2578821	100.0%	29.85	779.5 GB	100.0%	9459.70

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4041	188	3260	2	591
2	news.nask.pl	376	5	272	23	76
3	news.icm.edu.pl	159	0	4	0	155
TOTAL: 3		4576	193	3536	25	822

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	94
it.comp.os.win.windows10	45
it.tlc.cellulari.android	23
comp.mobile.android	8
it.comp.os.win.windows7	7
uk.rec.cycling.moderated	4
it.comp.os.win.windows8	2
de.rec.sf.startrek	2
comp.sys.raspberry-pi	2
rec.arts.tv.x-factor	2
nl.comp.os.linux.techniek	1
alt.sex.telephones	1
hr.comp.os.linux.ubuntu	1
de.comp.lang.vbclassic	1
TOTAL: 14	193

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

Groups	Count
pl.praca.oferowana	22
comp.archives	2
bit.listserv.catholic	1
TOTAL: 3	25

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	271
EMP (md5)	71
Too many newsgroups	5
HTML post	2
Scoring filter (8)	1
TOTAL: 5	350

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	88390	1957	59914	736	30	116913	2%	23:59:48
2	icm	66635	953	26886	22940	0	6	1%	23:58:42
3	poznan	6598	132	4193	44	0	0	2%	24:03:59
4	nask	0	0	0	0	0	13159	0%	24:00:01
5	okapi	0	0	0	0	0	2358	0%	24:00:01
TOTAL: 5		161623	3042	90993	23720	30	132436	1%	120:02:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.8 MB	5.6 GB	5.6 GB	67.9 KB/s	245.5 KB	23:58:42
2	poznan	7.3 MB	74.7 KB	7.4 MB	0.1 KB/s	43.2 KB	24:03:59
3	panorama	6.9 MB	3.4 MB	10.3 MB	0.1 KB/s	3.9 KB	23:59:48
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		29.0 MB	5.6 GB	5.6 GB	13.6 KB/s	219.8 KB	120:02:31

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
TOTAL: 1	1.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	48	52.4 KB	22	0	00:00:32
2	lze200.lze.lodz.pl	2	10	15.8 KB	1	0	00:34:20
3	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 3		298	58	68.2 KB	23	8	00:35:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	680	48	52.4 KB	22	8	00:00:37
2	*.lze.lodz.pl	2	10	15.8 KB	1	0	00:34:20
3	unresolved	2	0	0.0 KB	0	0	00:00:10
4	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:26
5	*.toya.net.pl	54	0	0.0 KB	0	0	00:00:03
TOTAL: 5		742	58	68.2 KB	23	8	00:35:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.004	0.053	00:34:20
web.man.lodz.pl	1.045	5.593	00:00:32
host227.elsnet.pl	0.019	0.074	00:00:26
89.38.40.223	0.004	0.022	00:00:10
staticline41435.toya.net.pl	0.219	1.030	00:00:03
mon.man.lodz.pl	1.105	5.384	00:00:02
david.man.lodz.pl	0.341	1.832	00:00:00
isvcs01.man.lodz.pl	0.028	0.193	00:00:00
TOTAL: 8	2.765	14.181	00:35:37

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	54
host227.elsnet.pl	4
89.38.40.223	2
TOTAL: 5	444

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Sep 2 04:15:02 -- Sep 3 04:15:02