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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	59364	46.4%	10.7 MB	62.1%
nnrpd	33261	26.0%	2.8 MB	16.2%
innd	32166	25.1%	3.2 MB	18.6%
innfeed	3182	2.5%	542.4 KB	3.1%
nocem	56	0.0%	5.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	128030	100.0%	17.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	633232	55.7%
Negative hits	444296	39.1%
Do not exist	58510	5.1%
Cache misses	757	0.1%
TOTAL: 4	1136795	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.253	0.0%	191	0.000	1.325	17.200
article cleanup	00:00:00.306	0.0%	58612	0.000	0.005	0.035
article logging	00:00:00.832	0.0%	59137	0.000	0.014	0.238
article parse	00:00:02.727	0.0%	253344	0.003	0.011	0.101
article write	00:01:13.832	0.1%	29667	0.193	2.489	8.361
artlog/artcncl	00:00:00.001	0.0%	168	0.000	0.006	0.056
artlog/artparse	00:00:00.003	0.0%	311	0.000	0.010	1.000
data move	00:00:00.370	0.0%	1101821	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.007	0.0%	190	0.000	0.037	2.000
hishave/artcncl	00:00:00.001	0.0%	191	0.000	0.005	0.056
hishave/artparse	00:00:00.000	0.0%	310	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:09.524	0.0%	1142099	0.001	0.008	0.083
history sync	00:00:02.610	0.0%	300	0.000	8.700	118.000
history write	00:01:30.371	0.1%	58565	0.090	1.543	6.710
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.621	0.0%	310	0.000	2.003	52.000
idle	23:51:42.212	99.4%	1113567	34.320	77.141	245.120
nntp read	00:00:03.659	0.0%	1126331	0.002	0.003	0.006
overview write	00:01:32.765	0.1%	29667	0.630	3.127	35.843
perl filter	00:02:49.813	0.2%	58564	0.752	2.900	4.763
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.470	0.0%	59386	0.000	0.008	0.022
TOTAL: 24:00:16.409	23:59:10.377	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.082	0.0%	29625	0.000	0.003	0.015
article prepare	00:00:00.007	0.0%	43432	0.000	0.000	0.005
article read	00:00:00.820	0.0%	5653	0.000	0.145	7.667
backlog stats	00:00:00.321	0.0%	716004	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.607	0.0%	517570	0.002	0.005	0.009
data write	00:00:03.532	0.0%	498276	0.003	0.007	0.012
idle	23:52:37.130	100.0%	716004	79.024	120.051	292.431
status file	00:00:00.190	0.0%	277	0.000	0.686	3.000
TOTAL: 23:52:58.176	23:52:44.689	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.869	0.0%	16503	0.034	0.174	0.439
idle	47:36:52.242	100.0%	49348	400.549	3473.540	600194.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.625	0.0%	99601	0.000	0.006	0.167
readart	00:00:00.194	0.0%	81	0.000	2.395	53.000
TOTAL: 47:38:12.534	47:36:55.930	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	38309	6861	28336	3112	17%	23:44:45
2	csiph-out.feeder.erje.net	1152	106465	4373	99840	2252	4%	95:08:13
3	feeder.eternal-september.org	5	54007	4007	43502	6498	7%	24:52:56
4	csiph-out.news.weretis.net	5	67523	2918	64181	424	4%	47:31:53
5	news-out.aioe.org	2	28879	2653	26194	32	9%	24:09:57
6	news-out.mixmin.net	2	33294	2157	30003	1134	6%	24:08:49
7	newsfeed.neva.ru	146	75886	2115	62934	10837	2%	72:19:17
8	newsfeed.fu-berlin.de	61	34905	1440	33280	185	4%	35:43:59
9	news.freedyn.net	3	33698	807	32410	481	2%	24:10:01
10	newsfeeds.xmission.com	1	31881	715	31141	25	2%	25:12:18
11	news.redatomik.org	1	29513	631	28860	22	2%	24:50:12
12	xanadu-bbs.net	1	57319	457	52662	4200	0%	24:09:30
13	news.fcku.it	1	10403	424	9977	2	4%	24:23:24
14	news.swapon.de	2	2262	132	2128	2	5%	22:49:47
15	au2pb.net	1	33121	74	33008	39	0%	24:24:16
16	nntp.pasdenom.info	3	31816	20	31783	13	0%	24:08:35
17	news.bbs.geek.nz	1	31579	1	31575	3	0%	23:59:44
18	localhost	31	0	0	0	0	0%	00:00:00
TOTAL: 18		1706	700860	29785	641814	29261	4%	565:47:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	19.1 MB	0.0 KB	33.5 MB	52.7 MB	36%	5.4 KB
2	csiph-out.feeder.erje.net	15.4 MB	205.1 KB	8.6 MB	24.2 MB	63%	3.7 KB
3	feeder.eternal-september.org	9.7 MB	10.4 KB	77.5 MB	87.2 MB	11%	8.5 KB
4	csiph-out.news.weretis.net	9.1 MB	1.3 KB	6.0 MB	15.1 MB	59%	4.6 KB
5	news-out.mixmin.net	8.0 MB	4.5 KB	2.3 MB	10.3 MB	77%	3.2 KB
6	news-out.aioe.org	6.5 MB	4.3 KB	54.0 KB	6.6 MB	99%	2.5 KB
7	newsfeed.neva.ru	6.0 MB	99.6 KB	79.5 MB	85.5 MB	7%	6.8 KB
8	news.freedyn.net	3.3 MB	3.4 KB	3.4 MB	6.7 MB	49%	5.3 KB
9	newsfeed.fu-berlin.de	3.1 MB	2.9 KB	533.0 KB	3.6 MB	85%	2.3 KB
10	newsfeeds.xmission.com	2.7 MB	0.0 KB	816.6 KB	3.5 MB	76%	4.8 KB
11	news.redatomik.org	1.3 MB	3.0 KB	170.4 KB	1.5 MB	88%	2.3 KB
12	xanadu-bbs.net	1.1 MB	4.4 KB	41.8 MB	42.9 MB	2%	9.4 KB
13	news.fcku.it	797.2 KB	0.0 KB	43.0 KB	840.2 KB	94%	2.0 KB
14	news.swapon.de	338.7 KB	0.0 KB	2.8 KB	341.5 KB	99%	2.5 KB
15	au2pb.net	217.0 KB	1.3 KB	224.9 KB	443.2 KB	48%	3.9 KB
16	nntp.pasdenom.info	39.8 KB	3.0 KB	39.9 KB	82.7 KB	48%	2.5 KB
17	news.bbs.geek.nz	5.2 KB	0.0 KB	3.2 KB	8.5 KB	61%	2.1 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		86.6 MB	343.2 KB	254.5 MB	341.4 MB	25%	5.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 26 04:15:00 - 04:59:59	478	1.6%	0.18	1.6 MB	1.8%	0.60
Jan 26 05:00:00 - 05:59:59	798	2.7%	0.22	3.1 MB	3.6%	0.88
Jan 26 06:00:00 - 06:59:59	662	2.2%	0.18	2.1 MB	2.5%	0.61
Jan 26 07:00:00 - 07:59:59	874	2.9%	0.24	2.8 MB	3.2%	0.80
Jan 26 08:00:00 - 08:59:59	1455	4.9%	0.40	4.5 MB	5.3%	1.29
Jan 26 09:00:00 - 09:59:59	1295	4.4%	0.36	3.3 MB	3.8%	0.93
Jan 26 10:00:00 - 10:59:59	1466	4.9%	0.41	3.6 MB	4.2%	1.03
Jan 26 11:00:00 - 11:59:59	1310	4.4%	0.36	3.7 MB	4.3%	1.06
Jan 26 12:00:00 - 12:59:59	1385	4.7%	0.38	4.1 MB	4.7%	1.16
Jan 26 13:00:00 - 13:59:59	1514	5.1%	0.42	3.9 MB	4.5%	1.11
Jan 26 14:00:00 - 14:59:59	1602	5.4%	0.45	4.0 MB	4.6%	1.12
Jan 26 15:00:00 - 15:59:59	1661	5.6%	0.46	4.3 MB	5.0%	1.22
Jan 26 16:00:00 - 16:59:59	1672	5.6%	0.46	5.0 MB	5.8%	1.42
Jan 26 17:00:00 - 17:59:59	1672	5.6%	0.46	5.2 MB	6.0%	1.47
Jan 26 18:00:00 - 18:59:59	1682	5.7%	0.47	4.9 MB	5.7%	1.40
Jan 26 19:00:00 - 19:59:59	1653	5.6%	0.46	5.4 MB	6.2%	1.53
Jan 26 20:00:00 - 20:59:59	1502	5.1%	0.42	4.4 MB	5.1%	1.26
Jan 26 21:00:00 - 21:59:59	1678	5.7%	0.47	4.9 MB	5.7%	1.39
Jan 26 22:00:00 - 22:59:59	1474	5.0%	0.41	4.2 MB	4.8%	1.18
Jan 26 23:00:00 - 23:59:59	1001	3.4%	0.28	2.9 MB	3.3%	0.81
Jan 27 00:00:00 - 00:59:59	869	2.9%	0.24	2.6 MB	3.0%	0.74
Jan 27 01:00:00 - 01:59:59	601	2.0%	0.17	1.8 MB	2.0%	0.50
Jan 27 02:00:00 - 02:59:59	591	2.0%	0.16	1.6 MB	1.9%	0.47
Jan 27 03:00:00 - 03:59:59	602	2.0%	0.17	1.8 MB	2.1%	0.52
Jan 27 04:00:00 - 04:15:00	167	0.6%	0.19	694.0 KB	0.8%	0.77
TOTAL: 24:00:00	29664	100.0%	0.34	86.4 MB	100.0%	1.02

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	10778	1743	11	0	9024
2	feeder.eternal-september.org	6485	169	3	0	6313
3	xanadu-bbs.net	4142	23	1	0	4118
4	usenet.blueworldhosting.com	3073	652	0	1	2420
5	csiph-out.feeder.erje.net	2273	1307	22	1	943
6	news-out.mixmin.net	1156	7	2	0	1147
7	newsfeed.fu-berlin.de	554	25	1	0	528
8	news.freedyn.net	479	0	2	0	477
9	csiph-out.news.weretis.net	432	253	1	0	178
10	news-out.aioe.org	46	29	2	0	15
11	au2pb.net	31	23	1	0	7
12	newsfeeds.xmission.com	30	15	0	0	15
13	news.swapon.de	25	0	0	0	25
14	nntp.pasdenom.info	13	8	1	0	4
15	news.redatomik.org	8	0	1	0	7
16	news.fcku.it	6	0	0	0	6
17	news.bbs.geek.nz	1	1	0	0	0
TOTAL: 17		29532	4255	48	2	25227

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1022
lada.talk	302
ut.test	187
alt.politics.scorched-earth	159
mailing.freebsd.svn-src-all	137
fa.freebsd.cvs-all	128
mailing.freebsd.pkg-fallout	114
mailing.freebsd.ports-bugs	106
mailing.freebsd.svn-src-head	96
mailing.freebsd.svn-ports-all	83
mailing.freebsd.svn-ports-head	78
lada.auto	74
fa.netbsd.bugs	53
it-alt.sport.calcio.juventus	53
mailing.gcc.gcc-cvs	50
pld.cvs.commit	50
alt.binaries.multimedia.tatu	41
gmane.comp.gis.openstreetmap.tagging	38
mailing.openbsd.source-changes	37
mpc.lists.nanog	34
TOTAL: 267	4255

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

Groups	Count
alt.binaries.sounds.mp3.jazz.vocals	1
nz.politics.announce	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	17476
EMP (phn path)	3419
User-issued cancel	1728
EMP (phl)	1049
PFM bot misc	220
EMP (phn nph)	172
Too many newsgroups	50
Scoring filter (21)	49
HTML Multipart	41
Scoring filter (19)	31
Scoring filter (22)	18
Scoring filter (18)	16
User-issued spam cancel	15
Binary Payload	12
Scoring filter (17)	12
Scoring filter (10)	11
Scoring filter (12)	11
HTML post	10
Scoring filter (8)	10
Too many newsgroups (meow)	6
TOTAL: 35	24391

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	133
	usenet.blueworldhosting.com	60
	csiph-out.news.weretis.net	41
	feeder.eternal-september.org	39
	newsfeeds.xmission.com	14
	news-out.mixmin.net	9
	news.freedyn.net	9
	au2pb.net	2
	news.redatomik.org	1
	news.fcku.it	1
	TOTAL: 11	310
Including strange strings
	newsfeed.fu-berlin.de	371
	feeder.eternal-september.org	40
	csiph-out.news.weretis.net	28
	news.swapon.de	23
	csiph-out.feeder.erje.net	20
	news-out.mixmin.net	16
	news-out.aioe.org	14
	news.fcku.it	4
	news.redatomik.org	3
	news.freedyn.net	2
	TOTAL: 13	525
TOTAL: 2		835

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	erje	30189	3131	22025	83	1	10%	23:59:52
2	news.freedyn.net	34345	1452	27393	7	1	4%	23:59:52
3	xanadu-bbs	32422	1217	27987	1	21	3%	23:59:52
4	news.bbs.geek.nz	55304	1175	27616	20	2	2%	23:59:51
5	fu-berlin.de	27807	841	26966	0	0	3%	23:59:52
6	aioe	27714	436	23762	35	0	1%	23:59:52
7	usenet.pasdenom.info	38193	357	27963	1323	5	0%	23:59:52
8	au2pb	29762	198	28507	18	1	0%	23:59:52
9	news.redatomik.org	34893	181	28043	7	1	0%	23:59:41
10	xmission	32477	48	28023	2	0	0%	23:59:52
11	weretis.net	26088	25	25121	10	1	0%	23:59:22
12	eternal-september	30178	24	24268	0	2	0%	23:59:46
13	neva.ru	31783	24	26131	236	1	0%	23:59:51
14	news.fcku.it	4764	22	3918	0	0	0%	21:57:32
15	news.mixmin.net	30936	15	26354	0	0	0%	23:59:29
16	bwh	23300	3	21773	0	1	0%	23:59:52
17	news.swapon.de	2619	3	2347	6	0	0%	23:50:31
TOTAL: 17		492774	9152	398197	1748	37	1%	405:44:51

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.6 MB	48.9 KB	9.7 MB	0.1 KB/s	6.8 KB	23:59:52
2	erje	8.8 MB	733.0 KB	9.6 MB	0.1 KB/s	3.0 KB	23:59:52
3	xanadu-bbs	8.8 MB	3.6 KB	8.8 MB	0.1 KB/s	7.4 KB	23:59:52
4	fu-berlin.de	7.3 MB	0.0 KB	7.3 MB	0.1 KB/s	8.9 KB	23:59:52
5	news.bbs.geek.nz	3.6 MB	67.5 KB	3.7 MB	0.0 KB/s	3.1 KB	23:59:51
6	usenet.pasdenom.info	1.9 MB	8.2 MB	10.1 MB	0.1 KB/s	6.2 KB	23:59:52
7	aioe	1.3 MB	370.6 KB	1.7 MB	0.0 KB/s	3.6 KB	23:59:52
8	au2pb	904.4 KB	315.2 KB	1.2 MB	0.0 KB/s	5.6 KB	23:59:52
9	news.redatomik.org	712.8 KB	84.3 KB	797.1 KB	0.0 KB/s	4.2 KB	23:59:41
10	xmission	128.2 KB	4.9 KB	133.2 KB	0.0 KB/s	2.7 KB	23:59:52
11	eternal-september	93.9 KB	0.0 KB	93.9 KB	0.0 KB/s	3.9 KB	23:59:46
12	neva.ru	93.9 KB	900.3 KB	994.2 KB	0.0 KB/s	3.8 KB	23:59:51
13	news.mixmin.net	77.4 KB	0.0 KB	77.4 KB	0.0 KB/s	5.2 KB	23:59:29
14	weretis.net	63.2 KB	238.2 KB	301.4 KB	0.0 KB/s	8.6 KB	23:59:22
15	news.fcku.it	44.8 KB	0.0 KB	44.8 KB	0.0 KB/s	2.0 KB	21:57:32
16	bwh	5.8 KB	0.0 KB	5.8 KB	0.0 KB/s	1.9 KB	23:59:52
17	news.swapon.de	5.8 KB	10.2 KB	15.9 KB	0.0 KB/s	1.8 KB	23:50:31
TOTAL: 17		43.5 MB	10.9 MB	54.4 MB	0.0 KB/s	5.1 KB	405:44:51

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	73	256.6 KB	68	04:35:25
2	tempe0.bbox.io	17	8	12.2 KB	2	02:23:26
TOTAL: 2		305	81	268.7 KB	70	47:38:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	293	73	256.6 KB	68	33:01:55
2	*.bbox.io	17	8	12.2 KB	2	02:23:26
3	*.chello.nl	22	0	0.0 KB	0	09:15:43
4	*.dynamic.caiway.nl	8	0	0.0 KB	0	02:08:33
5	*.tampfl.dsl-w.verizon.net	2	0	0.0 KB	0	00:48:29
TOTAL: 5		342	81	268.7 KB	70	47:38:08

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
162.212.88.221	0.484	0.471	0.000	27:56:27
dhcp-089-099-203-254.chello.nl	1.821	0.364	0.000	09:15:43
202.89.176.220	28.875	7.050	0.735	04:35:25
tempe0.bbox.io	1.503	0.406	0.000	02:23:26
041-020-158-163.dynamic.caiway.nl	0.738	0.480	0.000	02:08:33
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.190	0.030	0.000	00:48:29
138.108.60.60	0.078	0.023	0.000	00:30:03
TOTAL: 7	33.689	8.824	0.735	47:38:08

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	22
041-020-158-163.dynamic.caiway.nl	8
162.212.88.221	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2
138.108.60.60	1
TOTAL: 5	37

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	17	0
tempe0.bbox.io	4	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
138.108.60.60	1	0
TOTAL: 4	23	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	75	92.6%
2	news	6	7.4%
TOTAL: 2		81	100.0%

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

Newsgroup		Count
1	comp.arch	29
2	comp.os.vms	24
3	news.admin.peering	6
4	comp.arch.embedded	6
5	comp.sys.ibm.as400.misc	4
6	comp.sys.ibm.pc.games.action	3
7	comp.ai.neural-nets	2
8	comp.sys.ibm.pc.hardware.storage	2
9	comp.sys.ibm.ps2.hardware	2
10	comp.os.msdos.programmer	1
11	comp.os.cpm	1
12	comp.arch.fpga	1
TOTAL: 12		81

Daily Usenet report for csiph.com

Jan 26 04:15:00 -- Jan 27 04:15:00