Daily Usenet report: Dec 20 04:15:03

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Dec 20 05:58:08 newsfeed nnrpd[15507]: SERVER perl filter not defined
Dec 20 08:50:14 newsfeed nnrpd[22285]: SERVER perl filter not defined
Dec 20 08:50:42 newsfeed nnrpd[22297]: SERVER perl filter not defined
Dec 20 09:17:47 newsfeed overchan[11539]: input line too long, skipping
Dec 20 09:24:23 newsfeed overchan[11539]: input line too long, skipping
Dec 20 09:35:06 newsfeed nnrpd[23843]: SERVER perl filter not defined
Dec 20 09:41:01 newsfeed nnrpd[24113]: SERVER perl filter not defined
Dec 20 11:55:45 newsfeed nnrpd[2735]: SERVER perl filter not defined
Dec 20 15:50:19 newsfeed overchan[11539]: input line too long, skipping
Dec 20 16:13:47 newsfeed overchan[11539]: input line too long, skipping
Dec 20 18:18:04 newsfeed nnrpd[17053]: SERVER perl filter not defined
Dec 20 19:45:07 newsfeed overchan[11539]: input line too long, skipping
Dec 20 19:50:37 newsfeed overchan[11539]: input line too long, skipping
Dec 20 20:24:00 newsfeed overchan[11539]: input line too long, skipping
Dec 20 21:48:39 newsfeed overchan[11539]: input line too long, skipping
Dec 21 02:47:23 newsfeed overchan[11539]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4731926	98.5%	536.2 MB	98.2%
innfeed	36771	0.8%	4.4 MB	0.8%
innd	30731	0.6%	5.0 MB	0.9%
nnrpd	4801	0.1%	502.6 KB	0.1%
overchan	10	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	4804240	100.0%	546.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4703829	97.7%
Negative hits	101009	2.1%
Positive hits	10483	0.2%
Cache misses	139	0.0%
TOTAL: 4	4815460	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.008	0.0%	193	0.000	20.767	204.250
article cleanup	00:00:53.754	0.1%	4731206	0.008	0.011	0.016
article logging	00:03:30.128	0.2%	4731980	0.024	0.044	6.013
article parse	00:59:51.250	4.2%	118595527	0.019	0.030	0.054
article write	00:24:09.470	1.7%	4723861	0.144	0.307	2.925
artlog/artcncl	00:00:00.003	0.0%	192	0.000	0.016	1.000
data move	00:04:06.479	0.3%	138489922	0.001	0.002	0.005
hisgrep/artcncl	00:00:00.955	0.0%	187	0.000	5.107	66.750
hishave/artcncl	00:00:00.059	0.0%	193	0.000	0.306	1.944
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:39:10.120	6.9%	4844010	0.153	1.228	17.469
history sync	00:00:00.015	0.0%	290	0.000	0.052	0.500
history write	04:48:02.414	20.0%	4729042	0.556	3.655	60.071
hiswrite/artcncl	00:00:00.009	0.0%	6	0.000	1.500	9.000
idle	07:08:41.131	29.8%	73010713	0.085	0.352	0.853
nntp read	01:01:24.054	4.3%	114591983	0.020	0.032	0.052
overview write	00:00:23.459	0.0%	4723861	0.004	0.005	0.007
perl filter	07:14:19.902	30.2%	4729022	4.135	5.511	7.707
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:35.119	0.5%	9469598	0.027	0.042	0.056
TOTAL: 24:00:01.747	23:31:12.329	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.218	0.0%	101178	0.000	0.002	0.015
article prepare	00:00:00.022	0.0%	57980	0.000	0.000	0.017
article read	00:06:35.368	0.5%	14583	0.000	27.112	3072.829
backlog stats	00:00:00.186	0.0%	228097	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	103	0.000	0.000	0.000
data read	00:00:01.768	0.0%	121858	0.001	0.015	0.032
data write	00:00:05.620	0.0%	102199	0.012	0.055	0.239
idle	23:44:16.899	99.3%	228097	12.459	374.652	1092.594
status file	00:03:42.421	0.3%	13902	0.333	15.999	826.341
TOTAL: 23:54:57.785	23:54:42.502	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	24:20:39.179	99.9%	672677	0.000	130.284	130.853
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:36.303	0.0%	1345958	0.000	0.027	0.333
readart	00:00:00.000	0.0%	32	0.000	0.000	0.000
TOTAL: 24:22:09.295	24:21:15.482	99.9%	-	-	-	-

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	375	4723183	4713777	7053	2353	99%	539:01:39
2	news.nask.pl	3	15973	9952	1038	4983	62%	24:04:00
3	news.icm.edu.pl	40	247	12	233	2	4%	16:33:04
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		426	4739410	4723748	8324	7338	99%	579:38:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	906.7 GB	15.9 MB	17.6 MB	906.8 GB	99%	201.6 KB
2	news.nask.pl	51.0 MB	315.6 KB	18.6 MB	69.9 MB	72%	4.8 KB
3	news.icm.edu.pl	190.6 KB	0.0 KB	69.8 KB	260.4 KB	73%	18.6 KB
4	localhost	9.9 KB	0.0 KB	0.0 KB	9.9 KB	100%	1.4 KB
TOTAL: 4		906.8 GB	16.2 MB	36.3 MB	906.8 GB	99%	201.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 20 04:15:03 - 04:59:59	121267	2.6%	44.96	27.8 GB	3.1%	10795.17
Dec 20 05:00:00 - 05:59:59	176576	3.7%	49.05	41.7 GB	4.6%	12137.44
Dec 20 06:00:00 - 06:59:59	169923	3.6%	47.20	40.9 GB	4.5%	11922.28
Dec 20 07:00:00 - 07:59:59	159735	3.4%	44.37	42.3 GB	4.7%	12311.29
Dec 20 08:00:00 - 08:59:59	173230	3.7%	48.12	42.0 GB	4.6%	12240.98
Dec 20 09:00:00 - 09:59:59	181796	3.8%	50.50	42.9 GB	4.7%	12481.14
Dec 20 10:00:00 - 10:59:59	149825	3.2%	41.62	36.5 GB	4.0%	10644.48
Dec 20 11:00:00 - 11:59:59	175515	3.7%	48.75	39.8 GB	4.4%	11589.39
Dec 20 12:00:00 - 12:59:59	163740	3.5%	45.48	36.5 GB	4.0%	10630.83
Dec 20 13:00:00 - 13:59:59	178429	3.8%	49.56	38.5 GB	4.2%	11202.69
Dec 20 14:00:00 - 14:59:59	219066	4.6%	60.85	40.3 GB	4.4%	11729.97
Dec 20 15:00:00 - 15:59:59	227085	4.8%	63.08	39.6 GB	4.4%	11545.94
Dec 20 16:00:00 - 16:59:59	198888	4.2%	55.25	40.5 GB	4.5%	11802.98
Dec 20 17:00:00 - 17:59:59	207005	4.4%	57.50	41.7 GB	4.6%	12143.92
Dec 20 18:00:00 - 18:59:59	195909	4.1%	54.42	42.6 GB	4.7%	12416.55
Dec 20 19:00:00 - 19:59:59	192958	4.1%	53.60	41.7 GB	4.6%	12153.16
Dec 20 20:00:00 - 20:59:59	231999	4.9%	64.44	40.3 GB	4.4%	11724.36
Dec 20 21:00:00 - 21:59:59	265950	5.6%	73.88	37.8 GB	4.2%	11009.28
Dec 20 22:00:00 - 22:59:59	316196	6.7%	87.83	36.6 GB	4.0%	10653.59
Dec 20 23:00:00 - 23:59:59	292170	6.2%	81.16	33.4 GB	3.7%	9714.70
Dec 21 00:00:00 - 00:59:59	240160	5.1%	66.71	29.4 GB	3.2%	8551.12
Dec 21 01:00:00 - 01:59:59	175510	3.7%	48.75	32.3 GB	3.6%	9412.94
Dec 21 02:00:00 - 02:59:59	118220	2.5%	32.84	23.7 GB	2.6%	6917.36
Dec 21 03:00:00 - 03:59:59	153368	3.2%	42.60	30.9 GB	3.4%	9007.90
Dec 21 04:00:00 - 04:15:02	39093	0.8%	43.34	8.2 GB	0.9%	9536.01
TOTAL: 23:59:59	4723613	100.0%	54.67	907.8 GB	100.0%	11017.67

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	5215	17	122	6	5070
2	newsfeed.pionier.net.pl	2904	216	2043	1	644
3	news.icm.edu.pl	2	0	0	0	2
TOTAL: 3		8121	233	2165	7	5716

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	173
it.tlc.cellulari.android	22
it.comp.os.win.windows10	14
comp.mobile.android	12
comp.sys.raspberry-pi	4
comp.mobile.ipad	4
it.comp.os.win.windows7	4
TOTAL: 7	233

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4641
Binary in non-binary group	126
Scoring filter (8)	64
Scoring filter (9)	43
HTML post	20
EMP (fsl)	8
Too many newsgroups	6
Scoring filter (16)	4
Scoring filter (12)	2
Scoring filter (11)	2
Scoring filter (10)	2
Scoring filter (18)	1
Scoring filter (13)	1
Scoring filter (14)	1
TOTAL: 14	4921

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	551
	news.nask.pl	224
	TOTAL: 2	775
TOTAL: 1		775

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	89084	6751	53396	5541	352	35158	7%	23:59:57
2	poznan	15834	350	9538	19	0	1	2%	23:59:56
3	icm	24150	42	19222	687	0	1	0%	23:59:57
4	nask	3272	7	2768	1	0	0	0%	23:59:57
5	okapi	0	0	0	0	0	1109	0%	23:57:50
TOTAL: 5		132340	7150	84924	6248	352	36269	5%	119:57:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	52.7 MB	27.6 MB	80.3 MB	1.0 KB/s	6.7 KB	23:59:57
2	icm	3.8 MB	230.2 MB	234.0 MB	2.8 KB/s	328.6 KB	23:59:57
3	poznan	1.1 MB	103.2 KB	1.2 MB	0.0 KB/s	3.3 KB	23:59:56
4	nask	21.1 KB	4.1 KB	25.2 KB	0.0 KB/s	3.2 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		57.6 MB	257.9 MB	315.4 MB	0.7 KB/s	24.1 KB	119:57:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	32	25.1 KB	16	0	00:00:24
2	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:01
TOTAL: 2		295	32	25.1 KB	16	7	24:22:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	680	32	25.1 KB	16	7	00:00:31
2	unresolved	1	0	0.0 KB	0	0	24:21:36
TOTAL: 2		681	32	25.1 KB	16	7	24:22:08

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.177	35.716	61.956	24:21:36
web.man.lodz.pl	1.264	6.156	00:00:24
mon.man.lodz.pl	1.076	5.792	00:00:04
david.man.lodz.pl	0.328	1.808	00:00:01
isvcs01.man.lodz.pl	0.052	0.132	00:00:01
TOTAL: 5	38.436	75.844	24:22:08

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	289
david.man.lodz.pl	96
185.48.192.177	1
TOTAL: 3	386

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	22
2	pl.comp.pecet	10
TOTAL: 2		32

Daily Usenet report

Dec 20 04:15:03 -- Dec 21 04:15:02