Daily Usenet report: Nov 2 04:15:03

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Nov 2 08:40:39 newsfeed pgpverify[16762]: pgpverify: verification failed
Nov 2 21:45:45 newsfeed overchan[2136]: input line too long, skipping
Nov 2 23:31:24 newsfeed nnrpd[13311]: SERVER perl filter not defined
Nov 3 00:02:47 newsfeed nnrpd[14332]: SERVER perl filter not defined
Nov 3 01:37:17 newsfeed pgpverify[17461]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1886174	98.4%	212.8 MB	97.9%
innd	17197	0.9%	2.9 MB	1.3%
nnrpd	7003	0.4%	720.9 KB	0.3%
innfeed	6884	0.4%	1.0 MB	0.5%
controlchan	6	0.0%	0.8 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 7	1917267	100.0%	217.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1878710	94.9%
Negative hits	66006	3.3%
Positive hits	35129	1.8%
Cache misses	322	0.0%
TOTAL: 4	1980167	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:20.469	0.0%	831	0.000	24.632	281.000
article cleanup	00:00:24.699	0.0%	1884786	0.003	0.013	0.030
article logging	00:00:58.597	0.1%	1884906	0.010	0.031	0.303
article parse	00:57:33.074	4.0%	64031218	0.011	0.054	0.096
article write	00:18:09.023	1.3%	1878058	0.284	0.580	1.285
artlog/artcncl	00:00:00.021	0.0%	814	0.000	0.026	1.000
artlog/artparse	00:00:00.001	0.0%	15	0.000	0.067	0.500
data move	00:06:06.391	0.4%	75508756	0.000	0.005	0.112
hisgrep/artcncl	00:00:03.946	0.0%	413	0.000	9.554	184.000
hishave/artcncl	00:00:02.967	0.0%	831	0.000	3.570	27.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:28:23.750	14.5%	1981156	0.012	6.311	33.419
history sync	00:00:00.015	0.0%	290	0.000	0.052	1.000
history write	05:03:42.217	21.1%	1879760	0.073	9.694	58.962
hiswrite/artcncl	00:00:03.365	0.0%	418	0.000	8.050	54.000
idle	08:18:49.325	34.7%	33630731	0.209	0.890	5.575
nntp read	00:31:13.738	2.2%	62506649	0.009	0.030	0.081
overview write	00:00:11.435	0.0%	1878058	0.001	0.006	0.017
perl filter	04:53:29.720	20.4%	1879745	4.166	9.368	14.293
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:35.918	0.2%	3795296	0.029	0.057	0.092
TOTAL: 23:59:30.166	23:43:08.671	98.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.153	0.0%	39253	0.000	0.004	0.167
article prepare	00:00:00.012	0.0%	39056	0.000	0.000	0.062
article read	00:00:13.098	0.0%	9402	0.000	1.393	276.154
backlog stats	00:00:00.213	0.0%	298019	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.978	0.0%	168085	0.000	0.012	1.000
data write	00:00:08.318	0.0%	159317	0.000	0.052	3.667
idle	23:59:36.900	100.0%	298019	0.000	289.837	21027.000
status file	00:00:02.276	0.0%	243	0.000	9.366	238.000
TOTAL: 24:00:09.589	24:00:02.948	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.175	0.0%	24	7.292	7.292	7.292
idle	01:37:19.098	99.4%	5024	0.000	1162.241	209860.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.501	0.0%	11718	0.000	0.043	5.778
readart	00:00:00.959	0.0%	351	0.000	2.732	167.000
TOTAL: 01:37:55.214	01:37:20.733	99.4%	-	-	-	-

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
hun-mnt@news.sztaki.hu	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	971	1872267	1867597	1294	3376	99%	405:09:43
2	news.task.gda.pl	17	27415	6759	19589	1067	24%	46:11:58
3	news.nask.pl	2	15528	4708	8581	2239	30%	24:26:12
4	news.icm.edu.pl	47	306	10	226	70	3%	17:46:31
5	localhost	3	2	2	0	0	100%	00:00:01
TOTAL: 5		1040	1915518	1879076	29690	6752	98%	493:34:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	716.7 GB	18.3 MB	53.4 MB	716.7 GB	99%	401.7 KB
2	news.nask.pl	18.2 MB	5.6 MB	5.7 MB	29.4 MB	61%	4.3 KB
3	news.task.gda.pl	13.2 MB	61.7 KB	2.9 MB	16.1 MB	81%	2.1 KB
4	news.icm.edu.pl	875.4 KB	83.2 KB	5.5 MB	6.4 MB	13%	82.1 KB
5	localhost	3.8 KB	0.0 KB	0.0 KB	3.8 KB	100%	1.9 KB
TOTAL: 5		716.7 GB	24.0 MB	67.4 MB	716.8 GB	99%	398.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 2 04:15:03 - 04:59:59	67109	3.6%	24.88	26.6 GB	3.7%	10338.88
Nov 2 05:00:00 - 05:59:59	93583	5.0%	26.00	36.7 GB	5.1%	10678.74
Nov 2 06:00:00 - 06:59:59	83344	4.4%	23.15	33.0 GB	4.6%	9613.40
Nov 2 07:00:00 - 07:59:59	87966	4.7%	24.43	33.4 GB	4.7%	9728.25
Nov 2 08:00:00 - 08:59:59	77344	4.1%	21.48	29.0 GB	4.1%	8457.32
Nov 2 09:00:00 - 09:59:59	73707	3.9%	20.47	28.2 GB	3.9%	8214.88
Nov 2 10:00:00 - 10:59:59	83312	4.4%	23.14	31.8 GB	4.4%	9272.55
Nov 2 11:00:00 - 11:59:59	85796	4.6%	23.83	32.4 GB	4.5%	9450.71
Nov 2 12:00:00 - 12:59:59	82104	4.4%	22.81	30.6 GB	4.3%	8910.47
Nov 2 13:00:00 - 13:59:59	73071	3.9%	20.30	26.7 GB	3.7%	7782.43
Nov 2 14:00:00 - 14:59:59	72322	3.9%	20.09	25.9 GB	3.6%	7546.82
Nov 2 15:00:00 - 15:59:59	61439	3.3%	17.07	22.2 GB	3.1%	6473.56
Nov 2 16:00:00 - 16:59:59	66299	3.5%	18.42	25.2 GB	3.5%	7349.72
Nov 2 17:00:00 - 17:59:59	77588	4.1%	21.55	29.1 GB	4.1%	8464.33
Nov 2 18:00:00 - 18:59:59	85641	4.6%	23.79	30.9 GB	4.3%	9001.07
Nov 2 19:00:00 - 19:59:59	81710	4.3%	22.70	30.9 GB	4.3%	9007.22
Nov 2 20:00:00 - 20:59:59	86109	4.6%	23.92	32.5 GB	4.5%	9470.62
Nov 2 21:00:00 - 21:59:59	83720	4.5%	23.26	30.3 GB	4.2%	8827.52
Nov 2 22:00:00 - 22:59:59	79757	4.2%	22.15	29.9 GB	4.2%	8716.59
Nov 2 23:00:00 - 23:59:59	84902	4.5%	23.58	32.9 GB	4.6%	9575.38
Nov 3 00:00:00 - 00:59:59	69058	3.7%	19.18	26.9 GB	3.8%	7841.64
Nov 3 01:00:00 - 01:59:59	60695	3.2%	16.86	24.9 GB	3.5%	7240.98
Nov 3 02:00:00 - 02:59:59	72013	3.8%	20.00	28.9 GB	4.0%	8417.57
Nov 3 03:00:00 - 03:59:59	73613	3.9%	20.45	30.0 GB	4.2%	8738.83
Nov 3 04:00:00 - 04:15:03	16291	0.9%	18.04	6.8 GB	1.0%	7944.42
TOTAL: 24:00:00	1878493	100.0%	21.74	715.9 GB	100.0%	8688.29

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3476	195	2835	2	444
2	news.nask.pl	2228	29	2171	10	18
3	news.task.gda.pl	1092	883	37	3	169
4	news.icm.edu.pl	70	0	1	0	69
TOTAL: 4		6866	1107	5044	15	700

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	135
mailing.freebsd.svn-ports-head	135
mailing.freebsd.ports-bugs	103
comp.mobile.ipad	52
uk.railway	49
mailing.openbsd.source-changes	48
mailing.openbsd.ports-changes	42
24hoursupport.helpdesk	41
alt.politics.scorched-earth	31
mailing.freebsd.current	30
mailing.freebsd.ports	23
mozilla.dev.tree-management	23
mailing.freebsd.svn-src-all	22
mailing.freebsd.svn-src-head	21
mailing.unix.dovecot	20
fa.freebsd.cvs-all	20
uk.rec.cycling.moderated	19
de.comm.provider.telefonie	19
mailing.database.pgsql-general	17
mailing.gcc.gcc-cvs	15
TOTAL: 73	1107

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

Groups	Count
pl.sci.historia	10
fr.comp.lang.php	1
fr.bienvenue	1
bit.listserv.catholic	1
pl.sci.matematyka	1
fr.comp.applications.libres	1
TOTAL: 6	15

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	284
EMP (fsl)	130
EMP (md5)	76
Attached HTML file	56
EMP (phl)	16
HTML post	2
Angle-bracket bot	1
TOTAL: 7	565

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40158	2357	36281	491	0	0	5%	23:53:51
2	icm	53270	2128	29709	4584	8	69	3%	23:59:49
3	poznan	12490	70	10970	62	0	0	0%	24:01:48
4	task	2112	45	1112	6	0	0	2%	23:09:17
5	nask	16369	16	11615	0	0	0	0%	23:56:47
6	okapi	0	0	0	0	0	1811	0%	24:00:01
TOTAL: 6		124399	4616	89687	5143	8	1880	3%	143:01:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	24.8 MB	2.3 GB	2.3 GB	27.9 KB/s	359.6 KB	23:59:49
2	panorama	8.8 MB	1.7 MB	10.5 MB	0.1 KB/s	3.8 KB	23:53:51
3	poznan	294.6 KB	201.2 KB	495.8 KB	0.0 KB/s	3.8 KB	24:01:48
4	task	92.0 KB	37.6 KB	129.5 KB	0.0 KB/s	2.5 KB	23:09:17
5	nask	39.3 KB	0.0 KB	39.3 KB	0.0 KB/s	2.5 KB	23:56:47
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		34.1 MB	2.3 GB	2.3 GB	4.7 KB/s	248.5 KB	143:01:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	287	299	539.8 KB	287	0	00:00:15
2	web.man.lodz.pl	289	49	48.9 KB	24	1	00:00:22
3	kujawiak.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 3		577	348	588.7 KB	311	2	01:37:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	287	299	539.8 KB	287	0	00:00:15
2	*.man.lodz.pl	675	49	48.9 KB	24	2	00:00:32
3	*.191.14.62.static.jazztel.es	4	0	0.0 KB	0	0	00:03:13
4	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:02:12
5	*.lodz.mm.pl	10	0	0.0 KB	0	0	01:30:28
6	unresolved	1	0	0.0 KB	0	0	00:01:07
7	*.toya.net.pl	37	0	0.0 KB	0	0	00:00:04
TOTAL: 7		1015	348	588.7 KB	311	2	01:37:54

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-228-165-199.lodz.mm.pl	0.024	0.128	01:30:28
16.191.14.62.static.jazztel.es	0.072	0.124	00:03:13
eci49.neoplus.adsl.tpnet.pl	0.000	0.016	00:02:12
5.172.252.88	0.028	0.028	00:01:07
web.man.lodz.pl	0.616	4.092	00:00:22
vegas.cm.p.lodz.pl	0.544	4.340	00:00:15
f-oper-v100.man.lodz.pl	0.580	4.356	00:00:06
staticline41435.toya.net.pl	0.072	0.628	00:00:04
david.man.lodz.pl	0.188	1.424	00:00:02
kujawiak.man.lodz.pl	0.000	0.024	00:00:00
TOTAL: 10	2.124	15.160	01:37:54

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	37
host-89-228-165-199.lodz.mm.pl	10
16.191.14.62.static.jazztel.es	4
eci49.neoplus.adsl.tpnet.pl	1
5.172.252.88	1
TOTAL: 7	438

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	299	85.9%
2	pl	49	14.1%
TOTAL: 2		348	100.0%

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

Newsgroup		Count
1	lodman.info	299
2	pl.comp.os.ms-windows.winnt	46
3	pl.comp.pecet	2
4	pl.rec.mineraly	1
TOTAL: 4		348

Daily Usenet report

Nov 2 04:15:03 -- Nov 3 04:15:03