Daily Usenet report: Nov 14 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Nov 14 10:41:32 newsfeed nnrpd[18998]: SERVER perl filter not defined
Nov 14 11:00:12 newsfeed nnrpd[19744]: SERVER perl filter not defined
Nov 14 11:01:53 newsfeed nnrpd[19751]: SERVER perl filter not defined
Nov 14 11:31:26 newsfeed overchan[3545]: input line too long, skipping
Nov 14 13:10:07 newsfeed nnrpd[24694]: SERVER perl filter not defined
Nov 14 13:25:14 newsfeed nnrpd[25339]: SERVER perl filter not defined
Nov 14 18:56:23 newsfeed overchan[3545]: input line too long, skipping
Nov 14 20:01:39 newsfeed overchan[3545]: input line too long, skipping
Nov 14 22:55:30 newsfeed overchan[3545]: input line too long, skipping
Nov 14 23:05:47 newsfeed overchan[3545]: input line too long, skipping
Nov 15 00:16:56 newsfeed overchan[3545]: input line too long, skipping
Nov 15 00:18:02 newsfeed overchan[3545]: input line too long, skipping
Nov 15 00:33:10 newsfeed overchan[3545]: input line too long, skipping
Nov 15 00:54:09 newsfeed overchan[3545]: input line too long, skipping
Nov 15 00:56:44 newsfeed overchan[3545]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3639019	97.1%	416.7 MB	96.7%
innd	75009	2.0%	10.2 MB	2.4%
innfeed	29589	0.8%	3.5 MB	0.8%
nnrpd	4797	0.1%	495.8 KB	0.1%
overchan	11	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3748426	100.0%	430.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3581643	89.7%
Negative hits	367019	9.2%
Positive hits	45496	1.1%
Cache misses	191	0.0%
TOTAL: 4	3994349	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.405	0.0%	322	0.000	7.469	113.000
article cleanup	00:00:47.434	0.1%	3630189	0.010	0.013	0.017
article logging	00:02:28.169	0.2%	3631079	0.033	0.041	0.087
article parse	00:59:10.288	4.1%	106643411	0.017	0.033	0.046
article write	00:30:31.594	2.1%	3554169	0.174	0.515	1.750
artlog/artcncl	00:00:00.007	0.0%	321	0.000	0.022	0.111
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:19.911	0.2%	125611842	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.298	0.0%	313	0.000	0.952	25.000
hishave/artcncl	00:00:00.061	0.0%	322	0.000	0.189	1.700
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:44:05.135	3.1%	4020420	0.063	0.658	2.979
history sync	00:00:00.016	0.0%	290	0.000	0.055	1.000
history write	02:04:40.850	8.7%	3606231	0.262	2.074	8.069
hiswrite/artcncl	00:00:00.011	0.0%	9	0.000	1.222	11.000
idle	12:05:54.844	50.4%	76149451	0.388	0.572	1.094
nntp read	00:57:41.635	4.0%	103962867	0.022	0.033	0.043
overview write	00:00:18.642	0.0%	3554169	0.004	0.005	0.007
perl filter	05:55:42.083	24.7%	3606225	3.555	5.918	8.427
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:45.552	0.4%	7136725	0.038	0.048	0.058
TOTAL: 24:00:02.551	23:29:28.935	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.193	0.0%	61956	0.000	0.003	0.024
article prepare	00:00:00.003	0.0%	17072	0.000	0.000	0.017
article read	00:02:51.692	0.2%	4280	1.000	40.115	542.429
backlog stats	00:00:00.149	0.0%	174419	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	14	0.000	0.000	0.000
data read	00:00:01.544	0.0%	98074	0.003	0.016	0.057
data write	00:00:04.276	0.0%	70379	0.022	0.061	0.163
idle	23:51:04.830	99.8%	174419	235.984	492.291	1108.438
status file	00:00:09.316	0.0%	9960	0.627	0.935	2.667
TOTAL: 23:54:19.824	23:54:12.003	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.070	63.5%	3087	0.000	5.854	109.818
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.206	0.7%	6766	0.000	0.030	1.333
readart	00:00:00.005	0.0%	38	0.000	0.132	0.500
TOTAL: 00:00:28.462	00:00:18.281	64.2%	-	-	-	-

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	1029	3608999	3549598	13074	46327	98%	467:29:10
2	news.nask.pl	2	50527	12184	8312	30031	24%	24:16:45
3	news.icm.edu.pl	43	200	16	181	3	8%	17:04:23
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1080	3659731	3561803	21567	76361	97%	508:50:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	894.7 GB	3.8 GB	8.2 GB	906.7 GB	98%	264.4 KB
2	news.nask.pl	117.2 MB	73.5 MB	4.8 GB	5.0 GB	2%	124.6 KB
3	news.icm.edu.pl	407.1 KB	0.0 KB	110.1 KB	517.1 KB	78%	27.2 KB
4	localhost	8.3 KB	0.0 KB	0.0 KB	8.3 KB	100%	1.7 KB
TOTAL: 4		894.8 GB	3.9 GB	13.0 GB	911.7 GB	98%	262.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 14 04:15:02 - 04:59:59	140005	3.9%	51.89	26.9 GB	3.0%	10441.08
Nov 14 05:00:00 - 05:59:59	136170	3.8%	37.83	39.0 GB	4.4%	11369.58
Nov 14 06:00:00 - 06:59:59	127414	3.6%	35.39	41.8 GB	4.7%	12176.53
Nov 14 07:00:00 - 07:59:59	103893	2.9%	28.86	34.5 GB	3.9%	10036.45
Nov 14 08:00:00 - 08:59:59	125929	3.5%	34.98	39.1 GB	4.4%	11398.13
Nov 14 09:00:00 - 09:59:59	123776	3.5%	34.38	37.7 GB	4.2%	10983.14
Nov 14 10:00:00 - 10:59:59	162453	4.6%	45.13	39.6 GB	4.4%	11534.54
Nov 14 11:00:00 - 11:59:59	144101	4.0%	40.03	41.1 GB	4.6%	11982.02
Nov 14 12:00:00 - 12:59:59	135403	3.8%	37.61	40.6 GB	4.5%	11816.39
Nov 14 13:00:00 - 13:59:59	136744	3.8%	37.98	36.3 GB	4.1%	10569.84
Nov 14 14:00:00 - 14:59:59	117882	3.3%	32.74	35.6 GB	4.0%	10356.64
Nov 14 15:00:00 - 15:59:59	122939	3.5%	34.15	37.9 GB	4.2%	11051.44
Nov 14 16:00:00 - 16:59:59	132775	3.7%	36.88	39.5 GB	4.4%	11499.21
Nov 14 17:00:00 - 17:59:59	128839	3.6%	35.79	39.4 GB	4.4%	11488.68
Nov 14 18:00:00 - 18:59:59	131468	3.7%	36.52	43.3 GB	4.8%	12613.05
Nov 14 19:00:00 - 19:59:59	154995	4.4%	43.05	35.8 GB	4.0%	10427.01
Nov 14 20:00:00 - 20:59:59	186680	5.2%	51.86	34.5 GB	3.9%	10050.43
Nov 14 21:00:00 - 21:59:59	145335	4.1%	40.37	34.8 GB	3.9%	10130.70
Nov 14 22:00:00 - 22:59:59	134095	3.8%	37.25	36.3 GB	4.1%	10566.91
Nov 14 23:00:00 - 23:59:59	177147	5.0%	49.21	36.7 GB	4.1%	10687.36
Nov 15 00:00:00 - 00:59:59	157724	4.4%	43.81	28.8 GB	3.2%	8391.77
Nov 15 01:00:00 - 01:59:59	220099	6.2%	61.14	33.3 GB	3.7%	9692.81
Nov 15 02:00:00 - 02:59:59	247715	7.0%	68.81	36.4 GB	4.1%	10605.66
Nov 15 03:00:00 - 03:59:59	129775	3.6%	36.05	35.4 GB	4.0%	10302.67
Nov 15 04:00:00 - 04:15:02	38082	1.1%	42.22	9.6 GB	1.1%	11209.54
TOTAL: 24:00:00	3561438	100.0%	41.22	893.9 GB	100.0%	10848.52

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	46668	179	23368	9	23112
2	news.nask.pl	30589	28	717	9	29835
3	news.icm.edu.pl	3	0	0	0	3
TOTAL: 3		77260	207	24085	18	52950

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	151
comp.mobile.android	20
comp.sys.raspberry-pi	16
it.comp.os.win.windows10	10
it.comp.os.win.windows7	5
it.tlc.cellulari.android	2
alt.sex.telephones	1
hr.sci.med	1
it.discussioni.energia	1
TOTAL: 9	207

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	48650
EMP (md5)	3162
Scoring filter (9)	92
EMP (phl)	42
Scoring filter (8)	33
Scoring filter (11)	24
Scoring filter (10)	22
HTML post	10
Too many newsgroups	3
Poison newsgroup	3
EMP (fsl)	2
Too many newsgroups (low_xpost)	1
Scoring filter (12)	1
Scoring filter (13)	1
TOTAL: 14	52046

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	559
	newsfeed.pionier.net.pl	339
	TOTAL: 2	898
TOTAL: 1		898

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	36782	2018	17710	498	48	41814	5%	23:59:23
2	poznan	19635	77	11724	86	0	0	0%	23:59:52
3	icm	25848	71	20724	685	0	0	0%	23:59:58
4	nask	3056	7	2201	0	0	0	0%	23:51:50
5	okapi	0	0	0	0	0	1253	0%	23:57:51
TOTAL: 5		85321	2173	52359	1269	48	43067	2%	119:48:54

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	20.5 MB	4.4 MB	24.9 MB	0.3 KB/s	10.1 KB	23:59:23
2	icm	8.1 MB	183.0 MB	191.1 MB	2.3 KB/s	258.8 KB	23:59:58
3	nask	433.6 KB	0.0 KB	433.6 KB	0.0 KB/s	61.9 KB	23:51:50
4	poznan	349.5 KB	1.2 MB	1.5 MB	0.0 KB/s	9.7 KB	23:59:52
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		29.3 MB	188.6 MB	217.9 MB	0.5 KB/s	64.8 KB	119:48:54

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	38	33.7 KB	19	0	00:00:19
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	38	33.7 KB	19	5	00:00:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	677	38	33.7 KB	19	5	00:00:24
2	unresolved	3	0	0.0 KB	0	0	00:00:03
TOTAL: 2		680	38	33.7 KB	19	5	00:00:27

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.380	6.036	00:00:19
mon.man.lodz.pl	1.132	5.640	00:00:03
46.11.19.24	0.008	0.040	00:00:03
david.man.lodz.pl	0.364	1.748	00:00:00
isvcs01.man.lodz.pl	0.048	0.132	00:00:00
TOTAL: 5	2.932	13.596	00:00:27

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
46.11.19.24	3
TOTAL: 3	387

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Nov 14 04:15:02 -- Nov 15 04:15:02