Daily Usenet report for csiph.com: Feb 12 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45150	58.9%	8.1 MB	69.7%
innd	26908	35.1%	2.8 MB	24.4%
innfeed	3255	4.2%	546.2 KB	4.6%
nnrpd	1356	1.8%	153.4 KB	1.3%
nocem	49	0.1%	5.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	76719	100.0%	11.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	423807	56.2%
Negative hits	286043	37.9%
Do not exist	43697	5.8%
Cache misses	713	0.1%
TOTAL: 4	754260	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.009	0.0%	189	0.000	5.339	59.000
article cleanup	00:00:00.290	0.0%	44258	0.000	0.007	0.021
article logging	00:00:03.281	0.0%	44912	0.000	0.073	0.978
article parse	00:00:02.469	0.0%	154157	0.000	0.016	0.516
article write	00:01:59.498	0.1%	22426	0.140	5.329	43.073
artlog/artcncl	00:00:00.049	0.0%	157	0.000	0.312	1.412
artlog/artparse	00:00:00.004	0.0%	110	0.000	0.036	1.000
data move	00:00:00.389	0.0%	689246	0.000	0.001	0.014
hisgrep/artcncl	00:00:00.055	0.0%	187	0.000	0.294	7.000
hishave/artcncl	00:00:00.054	0.0%	189	0.000	0.286	10.000
hishave/artparse	00:00:00.001	0.0%	109	0.000	0.009	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:12.035	0.1%	755920	0.002	0.095	0.541
history sync	00:00:04.452	0.0%	364	0.000	12.231	75.000
history write	00:02:44.800	0.2%	43573	0.062	3.782	29.074
hiswrite/artcncl	00:00:00.008	0.0%	2	4.000	4.000	4.000
hiswrite/artparse	00:00:00.834	0.0%	109	0.000	7.651	95.000
idle	23:47:58.334	99.1%	677549	63.089	126.453	430.745
nntp read	00:00:03.302	0.0%	702728	0.002	0.005	0.011
overview write	00:03:40.955	0.3%	22426	1.026	9.853	288.197
perl filter	00:01:54.469	0.1%	43467	1.494	2.633	6.561
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.416	0.0%	44896	0.000	0.009	0.031
TOTAL: 24:00:55.556	23:59:46.704	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.072	0.0%	22636	0.000	0.003	0.029
article prepare	00:00:00.011	0.0%	41094	0.000	0.000	0.006
article read	00:00:01.320	0.0%	7314	0.047	0.180	2.560
backlog stats	00:00:00.288	0.0%	432261	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.193	0.0%	323703	0.002	0.007	0.013
data write	00:00:01.903	0.0%	308133	0.003	0.006	0.012
idle	23:54:06.656	100.0%	432261	111.647	199.062	596.850
status file	00:00:00.226	0.0%	313	0.000	0.722	15.000
TOTAL: 23:54:21.090	23:54:12.669	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.101	0.0%	1792	0.000	0.056	12.000
idle	80:13:45.235	99.8%	2508	3957.605	115161.577	900278.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.029	0.0%	5209	0.000	0.006	0.250
readart	00:00:00.535	0.0%	59	0.000	9.068	61.000
TOTAL: 80:23:12.771	80:13:45.900	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	138455	9233	117187	12035	6%	94:58:20
2	feeder.eternal-september.org	61	38851	3225	29193	6433	8%	38:10:02
3	csiph-out.news.weretis.net	4	48311	2548	45011	752	5%	48:00:28
4	news-out.aioe.org	3	21424	2170	19200	54	10%	24:09:02
5	newsfeed.neva.ru	139	57617	1308	53349	2960	2%	72:10:50
6	newsfeed.fu-berlin.de	27	24607	1221	22840	546	4%	31:38:09
7	news-out.mixmin.net	2	25023	671	23899	453	2%	24:08:55
8	newsfeeds.xmission.com	3	21237	646	20586	5	3%	24:47:17
9	news.redatomik.org	1	17971	447	17520	4	2%	22:02:44
10	news.freedyn.net	3	26234	342	25534	358	1%	24:09:50
11	news.fcku.it	2	8961	272	8639	50	3%	23:59:55
12	nntp.pasdenom.info	3	20592	80	20489	23	0%	24:51:46
13	news.swapon.de	1	2217	63	2143	11	2%	24:28:22
14	news.bbs.geek.nz	2	15001	28	14971	2	0%	23:59:43
15	xanadu-bbs.net	6	309	13	290	6	4%	11:53:14
16	localhost	44	12	12	0	0	100%	00:00:00
TOTAL: 16		1453	466822	22279	420851	23692	4%	513:28:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	29.2 MB	2.3 MB	66.3 MB	97.8 MB	29%	4.7 KB
2	feeder.eternal-september.org	9.1 MB	6.0 MB	84.2 MB	99.3 MB	9%	10.5 KB
3	csiph-out.news.weretis.net	8.2 MB	0.0 KB	12.2 MB	20.3 MB	40%	6.3 KB
4	news-out.aioe.org	5.8 MB	1.5 KB	218.9 KB	6.0 MB	96%	2.8 KB
5	news-out.mixmin.net	3.6 MB	0.0 KB	1.7 MB	5.2 MB	68%	4.8 KB
6	newsfeed.neva.ru	3.0 MB	418.1 KB	28.1 MB	31.5 MB	9%	7.6 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	1.4 MB	4.2 MB	65%	2.4 KB
8	newsfeeds.xmission.com	1.7 MB	0.0 KB	354.7 KB	2.0 MB	82%	3.2 KB
9	news.freedyn.net	1.1 MB	1.5 KB	4.3 MB	5.5 MB	20%	8.0 KB
10	news.redatomik.org	1018.1 KB	2.5 KB	63.2 KB	1.1 MB	93%	2.4 KB
11	news.fcku.it	530.3 KB	0.0 KB	1.2 MB	1.7 MB	30%	5.3 KB
12	nntp.pasdenom.info	291.7 KB	2.0 KB	44.8 KB	338.5 KB	86%	3.3 KB
13	news.swapon.de	155.1 KB	0.0 KB	110.8 KB	266.0 KB	58%	3.6 KB
14	news.bbs.geek.nz	65.3 KB	0.0 KB	2.7 KB	68.0 KB	96%	2.3 KB
15	xanadu-bbs.net	32.3 KB	0.0 KB	35.3 KB	67.7 KB	47%	3.6 KB
16	localhost	17.5 KB	0.0 KB	0.0 KB	17.5 KB	100%	1.5 KB
TOTAL: 16		66.5 MB	8.7 MB	200.1 MB	275.3 MB	24%	6.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 12 04:15:00 - 04:59:59	389	1.7%	0.14	2.7 MB	4.1%	1.03
Feb 12 05:00:00 - 05:59:59	383	1.7%	0.11	1.1 MB	1.7%	0.32
Feb 12 06:00:00 - 06:59:59	406	1.8%	0.11	1.4 MB	2.1%	0.39
Feb 12 07:00:00 - 07:59:59	459	2.0%	0.13	1.4 MB	2.0%	0.39
Feb 12 08:00:00 - 08:59:59	658	2.9%	0.18	2.0 MB	3.0%	0.56
Feb 12 09:00:00 - 09:59:59	934	4.2%	0.26	2.7 MB	4.0%	0.75
Feb 12 10:00:00 - 10:59:59	915	4.1%	0.25	2.8 MB	4.1%	0.79
Feb 12 11:00:00 - 11:59:59	1019	4.5%	0.28	3.4 MB	5.1%	0.98
Feb 12 12:00:00 - 12:59:59	1106	4.9%	0.31	3.1 MB	4.7%	0.89
Feb 12 13:00:00 - 13:59:59	1045	4.7%	0.29	3.3 MB	5.0%	0.95
Feb 12 14:00:00 - 14:59:59	1360	6.1%	0.38	3.6 MB	5.4%	1.03
Feb 12 15:00:00 - 15:59:59	1384	6.2%	0.38	3.6 MB	5.4%	1.03
Feb 12 16:00:00 - 16:59:59	1304	5.8%	0.36	3.5 MB	5.2%	0.99
Feb 12 17:00:00 - 17:59:59	1295	5.8%	0.36	3.3 MB	4.9%	0.93
Feb 12 18:00:00 - 18:59:59	1319	5.9%	0.37	3.4 MB	5.0%	0.96
Feb 12 19:00:00 - 19:59:59	1347	6.0%	0.37	4.0 MB	5.9%	1.12
Feb 12 20:00:00 - 20:59:59	1285	5.7%	0.36	4.0 MB	6.0%	1.15
Feb 12 21:00:00 - 21:59:59	1268	5.7%	0.35	4.0 MB	6.0%	1.14
Feb 12 22:00:00 - 22:59:59	1139	5.1%	0.32	3.6 MB	5.4%	1.03
Feb 12 23:00:00 - 23:59:59	884	3.9%	0.25	2.7 MB	4.0%	0.77
Feb 13 00:00:00 - 00:59:59	734	3.3%	0.20	2.1 MB	3.1%	0.59
Feb 13 01:00:00 - 01:59:59	652	2.9%	0.18	1.8 MB	2.8%	0.52
Feb 13 02:00:00 - 02:59:59	537	2.4%	0.15	1.6 MB	2.4%	0.46
Feb 13 03:00:00 - 03:59:59	444	2.0%	0.12	1.4 MB	2.2%	0.41
Feb 13 04:00:00 - 04:15:00	136	0.6%	0.15	376.8 KB	0.5%	0.42
TOTAL: 24:00:00	22402	100.0%	0.26	66.9 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	10379	248	185	9946
2	feeder.eternal-september.org	6455	51	464	5940
3	newsfeed.neva.ru	2962	155	30	2777
4	newsfeed.fu-berlin.de	938	33	0	905
5	csiph-out.news.weretis.net	801	131	0	670
6	news-out.mixmin.net	475	1	0	474
7	news.freedyn.net	360	8	1	351
8	news-out.aioe.org	81	43	1	37
9	news.fcku.it	54	1	0	53
10	nntp.pasdenom.info	24	15	1	8
11	news.swapon.de	23	0	2	21
12	news.redatomik.org	14	0	1	13
13	xanadu-bbs.net	10	0	0	10
14	newsfeeds.xmission.com	10	0	0	10
15	news.bbs.geek.nz	5	1	1	3
TOTAL: 15		22591	687	686	21218

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	125
it-alt.sport.calcio.juventus	65
lada.talk	43
alt.windows7.general	41
alt.survival	27
spline.eisfair	23
mpc.lists.freebsd.gnome	23
mpc.lists.freebsd.x11	22
gmane.comp.gis.openstreetmap.tagging	20
lada.auto	19
fido.linux	14
lada.kitchen	11
gmane.linux.drivers.devicetree	11
mozilla.general	11
mozilla.support.thunderbird	10
alt.computer.workshop	10
mozilla.support.seamonkey	10
eagle.support.eng	10
mpc.lists.nanog	9
oesterreich.tratsch	8
TOTAL: 116	687

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16715
EMP (phn path)	2362
EMP (phl)	627
EMP (phn nph)	121
Scoring filter (12)	90
Scoring filter (14)	56
Too many newsgroups	52
Scoring filter (11)	51
Scoring filter (10)	44
Binary Payload	44
Scoring filter (13)	43
Scoring filter (8)	32
Scoring filter (9)	24
User-issued cancel	22
HTML Multipart	16
Scoring filter (15)	14
HTML post	11
Scoring filter (17)	7
Binary Image: misplaced jpg	5
Scoring filter (16)	5
TOTAL: 24	20349

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	105
	TOTAL: 1	105
Huge articles
	csiph-out.news.weretis.net	35
	feeder.eternal-september.org	25
	news.freedyn.net	24
	news-out.mixmin.net	15
	newsfeeds.xmission.com	4
	newsfeed.fu-berlin.de	3
	news.fcku.it	2
	news.redatomik.org	1
	TOTAL: 8	109
Including strange strings
	newsfeed.fu-berlin.de	418
	csiph-out.feeder.erje.net	113
	feeder.eternal-september.org	28
	news-out.aioe.org	24
	news-out.mixmin.net	21
	newsfeed.neva.ru	12
	csiph-out.news.weretis.net	8
	news.swapon.de	8
	news.redatomik.org	8
	newsfeeds.xmission.com	6
	TOTAL: 13	654
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		869

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	36948	5383	16114	92	1	0	14%	23:50:29
2	news.freedyn.net	23384	1050	20934	8	0	0	4%	23:50:07
3	xanadu-bbs	25440	828	21510	1	0	0	3%	23:59:56
4	fu-berlin.de	20921	681	20237	0	0	0	3%	23:53:37
5	usenet.pasdenom.info	25610	211	20464	1647	0	15	0%	23:59:56
6	xmission	25658	115	20857	17	0	0	0%	23:59:48
7	news.redatomik.org	22611	42	21083	8	0	86	0%	23:58:49
8	aioe	20136	36	18371	5	0	0	0%	23:50:06
9	eternal-september	20172	17	18039	0	0	138	0%	23:59:56
10	weretis.net	18337	17	17572	3	0	0	0%	23:50:07
11	news.mixmin.net	21569	15	20107	0	0	0	0%	23:52:45
12	news.swapon.de	2643	12	2522	1	0	0	0%	23:58:53
13	neva.ru	21810	11	20037	9	0	0	0%	23:59:44
14	erje	13089	2	12955	40	0	0	0%	23:56:26
15	bwh	0	0	0	0	0	21470	0%	23:50:11
16	news.fcku.it	1516	0	1473	0	0	0	0%	22:33:01
17	au2pb	0	0	0	0	0	21618	0%	23:50:06
TOTAL: 17		299844	8420	252275	1831	1	43327	2%	405:13:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	13.9 MB	684.2 KB	14.5 MB	0.2 KB/s	2.7 KB	23:50:29
2	news.freedyn.net	7.5 MB	149.5 KB	7.7 MB	0.1 KB/s	7.4 KB	23:50:07
3	xanadu-bbs	6.5 MB	1.2 KB	6.5 MB	0.1 KB/s	8.1 KB	23:59:56
4	fu-berlin.de	6.1 MB	0.0 KB	6.1 MB	0.1 KB/s	9.2 KB	23:53:37
5	usenet.pasdenom.info	557.6 KB	8.0 MB	8.6 MB	0.1 KB/s	4.7 KB	23:59:56
6	xmission	243.2 KB	31.2 KB	274.5 KB	0.0 KB/s	2.1 KB	23:59:48
7	news.redatomik.org	131.9 KB	41.6 KB	173.6 KB	0.0 KB/s	3.5 KB	23:58:49
8	aioe	59.7 KB	9.7 KB	69.5 KB	0.0 KB/s	1.7 KB	23:50:06
9	news.mixmin.net	35.6 KB	0.0 KB	35.6 KB	0.0 KB/s	2.4 KB	23:52:45
10	eternal-september	31.0 KB	0.0 KB	31.0 KB	0.0 KB/s	1.8 KB	23:59:56
11	weretis.net	30.8 KB	26.4 KB	57.2 KB	0.0 KB/s	2.9 KB	23:50:07
12	news.swapon.de	18.8 KB	4.6 KB	23.4 KB	0.0 KB/s	1.8 KB	23:58:53
13	neva.ru	16.3 KB	21.5 KB	37.7 KB	0.0 KB/s	1.9 KB	23:59:44
14	erje	3.4 KB	117.4 KB	120.8 KB	0.0 KB/s	2.9 KB	23:56:26
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:33:01
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
TOTAL: 17		35.1 MB	9.1 MB	44.2 MB	0.0 KB/s	4.4 KB	405:13:57

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	77.238.broadband2.iol.cz	3	19	67.0 KB	2	0	0	00:10:03
2	dhcp-089-099-203-254.chello.nl	57	18	31.2 KB	4	0	0	17:29:49
3	68-185-181-64.dhcp.mdsn.wi.charter.com	31	16	27.2 KB	13	11	11	06:51:25
4	tempe0.bbox.io	10	4	5.8 KB	2	1	1	02:58:30
5	194-73-239-109.cust.centrio.cz	4	2	4.0 KB	1	0	0	01:30:23
TOTAL: 5		105	59	135.3 KB	22	12	12	80:23:10

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.238.broadband2.iol.cz	3	19	67.0 KB	2	0	0	00:10:03
2	*.chello.nl	57	18	31.2 KB	4	0	0	17:29:49
3	*.dhcp.mdsn.wi.charter.com	31	16	27.2 KB	13	11	11	06:51:25
4	*.bbox.io	10	4	5.8 KB	2	1	1	02:58:30
5	*.cust.centrio.cz	4	2	4.0 KB	1	0	0	01:30:23
6	unresolved	101	0	0.0 KB	0	0	0	51:22:57
TOTAL: 6		206	59	135.3 KB	22	12	12	80:23:10

NNRP auth users [Top 20]:

User	Conn
ohlandl	11
ctomczyk	5
kev009	1
TOTAL: 3	17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.571	1.561	0.000	49:31:16
dhcp-089-099-203-254.chello.nl	4.682	0.853	0.000	17:29:49
68-185-181-64.dhcp.mdsn.wi.charter.com	3.488	0.634	0.042	06:51:25
tempe0.bbox.io	1.002	0.275	0.006	02:58:30
50.96.153.220	0.082	0.124	0.000	01:51:40
194-73-239-109.cust.centrio.cz	0.312	0.084	0.011	01:30:23
77.238.broadband2.iol.cz	0.316	0.079	0.029	00:10:03
TOTAL: 7	17.453	3.610	0.088	80:23:10

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	100
50.96.153.220	1
TOTAL: 2	101

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	97	0
tempe0.bbox.io	5	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
194-73-239-109.cust.centrio.cz	3	0
TOTAL: 4	109	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	59	100.0%
TOTAL: 1		59	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	38
2	comp.lang.javascript	21
TOTAL: 2		59

Daily Usenet report for csiph.com

Feb 12 04:15:00 -- Feb 13 04:15:00