Daily Usenet report: Jun 15 04:15:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jun 15 08:52:05 newsfeed nnrpd[31045]: SERVER perl filter not defined
Jun 15 11:11:05 newsfeed controlchan[2331]: control_checkgroups, ^.*$ !ka.lists #20140615 usenet@karlsruhe.org usenet@karlsruhe.org @03044255463035000000079352A50000006E@, , , utf-8, news.task.gda.pl, mail, 1
Jun 15 11:11:05 newsfeed controlchan[2331]: control_checkgroups, ^.*$ !ka.lists #20140615 usenet@karlsruhe.org usenet@karlsruhe.org @03044255463035000000079352A50000006E@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Jun 15 17:00:09 newsfeed pgpverify[15021]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2026299	98.5%	241.5 MB	98.1%
innd	16547	0.8%	2.8 MB	1.1%
nnrpd	7919	0.4%	865.1 KB	0.3%
innfeed	7153	0.3%	1.1 MB	0.4%
controlchan	5	0.0%	0.8 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2057924	100.0%	246.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2020452	96.6%
Positive hits	35692	1.7%
Negative hits	35438	1.7%
Cache misses	186	0.0%
TOTAL: 4	2091768	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.105	0.0%	464	0.000	17.468	381.000
article cleanup	00:00:20.180	0.0%	2026792	0.000	0.010	0.024
article logging	00:00:46.877	0.1%	2026881	0.008	0.023	0.417
article parse	00:35:29.664	2.5%	255111446	0.004	0.008	0.049
article write	00:13:22.564	0.9%	2020551	0.168	0.397	1.864
artlog/artcncl	00:00:00.002	0.0%	368	0.000	0.005	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:02:17.826	0.2%	268432752	0.000	0.001	0.051
hisgrep/artcncl	00:00:01.030	0.0%	328	0.000	3.140	24.278
hishave/artcncl	00:00:00.577	0.0%	464	0.000	1.244	38.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:23:48.883	5.8%	2093462	0.012	2.402	6.824
history sync	00:00:00.008	0.0%	291	0.000	0.027	1.000
history write	03:04:47.622	12.8%	2022337	0.114	5.483	17.373
hiswrite/artcncl	00:00:00.682	0.0%	136	0.000	5.015	283.000
idle	14:28:02.449	60.3%	207882135	0.093	0.251	5.396
nntp read	00:40:08.180	2.8%	253681528	0.006	0.009	0.030
overview write	00:00:09.581	0.0%	2020551	0.000	0.005	0.014
perl filter	02:46:04.259	11.5%	2022333	3.199	4.927	7.087
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:17.297	0.2%	4086522	0.030	0.048	0.143
TOTAL: 24:00:30.161	23:18:45.786	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.141	0.0%	45413	0.000	0.003	0.182
article prepare	00:00:00.004	0.0%	38580	0.000	0.000	0.062
article read	00:00:00.614	0.0%	8984	0.000	0.068	3.333
backlog stats	00:00:00.172	0.0%	325895	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.363	0.0%	182583	0.000	0.007	1.000
data write	00:00:04.183	0.0%	155958	0.000	0.027	1.000
idle	24:00:15.953	100.0%	325895	0.000	265.165	22100.000
status file	00:00:00.224	0.0%	216	0.000	1.037	33.000
TOTAL: 24:00:27.534	24:00:22.654	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:21:04.140	99.9%	25535	0.000	613.438	564548.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.705	0.0%	53033	0.000	0.013	0.500
readart	00:00:00.437	0.0%	394	0.000	1.109	20.438
TOTAL: 04:21:27.127	04:21:05.282	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
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	571	2015529	2011408	291	3830	99%	230:04:34
2	news.task.gda.pl	18	25731	4915	19524	1292	19%	45:05:55
3	news.nask.pl	2	16078	3993	11013	1072	24%	23:36:17
4	localhost	2	1	1	0	0	100%	00:00:00
5	news.icm.edu.pl	37	248	0	203	45	0%	14:47:30
TOTAL: 5		630	2057587	2020317	31031	6239	98%	313:34:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	516.6 GB	131.3 MB	135.7 MB	516.9 GB	99%	268.9 KB
2	news.nask.pl	14.7 MB	2.9 MB	3.2 MB	20.9 MB	70%	4.2 KB
3	news.task.gda.pl	9.4 MB	28.4 KB	2.9 MB	12.3 MB	76%	2.0 KB
4	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
5	news.icm.edu.pl	0.0 KB	0.0 KB	538.8 KB	538.8 KB	0%	12.0 KB
TOTAL: 5		516.6 GB	134.2 MB	142.4 MB	516.9 GB	99%	267.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 15 04:15:01 - 04:59:59	87371	4.3%	32.37	22.9 GB	4.4%	8899.31
Jun 15 05:00:00 - 05:59:59	106550	5.3%	29.60	28.7 GB	5.5%	8367.97
Jun 15 06:00:00 - 06:59:59	99568	4.9%	27.66	27.3 GB	5.3%	7941.52
Jun 15 07:00:00 - 07:59:59	111124	5.5%	30.87	31.9 GB	6.2%	9293.42
Jun 15 08:00:00 - 08:59:59	86313	4.3%	23.98	22.0 GB	4.2%	6403.41
Jun 15 09:00:00 - 09:59:59	84673	4.2%	23.52	21.2 GB	4.1%	6173.02
Jun 15 10:00:00 - 10:59:59	88114	4.4%	24.48	21.3 GB	4.1%	6211.08
Jun 15 11:00:00 - 11:59:59	83640	4.1%	23.23	21.0 GB	4.0%	6102.71
Jun 15 12:00:00 - 12:59:59	91332	4.5%	25.37	22.5 GB	4.3%	6550.50
Jun 15 13:00:00 - 13:59:59	86431	4.3%	24.01	22.0 GB	4.3%	6417.57
Jun 15 14:00:00 - 14:59:59	90100	4.5%	25.03	22.3 GB	4.3%	6501.51
Jun 15 15:00:00 - 15:59:59	89959	4.5%	24.99	21.2 GB	4.1%	6163.98
Jun 15 16:00:00 - 16:59:59	82629	4.1%	22.95	20.4 GB	3.9%	5948.91
Jun 15 17:00:00 - 17:59:59	85217	4.2%	23.67	21.4 GB	4.1%	6236.75
Jun 15 18:00:00 - 18:59:59	34015	1.7%	9.45	11.0 GB	2.1%	3215.26
Jun 15 19:00:00 - 19:59:59	52322	2.6%	14.53	10.8 GB	2.1%	3158.96
Jun 15 20:00:00 - 20:59:59	82208	4.1%	22.84	19.8 GB	3.8%	5773.89
Jun 15 21:00:00 - 21:59:59	59249	2.9%	16.46	11.2 GB	2.2%	3272.43
Jun 15 22:00:00 - 22:59:59	68692	3.4%	19.08	13.3 GB	2.6%	3870.88
Jun 15 23:00:00 - 23:59:59	85857	4.3%	23.85	19.5 GB	3.8%	5677.68
Jun 16 00:00:00 - 00:59:59	93624	4.6%	26.01	19.6 GB	3.8%	5713.58
Jun 16 01:00:00 - 01:59:59	76473	3.8%	21.24	22.8 GB	4.4%	6637.76
Jun 16 02:00:00 - 02:59:59	83797	4.1%	23.28	27.5 GB	5.3%	8003.87
Jun 16 03:00:00 - 03:59:59	86515	4.3%	24.03	28.7 GB	5.5%	8358.45
Jun 16 04:00:00 - 04:15:02	23826	1.2%	26.41	7.6 GB	1.5%	8836.48
TOTAL: 24:00:01	2019599	100.0%	23.37	518.0 GB	100.0%	6286.64

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3910	183	3514	0	213
2	news.task.gda.pl	1294	808	10	0	476
3	news.nask.pl	1055	7	933	4	111
4	news.icm.edu.pl	73	0	0	0	73
TOTAL: 4		6332	998	4457	4	873

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	141
mailing.freebsd.svn-ports-all	139
mailing.freebsd.svn-ports-head	138
uk.railway	76
it.tlc.cellulari.android	50
alt.windows7.general	43
mailing.freebsd.svn-src-all	29
fa.freebsd.cvs-all	29
alt.politics.scorched-earth	28
mailing.freebsd.ports	27
mailing.freebsd.svn-src-head	26
24hoursupport.helpdesk	21
mailing.openbsd.source-changes	19
fr.usenet.abus.nocem	19
mailing.gcc.gcc-cvs	16
mailing.openbsd.misc	16
mailing.unix.spamassassin-dev	11
comp.sys.raspberry-pi	11
mailing.postfix.users	11
nl.comp.os.linux.techniek	11
TOTAL: 60	998

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

Groups	Count
pl.sci.matematyka	3
pl.sci.historia	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	546
Binary in non-binary group	118
EMP (md5)	92
Attached HTML file	14
EMP (phl)	6
Scoring filter (8)	3
HTML post	1
TOTAL: 7	780

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	48587	4275	24348	1937	12	6	8%	23:59:47
2	panorama	53754	2202	42639	312	0	1	4%	24:00:42
3	nask	22368	407	12681	424	0	0	1%	24:03:22
4	task	1425	50	726	10	0	0	3%	20:55:41
5	poznan	10859	26	8839	6	0	0	0%	23:56:05
6	okapi	0	0	0	0	0	1712	0%	24:00:01
TOTAL: 6		136993	6960	89233	2689	12	1719	5%	140:55:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	118.2 MB	293.3 MB	411.5 MB	4.9 KB/s	67.8 KB	23:59:47
2	panorama	9.0 MB	1.1 MB	10.0 MB	0.1 KB/s	4.1 KB	24:00:42
3	nask	1.6 MB	155.2 MB	156.8 MB	1.9 KB/s	193.3 KB	24:03:22
4	task	108.7 KB	16.1 KB	124.8 KB	0.0 KB/s	2.1 KB	20:55:41
5	poznan	65.9 KB	14.3 KB	80.3 KB	0.0 KB/s	2.5 KB	23:56:05
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		128.9 MB	449.6 MB	578.6 MB	1.2 KB/s	61.4 KB	140:55:38

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	300	442.7 KB	288	0	00:00:07
2	web.man.lodz.pl	288	78	163.1 KB	35	0	00:00:19
3	ip-176.105.135.22.tvsat364.lodz.pl	5	16	26.9 KB	2	0	01:32:25
4	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:01
TOTAL: 4		582	394	632.7 KB	325	1	04:21:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	300	442.7 KB	288	0	00:00:07
2	*.man.lodz.pl	673	78	163.1 KB	35	1	00:00:25
3	*.105.135.22.tvsat364.lodz.pl	5	16	26.9 KB	2	0	01:32:25
4	*.pa.nsw.optusnet.com.au	230	0	0.0 KB	0	0	00:40:09
5	unresolved	1	0	0.0 KB	0	0	00:01:43
6	*.toya.net.pl	32	0	0.0 KB	0	0	00:00:01
7	*.205.15.62.static.jazztel.es	8	0	0.0 KB	0	0	02:06:33
TOTAL: 7		1237	394	632.7 KB	325	1	04:21:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
234.205.15.62.static.jazztel.es	0.912	1.004	02:06:33
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.060	01:32:25
pa49-181-246-70.pa.nsw.optusnet.com.au	0.180	1.472	00:40:09
213.231.196.238	0.024	0.008	00:01:43
web.man.lodz.pl	0.460	2.840	00:00:19
pc-skrzep2.cm.p.lodz.pl	0.396	2.240	00:00:07
mon.man.lodz.pl	0.332	1.968	00:00:03
staticline41435.toya.net.pl	0.048	0.280	00:00:01
isvcs01.man.lodz.pl	0.000	0.012	00:00:01
david.man.lodz.pl	0.104	0.688	00:00:01
TOTAL: 10	2.456	10.572	04:21:26

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-246-70.pa.nsw.optusnet.com.au	230
david.man.lodz.pl	96
staticline41435.toya.net.pl	32
234.205.15.62.static.jazztel.es	8
213.231.196.238	1
TOTAL: 6	655

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-246-70.pa.nsw.optusnet.com.au	4	0
234.205.15.62.static.jazztel.es	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
TOTAL: 3	8	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	300	76.1%
2	pl	94	23.9%
TOTAL: 2		394	100.0%

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

Newsgroup		Count
1	lodman.info	300
2	pl.comp.os.ms-windows.winnt	64
3	pl.soc.prawo	15
4	pl.comp.pecet	12
5	pl.internet.polip	2
6	pl.regionalne.lodz	1
TOTAL: 6		394

Daily Usenet report

Jun 15 04:15:01 -- Jun 16 04:15:02