Daily Usenet report: Mar 17 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Mar 17 06:04:17 newsfeed nnrpd[30400]: SERVER perl filter not defined
Mar 17 09:35:26 newsfeed nnrpd[5395]: SERVER perl filter not defined
Mar 17 10:55:16 newsfeed nnrpd[8358]: SERVER perl filter not defined
Mar 17 11:15:44 newsfeed nnrpd[9048]: SERVER perl filter not defined
Mar 17 13:10:02 newsfeed pgpverify[12799]: pgpverify: verification failed
Mar 17 22:01:21 newsfeed nnrpd[30588]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1457144	98.1%	167.1 MB	97.6%
innd	13798	0.9%	2.3 MB	1.4%
nnrpd	7117	0.5%	735.9 KB	0.4%
innfeed	7078	0.5%	1.1 MB	0.6%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1485141	100.0%	171.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1451933	94.9%
Negative hits	39120	2.6%
Positive hits	38124	2.5%
Cache misses	153	0.0%
TOTAL: 4	1529330	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.554	0.0%	223	0.000	51.812	680.000
article cleanup	00:00:23.896	0.0%	1457507	0.000	0.016	0.035
article logging	00:00:50.223	0.1%	1457693	0.014	0.034	0.415
article parse	00:51:56.746	3.6%	174576461	0.005	0.018	0.090
article write	00:13:35.044	0.9%	1452358	0.268	0.561	2.014
artlog/artcncl	00:00:00.009	0.0%	209	0.000	0.043	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:02:22.096	0.2%	184455603	0.000	0.001	0.026
hisgrep/artcncl	00:00:01.656	0.0%	159	0.000	10.415	219.000
hishave/artcncl	00:00:00.607	0.0%	223	0.000	2.722	53.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:43:21.638	7.2%	1531780	0.016	4.049	8.582
history sync	00:00:00.020	0.0%	290	0.000	0.069	2.000
history write	03:35:58.836	15.0%	1454243	0.114	8.911	33.618
hiswrite/artcncl	00:00:00.928	0.0%	64	0.000	14.500	315.000
idle	12:25:00.262	51.7%	142061501	0.091	0.315	1.122
nntp read	00:53:54.780	3.7%	173569219	0.008	0.019	0.046
overview write	00:00:09.340	0.0%	1452358	0.000	0.006	0.018
perl filter	03:18:07.995	13.8%	1454238	5.114	8.175	10.763
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:18.328	0.2%	2946426	0.007	0.067	0.102
TOTAL: 24:00:27.084	23:09:13.958	96.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.139	0.0%	41708	0.000	0.003	0.250
article prepare	00:00:00.015	0.0%	54776	0.000	0.000	0.062
article read	00:00:01.841	0.0%	13488	0.000	0.136	1.182
backlog stats	00:00:00.268	0.0%	343936	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.129	0.0%	191350	0.000	0.011	0.074
data write	00:00:10.739	0.0%	176471	0.000	0.061	37.000
idle	23:59:18.541	100.0%	343936	0.000	251.089	18089.000
status file	00:00:00.890	0.0%	226	0.000	3.938	160.000
TOTAL: 23:59:41.674	23:59:34.562	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.161	0.0%	60	2.683	2.683	2.683
idle	13:03:14.119	99.9%	133002	0.000	353.334	600039.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.486	0.0%	267830	0.000	0.017	1.000
readart	00:00:00.769	0.0%	430	0.000	1.788	38.571
TOTAL: 13:04:13.757	13:03:19.535	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	579	1444671	1440960	132	3579	99%	229:44:55
2	news.task.gda.pl	16	29519	7533	20933	1053	25%	44:52:11
3	news.nask.pl	2	17048	3190	13351	507	18%	23:51:03
4	localhost	6	5	5	0	0	100%	00:00:00
5	news.icm.edu.pl	40	209	1	202	6	0%	15:42:57
TOTAL: 5		643	1491452	1451689	34618	5145	97%	314:11:06

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	478.2 GB	27.1 MB	76.2 MB	478.3 GB	99%	347.2 KB
2	news.nask.pl	15.8 MB	1.9 MB	2.0 MB	19.6 MB	80%	5.4 KB
3	news.task.gda.pl	14.4 MB	73.1 KB	2.8 MB	17.3 MB	83%	2.1 KB
4	news.icm.edu.pl	38.9 KB	55.1 KB	211.4 KB	305.4 KB	12%	43.6 KB
5	localhost	7.9 KB	0.0 KB	0.0 KB	7.9 KB	100%	1.6 KB
TOTAL: 5		478.2 GB	29.1 MB	81.2 MB	478.3 GB	99%	344.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 17 04:15:02 - 04:59:59	75069	5.2%	27.82	24.3 GB	5.1%	9428.22
Mar 17 05:00:00 - 05:59:59	88575	6.1%	24.60	29.2 GB	6.1%	8502.85
Mar 17 06:00:00 - 06:59:59	84495	5.8%	23.47	28.0 GB	5.9%	8168.78
Mar 17 07:00:00 - 07:59:59	82205	5.7%	22.83	28.1 GB	5.9%	8178.93
Mar 17 08:00:00 - 08:59:59	86339	5.9%	23.98	29.1 GB	6.1%	8488.79
Mar 17 09:00:00 - 09:59:59	42774	2.9%	11.88	15.4 GB	3.2%	4488.21
Mar 17 10:00:00 - 10:59:59	42846	3.0%	11.90	15.1 GB	3.2%	4406.25
Mar 17 11:00:00 - 11:59:59	44719	3.1%	12.42	15.6 GB	3.3%	4552.97
Mar 17 12:00:00 - 12:59:59	45392	3.1%	12.61	15.3 GB	3.2%	4447.49
Mar 17 13:00:00 - 13:59:59	41763	2.9%	11.60	14.0 GB	2.9%	4081.99
Mar 17 14:00:00 - 14:59:59	44583	3.1%	12.38	15.0 GB	3.1%	4383.33
Mar 17 15:00:00 - 15:59:59	45573	3.1%	12.66	14.8 GB	3.1%	4324.35
Mar 17 16:00:00 - 16:59:59	46341	3.2%	12.87	14.2 GB	3.0%	4121.87
Mar 17 17:00:00 - 17:59:59	42800	2.9%	11.89	13.3 GB	2.8%	3870.03
Mar 17 18:00:00 - 18:59:59	45545	3.1%	12.65	15.0 GB	3.1%	4367.41
Mar 17 19:00:00 - 19:59:59	46281	3.2%	12.86	15.0 GB	3.1%	4383.26
Mar 17 20:00:00 - 20:59:59	50293	3.5%	13.97	15.0 GB	3.1%	4357.66
Mar 17 21:00:00 - 21:59:59	38144	2.6%	10.60	11.3 GB	2.4%	3296.10
Mar 17 22:00:00 - 22:59:59	47584	3.3%	13.22	14.8 GB	3.1%	4314.69
Mar 17 23:00:00 - 23:59:59	49800	3.4%	13.83	15.3 GB	3.2%	4446.92
Mar 18 00:00:00 - 00:59:59	79261	5.5%	22.02	24.9 GB	5.2%	7246.26
Mar 18 01:00:00 - 01:59:59	77621	5.3%	21.56	25.9 GB	5.4%	7542.15
Mar 18 02:00:00 - 02:59:59	95622	6.6%	26.56	33.2 GB	6.9%	9679.64
Mar 18 03:00:00 - 03:59:59	86497	6.0%	24.03	29.3 GB	6.1%	8533.91
Mar 18 04:00:00 - 04:15:02	21473	1.5%	23.81	7.4 GB	1.5%	8580.16
TOTAL: 24:00:00	1451595	100.0%	16.80	478.6 GB	100.0%	5808.32

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3752	208	2736	1	807
2	news.task.gda.pl	1062	842	40	0	180
3	news.nask.pl	520	9	491	0	20
4	news.icm.edu.pl	6	0	2	0	4
TOTAL: 4		5340	1059	3269	1	1011

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	73
mailing.freebsd.svn-ports-head	73
mailing.openbsd.source-changes	68
mailing.freebsd.ports-bugs	66
uk.railway	58
mailing.unix.samba	46
it.comp.os.win.windows8	40
mailing.freebsd.svn-src-all	38
fa.freebsd.cvs-all	37
alt.windows7.general	33
mailing.freebsd.ports	27
mailing.openbsd.ports-changes	24
mailing.openbsd.misc	24
mailing.freebsd.svn-src-head	24
mailing.postfix.users	23
mailing.gcc.gcc-cvs	21
it.comp.os.win.windows7	20
mailing.database.pgsql-general	20
alt.politics.scorched-earth	19
it.tlc.cellulari.android	19
TOTAL: 79	1059

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	539
EMP (fsl)	157
Attached HTML file	100
EMP (md5)	18
HTML post	5
Scoring filter (12)	1
TOTAL: 6	820

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	61748	3703	30141	7765	6	8	5%	23:59:59
2	panorama	47772	1757	39589	314	0	0	3%	23:56:32
3	nask	23821	98	14324	2	0	0	0%	24:01:34
4	task	1839	27	945	2	0	0	1%	21:54:03
5	poznan	12021	13	10647	7	0	0	0%	23:59:54
6	okapi	0	0	0	0	0	2438	0%	24:00:01
TOTAL: 6		147201	5598	95646	8090	6	2446	3%	141:52:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	28.8 MB	1.8 GB	1.8 GB	21.9 KB/s	165.3 KB	23:59:59
2	panorama	8.0 MB	3.3 MB	11.3 MB	0.1 KB/s	5.6 KB	23:56:32
3	nask	359.0 KB	2.5 KB	361.5 KB	0.0 KB/s	3.6 KB	24:01:34
4	task	58.3 KB	3.7 KB	62.0 KB	0.0 KB/s	2.1 KB	21:54:03
5	poznan	25.8 KB	18.1 KB	43.9 KB	0.0 KB/s	2.2 KB	23:59:54
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		37.2 MB	1.8 GB	1.8 GB	3.7 KB/s	139.3 KB	141:52:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	348	560.7 KB	288	0	00:00:19
2	web.man.lodz.pl	288	52	54.6 KB	25	0	00:00:57
3	lze200.lze.lodz.pl	2	18	29.3 KB	4	0	00:11:58
4	router2.cbmm.lodz.pl	1	7	12.9 KB	1	0	00:03:33
5	proxy.filmschool.lodz.pl	1	2	2.6 KB	1	0	00:01:03
6	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 6		585	427	660.1 KB	319	5	13:04:13

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	560.7 KB	288	0	00:00:19
2	*.man.lodz.pl	677	52	54.6 KB	25	5	00:01:04
3	*.lze.lodz.pl	2	18	29.3 KB	4	0	00:11:58
4	*.cbmm.lodz.pl	1	7	12.9 KB	1	0	00:03:33
5	*.filmschool.lodz.pl	1	2	2.6 KB	1	0	00:01:03
6	*.207.15.62.static.jazztel.es	2	0	0.0 KB	0	0	11:46:50
7	unresolved	3	0	0.0 KB	0	0	00:30:24
8	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:01:35
9	*.105.135.22.tvsat364.lodz.pl	4	0	0.0 KB	0	0	00:27:09
10	*.toya.net.pl	52	0	0.0 KB	0	0	00:00:14
TOTAL: 10		1031	427	660.1 KB	319	5	13:04:13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
198.207.15.62.static.jazztel.es	7.300	8.744	11:46:50
212.51.193.43	0.016	0.016	00:30:00
ip-176.105.135.22.tvsat364.lodz.pl	0.020	0.052	00:27:09
lze200.lze.lodz.pl	0.008	0.048	00:11:58
router2.cbmm.lodz.pl	0.008	0.012	00:03:33
eck194.neoplus.adsl.tpnet.pl	0.000	0.020	00:01:35
proxy.filmschool.lodz.pl	0.000	0.012	00:01:03
web.man.lodz.pl	0.688	4.924	00:00:57
212.51.203.199	0.004	0.028	00:00:23
vegas.cm.p.lodz.pl	0.676	4.296	00:00:19
staticline41435.toya.net.pl	0.124	0.844	00:00:14
mon.man.lodz.pl	0.556	4.344	00:00:04
david.man.lodz.pl	0.184	1.336	00:00:01
isvcs01.man.lodz.pl	0.024	0.096	00:00:00
TOTAL: 14	9.608	24.772	13:04:13

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	52
ip-176.105.135.22.tvsat364.lodz.pl	4
198.207.15.62.static.jazztel.es	2
212.51.203.199	2
212.51.193.43	1
eck194.neoplus.adsl.tpnet.pl	1
TOTAL: 8	446

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.51.193.43	1	0
198.207.15.62.static.jazztel.es	1	1
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	348	81.5%
2	pl	79	18.5%
TOTAL: 2		427	100.0%

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

Newsgroup		Count
1	lodman.info	348
2	pl.comp.pecet	30
3	pl.comp.os.ms-windows.winnt	25
4	pl.misc.telefonia.gsm	8
5	pl.sci.chemia	7
6	pl.internet.polip	6
7	pl.rec.foto.cyfrowa	2
8	pl.comp.os.linux	1
TOTAL: 8		427

Daily Usenet report

Mar 17 04:15:02 -- Mar 18 04:15:02