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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	71409	50.3%	7.1 MB	39.5%
inn	41640	29.3%	7.7 MB	42.7%
innd	25572	18.0%	2.6 MB	14.8%
innfeed	3153	2.2%	534.0 KB	2.9%
nocem	142	0.1%	14.6 KB	0.1%
TOTAL: 5	141916	100.0%	17.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	401643	53.9%
Negative hits	302907	40.6%
Do not exist	40584	5.4%
Cache misses	651	0.1%
TOTAL: 4	745785	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.888	0.0%	387	0.000	4.879	41.667
article cleanup	00:00:00.294	0.0%	40181	0.000	0.007	0.022
article logging	00:00:07.256	0.0%	40835	0.000	0.178	2.810
article parse	00:00:05.946	0.0%	144854	0.000	0.041	0.357
article write	00:03:08.729	0.2%	21482	0.154	8.785	60.493
artlog/artcncl	00:00:00.099	0.0%	238	0.000	0.416	3.778
artlog/artparse	00:00:00.084	0.0%	583	0.000	0.144	2.333
data move	00:00:00.345	0.0%	710970	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.314	0.0%	382	0.000	0.822	18.889
hishave/artcncl	00:00:00.066	0.0%	387	0.000	0.171	9.000
hishave/artparse	00:00:00.003	0.0%	583	0.000	0.005	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:40.428	0.2%	746303	0.002	0.215	1.274
history sync	00:00:06.039	0.0%	346	0.000	17.454	171.500
history write	00:05:11.245	0.4%	40068	0.088	7.768	51.901
hiswrite/artcncl	00:00:00.004	0.0%	5	0.333	0.800	2.000
hiswrite/artparse	00:00:04.167	0.0%	583	0.000	7.148	63.667
idle	23:38:00.321	98.4%	694007	71.413	122.593	346.547
nntp read	00:00:03.335	0.0%	720087	0.003	0.005	0.009
overview write	00:08:02.814	0.6%	21482	2.264	22.475	452.985
perl filter	00:01:40.819	0.1%	39825	1.511	2.532	3.529
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.411	0.0%	43096	0.000	0.010	0.026
TOTAL: 24:01:04.859	23:59:14.607	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	21435	0.000	0.002	0.015
article prepare	00:00:00.003	0.0%	19680	0.000	0.000	0.011
article read	00:00:01.499	0.0%	2525	0.000	0.594	18.400
backlog stats	00:00:00.207	0.0%	380256	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.904	0.0%	301403	0.001	0.006	0.012
data write	00:00:01.692	0.0%	284831	0.003	0.006	0.013
idle	23:53:50.609	100.0%	380256	127.621	226.244	609.949
status file	00:00:00.674	0.0%	261	0.000	2.582	112.000
TOTAL: 23:54:04.911	23:53:56.635	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	335:06:53.241	99.5%	10163689	29.000	118.698	600185.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:02:48.320	0.0%	29232264	0.000	0.006	0.333
readart	01:22:39.860	0.4%	9953934	0.000	0.498	381.000
TOTAL: 336:42:09.240	336:32:21.421	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	144908	7285	129686	7937	5%	118:44:39
2	csiph-out.news.weretis.net	5	44094	4121	38793	1180	9%	48:17:39
3	feeder.eternal-september.org	5	35947	2659	27744	5544	7%	23:46:42
4	news-out.aioe.org	2	21577	1877	19607	93	8%	24:09:02
5	news-out.mixmin.net	4	22950	1604	20757	589	6%	24:11:48
6	newsfeed.fu-berlin.de	19	20800	1214	19340	246	5%	26:09:41
7	newsfeed.neva.ru	139	33220	1045	28922	3253	3%	71:30:28
8	news.redatomik.org	1	20581	524	20055	2	2%	25:01:45
9	newsfeeds.xmission.com	2	20297	514	19778	5	2%	24:23:48
10	news.fcku.it	2	8888	169	8684	35	1%	23:59:55
11	news.freedyn.net	3	24051	153	23705	193	0%	24:09:43
12	xanadu-bbs.net	12	873	91	774	8	10%	20:58:13
13	news.swapon.de	1	2072	55	2016	1	2%	22:42:37
14	nntp.pasdenom.info	2	19726	48	19678	0	0%	23:58:54
15	localhost	38	5	5	0	0	100%	00:00:03
16	news.bbs.geek.nz	2	18164	0	18163	1	0%	23:59:44
TOTAL: 16		1677	438153	21364	397702	19087	4%	526:04:41

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	27.4 MB	1.3 MB	58.0 MB	86.7 MB	31%	5.8 KB
2	csiph-out.news.weretis.net	12.9 MB	0.0 KB	16.3 MB	29.2 MB	44%	5.6 KB
3	feeder.eternal-september.org	7.3 MB	37.7 KB	75.4 MB	82.7 MB	8%	10.3 KB
4	news-out.mixmin.net	5.8 MB	34.9 KB	1.3 MB	7.2 MB	81%	3.4 KB
5	news-out.aioe.org	4.8 MB	0.0 KB	220.8 KB	5.0 MB	95%	2.6 KB
6	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	739.1 KB	3.4 MB	78%	2.4 KB
7	newsfeed.neva.ru	2.5 MB	24.9 KB	32.7 MB	35.2 MB	7%	8.4 KB
8	news.redatomik.org	1.5 MB	0.0 KB	518.7 KB	2.0 MB	74%	3.8 KB
9	newsfeeds.xmission.com	1.5 MB	0.0 KB	282.5 KB	1.7 MB	84%	3.4 KB
10	news.freedyn.net	675.8 KB	0.0 KB	3.1 MB	3.8 MB	17%	11.1 KB
11	news.fcku.it	364.8 KB	0.0 KB	1.9 MB	2.3 MB	15%	11.4 KB
12	xanadu-bbs.net	239.7 KB	0.0 KB	195.0 KB	434.7 KB	55%	4.4 KB
13	news.swapon.de	152.2 KB	0.0 KB	9.8 KB	162.0 KB	93%	2.9 KB
14	nntp.pasdenom.info	137.8 KB	0.0 KB	0.0 KB	137.8 KB	100%	2.9 KB
15	localhost	11.4 KB	0.0 KB	0.0 KB	11.4 KB	100%	2.3 KB
16	news.bbs.geek.nz	0.0 KB	0.0 KB	475.1 KB	475.1 KB	0%	475.1 KB
TOTAL: 16		67.9 MB	1.4 MB	191.1 MB	260.4 MB	26%	6.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 1 04:15:00 - 04:59:59	469	2.2%	0.17	2.7 MB	4.0%	1.03
May 1 05:00:00 - 05:59:59	512	2.4%	0.14	2.4 MB	3.5%	0.68
May 1 06:00:00 - 06:59:59	619	2.9%	0.17	2.9 MB	4.2%	0.81
May 1 07:00:00 - 07:59:59	823	3.8%	0.23	2.4 MB	3.6%	0.69
May 1 08:00:00 - 08:59:59	823	3.8%	0.23	2.1 MB	3.1%	0.60
May 1 09:00:00 - 09:59:59	836	3.9%	0.23	2.2 MB	3.2%	0.62
May 1 10:00:00 - 10:59:59	848	3.9%	0.24	2.4 MB	3.6%	0.70
May 1 11:00:00 - 11:59:59	820	3.8%	0.23	2.3 MB	3.3%	0.64
May 1 12:00:00 - 12:59:59	1045	4.9%	0.29	2.9 MB	4.2%	0.82
May 1 13:00:00 - 13:59:59	1034	4.8%	0.29	2.8 MB	4.0%	0.78
May 1 14:00:00 - 14:59:59	1286	6.0%	0.36	4.0 MB	5.8%	1.12
May 1 15:00:00 - 15:59:59	1271	5.9%	0.35	3.9 MB	5.8%	1.12
May 1 16:00:00 - 16:59:59	1210	5.6%	0.34	3.3 MB	4.9%	0.95
May 1 17:00:00 - 17:59:59	1281	6.0%	0.36	4.2 MB	6.2%	1.20
May 1 18:00:00 - 18:59:59	1408	6.6%	0.39	5.0 MB	7.4%	1.43
May 1 19:00:00 - 19:59:59	1208	5.6%	0.34	3.6 MB	5.3%	1.02
May 1 20:00:00 - 20:59:59	1067	5.0%	0.30	3.4 MB	4.9%	0.95
May 1 21:00:00 - 21:59:59	1179	5.5%	0.33	4.0 MB	5.8%	1.13
May 1 22:00:00 - 22:59:59	896	4.2%	0.25	3.1 MB	4.5%	0.87
May 1 23:00:00 - 23:59:59	628	2.9%	0.17	1.7 MB	2.5%	0.48
May 2 00:00:00 - 00:59:59	512	2.4%	0.14	1.3 MB	2.0%	0.38
May 2 01:00:00 - 01:59:59	548	2.6%	0.15	1.6 MB	2.4%	0.46
May 2 02:00:00 - 02:59:59	559	2.6%	0.16	2.0 MB	3.0%	0.58
May 2 03:00:00 - 03:59:59	474	2.2%	0.13	1.5 MB	2.2%	0.43
May 2 04:00:00 - 04:15:00	115	0.5%	0.13	452.6 KB	0.6%	0.50
TOTAL: 24:00:00	21471	100.0%	0.25	68.1 MB	100.0%	0.81

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	7995	244	106	1	7644
2	feeder.eternal-september.org	5650	54	3	0	5593
3	newsfeed.neva.ru	3296	137	2	0	3157
4	csiph-out.news.weretis.net	1232	127	0	0	1105
5	newsfeed.fu-berlin.de	777	46	0	0	731
6	news-out.mixmin.net	628	3	1	0	624
7	news.freedyn.net	192	1	0	0	191
8	news-out.aioe.org	100	30	1	0	69
9	news.fcku.it	34	0	0	0	34
10	xanadu-bbs.net	9	0	0	0	9
11	news.swapon.de	5	0	0	0	5
12	newsfeeds.xmission.com	5	0	0	0	5
13	news.redatomik.org	2	0	0	0	2
14	news.bbs.geek.nz	1	0	0	0	1
TOTAL: 14		19926	642	113	1	19170

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	115
alt.windows7.general	48
mpc.lists.freebsd.ports.bugs	47
spline.eisfair	37
it-alt.sport.calcio.juventus	31
lada.talk	28
mozilla.dev.security.policy	23
gmane.comp.gis.openstreetmap.tagging	22
alt.survival	20
cakewalk.audio	18
lada.commerce	18
mpc.lists.freebsd.gnome	11
alt.slack.goathead	10
mozilla.support.seamonkey	10
mozilla.support.thunderbird	8
lada.auto	8
spline.eisfair.dev	7
mozilla.dev.planning	7
mpc.lists.nanog	6
alt.philosophy	6
TOTAL: 106	642

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

Groups	Count
alt.culture.china	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15848
EMP (phn path)	1479
EMP (phl)	148
EMP (phn nph)	64
User-issued cancel	31
Too many newsgroups	28
Binary Payload	18
Scoring filter (13)	13
HTML Multipart	12
Scoring filter (12)	12
Scoring filter (10)	9
Scoring filter (8)	8
Scoring filter (9)	7
HTML post	5
Scoring filter (11)	3
Scoring filter (14)	2
Scoring filter (15)	2
Scoring filter (18)	1
Binary: misplaced par2	1
Binary: misplaced PAR2	1
TOTAL: 21	17693

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	135	0	145	145
TOTAL: 1	135	0	145	145

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	243
	TOTAL: 1	243
Bad Message-ID's offered
	csiph-out.feeder.erje.net	5
	TOTAL: 1	5
Huge articles
	csiph-out.feeder.erje.net	271
	feeder.eternal-september.org	138
	csiph-out.news.weretis.net	119
	news.freedyn.net	19
	news.fcku.it	14
	news-out.mixmin.net	8
	newsfeeds.xmission.com	4
	newsfeed.fu-berlin.de	4
	xanadu-bbs.net	3
	news.redatomik.org	1
	TOTAL: 11	582
Including strange strings
	newsfeed.fu-berlin.de	504
	news-out.mixmin.net	42
	csiph-out.news.weretis.net	41
	csiph-out.feeder.erje.net	38
	newsfeed.neva.ru	14
	news.swapon.de	4
	news-out.aioe.org	4
	feeder.eternal-september.org	3
	news.redatomik.org	1
	newsfeeds.xmission.com	1
	TOTAL: 10	652
TOTAL: 4		1482

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	22301	825	20541	0	0	3%	23:59:53
2	news.freedyn.net	21998	816	20501	2	0	3%	23:59:54
3	fu-berlin.de	20029	716	19313	0	0	3%	23:59:48
4	news.bbs.geek.nz	38589	492	20169	71	0	1%	23:59:55
5	usenet.pasdenom.info	23478	102	19567	1754	9	0%	23:59:55
6	aioe	19226	29	18182	7	0	0%	23:59:54
7	news.redatomik.org	20793	16	20181	2	0	0%	23:59:55
8	xmission	21317	7	20170	0	0	0%	23:59:55
9	news.mixmin.net	19540	6	18933	0	0	0%	23:59:55
10	weretis.net	16516	6	16339	0	0	0%	23:59:55
11	eternal-september	18667	5	17733	0	0	0%	23:59:55
12	news.swapon.de	2217	5	2162	0	0	0%	23:52:56
13	neva.ru	19954	4	19498	16	0	0%	23:59:55
14	erje	14159	2	14046	41	6	0%	23:59:55
15	bwh	0	0	0	0	20720	0%	23:58:42
16	news.fcku.it	1850	0	1832	0	0	0%	22:20:20
17	au2pb	0	0	0	0	20732	0%	23:58:42
TOTAL: 17		280634	3031	249167	1893	41467	1%	406:09:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.9 MB	5.4 KB	9.0 MB	0.1 KB/s	11.2 KB	23:59:54
2	xanadu-bbs	8.9 MB	0.0 KB	8.9 MB	0.1 KB/s	11.1 KB	23:59:53
3	fu-berlin.de	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	12.4 KB	23:59:48
4	news.bbs.geek.nz	1.3 MB	219.2 KB	1.5 MB	0.0 KB/s	2.7 KB	23:59:55
5	usenet.pasdenom.info	311.4 KB	10.4 MB	10.7 MB	0.1 KB/s	5.9 KB	23:59:55
6	aioe	58.9 KB	25.0 KB	83.8 KB	0.0 KB/s	2.3 KB	23:59:54
7	news.redatomik.org	38.7 KB	3.2 KB	41.9 KB	0.0 KB/s	2.3 KB	23:59:55
8	xmission	15.1 KB	0.0 KB	15.1 KB	0.0 KB/s	2.2 KB	23:59:55
9	news.mixmin.net	14.9 KB	0.0 KB	14.9 KB	0.0 KB/s	2.5 KB	23:59:55
10	weretis.net	13.1 KB	0.0 KB	13.1 KB	0.0 KB/s	2.2 KB	23:59:55
11	eternal-september	11.4 KB	0.0 KB	11.4 KB	0.0 KB/s	2.3 KB	23:59:55
12	news.swapon.de	11.4 KB	0.0 KB	11.4 KB	0.0 KB/s	2.3 KB	23:52:56
13	neva.ru	6.9 KB	43.5 KB	50.4 KB	0.0 KB/s	2.5 KB	23:59:55
14	erje	3.3 KB	120.2 KB	123.6 KB	0.0 KB/s	2.9 KB	23:59:55
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:20:20
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		28.3 MB	10.8 MB	39.1 MB	0.0 KB/s	8.1 KB	406:09:24

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	2704	4411388	21.6 GB	2020	0	0	303:40:58
2	144.138.90.254	280	88	253.2 KB	80	0	0	03:44:41
3	204-73-239-109.cust.centrio.cz	6	34	68.9 KB	3	0	0	00:15:02
4	dhcp-089-099-203-254.chello.nl	78	7	14.3 KB	5	0	0	23:53:17
5	g235242.upc-g.chello.nl	2	6	10.1 KB	1	0	0	00:08:00
6	68-185-181-64.dhcp.mdsn.wi.charter.com	22	5	11.4 KB	5	5	5	04:29:39
TOTAL: 6		3092	4411528	21.6 GB	2114	5	5	336:41:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	2704	4411388	21.6 GB	2020	0	0	303:40:58
2	unresolved	280	88	253.2 KB	80	0	0	03:44:41
3	*.cust.centrio.cz	6	34	68.9 KB	3	0	0	00:15:02
4	*.chello.nl	78	7	14.3 KB	5	0	0	23:53:17
5	*.upc-g.chello.nl	2	6	10.1 KB	1	0	0	00:08:00
6	*.dhcp.mdsn.wi.charter.com	22	5	11.4 KB	5	5	5	04:29:39
7	*.snarked.org	2	0	0.0 KB	0	0	0	00:00:03
8	*.19-1.cable.virginm.net	1	0	0.0 KB	0	0	0	00:30:01
TOTAL: 8		3095	4411528	21.6 GB	2114	5	5	336:41:45

NNRP auth users [Top 20]:

User	Conn
moussa	280
ohlandl	5
ctomczyk	3
TOTAL: 3	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	897.770	916.679	0.000	303:40:58
dhcp-089-099-203-254.chello.nl	6.177	1.195	0.000	23:53:17
68-185-181-64.dhcp.mdsn.wi.charter.com	1.764	0.442	0.019	04:29:39
144.138.90.254	24.605	6.858	0.999	03:44:41
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	0.063	0.024	0.000	00:30:01
204-73-239-109.cust.centrio.cz	0.620	0.142	0.011	00:15:02
g235242.upc-g.chello.nl	0.145	0.030	0.000	00:08:00
news.snarked.org	0.162	0.117	0.000	00:00:03
TOTAL: 8	931.306	925.487	1.029	336:41:45

Curious NNRP server explorers [Top 20]:

System	Conn
news.snarked.org	2
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	23	7
144.138.90.254	6	0
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1	0
TOTAL: 4	33	7

Newsgroup request counts (by category):

Category		Count	Pct
1	linux	1339927	81.0%
2	talk	307697	18.6%
3	rec	6051	0.4%
4	alt	1336	0.1%
5	comp	140	0.0%
6	uk	1	0.0%
TOTAL: 6		1655152	100.0%

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

Newsgroup		Count
1	linux.kernel	1339927
2	talk.politics.guns	307697
3	rec.arts.movies.past-films	6051
4	alt.binaries.series.tv.divx.french.d	996
5	alt.binaries.warez.quebec-hackers.d	238
6	alt.binaries.cd.image.french.d	47
7	comp.arch	36
8	comp.lang.javascript	34
9	alt.binaries.cd.genealogy.d	28
10	comp.sys.ibm.ps2.hardware	23
11	comp.os.vms	23
12	comp.arch.fpga	11
13	alt.binaries.series.tv.french.d	10
14	comp.sys.ibm.pc.games.action	6
15	alt.binaries.movies.french.d	6
16	alt.binaries.blu-ray.d	4
17	alt.binaries.movies.divx.french.d	2
18	comp.ai.neural-nets	2
19	comp.arch.embedded	1
20	alt.binaries.multimedia.anime.d	1
21	alt.global-warming	1
22	alt.life.sucks	1
23	uk.rec.drugs.cannabis	1
24	comp.sys.ibm.pc.hardware.chips	1
25	comp.sys.ibm.as400.misc	1
26	alt.binaries.cartoons.french.d	1
27	comp.admin.policy	1
28	alt.binaries.divx.french.d	1
29	comp.windows.x	1
TOTAL: 29		1655152

Daily Usenet report for csiph.com

May 1 04:15:00 -- May 2 04:15:00