Daily Usenet report: Mar 27 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Mar 27 11:30:28 newsfeed nnrpd[21066]: SERVER perl filter not defined
Mar 27 12:00:12 newsfeed nnrpd[22327]: SERVER perl filter not defined
Mar 27 12:01:46 newsfeed nnrpd[22349]: SERVER perl filter not defined
Mar 27 14:34:47 newsfeed nnrpd[6955]: SERVER perl filter not defined
Mar 27 15:08:29 newsfeed overchan[2625]: input line too long, skipping
Mar 27 18:32:35 newsfeed nnrpd[15254]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2840227	98.4%	333.8 MB	98.1%
innd	36668	1.3%	5.1 MB	1.5%
nnrpd	5544	0.2%	589.2 KB	0.2%
innfeed	4809	0.2%	663.7 KB	0.2%
overchan	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2887250	100.0%	340.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2827340	96.0%
Negative hits	94205	3.2%
Positive hits	22503	0.8%
Cache misses	115	0.0%
TOTAL: 4	2944163	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.354	0.0%	229	0.000	14.646	657.333
article cleanup	00:00:40.443	0.0%	2838523	0.006	0.014	0.027
article logging	00:01:36.944	0.1%	2839055	0.017	0.034	0.798
article parse	01:11:48.476	5.0%	202606286	0.012	0.021	0.077
article write	00:23:33.821	1.6%	2815918	0.336	0.502	1.819
artlog/artcncl	00:00:00.005	0.0%	226	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:38.539	0.3%	220331027	0.001	0.001	0.004
hisgrep/artcncl	00:00:01.250	0.0%	211	0.000	5.924	356.667
hishave/artcncl	00:00:00.045	0.0%	229	0.000	0.197	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:45.340	1.9%	2946917	0.023	0.565	9.509
history sync	00:00:00.014	0.0%	292	0.000	0.048	1.000
history write	00:42:42.899	3.0%	2830135	0.163	0.906	9.685
hiswrite/artcncl	00:00:00.085	0.0%	18	0.000	4.722	48.000
idle	13:16:50.922	55.4%	153389824	0.143	0.312	0.590
nntp read	01:16:54.606	5.3%	200403092	0.014	0.023	0.049
overview write	00:00:15.662	0.0%	2815918	0.001	0.006	0.015
perl filter	05:51:01.469	24.4%	2830135	5.160	7.442	9.380
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:49.735	0.3%	5667944	0.026	0.051	0.096
TOTAL: 23:58:27.356	23:22:43.609	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.140	0.0%	39828	0.000	0.004	0.250
article prepare	00:00:00.006	0.0%	20660	0.000	0.000	0.083
article read	00:00:18.015	0.0%	4988	0.000	3.612	468.750
backlog stats	00:00:00.190	0.0%	213798	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:01.584	0.0%	120570	0.000	0.013	0.156
data write	00:00:05.105	0.0%	94471	0.000	0.054	0.250
idle	23:58:35.118	100.0%	213798	57.316	403.723	4129.611
status file	00:00:00.971	0.0%	1199	0.000	0.810	3.000
TOTAL: 23:59:06.776	23:59:01.129	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:28.348	40.8%	3271	0.000	8.666	577.312
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.175	0.3%	7469	0.000	0.023	0.667
readart	00:00:25.235	36.3%	10449	0.000	2.415	5.000
TOTAL: 00:01:09.492	00:00:53.758	77.4%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	189

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1324	2815973	2802855	5970	7148	99%	350:06:29
2	news.nask.pl	3	35886	12902	7716	15268	35%	23:41:04
3	news.icm.edu.pl	39	342	31	263	48	9%	16:52:15
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1372	2852206	2815793	13949	22464	98%	390:39:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	854.0 GB	498.7 MB	740.6 MB	855.2 GB	99%	319.1 KB
2	news.nask.pl	518.1 MB	519.9 MB	678.3 MB	1.7 GB	30%	62.4 KB
3	news.icm.edu.pl	509.6 KB	26.1 KB	873.8 KB	1.4 MB	36%	17.8 KB
4	localhost	7.2 KB	0.0 KB	0.0 KB	7.2 KB	100%	1.4 KB
TOTAL: 4		854.5 GB	1018.6 MB	1.4 GB	856.9 GB	99%	316.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 27 04:15:02 - 04:59:59	102689	3.6%	38.06	28.8 GB	3.4%	11183.85
Mar 27 05:00:00 - 05:59:59	131656	4.7%	36.57	37.3 GB	4.4%	10860.50
Mar 27 06:00:00 - 06:59:59	123777	4.4%	34.38	36.0 GB	4.2%	10492.14
Mar 27 07:00:00 - 07:59:59	103926	3.7%	28.87	31.8 GB	3.7%	9255.84
Mar 27 08:00:00 - 08:59:59	126463	4.5%	35.13	38.4 GB	4.5%	11187.22
Mar 27 09:00:00 - 09:59:59	122633	4.4%	34.06	38.3 GB	4.5%	11156.11
Mar 27 10:00:00 - 10:59:59	123167	4.4%	34.21	38.8 GB	4.5%	11304.09
Mar 27 11:00:00 - 11:59:59	99303	3.5%	27.58	32.6 GB	3.8%	9488.58
Mar 27 12:00:00 - 12:59:59	119001	4.2%	33.06	36.6 GB	4.3%	10662.89
Mar 27 13:00:00 - 13:59:59	106970	3.8%	29.71	30.3 GB	3.6%	8834.18
Mar 27 14:00:00 - 14:59:59	116928	4.2%	32.48	35.6 GB	4.2%	10357.56
Mar 27 15:00:00 - 15:59:59	119460	4.2%	33.18	37.1 GB	4.3%	10793.37
Mar 27 16:00:00 - 16:59:59	121879	4.3%	33.86	37.2 GB	4.4%	10845.14
Mar 27 17:00:00 - 17:59:59	119272	4.2%	33.13	38.0 GB	4.4%	11057.84
Mar 27 18:00:00 - 18:59:59	120928	4.3%	33.59	37.1 GB	4.3%	10815.60
Mar 27 19:00:00 - 19:59:59	121342	4.3%	33.71	37.1 GB	4.3%	10812.30
Mar 27 20:00:00 - 20:59:59	126597	4.5%	35.17	36.7 GB	4.3%	10695.13
Mar 27 21:00:00 - 21:59:59	111861	4.0%	31.07	35.9 GB	4.2%	10442.52
Mar 27 22:00:00 - 22:59:59	96687	3.4%	26.86	32.3 GB	3.8%	9410.02
Mar 27 23:00:00 - 23:59:59	100779	3.6%	27.99	31.5 GB	3.7%	9187.00
Mar 28 00:00:00 - 00:59:59	101457	3.6%	28.18	29.9 GB	3.5%	8709.68
Mar 28 01:00:00 - 01:59:59	132441	4.7%	36.79	37.7 GB	4.4%	10975.89
Mar 28 02:00:00 - 02:59:59	132449	4.7%	36.79	39.5 GB	4.6%	11512.91
Mar 28 03:00:00 - 03:59:59	113081	4.0%	31.41	32.5 GB	3.8%	9466.72
Mar 28 04:00:00 - 04:15:02	22118	0.8%	24.52	6.9 GB	0.8%	7967.68
TOTAL: 24:00:00	2816864	100.0%	32.60	853.8 GB	100.0%	10362.55

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	15578	17	3059	2	12500
2	newsfeed.pionier.net.pl	7509	298	5324	1	1886
3	news.icm.edu.pl	50	0	2	0	48
TOTAL: 3		23137	315	8385	3	14434

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	140
it.tlc.cellulari.android	81
it.comp.os.win.windows10	33
comp.mobile.android	26
it.comp.os.win.windows7	14
it.comp.os.win.windows8	6
uk.rec.cycling.moderated	4
hr.alt.cellular.gsm.android	3
comp.mobile.ipad	2
de.comp.os.os2	2
alt.sex.telephones	1
hr.ponuda.hardver.komponente	1
hr.sci.med	1
de.comp.lang.vbclassic	1
TOTAL: 14	315

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11669
Binary in non-binary group	1874
Scoring filter (8)	150
Scoring filter (9)	85
Scoring filter (10)	41
Poison newsgroup	23
Excessively crossposted test article	17
Scoring filter (12)	9
Scoring filter (11)	7
Scoring filter (13)	7
Scoring filter (17)	7
Too many newsgroups	6
HTML post	5
Scoring filter (18)	1
Scoring filter (14)	1
TOTAL: 15	13902

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	9414	1426	5632	497	14	32126	15%	24:00:05
2	nask	17515	312	12357	18	0	0	1%	23:50:02
3	poznan	15971	149	10952	12	0	0	0%	23:54:25
4	icm	44761	51	32483	2477	0	0	0%	24:00:30
5	okapi	0	0	0	0	0	2071	0%	23:40:01
TOTAL: 5		87661	1938	61424	3004	14	34197	2%	119:25:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	73.7 MB	5.0 MB	78.7 MB	0.9 KB/s	244.3 KB	23:50:02
2	panorama	7.1 MB	1.5 MB	8.6 MB	0.1 KB/s	4.6 KB	24:00:05
3	icm	4.5 MB	819.2 MB	823.7 MB	9.8 KB/s	333.6 KB	24:00:30
4	poznan	939.7 KB	188.9 KB	1.1 MB	0.0 KB/s	7.0 KB	23:54:25
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:40:01
TOTAL: 5		86.2 MB	825.9 MB	912.2 MB	2.2 KB/s	189.0 KB	119:25:03

Backlog files shrunk by innfeed:

Server	Size
panorama	2.4 MB
TOTAL: 1	2.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	56	49.3 KB	23	0	00:00:25
2	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 2		293	56	49.3 KB	23	5	00:01:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	863	56	49.3 KB	23	5	00:00:34
2	*.dyn.optonline.net	1	0	0.0 KB	0	0	00:00:34
TOTAL: 2		864	56	49.3 KB	23	5	00:01:09

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
ool-ae2c99f9.dyn.optonline.net	0.257	0.781	00:00:34
web.man.lodz.pl	1.044	3.786	00:00:25
mon.man.lodz.pl	1.546	4.713	00:00:06
david.man.lodz.pl	0.329	1.027	00:00:01
isvcs01.man.lodz.pl	0.050	0.064	00:00:00
TOTAL: 5	3.226	10.371	00:01:09

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	474
david.man.lodz.pl	96
ool-ae2c99f9.dyn.optonline.net	1
TOTAL: 3	571

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Mar 27 04:15:02 -- Mar 28 04:15:02