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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	58300	46.6%	10.7 MB	62.6%
nnrpd	33478	26.7%	2.8 MB	16.5%
innd	30177	24.1%	3.0 MB	17.7%
innfeed	3239	2.6%	536.2 KB	3.1%
nocem	45	0.0%	4.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	125240	100.0%	17.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	607770	55.7%
Negative hits	424718	38.9%
Do not exist	57323	5.3%
Cache misses	761	0.1%
TOTAL: 4	1090572	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.310	0.0%	176	0.000	1.761	30.000
article cleanup	00:00:00.294	0.0%	56933	0.000	0.005	0.025
article logging	00:00:00.913	0.0%	57510	0.000	0.016	0.545
article parse	00:00:03.251	0.0%	250995	0.002	0.013	0.069
article write	00:01:15.452	0.1%	30378	0.272	2.484	8.759
artlog/artcncl	00:00:00.002	0.0%	162	0.000	0.012	0.167
artlog/artparse	00:00:00.006	0.0%	550	0.000	0.011	0.250
data move	00:00:00.370	0.0%	1065808	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.073	0.0%	176	0.000	0.415	21.000
hishave/artcncl	00:00:00.001	0.0%	176	0.000	0.006	0.056
hishave/artparse	00:00:00.002	0.0%	458	0.000	0.004	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:31.734	0.0%	1093482	0.001	0.029	0.200
history sync	00:00:04.301	0.0%	334	0.000	12.877	79.333
history write	00:03:04.473	0.2%	56973	0.079	3.238	12.492
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.126	0.0%	458	0.000	2.459	44.000
idle	23:49:20.064	99.2%	1069962	46.976	80.152	329.933
nntp read	00:00:03.554	0.0%	1087199	0.001	0.003	0.005
overview write	00:02:23.885	0.2%	30378	1.463	4.736	40.332
perl filter	00:02:42.977	0.2%	56881	1.631	2.865	4.857
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.512	0.0%	60796	0.000	0.008	0.026
TOTAL: 24:00:37.624	23:59:33.300	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.098	0.0%	30772	0.000	0.003	0.020
article prepare	00:00:00.007	0.0%	31594	0.000	0.000	0.008
article read	00:00:00.746	0.0%	3283	0.000	0.227	10.200
backlog stats	00:00:00.274	0.0%	706561	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.679	0.0%	515770	0.002	0.005	0.009
data write	00:00:03.352	0.0%	500195	0.003	0.007	0.012
idle	23:52:46.181	100.0%	706561	74.080	121.668	379.198
status file	00:00:00.250	0.0%	310	0.000	0.806	6.000
TOTAL: 23:53:06.055	23:52:53.587	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.802	0.0%	4923	0.468	0.569	0.690
idle	36:00:21.668	99.9%	37910	316.000	3419.195	600145.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:05.517	0.0%	77105	0.000	0.072	29.746
readart	00:00:11.133	0.0%	6426	0.000	1.732	31.333
TOTAL: 36:01:52.549	36:00:41.120	99.9%	-	-	-	-

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	37800	6893	26987	3920	18%	23:49:52
2	feeder.eternal-september.org	8	52647	4529	42344	5774	8%	25:50:07
3	news-out.mixmin.net	4	34080	3803	28701	1576	11%	24:17:50
4	news-out.aioe.org	5	29615	3363	26182	70	11%	24:19:38
5	csiph-out.feeder.erje.net	1151	114031	3308	109152	1571	2%	95:03:41
6	csiph-out.news.weretis.net	5	71839	2674	68518	647	3%	51:42:39
7	newsfeed.neva.ru	143	70433	2314	59306	8813	3%	71:42:13
8	newsfeed.fu-berlin.de	68	29649	1237	28241	171	4%	30:02:22
9	news.redatomik.org	1	32710	699	31994	17	2%	27:22:53
10	newsfeeds.xmission.com	1	32704	626	32048	30	1%	25:12:18
11	xanadu-bbs.net	1	55073	485	50189	4399	0%	23:00:27
12	news.fcku.it	1	9807	432	9375	0	4%	24:23:24
13	news.swapon.de	2	2427	163	2249	15	6%	24:35:40
14	au2pb.net	1	32426	87	32312	27	0%	24:24:16
15	news.freedyn.net	2	1661	15	1640	6	0%	02:24:40
16	nntp.pasdenom.info	3	32270	15	32242	13	0%	24:08:36
17	localhost	33	1	1	0	0	100%	00:00:00
18	news.bbs.geek.nz	2	30168	0	30167	1	0%	23:59:44
TOTAL: 18		1719	669341	30644	611647	27050	4%	546:20:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	19.6 MB	3.1 KB	41.1 MB	60.6 MB	32%	5.7 KB
2	csiph-out.feeder.erje.net	13.5 MB	346.0 KB	5.9 MB	19.8 MB	68%	4.2 KB
3	news-out.mixmin.net	12.2 MB	6.5 KB	3.1 MB	15.2 MB	79%	2.9 KB
4	feeder.eternal-september.org	11.3 MB	10.1 KB	67.5 MB	78.8 MB	14%	7.8 KB
5	csiph-out.news.weretis.net	8.5 MB	0.0 KB	7.4 MB	15.9 MB	53%	4.9 KB
6	news-out.aioe.org	8.2 MB	0.0 KB	106.0 KB	8.3 MB	98%	2.5 KB
7	newsfeed.neva.ru	6.9 MB	30.4 KB	69.9 MB	76.8 MB	8%	7.1 KB
8	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	422.1 KB	3.0 MB	86%	2.2 KB
9	newsfeeds.xmission.com	2.4 MB	0.0 KB	581.1 KB	2.9 MB	80%	4.6 KB
10	news.redatomik.org	1.6 MB	0.0 KB	212.7 KB	1.8 MB	88%	2.5 KB
11	xanadu-bbs.net	1.2 MB	0.0 KB	42.9 MB	44.1 MB	2%	9.2 KB
12	news.fcku.it	704.5 KB	0.0 KB	0.0 KB	704.5 KB	100%	1.6 KB
13	news.swapon.de	411.4 KB	3.0 KB	39.0 KB	453.4 KB	90%	2.5 KB
14	au2pb.net	258.3 KB	0.0 KB	230.0 KB	488.3 KB	52%	4.3 KB
15	news.freedyn.net	66.2 KB	0.0 KB	77.6 KB	143.8 KB	46%	6.8 KB
16	nntp.pasdenom.info	35.6 KB	0.0 KB	76.3 KB	111.9 KB	31%	4.0 KB
17	localhost	2.1 KB	0.0 KB	0.0 KB	2.1 KB	100%	2.1 KB
18	news.bbs.geek.nz	0.0 KB	0.0 KB	1.4 KB	1.4 KB	0%	1.4 KB
TOTAL: 18		89.3 MB	399.1 KB	239.4 MB	329.0 MB	27%	5.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 27 04:15:00 - 04:59:59	435	1.4%	0.16	1.4 MB	1.6%	0.54
Jan 27 05:00:00 - 05:59:59	648	2.1%	0.18	2.3 MB	2.6%	0.65
Jan 27 06:00:00 - 06:59:59	698	2.3%	0.19	2.4 MB	2.7%	0.68
Jan 27 07:00:00 - 07:59:59	804	2.6%	0.22	2.2 MB	2.5%	0.62
Jan 27 08:00:00 - 08:59:59	1144	3.8%	0.32	3.0 MB	3.4%	0.85
Jan 27 09:00:00 - 09:59:59	1439	4.7%	0.40	3.7 MB	4.2%	1.06
Jan 27 10:00:00 - 10:59:59	1601	5.3%	0.44	5.2 MB	5.9%	1.49
Jan 27 11:00:00 - 11:59:59	1525	5.0%	0.42	4.3 MB	4.9%	1.23
Jan 27 12:00:00 - 12:59:59	1439	4.7%	0.40	4.2 MB	4.8%	1.20
Jan 27 13:00:00 - 13:59:59	1823	6.0%	0.51	5.4 MB	6.1%	1.52
Jan 27 14:00:00 - 14:59:59	1560	5.1%	0.43	4.3 MB	4.9%	1.23
Jan 27 15:00:00 - 15:59:59	1706	5.6%	0.47	4.3 MB	4.9%	1.24
Jan 27 16:00:00 - 16:59:59	1792	5.9%	0.50	5.8 MB	6.6%	1.65
Jan 27 17:00:00 - 17:59:59	1771	5.8%	0.49	4.9 MB	5.6%	1.40
Jan 27 18:00:00 - 18:59:59	1780	5.9%	0.49	5.5 MB	6.3%	1.57
Jan 27 19:00:00 - 19:59:59	1688	5.6%	0.47	4.7 MB	5.3%	1.33
Jan 27 20:00:00 - 20:59:59	1728	5.7%	0.48	4.9 MB	5.6%	1.40
Jan 27 21:00:00 - 21:59:59	1603	5.3%	0.45	4.3 MB	4.9%	1.23
Jan 27 22:00:00 - 22:59:59	1462	4.8%	0.41	3.8 MB	4.3%	1.09
Jan 27 23:00:00 - 23:59:59	1007	3.3%	0.28	2.9 MB	3.3%	0.84
Jan 28 00:00:00 - 00:59:59	873	2.9%	0.24	2.9 MB	3.3%	0.83
Jan 28 01:00:00 - 01:59:59	638	2.1%	0.18	1.8 MB	2.1%	0.52
Jan 28 02:00:00 - 02:59:59	523	1.7%	0.15	1.5 MB	1.7%	0.44
Jan 28 03:00:00 - 03:59:59	526	1.7%	0.15	1.6 MB	1.8%	0.45
Jan 28 04:00:00 - 04:15:00	150	0.5%	0.17	680.7 KB	0.8%	0.76
TOTAL: 24:00:00	30363	100.0%	0.35	88.3 MB	100.0%	1.05

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	8872	1860	8	7004
2	feeder.eternal-september.org	5694	166	5	5523
3	xanadu-bbs.net	4508	38	0	4470
4	usenet.blueworldhosting.com	3941	1342	1	2598
5	news-out.mixmin.net	1586	3	2	1581
6	csiph-out.feeder.erje.net	1586	491	35	1060
7	csiph-out.news.weretis.net	669	330	0	339
8	newsfeed.fu-berlin.de	659	30	0	629
9	news-out.aioe.org	88	69	0	19
10	news.swapon.de	51	0	1	50
11	au2pb.net	50	41	0	9
12	newsfeeds.xmission.com	25	22	0	3
13	news.redatomik.org	21	0	0	21
14	nntp.pasdenom.info	13	11	0	2
15	news.freedyn.net	8	0	0	8
16	news.fcku.it	3	0	0	3
17	news.bbs.geek.nz	1	1	0	0
TOTAL: 17		27775	4404	52	23319

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1299
lada.talk	187
it-alt.sport.calcio.juventus	116
ut.test	115
alt.binaries.multimedia.tatu	112
mailing.freebsd.svn-ports-all	95
mailing.freebsd.ports-bugs	94
mailing.freebsd.svn-src-all	93
mailing.freebsd.svn-ports-head	88
fa.freebsd.cvs-all	78
mailing.freebsd.pkg-fallout	73
alt.biology	58
mailing.freebsd.svn-src-head	57
pld.cvs.commit	56
mozilla.dev.tree-alerts	54
lada.auto	54
mailing.openbsd.source-changes	52
mailing.gcc.gcc-cvs	48
gmane.comp.gis.openstreetmap.tagging	47
mpc.lists.nanog	45
TOTAL: 261	4404

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16403
EMP (phn path)	3227
EMP (phl)	1308
User-issued cancel	337
PFM bot misc	200
EMP (phn nph)	178
Scoring filter (26)	61
Too many newsgroups	48
HTML Multipart	43
Scoring filter (19)	27
Scoring filter (21)	27
Scoring filter (10)	25
Scoring filter (22)	25
Scoring filter (17)	21
Scoring filter (20)	21
Scoring filter (29)	20
Scoring filter (14)	17
HTML post	16
Binary Payload	15
Scoring filter (24)	15
TOTAL: 42	22192

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	191
	usenet.blueworldhosting.com	138
	csiph-out.news.weretis.net	59
	feeder.eternal-september.org	53
	news-out.mixmin.net	10
	news.redatomik.org	4
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	1
	TOTAL: 8	458
Including strange strings
	newsfeed.fu-berlin.de	442
	news.swapon.de	36
	feeder.eternal-september.org	23
	news-out.mixmin.net	22
	news-out.aioe.org	18
	csiph-out.feeder.erje.net	16
	csiph-out.news.weretis.net	7
	newsfeed.neva.ru	5
	news.redatomik.org	3
	news.fcku.it	3
	TOTAL: 12	577
No colon-space in header
	csiph-out.feeder.erje.net	92
	TOTAL: 1	92
TOTAL: 3		1127

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.freedyn.net	34445	1314	29027	3	0	0	3%	23:59:53
2	xanadu-bbs	32690	1204	28658	0	0	14	3%	23:59:53
3	news.bbs.geek.nz	56120	1124	28298	23	1	274	2%	23:59:53
4	fu-berlin.de	28582	896	27686	0	0	0	3%	23:59:53
5	aioe	27591	580	23182	53	0	0	2%	23:59:21
6	usenet.pasdenom.info	38418	312	28677	1360	0	2	0%	23:59:53
7	au2pb	30243	221	29099	21	0	0	0%	23:59:53
8	news.redatomik.org	35300	196	28588	9	0	0	0%	23:59:49
9	eternal-september	30144	87	24281	0	0	0	0%	23:59:53
10	weretis.net	26287	75	25637	4	0	0	0%	23:59:21
11	neva.ru	32850	31	26566	205	0	0	0%	23:59:53
12	news.mixmin.net	29421	28	25324	0	0	167	0%	23:59:53
13	xmission	35857	26	28838	8	0	0	0%	23:59:53
14	news.fcku.it	4695	19	3919	0	0	0	0%	22:53:59
15	erje	30117	18	26875	110	0	0	0%	23:59:50
16	news.swapon.de	2721	3	2412	1	0	0	0%	23:58:45
17	bwh	23706	1	22406	0	0	0	0%	23:59:53
TOTAL: 17		499187	6135	409473	1797	1	457	1%	406:49:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.4 MB	35.0 KB	9.4 MB	0.1 KB/s	7.3 KB	23:59:53
2	xanadu-bbs	9.1 MB	0.0 KB	9.1 MB	0.1 KB/s	7.8 KB	23:59:53
3	fu-berlin.de	8.2 MB	0.0 KB	8.2 MB	0.1 KB/s	9.3 KB	23:59:53
4	news.bbs.geek.nz	3.3 MB	72.4 KB	3.4 MB	0.0 KB/s	3.0 KB	23:59:53
5	aioe	1.6 MB	403.2 KB	2.0 MB	0.0 KB/s	3.3 KB	23:59:21
6	usenet.pasdenom.info	1.2 MB	8.8 MB	10.1 MB	0.1 KB/s	6.2 KB	23:59:53
7	au2pb	831.4 KB	249.6 KB	1.1 MB	0.0 KB/s	4.5 KB	23:59:53
8	news.redatomik.org	607.1 KB	71.6 KB	678.6 KB	0.0 KB/s	3.3 KB	23:59:49
9	weretis.net	250.1 KB	114.9 KB	365.1 KB	0.0 KB/s	4.6 KB	23:59:21
10	eternal-september	241.4 KB	0.0 KB	241.4 KB	0.0 KB/s	2.8 KB	23:59:53
11	news.mixmin.net	114.5 KB	0.0 KB	114.5 KB	0.0 KB/s	4.1 KB	23:59:53
12	neva.ru	86.7 KB	892.8 KB	979.5 KB	0.0 KB/s	4.2 KB	23:59:53
13	xmission	56.1 KB	12.1 KB	68.2 KB	0.0 KB/s	2.0 KB	23:59:53
14	news.fcku.it	37.8 KB	0.0 KB	37.8 KB	0.0 KB/s	2.0 KB	22:53:59
15	erje	33.5 KB	470.6 KB	504.1 KB	0.0 KB/s	3.9 KB	23:59:50
16	news.swapon.de	4.3 KB	1.0 KB	5.2 KB	0.0 KB/s	1.3 KB	23:58:45
17	bwh	2.1 KB	0.0 KB	2.1 KB	0.0 KB/s	2.1 KB	23:59:53
TOTAL: 17		35.1 MB	11.1 MB	46.2 MB	0.0 KB/s	6.0 KB	406:49:48

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	287	103	294.8 KB	98	0	0	04:31:20
2	tempe0.bbox.io	75	56	118.5 KB	5	1	1	07:08:22
TOTAL: 2		362	159	413.3 KB	103	1	1	36:01:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	293	103	294.8 KB	98	0	0	22:10:52
2	*.bbox.io	75	56	118.5 KB	5	1	1	07:08:22
3	*.chello.nl	14	0	0.0 KB	0	0	0	05:12:30
4	*.tampfl.dsl-w.verizon.net	3	0	0.0 KB	0	0	0	01:30:04
TOTAL: 4		385	159	413.3 KB	103	1	1	36:01:48

NNRP auth users [Top 20]:

User	Conn
moussa	287
kev009	1
TOTAL: 2	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	0.387	0.333	0.000	17:09:10
tempe0.bbox.io	6.295	1.365	0.003	07:08:22
dhcp-089-099-203-254.chello.nl	1.089	0.230	0.000	05:12:30
202.89.176.220	30.670	7.025	0.757	04:31:20
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.286	0.039	0.000	01:30:04
138.108.60.60	0.086	0.023	0.000	00:30:00
196.52.22.18	0.275	0.592	0.000	00:00:21
TOTAL: 7	39.088	9.607	0.760	36:01:48

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	14
162.212.88.221	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3
196.52.22.18	1
138.108.60.60	1
TOTAL: 5	23

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	125	78.6%
2	bit	34	21.4%
TOTAL: 2		159	100.0%

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

Newsgroup		Count
1	comp.arch	52
2	bit.listserv.ibm-main	34
3	comp.unix.aix	22
4	comp.sys.ibm.as400.misc	16
5	comp.sys.ibm.pc.games.action	11
6	comp.os.vms	7
7	comp.sys.hp.hpux	3
8	comp.ai.neural-nets	3
9	comp.os.msdos.djgpp	3
10	comp.os.os2.apps	3
11	comp.ai.philosophy	1
12	comp.os.msdos.programmer	1
13	comp.sys.ibm.pc.hardware.chips	1
14	comp.sys.ibm.pc.games.rpg	1
15	comp.os.cpm	1
TOTAL: 15		159

Daily Usenet report for csiph.com

Jan 27 04:15:00 -- Jan 28 04:15:00