Daily Usenet report: Oct 30 04:15:04

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Oct 30 09:29:34 newsfeed nnrpd[3756]: SERVER perl filter not defined
Oct 30 10:11:45 newsfeed nnrpd[5283]: SERVER perl filter not defined
Oct 30 10:39:54 newsfeed nnrpd[6056]: SERVER perl filter not defined
Oct 30 11:18:02 newsfeed nnrpd[7343]: SERVER perl filter not defined
Oct 30 16:27:49 newsfeed nnrpd[17530]: SERVER perl filter not defined
Oct 30 17:59:10 newsfeed nnrpd[20463]: SERVER perl filter not defined
Oct 30 23:12:07 newsfeed overchan[2136]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2243610	97.9%	253.4 MB	97.4%
innd	30448	1.3%	4.5 MB	1.7%
nnrpd	10413	0.5%	1.1 MB	0.4%
innfeed	7164	0.3%	1.0 MB	0.4%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	2291636	100.0%	260.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2235424	93.7%
Negative hits	108232	4.5%
Positive hits	41826	1.8%
Cache misses	252	0.0%
TOTAL: 4	2385734	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.230	0.0%	282	0.000	39.823	1026.000
article cleanup	00:00:27.848	0.0%	2242001	0.000	0.012	0.048
article logging	00:01:01.867	0.1%	2242202	0.000	0.028	0.548
article parse	01:05:45.547	4.6%	64999170	0.008	0.061	0.095
article write	00:20:14.727	1.4%	2223267	0.221	0.546	12.810
artlog/artcncl	00:00:00.006	0.0%	279	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:06:41.933	0.5%	77887223	0.000	0.005	0.114
hisgrep/artcncl	00:00:01.374	0.0%	257	0.000	5.346	85.000
hishave/artcncl	00:00:00.513	0.0%	282	0.000	1.819	212.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:57:42.180	12.3%	2387171	0.008	4.466	40.095
history sync	00:00:00.016	0.0%	294	0.000	0.054	1.000
history write	05:32:05.079	23.1%	2236791	0.052	8.908	288.115
hiswrite/artcncl	00:00:00.606	0.0%	25	0.000	24.240	226.000
idle	07:17:05.063	30.4%	29440966	0.182	0.891	370.068
nntp read	00:33:46.263	2.3%	63170444	0.009	0.032	0.092
overview write	00:00:13.376	0.0%	2223267	0.000	0.006	0.048
perl filter	05:40:28.004	23.7%	2236788	1.304	9.133	13.613
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:05.079	0.3%	4498930	0.020	0.054	0.129
TOTAL: 23:59:22.437	23:39:50.711	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.188	0.0%	52422	0.000	0.004	0.125
article prepare	00:00:00.022	0.0%	67732	0.000	0.000	0.050
article read	00:00:08.335	0.0%	16781	0.000	0.497	204.750
backlog stats	00:00:00.312	0.0%	389424	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.577	0.0%	215248	0.000	0.012	0.121
data write	00:00:13.333	0.0%	207645	0.000	0.064	24.000
idle	24:00:02.602	99.9%	389424	0.000	221.873	13065.000
status file	00:00:14.731	0.0%	241	0.000	61.124	2833.000
TOTAL: 24:00:51.575	24:00:42.100	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	74:30:20.165	100.0%	366100	0.000	732.642	600264.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:13.132	0.0%	734532	0.000	0.018	37.250
readart	00:00:01.774	0.0%	548	0.000	3.237	66.600
TOTAL: 74:31:46.789	74:30:35.071	100.0%	-	-	-	-

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	1023	2229148	2211763	1387	15998	99%	440:57:37
2	news.task.gda.pl	17	31647	6659	23352	1636	21%	44:24:29
3	news.nask.pl	3	17831	5766	11060	1005	32%	23:43:18
4	news.icm.edu.pl	42	299	15	259	25	5%	17:23:29
5	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 5		1092	2278931	2224209	36058	18664	97%	526:28:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	788.2 GB	175.0 MB	1.7 GB	790.1 GB	99%	371.9 KB
2	news.nask.pl	185.5 MB	11.2 MB	11.3 MB	208.0 MB	89%	31.5 KB
3	news.task.gda.pl	13.8 MB	68.3 KB	4.5 MB	18.4 MB	74%	2.3 KB
4	news.icm.edu.pl	184.6 KB	25.8 KB	457.6 KB	668.0 KB	27%	16.7 KB
5	localhost	9.9 KB	0.0 KB	0.0 KB	9.9 KB	100%	1.6 KB
TOTAL: 5		788.4 GB	186.4 MB	1.7 GB	790.3 GB	99%	369.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 30 04:15:04 - 04:59:59	68989	3.1%	25.59	26.7 GB	3.4%	10392.91
Oct 30 05:00:00 - 05:59:59	79352	3.6%	22.04	30.1 GB	3.8%	8766.13
Oct 30 06:00:00 - 06:59:59	99754	4.5%	27.71	37.0 GB	4.7%	10775.00
Oct 30 07:00:00 - 07:59:59	99174	4.5%	27.55	36.4 GB	4.6%	10609.92
Oct 30 08:00:00 - 08:59:59	104900	4.7%	29.14	38.7 GB	4.9%	11286.63
Oct 30 09:00:00 - 09:59:59	106849	4.8%	29.68	36.9 GB	4.7%	10751.23
Oct 30 10:00:00 - 10:59:59	107959	4.9%	29.99	35.9 GB	4.6%	10450.48
Oct 30 11:00:00 - 11:59:59	95886	4.3%	26.64	33.1 GB	4.2%	9649.10
Oct 30 12:00:00 - 12:59:59	100941	4.5%	28.04	35.8 GB	4.5%	10435.46
Oct 30 13:00:00 - 13:59:59	97523	4.4%	27.09	35.9 GB	4.6%	10453.08
Oct 30 14:00:00 - 14:59:59	109579	4.9%	30.44	38.3 GB	4.9%	11162.42
Oct 30 15:00:00 - 15:59:59	110803	5.0%	30.78	37.8 GB	4.8%	11013.58
Oct 30 16:00:00 - 16:59:59	111646	5.0%	31.01	37.5 GB	4.8%	10935.32
Oct 30 17:00:00 - 17:59:59	93333	4.2%	25.93	31.4 GB	4.0%	9154.51
Oct 30 18:00:00 - 18:59:59	74003	3.3%	20.56	25.7 GB	3.3%	7485.29
Oct 30 19:00:00 - 19:59:59	101418	4.6%	28.17	34.9 GB	4.4%	10175.16
Oct 30 20:00:00 - 20:59:59	67295	3.0%	18.69	23.0 GB	2.9%	6693.57
Oct 30 21:00:00 - 21:59:59	67273	3.0%	18.69	25.1 GB	3.2%	7307.92
Oct 30 22:00:00 - 22:59:59	57931	2.6%	16.09	20.0 GB	2.5%	5825.60
Oct 30 23:00:00 - 23:59:59	89626	4.0%	24.90	30.4 GB	3.9%	8845.89
Oct 31 00:00:00 - 00:59:59	69348	3.1%	19.26	24.6 GB	3.1%	7168.44
Oct 31 01:00:00 - 01:59:59	86578	3.9%	24.05	30.3 GB	3.8%	8822.15
Oct 31 02:00:00 - 02:59:59	100912	4.5%	28.03	36.4 GB	4.6%	10607.63
Oct 31 03:00:00 - 03:59:59	97152	4.4%	26.99	35.9 GB	4.6%	10454.79
Oct 31 04:00:00 - 04:15:02	26173	1.2%	29.02	9.8 GB	1.2%	11354.92
TOTAL: 23:59:58	2224397	100.0%	25.75	787.8 GB	100.0%	9560.95

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	16155	295	4180	8	11672
2	news.task.gda.pl	1699	1302	40	1	356
3	news.nask.pl	1056	15	992	11	38
4	news.icm.edu.pl	24	0	2	0	22
TOTAL: 4		18934	1612	5214	20	12088

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	170
mailing.freebsd.svn-ports-all	153
mailing.freebsd.svn-ports-head	153
mailing.freebsd.ports-bugs	90
alt.windows7.general	55
mailing.gcc.gcc-cvs	52
gmane.linux.alsa.devel	49
mailing.freebsd.svn-src-all	49
fa.freebsd.cvs-all	49
mailing.freebsd.svn-src-head	43
mailing.openbsd.ports-changes	41
mozilla.dev.gaia	40
mailing.freebsd.ports	40
mailing.openbsd.source-changes	35
fr.rec.loisirs-creatifs	35
mailing.unix.spamassassin-talk	30
mozilla.dev.tree-management	28
mailing.postfix.users	27
mailing.freebsd.current	22
mailing.openbsd.misc	22
TOTAL: 92	1612

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

Groups	Count
pl.sci.historia	16
alt.religion.judaism	3
fr.comp.lang.php	1
TOTAL: 3	20

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	11469
EMP (fsl)	262
EMP (phl)	60
Attached HTML file	39
EMP (md5)	29
HTML post	25
TOTAL: 6	11884

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	65133	2889	35417	6299	6	0	4%	23:57:30
2	panorama	54086	2629	48961	722	0	0	4%	23:53:52
3	nask	23902	111	14076	4161	0	0	0%	23:56:45
4	poznan	13148	53	11223	23	0	0	0%	24:01:46
5	task	2147	20	1154	5	0	0	0%	21:03:23
6	okapi	0	0	0	0	0	2706	0%	24:00:02
TOTAL: 6		158416	5702	110831	11210	6	2706	3%	140:53:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	20.8 MB	2.8 GB	2.8 GB	34.3 KB/s	321.5 KB	23:57:30
2	panorama	8.7 MB	3.3 MB	12.0 MB	0.1 KB/s	3.7 KB	23:53:52
3	nask	491.2 KB	1.5 GB	1.5 GB	18.8 KB/s	379.3 KB	23:56:45
4	poznan	165.4 KB	34.9 KB	200.3 KB	0.0 KB/s	2.6 KB	24:01:46
5	task	56.6 KB	8.3 KB	64.9 KB	0.0 KB/s	2.6 KB	21:03:23
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		30.2 MB	4.3 GB	4.4 GB	9.0 KB/s	271.2 KB	140:53:18

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	360	627.1 KB	288	0	0	00:00:14
2	web.man.lodz.pl	288	100	90.0 KB	47	0	0	00:00:27
3	lze200.lze.lodz.pl	6	53	94.5 KB	6	0	0	01:14:20
4	212.191.78.190	295	33	73.1 KB	13	0	0	47:56:36
5	adex58.neoplus.adsl.tpnet.pl	3	2	2.8 KB	1	0	0	01:18:15
6	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	0	00:00:01
7	195.200.199.227	1	0	0.0 KB	0	0	1	00:00:00
TOTAL: 7		887	548	887.5 KB	355	6	1	74:31:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	360	627.1 KB	288	0	0	00:00:14
2	*.man.lodz.pl	678	100	90.0 KB	47	6	0	00:00:34
3	*.lze.lodz.pl	6	53	94.5 KB	6	0	0	01:14:20
4	unresolved	296	33	73.1 KB	13	0	1	47:56:36
5	*.neoplus.adsl.tpnet.pl	3	2	2.8 KB	1	0	0	01:18:15
6	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	0	13:49:04
7	*.dynamic.chello.pl	1	0	0.0 KB	0	0	0	00:00:30
8	*.lodz.mm.pl	68	0	0.0 KB	0	0	0	10:06:26
9	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	0	00:05:37
10	*.toya.net.pl	36	0	0.0 KB	0	0	0	00:00:04
TOTAL: 10		1380	548	887.5 KB	355	6	1	74:31:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.700	4.416	47:56:36
16.191.14.62.static.jazztel.es	19.192	32.668	13:49:04
host-89-228-165-199.lodz.mm.pl	0.140	0.952	10:06:26
adex58.neoplus.adsl.tpnet.pl	0.064	0.052	01:18:15
lze200.lze.lodz.pl	0.008	0.124	01:14:20
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.032	00:05:37
89-65-201-29.dynamic.chello.pl	0.032	0.016	00:00:30
web.man.lodz.pl	0.572	4.212	00:00:27
vegas.cm.p.lodz.pl	0.616	4.356	00:00:14
staticline41435.toya.net.pl	0.080	0.504	00:00:04
f-oper-v100.man.lodz.pl	0.660	3.900	00:00:04
kujawiak.man.lodz.pl	0.036	0.116	00:00:01
david.man.lodz.pl	0.140	1.268	00:00:01
195.200.199.227	0.004	0.012	00:00:00
TOTAL: 14	22.248	52.628	74:31:44

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	289
david.man.lodz.pl	95
host-89-228-165-199.lodz.mm.pl	68
staticline41435.toya.net.pl	36
16.191.14.62.static.jazztel.es	2
ip-176.105.135.22.tvsat364.lodz.pl	2
89-65-201-29.dynamic.chello.pl	1
TOTAL: 7	493

NNRP client timeouts [Top 20]:

System	Conn	Peer
adex58.neoplus.adsl.tpnet.pl	4	0
lze200.lze.lodz.pl	2	0
TOTAL: 2	6	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	362	66.1%
2	pl	185	33.8%
3	alt	1	0.2%
TOTAL: 3		548	100.0%

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

Newsgroup		Count
1	lodman.info	362
2	pl.comp.pecet	58
3	pl.comp.os.ms-windows.winnt	48
4	pl.comp.os.linux	17
5	pl.regionalne.lodz	16
6	pl.rec.dom	12
7	pl.misc.telefonia.gsm	11
8	pl.soc.prawo	9
9	pl.regionalne.warszawa	6
10	pl.misc.kolej	3
11	pl.internet.polip	2
12	pl.rec.rowery	2
13	pl.rec.gory	1
14	alt.pl.praca.oferowana	1
TOTAL: 14		548

Daily Usenet report

Oct 30 04:15:04 -- Oct 31 04:15:02