Daily Usenet report for csiph.com: Sep 23 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22629	49.2%	5.5 MB	64.7%
innd	19261	41.9%	2.4 MB	27.8%
innfeed	3372	7.3%	572.4 KB	6.5%
nnrpd	699	1.5%	77.8 KB	0.9%
nocem	36	0.1%	4.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	45998	100.0%	8.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	304747	57.3%
Negative hits	203975	38.4%
Do not exist	22091	4.2%
Cache misses	767	0.1%
TOTAL: 4	531580	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.560	0.0%	81	0.000	6.914	67.000
article cleanup	00:00:00.141	0.0%	20790	0.000	0.007	0.047
article logging	00:00:00.470	0.0%	21180	0.000	0.022	0.725
article parse	00:00:03.893	0.0%	100631	0.000	0.039	0.572
article write	00:02:58.201	0.2%	16263	0.167	10.957	64.500
artlog/artcncl	00:00:00.000	0.0%	68	0.000	0.000	0.000
artlog/artparse	00:00:00.015	0.0%	1442	0.000	0.010	0.333
data move	00:00:00.156	0.0%	532691	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.027	0.0%	78	0.000	0.346	1.700
hishave/artcncl	00:00:00.006	0.0%	81	0.000	0.074	0.600
hishave/artparse	00:00:00.004	0.0%	1441	0.000	0.003	0.083
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:29.191	0.0%	533284	0.003	0.055	0.312
history sync	00:00:00.964	0.0%	355	0.000	2.715	42.000
history write	00:00:35.697	0.0%	20788	0.317	1.717	21.032
hiswrite/artcncl	00:00:00.003	0.0%	3	1.000	1.000	1.000
hiswrite/artparse	00:00:02.891	0.0%	1441	0.000	2.006	35.857
idle	23:51:58.466	99.4%	530153	86.629	162.064	642.126
nntp read	00:00:02.577	0.0%	540506	0.001	0.005	0.035
overview write	00:03:17.871	0.2%	16263	1.419	12.167	515.589
perl filter	00:00:35.888	0.0%	20786	1.369	1.727	3.241
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.276	0.0%	32559	0.000	0.008	0.038
TOTAL: 24:00:46.945	24:00:07.297	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.045	0.0%	16329	0.000	0.003	0.020
article prepare	00:00:00.001	0.0%	12612	0.000	0.000	0.006
article read	00:00:04.228	0.0%	929	0.000	4.551	116.800
backlog stats	00:00:00.203	0.0%	348928	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.441	0.0%	266364	0.001	0.005	0.014
data write	00:00:01.664	0.0%	253509	0.002	0.007	0.019
idle	23:55:46.376	100.0%	348928	129.387	246.889	870.701
status file	00:00:00.790	0.0%	304	0.000	2.599	117.000
TOTAL: 23:56:05.743	23:55:54.748	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	22:30:09.354	99.8%	552	0.000	146756.076	600088.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:47.407	0.1%	1453	0.000	32.627	590.682
readart	00:00:01.038	0.0%	36	0.000	28.833	349.000
TOTAL: 22:32:16.823	22:30:57.799	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1441	78375	3538	74380	457	4%	119:02:31
2	csiph-out.news.weretis.net	3	32899	2569	30140	190	7%	49:25:38
3	feeder.eternal-september.org	2	17760	1973	15689	98	11%	22:42:47
4	news.freedyn.net	2	21430	1499	18054	1877	6%	24:09:02
5	news-out.aioe.org	1	15067	1463	13591	13	9%	22:06:21
6	csiph-out.news.xs4all.nl	861	19636	1461	16337	1838	7%	71:11:55
7	news-out.mixmin.net	3	16985	1032	15738	215	6%	24:08:09
8	newsfeed.neva.ru	151	23860	905	22107	848	3%	71:26:55
9	newsfeed.fu-berlin.de	11	14603	788	13770	45	5%	24:42:23
10	newsfeeds.xmission.com	1	12850	384	12462	4	2%	18:40:34
11	news.fcku.it	12	2300	284	2011	5	12%	22:18:33
12	csiph-out.feed.uzoreto.com	717	24694	178	24166	350	0%	59:19:42
13	nntp.pasdenom.info	2	11254	35	11214	5	0%	23:58:06
14	news.swapon.de	8	1362	30	1331	1	2%	24:37:09
15	news.samoylyk.net	1	15863	25	15838	0	0%	23:59:56
16	localhost	39	9	9	0	0	100%	00:00:00
17	news.bbs.nz	1	14141	0	14141	0	0%	23:59:53
18	xanadu-bbs.net	32	448	0	448	0	0%	23:15:03
TOTAL: 18		3288	323536	16173	301417	5946	4%	649:04:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	15.1 MB	25.6 KB	10.9 MB	26.1 MB	57%	6.7 KB
2	csiph-out.news.weretis.net	8.3 MB	0.0 KB	5.5 MB	13.8 MB	59%	5.1 KB
3	feeder.eternal-september.org	5.1 MB	0.0 KB	365.8 KB	5.4 MB	93%	2.7 KB
4	csiph-out.news.xs4all.nl	4.0 MB	24.6 KB	12.3 MB	16.4 MB	24%	5.1 KB
5	news.freedyn.net	3.9 MB	0.0 KB	115.5 MB	119.4 MB	3%	36.2 KB
6	news-out.aioe.org	3.3 MB	0.0 KB	44.6 KB	3.3 MB	98%	2.3 KB
7	news-out.mixmin.net	3.2 MB	0.0 KB	624.5 KB	3.8 MB	84%	3.2 KB
8	newsfeed.neva.ru	2.0 MB	0.0 KB	2.6 MB	4.7 MB	43%	2.7 KB
9	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	109.8 KB	1.9 MB	94%	2.4 KB
10	newsfeeds.xmission.com	1.2 MB	0.0 KB	207.7 KB	1.4 MB	85%	3.7 KB
11	csiph-out.feed.uzoreto.com	656.2 KB	32.4 KB	3.8 MB	4.4 MB	14%	8.6 KB
12	news.fcku.it	505.5 KB	0.0 KB	26.9 KB	532.4 KB	94%	1.8 KB
13	news.samoylyk.net	86.2 KB	0.0 KB	0.0 KB	86.2 KB	100%	3.4 KB
14	nntp.pasdenom.info	84.0 KB	0.0 KB	10.4 KB	94.5 KB	88%	2.4 KB
15	news.swapon.de	67.6 KB	0.0 KB	73.3 KB	141.0 KB	47%	4.5 KB
16	localhost	10.9 KB	0.0 KB	0.0 KB	10.9 KB	100%	1.2 KB
17	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		49.4 MB	82.6 KB	152.1 MB	201.6 MB	24%	9.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 23 04:15:00 - 04:59:59	296	1.8%	0.11	1.3 MB	2.7%	0.50
Sep 23 05:00:00 - 05:59:59	340	2.1%	0.09	1.3 MB	2.6%	0.37
Sep 23 06:00:00 - 06:59:59	440	2.7%	0.12	1.6 MB	3.3%	0.47
Sep 23 07:00:00 - 07:59:59	667	4.1%	0.19	1.9 MB	3.9%	0.54
Sep 23 08:00:00 - 08:59:59	699	4.3%	0.19	1.9 MB	3.9%	0.54
Sep 23 09:00:00 - 09:59:59	769	4.7%	0.21	2.1 MB	4.2%	0.60
Sep 23 10:00:00 - 10:59:59	830	5.1%	0.23	2.6 MB	5.2%	0.73
Sep 23 11:00:00 - 11:59:59	730	4.5%	0.20	2.1 MB	4.2%	0.59
Sep 23 12:00:00 - 12:59:59	797	4.9%	0.22	2.1 MB	4.3%	0.61
Sep 23 13:00:00 - 13:59:59	881	5.4%	0.24	2.3 MB	4.7%	0.67
Sep 23 14:00:00 - 14:59:59	1014	6.2%	0.28	3.2 MB	6.5%	0.92
Sep 23 15:00:00 - 15:59:59	896	5.5%	0.25	2.4 MB	4.9%	0.69
Sep 23 16:00:00 - 16:59:59	903	5.6%	0.25	2.9 MB	5.9%	0.83
Sep 23 17:00:00 - 17:59:59	1040	6.4%	0.29	2.9 MB	5.8%	0.81
Sep 23 18:00:00 - 18:59:59	928	5.7%	0.26	2.7 MB	5.5%	0.77
Sep 23 19:00:00 - 19:59:59	882	5.4%	0.24	2.4 MB	4.9%	0.68
Sep 23 20:00:00 - 20:59:59	836	5.2%	0.23	2.6 MB	5.2%	0.73
Sep 23 21:00:00 - 21:59:59	663	4.1%	0.18	2.2 MB	4.5%	0.63
Sep 23 22:00:00 - 22:59:59	679	4.2%	0.19	2.2 MB	4.5%	0.64
Sep 23 23:00:00 - 23:59:59	472	2.9%	0.13	1.3 MB	2.7%	0.38
Sep 24 00:00:00 - 00:59:59	430	2.7%	0.12	1.5 MB	3.0%	0.42
Sep 24 01:00:00 - 01:59:59	405	2.5%	0.11	1.3 MB	2.7%	0.38
Sep 24 02:00:00 - 02:59:59	277	1.7%	0.08	1.1 MB	2.2%	0.31
Sep 24 03:00:00 - 03:59:59	266	1.6%	0.07	883.9 KB	1.7%	0.25
Sep 24 04:00:00 - 04:15:00	86	0.5%	0.10	453.5 KB	0.9%	0.50
TOTAL: 24:00:00	16226	100.0%	0.19	49.4 MB	100.0%	0.59

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.freedyn.net	1894	19	0	1	1874
2	csiph-out.news.xs4all.nl	1869	34	1	2	1832
3	newsfeed.neva.ru	840	52	0	0	788
4	csiph-out.feeder.erje.net	462	87	1	1	373
5	newsfeed.fu-berlin.de	372	13	0	0	359
6	csiph-out.feed.uzoreto.com	351	7	1	1	342
7	news-out.mixmin.net	221	1	0	0	220
8	csiph-out.news.weretis.net	187	64	0	0	123
9	feeder.eternal-september.org	105	25	0	0	80
10	news-out.aioe.org	16	10	0	0	6
11	news.swapon.de	7	0	0	0	7
12	newsfeeds.xmission.com	5	0	0	0	5
13	nntp.pasdenom.info	5	0	0	0	5
14	news.fcku.it	5	4	0	0	1
15	news.samoylyk.net	1	0	0	0	1
TOTAL: 15		6340	316	3	5	6016

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.windows7.general	35
lada.talk	22
alt.bbs.synchronet	19
mozilla.support.firefox	18
lada.auto	18
alt.binaries.pictures.purebeauty	18
it-alt.sport.calcio.juventus	17
mozilla.dev.security.policy	14
mozilla.general	14
alt.english.usage	11
mozilla.support.seamonkey	11
oesterreich.tratsch	7
mozilla.dev.platform	6
mozilla.support.thunderbird	6
alt.binaries.ebook	5
comp.sys.apple	5
alt.math.undergrad	5
newshosting.test	4
mozilla.support.bugzilla	4
rocksolid.shared.hacking	3
TOTAL: 75	316

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

Groups	Count
aaa.inu-chan	5
TOTAL: 1	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3731
Too many newsgroups	104
EMP (phl)	84
EMP (phn nph)	71
EMP (phn path)	67
HTML Multipart	21
Binary: misplaced rar	18
Scoring filter (10)	17
Binary Payload	11
Scoring filter (14)	9
User-issued cancel	9
Scoring filter (8)	8
Scoring filter (9)	7
Scoring filter (13)	6
Too many newsgroups (meow)	6
Scoring filter (15)	4
Scoring filter (12)	4
User-issued spam cancel	3
HTML Image Tags	3
Scoring filter (11)	2
TOTAL: 21	4186

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	28	0	28	28
TOTAL: 1	28	5	28	28

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	950
	csiph-out.feeder.erje.net	191
	csiph-out.news.xs4all.nl	162
	csiph-out.news.weretis.net	67
	csiph-out.feed.uzoreto.com	58
	newsfeeds.xmission.com	5
	news-out.mixmin.net	2
	feeder.eternal-september.org	2
	news.swapon.de	1
	TOTAL: 9	1438
Including strange strings
	newsfeed.fu-berlin.de	325
	csiph-out.news.xs4all.nl	32
	csiph-out.news.weretis.net	14
	news.swapon.de	6
	csiph-out.feeder.erje.net	5
	news-out.aioe.org	3
	newsfeed.neva.ru	1
	news.freedyn.net	1
	csiph-out.feed.uzoreto.com	1
	news-out.mixmin.net	1
	TOTAL: 11	390
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		1874

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19206	559	15665	0	44	2%	23:58:50
2	news.freedyn.net	14884	517	14136	13	0	3%	23:59:01
3	news.samoylyk.net	17039	460	15737	4	0	2%	23:59:19
4	fu-berlin.de	15205	443	14762	0	0	2%	23:50:04
5	news.bbs.nz	30072	202	15510	7	0	0%	23:50:44
6	usenet.pasdenom.info	16691	147	15577	463	22	0%	23:58:20
7	erje	14390	73	12506	16	0	0%	23:56:53
8	newsfeed.xs4all.nl	15249	41	14274	4	51	0%	23:57:00
9	aioe	12926	33	12373	1	1190	0%	23:58:43
10	news.mixmin.net	14916	16	14526	0	0	0%	23:50:37
11	xmission	18846	14	15196	0	0	0%	23:50:04
12	neva.ru	15133	13	14552	61	0	0%	23:55:57
13	weretis.net	14230	13	13068	1	0	0%	23:52:17
14	eternal-september	14048	12	13570	1	0	0%	23:50:04
15	news.swapon.de	1362	9	1329	0	0	0%	23:46:02
16	news.uzoreto.com	14532	7	14252	28	1354	0%	23:57:02
17	news.redatomik.org	0	0	0	0	15721	0%	23:50:04
18	news.fcku.it	1925	0	1906	0	0	0%	21:32:38
TOTAL: 18		250654	2559	218939	599	18382	1%	427:53:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.0 MB	19.0 KB	6.0 MB	0.1 KB/s	11.6 KB	23:59:01
2	xanadu-bbs	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	10.9 KB	23:58:50
3	news.samoylyk.net	5.7 MB	4.1 KB	5.7 MB	0.1 KB/s	12.6 KB	23:59:19
4	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	12.7 KB	23:50:04
5	news.bbs.nz	514.3 KB	23.7 KB	538.0 KB	0.0 KB/s	2.6 KB	23:50:44
6	usenet.pasdenom.info	408.4 KB	5.7 MB	6.1 MB	0.1 KB/s	10.3 KB	23:58:20
7	erje	313.5 KB	85.0 KB	398.5 KB	0.0 KB/s	4.5 KB	23:56:53
8	newsfeed.xs4all.nl	125.9 KB	13.4 KB	139.3 KB	0.0 KB/s	3.1 KB	23:57:00
9	aioe	84.3 KB	1.0 KB	85.2 KB	0.0 KB/s	2.5 KB	23:58:43
10	neva.ru	34.3 KB	118.7 KB	153.0 KB	0.0 KB/s	2.1 KB	23:55:57
11	xmission	32.4 KB	0.0 KB	32.4 KB	0.0 KB/s	2.3 KB	23:50:04
12	news.mixmin.net	27.6 KB	0.0 KB	27.6 KB	0.0 KB/s	1.7 KB	23:50:37
13	weretis.net	27.6 KB	5.3 KB	32.9 KB	0.0 KB/s	2.3 KB	23:52:17
14	eternal-september	26.5 KB	1.6 KB	28.1 KB	0.0 KB/s	2.2 KB	23:50:04
15	news.uzoreto.com	14.9 KB	84.4 KB	99.2 KB	0.0 KB/s	2.8 KB	23:57:02
16	news.swapon.de	10.9 KB	0.0 KB	10.9 KB	0.0 KB/s	1.2 KB	23:46:02
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:32:38
TOTAL: 18		24.7 MB	6.1 MB	30.8 MB	0.0 KB/s	10.0 KB	427:53:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	41	18	26.5 KB	2	0	0	12:31:06
2	71-13-250-50.dhcp.ftbg.wi.charter.com	25	10	11.9 KB	8	9	8	07:58:20
3	91-167-239-72.subs.proxad.net	32	8	21.4 KB	8	0	0	00:02:29
TOTAL: 3		98	36	59.8 KB	18	9	8	22:32:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	41	18	26.5 KB	2	0	0	12:31:06
2	*.dhcp.ftbg.wi.charter.com	25	10	11.9 KB	8	9	8	07:58:20
3	*.subs.proxad.net	32	8	21.4 KB	8	0	0	00:02:29
4	unresolved	3	0	0.0 KB	0	0	0	00:00:03
5	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:15
TOTAL: 5		105	36	59.8 KB	18	9	8	22:32:15

NNRP auth users [Top 20]:

User	Conn
ohlandl	8
TOTAL: 1	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.747	0.694	0.000	12:31:06
71-13-250-50.dhcp.ftbg.wi.charter.com	2.048	0.401	0.024	07:58:20
ip72-222-200-117.ph.ph.cox.net	0.425	0.108	0.000	02:00:15
91-167-239-72.subs.proxad.net	3.854	2.375	0.000	00:02:29
94.160.55.139	0.060	0.022	0.000	00:00:01
94.160.110.175	0.063	0.016	0.000	00:00:01
94.161.194.122	0.063	0.016	0.000	00:00:01
TOTAL: 7	9.260	3.632	0.024	22:32:15

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
94.160.55.139	1
94.161.194.122	1
94.160.110.175	1
TOTAL: 4	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-13-250-50.dhcp.ftbg.wi.charter.com	9	0
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	13	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	28	77.8%
2	alt	8	22.2%
TOTAL: 2		36	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	28
2	alt.bainaries.pictures.erotica.teen.d	5
3	alt.binaries.pictures.teen-starlets	2
4	alt.binaries.pictures.erotica.teen	1
TOTAL: 4		36

Daily Usenet report for csiph.com

Sep 23 04:15:00 -- Sep 24 04:15:00