Daily Usenet report: Sep 11 04:15:06

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Sep 11 04:25:01 newsfeed nnrpd[16013]: web.man.lodz.pl rejected Expiring process 15964
Sep 11 04:25:01 newsfeed nnrpd[16014]: web.man.lodz.pl rejected Expiring process 15964
Sep 11 04:25:01 newsfeed nnrpd[16015]: web.man.lodz.pl rejected Expiring process 15964
Sep 11 04:25:01 newsfeed nnrpd[16019]: web.man.lodz.pl rejected Expiring process 15964
Sep 11 04:25:01 newsfeed nnrpd[16024]: web.man.lodz.pl rejected Expiring process 15964
Sep 11 04:25:02 newsfeed nnrpd[16038]: web.man.lodz.pl rejected Expiring process 15964
Sep 11 04:25:02 newsfeed nnrpd[16044]: web.man.lodz.pl rejected Expiring process 15964
Sep 11 04:30:16 newsfeed overchan[15615]: input line too long, skipping
Sep 11 04:30:53 newsfeed overchan[15615]: input line too long, skipping
Sep 11 08:31:11 newsfeed overchan[15615]: input line too long, skipping
Sep 11 10:32:34 newsfeed nnrpd[29876]: SERVER perl filter not defined
Sep 11 12:51:41 newsfeed nnrpd[2639]: SERVER perl filter not defined
Sep 11 12:52:16 newsfeed nnrpd[2640]: SERVER perl filter not defined
Sep 11 13:23:38 newsfeed nnrpd[3908]: SERVER perl filter not defined
Sep 11 14:06:19 newsfeed nnrpd[5680]: SERVER perl filter not defined
Sep 11 16:11:41 newsfeed overchan[15615]: input line too long, skipping
Sep 12 04:03:02 newsfeed overchan[15615]: input line too long, skipping
Sep 12 04:08:10 newsfeed overchan[15615]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2542728	97.3%	297.3 MB	97.1%
innfeed	42321	1.6%	5.0 MB	1.6%
innd	22259	0.9%	3.5 MB	1.1%
nnrpd	4786	0.2%	500.8 KB	0.2%
overchan	7	0.0%	0.9 KB	0.0%
TOTAL: 5	2612101	100.0%	306.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2537781	96.2%
Negative hits	88171	3.3%
Positive hits	12176	0.5%
Cache misses	982	0.0%
TOTAL: 4	2639110	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.999	0.0%	161	0.000	12.416	240.000
article cleanup	00:00:46.943	0.1%	2542007	0.013	0.018	0.024
article logging	00:01:58.168	0.1%	2542577	0.036	0.046	0.198
article parse	01:21:11.796	5.6%	145629243	0.020	0.033	0.059
article write	00:28:44.953	2.0%	2533883	0.439	0.681	2.215
artlog/artcncl	00:00:00.006	0.0%	156	0.000	0.038	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:36.866	0.3%	161895268	0.001	0.001	0.004
hisgrep/artcncl	00:00:00.350	0.0%	157	0.000	2.229	21.625
hishave/artcncl	00:00:00.320	0.0%	161	0.000	1.988	36.875
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:27.487	2.3%	2639648	0.103	0.738	4.260
history sync	00:00:00.016	0.0%	290	0.000	0.055	1.000
history write	01:25:30.103	5.9%	2538390	0.273	2.021	7.545
hiswrite/artcncl	00:00:00.015	0.0%	4	0.000	3.750	14.000
idle	11:25:42.357	47.6%	95944292	0.254	0.429	0.937
nntp read	01:33:38.684	6.5%	143631698	0.026	0.039	0.051
overview write	00:00:16.684	0.0%	2533883	0.005	0.007	0.009
perl filter	06:08:19.363	25.6%	2538390	7.639	8.706	9.949
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:59.421	0.3%	5092129	0.045	0.059	0.074
TOTAL: 24:00:02.105	23:07:15.531	96.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.234	0.0%	68892	0.000	0.003	0.024
article prepare	00:00:00.011	0.0%	22168	0.000	0.000	0.009
article read	00:01:00.408	0.1%	5512	0.000	10.959	223.500
backlog stats	00:00:00.228	0.0%	199384	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:03.051	0.0%	103850	0.006	0.029	0.075
data write	00:00:12.350	0.0%	88214	0.017	0.140	0.505
idle	23:52:30.403	99.9%	199384	120.160	431.080	984.241
status file	00:00:22.184	0.0%	16004	0.767	1.386	17.538
TOTAL: 23:54:28.489	23:54:08.869	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.885	57.4%	3026	0.000	6.241	37.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.228	0.7%	6646	0.000	0.034	1.000
readart	00:00:00.035	0.1%	20	0.000	1.750	9.500
TOTAL: 00:00:32.888	00:00:19.148	58.2%	-	-	-	-

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	1147	2534677	2523890	7264	3523	99%	384:08:56
2	news.nask.pl	3	16402	9926	1881	4595	60%	24:00:17
3	news.icm.edu.pl	41	274	25	246	3	9%	18:10:48
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1197	2551358	2533846	9391	8121	99%	426:20:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	845.9 GB	89.9 MB	90.6 MB	846.1 GB	99%	351.0 KB
2	news.nask.pl	113.9 MB	1.4 MB	28.4 MB	143.6 MB	79%	10.1 KB
3	news.icm.edu.pl	725.0 KB	8.5 KB	59.4 KB	792.9 KB	91%	28.3 KB
4	localhost	7.4 KB	0.0 KB	0.0 KB	7.4 KB	100%	1.5 KB
TOTAL: 4		846.1 GB	91.2 MB	119.0 MB	846.3 GB	99%	349.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 11 04:15:06 - 04:59:59	98735	3.9%	36.65	29.0 GB	3.4%	11279.51
Sep 11 05:00:00 - 05:59:59	131306	5.2%	36.47	39.0 GB	4.6%	11365.44
Sep 11 06:00:00 - 06:59:59	111203	4.4%	30.89	36.3 GB	4.3%	10577.50
Sep 11 07:00:00 - 07:59:59	102567	4.0%	28.49	37.2 GB	4.4%	10824.65
Sep 11 08:00:00 - 08:59:59	104846	4.1%	29.12	37.4 GB	4.4%	10896.91
Sep 11 09:00:00 - 09:59:59	92335	3.6%	25.65	32.9 GB	3.9%	9570.79
Sep 11 10:00:00 - 10:59:59	102089	4.0%	28.36	33.7 GB	4.0%	9826.34
Sep 11 11:00:00 - 11:59:59	69664	2.7%	19.35	26.4 GB	3.1%	7675.44
Sep 11 12:00:00 - 12:59:59	55239	2.2%	15.34	19.6 GB	2.3%	5709.60
Sep 11 13:00:00 - 13:59:59	59175	2.3%	16.44	18.8 GB	2.2%	5467.19
Sep 11 14:00:00 - 14:59:59	105722	4.2%	29.37	35.6 GB	4.2%	10364.35
Sep 11 15:00:00 - 15:59:59	117714	4.6%	32.70	41.7 GB	4.9%	12160.23
Sep 11 16:00:00 - 16:59:59	123564	4.9%	34.32	43.4 GB	5.1%	12632.80
Sep 11 17:00:00 - 17:59:59	118231	4.7%	32.84	41.6 GB	4.9%	12127.21
Sep 11 18:00:00 - 18:59:59	98353	3.9%	27.32	35.8 GB	4.2%	10441.62
Sep 11 19:00:00 - 19:59:59	121319	4.8%	33.70	43.1 GB	5.1%	12555.83
Sep 11 20:00:00 - 20:59:59	116370	4.6%	32.33	40.9 GB	4.8%	11915.20
Sep 11 21:00:00 - 21:59:59	127863	5.0%	35.52	43.2 GB	5.1%	12582.60
Sep 11 22:00:00 - 22:59:59	130428	5.1%	36.23	39.5 GB	4.7%	11494.16
Sep 11 23:00:00 - 23:59:59	125090	4.9%	34.75	38.3 GB	4.5%	11165.32
Sep 12 00:00:00 - 00:59:59	93752	3.7%	26.04	28.5 GB	3.4%	8307.39
Sep 12 01:00:00 - 01:59:59	94067	3.7%	26.13	28.8 GB	3.4%	8396.61
Sep 12 02:00:00 - 02:59:59	105188	4.2%	29.22	33.9 GB	4.0%	9885.15
Sep 12 03:00:00 - 03:59:59	102187	4.0%	28.39	32.5 GB	3.8%	9468.71
Sep 12 04:00:00 - 04:15:02	26865	1.1%	29.78	8.0 GB	0.9%	9322.49
TOTAL: 23:59:56	2533872	100.0%	29.33	845.2 GB	100.0%	10258.29

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4845	27	332	1	4485
2	newsfeed.pionier.net.pl	3849	189	3289	1	370
3	news.icm.edu.pl	5	0	1	0	4
TOTAL: 3		8699	216	3622	2	4859

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	116
it.comp.os.win.windows10	37
comp.mobile.android	27
it.tlc.cellulari.android	20
de.comp.lang.vbclassic	7
it.comp.os.linux.ubuntu	3
hr.rec.glazba.hifi	2
it.tlc.cellulari.iphone	2
it.comp.os.win.windows7	2
TOTAL: 9	216

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

Groups	Count
bit.listserv.catholic	1
pl.praca.oferowana	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4033
Binary in non-binary group	141
EMP (phl)	33
Scoring filter (8)	20
Scoring filter (9)	17
Scoring filter (11)	15
Scoring filter (10)	10
Scoring filter (14)	5
Scoring filter (12)	5
Too many newsgroups	3
Poison newsgroup	3
HTML post	2
Scoring filter (17)	1
Too many newsgroups (low_xpost)	1
TOTAL: 14	4289

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	334
	news.nask.pl	234
	news.icm.edu.pl	2
	TOTAL: 3	570
TOTAL: 1		570

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	52657	1151	24741	344	6	42848	2%	23:59:57
2	poznan	15629	165	9469	50	0	2	1%	23:59:51
3	icm	28786	61	19857	3055	0	0	0%	23:59:57
4	nask	4250	4	3030	0	0	0	0%	23:59:57
5	okapi	0	0	0	0	0	1765	0%	23:52:53
TOTAL: 5		101322	1381	57097	3449	6	44615	1%	119:52:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.0 MB	1.9 MB	6.9 MB	0.1 KB/s	4.7 KB	23:59:57
2	icm	911.2 KB	1.1 GB	1.1 GB	13.2 KB/s	366.1 KB	23:59:57
3	poznan	685.5 KB	182.4 KB	867.9 KB	0.0 KB/s	4.0 KB	23:59:51
4	nask	9.8 KB	0.0 KB	9.8 KB	0.0 KB/s	2.5 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:52:53
TOTAL: 5		6.5 MB	1.1 GB	1.1 GB	2.7 KB/s	237.8 KB	119:52:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	294	20	21.2 KB	10	0	00:00:25
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		299	20	21.2 KB	10	5	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	683	20	21.2 KB	10	5	0	00:00:31
TOTAL: 1		683	20	21.2 KB	10	5	0	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.516	5.840	00:00:25
mon.man.lodz.pl	1.324	6.132	00:00:04
david.man.lodz.pl	0.412	1.664	00:00:01
isvcs01.man.lodz.pl	0.048	0.100	00:00:00
TOTAL: 4	3.300	13.736	00:00:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	12
2	pl.comp.os.ms-windows.winnt	8
TOTAL: 2		20

Daily Usenet report

Sep 11 04:15:06 -- Sep 12 04:15:02