Daily Usenet report: Aug 1 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Aug 1 08:26:09 newsfeed pgpverify[26765]: pgpverify: verification failed
Aug 1 10:52:28 newsfeed nnrpd[31400]: SERVER perl filter not defined
Aug 1 10:54:23 newsfeed nnrpd[31434]: SERVER perl filter not defined
Aug 1 11:02:12 newsfeed nnrpd[31731]: SERVER perl filter not defined
Aug 1 11:06:25 newsfeed nnrpd[31816]: SERVER perl filter not defined
Aug 1 11:08:26 newsfeed nnrpd[31996]: SERVER perl filter not defined
Aug 1 11:29:13 newsfeed nnrpd[32622]: SERVER perl filter not defined
Aug 1 11:30:33 newsfeed nnrpd[32670]: SERVER perl filter not defined
Aug 1 11:31:49 newsfeed nnrpd[32673]: SERVER perl filter not defined
Aug 2 01:32:40 newsfeed pgpverify[27006]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2199579	98.5%	248.0 MB	98.1%
innd	18144	0.8%	3.0 MB	1.2%
innfeed	7259	0.3%	1.1 MB	0.4%
nnrpd	7155	0.3%	756.7 KB	0.3%
controlchan	6	0.0%	0.8 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 6	2232145	100.0%	252.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2194574	94.8%
Negative hits	79209	3.4%
Positive hits	39367	1.7%
Cache misses	2156	0.1%
TOTAL: 4	2315306	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.498	0.0%	366	0.000	28.683	457.000
article cleanup	00:00:28.987	0.0%	2197396	0.004	0.013	0.024
article logging	00:01:07.044	0.1%	2197691	0.012	0.031	0.815
article parse	01:17:40.459	5.4%	100610734	0.014	0.046	0.091
article write	00:23:58.897	1.7%	2192774	0.419	0.656	1.484
artlog/artcncl	00:00:00.015	0.0%	360	0.000	0.042	4.000
artlog/artparse	00:00:00.022	0.0%	674	0.000	0.033	0.500
data move	00:03:09.999	0.2%	114630473	0.001	0.002	0.008
hisgrep/artcncl	00:00:02.638	0.0%	331	0.000	7.970	293.000
hishave/artcncl	00:00:00.486	0.0%	366	0.000	1.328	177.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:59:03.884	8.3%	2315914	0.659	3.085	12.944
history sync	00:00:00.006	0.0%	290	0.000	0.021	1.000
history write	03:14:41.174	13.5%	2195166	1.582	5.321	18.314
hiswrite/artcncl	00:00:00.123	0.0%	35	0.000	3.514	23.000
idle	09:45:48.649	40.7%	66530980	0.214	0.528	3.076
nntp read	00:50:17.141	3.5%	98859924	0.014	0.031	0.045
overview write	00:00:12.995	0.0%	2192774	0.001	0.006	0.014
perl filter	05:50:07.379	24.3%	2194492	6.013	9.573	11.221
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:37.288	0.3%	4424678	0.026	0.049	0.077
TOTAL: 23:59:33.532	23:30:27.684	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.134	0.0%	39135	0.000	0.003	0.250
article prepare	00:00:00.011	0.0%	44964	0.000	0.000	0.125
article read	00:00:00.407	0.0%	10631	0.000	0.038	1.000
backlog stats	00:00:00.204	0.0%	299644	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.844	0.0%	178739	0.000	0.010	1.000
data write	00:00:03.788	0.0%	143370	0.000	0.026	4.000
idle	24:00:07.775	100.0%	299644	0.000	288.368	12177.000
status file	00:00:01.794	0.0%	223	0.000	8.045	181.667
TOTAL: 24:00:23.019	24:00:15.957	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:44:17.198	99.8%	4326	0.000	2278.594	600054.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.187	0.0%	10444	0.000	0.018	0.667
readart	00:00:00.418	0.0%	456	0.000	0.917	8.846
TOTAL: 02:44:40.746	02:44:17.803	99.8%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
control@usenet.org.uk	0	0	0	1	0	0
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	757	2192817	2182794	6531	3492	99%	338:35:24
2	news.nask.pl	2	17168	7125	9159	884	41%	24:20:32
3	news.task.gda.pl	15	28750	4266	23541	943	14%	47:26:05
4	news.icm.edu.pl	43	255	18	205	32	7%	17:27:30
5	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 5		826	2238998	2194211	39436	5351	97%	427:49:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	804.7 GB	63.9 MB	580.5 MB	805.3 GB	99%	386.2 KB
2	news.nask.pl	71.5 MB	10.1 MB	10.1 MB	91.7 MB	77%	11.7 KB
3	news.task.gda.pl	10.6 MB	61.2 KB	2.8 MB	13.5 MB	78%	2.7 KB
4	news.icm.edu.pl	1.2 MB	276.0 KB	2.5 MB	3.9 MB	30%	80.9 KB
5	localhost	13.1 KB	0.0 KB	0.0 KB	13.1 KB	100%	1.6 KB
TOTAL: 5		804.7 GB	74.3 MB	595.9 MB	805.4 GB	99%	383.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 1 04:15:02 - 04:59:59	76291	3.5%	28.28	27.3 GB	3.4%	10622.30
Aug 1 05:00:00 - 05:59:59	97082	4.4%	26.97	37.8 GB	4.7%	11012.35
Aug 1 06:00:00 - 06:59:59	95118	4.3%	26.42	36.7 GB	4.6%	10692.41
Aug 1 07:00:00 - 07:59:59	101619	4.6%	28.23	37.8 GB	4.7%	10998.02
Aug 1 08:00:00 - 08:59:59	94829	4.3%	26.34	34.4 GB	4.3%	10006.63
Aug 1 09:00:00 - 09:59:59	92460	4.2%	25.68	32.8 GB	4.1%	9549.93
Aug 1 10:00:00 - 10:59:59	108089	4.9%	30.02	38.3 GB	4.8%	11143.25
Aug 1 11:00:00 - 11:59:59	83595	3.8%	23.22	31.6 GB	3.9%	9206.32
Aug 1 12:00:00 - 12:59:59	101441	4.6%	28.18	37.3 GB	4.6%	10877.17
Aug 1 13:00:00 - 13:59:59	102958	4.7%	28.60	37.3 GB	4.6%	10866.61
Aug 1 14:00:00 - 14:59:59	99353	4.5%	27.60	36.5 GB	4.5%	10627.98
Aug 1 15:00:00 - 15:59:59	85878	3.9%	23.86	31.3 GB	3.9%	9125.09
Aug 1 16:00:00 - 16:59:59	102983	4.7%	28.61	35.5 GB	4.4%	10343.59
Aug 1 17:00:00 - 17:59:59	83741	3.8%	23.26	30.1 GB	3.7%	8769.51
Aug 1 18:00:00 - 18:59:59	90602	4.1%	25.17	32.5 GB	4.0%	9462.18
Aug 1 19:00:00 - 19:59:59	88453	4.0%	24.57	31.2 GB	3.9%	9092.43
Aug 1 20:00:00 - 20:59:59	87762	4.0%	24.38	31.0 GB	3.9%	9035.28
Aug 1 21:00:00 - 21:59:59	66285	3.0%	18.41	23.6 GB	2.9%	6885.54
Aug 1 22:00:00 - 22:59:59	76570	3.5%	21.27	27.9 GB	3.5%	8113.29
Aug 1 23:00:00 - 23:59:59	86620	3.9%	24.06	32.6 GB	4.1%	9491.40
Aug 2 00:00:00 - 00:59:59	85436	3.9%	23.73	32.3 GB	4.0%	9407.87
Aug 2 01:00:00 - 01:59:59	85201	3.9%	23.67	32.5 GB	4.0%	9468.20
Aug 2 02:00:00 - 02:59:59	95629	4.4%	26.56	35.9 GB	4.5%	10458.50
Aug 2 03:00:00 - 03:59:59	78845	3.6%	21.90	30.5 GB	3.8%	8876.21
Aug 2 04:00:00 - 04:15:03	26786	1.2%	29.66	9.5 GB	1.2%	11042.58
TOTAL: 24:00:01	2193626	100.0%	25.39	804.2 GB	100.0%	9759.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3735	252	2025	2	1456
2	news.nask.pl	919	29	848	1	41
3	news.task.gda.pl	908	706	30	3	169
4	news.icm.edu.pl	31	0	3	0	28
TOTAL: 4		5593	987	2906	6	1694

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	86
mailing.freebsd.svn-ports-all	56
mailing.freebsd.svn-ports-head	56
uk.rec.cycling.moderated	51
comp.mobile.ipad	38
it.tlc.cellulari.android	38
mozilla.dev.platform	33
gmane.linux.alsa.devel	31
mailing.unix.dovecot	28
mailing.gcc.gcc-cvs	27
24hoursupport.helpdesk	27
mailing.freebsd.svn-src-all	26
fa.freebsd.cvs-all	26
mailing.freebsd.ports-bugs	26
alt.windows7.general	25
mozilla.dev.tree-management	25
mailing.freebsd.svn-src-head	23
mailing.unix.samba-technical	22
mailing.www.horde-bugs	21
mailing.freebsd.ports	19
TOTAL: 79	987

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

Groups	Count
pl.sci.historia	3
fr.comp.securite	2
fr.bienvenue	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	478
EMP (fsl)	134
Attached HTML file	70
EMP (md5)	27
HTML post	15
Scoring filter (8)	1
TOTAL: 6	725

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	54177	4490	28107	2253	8	0	8%	23:54:29
2	panorama	44889	3306	34707	1099	0	0	7%	24:00:09
3	poznan	13222	24	10920	13	0	0	0%	24:00:15
4	task	1961	15	1026	2	0	0	0%	21:58:01
5	nask	18737	10	12269	1	0	0	0%	24:03:54
6	okapi	0	0	0	0	0	2575	0%	24:00:00
TOTAL: 6		132986	7845	87029	3368	8	2575	5%	141:56:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	31.4 MB	167.7 MB	199.1 MB	2.4 KB/s	30.2 KB	23:54:29
2	panorama	9.9 MB	11.9 MB	21.9 MB	0.3 KB/s	5.1 KB	24:00:09
3	poznan	102.8 KB	25.4 KB	128.2 KB	0.0 KB/s	3.5 KB	24:00:15
4	task	30.2 KB	6.3 KB	36.5 KB	0.0 KB/s	2.1 KB	21:58:01
5	nask	24.2 KB	1.3 KB	25.6 KB	0.0 KB/s	2.3 KB	24:03:54
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		41.4 MB	179.7 MB	221.1 MB	0.4 KB/s	20.2 KB	141:56:48

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	354	614.8 KB	288	0	00:00:08
2	web.man.lodz.pl	287	58	54.3 KB	27	0	00:00:24
3	lze200.lze.lodz.pl	18	27	56.6 KB	8	0	00:59:18
4	212.191.196.1	2	13	22.5 KB	3	0	00:09:04
5	ip-176.105.135.22.tvsat364.lodz.pl	5	3	3.3 KB	2	0	00:53:57
6	ebu143.neoplus.adsl.tpnet.pl	5	1	1.9 KB	1	0	00:40:40
7	kujawiak.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 7		613	456	753.4 KB	329	8	02:44:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	354	614.8 KB	288	0	00:00:08
2	*.man.lodz.pl	679	58	54.3 KB	27	8	00:00:34
3	*.lze.lodz.pl	18	27	56.6 KB	8	0	00:59:18
4	unresolved	2	13	22.5 KB	3	0	00:09:04
5	*.105.135.22.tvsat364.lodz.pl	5	3	3.3 KB	2	0	00:53:57
6	*.neoplus.adsl.tpnet.pl	6	1	1.9 KB	1	0	00:41:33
7	*.internetdsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:00
8	*.inhort.pl	1	0	0.0 KB	0	0	00:00:02
TOTAL: 8		1000	456	753.4 KB	329	8	02:44:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.060	0.228	00:59:18
ip-176.105.135.22.tvsat364.lodz.pl	0.012	0.056	00:53:57
ebu143.neoplus.adsl.tpnet.pl	0.016	0.048	00:40:40
212.191.196.1	0.008	0.024	00:09:04
eby10.neoplus.adsl.tpnet.pl	0.000	0.012	00:00:53
web.man.lodz.pl	0.588	3.136	00:00:24
vegas.cm.p.lodz.pl	0.688	3.388	00:00:08
f-oper-v100.man.lodz.pl	0.600	3.448	00:00:07
g.inhort.pl	0.004	0.008	00:00:02
david.man.lodz.pl	0.212	1.100	00:00:02
kujawiak.man.lodz.pl	0.040	0.120	00:00:00
gcq193.internetdsl.tpnet.pl	0.004	0.008	00:00:00
TOTAL: 12	2.232	11.576	02:44:40

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
gcq193.internetdsl.tpnet.pl	1
g.inhort.pl	1
eby10.neoplus.adsl.tpnet.pl	1
TOTAL: 5	387

NNRP client timeouts [Top 20]:

System	Conn	Peer
ebu143.neoplus.adsl.tpnet.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	355	77.9%
2	pl	101	22.1%
TOTAL: 2		456	100.0%

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

Newsgroup		Count
1	lodman.info	355
2	pl.comp.os.ms-windows.winnt	50
3	pl.comp.pecet	22
4	pl.misc.paranauki	10
5	pl.comp.os.linux	8
6	pl.regionalne.lodz	8
7	pl.news.admin	2
8	pl.sci.filozofia	1
TOTAL: 8		456

Daily Usenet report

Aug 1 04:15:02 -- Aug 2 04:15:03