Daily Usenet report for csiph.com: Jun 18 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	21456	48.2%	5.3 MB	63.9%
innd	18889	42.5%	2.4 MB	28.7%
innfeed	3358	7.6%	540.1 KB	6.3%
nnrpd	706	1.6%	79.7 KB	0.9%
nocem	62	0.1%	6.5 KB	0.1%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	44472	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	299189	56.8%
Negative hits	205178	38.9%
Do not exist	20893	4.0%
Cache misses	1779	0.3%
TOTAL: 4	527039	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.010	0.0%	108	0.000	9.352	77.000
article cleanup	00:00:00.131	0.0%	19788	0.000	0.007	0.031
article logging	00:00:00.391	0.0%	20222	0.000	0.019	0.375
article parse	00:00:03.677	0.0%	95500	0.000	0.039	0.359
article write	00:03:35.258	0.2%	15868	0.723	13.566	83.596
artlog/artcncl	00:00:00.001	0.0%	102	0.000	0.010	0.100
artlog/artparse	00:00:00.014	0.0%	1145	0.000	0.012	1.000
data move	00:00:00.191	0.0%	523057	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.077	0.0%	107	0.000	0.720	6.500
hishave/artcncl	00:00:00.011	0.0%	108	0.000	0.102	1.000
hishave/artparse	00:00:00.003	0.0%	1145	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:35.190	0.0%	526386	0.002	0.067	0.477
history sync	00:00:01.535	0.0%	374	0.000	4.104	163.000
history write	00:00:39.461	0.0%	19786	0.109	1.994	9.389
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:02.785	0.0%	1144	0.000	2.434	25.750
idle	23:49:12.600	99.2%	514577	84.076	166.647	610.998
nntp read	00:00:02.515	0.0%	529342	0.002	0.005	0.041
overview write	00:05:26.215	0.4%	15868	0.642	20.558	1897.085
perl filter	00:00:36.659	0.0%	19786	1.321	1.853	4.414
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.272	0.0%	31790	0.000	0.009	0.050
TOTAL: 24:00:54.823	24:00:17.996	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.070	0.0%	16354	0.000	0.004	0.023
article prepare	00:00:00.002	0.0%	19768	0.000	0.000	0.008
article read	00:00:04.984	0.0%	1760	0.000	2.832	62.500
backlog stats	00:00:00.202	0.0%	332005	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.430	0.0%	258868	0.002	0.006	0.011
data write	00:00:01.635	0.0%	246381	0.002	0.007	0.016
idle	23:54:55.312	100.0%	332005	140.734	259.319	690.435
status file	00:00:01.244	0.0%	288	0.000	4.319	86.000
TOTAL: 23:55:14.590	23:55:04.879	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	24:36:19.537	99.9%	512	76.000	173006.908	600093.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	1209	0.000	0.006	0.100
readart	00:00:00.821	0.0%	71	0.000	11.563	47.000
TOTAL: 24:37:20.319	24:36:20.365	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	77338	2792	74174	372	3%	118:46:47
2	csiph-out.news.weretis.net	5	32754	2724	29634	396	8%	49:29:43
3	feeder.eternal-september.org	2	17394	1891	15436	67	10%	23:15:04
4	news.freedyn.net	3	20099	1807	16317	1975	8%	24:08:50
5	news-out.aioe.org	2	15380	1528	13828	24	9%	24:09:55
6	newsfeed.neva.ru	143	18371	1078	16501	792	5%	71:55:09
7	newsfeeds.xmission.com	2	11705	973	10726	6	8%	17:17:27
8	newsfeed.fu-berlin.de	10	14765	881	13790	94	5%	24:19:44
9	news-out.mixmin.net	3	16694	769	15757	168	4%	24:08:36
10	csiph-out.news.xs4all.nl	863	18308	698	17022	588	3%	71:17:35
11	csiph-out.feed.uzoreto.com	863	27076	316	26273	487	1%	71:17:28
12	news.fcku.it	10	2259	243	2011	5	10%	22:21:53
13	news.swapon.de	3	1423	27	1390	6	1%	23:44:29
14	nntp.pasdenom.info	2	13699	21	13667	11	0%	23:58:02
15	news.samoylyk.net	3	15400	11	15385	4	0%	25:41:46
16	localhost	44	9	9	0	0	100%	00:00:02
17	news.bbs.nz	1	16341	3	16338	0	0%	23:59:54
18	xanadu-bbs.net	16	630	1	629	0	0%	23:12:12
TOTAL: 18		3415	319645	15772	298878	4995	4%	663:04:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	14.6 MB	0.0 KB	13.8 MB	28.3 MB	51%	9.2 KB
2	csiph-out.news.weretis.net	8.7 MB	0.0 KB	9.0 MB	17.7 MB	49%	5.8 KB
3	feeder.eternal-september.org	5.0 MB	1.5 KB	209.6 KB	5.2 MB	96%	2.7 KB
4	news.freedyn.net	5.0 MB	0.0 KB	99.1 MB	104.0 MB	4%	28.2 KB
5	news-out.aioe.org	3.8 MB	0.0 KB	51.2 KB	3.9 MB	98%	2.5 KB
6	newsfeeds.xmission.com	2.8 MB	0.0 KB	410.7 KB	3.2 MB	87%	3.3 KB
7	newsfeed.neva.ru	2.7 MB	7.4 KB	2.6 MB	5.3 MB	51%	2.9 KB
8	news-out.mixmin.net	2.3 MB	0.0 KB	372.7 KB	2.7 MB	86%	2.9 KB
9	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	264.8 KB	2.2 MB	88%	2.3 KB
10	csiph-out.news.xs4all.nl	1.7 MB	0.0 KB	3.6 MB	5.2 MB	31%	4.2 KB
11	csiph-out.feed.uzoreto.com	1013.9 KB	0.0 KB	6.4 MB	7.4 MB	13%	9.5 KB
12	news.fcku.it	401.0 KB	0.0 KB	4.9 KB	405.9 KB	98%	1.6 KB
13	news.swapon.de	64.7 KB	0.0 KB	3.8 MB	3.9 MB	1%	120.4 KB
14	news.samoylyk.net	44.7 KB	0.0 KB	1.1 MB	1.2 MB	3%	78.9 KB
15	nntp.pasdenom.info	43.4 KB	0.0 KB	23.1 KB	66.5 KB	65%	2.1 KB
16	localhost	11.3 KB	0.0 KB	0.0 KB	11.3 KB	100%	1.3 KB
17	news.bbs.nz	10.8 KB	0.0 KB	0.0 KB	10.8 KB	100%	3.6 KB
18	xanadu-bbs.net	1.8 KB	0.0 KB	0.0 KB	1.8 KB	100%	1.8 KB
TOTAL: 18		50.0 MB	8.9 KB	140.6 MB	190.6 MB	26%	9.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 18 04:15:00 - 04:59:59	291	1.8%	0.11	1.2 MB	2.3%	0.45
Jun 18 05:00:00 - 05:59:59	448	2.8%	0.12	2.0 MB	4.0%	0.58
Jun 18 06:00:00 - 06:59:59	569	3.6%	0.16	1.9 MB	3.7%	0.54
Jun 18 07:00:00 - 07:59:59	694	4.4%	0.19	1.8 MB	3.5%	0.50
Jun 18 08:00:00 - 08:59:59	718	4.5%	0.20	1.8 MB	3.6%	0.52
Jun 18 09:00:00 - 09:59:59	705	4.4%	0.20	1.8 MB	3.6%	0.52
Jun 18 10:00:00 - 10:59:59	778	4.9%	0.22	2.3 MB	4.5%	0.64
Jun 18 11:00:00 - 11:59:59	802	5.1%	0.22	2.3 MB	4.5%	0.65
Jun 18 12:00:00 - 12:59:59	834	5.3%	0.23	2.1 MB	4.2%	0.61
Jun 18 13:00:00 - 13:59:59	727	4.6%	0.20	2.0 MB	4.0%	0.57
Jun 18 14:00:00 - 14:59:59	761	4.8%	0.21	2.5 MB	5.0%	0.71
Jun 18 15:00:00 - 15:59:59	1091	6.9%	0.30	4.6 MB	9.2%	1.31
Jun 18 16:00:00 - 16:59:59	969	6.1%	0.27	3.3 MB	6.6%	0.95
Jun 18 17:00:00 - 17:59:59	768	4.8%	0.21	2.2 MB	4.4%	0.62
Jun 18 18:00:00 - 18:59:59	832	5.2%	0.23	2.6 MB	5.2%	0.75
Jun 18 19:00:00 - 19:59:59	797	5.0%	0.22	2.4 MB	4.7%	0.67
Jun 18 20:00:00 - 20:59:59	753	4.7%	0.21	2.8 MB	5.5%	0.79
Jun 18 21:00:00 - 21:59:59	734	4.6%	0.20	2.3 MB	4.5%	0.64
Jun 18 22:00:00 - 22:59:59	565	3.6%	0.16	2.0 MB	4.0%	0.57
Jun 18 23:00:00 - 23:59:59	509	3.2%	0.14	1.5 MB	2.9%	0.42
Jun 19 00:00:00 - 00:59:59	386	2.4%	0.11	1.1 MB	2.2%	0.31
Jun 19 01:00:00 - 01:59:59	408	2.6%	0.11	1.2 MB	2.4%	0.34
Jun 19 02:00:00 - 02:59:59	335	2.1%	0.09	1.3 MB	2.6%	0.37
Jun 19 03:00:00 - 03:59:59	273	1.7%	0.08	920.9 KB	1.8%	0.26
Jun 19 04:00:00 - 04:15:00	116	0.7%	0.13	546.6 KB	1.1%	0.61
TOTAL: 24:00:00	15863	100.0%	0.18	50.4 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.freedyn.net	2035	19	0	2016
2	newsfeed.neva.ru	795	138	1	656
3	csiph-out.news.xs4all.nl	612	15	0	597
4	csiph-out.feed.uzoreto.com	488	12	0	476
5	newsfeed.fu-berlin.de	462	20	0	442
6	csiph-out.news.weretis.net	413	64	0	349
7	csiph-out.feeder.erje.net	375	38	0	337
8	news-out.mixmin.net	174	2	0	172
9	feeder.eternal-september.org	65	12	1	52
10	news-out.aioe.org	30	22	0	8
11	news.swapon.de	12	0	0	12
12	nntp.pasdenom.info	11	0	0	11
13	newsfeeds.xmission.com	11	0	0	11
14	news.fcku.it	5	5	0	0
15	news.samoylyk.net	4	0	0	4
TOTAL: 15		5492	347	2	5143

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	90
it-alt.sport.calcio.juventus	43
lada.auto	22
lada.commerce	16
alt.windows7.general	16
alt.computer.workshop	12
it-alt.comp.software.openoffice	11
lada.kitchen	10
mozilla.support.firefox	8
mozilla.general	8
spline.eisfair	7
oesterreich.tratsch	6
mozilla.support.seamonkey	5
mozilla.support.bugzilla	5
alt.bbs.synchronet	5
alt.politics	4
alt.binaries.ebook	4
alt.english.usage	3
alt.philosophy	3
alt.survival	3
TOTAL: 70	347

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3122
EMP (phn nph)	127
EMP (phn path)	124
Too many newsgroups	57
HTML Multipart	34
Binary: misplaced rar	34
User-issued cancel	11
EMP (phl)	8
Binary Payload	8
User-issued spam cancel	8
Scoring filter (13)	7
Scoring filter (10)	6
Scoring filter (9)	6
Too many newsgroups (low_xpost)	3
Scoring filter (11)	3
Too many newsgroups (meow)	2
UUencoded txt	1
Scoring filter (8)	1
Scoring filter (14)	1
Cancel for rejected article	1
TOTAL: 21	3565

NoCeM on Spool:

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

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	780
	csiph-out.feeder.erje.net	255
	csiph-out.news.weretis.net	80
	newsfeeds.xmission.com	8
	csiph-out.feed.uzoreto.com	8
	news.swapon.de	6
	news.samoylyk.net	4
	csiph-out.news.xs4all.nl	1
	news-out.mixmin.net	1
	newsfeed.fu-berlin.de	1
	TOTAL: 10	1144
Including strange strings
	newsfeed.fu-berlin.de	373
	csiph-out.news.xs4all.nl	18
	csiph-out.news.weretis.net	15
	news-out.mixmin.net	11
	news-out.aioe.org	6
	news.swapon.de	6
	csiph-out.feeder.erje.net	2
	newsfeed.neva.ru	1
	newsfeeds.xmission.com	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 10	434
TOTAL: 3		1623

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18040	768	15094	0	217	4%	23:59:53
2	news.freedyn.net	14312	705	13289	22	0	4%	23:59:32
3	news.samoylyk.net	16574	692	15159	1	239	4%	23:59:53
4	fu-berlin.de	14789	672	14117	0	0	4%	23:59:53
5	news.bbs.nz	28867	289	14874	2	0	1%	23:59:44
6	usenet.pasdenom.info	16496	98	14417	1325	10	0%	23:59:53
7	aioe	13619	30	13080	0	0	0%	23:59:53
8	newsfeed.xs4all.nl	15659	26	14878	1	47	0%	23:59:52
9	news.uzoreto.com	15474	16	15183	104	0	0%	23:59:51
10	news.swapon.de	1654	15	1579	0	0	0%	23:55:18
11	neva.ru	13937	12	13659	164	0	0%	23:59:53
12	weretis.net	12640	10	12041	1	9	0%	23:59:53
13	news.mixmin.net	14591	10	14214	0	0	0%	23:59:53
14	eternal-september	13558	9	13112	0	0	0%	23:59:52
15	xmission	16494	9	14136	0	0	0%	23:59:46
16	erje	13315	6	12950	10	1	0%	23:59:53
17	news.redatomik.org	0	0	0	0	15166	0%	23:59:53
18	news.fcku.it	1857	0	1850	0	0	0%	22:24:36
TOTAL: 18		241876	3367	213632	1630	15689	1%	430:17:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.1 MB	0.0 KB	9.1 MB	0.1 KB/s	12.1 KB	23:59:53
2	news.freedyn.net	9.0 MB	35.3 KB	9.0 MB	0.1 KB/s	12.7 KB	23:59:32
3	news.samoylyk.net	8.9 MB	1.1 KB	8.9 MB	0.1 KB/s	13.1 KB	23:59:53
4	fu-berlin.de	8.9 MB	0.0 KB	8.9 MB	0.1 KB/s	13.5 KB	23:59:53
5	news.bbs.nz	725.6 KB	2.6 KB	728.2 KB	0.0 KB/s	2.5 KB	23:59:44
6	usenet.pasdenom.info	251.5 KB	10.2 MB	10.4 MB	0.1 KB/s	7.5 KB	23:59:53
7	newsfeed.xs4all.nl	133.9 KB	12.2 KB	146.0 KB	0.0 KB/s	5.4 KB	23:59:52
8	aioe	48.4 KB	0.0 KB	48.4 KB	0.0 KB/s	1.6 KB	23:59:53
9	news.uzoreto.com	45.4 KB	413.9 KB	459.3 KB	0.0 KB/s	3.8 KB	23:59:51
10	news.swapon.de	24.0 KB	0.0 KB	24.0 KB	0.0 KB/s	1.6 KB	23:55:18
11	erje	22.6 KB	12.4 KB	35.0 KB	0.0 KB/s	2.2 KB	23:59:53
12	neva.ru	19.5 KB	353.6 KB	373.1 KB	0.0 KB/s	2.1 KB	23:59:53
13	news.mixmin.net	13.5 KB	0.0 KB	13.5 KB	0.0 KB/s	1.3 KB	23:59:53
14	weretis.net	12.7 KB	1.1 KB	13.8 KB	0.0 KB/s	1.3 KB	23:59:53
15	eternal-september	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	1.3 KB	23:59:52
16	xmission	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	1.3 KB	23:59:46
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:24:36
TOTAL: 18		37.1 MB	11.0 MB	48.1 MB	0.0 KB/s	9.9 KB	430:17:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	109.239.71.208	7	48	142.5 KB	4	0	0	02:12:24
2	68-185-188-174.dhcp.mdsn.wi.charter.com	39	13	17.9 KB	12	9	9	10:05:39
3	a131033.upc-a.chello.nl	45	10	13.5 KB	4	0	0	10:48:54
TOTAL: 3		91	71	173.9 KB	20	9	9	24:37:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	9	48	142.5 KB	4	0	0	02:12:38
2	*.dhcp.mdsn.wi.charter.com	39	13	17.9 KB	12	9	9	10:05:39
3	*.upc-a.chello.nl	45	10	13.5 KB	4	0	0	10:48:54
4	*.ph.ph.cox.net	5	0	0.0 KB	0	0	0	01:30:06
5	*.threatsinkhole.com	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 5		99	71	173.9 KB	20	9	9	24:37:19

NNRP auth users [Top 20]:

User	Conn
ohlandl	9
ctomczyk	7
TOTAL: 2	16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.370	0.614	0.000	10:48:54
68-185-188-174.dhcp.mdsn.wi.charter.com	3.175	0.665	0.166	10:05:39
109.239.71.208	0.688	0.131	0.271	02:12:24
ip72-222-200-117.ph.ph.cox.net	0.358	0.030	0.000	01:30:06
107.170.204.26	0.063	0.018	0.000	00:00:09
117.50.6.201	0.073	0.008	0.000	00:00:03
winnti-scanner-victims-will-be-notified.threatsinkhole.com	0.072	0.008	0.000	00:00:00
TOTAL: 7	7.799	1.474	0.437	24:37:19

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	5
117.50.6.201	1
107.170.204.26	1
winnti-scanner-victims-will-be-notified.threatsinkhole.com	1
TOTAL: 4	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-188-174.dhcp.mdsn.wi.charter.com	11	0
109.239.71.208	4	0
ip72-222-200-117.ph.ph.cox.net	3	0
TOTAL: 3	18	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.lang.javascript	48
2	comp.sys.ibm.ps2.hardware	23
TOTAL: 2		71

Daily Usenet report for csiph.com

Jun 18 04:15:00 -- Jun 19 04:15:00