Daily Usenet report: Jun 16 04:15:03

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jun 16 04:26:31 newsfeed nnrpd[12793]: SERVER perl filter not defined
Jun 16 08:02:38 newsfeed nnrpd[20132]: SERVER perl filter not defined
Jun 16 10:02:25 newsfeed nnrpd[24372]: SERVER perl filter not defined
Jun 16 10:03:26 newsfeed nnrpd[24400]: SERVER perl filter not defined
Jun 16 10:03:42 newsfeed nnrpd[24402]: SERVER perl filter not defined
Jun 16 20:06:11 newsfeed nnrpd[24290]: SERVER perl filter not defined
Jun 16 20:36:46 newsfeed overchan[2702]: input line too long, skipping
Jun 17 00:09:13 newsfeed nnrpd[309]: SERVER perl filter not defined
Jun 17 02:13:15 newsfeed nnrpd[4658]: SERVER perl filter not defined
Jun 17 02:27:27 newsfeed nnrpd[5241]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1400302	98.3%	155.7 MB	97.9%
innd	14135	1.0%	2.3 MB	1.4%
nnrpd	7168	0.5%	770.1 KB	0.5%
innfeed	2593	0.2%	371.3 KB	0.2%
overchan	1	0.0%	0.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1424200	100.0%	159.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1394417	97.3%
Negative hits	21888	1.5%
Positive hits	16961	1.2%
Cache misses	138	0.0%
TOTAL: 4	1433404	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.875	0.0%	302	0.000	6.209	82.000
article cleanup	00:00:17.968	0.0%	1398397	0.002	0.013	0.032
article logging	00:00:45.528	0.1%	1398600	0.012	0.033	0.366
article parse	00:39:26.187	2.7%	76674690	0.021	0.031	0.101
article write	00:13:30.471	0.9%	1394202	0.384	0.581	1.970
artlog/artcncl	00:00:00.001	0.0%	294	0.000	0.003	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:54.253	0.1%	86117424	0.000	0.001	0.007
hisgrep/artcncl	00:00:00.692	0.0%	254	0.000	2.724	34.000
hishave/artcncl	00:00:00.106	0.0%	302	0.000	0.351	9.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:13:15.079	0.9%	1434450	0.009	0.554	6.578
history sync	00:00:00.015	0.0%	289	0.000	0.052	1.000
history write	00:19:15.570	1.3%	1395591	0.067	0.828	12.228
hiswrite/artcncl	00:00:00.035	0.0%	48	0.000	0.729	14.000
idle	18:05:12.725	75.5%	67117424	0.304	0.970	58.739
nntp read	00:35:14.229	2.5%	75740806	0.017	0.028	0.042
overview write	00:00:08.494	0.0%	1394202	0.000	0.006	0.018
perl filter	03:28:32.092	14.5%	1395590	5.936	8.965	15.392
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:53.516	0.2%	2818996	0.040	0.062	0.170
TOTAL: 23:58:08.548	23:40:28.836	98.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.097	0.0%	30663	0.000	0.003	0.111
article prepare	00:00:00.004	0.0%	15396	0.000	0.000	0.050
article read	00:00:00.191	0.0%	3663	0.000	0.052	1.000
backlog stats	00:00:00.153	0.0%	214092	0.000	0.001	0.025
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.407	0.0%	123179	0.000	0.011	0.107
data write	00:00:02.788	0.0%	92975	0.000	0.030	0.320
idle	23:59:03.469	100.0%	214092	76.553	403.301	3571.294
status file	00:00:00.395	0.0%	274	0.000	1.442	11.000
TOTAL: 23:59:13.418	23:59:08.504	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.003	0.0%	19	0.158	0.158	0.158
idle	06:10:01.603	99.9%	125496	0.000	176.911	302006.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.473	0.0%	252902	0.000	0.018	1.000
readart	00:00:01.401	0.0%	464	0.000	3.019	19.211
TOTAL: 06:10:32.690	06:10:07.480	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	656	1396096	1391767	523	3806	99%	171:59:17
2	news.nask.pl	3	16822	3529	13026	267	20%	23:22:03
3	news.icm.edu.pl	46	320	32	180	108	10%	17:31:23
4	localhost	10	9	9	0	0	100%	00:00:00
TOTAL: 4		715	1413247	1395337	13729	4181	98%	212:52:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	514.2 GB	14.0 MB	177.2 MB	514.4 GB	99%	386.5 KB
2	news.nask.pl	24.0 MB	767.9 KB	811.4 KB	25.6 MB	93%	6.9 KB
3	news.icm.edu.pl	1.0 MB	29.1 KB	1.9 MB	3.0 MB	35%	21.8 KB
4	localhost	13.4 KB	0.0 KB	0.0 KB	13.4 KB	100%	1.5 KB
TOTAL: 4		514.2 GB	14.8 MB	179.9 MB	514.4 GB	99%	385.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 16 04:15:03 - 04:59:59	56316	4.0%	20.88	22.7 GB	4.4%	8832.52
Jun 16 05:00:00 - 05:59:59	73479	5.3%	20.41	29.3 GB	5.7%	8547.20
Jun 16 06:00:00 - 06:59:59	41222	3.0%	11.45	16.2 GB	3.1%	4708.76
Jun 16 07:00:00 - 07:59:59	38918	2.8%	10.81	15.8 GB	3.1%	4591.44
Jun 16 08:00:00 - 08:59:59	41835	3.0%	11.62	16.1 GB	3.1%	4697.63
Jun 16 09:00:00 - 09:59:59	40910	2.9%	11.36	15.4 GB	3.0%	4480.98
Jun 16 10:00:00 - 10:59:59	37523	2.7%	10.42	15.5 GB	3.0%	4506.39
Jun 16 11:00:00 - 11:59:59	45309	3.2%	12.59	15.8 GB	3.1%	4588.20
Jun 16 12:00:00 - 12:59:59	48089	3.4%	13.36	15.7 GB	3.1%	4586.16
Jun 16 13:00:00 - 13:59:59	45167	3.2%	12.55	15.3 GB	3.0%	4455.12
Jun 16 14:00:00 - 14:59:59	35562	2.5%	9.88	15.9 GB	3.1%	4621.46
Jun 16 15:00:00 - 15:59:59	36302	2.6%	10.08	16.2 GB	3.2%	4718.90
Jun 16 16:00:00 - 16:59:59	37254	2.7%	10.35	16.2 GB	3.2%	4723.33
Jun 16 17:00:00 - 17:59:59	34656	2.5%	9.63	15.6 GB	3.0%	4537.44
Jun 16 18:00:00 - 18:59:59	39286	2.8%	10.91	15.9 GB	3.1%	4640.41
Jun 16 19:00:00 - 19:59:59	39156	2.8%	10.88	15.7 GB	3.0%	4564.75
Jun 16 20:00:00 - 20:59:59	74253	5.3%	20.63	26.8 GB	5.2%	7811.70
Jun 16 21:00:00 - 21:59:59	75671	5.4%	21.02	26.1 GB	5.1%	7614.36
Jun 16 22:00:00 - 22:59:59	95865	6.9%	26.63	33.8 GB	6.6%	9837.55
Jun 16 23:00:00 - 23:59:59	92712	6.6%	25.75	29.9 GB	5.8%	8697.88
Jun 17 00:00:00 - 00:59:59	77513	5.6%	21.53	25.0 GB	4.9%	7292.47
Jun 17 01:00:00 - 01:59:59	97273	7.0%	27.02	32.4 GB	6.3%	9444.57
Jun 17 02:00:00 - 02:59:59	93788	6.7%	26.05	32.1 GB	6.2%	9340.63
Jun 17 03:00:00 - 03:59:59	78086	5.6%	21.69	27.9 GB	5.4%	8113.75
Jun 17 04:00:00 - 04:15:02	19461	1.4%	21.58	6.8 GB	1.3%	7915.86
TOTAL: 23:59:59	1395606	100.0%	16.15	514.0 GB	100.0%	6238.47

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3992	209	2539	1	1243
2	news.nask.pl	301	11	268	2	20
3	news.icm.edu.pl	109	0	2	0	107
TOTAL: 3		4402	220	2809	3	1370

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	95
it.comp.os.win.windows8	34
nl.comp.os.linux.techniek	24
it.tlc.cellulari.android	20
comp.sys.raspberry-pi	17
uk.rec.cycling.moderated	6
comp.mobile.android	5
it.comp.os.win.windows7	5
alt.sex.telephones	5
it.comp.os.linux.ubuntu	4
de.rec.sf.startrek	2
nl.actueel	2
hr.ponuda.hardver.komponente	1
TOTAL: 13	220

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

Groups	Count
pl.praca.oferowana	2
pl.sci.historia	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1045
EMP (md5)	106
HTML post	11
EMP (fsl)	2
Attached HTML file	2
TOTAL: 5	1166

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	44560	2628	27541	343	56	5%	23:52:14
2	icm	40557	619	26835	160	8	1%	23:52:25
3	poznan	5314	75	3447	17	0	1%	23:51:19
4	okapi	0	0	0	0	2391	0%	23:50:00
5	nask	0	0	0	0	13770	0%	23:50:00
TOTAL: 5		90431	3322	57823	520	16225	3%	119:15:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	16.3 MB	1.7 MB	18.0 MB	0.2 KB/s	6.2 KB	23:52:14
2	poznan	7.7 MB	1.2 MB	8.9 MB	0.1 KB/s	99.1 KB	23:51:19
3	icm	2.3 MB	5.5 MB	7.9 MB	0.1 KB/s	10.3 KB	23:52:25
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 5		26.4 MB	8.4 MB	34.8 MB	0.1 KB/s	9.3 KB	119:15:58

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	376	574.2 KB	288	0	00:00:07
2	dmcs2.p.lodz.pl	6	58	122.0 KB	5	0	01:31:15
3	web.man.lodz.pl	286	16	13.0 KB	8	0	00:00:30
4	lze200.lze.lodz.pl	2	10	23.3 KB	2	0	00:08:33
5	pc-212-51-218-225.p.lodz.pl	3	4	6.8 KB	3	0	00:41:01
6	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:01
TOTAL: 6		594	464	739.3 KB	306	9	06:10:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	376	574.2 KB	288	0	00:00:07
2	*.p.lodz.pl	9	62	128.8 KB	8	0	02:12:17
3	*.man.lodz.pl	679	16	13.0 KB	8	9	00:00:36
4	*.lze.lodz.pl	2	10	23.3 KB	2	0	00:08:33
5	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:06
6	*.toya.net.pl	67	0	0.0 KB	0	0	00:00:03
7	unresolved	3	0	0.0 KB	0	0	03:48:46
TOTAL: 7		1050	464	739.3 KB	306	9	06:10:32

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.42	6.200	9.250	03:48:46
dmcs2.p.lodz.pl	0.017	0.108	01:31:15
pc-212-51-218-225.p.lodz.pl	0.009	0.021	00:41:01
lze200.lze.lodz.pl	0.014	0.127	00:08:33
web.man.lodz.pl	1.347	5.868	00:00:30
pc-skrzep2.cm.p.lodz.pl	1.045	2.704	00:00:07
host227.elsnet.pl	0.012	0.012	00:00:06
staticline41435.toya.net.pl	0.219	0.546	00:00:03
mon.man.lodz.pl	0.977	2.648	00:00:03
isvcs01.man.lodz.pl	0.067	0.282	00:00:01
david.man.lodz.pl	0.439	1.653	00:00:01
TOTAL: 11	10.346	23.219	06:10:32

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	67
185.48.192.42	3
host227.elsnet.pl	2
TOTAL: 5	456

NNRP client timeouts [Top 20]:

System	Conn	Peer
dmcs2.p.lodz.pl	1	0
pc-212-51-218-225.p.lodz.pl	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	376	81.0%
2	pl	88	19.0%
TOTAL: 2		464	100.0%

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

Newsgroup		Count
1	lodman.info	376
2	pl.misc.elektronika	46
3	pl.misc.kolej	16
4	pl.comp.pecet	14
5	pl.rec.dom	9
6	pl.internet.polip	2
7	pl.misc.telefonia.gsm	1
TOTAL: 7		464

Daily Usenet report

Jun 16 04:15:03 -- Jun 17 04:15:02