Daily Usenet report for csiph.com: Mar 25 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22818	54.8%	5.0 MB	69.2%
innd	9872	23.7%	1.2 MB	17.1%
nnrpd	5993	14.4%	510.3 KB	6.9%
innfeed	2884	6.9%	500.9 KB	6.8%
nocem	69	0.2%	8.1 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	41637	100.0%	7.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	274323	57.3%
Negative hits	182034	38.0%
Do not exist	21964	4.6%
Cache misses	262	0.1%
TOTAL: 4	478583	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.001	0.0%	243	0.000	4.119	26.556
article cleanup	00:00:00.130	0.0%	21794	0.000	0.006	0.038
article logging	00:00:00.525	0.0%	22402	0.000	0.023	1.273
article parse	00:00:01.170	0.0%	54326	0.000	0.022	0.193
article write	00:00:46.537	0.1%	17674	0.238	2.633	24.632
artlog/artcncl	00:00:00.001	0.0%	178	0.000	0.006	0.071
artlog/artparse	00:00:00.003	0.0%	229	0.000	0.013	1.000
data move	00:00:00.143	0.0%	460181	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.291	0.0%	237	0.000	1.228	22.000
hishave/artcncl	00:00:00.026	0.0%	243	0.000	0.107	0.929
hishave/artparse	00:00:00.001	0.0%	229	0.000	0.004	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:32.352	0.0%	479525	0.004	0.067	0.515
history sync	00:00:00.970	0.0%	389	0.000	2.494	10.000
history write	00:00:45.809	0.1%	21787	0.233	2.103	12.899
hiswrite/artcncl	00:00:00.002	0.0%	6	0.333	0.333	0.333
hiswrite/artparse	00:00:00.452	0.0%	229	0.000	1.974	57.000
idle	23:52:59.838	99.4%	462688	100.461	185.827	649.857
nntp read	00:00:02.213	0.0%	466898	0.001	0.005	0.009
overview write	00:04:35.762	0.3%	17674	0.284	15.603	4049.320
perl filter	00:00:42.480	0.0%	21623	1.399	1.965	6.457
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.288	0.0%	35412	0.000	0.008	0.027
TOTAL: 24:01:08.402	24:00:29.994	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	17658	0.000	0.003	0.033
article prepare	00:00:00.001	0.0%	19844	0.000	0.000	0.008
article read	00:00:00.894	0.0%	2533	0.000	0.353	51.800
backlog stats	00:00:00.215	0.0%	337367	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.796	0.0%	254575	0.000	0.007	0.013
data write	00:00:01.446	0.0%	239619	0.000	0.006	0.012
idle	23:54:32.648	100.0%	337367	144.615	255.131	777.276
status file	00:00:00.251	0.0%	259	0.000	0.969	4.000
TOTAL: 23:54:45.324	23:54:37.298	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	21:26:22.220	100.0%	5956	329.670	12958.734	600124.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.058	0.0%	12435	0.000	0.005	0.167
readart	00:00:00.282	0.0%	104	0.000	2.712	36.000
TOTAL: 21:26:48.134	21:26:22.560	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	4	35308	5456	29609	243	15%	48:34:25
2	csiph-out.feeder.erje.net	1153	74811	4218	68559	2034	5%	95:01:30
3	feeder.eternal-september.org	2	19128	2270	16535	323	11%	23:59:54
4	news-out.aioe.org	3	17241	1781	15422	38	10%	24:09:26
5	newsfeed.fu-berlin.de	15	16371	1116	14990	265	6%	24:52:29
6	newsfeeds.xmission.com	2	13812	682	13125	5	4%	18:28:40
7	news.redatomik.org	1	16266	673	15580	13	4%	23:51:44
8	newsfeed.neva.ru	152	22381	651	20797	933	2%	71:49:01
9	news.fcku.it	2	7062	466	6468	128	6%	23:59:56
10	news-out.mixmin.net	2	18278	136	17805	337	0%	23:59:49
11	news.swapon.de	5	1696	58	1637	1	3%	22:22:13
12	nntp.pasdenom.info	2	15924	48	15863	13	0%	23:59:13
13	news.freedyn.net	2	18250	7	18240	3	0%	23:17:29
14	localhost	38	6	6	0	0	100%	00:00:00
15	news.bbs.geek.nz	2	16451	4	16445	2	0%	23:59:42
16	xanadu-bbs.net	23	381	0	381	0	0%	15:22:14
TOTAL: 16		1408	293366	17572	271456	4338	5%	487:47:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	16.9 MB	0.0 KB	4.0 MB	20.9 MB	81%	3.8 KB
2	csiph-out.feeder.erje.net	16.7 MB	0.0 KB	11.2 MB	27.8 MB	59%	4.6 KB
3	feeder.eternal-september.org	5.8 MB	0.0 KB	681.8 KB	6.5 MB	89%	2.5 KB
4	news-out.aioe.org	4.3 MB	0.0 KB	103.3 KB	4.4 MB	97%	2.5 KB
5	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	589.6 KB	3.2 MB	82%	2.4 KB
6	newsfeeds.xmission.com	2.4 MB	0.0 KB	327.3 KB	2.7 MB	88%	4.0 KB
7	newsfeed.neva.ru	1.7 MB	161.3 KB	2.6 MB	4.5 MB	37%	2.9 KB
8	news.redatomik.org	1.6 MB	0.0 KB	21.4 KB	1.6 MB	98%	2.4 KB
9	news.fcku.it	1.2 MB	0.0 KB	2.1 MB	3.3 MB	35%	5.7 KB
10	news-out.mixmin.net	460.1 KB	4.0 KB	647.9 KB	1.1 MB	41%	2.4 KB
11	news.swapon.de	163.5 KB	0.0 KB	1.0 KB	164.5 KB	99%	2.8 KB
12	nntp.pasdenom.info	118.2 KB	0.0 KB	34.2 KB	152.4 KB	77%	2.5 KB
13	news.freedyn.net	21.4 KB	0.0 KB	3.6 KB	25.1 KB	85%	2.5 KB
14	localhost	9.7 KB	0.0 KB	0.0 KB	9.7 KB	100%	1.6 KB
15	news.bbs.geek.nz	7.7 KB	0.0 KB	1.6 KB	9.3 KB	83%	1.6 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		53.9 MB	165.3 KB	22.3 MB	76.3 MB	70%	3.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 25 04:15:00 - 04:59:59	239	1.4%	0.09	943.4 KB	1.7%	0.35
Mar 25 05:00:00 - 05:59:59	344	1.9%	0.10	979.7 KB	1.8%	0.27
Mar 25 06:00:00 - 06:59:59	462	2.6%	0.13	1.5 MB	2.9%	0.44
Mar 25 07:00:00 - 07:59:59	572	3.2%	0.16	2.0 MB	3.8%	0.58
Mar 25 08:00:00 - 08:59:59	663	3.7%	0.18	2.7 MB	5.0%	0.77
Mar 25 09:00:00 - 09:59:59	721	4.1%	0.20	2.2 MB	4.1%	0.63
Mar 25 10:00:00 - 10:59:59	768	4.3%	0.21	2.3 MB	4.2%	0.65
Mar 25 11:00:00 - 11:59:59	844	4.8%	0.23	3.4 MB	6.3%	0.97
Mar 25 12:00:00 - 12:59:59	876	5.0%	0.24	2.9 MB	5.3%	0.82
Mar 25 13:00:00 - 13:59:59	901	5.1%	0.25	3.1 MB	5.7%	0.88
Mar 25 14:00:00 - 14:59:59	959	5.4%	0.27	2.7 MB	5.0%	0.77
Mar 25 15:00:00 - 15:59:59	938	5.3%	0.26	2.5 MB	4.5%	0.70
Mar 25 16:00:00 - 16:59:59	1022	5.8%	0.28	2.6 MB	4.9%	0.75
Mar 25 17:00:00 - 17:59:59	1132	6.4%	0.31	3.2 MB	5.9%	0.91
Mar 25 18:00:00 - 18:59:59	998	5.6%	0.28	3.2 MB	6.0%	0.92
Mar 25 19:00:00 - 19:59:59	1105	6.2%	0.31	3.1 MB	5.8%	0.89
Mar 25 20:00:00 - 20:59:59	1047	5.9%	0.29	2.9 MB	5.3%	0.82
Mar 25 21:00:00 - 21:59:59	830	4.7%	0.23	2.1 MB	3.9%	0.60
Mar 25 22:00:00 - 22:59:59	839	4.7%	0.23	2.4 MB	4.4%	0.68
Mar 25 23:00:00 - 23:59:59	632	3.6%	0.18	1.7 MB	3.1%	0.47
Mar 26 00:00:00 - 00:59:59	406	2.3%	0.11	1.1 MB	2.1%	0.33
Mar 26 01:00:00 - 01:59:59	391	2.2%	0.11	1.3 MB	2.3%	0.36
Mar 26 02:00:00 - 02:59:59	495	2.8%	0.14	1.7 MB	3.1%	0.47
Mar 26 03:00:00 - 03:59:59	377	2.1%	0.10	1.2 MB	2.2%	0.34
Mar 26 04:00:00 - 04:15:00	131	0.7%	0.15	483.7 KB	0.9%	0.54
TOTAL: 24:00:00	17692	100.0%	0.20	54.2 MB	100.0%	0.64

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	2044	105	0	1939
2	newsfeed.neva.ru	936	68	6	862
3	newsfeed.fu-berlin.de	782	55	0	727
4	news-out.mixmin.net	342	1	1	340
5	feeder.eternal-september.org	323	75	0	248
6	csiph-out.news.weretis.net	294	127	0	167
7	news.fcku.it	114	0	0	114
8	news-out.aioe.org	42	18	0	24
9	news.redatomik.org	34	0	0	34
10	news.swapon.de	13	0	0	13
11	nntp.pasdenom.info	13	13	0	0
12	newsfeeds.xmission.com	4	0	0	4
13	news.bbs.geek.nz	3	3	0	0
14	news.freedyn.net	3	0	0	3
15	xanadu-bbs.net	2	0	0	2
TOTAL: 15		4949	465	7	4477

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.windows7.general	66
lada.talk	33
mpc.lists.freebsd.ports.bugs	32
spline.eisfair	32
alt.politics.usa	21
mpc.lists.freebsd.gnome	18
lada.auto	18
alt.politics	16
mpc.lists.freebsd.x11	14
alt.bbs.synchronet	13
alt.pl.nieruchomosci.gielda	11
abg.test	10
mozilla.general	9
spline.eisfair.dev	9
oesterreich.tratsch	8
nemo.dev.client	8
ger.ct.test	7
mozilla.support.seamonkey	7
it-alt.sport.calcio.juventus	7
alt.binaries.erotica.pornstars.80s.video	6
TOTAL: 87	465

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2940
EMP (phn path)	290
EMP (fsl)	45
EMP (phn nph)	39
User-issued cancel	33
HTML Multipart	27
EMP (phl)	22
Binary: misplaced rar	19
Too many newsgroups	18
Binary Payload	17
Scoring filter (9)	7
Scoring filter (10)	4
Scoring filter (14)	4
Scoring filter (12)	2
Scoring filter (8)	2
Binary: misplaced 037	1
Scoring filter (18)	1
Scoring filter (16)	1
Too many newsgroups (meow)	1
Scoring filter (11)	1
TOTAL: 20	3474

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	47	0	73	73
TOTAL: 1	47	17	73	73

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	164
	TOTAL: 1	164
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.feeder.erje.net	167
	csiph-out.news.weretis.net	43
	news.fcku.it	10
	feeder.eternal-september.org	4
	newsfeeds.xmission.com	3
	news-out.mixmin.net	2
	news.redatomik.org	1
	TOTAL: 7	230
Including strange strings
	newsfeed.fu-berlin.de	512
	csiph-out.news.weretis.net	38
	news.redatomik.org	21
	csiph-out.feeder.erje.net	13
	news.swapon.de	12
	news-out.aioe.org	4
	newsfeed.neva.ru	3
	news-out.mixmin.net	2
	news.fcku.it	2
	feeder.eternal-september.org	2
	TOTAL: 10	609
TOTAL: 4		1007

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	32212	1075	15927	13	2	3%	23:59:53
2	news.freedyn.net	18921	871	16808	4	0	4%	23:59:52
3	xanadu-bbs	20747	719	16973	0	3	3%	23:59:55
4	fu-berlin.de	16368	656	15712	0	0	4%	23:59:55
5	usenet.pasdenom.info	19133	156	16257	1231	3	0%	23:59:53
6	aioe	15364	50	14510	0	0	0%	23:59:54
7	weretis.net	12521	22	10747	4	0	0%	23:59:46
8	erje	13839	11	13419	0	9	0%	23:59:55
9	xmission	17508	10	16166	2	0	0%	23:59:55
10	news.redatomik.org	17026	9	16296	0	0	0%	23:59:55
11	eternal-september	15524	7	14573	0	0	0%	23:59:52
12	news.mixmin.net	16812	7	16309	0	0	0%	23:59:48
13	neva.ru	16496	7	16016	110	0	0%	23:59:43
14	news.swapon.de	1808	6	1772	0	0	0%	23:52:47
15	news.fcku.it	1112	0	1100	0	0	0%	22:23:30
TOTAL: 15		235391	3606	202585	1364	17	1%	358:14:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.8 MB	14.9 KB	7.9 MB	0.1 KB/s	9.2 KB	23:59:52
2	xanadu-bbs	7.3 MB	0.0 KB	7.3 MB	0.1 KB/s	10.5 KB	23:59:55
3	fu-berlin.de	7.2 MB	0.0 KB	7.2 MB	0.1 KB/s	11.3 KB	23:59:55
4	news.bbs.geek.nz	3.0 MB	34.9 KB	3.1 MB	0.0 KB/s	2.9 KB	23:59:53
5	usenet.pasdenom.info	473.0 KB	8.1 MB	8.6 MB	0.1 KB/s	6.3 KB	23:59:53
6	aioe	101.0 KB	0.0 KB	101.0 KB	0.0 KB/s	2.0 KB	23:59:54
7	weretis.net	58.1 KB	14.4 KB	72.6 KB	0.0 KB/s	2.8 KB	23:59:46
8	erje	19.6 KB	0.0 KB	19.6 KB	0.0 KB/s	1.8 KB	23:59:55
9	xmission	18.4 KB	32.8 KB	51.2 KB	0.0 KB/s	4.3 KB	23:59:55
10	neva.ru	16.8 KB	266.8 KB	283.6 KB	0.0 KB/s	2.4 KB	23:59:43
11	news.mixmin.net	15.7 KB	0.0 KB	15.7 KB	0.0 KB/s	2.2 KB	23:59:48
12	news.redatomik.org	14.9 KB	0.0 KB	14.9 KB	0.0 KB/s	1.7 KB	23:59:55
13	eternal-september	12.1 KB	0.0 KB	12.1 KB	0.0 KB/s	1.7 KB	23:59:52
14	news.swapon.de	9.7 KB	0.0 KB	9.7 KB	0.0 KB/s	1.6 KB	23:52:47
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:23:30
TOTAL: 15		26.2 MB	8.5 MB	34.6 MB	0.0 KB/s	7.1 KB	358:14:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	47	77	216.5 KB	54	0	0	00:40:48
2	cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	1	11	14.4 KB	2	0	0	00:03:12
3	a131033.upc-a.chello.nl	39	10	14.5 KB	2	0	0	11:57:45
4	96-42-18-226.dhcp.mdsn.wi.charter.com	19	6	9.7 KB	6	6	5	05:44:40
TOTAL: 4		106	104	255.1 KB	64	6	5	21:26:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	47	77	216.5 KB	54	0	0	00:40:48
2	*.19-1.cable.virginm.net	1	11	14.4 KB	2	0	0	00:03:12
3	*.upc-a.chello.nl	39	10	14.5 KB	2	0	0	11:57:45
4	*.dhcp.mdsn.wi.charter.com	19	6	9.7 KB	6	6	5	05:44:40
5	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:03
6	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
7	*.ph.ph.cox.net	5	0	0.0 KB	0	0	0	02:30:15
TOTAL: 7		113	104	255.1 KB	64	6	5	21:26:47

NNRP auth users [Top 20]:

User	Conn
moussa	47
ohlandl	5
TOTAL: 2	52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.153	0.609	0.000	11:57:45
96-42-18-226.dhcp.mdsn.wi.charter.com	1.633	0.331	0.018	05:44:40
ip72-222-200-59.ph.ph.cox.net	0.476	0.063	0.000	02:30:15
141.168.117.108	5.492	1.049	0.317	00:40:48
c-67-160-62-214.hsd1.wa.comcast.net	0.050	0.014	0.000	00:30:00
cpc116472-smal17-2-0-cust454.19-1.cable.virginm.net	0.116	0.039	0.000	00:03:12
census9.shodan.io	0.085	0.015	0.000	00:00:03
TOTAL: 7	11.005	2.120	0.335	21:26:47

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-59.ph.ph.cox.net	5
census9.shodan.io	1
c-67-160-62-214.hsd1.wa.comcast.net	1
TOTAL: 3	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-18-226.dhcp.mdsn.wi.charter.com	5	0
ip72-222-200-59.ph.ph.cox.net	5	0
c-67-160-62-214.hsd1.wa.comcast.net	1	0
TOTAL: 3	11	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	78	75.0%
2	alt	26	25.0%
TOTAL: 2		104	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	32
2	alt.comp.freeware	26
3	comp.arch	17
4	comp.ai.philosophy	10
5	comp.sys.ibm.pc.games.action	5
6	comp.sys.ibm.pc.games.rpg	4
7	comp.ai.neural-nets	2
8	comp.os.cpm	2
9	comp.os.vms	2
10	comp.os.os2.setup.misc	1
11	comp.lang.pl1	1
12	comp.sys.ibm.pc.hardware.storage	1
13	comp.sys.hp.hardware	1
TOTAL: 13		104

Daily Usenet report for csiph.com

Mar 25 04:15:00 -- Mar 26 04:15:00