Daily Usenet report: Nov 6 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Nov 6 11:29:26 newsfeed nnrpd[19301]: SERVER perl filter not defined
Nov 6 11:29:39 newsfeed nnrpd[19278]: SERVER perl filter not defined
Nov 6 12:47:20 newsfeed nnrpd[23024]: SERVER perl filter not defined
Nov 6 12:47:20 newsfeed nnrpd[23038]: SERVER perl filter not defined
Nov 6 13:13:26 newsfeed nnrpd[23959]: SERVER perl filter not defined
Nov 6 13:32:27 newsfeed nnrpd[24379]: SERVER perl filter not defined
Nov 6 15:41:21 newsfeed nnrpd[28741]: SERVER perl filter not defined
Nov 6 15:49:24 newsfeed nnrpd[29011]: SERVER perl filter not defined
Nov 6 16:47:09 newsfeed nnrpd[30947]: SERVER perl filter not defined
Nov 6 16:53:56 newsfeed nnrpd[31202]: SERVER perl filter not defined
Nov 6 22:10:09 newsfeed overchan[2136]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1823307	98.2%	204.3 MB	97.7%
innd	15862	0.9%	2.7 MB	1.3%
nnrpd	10507	0.6%	1.1 MB	0.5%
innfeed	7066	0.4%	1.1 MB	0.5%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	1856743	100.0%	209.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1816788	92.5%
Negative hits	84708	4.3%
Positive hits	38615	2.0%
Cache misses	24007	1.2%
TOTAL: 4	1964118	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.114	0.0%	297	0.000	37.421	695.000
article cleanup	00:00:22.276	0.0%	1822128	0.003	0.012	0.026
article logging	00:00:50.385	0.1%	1822291	0.006	0.028	0.430
article parse	00:58:16.721	4.0%	56576493	0.008	0.062	0.092
article write	00:17:01.672	1.2%	1815123	0.365	0.563	1.344
artlog/artcncl	00:00:00.004	0.0%	290	0.000	0.014	1.000
artlog/artparse	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:01:33.462	0.1%	67730828	0.000	0.001	0.005
hisgrep/artcncl	00:00:01.152	0.0%	241	0.000	4.780	152.000
hishave/artcncl	00:00:00.703	0.0%	297	0.000	2.367	15.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:24:17.075	14.2%	1965074	0.010	6.237	75.313
history sync	00:00:00.026	0.0%	292	0.000	0.089	4.000
history write	04:54:45.222	20.5%	1817698	0.512	9.729	85.027
hiswrite/artcncl	00:00:00.392	0.0%	56	0.000	7.000	62.000
idle	08:34:25.786	35.7%	26934283	0.178	1.146	4.162
nntp read	00:29:41.731	2.1%	55205100	0.009	0.032	0.042
overview write	00:00:11.033	0.0%	1815123	0.000	0.006	0.017
perl filter	04:56:14.277	20.6%	1817691	4.089	9.778	11.935
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:27.831	0.2%	3670462	0.029	0.057	0.083
TOTAL: 23:59:24.373	23:41:20.862	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.173	0.0%	40235	0.000	0.004	1.000
article prepare	00:00:00.007	0.0%	31222	0.000	0.000	0.250
article read	00:00:00.715	0.0%	7490	0.000	0.095	2.000
backlog stats	00:00:00.214	0.0%	294454	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.093	0.0%	176291	0.000	0.012	1.000
data write	00:00:05.348	0.0%	148393	0.000	0.036	2.333
idle	24:00:20.939	100.0%	294454	0.000	293.496	13061.000
status file	00:00:01.898	0.0%	222	0.000	8.550	127.667
TOTAL: 24:00:38.006	24:00:31.387	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	62:53:15.610	100.0%	260570	0.000	868.848	450121.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:37.541	0.0%	946619	0.000	0.040	70.122
readart	00:00:02.470	0.0%	633	0.000	3.902	43.250
TOTAL: 62:55:02.879	62:53:55.621	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	618	1832903	1802361	26327	4215	98%	407:56:44
2	news.task.gda.pl	10	30764	6987	22095	1682	22%	44:53:25
3	news.nask.pl	2	16599	6321	9145	1133	38%	24:19:24
4	localhost	12	11	11	0	0	100%	00:00:00
5	news.icm.edu.pl	44	319	10	282	27	3%	18:54:26
TOTAL: 5		686	1880596	1815690	57849	7057	96%	496:03:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	689.3 GB	33.2 MB	67.9 MB	689.4 GB	99%	400.2 KB
2	news.nask.pl	27.9 MB	2.7 MB	2.8 MB	33.4 MB	83%	4.6 KB
3	news.task.gda.pl	14.7 MB	28.4 KB	5.3 MB	20.0 MB	73%	2.4 KB
4	news.icm.edu.pl	638.0 KB	0.0 KB	470.1 KB	1.1 MB	57%	29.9 KB
5	localhost	10.6 KB	0.0 KB	0.0 KB	10.6 KB	100%	1.0 KB
TOTAL: 5		689.4 GB	36.0 MB	76.5 MB	689.5 GB	99%	396.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 6 04:15:03 - 04:59:59	64451	3.5%	23.90	24.7 GB	3.6%	9614.49
Nov 6 05:00:00 - 05:59:59	83528	4.6%	23.20	32.0 GB	4.6%	9317.22
Nov 6 06:00:00 - 06:59:59	87127	4.8%	24.20	32.2 GB	4.7%	9386.32
Nov 6 07:00:00 - 07:59:59	59563	3.3%	16.55	22.4 GB	3.2%	6514.57
Nov 6 08:00:00 - 08:59:59	52347	2.9%	14.54	20.2 GB	2.9%	5898.15
Nov 6 09:00:00 - 09:59:59	53543	2.9%	14.87	19.7 GB	2.9%	5748.81
Nov 6 10:00:00 - 10:59:59	64605	3.6%	17.95	24.5 GB	3.6%	7140.05
Nov 6 11:00:00 - 11:59:59	71648	3.9%	19.90	26.5 GB	3.8%	7709.75
Nov 6 12:00:00 - 12:59:59	86347	4.8%	23.99	31.2 GB	4.5%	9094.89
Nov 6 13:00:00 - 13:59:59	84484	4.7%	23.47	30.7 GB	4.5%	8954.29
Nov 6 14:00:00 - 14:59:59	67267	3.7%	18.69	24.0 GB	3.5%	6987.04
Nov 6 15:00:00 - 15:59:59	54661	3.0%	15.18	20.4 GB	3.0%	5944.57
Nov 6 16:00:00 - 16:59:59	68619	3.8%	19.06	26.7 GB	3.9%	7786.14
Nov 6 17:00:00 - 17:59:59	83794	4.6%	23.28	31.6 GB	4.6%	9205.27
Nov 6 18:00:00 - 18:59:59	92599	5.1%	25.72	34.0 GB	4.9%	9910.77
Nov 6 19:00:00 - 19:59:59	90888	5.0%	25.25	32.8 GB	4.8%	9543.91
Nov 6 20:00:00 - 20:59:59	84614	4.7%	23.50	31.4 GB	4.6%	9138.41
Nov 6 21:00:00 - 21:59:59	80104	4.4%	22.25	30.6 GB	4.4%	8919.62
Nov 6 22:00:00 - 22:59:59	80001	4.4%	22.22	31.8 GB	4.6%	9248.98
Nov 6 23:00:00 - 23:59:59	81088	4.5%	22.52	33.1 GB	4.8%	9649.07
Nov 7 00:00:00 - 00:59:59	71435	3.9%	19.84	28.8 GB	4.2%	8395.93
Nov 7 01:00:00 - 01:59:59	88296	4.9%	24.53	34.5 GB	5.0%	10047.86
Nov 7 02:00:00 - 02:59:59	69954	3.9%	19.43	27.6 GB	4.0%	8030.18
Nov 7 03:00:00 - 03:59:59	77262	4.3%	21.46	30.7 GB	4.5%	8945.77
Nov 7 04:00:00 - 04:15:03	17613	1.0%	19.50	6.8 GB	1.0%	7912.19
TOTAL: 24:00:00	1815838	100.0%	21.02	689.1 GB	100.0%	8362.71

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4336	188	3366	0	782
2	news.task.gda.pl	1678	1452	15	3	208
3	news.nask.pl	1139	18	1059	2	60
4	news.icm.edu.pl	26	0	0	0	26
TOTAL: 4		7179	1658	4440	5	1076

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	153
mailing.freebsd.svn-ports-head	152
mailing.freebsd.current	81
gmane.linux.alsa.devel	73
mailing.freebsd.ports-bugs	72
alt.windows7.general	62
uk.railway	58
mailing.gcc.gcc-cvs	57
fa.freebsd.cvs-all	56
mailing.freebsd.svn-src-all	54
mailing.freebsd.svn-src-head	49
mailing.openbsd.source-changes	46
mozilla.dev.tree-management	46
it.tlc.cellulari.android	39
mailing.freebsd.ports	35
mailing.freebsd.tinderbox	34
mailing.openbsd.misc	33
fr.comp.os.android	32
alt.politics.scorched-earth	32
mailing.freebsd.kde	27
TOTAL: 78	1658

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

Groups	Count
fr.comp.lang.php	2
pl.sci.historia	2
fr.comp.sys.mac.annonces	1
TOTAL: 3	5

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	544
EMP (fsl)	176
Attached HTML file	91
EMP (md5)	51
HTML post	42
Scoring filter (8)	1
TOTAL: 6	905

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	41360	3587	35699	1041	0	0	8%	24:03:50
2	icm	60386	842	34904	2120	3	0	1%	23:57:29
3	task	1806	78	915	4	0	0	4%	21:14:46
4	poznan	15451	68	12943	29	0	0	0%	23:51:48
5	nask	17260	28	12683	1	0	0	0%	24:06:45
6	okapi	0	0	0	0	0	2544	0%	24:00:00
TOTAL: 6		136263	4603	97144	3195	3	2544	3%	141:14:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	20.1 MB	633.9 MB	653.9 MB	7.8 KB/s	226.1 KB	23:57:29
2	panorama	13.9 MB	4.7 MB	18.6 MB	0.2 KB/s	4.1 KB	24:03:50
3	poznan	330.7 KB	69.1 KB	399.8 KB	0.0 KB/s	4.1 KB	23:51:48
4	task	173.1 KB	6.4 KB	179.5 KB	0.0 KB/s	2.2 KB	21:14:46
5	nask	49.6 KB	1.7 KB	51.3 KB	0.0 KB/s	1.8 KB	24:06:45
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		34.5 MB	638.7 MB	673.2 MB	1.4 KB/s	88.4 KB	141:14:38

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	Elapsed
1	vegas.cm.p.lodz.pl	288	324	498.7 KB	288	0	00:00:11
2	212.191.78.190	301	102	200.9 KB	38	0	48:18:06
3	web.man.lodz.pl	288	88	79.2 KB	37	0	00:00:20
4	pc24.zsk.p.lodz.pl	13	40	34.5 KB	10	8	00:40:57
5	lze200.lze.lodz.pl	8	38	71.2 KB	7	0	01:41:20
6	pc26.zsk.p.lodz.pl	3	32	36.2 KB	3	0	00:14:02
7	pc-vicki.man.lodz.pl	1	8	103.0 KB	1	0	00:30:43
8	ip-176.105.135.22.tvsat364.lodz.pl	2	1	1.4 KB	1	0	00:25:40
9	kujawiak.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 9		907	633	1.0 MB	385	11	62:55:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	324	498.7 KB	288	0	00:00:11
2	unresolved	301	102	200.9 KB	38	0	48:18:06
3	*.man.lodz.pl	677	96	182.2 KB	38	3	01:01:08
4	*.zsk.p.lodz.pl	16	72	70.7 KB	13	8	00:54:59
5	*.lze.lodz.pl	8	38	71.2 KB	7	0	01:41:20
6	*.105.135.22.tvsat364.lodz.pl	2	1	1.4 KB	1	0	00:25:40
7	*.191.14.62.static.jazztel.es	1	0	0.0 KB	0	0	10:28:14
8	*.neoplus.adsl.tpnet.pl	7	0	0.0 KB	0	0	00:05:14
9	*.toya.net.pl	73	0	0.0 KB	0	0	00:00:05
TOTAL: 9		1373	633	1.0 MB	385	11	62:55:01

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.736	4.960	48:18:06
16.191.14.62.static.jazztel.es	13.164	23.152	10:28:14
lze200.lze.lodz.pl	0.016	0.160	01:41:20
pc24.zsk.p.lodz.pl	2.020	1.104	00:40:57
pc-vicki.man.lodz.pl	0.000	0.024	00:30:43
vpn102.man.lodz.pl	0.004	0.016	00:30:00
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.040	00:25:40
pc26.zsk.p.lodz.pl	0.244	0.160	00:14:02
afsz63.neoplus.adsl.tpnet.pl	0.052	0.148	00:05:14
web.man.lodz.pl	0.684	4.620	00:00:20
vegas.cm.p.lodz.pl	0.664	4.900	00:00:11
staticline41435.toya.net.pl	0.188	1.220	00:00:05
f-oper-v100.man.lodz.pl	0.560	4.256	00:00:02
david.man.lodz.pl	0.200	1.612	00:00:01
kujawiak.man.lodz.pl	0.020	0.052	00:00:00
TOTAL: 15	18.556	46.424	62:55:01

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	73
afsz63.neoplus.adsl.tpnet.pl	7
vpn102.man.lodz.pl	1
16.191.14.62.static.jazztel.es	1
TOTAL: 6	466

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	3	0
212.191.78.190	3	0
vpn102.man.lodz.pl	1	0
pc-vicki.man.lodz.pl	1	0
16.191.14.62.static.jazztel.es	1	1
TOTAL: 5	9	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	324	51.2%
2	pl	309	48.8%
TOTAL: 2		633	100.0%

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

Newsgroup		Count
1	lodman.info	324
2	pl.test	72
3	pl.comp.pecet	64
4	pl.soc.prawo	45
5	pl.misc.kolej	31
6	pl.comp.os.ms-windows.winnt	24
7	pl.rec.dom	18
8	pl.rec.kuchnia	14
9	pl.regionalne.warszawa	10
10	pl.sci.medycyna	8
11	pl.rec.rowery	8
12	pl.comp.os.linux	6
13	pl.rec.gory	3
14	pl.misc.telefonia.gsm	3
15	pl.regionalne.lodz	3
TOTAL: 15		633

Daily Usenet report

Nov 6 04:15:03 -- Nov 7 04:15:03