Daily Usenet report: Oct 13 04:15:06

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Oct 13 11:01:00 newsfeed nnrpd[4371]: SERVER perl filter not defined
Oct 13 11:04:39 newsfeed nnrpd[4431]: SERVER perl filter not defined
Oct 13 11:58:44 newsfeed nnrpd[6424]: SERVER perl filter not defined
Oct 13 12:50:11 newsfeed nnrpd[8323]: SERVER perl filter not defined
Oct 13 12:52:45 newsfeed nnrpd[8330]: SERVER perl filter not defined
Oct 13 14:07:10 newsfeed nnrpd[11107]: SERVER perl filter not defined
Oct 13 14:07:18 newsfeed nnrpd[11108]: SERVER perl filter not defined
Oct 13 14:07:25 newsfeed nnrpd[11110]: SERVER perl filter not defined
Oct 13 14:23:12 newsfeed nnrpd[11736]: SERVER perl filter not defined
Oct 13 14:23:54 newsfeed nnrpd[11754]: SERVER perl filter not defined
Oct 13 16:26:27 newsfeed overchan[21431]: input line too long, skipping
Oct 13 16:34:43 newsfeed overchan[21431]: input line too long, skipping
Oct 13 16:41:49 newsfeed overchan[21431]: input line too long, skipping
Oct 13 16:46:15 newsfeed overchan[21431]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4458648	98.3%	512.0 MB	98.0%
innfeed	40961	0.9%	4.9 MB	0.9%
innd	31174	0.7%	5.1 MB	1.0%
nnrpd	4844	0.1%	511.1 KB	0.1%
overchan	5	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	4535633	100.0%	522.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4318000	84.3%
Negative hits	531370	10.4%
Positive hits	260151	5.1%
Cache misses	14329	0.3%
TOTAL: 4	5123850	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.919	0.0%	230	0.000	12.691	90.000
article cleanup	00:01:00.804	0.1%	4458926	0.009	0.014	0.019
article logging	00:03:31.810	0.2%	4459572	0.028	0.047	0.176
article parse	01:15:01.332	5.2%	187890762	0.018	0.024	0.043
article write	00:27:31.144	1.9%	4350169	0.094	0.380	1.050
artlog/artcncl	00:00:00.008	0.0%	226	0.000	0.035	0.500
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:03:45.707	0.3%	207267277	0.001	0.001	0.005
hisgrep/artcncl	00:00:01.084	0.0%	223	0.000	4.861	26.737
hishave/artcncl	00:00:00.130	0.0%	230	0.000	0.565	7.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:35:36.098	2.5%	5179343	0.016	0.412	3.892
history sync	00:00:00.021	0.0%	290	0.000	0.072	1.000
history write	01:16:25.671	5.3%	4353420	0.081	1.053	8.065
hiswrite/artcncl	00:00:00.001	0.0%	7	0.000	0.143	0.500
idle	11:05:17.028	46.2%	140727570	0.155	0.284	0.563
nntp read	01:22:28.175	5.7%	185154218	0.020	0.027	0.038
overview write	00:00:22.551	0.0%	4350169	0.004	0.005	0.008
perl filter	06:52:38.890	28.7%	4353419	1.793	5.687	9.810
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:08:10.774	0.6%	9077339	0.043	0.054	0.072
TOTAL: 24:00:08.743	23:11:54.147	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:02.191	0.0%	712428	0.000	0.003	0.007
article prepare	00:00:00.046	0.0%	142444	0.000	0.000	0.003
article read	00:00:26.476	0.0%	33780	0.062	0.784	9.971
backlog stats	00:00:01.476	0.0%	2145364	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:24.479	0.0%	1343349	0.010	0.018	0.028
data write	00:01:34.891	0.1%	1029217	0.034	0.092	0.162
idle	23:46:39.992	99.7%	2145364	23.674	39.900	139.766
status file	00:00:21.844	0.0%	17426	0.686	1.254	29.785
TOTAL: 23:50:33.252	23:49:31.395	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:23.142	59.0%	3129	0.000	7.396	217.091
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.190	0.5%	6930	0.000	0.027	0.500
readart	00:00:00.036	0.1%	76	0.000	0.474	10.000
TOTAL: 00:00:39.217	00:00:23.368	59.6%	-	-	-	-

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	1288	4368372	4244221	27048	97103	97%	391:53:45
2	news.nask.pl	2	268594	105284	151848	11462	39%	24:00:06
3	news.icm.edu.pl	38	374	62	249	63	16%	18:36:27
4	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 4		1339	4637350	4349577	179145	108628	93%	434:30:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	830.0 GB	31.0 GB	31.0 GB	891.9 GB	93%	215.4 KB
2	news.nask.pl	32.5 GB	2.7 GB	2.7 GB	38.0 GB	85%	340.9 KB
3	news.icm.edu.pl	1.8 MB	69.3 KB	1.9 MB	3.7 MB	47%	30.5 KB
4	localhost	15.4 KB	0.0 KB	0.0 KB	15.4 KB	100%	1.5 KB
TOTAL: 4		862.6 GB	33.6 GB	33.7 GB	929.9 GB	92%	218.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 13 04:15:06 - 04:59:59	94817	2.2%	35.20	28.2 GB	3.3%	10973.50
Oct 13 05:00:00 - 05:59:59	115652	2.7%	32.13	39.4 GB	4.6%	11482.62
Oct 13 06:00:00 - 06:59:59	116985	2.7%	32.50	41.4 GB	4.8%	12052.90
Oct 13 07:00:00 - 07:59:59	117604	2.7%	32.67	40.6 GB	4.7%	11832.04
Oct 13 08:00:00 - 08:59:59	380937	8.8%	105.82	29.3 GB	3.4%	8528.79
Oct 13 09:00:00 - 09:59:59	561303	12.9%	155.92	21.7 GB	2.5%	6323.53
Oct 13 10:00:00 - 10:59:59	495931	11.4%	137.76	23.2 GB	2.7%	6748.31
Oct 13 11:00:00 - 11:59:59	307155	7.1%	85.32	28.0 GB	3.2%	8148.91
Oct 13 12:00:00 - 12:59:59	135969	3.1%	37.77	31.8 GB	3.7%	9263.28
Oct 13 13:00:00 - 13:59:59	157476	3.6%	43.74	37.9 GB	4.4%	11049.64
Oct 13 14:00:00 - 14:59:59	157283	3.6%	43.69	34.0 GB	3.9%	9911.85
Oct 13 15:00:00 - 15:59:59	154389	3.5%	42.89	38.8 GB	4.5%	11301.94
Oct 13 16:00:00 - 16:59:59	160672	3.7%	44.63	39.9 GB	4.6%	11608.60
Oct 13 17:00:00 - 17:59:59	156381	3.6%	43.44	40.2 GB	4.7%	11719.87
Oct 13 18:00:00 - 18:59:59	141063	3.2%	39.18	38.9 GB	4.5%	11318.50
Oct 13 19:00:00 - 19:59:59	133022	3.1%	36.95	41.3 GB	4.8%	12034.53
Oct 13 20:00:00 - 20:59:59	140740	3.2%	39.09	41.4 GB	4.8%	12071.69
Oct 13 21:00:00 - 21:59:59	130687	3.0%	36.30	40.7 GB	4.7%	11850.80
Oct 13 22:00:00 - 22:59:59	117510	2.7%	32.64	40.0 GB	4.6%	11661.04
Oct 13 23:00:00 - 23:59:59	119128	2.7%	33.09	38.6 GB	4.5%	11245.29
Oct 14 00:00:00 - 00:59:59	101322	2.3%	28.14	33.6 GB	3.9%	9794.67
Oct 14 01:00:00 - 01:59:59	111872	2.6%	31.08	39.3 GB	4.6%	11440.44
Oct 14 02:00:00 - 02:59:59	109313	2.5%	30.36	36.3 GB	4.2%	10576.02
Oct 14 03:00:00 - 03:59:59	104504	2.4%	29.03	28.7 GB	3.3%	8353.23
Oct 14 04:00:00 - 04:15:03	27426	0.6%	30.37	8.8 GB	1.0%	10248.83
TOTAL: 23:59:57	4349141	100.0%	50.34	862.1 GB	100.0%	10462.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	97527	210	96855	1	461
2	news.nask.pl	11686	13	8508	3	3162
3	news.icm.edu.pl	70	0	3	0	67
TOTAL: 3		109283	223	105366	4	3690

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	121
it.tlc.cellulari.android	32
it.comp.os.win.windows10	12
uk.rec.cycling.moderated	12
nl.comp.os.linux.techniek	11
nl.comp.sys.android	7
it.comp.os.win.windows7	6
comp.mobile.ipad	6
comp.mobile.android	4
de.comp.office-pakete.ms-office.outlook	3
de.rec.sf.startrek	3
alt.sex.telephones	2
hr.rec.glazba.hifi	1
rec.arts.tv.x-factor	1
it.politica.m5s	1
it.discussioni.energia	1
TOTAL: 16	223

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2864
Binary in non-binary group	77
Scoring filter (9)	31
Scoring filter (11)	23
Scoring filter (8)	17
HTML post	15
Scoring filter (10)	8
Too many newsgroups	5
Scoring filter (12)	3
Scoring filter (14)	1
TOTAL: 10	3044

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	418
	news.nask.pl	225
	news.icm.edu.pl	2
	TOTAL: 3	645
TOTAL: 1		645

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	395650	31376	231529	4	0	12	7%	23:59:58
2	panorama	360909	589	331794	197	8	386623	0%	23:59:58
3	poznan	15090	346	8715	29	0	0	2%	23:59:32
4	icm	418549	159	372114	3422	0	7	0%	23:59:58
5	okapi	0	0	0	0	0	1224	0%	23:32:36
TOTAL: 5		1190198	32470	944152	3652	8	387866	2%	119:32:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	10.6 GB	87.8 KB	10.6 GB	128.5 KB/s	353.9 KB	23:59:58
2	panorama	14.6 MB	7.9 MB	22.4 MB	0.3 KB/s	29.2 KB	23:59:58
3	icm	7.3 MB	1.1 GB	1.1 GB	13.9 KB/s	335.7 KB	23:59:58
4	poznan	1.4 MB	112.1 KB	1.6 MB	0.0 KB/s	4.3 KB	23:59:32
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:32:36
TOTAL: 5		10.6 GB	1.1 GB	11.8 GB	28.7 KB/s	341.4 KB	119:32:02

Backlog files shrunk by innfeed:

Server	Size
panorama	6.7 MB
TOTAL: 1	6.7 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	76	78.0 KB	35	0	00:00:30
2	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	00:00:01
TOTAL: 2		298	76	78.0 KB	35	10	00:00:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	681	76	78.0 KB	35	10	0	00:00:38
TOTAL: 1		681	76	78.0 KB	35	10	0	00:00:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.528	9.908	00:00:30
mon.man.lodz.pl	1.416	11.632	00:00:04
david.man.lodz.pl	0.432	3.252	00:00:01
isvcs01.man.lodz.pl	0.060	0.420	00:00:01
TOTAL: 4	3.436	25.212	00:00:38

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	95
TOTAL: 2	383

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	48
2	pl.comp.os.ms-windows.winnt	28
TOTAL: 2		76

Daily Usenet report

Oct 13 04:15:06 -- Oct 14 04:15:03