Daily Usenet report for csiph.com: Jun 4 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	28944	54.1%	6.1 MB	68.2%
innd	15174	28.4%	1.8 MB	20.4%
nnrpd	6156	11.5%	528.3 KB	5.8%
innfeed	3165	5.9%	517.6 KB	5.6%
nocem	53	0.1%	5.2 KB	0.1%
TOTAL: 5	53492	100.0%	9.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	341281	56.2%
Negative hits	237496	39.1%
Do not exist	28179	4.6%
Cache misses	348	0.1%
TOTAL: 4	607304	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.769	0.0%	183	0.000	9.667	126.167
article cleanup	00:00:00.204	0.0%	27580	0.000	0.007	0.028
article logging	00:00:04.642	0.0%	28175	0.000	0.165	3.252
article parse	00:00:04.836	0.0%	96157	0.000	0.050	0.530
article write	00:03:52.806	0.3%	19567	0.122	11.898	107.378
artlog/artcncl	00:00:00.041	0.0%	146	0.000	0.281	4.000
artlog/artparse	00:00:00.094	0.0%	629	0.000	0.149	18.000
data move	00:00:00.224	0.0%	593267	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.603	0.0%	183	0.000	3.295	33.833
hishave/artcncl	00:00:00.251	0.0%	183	0.000	1.372	28.500
hishave/artparse	00:00:00.001	0.0%	624	0.000	0.002	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:57.528	0.2%	607345	0.002	0.292	2.422
history sync	00:00:03.825	0.0%	360	0.000	10.625	167.667
history write	00:03:18.349	0.2%	27584	0.085	7.191	53.985
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:03.473	0.0%	624	0.000	5.566	98.000
idle	23:42:27.324	98.7%	575508	55.646	148.299	404.197
nntp read	00:00:02.819	0.0%	604088	0.002	0.005	0.022
overview write	00:05:42.130	0.4%	19567	1.477	17.485	182.554
perl filter	00:01:01.074	0.1%	27417	1.429	2.228	5.311
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.392	0.0%	39180	0.000	0.010	0.028
TOTAL: 24:00:57.841	23:59:42.385	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.079	0.0%	19657	0.000	0.004	0.025
article prepare	00:00:00.003	0.0%	21484	0.000	0.000	0.016
article read	00:00:01.390	0.0%	2855	0.000	0.487	153.000
backlog stats	00:00:00.195	0.0%	341784	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.956	0.0%	269073	0.003	0.007	0.013
data write	00:00:01.547	0.0%	253463	0.001	0.006	0.014
idle	23:53:20.602	100.0%	341784	144.528	251.623	618.177
status file	00:00:00.488	0.0%	293	0.000	1.666	117.000
TOTAL: 23:53:35.085	23:53:26.260	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.009	0.0%	1	9.000	9.000	9.000
idle	20:53:39.770	99.9%	5996	47.500	12544.992	900033.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.060	0.0%	12379	0.000	0.005	0.250
readart	00:00:00.722	0.0%	85	0.000	8.494	60.000
TOTAL: 20:54:26.127	20:53:40.561	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	114093	7526	101602	4965	6%	118:44:21
2	feeder.eternal-september.org	3	25390	2894	21203	1293	11%	24:26:31
3	news-out.mixmin.net	4	20715	1832	18177	706	8%	24:13:19
4	csiph-out.news.weretis.net	3	37651	1569	35766	316	4%	45:50:17
5	news-out.aioe.org	2	18878	1271	17564	43	6%	24:09:01
6	newsfeeds.xmission.com	3	18035	1247	16769	19	6%	22:47:50
7	newsfeed.fu-berlin.de	22	20174	1172	18884	118	5%	29:22:26
8	newsfeed.neva.ru	148	26167	928	24417	822	3%	72:02:42
9	news.redatomik.org	1	21052	566	20482	4	2%	29:29:57
10	news.freedyn.net	2	21573	253	21133	187	1%	23:59:44
11	news.fcku.it	1	8065	110	7871	84	1%	23:59:58
12	nntp.pasdenom.info	2	17954	104	17799	51	0%	23:59:10
13	xanadu-bbs.net	22	543	64	459	20	11%	23:21:47
14	news.swapon.de	5	1639	42	1597	0	2%	23:57:08
15	news.bbs.geek.nz	1	19018	15	19003	0	0%	23:59:50
16	localhost	35	1	1	0	0	100%	00:00:13
TOTAL: 16		1694	370948	19594	342726	8628	5%	534:24:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	28.5 MB	3.2 KB	26.6 MB	55.1 MB	51%	4.5 KB
2	feeder.eternal-september.org	7.8 MB	0.0 KB	45.6 MB	53.4 MB	14%	13.1 KB
3	news-out.mixmin.net	6.3 MB	0.0 KB	1.7 MB	7.9 MB	78%	3.2 KB
4	csiph-out.news.weretis.net	5.4 MB	0.0 KB	11.2 MB	16.6 MB	32%	9.0 KB
5	newsfeeds.xmission.com	3.6 MB	0.0 KB	1.2 MB	4.7 MB	75%	3.8 KB
6	news-out.aioe.org	3.1 MB	0.0 KB	63.5 KB	3.2 MB	98%	2.5 KB
7	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	335.1 KB	2.8 MB	88%	2.2 KB
8	newsfeed.neva.ru	2.3 MB	0.0 KB	2.8 MB	5.1 MB	44%	3.0 KB
9	news.redatomik.org	1.5 MB	0.0 KB	5.6 KB	1.5 MB	99%	2.7 KB
10	news.freedyn.net	988.4 KB	0.0 KB	5.9 MB	6.8 MB	14%	15.9 KB
11	news.fcku.it	269.1 KB	0.0 KB	1.5 MB	1.8 MB	14%	9.4 KB
12	nntp.pasdenom.info	228.8 KB	0.0 KB	101.8 KB	330.6 KB	69%	2.1 KB
13	xanadu-bbs.net	180.4 KB	0.0 KB	51.6 KB	232.0 KB	77%	2.8 KB
14	news.swapon.de	95.5 KB	0.0 KB	0.0 KB	95.5 KB	100%	2.3 KB
15	news.bbs.geek.nz	35.9 KB	0.0 KB	0.0 KB	35.9 KB	100%	2.4 KB
16	localhost	1.0 KB	0.0 KB	0.0 KB	1.0 KB	100%	1.0 KB
TOTAL: 16		62.7 MB	3.2 KB	97.0 MB	159.7 MB	39%	5.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 4 04:15:01 - 04:59:59	308	1.6%	0.11	908.5 KB	1.4%	0.34
Jun 4 05:00:00 - 05:59:59	448	2.3%	0.12	2.2 MB	3.5%	0.62
Jun 4 06:00:00 - 06:59:59	645	3.3%	0.18	2.6 MB	4.2%	0.74
Jun 4 07:00:00 - 07:59:59	797	4.1%	0.22	3.4 MB	5.4%	0.97
Jun 4 08:00:00 - 08:59:59	914	4.7%	0.25	3.8 MB	6.0%	1.07
Jun 4 09:00:00 - 09:59:59	934	4.8%	0.26	2.6 MB	4.2%	0.75
Jun 4 10:00:00 - 10:59:59	875	4.5%	0.24	2.8 MB	4.5%	0.80
Jun 4 11:00:00 - 11:59:59	818	4.2%	0.23	2.4 MB	3.8%	0.68
Jun 4 12:00:00 - 12:59:59	1037	5.3%	0.29	2.9 MB	4.6%	0.82
Jun 4 13:00:00 - 13:59:59	1008	5.2%	0.28	2.7 MB	4.4%	0.78
Jun 4 14:00:00 - 14:59:59	1058	5.4%	0.29	3.1 MB	4.9%	0.88
Jun 4 15:00:00 - 15:59:59	971	5.0%	0.27	3.0 MB	4.7%	0.84
Jun 4 16:00:00 - 16:59:59	1131	5.8%	0.31	3.5 MB	5.6%	1.00
Jun 4 17:00:00 - 17:59:59	1088	5.6%	0.30	3.3 MB	5.2%	0.93
Jun 4 18:00:00 - 18:59:59	1177	6.0%	0.33	4.1 MB	6.5%	1.17
Jun 4 19:00:00 - 19:59:59	1044	5.3%	0.29	3.1 MB	5.0%	0.89
Jun 4 20:00:00 - 20:59:59	969	5.0%	0.27	2.7 MB	4.3%	0.76
Jun 4 21:00:00 - 21:59:59	957	4.9%	0.27	3.0 MB	4.8%	0.86
Jun 4 22:00:00 - 22:59:59	757	3.9%	0.21	2.3 MB	3.7%	0.66
Jun 4 23:00:00 - 23:59:59	674	3.4%	0.19	2.0 MB	3.2%	0.57
Jun 5 00:00:00 - 00:59:59	563	2.9%	0.16	1.9 MB	3.0%	0.53
Jun 5 01:00:00 - 01:59:59	454	2.3%	0.13	1.5 MB	2.4%	0.42
Jun 5 02:00:00 - 02:59:59	396	2.0%	0.11	1.3 MB	2.0%	0.36
Jun 5 03:00:00 - 03:59:59	422	2.2%	0.12	1.3 MB	2.2%	0.38
Jun 5 04:00:00 - 04:15:00	113	0.6%	0.13	424.9 KB	0.7%	0.47
TOTAL: 23:59:59	19558	100.0%	0.23	62.8 MB	100.0%	0.74

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	5004	234	1	4769
2	feeder.eternal-september.org	1303	37	0	1266
3	newsfeed.neva.ru	840	183	0	657
4	news-out.mixmin.net	727	0	0	727
5	newsfeed.fu-berlin.de	562	20	0	542
6	csiph-out.news.weretis.net	373	92	0	281
7	news.freedyn.net	187	6	0	181
8	news.fcku.it	85	16	0	69
9	news-out.aioe.org	55	42	0	13
10	nntp.pasdenom.info	51	1	0	50
11	newsfeeds.xmission.com	22	0	0	22
12	xanadu-bbs.net	19	0	0	19
13	news.redatomik.org	6	0	0	6
14	news.swapon.de	6	0	0	6
TOTAL: 14		9240	631	1	8608

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	129
lada.talk	110
ut.test	59
mpc.lists.freebsd.ports.bugs	23
mozilla.support.seamonkey	19
lada.auto	16
mpc.lists.freebsd.gnome	14
mozilla.dev.ports.os2	13
mpc.lists.nanog	12
lada.photo	11
alt.pl.nieruchomosci.gielda	11
alt.religion	9
alt.windows7.general	9
alt.binaries.kindle	8
mozilla.support.thunderbird	8
oesterreich.tratsch	7
alt.politics	7
cakewalk.audio	7
spline.eisfair	6
gmane.comp.gis.openstreetmap	5
TOTAL: 106	631

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6000
EMP (phn path)	546
EMP (phl)	233
EMP (phn nph)	218
Too many newsgroups	85
Scoring filter (8)	25
User-issued cancel	25
Scoring filter (9)	20
Binary Payload	15
Scoring filter (12)	11
HTML Multipart	9
Scoring filter (13)	6
HTML post	6
Scoring filter (10)	5
Scoring filter (14)	5
Scoring filter (20)	5
Too many newsgroups (meow)	2
Scoring filter (11)	2
Scoring filter (16)	1
User-issued spam cancel	1
TOTAL: 22	7222

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	162
	TOTAL: 1	162
Huge articles
	csiph-out.feeder.erje.net	238
	feeder.eternal-september.org	218
	csiph-out.news.weretis.net	109
	news.freedyn.net	32
	newsfeeds.xmission.com	15
	news-out.mixmin.net	9
	news.fcku.it	4
	TOTAL: 7	625
Including strange strings
	newsfeed.fu-berlin.de	451
	csiph-out.news.weretis.net	39
	csiph-out.feeder.erje.net	38
	news-out.mixmin.net	22
	newsfeed.neva.ru	14
	news-out.aioe.org	11
	feeder.eternal-september.org	9
	news.swapon.de	6
	newsfeeds.xmission.com	3
	news.redatomik.org	2
	TOTAL: 10	595
TOTAL: 3		1382

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	19846	890	18341	3	0	4%	23:50:04
2	news.bbs.geek.nz	34890	819	17857	64	0	2%	23:50:04
3	xanadu-bbs	20194	758	18660	2	0	3%	23:50:04
4	fu-berlin.de	18225	719	17506	0	0	3%	23:58:46
5	usenet.pasdenom.info	19891	155	17501	1739	2	0%	23:51:13
6	erje	12626	113	11810	42	0	0%	23:54:43
7	weretis.net	13149	22	13038	3	0	0%	23:51:17
8	xmission	18578	21	17436	0	0	0%	23:53:11
9	news.redatomik.org	18795	8	18348	0	22	0%	23:54:16
10	aioe	17193	3	16591	0	0	0%	23:50:06
11	news.mixmin.net	17142	3	16627	0	117	0%	23:53:26
12	eternal-september	16284	3	15669	0	0	0%	23:59:08
13	news.swapon.de	1669	1	1651	0	0	0%	23:42:57
14	neva.ru	18005	0	17671	2	0	0%	23:50:51
15	au2pb	0	0	0	0	18777	0%	23:50:03
16	news.fcku.it	1659	0	1637	0	0	0%	21:50:19
17	bwh	0	0	0	0	18767	0%	23:50:03
TOTAL: 17		248146	3515	220343	1855	37685	1%	403:40:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.9 MB	16.7 KB	10.0 MB	0.1 KB/s	11.4 KB	23:50:04
2	xanadu-bbs	9.6 MB	7.2 KB	9.6 MB	0.1 KB/s	12.9 KB	23:50:04
3	fu-berlin.de	9.5 MB	0.0 KB	9.5 MB	0.1 KB/s	13.5 KB	23:58:46
4	news.bbs.geek.nz	1.9 MB	212.2 KB	2.1 MB	0.0 KB/s	2.5 KB	23:50:04
5	usenet.pasdenom.info	574.6 KB	11.3 MB	11.9 MB	0.1 KB/s	6.4 KB	23:51:13
6	erje	264.7 KB	352.3 KB	617.0 KB	0.0 KB/s	4.0 KB	23:54:43
7	xmission	64.9 KB	0.0 KB	64.9 KB	0.0 KB/s	3.1 KB	23:53:11
8	weretis.net	50.0 KB	65.8 KB	115.8 KB	0.0 KB/s	4.6 KB	23:51:17
9	news.redatomik.org	17.7 KB	0.0 KB	17.7 KB	0.0 KB/s	2.2 KB	23:54:16
10	news.mixmin.net	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	3.8 KB	23:53:26
11	eternal-september	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	3.8 KB	23:59:08
12	aioe	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	1.5 KB	23:50:06
13	news.swapon.de	1.0 KB	0.0 KB	1.0 KB	0.0 KB/s	1.0 KB	23:42:57
14	neva.ru	0.0 KB	2.3 KB	2.3 KB	0.0 KB/s	1.1 KB	23:50:51
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:50:19
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 17		31.9 MB	12.0 MB	43.9 MB	0.0 KB/s	8.4 KB	403:40:31

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	47-192-187-101.drr02.clwr.fl.frontiernet.net	36	39	94.1 KB	11	0	0	03:11:43
2	144.138.90.254	48	30	64.3 KB	27	0	0	00:40:48
3	204-73-239-109.cust.centrio.cz	2	8	17.2 KB	2	0	0	00:59:35
4	challenger.nielsenmedia.com	2	5	10.5 KB	1	0	0	01:02:21
5	dhcp-089-099-203-254.chello.nl	33	1	1.0 KB	1	0	0	06:44:11
6	96-42-16-100.dhcp.mdsn.wi.charter.com	14	1	1.0 KB	1	1	1	02:46:24
7	tempe0.bbox.io	13	1	1.0 KB	1	0	0	03:59:15
TOTAL: 7		148	85	189.2 KB	44	1	1	20:54:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.drr02.clwr.fl.frontiernet.net	36	39	94.1 KB	11	0	0	03:11:43
2	unresolved	49	30	64.3 KB	27	0	0	01:10:48
3	*.cust.centrio.cz	2	8	17.2 KB	2	0	0	00:59:35
4	*.nielsenmedia.com	2	5	10.5 KB	1	0	0	01:02:21
5	*.dhcp.mdsn.wi.charter.com	14	1	1.0 KB	1	1	1	02:46:24
6	*.chello.nl	33	1	1.0 KB	1	0	0	06:44:11
7	*.bbox.io	13	1	1.0 KB	1	0	0	03:59:15
8	*.hsd1.wa.comcast.net	2	0	0.0 KB	0	0	0	01:00:03
TOTAL: 8		151	85	189.2 KB	44	1	1	20:54:24

NNRP auth users [Top 20]:

User	Conn
moussa	48
ctomczyk	2
ohlandl	1
TOTAL: 3	51

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	2.555	0.454	0.000	06:44:11
tempe0.bbox.io	1.324	0.177	0.000	03:59:15
47-192-187-101.drr02.clwr.fl.frontiernet.net	2.485	0.349	0.000	03:11:43
96-42-16-100.dhcp.mdsn.wi.charter.com	1.098	0.216	0.003	02:46:24
challenger.nielsenmedia.com	0.253	0.046	0.000	01:02:21
c-174-61-130-222.hsd1.wa.comcast.net	0.100	0.023	0.000	01:00:03
204-73-239-109.cust.centrio.cz	0.252	0.024	0.008	00:59:35
144.138.90.254	4.901	0.986	0.181	00:40:48
216.239.174.218	0.073	0.024	0.000	00:30:00
TOTAL: 9	13.041	2.299	0.192	20:54:24

Curious NNRP server explorers [Top 20]:

System	Conn
c-174-61-130-222.hsd1.wa.comcast.net	2
216.239.174.218	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	6	0
47-192-187-101.drr02.clwr.fl.frontiernet.net	6	0
96-42-16-100.dhcp.mdsn.wi.charter.com	3	0
challenger.nielsenmedia.com	2	0
c-174-61-130-222.hsd1.wa.comcast.net	2	0
204-73-239-109.cust.centrio.cz	1	0
216.239.174.218	1	0
TOTAL: 7	21	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.lang.awk	44
2	comp.os.msdos.misc	10
3	comp.lang.javascript	8
4	comp.os.os2.apps	6
5	comp.os.vms	5
6	comp.sys.ibm.ps2.hardware	4
7	comp.sys.ibm.as400.misc	3
8	comp.os.os2.advocacy	2
9	comp.sys.ibm.pc.games.action	1
10	comp.answers	1
11	comp.arch	1
TOTAL: 11		85

Daily Usenet report for csiph.com

Jun 4 04:15:01 -- Jun 5 04:15:00