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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18122	46.1%	4.6 MB	61.2%
innd	17122	43.6%	2.3 MB	29.9%
innfeed	3412	8.7%	618.9 KB	8.0%
nnrpd	611	1.6%	68.7 KB	0.9%
nocem	33	0.1%	3.8 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	39301	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	293985	57.4%
Negative hits	200157	39.1%
Do not exist	17543	3.4%
Cache misses	216	0.0%
TOTAL: 4	511901	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.358	0.0%	25	0.000	14.320	20.600
article cleanup	00:00:00.094	0.0%	17046	0.000	0.006	0.043
article logging	00:00:00.262	0.0%	17553	0.000	0.015	0.571
article parse	00:00:00.790	0.0%	56707	0.000	0.014	0.053
article write	00:00:09.258	0.0%	14326	0.139	0.646	26.157
artlog/artcncl	00:00:00.000	0.0%	25	0.000	0.000	0.000
artlog/artparse	00:00:00.006	0.0%	555	0.000	0.011	0.143
data move	00:00:00.178	0.0%	482153	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.005	0.0%	25	0.000	0.200	1.000
hishave/artcncl	00:00:00.009	0.0%	25	0.000	0.360	1.000
hishave/artparse	00:00:00.000	0.0%	544	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.813	0.0%	512602	0.001	0.005	0.186
history sync	00:00:00.709	0.0%	368	0.000	1.927	27.500
history write	00:00:07.132	0.0%	17043	0.045	0.418	5.038
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.243	0.0%	544	0.000	0.447	5.000
idle	23:58:26.090	99.8%	489542	101.561	176.300	504.639
nntp read	00:00:01.820	0.0%	489780	0.001	0.004	0.008
overview write	00:01:16.393	0.1%	14326	0.072	5.332	730.395
perl filter	00:00:22.249	0.0%	17030	0.941	1.306	1.795
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.231	0.0%	28685	0.000	0.008	0.034
TOTAL: 24:00:59.317	24:00:28.640	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	14466	0.000	0.002	0.022
article prepare	00:00:00.004	0.0%	19160	0.000	0.000	0.006
article read	00:00:00.354	0.0%	1953	0.000	0.181	4.500
backlog stats	00:00:00.174	0.0%	357335	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.215	0.0%	275117	0.000	0.004	0.010
data write	00:00:01.548	0.0%	257512	0.002	0.006	0.012
idle	23:54:52.020	100.0%	357335	137.785	240.928	761.032
status file	00:00:00.182	0.0%	228	0.000	0.798	24.000
TOTAL: 23:55:02.834	23:54:55.532	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	16:58:29.710	100.0%	546	352.667	111922.546	600164.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.010	0.0%	1236	0.000	0.008	0.167
readart	00:00:00.593	0.0%	68	0.000	8.721	42.000
TOTAL: 16:58:54.882	16:58:30.313	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	13093	3319	9695	79	25%	22:30:01
2	news-out.aioe.org	3	13677	3214	10371	92	23%	24:09:27
3	feeder.eternal-september.org	4	17461	1760	15295	406	10%	27:41:48
4	csiph-out.feeder.erje.net	1440	71130	1316	69301	513	1%	118:51:07
5	news-out.mixmin.net	2	15153	754	13899	500	4%	23:59:44
6	csiph-out.news.xs4all.nl	861	16002	717	14398	887	4%	71:27:06
7	newsfeed.fu-berlin.de	11	11943	662	11199	82	5%	21:03:24
8	csiph-out.news.weretis.net	3	18236	642	17453	141	3%	34:06:11
9	newsfeed.neva.ru	143	15139	624	14293	222	4%	71:11:47
10	news.freedyn.net	1	16693	358	16215	120	2%	23:59:54
11	news-out.neodome.net	2	15187	149	15021	17	0%	23:59:49
12	news.fcku.it	8	1440	146	1290	4	10%	21:32:17
13	csiph-out.feed.uzoreto.com	864	22763	122	22490	151	0%	71:17:18
14	csiph-out.news.tnetconsulting.net	2	14890	116	14755	19	0%	23:59:51
15	nntp.pasdenom.info	2	9256	53	9171	32	0%	23:58:11
16	news.swapon.de	4	1298	25	1273	0	1%	23:51:34
17	localhost	44	18	18	0	0	100%	00:00:08
18	news.samoylyk.net	1	14223	8	14214	1	0%	23:59:54
19	news.bbs.nz	2	14472	5	14467	0	0%	23:59:53
20	xanadu-bbs.net	25	430	0	430	0	0%	21:08:35
TOTAL: 20		3424	302504	14008	285230	3266	4%	696:47:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.8 MB	7.7 KB	4.2 MB	14.1 MB	69%	4.2 KB
2	csiph-out.feeder.erje.net	9.2 MB	0.0 KB	12.1 MB	21.2 MB	43%	11.9 KB
3	news-out.aioe.org	7.7 MB	7.6 KB	367.5 KB	8.1 MB	95%	2.5 KB
4	feeder.eternal-september.org	4.7 MB	8.4 KB	2.8 MB	7.5 MB	62%	3.6 KB
5	news-out.mixmin.net	2.2 MB	7.7 KB	820.5 KB	3.0 MB	72%	2.4 KB
6	csiph-out.news.weretis.net	2.1 MB	7.8 KB	2.1 MB	4.2 MB	49%	5.5 KB
7	csiph-out.news.xs4all.nl	2.0 MB	0.0 KB	13.2 MB	15.3 MB	13%	9.7 KB
8	newsfeed.neva.ru	2.0 MB	0.0 KB	863.2 KB	2.8 MB	69%	3.4 KB
9	newsfeed.fu-berlin.de	1.4 MB	0.0 KB	187.7 KB	1.6 MB	88%	2.2 KB
10	news.freedyn.net	1.2 MB	0.0 KB	908.0 KB	2.1 MB	56%	4.4 KB
11	csiph-out.feed.uzoreto.com	452.6 KB	0.0 KB	4.6 MB	5.0 MB	8%	18.8 KB
12	news-out.neodome.net	400.7 KB	7.8 KB	79.5 KB	487.9 KB	82%	2.9 KB
13	csiph-out.news.tnetconsulting.net	374.7 KB	1.6 KB	150.5 KB	526.9 KB	71%	3.9 KB
14	news.fcku.it	287.7 KB	0.0 KB	5.1 KB	292.8 KB	98%	2.0 KB
15	nntp.pasdenom.info	122.6 KB	0.0 KB	50.8 KB	173.4 KB	70%	2.0 KB
16	news.swapon.de	61.7 KB	0.0 KB	0.0 KB	61.7 KB	100%	2.5 KB
17	localhost	31.7 KB	0.0 KB	0.0 KB	31.7 KB	100%	1.8 KB
18	news.samoylyk.net	23.6 KB	0.0 KB	1.2 KB	24.8 KB	95%	2.8 KB
19	news.bbs.nz	15.6 KB	0.0 KB	0.0 KB	15.6 KB	100%	3.1 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.0 MB	48.5 KB	42.4 MB	86.4 MB	50%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 18 04:15:00 - 04:59:59	228	1.6%	0.08	661.3 KB	1.4%	0.24
Jan 18 05:00:00 - 05:59:59	306	2.1%	0.09	953.1 KB	2.1%	0.26
Jan 18 06:00:00 - 06:59:59	289	2.0%	0.08	825.1 KB	1.8%	0.23
Jan 18 07:00:00 - 07:59:59	466	3.3%	0.13	2.3 MB	5.0%	0.65
Jan 18 08:00:00 - 08:59:59	558	3.9%	0.15	2.3 MB	5.2%	0.66
Jan 18 09:00:00 - 09:59:59	611	4.3%	0.17	1.8 MB	4.0%	0.52
Jan 18 10:00:00 - 10:59:59	609	4.3%	0.17	2.1 MB	4.7%	0.60
Jan 18 11:00:00 - 11:59:59	603	4.2%	0.17	1.8 MB	4.1%	0.52
Jan 18 12:00:00 - 12:59:59	664	4.6%	0.18	1.7 MB	3.8%	0.49
Jan 18 13:00:00 - 13:59:59	729	5.1%	0.20	2.2 MB	4.8%	0.62
Jan 18 14:00:00 - 14:59:59	703	4.9%	0.20	1.9 MB	4.1%	0.53
Jan 18 15:00:00 - 15:59:59	734	5.1%	0.20	2.2 MB	4.9%	0.63
Jan 18 16:00:00 - 16:59:59	852	6.0%	0.24	2.3 MB	5.0%	0.64
Jan 18 17:00:00 - 17:59:59	763	5.3%	0.21	2.2 MB	4.9%	0.62
Jan 18 18:00:00 - 18:59:59	878	6.1%	0.24	2.7 MB	6.1%	0.78
Jan 18 19:00:00 - 19:59:59	786	5.5%	0.22	2.4 MB	5.3%	0.68
Jan 18 20:00:00 - 20:59:59	795	5.6%	0.22	2.6 MB	5.7%	0.73
Jan 18 21:00:00 - 21:59:59	789	5.5%	0.22	2.1 MB	4.7%	0.60
Jan 18 22:00:00 - 22:59:59	667	4.7%	0.19	1.8 MB	3.9%	0.50
Jan 18 23:00:00 - 23:59:59	588	4.1%	0.16	2.5 MB	5.5%	0.70
Jan 19 00:00:00 - 00:59:59	476	3.3%	0.13	1.6 MB	3.6%	0.46
Jan 19 01:00:00 - 01:59:59	425	3.0%	0.12	1.4 MB	3.1%	0.39
Jan 19 02:00:00 - 02:59:59	403	2.8%	0.11	1.3 MB	2.9%	0.37
Jan 19 03:00:00 - 03:59:59	319	2.2%	0.09	1.3 MB	2.9%	0.37
Jan 19 04:00:00 - 04:15:00	75	0.5%	0.08	224.8 KB	0.5%	0.25
TOTAL: 24:00:00	14316	100.0%	0.17	45.0 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	883	45	0	838
2	csiph-out.feeder.erje.net	516	50	0	466
3	news-out.mixmin.net	508	7	2	499
4	newsfeed.fu-berlin.de	447	48	0	399
5	feeder.eternal-september.org	388	25	2	361
6	newsfeed.neva.ru	222	23	0	199
7	news-out.aioe.org	206	67	2	137
8	csiph-out.news.weretis.net	186	63	4	119
9	csiph-out.feed.uzoreto.com	152	6	0	146
10	news.freedyn.net	126	1	0	125
11	newsfeeds.xmission.com	84	4	2	78
12	nntp.pasdenom.info	32	0	0	32
13	news-out.neodome.net	16	0	2	14
14	csiph-out.news.tnetconsulting.net	8	7	0	1
15	news.fcku.it	4	2	0	2
16	news.swapon.de	2	0	0	2
17	news.bbs.nz	1	1	0	0
TOTAL: 17		3781	349	14	3418

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	98
spline.eisfair	27
it-alt.sport.calcio.juventus	18
alt.windows7.general	13
mozilla.support.thunderbird	12
mozilla.support.seamonkey	12
alt.music.pink-floyd.publius	12
alt.binaries.pictures.purebeauty	11
alt.binaries.ebooks	10
cakewalk.audio	9
mozilla.support.firefox	8
alt.bbs.synchronet	7
oesterreich.tratsch	7
alt.binaries.moovee	7
lada.talk	7
mozilla.general	6
alt.politics	5
alt.eunuchs	4
alt.christian.religion	4
lada.movies	4
TOTAL: 65	349

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1681
EMP (phn path)	189
EMP (phl)	167
EMP (phn nph)	147
0	117
HTML Multipart	30
Too many newsgroups	15
Scoring filter	3
Binary: misplaced binary	2
MID-Bot	2
Too many newsgroups (low_xpost)	1
HTML post	1
TOTAL: 12	2355

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	216
	csiph-out.news.xs4all.nl	152
	newsfeeds.xmission.com	74
	csiph-out.feed.uzoreto.com	58
	csiph-out.news.weretis.net	18
	feeder.eternal-september.org	18
	news.freedyn.net	8
	news-out.neodome.net	1
	TOTAL: 8	545
Including strange strings
	newsfeed.fu-berlin.de	360
	news-out.aioe.org	114
	csiph-out.news.weretis.net	14
	news-out.mixmin.net	7
	news.freedyn.net	5
	csiph-out.feeder.erje.net	3
	news.swapon.de	2
	newsfeeds.xmission.com	2
	TOTAL: 8	507
No colon-space in header
	csiph-out.news.xs4all.nl	6
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	2
	TOTAL: 3	10
TOTAL: 5		1109

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17876	1581	12734	1	27	8%	23:59:55
2	news.freedyn.net	14244	615	13312	19	1	4%	23:59:32
3	news.samoylyk.net	14768	611	13697	0	0	4%	23:59:54
4	fu-berlin.de	13406	601	12805	0	0	4%	23:59:54
5	news.bbs.nz	26671	358	13347	8	0	1%	23:59:54
6	usenet.pasdenom.info	14230	129	13510	28	19	0%	23:59:54
7	tnet	14972	111	13609	0	0	0%	23:59:55
8	erje	16043	98	12615	178	0	0%	23:59:48
9	neva.ru	13026	70	12771	87	0	0%	23:59:54
10	aioe	10018	46	9650	0	0	0%	23:59:54
11	newsfeed.xs4all.nl	13810	40	13391	7	116	0%	23:59:54
12	neodome.net	14632	26	14053	0	0	0%	23:59:53
13	xmission	14092	20	10177	0	0	0%	23:59:55
14	news.swapon.de	1391	18	1366	0	0	1%	23:56:06
15	news.mixmin.net	13145	18	12673	1	0	0%	23:59:54
16	weretis.net	13059	17	12781	3	0	0%	23:59:55
17	eternal-september	12485	17	12038	0	0	0%	23:59:54
18	news.uzoreto.com	14079	10	13824	72	0	0%	23:59:53
19	news.fcku.it	1161	0	1160	0	0	0%	21:36:13
TOTAL: 19		253108	4386	219513	404	163	1%	453:30:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.8 MB	4.4 KB	9.8 MB	0.1 KB/s	6.3 KB	23:59:55
2	news.freedyn.net	7.0 MB	24.9 KB	7.1 MB	0.1 KB/s	11.4 KB	23:59:32
3	news.samoylyk.net	7.0 MB	0.0 KB	7.0 MB	0.1 KB/s	11.7 KB	23:59:54
4	fu-berlin.de	7.0 MB	0.0 KB	7.0 MB	0.1 KB/s	11.9 KB	23:59:54
5	news.bbs.nz	901.6 KB	28.3 KB	929.8 KB	0.0 KB/s	2.5 KB	23:59:54
6	usenet.pasdenom.info	395.1 KB	95.0 KB	490.2 KB	0.0 KB/s	3.1 KB	23:59:54
7	tnet	274.5 KB	0.0 KB	274.5 KB	0.0 KB/s	2.5 KB	23:59:55
8	erje	224.1 KB	630.2 KB	854.3 KB	0.0 KB/s	3.1 KB	23:59:48
9	neva.ru	145.9 KB	190.2 KB	336.1 KB	0.0 KB/s	2.1 KB	23:59:54
10	newsfeed.xs4all.nl	110.5 KB	16.3 KB	126.8 KB	0.0 KB/s	2.7 KB	23:59:54
11	aioe	89.3 KB	0.0 KB	89.3 KB	0.0 KB/s	1.9 KB	23:59:54
12	neodome.net	85.9 KB	0.0 KB	85.9 KB	0.0 KB/s	3.3 KB	23:59:53
13	news.uzoreto.com	48.2 KB	208.7 KB	257.0 KB	0.0 KB/s	3.1 KB	23:59:53
14	xmission	40.3 KB	0.0 KB	40.3 KB	0.0 KB/s	2.0 KB	23:59:55
15	news.swapon.de	31.8 KB	0.0 KB	31.8 KB	0.0 KB/s	1.8 KB	23:56:06
16	news.mixmin.net	31.7 KB	4.0 KB	35.7 KB	0.0 KB/s	1.9 KB	23:59:54
17	weretis.net	30.7 KB	11.9 KB	42.6 KB	0.0 KB/s	2.1 KB	23:59:55
18	eternal-september	30.7 KB	0.0 KB	30.7 KB	0.0 KB/s	1.8 KB	23:59:54
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:36:13
TOTAL: 19		33.1 MB	1.2 MB	34.3 MB	0.0 KB/s	7.3 KB	453:30:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	12	31	51.5 KB	3	0	0	01:40:09
2	71-90-73-253.dhcp.ftbg.wi.charter.com	55	28	52.1 KB	26	18	13	12:42:57
3	208-71-239-109.cust.centrio.cz	5	9	19.0 KB	3	0	0	01:35:24
TOTAL: 3		72	68	122.6 KB	32	18	13	16:58:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	12	31	51.5 KB	3	0	0	01:40:09
2	*.dhcp.ftbg.wi.charter.com	55	28	52.1 KB	26	18	13	12:42:57
3	*.cust.centrio.cz	5	9	19.0 KB	3	0	0	01:35:24
4	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:07
5	unresolved	5	0	0.0 KB	0	0	0	00:00:11
6	*.dip0.t-ipconnect.de	1	0	0.0 KB	0	0	0	00:00:04
TOTAL: 6		80	68	122.6 KB	32	18	13	16:58:54

NNRP auth users [Top 20]:

User	Conn
ohlandl	13
ctomczyk	5
TOTAL: 2	18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-90-73-253.dhcp.ftbg.wi.charter.com	4.493	0.955	0.031	12:42:57
a131033.upc-a.chello.nl	0.959	0.180	0.000	01:40:09
208-71-239-109.cust.centrio.cz	0.422	0.101	0.011	01:35:24
ip72-222-200-117.ph.ph.cox.net	0.213	0.030	0.000	01:00:07
164.52.36.212	0.054	0.023	0.000	00:00:05
5.86.144.186	0.176	0.054	0.000	00:00:04
pd9e2f2c7.dip0.t-ipconnect.de	0.070	0.009	0.000	00:00:04
94.167.172.10	0.052	0.022	0.000	00:00:01
TOTAL: 8	6.439	1.374	0.042	16:58:54

Curious NNRP server explorers [Top 20]:

System	Conn
5.86.144.186	3
ip72-222-200-117.ph.ph.cox.net	2
94.167.172.10	1
pd9e2f2c7.dip0.t-ipconnect.de	1
164.52.36.212	1
TOTAL: 5	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-90-73-253.dhcp.ftbg.wi.charter.com	10	0
208-71-239-109.cust.centrio.cz	3	0
ip72-222-200-117.ph.ph.cox.net	2	0
TOTAL: 3	15	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	59
2	comp.lang.javascript	9
TOTAL: 2		68

Daily Usenet report for csiph.com

Jan 18 04:15:00 -- Jan 19 04:15:00