Daily Usenet report: Aug 30 04:15:03

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Aug 30 09:46:57 newsfeed nnrpd[14814]: SERVER perl filter not defined
Aug 30 11:17:58 newsfeed nnrpd[17669]: SERVER perl filter not defined
Aug 30 12:38:04 newsfeed nnrpd[20183]: SERVER perl filter not defined
Aug 30 12:50:51 newsfeed nnrpd[20711]: SERVER perl filter not defined
Aug 30 13:08:37 newsfeed nnrpd[21166]: SERVER perl filter not defined
Aug 30 13:47:20 newsfeed nnrpd[22377]: SERVER perl filter not defined
Aug 30 16:13:39 newsfeed nnrpd[28577]: SERVER perl filter not defined
Aug 30 20:37:52 newsfeed nnrpd[4463]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1789811	98.3%	204.6 MB	97.8%
innd	16386	0.9%	2.7 MB	1.3%
nnrpd	7269	0.4%	763.0 KB	0.4%
innfeed	7266	0.4%	1.1 MB	0.5%
TOTAL: 4	1820732	100.0%	209.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1785021	93.1%
Negative hits	92403	4.8%
Positive hits	40059	2.1%
Cache misses	769	0.0%
TOTAL: 4	1918252	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.638	0.0%	383	0.000	25.164	534.000
article cleanup	00:00:23.390	0.0%	1789161	0.003	0.013	0.029
article logging	00:00:49.158	0.1%	1789359	0.008	0.027	0.555
article parse	00:58:11.808	4.0%	71595307	0.020	0.049	0.088
article write	00:20:07.022	1.4%	1777931	0.359	0.679	2.818
artlog/artcncl	00:00:00.008	0.0%	382	0.000	0.021	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:55.343	0.1%	82192580	0.000	0.001	0.005
hisgrep/artcncl	00:00:02.647	0.0%	297	0.000	8.912	394.000
hishave/artcncl	00:00:01.660	0.0%	383	0.000	4.334	69.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:33:36.299	14.8%	1918738	0.250	6.680	13.872
history sync	00:00:00.010	0.0%	290	0.000	0.034	1.000
history write	04:33:10.646	19.0%	1785632	1.985	9.179	21.124
hiswrite/artcncl	00:00:00.811	0.0%	86	0.000	9.430	179.000
idle	08:56:16.141	37.2%	47306384	0.153	0.680	2.049
nntp read	00:34:13.889	2.4%	70153623	0.014	0.029	0.044
overview write	00:00:10.727	0.0%	1777931	0.000	0.006	0.018
perl filter	04:32:17.183	18.9%	1785631	5.456	9.149	11.639
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:04.475	0.2%	3644996	0.017	0.051	0.076
TOTAL: 23:59:50.061	23:34:30.855	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.374	0.0%	126181	0.000	0.003	0.143
article prepare	00:00:00.069	0.0%	204174	0.000	0.000	0.050
article read	00:15:54.182	1.1%	50128	0.000	19.035	310.333
backlog stats	00:00:00.417	0.0%	650755	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.382	0.0%	395822	0.000	0.011	1.000
data write	00:00:19.257	0.0%	363357	0.000	0.053	1.000
idle	23:43:53.339	98.9%	650755	0.000	131.283	11579.000
status file	00:00:02.013	0.0%	324	0.000	6.213	248.500
TOTAL: 24:00:27.016	24:00:14.033	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:35:11.739	99.8%	16938	0.000	762.294	600056.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.616	0.0%	35828	0.000	0.017	3.333
readart	00:00:02.185	0.0%	494	0.000	4.423	21.417
TOTAL: 03:35:40.145	03:35:14.540	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	480	1778856	1766627	3305	8924	99%	330:42:34
2	news.task.gda.pl	10	30199	5771	23173	1255	19%	44:59:27
3	news.nask.pl	2	16892	5634	10303	955	33%	24:10:18
4	localhost	9	8	8	0	0	100%	00:00:01
5	news.icm.edu.pl	37	387	1	281	105	0%	19:33:43
TOTAL: 5		538	1826342	1778041	37062	11239	97%	419:26:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	605.6 GB	114.0 MB	1.3 GB	607.0 GB	99%	358.5 KB
2	news.nask.pl	36.5 MB	2.6 MB	2.6 MB	41.7 MB	87%	6.5 KB
3	news.task.gda.pl	12.7 MB	54.1 KB	3.5 MB	16.2 MB	78%	2.4 KB
4	news.icm.edu.pl	18.9 KB	8.3 KB	8.1 MB	8.1 MB	0%	78.4 KB
5	localhost	13.0 KB	0.0 KB	0.0 KB	13.0 KB	100%	1.6 KB
TOTAL: 5		605.6 GB	116.6 MB	1.3 GB	607.0 GB	99%	355.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 30 04:15:03 - 04:59:59	75910	4.3%	28.15	24.0 GB	4.0%	9323.38
Aug 30 05:00:00 - 05:59:59	101597	5.7%	28.22	34.1 GB	5.6%	9938.56
Aug 30 06:00:00 - 06:59:59	105221	5.9%	29.23	34.4 GB	5.7%	10028.77
Aug 30 07:00:00 - 07:59:59	53682	3.0%	14.91	19.3 GB	3.2%	5607.00
Aug 30 08:00:00 - 08:59:59	46484	2.6%	12.91	18.5 GB	3.0%	5376.78
Aug 30 09:00:00 - 09:59:59	48180	2.7%	13.38	18.6 GB	3.1%	5406.76
Aug 30 10:00:00 - 10:59:59	45627	2.6%	12.67	16.8 GB	2.8%	4896.72
Aug 30 11:00:00 - 11:59:59	46286	2.6%	12.86	16.7 GB	2.8%	4871.22
Aug 30 12:00:00 - 12:59:59	46603	2.6%	12.95	16.0 GB	2.6%	4654.96
Aug 30 13:00:00 - 13:59:59	70162	3.9%	19.49	19.3 GB	3.2%	5625.71
Aug 30 14:00:00 - 14:59:59	55029	3.1%	15.29	18.8 GB	3.1%	5483.88
Aug 30 15:00:00 - 15:59:59	44914	2.5%	12.48	18.4 GB	3.0%	5366.70
Aug 30 16:00:00 - 16:59:59	70157	3.9%	19.49	19.6 GB	3.2%	5718.68
Aug 30 17:00:00 - 17:59:59	57141	3.2%	15.87	18.4 GB	3.0%	5358.00
Aug 30 18:00:00 - 18:59:59	89139	5.0%	24.76	26.7 GB	4.4%	7781.16
Aug 30 19:00:00 - 19:59:59	63096	3.5%	17.53	22.4 GB	3.7%	6514.88
Aug 30 20:00:00 - 20:59:59	80956	4.6%	22.49	27.9 GB	4.6%	8126.90
Aug 30 21:00:00 - 21:59:59	102365	5.8%	28.43	31.6 GB	5.2%	9192.10
Aug 30 22:00:00 - 22:59:59	114057	6.4%	31.68	34.3 GB	5.7%	9976.72
Aug 30 23:00:00 - 23:59:59	101979	5.7%	28.33	32.5 GB	5.4%	9466.06
Aug 31 00:00:00 - 00:59:59	87216	4.9%	24.23	31.0 GB	5.1%	9023.81
Aug 31 01:00:00 - 01:59:59	96653	5.4%	26.85	35.8 GB	5.9%	10415.25
Aug 31 02:00:00 - 02:59:59	82617	4.6%	22.95	33.3 GB	5.5%	9697.45
Aug 31 03:00:00 - 03:59:59	71344	4.0%	19.82	29.7 GB	4.9%	8649.82
Aug 31 04:00:00 - 04:15:02	21586	1.2%	23.93	7.6 GB	1.3%	8815.62
TOTAL: 23:59:59	1778001	100.0%	20.58	605.5 GB	100.0%	7348.90

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9080	4457	2569	1	2053
2	news.task.gda.pl	1259	1048	30	0	181
3	news.nask.pl	990	21	929	1	39
4	news.icm.edu.pl	99	0	1	0	98
TOTAL: 4		11428	5526	3529	2	2371

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	4271
mailing.freebsd.svn-ports-all	122
mailing.freebsd.svn-ports-head	122
mailing.freebsd.ports-bugs	65
mozilla.dev.platform	50
mailing.unix.samba	49
mailing.freebsd.current	42
mailing.postfix.users	40
mailing.freebsd.svn-src-all	39
fa.freebsd.cvs-all	39
it.tlc.cellulari.android	39
it.comp.os.win.windows7	34
mailing.freebsd.svn-src-head	34
mailing.gcc.gcc-cvs	33
uk.rec.cycling.moderated	32
fr.usenet.abus.nocem	31
mailing.openbsd.misc	27
comp.mobile.ipad	26
alt.politics.scorched-earth	26
cl.politik.atom-presseschau	25
TOTAL: 75	5526

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

Groups	Count
bit.listserv.catholic	1
pl.sci.historia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1823
EMP (fsl)	147
EMP (md5)	98
Attached HTML file	82
HTML post	21
Scoring filter (12)	1
TOTAL: 6	2172

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	106650	38333	46191	2529	1	37007	35%	23:52:17
2	panorama	90681	2523	86272	729	0	0	2%	24:05:30
3	poznan	12867	70	10956	26	0	0	0%	23:57:06
4	task	2184	15	1165	8	0	0	0%	21:01:15
5	nask	64871	10	45883	6742	0	0	0%	24:01:37
6	okapi	0	0	0	0	0	2647	0%	24:00:01
TOTAL: 6		277253	40951	190467	10034	1	39654	14%	140:57:46

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	7.0 GB	469.7 MB	7.5 GB	91.1 KB/s	191.7 KB	23:52:17
2	panorama	7.0 MB	3.7 MB	10.7 MB	0.1 KB/s	3.4 KB	24:05:30
3	poznan	378.0 KB	49.4 KB	427.3 KB	0.0 KB/s	4.5 KB	23:57:06
4	nask	31.1 KB	1.6 GB	1.6 GB	19.7 KB/s	251.9 KB	24:01:37
5	task	29.5 KB	13.9 KB	43.4 KB	0.0 KB/s	1.9 KB	21:01:15
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		7.0 GB	2.1 GB	9.1 GB	18.8 KB/s	187.2 KB	140:57:46

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	384	669.4 KB	288	0	00:00:07
2	lze200.lze.lodz.pl	10	42	80.0 KB	7	0	00:24:30
3	web.man.lodz.pl	288	38	88.9 KB	16	0	00:00:21
4	212.191.196.1	2	19	42.3 KB	4	0	00:54:34
5	212.51.203.199	3	8	14.4 KB	2	0	00:03:24
6	h59ec3930.dkkosun.dyn.perspektivbredband.net	2	1	0.8 KB	1	0	00:00:01
7	ip-176.105.135.22.tvsat364.lodz.pl	2	1	1.4 KB	1	0	00:30:51
8	kujawiak.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 8		603	493	897.2 KB	319	8	03:35:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	384	669.4 KB	288	0	00:00:07
2	*.lze.lodz.pl	10	42	80.0 KB	7	0	00:24:30
3	*.man.lodz.pl	680	38	88.9 KB	16	8	00:00:29
4	unresolved	5	27	56.7 KB	6	0	00:57:58
5	*.dkkosun.dyn.perspektivbredband.net	2	1	0.8 KB	1	0	00:00:01
6	*.105.135.22.tvsat364.lodz.pl	2	1	1.4 KB	1	0	00:30:51
7	*.207.15.62.static.jazztel.es	4	0	0.0 KB	0	0	01:29:01
8	*.cbmm.lodz.pl	5	0	0.0 KB	0	0	00:12:19
9	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:00
10	*.toya.net.pl	47	0	0.0 KB	0	0	00:00:19
TOTAL: 10		1045	493	897.2 KB	319	8	03:35:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
116.207.15.62.static.jazztel.es	0.752	1.152	01:29:01
212.191.196.1	0.012	0.032	00:54:34
ip-176.105.135.22.tvsat364.lodz.pl	0.012	0.024	00:30:51
lze200.lze.lodz.pl	0.028	0.180	00:24:30
router2.cbmm.lodz.pl	0.016	0.076	00:12:19
212.51.203.199	0.012	0.056	00:03:24
web.man.lodz.pl	0.736	4.768	00:00:21
staticline41435.toya.net.pl	0.136	0.760	00:00:19
vegas.cm.p.lodz.pl	0.608	4.284	00:00:07
f-oper-v100.man.lodz.pl	0.680	4.488	00:00:04
kujawiak.man.lodz.pl	0.032	0.152	00:00:01
david.man.lodz.pl	0.192	1.436	00:00:01
h59ec3930.dkkosun.dyn.perspektivbredband.net	0.024	0.044	00:00:01
gcq193.internetdsl.tpnet.pl	0.000	0.032	00:00:00
TOTAL: 14	3.240	17.484	03:35:39

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	47
router2.cbmm.lodz.pl	5
116.207.15.62.static.jazztel.es	4
gcq193.internetdsl.tpnet.pl	2
TOTAL: 6	442

NNRP client timeouts [Top 20]:

System	Conn	Peer
116.207.15.62.static.jazztel.es	1	1
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	384	77.9%
2	pl	102	20.7%
3	alt	6	1.2%
4	lodz	1	0.2%
TOTAL: 4		493	100.0%

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

Newsgroup		Count
1	lodman.info	384
2	pl.comp.os.ms-windows.winnt	37
3	pl.comp.pecet	36
4	pl.rec.radio.amatorskie	8
5	pl.comp.bazy-danych	8
6	pl.misc.paranauki	7
7	alt.pl.comp.os.linux.fedora	4
8	pl.rec.dom	3
9	alt.pl.matematyka	2
10	pl.regionalne.lodz	2
11	pl.soc.dzieci	1
12	lodz.test	1
TOTAL: 12		493

Daily Usenet report

Aug 30 04:15:03 -- Aug 31 04:15:02