Daily Usenet report: May 15 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

May 15 11:11:08 newsfeed controlchan[2206]: control_checkgroups, ^.*$ !ka.lists #20140515 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000005E5DDD50000006D@, , , utf-8, news.task.gda.pl, mail, 1
May 15 11:11:09 newsfeed controlchan[2206]: control_checkgroups, ^.*$ !ka.lists #20140515 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000005E5DDD50000006D@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
May 15 13:21:06 newsfeed nnrpd[25120]: SERVER perl filter not defined
May 15 13:23:13 newsfeed nnrpd[25297]: SERVER perl filter not defined
May 15 14:23:05 newsfeed nnrpd[27306]: SERVER perl filter not defined
May 15 15:17:08 newsfeed nnrpd[29072]: SERVER perl filter not defined
May 15 15:42:43 newsfeed nnrpd[29837]: SERVER perl filter not defined
May 15 17:00:06 newsfeed pgpverify[32485]: pgpverify: verification failed
May 15 17:24:05 newsfeed nnrpd[904]: SERVER perl filter not defined
May 15 17:50:03 newsfeed nnrpd[1719]: SERVER perl filter not defined
May 15 20:40:15 newsfeed nnrpd[7453]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	954296	97.4%	107.4 MB	96.7%
innd	10596	1.1%	1.8 MB	1.6%
nnrpd	7855	0.8%	829.6 KB	0.7%
innfeed	7158	0.7%	1.0 MB	0.9%
controlchan	5	0.0%	0.8 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	979911	100.0%	111.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	949268	92.6%
Positive hits	41439	4.0%
Negative hits	33897	3.3%
Cache misses	247	0.0%
TOTAL: 4	1024851	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.922	0.0%	309	0.000	35.346	388.000
article cleanup	00:00:16.553	0.0%	953830	0.002	0.017	0.044
article logging	00:00:29.084	0.0%	954037	0.008	0.030	0.409
article parse	00:45:05.283	3.1%	115694386	0.012	0.023	0.049
article write	00:10:49.876	0.8%	948576	0.286	0.685	2.318
artlog/artcncl	00:00:00.007	0.0%	307	0.000	0.023	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:39.656	0.2%	122286153	0.000	0.001	0.037
hisgrep/artcncl	00:00:03.217	0.0%	271	0.000	11.871	243.000
hishave/artcncl	00:00:00.582	0.0%	309	0.000	1.883	31.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:57:22.673	4.0%	1025820	0.754	3.356	13.055
history sync	00:00:00.018	0.0%	290	0.000	0.062	2.000
history write	02:07:33.808	8.9%	950321	1.026	8.054	27.478
hiswrite/artcncl	00:00:00.494	0.0%	38	0.000	13.000	328.000
idle	16:03:19.536	66.9%	91514894	0.313	0.632	1.708
nntp read	00:46:36.843	3.2%	114992343	0.016	0.024	0.047
overview write	00:00:06.303	0.0%	948576	0.000	0.007	0.036
perl filter	02:24:34.314	10.0%	950319	5.786	9.128	20.194
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:09.948	0.2%	1938950	0.041	0.067	0.435
TOTAL: 24:00:15.242	23:21:19.117	97.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.177	0.0%	41804	0.000	0.004	0.125
article prepare	00:00:00.007	0.0%	21520	0.000	0.000	0.083
article read	00:00:01.818	0.0%	5223	0.000	0.348	190.429
backlog stats	00:00:00.226	0.0%	293302	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.872	0.0%	174812	0.000	0.011	1.000
data write	00:00:03.584	0.0%	140501	0.000	0.026	0.188
idle	23:59:30.762	100.0%	293302	0.000	294.477	18165.000
status file	00:00:01.434	0.0%	212	0.000	6.764	175.000
TOTAL: 23:59:46.221	23:59:39.880	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	25:57:26.214	99.9%	598788	0.000	156.059	250324.800
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:20.435	0.0%	1199580	0.000	0.017	69.833
readart	00:00:00.686	0.0%	440	0.000	1.559	18.385
TOTAL: 25:58:40.397	25:57:47.335	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	442	942070	937990	570	3510	99%	199:16:20
2	news.task.gda.pl	15	28023	5545	21352	1126	19%	44:06:17
3	news.nask.pl	4	18787	4029	14235	523	21%	23:59:06
4	localhost	9	8	8	0	0	100%	00:00:00
5	news.icm.edu.pl	44	246	2	202	42	0%	17:07:42
TOTAL: 5		514	989134	947574	36359	5201	95%	284:29:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	343.7 GB	17.9 MB	60.0 MB	343.8 GB	99%	382.9 KB
2	news.nask.pl	18.9 MB	1.5 MB	1.7 MB	22.2 MB	85%	5.0 KB
3	news.task.gda.pl	12.8 MB	86.3 KB	3.1 MB	16.0 MB	79%	2.5 KB
4	news.icm.edu.pl	107.2 KB	0.0 KB	448.1 KB	555.3 KB	19%	12.6 KB
5	localhost	18.5 KB	0.0 KB	0.0 KB	18.5 KB	100%	2.3 KB
TOTAL: 5		343.7 GB	19.5 MB	65.3 MB	343.8 GB	99%	378.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 15 04:15:03 - 04:59:59	24894	2.6%	9.23	9.1 GB	2.7%	3553.51
May 15 05:00:00 - 05:59:59	29817	3.1%	8.28	13.9 GB	4.0%	4049.32
May 15 06:00:00 - 06:59:59	34210	3.6%	9.50	16.0 GB	4.6%	4646.11
May 15 07:00:00 - 07:59:59	28059	3.0%	7.79	11.8 GB	3.4%	3445.45
May 15 08:00:00 - 08:59:59	28045	3.0%	7.79	12.5 GB	3.6%	3640.38
May 15 09:00:00 - 09:59:59	32146	3.4%	8.93	11.8 GB	3.4%	3443.39
May 15 10:00:00 - 10:59:59	39634	4.2%	11.01	13.3 GB	3.9%	3878.96
May 15 11:00:00 - 11:59:59	28160	3.0%	7.82	10.7 GB	3.1%	3130.20
May 15 12:00:00 - 12:59:59	26956	2.8%	7.49	11.1 GB	3.2%	3229.24
May 15 13:00:00 - 13:59:59	30073	3.2%	8.35	11.3 GB	3.3%	3281.65
May 15 14:00:00 - 14:59:59	29141	3.1%	8.09	10.8 GB	3.1%	3145.74
May 15 15:00:00 - 15:59:59	29473	3.1%	8.19	10.5 GB	3.0%	3047.99
May 15 16:00:00 - 16:59:59	30708	3.2%	8.53	10.8 GB	3.2%	3156.07
May 15 17:00:00 - 17:59:59	34615	3.6%	9.62	12.1 GB	3.5%	3529.66
May 15 18:00:00 - 18:59:59	37363	3.9%	10.38	12.6 GB	3.7%	3678.79
May 15 19:00:00 - 19:59:59	33772	3.6%	9.38	11.4 GB	3.3%	3309.29
May 15 20:00:00 - 20:59:59	34452	3.6%	9.57	11.2 GB	3.3%	3271.29
May 15 21:00:00 - 21:59:59	41087	4.3%	11.41	15.2 GB	4.4%	4426.97
May 15 22:00:00 - 22:59:59	38082	4.0%	10.58	14.3 GB	4.2%	4165.44
May 15 23:00:00 - 23:59:59	34251	3.6%	9.51	11.9 GB	3.5%	3471.61
May 16 00:00:00 - 00:59:59	71216	7.5%	19.78	23.4 GB	6.8%	6818.04
May 16 01:00:00 - 01:59:59	74981	7.9%	20.83	25.0 GB	7.3%	7270.57
May 16 02:00:00 - 02:59:59	58642	6.2%	16.29	19.7 GB	5.7%	5752.12
May 16 03:00:00 - 03:59:59	77870	8.2%	21.63	25.9 GB	7.5%	7548.55
May 16 04:00:00 - 04:15:02	20877	2.2%	23.15	7.3 GB	2.1%	8529.96
TOTAL: 23:59:59	948524	100.0%	10.98	343.8 GB	100.0%	4172.39

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3704	202	2950	0	552
2	news.task.gda.pl	1187	896	54	2	235
3	news.nask.pl	531	9	508	0	14
4	news.icm.edu.pl	42	0	0	0	42
TOTAL: 4		5464	1107	3512	2	843

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	82
mailing.freebsd.svn-ports-head	81
uk.railway	78
mailing.freebsd.svn-src-all	67
fa.freebsd.cvs-all	60
alt.windows7.general	54
mailing.freebsd.ports-bugs	39
mailing.freebsd.svn-src-head	37
mailing.openbsd.source-changes	35
mailing.gcc.gcc-cvs	28
it.tlc.cellulari.android	26
mailing.openbsd.ports-changes	24
alt.politics.scorched-earth	24
mailing.openbsd.misc	24
mailing.unix.samba-technical	23
mailing.freebsd.ports	23
24hoursupport.helpdesk	23
comp.sys.raspberry-pi	23
mozilla.dev.platform	17
uk.rec.cycling.moderated	15
TOTAL: 81	1107

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

Groups	Count
fr.comp.securite	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	346
EMP (fsl)	185
EMP (md5)	44
EMP (phl)	28
Attached HTML file	17
HTML post	8
Scoring filter (8)	6
TOTAL: 7	634

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	59396	1619	31599	1758	4	14	2%	23:59:57
2	panorama	42961	1544	39919	306	0	0	3%	23:59:34
3	task	1846	102	885	14	0	0	5%	23:30:23
4	poznan	11257	28	9548	5	0	0	0%	24:04:06
5	nask	25022	10	15692	1	0	0	0%	24:05:26
6	okapi	0	0	0	0	0	2759	0%	24:00:02
TOTAL: 6		140482	3303	97643	2084	4	2773	2%	143:39:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	41.1 MB	27.5 MB	68.6 MB	0.8 KB/s	20.8 KB	23:59:57
2	panorama	8.6 MB	1.3 MB	9.8 MB	0.1 KB/s	5.4 KB	23:59:34
3	task	217.7 KB	21.7 KB	239.4 KB	0.0 KB/s	2.1 KB	23:30:23
4	poznan	88.2 KB	9.1 KB	97.3 KB	0.0 KB/s	2.9 KB	24:04:06
5	nask	29.7 KB	1.3 MB	1.4 MB	0.0 KB/s	127.5 KB	24:05:26
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		50.0 MB	30.1 MB	80.1 MB	0.2 KB/s	15.2 KB	143:39:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	374	847.2 KB	288	0	00:00:09
2	web.man.lodz.pl	288	56	58.0 KB	27	0	00:00:26
3	lze200.lze.lodz.pl	8	9	13.3 KB	5	0	01:04:47
4	ip-176.105.135.22.tvsat364.lodz.pl	4	1	1.5 KB	1	0	00:22:39
5	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 5		596	440	920.0 KB	321	8	25:58:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	374	847.2 KB	288	0	00:00:09
2	*.man.lodz.pl	680	56	58.0 KB	27	8	00:00:32
3	*.lze.lodz.pl	8	9	13.3 KB	5	0	01:04:47
4	*.105.135.22.tvsat364.lodz.pl	4	1	1.5 KB	1	0	00:22:39
5	*.pa.nsw.optusnet.com.au	125	0	0.0 KB	0	0	00:03:25
6	*.torservers.net	1	0	0.0 KB	0	0	00:00:43
7	*.wroclaw.mm.pl	20	0	0.0 KB	0	0	03:05:25
8	unresolved	2	0	0.0 KB	0	0	00:00:08
9	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	21:20:40
10	*.toya.net.pl	71	0	0.0 KB	0	0	00:00:05
TOTAL: 10		1201	440	920.0 KB	321	8	25:58:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
242.191.14.62.static.jazztel.es	26.916	37.520	21:20:40
host-89-231-50-15.wroclaw.mm.pl	0.036	0.216	03:05:25
lze200.lze.lodz.pl	0.032	0.100	01:04:47
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.040	00:22:39
pa49-181-246-46.pa.nsw.optusnet.com.au	0.260	1.440	00:03:25
politkovskaja.torservers.net	0.052	0.052	00:00:43
web.man.lodz.pl	0.744	3.768	00:00:26
pc-skrzep2.cm.p.lodz.pl	0.580	3.132	00:00:09
212.51.203.199	0.004	0.024	00:00:08
staticline41435.toya.net.pl	0.144	0.812	00:00:05
mon.man.lodz.pl	0.492	3.152	00:00:04
david.man.lodz.pl	0.196	1.020	00:00:01
isvcs01.man.lodz.pl	0.040	0.084	00:00:00
TOTAL: 13	29.504	51.360	25:58:39

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-246-46.pa.nsw.optusnet.com.au	125
david.man.lodz.pl	96
staticline41435.toya.net.pl	71
host-89-231-50-15.wroclaw.mm.pl	20
242.191.14.62.static.jazztel.es	2
212.51.203.199	2
politkovskaja.torservers.net	1
TOTAL: 8	605

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	1
ip-176.105.135.22.tvsat364.lodz.pl	1	1
TOTAL: 2	3	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	374	85.0%
2	pl	66	15.0%
TOTAL: 2		440	100.0%

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

Newsgroup		Count
1	lodman.info	374
2	pl.comp.os.ms-windows.winnt	34
3	pl.comp.pecet	24
4	pl.misc.telefonia.gsm	3
5	pl.rec.kuchnia	2
6	pl.internet.polip	2
7	pl.regionalne.lodz	1
TOTAL: 7		440

Daily Usenet report

May 15 04:15:03 -- May 16 04:15:02