Daily Usenet report: May 11 04:15:01

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

May 11 14:17:44 newsfeed nnrpd[7550]: SERVER perl filter not defined
May 11 14:22:15 newsfeed innd: getaddrinfo failed: Name or service not known
May 11 15:43:17 newsfeed nnrpd[5234]: SERVER perl filter not defined
May 11 19:08:08 newsfeed nnrpd[12377]: SERVER perl filter not defined
May 11 19:14:55 newsfeed nnrpd[12652]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1671718	98.2%	190.4 MB	97.8%
innd	14921	0.9%	2.4 MB	1.2%
innfeed	7746	0.5%	1.1 MB	0.6%
nnrpd	7204	0.4%	772.5 KB	0.4%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	1701590	100.0%	194.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1666986	98.2%
Positive hits	16302	1.0%
Negative hits	13258	0.8%
Cache misses	136	0.0%
TOTAL: 4	1696682	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.324	0.0%	287	0.000	4.613	46.000
article cleanup	00:00:20.661	0.0%	1670679	0.001	0.012	0.033
article logging	00:00:53.677	0.1%	1670810	0.014	0.032	0.075
article parse	00:40:12.643	2.8%	91853115	0.019	0.026	0.048
article write	00:16:08.494	1.1%	1666343	0.327	0.581	1.532
artlog/artcncl	00:00:00.008	0.0%	282	0.000	0.028	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:11.071	0.1%	102620674	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.343	0.0%	271	0.000	1.266	17.000
hishave/artcncl	00:00:00.041	0.0%	287	0.000	0.143	4.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:06:19.427	0.4%	1697339	0.006	0.224	3.060
history sync	00:00:00.013	0.0%	291	0.000	0.045	1.000
history write	00:16:30.119	1.1%	1667898	0.107	0.594	10.415
hiswrite/artcncl	00:00:00.003	0.0%	16	0.000	0.188	1.000
idle	17:56:09.591	74.8%	79360442	0.408	0.814	8.375
nntp read	00:34:36.403	2.4%	90653859	0.017	0.023	0.037
overview write	00:00:11.224	0.0%	1666343	0.000	0.007	0.020
perl filter	03:46:17.598	15.7%	1667897	6.462	8.141	10.139
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:31.820	0.2%	3367563	0.040	0.063	0.134
TOTAL: 23:59:16.917	23:42:24.460	98.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.135	0.0%	34907	0.000	0.004	0.333
article prepare	00:00:00.013	0.0%	43958	0.000	0.000	0.042
article read	00:00:01.366	0.0%	10746	0.000	0.127	3.000
backlog stats	00:00:00.157	0.0%	291380	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.998	0.0%	168128	0.000	0.012	0.071
data write	00:00:07.217	0.0%	138105	0.000	0.052	13.000
idle	23:58:29.218	100.0%	291380	0.000	296.208	25100.000
status file	00:00:00.214	0.0%	288	0.000	0.743	2.000
TOTAL: 23:58:46.578	23:58:40.318	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.008	0.0%	40	0.200	0.200	0.200
idle	03:37:28.662	99.8%	11050	0.000	1180.874	600109.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.470	0.0%	24168	0.000	0.019	0.500
readart	00:00:02.008	0.0%	525	0.000	3.825	21.000
TOTAL: 03:37:51.973	03:37:31.148	99.8%	-	-	-	-

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	450	1667439	1663180	212	4047	99%	148:32:19
2	news.nask.pl	2	16153	3113	12782	258	19%	24:07:29
3	news.icm.edu.pl	41	290	25	245	20	8%	19:25:21
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		498	1683886	1666322	13239	4325	98%	192:05:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	546.7 GB	13.7 MB	272.4 MB	547.0 GB	99%	344.0 KB
2	news.nask.pl	20.8 MB	638.6 KB	668.0 KB	22.0 MB	94%	6.7 KB
3	news.icm.edu.pl	570.6 KB	0.0 KB	1.0 MB	1.6 MB	35%	35.8 KB
4	localhost	5.8 KB	0.0 KB	0.0 KB	5.8 KB	100%	1.5 KB
TOTAL: 4		546.7 GB	14.3 MB	274.1 MB	547.0 GB	99%	343.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 11 04:15:01 - 04:59:59	27319	1.6%	10.12	9.2 GB	1.7%	3587.91
May 11 05:00:00 - 05:59:59	43385	2.6%	12.05	15.2 GB	2.8%	4414.24
May 11 06:00:00 - 06:59:59	60890	3.7%	16.91	21.5 GB	3.9%	6275.39
May 11 07:00:00 - 07:59:59	75655	4.5%	21.02	27.4 GB	5.0%	7990.58
May 11 08:00:00 - 08:59:59	57249	3.4%	15.90	19.2 GB	3.5%	5594.21
May 11 09:00:00 - 09:59:59	57671	3.5%	16.02	18.9 GB	3.5%	5515.69
May 11 10:00:00 - 10:59:59	88570	5.3%	24.60	27.3 GB	5.0%	7960.50
May 11 11:00:00 - 11:59:59	62598	3.8%	17.39	21.8 GB	4.0%	6359.63
May 11 12:00:00 - 12:59:59	67325	4.0%	18.70	23.6 GB	4.3%	6874.40
May 11 13:00:00 - 13:59:59	72132	4.3%	20.04	24.5 GB	4.5%	7136.45
May 11 14:00:00 - 14:59:59	89674	5.4%	24.91	27.5 GB	5.0%	7999.65
May 11 15:00:00 - 15:59:59	68942	4.1%	19.15	23.9 GB	4.4%	6956.65
May 11 16:00:00 - 16:59:59	63843	3.8%	17.73	21.9 GB	4.0%	6366.58
May 11 17:00:00 - 17:59:59	48606	2.9%	13.50	15.2 GB	2.8%	4417.07
May 11 18:00:00 - 18:59:59	80729	4.8%	22.42	28.2 GB	5.2%	8214.90
May 11 19:00:00 - 19:59:59	80069	4.8%	22.24	26.2 GB	4.8%	7638.67
May 11 20:00:00 - 20:59:59	69329	4.2%	19.26	17.9 GB	3.3%	5223.44
May 11 21:00:00 - 21:59:59	84215	5.1%	23.39	25.8 GB	4.7%	7500.83
May 11 22:00:00 - 22:59:59	75342	4.5%	20.93	25.8 GB	4.7%	7512.12
May 11 23:00:00 - 23:59:59	97492	5.8%	27.08	28.3 GB	5.2%	8253.44
May 12 00:00:00 - 00:59:59	61399	3.7%	17.06	17.0 GB	3.1%	4951.73
May 12 01:00:00 - 01:59:59	82636	5.0%	22.95	25.9 GB	4.7%	7534.86
May 12 02:00:00 - 02:59:59	74053	4.4%	20.57	25.6 GB	4.7%	7443.44
May 12 03:00:00 - 03:59:59	59975	3.6%	16.66	21.8 GB	4.0%	6348.32
May 12 04:00:00 - 04:15:02	17872	1.1%	19.81	6.7 GB	1.2%	7790.63
TOTAL: 24:00:01	1666970	100.0%	19.29	546.3 GB	100.0%	6630.12

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4179	127	2523	1	1528
2	news.nask.pl	273	8	258	4	3
3	news.icm.edu.pl	14	0	0	0	14
TOTAL: 3		4466	135	2781	5	1545

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	41
it.tlc.cellulari.android	35
comp.sys.raspberry-pi	14
hr.ponuda.hardver.komponente	8
nl.comp.sys.android	7
it.comp.os.win.windows7	6
alt.sex.telephones	4
nl.wetenschap.regulier	4
hr.alt.cellular.gsm.android	3
comp.mobile.ipad	3
it.comp.os.linux.ubuntu	2
uk.rec.cycling.moderated	2
comp.mobile.android	2
it.comp.os.win.windows8	2
hr.rec.glazba.hifi	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 16	135

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

Groups	Count
pl.sci.historia	5
TOTAL: 1	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1395
EMP (md5)	7
HTML post	3
Scoring filter (11)	3
Scoring filter (12)	2
Attached HTML file	2
Scoring filter (10)	1
TOTAL: 7	1413

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	46260	2839	25935	4585	1	12	6%	23:57:32
2	panorama	46231	2301	32216	322	0	0	4%	24:03:17
3	nask	26830	112	14052	577	0	0	0%	24:00:00
4	poznan	4900	111	2989	40	0	0	2%	24:00:00
5	okapi	0	0	0	0	0	3038	0%	24:00:01
TOTAL: 5		124221	5363	75192	5524	1	3050	4%	120:00:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	310.8 MB	817.3 MB	1.1 GB	13.4 KB/s	155.6 KB	23:57:32
2	panorama	17.2 MB	976.5 KB	18.2 MB	0.2 KB/s	7.1 KB	24:03:17
3	poznan	4.7 MB	552.3 KB	5.2 MB	0.1 KB/s	35.3 KB	24:00:00
4	nask	1.4 MB	166.3 MB	167.7 MB	2.0 KB/s	249.2 KB	24:00:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		334.0 MB	985.1 MB	1.3 GB	3.1 KB/s	124.1 KB	120:00:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	336	489.2 KB	288	0	00:00:07
2	dmcs2.p.lodz.pl	6	161	344.2 KB	8	0	00:49:31
3	web.man.lodz.pl	288	26	22.2 KB	13	0	00:00:35
4	pc-212-51-218-225.p.lodz.pl	11	2	3.0 KB	2	0	02:29:54
5	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 5		597	525	858.6 KB	311	4	03:37:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	489.2 KB	288	0	00:00:07
2	*.p.lodz.pl	17	163	347.2 KB	10	0	03:19:26
3	*.man.lodz.pl	676	26	22.2 KB	13	4	00:00:41
4	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:09
5	unresolved	2	0	0.0 KB	0	0	00:17:22
6	*.toya.net.pl	75	0	0.0 KB	0	0	00:00:04
TOTAL: 6		1060	525	858.6 KB	311	4	03:37:51

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.039	0.108	02:29:54
dmcs2.p.lodz.pl	0.050	0.104	00:49:31
185.48.195.96	0.409	0.525	00:17:22
web.man.lodz.pl	1.142	3.060	00:00:35
host227.elsnet.pl	0.014	0.015	00:00:09
pc-skrzep2.cm.p.lodz.pl	1.162	2.883	00:00:07
staticline41435.toya.net.pl	0.283	0.643	00:00:04
mon.man.lodz.pl	1.081	2.677	00:00:03
david.man.lodz.pl	0.381	0.831	00:00:01
isvcs01.man.lodz.pl	0.038	0.049	00:00:00
TOTAL: 10	4.599	10.895	03:37:51

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	75
host227.elsnet.pl	2
185.48.195.96	2
TOTAL: 5	463

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	336	64.0%
2	pl	189	36.0%
TOTAL: 2		525	100.0%

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

Newsgroup		Count
1	lodman.info	336
2	pl.misc.kolej	89
3	pl.misc.elektronika	74
4	pl.comp.pecet	24
5	pl.comp.os.ms-windows.winnt	2
TOTAL: 5		525

Daily Usenet report

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