Daily Usenet report: Dec 15 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 15 11:11:05 newsfeed controlchan[2208]: control_checkgroups, ^.*$ !ka.lists #20131215 usenet@karlsruhe.org usenet@karlsruhe.org @030442554630350000000A90BE330000006A@, , , utf-8, news.task.gda.pl, mail, 1
Dec 15 11:11:07 newsfeed controlchan[2208]: control_checkgroups, ^.*$ !ka.lists #20131215 usenet@karlsruhe.org usenet@karlsruhe.org @030442554630350000000A90BE330000006A@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Dec 15 17:00:08 newsfeed pgpverify[3378]: pgpverify: verification failed
Dec 15 23:26:24 newsfeed overchan[2210]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2164503	98.5%	245.7 MB	98.0%
innd	18305	0.8%	3.0 MB	1.2%
nnrpd	8408	0.4%	874.9 KB	0.3%
innfeed	7061	0.3%	1.1 MB	0.4%
controlchan	5	0.0%	0.7 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	2198284	100.0%	250.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2157266	95.4%
Negative hits	66960	3.0%
Positive hits	37063	1.6%
Cache misses	276	0.0%
TOTAL: 4	2261565	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:16.843	0.0%	347	0.000	48.539	573.000
article cleanup	00:00:37.042	0.0%	2164045	0.006	0.017	0.032
article logging	00:01:06.400	0.1%	2164180	0.011	0.031	0.437
article parse	01:22:06.975	5.7%	197235323	0.009	0.025	0.051
article write	00:17:57.773	1.2%	2157712	0.295	0.499	0.976
artlog/artcncl	00:00:00.010	0.0%	344	0.000	0.029	1.000
artlog/artparse	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:03:00.172	0.2%	211520033	0.000	0.001	0.003
hisgrep/artcncl	00:00:01.648	0.0%	303	0.000	5.439	268.000
hishave/artcncl	00:00:01.623	0.0%	347	0.000	4.677	261.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:11:48.410	13.3%	2263615	0.011	5.084	36.162
history sync	00:00:00.017	0.0%	292	0.000	0.058	2.000
history write	02:51:52.622	11.9%	2159250	0.617	4.776	53.404
hiswrite/artcncl	00:00:00.084	0.0%	44	0.000	1.909	14.000
idle	08:57:15.622	37.3%	118490032	0.164	0.272	0.824
nntp read	01:08:52.205	4.8%	195494351	0.009	0.021	0.037
overview write	00:00:13.184	0.0%	2157712	0.001	0.006	0.015
perl filter	05:15:47.735	21.9%	2159242	5.382	8.775	11.188
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:24.050	0.3%	4350081	0.015	0.061	0.102
TOTAL: 24:00:01.435	23:15:22.415	96.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.118	0.0%	34654	0.000	0.003	0.143
article prepare	00:00:00.008	0.0%	46014	0.000	0.000	0.050
article read	00:00:00.618	0.0%	10638	0.000	0.058	1.000
backlog stats	00:00:00.186	0.0%	269513	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.848	0.0%	163183	0.000	0.011	1.000
data write	00:00:04.949	0.0%	134283	0.000	0.037	2.109
idle	23:58:52.046	99.9%	269513	0.000	320.326	15071.000
status file	00:00:43.165	0.0%	241	0.000	179.108	18132.000
TOTAL: 23:59:49.361	23:59:42.938	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	28:01:43.941	100.0%	133716	0.000	754.614	540736.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.594	0.0%	269243	0.000	0.017	3.444
readart	00:00:01.000	0.0%	378	0.000	2.646	307.000
TOTAL: 28:02:30.535	28:01:49.535	100.0%	-	-	-	-

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	912	2151081	2144836	2047	4198	99%	356:39:12
2	news.task.gda.pl	18	28366	6945	20353	1068	24%	44:15:15
3	news.nask.pl	4	16465	5830	9568	1067	35%	23:42:01
4	news.icm.edu.pl	51	202	5	193	4	2%	15:30:31
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		986	2196114	2157616	32161	6337	98%	440:06:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	755.2 GB	144.1 MB	157.8 MB	755.5 GB	99%	368.6 KB
2	news.nask.pl	25.1 MB	3.1 MB	3.2 MB	31.4 MB	79%	4.7 KB
3	news.task.gda.pl	12.9 MB	31.8 KB	3.0 MB	15.9 MB	81%	2.0 KB
4	news.icm.edu.pl	60.5 KB	0.0 KB	52.8 KB	113.3 KB	53%	12.6 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		755.3 GB	147.3 MB	164.0 MB	755.6 GB	99%	366.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 15 04:15:02 - 04:59:59	62223	2.9%	23.06	23.6 GB	3.1%	9178.06
Dec 15 05:00:00 - 05:59:59	73141	3.4%	20.32	27.1 GB	3.6%	7886.61
Dec 15 06:00:00 - 06:59:59	78174	3.6%	21.71	27.9 GB	3.7%	8129.32
Dec 15 07:00:00 - 07:59:59	80640	3.7%	22.40	30.0 GB	4.0%	8741.10
Dec 15 08:00:00 - 08:59:59	85510	4.0%	23.75	34.5 GB	4.6%	10049.61
Dec 15 09:00:00 - 09:59:59	87931	4.1%	24.43	31.6 GB	4.2%	9193.21
Dec 15 10:00:00 - 10:59:59	88586	4.1%	24.61	31.0 GB	4.1%	9041.55
Dec 15 11:00:00 - 11:59:59	66649	3.1%	18.51	24.1 GB	3.2%	7011.67
Dec 15 12:00:00 - 12:59:59	81608	3.8%	22.67	28.7 GB	3.8%	8369.85
Dec 15 13:00:00 - 13:59:59	79208	3.7%	22.00	28.8 GB	3.8%	8390.85
Dec 15 14:00:00 - 14:59:59	84373	3.9%	23.44	31.5 GB	4.2%	9184.55
Dec 15 15:00:00 - 15:59:59	99144	4.6%	27.54	35.8 GB	4.7%	10424.01
Dec 15 16:00:00 - 16:59:59	101342	4.7%	28.15	35.4 GB	4.7%	10317.98
Dec 15 17:00:00 - 17:59:59	107688	5.0%	29.91	36.0 GB	4.8%	10490.88
Dec 15 18:00:00 - 18:59:59	106690	4.9%	29.64	35.7 GB	4.7%	10405.80
Dec 15 19:00:00 - 19:59:59	100713	4.7%	27.98	35.0 GB	4.6%	10200.67
Dec 15 20:00:00 - 20:59:59	78656	3.6%	21.85	26.8 GB	3.6%	7804.65
Dec 15 21:00:00 - 21:59:59	95767	4.4%	26.60	32.7 GB	4.3%	9526.98
Dec 15 22:00:00 - 22:59:59	101689	4.7%	28.25	34.7 GB	4.6%	10116.82
Dec 15 23:00:00 - 23:59:59	99095	4.6%	27.53	33.5 GB	4.4%	9771.49
Dec 16 00:00:00 - 00:59:59	86131	4.0%	23.93	27.4 GB	3.6%	7969.74
Dec 16 01:00:00 - 01:59:59	106520	4.9%	29.59	34.4 GB	4.6%	10033.11
Dec 16 02:00:00 - 02:59:59	101960	4.7%	28.32	34.0 GB	4.5%	9895.50
Dec 16 03:00:00 - 03:59:59	86449	4.0%	24.01	28.2 GB	3.7%	8210.02
Dec 16 04:00:00 - 04:15:02	17799	0.8%	19.73	5.9 GB	0.8%	6875.46
TOTAL: 24:00:00	2157686	100.0%	24.97	754.5 GB	100.0%	9156.96

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4291	173	3757	10	351
2	news.nask.pl	1107	17	1026	5	59
3	news.task.gda.pl	1070	851	16	1	202
4	news.icm.edu.pl	5	0	0	0	5
TOTAL: 4		6473	1041	4799	16	617

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	102
mailing.freebsd.svn-ports-head	102
uk.railway	93
mailing.freebsd.svn-src-all	45
alt.windows7.general	42
fa.freebsd.cvs-all	42
mailing.freebsd.current	39
fr.usenet.abus.nocem	39
mozilla.dev.tree-management	37
24hoursupport.helpdesk	36
mailing.freebsd.ports-bugs	36
mailing.freebsd.svn-src-head	33
gnu.hurd.bug	28
mailing.openbsd.ports-changes	24
fr.rec.loisirs-creatifs	21
mailing.postfix.users	19
comp.sys.raspberry-pi	19
mailing.freebsd.tinderbox	19
mailing.openbsd.source-changes	18
mailing.freebsd.ports	17
TOTAL: 69	1041

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

Groups	Count
pl.sci.historia	10
pl.sci.matematyka	5
fr.comp.securite	1
TOTAL: 3	16

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	190
EMP (fsl)	184
Attached HTML file	67
EMP (md5)	29
Scoring filter (8)	2
HTML post	1
Scoring filter (12)	1
TOTAL: 7	474

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	51746	5047	26522	2778	13	0	9%	24:00:48
2	panorama	36281	2942	31239	523	0	0	8%	24:03:37
3	poznan	14991	96	12522	47	0	0	0%	23:56:27
4	task	1575	55	797	5	0	0	3%	20:25:50
5	nask	18122	10	11891	1	0	0	0%	23:58:21
6	okapi	0	0	0	0	0	1654	0%	24:00:01
TOTAL: 6		122715	8150	82971	3354	13	1654	6%	140:25:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	23.6 MB	242.3 MB	265.9 MB	3.1 KB/s	34.8 KB	24:00:48
2	panorama	13.9 MB	1.5 MB	15.5 MB	0.2 KB/s	4.6 KB	24:03:37
3	poznan	294.2 KB	89.5 KB	383.7 KB	0.0 KB/s	2.7 KB	23:56:27
4	task	113.8 KB	11.8 KB	125.5 KB	0.0 KB/s	2.1 KB	20:25:50
5	nask	42.6 KB	11.1 KB	53.7 KB	0.0 KB/s	4.9 KB	23:58:21
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		38.0 MB	243.9 MB	281.9 MB	0.6 KB/s	25.1 KB	140:25:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	vegas.cm.p.lodz.pl	288	288	492.2 KB	288	00:00:13
2	web.man.lodz.pl	288	52	46.9 KB	24	00:00:25
3	212.191.78.190	147	38	71.2 KB	12	21:26:15
TOTAL: 3		723	378	610.2 KB	324	28:02:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cm.p.lodz.pl	288	288	492.2 KB	288	00:00:13
2	*.man.lodz.pl	672	52	46.9 KB	24	00:00:32
3	unresolved	147	38	71.2 KB	12	21:26:15
4	*.181.14.62.static.jazztel.es	5	0	0.0 KB	0	05:11:05
5	*.105.135.22.tvsat364.lodz.pl	6	0	0.0 KB	0	01:24:17
6	*.osk.mesh.ad.jp	1	0	0.0 KB	0	00:00:01
7	*.toya.net.pl	38	0	0.0 KB	0	00:00:03
TOTAL: 7		1157	378	610.2 KB	324	28:02:29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.312	1.936	21:26:15
219.181.14.62.static.jazztel.es	7.348	10.652	05:11:05
ip-176.105.135.22.tvsat364.lodz.pl	0.012	0.088	01:24:17
web.man.lodz.pl	0.656	4.472	00:00:25
vegas.cm.p.lodz.pl	0.704	4.472	00:00:13
mon.man.lodz.pl	0.568	3.844	00:00:04
staticline41435.toya.net.pl	0.096	0.488	00:00:03
eac1aao210.osk.mesh.ad.jp	0.000	0.016	00:00:01
david.man.lodz.pl	0.176	1.368	00:00:01
TOTAL: 9	9.872	27.336	28:02:29

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	38
ip-176.105.135.22.tvsat364.lodz.pl	6
219.181.14.62.static.jazztel.es	5
eac1aao210.osk.mesh.ad.jp	1
TOTAL: 6	434

NNRP client timeouts [Top 20]:

System	Conn	Peer
219.181.14.62.static.jazztel.es	1	1
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	288	76.2%
2	pl	90	23.8%
TOTAL: 2		378	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.pecet	34
3	pl.misc.kolej	24
4	pl.comp.os.ms-windows.winnt	18
5	pl.comp.os.linux	7
6	pl.rec.gory	3
7	pl.soc.prawo	2
8	pl.praca.dyskusje	1
9	pl.rec.rowery	1
TOTAL: 9		378

Daily Usenet report

Dec 15 04:15:02 -- Dec 16 04:15:02