Daily Usenet report for csiph.com: Jan 22 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	48249	45.6%	9.7 MB	63.4%
nnrpd	33209	31.4%	2.8 MB	18.3%
innd	21111	19.9%	2.3 MB	15.1%
innfeed	3204	3.0%	496.7 KB	3.2%
nocem	82	0.1%	8.1 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	105856	100.0%	15.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	596105	57.0%
Negative hits	401169	38.4%
Do not exist	47180	4.5%
Cache misses	895	0.1%
TOTAL: 4	1045349	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.020	0.0%	350	0.000	2.914	16.176
article cleanup	00:00:00.277	0.0%	46815	0.000	0.006	0.023
article logging	00:00:00.671	0.0%	47487	0.000	0.014	0.032
article parse	00:00:03.012	0.0%	196835	0.003	0.015	0.310
article write	00:00:29.492	0.0%	29490	0.256	1.000	10.403
artlog/artcncl	00:00:00.002	0.0%	259	0.000	0.008	0.286
artlog/artparse	00:00:00.014	0.0%	598	0.000	0.023	0.538
data move	00:00:00.360	0.0%	1043580	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.101	0.0%	347	0.000	0.291	2.050
hishave/artcncl	00:00:00.001	0.0%	350	0.000	0.003	0.040
hishave/artparse	00:00:00.002	0.0%	417	0.000	0.005	0.071
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.433	0.0%	1048088	0.001	0.004	0.069
history sync	00:00:01.488	0.0%	347	0.000	4.288	50.333
history write	00:00:30.370	0.0%	46934	0.088	0.647	9.653
hiswrite/artcncl	00:00:00.003	0.0%	3	0.000	1.000	3.000
hiswrite/artparse	00:00:00.221	0.0%	417	0.000	0.530	6.333
idle	23:55:36.787	99.6%	1058015	46.246	81.414	279.693
nntp read	00:00:03.285	0.0%	1063966	0.001	0.003	0.007
overview write	00:01:04.996	0.1%	29490	0.263	2.204	33.590
perl filter	00:01:49.887	0.1%	46740	1.526	2.351	4.224
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.537	0.0%	59055	0.000	0.009	0.032
TOTAL: 24:00:49.056	23:59:46.959	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.094	0.0%	29804	0.000	0.003	0.017
article prepare	00:00:00.005	0.0%	29766	0.000	0.000	0.007
article read	00:00:00.497	0.0%	3244	0.000	0.153	4.600
backlog stats	00:00:00.305	0.0%	691968	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.473	0.0%	505255	0.002	0.005	0.009
data write	00:00:03.277	0.0%	486236	0.004	0.007	0.011
idle	23:52:52.917	100.0%	691968	61.121	124.244	436.017
status file	00:00:00.195	0.0%	301	0.000	0.648	1.000
TOTAL: 23:53:14.768	23:52:59.763	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.601	0.0%	7979	0.015	0.075	2.263
idle	41:20:14.015	100.0%	40813	69.750	3646.241	600211.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.415	0.0%	173818	0.000	0.014	0.025
readart	00:00:00.026	0.0%	78	0.000	0.333	2.000
TOTAL: 41:21:19.624	41:20:17.057	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	34690	6307	25567	2816	18%	23:44:52
2	feeder.eternal-september.org	9	42246	4286	36785	1175	10%	26:12:46
3	csiph-out.feeder.erje.net	1152	110680	3873	105529	1278	3%	94:59:17
4	csiph-out.news.weretis.net	4	66294	3747	62161	386	5%	49:57:28
5	news-out.aioe.org	3	28697	2486	26184	27	8%	24:09:38
6	newsfeed.neva.ru	146	60791	2347	53468	4976	3%	72:16:33
7	news-out.mixmin.net	3	33316	1995	29823	1498	5%	24:11:51
8	newsfeed.fu-berlin.de	53	34558	1513	32858	187	4%	35:56:17
9	news.freedyn.net	4	32233	831	30944	458	2%	24:09:54
10	newsfeeds.xmission.com	1	30541	509	29996	36	1%	25:16:41
11	news.redatomik.org	2	22870	509	22303	58	2%	20:05:38
12	xanadu-bbs.net	1	44415	474	38859	5082	1%	22:28:25
13	news.fcku.it	1	10063	461	9600	2	4%	25:59:14
14	news.swapon.de	3	2414	183	2229	2	7%	23:10:53
15	au2pb.net	1	31963	105	31818	40	0%	24:24:16
16	nntp.pasdenom.info	3	29913	26	29876	11	0%	24:08:36
17	localhost	34	0	0	0	0	0%	00:00:00
18	news.bbs.geek.nz	2	30279	0	30279	0	0%	07:50:06
TOTAL: 18		1710	645963	29652	598279	18032	4%	549:02:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	18.5 MB	2.1 KB	31.2 MB	49.7 MB	37%	5.6 KB
2	csiph-out.feeder.erje.net	15.8 MB	119.9 KB	5.2 MB	21.1 MB	74%	4.2 KB
3	feeder.eternal-september.org	10.2 MB	3.7 KB	16.5 MB	26.7 MB	38%	5.0 KB
4	csiph-out.news.weretis.net	10.0 MB	9.1 KB	7.3 MB	17.3 MB	57%	4.3 KB
5	news-out.mixmin.net	7.1 MB	11.7 KB	3.3 MB	10.4 MB	68%	3.0 KB
6	newsfeed.neva.ru	6.8 MB	62.4 KB	35.5 MB	42.3 MB	15%	5.9 KB
7	news-out.aioe.org	5.9 MB	7.6 KB	53.6 KB	6.0 MB	98%	2.4 KB
8	newsfeed.fu-berlin.de	3.2 MB	0.0 KB	409.4 KB	3.6 MB	88%	2.2 KB
9	news.freedyn.net	3.0 MB	1.5 KB	1.1 MB	4.0 MB	72%	3.2 KB
10	newsfeeds.xmission.com	1.8 MB	0.0 KB	537.6 KB	2.3 MB	77%	4.3 KB
11	xanadu-bbs.net	1.2 MB	0.0 KB	54.6 MB	55.7 MB	2%	10.3 KB
12	news.redatomik.org	1.1 MB	3.6 KB	653.2 KB	1.8 MB	63%	3.2 KB
13	news.fcku.it	808.2 KB	0.0 KB	3.4 KB	811.6 KB	99%	1.8 KB
14	news.swapon.de	432.3 KB	0.0 KB	12.1 KB	444.3 KB	97%	2.4 KB
15	au2pb.net	299.6 KB	0.0 KB	267.5 KB	567.1 KB	52%	3.9 KB
16	nntp.pasdenom.info	50.2 KB	0.0 KB	120.2 KB	170.4 KB	29%	4.6 KB
17	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	news.bbs.geek.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		86.1 MB	221.6 KB	156.6 MB	242.9 MB	35%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 22 04:15:00 - 04:59:59	505	1.7%	0.19	1.6 MB	1.8%	0.59
Jan 22 05:00:00 - 05:59:59	707	2.4%	0.20	2.7 MB	3.2%	0.77
Jan 22 06:00:00 - 06:59:59	664	2.3%	0.18	2.4 MB	2.8%	0.68
Jan 22 07:00:00 - 07:59:59	874	3.0%	0.24	2.6 MB	3.1%	0.75
Jan 22 08:00:00 - 08:59:59	1173	4.0%	0.33	3.4 MB	3.9%	0.96
Jan 22 09:00:00 - 09:59:59	1393	4.7%	0.39	4.0 MB	4.7%	1.13
Jan 22 10:00:00 - 10:59:59	1445	4.9%	0.40	3.5 MB	4.1%	0.99
Jan 22 11:00:00 - 11:59:59	1283	4.4%	0.36	3.1 MB	3.6%	0.87
Jan 22 12:00:00 - 12:59:59	1416	4.8%	0.39	3.6 MB	4.2%	1.02
Jan 22 13:00:00 - 13:59:59	1458	4.9%	0.41	4.0 MB	4.7%	1.13
Jan 22 14:00:00 - 14:59:59	1510	5.1%	0.42	4.2 MB	5.0%	1.20
Jan 22 15:00:00 - 15:59:59	1529	5.2%	0.42	4.2 MB	4.9%	1.19
Jan 22 16:00:00 - 16:59:59	1739	5.9%	0.48	4.7 MB	5.5%	1.33
Jan 22 17:00:00 - 17:59:59	1764	6.0%	0.49	5.2 MB	6.1%	1.48
Jan 22 18:00:00 - 18:59:59	1660	5.6%	0.46	5.6 MB	6.5%	1.58
Jan 22 19:00:00 - 19:59:59	1658	5.6%	0.46	5.0 MB	5.9%	1.43
Jan 22 20:00:00 - 20:59:59	1536	5.2%	0.43	4.8 MB	5.6%	1.37
Jan 22 21:00:00 - 21:59:59	1783	6.1%	0.50	4.8 MB	5.7%	1.37
Jan 22 22:00:00 - 22:59:59	1326	4.5%	0.37	3.6 MB	4.2%	1.03
Jan 22 23:00:00 - 23:59:59	1182	4.0%	0.33	3.5 MB	4.1%	0.99
Jan 23 00:00:00 - 00:59:59	905	3.1%	0.25	2.8 MB	3.3%	0.80
Jan 23 01:00:00 - 01:59:59	793	2.7%	0.22	2.5 MB	2.9%	0.70
Jan 23 02:00:00 - 02:59:59	498	1.7%	0.14	1.4 MB	1.6%	0.40
Jan 23 03:00:00 - 03:59:59	513	1.7%	0.14	1.6 MB	1.8%	0.44
Jan 23 04:00:00 - 04:15:00	150	0.5%	0.17	685.7 KB	0.8%	0.76
TOTAL: 24:00:00	29464	100.0%	0.34	85.3 MB	100.0%	1.01

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	xanadu-bbs.net	5011	50	0	0	4961
2	newsfeed.neva.ru	4949	1494	16	0	3439
3	usenet.blueworldhosting.com	2814	1414	1	1	1398
4	news-out.mixmin.net	1493	0	4	0	1489
5	csiph-out.feeder.erje.net	1311	447	33	1	830
6	feeder.eternal-september.org	1150	181	1	0	968
7	newsfeed.fu-berlin.de	639	46	0	0	593
8	news.freedyn.net	464	0	1	0	463
9	csiph-out.news.weretis.net	454	247	3	0	204
10	news.redatomik.org	73	0	1	0	72
11	news-out.aioe.org	42	21	2	0	19
12	au2pb.net	39	34	0	0	5
13	news.swapon.de	38	0	0	0	38
14	newsfeeds.xmission.com	34	27	0	0	7
15	nntp.pasdenom.info	11	9	0	0	2
16	news.fcku.it	4	0	0	0	4
TOTAL: 16		18526	3970	62	2	14492

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	748
mailing.freebsd.pkg-fallout	315
lada.talk	296
lada.auto	134
alt.binaries.multimedia.tatu	122
alt.politics.scorched-earth	110
mailing.freebsd.ports-bugs	93
mailing.database.pgsql-general	83
mailing.freebsd.svn-src-all	78
mailing.freebsd.svn-ports-all	73
pld.cvs.commit	73
fa.freebsd.cvs-all	73
ut.test	70
mailing.freebsd.svn-ports-head	68
alt.biology	67
mailing.freebsd.svn-src-head	64
mozilla.dev.tree-alerts	61
mozilla.general	59
mailing.gcc.gcc-cvs	44
gmane.linux.alsa.devel	43
TOTAL: 264	3970

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9137
EMP (phn path)	1740
EMP (phl)	863
Binary: misplaced rar	424
PFM bot misc	223
EMP (phn nph)	141
Scoring filter (26)	92
User-issued cancel	68
Too many newsgroups	50
Binary Payload	43
Scoring filter (20)	39
HTML Multipart	32
Scoring filter (23)	25
Scoring filter (18)	23
Scoring filter (14)	22
Scoring filter (19)	22
Scoring filter (30)	22
Scoring filter (21)	21
Scoring filter (27)	20
Scoring filter (17)	20
TOTAL: 47	13214

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	74	0	75	75
TOTAL: 1	74	0	75	75

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	10
	usenet.blueworldhosting.com	3
	TOTAL: 2	13
Bad Message-ID's offered
	csiph-out.feeder.erje.net	9
	usenet.blueworldhosting.com	3
	TOTAL: 2	12
Huge articles
	usenet.blueworldhosting.com	130
	xanadu-bbs.net	119
	feeder.eternal-september.org	64
	csiph-out.news.weretis.net	59
	news-out.mixmin.net	19
	newsfeeds.xmission.com	6
	news.redatomik.org	6
	news.freedyn.net	5
	nntp.pasdenom.info	2
	au2pb.net	1
	TOTAL: 10	411
Including strange strings
	newsfeed.fu-berlin.de	473
	csiph-out.news.weretis.net	77
	news.swapon.de	36
	csiph-out.feeder.erje.net	34
	news-out.aioe.org	16
	feeder.eternal-september.org	11
	news.freedyn.net	8
	news.redatomik.org	6
	newsfeed.neva.ru	4
	news.fcku.it	3
	TOTAL: 13	673
No colon-space in header
	usenet.blueworldhosting.com	1
	TOTAL: 1	1
TOTAL: 5		1110

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.freedyn.net	33741	1563	27067	13	0	1	4%	23:59:28
2	xanadu-bbs	31943	1168	27807	0	0	9	3%	23:59:51
3	fu-berlin.de	27571	938	26632	0	0	0	3%	23:59:51
4	news.bbs.geek.nz	53907	637	27809	4	0	288	1%	24:00:00
5	aioe	28898	509	24115	57	1	0	1%	23:59:50
6	usenet.pasdenom.info	37472	284	27611	1542	0	6	0%	23:59:51
7	au2pb	29311	142	28199	4	0	0	0%	23:59:51
8	news.redatomik.org	33999	92	27702	11	0	98	0%	23:59:51
9	xmission	32584	72	27884	0	0	0	0%	23:59:49
10	neva.ru	31417	41	25414	262	0	1	0%	23:59:51
11	news.mixmin.net	30768	18	26116	1	0	0	0%	23:59:43
12	eternal-september	29488	17	23706	1	0	0	0%	23:59:51
13	weretis.net	24605	10	23961	0	0	0	0%	23:59:33
14	news.swapon.de	2716	5	2376	1	0	0	0%	23:57:26
15	news.fcku.it	3873	3	3316	0	0	0	0%	22:40:03
16	erje	28414	2	25477	45	0	4	0%	23:59:51
17	bwh	23229	1	21902	0	0	0	0%	23:59:45
TOTAL: 17		483936	5502	397094	1941	1	407	1%	406:34:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.9 MB	41.0 KB	10.9 MB	0.1 KB/s	7.1 KB	23:59:28
2	xanadu-bbs	9.5 MB	0.0 KB	9.5 MB	0.1 KB/s	8.3 KB	23:59:51
3	fu-berlin.de	8.8 MB	0.0 KB	8.8 MB	0.1 KB/s	9.6 KB	23:59:51
4	news.bbs.geek.nz	1.8 MB	17.9 KB	1.8 MB	0.0 KB/s	2.9 KB	24:00:00
5	aioe	1.5 MB	194.8 KB	1.7 MB	0.0 KB/s	3.1 KB	23:59:50
6	usenet.pasdenom.info	1020.9 KB	9.9 MB	10.9 MB	0.1 KB/s	6.1 KB	23:59:51
7	au2pb	542.9 KB	32.3 KB	575.2 KB	0.0 KB/s	3.9 KB	23:59:51
8	news.redatomik.org	286.1 KB	51.2 KB	337.3 KB	0.0 KB/s	3.3 KB	23:59:51
9	xmission	199.5 KB	0.0 KB	199.5 KB	0.0 KB/s	2.8 KB	23:59:49
10	neva.ru	165.2 KB	946.8 KB	1.1 MB	0.0 KB/s	3.7 KB	23:59:51
11	news.mixmin.net	71.0 KB	1.6 KB	72.6 KB	0.0 KB/s	3.8 KB	23:59:43
12	eternal-september	56.7 KB	1.5 KB	58.2 KB	0.0 KB/s	3.2 KB	23:59:51
13	weretis.net	36.5 KB	0.0 KB	36.5 KB	0.0 KB/s	3.6 KB	23:59:33
14	news.swapon.de	14.8 KB	4.1 KB	18.9 KB	0.0 KB/s	3.1 KB	23:57:26
15	news.fcku.it	5.9 KB	0.0 KB	5.9 KB	0.0 KB/s	2.0 KB	22:40:03
16	erje	3.2 KB	179.5 KB	182.7 KB	0.0 KB/s	3.9 KB	23:59:51
17	bwh	2.3 KB	0.0 KB	2.3 KB	0.0 KB/s	2.3 KB	23:59:45
TOTAL: 17		34.9 MB	11.4 MB	46.2 MB	0.0 KB/s	6.4 KB	406:34:25

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	69	168.6 KB	62	04:21:48
2	206.217.197.145	2	9	23.5 KB	2	00:01:46
TOTAL: 2		290	78	192.2 KB	64	41:21:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	298	78	192.2 KB	64	32:20:05
2	*.tampfl.dsl-w.verizon.net	1	0	0.0 KB	0	00:18:41
3	*.chello.nl	4	0	0.0 KB	0	01:33:05
4	*.nielsenmedia.com	4	0	0.0 KB	0	01:45:51
5	*.bbox.io	23	0	0.0 KB	0	05:23:32
TOTAL: 5		330	78	192.2 KB	64	41:21:16

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	0.656	0.452	0.000	27:56:30
tempe0.bbox.io	1.705	0.457	0.000	05:23:32
202.89.176.220	27.886	6.687	0.734	04:21:48
challenger.nielsenmedia.com	0.336	0.073	0.000	01:45:51
dhcp-089-099-203-254.chello.nl	0.322	0.054	0.000	01:33:05
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.096	0.015	0.000	00:18:41
206.217.197.145	0.353	0.180	0.000	00:01:46
TOTAL: 7	31.354	7.918	0.734	41:21:16

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	23
162.212.88.221	8
dhcp-089-099-203-254.chello.nl	4
challenger.nielsenmedia.com	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1
TOTAL: 5	40

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	9	0
challenger.nielsenmedia.com	3	0
dhcp-089-099-203-254.chello.nl	3	0
TOTAL: 3	15	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	78	100.0%
TOTAL: 1		78	100.0%

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

Newsgroup		Count
1	comp.arch	29
2	comp.os.vms	14
3	comp.sys.ibm.pc.games.action	10
4	comp.sys.intel	7
5	comp.arch.embedded	5
6	comp.sys.ibm.pc.hardware.storage	3
7	comp.os.cpm	3
8	comp.sys.ibm.ps2.hardware	2
9	comp.sys.ibm.as400.misc	2
10	comp.ai.alife	1
11	comp.os.linux.networking	1
12	comp.windows.x	1
TOTAL: 12		78

Daily Usenet report for csiph.com

Jan 22 04:15:00 -- Jan 23 04:15:00