Daily Usenet report for csiph.com: Feb 16 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43153	60.6%	8.1 MB	72.0%
innd	23615	33.2%	2.5 MB	22.3%
innfeed	3194	4.5%	527.8 KB	4.6%
nnrpd	1159	1.6%	130.7 KB	1.1%
nocem	88	0.1%	9.5 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	71210	100.0%	11.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	428452	56.3%
Negative hits	289880	38.1%
Do not exist	41958	5.5%
Cache misses	696	0.1%
TOTAL: 4	760986	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.908	0.0%	277	0.000	3.278	38.000
article cleanup	00:00:00.278	0.0%	42357	0.000	0.007	0.026
article logging	00:00:04.560	0.0%	42846	0.000	0.106	1.287
article parse	00:00:02.765	0.0%	138749	0.000	0.020	0.342
article write	00:02:56.833	0.2%	23851	0.184	7.414	38.286
artlog/artcncl	00:00:00.009	0.0%	229	0.000	0.039	0.235
artlog/artparse	00:00:00.046	0.0%	150	0.000	0.307	3.714
data move	00:00:00.473	0.0%	689612	0.000	0.001	0.046
hisgrep/artcncl	00:00:00.225	0.0%	275	0.000	0.818	30.000
hishave/artcncl	00:00:00.078	0.0%	277	0.000	0.282	2.000
hishave/artparse	00:00:00.002	0.0%	150	0.000	0.013	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:03.431	0.1%	764528	0.002	0.161	2.187
history sync	00:00:05.388	0.0%	360	0.000	14.967	97.000
history write	00:03:49.555	0.3%	42099	0.508	5.453	43.714
hiswrite/artcncl	00:00:00.012	0.0%	2	0.000	6.000	12.000
hiswrite/artparse	00:00:01.138	0.0%	149	0.000	7.638	47.000
idle	23:44:36.867	98.9%	675660	69.970	126.509	543.156
nntp read	00:00:03.279	0.0%	705736	0.002	0.005	0.009
overview write	00:04:04.549	0.3%	23851	1.251	10.253	91.614
perl filter	00:01:48.008	0.1%	41919	1.353	2.577	4.762
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.439	0.0%	47779	0.000	0.009	0.025
TOTAL: 24:00:55.191	23:59:38.843	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.068	0.0%	24002	0.000	0.003	0.049
article prepare	00:00:00.005	0.0%	47088	0.000	0.000	0.005
article read	00:00:02.188	0.0%	9108	0.026	0.240	12.750
backlog stats	00:00:00.280	0.0%	444022	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.299	0.0%	340213	0.002	0.007	0.017
data write	00:00:02.008	0.0%	323475	0.002	0.006	0.012
idle	23:53:36.481	100.0%	444022	124.325	193.721	537.706
status file	00:00:00.347	0.0%	295	0.000	1.176	21.000
TOTAL: 23:53:52.576	23:53:43.676	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.211	0.0%	1059	0.000	0.199	25.000
idle	73:21:53.300	99.8%	1585	1205.143	166632.997	900201.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.014	0.0%	3254	0.000	0.004	0.250
readart	00:00:00.387	0.0%	25	0.000	15.480	61.000
TOTAL: 73:30:58.302	73:21:53.912	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	134220	7299	116149	10772	5%	94:58:08
2	csiph-out.news.weretis.net	5	52746	4865	47040	841	9%	51:40:28
3	feeder.eternal-september.org	5	37321	3432	29980	3909	9%	23:40:32
4	news-out.aioe.org	3	22974	2318	20634	22	10%	24:06:39
5	newsfeed.neva.ru	145	54566	1429	49492	3645	2%	71:45:07
6	newsfeed.fu-berlin.de	42	23878	1160	22188	530	4%	30:39:01
7	news.redatomik.org	1	25294	1084	24202	8	4%	26:59:57
8	news-out.mixmin.net	4	26007	941	24845	221	3%	24:11:53
9	newsfeeds.xmission.com	3	24080	792	23276	12	3%	27:14:00
10	nntp.pasdenom.info	3	22926	142	22750	34	0%	24:08:26
11	news.swapon.de	2	4560	126	4418	16	2%	46:16:07
12	news.fcku.it	1	10216	105	10043	68	1%	23:59:57
13	news.freedyn.net	2	26486	94	26216	176	0%	23:59:51
14	xanadu-bbs.net	7	1020	63	955	2	6%	24:59:49
15	news.bbs.geek.nz	2	18134	34	18100	0	0%	23:59:31
16	localhost	45	9	9	0	0	100%	00:00:00
TOTAL: 16		1422	484437	23893	440288	20256	4%	542:39:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	21.9 MB	2.2 MB	60.3 MB	84.3 MB	25%	4.8 KB
2	csiph-out.news.weretis.net	14.9 MB	8.8 KB	14.2 MB	29.1 MB	51%	5.2 KB
3	feeder.eternal-september.org	8.7 MB	451.8 KB	49.7 MB	58.8 MB	14%	8.2 KB
4	news-out.aioe.org	5.8 MB	0.0 KB	70.0 KB	5.9 MB	98%	2.6 KB
5	news-out.mixmin.net	4.6 MB	12.8 KB	827.2 KB	5.4 MB	84%	4.8 KB
6	newsfeed.neva.ru	3.5 MB	419.1 KB	36.1 MB	40.0 MB	8%	8.1 KB
7	news.redatomik.org	3.1 MB	0.0 KB	71.9 KB	3.2 MB	97%	3.0 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	1.6 MB	4.1 MB	61%	2.5 KB
9	newsfeeds.xmission.com	2.3 MB	3.3 KB	246.1 KB	2.5 MB	90%	3.2 KB
10	nntp.pasdenom.info	404.1 KB	7.7 KB	84.0 KB	495.8 KB	81%	2.8 KB
11	news.freedyn.net	315.4 KB	4.1 KB	1.8 MB	2.1 MB	14%	7.9 KB
12	news.swapon.de	308.8 KB	0.0 KB	173.9 KB	482.7 KB	63%	3.4 KB
13	news.fcku.it	247.3 KB	0.0 KB	714.9 KB	962.2 KB	25%	5.6 KB
14	xanadu-bbs.net	183.5 KB	0.0 KB	8.7 KB	192.2 KB	95%	3.0 KB
15	news.bbs.geek.nz	78.7 KB	0.0 KB	0.0 KB	78.7 KB	100%	2.3 KB
16	localhost	15.3 KB	0.0 KB	0.0 KB	15.3 KB	100%	1.7 KB
TOTAL: 16		68.8 MB	3.1 MB	165.7 MB	237.6 MB	28%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 16 04:15:00 - 04:59:59	411	1.7%	0.15	1.2 MB	1.8%	0.46
Feb 16 05:00:00 - 05:59:59	594	2.5%	0.17	2.4 MB	3.5%	0.69
Feb 16 06:00:00 - 06:59:59	807	3.4%	0.22	2.9 MB	4.3%	0.83
Feb 16 07:00:00 - 07:59:59	755	3.2%	0.21	2.1 MB	3.0%	0.58
Feb 16 08:00:00 - 08:59:59	1017	4.3%	0.28	2.7 MB	3.9%	0.76
Feb 16 09:00:00 - 09:59:59	1057	4.4%	0.29	2.9 MB	4.2%	0.82
Feb 16 10:00:00 - 10:59:59	1183	5.0%	0.33	2.9 MB	4.3%	0.84
Feb 16 11:00:00 - 11:59:59	976	4.1%	0.27	2.6 MB	3.8%	0.74
Feb 16 12:00:00 - 12:59:59	917	3.8%	0.25	2.4 MB	3.5%	0.68
Feb 16 13:00:00 - 13:59:59	1219	5.1%	0.34	3.8 MB	5.6%	1.08
Feb 16 14:00:00 - 14:59:59	1255	5.3%	0.35	3.2 MB	4.7%	0.92
Feb 16 15:00:00 - 15:59:59	1207	5.1%	0.34	3.3 MB	4.8%	0.93
Feb 16 16:00:00 - 16:59:59	1414	5.9%	0.39	4.8 MB	7.0%	1.37
Feb 16 17:00:00 - 17:59:59	1249	5.2%	0.35	3.3 MB	4.8%	0.94
Feb 16 18:00:00 - 18:59:59	1453	6.1%	0.40	4.3 MB	6.2%	1.21
Feb 16 19:00:00 - 19:59:59	1371	5.8%	0.38	3.9 MB	5.7%	1.11
Feb 16 20:00:00 - 20:59:59	1258	5.3%	0.35	3.4 MB	4.9%	0.95
Feb 16 21:00:00 - 21:59:59	1160	4.9%	0.32	3.0 MB	4.3%	0.84
Feb 16 22:00:00 - 22:59:59	1193	5.0%	0.33	3.3 MB	4.8%	0.94
Feb 16 23:00:00 - 23:59:59	891	3.7%	0.25	2.7 MB	4.0%	0.77
Feb 17 00:00:00 - 00:59:59	711	3.0%	0.20	2.0 MB	3.0%	0.58
Feb 17 01:00:00 - 01:59:59	658	2.8%	0.18	2.0 MB	2.9%	0.57
Feb 17 02:00:00 - 02:59:59	521	2.2%	0.14	1.6 MB	2.4%	0.46
Feb 17 03:00:00 - 03:59:59	432	1.8%	0.12	1.5 MB	2.2%	0.43
Feb 17 04:00:00 - 04:15:00	117	0.5%	0.13	386.2 KB	0.5%	0.43
TOTAL: 24:00:00	23826	100.0%	0.28	68.6 MB	100.0%	0.81

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	9229	360	170	8699
2	feeder.eternal-september.org	3931	49	36	3846
3	newsfeed.neva.ru	3696	200	39	3457
4	newsfeed.fu-berlin.de	840	29	0	811
5	csiph-out.news.weretis.net	801	158	4	639
6	news-out.mixmin.net	227	1	2	224
7	news.freedyn.net	175	11	2	162
8	news.fcku.it	69	0	0	69
9	nntp.pasdenom.info	35	14	4	17
10	news-out.aioe.org	34	17	0	17
11	newsfeeds.xmission.com	18	7	1	10
12	news.redatomik.org	17	0	0	17
13	news.swapon.de	16	0	0	16
14	xanadu-bbs.net	5	0	0	5
15	news.bbs.geek.nz	1	0	0	1
TOTAL: 15		19094	846	258	17990

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	93
lada.auto	68
lada.talk	44
mozilla.dev.platform	43
it-alt.sport.calcio.juventus	42
gmane.linux.alsa.devel	42
it.annunci.commerciali	39
it.annunci.immobiliari	38
it.annunci.varie	34
alt.windows7.general	29
mpc.lists.freebsd.ports.bugs	28
alt.politics.usa	26
gmane.comp.gis.openstreetmap.tagging	24
lada.kitchen	14
cakewalk.audio	12
mozilla.dev.mdc	10
spline.eisfair	10
spline.fli4l.dev	10
mozilla.support.firefox	9
alt.politics.scorched-earth	9
TOTAL: 116	846

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14869
EMP (phn path)	1565
EMP (phl)	451
Too many newsgroups	100
EMP (phn nph)	40
User-issued cancel	32
HTML Multipart	21
Scoring filter (10)	13
Scoring filter (14)	12
Binary Payload	12
User-issued spam cancel	11
Scoring filter (8)	8
Scoring filter (16)	7
Scoring filter (9)	7
Scoring filter (19)	5
Scoring filter (11)	5
Scoring filter (12)	4
HTML post	3
Scoring filter (17)	2
Scoring filter (13)	2
TOTAL: 22	17172

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	79	0	88	88
TOTAL: 1	79	0	88	88

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	180
	TOTAL: 1	180
Huge articles
	csiph-out.news.weretis.net	82
	feeder.eternal-september.org	40
	news-out.mixmin.net	9
	news.freedyn.net	7
	newsfeed.fu-berlin.de	3
	newsfeeds.xmission.com	3
	news.redatomik.org	3
	xanadu-bbs.net	2
	news.fcku.it	1
	TOTAL: 9	150
Including strange strings
	newsfeed.fu-berlin.de	356
	csiph-out.feeder.erje.net	52
	feeder.eternal-september.org	20
	news-out.aioe.org	11
	csiph-out.news.weretis.net	11
	news-out.mixmin.net	8
	news.swapon.de	8
	news.redatomik.org	7
	newsfeeds.xmission.com	7
	newsfeed.neva.ru	6
	TOTAL: 12	488
TOTAL: 3		818

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37989	7575	15463	115	1	19%	23:55:58
2	news.freedyn.net	24980	940	22731	4	2	3%	23:50:04
3	xanadu-bbs	26428	800	22871	3	0	3%	23:59:56
4	fu-berlin.de	22356	573	21783	0	0	2%	23:50:04
5	usenet.pasdenom.info	26685	234	22251	1200	5	0%	23:59:56
6	xmission	26366	88	22322	7	146	0%	23:59:56
7	news.redatomik.org	23230	43	22045	12	0	0%	23:50:04
8	aioe	20877	34	19441	4	45	0%	23:51:39
9	weretis.net	17380	26	16614	2	0	0%	23:56:47
10	eternal-september	21870	23	19394	1	0	0%	23:59:56
11	news.mixmin.net	22732	14	21364	0	0	0%	23:50:04
12	news.swapon.de	2194	10	2078	0	0	0%	23:54:45
13	neva.ru	22675	8	21280	26	0	0%	23:59:56
14	news.fcku.it	3078	6	2884	0	0	0%	23:01:28
15	erje	16437	2	16353	35	2	0%	23:55:40
16	au2pb	0	0	0	0	23153	0%	23:50:09
17	bwh	0	0	0	0	23141	0%	23:50:08
TOTAL: 17		315277	10376	268874	1409	46495	3%	405:36:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	20.0 MB	703.6 KB	20.7 MB	0.2 KB/s	2.8 KB	23:55:58
2	news.freedyn.net	5.8 MB	8.1 KB	5.8 MB	0.1 KB/s	6.3 KB	23:50:04
3	xanadu-bbs	5.4 MB	10.3 KB	5.4 MB	0.1 KB/s	6.9 KB	23:59:56
4	fu-berlin.de	4.9 MB	0.0 KB	4.9 MB	0.1 KB/s	8.7 KB	23:50:04
5	usenet.pasdenom.info	557.0 KB	5.9 MB	6.5 MB	0.1 KB/s	4.6 KB	23:59:56
6	xmission	202.2 KB	10.0 KB	212.2 KB	0.0 KB/s	2.2 KB	23:59:56
7	news.redatomik.org	99.3 KB	33.5 KB	132.8 KB	0.0 KB/s	2.4 KB	23:50:04
8	aioe	74.4 KB	14.8 KB	89.2 KB	0.0 KB/s	2.3 KB	23:51:39
9	weretis.net	60.8 KB	5.7 KB	66.5 KB	0.0 KB/s	2.4 KB	23:56:47
10	eternal-september	48.7 KB	21.1 KB	69.7 KB	0.0 KB/s	2.9 KB	23:59:56
11	news.mixmin.net	25.8 KB	0.0 KB	25.8 KB	0.0 KB/s	1.8 KB	23:50:04
12	news.swapon.de	17.1 KB	0.0 KB	17.1 KB	0.0 KB/s	1.7 KB	23:54:45
13	neva.ru	13.5 KB	75.2 KB	88.6 KB	0.0 KB/s	2.6 KB	23:59:56
14	news.fcku.it	9.2 KB	0.0 KB	9.2 KB	0.0 KB/s	1.5 KB	23:01:28
15	erje	3.3 KB	135.9 KB	139.2 KB	0.0 KB/s	3.8 KB	23:55:40
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:08
TOTAL: 17		37.2 MB	6.9 MB	44.1 MB	0.0 KB/s	3.8 KB	405:36:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	16	11	17.9 KB	2	0	0	04:35:40
2	68-185-181-64.dhcp.mdsn.wi.charter.com	19	10	17.0 KB	6	9	8	04:46:59
3	194-73-239-109.cust.centrio.cz	6	2	3.7 KB	2	0	0	02:30:12
4	dhcp-089-099-203-254.chello.nl	44	2	3.1 KB	2	0	0	12:07:24
TOTAL: 4		85	25	41.8 KB	12	9	8	73:30:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	16	11	17.9 KB	2	0	0	04:35:40
2	*.dhcp.mdsn.wi.charter.com	19	10	17.0 KB	6	9	8	04:46:59
3	*.cust.centrio.cz	6	2	3.7 KB	2	0	0	02:30:12
4	*.chello.nl	44	2	3.1 KB	2	0	0	12:07:24
5	unresolved	99	0	0.0 KB	0	0	0	49:30:39
TOTAL: 5		184	25	41.8 KB	12	9	8	73:30:56

NNRP auth users [Top 20]:

User	Conn
ohlandl	8
ctomczyk	5
TOTAL: 2	13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.298	1.605	0.000	48:26:48
dhcp-089-099-203-254.chello.nl	3.514	0.624	0.000	12:07:24
68-185-181-64.dhcp.mdsn.wi.charter.com	1.805	0.373	0.028	04:46:59
tempe0.bbox.io	1.380	0.236	0.000	04:35:40
194-73-239-109.cust.centrio.cz	0.664	0.124	0.024	02:30:12
50.96.157.204	0.080	0.107	0.000	01:03:50
TOTAL: 6	14.741	3.069	0.052	73:30:56

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	98
50.96.157.204	1
TOTAL: 2	99

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	95	0
tempe0.bbox.io	9	0
194-73-239-109.cust.centrio.cz	5	0
68-185-181-64.dhcp.mdsn.wi.charter.com	2	0
TOTAL: 4	111	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	24	96.0%
2	pl	1	4.0%
TOTAL: 2		25	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	23
2	pl.comp.lang.javascript	1
3	comp.lang.javascript	1
TOTAL: 3		25

Daily Usenet report for csiph.com

Feb 16 04:15:00 -- Feb 17 04:15:00