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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	23510	60.7%	5.0 MB	71.3%
innd	11433	29.5%	1.5 MB	20.6%
innfeed	2830	7.3%	476.4 KB	6.6%
nnrpd	953	2.5%	109.7 KB	1.5%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	38728	100.0%	7.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	309730	52.4%
Negative hits	258712	43.7%
Do not exist	22766	3.8%
Cache misses	302	0.1%
TOTAL: 4	591510	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.600	0.0%	22	0.000	27.273	117.000
article cleanup	00:00:00.146	0.0%	22474	0.000	0.006	0.034
article logging	00:00:00.476	0.0%	23048	0.000	0.021	0.573
article parse	00:00:01.603	0.0%	72004	0.000	0.022	0.532
article write	00:01:47.848	0.1%	18218	0.183	5.920	91.506
artlog/artcncl	00:00:00.000	0.0%	22	0.000	0.000	0.000
artlog/artparse	00:00:00.007	0.0%	372	0.000	0.019	1.000
data move	00:00:00.144	0.0%	563218	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.003	0.0%	21	0.000	0.143	1.000
hishave/artcncl	00:00:00.003	0.0%	22	0.000	0.136	1.000
hishave/artparse	00:00:00.001	0.0%	372	0.000	0.003	0.053
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:35.306	0.0%	593276	0.002	0.060	0.536
history sync	00:00:00.789	0.0%	351	0.000	2.248	34.500
history write	00:00:43.355	0.1%	22468	0.086	1.930	10.562
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.999	0.0%	372	0.000	2.685	22.500
idle	23:41:39.297	99.4%	556864	79.898	153.178	438.027
nntp read	00:00:02.485	0.0%	568855	0.001	0.004	0.061
overview write	00:04:29.519	0.3%	18218	0.143	14.794	154.045
perl filter	00:00:40.129	0.0%	22468	1.295	1.786	7.095
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.312	0.0%	36436	0.000	0.009	0.028
TOTAL: 23:50:48.182	23:50:03.022	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.055	0.0%	18270	0.000	0.003	0.027
article prepare	00:00:00.000	0.0%	15860	0.000	0.000	0.000
article read	00:00:02.983	0.0%	1792	0.000	1.665	190.000
backlog stats	00:00:00.177	0.0%	336168	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.469	0.0%	259535	0.001	0.006	0.018
data write	00:00:01.540	0.0%	245108	0.001	0.006	0.013
idle	23:54:06.618	100.0%	336168	148.268	255.963	688.287
status file	00:00:01.340	0.0%	247	0.000	5.425	172.000
TOTAL: 23:54:21.960	23:54:14.182	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:04.892	0.0%	6501	0.444	0.752	3.573
idle	35:41:06.755	100.0%	7273	59.500	17663.516	600179.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.084	0.0%	14862	0.000	0.006	0.143
readart	00:00:00.867	0.0%	50	0.000	17.340	57.000
TOTAL: 35:41:48.997	35:41:12.598	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	7	56032	4249	51360	423	7%	71:28:16
2	news-out.mixmin.net	2	19139	2701	15690	748	14%	24:08:47
3	news.redatomik.org	5	17659	2318	15148	193	13%	24:14:48
4	feeder.eternal-september.org	3	21479	1953	19228	298	9%	26:24:14
5	csiph-out.feeder.erje.net	1440	93532	1786	91063	683	1%	118:48:04
6	news-out.aioe.org	3	18421	1757	16578	86	9%	24:12:02
7	newsfeed.neva.ru	153	16266	1456	13078	1732	8%	71:53:19
8	newsfeed.fu-berlin.de	15	17319	962	16121	236	5%	25:59:32
9	news.freedyn.net	3	19588	451	18968	169	2%	23:59:46
10	newsfeeds.xmission.com	1	18041	255	17771	15	1%	24:38:59
11	news.fcku.it	10	2120	238	1878	4	11%	22:40:27
12	nntp.pasdenom.info	2	16511	97	16397	17	0%	23:58:49
13	news.swapon.de	8	1538	69	1461	8	4%	23:49:53
14	localhost	44	18	18	0	0	100%	00:00:00
15	xanadu-bbs.net	26	507	2	505	0	0%	22:23:09
16	news.bbs.nz	2	17488	2	17486	0	0%	23:59:47
TOTAL: 16		1724	335658	18314	312732	4612	5%	552:39:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	13.6 MB	27.7 KB	7.5 MB	21.1 MB	64%	4.6 KB
2	csiph-out.feeder.erje.net	11.2 MB	0.0 KB	9.4 MB	20.6 MB	54%	8.5 KB
3	news-out.mixmin.net	8.2 MB	4.4 KB	1.7 MB	9.9 MB	82%	2.9 KB
4	news.redatomik.org	7.2 MB	4.4 KB	4.0 MB	11.2 MB	63%	4.6 KB
5	feeder.eternal-september.org	5.0 MB	0.0 KB	4.1 MB	9.1 MB	55%	4.1 KB
6	newsfeed.neva.ru	4.4 MB	0.0 KB	7.0 MB	11.4 MB	38%	3.7 KB
7	news-out.aioe.org	4.3 MB	32.0 KB	335.9 KB	4.7 MB	92%	2.6 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	527.7 KB	2.8 MB	81%	2.4 KB
9	news.freedyn.net	1.6 MB	0.0 KB	1.1 MB	2.7 MB	58%	4.4 KB
10	newsfeeds.xmission.com	814.3 KB	0.0 KB	764.8 KB	1.5 MB	51%	5.8 KB
11	news.fcku.it	433.4 KB	0.0 KB	5.8 KB	439.2 KB	98%	1.8 KB
12	nntp.pasdenom.info	198.6 KB	4.4 KB	37.2 KB	240.2 KB	82%	2.1 KB
13	news.swapon.de	175.5 KB	0.0 KB	3.3 MB	3.5 MB	4%	46.2 KB
14	localhost	34.7 KB	0.0 KB	0.0 KB	34.7 KB	100%	1.9 KB
15	xanadu-bbs.net	4.5 KB	0.0 KB	0.0 KB	4.5 KB	100%	2.2 KB
16	news.bbs.nz	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	2.2 KB
TOTAL: 16		59.2 MB	72.9 KB	39.7 MB	99.0 MB	59%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 28 04:15:00 - 04:59:59	341	1.9%	0.13	1.4 MB	2.3%	0.52
Jan 28 05:00:00 - 05:59:59	409	2.2%	0.11	1.8 MB	3.0%	0.50
Jan 28 06:00:00 - 06:59:59	432	2.4%	0.12	1.4 MB	2.3%	0.39
Jan 28 07:00:00 - 07:59:59	467	2.6%	0.13	1.6 MB	2.8%	0.47
Jan 28 08:00:00 - 08:59:59	654	3.6%	0.18	1.7 MB	2.8%	0.48
Jan 28 09:00:00 - 09:59:59	828	4.5%	0.23	3.1 MB	5.2%	0.87
Jan 28 10:00:00 - 10:59:59	786	4.3%	0.22	2.2 MB	3.6%	0.61
Jan 28 11:00:00 - 11:59:59	865	4.7%	0.24	2.8 MB	4.6%	0.79
Jan 28 12:00:00 - 12:59:59	845	4.6%	0.23	2.4 MB	4.0%	0.68
Jan 28 13:00:00 - 13:59:59	999	5.5%	0.28	2.3 MB	3.9%	0.66
Jan 28 14:00:00 - 14:59:59	964	5.3%	0.27	2.8 MB	4.7%	0.79
Jan 28 15:00:00 - 15:59:59	1104	6.0%	0.31	3.7 MB	6.2%	1.05
Jan 28 16:00:00 - 16:59:59	1164	6.4%	0.32	4.1 MB	6.9%	1.17
Jan 28 17:00:00 - 17:59:59	1045	5.7%	0.29	3.0 MB	5.0%	0.85
Jan 28 18:00:00 - 18:59:59	1088	6.0%	0.30	3.2 MB	5.4%	0.91
Jan 28 19:00:00 - 19:59:59	1069	5.9%	0.30	3.9 MB	6.6%	1.11
Jan 28 20:00:00 - 20:59:59	1038	5.7%	0.29	3.0 MB	5.0%	0.84
Jan 28 21:00:00 - 21:59:59	925	5.1%	0.26	3.1 MB	5.1%	0.87
Jan 28 22:00:00 - 22:59:59	730	4.0%	0.20	2.1 MB	3.5%	0.58
Jan 28 23:00:00 - 23:59:59	654	3.6%	0.18	2.6 MB	4.5%	0.75
Jan 29 00:00:00 - 00:59:59	542	3.0%	0.15	1.9 MB	3.2%	0.55
Jan 29 01:00:00 - 01:59:59	434	2.4%	0.12	1.7 MB	2.8%	0.48
Jan 29 02:00:00 - 02:59:59	474	2.6%	0.13	1.7 MB	2.8%	0.48
Jan 29 03:00:00 - 03:59:59	315	1.7%	0.09	1.8 MB	3.1%	0.52
Jan 29 04:00:00 - 04:15:00	95	0.5%	0.11	394.0 KB	0.6%	0.44
TOTAL: 24:00:00	18267	100.0%	0.21	59.4 MB	100.0%	0.70

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	1734	155	0	1579
2	newsfeed.fu-berlin.de	793	41	0	752
3	news-out.mixmin.net	766	2	1	763
4	csiph-out.feeder.erje.net	688	89	0	599
5	csiph-out.news.weretis.net	435	228	1	206
6	feeder.eternal-september.org	273	26	0	247
7	news.redatomik.org	209	0	1	208
8	news.freedyn.net	172	15	0	157
9	news-out.aioe.org	86	36	2	48
10	news.swapon.de	28	0	0	28
11	nntp.pasdenom.info	17	1	1	15
12	newsfeeds.xmission.com	15	0	0	15
13	news.fcku.it	4	4	0	0
14	xanadu-bbs.net	1	0	0	1
TOTAL: 14		5221	597	6	4618

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	64
gmane.linux.alsa.devel	51
alt.windows7.general	46
lada.auto	45
alt.music.pink-floyd.publius	36
mozilla.dev.platform	26
spline.eisfair	25
it-alt.sport.calcio.juventus	23
alt.bbs.synchronet	21
alt.survival	15
alt.politics.usa	15
spline.fli4l	14
alt.pl.nieruchomosci.gielda	12
free.it.religioni.scientology	10
mozilla.support.firefox	9
oesterreich.tratsch	9
mozilla.support.seamonkey	8
mozilla.support.thunderbird	8
alt.slack.goathead	7
alt.binaries.ebook	7
TOTAL: 97	597

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2792
EMP (phn path)	313
User-issued cancel	136
Binary: misplaced par2	96
EMP (phn nph)	85
Binary: misplaced nfo	62
Too many newsgroups	45
HTML Multipart	37
EMP (phl)	30
User-issued spam cancel	14
Scoring filter (10)	11
Scoring filter (9)	9
Scoring filter (12)	8
Binary: misplaced rar	7
Binary Payload	5
Scoring filter (8)	4
Scoring filter (11)	4
Scoring filter (13)	2
HTML post	2
Scoring filter (17)	2
TOTAL: 25	3669

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	TOTAL: 1	50
Huge articles
	csiph-out.feeder.erje.net	151
	csiph-out.news.weretis.net	91
	feeder.eternal-september.org	53
	news.redatomik.org	45
	newsfeeds.xmission.com	12
	news.freedyn.net	10
	news.swapon.de	8
	news-out.mixmin.net	2
	TOTAL: 8	372
Including strange strings
	newsfeed.fu-berlin.de	496
	news.swapon.de	20
	news-out.mixmin.net	17
	news.redatomik.org	16
	csiph-out.news.weretis.net	12
	newsfeed.neva.ru	8
	csiph-out.feeder.erje.net	5
	news-out.aioe.org	2
	xanadu-bbs.net	1
	TOTAL: 9	577
TOTAL: 3		999

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19706	653	17612	0	5	3%	23:59:55
2	news.freedyn.net	17971	643	17159	3	0	3%	23:59:38
3	fu-berlin.de	17111	580	16531	0	0	3%	23:59:44
4	news.bbs.nz	34176	147	17525	8	0	0%	23:59:55
5	erje	18743	91	16098	190	0	0%	23:59:55
6	usenet.pasdenom.info	18489	82	16863	1221	14	0%	23:59:55
7	aioe	15543	35	15162	1	0	0%	23:59:55
8	eternal-september	16074	21	15761	0	0	0%	23:59:44
9	news.redatomik.org	15585	20	15317	0	7	0%	23:59:55
10	neva.ru	15992	19	15747	177	0	0%	23:59:55
11	weretis.net	13168	18	13071	1	0	0%	23:59:55
12	xmission	21027	18	17346	1	0	0%	23:59:55
13	news.swapon.de	1507	18	1486	0	0	1%	23:29:49
14	news.mixmin.net	14936	18	14692	0	0	0%	23:59:55
15	news.fcku.it	1823	0	1822	0	0	0%	22:32:31
TOTAL: 15		241851	2363	212192	1602	26	0%	358:00:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.6 MB	0.0 KB	7.6 MB	0.1 KB/s	12.0 KB	23:59:55
2	news.freedyn.net	7.6 MB	3.1 KB	7.6 MB	0.1 KB/s	12.0 KB	23:59:38
3	fu-berlin.de	7.4 MB	0.0 KB	7.4 MB	0.1 KB/s	13.1 KB	23:59:44
4	news.bbs.nz	346.1 KB	82.3 KB	428.4 KB	0.0 KB/s	2.8 KB	23:59:55
5	usenet.pasdenom.info	211.7 KB	8.5 MB	8.7 MB	0.1 KB/s	6.8 KB	23:59:55
6	erje	198.9 KB	537.0 KB	735.9 KB	0.0 KB/s	2.6 KB	23:59:55
7	aioe	65.6 KB	1.3 KB	66.9 KB	0.0 KB/s	1.9 KB	23:59:55
8	neva.ru	50.7 KB	539.2 KB	589.9 KB	0.0 KB/s	3.0 KB	23:59:55
9	eternal-september	47.6 KB	0.0 KB	47.6 KB	0.0 KB/s	2.3 KB	23:59:44
10	news.redatomik.org	41.1 KB	0.0 KB	41.1 KB	0.0 KB/s	2.1 KB	23:59:55
11	weretis.net	34.8 KB	1.2 KB	35.9 KB	0.0 KB/s	1.9 KB	23:59:55
12	xmission	34.8 KB	2.1 KB	36.9 KB	0.0 KB/s	1.9 KB	23:59:55
13	news.swapon.de	34.8 KB	0.0 KB	34.8 KB	0.0 KB/s	1.9 KB	23:29:49
14	news.mixmin.net	34.8 KB	0.0 KB	34.8 KB	0.0 KB/s	1.9 KB	23:59:55
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:32:31
TOTAL: 15		23.7 MB	9.6 MB	33.3 MB	0.0 KB/s	8.6 KB	358:00:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	74	25	52.0 KB	11	0	0	20:02:24
2	71-82-226-171.dhcp.stpt.wi.charter.com	49	24	50.4 KB	22	19	14	12:00:14
3	ip72-222-200-117.ph.ph.cox.net	5	1	1.1 KB	1	0	0	02:30:27
TOTAL: 3		128	50	103.5 KB	34	19	14	35:41:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	74	25	52.0 KB	11	0	0	20:02:24
2	*.dhcp.stpt.wi.charter.com	49	24	50.4 KB	22	19	14	12:00:14
3	*.ph.ph.cox.net	5	1	1.1 KB	1	0	0	02:30:27
4	*.dip0.t-ipconnect.de	1	0	0.0 KB	0	0	0	00:00:03
5	unresolved	1	0	0.0 KB	0	0	0	00:00:01
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
7	*.cust.centrio.cz	1	0	0.0 KB	0	0	0	00:30:06
8	*.dhcp.chtrptr.net	6	0	0.0 KB	0	0	0	00:38:27
TOTAL: 8		138	50	103.5 KB	34	19	14	35:41:47

NNRP auth users [Top 20]:

User	Conn
ohlandl	14
ctomczyk	1
TOTAL: 2	15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	5.688	0.872	0.000	20:02:24
71-82-226-171.dhcp.stpt.wi.charter.com	4.389	0.655	0.075	12:00:14
ip72-222-200-117.ph.ph.cox.net	0.583	0.075	0.000	02:30:27
172-221-041-102.dhcp.chtrptr.net	0.576	1.109	0.000	00:38:27
208-71-239-109.cust.centrio.cz	0.077	0.000	0.002	00:30:06
p3ee23889.dip0.t-ipconnect.de	0.127	0.000	0.000	00:00:03
scan-141.security.ipip.net	0.072	0.008	0.000	00:00:01
94.166.18.230	0.081	0.000	0.000	00:00:01
TOTAL: 8	11.593	2.719	0.077	35:41:47

Curious NNRP server explorers [Top 20]:

System	Conn
172-221-041-102.dhcp.chtrptr.net	6
p3ee23889.dip0.t-ipconnect.de	1
scan-141.security.ipip.net	1
94.166.18.230	1
208-71-239-109.cust.centrio.cz	1
TOTAL: 5	10

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-226-171.dhcp.stpt.wi.charter.com	12	0
ip72-222-200-117.ph.ph.cox.net	5	0
208-71-239-109.cust.centrio.cz	1	0
TOTAL: 3	18	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	50
TOTAL: 1		50

Daily Usenet report for csiph.com

Jan 28 04:15:00 -- Jan 29 04:15:00