Daily Usenet report: Aug 15 04:15:03

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Aug 15 11:11:03 newsfeed controlchan[20314]: control_checkgroups, ^.*$ !ka.lists #20130815 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000006070B1800000067@, , , utf-8, news.task.gda.pl, mail, 1
Aug 15 11:11:03 newsfeed controlchan[20314]: control_checkgroups, ^.*$ !ka.lists #20130815 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000006070B1800000067@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Aug 15 17:00:10 newsfeed pgpverify[15598]: pgpverify: verification failed
Aug 16 04:15:03 newsfeed nnrpd[4606]: vegas.cm.p.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2395526	98.7%	269.9 MB	98.2%
innd	19040	0.8%	3.2 MB	1.2%
innfeed	6872	0.3%	1.0 MB	0.4%
nnrpd	6853	0.3%	719.8 KB	0.3%
controlchan	5	0.0%	0.8 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2428297	100.0%	274.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2390571	94.2%
Negative hits	107302	4.2%
Positive hits	35140	1.4%
Cache misses	4612	0.2%
TOTAL: 4	2537625	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.464	0.0%	288	0.000	32.861	603.000
article cleanup	00:00:31.830	0.0%	2395090	0.005	0.013	0.024
article logging	00:01:04.671	0.1%	2395321	0.010	0.027	0.591
article parse	01:16:57.124	5.3%	77390281	0.024	0.060	0.091
article write	00:27:26.553	1.9%	2390314	0.361	0.689	1.521
artlog/artcncl	00:00:00.005	0.0%	283	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:38.819	0.2%	91190198	0.001	0.002	0.004
hisgrep/artcncl	00:00:02.548	0.0%	262	0.000	9.725	231.000
hishave/artcncl	00:00:01.287	0.0%	288	0.000	4.469	29.571
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	04:12:13.598	17.5%	2539198	0.176	5.960	12.170
history sync	00:00:00.010	0.0%	293	0.000	0.034	1.000
history write	05:26:41.978	22.7%	2392119	0.854	8.194	19.185
hiswrite/artcncl	00:00:00.231	0.0%	26	0.000	8.885	136.000
idle	05:15:57.387	21.9%	38552512	0.171	0.492	3.048
nntp read	00:40:18.700	2.8%	75409958	0.015	0.032	0.042
overview write	00:00:14.544	0.0%	2390314	0.001	0.006	0.016
perl filter	06:07:45.580	25.5%	2392118	5.868	9.224	11.028
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:55.303	0.3%	4836855	0.023	0.049	0.090
TOTAL: 24:00:01.460	23:35:59.632	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.206	0.0%	56247	0.000	0.004	0.100
article prepare	00:00:00.043	0.0%	110510	0.000	0.000	0.042
article read	00:00:06.174	0.0%	27232	0.000	0.227	12.243
backlog stats	00:00:00.294	0.0%	410548	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.577	0.0%	233092	0.000	0.011	0.077
data write	00:00:11.554	0.0%	222105	0.000	0.052	1.000
idle	23:59:43.201	100.0%	410548	0.000	210.410	15075.000
status file	00:00:00.626	0.0%	253	0.000	2.474	159.500
TOTAL: 24:00:13.136	24:00:04.675	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:35:06.877	99.8%	30683	0.000	420.652	462815.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.054	0.0%	63032	0.000	0.017	1.111
readart	00:00:00.480	0.0%	396	0.000	1.212	24.833
TOTAL: 03:35:33.172	03:35:08.411	99.8%	-	-	-	-

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
group-admin@isc.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	768	2388849	2377002	9298	2549	99%	486:01:57
2	news.nask.pl	2	14684	7359	6245	1080	50%	23:39:58
3	news.task.gda.pl	19	28045	5877	21056	1112	20%	46:08:52
4	news.icm.edu.pl	38	224	10	208	6	4%	17:35:34
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		828	2431802	2390248	36807	4747	98%	573:26:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	806.7 GB	16.4 MB	29.8 MB	806.7 GB	99%	355.5 KB
2	news.nask.pl	25.0 MB	2.6 MB	2.8 MB	30.4 MB	82%	3.7 KB
3	news.task.gda.pl	12.1 MB	69.6 KB	3.3 MB	15.4 MB	78%	2.3 KB
4	news.icm.edu.pl	241.0 KB	14.8 KB	307.7 KB	563.4 KB	42%	35.2 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		806.7 GB	19.1 MB	36.1 MB	806.7 GB	99%	353.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 15 04:15:03 - 04:59:59	79526	3.3%	29.49	25.4 GB	3.1%	9864.62
Aug 15 05:00:00 - 05:59:59	101689	4.3%	28.25	33.9 GB	4.2%	9885.79
Aug 15 06:00:00 - 06:59:59	101516	4.2%	28.20	35.0 GB	4.3%	10198.03
Aug 15 07:00:00 - 07:59:59	71452	3.0%	19.85	24.4 GB	3.0%	7117.44
Aug 15 08:00:00 - 08:59:59	99709	4.2%	27.70	33.3 GB	4.1%	9709.72
Aug 15 09:00:00 - 09:59:59	108243	4.5%	30.07	36.3 GB	4.5%	10567.69
Aug 15 10:00:00 - 10:59:59	101712	4.3%	28.25	34.5 GB	4.3%	10060.65
Aug 15 11:00:00 - 11:59:59	108143	4.5%	30.04	36.7 GB	4.6%	10693.90
Aug 15 12:00:00 - 12:59:59	98537	4.1%	27.37	35.6 GB	4.4%	10377.22
Aug 15 13:00:00 - 13:59:59	93419	3.9%	25.95	35.0 GB	4.3%	10183.01
Aug 15 14:00:00 - 14:59:59	99070	4.1%	27.52	34.3 GB	4.3%	10004.67
Aug 15 15:00:00 - 15:59:59	107851	4.5%	29.96	33.3 GB	4.1%	9710.57
Aug 15 16:00:00 - 16:59:59	99440	4.2%	27.62	28.2 GB	3.5%	8213.55
Aug 15 17:00:00 - 17:59:59	114609	4.8%	31.84	30.8 GB	3.8%	8957.09
Aug 15 18:00:00 - 18:59:59	101247	4.2%	28.12	34.2 GB	4.2%	9966.00
Aug 15 19:00:00 - 19:59:59	106319	4.4%	29.53	36.7 GB	4.6%	10703.19
Aug 15 20:00:00 - 20:59:59	100332	4.2%	27.87	35.4 GB	4.4%	10322.65
Aug 15 21:00:00 - 21:59:59	98253	4.1%	27.29	34.9 GB	4.3%	10179.19
Aug 15 22:00:00 - 22:59:59	105143	4.4%	29.21	36.9 GB	4.6%	10746.35
Aug 15 23:00:00 - 23:59:59	100681	4.2%	27.97	35.6 GB	4.4%	10369.47
Aug 16 00:00:00 - 00:59:59	90770	3.8%	25.21	32.7 GB	4.1%	9518.67
Aug 16 01:00:00 - 01:59:59	105110	4.4%	29.20	37.4 GB	4.6%	10896.12
Aug 16 02:00:00 - 02:59:59	90625	3.8%	25.17	30.2 GB	3.7%	8804.28
Aug 16 03:00:00 - 03:59:59	83692	3.5%	23.25	27.7 GB	3.4%	8066.34
Aug 16 04:00:00 - 04:15:04	23148	1.0%	25.61	7.7 GB	0.9%	8879.02
TOTAL: 24:00:01	2390236	100.0%	27.66	806.4 GB	100.0%	9786.20

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	2725	165	1864	0	696
2	news.nask.pl	1177	17	1080	1	79
3	news.task.gda.pl	1099	893	26	0	180
4	news.icm.edu.pl	6	0	1	0	5
TOTAL: 4		5007	1075	2971	1	960

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	77
alt.windows7.general	54
mailing.database.pgsql-general	52
mailing.freebsd.ports-bugs	50
mozilla.dev.tree-management	47
mailing.freebsd.svn-src-all	40
fa.freebsd.cvs-all	40
mailing.freebsd.svn-ports-all	39
gnu.hurd.bug	38
mailing.freebsd.svn-ports-head	37
mailing.openbsd.ports-changes	35
mailing.unix.spamassassin-talk	32
mozilla.dev.identity	32
mailing.unix.dovecot	30
mailing.www.horde-bugs	24
fr.usenet.abus.nocem	24
mailing.postfix.users	22
mailing.freebsd.svn-src-head	22
mailing.unix.samba-technical	21
gmane.linux.alsa.devel	20
TOTAL: 85	1075

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	447
EMP (fsl)	152
Attached HTML file	80
EMP (md5)	28
HTML post	20
Scoring filter (8)	1
TOTAL: 6	728

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	56589	3229	51895	855	0	0	5%	23:59:31
2	icm	67948	1094	30237	22290	1	13	1%	24:03:08
3	poznan	15166	73	12906	49	0	0	0%	24:00:01
4	task	1338	47	706	7	0	5	3%	22:37:16
5	nask	14356	7	11116	1	0	0	0%	23:56:11
6	okapi	0	0	0	0	0	2048	0%	24:00:01
TOTAL: 6		155397	4450	106860	23202	1	2066	2%	142:36:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	27.0 MB	5.3 GB	5.3 GB	64.1 KB/s	237.2 KB	24:03:08
2	panorama	9.3 MB	2.8 MB	12.2 MB	0.1 KB/s	3.1 KB	23:59:31
3	poznan	245.6 KB	102.6 KB	348.3 KB	0.0 KB/s	2.9 KB	24:00:01
4	task	106.5 KB	13.5 KB	120.0 KB	0.0 KB/s	2.2 KB	22:37:16
5	nask	25.2 KB	9.7 KB	34.9 KB	0.0 KB/s	4.4 KB	23:56:11
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		36.7 MB	5.3 GB	5.3 GB	10.8 KB/s	201.0 KB	142:36:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	vegas.cm.p.lodz.pl	288	287	436.7 KB	287	00:00:10
2	web.man.lodz.pl	288	82	92.4 KB	35	00:00:19
3	ip-176.105.135.22.tvsat364.lodz.pl	7	27	46.9 KB	6	02:44:16
TOTAL: 3		583	396	575.9 KB	328	03:35:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cm.p.lodz.pl	288	287	436.7 KB	287	00:00:10
2	*.man.lodz.pl	672	82	92.4 KB	35	00:00:30
3	*.105.135.22.tvsat364.lodz.pl	7	27	46.9 KB	6	02:44:16
4	*.207.15.62.static.jazztel.es	4	0	0.0 KB	0	00:50:34
TOTAL: 4		971	396	575.9 KB	328	03:35:32

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
ip-176.105.135.22.tvsat364.lodz.pl	0.028	0.132	02:44:16
190.207.15.62.static.jazztel.es	1.356	2.284	00:50:34
web.man.lodz.pl	0.780	4.676	00:00:19
vegas.cm.p.lodz.pl	0.488	4.808	00:00:10
f-oper-v100.man.lodz.pl	0.668	5.324	00:00:09
david.man.lodz.pl	0.212	1.564	00:00:01
TOTAL: 6	3.532	18.788	03:35:32

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
190.207.15.62.static.jazztel.es	4
TOTAL: 3	388

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	2	0
TOTAL: 1	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	287	72.5%
2	pl	109	27.5%
TOTAL: 2		396	100.0%

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

Newsgroup		Count
1	lodman.info	287
2	pl.comp.pecet	72
3	pl.regionalne.lodz	14
4	pl.comp.os.ms-windows.winnt	10
5	pl.misc.dieta	9
6	pl.soc.dzieci	4
TOTAL: 6		396

Daily Usenet report

Aug 15 04:15:03 -- Aug 16 04:15:04