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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 3 11:18:58 csiph pgpverify[18491]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45428	55.3%	8.6 MB	67.8%
innd	23808	29.0%	2.5 MB	19.9%
nnrpd	9635	11.7%	1.0 MB	8.2%
innfeed	3219	3.9%	533.1 KB	4.1%
nocem	37	0.0%	3.9 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	82132	100.0%	12.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	458288	55.1%
Negative hits	329709	39.6%
Do not exist	43311	5.2%
Cache misses	696	0.1%
TOTAL: 4	832004	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.359	0.0%	84	0.000	4.274	99.000
article cleanup	00:00:00.274	0.0%	43799	0.000	0.006	0.021
article logging	00:00:04.203	0.0%	44518	0.000	0.094	1.555
article parse	00:00:02.250	0.0%	146376	0.002	0.015	0.137
article write	00:02:49.194	0.2%	25589	0.208	6.612	27.014
artlog/artcncl	00:00:00.001	0.0%	58	0.000	0.017	1.000
artlog/artparse	00:00:00.035	0.0%	107	0.000	0.327	5.667
data move	00:00:00.308	0.0%	754194	0.000	0.000	0.009
hisgrep/artcncl	00:00:00.066	0.0%	82	0.000	0.805	13.000
hishave/artcncl	00:00:00.003	0.0%	84	0.000	0.036	1.000
hishave/artparse	00:00:00.000	0.0%	103	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:18.909	0.2%	833664	0.002	0.167	0.381
history sync	00:00:04.268	0.0%	325	0.000	13.132	101.500
history write	00:03:46.960	0.3%	43200	0.054	5.254	20.176
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
hiswrite/artparse	00:00:00.640	0.0%	103	0.000	6.214	54.000
idle	23:33:05.069	98.8%	728477	49.210	116.387	422.824
nntp read	00:00:04.098	0.0%	764160	0.001	0.005	0.083
overview write	00:04:51.923	0.3%	25589	2.048	11.408	98.986
perl filter	00:01:49.808	0.1%	43195	1.583	2.542	4.889
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.464	0.0%	51211	0.000	0.009	0.025
TOTAL: 23:50:38.771	23:48:58.833	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.083	0.0%	25678	0.000	0.003	0.018
article prepare	00:00:00.004	0.0%	46160	0.000	0.000	0.003
article read	00:00:01.511	0.0%	8963	0.053	0.169	1.739
backlog stats	00:00:00.280	0.0%	466929	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.541	0.0%	364221	0.003	0.007	0.014
data write	00:00:02.134	0.0%	346739	0.001	0.006	0.011
idle	23:53:43.099	100.0%	466929	70.317	184.232	579.397
status file	00:00:00.258	0.0%	304	0.000	0.849	4.333
TOTAL: 23:54:02.352	23:53:49.910	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.443	0.0%	1623	0.000	2.121	47.000
idle	75:04:03.980	99.8%	62510	0.000	4323.212	900279.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.825	0.0%	127889	0.000	0.006	0.452
readart	00:00:45.836	0.0%	13068	0.000	3.507	47.000
TOTAL: 75:14:20.760	75:04:54.084	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
grisbi-control@grisbi.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	145090	8006	126980	10104	5%	94:58:37
2	csiph-out.news.weretis.net	3	54573	5027	48510	1036	9%	46:07:56
3	feeder.eternal-september.org	49	40159	3881	30856	5422	9%	35:13:12
4	news-out.aioe.org	4	25148	1535	23585	28	6%	24:09:23
5	newsfeed.neva.ru	144	53237	1524	48783	2930	2%	71:23:17
6	newsfeed.fu-berlin.de	51	24943	1343	23188	412	5%	31:53:06
7	news.redatomik.org	2	28453	1231	27190	32	4%	28:19:34
8	newsfeeds.xmission.com	4	26264	1089	25165	10	4%	27:23:41
9	news-out.mixmin.net	4	27729	979	26549	201	3%	24:11:53
10	news.fcku.it	1	11325	564	10606	155	4%	23:59:57
11	nntp.pasdenom.info	7	25088	145	24913	30	0%	24:12:40
12	news.freedyn.net	3	29844	110	29580	154	0%	24:09:51
13	news.swapon.de	3	2428	108	2318	2	4%	24:24:49
14	news.bbs.geek.nz	1	18306	77	18224	5	0%	23:59:47
15	xanadu-bbs.net	5	261	58	200	3	22%	10:02:02
16	localhost	42	10	10	0	0	100%	00:00:09
TOTAL: 16		1475	512858	25687	466647	20524	5%	514:29:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.3 MB	2.5 MB	57.2 MB	84.0 MB	28%	4.8 KB
2	csiph-out.news.weretis.net	14.4 MB	31.5 KB	16.4 MB	30.8 MB	46%	5.2 KB
3	feeder.eternal-september.org	10.5 MB	4.8 MB	71.2 MB	86.5 MB	12%	9.5 KB
4	news-out.mixmin.net	4.7 MB	8.8 KB	909.5 KB	5.5 MB	83%	4.8 KB
5	news-out.aioe.org	4.1 MB	5.7 KB	222.7 KB	4.3 MB	94%	2.8 KB
6	newsfeed.neva.ru	3.4 MB	495.3 KB	26.2 MB	30.1 MB	11%	6.9 KB
7	newsfeeds.xmission.com	3.3 MB	5.7 KB	364.6 KB	3.7 MB	90%	3.4 KB
8	news.redatomik.org	3.2 MB	5.7 KB	264.3 KB	3.4 MB	92%	2.8 KB
9	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	830.1 KB	3.8 MB	78%	2.2 KB
10	news.fcku.it	1.3 MB	0.0 KB	1.4 MB	2.6 MB	47%	3.8 KB
11	news.freedyn.net	566.0 KB	20.0 KB	1.7 MB	2.3 MB	24%	8.7 KB
12	nntp.pasdenom.info	363.7 KB	0.0 KB	61.5 KB	425.2 KB	85%	2.4 KB
13	xanadu-bbs.net	290.7 KB	0.0 KB	33.0 KB	323.7 KB	89%	5.3 KB
14	news.swapon.de	278.2 KB	0.0 KB	8.6 KB	286.8 KB	96%	2.6 KB
15	news.bbs.geek.nz	175.4 KB	1.5 KB	62.5 KB	239.4 KB	73%	2.9 KB
16	localhost	14.5 KB	0.0 KB	0.0 KB	14.5 KB	100%	1.4 KB
TOTAL: 16		73.8 MB	7.8 MB	176.8 MB	258.4 MB	28%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 3 04:15:00 - 04:59:59	347	1.4%	0.13	1010.6 KB	1.3%	0.37
Feb 3 05:00:00 - 05:59:59	396	1.5%	0.11	1.2 MB	1.7%	0.35
Feb 3 06:00:00 - 06:59:59	510	2.0%	0.14	1.5 MB	2.1%	0.43
Feb 3 07:00:00 - 07:59:59	703	2.7%	0.20	2.2 MB	3.0%	0.62
Feb 3 08:00:00 - 08:59:59	1078	4.2%	0.30	2.9 MB	4.0%	0.83
Feb 3 09:00:00 - 09:59:59	1177	4.6%	0.33	3.0 MB	4.1%	0.86
Feb 3 10:00:00 - 10:59:59	1322	5.2%	0.37	3.4 MB	4.6%	0.97
Feb 3 11:00:00 - 11:59:59	1057	4.1%	0.29	2.9 MB	4.0%	0.84
Feb 3 12:00:00 - 12:59:59	1143	4.5%	0.32	3.2 MB	4.3%	0.91
Feb 3 13:00:00 - 13:59:59	1399	5.5%	0.39	3.9 MB	5.3%	1.11
Feb 3 14:00:00 - 14:59:59	1358	5.3%	0.38	3.3 MB	4.4%	0.93
Feb 3 15:00:00 - 15:59:59	1453	5.7%	0.40	4.6 MB	6.2%	1.30
Feb 3 16:00:00 - 16:59:59	1750	6.8%	0.49	5.2 MB	7.0%	1.47
Feb 3 17:00:00 - 17:59:59	1558	6.1%	0.43	4.4 MB	5.9%	1.24
Feb 3 18:00:00 - 18:59:59	1312	5.1%	0.36	4.0 MB	5.4%	1.13
Feb 3 19:00:00 - 19:59:59	1904	7.4%	0.53	4.6 MB	6.2%	1.30
Feb 3 20:00:00 - 20:59:59	1227	4.8%	0.34	3.6 MB	4.9%	1.03
Feb 3 21:00:00 - 21:59:59	1368	5.3%	0.38	4.0 MB	5.4%	1.12
Feb 3 22:00:00 - 22:59:59	1194	4.7%	0.33	3.6 MB	4.9%	1.02
Feb 3 23:00:00 - 23:59:59	934	3.6%	0.26	3.2 MB	4.3%	0.91
Feb 4 00:00:00 - 00:59:59	707	2.8%	0.20	1.9 MB	2.6%	0.54
Feb 4 01:00:00 - 01:59:59	637	2.5%	0.18	2.3 MB	3.1%	0.65
Feb 4 02:00:00 - 02:59:59	538	2.1%	0.15	1.6 MB	2.2%	0.46
Feb 4 03:00:00 - 03:59:59	467	1.8%	0.13	1.7 MB	2.4%	0.49
Feb 4 04:00:00 - 04:15:00	118	0.5%	0.13	535.4 KB	0.7%	0.59
TOTAL: 24:00:00	25657	100.0%	0.30	73.7 MB	100.0%	0.87

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	csiph-out.feeder.erje.net	8285	465	198	2	0	7620
2	feeder.eternal-september.org	5406	49	364	0	0	4993
3	newsfeed.neva.ru	3157	328	46	0	0	2783
4	csiph-out.news.weretis.net	1210	168	5	0	0	1037
5	newsfeed.fu-berlin.de	949	39	0	0	0	910
6	news-out.mixmin.net	213	1	2	0	0	210
7	news.freedyn.net	161	14	1	0	0	146
8	news.fcku.it	158	0	0	0	0	158
9	news.redatomik.org	42	0	1	0	0	41
10	nntp.pasdenom.info	36	15	0	0	1	20
11	news.swapon.de	32	0	0	0	0	32
12	news-out.aioe.org	31	17	1	0	0	13
13	newsfeeds.xmission.com	24	4	1	0	0	19
14	xanadu-bbs.net	6	0	0	0	0	6
15	news.bbs.geek.nz	3	0	0	0	0	3
TOTAL: 15		19713	1100	619	2	1	17991

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	144
lada.talk	133
lada.auto	69
it-alt.sport.calcio.juventus	44
lada.commerce	41
alt.courts.pretrial-services	40
mozilla.support.seamonkey	38
mozilla.general	37
it.annunci.varie	36
gmane.linux.alsa.devel	31
it.annunci.commerciali	28
it.annunci.immobiliari	26
mozilla.support.thunderbird	21
cakewalk.audio	19
spline.eisfair	18
alt.sci.physics	17
spline.fli4l	14
oesterreich.tratsch	14
mozilla.support.firefox	14
alt.binaries.solar-xl	13
TOTAL: 133	1100

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

Groups	Count
nz.politics.announce	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12781
EMP (phn path)	3419
EMP (phl)	504
EMP (phn nph)	113
Too many newsgroups	89
Binary Payload	80
Scoring filter (8)	35
HTML Multipart	25
Scoring filter (10)	22
User-issued cancel	20
Scoring filter (11)	14
Scoring filter (16)	9
Scoring filter (9)	7
Scoring filter (13)	7
Scoring filter (14)	5
Binary: misplaced sfv	5
Scoring filter (18)	4
HTML post	4
Scoring filter (19)	4
Scoring filter (12)	3
TOTAL: 28	17164

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.news.weretis.net	48
	feeder.eternal-september.org	24
	news-out.mixmin.net	11
	news.fcku.it	11
	news.freedyn.net	4
	newsfeeds.xmission.com	3
	news.redatomik.org	2
	TOTAL: 7	103
Including strange strings
	newsfeed.fu-berlin.de	533
	csiph-out.feeder.erje.net	48
	news.swapon.de	30
	feeder.eternal-september.org	26
	csiph-out.news.weretis.net	18
	newsfeeds.xmission.com	16
	news-out.mixmin.net	13
	newsfeed.neva.ru	10
	news.redatomik.org	9
	news-out.aioe.org	6
	TOTAL: 13	720
No colon-space in header
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
TOTAL: 3		827

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	41822	7359	17439	85	0	17%	23:50:06
2	xanadu-bbs	28692	846	24589	0	0	2%	23:59:53
3	news.freedyn.net	26273	839	24629	2	0	3%	23:50:06
4	fu-berlin.de	23956	649	23307	0	0	2%	23:59:53
5	xmission	29046	189	23648	9	0	0%	23:59:07
6	usenet.pasdenom.info	27892	104	24145	1262	39	0%	23:59:53
7	news.redatomik.org	24348	31	23743	10	0	0%	23:53:57
8	aioe	22941	25	21855	9	0	0%	23:54:28
9	weretis.net	19458	16	18543	2	0	0%	23:54:54
10	eternal-september	22285	15	20755	0	1	0%	23:59:53
11	news.mixmin.net	24073	12	23296	0	0	0%	23:59:53
12	neva.ru	24098	11	23128	12	0	0%	23:59:53
13	news.swapon.de	2518	9	2455	2	0	0%	23:55:31
14	erje	17573	0	17459	32	1	0%	23:54:24
15	bwh	0	0	0	0	24918	0%	23:50:06
16	news.fcku.it	2924	0	2910	0	0	0%	22:25:40
17	au2pb	0	0	0	0	24988	0%	23:58:42
TOTAL: 17		337899	10105	291901	1425	49947	2%	405:26:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	19.3 MB	336.6 KB	19.7 MB	0.2 KB/s	2.7 KB	23:50:06
2	news.freedyn.net	6.1 MB	5.2 KB	6.1 MB	0.1 KB/s	7.5 KB	23:50:06
3	xanadu-bbs	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	7.3 KB	23:59:53
4	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	8.7 KB	23:59:53
5	xmission	430.3 KB	21.3 KB	451.6 KB	0.0 KB/s	2.3 KB	23:59:07
6	usenet.pasdenom.info	317.2 KB	6.6 MB	6.9 MB	0.1 KB/s	5.2 KB	23:59:53
7	news.redatomik.org	90.0 KB	32.7 KB	122.7 KB	0.0 KB/s	3.0 KB	23:53:57
8	aioe	53.6 KB	30.2 KB	83.8 KB	0.0 KB/s	2.5 KB	23:54:28
9	news.mixmin.net	37.1 KB	0.0 KB	37.1 KB	0.0 KB/s	3.1 KB	23:59:53
10	weretis.net	27.0 KB	54.2 KB	81.2 KB	0.0 KB/s	4.5 KB	23:54:54
11	eternal-september	24.4 KB	0.0 KB	24.4 KB	0.0 KB/s	1.6 KB	23:59:53
12	neva.ru	15.8 KB	31.4 KB	47.3 KB	0.0 KB/s	2.1 KB	23:59:53
13	news.swapon.de	12.8 KB	3.1 KB	15.9 KB	0.0 KB/s	1.4 KB	23:55:31
14	erje	0.0 KB	132.8 KB	132.8 KB	0.0 KB/s	4.1 KB	23:54:24
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:25:40
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		38.0 MB	7.3 MB	45.3 MB	0.0 KB/s	4.0 KB	405:26:19

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	180	1345	4.4 MB	84	0	0	01:34:43
2	tempe0.bbox.io	22	22	32.9 KB	8	1	1	04:37:46
3	68-185-181-64.dhcp.mdsn.wi.charter.com	25	16	26.4 KB	10	9	7	04:23:47
4	dhcp-089-099-203-254.chello.nl	56	12	18.4 KB	5	0	0	15:43:28
5	cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1	9	14.8 KB	2	0	0	00:01:43
TOTAL: 5		284	1404	4.5 MB	109	10	8	75:14:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	180	1345	4.4 MB	84	0	0	01:34:43
2	*.bbox.io	22	22	32.9 KB	8	1	1	04:37:46
3	*.dhcp.mdsn.wi.charter.com	25	16	26.4 KB	10	9	7	04:23:47
4	*.chello.nl	56	12	18.4 KB	5	0	0	15:43:28
5	*.19-1.cable.virginm.net	1	9	14.8 KB	2	0	0	00:01:43
6	unresolved	102	0	0.0 KB	0	0	0	48:05:36
7	*.171.131.174.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:47:10
TOTAL: 7		387	1404	4.5 MB	109	10	8	75:14:17

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
kev009	1
TOTAL: 2	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.289	1.403	0.000	47:58:53
dhcp-089-099-203-254.chello.nl	4.285	0.812	0.000	15:43:28
tempe0.bbox.io	1.816	0.411	0.005	04:37:46
68-185-181-64.dhcp.mdsn.wi.charter.com	2.338	0.475	0.041	04:23:47
mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	18.986	6.453	0.000	01:34:43
h231.171.131.174.dynamic.ip.windstream.net	0.070	0.097	0.000	00:47:10
5.51.176.30	0.441	0.122	0.000	00:06:43
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.143	0.032	0.000	00:01:43
TOTAL: 8	35.368	9.805	0.046	75:14:17

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	97
5.51.176.30	5
h231.171.131.174.dynamic.ip.windstream.net	1
TOTAL: 3	103

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	94	0
tempe0.bbox.io	8	0
68-185-181-64.dhcp.mdsn.wi.charter.com	2	0
TOTAL: 3	104	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	392	27.9%
2	aus	277	19.7%
3	linux	241	17.2%
4	fr	219	15.6%
5	can	82	5.8%
6	comp	78	5.6%
7	uk	40	2.8%
8	rec	40	2.8%
9	misc	20	1.4%
10	talk	13	0.9%
11	soc	2	0.1%
TOTAL: 11		1404	100.0%

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

Newsgroup		Count
1	linux.kernel	241
2	fr.soc.politique	198
3	aus.cars	142
4	aus.politics	135
5	alt.atheism	96
6	can.politics	82
7	alt.home.repair	65
8	alt.society.liberalism	61
9	alt.usage.english	58
10	comp.sys.ibm.ps2.hardware	44
11	rec.arts.tv	38
12	uk.legal	27
13	misc.survivalism	20
14	fr.rec.photo	15
15	alt.usenet.kooks	13
16	alt.slack	12
17	alt.happy.valley	11
18	comp.sys.apple2	10
19	alt.talk.creationism	10
20	alt.support.depression	10
21	alt.support.diabetes	9
22	talk.politics.misc	8
23	alt.religion.christian	8
24	comp.unix.aix	8
25	comp.lang.javascript	7
26	alt.assassination.jfk	7
27	fr.rec.moto	6
28	talk.politics.guns	5
29	alt.messianic	4
30	uk.politics.misc	4
31	comp.lang.pl1	4
32	alt.guitar.amps	4
33	alt.comp.os.windows-10	4
34	uk.rec.drugs.cannabis	3
35	uk.d-i-y	3
36	alt.california	3
37	alt.true-crime	2
38	alt.war.vietnam	2
39	alt.prophecies.nostradamus	2
40	comp.sys.hp.hpux	2
41	alt.guitar	2
42	alt.smokers.pipes	2
43	uk.rec.sheds	2
44	alt.support.sleep-disorder	2
45	rec.sport.football.college	1
46	uk.net.news.moderation	1
47	comp.unix.bsd.freebsd.misc	1
48	soc.culture.russian	1
49	alt.sports.football.pro.sf-49ers	1
50	alt.humor.puns	1
51	comp.sys.mac.system	1
52	alt.gossip.celebrities	1
53	soc.culture.usa	1
54	alt.christnet.christianlife	1
55	comp.lang.c	1
56	rec.arts.disney.parks	1
57	alt.rush-limbaugh	1
TOTAL: 57		1404

Daily Usenet report for csiph.com

Feb 3 04:15:00 -- Feb 4 04:15:00