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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19020	47.5%	5.0 MB	63.0%
innd	16916	42.3%	2.2 MB	28.3%
innfeed	3485	8.7%	643.8 KB	8.0%
nnrpd	556	1.4%	59.8 KB	0.7%
nocem	45	0.1%	5.1 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40023	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	320268	58.0%
Negative hits	212433	38.5%
Do not exist	18522	3.4%
Cache misses	637	0.1%
TOTAL: 4	551860	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.400	0.0%	21	0.000	19.048	31.800
article cleanup	00:00:00.093	0.0%	18222	0.000	0.005	0.038
article logging	00:00:00.262	0.0%	18623	0.000	0.014	0.076
article parse	00:00:00.903	0.0%	56664	0.000	0.016	0.175
article write	00:00:06.088	0.0%	15545	0.143	0.392	8.922
artlog/artcncl	00:00:00.000	0.0%	21	0.000	0.000	0.000
artlog/artparse	00:00:00.004	0.0%	387	0.000	0.010	1.000
data move	00:00:00.160	0.0%	519993	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.007	0.0%	21	0.000	0.333	1.000
hishave/artcncl	00:00:00.005	0.0%	21	0.000	0.238	1.000
hishave/artparse	00:00:00.000	0.0%	384	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:08.760	0.0%	553143	0.002	0.016	0.259
history sync	00:00:00.724	0.0%	369	0.000	1.962	9.333
history write	00:00:11.958	0.0%	18202	0.102	0.657	5.875
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.358	0.0%	384	0.000	0.932	15.000
idle	23:59:00.745	99.9%	526774	91.360	163.905	533.087
nntp read	00:00:01.902	0.0%	527568	0.000	0.004	0.007
overview write	00:00:29.156	0.0%	15545	0.133	1.876	111.179
perl filter	00:00:24.410	0.0%	18199	1.109	1.341	2.019
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.203	0.0%	31134	0.000	0.007	0.034
TOTAL: 24:00:58.811	24:00:26.138	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.037	0.0%	20724	0.000	0.002	0.026
article prepare	00:00:00.008	0.0%	38368	0.000	0.000	0.012
article read	00:00:04.475	0.0%	7291	0.000	0.614	2.200
backlog stats	00:00:00.214	0.0%	375542	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.295	0.0%	291876	0.001	0.004	0.010
data write	00:00:01.581	0.0%	280093	0.001	0.006	0.010
idle	23:54:54.356	100.0%	375542	111.969	229.254	682.608
status file	00:00:00.195	0.0%	264	0.000	0.739	2.000
TOTAL: 23:55:09.855	23:55:02.161	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	17:01:37.354	100.0%	424	325.333	144569.231	900184.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	926	0.000	0.008	0.167
readart	00:00:00.207	0.0%	21	0.000	9.857	42.000
TOTAL: 17:01:52.000	17:01:37.568	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	15028	4290	10646	92	28%	24:05:33
2	news-out.aioe.org	3	15247	3328	11840	79	21%	24:09:01
3	feeder.eternal-september.org	1	16782	1697	14670	415	10%	22:39:27
4	csiph-out.feeder.erje.net	1441	76119	1125	74555	439	1%	118:47:12
5	csiph-out.news.weretis.net	3	43882	975	42727	180	2%	61:53:25
6	newsfeed.neva.ru	144	18070	853	16955	262	4%	71:42:30
7	csiph-out.news.xs4all.nl	861	16846	825	15298	723	4%	71:27:43
8	newsfeed.fu-berlin.de	8	15261	710	14328	223	4%	24:29:58
9	news-out.mixmin.net	2	16375	704	15389	282	4%	23:59:47
10	news.freedyn.net	1	17958	339	17472	147	1%	23:59:57
11	news.fcku.it	13	1838	215	1619	4	11%	22:27:51
12	csiph-out.news.tnetconsulting.net	2	15915	152	15739	24	0%	23:59:53
13	csiph-out.feed.uzoreto.com	864	24081	148	23795	138	0%	71:12:29
14	news-out.neodome.net	1	16636	136	16477	23	0%	23:59:53
15	nntp.pasdenom.info	2	10798	33	10712	53	0%	23:58:35
16	news.swapon.de	8	1302	27	1274	1	2%	22:42:28
17	localhost	36	10	10	0	0	100%	00:00:04
18	news.samoylyk.net	2	15349	4	15344	1	0%	24:55:04
19	news.bbs.nz	3	3660	2	3658	0	0%	06:45:17
20	xanadu-bbs.net	37	297	0	297	0	0%	19:35:45
TOTAL: 20		3434	341454	15573	322795	3086	4%	706:51:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	13.6 MB	0.0 KB	4.9 MB	18.5 MB	73%	4.3 KB
2	news-out.aioe.org	8.1 MB	2.6 KB	221.8 KB	8.3 MB	97%	2.5 KB
3	csiph-out.feeder.erje.net	7.7 MB	4.0 KB	10.3 MB	18.1 MB	42%	11.8 KB
4	feeder.eternal-september.org	4.5 MB	4.1 KB	1.9 MB	6.5 MB	70%	3.1 KB
5	csiph-out.news.weretis.net	2.7 MB	3.2 KB	5.2 MB	7.9 MB	34%	7.0 KB
6	csiph-out.news.xs4all.nl	2.3 MB	1.5 KB	7.9 MB	10.2 MB	22%	6.8 KB
7	newsfeed.neva.ru	2.2 MB	0.0 KB	863.4 KB	3.0 MB	71%	2.8 KB
8	news-out.mixmin.net	1.8 MB	2.9 KB	783.9 KB	2.6 MB	70%	2.7 KB
9	newsfeed.fu-berlin.de	1.5 MB	0.0 KB	535.4 KB	2.1 MB	74%	2.3 KB
10	news.freedyn.net	1.3 MB	2.6 KB	834.4 KB	2.1 MB	61%	4.4 KB
11	csiph-out.feed.uzoreto.com	646.0 KB	3.0 KB	2.1 MB	2.8 MB	22%	10.0 KB
12	csiph-out.news.tnetconsulting.net	467.9 KB	0.0 KB	247.4 KB	715.2 KB	65%	4.1 KB
13	news.fcku.it	334.2 KB	0.0 KB	5.5 KB	339.7 KB	98%	1.6 KB
14	news-out.neodome.net	266.7 KB	4.4 KB	536.2 KB	807.3 KB	33%	5.1 KB
15	news.swapon.de	97.1 KB	1.5 KB	1.5 KB	100.1 KB	96%	3.6 KB
16	nntp.pasdenom.info	66.0 KB	0.0 KB	89.4 KB	155.4 KB	42%	1.8 KB
17	localhost	11.6 KB	0.0 KB	0.0 KB	11.6 KB	100%	1.2 KB
18	news.samoylyk.net	10.2 KB	1.5 KB	1.5 KB	13.3 KB	76%	2.7 KB
19	news.bbs.nz	3.4 KB	0.0 KB	0.0 KB	3.4 KB	100%	1.7 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		47.6 MB	31.4 KB	36.4 MB	84.1 MB	56%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 17 04:15:00 - 04:59:59	200	1.3%	0.07	620.0 KB	1.3%	0.23
Jan 17 05:00:00 - 05:59:59	276	1.8%	0.08	1019.9 KB	2.1%	0.28
Jan 17 06:00:00 - 06:59:59	492	3.2%	0.14	2.1 MB	4.4%	0.59
Jan 17 07:00:00 - 07:59:59	471	3.0%	0.13	1.9 MB	4.0%	0.54
Jan 17 08:00:00 - 08:59:59	658	4.2%	0.18	1.9 MB	4.1%	0.55
Jan 17 09:00:00 - 09:59:59	729	4.7%	0.20	2.1 MB	4.4%	0.60
Jan 17 10:00:00 - 10:59:59	724	4.7%	0.20	2.1 MB	4.3%	0.59
Jan 17 11:00:00 - 11:59:59	729	4.7%	0.20	2.3 MB	4.9%	0.67
Jan 17 12:00:00 - 12:59:59	754	4.9%	0.21	2.1 MB	4.5%	0.60
Jan 17 13:00:00 - 13:59:59	793	5.1%	0.22	2.0 MB	4.2%	0.57
Jan 17 14:00:00 - 14:59:59	917	5.9%	0.25	2.5 MB	5.2%	0.70
Jan 17 15:00:00 - 15:59:59	891	5.7%	0.25	3.0 MB	6.3%	0.86
Jan 17 16:00:00 - 16:59:59	843	5.4%	0.23	2.3 MB	4.8%	0.65
Jan 17 17:00:00 - 17:59:59	980	6.3%	0.27	3.1 MB	6.5%	0.88
Jan 17 18:00:00 - 18:59:59	840	5.4%	0.23	2.3 MB	4.9%	0.66
Jan 17 19:00:00 - 19:59:59	786	5.1%	0.22	2.4 MB	5.1%	0.69
Jan 17 20:00:00 - 20:59:59	729	4.7%	0.20	1.9 MB	4.1%	0.55
Jan 17 21:00:00 - 21:59:59	735	4.7%	0.20	2.3 MB	4.8%	0.65
Jan 17 22:00:00 - 22:59:59	742	4.8%	0.21	2.1 MB	4.5%	0.61
Jan 17 23:00:00 - 23:59:59	654	4.2%	0.18	2.2 MB	4.7%	0.63
Jan 18 00:00:00 - 00:59:59	463	3.0%	0.13	1.5 MB	3.1%	0.42
Jan 18 01:00:00 - 01:59:59	356	2.3%	0.10	1.2 MB	2.5%	0.34
Jan 18 02:00:00 - 02:59:59	388	2.5%	0.11	1.1 MB	2.3%	0.31
Jan 18 03:00:00 - 03:59:59	321	2.1%	0.09	1.3 MB	2.7%	0.37
Jan 18 04:00:00 - 04:15:00	68	0.4%	0.08	182.8 KB	0.4%	0.20
TOTAL: 24:00:00	15539	100.0%	0.18	47.5 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	726	43	1	682
2	newsfeed.fu-berlin.de	509	71	0	438
3	csiph-out.feeder.erje.net	438	64	3	371
4	feeder.eternal-september.org	414	20	3	391
5	news-out.mixmin.net	287	5	2	280
6	newsfeed.neva.ru	263	67	0	196
7	csiph-out.news.weretis.net	176	64	2	110
8	news-out.aioe.org	160	53	2	105
9	news.freedyn.net	142	5	2	135
10	csiph-out.feed.uzoreto.com	139	7	2	130
11	newsfeeds.xmission.com	95	7	0	88
12	nntp.pasdenom.info	53	0	0	53
13	csiph-out.news.tnetconsulting.net	25	23	1	1
14	news-out.neodome.net	23	0	3	20
15	news.swapon.de	4	0	1	3
16	news.fcku.it	4	4	0	0
17	news.samoylyk.net	2	0	1	1
TOTAL: 17		3460	433	23	3004

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	72
spline.eisfair	60
lada.talk	44
it-alt.sport.calcio.juventus	19
alt.windows7.general	14
alt.politics.usa	13
mozilla.support.thunderbird	13
alt.binaries.pictures.purebeauty	11
lada.photo	11
oesterreich.tratsch	8
mozilla.tools.socorro	7
alt.politics	7
alt.bittorrent	6
alt.music.pink-floyd.publius	6
alt.bbs.synchronet	5
argonet.cfc	5
mozilla.support.firefox	5
0.test	5
mozilla.dev.platform	4
spline.fli4l.dev	4
TOTAL: 95	433

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1550
EMP (phn path)	207
EMP (phn nph)	145
0	138
EMP (phl)	89
Too many newsgroups	33
HTML Multipart	26
Scoring filter	22
HTML Image Tags	2
Too many newsgroups (meow)	1
HTML post	1
Too many newsgroups (low_xpost)	1
TOTAL: 12	2215

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	47
	csiph-out.feed.uzoreto.com	20
	csiph-out.news.xs4all.nl	19
	TOTAL: 3	86
Huge articles
	csiph-out.feeder.erje.net	182
	newsfeeds.xmission.com	83
	csiph-out.news.xs4all.nl	38
	csiph-out.news.weretis.net	37
	csiph-out.feed.uzoreto.com	17
	news-out.neodome.net	10
	feeder.eternal-september.org	7
	news-out.mixmin.net	6
	news.freedyn.net	3
	csiph-out.news.tnetconsulting.net	1
	TOTAL: 10	384
Including strange strings
	newsfeed.fu-berlin.de	289
	news-out.aioe.org	80
	csiph-out.news.weretis.net	14
	news-out.mixmin.net	6
	newsfeeds.xmission.com	3
	news.swapon.de	3
	feeder.eternal-september.org	1
	csiph-out.news.xs4all.nl	1
	csiph-out.feeder.erje.net	1
	news.freedyn.net	1
	TOTAL: 12	401
No colon-space in header
	csiph-out.feeder.erje.net	3
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	4
TOTAL: 4		875

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.nz	34252	5239	14634	172	0	15%	23:59:23
2	xanadu-bbs	19452	1980	13556	2	0	10%	23:59:32
3	news.samoylyk.net	16152	538	14994	3	34	3%	23:59:54
4	news.freedyn.net	15509	524	14624	31	0	3%	23:59:54
5	fu-berlin.de	14690	512	14178	0	0	3%	23:59:54
6	usenet.pasdenom.info	15566	94	14833	46	20	0%	23:59:54
7	tnet	16232	83	14923	1	0	0%	23:59:55
8	newsfeed.xs4all.nl	15028	43	14532	3	102	0%	23:59:54
9	neva.ru	14074	37	13794	130	0	0%	23:59:54
10	aioe	11460	28	11012	0	0	0%	23:59:54
11	neodome.net	15916	22	15326	0	0	0%	23:59:54
12	erje	14736	14	14208	14	4	0%	23:59:54
13	news.mixmin.net	14653	11	14093	0	0	0%	23:59:54
14	news.uzoreto.com	15325	11	15034	15	0	0%	23:59:54
15	weretis.net	14259	10	14015	0	0	0%	23:59:55
16	news.swapon.de	1295	10	1274	0	0	0%	23:06:04
17	xmission	14449	10	10702	1	0	0%	23:59:55
18	eternal-september	13627	10	13102	0	0	0%	23:59:54
19	news.fcku.it	1525	0	1520	0	0	0%	21:58:08
TOTAL: 19		278200	9176	240354	418	160	3%	453:01:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.nz	15.3 MB	531.4 KB	15.9 MB	0.2 KB/s	3.0 KB	23:59:23
2	xanadu-bbs	10.3 MB	7.0 KB	10.3 MB	0.1 KB/s	5.3 KB	23:59:32
3	news.freedyn.net	6.2 MB	48.3 KB	6.3 MB	0.1 KB/s	11.6 KB	23:59:54
4	news.samoylyk.net	6.1 MB	3.4 KB	6.1 MB	0.1 KB/s	11.6 KB	23:59:54
5	fu-berlin.de	6.1 MB	0.0 KB	6.1 MB	0.1 KB/s	12.2 KB	23:59:54
6	usenet.pasdenom.info	305.8 KB	134.9 KB	440.7 KB	0.0 KB/s	3.1 KB	23:59:54
7	tnet	249.2 KB	3.6 KB	252.8 KB	0.0 KB/s	3.0 KB	23:59:55
8	newsfeed.xs4all.nl	158.8 KB	5.9 KB	164.7 KB	0.0 KB/s	3.6 KB	23:59:54
9	neva.ru	64.2 KB	291.3 KB	355.5 KB	0.0 KB/s	2.1 KB	23:59:54
10	neodome.net	62.1 KB	0.0 KB	62.1 KB	0.0 KB/s	2.8 KB	23:59:54
11	aioe	55.1 KB	0.0 KB	55.1 KB	0.0 KB/s	2.0 KB	23:59:54
12	erje	20.3 KB	27.6 KB	47.9 KB	0.0 KB/s	1.7 KB	23:59:54
13	news.uzoreto.com	17.4 KB	91.1 KB	108.5 KB	0.0 KB/s	4.2 KB	23:59:54
14	news.mixmin.net	16.2 KB	0.0 KB	16.2 KB	0.0 KB/s	1.5 KB	23:59:54
15	eternal-september	14.1 KB	0.0 KB	14.1 KB	0.0 KB/s	1.4 KB	23:59:54
16	weretis.net	11.6 KB	0.0 KB	11.6 KB	0.0 KB/s	1.2 KB	23:59:55
17	news.swapon.de	11.6 KB	0.0 KB	11.6 KB	0.0 KB/s	1.2 KB	23:06:04
18	xmission	11.6 KB	1.6 KB	13.2 KB	0.0 KB/s	1.2 KB	23:59:55
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:58:08
TOTAL: 19		45.1 MB	1.1 MB	46.2 MB	0.0 KB/s	4.9 KB	453:01:40

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	71-90-73-253.dhcp.ftbg.wi.charter.com	32	13	16.4 KB	11	10	10	08:54:12
2	a131033.upc-a.chello.nl	33	7	17.0 KB	3	0	0	05:15:25
3	208-71-239-109.cust.centrio.cz	1	1	1.7 KB	1	0	0	00:21:53
TOTAL: 3		66	21	35.1 KB	15	10	10	17:01:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.ftbg.wi.charter.com	32	13	16.4 KB	11	10	10	08:54:12
2	*.upc-a.chello.nl	33	7	17.0 KB	3	0	0	05:15:25
3	*.cust.centrio.cz	1	1	1.7 KB	1	0	0	00:21:53
4	unresolved	4	0	0.0 KB	0	0	0	00:30:04
5	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:15
TOTAL: 5		74	21	35.1 KB	15	10	10	17:01:51

NNRP auth users [Top 20]:

User	Conn
ohlandl	10
ctomczyk	1
TOTAL: 2	11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
71-90-73-253.dhcp.ftbg.wi.charter.com	2.478	0.537	0.026	08:54:12
a131033.upc-a.chello.nl	2.242	0.421	0.000	05:15:25
ip72-222-200-117.ph.ph.cox.net	0.408	0.081	0.000	02:00:15
60.190.248.13	0.066	0.007	0.000	00:30:00
208-71-239-109.cust.centrio.cz	0.104	0.017	0.002	00:21:53
94.163.228.46	0.067	0.008	0.000	00:00:01
94.161.247.55	0.043	0.034	0.000	00:00:01
94.160.140.148	0.053	0.023	0.000	00:00:01
TOTAL: 8	5.461	1.128	0.028	17:01:51

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
60.190.248.13	1
94.161.247.55	1
94.163.228.46	1
94.160.140.148	1
TOTAL: 5	8

NNRP unrecognized commands (by host):

System	Conn
60.190.248.13	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.0	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-90-73-253.dhcp.ftbg.wi.charter.com	10	0
ip72-222-200-117.ph.ph.cox.net	4	0
60.190.248.13	1	0
TOTAL: 3	15	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	20
2	comp.lang.javascript	1
TOTAL: 2		21

Daily Usenet report for csiph.com

Jan 17 04:15:00 -- Jan 18 04:15:00