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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	25275	54.0%	5.6 MB	68.7%
innd	11715	25.0%	1.4 MB	17.7%
nnrpd	6627	14.2%	594.8 KB	7.1%
innfeed	3165	6.8%	527.3 KB	6.3%
nocem	37	0.1%	3.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	46820	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	293291	57.0%
Negative hits	196999	38.3%
Do not exist	24456	4.7%
Cache misses	240	0.0%
TOTAL: 4	514986	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.410	0.0%	169	0.000	14.260	114.000
article cleanup	00:00:00.187	0.0%	24225	0.000	0.008	0.053
article logging	00:00:08.900	0.0%	24739	0.000	0.360	3.113
article parse	00:00:06.327	0.0%	68563	0.000	0.092	1.274
article write	00:05:46.623	0.4%	18377	0.776	18.862	108.660
artlog/artcncl	00:00:00.016	0.0%	156	0.000	0.103	0.625
artlog/artparse	00:00:00.232	0.0%	376	0.000	0.617	36.000
data move	00:00:00.157	0.0%	501568	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.703	0.0%	169	0.000	4.160	86.333
hishave/artcncl	00:00:00.154	0.0%	169	0.000	0.911	27.667
hishave/artparse	00:00:00.006	0.0%	375	0.000	0.016	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:46.365	0.2%	517745	0.002	0.321	1.596
history sync	00:00:05.716	0.0%	368	0.000	15.533	154.333
history write	00:07:27.615	0.5%	24224	0.375	18.478	91.008
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:05.208	0.0%	375	0.000	13.888	127.000
idle	23:26:08.882	97.6%	470160	88.680	179.447	652.184
nntp read	00:00:02.304	0.0%	511820	0.001	0.005	0.019
overview write	00:15:29.950	1.1%	18377	0.577	50.604	304.140
perl filter	00:00:51.029	0.1%	24111	1.381	2.116	3.087
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.323	0.0%	36783	0.000	0.009	0.037
TOTAL: 24:01:01.886	23:59:03.107	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.055	0.0%	18346	0.000	0.003	0.025
article prepare	00:00:00.001	0.0%	22180	0.000	0.000	0.005
article read	00:00:02.871	0.0%	2733	0.000	1.050	35.211
backlog stats	00:00:00.202	0.0%	352646	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.753	0.0%	266096	0.003	0.007	0.014
data write	00:00:01.475	0.0%	250786	0.000	0.006	0.010
idle	23:54:32.999	100.0%	352646	108.081	244.078	899.501
status file	00:00:01.218	0.0%	280	0.000	4.350	64.000
TOTAL: 23:54:49.125	23:54:40.574	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:36.554	0.0%	8914	0.000	4.101	76.000
idle	113:51:18.454	100.0%	15238	167.750	26898.442	600203.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.534	0.0%	31577	0.000	0.017	3.652
readart	00:00:05.600	0.0%	426	0.000	13.146	171.500
TOTAL: 113:53:05.246	113:52:01.142	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1153	80500	5743	73014	1743	7%	95:05:15
2	csiph-out.news.weretis.net	5	36643	3834	32419	390	10%	47:35:38
3	feeder.eternal-september.org	4	21781	2532	18139	1110	11%	24:37:25
4	news-out.aioe.org	2	18395	1337	17000	58	7%	24:09:01
5	newsfeeds.xmission.com	2	18678	1315	17351	12	7%	26:41:59
6	newsfeed.fu-berlin.de	14	18880	1060	17491	329	5%	27:02:10
7	newsfeed.neva.ru	151	23924	894	21399	1631	3%	71:19:49
8	news-out.mixmin.net	4	19865	692	18470	703	3%	24:07:48
9	news.redatomik.org	1	19389	662	18697	30	3%	25:55:59
10	news.fcku.it	2	7920	380	7244	296	4%	23:58:33
11	news.swapon.de	4	1733	64	1667	2	3%	24:23:51
12	nntp.pasdenom.info	2	16820	33	16787	0	0%	23:58:43
13	news.freedyn.net	2	19849	17	19827	5	0%	23:59:43
14	news.bbs.geek.nz	2	18985	3	18982	0	0%	23:59:49
15	localhost	36	2	2	0	0	100%	00:00:08
16	xanadu-bbs.net	21	591	0	591	0	0%	23:38:54
TOTAL: 16		1405	323955	18568	299078	6309	5%	510:34:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.7 MB	2.8 KB	12.9 MB	33.6 MB	61%	4.6 KB
2	csiph-out.news.weretis.net	11.7 MB	0.0 KB	10.4 MB	22.2 MB	53%	5.4 KB
3	feeder.eternal-september.org	6.9 MB	0.0 KB	6.5 MB	13.4 MB	51%	3.8 KB
4	newsfeeds.xmission.com	4.4 MB	0.0 KB	582.5 KB	5.0 MB	88%	3.8 KB
5	news-out.aioe.org	3.1 MB	0.0 KB	101.5 KB	3.2 MB	96%	2.3 KB
6	newsfeed.neva.ru	2.6 MB	0.0 KB	7.2 MB	9.8 MB	26%	4.0 KB
7	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	522.1 KB	3.0 MB	82%	2.2 KB
8	news-out.mixmin.net	2.3 MB	0.0 KB	1.3 MB	3.6 MB	63%	2.6 KB
9	news.redatomik.org	1.7 MB	0.0 KB	84.6 KB	1.8 MB	95%	2.7 KB
10	news.fcku.it	1.2 MB	0.0 KB	6.7 MB	7.8 MB	14%	11.9 KB
11	news.swapon.de	159.0 KB	0.0 KB	675.8 KB	834.8 KB	19%	12.6 KB
12	nntp.pasdenom.info	84.0 KB	0.0 KB	0.0 KB	84.0 KB	100%	2.5 KB
13	news.freedyn.net	82.1 KB	0.0 KB	6.6 KB	88.7 KB	92%	4.0 KB
14	news.bbs.geek.nz	6.7 KB	0.0 KB	0.0 KB	6.7 KB	100%	2.2 KB
15	localhost	1.8 KB	0.0 KB	0.0 KB	1.8 KB	100%	0.9 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		57.5 MB	2.8 KB	46.9 MB	104.3 MB	55%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 14 04:15:00 - 04:59:59	211	1.1%	0.08	549.9 KB	0.9%	0.20
Oct 14 05:00:00 - 05:59:59	449	2.4%	0.12	1.3 MB	2.3%	0.38
Oct 14 06:00:00 - 06:59:59	541	2.9%	0.15	1.6 MB	2.7%	0.44
Oct 14 07:00:00 - 07:59:59	714	3.9%	0.20	2.0 MB	3.5%	0.57
Oct 14 08:00:00 - 08:59:59	860	4.7%	0.24	3.4 MB	5.9%	0.96
Oct 14 09:00:00 - 09:59:59	824	4.5%	0.23	2.8 MB	4.9%	0.79
Oct 14 10:00:00 - 10:59:59	831	4.5%	0.23	2.3 MB	4.1%	0.66
Oct 14 11:00:00 - 11:59:59	854	4.6%	0.24	2.3 MB	4.0%	0.66
Oct 14 12:00:00 - 12:59:59	832	4.5%	0.23	2.7 MB	4.8%	0.77
Oct 14 13:00:00 - 13:59:59	847	4.6%	0.24	2.4 MB	4.2%	0.68
Oct 14 14:00:00 - 14:59:59	1154	6.3%	0.32	4.8 MB	8.4%	1.37
Oct 14 15:00:00 - 15:59:59	1182	6.4%	0.33	3.7 MB	6.4%	1.04
Oct 14 16:00:00 - 16:59:59	1042	5.7%	0.29	3.0 MB	5.2%	0.84
Oct 14 17:00:00 - 17:59:59	1059	5.8%	0.29	2.9 MB	5.2%	0.84
Oct 14 18:00:00 - 18:59:59	990	5.4%	0.28	2.8 MB	5.0%	0.81
Oct 14 19:00:00 - 19:59:59	977	5.3%	0.27	2.7 MB	4.8%	0.78
Oct 14 20:00:00 - 20:59:59	919	5.0%	0.26	2.8 MB	5.0%	0.81
Oct 14 21:00:00 - 21:59:59	822	4.5%	0.23	2.3 MB	4.0%	0.65
Oct 14 22:00:00 - 22:59:59	723	3.9%	0.20	2.4 MB	4.2%	0.68
Oct 14 23:00:00 - 23:59:59	621	3.4%	0.17	1.8 MB	3.1%	0.50
Oct 15 00:00:00 - 00:59:59	512	2.8%	0.14	1.6 MB	2.8%	0.45
Oct 15 01:00:00 - 01:59:59	461	2.5%	0.13	1.7 MB	2.9%	0.47
Oct 15 02:00:00 - 02:59:59	409	2.2%	0.11	1.4 MB	2.5%	0.41
Oct 15 03:00:00 - 03:59:59	425	2.3%	0.12	1.5 MB	2.5%	0.41
Oct 15 04:00:00 - 04:15:00	120	0.7%	0.13	407.2 KB	0.7%	0.45
TOTAL: 24:00:00	18379	100.0%	0.21	57.1 MB	100.0%	0.68

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	1705	136	1	1	1567
2	newsfeed.neva.ru	1647	149	0	0	1498
3	feeder.eternal-september.org	1111	57	1	0	1053
4	news-out.mixmin.net	720	1	0	0	719
5	newsfeed.fu-berlin.de	668	32	0	0	636
6	csiph-out.news.weretis.net	454	138	0	0	316
7	news.fcku.it	299	4	0	0	295
8	news-out.aioe.org	64	43	0	0	21
9	news.redatomik.org	35	0	0	0	35
10	newsfeeds.xmission.com	17	2	0	0	15
11	news.swapon.de	16	0	0	0	16
12	news.freedyn.net	5	0	0	0	5
TOTAL: 12		6741	562	2	1	6176

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	97
it-alt.sport.calcio.juventus	52
alt.survival	50
ut.test	47
mozilla.support.firefox	24
lada.auto	18
mozilla.support.seamonkey	17
alt.politics	14
alt.computer.workshop	13
spline.fli4l.dev	11
alt.binaries.tvshows	11
oesterreich.tratsch	10
free.it.religioni.scientology	10
gmane.comp.gis.openstreetmap	10
mpc.lists.freebsd.x11	9
spline.eisfair.dev	8
gmane.comp.gis.openstreetmap.tagging	8
mpc.lists.freebsd.gnome	8
mozilla.general	8
lada.commerce	7
TOTAL: 90	562

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

Groups	Count
nz.politics.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4302
EMP (phn path)	420
EMP (phn nph)	251
Scoring filter (8)	31
Too many newsgroups	21
Scoring filter (14)	20
HTML Multipart	20
Scoring filter (9)	18
Scoring filter (12)	16
User-issued cancel	15
Scoring filter (10)	15
Scoring filter (15)	14
HTML post	8
Scoring filter (11)	6
Binary Payload	6
Binary: misplaced sfv	5
HTML Image Tags	1
Too many newsgroups (low_xpost)	1
Binary: misplaced 075	1
Scoring filter (13)	1
TOTAL: 21	5173

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	112
	TOTAL: 1	112
Huge articles
	csiph-out.feeder.erje.net	158
	csiph-out.news.weretis.net	105
	news.fcku.it	68
	feeder.eternal-september.org	31
	newsfeeds.xmission.com	10
	news.swapon.de	2
	news-out.mixmin.net	1
	TOTAL: 7	375
Including strange strings
	newsfeed.fu-berlin.de	366
	csiph-out.news.weretis.net	59
	csiph-out.feeder.erje.net	39
	news-out.mixmin.net	15
	news.swapon.de	14
	news-out.aioe.org	6
	news.redatomik.org	6
	newsfeed.neva.ru	5
	newsfeeds.xmission.com	3
	feeder.eternal-september.org	2
	TOTAL: 10	515
TOTAL: 3		1002

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	19523	1128	17157	6	0	5%	23:50:04
2	xanadu-bbs	21542	742	17599	0	0	3%	23:54:37
3	news.bbs.geek.nz	30503	702	16977	44	0	2%	23:50:03
4	fu-berlin.de	17064	558	16504	0	0	3%	23:53:26
5	aioe	17201	224	15378	5	0	1%	23:50:03
6	usenet.pasdenom.info	19166	188	16604	1495	4	0%	23:51:24
7	erje	15285	156	12362	7	0	1%	23:50:04
8	news.redatomik.org	18865	29	17139	4	0	0%	23:50:04
9	eternal-september	16482	13	14981	19	0	0%	23:50:04
10	neva.ru	16826	8	16439	198	0	0%	23:50:03
11	xmission	17520	6	16369	0	0	0%	23:52:44
12	weretis.net	15337	5	13600	0	0	0%	23:50:04
13	news.mixmin.net	17053	3	16388	0	0	0%	23:50:04
14	news.swapon.de	1781	2	1740	0	0	0%	23:48:10
15	au2pb	0	0	0	0	17739	0%	23:50:04
16	news.fcku.it	1132	0	1122	0	0	0%	21:20:27
17	bwh	0	0	0	0	17730	0%	23:50:03
TOTAL: 17		245280	3764	210359	1778	35473	1%	402:51:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.7 MB	27.1 KB	8.7 MB	0.1 KB/s	7.9 KB	23:50:04
2	xanadu-bbs	7.3 MB	0.0 KB	7.3 MB	0.1 KB/s	10.1 KB	23:54:37
3	fu-berlin.de	6.7 MB	0.0 KB	6.7 MB	0.1 KB/s	12.3 KB	23:53:26
4	news.bbs.geek.nz	2.1 MB	173.1 KB	2.3 MB	0.0 KB/s	3.2 KB	23:50:03
5	aioe	833.3 KB	14.7 KB	848.0 KB	0.0 KB/s	3.7 KB	23:50:03
6	usenet.pasdenom.info	735.2 KB	8.4 MB	9.1 MB	0.1 KB/s	5.6 KB	23:51:24
7	erje	570.6 KB	51.7 KB	622.3 KB	0.0 KB/s	3.8 KB	23:50:04
8	news.redatomik.org	121.3 KB	12.8 KB	134.1 KB	0.0 KB/s	4.1 KB	23:50:04
9	eternal-september	28.1 KB	81.8 KB	109.9 KB	0.0 KB/s	3.4 KB	23:50:04
10	neva.ru	27.8 KB	772.1 KB	799.9 KB	0.0 KB/s	3.9 KB	23:50:03
11	weretis.net	17.4 KB	0.0 KB	17.4 KB	0.0 KB/s	3.5 KB	23:50:04
12	xmission	12.8 KB	0.0 KB	12.8 KB	0.0 KB/s	2.1 KB	23:52:44
13	news.mixmin.net	4.7 KB	0.0 KB	4.7 KB	0.0 KB/s	1.6 KB	23:50:04
14	news.swapon.de	1.8 KB	0.0 KB	1.8 KB	0.0 KB/s	0.9 KB	23:48:10
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:20:27
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 17		27.1 MB	9.5 MB	36.6 MB	0.0 KB/s	6.8 KB	402:51:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	g235242.upc-g.chello.nl	6	356	660.2 KB	3	0	0	01:10:27
2	141.168.117.108	48	62	179.9 KB	45	0	0	00:44:43
3	tempe0.bbox.io	7	5	15.4 KB	3	0	0	03:25:28
4	96-42-18-93.dhcp.mdsn.wi.charter.com	19	2	1.8 KB	2	2	2	04:55:02
5	a131033.upc-a.chello.nl	19	1	0.9 KB	1	0	0	04:41:40
TOTAL: 5		99	426	858.2 KB	54	2	2	113:53:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-g.chello.nl	6	356	660.2 KB	3	0	0	01:10:27
2	unresolved	53	62	179.9 KB	45	0	0	74:23:43
3	*.bbox.io	7	5	15.4 KB	3	0	0	03:25:28
4	*.dhcp.mdsn.wi.charter.com	19	2	1.8 KB	2	2	2	04:55:02
5	*.upc-a.chello.nl	19	1	0.9 KB	1	0	0	04:41:40
6	*.151.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:27:14
7	*.cust.centrio.cz	2	0	0.0 KB	0	0	0	00:49:08
8	*.dhcp.chtrptr.net	175	0	0.0 KB	0	0	0	23:00:08
9	*.nwrknj.fios.verizon.net	2	0	0.0 KB	0	0	0	01:00:08
TOTAL: 9		284	426	858.2 KB	54	2	2	113:53:02

NNRP auth users [Top 20]:

User	Conn
moussa	48
ohlandl	2
ctomczyk	2
TOTAL: 3	52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.182	0.517	0.511	0.000	73:38:49
172-221-045-126.dhcp.chtrptr.net	12.772	3.396	0.000	23:00:08
96-42-18-93.dhcp.mdsn.wi.charter.com	1.547	0.278	0.007	04:55:02
a131033.upc-a.chello.nl	1.443	0.280	0.000	04:41:40
tempe0.bbox.io	0.687	0.152	0.000	03:25:28
g235242.upc-g.chello.nl	0.615	0.212	0.000	01:10:27
pool-74-102-9-20.nwrknj.fios.verizon.net	0.131	0.029	0.000	01:00:08
194-73-239-109.cust.centrio.cz	0.134	0.030	0.007	00:49:08
141.168.117.108	4.841	1.058	0.185	00:44:43
h87.151.22.98.dynamic.ip.windstream.net	0.113	0.066	0.000	00:27:14
45.55.21.77	0.059	0.008	0.000	00:00:09
TOTAL: 11	22.859	6.020	0.199	113:53:02

Curious NNRP server explorers [Top 20]:

System	Conn
172-221-045-126.dhcp.chtrptr.net	175
216.239.174.182	4
194-73-239-109.cust.centrio.cz	2
pool-74-102-9-20.nwrknj.fios.verizon.net	2
45.55.21.77	1
h87.151.22.98.dynamic.ip.windstream.net	1
TOTAL: 6	185

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	6	0
96-42-18-93.dhcp.mdsn.wi.charter.com	4	0
g235242.upc-g.chello.nl	2	0
pool-74-102-9-20.nwrknj.fios.verizon.net	2	0
194-73-239-109.cust.centrio.cz	1	0
TOTAL: 5	15	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	402	94.4%
2	uk	20	4.7%
3	muc	3	0.7%
4	news	1	0.2%
TOTAL: 4		426	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	342
2	comp.os.vms	24
3	uk.radio.amateur.moderated	20
4	comp.sys.ibm.pc.games.action	13
5	comp.arch	7
6	comp.os.cpm	4
7	comp.arch.embedded	3
8	comp.ai.philosophy	3
9	muc.lists.freebsd.current	3
10	comp.windows.misc	2
11	news.admin.peering	1
12	comp.sys.ibm.as400.misc	1
13	comp.os.msdos.djgpp	1
14	comp.sys.ibm.pc.hardware.chips	1
15	comp.os.os2.misc	1
TOTAL: 15		426

Daily Usenet report for csiph.com

Oct 14 04:15:00 -- Oct 15 04:15:00