Daily Usenet report: Sep 15 04:15:04

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Sep 15 11:11:06 newsfeed controlchan[2377]: control_checkgroups, ^.*$ !ka.lists #20130915 usenet@karlsruhe.org usenet@karlsruhe.org @03044255463035000000036BF6FF00000068@, , , utf-8, news.task.gda.pl, mail, 1
Sep 15 11:11:06 newsfeed controlchan[2377]: control_checkgroups, ^.*$ !ka.lists #20130915 usenet@karlsruhe.org usenet@karlsruhe.org @03044255463035000000036BF6FF00000068@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Sep 15 17:00:09 newsfeed pgpverify[17022]: pgpverify: verification failed
Sep 16 00:40:02 newsfeed controlchan[2377]: Unknown "severity" control by robomod@news.nic.it

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2228294	98.5%	249.3 MB	98.1%
innd	18639	0.8%	3.1 MB	1.2%
nnrpd	7229	0.3%	754.7 KB	0.3%
innfeed	7036	0.3%	1.1 MB	0.4%
controlchan	6	0.0%	0.9 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2261205	100.0%	254.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2222911	94.8%
Negative hits	87867	3.7%
Positive hits	33187	1.4%
Cache misses	298	0.0%
TOTAL: 4	2344263	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.061	0.0%	443	0.000	18.196	336.000
article cleanup	00:00:31.224	0.0%	2227693	0.005	0.014	0.026
article logging	00:01:28.573	0.1%	2227836	0.013	0.040	0.954
article parse	01:09:10.846	4.8%	77335926	0.020	0.054	0.098
article write	00:25:41.775	1.8%	2222136	0.390	0.694	1.585
artlog/artcncl	00:00:00.008	0.0%	410	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:53.930	0.2%	90874094	0.001	0.002	0.008
hisgrep/artcncl	00:00:01.928	0.0%	301	0.000	6.405	204.000
hishave/artcncl	00:00:01.027	0.0%	443	0.000	2.318	311.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:01:48.863	12.6%	2344937	0.303	4.652	17.859
history sync	00:00:00.017	0.0%	295	0.000	0.058	1.000
history write	04:23:13.185	18.3%	2223585	2.039	7.103	22.520
hiswrite/artcncl	00:00:00.601	0.0%	142	0.000	4.232	135.000
idle	08:03:20.815	33.6%	40527738	0.196	0.716	4.240
nntp read	00:41:49.542	2.9%	75548801	0.016	0.033	0.054
overview write	00:00:14.497	0.0%	2222136	0.001	0.007	0.016
perl filter	05:40:56.223	23.7%	2223584	5.909	9.200	12.278
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:30.332	0.3%	4480962	0.028	0.060	0.090
TOTAL: 23:59:53.654	23:35:51.447	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.129	0.0%	36716	0.000	0.004	0.111
article prepare	00:00:00.009	0.0%	38172	0.000	0.000	0.062
article read	00:00:01.040	0.0%	8885	0.000	0.117	1.500
backlog stats	00:00:00.228	0.0%	271061	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.046	0.0%	161277	0.000	0.013	0.121
data write	00:00:04.795	0.0%	136336	0.000	0.035	0.333
idle	23:59:28.073	100.0%	271061	0.000	318.630	15075.000
status file	00:00:02.066	0.0%	237	0.000	8.717	219.000
TOTAL: 23:59:44.594	23:59:38.386	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.239	0.0%	30357	0.074	0.074	0.074
idle	43:16:55.244	99.9%	866051	0.000	179.915	600261.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:30.201	0.0%	1733721	0.000	0.017	0.778
readart	00:00:09.160	0.0%	30615	0.000	0.299	9.000
TOTAL: 43:18:50.671	43:17:36.844	100.0%	-	-	-	-

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
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	910	2214975	2208729	2690	3556	99%	454:58:05
2	news.task.gda.pl	21	25732	6104	18827	801	23%	44:44:11
3	news.nask.pl	2	13988	5994	6918	1076	42%	23:58:11
4	news.icm.edu.pl	41	385	14	277	94	3%	18:56:47
5	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 5		975	2255080	2220841	28712	5527	98%	542:37:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	811.9 GB	13.4 MB	51.2 MB	811.9 GB	99%	384.8 KB
2	news.nask.pl	21.8 MB	2.5 MB	2.7 MB	27.1 MB	80%	3.9 KB
3	news.task.gda.pl	11.8 MB	67.2 KB	2.1 MB	13.9 MB	84%	2.1 KB
4	news.icm.edu.pl	536.7 KB	105.1 KB	4.8 MB	5.4 MB	9%	51.2 KB
5	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 5		811.9 GB	16.1 MB	60.7 MB	812.0 GB	99%	382.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 15 04:15:04 - 04:59:59	67342	3.0%	24.98	26.6 GB	3.3%	10339.31
Sep 15 05:00:00 - 05:59:59	78099	3.5%	21.69	29.4 GB	3.6%	8549.25
Sep 15 06:00:00 - 06:59:59	96392	4.3%	26.78	37.8 GB	4.7%	11020.01
Sep 15 07:00:00 - 07:59:59	90614	4.1%	25.17	35.9 GB	4.4%	10446.70
Sep 15 08:00:00 - 08:59:59	78470	3.5%	21.80	31.4 GB	3.9%	9131.50
Sep 15 09:00:00 - 09:59:59	92994	4.2%	25.83	35.6 GB	4.4%	10359.71
Sep 15 10:00:00 - 10:59:59	90511	4.1%	25.14	33.5 GB	4.1%	9756.22
Sep 15 11:00:00 - 11:59:59	95146	4.3%	26.43	33.0 GB	4.1%	9609.68
Sep 15 12:00:00 - 12:59:59	99354	4.5%	27.60	35.8 GB	4.4%	10425.69
Sep 15 13:00:00 - 13:59:59	110900	5.0%	30.81	39.2 GB	4.8%	11423.83
Sep 15 14:00:00 - 14:59:59	100347	4.5%	27.87	36.1 GB	4.5%	10523.85
Sep 15 15:00:00 - 15:59:59	107588	4.8%	29.89	36.7 GB	4.5%	10703.64
Sep 15 16:00:00 - 16:59:59	89741	4.0%	24.93	31.6 GB	3.9%	9200.28
Sep 15 17:00:00 - 17:59:59	86971	3.9%	24.16	29.9 GB	3.7%	8698.20
Sep 15 18:00:00 - 18:59:59	100139	4.5%	27.82	35.0 GB	4.3%	10207.45
Sep 15 19:00:00 - 19:59:59	105124	4.7%	29.20	37.0 GB	4.6%	10768.58
Sep 15 20:00:00 - 20:59:59	82447	3.7%	22.90	29.0 GB	3.6%	8441.44
Sep 15 21:00:00 - 21:59:59	97030	4.4%	26.95	34.6 GB	4.3%	10076.84
Sep 15 22:00:00 - 22:59:59	92882	4.2%	25.80	32.1 GB	4.0%	9344.18
Sep 15 23:00:00 - 23:59:59	85059	3.8%	23.63	30.3 GB	3.7%	8813.16
Sep 16 00:00:00 - 00:59:59	84870	3.8%	23.57	32.1 GB	4.0%	9341.30
Sep 16 01:00:00 - 01:59:59	102836	4.6%	28.57	38.3 GB	4.7%	11146.64
Sep 16 02:00:00 - 02:59:59	88335	4.0%	24.54	33.6 GB	4.1%	9772.98
Sep 16 03:00:00 - 03:59:59	84716	3.8%	23.53	32.1 GB	3.9%	9337.35
Sep 16 04:00:00 - 04:15:02	14276	0.6%	15.83	5.4 GB	0.7%	6221.80
TOTAL: 23:59:58	2222183	100.0%	25.72	811.6 GB	100.0%	9850.24

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3662	223	3020	0	419
2	news.nask.pl	1116	11	1046	1	58
3	news.task.gda.pl	829	604	38	1	186
4	news.icm.edu.pl	94	0	5	0	89
TOTAL: 4		5701	838	4109	2	752

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	112
mailing.freebsd.ports-bugs	57
mailing.freebsd.svn-ports-all	37
mailing.freebsd.svn-ports-head	37
alt.windows7.general	36
mailing.openbsd.misc	35
fr.usenet.abus.nocem	34
mozilla.dev.tree-management	23
it.comp.os.win.windows7	22
de.rec.sf.startrek	22
24hoursupport.helpdesk	22
mailing.unix.spamassassin-talk	21
mailing.freebsd.svn-src-all	20
fa.freebsd.cvs-all	19
mailing.freebsd.svn-src-head	19
gnu.hurd.bug	18
mailing.freebsd.current	17
fr.rec.loisirs-creatifs	16
mailing.database.pgsql-general	14
mailing.openbsd.source-changes	14
TOTAL: 75	838

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

Groups	Count
christnet.poetry	1
pl.sci.historia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	312
EMP (fsl)	162
EMP (md5)	127
EMP (phl)	7
TOTAL: 4	608

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	38163	3514	32599	845	0	9%	23:58:16
2	icm	51781	3080	28866	1968	10	5%	23:51:42
3	poznan	14264	54	11998	21	0	0%	24:00:22
4	task	1644	42	867	2	0	2%	21:37:29
5	nask	13616	4	9559	2	0	0%	23:59:58
6	okapi	0	0	0	0	1616	0%	24:00:00
TOTAL: 6		119468	6694	83889	2838	1626	5%	141:27:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	156.5 MB	784.1 MB	940.6 MB	11.2 KB/s	190.8 KB	23:51:42
2	panorama	10.9 MB	3.7 MB	14.6 MB	0.2 KB/s	3.4 KB	23:58:16
3	poznan	158.2 KB	43.0 KB	201.2 KB	0.0 KB/s	2.7 KB	24:00:22
4	task	100.0 KB	4.1 KB	104.1 KB	0.0 KB/s	2.4 KB	21:37:29
5	nask	7.8 KB	761.8 KB	769.6 KB	0.0 KB/s	128.3 KB	23:59:58
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		167.7 MB	788.6 MB	956.3 MB	1.9 KB/s	102.7 KB	141:27:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	vegas.cm.p.lodz.pl	288	288	469.4 KB	288	00:00:06
2	web.man.lodz.pl	288	60	61.1 KB	28	00:00:25
3	ip-176.105.135.22.tvsat364.lodz.pl	4	2	2.5 KB	2	01:09:32
TOTAL: 3		580	350	533.1 KB	318	43:18:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cm.p.lodz.pl	288	288	469.4 KB	288	00:00:06
2	*.man.lodz.pl	672	60	61.1 KB	28	00:00:30
3	*.105.135.22.tvsat364.lodz.pl	4	2	2.5 KB	2	01:09:32
4	*.neoplus.adsl.tpnet.pl	10	0	0.0 KB	0	00:07:14
5	*.lodz.mm.pl	70	0	0.0 KB	0	11:30:56
6	*.181.14.62.static.jazztel.es	4	0	0.0 KB	0	30:30:27
7	*.toya.net.pl	4	0	0.0 KB	0	00:00:00
TOTAL: 7		1052	350	533.1 KB	318	43:18:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
205.181.14.62.static.jazztel.es	53.999	89.754	30:30:27
host-89-228-165-199.lodz.mm.pl	0.152	0.848	11:30:56
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.056	01:09:32
acvh123.neoplus.adsl.tpnet.pl	0.020	0.136	00:07:14
web.man.lodz.pl	0.800	4.404	00:00:25
vegas.cm.p.lodz.pl	0.708	3.956	00:00:06
f-oper-v100.man.lodz.pl	0.548	3.412	00:00:03
david.man.lodz.pl	0.204	1.296	00:00:02
staticline41435.toya.net.pl	0.008	0.072	00:00:00
TOTAL: 9	56.447	103.934	43:18:50

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
host-89-228-165-199.lodz.mm.pl	70
acvh123.neoplus.adsl.tpnet.pl	10
205.181.14.62.static.jazztel.es	4
staticline41435.toya.net.pl	4
TOTAL: 6	472

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	288	82.3%
2	pl	62	17.7%
TOTAL: 2		350	100.0%

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

Newsgroup		Count
1	lodman.info	288
2	pl.comp.os.ms-windows.winnt	36
3	pl.comp.pecet	24
4	pl.soc.dzieci	1
5	pl.regionalne.lodz	1
TOTAL: 5		350

Daily Usenet report

Sep 15 04:15:04 -- Sep 16 04:15:02