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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 10 14:25:04 csiph controlchan[29150]: Cannot send mail: (<checkgroups-1549808348@news.novabbs.com>)

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	47110	57.2%	5.6 MB	46.7%
inn	20769	25.2%	4.5 MB	37.1%
innd	11575	14.0%	1.5 MB	12.1%
innfeed	2876	3.5%	499.8 KB	4.0%
nocem	49	0.1%	5.4 KB	0.0%
controlchan	12	0.0%	2.5 KB	0.0%
TOTAL: 6	82391	100.0%	12.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	282933	56.4%
Negative hits	198582	39.6%
Do not exist	20037	4.0%
Cache misses	288	0.1%
TOTAL: 4	501840	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.175	0.0%	99	0.000	1.768	17.000
article cleanup	00:00:00.130	0.0%	19740	0.000	0.007	0.027
article logging	00:00:00.316	0.0%	20321	0.000	0.016	0.049
article parse	00:00:01.415	0.0%	62367	0.000	0.023	0.253
article write	00:00:46.223	0.1%	16012	0.173	2.887	37.311
artlog/artcncl	00:00:00.001	0.0%	49	0.000	0.020	1.000
artlog/artparse	00:00:00.001	0.0%	406	0.000	0.002	0.500
data move	00:00:00.111	0.0%	487110	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.006	0.0%	92	0.000	0.065	2.000
hishave/artcncl	00:00:00.002	0.0%	99	0.000	0.020	1.000
hishave/artparse	00:00:00.000	0.0%	405	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:21.850	0.0%	503848	0.000	0.043	0.382
history sync	00:00:00.602	0.0%	371	0.000	1.623	8.000
history write	00:00:43.665	0.1%	19728	0.061	2.213	12.286
hiswrite/artcncl	00:00:00.004	0.0%	7	0.000	0.571	1.500
hiswrite/artparse	00:00:00.848	0.0%	399	0.000	2.125	18.000
idle	23:56:48.198	99.7%	494677	97.144	174.272	499.618
nntp read	00:00:02.058	0.0%	493495	0.001	0.004	0.010
overview write	00:00:39.254	0.0%	16012	0.158	2.452	23.365
perl filter	00:00:34.079	0.0%	19664	1.117	1.733	5.142
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.262	0.0%	32070	0.000	0.008	0.030
TOTAL: 24:00:50.252	23:59:59.200	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.025	0.0%	16017	0.000	0.002	0.018
article prepare	00:00:00.001	0.0%	17100	0.000	0.000	0.004
article read	00:00:01.077	0.0%	1970	0.000	0.547	10.300
backlog stats	00:00:00.175	0.0%	319716	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.224	0.0%	236078	0.000	0.005	0.011
data write	00:00:01.470	0.0%	221849	0.001	0.007	0.017
idle	23:54:50.475	100.0%	319716	158.168	269.272	771.574
status file	00:00:00.196	0.0%	257	0.000	0.763	19.000
TOTAL: 23:55:00.450	23:54:54.643	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:48.850	0.0%	591927	0.000	0.083	8.605
idle	194:25:16.157	99.8%	4456610	62.500	157.051	600083.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:57.585	0.0%	11619850	0.000	0.005	3.700
readart	00:18:46.528	0.2%	3810338	0.000	0.296	136.000
TOTAL: 194:53:51.817	194:45:49.120	99.9%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
usenet@novabbs.com	0	0	1	0	0	1
TOTAL	0	0	1	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	7	48787	3446	44982	359	7%	72:09:14
2	csiph-out.feeder.erje.net	1557	88888	3090	84799	999	3%	127:25:10
3	news-out.mixmin.net	3	17466	2178	14119	1169	12%	24:11:43
4	feeder.eternal-september.org	4	17732	1739	15789	204	9%	23:20:15
5	news-out.aioe.org	3	15489	1461	14007	21	9%	24:09:55
6	news.redatomik.org	3	15455	1244	14164	47	8%	24:02:43
7	newsfeed.fu-berlin.de	7	17722	1165	16418	139	6%	28:50:31
8	newsfeeds.xmission.com	2	13098	761	12241	96	5%	20:13:48
9	newsfeed.neva.ru	151	18990	528	17571	891	2%	72:22:49
10	news.freedyn.net	2	17427	204	17028	195	1%	23:59:47
11	news.fcku.it	10	1554	101	1453	0	6%	21:21:06
12	news.swapon.de	7	1269	35	1234	0	2%	21:54:52
13	nntp.pasdenom.info	2	13470	5	13451	14	0%	23:58:49
14	localhost	34	3	3	0	0	100%	00:00:00
15	news.bbs.nz	1	15220	1	15219	0	0%	23:59:51
16	xanadu-bbs.net	33	419	1	418	0	0%	21:21:31
TOTAL: 16		1826	302989	15962	282893	4134	5%	553:22:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	13.2 MB	44.8 KB	10.9 MB	24.1 MB	54%	6.0 KB
2	csiph-out.news.weretis.net	11.4 MB	18.0 KB	7.0 MB	18.4 MB	61%	4.9 KB
3	news-out.mixmin.net	6.1 MB	3.5 KB	2.5 MB	8.5 MB	71%	2.6 KB
4	feeder.eternal-september.org	4.9 MB	3.6 KB	5.3 MB	10.2 MB	47%	5.4 KB
5	news.redatomik.org	3.9 MB	0.0 KB	1.1 MB	5.0 MB	78%	4.0 KB
6	news-out.aioe.org	3.7 MB	16.4 KB	116.4 KB	3.9 MB	96%	2.7 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	407.4 KB	3.2 MB	87%	2.5 KB
8	newsfeeds.xmission.com	2.7 MB	0.0 KB	8.0 MB	10.7 MB	25%	12.8 KB
9	newsfeed.neva.ru	1.5 MB	25.1 KB	3.1 MB	4.6 MB	31%	3.3 KB
10	news.freedyn.net	756.0 KB	0.0 KB	700.9 KB	1.4 MB	51%	3.7 KB
11	news.fcku.it	195.2 KB	0.0 KB	0.0 KB	195.2 KB	100%	1.9 KB
12	news.swapon.de	94.6 KB	0.0 KB	0.0 KB	94.6 KB	100%	2.7 KB
13	nntp.pasdenom.info	10.4 KB	0.0 KB	30.1 KB	40.5 KB	25%	2.1 KB
14	localhost	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.2 KB
15	xanadu-bbs.net	2.2 KB	0.0 KB	0.0 KB	2.2 KB	100%	2.2 KB
16	news.bbs.nz	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
TOTAL: 16		51.3 MB	111.3 KB	39.1 MB	90.5 MB	56%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 10 04:15:00 - 04:59:59	285	1.8%	0.11	930.2 KB	1.8%	0.34
Feb 10 05:00:00 - 05:59:59	409	2.6%	0.11	1.6 MB	3.1%	0.46
Feb 10 06:00:00 - 06:59:59	306	1.9%	0.09	1.1 MB	2.1%	0.31
Feb 10 07:00:00 - 07:59:59	374	2.3%	0.10	1.5 MB	3.0%	0.44
Feb 10 08:00:00 - 08:59:59	514	3.2%	0.14	1.4 MB	2.6%	0.38
Feb 10 09:00:00 - 09:59:59	696	4.3%	0.19	2.2 MB	4.2%	0.62
Feb 10 10:00:00 - 10:59:59	783	4.9%	0.22	2.5 MB	4.9%	0.71
Feb 10 11:00:00 - 11:59:59	654	4.1%	0.18	2.0 MB	3.9%	0.57
Feb 10 12:00:00 - 12:59:59	746	4.7%	0.21	2.4 MB	4.7%	0.69
Feb 10 13:00:00 - 13:59:59	731	4.6%	0.20	2.0 MB	3.9%	0.57
Feb 10 14:00:00 - 14:59:59	768	4.8%	0.21	2.4 MB	4.6%	0.68
Feb 10 15:00:00 - 15:59:59	890	5.6%	0.25	3.3 MB	6.4%	0.93
Feb 10 16:00:00 - 16:59:59	843	5.3%	0.23	2.5 MB	4.9%	0.72
Feb 10 17:00:00 - 17:59:59	880	5.5%	0.24	2.5 MB	4.8%	0.71
Feb 10 18:00:00 - 18:59:59	879	5.5%	0.24	2.5 MB	4.9%	0.72
Feb 10 19:00:00 - 19:59:59	1018	6.4%	0.28	3.4 MB	6.6%	0.96
Feb 10 20:00:00 - 20:59:59	804	5.0%	0.22	2.3 MB	4.5%	0.66
Feb 10 21:00:00 - 21:59:59	912	5.7%	0.25	2.8 MB	5.4%	0.80
Feb 10 22:00:00 - 22:59:59	786	4.9%	0.22	2.4 MB	4.7%	0.69
Feb 10 23:00:00 - 23:59:59	729	4.6%	0.20	2.6 MB	5.0%	0.74
Feb 11 00:00:00 - 00:59:59	595	3.7%	0.17	2.1 MB	4.0%	0.58
Feb 11 01:00:00 - 01:59:59	462	2.9%	0.13	1.4 MB	2.7%	0.40
Feb 11 02:00:00 - 02:59:59	421	2.6%	0.12	1.4 MB	2.8%	0.41
Feb 11 03:00:00 - 03:59:59	418	2.6%	0.12	1.9 MB	3.8%	0.55
Feb 11 04:00:00 - 04:15:00	109	0.7%	0.12	375.3 KB	0.7%	0.42
TOTAL: 24:00:00	16012	100.0%	0.19	51.5 MB	100.0%	0.61

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news-out.mixmin.net	1197	0	2	1195
2	csiph-out.feeder.erje.net	1005	86	2	917
3	newsfeed.neva.ru	890	80	1	809
4	newsfeed.fu-berlin.de	634	33	0	601
5	csiph-out.news.weretis.net	393	142	2	249
6	feeder.eternal-september.org	204	20	2	182
7	news.freedyn.net	197	5	0	192
8	newsfeeds.xmission.com	85	0	0	85
9	news.redatomik.org	57	0	0	57
10	news-out.aioe.org	24	14	4	6
11	nntp.pasdenom.info	14	0	0	14
12	news.swapon.de	8	0	0	8
TOTAL: 12		4708	380	13	4315

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	65
gmane.comp.gis.openstreetmap.tagging	34
spline.eisfair	34
alt.music.pink-floyd.publius	30
alt.windows7.general	21
mozilla.support.seamonkey	10
mozilla.support.firefox	10
mozilla.support.thunderbird	9
oesterreich.tratsch	9
alt.philosophy	8
alt.politics	8
fido7.ru.android	6
alt.binaries.ebooks	6
spline.fli4l	5
alt.slack.goathead	5
rocksolid.shared.test	5
rocksolid.feeds.indymedia.argentina	5
alt.survival	4
it-alt.sport.calcio.juventus	4
alt.binaries.newstar	3
TOTAL: 98	380

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2702
EMP (phn path)	229
EMP (phn nph)	151
HTML Multipart	69
Too many newsgroups	49
User-issued spam cancel	14
Binary: misplaced rar	12
User-issued cancel	11
Binary Payload	10
Scoring filter (10)	7
Scoring filter (9)	4
Cancel for rejected article	3
Angle-bracket bot	2
Scoring filter (11)	1
HTML post	1
HTML Image Tags	1
Scoring filter (16)	1
TOTAL: 17	3267

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
Bad Message-ID's offered
	csiph-out.feeder.erje.net	56
	TOTAL: 1	56
Bad command received
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
Huge articles
	csiph-out.feeder.erje.net	150
	csiph-out.news.weretis.net	91
	newsfeeds.xmission.com	83
	feeder.eternal-september.org	54
	news.redatomik.org	11
	news-out.mixmin.net	9
	news.freedyn.net	5
	newsfeed.fu-berlin.de	1
	TOTAL: 8	404
Including strange strings
	newsfeed.fu-berlin.de	516
	csiph-out.news.weretis.net	19
	news-out.mixmin.net	14
	news.redatomik.org	10
	news.swapon.de	8
	csiph-out.feeder.erje.net	7
	feeder.eternal-september.org	3
	news-out.aioe.org	2
	newsfeed.neva.ru	1
	TOTAL: 9	580
TOTAL: 5		1043

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	16120	738	15058	9	0	4%	23:59:56
2	xanadu-bbs	18810	733	15278	0	0	3%	23:59:46
3	fu-berlin.de	14769	523	14246	0	0	3%	23:59:41
4	erje	14392	260	12545	41	19	1%	23:59:56
5	news.bbs.nz	30200	242	15209	7	0	0%	23:59:56
6	usenet.pasdenom.info	17051	151	14553	1306	8	0%	23:59:56
7	aioe	13778	73	13022	0	0	0%	23:59:34
8	news.mixmin.net	13663	8	13104	0	0	0%	23:59:56
9	xmission	18431	7	14591	1	0	0%	23:59:39
10	neva.ru	14981	4	14688	162	0	0%	23:59:56
11	news.swapon.de	1347	4	1328	0	0	0%	23:45:37
12	news.redatomik.org	14941	4	14193	0	5	0%	23:59:56
13	eternal-september	15028	3	13524	0	0	0%	23:59:39
14	weretis.net	12958	3	11917	0	0	0%	23:59:43
15	news.fcku.it	1408	0	1391	0	0	0%	22:07:32
TOTAL: 15		217877	2753	184647	1526	32	1%	357:50:43

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	9.1 KB	23:59:46
2	news.freedyn.net	6.5 MB	48.6 KB	6.5 MB	0.1 KB/s	8.9 KB	23:59:56
3	fu-berlin.de	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	11.5 KB	23:59:41
4	erje	911.8 KB	362.8 KB	1.2 MB	0.0 KB/s	4.2 KB	23:59:56
5	news.bbs.nz	658.2 KB	28.1 KB	686.4 KB	0.0 KB/s	2.8 KB	23:59:56
6	usenet.pasdenom.info	511.7 KB	7.1 MB	7.6 MB	0.1 KB/s	5.3 KB	23:59:56
7	aioe	172.0 KB	0.0 KB	172.0 KB	0.0 KB/s	2.4 KB	23:59:34
8	neva.ru	21.2 KB	435.4 KB	456.6 KB	0.0 KB/s	2.8 KB	23:59:56
9	news.mixmin.net	17.6 KB	0.0 KB	17.6 KB	0.0 KB/s	2.2 KB	23:59:56
10	xmission	15.9 KB	5.1 KB	21.1 KB	0.0 KB/s	2.6 KB	23:59:39
11	news.redatomik.org	5.4 KB	0.0 KB	5.4 KB	0.0 KB/s	1.4 KB	23:59:56
12	news.swapon.de	5.2 KB	0.0 KB	5.2 KB	0.0 KB/s	1.3 KB	23:45:37
13	eternal-september	3.5 KB	0.0 KB	3.5 KB	0.0 KB/s	1.2 KB	23:59:39
14	weretis.net	3.5 KB	0.0 KB	3.5 KB	0.0 KB/s	1.2 KB	23:59:43
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:07:32
TOTAL: 15		21.1 MB	8.0 MB	29.1 MB	0.0 KB/s	7.0 KB	357:50:43

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	bras-vprn-mtrlpq2320w-lp140-01-70-27-192-142.dsl.bell.ca	3570	1352341	3.6 GB	19500	0	0	144:55:58
2	mtrlpq2320w-lp130-01-184-146-239-88.dsl.bell.ca	139	42	102.9 KB	12	0	0	00:15:35
3	ip72-222-200-117.ph.ph.cox.net	7	17	35.2 KB	3	0	0	02:38:30
4	68-114-222-192.dhcp.mdsn.wi.charter.com	19	7	10.4 KB	4	3	3	05:45:46
5	a131033.upc-a.chello.nl	23	5	6.5 KB	3	0	0	04:55:21
TOTAL: 5		3758	1352412	3.6 GB	19522	3	3	194:52:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	3709	1352383	3.6 GB	19512	0	0	145:11:34
2	*.ph.ph.cox.net	7	17	35.2 KB	3	0	0	02:38:30
3	*.dhcp.mdsn.wi.charter.com	19	7	10.4 KB	4	3	3	05:45:46
4	*.upc-a.chello.nl	23	5	6.5 KB	3	0	0	04:55:21
5	*.dhcp.chtrptr.net	60	0	0.0 KB	0	0	0	36:21:14
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
7	unresolved	2	0	0.0 KB	0	0	0	00:00:10
TOTAL: 7		3821	1352412	3.6 GB	19522	3	3	194:52:39

NNRP auth users [Top 20]:

User	Conn
ohlandl	3
TOTAL: 1	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
bras-vprn-mtrlpq2320w-lp140-01-70-27-192-142.dsl.bell.ca	461.820	329.328	0.000	144:55:58
172-221-041-102.dhcp.chtrptr.net	16.964	43.763	0.000	36:21:14
68-114-222-192.dhcp.mdsn.wi.charter.com	1.533	0.189	0.009	05:45:46
a131033.upc-a.chello.nl	1.684	0.302	0.000	04:55:21
ip72-222-200-117.ph.ph.cox.net	0.568	0.104	0.000	02:38:30
mtrlpq2320w-lp130-01-184-146-239-88.dsl.bell.ca	10.002	1.257	0.000	00:15:35
198.199.119.194	0.069	0.008	0.000	00:00:09
scan-141.security.ipip.net	0.069	0.008	0.000	00:00:01
122.228.19.79	0.069	0.009	0.000	00:00:00
TOTAL: 9	492.778	374.968	0.009	194:52:39

Curious NNRP server explorers [Top 20]:

System	Conn
172-221-041-102.dhcp.chtrptr.net	60
scan-141.security.ipip.net	1
198.199.119.194	1
122.228.19.79	1
TOTAL: 4	63

NNRP unrecognized commands (by host):

System	Conn
122.228.19.79	5
scan-141.security.ipip.net	4
TOTAL: 2	9

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
Host: 69.160.37.58:119	2
Connection: close	1
Accept: /	1
Connection: keep-alive	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 7	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
bras-vprn-mtrlpq2320w-lp140-01-70-27-192-142.dsl.bell.ca	29	29
68-114-222-192.dhcp.mdsn.wi.charter.com	7	0
ip72-222-200-117.ph.ph.cox.net	4	0
172-221-041-102.dhcp.chtrptr.net	3	0
TOTAL: 4	43	29

Newsgroup request counts (by category):

Category		Count	Pct
1	soc	439215	27.4%
2	rec	327035	20.4%
3	uk	307745	19.2%
4	talk	276533	17.3%
5	alt	186191	11.6%
6	sci	65744	4.1%
7	fr	128	0.0%
8	aus	93	0.0%
9	comp	41	0.0%
10	misc	26	0.0%
11	news	4	0.0%
TOTAL: 11		1602755	100.0%

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

Newsgroup		Count
1	rec.arts.drwho	289200
2	talk.politics.misc	276533
3	soc.retirement	251338
4	uk.railway	222650
5	alt.russian.z1	185748
6	soc.culture.polish	175256
7	sci.logic	60979
8	uk.tech.digital-tv	53929
9	rec.aviation.soaring	37831
10	uk.rec.motorcycles	31052
11	soc.penpals	12621
12	sci.anthropology.paleo	4764
13	alt.usage.english	105
14	aus.cars	93
15	uk.d-i-y	81
16	fr.soc.politique	71
17	alt.support.depression	68
18	alt.assassination.jfk	60
19	alt.humor.puns	55
20	fr.sci.zetetique	37
21	uk.sport.cricket	30
22	alt.politics.economics	27
23	alt.comp.os.windows-10	24
24	misc.survivalism	24
25	alt.slack	23
26	comp.arch	21
27	comp.sys.ibm.ps2.hardware	19
28	alt.baldspot	16
29	fr.soc.environnement	16
30	alt.life.sucks	13
31	alt.os.linux	11
32	alt.prophecies.nostradamus	11
33	alt.toys.transformers	10
34	rec.arts.sf.tv	4
35	alt.appalachian	4
36	alt.california	4
37	news.lists.filters	4
38	alt.tv.law-and-order	4
39	alt.tv.game-shows	3
40	fr.comp.sys.atari	3
41	uk.radio.amateur	3
42	misc.health.diabetes	2
43	sci.space.policy	1
44	fr.sci.physique	1
45	alt.sixtyplus	1
46	alt.callahans	1
47	alt.fan.art-bell	1
48	alt.tv.days-of-our-lives	1
49	comp.ai.philosophy	1
50	alt.suicide.methods	1
TOTAL: 50		1602755

Daily Usenet report for csiph.com

Feb 10 04:15:00 -- Feb 11 04:15:00