Daily Usenet report for csiph.com: Dec 3 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20193	49.5%	5.2 MB	64.2%
innd	16576	40.6%	2.2 MB	27.4%
innfeed	3338	8.2%	618.4 KB	7.5%
nnrpd	717	1.8%	77.9 KB	0.9%
nocem	7	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40832	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	317489	57.5%
Negative hits	215073	38.9%
Do not exist	19706	3.6%
Cache misses	234	0.0%
TOTAL: 4	552502	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.327	0.0%	25	0.000	13.080	21.000
article cleanup	00:00:00.102	0.0%	19279	0.000	0.005	0.027
article logging	00:00:00.260	0.0%	19729	0.000	0.013	0.110
article parse	00:00:00.998	0.0%	56941	0.000	0.018	0.289
article write	00:00:10.009	0.0%	16974	0.165	0.590	35.694
artlog/artcncl	00:00:00.000	0.0%	25	0.000	0.000	0.000
artlog/artparse	00:00:00.010	0.0%	450	0.000	0.022	0.500
data move	00:00:00.146	0.0%	509326	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.014	0.0%	24	0.000	0.583	1.000
hishave/artcncl	00:00:00.004	0.0%	25	0.000	0.160	0.500
hishave/artparse	00:00:00.001	0.0%	446	0.000	0.002	0.043
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:08.240	0.0%	552597	0.000	0.015	0.202
history sync	00:00:00.885	0.0%	374	0.000	2.366	25.667
history write	00:00:15.005	0.0%	19279	0.193	0.778	6.380
hiswrite/artcncl	00:00:00.001	0.0%	1	1.000	1.000	1.000
hiswrite/artparse	00:00:00.521	0.0%	446	0.000	1.168	11.000
idle	23:58:53.496	99.9%	518320	90.698	166.564	557.591
nntp read	00:00:02.028	0.0%	519305	0.001	0.004	0.007
overview write	00:00:29.018	0.0%	16974	0.113	1.710	87.143
perl filter	00:00:27.886	0.0%	19274	1.111	1.447	2.563
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.277	0.0%	33955	0.000	0.008	0.030
TOTAL: 24:00:58.839	24:00:29.228	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	17039	0.000	0.002	0.022
article prepare	00:00:00.004	0.0%	16824	0.000	0.000	0.025
article read	00:00:00.318	0.0%	1720	0.000	0.185	12.500
backlog stats	00:00:00.219	0.0%	388644	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.364	0.0%	301094	0.000	0.005	0.013
data write	00:00:01.666	0.0%	286692	0.002	0.006	0.013
idle	23:53:45.677	100.0%	388644	100.256	221.348	738.977
status file	00:00:00.172	0.0%	260	0.000	0.662	2.000
TOTAL: 23:53:54.392	23:53:49.456	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	21:43:16.581	100.0%	661	51.000	118300.425	600179.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.042	0.0%	1658	0.000	0.025	0.248
readart	00:00:01.069	0.0%	121	0.000	8.835	53.000
TOTAL: 21:43:42.120	21:43:17.692	100.0%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	140
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	160

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	14664	2977	11647	40	20%	20:57:41
2	news-out.aioe.org	2	15739	2786	12917	36	17%	23:51:07
3	csiph-out.feeder.erje.net	1440	78642	2619	75715	308	3%	119:04:39
4	feeder.eternal-september.org	1	16894	1953	14874	67	11%	21:15:59
5	news.freedyn.net	3	18701	1182	16844	675	6%	23:50:43
6	newsfeed.neva.ru	144	19134	1115	17759	260	5%	71:53:52
7	newsfeed.fu-berlin.de	12	15461	933	14486	42	6%	24:11:58
8	csiph-out.news.xs4all.nl	861	17799	870	16104	825	4%	71:28:58
9	news-out.mixmin.net	2	17025	824	16082	119	4%	23:26:50
10	csiph-out.news.weretis.net	4	18642	701	17861	80	3%	29:26:40
11	news.fcku.it	15	2170	211	1953	6	9%	22:31:19
12	csiph-out.feed.uzoreto.com	863	25066	197	24644	225	0%	71:17:14
13	nntp.pasdenom.info	2	10775	47	10714	14	0%	23:40:21
14	news.swapon.de	4	1329	25	1304	0	1%	23:46:56
15	news.samoylyk.net	2	15671	18	15653	0	0%	24:35:09
16	localhost	37	11	11	0	0	100%	00:00:04
17	csiph-out.news.tnetconsulting.net	1	3109	3	3100	6	0%	06:41:52
18	news.bbs.nz	1	3535	1	3534	0	0%	07:24:53
19	xanadu-bbs.net	29	378	0	378	0	0%	20:57:13
TOTAL: 19		3425	294745	16473	275569	2703	5%	630:23:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	12.3 MB	0.0 KB	11.2 MB	23.5 MB	52%	8.2 KB
2	newsfeeds.xmission.com	9.2 MB	0.0 KB	2.0 MB	11.2 MB	82%	3.8 KB
3	news-out.aioe.org	6.6 MB	0.0 KB	101.3 KB	6.7 MB	98%	2.4 KB
4	feeder.eternal-september.org	5.1 MB	1.5 KB	212.1 KB	5.3 MB	96%	2.7 KB
5	news.freedyn.net	3.0 MB	0.0 KB	1.9 MB	4.9 MB	61%	2.7 KB
6	newsfeed.neva.ru	2.7 MB	2.8 KB	964.0 KB	3.6 MB	74%	2.7 KB
7	csiph-out.news.xs4all.nl	2.6 MB	0.0 KB	6.7 MB	9.3 MB	28%	5.6 KB
8	news-out.mixmin.net	2.3 MB	2.8 KB	288.1 KB	2.6 MB	88%	2.8 KB
9	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	217.5 KB	2.4 MB	91%	2.5 KB
10	csiph-out.news.weretis.net	2.1 MB	0.0 KB	1.2 MB	3.3 MB	63%	4.4 KB
11	csiph-out.feed.uzoreto.com	753.0 KB	0.0 KB	4.0 MB	4.7 MB	15%	11.4 KB
12	news.fcku.it	319.7 KB	0.0 KB	59.3 KB	379.0 KB	84%	1.7 KB
13	nntp.pasdenom.info	99.7 KB	0.0 KB	25.1 KB	124.8 KB	79%	2.0 KB
14	news.swapon.de	66.3 KB	0.0 KB	0.0 KB	66.3 KB	100%	2.7 KB
15	news.samoylyk.net	53.7 KB	0.0 KB	0.0 KB	53.7 KB	100%	3.0 KB
16	localhost	17.1 KB	0.0 KB	0.0 KB	17.1 KB	100%	1.6 KB
17	csiph-out.news.tnetconsulting.net	10.9 KB	0.0 KB	17.3 KB	28.2 KB	38%	3.1 KB
18	news.bbs.nz	8.4 KB	0.0 KB	0.0 KB	8.4 KB	100%	8.4 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		49.3 MB	7.0 KB	28.8 MB	78.0 MB	63%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 3 04:15:00 - 04:59:59	186	1.1%	0.07	542.9 KB	1.0%	0.20
Dec 3 05:00:00 - 05:59:59	410	2.4%	0.11	2.1 MB	4.2%	0.60
Dec 3 06:00:00 - 06:59:59	411	2.4%	0.11	1.7 MB	3.4%	0.50
Dec 3 07:00:00 - 07:59:59	684	4.0%	0.19	2.3 MB	4.6%	0.66
Dec 3 08:00:00 - 08:59:59	607	3.6%	0.17	1.8 MB	3.5%	0.51
Dec 3 09:00:00 - 09:59:59	708	4.2%	0.20	2.0 MB	3.9%	0.56
Dec 3 10:00:00 - 10:59:59	761	4.5%	0.21	1.9 MB	3.8%	0.55
Dec 3 11:00:00 - 11:59:59	867	5.1%	0.24	2.6 MB	5.1%	0.73
Dec 3 12:00:00 - 12:59:59	774	4.6%	0.21	2.0 MB	3.9%	0.56
Dec 3 13:00:00 - 13:59:59	853	5.0%	0.24	2.1 MB	4.2%	0.61
Dec 3 14:00:00 - 14:59:59	989	5.8%	0.27	2.6 MB	5.2%	0.74
Dec 3 15:00:00 - 15:59:59	1012	6.0%	0.28	3.3 MB	6.5%	0.94
Dec 3 16:00:00 - 16:59:59	1104	6.5%	0.31	3.1 MB	6.2%	0.89
Dec 3 17:00:00 - 17:59:59	948	5.6%	0.26	2.9 MB	5.7%	0.82
Dec 3 18:00:00 - 18:59:59	921	5.4%	0.26	2.4 MB	4.7%	0.67
Dec 3 19:00:00 - 19:59:59	906	5.3%	0.25	2.7 MB	5.3%	0.76
Dec 3 20:00:00 - 20:59:59	983	5.8%	0.27	2.9 MB	5.7%	0.82
Dec 3 21:00:00 - 21:59:59	911	5.4%	0.25	2.4 MB	4.7%	0.68
Dec 3 22:00:00 - 22:59:59	746	4.4%	0.21	2.3 MB	4.5%	0.64
Dec 3 23:00:00 - 23:59:59	685	4.0%	0.19	2.2 MB	4.4%	0.63
Dec 4 00:00:00 - 00:59:59	441	2.6%	0.12	1.3 MB	2.6%	0.38
Dec 4 01:00:00 - 01:59:59	339	2.0%	0.09	1.1 MB	2.2%	0.31
Dec 4 02:00:00 - 02:59:59	314	1.9%	0.09	930.0 KB	1.8%	0.26
Dec 4 03:00:00 - 03:59:59	327	1.9%	0.09	1.3 MB	2.5%	0.37
Dec 4 04:00:00 - 04:15:00	77	0.5%	0.09	259.2 KB	0.5%	0.29
TOTAL: 24:00:00	16964	100.0%	0.20	50.6 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	830	38	0	792
2	news.freedyn.net	681	18	0	663
3	newsfeed.fu-berlin.de	400	18	0	382
4	csiph-out.feeder.erje.net	308	63	0	245
5	newsfeed.neva.ru	262	125	1	136
6	csiph-out.feed.uzoreto.com	225	10	0	215
7	news-out.mixmin.net	130	2	1	127
8	csiph-out.news.weretis.net	121	76	0	45
9	news-out.aioe.org	96	36	0	60
10	feeder.eternal-september.org	73	16	1	56
11	newsfeeds.xmission.com	44	0	1	43
12	nntp.pasdenom.info	14	0	0	14
13	csiph-out.news.tnetconsulting.net	11	11	0	0
14	news.fcku.it	6	4	0	2
15	news.swapon.de	3	0	0	3
TOTAL: 15		3204	417	4	2783

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.auto	69
lada.talk	36
it-alt.sport.calcio.juventus	28
alt.windows7.general	26
alt.binaries.pictures.purebeauty	19
mozilla.support.seamonkey	18
mozilla.dev.platform	17
lada.commerce	16
mozilla.test	15
alt.bbs.synchronet	11
mozilla.support.firefox	11
test.post	9
rocksolid.nodes.help	8
alt.computer.workshop	8
spline.fli4l.dev	8
alt.binaries.ctb	7
alt.survival	7
lada.kitchen	7
oesterreich.tratsch	5
at.jobs	5
TOTAL: 79	417

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1726
Too many newsgroups	46
0	31
EMP (phn nph)	31
Binary Payload	15
HTML Multipart	14
EMP (phl)	13
Scoring filter	4
Binary: misplaced binary	2
Too many newsgroups (meow)	1
TOTAL: 10	1883

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	204
	csiph-out.news.xs4all.nl	104
	csiph-out.feed.uzoreto.com	71
	newsfeeds.xmission.com	43
	csiph-out.news.weretis.net	12
	news.freedyn.net	11
	feeder.eternal-september.org	1
	TOTAL: 7	446
Including strange strings
	newsfeed.fu-berlin.de	359
	news-out.aioe.org	56
	csiph-out.news.weretis.net	15
	news-out.mixmin.net	9
	csiph-out.news.xs4all.nl	3
	news.swapon.de	3
	feeder.eternal-september.org	2
	news.freedyn.net	1
	newsfeed.neva.ru	1
	TOTAL: 9	449
No colon-space in header
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	4
TOTAL: 4		944

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	21404	1279	15665	2	0	5%	23:59:57
2	news.freedyn.net	16086	520	15214	30	0	3%	23:59:57
3	news.samoylyk.net	17438	518	16428	0	27	2%	23:59:57
4	fu-berlin.de	15820	499	15321	0	0	3%	23:59:57
5	news.bbs.nz	31917	456	15976	19	0	1%	23:59:40
6	usenet.pasdenom.info	16912	161	16230	20	22	0%	23:59:57
7	tnet	19150	143	16317	0	0	0%	23:59:29
8	neva.ru	15422	58	14852	135	0	0%	23:59:57
9	newsfeed.xs4all.nl	16318	52	15902	2	41	0%	23:59:56
10	aioe	13148	35	12696	1	0	0%	23:59:57
11	xmission	16706	30	13319	2	0	0%	23:59:36
12	erje	14337	25	14109	93	4	0%	23:59:57
13	news.mixmin.net	15926	21	15427	0	0	0%	23:59:42
14	news.uzoreto.com	16639	16	16273	39	0	0%	23:59:47
15	weretis.net	16529	13	15422	16	0	0%	23:59:48
16	eternal-september	14674	12	14227	0	0	0%	23:59:57
17	news.swapon.de	1355	11	1332	0	0	0%	23:56:44
18	news.fcku.it	7520	7	1897	0	0	0%	22:49:37
TOTAL: 18		287301	3856	246607	359	94	1%	430:43:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	8.2 MB	15.2 KB	8.3 MB	0.1 KB/s	6.6 KB	23:59:57
2	news.freedyn.net	6.1 MB	46.1 KB	6.1 MB	0.1 KB/s	11.4 KB	23:59:57
3	news.samoylyk.net	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	12.0 KB	23:59:57
4	fu-berlin.de	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	12.4 KB	23:59:57
5	news.bbs.nz	1.1 MB	47.0 KB	1.2 MB	0.0 KB/s	2.5 KB	23:59:40
6	usenet.pasdenom.info	380.8 KB	72.3 KB	453.1 KB	0.0 KB/s	2.5 KB	23:59:57
7	tnet	295.6 KB	0.0 KB	295.6 KB	0.0 KB/s	2.1 KB	23:59:29
8	newsfeed.xs4all.nl	163.0 KB	5.5 KB	168.5 KB	0.0 KB/s	3.1 KB	23:59:56
9	neva.ru	127.9 KB	287.0 KB	414.9 KB	0.0 KB/s	2.1 KB	23:59:57
10	xmission	77.4 KB	4.6 KB	82.0 KB	0.0 KB/s	2.6 KB	23:59:36
11	aioe	71.7 KB	0.8 KB	72.5 KB	0.0 KB/s	2.0 KB	23:59:57
12	news.mixmin.net	54.1 KB	0.0 KB	54.1 KB	0.0 KB/s	2.6 KB	23:59:42
13	erje	53.6 KB	300.4 KB	353.9 KB	0.0 KB/s	3.0 KB	23:59:57
14	news.uzoreto.com	35.8 KB	151.9 KB	187.7 KB	0.0 KB/s	3.4 KB	23:59:47
15	weretis.net	23.0 KB	27.0 KB	50.0 KB	0.0 KB/s	1.7 KB	23:59:48
16	eternal-september	21.6 KB	0.0 KB	21.6 KB	0.0 KB/s	1.8 KB	23:59:57
17	news.swapon.de	17.1 KB	0.0 KB	17.1 KB	0.0 KB/s	1.6 KB	23:56:44
18	news.fcku.it	14.0 KB	0.0 KB	14.0 KB	0.0 KB/s	2.0 KB	22:49:37
TOTAL: 18		28.9 MB	957.8 KB	29.8 MB	0.0 KB/s	7.2 KB	430:43:52

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	11	53	97.8 KB	10	1	1	04:04:30
2	a131033.upc-a.chello.nl	16	33	66.3 KB	4	0	0	02:49:00
3	71-90-122-177.dhcp.eucl.wi.charter.com	46	32	65.0 KB	25	10	9	12:19:23
4	208-71-239-109.cust.centrio.cz	5	3	7.6 KB	2	0	0	02:30:37
TOTAL: 4		78	121	236.8 KB	41	11	10	21:43:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	11	53	97.8 KB	10	1	1	04:04:30
2	*.upc-a.chello.nl	16	33	66.3 KB	4	0	0	02:49:00
3	*.dhcp.eucl.wi.charter.com	46	32	65.0 KB	25	10	9	12:19:23
4	*.cust.centrio.cz	5	3	7.6 KB	2	0	0	02:30:37
5	unresolved	7	0	0.0 KB	0	0	0	00:00:07
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
TOTAL: 6		86	121	236.8 KB	41	11	10	21:43:41

NNRP auth users [Top 20]:

User	Conn
ohlandl	9
ctomczyk	5
kev009	1
TOTAL: 3	15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-90-122-177.dhcp.eucl.wi.charter.com	4.050	0.668	0.023	12:19:23
ip72-222-200-117.ph.ph.cox.net	1.095	0.269	0.003	04:04:30
a131033.upc-a.chello.nl	1.287	0.237	0.000	02:49:00
208-71-239-109.cust.centrio.cz	0.482	0.070	0.014	02:30:37
5.87.61.172	0.198	0.031	0.000	00:00:03
94.162.139.156	0.130	0.024	0.000	00:00:02
scan-141.security.ipip.net	0.063	0.016	0.000	00:00:01
94.162.179.188	0.067	0.007	0.000	00:00:01
198.20.87.98	0.067	0.007	0.000	00:00:00
TOTAL: 9	7.439	1.329	0.040	21:43:41

Curious NNRP server explorers [Top 20]:

System	Conn
5.87.61.172	3
94.162.139.156	2
94.162.179.188	1
scan-141.security.ipip.net	1
198.20.87.98	1
TOTAL: 5	8

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Host: 69.160.37.58:119	1
User-Agent: HTTP Banner Detection (https...	1
GET / HTTP/1.1	1
Connection: close	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-90-122-177.dhcp.eucl.wi.charter.com	12	0
ip72-222-200-117.ph.ph.cox.net	7	0
208-71-239-109.cust.centrio.cz	5	0
TOTAL: 3	24	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	121	99.2%
2	pl	1	0.8%
TOTAL: 2		122	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	103
2	comp.unix.tru64	8
3	comp.sys.att	4
4	comp.lang.javascript	2
5	comp.protocols.tcp-ip	2
6	comp.unix.solaris	2
7	pl.comp.www.nowe-strony	1
TOTAL: 7		122

Daily Usenet report for csiph.com

Dec 3 04:15:00 -- Dec 4 04:15:00