Daily Usenet report for csiph.com: Oct 2 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	38558	60.3%	7.2 MB	71.1%
innd	21654	33.8%	2.4 MB	23.5%
innfeed	3217	5.0%	491.7 KB	4.8%
nnrpd	528	0.8%	58.5 KB	0.6%
nocem	24	0.0%	2.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	63982	100.0%	10.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	378779	55.0%
Negative hits	271350	39.4%
Do not exist	37148	5.4%
Cache misses	893	0.1%
TOTAL: 4	688170	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.098	0.0%	411	0.000	5.105	40.000
article cleanup	00:00:00.229	0.0%	37114	0.000	0.006	0.028
article logging	00:00:00.498	0.0%	37676	0.000	0.013	0.155
article parse	00:00:01.430	0.0%	135180	0.000	0.011	0.032
article write	00:05:37.423	0.4%	20632	0.247	16.354	47.345
artlog/artcncl	00:00:00.004	0.0%	411	0.000	0.010	0.200
artlog/artparse	00:00:00.004	0.0%	293	0.000	0.014	0.100
data move	00:00:00.223	0.0%	645850	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.059	0.0%	411	0.000	0.144	14.000
hishave/artcncl	00:00:00.007	0.0%	411	0.000	0.017	0.050
hishave/artparse	00:00:00.000	0.0%	293	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:11.384	0.0%	690748	0.002	0.016	0.435
history sync	00:00:01.010	0.0%	347	0.000	2.911	43.333
history write	00:00:30.007	0.0%	37044	0.088	0.810	10.859
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.159	0.0%	293	0.000	0.543	3.000
idle	23:40:37.511	99.3%	659847	54.475	129.178	335.204
nntp read	00:00:02.294	0.0%	674129	0.002	0.003	0.006
overview write	00:01:18.310	0.1%	20632	0.162	3.796	55.334
perl filter	00:01:43.100	0.1%	37044	1.408	2.783	4.294
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.382	0.0%	41278	0.000	0.009	0.042
TOTAL: 23:50:48.441	23:50:06.132	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.066	0.0%	21232	0.000	0.003	0.022
article prepare	00:00:00.006	0.0%	28264	0.000	0.000	0.006
article read	00:00:04.648	0.0%	4094	0.000	1.135	14.217
backlog stats	00:00:00.173	0.0%	399047	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.681	0.0%	299935	0.001	0.006	0.012
data write	00:00:02.102	0.0%	283341	0.002	0.007	0.013
idle	23:53:59.082	100.0%	399047	106.322	215.611	750.950
status file	00:00:00.416	0.0%	313	0.000	1.329	19.000
TOTAL: 23:54:15.758	23:54:08.174	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	16:52:15.104	99.9%	366	0.000	165942.907	600024.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.092	0.0%	929	0.000	0.099	0.571
readart	00:00:11.570	0.0%	5318	0.000	2.176	35.333
TOTAL: 16:52:55.148	16:52:26.766	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	116013	5790	102180	8043	4%	95:03:23
2	csiph-out.news.weretis.net	5	45086	4920	38929	1237	10%	47:39:31
3	feeder.eternal-september.org	4	33392	2862	29722	808	8%	24:38:11
4	news-out.aioe.org	2	19937	1528	18336	73	7%	24:09:02
5	newsfeed.neva.ru	143	48445	1178	41118	6149	2%	71:54:33
6	newsfeed.fu-berlin.de	25	21668	1092	20472	104	5%	28:21:19
7	news-out.mixmin.net	4	23262	900	22068	294	3%	24:14:55
8	news.redatomik.org	1	18007	736	17265	6	4%	21:45:18
9	newsfeeds.xmission.com	1	18736	646	18083	7	3%	21:30:18
10	news.fcku.it	2	8349	534	7753	62	6%	23:59:57
11	xanadu-bbs.net	12	706	157	547	2	22%	23:05:57
12	news.swapon.de	2	1926	59	1853	14	3%	23:48:14
13	news.freedyn.net	3	23173	37	23087	49	0%	23:57:00
14	nntp.pasdenom.info	2	19601	8	19593	0	0%	23:58:58
15	news.bbs.geek.nz	2	18792	4	18787	1	0%	23:59:21
16	localhost	32	1	1	0	0	100%	00:00:00
TOTAL: 16		1392	417094	20452	379793	16849	4%	502:05:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.9 MB	274.0 KB	47.1 MB	67.3 MB	29%	5.0 KB
2	csiph-out.news.weretis.net	12.3 MB	0.0 KB	16.9 MB	29.2 MB	42%	4.9 KB
3	feeder.eternal-september.org	7.5 MB	55.2 KB	10.4 MB	18.0 MB	41%	5.0 KB
4	news-out.mixmin.net	3.4 MB	27.6 KB	1.3 MB	4.8 MB	71%	4.1 KB
5	newsfeed.neva.ru	3.4 MB	42.8 KB	58.6 MB	62.0 MB	5%	8.7 KB
6	news-out.aioe.org	3.3 MB	0.0 KB	106.9 KB	3.4 MB	96%	2.2 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	311.9 KB	2.7 MB	88%	2.3 KB
8	newsfeeds.xmission.com	2.0 MB	0.0 KB	419.1 KB	2.4 MB	83%	3.8 KB
9	news.redatomik.org	1.6 MB	0.0 KB	368.6 KB	2.0 MB	81%	2.7 KB
10	news.fcku.it	1.4 MB	0.0 KB	2.1 MB	3.5 MB	38%	6.0 KB
11	xanadu-bbs.net	557.5 KB	0.0 KB	11.5 KB	569.0 KB	97%	3.6 KB
12	news.freedyn.net	214.2 KB	0.0 KB	3.7 MB	3.9 MB	5%	46.3 KB
13	news.swapon.de	156.8 KB	0.0 KB	7.2 MB	7.4 MB	2%	103.5 KB
14	nntp.pasdenom.info	21.6 KB	0.0 KB	0.0 KB	21.6 KB	100%	2.7 KB
15	news.bbs.geek.nz	6.0 KB	0.0 KB	0.8 KB	6.8 KB	87%	1.4 KB
16	localhost	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
TOTAL: 16		58.2 MB	399.6 KB	148.5 MB	207.1 MB	28%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 2 04:15:00 - 04:59:59	537	2.6%	0.20	2.2 MB	3.7%	0.83
Oct 2 05:00:00 - 05:59:59	508	2.4%	0.14	1.4 MB	2.4%	0.40
Oct 2 06:00:00 - 06:59:59	490	2.4%	0.14	1.9 MB	3.2%	0.54
Oct 2 07:00:00 - 07:59:59	598	2.9%	0.17	1.8 MB	3.1%	0.53
Oct 2 08:00:00 - 08:59:59	714	3.4%	0.20	2.4 MB	4.0%	0.68
Oct 2 09:00:00 - 09:59:59	829	4.0%	0.23	2.0 MB	3.4%	0.57
Oct 2 10:00:00 - 10:59:59	826	4.0%	0.23	2.3 MB	3.9%	0.66
Oct 2 11:00:00 - 11:59:59	886	4.3%	0.25	2.3 MB	3.9%	0.65
Oct 2 12:00:00 - 12:59:59	939	4.5%	0.26	2.8 MB	4.7%	0.79
Oct 2 13:00:00 - 13:59:59	992	4.8%	0.28	2.7 MB	4.5%	0.76
Oct 2 14:00:00 - 14:59:59	1169	5.6%	0.32	3.4 MB	5.7%	0.96
Oct 2 15:00:00 - 15:59:59	1074	5.2%	0.30	3.1 MB	5.2%	0.87
Oct 2 16:00:00 - 16:59:59	1240	6.0%	0.34	3.4 MB	5.8%	0.97
Oct 2 17:00:00 - 17:59:59	1061	5.1%	0.29	2.5 MB	4.2%	0.71
Oct 2 18:00:00 - 18:59:59	1291	6.2%	0.36	3.4 MB	5.7%	0.96
Oct 2 19:00:00 - 19:59:59	1330	6.4%	0.37	3.7 MB	6.3%	1.06
Oct 2 20:00:00 - 20:59:59	1269	6.1%	0.35	2.9 MB	4.8%	0.82
Oct 2 21:00:00 - 21:59:59	1011	4.9%	0.28	2.8 MB	4.7%	0.79
Oct 2 22:00:00 - 22:59:59	854	4.1%	0.24	2.4 MB	4.0%	0.68
Oct 2 23:00:00 - 23:59:59	841	4.1%	0.23	2.6 MB	4.4%	0.74
Oct 3 00:00:00 - 00:59:59	605	2.9%	0.17	1.8 MB	3.0%	0.50
Oct 3 01:00:00 - 01:59:59	575	2.8%	0.16	1.9 MB	3.1%	0.53
Oct 3 02:00:00 - 02:59:59	540	2.6%	0.15	2.0 MB	3.3%	0.56
Oct 3 03:00:00 - 03:59:59	414	2.0%	0.12	1.4 MB	2.4%	0.40
Oct 3 04:00:00 - 04:15:00	145	0.7%	0.16	443.0 KB	0.7%	0.49
TOTAL: 24:00:00	20738	100.0%	0.24	59.4 MB	100.0%	0.70

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	8084	326	46	7712
2	newsfeed.neva.ru	6167	504	6	5657
3	csiph-out.news.weretis.net	1287	223	0	1064
4	feeder.eternal-september.org	795	65	10	720
5	newsfeed.fu-berlin.de	490	31	0	459
6	news-out.mixmin.net	325	5	6	314
7	news-out.aioe.org	82	72	1	9
8	news.fcku.it	66	0	0	66
9	news.freedyn.net	51	0	1	50
10	news.swapon.de	22	0	0	22
11	news.redatomik.org	16	0	0	16
12	newsfeeds.xmission.com	9	1	0	8
13	news.bbs.geek.nz	2	2	0	0
14	xanadu-bbs.net	2	0	0	2
TOTAL: 14		17398	1229	70	16099

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	168
fa.linux.kernel	155
lada.talk	146
ut.test	109
lada.auto	46
fa.freebsd.cvs-all	40
mozilla.support.seamonkey	37
alt.survival	35
fa.openbsd.source-changes	34
alt.windows7.general	25
mozilla.support.thunderbird	24
gmane.comp.bacula.user	24
fa.openbsd.tech	21
fa.netbsd.bugs	17
alt.politics.scorched-earth	17
lada.photo	14
lada.commerce	13
fa.netbsd.current-users	12
mpc.lists.nanog	11
mozilla.dev.security.policy	11
TOTAL: 121	1229

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11815
EMP (phn path)	2146
EMP (phl)	926
EMP (phn nph)	115
Too many newsgroups	47
Binary Payload	34
Scoring filter (17)	26
User-issued cancel	17
Scoring filter (11)	17
Scoring filter (12)	12
Scoring filter (16)	11
Scoring filter (8)	10
Scoring filter (18)	9
HTML Multipart	9
Scoring filter (15)	7
Scoring filter (9)	5
Scoring filter (13)	5
Scoring filter (21)	5
Scoring filter (20)	5
Scoring filter (14)	4
TOTAL: 29	15244

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	20	0	21	21
TOTAL: 1	20	0	21	21

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.news.weretis.net	101
	feeder.eternal-september.org	79
	news.fcku.it	46
	news.freedyn.net	28
	news-out.mixmin.net	15
	news.swapon.de	13
	newsfeeds.xmission.com	7
	news.redatomik.org	3
	newsfeed.fu-berlin.de	1
	TOTAL: 9	293
Including strange strings
	newsfeed.fu-berlin.de	394
	csiph-out.feeder.erje.net	41
	csiph-out.news.weretis.net	41
	feeder.eternal-september.org	22
	newsfeed.neva.ru	18
	news-out.mixmin.net	15
	news.redatomik.org	12
	news.swapon.de	8
	news-out.aioe.org	8
	news.fcku.it	2
	TOTAL: 11	562
TOTAL: 2		855

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	36288	2429	17451	51	0	6%	23:57:39
2	news.freedyn.net	22606	1176	19477	7	0	5%	23:56:43
3	xanadu-bbs	22134	909	19629	36	178	4%	23:59:55
4	fu-berlin.de	19399	724	18675	0	0	3%	23:59:40
5	erje	15536	235	14576	41	1	1%	23:54:15
6	usenet.pasdenom.info	23994	219	19388	1124	364	0%	23:59:25
7	news.redatomik.org	20750	26	19062	19	0	0%	23:53:31
8	aioe	18671	18	17662	9	0	0%	23:59:27
9	xmission	21718	17	19130	2	19	0%	23:57:33
10	weretis.net	14577	8	14164	6	0	0%	23:50:05
11	news.mixmin.net	19326	3	18621	0	0	0%	23:50:05
12	eternal-september	19279	3	16836	0	0	0%	23:59:16
13	news.fcku.it	2289	1	2180	0	0	0%	22:43:11
14	news.swapon.de	2057	0	1954	2	0	0%	23:48:34
15	bwh	0	0	0	0	19864	0%	23:50:04
16	au2pb	0	0	0	0	19865	0%	23:50:04
17	neva.ru	20177	0	18399	9	0	0%	23:54:55
TOTAL: 17		278801	5768	237204	1306	40291	2%	405:24:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.8 MB	27.8 KB	7.8 MB	0.1 KB/s	6.8 KB	23:56:43
2	xanadu-bbs	7.0 MB	76.9 KB	7.0 MB	0.1 KB/s	7.6 KB	23:59:55
3	fu-berlin.de	6.4 MB	0.0 KB	6.4 MB	0.1 KB/s	9.1 KB	23:59:40
4	news.bbs.geek.nz	6.3 MB	243.1 KB	6.5 MB	0.1 KB/s	2.7 KB	23:57:39
5	erje	834.1 KB	156.0 KB	990.1 KB	0.0 KB/s	3.6 KB	23:54:15
6	usenet.pasdenom.info	626.2 KB	7.2 MB	7.9 MB	0.1 KB/s	6.0 KB	23:59:25
7	news.redatomik.org	74.4 KB	126.6 KB	201.0 KB	0.0 KB/s	4.5 KB	23:53:31
8	xmission	49.4 KB	4.4 KB	53.8 KB	0.0 KB/s	2.8 KB	23:57:33
9	aioe	44.2 KB	33.3 KB	77.5 KB	0.0 KB/s	2.9 KB	23:59:27
10	weretis.net	21.9 KB	101.6 KB	123.5 KB	0.0 KB/s	8.8 KB	23:50:05
11	news.mixmin.net	6.3 KB	0.0 KB	6.3 KB	0.0 KB/s	2.1 KB	23:50:05
12	eternal-september	6.2 KB	0.0 KB	6.2 KB	0.0 KB/s	2.1 KB	23:59:16
13	news.fcku.it	1.3 KB	0.0 KB	1.3 KB	0.0 KB/s	1.3 KB	22:43:11
14	news.swapon.de	0.0 KB	6.7 KB	6.7 KB	0.0 KB/s	3.3 KB	23:48:34
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
17	neva.ru	0.0 KB	22.7 KB	22.7 KB	0.0 KB/s	2.5 KB	23:54:55
TOTAL: 17		29.1 MB	8.0 MB	37.2 MB	0.0 KB/s	5.4 KB	405:24:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	47.192.187.101	7	10	22.0 KB	3	0	0	02:14:38
2	challenger.nielsenmedia.com	7	10	22.0 KB	3	0	0	01:31:07
3	68-185-181-64.dhcp.mdsn.wi.charter.com	5	1	1.3 KB	1	1	1	01:19:04
TOTAL: 3		19	21	45.4 KB	7	1	1	16:52:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.nielsenmedia.com	7	10	22.0 KB	3	0	0	01:31:07
2	unresolved	9	10	22.0 KB	3	0	0	02:15:02
3	*.dhcp.mdsn.wi.charter.com	5	1	1.3 KB	1	1	1	01:19:04
4	*.bbox.io	14	0	0.0 KB	0	0	0	03:32:54
5	*.chello.nl	50	0	0.0 KB	0	0	0	08:14:45
TOTAL: 5		85	21	45.4 KB	7	1	1	16:52:54

NNRP auth users [Top 20]:

User	Conn
ohlandl	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	4.207	1.137	0.000	08:14:45
tempe0.bbox.io	1.252	0.301	0.000	03:32:54
47.192.187.101	1.063	0.176	0.000	02:14:38
challenger.nielsenmedia.com	0.603	0.154	0.000	01:31:07
68-185-181-64.dhcp.mdsn.wi.charter.com	0.417	0.136	0.005	01:19:04
196.52.22.22	0.336	0.573	0.000	00:00:23
TOTAL: 6	7.878	2.477	0.005	16:52:54

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	50
tempe0.bbox.io	14
196.52.22.22	2
TOTAL: 3	66

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
47.192.187.101	4	0
challenger.nielsenmedia.com	3	0
68-185-181-64.dhcp.mdsn.wi.charter.com	1	0
TOTAL: 4	15	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.lang.awk	20
2	comp.sys.ibm.ps2.hardware	1
TOTAL: 2		21

Daily Usenet report for csiph.com

Oct 2 04:15:00 -- Oct 3 04:15:00