Daily Usenet report: Sep 16 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Sep 16 09:14:39 newsfeed nnrpd[19554]: SERVER perl filter not defined
Sep 16 10:52:03 newsfeed nnrpd[22700]: SERVER perl filter not defined
Sep 16 13:33:43 newsfeed nnrpd[28156]: SERVER perl filter not defined
Sep 16 14:26:16 newsfeed nnrpd[29892]: SERVER perl filter not defined
Sep 17 00:28:54 newsfeed nnrpd[17455]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	617037	96.4%	72.9 MB	95.9%
innd	9295	1.5%	1.5 MB	2.0%
nnrpd	7398	1.2%	755.5 KB	1.0%
innfeed	6346	1.0%	902.6 KB	1.2%
TOTAL: 4	640076	100.0%	76.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	614263	94.4%
Negative hits	18490	2.8%
Positive hits	17923	2.8%
Cache misses	157	0.0%
TOTAL: 4	650833	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.811	0.0%	412	0.000	23.813	940.000
article cleanup	00:00:12.818	0.0%	616630	0.000	0.021	0.059
article logging	00:00:22.755	0.0%	616764	0.000	0.037	0.117
article parse	00:17:48.797	1.2%	47834501	0.011	0.022	0.037
article write	00:04:56.247	0.3%	612818	0.234	0.483	2.585
artlog/artcncl	00:00:00.008	0.0%	376	0.000	0.021	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:56.691	0.1%	51395008	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.565	0.0%	328	0.000	4.771	112.000
hishave/artcncl	00:00:00.944	0.0%	412	0.000	2.291	17.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:44:16.181	3.1%	650701	0.047	4.082	15.244
history sync	00:00:00.028	0.0%	460	0.000	0.061	1.000
history write	01:11:00.118	4.9%	614441	3.476	6.933	30.682
hiswrite/artcncl	00:00:01.446	0.0%	84	0.000	17.214	261.000
idle	19:51:18.730	82.7%	39347839	0.444	1.817	7.836
nntp read	00:20:09.044	1.4%	47422331	0.016	0.025	0.037
overview write	00:00:04.528	0.0%	612818	0.000	0.007	0.036
perl filter	01:12:10.668	5.0%	614440	4.338	7.048	11.123
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:44.418	0.1%	1256143	0.023	0.083	0.156
TOTAL: 24:00:17.573	23:45:14.797	99.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.099	0.0%	30521	0.000	0.003	0.250
article prepare	00:00:00.004	0.0%	11180	0.000	0.000	0.250
article read	00:00:00.240	0.0%	2663	0.000	0.090	5.000
backlog stats	00:00:00.144	0.0%	216617	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.535	0.0%	133551	0.000	0.011	1.000
data write	00:00:04.002	0.0%	108723	0.000	0.037	0.265
idle	23:59:58.462	100.0%	216617	0.000	398.854	24097.000
status file	00:00:00.479	0.0%	172	0.000	2.785	191.000
TOTAL: 24:00:10.475	24:00:04.965	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:39:33.482	99.6%	16351	0.000	365.328	91826.381
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.865	0.0%	34773	0.000	0.025	4.000
readart	00:00:01.109	0.0%	524	0.000	2.116	18.080
TOTAL: 01:39:54.974	01:39:35.456	99.7%	-	-	-	-

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	190	612833	609195	316	3322	99%	117:23:03
2	news.nask.pl	3	18212	2784	15043	385	15%	23:39:35
3	news.icm.edu.pl	41	380	39	217	124	10%	19:42:09
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		240	631430	612023	15576	3831	96%	160:44:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	138.3 GB	9.6 MB	274.8 MB	138.6 GB	99%	237.2 KB
2	news.nask.pl	10.4 MB	976.4 KB	1.2 MB	12.6 MB	82%	4.1 KB
3	news.icm.edu.pl	1.7 MB	77.2 KB	5.9 MB	7.6 MB	21%	47.9 KB
4	localhost	8.2 KB	0.0 KB	0.0 KB	8.2 KB	100%	1.6 KB
TOTAL: 4		138.3 GB	10.7 MB	281.9 MB	138.6 GB	99%	236.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 16 04:15:02 - 04:59:59	29562	4.8%	10.96	9.2 GB	6.7%	3593.92
Sep 16 05:00:00 - 05:59:59	52979	8.6%	14.72	17.4 GB	12.6%	5075.02
Sep 16 06:00:00 - 06:59:59	17908	2.9%	4.97	4.9 GB	3.6%	1436.38
Sep 16 07:00:00 - 07:59:59	9277	1.5%	2.58	2.1 GB	1.5%	622.30
Sep 16 08:00:00 - 08:59:59	9553	1.6%	2.65	2.0 GB	1.4%	575.95
Sep 16 09:00:00 - 09:59:59	12214	2.0%	3.39	2.0 GB	1.5%	595.06
Sep 16 10:00:00 - 10:59:59	11079	1.8%	3.08	1.9 GB	1.4%	561.00
Sep 16 11:00:00 - 11:59:59	11531	1.9%	3.20	2.3 GB	1.7%	667.24
Sep 16 12:00:00 - 12:59:59	10860	1.8%	3.02	2.1 GB	1.5%	619.28
Sep 16 13:00:00 - 13:59:59	9678	1.6%	2.69	2.0 GB	1.4%	579.75
Sep 16 14:00:00 - 14:59:59	10261	1.7%	2.85	2.0 GB	1.4%	580.21
Sep 16 15:00:00 - 15:59:59	11566	1.9%	3.21	2.3 GB	1.6%	657.63
Sep 16 16:00:00 - 16:59:59	10794	1.8%	3.00	1.8 GB	1.3%	534.64
Sep 16 17:00:00 - 17:59:59	10361	1.7%	2.88	2.0 GB	1.4%	574.58
Sep 16 18:00:00 - 18:59:59	11160	1.8%	3.10	1.9 GB	1.4%	546.83
Sep 16 19:00:00 - 19:59:59	12594	2.1%	3.50	2.0 GB	1.4%	572.22
Sep 16 20:00:00 - 20:59:59	11847	1.9%	3.29	2.0 GB	1.4%	573.16
Sep 16 21:00:00 - 21:59:59	18669	3.0%	5.19	2.9 GB	2.1%	855.91
Sep 16 22:00:00 - 22:59:59	29817	4.9%	8.28	4.2 GB	3.0%	1225.25
Sep 16 23:00:00 - 23:59:59	32019	5.2%	8.89	4.4 GB	3.2%	1273.90
Sep 17 00:00:00 - 00:59:59	61653	10.1%	17.13	13.9 GB	10.0%	4034.11
Sep 17 01:00:00 - 01:59:59	58789	9.6%	16.33	13.6 GB	9.8%	3954.56
Sep 17 02:00:00 - 02:59:59	62619	10.2%	17.39	14.6 GB	10.6%	4256.95
Sep 17 03:00:00 - 03:59:59	77338	12.6%	21.48	20.0 GB	14.5%	5825.35
Sep 17 04:00:00 - 04:15:02	18586	3.0%	20.61	4.9 GB	3.5%	5661.99
TOTAL: 24:00:00	612714	100.0%	7.09	138.4 GB	100.0%	1679.56

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3458	244	1863	0	1351
2	news.nask.pl	396	59	326	2	9
3	news.icm.edu.pl	93	0	1	0	92
TOTAL: 3		3947	303	2190	2	1452

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	83
alt.sex.telephones	42
comp.sys.raspberry-pi	41
comp.mobile.ipad	37
uk.rec.cycling.moderated	22
it.tlc.cellulari.android	18
it.comp.os.win.windows7	18
comp.mobile.android	14
de.comp.lang.vbclassic	8
it.comp.os.win.windows8	6
nl.wetenschap.regulier	3
hr.ponuda.hardver.komponente	3
hr.ponuda.hardver.periferije	2
de.comp.office-pakete.ms-office.excel	2
hr.rec.glazba.hifi	1
de.comm.software.mailreader	1
de.comm.provider.telefonie	1
it.discussioni.energia	1
TOTAL: 18	303

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1213
EMP (md5)	96
HTML post	5
EMP (fsl)	1
Scoring filter (9)	1
Attached HTML file	1
TOTAL: 6	1317

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	31351	1055	29247	205	0	3%	24:00:59
2	icm	38877	791	28697	231	18	2%	23:51:15
3	nask	28209	81	17012	412	0	0%	24:02:14
4	poznan	3819	13	2741	5	0	0%	23:57:28
5	okapi	0	0	0	0	2782	0%	24:00:01
TOTAL: 5		102256	1940	77697	853	2800	1%	119:51:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	22.6 MB	5.5 MB	28.0 MB	0.3 KB/s	28.1 KB	23:51:15
2	panorama	4.1 MB	654.2 KB	4.8 MB	0.1 KB/s	3.9 KB	24:00:59
3	nask	1.3 MB	139.7 MB	141.0 MB	1.7 KB/s	292.9 KB	24:02:14
4	poznan	64.0 KB	7.6 KB	71.5 KB	0.0 KB/s	4.0 KB	23:57:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		28.0 MB	145.9 MB	173.9 MB	0.4 KB/s	63.8 KB	119:51:57

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	348	616.7 KB	288	0	00:00:07
2	web.man.lodz.pl	288	122	143.4 KB	52	0	00:00:22
3	lze200.lze.lodz.pl	3	54	97.7 KB	6	0	01:06:22
4	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 4		584	524	857.8 KB	346	5	01:39:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	616.7 KB	288	0	00:00:07
2	*.man.lodz.pl	677	122	143.4 KB	52	5	00:00:28
3	*.lze.lodz.pl	3	54	97.7 KB	6	0	01:06:22
4	*.188.249.62.customer.cdi.no	24	0	0.0 KB	0	0	00:03:54
5	*.181.14.62.static.jazztel.es	5	0	0.0 KB	0	0	00:28:47
6	*.cbmm.lodz.pl	1	0	0.0 KB	0	0	00:00:05
7	*.toya.net.pl	87	0	0.0 KB	0	0	00:00:08
TOTAL: 7		1085	524	857.8 KB	346	5	01:39:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.027	0.062	01:06:22
162.181.14.62.static.jazztel.es	0.472	0.512	00:16:10
73.181.14.62.static.jazztel.es	0.350	0.467	00:12:36
191.188.249.62.customer.cdi.no	0.145	0.431	00:03:54
web.man.lodz.pl	1.167	4.730	00:00:22
staticline41435.toya.net.pl	0.408	1.270	00:00:08
pc-skrzep2.cm.p.lodz.pl	1.240	4.503	00:00:07
router2.cbmm.lodz.pl	0.008	0.012	00:00:05
mon.man.lodz.pl	1.050	4.483	00:00:04
david.man.lodz.pl	0.339	1.432	00:00:01
isvcs01.man.lodz.pl	0.051	0.082	00:00:00
?	0.000	0.014	00:00:00
TOTAL: 12	5.257	17.998	01:39:54

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	87
191.188.249.62.customer.cdi.no	24
73.181.14.62.static.jazztel.es	3
162.181.14.62.static.jazztel.es	2
router2.cbmm.lodz.pl	1
TOTAL: 7	501

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	0
162.181.14.62.static.jazztel.es	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	66.4%
2	pl	176	33.6%
TOTAL: 2		524	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.comp.os.ms-windows.winnt	108
3	pl.comp.pecet	56
4	pl.misc.telefonia.gsm	6
5	pl.rec.dom	4
6	pl.comp.os.linux	2
TOTAL: 6		524

Daily Usenet report

Sep 16 04:15:02 -- Sep 17 04:15:02