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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22138	61.3%	4.8 MB	71.9%
innd	10540	29.2%	1.3 MB	20.0%
innfeed	2799	7.8%	490.6 KB	7.1%
nnrpd	611	1.7%	69.7 KB	1.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	36089	100.0%	6.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	300194	54.2%
Negative hits	232067	41.9%
Do not exist	21513	3.9%
Cache misses	298	0.1%
TOTAL: 4	554072	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.210	0.0%	30	0.000	7.000	19.000
article cleanup	00:00:00.121	0.0%	21280	0.000	0.006	0.054
article logging	00:00:00.394	0.0%	21793	0.000	0.018	0.210
article parse	00:00:01.454	0.0%	66552	0.000	0.022	0.266
article write	00:00:16.881	0.0%	17744	0.152	0.951	18.333
artlog/artcncl	00:00:00.001	0.0%	30	0.000	0.033	0.500
artlog/artparse	00:00:00.019	0.0%	300	0.000	0.063	15.000
data move	00:00:00.129	0.0%	530908	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.029	0.0%	30	0.000	0.967	14.000
hishave/artcncl	00:00:00.020	0.0%	30	0.000	0.667	9.000
hishave/artparse	00:00:00.001	0.0%	299	0.000	0.003	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:40.696	0.0%	554899	0.000	0.073	0.441
history sync	00:00:01.575	0.0%	354	0.000	4.449	36.333
history write	00:00:57.242	0.1%	21260	0.030	2.692	11.660
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.891	0.0%	299	0.000	2.980	16.000
idle	23:56:55.188	99.7%	531549	91.221	162.196	451.036
nntp read	00:00:02.141	0.0%	536321	0.001	0.004	0.014
overview write	00:00:35.168	0.0%	17744	0.164	1.982	19.213
perl filter	00:00:38.454	0.0%	21259	1.246	1.809	3.533
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.276	0.0%	35488	0.000	0.008	0.027
TOTAL: 24:00:47.142	24:00:10.890	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.060	0.0%	17721	0.000	0.003	0.022
article prepare	00:00:00.002	0.0%	13996	0.000	0.000	0.008
article read	00:00:00.294	0.0%	1610	0.000	0.183	11.500
backlog stats	00:00:00.158	0.0%	326485	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.234	0.0%	252847	0.001	0.005	0.013
data write	00:00:01.484	0.0%	237609	0.003	0.006	0.014
idle	23:54:05.826	100.0%	326485	151.419	263.552	765.244
status file	00:00:00.153	0.0%	222	0.000	0.689	8.000
TOTAL: 23:54:15.636	23:54:09.211	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.067	0.0%	2273	0.383	0.469	0.556
idle	23:34:03.664	100.0%	2739	81.500	30976.146	600119.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.035	0.0%	5640	0.000	0.006	0.143
readart	00:00:00.269	0.0%	21	0.000	12.810	48.000
TOTAL: 23:34:28.002	23:34:05.035	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	54012	3999	49518	495	7%	70:24:37
2	news-out.mixmin.net	3	18444	2715	15006	723	14%	24:08:36
3	feeder.eternal-september.org	1	20151	2262	17617	272	11%	25:50:04
4	csiph-out.feeder.erje.net	1441	90184	2176	87046	962	2%	118:46:31
5	news.redatomik.org	3	13606	2024	11552	30	14%	24:08:50
6	news-out.aioe.org	2	17367	1596	15720	51	9%	24:09:57
7	newsfeed.neva.ru	141	17737	1076	15697	964	6%	71:21:57
8	newsfeed.fu-berlin.de	10	16453	938	15345	170	5%	24:42:15
9	news.freedyn.net	3	19292	316	18839	137	1%	23:57:29
10	news.fcku.it	5	2131	279	1850	2	13%	21:49:01
11	newsfeeds.xmission.com	2	17042	222	16817	3	1%	23:59:35
12	news.swapon.de	2	1602	33	1569	0	2%	24:24:58
13	nntp.pasdenom.info	3	16263	29	16228	6	0%	23:58:24
14	localhost	36	10	10	0	0	100%	00:00:00
15	news.bbs.nz	2	17150	1	17149	0	0%	23:59:41
16	xanadu-bbs.net	15	668	0	668	0	0%	22:46:50
TOTAL: 16		1674	322112	17676	300621	3815	5%	548:28:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	13.7 MB	21.9 KB	11.2 MB	24.9 MB	55%	5.7 KB
2	csiph-out.feeder.erje.net	11.7 MB	8.3 KB	11.1 MB	22.8 MB	51%	7.5 KB
3	news-out.mixmin.net	8.2 MB	1.7 KB	1.8 MB	10.0 MB	81%	3.0 KB
4	feeder.eternal-september.org	6.4 MB	1.7 KB	5.2 MB	11.6 MB	55%	4.7 KB
5	news.redatomik.org	5.7 MB	41.6 KB	138.7 KB	5.9 MB	97%	2.9 KB
6	news-out.aioe.org	3.8 MB	21.8 KB	348.3 KB	4.2 MB	91%	2.6 KB
7	newsfeed.neva.ru	3.1 MB	1.3 KB	4.0 MB	7.1 MB	43%	3.6 KB
8	newsfeed.fu-berlin.de	2.2 MB	1.6 KB	515.5 KB	2.7 MB	81%	2.5 KB
9	news.freedyn.net	1.4 MB	18.5 KB	862.1 KB	2.2 MB	61%	5.1 KB
10	newsfeeds.xmission.com	706.3 KB	0.0 KB	250.6 KB	956.9 KB	73%	4.3 KB
11	news.fcku.it	464.2 KB	1.7 KB	2.7 KB	468.6 KB	99%	1.7 KB
12	news.swapon.de	89.6 KB	0.0 KB	0.0 KB	89.6 KB	100%	2.7 KB
13	nntp.pasdenom.info	67.4 KB	0.0 KB	15.6 KB	83.1 KB	81%	2.4 KB
14	localhost	14.1 KB	0.0 KB	0.0 KB	14.1 KB	100%	1.4 KB
15	news.bbs.nz	0.9 KB	0.0 KB	0.0 KB	0.9 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.6 MB	120.1 KB	35.4 MB	93.1 MB	61%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:00 - 04:59:59	316	1.8%	0.12	1.6 MB	2.7%	0.60
Jan 15 05:00:00 - 05:59:59	390	2.2%	0.11	2.6 MB	4.4%	0.73
Jan 15 06:00:00 - 06:59:59	353	2.0%	0.10	1.1 MB	1.9%	0.32
Jan 15 07:00:00 - 07:59:59	508	2.9%	0.14	1.6 MB	2.7%	0.44
Jan 15 08:00:00 - 08:59:59	605	3.4%	0.17	1.6 MB	2.8%	0.47
Jan 15 09:00:00 - 09:59:59	721	4.1%	0.20	2.7 MB	4.6%	0.76
Jan 15 10:00:00 - 10:59:59	793	4.5%	0.22	2.2 MB	3.8%	0.63
Jan 15 11:00:00 - 11:59:59	837	4.7%	0.23	2.8 MB	4.8%	0.78
Jan 15 12:00:00 - 12:59:59	812	4.6%	0.23	2.1 MB	3.6%	0.60
Jan 15 13:00:00 - 13:59:59	792	4.5%	0.22	2.1 MB	3.5%	0.58
Jan 15 14:00:00 - 14:59:59	893	5.0%	0.25	2.7 MB	4.7%	0.78
Jan 15 15:00:00 - 15:59:59	1134	6.4%	0.32	3.9 MB	6.8%	1.12
Jan 15 16:00:00 - 16:59:59	1156	6.5%	0.32	3.5 MB	6.1%	1.01
Jan 15 17:00:00 - 17:59:59	1070	6.0%	0.30	3.1 MB	5.4%	0.89
Jan 15 18:00:00 - 18:59:59	1052	5.9%	0.29	2.9 MB	4.9%	0.81
Jan 15 19:00:00 - 19:59:59	889	5.0%	0.25	2.6 MB	4.6%	0.75
Jan 15 20:00:00 - 20:59:59	894	5.0%	0.25	2.6 MB	4.5%	0.74
Jan 15 21:00:00 - 21:59:59	922	5.2%	0.26	2.8 MB	4.8%	0.79
Jan 15 22:00:00 - 22:59:59	782	4.4%	0.22	2.2 MB	3.8%	0.62
Jan 15 23:00:00 - 23:59:59	818	4.6%	0.23	3.6 MB	6.2%	1.03
Jan 16 00:00:00 - 00:59:59	590	3.3%	0.16	2.1 MB	3.6%	0.59
Jan 16 01:00:00 - 01:59:59	492	2.8%	0.14	1.9 MB	3.3%	0.55
Jan 16 02:00:00 - 02:59:59	416	2.3%	0.12	1.3 MB	2.3%	0.38
Jan 16 03:00:00 - 03:59:59	424	2.4%	0.12	2.0 MB	3.5%	0.58
Jan 16 04:00:00 - 04:15:00	97	0.5%	0.11	326.6 KB	0.5%	0.36
TOTAL: 24:00:00	17756	100.0%	0.21	58.0 MB	100.0%	0.69

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	989	176	1	812
2	csiph-out.feeder.erje.net	974	85	3	886
3	news-out.mixmin.net	747	1	1	745
4	newsfeed.fu-berlin.de	605	16	1	588
5	csiph-out.news.weretis.net	540	179	4	357
6	feeder.eternal-september.org	240	21	1	218
7	news.freedyn.net	137	6	1	130
8	news-out.aioe.org	55	45	4	6
9	news.redatomik.org	45	0	3	42
10	news.swapon.de	8	0	0	8
11	nntp.pasdenom.info	6	0	0	6
12	newsfeeds.xmission.com	4	0	1	3
13	news.fcku.it	2	1	1	0
TOTAL: 13		4352	530	21	3801

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	122
gmane.linux.alsa.devel	46
gmane.comp.gis.openstreetmap.tagging	39
lada.auto	28
lada.soft	27
spline.eisfair	24
alt.music.pink-floyd.publius	16
mozilla.support.thunderbird	16
gmane.linux.kernel	15
alt.slack.goathead	15
alt.bbs.synchronet	14
alt.windows7.general	11
alt.survival	10
alt.binaries.ebook	10
it-alt.sport.calcio.juventus	8
mozilla.dev.security.policy	8
alt.philosophy	6
alt.politics.usa	6
alt.politics	5
mozilla.support.bugzilla	5
TOTAL: 88	530

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2416
EMP (phn path)	240
Too many newsgroups	86
EMP (phl)	50
User-issued cancel	36
HTML Multipart	31
EMP (phn nph)	21
Scoring filter (10)	19
Binary: misplaced par2	19
Binary Payload	10
Scoring filter (8)	10
Binary: misplaced nfo	10
Scoring filter (11)	9
Scoring filter (9)	9
Binary: misplaced rar	8
Scoring filter (12)	7
User-issued spam cancel	5
Binary Image: misplaced jpg	1
Binary: misplaced mp4	1
Scoring filter (13)	1
TOTAL: 21	2990

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	151
	csiph-out.news.weretis.net	119
	feeder.eternal-september.org	9
	news-out.mixmin.net	8
	news.freedyn.net	6
	newsfeeds.xmission.com	3
	newsfeed.fu-berlin.de	2
	news.redatomik.org	1
	TOTAL: 8	299
Including strange strings
	newsfeed.fu-berlin.de	434
	csiph-out.news.weretis.net	17
	news-out.mixmin.net	16
	news.redatomik.org	15
	csiph-out.feeder.erje.net	13
	news.swapon.de	8
	news-out.aioe.org	4
	newsfeed.neva.ru	3
	feeder.eternal-september.org	1
	TOTAL: 9	511
TOTAL: 3		855

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	17592	549	16881	5	0	3%	23:59:55
2	xanadu-bbs	19159	541	17215	0	0	2%	23:59:55
3	fu-berlin.de	16607	494	16113	0	0	2%	23:59:36
4	news.bbs.nz	33773	400	16825	28	3	1%	23:59:55
5	usenet.pasdenom.info	18322	66	16491	1168	7	0%	23:59:55
6	aioe	15049	36	14732	0	0	0%	23:59:55
7	news.redatomik.org	15422	17	15171	8	4	0%	23:59:55
8	news.swapon.de	1718	15	1694	0	0	0%	23:59:23
9	news.mixmin.net	14616	14	14396	0	0	0%	23:59:55
10	xmission	21083	12	16956	0	0	0%	23:59:50
11	neva.ru	16080	11	15830	98	0	0%	23:59:55
12	eternal-september	15228	11	14936	0	0	0%	23:59:55
13	weretis.net	12802	10	12682	1	0	0%	23:59:49
14	erje	15744	3	15471	22	0	0%	23:59:55
15	news.fcku.it	1823	0	1817	0	0	0%	21:52:12
TOTAL: 15		235018	2179	207210	1330	14	0%	357:50:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.4 MB	6.5 KB	6.5 MB	0.1 KB/s	11.9 KB	23:59:55
2	xanadu-bbs	6.4 MB	0.0 KB	6.4 MB	0.1 KB/s	12.2 KB	23:59:55
3	fu-berlin.de	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	13.0 KB	23:59:36
4	news.bbs.nz	1.1 MB	200.2 KB	1.3 MB	0.0 KB/s	3.0 KB	23:59:55
5	usenet.pasdenom.info	205.0 KB	7.4 MB	7.6 MB	0.1 KB/s	6.3 KB	23:59:55
6	aioe	99.3 KB	0.0 KB	99.3 KB	0.0 KB/s	2.8 KB	23:59:55
7	news.redatomik.org	46.8 KB	27.9 KB	74.7 KB	0.0 KB/s	3.0 KB	23:59:55
8	news.swapon.de	22.6 KB	0.0 KB	22.6 KB	0.0 KB/s	1.5 KB	23:59:23
9	neva.ru	22.0 KB	328.6 KB	350.6 KB	0.0 KB/s	3.2 KB	23:59:55
10	xmission	21.2 KB	0.0 KB	21.2 KB	0.0 KB/s	1.8 KB	23:59:50
11	news.mixmin.net	20.3 KB	0.0 KB	20.3 KB	0.0 KB/s	1.5 KB	23:59:55
12	eternal-september	17.1 KB	0.0 KB	17.1 KB	0.0 KB/s	1.6 KB	23:59:55
13	weretis.net	14.2 KB	33.8 KB	48.0 KB	0.0 KB/s	4.4 KB	23:59:49
14	erje	4.6 KB	124.7 KB	129.3 KB	0.0 KB/s	5.2 KB	23:59:55
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:52:12
TOTAL: 15		20.7 MB	8.1 MB	28.8 MB	0.0 KB/s	8.4 KB	357:50:00

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	71-82-226-171.dhcp.stpt.wi.charter.com	35	16	25.9 KB	10	10	7	08:52:10
2	a131033.upc-a.chello.nl	45	5	6.1 KB	3	0	0	11:59:17
TOTAL: 2		80	21	32.0 KB	13	10	7	23:34:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.stpt.wi.charter.com	35	16	25.9 KB	10	10	7	08:52:10
2	*.upc-a.chello.nl	45	5	6.1 KB	3	0	0	11:59:17
3	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:14
4	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
5	unresolved	1	0	0.0 KB	0	0	0	00:00:10
6	*.dhcp.chtrptr.net	2	0	0.0 KB	0	0	0	00:42:33
TOTAL: 6		88	21	32.0 KB	13	10	7	23:34:27

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
TOTAL: 1	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.167	0.582	0.000	11:59:17
71-82-226-171.dhcp.stpt.wi.charter.com	2.951	0.528	0.015	08:52:10
ip72-222-200-117.ph.ph.cox.net	0.465	0.054	0.000	02:00:14
172-221-041-102.dhcp.chtrptr.net	0.178	0.246	0.000	00:42:33
210.48.139.158	0.072	0.008	0.000	00:00:10
scan-141.security.ipip.net	0.062	0.018	0.000	00:00:00
TOTAL: 6	6.895	1.436	0.015	23:34:27

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
172-221-041-102.dhcp.chtrptr.net	2
scan-141.security.ipip.net	1
210.48.139.158	1
TOTAL: 4	8

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

Command	Count
Connection: close	1
Host: 69.160.37.58:119	1
User-Agent: HTTP Banner Detection (https...	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	7	0
ip72-222-200-117.ph.ph.cox.net	4	0
TOTAL: 2	11	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Jan 15 04:15:00 -- Jan 16 04:15:00