Daily Usenet report: Sep 22 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Sep 22 08:19:16 newsfeed nnrpd[533]: SERVER perl filter not defined
Sep 22 10:22:49 newsfeed nnrpd[4779]: SERVER perl filter not defined
Sep 22 11:17:39 newsfeed nnrpd[6604]: SERVER perl filter not defined
Sep 22 11:25:19 newsfeed nnrpd[6919]: SERVER perl filter not defined
Sep 22 12:12:51 newsfeed nnrpd[8689]: SERVER perl filter not defined
Sep 22 12:14:50 newsfeed nnrpd[8719]: SERVER perl filter not defined
Sep 22 13:33:16 newsfeed nnrpd[11454]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2654328	98.2%	313.9 MB	98.0%
innd	37784	1.4%	5.1 MB	1.6%
nnrpd	4979	0.2%	520.3 KB	0.2%
innfeed	4839	0.2%	686.3 KB	0.2%
TOTAL: 4	2701930	100.0%	320.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2647311	95.6%
Negative hits	105955	3.8%
Positive hits	17207	0.6%
Cache misses	118	0.0%
TOTAL: 4	2770591	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.224	0.0%	162	0.000	19.901	310.000
article cleanup	00:00:37.461	0.0%	2652266	0.006	0.014	0.027
article logging	00:01:25.577	0.1%	2652923	0.009	0.032	1.224
article parse	01:04:34.551	4.5%	192122928	0.009	0.020	0.078
article write	00:26:13.816	1.8%	2632006	0.314	0.598	4.454
artlog/artcncl	00:00:00.002	0.0%	156	0.000	0.013	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:21.810	0.2%	208560482	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.722	0.0%	150	0.000	4.813	308.000
hishave/artcncl	00:00:00.121	0.0%	162	0.000	0.747	6.235
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:56.119	1.9%	2771678	0.123	0.605	7.579
history sync	00:00:00.013	0.0%	291	0.000	0.045	1.000
history write	00:39:53.201	2.8%	2648440	0.216	0.904	10.158
hiswrite/artcncl	00:00:00.003	0.0%	12	0.000	0.250	1.000
idle	13:49:37.231	57.6%	159941173	0.199	0.311	0.715
nntp read	01:02:12.673	4.3%	190040542	0.010	0.020	0.037
overview write	00:00:15.144	0.0%	2632006	0.001	0.006	0.015
perl filter	05:22:38.032	22.4%	2648440	4.425	7.309	9.745
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:27.650	0.3%	5309549	0.033	0.050	0.086
TOTAL: 23:59:15.177	23:02:17.350	96.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.202	0.0%	59830	0.000	0.003	0.333
article prepare	00:00:00.017	0.0%	89422	0.000	0.000	0.018
article read	00:00:50.260	0.1%	22362	0.000	2.248	780.000
backlog stats	00:00:00.278	0.0%	299556	0.000	0.001	0.022
callbacks	00:00:00.001	0.0%	8	0.000	0.125	1.000
data read	00:00:01.977	0.0%	145906	0.000	0.014	0.100
data write	00:00:15.476	0.0%	153493	0.000	0.101	0.306
idle	23:58:00.833	99.9%	299556	17.577	288.029	3750.765
status file	00:00:00.631	0.0%	869	0.000	0.726	2.500
TOTAL: 23:59:17.379	23:59:09.675	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:50:07.533	99.9%	56878	0.000	432.637	584573.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.630	0.0%	114882	0.000	0.023	7.875
readart	00:00:00.229	0.0%	42	0.000	5.452	18.000
TOTAL: 06:50:28.535	06:50:10.392	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	1270	2635284	2622289	8135	4860	99%	336:53:41
2	news.nask.pl	2	31352	11057	4951	15344	35%	23:40:42
3	news.icm.edu.pl	47	261	10	248	3	3%	17:46:48
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1327	2666904	2633363	13334	20207	98%	378:21:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	771.9 GB	160.9 MB	345.1 MB	772.4 GB	99%	308.3 KB
2	news.nask.pl	92.0 MB	2.3 MB	275.4 MB	369.6 MB	24%	14.3 KB
3	news.icm.edu.pl	555.7 KB	37.8 KB	135.3 KB	728.9 KB	76%	56.1 KB
4	localhost	14.1 KB	0.0 KB	0.0 KB	14.1 KB	100%	2.0 KB
TOTAL: 4		772.0 GB	163.2 MB	620.6 MB	772.8 GB	99%	305.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 22 04:15:02 - 04:59:59	78150	3.0%	28.97	25.7 GB	3.3%	9995.42
Sep 22 05:00:00 - 05:59:59	91571	3.5%	25.44	31.2 GB	4.1%	9096.55
Sep 22 06:00:00 - 06:59:59	92841	3.5%	25.79	31.3 GB	4.1%	9129.44
Sep 22 07:00:00 - 07:59:59	109664	4.2%	30.46	33.2 GB	4.3%	9682.26
Sep 22 08:00:00 - 08:59:59	130669	5.0%	36.30	38.5 GB	5.0%	11224.67
Sep 22 09:00:00 - 09:59:59	125085	4.8%	34.75	35.8 GB	4.6%	10414.15
Sep 22 10:00:00 - 10:59:59	141944	5.4%	39.43	39.4 GB	5.1%	11480.01
Sep 22 11:00:00 - 11:59:59	105091	4.0%	29.19	31.3 GB	4.1%	9102.57
Sep 22 12:00:00 - 12:59:59	142949	5.4%	39.71	39.1 GB	5.1%	11397.99
Sep 22 13:00:00 - 13:59:59	138629	5.3%	38.51	38.7 GB	5.0%	11269.03
Sep 22 14:00:00 - 14:59:59	143039	5.4%	39.73	39.2 GB	5.1%	11431.16
Sep 22 15:00:00 - 15:59:59	139883	5.3%	38.86	39.2 GB	5.1%	11427.53
Sep 22 16:00:00 - 16:59:59	141048	5.4%	39.18	38.8 GB	5.0%	11301.73
Sep 22 17:00:00 - 17:59:59	158429	6.0%	44.01	39.9 GB	5.2%	11613.56
Sep 22 18:00:00 - 18:59:59	153011	5.8%	42.50	38.5 GB	5.0%	11226.50
Sep 22 19:00:00 - 19:59:59	76211	2.9%	21.17	23.5 GB	3.0%	6844.18
Sep 22 20:00:00 - 20:59:59	63913	2.4%	17.75	20.2 GB	2.6%	5876.44
Sep 22 21:00:00 - 21:59:59	56087	2.1%	15.58	17.6 GB	2.3%	5112.84
Sep 22 22:00:00 - 22:59:59	64650	2.5%	17.96	19.7 GB	2.6%	5741.51
Sep 22 23:00:00 - 23:59:59	83682	3.2%	23.25	25.2 GB	3.3%	7339.37
Sep 23 00:00:00 - 00:59:59	79973	3.0%	22.21	24.9 GB	3.2%	7255.68
Sep 23 01:00:00 - 01:59:59	104427	4.0%	29.01	31.0 GB	4.0%	9020.39
Sep 23 02:00:00 - 02:59:59	97700	3.7%	27.14	31.9 GB	4.1%	9299.56
Sep 23 03:00:00 - 03:59:59	90957	3.5%	25.27	29.5 GB	3.8%	8584.77
Sep 23 04:00:00 - 04:15:03	23649	0.9%	26.19	7.5 GB	1.0%	8759.91
TOTAL: 24:00:01	2633252	100.0%	30.48	771.0 GB	100.0%	9356.57

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	15695	27	334	4	15330
2	newsfeed.pionier.net.pl	5222	297	3493	1	1431
3	news.icm.edu.pl	3	0	1	0	2
TOTAL: 3		20920	324	3828	5	16763

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	128
it.tlc.cellulari.android	74
it.comp.os.win.windows10	47
nl.comp.os.linux.techniek	19
comp.sys.raspberry-pi	19
uk.rec.cycling.moderated	13
it.comp.os.win.windows7	8
comp.mobile.ipad	7
comp.mobile.android	2
hr.alt.cellular.gsm.iphone	2
de.comp.lang.vbclassic	2
hr.alt.cellular.gsm.android	1
it.comp.os.linux.ubuntu	1
it.discussioni.energia	1
TOTAL: 14	324

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

Groups	Count
pl.praca.oferowana	3
bit.listserv.catholic	1
christnet.philosophy	1
TOTAL: 3	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13858
Binary in non-binary group	1884
EMP (phl)	128
Scoring filter (8)	104
Scoring filter (9)	64
Scoring filter (10)	27
Adult group ECP	18
Scoring filter (13)	7
Scoring filter (11)	4
Poison newsgroup	4
HTML post	2
Scoring filter (21)	2
Scoring filter (23)	2
Scoring filter (12)	1
Attached HTML file	1
TOTAL: 15	16106

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	15359	1574	11897	519	23	45626	10%	23:57:22
2	icm	53214	225	24986	19703	0	37	0%	23:59:04
3	poznan	16522	66	10807	23	0	3	0%	23:57:20
4	nask	9724	43	6260	1	0	0	0%	23:58:27
5	okapi	0	0	0	0	0	2480	0%	24:00:02
TOTAL: 5		94819	1908	53950	20246	23	48146	2%	119:52:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	7.8 MB	4.2 MB	12.1 MB	0.1 KB/s	5.9 KB	23:57:22
2	icm	2.3 MB	4.8 GB	4.8 GB	58.3 KB/s	252.4 KB	23:59:04
3	nask	552.4 KB	2.4 KB	554.8 KB	0.0 KB/s	12.6 KB	23:58:27
4	poznan	418.5 KB	131.9 KB	550.4 KB	0.0 KB/s	6.2 KB	23:57:20
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		11.1 MB	4.8 GB	4.8 GB	11.7 KB/s	227.7 KB	119:52:15

Backlog files shrunk by innfeed:

Server	Size
panorama	3.3 MB
TOTAL: 1	3.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	22	18.1 KB	11	0	0	00:00:21
2	clif140239.ostnet.pl	28	20	31.6 KB	5	0	1	04:20:37
3	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	0	00:00:00
TOTAL: 3		323	42	49.7 KB	16	7	1	06:50:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	679	22	18.1 KB	11	7	0	00:00:26
2	*.ostnet.pl	28	20	31.6 KB	5	0	1	04:20:37
3	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	0	00:30:12
4	unresolved	2	0	0.0 KB	0	0	0	01:59:10
TOTAL: 4		710	42	49.7 KB	16	7	1	06:50:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
clif140239.ostnet.pl	0.374	0.529	04:20:37
89.38.41.67	2.926	3.613	01:59:10
cew33.neoplus.adsl.tpnet.pl	1.015	0.088	00:30:12
web.man.lodz.pl	0.936	5.158	00:00:21
mon.man.lodz.pl	1.006	5.010	00:00:03
david.man.lodz.pl	0.290	1.627	00:00:01
isvcs01.man.lodz.pl	0.042	0.132	00:00:00
TOTAL: 7	6.589	16.157	06:50:28

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
89.38.41.67	2
cew33.neoplus.adsl.tpnet.pl	1
TOTAL: 4	387

NNRP client timeouts [Top 20]:

System	Conn	Peer
clif140239.ostnet.pl	2	0
cew33.neoplus.adsl.tpnet.pl	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	22	52.4%
2	lodman	18	42.9%
3	lodz	2	4.8%
TOTAL: 3		42	100.0%

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

Newsgroup		Count
1	lodman.info	18
2	pl.comp.pecet	16
3	pl.comp.os.ms-windows.winnt	6
4	lodz.ogloszenia	2
TOTAL: 4		42

Daily Usenet report

Sep 22 04:15:02 -- Sep 23 04:15:03