Daily Usenet report for csiph.com: Oct 14 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	23322	46.2%	4.7 MB	58.1%
innd	13520	26.8%	1.7 MB	20.9%
nnrpd	10794	21.4%	1.2 MB	15.2%
innfeed	2872	5.7%	483.0 KB	5.8%
nocem	18	0.0%	1.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	50527	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	275367	57.1%
Negative hits	183670	38.1%
Do not exist	22600	4.7%
Cache misses	783	0.2%
TOTAL: 4	482420	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.940	0.0%	45	1.000	43.111	172.000
article cleanup	00:00:00.175	0.0%	22330	0.000	0.008	0.036
article logging	00:00:00.400	0.0%	22858	0.000	0.017	0.071
article parse	00:00:01.386	0.0%	60537	0.000	0.023	0.316
article write	00:03:54.313	0.3%	15774	0.293	14.854	68.216
artlog/artcncl	00:00:00.001	0.0%	45	0.000	0.022	1.000
artlog/artparse	00:00:00.009	0.0%	414	0.000	0.022	0.250
data move	00:00:00.125	0.0%	449672	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.067	0.0%	45	0.000	1.489	5.400
hishave/artcncl	00:00:00.327	0.0%	45	0.000	7.267	31.900
hishave/artparse	00:00:00.003	0.0%	414	0.000	0.007	0.056
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:36.674	0.0%	483802	0.002	0.076	0.764
history sync	00:00:01.297	0.0%	374	0.000	3.468	90.000
history write	00:00:44.173	0.1%	22248	0.154	1.985	15.143
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.616	0.0%	414	0.000	1.488	16.000
idle	23:51:13.054	99.3%	447093	46.354	192.070	545.104
nntp read	00:00:05.876	0.0%	457668	0.002	0.013	0.219
overview write	00:03:00.378	0.2%	15774	1.303	11.435	179.189
perl filter	00:00:39.398	0.0%	22248	0.577	1.771	4.712
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.307	0.0%	31565	0.000	0.010	0.035
TOTAL: 24:01:04.235	24:00:20.519	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.043	0.0%	16066	0.000	0.003	0.019
article prepare	00:00:00.003	0.0%	16192	0.000	0.000	0.016
article read	00:00:07.540	0.0%	1973	0.000	3.822	50.125
backlog stats	00:00:00.206	0.0%	313279	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.729	0.0%	231135	0.000	0.007	0.014
data write	00:00:01.449	0.0%	216720	0.001	0.007	0.016
idle	23:56:37.627	100.0%	313279	77.307	275.147	733.642
status file	00:00:00.769	0.0%	272	0.000	2.827	190.000
TOTAL: 23:56:59.995	23:56:49.366	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	55:42:40.147	84.5%	755738	0.000	265.383	600057.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:16.103	0.0%	2047315	0.000	0.008	29.406
readart	10:12:18.819	15.5%	723605	0.000	50.772	572.000
TOTAL: 65:57:34.919	65:55:15.069	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1441	85725	3671	80588	1466	4%	118:50:47
2	csiph-out.news.weretis.net	4	36670	2746	33707	217	7%	48:50:40
3	newsfeeds.xmission.com	2	14012	2200	11797	15	15%	21:14:10
4	feeder.eternal-september.org	3	20280	2043	16125	2112	10%	24:39:04
5	news-out.aioe.org	3	15448	1723	13691	34	11%	24:09:51
6	newsfeed.fu-berlin.de	9	16355	966	15211	178	5%	23:07:14
7	newsfeed.neva.ru	141	23731	742	21043	1946	3%	70:52:27
8	news.redatomik.org	2	15194	632	14521	41	4%	23:59:38
9	news-out.mixmin.net	2	16616	554	15276	786	3%	23:59:28
10	news.freedyn.net	3	19307	154	19052	101	0%	24:30:37
11	news.fcku.it	8	1718	117	1597	4	6%	22:07:55
12	nntp.pasdenom.info	4	16313	116	16159	38	0%	23:58:24
13	news.swapon.de	7	1524	65	1451	8	4%	22:43:40
14	news.bbs.nz	1	15775	5	15770	0	0%	23:59:52
15	localhost	30	4	4	0	0	100%	00:00:09
16	xanadu-bbs.net	26	495	0	495	0	0%	22:26:49
TOTAL: 16		1686	299167	15738	276483	6946	5%	519:30:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	12.9 MB	67.2 KB	17.8 MB	30.8 MB	41%	6.1 KB
2	csiph-out.news.weretis.net	8.7 MB	1.4 KB	3.4 MB	12.1 MB	71%	4.2 KB
3	newsfeeds.xmission.com	6.6 MB	0.0 KB	711.9 KB	7.3 MB	90%	3.4 KB
4	feeder.eternal-september.org	5.5 MB	31.6 KB	8.0 MB	13.6 MB	40%	3.3 KB
5	news-out.aioe.org	4.5 MB	3.9 KB	219.1 KB	4.7 MB	95%	2.8 KB
6	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	480.8 KB	2.6 MB	82%	2.4 KB
7	news-out.mixmin.net	2.1 MB	1.8 KB	1.8 MB	3.9 MB	54%	3.0 KB
8	newsfeed.neva.ru	2.0 MB	20.2 KB	5.6 MB	7.7 MB	26%	2.9 KB
9	news.redatomik.org	1.7 MB	3.4 KB	1.1 MB	2.9 MB	60%	4.4 KB
10	news.freedyn.net	425.4 KB	0.0 KB	177.0 KB	602.4 KB	70%	2.4 KB
11	nntp.pasdenom.info	312.8 KB	0.0 KB	105.1 KB	417.9 KB	74%	2.7 KB
12	news.fcku.it	222.1 KB	0.0 KB	35.5 KB	257.6 KB	86%	2.1 KB
13	news.swapon.de	156.9 KB	0.0 KB	4.1 MB	4.3 MB	3%	59.9 KB
14	news.bbs.nz	11.3 KB	0.0 KB	0.0 KB	11.3 KB	100%	2.3 KB
15	localhost	6.0 KB	0.0 KB	0.0 KB	6.0 KB	100%	1.5 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		47.4 MB	129.3 KB	43.7 MB	91.2 MB	51%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 14 04:15:00 - 04:59:59	259	1.6%	0.10	822.7 KB	1.7%	0.30
Oct 14 05:00:00 - 05:59:59	410	2.6%	0.11	1000.3 KB	2.1%	0.28
Oct 14 06:00:00 - 06:59:59	433	2.7%	0.12	1.3 MB	2.7%	0.37
Oct 14 07:00:00 - 07:59:59	677	4.3%	0.19	2.4 MB	5.1%	0.69
Oct 14 08:00:00 - 08:59:59	729	4.6%	0.20	2.5 MB	5.2%	0.70
Oct 14 09:00:00 - 09:59:59	706	4.5%	0.20	2.6 MB	5.4%	0.73
Oct 14 10:00:00 - 10:59:59	809	5.1%	0.22	2.8 MB	5.8%	0.79
Oct 14 11:00:00 - 11:59:59	775	4.9%	0.22	2.1 MB	4.4%	0.59
Oct 14 12:00:00 - 12:59:59	764	4.8%	0.21	2.3 MB	4.9%	0.66
Oct 14 13:00:00 - 13:59:59	769	4.9%	0.21	2.0 MB	4.2%	0.56
Oct 14 14:00:00 - 14:59:59	860	5.5%	0.24	3.0 MB	6.4%	0.86
Oct 14 15:00:00 - 15:59:59	838	5.3%	0.23	2.2 MB	4.6%	0.62
Oct 14 16:00:00 - 16:59:59	911	5.8%	0.25	2.4 MB	5.1%	0.68
Oct 14 17:00:00 - 17:59:59	894	5.7%	0.25	2.3 MB	4.8%	0.65
Oct 14 18:00:00 - 18:59:59	899	5.7%	0.25	2.7 MB	5.6%	0.76
Oct 14 19:00:00 - 19:59:59	816	5.2%	0.23	2.1 MB	4.5%	0.61
Oct 14 20:00:00 - 20:59:59	837	5.3%	0.23	2.7 MB	5.7%	0.76
Oct 14 21:00:00 - 21:59:59	766	4.9%	0.21	2.3 MB	4.8%	0.65
Oct 14 22:00:00 - 22:59:59	613	3.9%	0.17	1.9 MB	3.9%	0.53
Oct 14 23:00:00 - 23:59:59	496	3.1%	0.14	1.4 MB	3.0%	0.40
Oct 15 00:00:00 - 00:59:59	410	2.6%	0.11	1.3 MB	2.8%	0.37
Oct 15 01:00:00 - 01:59:59	336	2.1%	0.09	1.1 MB	2.3%	0.31
Oct 15 02:00:00 - 02:59:59	337	2.1%	0.09	1.1 MB	2.4%	0.33
Oct 15 03:00:00 - 03:59:59	319	2.0%	0.09	1015.1 KB	2.1%	0.28
Oct 15 04:00:00 - 04:15:00	105	0.7%	0.12	386.2 KB	0.8%	0.43
TOTAL: 24:00:00	15768	100.0%	0.18	47.5 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	feeder.eternal-september.org	2128	38	21	2069
2	newsfeed.neva.ru	1959	234	9	1716
3	csiph-out.feeder.erje.net	1481	151	42	1288
4	news-out.mixmin.net	794	1	1	792
5	newsfeed.fu-berlin.de	656	24	0	632
6	csiph-out.news.weretis.net	225	53	1	171
7	news.freedyn.net	101	1	0	100
8	news.redatomik.org	44	0	1	43
9	news-out.aioe.org	37	29	2	6
10	news.swapon.de	35	0	0	35
11	nntp.pasdenom.info	33	26	0	7
12	newsfeeds.xmission.com	15	3	0	12
13	news.fcku.it	4	0	0	4
TOTAL: 13		7512	560	77	6875

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	183
lada.auto	40
local.spam.fr	25
alt.survival	22
alt.bbs.synchronet	20
mozilla.support.firefox	18
alt.windows7.general	16
gmane.comp.gis.openstreetmap.tagging	16
lada.photo	16
gmane.linux.alsa.devel	14
spline.eisfair	13
it-alt.sport.calcio.juventus	12
lada.commerce	11
oesterreich.tratsch	11
alt.pl.nieruchomosci.gielda	11
alt.politics	9
alt.philosophy	7
alt.binaries.ebooks	7
spline.fli4l.geschnatter	6
it-alt.comp.software.mozilla	6
TOTAL: 91	560

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3204
User-issued cancel	2150
EMP (phn path)	357
EMP (phl)	65
Too many newsgroups	46
Binary Payload	36
EMP (phn nph)	16
HTML Multipart	16
User-issued spam cancel	6
Scoring filter (9)	5
Scoring filter (10)	4
Binary: misplaced rar	3
Binary: misplaced par2	2
Scoring filter (17)	2
Binary Image: misplaced jpg	2
Scoring filter (8)	2
Angle-bracket bot	2
Scoring filter (16)	2
Scoring filter (12)	1
Binary: misplaced 004	1
TOTAL: 26	5928

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	268
	feeder.eternal-september.org	56
	csiph-out.news.weretis.net	49
	news.redatomik.org	13
	newsfeeds.xmission.com	11
	news-out.mixmin.net	9
	news.swapon.de	7
	TOTAL: 7	413
Including strange strings
	newsfeed.fu-berlin.de	475
	news.swapon.de	27
	csiph-out.feeder.erje.net	14
	news-out.aioe.org	4
	csiph-out.news.weretis.net	4
	news.redatomik.org	2
	newsfeed.neva.ru	1
	feeder.eternal-september.org	1
	news-out.mixmin.net	1
	TOTAL: 9	529
TOTAL: 3		987

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	16394	745	14848	20	0	4%	23:59:55
2	xanadu-bbs	18351	706	15061	0	16	3%	23:59:55
3	fu-berlin.de	14631	564	14067	0	0	3%	23:59:31
4	news.bbs.nz	28909	163	15003	8	1	0%	23:59:55
5	usenet.pasdenom.info	30010	134	14208	1311	298	0%	23:59:55
6	erje	14484	46	11950	66	0	0%	23:59:14
7	aioe	13817	21	12868	0	0	0%	23:59:53
8	neva.ru	14384	14	14009	201	0	0%	23:59:36
9	weretis.net	11572	12	10877	11	0	0%	23:58:26
10	xmission	14538	11	12705	1	0	0%	23:59:55
11	news.redatomik.org	15455	7	14534	1	4	0%	23:59:55
12	eternal-september	14060	6	13069	0	0	0%	23:59:24
13	news.mixmin.net	14608	5	14045	0	0	0%	23:59:47
14	news.swapon.de	1541	4	1512	0	0	0%	23:23:23
15	news.fcku.it	1044	0	1031	0	0	0%	21:16:27
TOTAL: 15		223798	2438	179787	1619	319	1%	356:35:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	5.8 MB	117.0 KB	5.9 MB	0.1 KB/s	7.9 KB	23:59:55
2	xanadu-bbs	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	8.1 KB	23:59:55
3	fu-berlin.de	5.2 MB	0.0 KB	5.2 MB	0.1 KB/s	9.5 KB	23:59:31
4	usenet.pasdenom.info	447.6 KB	6.4 MB	6.8 MB	0.1 KB/s	4.8 KB	23:59:55
5	news.bbs.nz	408.6 KB	18.3 KB	426.9 KB	0.0 KB/s	2.5 KB	23:59:55
6	erje	202.7 KB	238.6 KB	441.3 KB	0.0 KB/s	3.9 KB	23:59:14
7	news.mixmin.net	43.1 KB	0.0 KB	43.1 KB	0.0 KB/s	8.6 KB	23:59:47
8	aioe	41.3 KB	0.0 KB	41.3 KB	0.0 KB/s	2.0 KB	23:59:53
9	weretis.net	36.7 KB	28.5 KB	65.1 KB	0.0 KB/s	2.8 KB	23:58:26
10	neva.ru	34.8 KB	555.8 KB	590.6 KB	0.0 KB/s	2.7 KB	23:59:36
11	xmission	21.6 KB	2.9 KB	24.5 KB	0.0 KB/s	2.0 KB	23:59:55
12	news.redatomik.org	12.2 KB	2.7 KB	14.8 KB	0.0 KB/s	1.9 KB	23:59:55
13	eternal-september	10.6 KB	0.0 KB	10.6 KB	0.0 KB/s	1.8 KB	23:59:24
14	news.swapon.de	6.0 KB	0.0 KB	6.0 KB	0.0 KB/s	1.5 KB	23:23:23
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:16:27
TOTAL: 15		17.9 MB	7.3 MB	25.2 MB	0.0 KB/s	6.4 KB	356:35:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-05-70-55-68-47.dsl.bell.ca	890	267692	566.9 MB	2244	0	0	45:04:49
2	167-179-154-5.a7b39a.mel.nbn.aussiebb.net	1	170	615.8 KB	7	0	0	00:02:43
3	24.178.46.151	21	6	10.5 KB	6	4	4	05:28:54
4	ip72-222-200-117.ph.ph.cox.net	5	6	10.0 KB	2	0	0	02:30:37
5	208-71-239-109.cust.centrio.cz	2	6	17.3 KB	2	0	0	01:02:32
6	a131033.upc-a.chello.nl	39	5	9.6 KB	3	0	0	11:47:35
TOTAL: 6		958	267885	567.5 MB	2264	4	4	65:57:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	890	267692	566.9 MB	2244	0	0	45:04:49
2	*.a7b39a.mel.nbn.aussiebb.net	1	170	615.8 KB	7	0	0	00:02:43
3	unresolved	24	6	10.5 KB	6	4	4	05:28:56
4	*.ph.ph.cox.net	5	6	10.0 KB	2	0	0	02:30:37
5	*.cust.centrio.cz	2	6	17.3 KB	2	0	0	01:02:32
6	*.upc-a.chello.nl	39	5	9.6 KB	3	0	0	11:47:35
7	*.dip0.t-ipconnect.de	1	0	0.0 KB	0	0	0	00:00:08
8	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:03
TOTAL: 8		963	267885	567.5 MB	2264	4	4	65:57:27

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
ctomczyk	2
TOTAL: 2	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp130-05-70-55-68-47.dsl.bell.ca	132.158	97.902	0.000	45:04:49
a131033.upc-a.chello.nl	2.934	0.562	0.000	11:47:35
24.178.46.151	1.935	0.411	0.016	05:28:54
ip72-222-200-117.ph.ph.cox.net	0.583	0.102	0.000	02:30:37
208-71-239-109.cust.centrio.cz	0.271	0.040	0.007	01:02:32
167-179-154-5.a7b39a.mel.nbn.aussiebb.net	0.113	0.039	0.000	00:02:43
p2e5b58f3.dip0.t-ipconnect.de	0.145	0.032	0.000	00:00:08
census6.shodan.io	0.057	0.008	0.000	00:00:03
185.100.87.249	0.171	0.031	0.000	00:00:02
119.133.131.43	0.092	0.015	0.000	00:00:00
?	0.080	0.024	0.000	00:00:00
TOTAL: 11	138.539	99.166	0.023	65:57:27

Curious NNRP server explorers [Top 20]:

System	Conn
185.100.87.249	2
census6.shodan.io	1
p2e5b58f3.dip0.t-ipconnect.de	1
119.133.131.43	1
TOTAL: 4	5

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
24.178.46.151	6	0
mtrlpq2314w-lp130-05-70-55-68-47.dsl.bell.ca	6	0
ip72-222-200-117.ph.ph.cox.net	5	0
208-71-239-109.cust.centrio.cz	2	0
TOTAL: 4	19	0

Newsgroup request counts (by category):

Category		Count	Pct
1	rec	180303	67.3%
2	alt	87447	32.6%
3	comp	111	0.0%
4	uk	9	0.0%
5	perl	4	0.0%
6	talk	4	0.0%
7	news	3	0.0%
8	fr	2	0.0%
9	soc	1	0.0%
10	sci	1	0.0%
TOTAL: 10		267885	100.0%

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

Newsgroup		Count
1	rec.sport.pro-wrestling	180282
2	alt.comp.freeware	78481
3	alt.os.linux.mageia	8937
4	comp.arch.embedded	29
5	comp.arch	27
6	comp.sys.ibm.ps2.hardware	17
7	comp.os.linux.advocacy	12
8	alt.atheism	11
9	rec.food.cooking	10
10	comp.mobile.android	7
11	alt.comp.os.windows-10	7
12	comp.sys.ibm.pc.games.action	6
13	comp.lang.javascript	6
14	comp.lang.xharbour	4
15	rec.gambling.poker	4
16	rec.sport.football.college	4
17	uk.legal.moderated	4
18	perl.cpan.uploads	4
19	uk.d-i-y	3
20	talk.origins	3
21	news.lists.filters	3
22	alt.os.linux.ubuntu	2
23	alt.usage.english	2
24	alt.home.repair	2
25	alt.os.linux.debian	2
26	fr.soc.politique	1
27	comp.sys.ibm.pc.games.strategic	1
28	rec.arts.sf.written	1
29	alt.sports.baseball.wash-nationals	1
30	sci.electronics.design	1
31	uk.legal	1
32	soc.net-people	1
33	talk.politics.misc	1
34	comp.sys.ibm.as400.misc	1
35	uk.music.charts	1
36	rec.drugs.cannabis	1
37	alt.bible.prophecy	1
38	alt.politics.economics	1
39	comp.sys.ibm.pc.games.rpg	1
40	fr.rec.sport.football	1
41	rec.boats	1
TOTAL: 41		267885

Daily Usenet report for csiph.com

Oct 14 04:15:00 -- Oct 15 04:15:00