Daily Usenet report: Nov 29 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Nov 29 12:31:56 newsfeed nnrpd[4826]: SERVER perl filter not defined
Nov 29 13:01:36 newsfeed nnrpd[5696]: SERVER perl filter not defined
Nov 29 13:07:27 newsfeed nnrpd[5903]: SERVER perl filter not defined
Nov 29 13:39:00 newsfeed nnrpd[6883]: SERVER perl filter not defined
Nov 29 14:54:28 newsfeed nnrpd[9426]: SERVER perl filter not defined
Nov 30 01:22:55 newsfeed overchan[2039]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2070002	98.1%	236.1 MB	97.6%
innd	18646	0.9%	3.1 MB	1.3%
innfeed	11739	0.6%	1.8 MB	0.7%
nnrpd	10212	0.5%	1.0 MB	0.4%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	2110600	100.0%	242.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2064549	95.0%
Negative hits	72977	3.4%
Positive hits	35650	1.6%
Cache misses	247	0.0%
TOTAL: 4	2173423	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:13.677	0.0%	298	0.000	45.896	828.000
article cleanup	00:00:35.938	0.0%	2069017	0.007	0.017	0.031
article logging	00:01:07.443	0.1%	2069129	0.015	0.033	0.443
article parse	01:15:50.871	5.3%	157602296	0.007	0.029	0.076
article write	00:17:20.430	1.2%	2063265	0.300	0.504	1.037
artlog/artcncl	00:00:00.006	0.0%	293	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:06:53.319	0.5%	170512525	0.001	0.002	0.100
hisgrep/artcncl	00:00:01.626	0.0%	237	0.000	6.861	228.000
hishave/artcncl	00:00:01.227	0.0%	298	0.000	4.117	282.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:42:59.878	11.3%	2174431	0.008	4.498	59.566
history sync	00:00:00.008	0.0%	291	0.000	0.027	1.000
history write	04:51:09.530	20.2%	2065587	1.125	8.457	37.094
hiswrite/artcncl	00:00:00.702	0.0%	61	0.000	11.508	72.333
idle	07:55:43.211	33.0%	93484712	0.142	0.305	16.285
nntp read	01:00:53.060	4.2%	155923291	0.011	0.023	0.056
overview write	00:00:12.950	0.0%	2063265	0.000	0.006	0.023
perl filter	05:06:40.600	21.3%	2065584	5.407	8.908	11.345
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:26.538	0.3%	4163861	0.029	0.064	0.095
TOTAL: 23:59:35.438	23:24:11.014	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.373	0.0%	125981	0.000	0.003	0.083
article prepare	00:00:00.031	0.0%	116282	0.000	0.000	0.250
article read	00:03:45.628	0.3%	28743	0.000	7.850	821.100
backlog stats	00:00:00.464	0.0%	501905	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	150	0.000	0.000	0.000
data read	00:00:03.386	0.0%	267500	0.000	0.013	1.000
data write	00:00:17.016	0.0%	260793	0.000	0.065	19.000
idle	23:55:52.855	99.7%	501905	0.000	171.652	12961.000
status file	00:00:02.722	0.0%	232	0.000	11.733	253.000
TOTAL: 24:00:14.945	24:00:02.475	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	80:33:36.049	99.9%	732491	0.000	395.931	553430.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:01:25.600	0.0%	1700925	0.000	0.050	100.900
readart	00:00:43.301	0.0%	4196	0.000	10.320	14.189
TOTAL: 80:37:06.415	80:35:44.950	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	968	2058045	2052419	2025	3601	99%	398:17:23
2	news.task.gda.pl	15	27630	6342	20114	1174	22%	43:12:57
3	news.nask.pl	2	14997	5179	8930	888	34%	24:46:53
4	localhost	6	5	5	0	0	100%	00:00:00
5	news.icm.edu.pl	37	326	1	223	102	0%	16:21:09
TOTAL: 5		1028	2101003	2063946	31292	5765	98%	482:38:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	678.6 GB	10.5 MB	105.8 MB	678.7 GB	99%	346.2 KB
2	news.nask.pl	17.8 MB	2.2 MB	2.4 MB	22.4 MB	79%	3.8 KB
3	news.task.gda.pl	12.6 MB	32.5 KB	3.5 MB	16.0 MB	78%	2.2 KB
4	news.icm.edu.pl	9.8 KB	0.0 KB	4.8 MB	4.8 MB	0%	47.3 KB
5	localhost	4.8 KB	0.0 KB	0.0 KB	4.8 KB	100%	1.0 KB
TOTAL: 5		678.7 GB	12.8 MB	116.5 MB	678.8 GB	99%	343.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 29 04:15:03 - 04:59:59	67131	3.3%	24.89	23.5 GB	3.5%	9122.79
Nov 29 05:00:00 - 05:59:59	87030	4.2%	24.18	30.2 GB	4.4%	8785.08
Nov 29 06:00:00 - 06:59:59	93348	4.5%	25.93	32.6 GB	4.8%	9494.67
Nov 29 07:00:00 - 07:59:59	97574	4.7%	27.10	33.6 GB	5.0%	9786.46
Nov 29 08:00:00 - 08:59:59	94498	4.6%	26.25	33.0 GB	4.9%	9604.84
Nov 29 09:00:00 - 09:59:59	98324	4.8%	27.31	33.0 GB	4.9%	9615.82
Nov 29 10:00:00 - 10:59:59	104752	5.1%	29.10	34.2 GB	5.0%	9966.75
Nov 29 11:00:00 - 11:59:59	103386	5.0%	28.72	30.1 GB	4.4%	8754.51
Nov 29 12:00:00 - 12:59:59	67857	3.3%	18.85	24.1 GB	3.6%	7013.70
Nov 29 13:00:00 - 13:59:59	68561	3.3%	19.04	24.8 GB	3.7%	7233.00
Nov 29 14:00:00 - 14:59:59	98591	4.8%	27.39	33.3 GB	4.9%	9696.80
Nov 29 15:00:00 - 15:59:59	94886	4.6%	26.36	33.1 GB	4.9%	9654.39
Nov 29 16:00:00 - 16:59:59	92197	4.5%	25.61	28.8 GB	4.3%	8399.54
Nov 29 17:00:00 - 17:59:59	104961	5.1%	29.16	26.8 GB	3.9%	7796.44
Nov 29 18:00:00 - 18:59:59	93702	4.5%	26.03	22.0 GB	3.2%	6404.19
Nov 29 19:00:00 - 19:59:59	90025	4.4%	25.01	30.5 GB	4.5%	8880.54
Nov 29 20:00:00 - 20:59:59	83951	4.1%	23.32	29.3 GB	4.3%	8522.40
Nov 29 21:00:00 - 21:59:59	80353	3.9%	22.32	28.0 GB	4.1%	8165.44
Nov 29 22:00:00 - 22:59:59	76398	3.7%	21.22	26.3 GB	3.9%	7654.99
Nov 29 23:00:00 - 23:59:59	71818	3.5%	19.95	24.7 GB	3.6%	7190.92
Nov 30 00:00:00 - 00:59:59	72437	3.5%	20.12	23.0 GB	3.4%	6684.79
Nov 30 01:00:00 - 01:59:59	68927	3.3%	19.15	21.7 GB	3.2%	6329.24
Nov 30 02:00:00 - 02:59:59	60423	2.9%	16.78	19.7 GB	2.9%	5737.56
Nov 30 03:00:00 - 03:59:59	72083	3.5%	20.02	24.6 GB	3.6%	7155.81
Nov 30 04:00:00 - 04:15:02	20629	1.0%	22.87	7.4 GB	1.1%	8547.43
TOTAL: 23:59:59	2063842	100.0%	23.89	678.1 GB	100.0%	8229.43

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3680	192	2613	4	871
2	news.task.gda.pl	1211	962	19	0	230
3	news.nask.pl	875	11	801	2	61
4	news.icm.edu.pl	101	0	0	0	101
TOTAL: 4		5867	1165	3433	6	1263

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	127
mailing.freebsd.svn-ports-head	127
uk.railway	76
gnu.hurd.bug	75
gmane.linux.alsa.devel	64
mailing.freebsd.ports-bugs	42
mailing.gcc.gcc-cvs	40
it.tlc.cellulari.android	39
alt.windows7.general	34
mailing.freebsd.svn-src-all	29
fa.freebsd.cvs-all	29
mailing.unix.samba-technical	27
mailing.openbsd.misc	27
fr.rec.loisirs-creatifs	25
it.comp.os.win.windows7	24
mailing.freebsd.svn-src-head	24
fr.usenet.abus.nocem	24
mailing.freebsd.current	23
mailing.openbsd.ports-changes	20
mozilla.dev.tree-management	18
TOTAL: 77	1165

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	720
EMP (fsl)	196
EMP (md5)	139
Attached HTML file	76
EMP (phl)	9
Scoring filter (9)	4
HTML post	4
TOTAL: 7	1148

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	141253	6450	99641	18621	165	6	4%	23:59:57
2	panorama	38306	2937	33626	819	0	0	7%	24:05:58
3	poznan	13099	103	11194	36	0	0	0%	24:01:59
4	task	2279	75	1186	1	0	0	3%	21:51:19
5	nask	16721	9	11977	1	0	0	0%	23:56:31
6	okapi	0	0	0	0	0	2491	0%	24:00:00
TOTAL: 6		211658	9574	157624	19478	165	2497	4%	141:55:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	149.9 MB	4.0 GB	4.1 GB	49.7 KB/s	171.3 KB	23:59:57
2	panorama	8.3 MB	3.2 MB	11.5 MB	0.1 KB/s	3.1 KB	24:05:58
3	poznan	376.4 KB	69.7 KB	446.1 KB	0.0 KB/s	3.2 KB	24:01:59
4	task	161.3 KB	1.9 KB	163.2 KB	0.0 KB/s	2.1 KB	21:51:19
5	nask	13.8 KB	1.3 KB	15.1 KB	0.0 KB/s	1.5 KB	23:56:31
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		158.7 MB	4.0 GB	4.1 GB	8.4 KB/s	148.3 KB	141:55:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	212.191.130.195	3	3777	5.8 MB	3	0	00:01:09
2	vegas.cm.p.lodz.pl	288	300	545.9 KB	288	0	00:00:11
3	web.man.lodz.pl	288	88	95.3 KB	41	0	00:00:20
4	ip-176.105.135.22.tvsat364.lodz.pl	3	29	59.2 KB	6	0	00:53:28
5	pc-212-51-221-162.p.lodz.pl	2	1	0.7 KB	1	1	00:06:57
6	212.51.203.199	2	1	1.5 KB	1	0	00:37:59
7	kujawiak.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
8	pc26.zsk.p.lodz.pl	6	0	0.0 KB	0	3	01:37:32
TOTAL: 8		593	4196	6.5 MB	340	5	80:37:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	291	3778	5.8 MB	4	0	48:32:21
2	*.cm.p.lodz.pl	288	300	545.9 KB	288	0	00:00:11
3	*.man.lodz.pl	673	88	95.3 KB	41	1	00:00:28
4	*.105.135.22.tvsat364.lodz.pl	3	29	59.2 KB	6	0	00:53:28
5	*.p.lodz.pl	2	1	0.7 KB	1	1	00:06:57
6	*.207.15.62.static.jazztel.es	1	0	0.0 KB	0	0	16:33:55
7	*.zsk.p.lodz.pl	6	0	0.0 KB	0	3	01:37:32
8	*.191.14.62.static.jazztel.es	1	0	0.0 KB	0	0	11:31:02
9	*.lodz.mm.pl	8	0	0.0 KB	0	0	01:20:59
10	*.toya.net.pl	90	0	0.0 KB	0	0	00:00:07
TOTAL: 10		1363	4196	6.5 MB	340	5	80:37:03

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.728	3.584	47:53:12
224.207.15.62.static.jazztel.es	23.848	34.976	16:33:55
178.191.14.62.static.jazztel.es	16.768	26.760	11:04:01
pc26.zsk.p.lodz.pl	0.876	0.340	01:37:32
host-89-228-165-199.lodz.mm.pl	0.032	0.076	01:20:59
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.052	00:53:28
212.51.203.199	0.004	0.028	00:37:59
165.191.14.62.static.jazztel.es	0.316	0.560	00:27:00
pc-212-51-221-162.p.lodz.pl	0.288	0.148	00:06:57
212.191.130.195	0.252	0.980	00:01:09
web.man.lodz.pl	0.464	2.792	00:00:20
vegas.cm.p.lodz.pl	0.404	3.032	00:00:11
staticline41435.toya.net.pl	0.188	1.168	00:00:07
mon.man.lodz.pl	0.672	3.468	00:00:05
david.man.lodz.pl	0.176	1.160	00:00:01
kujawiak.man.lodz.pl	0.004	0.016	00:00:00
TOTAL: 16	45.028	79.140	80:37:03

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.191.78.190	286
david.man.lodz.pl	96
staticline41435.toya.net.pl	90
host-89-228-165-199.lodz.mm.pl	8
224.207.15.62.static.jazztel.es	1
165.191.14.62.static.jazztel.es	1
TOTAL: 7	770

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc26.zsk.p.lodz.pl	3	0
224.207.15.62.static.jazztel.es	1	1
212.51.203.199	1	0
165.191.14.62.static.jazztel.es	1	0
178.191.14.62.static.jazztel.es	1	0
TOTAL: 5	7	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	4077	97.2%
2	pl	119	2.8%
TOTAL: 2		4196	100.0%

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

Newsgroup		Count
1	lodman.info	4077
2	pl.comp.os.ms-windows.winnt	48
3	pl.comp.pecet	40
4	pl.soc.dzieci	24
5	pl.regionalne.lodz	4
6	pl.test	1
7	pl.misc.dieta	1
8	pl.rec.radio.amatorskie	1
TOTAL: 8		4196

Daily Usenet report

Nov 29 04:15:03 -- Nov 30 04:15:02