Daily Usenet report: May 10 04:15:01

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

May 10 08:09:45 newsfeed nnrpd[17625]: SERVER perl filter not defined
May 10 08:10:23 newsfeed nnrpd[17675]: SERVER perl filter not defined
May 10 10:53:03 newsfeed nnrpd[24395]: SERVER perl filter not defined
May 10 11:29:27 newsfeed nnrpd[25692]: SERVER perl filter not defined
May 10 14:12:51 newsfeed overchan[26502]: input line too long, skipping
May 10 19:07:51 newsfeed nnrpd[10147]: SERVER perl filter not defined
May 10 22:42:56 newsfeed overchan[26502]: input line too long, skipping
May 10 23:26:54 newsfeed overchan[26502]: input line too long, skipping
May 10 23:28:58 newsfeed overchan[26502]: input line too long, skipping
May 11 00:21:33 newsfeed overchan[26502]: input line too long, skipping
May 11 00:21:51 newsfeed overchan[26502]: input line too long, skipping
May 11 00:22:36 newsfeed overchan[26502]: input line too long, skipping
May 11 00:23:27 newsfeed overchan[26502]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2900092	97.5%	331.3 MB	97.1%
innfeed	45869	1.5%	5.5 MB	1.6%
innd	24668	0.8%	3.9 MB	1.1%
nnrpd	4936	0.2%	524.2 KB	0.2%
overchan	9	0.0%	1.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2975575	100.0%	341.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2891188	96.5%
Negative hits	92521	3.1%
Positive hits	11210	0.4%
Cache misses	108	0.0%
TOTAL: 4	2995027	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.313	0.0%	207	0.000	11.174	301.500
article cleanup	00:00:33.287	0.0%	2898466	0.009	0.011	0.015
article logging	00:01:58.558	0.1%	2899068	0.027	0.041	0.127
article parse	00:43:16.914	3.0%	94801327	0.020	0.027	0.038
article write	00:25:06.964	1.7%	2890725	0.221	0.521	1.118
artlog/artcncl	00:00:00.017	0.0%	206	0.000	0.083	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:42.834	0.1%	110490745	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.588	0.0%	197	0.000	2.985	80.000
hishave/artcncl	00:00:00.065	0.0%	207	0.000	0.314	4.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:33:11.746	2.3%	2999462	0.024	0.664	5.336
history sync	00:00:00.019	0.0%	290	0.000	0.066	1.000
history write	01:31:16.581	6.3%	2895527	0.230	1.891	7.245
hiswrite/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
idle	14:52:56.125	62.0%	74921701	0.339	0.715	1.509
nntp read	00:52:29.103	3.6%	92651399	0.027	0.034	0.047
overview write	00:00:13.638	0.0%	2890725	0.003	0.005	0.006
perl filter	04:23:36.053	18.3%	2895525	3.637	5.462	7.555
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:19.700	0.4%	5809338	0.043	0.055	0.071
TOTAL: 24:00:02.656	23:31:44.505	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.250	0.0%	139009	0.000	0.002	0.011
article prepare	00:00:00.010	0.0%	60198	0.000	0.000	0.040
article read	00:02:16.391	0.2%	15274	0.000	8.930	430.400
backlog stats	00:00:00.190	0.0%	273905	0.000	0.001	0.003
callbacks	00:00:00.001	0.0%	144	0.000	0.007	0.036
data read	00:00:02.096	0.0%	145818	0.002	0.014	0.052
data write	00:00:04.446	0.0%	118439	0.015	0.038	0.093
idle	23:51:05.401	99.8%	273905	6.895	313.486	1025.171
status file	00:00:13.372	0.0%	15792	0.608	0.847	1.871
TOTAL: 23:53:59.848	23:53:42.157	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:21.844	95.0%	3191	0.000	82.057	18854.167
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.275	0.1%	7026	0.000	0.039	0.947
readart	00:00:00.001	0.0%	40	0.000	0.025	0.500
TOTAL: 00:04:35.739	00:04:22.120	95.1%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1205	2892955	2881980	6244	4731	99%	386:30:09
2	news.nask.pl	3	14358	9592	1780	2986	66%	23:59:47
3	news.icm.edu.pl	55	201	13	182	6	6%	14:17:55
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1269	2907519	2891590	8206	7723	99%	424:47:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	734.9 GB	20.6 MB	288.5 MB	735.2 GB	99%	267.1 KB
2	news.nask.pl	39.5 MB	464.1 KB	28.9 MB	68.9 MB	57%	5.6 KB
3	news.icm.edu.pl	155.2 KB	0.0 KB	85.5 KB	240.7 KB	64%	12.7 KB
4	localhost	7.9 KB	0.0 KB	0.0 KB	7.9 KB	100%	1.6 KB
TOTAL: 4		735.0 GB	21.1 MB	317.5 MB	735.3 GB	99%	265.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 10 04:15:01 - 04:59:59	88984	3.1%	32.97	23.5 GB	3.2%	9146.78
May 10 05:00:00 - 05:59:59	121390	4.2%	33.72	32.3 GB	4.4%	9405.13
May 10 06:00:00 - 06:59:59	147035	5.1%	40.84	32.2 GB	4.4%	9377.13
May 10 07:00:00 - 07:59:59	125066	4.3%	34.74	30.1 GB	4.1%	8772.89
May 10 08:00:00 - 08:59:59	135685	4.7%	37.69	34.6 GB	4.7%	10071.73
May 10 09:00:00 - 09:59:59	123972	4.3%	34.44	30.5 GB	4.2%	8892.60
May 10 10:00:00 - 10:59:59	121368	4.2%	33.71	35.2 GB	4.8%	10251.58
May 10 11:00:00 - 11:59:59	106587	3.7%	29.61	29.9 GB	4.1%	8712.69
May 10 12:00:00 - 12:59:59	111734	3.9%	31.04	32.2 GB	4.4%	9371.22
May 10 13:00:00 - 13:59:59	125653	4.3%	34.90	35.6 GB	4.8%	10376.89
May 10 14:00:00 - 14:59:59	130037	4.5%	36.12	34.3 GB	4.7%	9980.75
May 10 15:00:00 - 15:59:59	130793	4.5%	36.33	24.6 GB	3.3%	7166.57
May 10 16:00:00 - 16:59:59	124942	4.3%	34.71	33.6 GB	4.6%	9800.79
May 10 17:00:00 - 17:59:59	121450	4.2%	33.74	29.1 GB	4.0%	8485.68
May 10 18:00:00 - 18:59:59	105317	3.6%	29.25	31.9 GB	4.3%	9294.09
May 10 19:00:00 - 19:59:59	118370	4.1%	32.88	30.8 GB	4.2%	8964.66
May 10 20:00:00 - 20:59:59	131874	4.6%	36.63	31.2 GB	4.2%	9086.29
May 10 21:00:00 - 21:59:59	102593	3.5%	28.50	25.7 GB	3.5%	7499.76
May 10 22:00:00 - 22:59:59	125657	4.3%	34.90	32.1 GB	4.4%	9361.42
May 10 23:00:00 - 23:59:59	158234	5.5%	43.95	35.1 GB	4.8%	10214.25
May 11 00:00:00 - 00:59:59	117904	4.1%	32.75	22.8 GB	3.1%	6644.99
May 11 01:00:00 - 01:59:59	104326	3.6%	28.98	28.1 GB	3.8%	8187.88
May 11 02:00:00 - 02:59:59	94887	3.3%	26.36	26.9 GB	3.7%	7835.62
May 11 03:00:00 - 03:59:59	97392	3.4%	27.05	26.4 GB	3.6%	7684.94
May 11 04:00:00 - 04:15:02	20273	0.7%	22.48	6.6 GB	0.9%	7639.99
TOTAL: 24:00:01	2891523	100.0%	33.47	735.4 GB	100.0%	8925.37

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5121	156	2820	3	2142
2	news.nask.pl	3235	23	123	18	3071
3	news.icm.edu.pl	7	0	0	0	7
TOTAL: 3		8363	179	2943	21	5220

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	91
it.tlc.cellulari.android	40
comp.mobile.android	26
it.comp.os.win.windows10	16
it.comp.os.win.windows7	3
de.comp.lang.php	2
de.rec.sf.startrek	1
TOTAL: 7	179

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

Groups	Count
pl.sci.matematyka	17
pl.praca.oferowana	3
bit.listserv.catholic	1
TOTAL: 3	21

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2540
Binary in non-binary group	1941
Poison newsgroup	48
Scoring filter (9)	28
Scoring filter (8)	26
Scoring filter (10)	14
EMP (fsl)	7
Too many newsgroups	4
EMP (phl)	4
Excessively crossposted test article	2
HTML post	1
TOTAL: 11	4615

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	newsfeed.pionier.net.pl	2
	TOTAL: 1	2
Including strange strings
	newsfeed.pionier.net.pl	389
	news.nask.pl	213
	news.icm.edu.pl	1
	TOTAL: 3	603
TOTAL: 2		605

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	115961	5833	75309	8098	491	49370	5%	23:59:58
2	poznan	15656	236	9269	23	0	0	1%	23:59:56
3	icm	26118	49	18474	27	0	0	0%	23:59:54
4	nask	3951	6	2783	0	0	0	0%	23:59:58
5	okapi	0	0	0	0	0	1067	0%	23:57:51
TOTAL: 5		161686	6124	105835	8148	491	50437	3%	119:57:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	16.1 MB	40.3 MB	56.5 MB	0.7 KB/s	4.2 KB	23:59:58
2	icm	2.4 MB	7.6 MB	10.0 MB	0.1 KB/s	134.4 KB	23:59:54
3	poznan	955.5 KB	179.8 KB	1.1 MB	0.0 KB/s	4.4 KB	23:59:56
4	nask	11.5 KB	0.0 KB	11.5 KB	0.0 KB/s	1.9 KB	23:59:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		19.5 MB	48.1 MB	67.6 MB	0.2 KB/s	4.8 KB	119:57:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	30	23.6 KB	13	0	0	00:00:20
2	0890788407.static.corbina.ru	12	5	8.0 KB	3	0	0	00:01:45
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	0	00:00:00
4	asn55.neoplus.adsl.tpnet.pl	3	0	0.0 KB	0	0	2	00:02:24
TOTAL: 4		308	35	31.5 KB	16	5	2	00:04:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	677	30	23.6 KB	13	5	0	00:00:25
2	*.static.corbina.ru	12	5	8.0 KB	3	0	0	00:01:45
3	*.neoplus.adsl.tpnet.pl	3	0	0.0 KB	0	0	2	00:02:24
TOTAL: 3		692	35	31.5 KB	16	5	2	00:04:35

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
asn55.neoplus.adsl.tpnet.pl	0.032	0.032	00:02:24
0890788407.static.corbina.ru	0.076	0.240	00:01:45
web.man.lodz.pl	1.496	6.480	00:00:20
mon.man.lodz.pl	1.384	5.844	00:00:03
david.man.lodz.pl	0.404	2.012	00:00:01
isvcs01.man.lodz.pl	0.020	0.104	00:00:00
TOTAL: 6	3.412	14.712	00:04:35

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	30	85.7%
2	lodman	5	14.3%
TOTAL: 2		35	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	24
2	pl.comp.os.ms-windows.winnt	6
3	lodman.info	5
TOTAL: 3		35

Daily Usenet report

May 10 04:15:01 -- May 11 04:15:02