Daily Usenet report: Jul 29 04:15:03

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Jul 29 08:02:58 newsfeed nnrpd[11045]: SERVER perl filter not defined
Jul 29 08:07:13 newsfeed nnrpd[11123]: SERVER perl filter not defined
Jul 29 08:09:09 newsfeed nnrpd[11163]: SERVER perl filter not defined
Jul 29 09:31:55 newsfeed nnrpd[13863]: SERVER perl filter not defined
Jul 29 09:48:18 newsfeed nnrpd[14292]: SERVER perl filter not defined
Jul 29 13:27:01 newsfeed nnrpd[21182]: SERVER perl filter not defined
Jul 29 13:28:22 newsfeed nnrpd[21220]: SERVER perl filter not defined
Jul 29 16:55:53 newsfeed nnrpd[27821]: SERVER perl filter not defined
Jul 29 16:57:07 newsfeed nnrpd[27823]: SERVER perl filter not defined
Jul 29 22:00:37 newsfeed nnrpd[4943]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1449930	98.0%	164.9 MB	97.5%
innd	14901	1.0%	2.4 MB	1.4%
nnrpd	7110	0.5%	753.9 KB	0.4%
innfeed	7060	0.5%	1.0 MB	0.6%
TOTAL: 4	1479001	100.0%	169.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1444318	91.4%
Negative hits	67064	4.2%
Positive hits	37608	2.4%
Cache misses	31436	2.0%
TOTAL: 4	1580426	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:12.524	0.0%	441	0.000	28.399	451.000
article cleanup	00:00:19.705	0.0%	1449753	0.002	0.014	0.035
article logging	00:00:40.656	0.0%	1449934	0.009	0.028	0.403
article parse	00:53:35.425	3.7%	77075128	0.016	0.042	0.092
article write	00:17:57.653	1.2%	1443640	0.407	0.746	3.179
artlog/artcncl	00:00:00.007	0.0%	394	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:28.753	0.1%	86566197	0.000	0.001	0.005
hisgrep/artcncl	00:00:03.142	0.0%	380	0.000	8.268	207.000
hishave/artcncl	00:00:02.111	0.0%	441	0.000	4.787	295.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:57:53.555	12.4%	1582967	0.140	6.743	14.965
history sync	00:00:00.007	0.0%	293	0.000	0.024	1.000
history write	03:46:49.773	15.8%	1446165	0.731	9.411	44.104
hiswrite/artcncl	00:00:01.049	0.0%	61	0.000	17.197	200.333
idle	11:01:27.712	45.9%	57591529	0.132	0.689	2.626
nntp read	00:35:40.615	2.5%	75971961	0.013	0.028	0.050
overview write	00:00:08.901	0.0%	1443640	0.000	0.006	0.018
perl filter	03:54:45.370	16.3%	1446165	5.140	9.740	11.755
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:28.064	0.2%	2930844	0.014	0.051	0.082
TOTAL: 23:59:44.264	23:33:35.022	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.148	0.0%	44867	0.000	0.003	1.000
article prepare	00:00:00.014	0.0%	56462	0.000	0.000	0.125
article read	00:01:02.919	0.1%	13838	0.000	4.547	178.400
backlog stats	00:00:00.192	0.0%	328394	0.000	0.001	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.969	0.0%	192991	0.000	0.010	1.000
data write	00:00:06.352	0.0%	167470	0.000	0.038	0.333
idle	23:58:03.377	99.9%	328394	0.000	262.743	17365.000
status file	00:00:01.606	0.0%	293	0.000	5.481	172.000
TOTAL: 23:59:23.045	23:59:16.577	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.480	0.0%	2553	0.145	0.188	2.636
idle	07:19:31.563	99.9%	23617	0.000	1116.635	600077.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.827	0.0%	49325	0.000	0.017	1.577
readart	00:00:02.968	0.0%	3046	0.000	0.974	186.000
TOTAL: 07:20:01.298	07:19:35.838	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	873	1468546	1432242	33232	3072	97%	297:10:26
2	news.task.gda.pl	16	29507	5548	22406	1553	18%	47:43:06
3	news.nask.pl	2	16800	4709	10556	1535	28%	23:50:29
4	localhost	11	10	10	0	0	100%	00:00:00
5	news.icm.edu.pl	39	275	7	247	21	2%	18:06:34
TOTAL: 5		941	1515138	1442516	66441	6181	95%	386:50:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	548.5 GB	14.4 MB	42.8 MB	548.6 GB	99%	400.8 KB
2	news.nask.pl	19.0 MB	4.1 MB	4.2 MB	27.3 MB	69%	4.5 KB
3	news.task.gda.pl	12.0 MB	40.3 KB	4.9 MB	17.0 MB	70%	2.4 KB
4	news.icm.edu.pl	604.0 KB	116.6 KB	834.0 KB	1.5 MB	38%	55.5 KB
5	localhost	15.0 KB	0.0 KB	0.0 KB	15.0 KB	100%	1.5 KB
TOTAL: 5		548.6 GB	18.6 MB	52.7 MB	548.6 GB	99%	397.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 29 04:15:03 - 04:59:59	60654	4.2%	22.49	23.3 GB	4.2%	9058.76
Jul 29 05:00:00 - 05:59:59	67760	4.7%	18.82	26.2 GB	4.8%	7620.41
Jul 29 06:00:00 - 06:59:59	47143	3.3%	13.10	19.0 GB	3.5%	5523.79
Jul 29 07:00:00 - 07:59:59	45212	3.1%	12.56	18.6 GB	3.4%	5425.29
Jul 29 08:00:00 - 08:59:59	50286	3.5%	13.97	18.9 GB	3.4%	5498.19
Jul 29 09:00:00 - 09:59:59	50119	3.5%	13.92	17.9 GB	3.3%	5214.50
Jul 29 10:00:00 - 10:59:59	41980	2.9%	11.66	15.5 GB	2.8%	4502.34
Jul 29 11:00:00 - 11:59:59	49252	3.4%	13.68	18.1 GB	3.3%	5268.05
Jul 29 12:00:00 - 12:59:59	39739	2.8%	11.04	17.0 GB	3.1%	4957.24
Jul 29 13:00:00 - 13:59:59	43223	3.0%	12.01	17.4 GB	3.2%	5057.09
Jul 29 14:00:00 - 14:59:59	42196	2.9%	11.72	15.9 GB	2.9%	4639.78
Jul 29 15:00:00 - 15:59:59	45294	3.1%	12.58	18.7 GB	3.4%	5453.08
Jul 29 16:00:00 - 16:59:59	50287	3.5%	13.97	19.2 GB	3.5%	5606.82
Jul 29 17:00:00 - 17:59:59	45127	3.1%	12.54	18.7 GB	3.4%	5448.83
Jul 29 18:00:00 - 18:59:59	52746	3.7%	14.65	21.1 GB	3.8%	6136.03
Jul 29 19:00:00 - 19:59:59	73930	5.1%	20.54	28.6 GB	5.2%	8343.43
Jul 29 20:00:00 - 20:59:59	100424	7.0%	27.90	34.7 GB	6.3%	10097.66
Jul 29 21:00:00 - 21:59:59	86809	6.0%	24.11	26.8 GB	4.9%	7795.96
Jul 29 22:00:00 - 22:59:59	44069	3.1%	12.24	16.9 GB	3.1%	4930.03
Jul 29 23:00:00 - 23:59:59	47065	3.3%	13.07	18.3 GB	3.3%	5331.96
Jul 30 00:00:00 - 00:59:59	81870	5.7%	22.74	31.2 GB	5.7%	9076.48
Jul 30 01:00:00 - 01:59:59	71610	5.0%	19.89	28.7 GB	5.2%	8363.54
Jul 30 02:00:00 - 02:59:59	95201	6.6%	26.44	36.5 GB	6.6%	10619.28
Jul 30 03:00:00 - 03:59:59	93837	6.5%	26.07	35.0 GB	6.4%	10179.93
Jul 30 04:00:00 - 04:15:03	17409	1.2%	19.28	6.7 GB	1.2%	7733.29
TOTAL: 24:00:00	1443242	100.0%	16.70	548.7 GB	100.0%	6659.00

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3211	367	2097	3	744
2	news.nask.pl	1544	18	1470	6	50
3	news.task.gda.pl	1518	1285	20	1	212
4	news.icm.edu.pl	21	0	1	0	20
TOTAL: 4		6294	1670	3588	10	1026

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	232
mailing.freebsd.svn-ports-all	129
mailing.freebsd.svn-ports-head	129
mailing.freebsd.current	68
mailing.freebsd.svn-src-all	68
mailing.freebsd.ports-bugs	67
fr.usenet.abus.nocem	66
mailing.freebsd.svn-src-head	59
24hoursupport.helpdesk	48
alt.windows7.general	42
mailing.freebsd.ports	42
fa.freebsd.cvs-all	40
comp.mobile.ipad	39
gmane.linux.alsa.devel	37
mailing.freebsd.tinderbox	37
mailing.database.pgsql-general	34
mailing.www.horde-bugs	31
mailing.freebsd.wireless	30
mailing.gcc.gcc-cvs	28
uk.rec.cycling.moderated	27
TOTAL: 86	1670

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

Groups	Count
pl.sci.matematyka	5
pl.sci.historia	4
fr.comp.securite	1
TOTAL: 3	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	571
EMP (fsl)	166
EMP (md5)	45
Attached HTML file	38
HTML post	22
Scoring filter (12)	1
Scoring filter (9)	1
Scoring filter (8)	1
TOTAL: 8	845

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	60630	2269	30491	8791	1	1316	3%	23:50:43
2	panorama	46811	2187	41232	724	0	0	4%	24:01:22
3	task	2015	38	1041	12	0	8	1%	21:33:37
4	poznan	11592	24	9995	11	0	0	0%	24:07:45
5	nask	20089	5	13680	0	0	0	0%	24:01:47
6	okapi	0	0	0	0	0	2546	0%	24:00:01
TOTAL: 6		141137	4523	96439	9538	1	3870	3%	141:35:15

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	26.9 MB	1.8 GB	1.8 GB	22.1 KB/s	171.9 KB	23:50:43
2	panorama	5.9 MB	7.7 MB	13.5 MB	0.2 KB/s	4.8 KB	24:01:22
3	nask	135.4 KB	0.0 KB	135.4 KB	0.0 KB/s	27.1 KB	24:01:47
4	poznan	110.8 KB	24.0 KB	134.8 KB	0.0 KB/s	3.9 KB	24:07:45
5	task	76.8 KB	26.6 KB	103.4 KB	0.0 KB/s	2.1 KB	21:33:37
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		33.1 MB	1.8 GB	1.8 GB	3.8 KB/s	136.2 KB	141:35:15

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	388	593.7 KB	288	0	00:00:09
2	web.man.lodz.pl	288	118	115.3 KB	55	0	00:00:25
3	212.191.196.1	4	71	179.2 KB	5	0	02:14:09
4	lze200.lze.lodz.pl	4	31	62.8 KB	3	0	00:20:20
5	ip-176.105.135.22.tvsat364.lodz.pl	6	6	88.0 KB	4	0	01:52:14
6	kujawiak.man.lodz.pl	10	0	0.0 KB	0	10	00:00:01
TOTAL: 6		600	614	1.0 MB	355	10	07:20:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	388	593.7 KB	288	0	00:00:09
2	*.man.lodz.pl	682	118	115.3 KB	55	10	00:00:34
3	unresolved	4	71	179.2 KB	5	0	02:14:09
4	*.lze.lodz.pl	4	31	62.8 KB	3	0	00:20:20
5	*.105.135.22.tvsat364.lodz.pl	6	6	88.0 KB	4	0	01:52:14
6	*.207.15.62.static.jazztel.es	4	0	0.0 KB	0	0	00:18:46
7	*.191.14.62.static.jazztel.es	3	0	0.0 KB	0	0	01:22:34
8	*.neoplus.adsl.tpnet.pl	5	0	0.0 KB	0	0	01:11:04
9	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:00
10	*.toya.net.pl	1	0	0.0 KB	0	0	00:00:06
TOTAL: 10		999	614	1.0 MB	355	10	07:20:00

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.196.1	0.008	0.092	02:14:09
ip-176.105.135.22.tvsat364.lodz.pl	0.016	0.092	01:52:14
158.191.14.62.static.jazztel.es	0.800	1.052	01:22:34
eby22.neoplus.adsl.tpnet.pl	0.000	0.048	00:40:57
ebz97.neoplus.adsl.tpnet.pl	0.000	0.036	00:30:07
lze200.lze.lodz.pl	0.012	0.076	00:20:20
83.207.15.62.static.jazztel.es	0.500	0.884	00:18:46
web.man.lodz.pl	0.740	4.344	00:00:25
vegas.cm.p.lodz.pl	0.616	4.360	00:00:09
staticline43485.toya.net.pl	0.000	0.016	00:00:06
f-oper-v100.man.lodz.pl	0.624	4.724	00:00:06
kujawiak.man.lodz.pl	0.056	0.164	00:00:01
david.man.lodz.pl	0.196	1.360	00:00:01
gcq193.internetdsl.tpnet.pl	0.008	0.024	00:00:00
TOTAL: 14	3.576	17.272	07:20:00

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
83.207.15.62.static.jazztel.es	4
158.191.14.62.static.jazztel.es	3
eby22.neoplus.adsl.tpnet.pl	3
ebz97.neoplus.adsl.tpnet.pl	2
gcq193.internetdsl.tpnet.pl	2
staticline43485.toya.net.pl	1
TOTAL: 8	399

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.196.1	3	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
ebz97.neoplus.adsl.tpnet.pl	1	0
eby22.neoplus.adsl.tpnet.pl	1	0
TOTAL: 4	7	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	388	63.2%
2	pl	226	36.8%
TOTAL: 2		614	100.0%

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

Newsgroup		Count
1	lodman.info	388
2	pl.comp.pecet	107
3	pl.misc.paranauki	52
4	pl.comp.os.ms-windows.winnt	39
5	pl.listserv.chomor-l	13
6	pl.regionalne.lodz	10
7	pl.misc.dieta	2
8	pl.internet.polip	2
9	pl.rec.kuchnia	1
TOTAL: 9		614

Daily Usenet report

Jul 29 04:15:03 -- Jul 30 04:15:03