Daily Usenet report: Mar 4 04:15:04

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Mar 4 11:12:18 newsfeed nnrpd[18044]: SERVER perl filter not defined
Mar 4 11:50:04 newsfeed pgpverify[19388]: pgpverify: verification failed
Mar 4 12:50:11 newsfeed nnrpd[21331]: SERVER perl filter not defined
Mar 4 13:29:45 newsfeed nnrpd[22788]: SERVER perl filter not defined
Mar 4 15:54:56 newsfeed nnrpd[27535]: SERVER perl filter not defined
Mar 4 15:57:49 newsfeed nnrpd[27618]: SERVER perl filter not defined
Mar 4 16:52:28 newsfeed nnrpd[29245]: SERVER perl filter not defined
Mar 4 20:18:38 newsfeed nnrpd[3708]: SERVER perl filter not defined
Mar 4 21:03:00 newsfeed nnrpd[5245]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1904208	98.2%	218.7 MB	97.8%
innd	17759	0.9%	2.9 MB	1.3%
nnrpd	10015	0.5%	1.0 MB	0.4%
innfeed	7421	0.4%	1.1 MB	0.5%
controlchan	3	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1939407	100.0%	223.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1896632	93.6%
Negative hits	83041	4.1%
Positive hits	46564	2.3%
Cache misses	406	0.0%
TOTAL: 4	2026643	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.423	0.0%	416	0.000	20.248	535.000
article cleanup	00:00:18.709	0.0%	1904311	0.000	0.010	0.034
article logging	00:00:42.343	0.0%	1904501	0.005	0.022	0.306
article parse	00:39:42.278	2.8%	241768685	0.003	0.010	0.072
article write	00:11:42.934	0.8%	1894881	0.154	0.371	1.077
artlog/artcncl	00:00:00.001	0.0%	399	0.000	0.003	0.167
artlog/artparse	00:00:00.000	0.0%	7	0.000	0.000	0.000
data move	00:02:06.625	0.1%	254142975	0.000	0.000	0.002
hisgrep/artcncl	00:00:01.015	0.0%	330	0.000	3.076	231.000
hishave/artcncl	00:00:00.539	0.0%	416	0.000	1.296	91.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:25:03.189	10.1%	2028624	0.007	4.290	13.250
history sync	00:00:00.013	0.0%	292	0.000	0.045	2.000
history write	03:55:55.803	16.4%	1898164	3.739	7.458	29.642
hiswrite/artcncl	00:00:00.286	0.0%	86	0.000	3.326	17.000
idle	12:33:20.906	52.3%	158041709	0.132	0.286	1.499
nntp read	00:40:02.319	2.8%	240382691	0.006	0.010	0.043
overview write	00:00:08.569	0.0%	1894881	0.000	0.005	0.018
perl filter	02:56:19.916	12.2%	1898157	3.115	5.574	9.975
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:52.825	0.2%	3832269	0.003	0.045	0.088
TOTAL: 24:00:30.042	23:28:26.693	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.108	0.0%	42589	0.000	0.003	0.167
article prepare	00:00:00.006	0.0%	44974	0.000	0.000	0.083
article read	00:00:00.431	0.0%	10369	0.000	0.042	1.000
backlog stats	00:00:00.168	0.0%	323963	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.448	0.0%	193539	0.000	0.007	2.000
data write	00:00:03.476	0.0%	160441	0.000	0.022	1.800
idle	24:00:02.940	100.0%	323963	0.000	266.706	18829.000
status file	00:00:01.530	0.0%	221	0.000	6.923	158.000
TOTAL: 24:00:15.950	24:00:10.107	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.107	0.0%	63	1.698	1.698	1.698
idle	54:15:45.401	100.0%	31862	0.000	6130.984	360544.400
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.257	0.0%	65824	0.000	0.019	4.778
readart	00:00:00.909	0.0%	464	0.000	1.959	21.538
TOTAL: 54:17:17.326	54:15:47.674	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
admin@openwatcom.news-admin.org	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	733	1885755	1877189	2369	6197	99%	402:41:28
2	news.nask.pl	3	20753	8231	11692	830	39%	23:38:37
3	news.task.gda.pl	22	35756	7291	26074	2391	20%	45:30:33
4	news.icm.edu.pl	33	321	8	305	8	2%	18:21:12
5	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 5		800	1942593	1892727	40440	9426	97%	490:11:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	604.2 GB	29.2 MB	48.3 MB	604.3 GB	99%	336.4 KB
2	news.nask.pl	37.4 MB	2.6 MB	2.7 MB	42.6 MB	87%	4.8 KB
3	news.task.gda.pl	16.1 MB	68.0 KB	4.9 MB	21.1 MB	76%	2.2 KB
4	news.icm.edu.pl	262.4 KB	9.2 KB	160.1 KB	431.7 KB	60%	27.0 KB
5	localhost	12.4 KB	0.0 KB	0.0 KB	12.4 KB	100%	1.5 KB
TOTAL: 5		604.2 GB	31.9 MB	56.0 MB	604.3 GB	99%	333.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 4 04:15:04 - 04:59:59	64249	3.4%	23.83	23.1 GB	3.8%	8980.55
Mar 4 05:00:00 - 05:59:59	77927	4.1%	21.65	29.1 GB	4.8%	8489.79
Mar 4 06:00:00 - 06:59:59	69436	3.7%	19.29	29.1 GB	4.8%	8477.64
Mar 4 07:00:00 - 07:59:59	73323	3.9%	20.37	27.5 GB	4.6%	8022.27
Mar 4 08:00:00 - 08:59:59	79133	4.2%	21.98	28.3 GB	4.7%	8243.66
Mar 4 09:00:00 - 09:59:59	91408	4.8%	25.39	31.2 GB	5.2%	9089.87
Mar 4 10:00:00 - 10:59:59	98282	5.2%	27.30	31.8 GB	5.3%	9267.56
Mar 4 11:00:00 - 11:59:59	78797	4.2%	21.89	25.3 GB	4.2%	7382.16
Mar 4 12:00:00 - 12:59:59	99958	5.3%	27.77	28.4 GB	4.7%	8269.06
Mar 4 13:00:00 - 13:59:59	102740	5.4%	28.54	29.5 GB	4.9%	8580.52
Mar 4 14:00:00 - 14:59:59	92386	4.9%	25.66	27.0 GB	4.5%	7864.99
Mar 4 15:00:00 - 15:59:59	88566	4.7%	24.60	27.1 GB	4.5%	7903.10
Mar 4 16:00:00 - 16:59:59	70869	3.7%	19.69	22.4 GB	3.7%	6526.57
Mar 4 17:00:00 - 17:59:59	58059	3.1%	16.13	18.8 GB	3.1%	5484.63
Mar 4 18:00:00 - 18:59:59	62846	3.3%	17.46	19.3 GB	3.2%	5608.25
Mar 4 19:00:00 - 19:59:59	85359	4.5%	23.71	26.3 GB	4.3%	7647.75
Mar 4 20:00:00 - 20:59:59	81214	4.3%	22.56	27.9 GB	4.6%	8139.97
Mar 4 21:00:00 - 21:59:59	40090	2.1%	11.14	11.7 GB	1.9%	3400.23
Mar 4 22:00:00 - 22:59:59	36703	1.9%	10.20	11.5 GB	1.9%	3343.50
Mar 4 23:00:00 - 23:59:59	44494	2.3%	12.36	14.6 GB	2.4%	4263.83
Mar 5 00:00:00 - 00:59:59	82703	4.4%	22.97	24.9 GB	4.1%	7252.60
Mar 5 01:00:00 - 01:59:59	86616	4.6%	24.06	24.4 GB	4.0%	7099.78
Mar 5 02:00:00 - 02:59:59	105703	5.6%	29.36	29.7 GB	4.9%	8655.48
Mar 5 03:00:00 - 03:59:59	103248	5.5%	28.68	30.2 GB	5.0%	8807.62
Mar 5 04:00:00 - 04:15:04	20074	1.1%	22.21	5.4 GB	0.9%	6253.82
TOTAL: 24:00:00	1894183	100.0%	21.92	604.6 GB	100.0%	7338.19

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.pionier.net.pl	6350	178	5352	820
2	news.task.gda.pl	2392	1068	38	1286
3	news.nask.pl	876	11	762	103
4	news.icm.edu.pl	8	0	1	7
TOTAL: 4		9626	1257	6153	2216

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	132
mailing.freebsd.svn-ports-head	130
uk.railway	72
mailing.freebsd.ports-bugs	64
gmane.linux.alsa.devel	50
it.comp.os.win.windows8	49
mozilla.dev.tree-management	49
fa.freebsd.cvs-all	48
mailing.freebsd.svn-src-all	46
mailing.unix.samba-technical	38
mailing.gcc.gcc-cvs	36
mailing.freebsd.ports	35
mailing.freebsd.svn-src-head	31
mailing.unix.samba	30
mailing.database.pgsql-general	28
mailing.freebsd.kde	28
mailing.freebsd.current	27
mozilla.dev.gaia	21
mailing.postfix.users	19
alt.politics.scorched-earth	19
TOTAL: 78	1257

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	1248
Binary in non-binary group	545
Attached HTML file	117
EMP (md5)	75
EMP (phl)	20
HTML post	9
Scoring filter (8)	3
Scoring filter (12)	1
Scoring filter (10)	1
TOTAL: 9	2019

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Blocked server feeds
	overview!	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44032	4172	37480	828	0	0	9%	23:58:52
2	icm	67765	3630	36113	2482	3	79	5%	23:56:26
3	task	2222	32	1191	7	0	8	1%	22:02:44
4	nask	21737	32	14379	0	0	0	0%	24:05:31
5	poznan	19002	14	15377	11	0	0	0%	24:00:00
6	okapi	0	0	0	0	0	3212	0%	24:00:03
TOTAL: 6		154758	7880	104540	3328	3	3299	5%	142:03:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	176.7 MB	65.0 MB	241.7 MB	2.9 KB/s	40.5 KB	23:56:26
2	panorama	21.2 MB	2.4 MB	23.6 MB	0.3 KB/s	4.8 KB	23:58:52
3	nask	133.3 KB	0.0 KB	133.3 KB	0.0 KB/s	4.2 KB	24:05:31
4	task	58.9 KB	14.4 KB	73.4 KB	0.0 KB/s	1.9 KB	22:02:44
5	poznan	31.9 KB	26.8 KB	58.7 KB	0.0 KB/s	2.3 KB	24:00:00
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 6		198.1 MB	67.5 MB	265.6 MB	0.5 KB/s	24.3 KB	142:03:36

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	360	581.9 KB	288	0	00:00:19
2	web.man.lodz.pl	288	72	63.4 KB	31	0	00:00:40
3	212.191.78.190	271	21	34.4 KB	11	2	44:38:18
4	ip-176.105.135.22.tvsat364.lodz.pl	5	2	6.1 KB	1	0	00:17:34
5	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 5		858	455	685.8 KB	331	8	54:17:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	360	581.9 KB	288	0	00:00:19
2	*.man.lodz.pl	678	72	63.4 KB	31	6	00:00:48
3	unresolved	271	21	34.4 KB	11	2	44:38:18
4	*.105.135.22.tvsat364.lodz.pl	5	2	6.1 KB	1	0	00:17:34
5	*.cbmm.lodz.pl	3	0	0.0 KB	0	0	00:00:05
6	*.wroclaw.mm.pl	40	0	0.0 KB	0	0	06:30:10
7	*.181.14.62.static.jazztel.es	12	0	0.0 KB	0	0	02:48:29
8	*.toya.net.pl	44	0	0.0 KB	0	0	00:01:30
TOTAL: 8		1341	455	685.8 KB	331	8	54:17:16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.356	2.400	44:38:18
host-89-231-50-15.wroclaw.mm.pl	0.020	0.348	06:30:10
113.181.14.62.static.jazztel.es	1.436	1.964	02:48:29
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.044	00:17:34
staticline41435.toya.net.pl	0.056	0.412	00:01:30
web.man.lodz.pl	0.488	2.844	00:00:40
vegas.cm.p.lodz.pl	0.396	2.632	00:00:19
router2.cbmm.lodz.pl	0.004	0.028	00:00:05
mon.man.lodz.pl	0.380	2.456	00:00:05
david.man.lodz.pl	0.096	0.836	00:00:01
isvcs01.man.lodz.pl	0.012	0.064	00:00:00
TOTAL: 11	3.248	14.028	54:17:16

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	44
host-89-231-50-15.wroclaw.mm.pl	40
113.181.14.62.static.jazztel.es	12
router2.cbmm.lodz.pl	3
TOTAL: 6	483

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.191.78.190	2	0
113.181.14.62.static.jazztel.es	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	360	79.1%
2	pl	95	20.9%
TOTAL: 2		455	100.0%

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

Newsgroup		Count
1	lodman.info	360
2	pl.comp.pecet	40
3	pl.comp.os.ms-windows.winnt	32
4	pl.rec.rowery	17
5	pl.misc.kolej	3
6	pl.soc.prawo	2
7	pl.praca.dyskusje	1
TOTAL: 7		455

Daily Usenet report

Mar 4 04:15:04 -- Mar 5 04:15:04