Daily Usenet report for csiph.com: May 16 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	328534	85.5%	33.8 MB	76.9%
inn	35715	9.3%	7.7 MB	17.5%
innd	16887	4.4%	1.9 MB	4.4%
innfeed	3212	0.8%	535.7 KB	1.2%
nocem	67	0.0%	6.7 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	384417	100.0%	43.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	423083	56.8%
Negative hits	286958	38.5%
Do not exist	34667	4.7%
Cache misses	491	0.1%
TOTAL: 4	745199	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.992	0.0%	252	0.000	3.937	43.000
article cleanup	00:00:00.212	0.0%	33691	0.000	0.006	0.022
article logging	00:00:05.744	0.0%	34257	0.000	0.168	2.041
article parse	00:00:09.574	0.0%	158252	0.006	0.060	0.725
article write	00:03:53.042	0.3%	24439	0.218	9.536	67.428
artlog/artcncl	00:00:00.028	0.0%	227	0.000	0.123	3.125
artlog/artparse	00:00:00.106	0.0%	1056	0.000	0.100	2.500
data move	00:00:00.612	0.0%	766615	0.000	0.001	0.042
hisgrep/artcncl	00:00:00.045	0.0%	250	0.000	0.180	10.000
hishave/artcncl	00:00:00.020	0.0%	252	0.000	0.079	8.000
hishave/artparse	00:00:00.002	0.0%	1056	0.000	0.002	0.016
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:33.507	0.2%	745421	0.001	0.206	1.005
history sync	00:00:06.297	0.0%	332	0.000	18.967	96.000
history write	00:04:08.000	0.3%	33677	0.064	7.364	41.387
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:07.250	0.0%	1056	0.000	6.866	122.000
idle	23:28:43.606	98.5%	741424	42.320	114.002	317.261
nntp read	00:00:03.963	0.0%	775869	0.002	0.005	0.072
overview write	00:08:01.883	0.6%	24439	1.241	19.718	212.188
perl filter	00:01:09.138	0.1%	33675	1.535	2.053	2.654
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.436	0.0%	48938	0.000	0.009	0.023
TOTAL: 23:50:41.219	23:49:04.457	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.067	0.0%	24474	0.000	0.003	0.016
article prepare	00:00:00.005	0.0%	29288	0.000	0.000	0.010
article read	00:00:01.462	0.0%	4634	0.000	0.315	13.500
backlog stats	00:00:00.243	0.0%	434377	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.297	0.0%	343696	0.002	0.007	0.013
data write	00:00:01.990	0.0%	325771	0.003	0.006	0.010
idle	23:53:32.628	100.0%	434377	103.668	198.014	425.767
status file	00:00:00.380	0.0%	281	0.000	1.352	31.000
TOTAL: 23:53:49.854	23:53:39.072	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.916	0.0%	1394	4.244	4.244	4.244
idle	42:46:18.569	99.1%	612058	96.548	251.575	600047.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.050	0.0%	1434094	0.000	0.006	0.125
readart	00:21:48.767	0.8%	246344	0.000	5.313	61.000
TOTAL: 43:10:39.193	43:08:21.302	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	138
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	158

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	138187	6416	125482	6289	4%	118:43:39
2	csiph-out.news.weretis.net	2	45893	6268	39232	393	13%	42:17:43
3	feeder.eternal-september.org	5	31261	3010	26684	1567	9%	24:38:01
4	news-out.aioe.org	3	24507	1841	22614	52	7%	24:09:48
5	news-out.mixmin.net	2	25667	1635	23608	424	6%	24:08:52
6	newsfeed.neva.ru	148	34483	1577	31919	987	4%	71:24:23
7	newsfeed.fu-berlin.de	42	26285	1138	24798	349	4%	34:20:59
8	news.redatomik.org	1	23391	802	22579	10	3%	24:31:56
9	newsfeeds.xmission.com	3	23863	760	23094	9	3%	25:35:04
10	news.fcku.it	3	11192	458	10584	150	4%	23:59:31
11	news.freedyn.net	3	26137	337	25653	147	1%	24:09:45
12	news.swapon.de	1	1063	36	1027	0	3%	12:55:47
13	nntp.pasdenom.info	2	22979	28	22949	2	0%	23:59:24
14	xanadu-bbs.net	3	165	22	143	0	13%	07:01:04
15	localhost	42	9	9	0	0	100%	00:00:13
16	news.bbs.geek.nz	2	22074	6	22068	0	0%	23:59:45
TOTAL: 16		1702	457156	24343	422434	10379	5%	505:55:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	26.0 MB	57.3 KB	33.3 MB	59.3 MB	43%	4.8 KB
2	csiph-out.news.weretis.net	17.6 MB	0.0 KB	4.4 MB	22.0 MB	80%	3.4 KB
3	feeder.eternal-september.org	8.2 MB	4.3 KB	139.2 MB	147.3 MB	5%	33.0 KB
4	news-out.mixmin.net	5.9 MB	4.3 KB	1.3 MB	7.1 MB	82%	3.5 KB
5	news-out.aioe.org	4.7 MB	4.2 KB	110.9 KB	4.8 MB	97%	2.6 KB
6	newsfeed.neva.ru	4.2 MB	101.2 KB	4.2 MB	8.5 MB	49%	3.4 KB
7	news.redatomik.org	2.6 MB	4.3 KB	186.6 KB	2.8 MB	93%	3.5 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	889.9 KB	3.4 MB	74%	2.3 KB
9	newsfeeds.xmission.com	2.2 MB	4.3 KB	374.5 KB	2.6 MB	85%	3.4 KB
10	news.freedyn.net	1.0 MB	0.0 KB	2.9 MB	3.9 MB	26%	8.3 KB
11	news.fcku.it	1015.8 KB	0.0 KB	982.6 KB	2.0 MB	50%	3.3 KB
12	news.swapon.de	97.6 KB	0.0 KB	0.0 KB	97.6 KB	100%	2.7 KB
13	nntp.pasdenom.info	62.1 KB	0.0 KB	5.5 KB	67.7 KB	91%	2.3 KB
14	xanadu-bbs.net	56.5 KB	0.0 KB	0.0 KB	56.5 KB	100%	2.6 KB
15	localhost	13.9 KB	0.0 KB	0.0 KB	13.9 KB	100%	1.5 KB
16	news.bbs.geek.nz	9.9 KB	0.0 KB	0.0 KB	9.9 KB	100%	1.7 KB
TOTAL: 16		76.2 MB	180.0 KB	187.7 MB	264.0 MB	28%	7.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 16 04:15:00 - 04:59:59	638	2.6%	0.24	3.6 MB	4.7%	1.37
May 16 05:00:00 - 05:59:59	740	3.0%	0.21	3.6 MB	4.7%	1.03
May 16 06:00:00 - 06:59:59	791	3.2%	0.22	2.5 MB	3.3%	0.72
May 16 07:00:00 - 07:59:59	1073	4.4%	0.30	3.5 MB	4.6%	1.01
May 16 08:00:00 - 08:59:59	987	4.0%	0.27	2.7 MB	3.5%	0.76
May 16 09:00:00 - 09:59:59	1084	4.4%	0.30	2.7 MB	3.6%	0.78
May 16 10:00:00 - 10:59:59	957	3.9%	0.27	2.9 MB	3.8%	0.83
May 16 11:00:00 - 11:59:59	1022	4.2%	0.28	2.9 MB	3.8%	0.83
May 16 12:00:00 - 12:59:59	1259	5.1%	0.35	3.6 MB	4.6%	1.01
May 16 13:00:00 - 13:59:59	1129	4.6%	0.31	2.9 MB	3.8%	0.84
May 16 14:00:00 - 14:59:59	1345	5.5%	0.37	3.4 MB	4.4%	0.96
May 16 15:00:00 - 15:59:59	1572	6.4%	0.44	5.6 MB	7.2%	1.58
May 16 16:00:00 - 16:59:59	1367	5.6%	0.38	4.6 MB	6.0%	1.30
May 16 17:00:00 - 17:59:59	1404	5.7%	0.39	4.5 MB	5.8%	1.27
May 16 18:00:00 - 18:59:59	1319	5.4%	0.37	3.8 MB	5.0%	1.09
May 16 19:00:00 - 19:59:59	1408	5.7%	0.39	4.7 MB	6.2%	1.35
May 16 20:00:00 - 20:59:59	1258	5.1%	0.35	3.9 MB	5.0%	1.10
May 16 21:00:00 - 21:59:59	1101	4.5%	0.31	2.8 MB	3.7%	0.81
May 16 22:00:00 - 22:59:59	1054	4.3%	0.29	3.3 MB	4.3%	0.95
May 16 23:00:00 - 23:59:59	633	2.6%	0.18	1.7 MB	2.3%	0.50
May 17 00:00:00 - 00:59:59	569	2.3%	0.16	1.6 MB	2.1%	0.45
May 17 01:00:00 - 01:59:59	581	2.4%	0.16	1.6 MB	2.1%	0.47
May 17 02:00:00 - 02:59:59	484	2.0%	0.13	1.5 MB	2.0%	0.43
May 17 03:00:00 - 03:59:59	584	2.4%	0.16	2.0 MB	2.5%	0.56
May 17 04:00:00 - 04:15:00	177	0.7%	0.20	678.4 KB	0.9%	0.75
TOTAL: 24:00:00	24536	100.0%	0.28	76.8 MB	100.0%	0.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	6342	321	7	3	6011
2	feeder.eternal-september.org	1584	57	0	0	1527
3	newsfeed.neva.ru	1007	204	7	0	796
4	newsfeed.fu-berlin.de	736	16	0	0	720
5	csiph-out.news.weretis.net	455	208	0	0	247
6	news-out.mixmin.net	454	0	0	0	454
7	news.fcku.it	154	13	0	0	141
8	news.freedyn.net	124	7	0	0	117
9	news-out.aioe.org	53	26	0	0	27
10	news.redatomik.org	13	0	0	0	13
11	newsfeeds.xmission.com	9	0	0	0	9
12	news.swapon.de	9	0	0	0	9
13	nntp.pasdenom.info	2	1	0	0	1
14	xanadu-bbs.net	1	0	0	0	1
TOTAL: 14		10943	853	14	3	10073

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	102
it-alt.sport.calcio.juventus	58
gmane.linux.alsa.devel	50
mozilla.dev.security.policy	49
lada.auto	41
mpc.lists.freebsd.ports.bugs	41
alt.windows7.general	39
lada.talk	39
it.annunci.varie	33
it.annunci.immobiliari	32
it.annunci.commerciali	31
lada.photo	26
mozilla.support.seamonkey	23
mpc.lists.nanog	22
gmane.comp.gis.openstreetmap.tagging	18
alt.survival	17
lada.commerce	14
mozilla.support.thunderbird	14
eagle.support.eng	10
alt.2600	8
TOTAL: 114	853

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

Groups	Count
nz.politics.announce	3
TOTAL: 1	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7327
EMP (phn path)	656
EMP (phl)	115
EMP (phn nph)	98
Too many newsgroups	79
MID-Bot	30
HTML Multipart	27
User-issued cancel	24
Scoring filter (8)	18
Binary Payload	10
Scoring filter (11)	9
Scoring filter (10)	8
Too many newsgroups (meow)	7
Scoring filter (9)	7
HTML post	6
Scoring filter (14)	6
Scoring filter (12)	4
Binary Image: misplaced jpg	2
Scoring filter (18)	2
Scoring filter (21)	2
TOTAL: 28	8446

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	60	0	68	68
TOTAL: 1	60	0	68	68

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
Bad Message-ID's offered
	csiph-out.feeder.erje.net	5
	TOTAL: 1	5
Huge articles
	feeder.eternal-september.org	771
	csiph-out.feeder.erje.net	216
	csiph-out.news.weretis.net	45
	news-out.mixmin.net	8
	newsfeeds.xmission.com	7
	news.fcku.it	4
	news.redatomik.org	4
	newsfeed.fu-berlin.de	2
	news.freedyn.net	1
	news.swapon.de	1
	TOTAL: 10	1059
Including strange strings
	newsfeed.fu-berlin.de	438
	csiph-out.news.weretis.net	44
	csiph-out.feeder.erje.net	37
	news-out.mixmin.net	24
	news.swapon.de	8
	newsfeed.neva.ru	7
	news-out.aioe.org	4
	news.redatomik.org	2
	news.fcku.it	1
	newsfeeds.xmission.com	1
	TOTAL: 10	566
TOTAL: 4		1632

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	43475	2794	20974	58	1	6%	23:59:53
2	news.freedyn.net	25224	954	23239	3	0	3%	23:59:53
3	xanadu-bbs	25370	763	23622	2	0	3%	23:59:53
4	fu-berlin.de	23233	698	22535	0	0	3%	23:59:52
5	usenet.pasdenom.info	25909	189	22705	1614	14	0%	23:59:52
6	erje	18946	82	17860	38	0	0%	23:59:39
7	aioe	21783	50	20595	0	0	0%	23:59:53
8	news.redatomik.org	23583	21	22990	0	0	0%	23:59:41
9	xmission	24397	16	22864	0	0	0%	23:59:53
10	weretis.net	17375	16	17218	1	0	0%	23:59:10
11	eternal-september	21408	14	20342	0	0	0%	23:59:48
12	news.mixmin.net	22675	12	21906	0	0	0%	23:59:53
13	news.swapon.de	2148	12	2105	0	0	0%	23:59:53
14	neva.ru	22463	6	21897	10	0	0%	23:59:53
15	news.fcku.it	2633	1	2607	0	1	0%	22:00:01
16	bwh	0	0	0	0	23816	0%	23:58:42
17	au2pb	0	0	0	0	23831	0%	23:58:42
TOTAL: 17		320622	5628	283459	1726	47663	1%	405:54:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.7 MB	11.6 KB	9.7 MB	0.1 KB/s	10.3 KB	23:59:53
2	xanadu-bbs	9.1 MB	3.4 KB	9.1 MB	0.1 KB/s	12.2 KB	23:59:53
3	fu-berlin.de	8.9 MB	0.0 KB	8.9 MB	0.1 KB/s	13.1 KB	23:59:52
4	news.bbs.geek.nz	6.9 MB	246.1 KB	7.2 MB	0.1 KB/s	2.6 KB	23:59:53
5	usenet.pasdenom.info	515.7 KB	10.6 MB	11.1 MB	0.1 KB/s	6.3 KB	23:59:52
6	erje	262.6 KB	316.7 KB	579.3 KB	0.0 KB/s	4.8 KB	23:59:39
7	aioe	149.5 KB	0.0 KB	149.5 KB	0.0 KB/s	3.0 KB	23:59:53
8	news.redatomik.org	49.7 KB	0.0 KB	49.7 KB	0.0 KB/s	2.4 KB	23:59:41
9	eternal-september	31.5 KB	0.0 KB	31.5 KB	0.0 KB/s	2.3 KB	23:59:48
10	xmission	30.8 KB	0.0 KB	30.8 KB	0.0 KB/s	1.9 KB	23:59:53
11	weretis.net	27.2 KB	1.3 KB	28.5 KB	0.0 KB/s	1.7 KB	23:59:10
12	news.mixmin.net	26.3 KB	0.0 KB	26.3 KB	0.0 KB/s	2.2 KB	23:59:53
13	news.swapon.de	19.5 KB	0.0 KB	19.5 KB	0.0 KB/s	1.6 KB	23:59:53
14	neva.ru	8.0 KB	19.5 KB	27.5 KB	0.0 KB/s	1.7 KB	23:59:53
15	news.fcku.it	1.5 KB	0.0 KB	1.5 KB	0.0 KB/s	1.5 KB	22:00:01
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		35.7 MB	11.1 MB	46.8 MB	0.0 KB/s	6.5 KB	405:54:31

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	399	104483	227.5 MB	457	0	0	26:41:52
2	144.138.90.254	288	92	274.6 KB	87	0	0	03:33:50
3	68-185-181-64.dhcp.mdsn.wi.charter.com	27	9	14.0 KB	8	8	8	06:05:38
4	204-73-239-109.cust.centrio.cz	12	5	8.5 KB	4	1	0	02:48:36
TOTAL: 4		726	104589	227.8 MB	556	9	8	43:10:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	399	104483	227.5 MB	457	0	0	26:41:52
2	unresolved	289	92	274.6 KB	87	0	0	03:33:53
3	*.dhcp.mdsn.wi.charter.com	27	9	14.0 KB	8	8	8	06:05:38
4	*.cust.centrio.cz	12	5	8.5 KB	4	1	0	02:48:36
5	*.15.89.75.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	02:00:25
6	*.bbox.io	4	0	0.0 KB	0	0	0	02:00:05
TOTAL: 6		732	104589	227.8 MB	556	9	8	43:10:32

NNRP auth users [Top 20]:

User	Conn
moussa	288
ctomczyk	9
ohlandl	8
TOTAL: 3	305

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	63.596	49.982	0.000	26:41:52
68-185-181-64.dhcp.mdsn.wi.charter.com	2.357	0.516	0.027	06:05:38
144.138.90.254	25.706	6.785	1.018	03:33:50
204-73-239-109.cust.centrio.cz	1.172	0.233	0.031	02:48:36
h246.15.89.75.dynamic.ip.windstream.net	0.076	0.162	0.000	02:00:25
tempe0.bbox.io	0.447	0.123	0.000	02:00:05
212.61.180.204	0.059	0.008	0.000	00:00:02
TOTAL: 7	93.413	57.809	1.076	43:10:32

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	4
212.61.180.204	1
h246.15.89.75.dynamic.ip.windstream.net	1
TOTAL: 3	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	4	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
204-73-239-109.cust.centrio.cz	2	0
h246.15.89.75.dynamic.ip.windstream.net	1	0
TOTAL: 4	11	0

Newsgroup request counts (by category):

Category		Count	Pct
1	it	40168	38.4%
2	sci	31479	30.1%
3	rec	20473	19.6%
4	alt	11358	10.9%
5	free	863	0.8%
6	demon	139	0.1%
7	comp	106	0.1%
8	uk	3	0.0%
TOTAL: 8		104589	100.0%

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

Newsgroup		Count
1	it.comp.console	40168
2	sci.physics	31479
3	rec.sport.pro-wrestling	20472
4	alt.comp.freeware	10503
5	free.usenet	861
6	alt.os.linux.mageia	809
7	demon.local	139
8	comp.arch.embedded	30
9	alt.free.newsservers	26
10	comp.os.vms	24
11	comp.sys.ibm.ps2.hardware	18
12	alt.drugs.hard	9
13	alt.drugs	8
14	comp.ai.philosophy	7
15	comp.arch	7
16	comp.os.msdos.djgpp	5
17	comp.lang.javascript	5
18	comp.arch.fpga	4
19	alt.support.chronic-pain	3
20	comp.sys.ibm.pc.games.action	3
21	uk.rec.drugs.cannabis	3
22	free.at	2
23	comp.sys.ibm.pc.hardware.storage	1
24	rec.arts.tv	1
25	comp.ai.nat-lang	1
26	comp.ai.neural-nets	1
TOTAL: 26		104589

Daily Usenet report for csiph.com

May 16 04:15:00 -- May 17 04:15:00