Daily Usenet report for csiph.com: Apr 26 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	27421	59.0%	6.0 MB	72.6%
innd	10220	22.0%	1.3 MB	15.5%
nnrpd	5875	12.6%	503.5 KB	5.9%
innfeed	2841	6.1%	497.6 KB	5.9%
nocem	135	0.3%	14.7 KB	0.2%
TOTAL: 5	46492	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	328579	56.9%
Negative hits	222450	38.5%
Do not exist	26214	4.5%
Cache misses	399	0.1%
TOTAL: 4	577642	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.526	0.0%	332	0.000	25.681	730.000
article cleanup	00:00:00.185	0.0%	26263	0.000	0.007	0.029
article logging	00:00:10.547	0.0%	26940	0.000	0.391	7.708
article parse	00:00:03.677	0.0%	65928	0.000	0.056	1.337
article write	00:06:24.798	0.4%	21342	1.243	18.030	124.520
artlog/artcncl	00:00:00.002	0.0%	322	0.000	0.006	0.500
artlog/artparse	00:00:00.794	0.0%	192	0.000	4.135	34.435
data move	00:00:00.152	0.0%	546227	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.450	0.0%	330	0.000	1.364	19.000
hishave/artcncl	00:00:00.013	0.0%	332	0.000	0.039	1.000
hishave/artparse	00:00:00.001	0.0%	192	0.000	0.005	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:04.876	0.1%	580415	0.007	0.215	1.850
history sync	00:00:05.153	0.0%	372	0.000	13.852	255.667
history write	00:03:25.693	0.2%	26169	0.375	7.860	42.485
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:02.040	0.0%	192	0.000	10.625	124.500
idle	23:24:57.118	97.4%	519589	64.581	162.238	470.343
nntp read	00:00:02.652	0.0%	552077	0.002	0.005	0.008
overview write	00:22:58.372	1.6%	21342	0.826	64.585	2228.885
perl filter	00:00:53.814	0.1%	26160	1.420	2.057	3.568
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.404	0.0%	42815	0.000	0.009	0.029
TOTAL: 24:03:01.139	24:01:19.267	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.058	0.0%	21290	0.000	0.003	0.022
article prepare	00:00:00.005	0.0%	35602	0.000	0.000	0.005
article read	00:00:26.015	0.0%	6601	0.034	3.941	26.059
backlog stats	00:00:00.255	0.0%	386564	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.063	0.0%	302100	0.002	0.007	0.017
data write	00:00:01.724	0.0%	285082	0.000	0.006	0.012
idle	23:54:02.508	99.9%	386564	105.287	222.583	680.801
status file	00:00:01.794	0.0%	279	0.000	6.430	218.000
TOTAL: 23:54:49.248	23:54:34.422	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	15:19:40.356	99.9%	5711	171.500	9662.118	600097.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.091	0.0%	11725	0.000	0.008	0.167
readart	00:00:00.137	0.0%	25	0.000	5.480	60.000
TOTAL: 15:20:12.740	15:19:40.584	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	6	43918	7521	35855	542	17%	48:53:25
2	csiph-out.feeder.erje.net	1151	88927	2835	84503	1589	3%	95:07:57
3	feeder.eternal-september.org	8	23269	2494	20323	452	10%	24:57:15
4	newsfeed.neva.ru	140	26984	2123	23263	1598	7%	71:28:32
5	news-out.aioe.org	4	21154	1887	19164	103	8%	24:09:38
6	news-out.mixmin.net	3	21998	1392	20257	349	6%	24:08:39
7	newsfeed.fu-berlin.de	22	19253	1300	17834	119	6%	26:17:59
8	news.redatomik.org	1	16432	631	15790	11	3%	19:07:51
9	news.fcku.it	2	9176	617	8336	223	6%	23:59:44
10	newsfeeds.xmission.com	2	18334	239	18079	16	1%	19:24:26
11	news.swapon.de	6	1778	76	1699	3	4%	23:32:17
12	nntp.pasdenom.info	3	19651	55	19589	7	0%	23:58:12
13	news.bbs.geek.nz	2	17995	26	17969	0	0%	24:01:32
14	news.freedyn.net	1	1347	7	1339	1	0%	02:22:25
15	xanadu-bbs.net	17	635	6	627	2	0%	22:41:54
16	localhost	36	5	5	0	0	100%	00:00:00
TOTAL: 16		1404	330856	21214	304627	5015	6%	474:11:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	22.9 MB	39.5 KB	7.8 MB	30.7 MB	74%	3.9 KB
2	csiph-out.feeder.erje.net	14.0 MB	178.6 KB	8.0 MB	22.2 MB	63%	5.1 KB
3	newsfeed.neva.ru	7.1 MB	4.4 KB	5.1 MB	12.3 MB	58%	3.4 KB
4	feeder.eternal-september.org	6.8 MB	179.2 KB	1.3 MB	8.4 MB	81%	2.9 KB
5	news-out.aioe.org	4.5 MB	42.2 KB	678.5 KB	5.2 MB	86%	2.7 KB
6	news-out.mixmin.net	4.0 MB	0.0 KB	636.4 KB	4.6 MB	86%	2.7 KB
7	newsfeed.fu-berlin.de	3.3 MB	0.0 KB	244.6 KB	3.5 MB	93%	2.5 KB
8	news.redatomik.org	1.5 MB	16.7 KB	87.9 KB	1.6 MB	93%	2.5 KB
9	news.fcku.it	1.2 MB	0.0 KB	875.8 KB	2.1 MB	58%	2.5 KB
10	newsfeeds.xmission.com	658.2 KB	58.0 KB	202.7 KB	918.9 KB	71%	3.6 KB
11	news.swapon.de	212.1 KB	0.0 KB	5.5 KB	217.6 KB	97%	2.8 KB
12	nntp.pasdenom.info	149.2 KB	0.0 KB	22.0 KB	171.2 KB	87%	2.8 KB
13	news.bbs.geek.nz	110.2 KB	0.0 KB	0.0 KB	110.2 KB	100%	4.2 KB
14	news.freedyn.net	15.3 KB	0.0 KB	1.3 KB	16.5 KB	92%	2.1 KB
15	xanadu-bbs.net	12.6 KB	0.0 KB	5.5 KB	18.1 KB	69%	2.3 KB
16	localhost	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	1.5 KB
TOTAL: 16		66.4 MB	518.5 KB	24.9 MB	91.8 MB	72%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 26 04:15:01 - 04:59:59	396	1.9%	0.15	1.8 MB	2.7%	0.68
Apr 26 05:00:00 - 05:59:59	876	4.1%	0.24	4.0 MB	6.0%	1.14
Apr 26 06:00:00 - 06:59:59	1396	6.5%	0.39	5.8 MB	8.6%	1.64
Apr 26 07:00:00 - 07:59:59	916	4.3%	0.25	2.7 MB	4.0%	0.76
Apr 26 08:00:00 - 08:59:59	951	4.5%	0.26	2.6 MB	3.9%	0.74
Apr 26 09:00:00 - 09:59:59	1043	4.9%	0.29	2.9 MB	4.4%	0.83
Apr 26 10:00:00 - 10:59:59	959	4.5%	0.27	2.5 MB	3.7%	0.71
Apr 26 11:00:00 - 11:59:59	872	4.1%	0.24	2.3 MB	3.5%	0.66
Apr 26 12:00:00 - 12:59:59	1065	5.0%	0.30	3.2 MB	4.8%	0.90
Apr 26 13:00:00 - 13:59:59	1075	5.0%	0.30	3.0 MB	4.4%	0.84
Apr 26 14:00:00 - 14:59:59	1284	6.0%	0.36	4.2 MB	6.4%	1.21
Apr 26 15:00:00 - 15:59:59	1272	6.0%	0.35	4.1 MB	6.2%	1.17
Apr 26 16:00:00 - 16:59:59	1084	5.1%	0.30	2.9 MB	4.3%	0.82
Apr 26 17:00:00 - 17:59:59	1190	5.6%	0.33	3.5 MB	5.2%	0.99
Apr 26 18:00:00 - 18:59:59	1094	5.1%	0.30	2.9 MB	4.3%	0.82
Apr 26 19:00:00 - 19:59:59	967	4.5%	0.27	2.9 MB	4.3%	0.82
Apr 26 20:00:00 - 20:59:59	898	4.2%	0.25	2.7 MB	4.0%	0.76
Apr 26 21:00:00 - 21:59:59	799	3.7%	0.22	2.5 MB	3.8%	0.72
Apr 26 22:00:00 - 22:59:59	655	3.1%	0.18	2.3 MB	3.4%	0.65
Apr 26 23:00:00 - 23:59:59	654	3.1%	0.18	2.4 MB	3.6%	0.68
Apr 27 00:00:00 - 00:59:59	453	2.1%	0.13	1.4 MB	2.1%	0.40
Apr 27 01:00:00 - 01:59:59	481	2.3%	0.13	1.2 MB	1.9%	0.35
Apr 27 02:00:00 - 02:59:59	461	2.2%	0.13	1.5 MB	2.3%	0.44
Apr 27 03:00:00 - 03:59:59	381	1.8%	0.11	1.2 MB	1.9%	0.35
Apr 27 04:00:00 - 04:15:00	100	0.5%	0.11	268.2 KB	0.4%	0.30
TOTAL: 23:59:59	21322	100.0%	0.25	66.8 MB	100.0%	0.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	1617	225	1	1	1390
2	csiph-out.feeder.erje.net	1602	195	33	0	1374
3	newsfeed.fu-berlin.de	679	19	0	0	660
4	csiph-out.news.weretis.net	534	306	7	0	221
5	feeder.eternal-september.org	468	27	28	0	413
6	news-out.mixmin.net	366	1	0	0	365
7	news.fcku.it	240	2	0	0	238
8	news-out.aioe.org	112	96	5	0	11
9	news.freedyn.net	69	2	3	0	64
10	news.redatomik.org	41	0	4	0	37
11	news.swapon.de	25	0	0	0	25
12	newsfeeds.xmission.com	18	0	13	0	5
13	nntp.pasdenom.info	7	7	0	0	0
14	xanadu-bbs.net	2	0	0	0	2
TOTAL: 14		5780	880	94	1	4805

Unwanted newsgroups [Top 20]:

Newsgroup	Count
gmane.linux.alsa.devel	96
lada.talk	83
mpc.lists.freebsd.ports.bugs	52
lada.auto	49
it.annunci.immobiliari	44
it.annunci.commerciali	43
it.annunci.varie	43
gmane.linux.kernel	42
it-alt.sport.calcio.juventus	41
lada.commerce	26
alt.bbs.synchronet	24
gmane.comp.gis.openstreetmap.tagging	22
alt.politics	20
spline.eisfair	18
mozilla.support.seamonkey	16
gmane.comp.gis.openstreetmap	15
oesterreich.tratsch	14
alt.windows7.general	14
lada.photo	12
free.it.religioni.scientology	10
TOTAL: 112	880

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
it.scienza.astronomia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3541
EMP (phn path)	181
Too many newsgroups	49
EMP (phn nph)	39
HTML Multipart	27
Scoring filter (8)	17
Scoring filter (9)	16
EMP (phl)	15
Binary: misplaced par2	10
User-issued cancel	10
Scoring filter (10)	8
Binary Payload	7
Scoring filter (11)	5
HTML post	2
User-issued spam cancel	1
Scoring filter (15)	1
Scoring filter (12)	1
Too many newsgroups (meow)	1
Binary Image: misplaced jpg	1
Scoring filter (13)	1
TOTAL: 20	3933

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	104	0	106	106
TOTAL: 1	104	27	106	106

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	9
	TOTAL: 1	9
Huge articles
	csiph-out.news.weretis.net	97
	csiph-out.feeder.erje.net	62
	news.freedyn.net	24
	newsfeeds.xmission.com	5
	news.fcku.it	2
	feeder.eternal-september.org	2
	news.redatomik.org	1
	TOTAL: 7	193
Including strange strings
	newsfeed.fu-berlin.de	559
	news.redatomik.org	29
	news.swapon.de	22
	news-out.mixmin.net	20
	newsfeed.neva.ru	17
	csiph-out.feeder.erje.net	11
	news-out.aioe.org	8
	csiph-out.news.weretis.net	3
	news.fcku.it	1
	TOTAL: 9	670
TOTAL: 3		872

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	33807	5207	15449	15	3	0	15%	23:51:18
2	xanadu-bbs	23416	707	20608	0	0	0	3%	23:59:32
3	news.freedyn.net	22289	660	20589	0	0	0	2%	23:57:05
4	fu-berlin.de	19734	557	19177	0	0	0	2%	23:53:13
5	usenet.pasdenom.info	24147	122	19823	1323	0	2	0%	23:59:47
6	aioe	18363	43	17980	1	0	0	0%	23:51:12
7	erje	18441	24	18312	0	0	0	0%	23:51:19
8	weretis.net	12633	21	12200	10	0	0	0%	23:50:01
9	news.redatomik.org	20224	19	19941	0	0	0	0%	23:54:33
10	xmission	24024	16	20368	0	0	0	0%	23:59:51
11	neva.ru	18768	12	17985	132	0	1	0%	23:59:53
12	news.swapon.de	1749	10	1726	0	0	0	0%	23:49:59
13	news.mixmin.net	19338	8	18937	1	0	1	0%	23:59:45
14	eternal-september	18396	5	18027	0	0	1	0%	23:59:53
15	news.fcku.it	1762	0	1743	0	0	0	0%	20:48:05
TOTAL: 15		277091	7411	242865	1482	3	5	2%	355:45:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	15.1 MB	110.9 KB	15.2 MB	0.2 KB/s	3.0 KB	23:51:18
2	xanadu-bbs	6.9 MB	0.0 KB	6.9 MB	0.1 KB/s	10.0 KB	23:59:32
3	news.freedyn.net	6.8 MB	0.0 KB	6.8 MB	0.1 KB/s	10.5 KB	23:57:05
4	fu-berlin.de	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	11.9 KB	23:53:13
5	usenet.pasdenom.info	342.9 KB	7.9 MB	8.2 MB	0.1 KB/s	5.8 KB	23:59:47
6	aioe	96.8 KB	4.4 KB	101.2 KB	0.0 KB/s	2.3 KB	23:51:12
7	erje	83.7 KB	0.0 KB	83.7 KB	0.0 KB/s	3.5 KB	23:51:19
8	news.redatomik.org	41.1 KB	0.0 KB	41.1 KB	0.0 KB/s	2.2 KB	23:54:33
9	xmission	39.8 KB	0.0 KB	39.8 KB	0.0 KB/s	2.5 KB	23:59:51
10	weretis.net	37.0 KB	29.0 KB	66.0 KB	0.0 KB/s	2.1 KB	23:50:01
11	neva.ru	24.6 KB	279.0 KB	303.6 KB	0.0 KB/s	2.1 KB	23:59:53
12	news.swapon.de	16.0 KB	0.0 KB	16.0 KB	0.0 KB/s	1.6 KB	23:49:59
13	news.mixmin.net	15.6 KB	1.2 KB	16.8 KB	0.0 KB/s	1.9 KB	23:59:45
14	eternal-september	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	1.5 KB	23:59:53
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:48:05
TOTAL: 15		35.9 MB	8.3 MB	44.2 MB	0.0 KB/s	5.1 KB	355:45:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	194-73-239-109.cust.centrio.cz	4	8	22.3 KB	2	0	0	00:15:27
2	96-42-18-226.dhcp.mdsn.wi.charter.com	17	7	11.2 KB	7	5	5	05:32:03
3	141.168.117.108	47	7	11.2 KB	6	0	0	00:41:30
4	a131033.upc-a.chello.nl	28	3	3.5 KB	2	0	0	04:48:08
TOTAL: 4		96	25	48.1 KB	17	5	5	15:20:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cust.centrio.cz	4	8	22.3 KB	2	0	0	00:15:27
2	unresolved	51	7	11.2 KB	6	0	0	00:41:48
3	*.dhcp.mdsn.wi.charter.com	17	7	11.2 KB	7	5	5	05:32:03
4	*.upc-a.chello.nl	28	3	3.5 KB	2	0	0	04:48:08
5	*.172.90.9.176.clients.your-server.de	1	0	0.0 KB	0	0	0	00:00:01
6	*.ph.ph.cox.net	13	0	0.0 KB	0	0	0	03:32:36
7	*.nielsenmedia.com	1	0	0.0 KB	0	0	0	00:30:06
TOTAL: 7		115	25	48.1 KB	17	5	5	15:20:11

NNRP auth users [Top 20]:

User	Conn
ohlandl	5
ctomczyk	4
TOTAL: 2	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
96-42-18-226.dhcp.mdsn.wi.charter.com	1.656	0.256	0.028	05:32:03
a131033.upc-a.chello.nl	2.047	0.518	0.000	04:48:08
ip72-222-200-117.ph.ph.cox.net	0.890	0.227	0.000	03:32:36
141.168.117.108	4.962	1.035	0.000	00:41:30
challenger.nielsenmedia.com	0.062	0.008	0.000	00:30:06
194-73-239-109.cust.centrio.cz	0.268	0.069	0.013	00:15:27
107.170.253.95	0.092	0.008	0.000	00:00:09
94.163.86.175	0.087	0.014	0.000	00:00:03
94.163.165.137	0.071	0.031	0.000	00:00:02
94.162.10.161	0.068	0.038	0.000	00:00:02
static.172.90.9.176.clients.your-server.de	0.083	0.015	0.000	00:00:01
TOTAL: 11	10.286	2.219	0.041	15:20:11

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	13
94.163.86.175	1
107.170.253.95	1
94.162.10.161	1
challenger.nielsenmedia.com	1
94.163.165.137	1
static.172.90.9.176.clients.your-server.de	1
TOTAL: 7	19

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-18-226.dhcp.mdsn.wi.charter.com	6	0
ip72-222-200-117.ph.ph.cox.net	5	0
challenger.nielsenmedia.com	1	0
TOTAL: 3	12	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	17
2	comp.lang.javascript	8
TOTAL: 2		25

Daily Usenet report for csiph.com

Apr 26 04:15:01 -- Apr 27 04:15:00