Daily Usenet report: Apr 16 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Apr 16 08:44:22 newsfeed nnrpd[4364]: SERVER perl filter not defined
Apr 16 11:18:36 newsfeed nnrpd[9427]: SERVER perl filter not defined
Apr 16 14:58:15 newsfeed nnrpd[16996]: SERVER perl filter not defined
Apr 16 15:00:16 newsfeed nnrpd[17008]: SERVER perl filter not defined
Apr 16 16:18:04 newsfeed nnrpd[19706]: SERVER perl filter not defined
Apr 16 17:47:55 newsfeed nnrpd[22706]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1161419	97.8%	131.8 MB	97.2%
innd	10738	0.9%	1.9 MB	1.4%
nnrpd	8090	0.7%	872.2 KB	0.6%
innfeed	6998	0.6%	1.0 MB	0.8%
TOTAL: 4	1187245	100.0%	135.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1156711	92.8%
Negative hits	50075	4.0%
Positive hits	39737	3.2%
Cache misses	300	0.0%
TOTAL: 4	1246823	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.397	0.0%	450	0.000	25.327	552.000
article cleanup	00:00:20.452	0.0%	1161354	0.002	0.018	0.041
article logging	00:00:39.902	0.0%	1161476	0.007	0.034	0.705
article parse	00:48:54.777	3.4%	130703916	0.003	0.022	0.080
article write	00:14:05.995	1.0%	1155808	0.194	0.732	2.808
artlog/artcncl	00:00:00.007	0.0%	445	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:02:36.911	0.2%	138462605	0.000	0.001	0.028
hisgrep/artcncl	00:00:01.371	0.0%	343	0.000	3.997	293.000
hishave/artcncl	00:00:00.923	0.0%	450	0.000	2.051	19.917
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:25:57.107	6.0%	1247907	0.008	4.133	9.994
history sync	00:00:00.014	0.0%	291	0.000	0.048	1.000
history write	02:45:13.395	11.5%	1158055	0.064	8.560	25.067
hiswrite/artcncl	00:00:00.424	0.0%	107	0.000	3.963	106.000
idle	14:38:28.524	61.0%	95892668	0.192	0.550	1.386
nntp read	00:45:41.176	3.2%	129791268	0.005	0.021	0.046
overview write	00:00:07.851	0.0%	1155808	0.000	0.007	0.023
perl filter	02:45:17.764	11.5%	1158051	3.684	8.564	13.563
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:35.282	0.2%	2366429	0.027	0.066	0.164
TOTAL: 24:00:22.182	23:30:13.272	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.208	0.0%	54867	0.000	0.004	0.100
article prepare	00:00:00.022	0.0%	86508	0.000	0.000	0.050
article read	00:00:04.779	0.0%	21470	0.000	0.223	38.000
backlog stats	00:00:00.320	0.0%	402258	0.000	0.001	0.029
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.554	0.0%	235522	0.000	0.011	1.000
data write	00:00:12.231	0.0%	207364	0.000	0.059	5.000
idle	23:58:47.856	100.0%	402258	0.000	214.608	15072.000
status file	00:00:01.255	0.0%	269	0.000	4.665	154.333
TOTAL: 23:59:18.487	23:59:09.225	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	15:51:09.838	99.8%	214893	0.000	265.573	223044.111
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:01:05.838	0.1%	734318	0.000	0.090	56.700
readart	00:00:02.654	0.0%	857	0.000	3.097	25.750
TOTAL: 15:53:07.647	15:52:18.330	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	164	1150392	1145192	1552	3648	99%	156:38:49
2	news.task.gda.pl	21	31292	6237	23648	1407	19%	46:55:26
3	news.nask.pl	2	17152	4622	12072	458	26%	23:58:16
4	localhost	7	6	6	0	0	100%	00:00:00
5	news.icm.edu.pl	30	344	2	267	75	0%	18:45:14
TOTAL: 5		224	1199186	1156059	37539	5588	96%	246:17:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	413.5 GB	30.7 MB	47.9 MB	413.6 GB	99%	377.5 KB
2	news.nask.pl	22.6 MB	1.4 MB	1.5 MB	25.5 MB	88%	5.1 KB
3	news.task.gda.pl	14.8 MB	41.8 KB	4.4 MB	19.2 MB	76%	2.6 KB
4	news.icm.edu.pl	92.4 KB	0.0 KB	791.7 KB	884.0 KB	10%	11.5 KB
5	localhost	11.3 KB	0.0 KB	0.0 KB	11.3 KB	100%	1.9 KB
TOTAL: 5		413.6 GB	32.1 MB	54.6 MB	413.7 GB	99%	373.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 16 04:15:03 - 04:59:59	54992	4.8%	20.39	21.7 GB	5.3%	8439.45
Apr 16 05:00:00 - 05:59:59	78333	6.8%	21.76	30.3 GB	7.3%	8817.17
Apr 16 06:00:00 - 06:59:59	60788	5.3%	16.89	24.9 GB	6.0%	7243.81
Apr 16 07:00:00 - 07:59:59	59915	5.2%	16.64	23.5 GB	5.7%	6838.71
Apr 16 08:00:00 - 08:59:59	66290	5.7%	18.41	26.8 GB	6.5%	7810.88
Apr 16 09:00:00 - 09:59:59	54683	4.7%	15.19	17.8 GB	4.3%	5193.97
Apr 16 10:00:00 - 10:59:59	31366	2.7%	8.71	10.2 GB	2.5%	2965.56
Apr 16 11:00:00 - 11:59:59	34038	2.9%	9.46	11.7 GB	2.8%	3402.94
Apr 16 12:00:00 - 12:59:59	37354	3.2%	10.38	13.2 GB	3.2%	3836.60
Apr 16 13:00:00 - 13:59:59	41875	3.6%	11.63	12.9 GB	3.1%	3757.09
Apr 16 14:00:00 - 14:59:59	39538	3.4%	10.98	11.5 GB	2.8%	3338.01
Apr 16 15:00:00 - 15:59:59	42965	3.7%	11.93	13.3 GB	3.2%	3870.27
Apr 16 16:00:00 - 16:59:59	37437	3.2%	10.40	13.8 GB	3.3%	4015.30
Apr 16 17:00:00 - 17:59:59	37821	3.3%	10.51	14.9 GB	3.6%	4326.54
Apr 16 18:00:00 - 18:59:59	33655	2.9%	9.35	12.4 GB	3.0%	3598.95
Apr 16 19:00:00 - 19:59:59	39279	3.4%	10.91	15.9 GB	3.9%	4636.98
Apr 16 20:00:00 - 20:59:59	35730	3.1%	9.93	12.8 GB	3.1%	3721.21
Apr 16 21:00:00 - 21:59:59	33078	2.9%	9.19	11.1 GB	2.7%	3224.45
Apr 16 22:00:00 - 22:59:59	19057	1.6%	5.29	5.9 GB	1.4%	1726.38
Apr 16 23:00:00 - 23:59:59	31770	2.7%	8.82	11.6 GB	2.8%	3365.43
Apr 17 00:00:00 - 00:59:59	70688	6.1%	19.64	24.7 GB	6.0%	7184.81
Apr 17 01:00:00 - 01:59:59	63384	5.5%	17.61	21.5 GB	5.2%	6276.63
Apr 17 02:00:00 - 02:59:59	49926	4.3%	13.87	16.5 GB	4.0%	4808.20
Apr 17 03:00:00 - 03:59:59	78604	6.8%	21.83	27.0 GB	6.5%	7855.31
Apr 17 04:00:00 - 04:15:04	22739	2.0%	25.15	7.6 GB	1.8%	8777.14
TOTAL: 24:00:01	1155305	100.0%	13.37	413.2 GB	100.0%	5014.18

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3749	282	2853	1	613
2	news.task.gda.pl	1369	1134	20	0	215
3	news.nask.pl	470	14	428	1	27
4	news.icm.edu.pl	81	0	0	0	81
TOTAL: 4		5669	1430	3301	2	936

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	132
mailing.openbsd.source-changes	76
gmane.linux.alsa.devel	75
mailing.freebsd.ports-bugs	67
mozilla.dev.tree-management	67
mailing.database.pgsql-general	60
mailing.freebsd.svn-ports-all	59
24hoursupport.helpdesk	57
mailing.freebsd.svn-ports-head	56
mailing.postfix.users	55
alt.windows7.general	52
mozilla.dev.platform	44
comp.mobile.android	40
mailing.freebsd.svn-src-all	36
fa.freebsd.cvs-all	35
it.comp.os.win.windows7	33
mailing.unix.samba-technical	32
mozilla.dev.gaia	29
mailing.freebsd.svn-src-head	28
alt.politics.scorched-earth	27
TOTAL: 84	1430

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	517
EMP (fsl)	178
EMP (md5)	97
EMP (phl)	13
HTML post	3
Excessively crossposted test article	1
Scoring filter (12)	1
TOTAL: 7	810

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	74357	17284	35292	2191	12	82	23%	24:08:31
2	panorama	56096	1794	52784	294	0	0	3%	24:07:12
3	task	1852	49	940	11	0	0	2%	21:27:09
4	poznan	12447	37	10630	13	0	0	0%	24:03:49
5	nask	26603	14	18941	2	0	0	0%	23:56:55
6	okapi	0	0	0	0	0	2564	0%	24:00:02
TOTAL: 6		171355	19178	118587	2511	12	2646	11%	141:43:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.6 GB	70.7 MB	2.7 GB	32.7 KB/s	145.8 KB	24:08:31
2	panorama	10.0 MB	1.6 MB	11.6 MB	0.1 KB/s	5.7 KB	24:07:12
3	task	91.6 KB	43.6 KB	135.2 KB	0.0 KB/s	2.3 KB	21:27:09
4	poznan	82.1 KB	28.4 KB	110.5 KB	0.0 KB/s	2.2 KB	24:03:49
5	nask	56.6 KB	255.0 KB	311.6 KB	0.0 KB/s	19.5 KB	23:56:55
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		2.6 GB	72.6 MB	2.7 GB	5.6 KB/s	131.5 KB	141:43:38

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	312	876.3 KB	288	0	0	00:00:15
2	pc225.geo.uni.lodz.pl	2	84	151.3 KB	4	0	0	01:12:54
3	web.man.lodz.pl	288	64	56.5 KB	25	0	0	00:00:24
4	lze200.lze.lodz.pl	5	59	103.8 KB	10	0	0	01:17:11
5	ip-176.105.135.22.tvsat364.lodz.pl	5	22	35.5 KB	3	0	0	00:49:19
6	gate-ife.p.lodz.pl	2	16	17.8 KB	1	0	0	00:09:10
7	router2.cbmm.lodz.pl	1	7	11.7 KB	1	0	0	00:01:02
8	pc26.zsk.p.lodz.pl	3	6	7.1 KB	2	2	2	01:03:50
9	radius2.p.lodz.pl	4	1	3.4 KB	1	1	0	00:16:05
10	pc24.zsk.p.lodz.pl	2	1	0.7 KB	1	1	0	00:30:58
11	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	0	00:00:00
TOTAL: 11		602	572	1.2 MB	336	6	2	15:53:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	312	876.3 KB	288	0	0	00:00:15
2	*.geo.uni.lodz.pl	2	84	151.3 KB	4	0	0	01:12:54
3	*.man.lodz.pl	674	64	56.5 KB	25	2	0	00:00:29
4	*.lze.lodz.pl	5	59	103.8 KB	10	0	0	01:17:11
5	*.105.135.22.tvsat364.lodz.pl	5	22	35.5 KB	3	0	0	00:49:19
6	*.p.lodz.pl	6	17	21.2 KB	2	1	0	00:25:16
7	*.zsk.p.lodz.pl	5	7	7.8 KB	3	3	2	01:34:48
8	*.cbmm.lodz.pl	1	7	11.7 KB	1	0	0	00:01:02
9	*.pa.nsw.optusnet.com.au	166	0	0.0 KB	0	0	0	00:04:43
10	unresolved	4	0	0.0 KB	0	0	0	00:01:53
11	*.wroclaw.mm.pl	2	0	0.0 KB	0	0	0	00:16:47
12	*.toya.net.pl	98	0	0.0 KB	0	0	0	00:00:07
TOTAL: 12		1256	572	1.2 MB	336	6	2	15:53:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
160.206.15.62.static.jazztel.es	10.768	16.660	10:08:17
lze200.lze.lodz.pl	0.016	0.096	01:17:11
pc225.geo.uni.lodz.pl	0.016	0.040	01:12:54
pc26.zsk.p.lodz.pl	0.828	0.428	01:03:50
ip-176.105.135.22.tvsat364.lodz.pl	0.016	0.036	00:49:19
pc24.zsk.p.lodz.pl	0.244	0.168	00:30:58
host-89-231-50-15.wroclaw.mm.pl	0.000	0.024	00:16:47
radius2.p.lodz.pl	0.320	0.160	00:16:05
gate-ife.p.lodz.pl	0.000	0.028	00:09:10
pa49-181-246-172.pa.nsw.optusnet.com.au	0.412	1.568	00:04:43
router2.cbmm.lodz.pl	0.004	0.012	00:01:02
74.205.216.171	0.004	0.008	00:00:51
91.223.68.250	0.048	0.016	00:00:39
web.man.lodz.pl	0.588	2.892	00:00:24
212.51.203.199	0.004	0.020	00:00:21
pc-skrzep2.cm.p.lodz.pl	0.640	2.648	00:00:15
staticline41435.toya.net.pl	0.200	0.880	00:00:07
mon.man.lodz.pl	0.536	2.492	00:00:04
david.man.lodz.pl	0.168	0.816	00:00:01
isvcs01.man.lodz.pl	0.012	0.012	00:00:00
TOTAL: 21	14.824	29.012	15:53:06

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-246-172.pa.nsw.optusnet.com.au	166
staticline41435.toya.net.pl	98
david.man.lodz.pl	96
host-89-231-50-15.wroclaw.mm.pl	2
212.51.203.199	2
91.223.68.250	1
74.205.216.171	1
TOTAL: 8	654

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	2	0
pc225.geo.uni.lodz.pl	2	0
pc24.zsk.p.lodz.pl	1	0
pc26.zsk.p.lodz.pl	1	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 5	7	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	54.5%
2	pl	260	45.5%
TOTAL: 2		572	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	69
3	pl.comp.os.linux	46
4	pl.rec.rowery	43
5	pl.regionalne.lodz	34
6	pl.test	24
7	pl.rec.kuchnia	18
8	pl.misc.telefonia.gsm	16
9	pl.soc.prawo	6
10	pl.rec.dom	2
11	pl.comp.os.ms-windows.winnt	2
TOTAL: 11		572

Daily Usenet report

Apr 16 04:15:03 -- Apr 17 04:15:04