Daily Usenet report: Dec 11 04:15:03

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Dec 11 10:18:32 newsfeed nnrpd[22435]: SERVER perl filter not defined
Dec 11 11:44:59 newsfeed nnrpd[25283]: SERVER perl filter not defined
Dec 11 13:44:12 newsfeed nnrpd[29215]: SERVER perl filter not defined
Dec 11 14:25:02 newsfeed nnrpd[30581]: SERVER perl filter not defined
Dec 11 14:25:28 newsfeed nnrpd[30596]: SERVER perl filter not defined
Dec 11 14:25:55 newsfeed nnrpd[30597]: SERVER perl filter not defined
Dec 11 14:26:37 newsfeed nnrpd[30612]: SERVER perl filter not defined
Dec 11 14:28:53 newsfeed nnrpd[30631]: SERVER perl filter not defined
Dec 11 14:29:23 newsfeed nnrpd[30634]: SERVER perl filter not defined
Dec 11 14:30:44 newsfeed nnrpd[30832]: SERVER perl filter not defined
Dec 11 14:31:14 newsfeed nnrpd[30846]: SERVER perl filter not defined
Dec 11 14:32:01 newsfeed nnrpd[30850]: SERVER perl filter not defined
Dec 11 14:32:24 newsfeed nnrpd[30856]: SERVER perl filter not defined
Dec 11 14:32:54 newsfeed nnrpd[30858]: SERVER perl filter not defined
Dec 12 00:52:44 newsfeed overchan[2210]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1997880	98.2%	225.5 MB	97.8%
innd	18417	0.9%	3.0 MB	1.3%
nnrpd	10403	0.5%	1.1 MB	0.5%
innfeed	7043	0.3%	1.1 MB	0.5%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	2033744	100.0%	230.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1990173	93.6%
Negative hits	91671	4.3%
Positive hits	44987	2.1%
Cache misses	294	0.0%
TOTAL: 4	2127125	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:27.961	0.0%	1096	0.000	25.512	551.000
article cleanup	00:00:34.872	0.0%	1996842	0.007	0.017	0.030
article logging	00:01:04.243	0.1%	1997000	0.015	0.032	0.564
article parse	01:19:25.234	5.5%	150349781	0.010	0.032	0.081
article write	00:18:33.494	1.3%	1988839	0.348	0.560	1.086
artlog/artcncl	00:00:00.024	0.0%	1072	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:02:57.740	0.2%	163251267	0.000	0.001	0.005
hisgrep/artcncl	00:00:01.527	0.0%	293	0.000	5.212	237.000
hishave/artcncl	00:00:08.053	0.0%	1096	0.000	7.348	164.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:46:15.444	15.7%	2127891	0.011	6.380	45.556
history sync	00:00:00.011	0.0%	293	0.000	0.038	2.000
history write	04:47:30.691	20.0%	1990871	0.819	8.665	80.153
hiswrite/artcncl	00:00:08.314	0.0%	803	0.000	10.354	86.333
idle	06:54:06.860	28.8%	83983963	0.162	0.296	2.604
nntp read	01:00:17.908	4.2%	148792401	0.010	0.024	0.039
overview write	00:00:12.968	0.0%	1988839	0.000	0.007	0.016
perl filter	05:07:13.023	21.3%	1990867	5.940	9.259	10.691
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:06.773	0.3%	4022135	0.032	0.061	0.102
TOTAL: 24:00:04.081	23:23:05.140	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.167	0.0%	44456	0.000	0.004	0.100
article prepare	00:00:00.017	0.0%	69432	0.000	0.000	0.062
article read	00:00:02.261	0.0%	16587	0.000	0.136	26.344
backlog stats	00:00:00.279	0.0%	328152	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:02.571	0.0%	198462	0.000	0.013	2.000
data write	00:00:07.077	0.0%	166418	0.000	0.043	32.000
idle	23:59:31.805	100.0%	328152	0.000	263.207	13065.000
status file	00:00:00.549	0.0%	233	0.000	2.356	33.000
TOTAL: 23:59:53.164	23:59:44.726	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	52:22:09.377	100.0%	6783	0.000	27794.394	450488.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.433	0.0%	15997	0.000	0.027	1.333
readart	00:00:02.789	0.0%	553	0.000	5.043	269.000
TOTAL: 52:22:58.433	52:22:12.599	100.0%	-	-	-	-

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	1102	1981868	1974367	2430	5071	99%	435:14:44
2	news.nask.pl	4	19122	7216	10338	1568	37%	24:01:25
3	news.task.gda.pl	15	33905	6686	25895	1324	19%	46:15:31
4	localhost	15	14	14	0	0	100%	00:00:00
5	news.icm.edu.pl	46	219	12	191	16	5%	16:56:12
TOTAL: 5		1182	2035128	1988295	38854	7979	97%	522:27:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	723.2 GB	23.5 MB	33.8 MB	723.2 GB	99%	383.1 KB
2	news.nask.pl	35.0 MB	5.0 MB	5.2 MB	45.2 MB	77%	5.3 KB
3	news.task.gda.pl	14.7 MB	72.5 KB	4.1 MB	18.8 MB	77%	2.4 KB
4	news.icm.edu.pl	468.8 KB	0.0 KB	414.8 KB	883.7 KB	53%	31.6 KB
5	localhost	21.4 KB	0.0 KB	0.0 KB	21.4 KB	100%	1.5 KB
TOTAL: 5		723.2 GB	28.6 MB	43.5 MB	723.3 GB	99%	379.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 11 04:15:03 - 04:59:59	53795	2.7%	19.95	20.9 GB	2.9%	8115.71
Dec 11 05:00:00 - 05:59:59	66307	3.3%	18.42	26.3 GB	3.6%	7671.71
Dec 11 06:00:00 - 06:59:59	75763	3.8%	21.05	30.3 GB	4.2%	8831.50
Dec 11 07:00:00 - 07:59:59	79607	4.0%	22.11	30.4 GB	4.2%	8843.87
Dec 11 08:00:00 - 08:59:59	77844	3.9%	21.62	30.1 GB	4.2%	8765.18
Dec 11 09:00:00 - 09:59:59	68128	3.4%	18.92	25.4 GB	3.5%	7408.62
Dec 11 10:00:00 - 10:59:59	92614	4.7%	25.73	33.3 GB	4.6%	9711.89
Dec 11 11:00:00 - 11:59:59	96794	4.9%	26.89	34.0 GB	4.7%	9898.73
Dec 11 12:00:00 - 12:59:59	94712	4.8%	26.31	33.5 GB	4.6%	9763.64
Dec 11 13:00:00 - 13:59:59	80104	4.0%	22.25	28.7 GB	4.0%	8350.00
Dec 11 14:00:00 - 14:59:59	85949	4.3%	23.87	30.4 GB	4.2%	8852.89
Dec 11 15:00:00 - 15:59:59	98051	4.9%	27.24	34.9 GB	4.8%	10170.10
Dec 11 16:00:00 - 16:59:59	90649	4.6%	25.18	34.7 GB	4.8%	10102.48
Dec 11 17:00:00 - 17:59:59	94235	4.7%	26.18	34.2 GB	4.7%	9959.77
Dec 11 18:00:00 - 18:59:59	97572	4.9%	27.10	34.8 GB	4.8%	10123.93
Dec 11 19:00:00 - 19:59:59	92103	4.6%	25.58	34.3 GB	4.7%	9991.83
Dec 11 20:00:00 - 20:59:59	98376	4.9%	27.33	34.8 GB	4.8%	10145.70
Dec 11 21:00:00 - 21:59:59	97554	4.9%	27.10	33.6 GB	4.6%	9780.24
Dec 11 22:00:00 - 22:59:59	89161	4.5%	24.77	32.0 GB	4.4%	9308.00
Dec 11 23:00:00 - 23:59:59	64835	3.3%	18.01	23.3 GB	3.2%	6791.47
Dec 12 00:00:00 - 00:59:59	67167	3.4%	18.66	23.3 GB	3.2%	6783.83
Dec 12 01:00:00 - 01:59:59	68093	3.4%	18.91	23.7 GB	3.3%	6912.03
Dec 12 02:00:00 - 02:59:59	77434	3.9%	21.51	27.2 GB	3.8%	7935.05
Dec 12 03:00:00 - 03:59:59	67758	3.4%	18.82	23.4 GB	3.2%	6826.13
Dec 12 04:00:00 - 04:15:03	14041	0.7%	15.55	5.2 GB	0.7%	6008.87
TOTAL: 24:00:00	1988646	100.0%	23.02	722.7 GB	100.0%	8771.49

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5193	198	4378	1	616
2	news.nask.pl	1603	7	1553	1	42
3	news.task.gda.pl	1350	1097	41	0	212
4	news.icm.edu.pl	16	0	0	0	16
TOTAL: 4		8162	1302	5972	2	886

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	108
mailing.freebsd.svn-ports-head	108
uk.railway	74
mozilla.dev.tree-management	55
it.tlc.cellulari.android	53
mailing.freebsd.ports-bugs	50
alt.politics.scorched-earth	45
mailing.unix.samba-technical	42
alt.windows7.general	41
mailing.database.pgsql-general	39
mailing.postfix.users	37
mailing.gcc.gcc-cvs	36
fr.usenet.abus.nocem	35
mailing.freebsd.svn-src-all	34
mailing.unix.samba	33
mailing.openbsd.misc	33
fa.freebsd.cvs-all	32
mailing.openbsd.ports-changes	31
mailing.openbsd.source-changes	31
mailing.unix.dovecot	28
TOTAL: 74	1302

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	509
EMP (fsl)	164
EMP (md5)	28
HTML post	20
Attached HTML file	3
TOTAL: 5	724

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	62077	7887	30324	5776	12	0	12%	24:00:48
2	panorama	46071	2980	40901	487	0	0	6%	23:53:42
3	task	2135	149	1062	5	0	0	6%	23:17:34
4	poznan	16229	53	13612	31	1	0	0%	23:56:28
5	nask	19776	10	14069	0	0	0	0%	23:58:21
6	okapi	0	0	0	0	0	2707	0%	24:00:00
TOTAL: 6		146288	11079	99968	6299	13	2707	7%	143:06:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	25.7 MB	903.0 MB	928.7 MB	11.0 KB/s	69.6 KB	24:00:48
2	panorama	15.9 MB	4.7 MB	20.6 MB	0.2 KB/s	6.1 KB	23:53:42
3	poznan	810.2 KB	52.2 KB	862.5 KB	0.0 KB/s	10.3 KB	23:56:28
4	task	353.7 KB	7.3 KB	361.0 KB	0.0 KB/s	2.3 KB	23:17:34
5	nask	68.3 KB	0.0 KB	68.3 KB	0.0 KB/s	6.8 KB	23:58:21
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		42.8 MB	907.7 MB	950.5 MB	1.9 KB/s	56.0 KB	143:06:53

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	366	538.3 KB	288	0	0	00:00:15
2	web.man.lodz.pl	288	94	85.4 KB	47	0	0	00:00:30
3	lze200.lze.lodz.pl	4	65	118.0 KB	6	0	0	00:22:44
4	pc26.zsk.p.lodz.pl	5	24	28.1 KB	4	0	0	00:07:54
5	ip-176.105.135.22.tvsat364.lodz.pl	11	4	6.3 KB	4	0	0	02:57:59
6	staticline-31-182-204-10.toya.net.pl	5	0	0.0 KB	0	0	3	00:28:00
7	kujawiak.man.lodz.pl	14	0	0.0 KB	0	14	0	00:00:02
TOTAL: 7		615	553	776.0 KB	349	14	3	52:22:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	366	538.3 KB	288	0	0	00:00:15
2	*.man.lodz.pl	686	94	85.4 KB	47	14	0	00:00:40
3	*.lze.lodz.pl	4	65	118.0 KB	6	0	0	00:22:44
4	*.zsk.p.lodz.pl	5	24	28.1 KB	4	0	0	00:07:54
5	*.105.135.22.tvsat364.lodz.pl	11	4	6.3 KB	4	0	0	02:57:59
6	*.sieradz.vectranet.pl	2	0	0.0 KB	0	0	0	00:00:29
7	unresolved	288	0	0.0 KB	0	0	0	48:07:30
8	*.cpe.cableone.net	1	0	0.0 KB	0	0	0	00:05:03
9	*.adsl.inetia.pl	1	0	0.0 KB	0	0	0	00:08:42
10	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	0	00:03:20
11	*.181.14.62.static.jazztel.es	2	0	0.0 KB	0	0	0	00:00:10
12	*.toya.net.pl	96	0	0.0 KB	0	0	3	00:28:07
TOTAL: 12		1386	553	776.0 KB	349	14	3	52:22:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.656	3.940	48:07:30
ip-176.105.135.22.tvsat364.lodz.pl	0.044	0.156	02:57:59
staticline-31-182-204-10.toya.net.pl	0.028	0.080	00:28:00
lze200.lze.lodz.pl	0.032	0.076	00:22:44
159-205-134-184.adsl.inetia.pl	0.036	0.024	00:08:42
pc26.zsk.p.lodz.pl	0.016	0.068	00:07:54
174-126-87-136.cpe.cableone.net	0.040	0.016	00:05:03
ecg181.neoplus.adsl.tpnet.pl	0.004	0.016	00:01:59
ebu48.neoplus.adsl.tpnet.pl	0.000	0.016	00:01:20
web.man.lodz.pl	0.688	4.156	00:00:30
078088068228.sieradz.vectranet.pl	0.004	0.012	00:00:29
vegas.cm.p.lodz.pl	0.628	4.128	00:00:15
240.181.14.62.static.jazztel.es	0.000	0.012	00:00:10
staticline41435.toya.net.pl	0.192	1.240	00:00:07
mon.man.lodz.pl	0.728	3.736	00:00:07
kujawiak.man.lodz.pl	0.076	0.224	00:00:02
david.man.lodz.pl	0.184	1.260	00:00:01
TOTAL: 17	3.356	19.160	52:22:57

Curious NNRP server explorers [Top 20]:

System	Conn
212.191.78.190	288
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	91
078088068228.sieradz.vectranet.pl	2
240.181.14.62.static.jazztel.es	2
ebu48.neoplus.adsl.tpnet.pl	1
174-126-87-136.cpe.cableone.net	1
159-205-134-184.adsl.inetia.pl	1
ecg181.neoplus.adsl.tpnet.pl	1
TOTAL: 10	771

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	3	0
TOTAL: 1	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	366	66.2%
2	pl	187	33.8%
TOTAL: 2		553	100.0%

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

Newsgroup		Count
1	lodman.info	366
2	pl.comp.pecet	65
3	pl.comp.os.ms-windows.winnt	40
4	pl.test	24
5	pl.comp.os.linux	18
6	pl.rec.kuchnia	17
7	pl.rec.dom	8
8	pl.misc.telefonia.gsm	7
9	pl.internet.polip	4
10	pl.misc.dieta	2
11	pl.regionalne.lodz	2
TOTAL: 11		553

Daily Usenet report

Dec 11 04:15:03 -- Dec 12 04:15:03