Daily Usenet report for csiph.com: Sep 2 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	21332	49.5%	5.5 MB	65.6%
innd	17725	41.1%	2.2 MB	26.4%
innfeed	3323	7.7%	600.2 KB	7.0%
nnrpd	657	1.5%	72.9 KB	0.9%
nocem	88	0.2%	8.8 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	43126	100.0%	8.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	311287	58.4%
Negative hits	200686	37.7%
Do not exist	20585	3.9%
Cache misses	304	0.1%
TOTAL: 4	532862	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.455	0.0%	157	0.000	2.898	74.500
article cleanup	00:00:00.118	0.0%	19913	0.000	0.006	0.026
article logging	00:00:00.360	0.0%	20368	0.000	0.018	0.324
article parse	00:00:02.450	0.0%	77625	0.000	0.032	0.265
article write	00:00:16.652	0.0%	16710	0.155	0.997	23.059
artlog/artcncl	00:00:00.001	0.0%	138	0.000	0.007	1.000
artlog/artparse	00:00:00.023	0.0%	861	0.000	0.027	1.700
data move	00:00:00.130	0.0%	516773	0.000	0.000	0.007
hisgrep/artcncl	00:00:00.071	0.0%	156	0.000	0.455	12.000
hishave/artcncl	00:00:00.180	0.0%	157	0.000	1.146	42.750
hishave/artparse	00:00:00.001	0.0%	861	0.000	0.001	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:23.283	0.0%	536286	0.001	0.043	0.465
history sync	00:00:00.880	0.0%	381	0.000	2.310	14.500
history write	00:00:33.609	0.0%	19913	0.048	1.688	12.000
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.733	0.0%	861	0.000	2.013	14.000
idle	23:57:51.325	99.8%	522874	89.265	164.994	441.288
nntp read	00:00:02.255	0.0%	524686	0.001	0.004	0.010
overview write	00:00:44.890	0.1%	16710	0.130	2.686	71.793
perl filter	00:00:32.679	0.0%	19913	1.352	1.641	2.714
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.264	0.0%	33501	0.000	0.008	0.023
TOTAL: 24:01:07.272	24:00:31.359	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.045	0.0%	16800	0.000	0.003	0.034
article prepare	00:00:00.001	0.0%	14516	0.000	0.000	0.006
article read	00:00:02.546	0.0%	1344	0.000	1.894	166.500
backlog stats	00:00:00.168	0.0%	353588	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.320	0.0%	274928	0.001	0.005	0.010
data write	00:00:01.622	0.0%	262406	0.001	0.006	0.015
idle	23:53:10.248	100.0%	353588	140.144	243.193	717.689
status file	00:00:00.300	0.0%	231	0.000	1.299	8.400
TOTAL: 23:53:23.208	23:53:16.250	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	26:24:30.853	100.0%	476	27.000	199728.683	600127.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	1044	0.000	0.009	0.167
readart	00:00:00.418	0.0%	22	0.000	19.000	47.000
TOTAL: 26:24:51.817	26:24:31.280	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	4	33560	2785	30565	210	8%	47:06:17
2	newsfeeds.xmission.com	3	15133	2391	12736	6	15%	21:15:16
3	feeder.eternal-september.org	1	17707	1988	15642	77	11%	22:02:18
4	csiph-out.news.xs4all.nl	864	18523	1951	15145	1427	10%	71:13:05
5	news-out.aioe.org	3	16436	1598	14820	18	9%	24:09:40
6	csiph-out.feeder.erje.net	1440	80851	1458	79030	363	1%	118:51:01
7	news.freedyn.net	3	20063	974	18180	909	4%	24:09:01
8	news-out.mixmin.net	1	17412	964	16150	298	5%	23:59:55
9	newsfeed.neva.ru	142	20968	962	19583	423	4%	71:32:37
10	newsfeed.fu-berlin.de	18	16604	842	15668	94	5%	26:58:51
11	news.fcku.it	6	2396	235	2151	10	9%	23:01:36
12	csiph-out.feed.uzoreto.com	864	25946	205	25578	163	0%	71:16:35
13	nntp.pasdenom.info	3	11940	44	11874	22	0%	23:58:27
14	news.swapon.de	4	1606	37	1567	2	2%	26:59:51
15	news.bbs.nz	1	15450	14	15436	0	0%	23:59:52
16	news.samoylyk.net	2	16273	11	16262	0	0%	24:57:10
17	localhost	42	8	8	0	0	100%	00:00:01
18	xanadu-bbs.net	10	708	0	708	0	0%	25:03:52
TOTAL: 18		3411	331584	16467	311095	4022	4%	670:35:25

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	8.8 MB	4.1 MB	12.8 MB	68%	4.4 KB
2	csiph-out.feeder.erje.net	8.1 MB	11.1 MB	19.1 MB	42%	10.8 KB
3	newsfeeds.xmission.com	7.0 MB	353.0 KB	7.4 MB	95%	3.1 KB
4	feeder.eternal-september.org	5.6 MB	598.6 KB	6.2 MB	90%	3.1 KB
5	csiph-out.news.xs4all.nl	5.3 MB	12.2 MB	17.5 MB	30%	5.3 KB
6	news-out.aioe.org	3.9 MB	27.6 KB	4.0 MB	99%	2.5 KB
7	news.freedyn.net	2.9 MB	52.8 MB	55.7 MB	5%	30.3 KB
8	news-out.mixmin.net	2.6 MB	554.5 KB	3.1 MB	82%	2.5 KB
9	newsfeed.neva.ru	2.3 MB	1.3 MB	3.6 MB	63%	2.7 KB
10	newsfeed.fu-berlin.de	2.0 MB	197.7 KB	2.2 MB	91%	2.5 KB
11	csiph-out.feed.uzoreto.com	689.5 KB	4.0 MB	4.6 MB	14%	12.9 KB
12	news.fcku.it	401.8 KB	15.9 KB	417.7 KB	96%	1.7 KB
13	news.swapon.de	116.3 KB	21.0 KB	137.3 KB	84%	3.5 KB
14	nntp.pasdenom.info	102.1 KB	37.5 KB	139.6 KB	73%	2.1 KB
15	news.samoylyk.net	34.3 KB	0.0 KB	34.3 KB	100%	3.1 KB
16	news.bbs.nz	31.3 KB	0.0 KB	31.3 KB	100%	2.2 KB
17	localhost	13.9 KB	0.0 KB	13.9 KB	100%	1.7 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		49.8 MB	87.2 MB	137.0 MB	36%	6.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 2 04:15:00 - 04:59:59	272	1.6%	0.10	945.7 KB	1.8%	0.35
Sep 2 05:00:00 - 05:59:59	428	2.6%	0.12	1.7 MB	3.4%	0.49
Sep 2 06:00:00 - 06:59:59	545	3.3%	0.15	1.9 MB	3.7%	0.53
Sep 2 07:00:00 - 07:59:59	665	4.0%	0.18	1.8 MB	3.7%	0.52
Sep 2 08:00:00 - 08:59:59	733	4.4%	0.20	1.9 MB	3.8%	0.54
Sep 2 09:00:00 - 09:59:59	767	4.6%	0.21	2.0 MB	4.0%	0.57
Sep 2 10:00:00 - 10:59:59	762	4.6%	0.21	2.4 MB	4.7%	0.67
Sep 2 11:00:00 - 11:59:59	810	4.9%	0.23	2.2 MB	4.3%	0.62
Sep 2 12:00:00 - 12:59:59	842	5.0%	0.23	2.2 MB	4.4%	0.63
Sep 2 13:00:00 - 13:59:59	895	5.4%	0.25	2.6 MB	5.1%	0.73
Sep 2 14:00:00 - 14:59:59	970	5.8%	0.27	3.5 MB	7.0%	1.01
Sep 2 15:00:00 - 15:59:59	1009	6.0%	0.28	2.8 MB	5.5%	0.79
Sep 2 16:00:00 - 16:59:59	990	5.9%	0.28	3.0 MB	6.0%	0.85
Sep 2 17:00:00 - 17:59:59	877	5.3%	0.24	2.2 MB	4.4%	0.63
Sep 2 18:00:00 - 18:59:59	935	5.6%	0.26	3.0 MB	5.9%	0.85
Sep 2 19:00:00 - 19:59:59	831	5.0%	0.23	2.2 MB	4.5%	0.64
Sep 2 20:00:00 - 20:59:59	922	5.5%	0.26	2.8 MB	5.6%	0.81
Sep 2 21:00:00 - 21:59:59	750	4.5%	0.21	2.2 MB	4.3%	0.62
Sep 2 22:00:00 - 22:59:59	570	3.4%	0.16	1.9 MB	3.9%	0.55
Sep 2 23:00:00 - 23:59:59	562	3.4%	0.16	1.8 MB	3.5%	0.50
Sep 3 00:00:00 - 00:59:59	476	2.9%	0.13	1.5 MB	3.0%	0.43
Sep 3 01:00:00 - 01:59:59	330	2.0%	0.09	1.0 MB	2.0%	0.29
Sep 3 02:00:00 - 02:59:59	343	2.1%	0.10	1.3 MB	2.6%	0.37
Sep 3 03:00:00 - 03:59:59	302	1.8%	0.08	939.9 KB	1.8%	0.26
Sep 3 04:00:00 - 04:15:00	103	0.6%	0.11	536.3 KB	1.0%	0.60
TOTAL: 24:00:00	16689	100.0%	0.19	50.4 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	1441	75	1366
2	news.freedyn.net	922	35	887
3	newsfeed.fu-berlin.de	488	16	472
4	newsfeed.neva.ru	424	184	240
5	csiph-out.feeder.erje.net	365	58	307
6	news-out.mixmin.net	316	0	316
7	csiph-out.news.weretis.net	226	108	118
8	csiph-out.feed.uzoreto.com	161	11	150
9	feeder.eternal-september.org	88	48	40
10	news-out.aioe.org	24	19	5
11	nntp.pasdenom.info	22	0	22
12	news.fcku.it	10	9	1
13	news.swapon.de	9	0	9
14	newsfeeds.xmission.com	9	1	8
TOTAL: 14		4505	564	3941

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	136
alt.bbs.synchronet	86
it-alt.sport.calcio.juventus	43
lada.auto	35
mozilla.support.thunderbird	30
alt.windows7.general	20
alt.survival	19
mozilla.support.seamonkey	12
alt.hack.nl	11
mozilla.dev.security.policy	9
alt.computer.workshop	9
oesterreich.tratsch	9
alt.testing	7
alt.binaries.ebooks	7
mozilla.dev.l10n	6
mozilla.support.firefox	6
mozilla.dev.platform	5
rocksolid.shared.offtopic	5
alt.philosophy	4
lada.kitchen	4
TOTAL: 89	564

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2010
EMP (phn path)	197
EMP (phn nph)	142
EMP (phl)	114
Too many newsgroups	46
User-issued cancel	31
User-issued spam cancel	23
Binary Payload	19
Binary: misplaced rar	16
Scoring filter (10)	10
HTML Multipart	7
HTML Image Tags	3
Scoring filter (11)	2
Scoring filter (13)	1
Too many newsgroups (low_xpost)	1
TOTAL: 15	2622

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	53	0	60	60
TOTAL: 1	53	28	60	60

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	413
	csiph-out.feeder.erje.net	204
	csiph-out.news.xs4all.nl	126
	csiph-out.news.weretis.net	59
	csiph-out.feed.uzoreto.com	52
	feeder.eternal-september.org	5
	newsfeeds.xmission.com	5
	news-out.mixmin.net	1
	TOTAL: 8	865
Including strange strings
	newsfeed.fu-berlin.de	379
	csiph-out.news.xs4all.nl	26
	csiph-out.news.weretis.net	21
	news-out.mixmin.net	12
	news.swapon.de	7
	news-out.aioe.org	4
	newsfeeds.xmission.com	3
	news.freedyn.net	1
	csiph-out.feeder.erje.net	1
	TOTAL: 9	454
TOTAL: 3		1364

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	20030	1050	15570	2	88	5%	23:59:56
2	news.freedyn.net	15907	462	15119	38	0	2%	23:59:53
3	fu-berlin.de	15745	454	15290	0	0	2%	23:59:56
4	news.samoylyk.net	17305	449	16188	41	65	2%	23:59:55
5	news.bbs.nz	31731	224	15979	2	0	0%	23:59:55
6	usenet.pasdenom.info	17540	100	16019	527	9	0%	23:59:55
7	aioe	14344	51	13808	0	0	0%	23:59:04
8	newsfeed.xs4all.nl	15067	47	14207	1	64	0%	23:59:55
9	neva.ru	15604	36	14913	104	0	0%	23:59:47
10	news.mixmin.net	15543	12	15179	0	0	0%	23:59:56
11	weretis.net	13446	10	13208	0	0	0%	23:59:56
12	xmission	18118	9	13704	0	0	0%	23:59:48
13	erje	15276	8	15012	38	0	0%	23:59:56
14	eternal-september	14450	8	14015	0	0	0%	23:59:07
15	news.swapon.de	1707	8	1681	0	0	0%	23:55:59
16	news.uzoreto.com	16141	3	15885	24	0	0%	23:59:55
17	news.fcku.it	1946	1	1942	0	0	0%	22:48:30
18	news.redatomik.org	0	0	0	0	16209	0%	23:59:56
TOTAL: 18		259900	2932	227719	777	16435	1%	430:41:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.5 MB	3.8 KB	6.5 MB	0.1 KB/s	6.3 KB	23:59:56
2	news.freedyn.net	5.2 MB	71.6 KB	5.3 MB	0.1 KB/s	10.8 KB	23:59:53
3	fu-berlin.de	5.1 MB	0.0 KB	5.1 MB	0.1 KB/s	11.6 KB	23:59:56
4	news.samoylyk.net	4.2 MB	1.0 MB	5.2 MB	0.1 KB/s	10.9 KB	23:59:55
5	news.bbs.nz	631.8 KB	4.5 KB	636.3 KB	0.0 KB/s	2.8 KB	23:59:55
6	usenet.pasdenom.info	294.6 KB	5.3 MB	5.6 MB	0.1 KB/s	9.2 KB	23:59:55
7	newsfeed.xs4all.nl	171.8 KB	4.7 KB	176.5 KB	0.0 KB/s	3.7 KB	23:59:55
8	neva.ru	111.8 KB	291.3 KB	403.1 KB	0.0 KB/s	2.9 KB	23:59:47
9	aioe	104.1 KB	0.0 KB	104.1 KB	0.0 KB/s	2.0 KB	23:59:04
10	news.mixmin.net	27.0 KB	0.0 KB	27.0 KB	0.0 KB/s	2.2 KB	23:59:56
11	news.uzoreto.com	26.8 KB	102.3 KB	129.1 KB	0.0 KB/s	4.8 KB	23:59:55
12	weretis.net	18.2 KB	0.0 KB	18.2 KB	0.0 KB/s	1.8 KB	23:59:56
13	xmission	16.0 KB	0.0 KB	16.0 KB	0.0 KB/s	1.8 KB	23:59:48
14	eternal-september	13.9 KB	0.0 KB	13.9 KB	0.0 KB/s	1.7 KB	23:59:07
15	news.swapon.de	13.9 KB	0.0 KB	13.9 KB	0.0 KB/s	1.7 KB	23:55:59
16	erje	12.5 KB	125.2 KB	137.7 KB	0.0 KB/s	3.0 KB	23:59:56
17	news.fcku.it	1.3 KB	0.0 KB	1.3 KB	0.0 KB/s	1.3 KB	22:48:30
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
TOTAL: 18		22.4 MB	7.0 MB	29.4 MB	0.0 KB/s	8.1 KB	430:41:19

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	51	14	28.0 KB	4	0	0	15:03:20
2	68-190-164-204.dhcp.eucl.wi.charter.com	34	8	13.9 KB	8	8	8	08:48:09
TOTAL: 2		85	22	41.9 KB	12	8	8	26:24:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	51	14	28.0 KB	4	0	0	15:03:20
2	*.dhcp.eucl.wi.charter.com	34	8	13.9 KB	8	8	8	08:48:09
3	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:00
4	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
5	*.sfj.corp.censys.io	2	0	0.0 KB	0	0	0	00:00:01
6	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:15
7	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
8	unresolved	4	0	0.0 KB	0	0	0	00:03:00
TOTAL: 8		98	22	41.9 KB	12	8	8	26:24:49

NNRP auth users [Top 20]:

User	Conn
ohlandl	8
TOTAL: 1	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.673	0.603	0.000	15:03:20
68-190-164-204.dhcp.eucl.wi.charter.com	2.715	0.530	0.017	08:48:09
ip72-222-200-117.ph.ph.cox.net	0.453	0.077	0.000	02:00:15
c-73-140-175-55.hsd1.wa.comcast.net	0.060	0.017	0.000	00:30:00
94.161.33.247	0.128	0.030	0.000	00:02:58
94.166.16.129	0.126	0.031	0.000	00:00:02
worker-18.sfj.corp.censys.io	0.124	0.030	0.000	00:00:01
scan-141.security.ipip.net	0.065	0.016	0.000	00:00:00
census9.shodan.io	0.064	0.016	0.000	00:00:00
TOTAL: 9	7.408	1.350	0.017	26:24:49

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
worker-18.sfj.corp.censys.io	2
94.166.16.129	2
94.161.33.247	2
census9.shodan.io	1
c-73-140-175-55.hsd1.wa.comcast.net	1
scan-141.security.ipip.net	1
TOTAL: 7	13

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
worker-18.sfj.corp.censys.io	2
TOTAL: 2	6

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
Host: 69.160.37.58:119	2
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
TOTAL: 4	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-190-164-204.dhcp.eucl.wi.charter.com	9	0
ip72-222-200-117.ph.ph.cox.net	4	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
worker-18.sfj.corp.censys.io	1	1
TOTAL: 4	15	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	22
TOTAL: 1		22

Daily Usenet report for csiph.com

Sep 2 04:15:00 -- Sep 3 04:15:00