Daily Usenet report for csiph.com: Jan 28 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	54396	45.6%	10.1 MB	61.9%
nnrpd	33481	28.0%	2.8 MB	17.3%
innd	28285	23.7%	2.9 MB	17.6%
innfeed	3145	2.6%	533.7 KB	3.2%
nocem	58	0.0%	5.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	119366	100.0%	16.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	610346	56.4%
Negative hits	417032	38.6%
Do not exist	53393	4.9%
Cache misses	652	0.1%
TOTAL: 4	1081423	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.425	0.0%	211	0.000	2.014	15.000
article cleanup	00:00:00.310	0.0%	53401	0.000	0.006	0.023
article logging	00:00:00.808	0.0%	53902	0.000	0.015	0.135
article parse	00:00:02.693	0.0%	235812	0.002	0.011	0.062
article write	00:01:02.035	0.1%	29221	0.182	2.123	9.147
artlog/artcncl	00:00:00.002	0.0%	182	0.000	0.011	0.143
artlog/artparse	00:00:00.004	0.0%	409	0.000	0.010	0.500
data move	00:00:00.347	0.0%	1050659	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.057	0.0%	211	0.000	0.270	9.000
hishave/artcncl	00:00:00.003	0.0%	211	0.000	0.014	0.133
hishave/artparse	00:00:00.001	0.0%	301	0.000	0.003	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:28.397	0.0%	1085385	0.002	0.026	0.207
history sync	00:00:04.213	0.0%	337	0.000	12.501	73.500
history write	00:02:57.196	0.2%	53438	0.076	3.316	16.118
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.662	0.0%	301	0.000	2.199	16.000
idle	23:50:06.097	99.3%	1067833	41.863	80.355	290.567
nntp read	00:00:03.482	0.0%	1083602	0.001	0.003	0.005
overview write	00:02:16.255	0.2%	29221	1.545	4.663	70.805
perl filter	00:02:32.523	0.2%	53330	1.654	2.860	4.772
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.544	0.0%	58494	0.000	0.009	0.028
TOTAL: 24:00:43.269	23:59:36.054	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.086	0.0%	29165	0.000	0.003	0.015
article prepare	00:00:00.008	0.0%	31732	0.000	0.000	0.018
article read	00:00:00.520	0.0%	3666	0.000	0.142	9.200
backlog stats	00:00:00.310	0.0%	694359	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.502	0.0%	502201	0.001	0.005	0.010
data write	00:00:03.275	0.0%	485540	0.003	0.007	0.012
idle	23:52:45.933	100.0%	694359	71.005	123.806	337.113
status file	00:00:00.177	0.0%	268	0.000	0.660	1.375
TOTAL: 23:53:02.931	23:52:52.811	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:04.048	0.0%	38779	0.000	0.104	1.030
idle	207:22:37.470	100.0%	71730	380.407	10407.883	600181.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.858	0.0%	144459	0.000	0.006	0.167
readart	00:00:00.166	0.0%	102	0.000	1.627	61.000
TOTAL: 207:23:55.701	207:22:42.542	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	35467	6907	25652	2908	19%	23:44:45
2	feeder.eternal-september.org	5	48473	3996	40929	3548	8%	24:20:46
3	csiph-out.feeder.erje.net	1153	110195	3698	104399	2098	3%	95:02:01
4	csiph-out.news.weretis.net	4	63314	2950	59900	464	4%	48:03:36
5	news-out.aioe.org	4	28507	2590	25886	31	9%	24:09:43
6	newsfeed.neva.ru	148	69714	2279	58469	8966	3%	71:49:55
7	news-out.mixmin.net	3	32658	2183	29111	1364	6%	24:08:54
8	newsfeed.fu-berlin.de	77	31884	1263	30436	185	3%	36:08:55
9	news.freedyn.net	4	32906	711	31913	282	2%	23:58:28
10	news.redatomik.org	1	22547	631	21915	1	2%	19:33:10
11	xanadu-bbs.net	1	53270	617	47941	4712	1%	25:03:16
12	newsfeeds.xmission.com	1	30469	566	29886	17	1%	25:12:18
13	news.fcku.it	1	10267	390	9875	2	3%	24:23:24
14	news.swapon.de	3	2143	158	1985	0	7%	24:09:23
15	au2pb.net	1	33457	104	33303	50	0%	24:24:16
16	nntp.pasdenom.info	3	29974	20	29948	6	0%	24:08:36
17	localhost	33	0	0	0	0	0%	00:00:00
18	news.bbs.geek.nz	2	31722	0	31722	0	0%	23:59:45
TOTAL: 18		1732	666967	29063	613270	24634	4%	562:21:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	19.4 MB	2.3 KB	30.3 MB	49.7 MB	39%	5.2 KB
2	csiph-out.feeder.erje.net	15.3 MB	154.8 KB	8.2 MB	23.7 MB	64%	4.2 KB
3	feeder.eternal-september.org	10.2 MB	19.7 KB	40.3 MB	50.5 MB	20%	6.9 KB
4	csiph-out.news.weretis.net	8.8 MB	6.1 KB	9.0 MB	17.8 MB	49%	5.3 KB
5	news-out.mixmin.net	7.7 MB	19.6 KB	2.4 MB	10.1 MB	75%	2.9 KB
6	newsfeed.neva.ru	6.1 MB	276.3 KB	74.5 MB	80.9 MB	7%	7.4 KB
7	news-out.aioe.org	5.8 MB	0.0 KB	44.8 KB	5.8 MB	99%	2.3 KB
8	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	518.0 KB	3.6 MB	85%	2.5 KB
9	news.freedyn.net	2.9 MB	4.0 KB	1.7 MB	4.7 MB	62%	4.8 KB
10	newsfeeds.xmission.com	1.9 MB	2.0 KB	609.7 KB	2.5 MB	76%	4.4 KB
11	xanadu-bbs.net	1.4 MB	2.1 KB	47.2 MB	48.6 MB	2%	9.3 KB
12	news.redatomik.org	1.3 MB	0.0 KB	59.6 KB	1.3 MB	95%	2.2 KB
13	news.fcku.it	713.8 KB	0.0 KB	8.9 KB	722.6 KB	98%	1.8 KB
14	news.swapon.de	423.0 KB	0.0 KB	0.0 KB	423.0 KB	100%	2.7 KB
15	au2pb.net	335.5 KB	0.0 KB	373.3 KB	708.8 KB	47%	4.6 KB
16	nntp.pasdenom.info	73.7 KB	0.0 KB	208.5 KB	282.2 KB	26%	10.9 KB
17	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	news.bbs.geek.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		85.4 MB	486.9 KB	215.5 MB	301.3 MB	28%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 28 04:15:00 - 04:59:59	414	1.4%	0.15	1.3 MB	1.5%	0.48
Jan 28 05:00:00 - 05:59:59	726	2.5%	0.20	2.7 MB	3.2%	0.78
Jan 28 06:00:00 - 06:59:59	674	2.3%	0.19	2.4 MB	2.7%	0.67
Jan 28 07:00:00 - 07:59:59	741	2.5%	0.21	2.0 MB	2.4%	0.58
Jan 28 08:00:00 - 08:59:59	1202	4.1%	0.33	3.5 MB	4.1%	1.00
Jan 28 09:00:00 - 09:59:59	1324	4.5%	0.37	3.5 MB	4.1%	1.00
Jan 28 10:00:00 - 10:59:59	1370	4.7%	0.38	3.5 MB	4.1%	1.00
Jan 28 11:00:00 - 11:59:59	1533	5.2%	0.43	4.7 MB	5.5%	1.33
Jan 28 12:00:00 - 12:59:59	1288	4.4%	0.36	3.6 MB	4.2%	1.03
Jan 28 13:00:00 - 13:59:59	1530	5.2%	0.42	3.8 MB	4.4%	1.08
Jan 28 14:00:00 - 14:59:59	1433	4.9%	0.40	3.7 MB	4.3%	1.06
Jan 28 15:00:00 - 15:59:59	1806	6.2%	0.50	5.1 MB	5.9%	1.45
Jan 28 16:00:00 - 16:59:59	1711	5.9%	0.48	5.4 MB	6.3%	1.54
Jan 28 17:00:00 - 17:59:59	1724	5.9%	0.48	4.9 MB	5.7%	1.39
Jan 28 18:00:00 - 18:59:59	1832	6.3%	0.51	5.0 MB	5.8%	1.41
Jan 28 19:00:00 - 19:59:59	1655	5.7%	0.46	5.0 MB	5.8%	1.41
Jan 28 20:00:00 - 20:59:59	1530	5.2%	0.42	5.4 MB	6.2%	1.53
Jan 28 21:00:00 - 21:59:59	1523	5.2%	0.42	4.2 MB	4.9%	1.19
Jan 28 22:00:00 - 22:59:59	1313	4.5%	0.36	3.9 MB	4.5%	1.11
Jan 28 23:00:00 - 23:59:59	1002	3.4%	0.28	2.9 MB	3.3%	0.81
Jan 29 00:00:00 - 00:59:59	810	2.8%	0.23	2.4 MB	2.8%	0.70
Jan 29 01:00:00 - 01:59:59	841	2.9%	0.23	2.8 MB	3.2%	0.79
Jan 29 02:00:00 - 02:59:59	594	2.0%	0.17	2.1 MB	2.4%	0.59
Jan 29 03:00:00 - 03:59:59	467	1.6%	0.13	1.5 MB	1.7%	0.42
Jan 29 04:00:00 - 04:15:00	184	0.6%	0.20	780.5 KB	0.9%	0.87
TOTAL: 24:00:00	29227	100.0%	0.34	85.9 MB	100.0%	1.02

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	8918	1554	34	0	7330
2	xanadu-bbs.net	4586	13	1	0	4572
3	feeder.eternal-september.org	3614	138	5	0	3471
4	usenet.blueworldhosting.com	2891	661	1	1	2228
5	csiph-out.feeder.erje.net	2105	820	19	0	1266
6	news-out.mixmin.net	1375	4	7	0	1364
7	newsfeed.fu-berlin.de	581	39	0	0	542
8	csiph-out.news.weretis.net	485	296	2	0	187
9	news.freedyn.net	282	1	1	0	280
10	news-out.aioe.org	45	30	0	0	15
11	au2pb.net	34	29	0	0	5
12	news.swapon.de	33	0	0	0	33
13	newsfeeds.xmission.com	16	8	1	0	7
14	news.redatomik.org	10	0	0	0	10
15	nntp.pasdenom.info	6	1	0	0	5
16	news.fcku.it	6	0	0	0	6
TOTAL: 16		24987	3594	71	1	21321

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	833
lada.talk	283
lada.auto	163
mailing.freebsd.pkg-fallout	148
ut.test	137
alt.biology	132
mailing.freebsd.ports-bugs	80
mpc.lists.nanog	52
24hoursupport.helpdesk	50
pld.cvs.commit	48
mailing.freebsd.svn-src-all	47
mailing.gcc.gcc-cvs	45
mailing.openssl.cvs	44
alt.politics.scorched-earth	42
mailing.freebsd.svn-ports-all	41
mailing.openbsd.ports-changes	41
fa.freebsd.cvs-all	40
mailing.freebsd.svn-ports-head	39
gmane.linux.alsa.devel	38
mailing.openbsd.misc	38
TOTAL: 269	3594

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

Groups	Count
rec.humor.funny	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15293
EMP (phn path)	2886
EMP (phl)	1221
PFM bot misc	213
EMP (phn nph)	173
User-issued cancel	127
Too many newsgroups	60
Binary Payload	45
HTML Multipart	43
Scoring filter (26)	28
Scoring filter (19)	27
Scoring filter (21)	26
Scoring filter (29)	23
Scoring filter (9)	20
Scoring filter (23)	19
Scoring filter (20)	19
Scoring filter (10)	19
Scoring filter (18)	17
Scoring filter (8)	16
Scoring filter (17)	16
TOTAL: 44	20409

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	12
	usenet.blueworldhosting.com	4
	TOTAL: 2	16
Huge articles
	xanadu-bbs.net	127
	csiph-out.news.weretis.net	60
	usenet.blueworldhosting.com	53
	feeder.eternal-september.org	32
	news.freedyn.net	8
	newsfeeds.xmission.com	7
	news-out.mixmin.net	7
	nntp.pasdenom.info	4
	news.redatomik.org	1
	news.fcku.it	1
	TOTAL: 11	301
Including strange strings
	newsfeed.fu-berlin.de	391
	news.swapon.de	33
	feeder.eternal-september.org	13
	csiph-out.news.weretis.net	13
	news-out.aioe.org	13
	news-out.mixmin.net	13
	news.redatomik.org	8
	csiph-out.feeder.erje.net	8
	newsfeed.neva.ru	5
	news.fcku.it	3
	TOTAL: 12	503
No colon-space in header
	csiph-out.feeder.erje.net	87
	TOTAL: 1	87
TOTAL: 4		907

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	33904	1409	27088	1	1	4%	23:59:53
2	news.bbs.geek.nz	53967	1172	27088	14	2	2%	23:59:55
3	xanadu-bbs	31134	1152	27463	2	13	3%	23:59:55
4	fu-berlin.de	27568	908	26660	0	0	3%	23:59:49
5	aioe	28056	572	23147	63	0	2%	23:59:54
6	usenet.pasdenom.info	37466	257	27569	1378	7	0%	23:59:55
7	news.redatomik.org	34221	154	27322	8	0	0%	23:59:55
8	au2pb	28965	137	28032	9	0	0%	23:59:55
9	erje	30294	28	24972	402	0	0%	23:59:55
10	news.fcku.it	4452	20	3637	0	0	0%	22:39:17
11	eternal-september	29835	15	23746	0	0	0%	23:59:54
12	xmission	32034	13	27692	0	0	0%	23:59:54
13	news.mixmin.net	30308	9	25610	0	0	0%	23:59:50
14	neva.ru	31266	9	25424	202	0	0%	23:59:55
15	weretis.net	25191	4	24688	6	0	0%	23:59:55
16	bwh	22627	0	21243	0	0	0%	23:59:32
17	news.swapon.de	2364	0	2116	0	0	0%	23:59:01
TOTAL: 17		483652	5859	393497	2085	23	1%	406:36:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.9 MB	1.7 KB	9.9 MB	0.1 KB/s	7.2 KB	23:59:53
2	xanadu-bbs	9.2 MB	3.7 KB	9.2 MB	0.1 KB/s	8.2 KB	23:59:55
3	fu-berlin.de	8.4 MB	0.0 KB	8.4 MB	0.1 KB/s	9.5 KB	23:59:49
4	news.bbs.geek.nz	3.3 MB	79.4 KB	3.4 MB	0.0 KB/s	2.9 KB	23:59:55
5	aioe	1.6 MB	231.9 KB	1.8 MB	0.0 KB/s	2.9 KB	23:59:54
6	usenet.pasdenom.info	1.0 MB	9.2 MB	10.2 MB	0.1 KB/s	6.4 KB	23:59:55
7	au2pb	563.6 KB	162.4 KB	726.0 KB	0.0 KB/s	5.0 KB	23:59:55
8	news.redatomik.org	503.3 KB	35.2 KB	538.5 KB	0.0 KB/s	3.3 KB	23:59:55
9	eternal-september	75.0 KB	0.0 KB	75.0 KB	0.0 KB/s	5.0 KB	23:59:54
10	news.mixmin.net	62.1 KB	0.0 KB	62.1 KB	0.0 KB/s	6.9 KB	23:59:50
11	xmission	61.9 KB	0.0 KB	61.9 KB	0.0 KB/s	4.8 KB	23:59:54
12	erje	61.8 KB	1.3 MB	1.4 MB	0.0 KB/s	3.3 KB	23:59:55
13	news.fcku.it	43.5 KB	0.0 KB	43.5 KB	0.0 KB/s	2.2 KB	22:39:17
14	weretis.net	28.3 KB	100.6 KB	129.0 KB	0.0 KB/s	12.9 KB	23:59:55
15	neva.ru	21.0 KB	742.6 KB	763.6 KB	0.0 KB/s	3.6 KB	23:59:55
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:32
17	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:01
TOTAL: 17		34.8 MB	11.8 MB	46.7 MB	0.0 KB/s	6.0 KB	406:36:24

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	95	281.9 KB	84	04:29:08
2	138.108.60.60	4	3	5.3 KB	1	01:15:25
3	challenger.nielsenmedia.com	2	3	5.3 KB	1	00:30:13
4	tempe0.bbox.io	33	1	2.7 KB	1	05:23:05
TOTAL: 4		327	102	295.1 KB	87	207:23:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	320	98	287.2 KB	85	196:33:25
2	*.nielsenmedia.com	2	3	5.3 KB	1	00:30:13
3	*.bbox.io	33	1	2.7 KB	1	05:23:05
4	*.137.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	00:00:00
5	*.tampfl.dsl-w.verizon.net	1	0	0.0 KB	0	00:04:26
6	*.chello.nl	13	0	0.0 KB	0	04:52:36
7	*.ip-188-165-239.eu	1	0	0.0 KB	0	00:00:04
TOTAL: 7		371	102	295.1 KB	87	207:23:51

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
50.96.155.141	0.770	1.583	0.000	168:22:41
162.212.88.221	2.588	1.073	0.000	22:26:09
tempe0.bbox.io	2.835	0.660	0.000	05:23:05
dhcp-089-099-203-254.chello.nl	1.124	0.288	0.000	04:52:36
202.89.176.220	30.014	7.010	0.739	04:29:08
138.108.60.60	0.408	0.071	0.000	01:15:25
challenger.nielsenmedia.com	0.228	0.054	0.000	00:30:13
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.089	0.024	0.000	00:04:26
ns342002.ip-188-165-239.eu	0.074	0.000	0.000	00:00:04
TOTAL: 9	38.130	10.763	0.739	207:23:51

Curious NNRP server explorers [Top 20]:

System	Conn
162.212.88.221	28
dhcp-089-099-203-254.chello.nl	13
ns342002.ip-188-165-239.eu	1
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1
h208.137.22.98.dynamic.ip.windstream.net	1
TOTAL: 5	44

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	9	0
tempe0.bbox.io	9	0
162.212.88.221	4	0
138.108.60.60	1	0
challenger.nielsenmedia.com	1	0
50.96.155.141	1	0
TOTAL: 6	25	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	100	98.0%
2	news	2	2.0%
TOTAL: 2		102	100.0%

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

Newsgroup		Count
1	comp.arch	55
2	comp.os.vms	10
3	comp.arch.embedded	7
4	comp.lang.awk	6
5	comp.sys.ibm.pc.hardware.chips	5
6	comp.sys.ibm.pc.games.action	4
7	comp.os.os2.apps	3
8	comp.os.cpm	3
9	news.admin.peering	2
10	comp.ai.neural-nets	2
11	comp.sys.ibm.as400.misc	1
12	comp.sys.ibm.pc.games.strategic	1
13	comp.ai.nat-lang	1
14	comp.os.os2.announce	1
15	comp.os.qnx	1
TOTAL: 15		102

Daily Usenet report for csiph.com

Jan 28 04:15:00 -- Jan 29 04:15:00