Daily Usenet report: Aug 23 04:15:02

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Aug 23 04:21:42 newsfeed nnrpd[5242]: mon.man.lodz.pl rejected Expiring process 5175
Aug 23 10:30:10 newsfeed nnrpd[19266]: SERVER perl filter not defined
Aug 23 11:38:14 newsfeed nnrpd[21757]: SERVER perl filter not defined
Aug 23 11:48:18 newsfeed overchan[4888]: input line too long, skipping
Aug 23 13:23:58 newsfeed nnrpd[25558]: SERVER perl filter not defined
Aug 23 15:53:49 newsfeed overchan[4888]: input line too long, skipping
Aug 23 16:49:53 newsfeed overchan[4888]: input line too long, skipping
Aug 23 17:44:13 newsfeed overchan[4888]: input line too long, skipping
Aug 23 18:07:03 newsfeed overchan[4888]: input line too long, skipping
Aug 23 18:26:14 newsfeed overchan[4888]: input line too long, skipping
Aug 23 20:16:58 newsfeed overchan[4888]: input line too long, skipping
Aug 23 20:50:08 newsfeed overchan[4888]: input line too long, skipping
Aug 23 22:20:24 newsfeed overchan[4888]: input line too long, skipping
Aug 23 22:54:26 newsfeed nnrpd[14183]: SERVER perl filter not defined
Aug 23 22:55:38 newsfeed nnrpd[14385]: SERVER perl filter not defined
Aug 23 22:58:00 newsfeed nnrpd[14416]: SERVER perl filter not defined
Aug 23 23:00:48 newsfeed nnrpd[14521]: SERVER perl filter not defined
Aug 24 01:51:02 newsfeed overchan[4888]: input line too long, skipping
Aug 24 03:36:51 newsfeed overchan[4888]: input line too long, skipping
Aug 24 03:38:08 newsfeed overchan[4888]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2219080	97.0%	256.9 MB	96.6%
innfeed	46892	2.0%	5.6 MB	2.1%
innd	17756	0.8%	2.9 MB	1.1%
nnrpd	4811	0.2%	501.8 KB	0.2%
overchan	13	0.0%	2.2 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2288553	100.0%	265.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2191644	90.5%
Positive hits	141126	5.8%
Negative hits	87288	3.6%
Cache misses	722	0.0%
TOTAL: 4	2420780	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.058	0.0%	102	0.000	10.373	147.000
article cleanup	00:00:26.184	0.0%	2216220	0.008	0.012	0.018
article logging	00:01:33.491	0.1%	2216752	0.030	0.042	0.188
article parse	00:37:24.155	2.6%	93111699	0.013	0.024	0.049
article write	00:15:20.914	1.1%	2208637	0.226	0.417	0.850
artlog/artcncl	00:00:00.002	0.0%	101	0.000	0.020	0.333
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:18.753	0.2%	106303257	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.282	0.0%	102	0.000	2.765	26.000
hishave/artcncl	00:00:00.081	0.0%	102	0.000	0.794	4.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:41:21.094	2.9%	2441292	0.223	1.016	6.002
history sync	00:00:00.020	0.0%	290	0.000	0.069	1.000
history write	01:18:54.382	5.5%	2210234	0.295	2.142	8.561
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	15:48:47.836	65.9%	76100748	0.450	0.748	1.403
nntp read	01:00:43.077	4.2%	91652265	0.023	0.040	0.072
overview write	00:00:10.770	0.0%	2208637	0.003	0.005	0.008
perl filter	03:40:11.046	15.3%	2210228	4.125	5.977	8.296
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:11.012	0.3%	4686935	0.036	0.054	0.077
TOTAL: 24:00:01.306	23:32:24.157	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.234	0.0%	528989	0.000	0.002	0.006
article prepare	00:00:00.118	0.0%	561254	0.000	0.000	0.002
article read	00:01:24.427	0.1%	126325	0.205	0.668	55.951
backlog stats	00:00:02.013	0.0%	2463715	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:16.523	0.0%	1058510	0.010	0.016	0.023
data write	00:05:58.868	0.4%	1422918	0.037	0.252	0.444
idle	23:41:24.659	99.4%	2463715	15.356	34.616	272.245
status file	00:00:16.211	0.0%	17095	0.469	0.948	6.397
TOTAL: 23:50:22.074	23:49:24.053	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:26.186	95.6%	3089	0.000	86.172	9840.240
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.252	0.1%	6834	0.000	0.037	0.789
readart	00:00:00.029	0.0%	48	0.000	0.604	1.857
TOTAL: 00:04:38.312	00:04:26.467	95.7%	-	-	-	-

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	1166	2210741	2204984	2797	2960	99%	396:37:14
2	news.nask.pl	2	140790	5790	130472	4528	4%	23:09:28
3	news.icm.edu.pl	48	222	89	108	25	40%	17:01:23
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1224	2351760	2210870	133377	7513	94%	436:48:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	644.2 GB	72.1 MB	83.1 MB	644.3 GB	99%	306.0 KB
2	news.nask.pl	196.6 MB	548.1 MB	559.4 MB	1.3 GB	15%	129.4 KB
3	news.icm.edu.pl	1.3 MB	8.7 KB	333.0 KB	1.6 MB	79%	14.6 KB
4	localhost	10.7 KB	0.0 KB	0.0 KB	10.7 KB	100%	1.5 KB
TOTAL: 4		644.3 GB	620.2 MB	642.9 MB	645.6 GB	99%	305.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 23 04:15:02 - 04:59:59	68194	3.1%	25.28	21.4 GB	3.3%	8298.56
Aug 23 05:00:00 - 05:59:59	105407	4.8%	29.28	35.7 GB	5.5%	10391.63
Aug 23 06:00:00 - 06:59:59	112954	5.1%	31.38	35.5 GB	5.5%	10338.65
Aug 23 07:00:00 - 07:59:59	79384	3.6%	22.05	23.6 GB	3.7%	6861.30
Aug 23 08:00:00 - 08:59:59	69010	3.1%	19.17	21.8 GB	3.4%	6348.15
Aug 23 09:00:00 - 09:59:59	86848	3.9%	24.12	27.3 GB	4.2%	7949.00
Aug 23 10:00:00 - 10:59:59	83375	3.8%	23.16	23.7 GB	3.7%	6904.60
Aug 23 11:00:00 - 11:59:59	94769	4.3%	26.32	23.1 GB	3.6%	6734.06
Aug 23 12:00:00 - 12:59:59	46018	2.1%	12.78	14.6 GB	2.3%	4243.12
Aug 23 13:00:00 - 13:59:59	60724	2.7%	16.87	20.0 GB	3.1%	5834.57
Aug 23 14:00:00 - 14:59:59	79472	3.6%	22.08	24.4 GB	3.8%	7098.59
Aug 23 15:00:00 - 15:59:59	116818	5.3%	32.45	37.9 GB	5.9%	11029.05
Aug 23 16:00:00 - 16:59:59	98253	4.4%	27.29	27.4 GB	4.2%	7974.97
Aug 23 17:00:00 - 17:59:59	96548	4.4%	26.82	25.4 GB	3.9%	7396.26
Aug 23 18:00:00 - 18:59:59	89389	4.0%	24.83	26.6 GB	4.1%	7749.53
Aug 23 19:00:00 - 19:59:59	114832	5.2%	31.90	34.1 GB	5.3%	9944.23
Aug 23 20:00:00 - 20:59:59	119276	5.4%	33.13	32.7 GB	5.1%	9517.57
Aug 23 21:00:00 - 21:59:59	106433	4.8%	29.56	28.5 GB	4.4%	8288.68
Aug 23 22:00:00 - 22:59:59	94320	4.3%	26.20	27.7 GB	4.3%	8070.68
Aug 23 23:00:00 - 23:59:59	115562	5.2%	32.10	31.0 GB	4.8%	9034.02
Aug 24 00:00:00 - 00:59:59	99907	4.5%	27.75	25.2 GB	3.9%	7354.04
Aug 24 01:00:00 - 01:59:59	96783	4.4%	26.88	26.9 GB	4.2%	7822.16
Aug 24 02:00:00 - 02:59:59	66990	3.0%	18.61	19.8 GB	3.1%	5764.25
Aug 24 03:00:00 - 03:59:59	85977	3.9%	23.88	23.7 GB	3.7%	6891.04
Aug 24 04:00:00 - 04:15:02	23588	1.1%	26.15	6.7 GB	1.0%	7761.53
TOTAL: 24:00:00	2210831	100.0%	25.59	644.4 GB	100.0%	7821.01

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4768	15	3344	3	1406
2	newsfeed.pionier.net.pl	3352	185	2660	1	506
3	news.icm.edu.pl	28	0	1	0	27
TOTAL: 3		8148	200	6005	4	1939

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	111
it.tlc.cellulari.android	45
it.comp.os.win.windows10	10
de.rec.sf.startrek	9
comp.mobile.android	8
comp.sys.raspberry-pi	6
de.comp.office-pakete.ms-office.outlook	3
comp.mobile.ipad	2
de.comm.software.mailreader	2
it.comp.os.win.windows7	1
comp.sys.mac.vintage	1
uk.net.providers.gradwell	1
uk.rec.cycling.moderated	1
TOTAL: 13	200

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1072
Binary in non-binary group	198
EMP (phl)	67
Scoring filter (8)	28
Scoring filter (16)	13
Scoring filter (9)	12
Scoring filter (18)	4
Scoring filter (10)	3
Too many newsgroups	3
Excessively crossposted test article	1
Scoring filter (12)	1
TOTAL: 11	1402

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	newsfeed.pionier.net.pl	6
	TOTAL: 1	6
Including strange strings
	newsfeed.pionier.net.pl	392
	news.nask.pl	137
	news.icm.edu.pl	2
	TOTAL: 3	531
TOTAL: 2		537

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	356759	117357	138774	62	0	726	32%	23:59:57
2	panorama	278535	1657	248358	353	7	279850	0%	23:59:57
3	poznan	7669	101	5388	13	0	0	1%	23:59:57
4	icm	342588	66	246649	21157	0	6	0%	23:59:57
5	okapi	0	0	0	0	0	1258	0%	23:57:50
TOTAL: 5		985551	119181	639169	21585	7	281840	12%	119:57:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	64.2 GB	29.7 MB	64.3 GB	780.0 KB/s	573.9 KB	23:59:57
2	panorama	320.8 MB	18.4 MB	339.1 MB	4.0 KB/s	172.8 KB	23:59:57
3	icm	5.5 MB	10.3 GB	10.3 GB	124.9 KB/s	508.6 KB	23:59:57
4	poznan	409.2 KB	45.8 KB	455.0 KB	0.0 KB/s	4.0 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		64.6 GB	10.3 GB	74.9 GB	181.8 KB/s	557.9 KB	119:57:38

Backlog files shrunk by innfeed:

Server	Size
panorama	976.8 KB
TOTAL: 1	976.8 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	34	28.7 KB	16	0	00:00:25
2	ool-4578089a.dyn.optonline.net	1	14	8.8 KB	1	0	00:04:06
3	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 3		296	48	37.5 KB	17	7	00:04:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	680	34	28.7 KB	16	7	00:00:31
2	*.dyn.optonline.net	1	14	8.8 KB	1	0	00:04:06
TOTAL: 2		681	48	37.5 KB	17	7	00:04:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
ool-4578089a.dyn.optonline.net	0.020	0.020	00:04:06
web.man.lodz.pl	1.452	4.924	00:00:25
mon.man.lodz.pl	1.076	3.912	00:00:03
david.man.lodz.pl	0.400	1.352	00:00:01
isvcs01.man.lodz.pl	0.064	0.140	00:00:00
TOTAL: 5	3.012	10.348	00:04:37

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	289
david.man.lodz.pl	96
TOTAL: 2	385

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	34	70.8%
2	lodman	14	29.2%
TOTAL: 2		48	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	24
2	lodman.info	14
3	pl.comp.pecet	10
TOTAL: 3		48

Daily Usenet report

Aug 23 04:15:02 -- Aug 24 04:15:02