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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	90231	47.0%	13.4 MB	58.8%
innd	61850	32.2%	5.7 MB	24.9%
nnrpd	36558	19.0%	3.2 MB	13.9%
innfeed	3313	1.7%	547.4 KB	2.4%
nocem	55	0.0%	5.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	192008	100.0%	22.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	753974	54.9%
Negative hits	530132	38.6%
Do not exist	88530	6.4%
Cache misses	1748	0.1%
TOTAL: 4	1374384	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.370	0.0%	285	0.000	4.807	24.200
article cleanup	00:00:00.416	0.0%	88276	0.000	0.005	0.017
article logging	00:00:01.070	0.0%	88977	0.000	0.012	0.131
article parse	00:00:02.898	0.0%	280841	0.003	0.010	0.069
article write	00:00:31.517	0.0%	29572	0.294	1.066	4.564
artlog/artcncl	00:00:00.005	0.0%	280	0.000	0.018	0.167
artlog/artparse	00:00:00.028	0.0%	982	0.000	0.029	1.000
data move	00:00:00.330	0.0%	1229088	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.581	0.0%	285	0.000	2.039	13.200
hishave/artcncl	00:00:00.027	0.0%	285	0.000	0.095	2.600
hishave/artparse	00:00:00.000	0.0%	391	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:18.678	0.0%	1377672	0.001	0.014	0.170
history sync	00:00:01.552	0.0%	330	0.000	4.703	111.500
history write	00:01:47.678	0.1%	88300	0.110	1.219	8.492
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.573	0.0%	391	0.000	1.465	13.000
idle	23:52:40.327	99.5%	1276452	21.753	67.343	211.948
nntp read	00:00:04.178	0.0%	1284972	0.002	0.003	0.005
overview write	00:01:27.866	0.1%	29572	0.344	2.971	29.411
perl filter	00:02:16.461	0.2%	87631	0.549	1.557	3.964
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.521	0.0%	59191	0.000	0.009	0.044
TOTAL: 24:00:34.812	23:59:16.076	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.095	0.0%	29608	0.000	0.003	0.025
article prepare	00:00:00.004	0.0%	35660	0.000	0.000	0.010
article read	00:00:00.590	0.0%	4008	0.000	0.147	9.800
backlog stats	00:00:00.311	0.0%	700703	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.643	0.0%	508633	0.003	0.005	0.009
data write	00:00:03.343	0.0%	490003	0.003	0.007	0.014
idle	23:52:12.024	100.0%	700703	70.123	122.637	437.938
status file	00:00:00.231	0.0%	335	0.000	0.690	1.000
TOTAL: 23:52:33.008	23:52:19.241	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:21.411	0.0%	136476	0.000	0.157	3.000
idle	301:29:49.520	99.7%	170769	132.816	6355.893	600209.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.070	0.0%	342663	0.000	0.006	9.200
readart	00:00:00.496	0.0%	131	0.000	3.786	62.000
TOTAL: 302:21:50.848	301:30:13.497	99.7%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	35670	6702	26187	2781	18%	23:49:44
2	csiph-out.feeder.erje.net	1155	258925	4719	211773	42433	1%	95:02:07
3	feeder.eternal-september.org	4	43224	3783	38634	807	8%	24:00:03
4	news-out.aioe.org	4	29553	2847	26657	49	9%	24:19:54
5	news-out.mixmin.net	2	34168	2753	30061	1354	8%	24:08:52
6	newsfeed.neva.ru	150	63645	2254	55763	5628	3%	72:04:49
7	csiph-out.news.weretis.net	3	64499	2215	61875	409	3%	47:51:17
8	newsfeed.fu-berlin.de	56	33971	1374	32236	361	4%	34:43:58
9	news.redatomik.org	4	29269	612	28643	14	2%	25:00:25
10	xanadu-bbs.net	1	86708	570	80605	5533	0%	24:31:04
11	newsfeeds.xmission.com	1	27026	548	26444	34	2%	20:22:27
12	news.fcku.it	1	9154	439	8713	2	4%	23:05:00
13	news.freedyn.net	7	34444	304	33977	163	0%	24:09:52
14	au2pb.net	1	32509	154	32318	37	0%	24:24:16
15	news.swapon.de	3	2422	137	2280	5	5%	24:44:36
16	nntp.pasdenom.info	3	30478	34	30404	40	0%	24:09:31
17	news.bbs.geek.nz	2	30693	11	30682	0	0%	24:08:50
18	localhost	37	3	3	0	0	100%	00:00:00
TOTAL: 18		1722	846361	29459	757252	59650	3%	560:36:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.7 MB	1.1 MB	58.0 MB	78.8 MB	24%	1.7 KB
2	usenet.blueworldhosting.com	19.0 MB	0.0 KB	24.1 MB	43.2 MB	44%	4.7 KB
3	feeder.eternal-september.org	9.4 MB	13.9 KB	10.2 MB	19.6 MB	47%	4.4 KB
4	news-out.mixmin.net	9.2 MB	3.5 KB	2.6 MB	11.8 MB	77%	2.9 KB
5	news-out.aioe.org	6.9 MB	10.2 KB	116.0 KB	7.0 MB	98%	2.5 KB
6	newsfeed.neva.ru	6.7 MB	274.6 KB	35.8 MB	42.8 MB	15%	5.6 KB
7	csiph-out.news.weretis.net	6.6 MB	21.2 KB	7.4 MB	13.9 MB	47%	5.4 KB
8	newsfeed.fu-berlin.de	3.2 MB	5.2 KB	793.8 KB	4.0 MB	80%	2.4 KB
9	newsfeeds.xmission.com	2.0 MB	0.0 KB	290.1 KB	2.2 MB	87%	4.0 KB
10	news.redatomik.org	1.5 MB	0.0 KB	240.1 KB	1.7 MB	86%	2.8 KB
11	xanadu-bbs.net	1.4 MB	21.3 KB	59.1 MB	60.6 MB	2%	10.2 KB
12	news.freedyn.net	1.2 MB	0.0 KB	1.1 MB	2.3 MB	52%	5.1 KB
13	news.fcku.it	734.9 KB	0.0 KB	2.2 KB	737.1 KB	99%	1.7 KB
14	au2pb.net	567.8 KB	10.7 KB	167.6 KB	746.1 KB	76%	3.9 KB
15	news.swapon.de	347.8 KB	0.0 KB	373.0 KB	720.7 KB	48%	5.1 KB
16	nntp.pasdenom.info	81.3 KB	0.0 KB	287.5 KB	368.8 KB	22%	5.0 KB
17	news.bbs.geek.nz	28.8 KB	0.0 KB	0.0 KB	28.8 KB	100%	2.6 KB
18	localhost	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.9 KB
TOTAL: 18		88.4 MB	1.4 MB	200.6 MB	290.4 MB	30%	3.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 14 04:15:00 - 04:59:59	406	1.4%	0.15	1.1 MB	1.3%	0.43
Jan 14 05:00:00 - 05:59:59	568	1.9%	0.16	2.0 MB	2.2%	0.57
Jan 14 06:00:00 - 06:59:59	665	2.2%	0.18	2.3 MB	2.5%	0.64
Jan 14 07:00:00 - 07:59:59	745	2.5%	0.21	1.9 MB	2.2%	0.55
Jan 14 08:00:00 - 08:59:59	1164	3.9%	0.32	3.3 MB	3.7%	0.94
Jan 14 09:00:00 - 09:59:59	1254	4.2%	0.35	3.7 MB	4.1%	1.04
Jan 14 10:00:00 - 10:59:59	1491	5.0%	0.41	3.8 MB	4.3%	1.09
Jan 14 11:00:00 - 11:59:59	1470	5.0%	0.41	3.9 MB	4.4%	1.11
Jan 14 12:00:00 - 12:59:59	1412	4.8%	0.39	3.8 MB	4.3%	1.08
Jan 14 13:00:00 - 13:59:59	1504	5.1%	0.42	4.6 MB	5.2%	1.32
Jan 14 14:00:00 - 14:59:59	1707	5.8%	0.47	4.2 MB	4.7%	1.19
Jan 14 15:00:00 - 15:59:59	1872	6.3%	0.52	5.2 MB	5.8%	1.47
Jan 14 16:00:00 - 16:59:59	1876	6.3%	0.52	5.6 MB	6.3%	1.59
Jan 14 17:00:00 - 17:59:59	1971	6.7%	0.55	6.6 MB	7.5%	1.89
Jan 14 18:00:00 - 18:59:59	1600	5.4%	0.44	6.1 MB	6.9%	1.74
Jan 14 19:00:00 - 19:59:59	1501	5.1%	0.42	4.4 MB	5.0%	1.26
Jan 14 20:00:00 - 20:59:59	1611	5.4%	0.45	5.3 MB	5.9%	1.50
Jan 14 21:00:00 - 21:59:59	1565	5.3%	0.43	4.7 MB	5.3%	1.35
Jan 14 22:00:00 - 22:59:59	1338	4.5%	0.37	4.2 MB	4.7%	1.20
Jan 14 23:00:00 - 23:59:59	1084	3.7%	0.30	3.6 MB	4.0%	1.01
Jan 15 00:00:00 - 00:59:59	801	2.7%	0.22	2.6 MB	2.9%	0.74
Jan 15 01:00:00 - 01:59:59	693	2.3%	0.19	1.9 MB	2.1%	0.54
Jan 15 02:00:00 - 02:59:59	565	1.9%	0.16	1.7 MB	1.9%	0.48
Jan 15 03:00:00 - 03:59:59	509	1.7%	0.14	1.6 MB	1.8%	0.47
Jan 15 04:00:00 - 04:15:00	212	0.7%	0.24	776.6 KB	0.9%	0.86
TOTAL: 24:00:00	29584	100.0%	0.34	89.0 MB	100.0%	1.05

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	42481	626	530	2	41323
2	newsfeed.neva.ru	5625	1748	24	0	3853
3	xanadu-bbs.net	5486	53	2	0	5431
4	usenet.blueworldhosting.com	2778	1303	0	1	1474
5	news-out.mixmin.net	1390	2	1	0	1387
6	feeder.eternal-september.org	849	108	2	0	739
7	newsfeed.fu-berlin.de	846	36	1	0	809
8	csiph-out.news.weretis.net	503	259	2	0	242
9	news.freedyn.net	165	2	0	0	163
10	news-out.aioe.org	73	23	4	0	46
11	nntp.pasdenom.info	40	34	0	0	6
12	au2pb.net	39	30	1	0	8
13	newsfeeds.xmission.com	37	33	0	0	4
14	news.redatomik.org	27	0	0	0	27
15	news.swapon.de	24	0	0	0	24
16	news.fcku.it	4	0	0	0	4
TOTAL: 16		60367	4257	567	3	55540

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	811
mailing.freebsd.svn-src-all	224
lada.talk	217
fa.freebsd.cvs-all	211
ut.test	211
lada.auto	172
mailing.freebsd.svn-src-stable	134
mailing.freebsd.svn-src-stable-10	124
mailing.freebsd.ports-bugs	109
mozilla.dev.tree-alerts	107
mozilla.general	88
mailing.freebsd.svn-src-head	86
alt.survival	64
mailing.freebsd.pkg-fallout	62
alt.binaries.multimedia.tatu	57
alt.biology	55
alt.politics.scorched-earth	49
mailing.openbsd.source-changes	48
mailing.freebsd.svn-ports-all	42
mailing.freebsd.svn-ports-head	41
TOTAL: 267	4257

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

Groups	Count
uk.radio.amateur.moderated	2
nz.politics.announce	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	49345
EMP (phn path)	2374
EMP (phl)	1001
EMP (phn nph)	237
PFM bot misc	162
User-issued cancel	156
HTML Multipart	60
Too many newsgroups	56
Scoring filter (26)	50
Scoring filter (19)	26
Scoring filter (21)	25
Scoring filter (22)	24
Scoring filter (29)	23
Scoring filter (18)	22
Scoring filter (20)	16
Scoring filter (12)	15
Scoring filter (17)	14
Binary Payload	14
Scoring filter (10)	14
Scoring filter (16)	11
TOTAL: 47	53776

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	73
	usenet.blueworldhosting.com	5
	TOTAL: 2	78
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	usenet.blueworldhosting.com	1
	TOTAL: 2	5
Huge articles
	xanadu-bbs.net	184
	csiph-out.news.weretis.net	61
	usenet.blueworldhosting.com	60
	feeder.eternal-september.org	57
	news-out.mixmin.net	14
	news.freedyn.net	6
	news.redatomik.org	3
	newsfeeds.xmission.com	3
	nntp.pasdenom.info	2
	newsfeed.fu-berlin.de	1
	TOTAL: 12	393
Including strange strings
	newsfeed.fu-berlin.de	471
	csiph-out.news.weretis.net	83
	csiph-out.feeder.erje.net	54
	news-out.aioe.org	24
	news.swapon.de	19
	news-out.mixmin.net	15
	feeder.eternal-september.org	13
	xanadu-bbs.net	7
	newsfeed.neva.ru	6
	news.freedyn.net	5
	TOTAL: 13	702
No colon-space in header
	csiph-out.feeder.erje.net	590
	usenet.blueworldhosting.com	1
	TOTAL: 2	591
TOTAL: 5		1769

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	54159	1727	26762	29	1	3%	23:59:50
2	news.freedyn.net	34822	1632	27633	7	0	4%	23:59:29
3	xanadu-bbs	32028	1317	27697	0	5	4%	23:59:52
4	fu-berlin.de	27865	984	26881	0	0	3%	23:59:52
5	aioe	27933	574	23563	30	0	2%	23:59:50
6	usenet.pasdenom.info	37857	354	27801	1395	5	0%	23:59:52
7	news.redatomik.org	34876	207	27669	12	8	0%	23:59:52
8	au2pb	29299	172	28191	11	0	0%	23:59:52
9	xmission	32029	54	27773	4	0	0%	23:59:40
10	erje	28627	27	24616	19	93	0%	23:59:52
11	news.mixmin.net	29410	22	25379	0	0	0%	23:59:52
12	eternal-september	30481	20	24241	0	0	0%	23:59:50
13	news.fcku.it	4590	18	3807	0	0	0%	23:15:57
14	neva.ru	31608	14	25649	270	0	0%	23:59:52
15	weretis.net	25697	13	24999	3	0	0%	23:59:52
16	bwh	23056	5	21606	0	0	0%	23:59:48
17	news.swapon.de	2634	4	2316	10	0	0%	23:56:54
TOTAL: 17		486971	7144	396583	1790	112	1%	407:10:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	11.4 MB	68.6 KB	11.5 MB	0.1 KB/s	7.2 KB	23:59:29
2	xanadu-bbs	10.3 MB	0.0 KB	10.3 MB	0.1 KB/s	8.0 KB	23:59:52
3	fu-berlin.de	9.3 MB	0.0 KB	9.3 MB	0.1 KB/s	9.7 KB	23:59:52
4	news.bbs.geek.nz	4.8 MB	127.5 KB	4.9 MB	0.1 KB/s	2.9 KB	23:59:50
5	aioe	1.8 MB	204.2 KB	2.0 MB	0.0 KB/s	3.4 KB	23:59:50
6	usenet.pasdenom.info	1.4 MB	9.9 MB	11.3 MB	0.1 KB/s	6.6 KB	23:59:52
7	au2pb	802.0 KB	71.1 KB	873.1 KB	0.0 KB/s	4.8 KB	23:59:52
8	news.redatomik.org	760.8 KB	101.7 KB	862.5 KB	0.0 KB/s	3.9 KB	23:59:52
9	xmission	146.3 KB	15.1 KB	161.4 KB	0.0 KB/s	2.8 KB	23:59:40
10	erje	96.2 KB	158.7 KB	254.9 KB	0.0 KB/s	5.5 KB	23:59:52
11	news.mixmin.net	88.4 KB	0.0 KB	88.4 KB	0.0 KB/s	4.0 KB	23:59:52
12	eternal-september	67.1 KB	0.0 KB	67.1 KB	0.0 KB/s	3.4 KB	23:59:50
13	neva.ru	62.2 KB	885.1 KB	947.3 KB	0.0 KB/s	3.3 KB	23:59:52
14	news.fcku.it	34.4 KB	0.0 KB	34.4 KB	0.0 KB/s	1.9 KB	23:15:57
15	weretis.net	32.6 KB	55.7 KB	88.3 KB	0.0 KB/s	5.5 KB	23:59:52
16	bwh	8.1 KB	0.0 KB	8.1 KB	0.0 KB/s	1.6 KB	23:59:48
17	news.swapon.de	5.9 KB	27.6 KB	33.5 KB	0.0 KB/s	2.4 KB	23:56:54
TOTAL: 17		41.1 MB	11.6 MB	52.7 MB	0.0 KB/s	6.0 KB	407:10:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	286	101	295.6 KB	96	0	0	06:42:51
2	tempe0.bbox.io	53	22	41.2 KB	11	3	3	08:49:11
3	dhcp-089-099-203-254.chello.nl	19	8	15.3 KB	4	0	0	06:15:18
TOTAL: 3		358	131	352.0 KB	111	3	3	302:21:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	869	101	295.6 KB	96	0	0	284:42:57
2	*.bbox.io	53	22	41.2 KB	11	3	3	08:49:11
3	*.chello.nl	19	8	15.3 KB	4	0	0	06:15:18
4	*.ip-188-165-239.eu	14	0	0.0 KB	0	0	0	01:01:20
5	*.sub-70-193-248.myvzw.com	2	0	0.0 KB	0	0	0	01:02:47
6	*.nielsenmedia.com	1	0	0.0 KB	0	0	0	00:30:01
TOTAL: 6		958	131	352.0 KB	111	3	3	302:21:37

NNRP auth users [Top 20]:

User	Conn
moussa	286
kev009	3
TOTAL: 2	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
63.142.120.8	50.861	16.218	0.000	233:50:13
162.212.88.221	0.574	0.754	0.000	43:39:52
tempe0.bbox.io	4.881	1.126	0.008	08:49:11
202.89.176.220	28.767	6.999	0.773	06:42:51
dhcp-089-099-203-254.chello.nl	1.924	0.313	0.000	06:15:18
76.sub-70-193-248.myvzw.com	0.183	0.039	0.000	01:02:47
ns342002.ip-188-165-239.eu	1.693	1.244	0.000	01:01:20
challenger.nielsenmedia.com	0.073	0.041	0.000	00:30:01
138.108.60.60	0.091	0.025	0.000	00:30:00
TOTAL: 9	89.047	26.759	0.781	302:21:37

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	578
ns342002.ip-188-165-239.eu	14
162.212.88.221	4
76.sub-70-193-248.myvzw.com	2
challenger.nielsenmedia.com	1
138.108.60.60	1
TOTAL: 6	600

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	13	0
dhcp-089-099-203-254.chello.nl	12	0
76.sub-70-193-248.myvzw.com	2	0
138.108.60.60	1	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	29	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	120	92.3%
2	news	10	7.7%
TOTAL: 2		130	100.0%

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

Newsgroup		Count
1	comp.arch	39
2	comp.sys.ibm.pc.hardware.storage	19
3	comp.sys.ibm.ps2.hardware	16
4	comp.os.vms	15
5	comp.arch.embedded	11
6	comp.sys.hp.hpux	8
7	news.software.nntp	8
8	comp.sys.ibm.pc.games.action	4
9	comp.ai.philosophy	3
10	news.admin.peering	2
11	comp.os.cpm	2
12	comp.os.os2.announce	1
13	comp.sys.ibm.pc.hardware.chips	1
14	comp.sys.ibm.as400.misc	1
TOTAL: 14		130

Daily Usenet report for csiph.com

Jan 14 04:15:00 -- Jan 15 04:15:00