Daily Usenet report: Jul 15 04:15:04

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jul 15 11:11:03 newsfeed controlchan[2487]: control_checkgroups, ^.*$ !ka.lists #20130715 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000008D3E84D00000066@, , , utf-8, news.task.gda.pl, mail, 1
Jul 15 11:11:03 newsfeed controlchan[2487]: control_checkgroups, ^.*$ !ka.lists #20130715 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000008D3E84D00000066@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Jul 15 12:47:49 newsfeed nnrpd[18282]: SERVER perl filter not defined
Jul 15 12:50:53 newsfeed nnrpd[18361]: SERVER perl filter not defined
Jul 15 14:53:33 newsfeed nnrpd[22176]: SERVER perl filter not defined
Jul 15 17:00:08 newsfeed pgpverify[26303]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1401645	98.1%	158.3 MB	97.5%
innd	13195	0.9%	2.2 MB	1.4%
innfeed	7229	0.5%	1.0 MB	0.6%
nnrpd	7027	0.5%	727.7 KB	0.4%
controlchan	5	0.0%	0.8 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1429102	100.0%	162.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1396045	93.6%
Negative hits	54237	3.6%
Positive hits	40073	2.7%
Cache misses	529	0.0%
TOTAL: 4	1490884	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:52.069	0.1%	2770	0.000	18.797	287.000
article cleanup	00:00:18.711	0.0%	1398528	0.000	0.013	0.046
article logging	00:00:41.871	0.0%	1398698	0.007	0.030	0.982
article parse	00:50:29.977	3.5%	77510041	0.013	0.039	0.090
article write	00:17:24.150	1.2%	1393173	0.440	0.749	3.253
artlog/artcncl	00:00:00.049	0.0%	2752	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:37.554	0.1%	86697305	0.000	0.001	0.005
hisgrep/artcncl	00:00:01.552	0.0%	298	0.000	5.208	92.667
hishave/artcncl	00:00:19.333	0.0%	2770	0.000	6.979	269.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:18:45.695	9.6%	1489904	0.091	5.588	13.834
history sync	00:00:00.009	0.0%	291	0.000	0.031	1.000
history write	03:11:26.670	13.3%	1395076	0.399	8.234	52.571
hiswrite/artcncl	00:00:26.451	0.0%	2472	0.000	10.700	49.000
idle	12:34:17.863	52.4%	59984581	0.163	0.754	3.167
nntp read	00:34:39.724	2.4%	76493952	0.012	0.027	0.048
overview write	00:00:08.538	0.0%	1393173	0.000	0.006	0.018
perl filter	03:40:06.697	15.3%	1395075	4.927	9.467	14.654
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:24.038	0.2%	2830885	0.015	0.051	0.081
TOTAL: 23:59:31.849	23:34:00.951	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.161	0.0%	44666	0.000	0.004	0.143
article prepare	00:00:00.015	0.0%	53888	0.000	0.000	0.050
article read	00:00:19.783	0.0%	12987	0.000	1.523	289.000
backlog stats	00:00:00.238	0.0%	354143	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.012	0.0%	204267	0.000	0.010	0.071
data write	00:00:06.270	0.0%	175649	0.000	0.036	1.000
idle	23:59:28.613	100.0%	354143	0.000	243.881	30100.000
status file	00:00:00.989	0.0%	244	0.000	4.053	219.000
TOTAL: 24:00:05.614	23:59:58.081	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:35:35.751	99.8%	4157	0.000	3111.800	360407.400
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.192	0.0%	10061	0.000	0.019	1.000
readart	00:00:00.510	0.0%	412	0.000	1.238	22.615
TOTAL: 03:35:55.788	03:35:36.453	99.9%	-	-	-	-

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	520	1388111	1382945	1746	3420	99%	261:57:50
2	news.task.gda.pl	17	29230	5244	22874	1112	17%	44:55:45
3	news.nask.pl	1	17623	5031	11814	778	28%	23:38:54
4	news.icm.edu.pl	37	343	11	278	54	3%	19:41:17
5	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 5		579	1435310	1393234	36712	5364	97%	350:13:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	519.4 GB	11.8 MB	43.5 MB	519.5 GB	99%	392.9 KB
2	news.nask.pl	18.0 MB	2.0 MB	2.6 MB	22.7 MB	79%	4.0 KB
3	news.task.gda.pl	11.4 MB	45.7 KB	3.7 MB	15.2 MB	75%	2.4 KB
4	news.icm.edu.pl	345.5 KB	14.3 KB	3.5 MB	3.9 MB	8%	60.8 KB
5	localhost	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	1.4 KB
TOTAL: 5		519.4 GB	13.9 MB	53.4 MB	519.5 GB	99%	389.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 15 04:15:04 - 04:59:59	71342	5.1%	26.46	26.6 GB	5.1%	10331.27
Jul 15 05:00:00 - 05:59:59	101560	7.3%	28.21	37.0 GB	7.1%	10780.90
Jul 15 06:00:00 - 06:59:59	95575	6.9%	26.55	36.5 GB	7.0%	10626.37
Jul 15 07:00:00 - 07:59:59	45276	3.2%	12.58	19.9 GB	3.8%	5794.40
Jul 15 08:00:00 - 08:59:59	35739	2.6%	9.93	15.3 GB	2.9%	4444.93
Jul 15 09:00:00 - 09:59:59	82041	5.9%	22.79	30.6 GB	5.9%	8915.12
Jul 15 10:00:00 - 10:59:59	51986	3.7%	14.44	19.1 GB	3.7%	5561.66
Jul 15 11:00:00 - 11:59:59	50437	3.6%	14.01	19.3 GB	3.7%	5613.47
Jul 15 12:00:00 - 12:59:59	44723	3.2%	12.42	16.2 GB	3.1%	4721.69
Jul 15 13:00:00 - 13:59:59	40217	2.9%	11.17	14.4 GB	2.8%	4205.85
Jul 15 14:00:00 - 14:59:59	46555	3.3%	12.93	16.9 GB	3.3%	4919.95
Jul 15 15:00:00 - 15:59:59	45593	3.3%	12.66	18.1 GB	3.5%	5269.31
Jul 15 16:00:00 - 16:59:59	40758	2.9%	11.32	17.4 GB	3.3%	5058.40
Jul 15 17:00:00 - 17:59:59	44373	3.2%	12.33	18.6 GB	3.6%	5426.44
Jul 15 18:00:00 - 18:59:59	47657	3.4%	13.24	18.8 GB	3.6%	5464.73
Jul 15 19:00:00 - 19:59:59	51246	3.7%	14.23	19.3 GB	3.7%	5623.77
Jul 15 20:00:00 - 20:59:59	47423	3.4%	13.17	18.6 GB	3.6%	5414.86
Jul 15 21:00:00 - 21:59:59	40511	2.9%	11.25	17.3 GB	3.3%	5040.82
Jul 15 22:00:00 - 22:59:59	23520	1.7%	6.53	10.5 GB	2.0%	3043.89
Jul 15 23:00:00 - 23:59:59	33096	2.4%	9.19	13.9 GB	2.7%	4037.07
Jul 16 00:00:00 - 00:59:59	75630	5.4%	21.01	28.1 GB	5.4%	8176.60
Jul 16 01:00:00 - 01:59:59	74958	5.4%	20.82	22.1 GB	4.3%	6439.49
Jul 16 02:00:00 - 02:59:59	107668	7.7%	29.91	31.8 GB	6.1%	9273.27
Jul 16 03:00:00 - 03:59:59	82754	5.9%	22.99	28.4 GB	5.5%	8270.60
Jul 16 04:00:00 - 04:15:02	12729	0.9%	14.11	4.9 GB	0.9%	5689.02
TOTAL: 23:59:58	1393367	100.0%	16.13	519.4 GB	100.0%	6303.73

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3548	142	2674	1	731
2	news.task.gda.pl	1105	944	22	3	136
3	news.nask.pl	818	3	756	0	59
4	news.icm.edu.pl	55	0	1	0	54
TOTAL: 4		5526	1089	3453	4	980

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-head	71
mailing.freebsd.svn-ports-all	70
gmane.linux.alsa.devel	55
alt.politics.scorched-earth	49
uk.railway	42
mailing.www.horde-bugs	42
mailing.freebsd.current	37
mailing.openbsd.ports-changes	34
fa.freebsd.cvs-all	34
mailing.freebsd.ports-bugs	32
mailing.freebsd.svn-src-all	31
24hoursupport.helpdesk	31
mozilla.dev.platform	29
alt.windows7.general	29
gnu.hurd.bug	22
mailing.openbsd.source-changes	21
mailing.freebsd.svn-src-head	21
mozilla.dev.identity	20
mailing.gcc.gcc-cvs	19
mailing.database.pgsql-general	19
TOTAL: 79	1089

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

Groups	Count
fr.comp.lang.php	3
pl.sci.matematyka	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	609
EMP (fsl)	96
EMP (md5)	82
HTML post	13
Attached HTML file	5
Scoring filter (8)	2
Scoring filter (12)	1
Scoring filter (9)	1
TOTAL: 8	809

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	51101	3028	40758	923	0	5%	24:01:22
2	icm	64901	1419	33154	8144	96	2%	24:00:14
3	task	2080	57	1072	9	8	2%	20:54:05
4	poznan	12174	19	10269	11	0	0%	24:07:45
5	nask	21779	13	14265	0	0	0%	24:03:35
6	okapi	0	0	0	0	2585	0%	24:00:01
TOTAL: 6		152035	4536	99518	9087	2689	2%	141:07:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	38.8 MB	1.7 GB	1.7 GB	21.1 KB/s	190.6 KB	24:00:14
2	panorama	8.6 MB	16.4 MB	25.0 MB	0.3 KB/s	6.5 KB	24:01:22
3	poznan	142.3 KB	24.6 KB	166.9 KB	0.0 KB/s	5.6 KB	24:07:45
4	task	125.5 KB	35.4 KB	160.9 KB	0.0 KB/s	2.4 KB	20:54:05
5	nask	31.2 KB	0.0 KB	31.2 KB	0.0 KB/s	2.4 KB	24:03:35
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		47.7 MB	1.7 GB	1.8 GB	3.6 KB/s	135.7 KB	141:07:02

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	324	480.5 KB	288	0	00:00:07
2	web.man.lodz.pl	288	50	45.9 KB	24	0	00:00:17
3	lze200.lze.lodz.pl	4	22	43.2 KB	6	0	00:45:15
4	router2.cbmm.lodz.pl	3	6	9.0 KB	1	0	00:16:55
5	ip-176.105.135.22.tvsat364.lodz.pl	4	4	5.7 KB	3	0	01:45:17
6	ebo215.neoplus.adsl.tpnet.pl	3	3	4.3 KB	2	0	00:41:52
7	178-37-153-219.adsl.inetia.pl	2	2	2.9 KB	1	0	00:04:36
8	212.51.203.199	2	1	1.6 KB	1	0	00:00:51
9	kujawiak.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 9		597	412	593.2 KB	326	3	03:35:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	324	480.5 KB	288	0	00:00:07
2	*.man.lodz.pl	675	50	45.9 KB	24	3	00:00:26
3	*.lze.lodz.pl	4	22	43.2 KB	6	0	00:45:15
4	*.cbmm.lodz.pl	3	6	9.0 KB	1	0	00:16:55
5	*.105.135.22.tvsat364.lodz.pl	4	4	5.7 KB	3	0	01:45:17
6	*.neoplus.adsl.tpnet.pl	3	3	4.3 KB	2	0	00:41:52
7	*.adsl.inetia.pl	2	2	2.9 KB	1	0	00:04:36
8	unresolved	2	1	1.6 KB	1	0	00:00:51
9	*.internetdsl.tpnet.pl	6	0	0.0 KB	0	0	00:00:01
10	*.toya.net.pl	12	0	0.0 KB	0	0	00:00:31
TOTAL: 10		999	412	593.2 KB	326	3	03:35:55

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
ip-176.105.135.22.tvsat364.lodz.pl	0.016	0.060	01:45:17
lze200.lze.lodz.pl	0.008	0.080	00:45:15
ebo215.neoplus.adsl.tpnet.pl	0.012	0.036	00:41:52
router2.cbmm.lodz.pl	0.012	0.036	00:16:55
178-37-153-219.adsl.inetia.pl	0.008	0.028	00:04:36
212.51.203.199	0.000	0.032	00:00:51
staticline41435.toya.net.pl	0.020	0.172	00:00:31
web.man.lodz.pl	0.672	3.828	00:00:17
vegas.cm.p.lodz.pl	0.712	4.008	00:00:07
f-oper-v100.man.lodz.pl	0.616	4.344	00:00:07
gcq193.internetdsl.tpnet.pl	0.012	0.092	00:00:01
david.man.lodz.pl	0.200	1.312	00:00:01
kujawiak.man.lodz.pl	0.012	0.048	00:00:00
TOTAL: 13	2.300	14.076	03:35:55

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	12
gcq193.internetdsl.tpnet.pl	6
TOTAL: 4	402

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	329	79.9%
2	pl	83	20.1%
TOTAL: 2		412	100.0%

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

Newsgroup		Count
1	lodman.info	329
2	pl.comp.pecet	41
3	pl.comp.os.ms-windows.winnt	25
4	pl.regionalne.lodz	10
5	pl.rec.kuchnia	4
6	pl.rec.gory	2
7	pl.rec.radio.amatorskie	1
TOTAL: 7		412

Daily Usenet report

Jul 15 04:15:04 -- Jul 16 04:15:02