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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	25226	52.2%	5.3 MB	66.2%
innd	13664	28.3%	1.6 MB	20.3%
nnrpd	6061	12.5%	517.3 KB	6.4%
innfeed	3287	6.8%	567.6 KB	7.0%
nocem	70	0.1%	7.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	48309	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	262376	54.0%
Negative hits	198838	40.9%
Do not exist	24513	5.0%
Cache misses	270	0.1%
TOTAL: 4	485997	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.249	0.0%	209	0.000	1.191	5.923
article cleanup	00:00:00.199	0.0%	23775	0.000	0.008	0.034
article logging	00:00:00.435	0.0%	24348	0.000	0.018	0.204
article parse	00:00:03.340	0.0%	72982	0.000	0.046	0.355
article write	00:00:18.488	0.0%	16543	0.171	1.118	15.169
artlog/artcncl	00:00:00.003	0.0%	135	0.000	0.022	0.500
artlog/artparse	00:00:00.014	0.0%	743	0.000	0.019	0.333
data move	00:00:00.175	0.0%	476320	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.083	0.0%	201	0.000	0.413	3.400
hishave/artcncl	00:00:00.036	0.0%	209	0.000	0.172	2.462
hishave/artparse	00:00:00.002	0.0%	743	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:33.718	0.0%	485285	0.005	0.069	0.573
history sync	00:00:01.095	0.0%	374	0.000	2.928	15.000
history write	00:00:46.635	0.1%	23768	0.208	1.962	11.212
hiswrite/artcncl	00:00:00.004	0.0%	8	0.000	0.500	2.000
hiswrite/artparse	00:00:02.312	0.0%	743	0.000	3.112	17.462
idle	23:56:17.169	99.7%	481459	104.500	178.992	809.885
nntp read	00:00:02.319	0.0%	485520	0.001	0.005	0.009
overview write	00:01:30.449	0.1%	16543	0.440	5.468	1257.738
perl filter	00:00:42.350	0.0%	23759	1.333	1.782	2.590
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.302	0.0%	33149	0.000	0.009	0.057
TOTAL: 24:00:54.347	24:00:19.377	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	16503	0.000	0.002	0.018
article prepare	00:00:00.002	0.0%	18044	0.000	0.000	0.006
article read	00:00:00.418	0.0%	2702	0.000	0.155	3.167
backlog stats	00:00:00.158	0.0%	302235	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.581	0.0%	233650	0.002	0.007	0.014
data write	00:00:01.285	0.0%	219751	0.001	0.006	0.011
idle	23:54:02.379	100.0%	302235	170.277	284.687	844.035
status file	00:00:00.250	0.0%	295	0.000	0.847	3.000
TOTAL: 23:54:12.409	23:54:06.107	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	21:08:15.164	100.0%	6105	222.857	12464.400	601576.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.881	0.0%	12898	0.000	0.146	14.350
readart	00:00:00.257	0.0%	179	0.000	1.436	60.000
TOTAL: 21:08:52.707	21:08:17.302	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	6	33389	4331	28557	501	12%	47:38:37
2	csiph-out.feeder.erje.net	1152	72611	2652	67171	2788	3%	95:02:53
3	feeder.eternal-september.org	4	20002	2032	16906	1064	10%	24:37:38
4	newsfeeds.xmission.com	3	15703	1734	13967	2	11%	24:18:51
5	news-out.aioe.org	2	15691	1431	14252	8	9%	24:09:03
6	newsfeed.neva.ru	152	21815	1035	18031	2749	4%	72:00:26
7	newsfeed.fu-berlin.de	8	15822	1022	14704	96	6%	25:43:22
8	news-out.mixmin.net	1	17645	916	16254	475	5%	23:59:51
9	news.redatomik.org	1	13785	586	13192	7	4%	22:36:47
10	news.fcku.it	2	8585	437	8022	126	5%	32:45:35
11	news.freedyn.net	1	16912	142	16672	98	0%	24:00:11
12	news.swapon.de	10	1384	33	1351	0	2%	23:39:58
13	nntp.pasdenom.info	2	15392	24	15366	2	0%	23:59:17
14	news.bbs.geek.nz	1	12760	6	12754	0	0%	23:59:51
15	xanadu-bbs.net	21	568	2	566	0	0%	22:02:47
16	localhost	34	1	1	0	0	100%	00:00:05
TOTAL: 16		1400	282065	16384	257765	7916	5%	510:35:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	13.4 MB	0.0 KB	14.5 MB	28.0 MB	48%	5.9 KB
2	csiph-out.feeder.erje.net	10.9 MB	55.5 KB	33.5 MB	44.4 MB	24%	8.4 KB
3	feeder.eternal-september.org	5.3 MB	0.0 KB	4.4 MB	9.6 MB	54%	3.2 KB
4	newsfeeds.xmission.com	4.8 MB	0.0 KB	72.0 KB	4.9 MB	98%	2.9 KB
5	news-out.aioe.org	3.6 MB	0.0 KB	24.2 KB	3.6 MB	99%	2.6 KB
6	news-out.mixmin.net	3.4 MB	0.0 KB	1.1 MB	4.5 MB	75%	3.3 KB
7	newsfeed.neva.ru	2.4 MB	109.1 KB	22.4 MB	24.9 MB	9%	6.7 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	139.0 KB	2.4 MB	94%	2.2 KB
9	news.redatomik.org	1.4 MB	0.0 KB	13.5 KB	1.4 MB	99%	2.5 KB
10	news.fcku.it	1.0 MB	0.0 KB	1.1 MB	2.2 MB	47%	3.9 KB
11	news.freedyn.net	418.6 KB	0.0 KB	4.6 MB	5.1 MB	8%	21.6 KB
12	news.swapon.de	78.3 KB	0.0 KB	0.0 KB	78.3 KB	100%	2.4 KB
13	nntp.pasdenom.info	48.1 KB	0.0 KB	4.1 KB	52.3 KB	92%	2.0 KB
14	news.bbs.geek.nz	11.4 KB	0.0 KB	0.0 KB	11.4 KB	100%	1.9 KB
15	xanadu-bbs.net	5.9 KB	0.0 KB	0.0 KB	5.9 KB	100%	2.9 KB
16	localhost	1.0 KB	0.0 KB	0.0 KB	1.0 KB	100%	1.0 KB
TOTAL: 16		49.0 MB	164.6 KB	81.9 MB	131.0 MB	37%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 26 04:15:00 - 04:59:59	176	1.1%	0.07	552.0 KB	1.1%	0.20
Dec 26 05:00:00 - 05:59:59	338	2.0%	0.09	1.1 MB	2.2%	0.30
Dec 26 06:00:00 - 06:59:59	284	1.7%	0.08	834.2 KB	1.7%	0.23
Dec 26 07:00:00 - 07:59:59	475	2.9%	0.13	1.6 MB	3.3%	0.46
Dec 26 08:00:00 - 08:59:59	562	3.4%	0.16	1.4 MB	2.9%	0.41
Dec 26 09:00:00 - 09:59:59	680	4.1%	0.19	1.9 MB	3.8%	0.54
Dec 26 10:00:00 - 10:59:59	840	5.1%	0.23	2.3 MB	4.7%	0.65
Dec 26 11:00:00 - 11:59:59	840	5.1%	0.23	3.2 MB	6.5%	0.92
Dec 26 12:00:00 - 12:59:59	717	4.3%	0.20	1.8 MB	3.7%	0.52
Dec 26 13:00:00 - 13:59:59	695	4.2%	0.19	1.9 MB	3.9%	0.54
Dec 26 14:00:00 - 14:59:59	790	4.8%	0.22	2.3 MB	4.6%	0.64
Dec 26 15:00:00 - 15:59:59	949	5.7%	0.26	3.2 MB	6.5%	0.91
Dec 26 16:00:00 - 16:59:59	839	5.1%	0.23	2.4 MB	4.8%	0.68
Dec 26 17:00:00 - 17:59:59	989	6.0%	0.27	2.7 MB	5.5%	0.77
Dec 26 18:00:00 - 18:59:59	1048	6.3%	0.29	3.0 MB	6.1%	0.85
Dec 26 19:00:00 - 19:59:59	971	5.9%	0.27	3.0 MB	6.0%	0.85
Dec 26 20:00:00 - 20:59:59	925	5.6%	0.26	2.4 MB	4.9%	0.69
Dec 26 21:00:00 - 21:59:59	910	5.5%	0.25	2.7 MB	5.5%	0.77
Dec 26 22:00:00 - 22:59:59	743	4.5%	0.21	2.1 MB	4.3%	0.61
Dec 26 23:00:00 - 23:59:59	681	4.1%	0.19	2.6 MB	5.2%	0.73
Dec 27 00:00:00 - 00:59:59	634	3.8%	0.18	2.0 MB	4.0%	0.56
Dec 27 01:00:00 - 01:59:59	562	3.4%	0.16	1.7 MB	3.4%	0.48
Dec 27 02:00:00 - 02:59:59	400	2.4%	0.11	1.1 MB	2.2%	0.31
Dec 27 03:00:00 - 03:59:59	396	2.4%	0.11	1.3 MB	2.7%	0.38
Dec 27 04:00:00 - 04:15:00	98	0.6%	0.11	337.3 KB	0.7%	0.37
TOTAL: 24:00:00	16542	100.0%	0.19	49.3 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	2843	82	3	2758
2	newsfeed.neva.ru	2762	145	4	2613
3	feeder.eternal-september.org	1101	36	0	1065
4	csiph-out.news.weretis.net	569	168	0	401
5	newsfeed.fu-berlin.de	509	12	0	497
6	news-out.mixmin.net	493	1	0	492
7	news.freedyn.net	131	7	0	124
8	news.fcku.it	100	0	0	100
9	news.redatomik.org	20	0	0	20
10	news-out.aioe.org	11	6	0	5
11	news.swapon.de	6	0	0	6
12	newsfeeds.xmission.com	2	1	0	1
13	nntp.pasdenom.info	2	0	0	2
TOTAL: 13		8549	458	7	8084

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	108
alt.survival	49
mpc.lists.freebsd.ports.bugs	40
mozilla.general	24
lada.auto	15
alt.windows7.general	13
alt.religion	12
mozilla.support.seamonkey	9
alt.philosophy	8
mpc.lists.freebsd.x11	8
gmane.linux.kernel.clk	7
cakewalk.audio	7
lada.soft	7
mpc.lists.nanog	7
oesterreich.tratsch	6
south-wales.test	6
mozilla.support.thunderbird	6
alt.politics.usa	6
gmane.comp.gis.openstreetmap	5
lada.hard	4
TOTAL: 88	458

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5643
Binary: misplaced rar	485
EMP (phn path)	353
EMP (phn nph)	107
Too many newsgroups	46
Binary Payload	20
Scoring filter (12)	16
Scoring filter (11)	14
HTML Multipart	12
Scoring filter (10)	11
User-issued cancel	11
Scoring filter (14)	7
EMP (phl)	5
Scoring filter (8)	5
Scoring filter (9)	5
HTML post	4
Poison newsgroup	3
User-issued spam cancel	3
Scoring filter (13)	3
Binary: misplaced PAR2	2
TOTAL: 28	6764

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	63	0	78	78
TOTAL: 1	63	0	78	78

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.feeder.erje.net	515
	csiph-out.news.weretis.net	167
	news.freedyn.net	31
	feeder.eternal-september.org	16
	news.fcku.it	9
	news-out.mixmin.net	4
	newsfeeds.xmission.com	1
	TOTAL: 7	743
Including strange strings
	newsfeed.fu-berlin.de	416
	csiph-out.feeder.erje.net	52
	csiph-out.news.weretis.net	51
	news-out.mixmin.net	16
	news.redatomik.org	14
	news.fcku.it	6
	news.swapon.de	6
	feeder.eternal-september.org	4
	news-out.aioe.org	2
	newsfeed.neva.ru	1
	TOTAL: 10	568
TOTAL: 2		1311

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	30343	1046	14965	54	4	3%	24:09:41
2	news.freedyn.net	16987	613	15732	2	0	3%	23:59:47
3	xanadu-bbs	18510	594	15955	0	2	3%	24:00:47
4	fu-berlin.de	15312	477	14835	0	0	3%	24:00:22
5	usenet.pasdenom.info	17176	66	14976	1531	0	0%	24:09:04
6	aioe	13990	42	13511	0	0	0%	24:09:44
7	erje	14089	17	13732	6	0	0%	24:00:31
8	neva.ru	15110	7	14743	62	0	0%	24:02:47
9	news.redatomik.org	16011	6	15438	0	0	0%	24:03:31
10	weretis.net	11366	2	10925	0	1	0%	24:05:00
11	news.swapon.de	1444	1	1428	0	0	0%	23:30:21
12	eternal-september	14192	1	13818	0	0	0%	24:00:22
13	xmission	15605	1	14241	0	1	0%	24:09:32
14	news.mixmin.net	15107	1	14843	0	0	0%	24:08:27
15	bwh	0	0	0	0	16053	0%	24:07:15
16	news.fcku.it	955	0	954	0	0	0%	21:48:23
17	au2pb	0	0	0	0	16061	0%	24:07:19
TOTAL: 17		216197	2874	190096	1655	32122	1%	406:32:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	5.4 MB	10.5 KB	5.4 MB	0.1 KB/s	9.0 KB	23:59:47
2	xanadu-bbs	5.3 MB	0.0 KB	5.3 MB	0.1 KB/s	9.2 KB	24:00:47
3	fu-berlin.de	5.1 MB	0.0 KB	5.1 MB	0.1 KB/s	10.9 KB	24:00:22
4	news.bbs.geek.nz	3.0 MB	232.2 KB	3.3 MB	0.0 KB/s	3.0 KB	24:09:41
5	usenet.pasdenom.info	154.1 KB	6.8 MB	6.9 MB	0.1 KB/s	4.4 KB	24:09:04
6	aioe	94.4 KB	0.0 KB	94.4 KB	0.0 KB/s	2.2 KB	24:09:44
7	erje	37.6 KB	17.7 KB	55.3 KB	0.0 KB/s	2.4 KB	24:00:31
8	news.redatomik.org	14.1 KB	0.0 KB	14.1 KB	0.0 KB/s	2.3 KB	24:03:31
9	neva.ru	12.9 KB	163.9 KB	176.8 KB	0.0 KB/s	2.6 KB	24:02:47
10	weretis.net	2.6 KB	0.0 KB	2.6 KB	0.0 KB/s	1.3 KB	24:05:00
11	news.swapon.de	1.0 KB	0.0 KB	1.0 KB	0.0 KB/s	1.0 KB	23:30:21
12	eternal-september	1.0 KB	0.0 KB	1.0 KB	0.0 KB/s	1.0 KB	24:00:22
13	xmission	1.0 KB	0.0 KB	1.0 KB	0.0 KB/s	1.0 KB	24:09:32
14	news.mixmin.net	1.0 KB	0.0 KB	1.0 KB	0.0 KB/s	1.0 KB	24:08:27
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:07:15
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:48:23
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:07:19
TOTAL: 17		19.1 MB	7.2 MB	26.3 MB	0.0 KB/s	5.9 KB	406:32:53

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	48	154	621.1 KB	84	0	0	00:42:48
2	tempe0.bbox.io	7	9	15.5 KB	3	1	1	04:17:07
3	96-42-18-226.dhcp.mdsn.wi.charter.com	7	7	12.9 KB	2	0	0	02:53:23
4	a131033.upc-a.chello.nl	40	7	12.9 KB	5	0	0	11:42:12
5	p4fc5eb2f.dip0.t-ipconnect.de	1	2	3.6 KB	2	0	0	00:00:05
TOTAL: 5		103	179	666.1 KB	96	1	1	21:08:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	49	154	621.1 KB	84	0	0	00:42:58
2	*.bbox.io	7	9	15.5 KB	3	1	1	04:17:07
3	*.dhcp.mdsn.wi.charter.com	7	7	12.9 KB	2	0	0	02:53:23
4	*.upc-a.chello.nl	40	7	12.9 KB	5	0	0	11:42:12
5	*.dip0.t-ipconnect.de	1	2	3.6 KB	2	0	0	00:00:05
6	*.nielsenmedia.com	2	0	0.0 KB	0	0	0	00:36:56
7	*.cust.centrio.cz	3	0	0.0 KB	0	0	0	00:56:06
TOTAL: 7		109	179	666.1 KB	96	1	1	21:08:51

NNRP auth users [Top 20]:

User	Conn
moussa	48
ctomczyk	3
kev009	1
TOTAL: 3	52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.351	0.629	0.000	11:42:12
tempe0.bbox.io	0.804	0.180	0.003	04:17:07
96-42-18-226.dhcp.mdsn.wi.charter.com	0.605	0.089	0.000	02:53:23
194-73-239-109.cust.centrio.cz	0.190	0.040	0.010	00:56:06
141.168.117.108	5.351	1.173	0.165	00:42:48
challenger.nielsenmedia.com	0.174	0.030	0.000	00:36:56
104.236.187.81	0.053	0.009	0.000	00:00:09
p4fc5eb2f.dip0.t-ipconnect.de	0.151	0.068	0.000	00:00:05
TOTAL: 8	10.679	2.218	0.178	21:08:51

Curious NNRP server explorers [Top 20]:

System	Conn
194-73-239-109.cust.centrio.cz	3
challenger.nielsenmedia.com	2
104.236.187.81	1
TOTAL: 3	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
96-42-18-226.dhcp.mdsn.wi.charter.com	5	0
challenger.nielsenmedia.com	1	0
TOTAL: 3	13	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	107	59.8%
2	alt	71	39.7%
3	de	1	0.6%
TOTAL: 3		179	100.0%

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

Newsgroup		Count
1	alt.comp.freeware	70
2	comp.arch	30
3	comp.sys.ibm.ps2.hardware	29
4	comp.ai.philosophy	24
5	comp.os.vms	20
6	comp.os.cpm	2
7	alt.free.newsservers	1
8	comp.os.msdos.desqview	1
9	de.alt.folklore.computer	1
10	comp.ai.neural-nets	1
TOTAL: 10		179

Daily Usenet report for csiph.com

Dec 26 04:15:00 -- Dec 27 04:15:00