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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22215	51.0%	5.8 MB	66.2%
innd	17150	39.4%	2.3 MB	25.9%
innfeed	3573	8.2%	641.0 KB	7.2%
nnrpd	557	1.3%	62.3 KB	0.7%
nocem	38	0.1%	4.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	43534	100.0%	8.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	341987	56.6%
Negative hits	241210	39.9%
Do not exist	20870	3.5%
Cache misses	500	0.1%
TOTAL: 4	604567	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.469	0.0%	36	0.000	13.028	31.500
article cleanup	00:00:00.115	0.0%	20703	0.000	0.006	0.036
article logging	00:00:00.313	0.0%	21275	0.000	0.015	0.346
article parse	00:00:00.619	0.0%	60413	0.000	0.010	0.037
article write	00:00:09.346	0.0%	17776	0.167	0.526	11.163
artlog/artcncl	00:00:00.001	0.0%	36	0.000	0.028	1.000
artlog/artparse	00:00:00.003	0.0%	279	0.000	0.011	1.000
data move	00:00:00.202	0.0%	560309	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.042	0.0%	33	0.000	1.273	3.200
hishave/artcncl	00:00:00.033	0.0%	36	0.000	0.917	3.444
hishave/artparse	00:00:00.001	0.0%	270	0.000	0.004	0.059
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.722	0.0%	607429	0.002	0.008	0.168
history sync	00:00:00.757	0.0%	336	0.000	2.253	10.500
history write	00:00:11.353	0.0%	20711	0.041	0.548	5.672
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hiswrite/artparse	00:00:00.086	0.0%	270	0.000	0.319	6.000
idle	23:58:28.947	99.8%	567040	86.653	152.210	392.006
nntp read	00:00:02.050	0.0%	567979	0.001	0.004	0.007
overview write	00:00:33.628	0.0%	17776	0.130	1.892	26.860
perl filter	00:00:28.262	0.0%	20702	1.090	1.365	2.062
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.281	0.0%	35590	0.000	0.008	0.026
TOTAL: 24:00:38.836	24:00:01.230	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	17960	0.000	0.003	0.024
article prepare	00:00:00.002	0.0%	12468	0.000	0.000	0.006
article read	00:00:00.266	0.0%	1427	0.000	0.186	22.000
backlog stats	00:00:00.225	0.0%	407469	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.468	0.0%	325669	0.000	0.005	0.011
data write	00:00:01.781	0.0%	310576	0.002	0.006	0.013
idle	23:54:44.100	100.0%	407469	123.802	211.265	547.621
status file	00:00:00.195	0.0%	291	0.000	0.670	2.000
TOTAL: 23:54:55.772	23:54:48.083	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	24:31:48.951	99.8%	322	0.000	274251.401	600103.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.665	0.0%	97149	0.000	0.007	0.167
readart	00:00:00.061	0.0%	5	0.000	12.200	27.000
TOTAL: 24:35:17.364	24:31:49.677	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	136
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	156

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	2	17046	4217	12723	106	24%	24:09:01
2	newsfeeds.xmission.com	2	17200	3815	13327	58	22%	24:30:15
3	feeder.eternal-september.org	5	19882	2443	17288	151	12%	25:55:48
4	csiph-out.news.weretis.net	3	40950	1314	39339	297	3%	54:42:15
5	newsfeed.neva.ru	157	18669	1044	17207	418	5%	71:52:16
6	news-out.mixmin.net	2	18582	1024	17142	416	5%	23:59:48
7	newsfeed.fu-berlin.de	11	20248	977	19136	135	4%	31:35:03
8	csiph-out.news.xs4all.nl	861	19247	823	17861	563	4%	71:26:37
9	csiph-out.feeder.erje.net	1440	87492	815	86372	305	0%	118:42:48
10	csiph-out.feed.uzoreto.com	864	26940	544	25665	731	2%	71:06:46
11	csiph-out.news.tnetconsulting.net	2	29427	443	28925	59	1%	39:28:54
12	news.fcku.it	10	1731	228	1502	1	13%	23:03:20
13	news-out.neodome.net	2	18716	181	18510	25	0%	23:59:50
14	nntp.pasdenom.info	4	12069	140	11832	97	1%	23:55:48
15	news.swapon.de	8	1561	51	1509	1	3%	24:07:50
16	news.bbs.nz	1	15818	11	15807	0	0%	23:59:52
17	news.samoylyk.net	2	17356	10	17344	2	0%	23:59:45
18	xanadu-bbs.net	25	501	1	500	0	0%	22:38:24
19	localhost	26	0	0	0	0	0%	00:00:09
TOTAL: 19		3427	383435	18081	361989	3365	4%	723:14:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	11.2 MB	0.0 KB	451.7 KB	11.7 MB	96%	2.8 KB
2	newsfeeds.xmission.com	10.6 MB	0.0 KB	3.5 MB	14.1 MB	75%	3.7 KB
3	feeder.eternal-september.org	6.5 MB	6.8 KB	1.6 MB	8.1 MB	80%	3.2 KB
4	csiph-out.feeder.erje.net	5.4 MB	0.0 KB	3.9 MB	9.3 MB	58%	8.5 KB
5	csiph-out.news.weretis.net	3.3 MB	0.0 KB	3.4 MB	6.7 MB	48%	4.3 KB
6	news-out.mixmin.net	2.5 MB	0.0 KB	1.6 MB	4.2 MB	60%	3.0 KB
7	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	396.0 KB	2.9 MB	86%	2.6 KB
8	newsfeed.neva.ru	2.3 MB	0.0 KB	1.3 MB	3.6 MB	64%	2.5 KB
9	csiph-out.news.xs4all.nl	2.2 MB	0.0 KB	4.2 MB	6.4 MB	34%	4.7 KB
10	csiph-out.feed.uzoreto.com	2.0 MB	0.0 KB	11.9 MB	13.9 MB	14%	11.2 KB
11	csiph-out.news.tnetconsulting.net	1.5 MB	0.0 KB	1.2 MB	2.7 MB	56%	5.4 KB
12	news-out.neodome.net	453.8 KB	0.0 KB	692.9 KB	1.1 MB	39%	5.6 KB
13	news.fcku.it	423.6 KB	0.0 KB	3.3 KB	427.0 KB	99%	1.9 KB
14	nntp.pasdenom.info	327.6 KB	0.0 KB	127.6 KB	455.2 KB	71%	1.9 KB
15	news.swapon.de	143.8 KB	0.0 KB	7.1 KB	151.0 KB	95%	2.9 KB
16	news.samoylyk.net	37.3 KB	0.0 KB	122.3 KB	159.7 KB	23%	13.3 KB
17	news.bbs.nz	25.6 KB	0.0 KB	0.0 KB	25.6 KB	100%	2.3 KB
18	xanadu-bbs.net	8.9 KB	0.0 KB	0.0 KB	8.9 KB	100%	8.9 KB
19	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		51.5 MB	6.8 KB	34.3 MB	85.8 MB	60%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 1 04:15:00 - 04:59:59	213	1.2%	0.08	495.3 KB	0.9%	0.18
Jun 1 05:00:00 - 05:59:59	433	2.4%	0.12	1.5 MB	2.8%	0.42
Jun 1 06:00:00 - 06:59:59	532	2.9%	0.15	1.9 MB	3.6%	0.54
Jun 1 07:00:00 - 07:59:59	677	3.7%	0.19	1.9 MB	3.7%	0.55
Jun 1 08:00:00 - 08:59:59	875	4.8%	0.24	3.9 MB	7.5%	1.11
Jun 1 09:00:00 - 09:59:59	742	4.1%	0.21	2.0 MB	3.8%	0.57
Jun 1 10:00:00 - 10:59:59	670	3.7%	0.19	1.8 MB	3.5%	0.53
Jun 1 11:00:00 - 11:59:59	777	4.3%	0.22	1.8 MB	3.5%	0.52
Jun 1 12:00:00 - 12:59:59	731	4.0%	0.20	1.9 MB	3.5%	0.53
Jun 1 13:00:00 - 13:59:59	767	4.2%	0.21	2.0 MB	3.8%	0.56
Jun 1 14:00:00 - 14:59:59	1018	5.6%	0.28	2.7 MB	5.1%	0.76
Jun 1 15:00:00 - 15:59:59	1102	6.1%	0.31	3.0 MB	5.7%	0.85
Jun 1 16:00:00 - 16:59:59	1063	5.9%	0.30	2.8 MB	5.4%	0.80
Jun 1 17:00:00 - 17:59:59	993	5.5%	0.28	2.5 MB	4.8%	0.71
Jun 1 18:00:00 - 18:59:59	1051	5.8%	0.29	2.7 MB	5.2%	0.77
Jun 1 19:00:00 - 19:59:59	1107	6.1%	0.31	3.0 MB	5.7%	0.84
Jun 1 20:00:00 - 20:59:59	854	4.7%	0.24	2.3 MB	4.5%	0.67
Jun 1 21:00:00 - 21:59:59	851	4.7%	0.24	2.5 MB	4.8%	0.72
Jun 1 22:00:00 - 22:59:59	741	4.1%	0.21	2.2 MB	4.2%	0.62
Jun 1 23:00:00 - 23:59:59	682	3.8%	0.19	2.1 MB	4.1%	0.60
Jun 2 00:00:00 - 00:59:59	517	2.9%	0.14	1.5 MB	2.9%	0.44
Jun 2 01:00:00 - 01:59:59	457	2.5%	0.13	1.3 MB	2.4%	0.36
Jun 2 02:00:00 - 02:59:59	385	2.1%	0.11	1.2 MB	2.3%	0.33
Jun 2 03:00:00 - 03:59:59	425	2.4%	0.12	1.3 MB	2.5%	0.37
Jun 2 04:00:00 - 04:15:00	402	2.2%	0.45	2.0 MB	3.8%	2.26
TOTAL: 24:00:00	18065	100.0%	0.21	52.2 MB	100.0%	0.62

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feed.uzoreto.com	731	33	0	698
2	csiph-out.news.xs4all.nl	580	42	1	537
3	newsfeed.fu-berlin.de	538	25	0	513
4	newsfeed.neva.ru	423	25	0	398
5	news-out.mixmin.net	402	5	2	395
6	csiph-out.feeder.erje.net	267	40	5	222
7	csiph-out.news.weretis.net	257	61	3	193
8	news-out.aioe.org	213	88	0	125
9	feeder.eternal-september.org	153	28	1	124
10	nntp.pasdenom.info	96	0	0	96
11	newsfeeds.xmission.com	72	5	0	67
12	csiph-out.news.tnetconsulting.net	42	28	0	14
13	news-out.neodome.net	25	0	0	25
14	news.swapon.de	18	0	0	18
15	news.samoylyk.net	3	0	0	3
16	news.fcku.it	1	1	0	0
TOTAL: 16		3821	381	12	3428

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	107
scinet.stats	23
alt.survival	23
alt.windows7.general	20
mozilla.support.firefox	17
alt.politics.usa	16
lada.talk	15
alt.bbs.synchronet	13
alt.philosophy	11
mozilla.support.seamonkey	9
alt.politics	8
alt.paranormal.spells	7
free.it.religioni.scientology	6
alt.binaries.pictures.purebeauty	6
spline.eisfair	5
alt.magick.folk	5
alt.culture	5
alt.christian.bible	4
at.jobs	4
mozilla.dev.platform	3
TOTAL: 74	381

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1770
EMP (phn path)	246
EMP (phl)	205
0	181
Too many newsgroups	66
EMP (phn nph)	27
HTML Multipart	26
Scoring filter	19
Binary Payload	16
Binary: misplaced binary	7
Too many newsgroups (meow)	4
HTML post	2
TOTAL: 12	2569

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	65
	csiph-out.news.xs4all.nl	4
	csiph-out.feed.uzoreto.com	4
	TOTAL: 3	73
Huge articles
	csiph-out.feeder.erje.net	68
	newsfeeds.xmission.com	59
	csiph-out.feed.uzoreto.com	58
	csiph-out.news.weretis.net	32
	csiph-out.news.xs4all.nl	18
	feeder.eternal-september.org	17
	news-out.neodome.net	12
	csiph-out.news.tnetconsulting.net	11
	news.samoylyk.net	2
	newsfeed.fu-berlin.de	1
	TOTAL: 11	279
Including strange strings
	newsfeed.fu-berlin.de	419
	news-out.aioe.org	105
	news.swapon.de	17
	csiph-out.news.weretis.net	16
	newsfeeds.xmission.com	7
	csiph-out.news.xs4all.nl	4
	news-out.mixmin.net	2
	newsfeed.neva.ru	1
	news.samoylyk.net	1
	TOTAL: 9	572
No colon-space in header
	csiph-out.news.xs4all.nl	6
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	9
TOTAL: 4		933

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	21023	855	17206	3	0	4%	23:59:51
2	news.bbs.nz	34271	501	17016	36	18	1%	23:59:54
3	fu-berlin.de	17012	474	16538	0	0	2%	23:59:51
4	news.samoylyk.net	18574	468	17581	5	0	2%	23:59:55
5	tnet	19134	127	17456	0	0	0%	23:59:55
6	news.freedyn.net	18512	115	17948	0	0	0%	23:59:55
7	newsfeed.xs4all.nl	17744	78	16930	60	193	0%	23:59:54
8	xmission	15381	47	13742	1	0	0%	23:59:55
9	usenet.pasdenom.info	18221	35	17110	8	317	0%	23:59:55
10	neva.ru	16463	14	16195	136	0	0%	23:59:46
11	neodome.net	18399	13	17823	0	1	0%	23:59:54
12	erje	16922	7	16654	105	0	0%	23:59:55
13	news.mixmin.net	16779	5	16228	0	0	0%	23:59:55
14	aioe	13352	4	12676	0	0	0%	23:59:55
15	news.uzoreto.com	17339	2	16998	19	0	0%	23:59:37
16	weretis.net	16249	1	16044	0	0	0%	23:59:51
17	news.swapon.de	1543	0	1521	0	0	0%	23:42:00
18	news.fcku.it	1433	0	1431	0	0	0%	22:04:46
19	eternal-september	15551	0	15012	0	0	0%	23:59:55
TOTAL: 19		313902	2746	282109	373	529	0%	453:44:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.4 MB	17.5 KB	5.4 MB	0.1 KB/s	6.5 KB	23:59:51
2	fu-berlin.de	4.5 MB	0.0 KB	4.5 MB	0.1 KB/s	9.7 KB	23:59:51
3	news.samoylyk.net	4.5 MB	4.7 KB	4.5 MB	0.1 KB/s	9.7 KB	23:59:55
4	news.freedyn.net	1.6 MB	0.0 KB	1.6 MB	0.0 KB/s	14.7 KB	23:59:55
5	news.bbs.nz	1.3 MB	131.0 KB	1.4 MB	0.0 KB/s	2.7 KB	23:59:54
6	tnet	323.3 KB	0.0 KB	323.3 KB	0.0 KB/s	2.5 KB	23:59:55
7	newsfeed.xs4all.nl	230.2 KB	169.6 KB	399.8 KB	0.0 KB/s	2.9 KB	23:59:54
8	xmission	142.1 KB	3.8 KB	146.0 KB	0.0 KB/s	3.0 KB	23:59:55
9	usenet.pasdenom.info	128.8 KB	9.2 KB	138.0 KB	0.0 KB/s	3.2 KB	23:59:55
10	aioe	63.1 KB	0.0 KB	63.1 KB	0.0 KB/s	15.8 KB	23:59:55
11	neva.ru	26.2 KB	357.7 KB	383.9 KB	0.0 KB/s	2.6 KB	23:59:46
12	neodome.net	25.9 KB	0.0 KB	25.9 KB	0.0 KB/s	2.0 KB	23:59:54
13	erje	18.6 KB	282.3 KB	300.8 KB	0.0 KB/s	2.7 KB	23:59:55
14	news.mixmin.net	10.2 KB	0.0 KB	10.2 KB	0.0 KB/s	2.0 KB	23:59:55
15	news.uzoreto.com	3.1 KB	147.5 KB	150.6 KB	0.0 KB/s	7.2 KB	23:59:37
16	weretis.net	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	2.0 KB	23:59:51
17	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:42:00
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:04:46
19	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 19		18.3 MB	1.1 MB	19.4 MB	0.0 KB/s	6.4 KB	453:44:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	5-168-143-94.cust.centrio.cz	6	3	11.0 KB	2	00:06:20
2	69.160.37.57	1	2	4.9 KB	2	00:02:17
TOTAL: 2		7	5	15.9 KB	4	24:35:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cust.centrio.cz	6	3	11.0 KB	2	00:06:20
2	unresolved	54	2	4.9 KB	2	16:10:56
3	*.upc-a.chello.nl	11	0	0.0 KB	0	01:33:01
4	*.res.spectrum.com	4	0	0.0 KB	0	01:42:33
5	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	00:30:00
6	*.home.otenet.gr	3	0	0.0 KB	0	00:00:03
7	*.security.ipip.net	1	0	0.0 KB	0	00:00:01
8	*.net.upcbroadband.cz	14	0	0.0 KB	0	04:32:19
TOTAL: 8		94	5	15.9 KB	4	24:35:16

NNRP auth users [Top 20]:

User	Conn
ctomczyk	6
kev009	1
TOTAL: 2	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	3.076	0.791	0.000	16:08:34
ip-89-176-128-154.net.upcbroadband.cz	0.915	0.190	0.000	04:32:19
071-013-248-209.res.spectrum.com	0.247	0.065	0.000	01:42:33
a131033.upc-a.chello.nl	0.702	0.156	0.000	01:33:01
c-73-140-175-55.hsd1.wa.comcast.net	0.059	0.017	0.000	00:30:00
5-168-143-94.cust.centrio.cz	0.476	0.126	0.015	00:06:20
69.160.37.57	0.401	1.140	0.003	00:02:17
ppp-2-84-171-149.home.otenet.gr	0.182	0.056	0.000	00:00:03
scan-141.security.ipip.net	0.067	0.007	0.000	00:00:01
62.74.4.161	0.139	0.016	0.000	00:00:01
151.46.86.246	0.072	0.008	0.000	00:00:01
204.93.154.209	0.071	0.008	0.000	00:00:00
TOTAL: 12	6.407	2.580	0.018	24:35:16

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	49
ip-89-176-128-154.net.upcbroadband.cz	14
a131033.upc-a.chello.nl	11
071-013-248-209.res.spectrum.com	4
ppp-2-84-171-149.home.otenet.gr	3
62.74.4.161	2
c-73-140-175-55.hsd1.wa.comcast.net	1
scan-141.security.ipip.net	1
204.93.154.209	1
151.46.86.246	1
TOTAL: 10	87

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Connection: close	1
Host: 69.160.37.58:119	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-248-209.res.spectrum.com	3	0
5-168-143-94.cust.centrio.cz	1	1
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 3	5	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	4	80.0%
2	news	1	20.0%
TOTAL: 2		5	100.0%

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

Newsgroup		Count
1	comp.lang.javascript	3
2	news.announce.newgroups	1
3	comp.unix.aix	1
TOTAL: 3		5

Daily Usenet report for csiph.com

Jun 1 04:15:00 -- Jun 2 04:15:00