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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20193	49.0%	5.3 MB	65.5%
innd	17134	41.6%	2.2 MB	26.7%
innfeed	3324	8.1%	586.6 KB	7.1%
nnrpd	510	1.2%	56.3 KB	0.7%
nocem	51	0.1%	5.3 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41213	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	300554	59.1%
Negative hits	187477	36.9%
Do not exist	19453	3.8%
Cache misses	1203	0.2%
TOTAL: 4	508687	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.173	0.0%	101	0.000	11.614	223.000
article cleanup	00:00:00.125	0.0%	18620	0.000	0.007	0.040
article logging	00:00:00.457	0.0%	19127	0.000	0.024	1.119
article parse	00:00:01.383	0.0%	78576	0.000	0.018	0.174
article write	00:01:59.995	0.1%	16267	0.620	7.377	91.079
artlog/artcncl	00:00:00.001	0.0%	97	0.000	0.010	0.250
artlog/artparse	00:00:00.036	0.0%	977	0.000	0.037	26.000
data move	00:00:00.132	0.0%	495914	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.020	0.0%	100	0.000	0.200	1.111
hishave/artcncl	00:00:00.013	0.0%	101	0.000	0.129	1.000
hishave/artparse	00:00:00.002	0.0%	977	0.000	0.002	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:06.030	0.0%	511619	0.002	0.012	0.162
history sync	00:00:00.817	0.0%	376	0.000	2.173	83.333
history write	00:00:12.165	0.0%	18617	0.248	0.653	6.807
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.621	0.0%	975	0.000	0.637	10.000
idle	23:55:03.305	99.6%	496808	93.001	173.313	444.948
nntp read	00:00:02.203	0.0%	502399	0.001	0.004	0.025
overview write	00:02:14.475	0.2%	16267	0.179	8.267	112.269
perl filter	00:00:32.327	0.0%	18617	1.366	1.736	7.281
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.286	0.0%	32581	0.000	0.009	0.034
TOTAL: 24:00:55.872	24:00:15.566	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.056	0.0%	18204	0.000	0.003	0.020
article prepare	00:00:00.002	0.0%	14886	0.000	0.000	0.010
article read	00:00:01.717	0.0%	1243	0.000	1.381	30.000
backlog stats	00:00:00.213	0.0%	360662	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.512	0.0%	271688	0.000	0.006	0.010
data write	00:00:01.692	0.0%	259460	0.001	0.007	0.014
idle	23:54:48.026	100.0%	360662	127.799	238.694	580.643
status file	00:00:01.404	0.0%	270	0.000	5.200	180.000
TOTAL: 23:55:02.763	23:54:54.622	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.027	0.0%	154	0.167	0.175	0.190
idle	17:13:22.570	100.0%	514	55.500	120627.568	600057.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	1183	0.000	0.006	0.167
readart	00:00:00.247	0.0%	16	0.000	15.438	48.000
TOTAL: 17:13:42.922	17:13:22.851	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	32063	3394	28439	230	10%	47:36:21
2	feeder.eternal-september.org	3	16631	2014	14583	34	12%	23:21:39
3	newsfeeds.xmission.com	3	15143	1793	13347	3	11%	23:15:44
4	csiph-out.news.xs4all.nl	861	17346	1719	14573	1054	9%	71:27:15
5	csiph-out.feeder.erje.net	1440	77102	1407	75341	354	1%	118:57:03
6	news-out.aioe.org	3	15904	1386	14472	46	8%	24:02:49
7	news.freedyn.net	3	18590	1037	16654	899	5%	24:08:44
8	news-out.mixmin.net	1	16371	1018	15143	210	6%	23:59:52
9	newsfeed.fu-berlin.de	12	14619	861	13733	25	5%	24:54:41
10	newsfeed.neva.ru	129	24082	832	23000	250	3%	69:35:32
11	news.fcku.it	10	2559	311	2246	2	12%	23:30:23
12	csiph-out.feed.uzoreto.com	864	24910	148	24574	188	0%	71:16:46
13	nntp.pasdenom.info	1	11373	35	11336	2	0%	23:59:52
14	news.swapon.de	4	1397	32	1365	0	2%	22:41:21
15	news.samoylyk.net	2	15727	16	15694	17	0%	24:56:15
16	localhost	35	4	4	0	0	100%	00:00:00
17	news.bbs.nz	1	15918	3	15915	0	0%	23:59:52
18	xanadu-bbs.net	16	593	1	592	0	0%	24:21:43
TOTAL: 18		3391	320332	16011	301007	3314	4%	666:05:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	10.6 MB	1.2 KB	7.6 MB	18.1 MB	58%	5.1 KB
2	csiph-out.feeder.erje.net	9.2 MB	16.6 KB	11.6 MB	20.7 MB	44%	12.1 KB
3	feeder.eternal-september.org	5.2 MB	0.0 KB	221.1 KB	5.5 MB	96%	2.7 KB
4	newsfeeds.xmission.com	4.8 MB	0.0 KB	109.7 KB	4.9 MB	97%	2.8 KB
5	csiph-out.news.xs4all.nl	4.7 MB	0.0 KB	9.6 MB	14.3 MB	32%	5.3 KB
6	news-out.aioe.org	3.1 MB	0.0 KB	84.6 KB	3.2 MB	97%	2.3 KB
7	news.freedyn.net	2.9 MB	1.2 KB	61.7 MB	64.6 MB	4%	34.2 KB
8	news-out.mixmin.net	2.7 MB	0.0 KB	540.2 KB	3.3 MB	83%	2.7 KB
9	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	89.1 KB	2.0 MB	95%	2.3 KB
10	newsfeed.neva.ru	1.7 MB	0.0 KB	852.6 KB	2.5 MB	66%	2.4 KB
11	csiph-out.feed.uzoreto.com	520.7 KB	0.0 KB	4.0 MB	4.5 MB	11%	13.8 KB
12	news.fcku.it	481.9 KB	0.0 KB	2.6 KB	484.5 KB	99%	1.5 KB
13	news.swapon.de	91.8 KB	0.0 KB	0.0 KB	91.8 KB	100%	2.9 KB
14	nntp.pasdenom.info	81.4 KB	0.0 KB	3.8 KB	85.2 KB	95%	2.3 KB
15	news.samoylyk.net	45.2 KB	0.0 KB	1.8 MB	1.9 MB	2%	58.6 KB
16	xanadu-bbs.net	9.4 KB	0.0 KB	0.0 KB	9.4 KB	100%	9.4 KB
17	news.bbs.nz	5.3 KB	0.0 KB	0.0 KB	5.3 KB	100%	1.8 KB
18	localhost	4.0 KB	0.0 KB	0.0 KB	4.0 KB	100%	1.0 KB
TOTAL: 18		47.9 MB	19.0 KB	98.2 MB	146.1 MB	32%	7.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 17 04:15:00 - 04:59:59	289	1.8%	0.11	1.1 MB	2.3%	0.43
Sep 17 05:00:00 - 05:59:59	447	2.7%	0.12	1.9 MB	4.0%	0.54
Sep 17 06:00:00 - 06:59:59	558	3.4%	0.15	1.9 MB	3.8%	0.53
Sep 17 07:00:00 - 07:59:59	720	4.4%	0.20	2.0 MB	4.2%	0.58
Sep 17 08:00:00 - 08:59:59	856	5.3%	0.24	2.8 MB	5.8%	0.80
Sep 17 09:00:00 - 09:59:59	767	4.7%	0.21	1.9 MB	4.0%	0.55
Sep 17 10:00:00 - 10:59:59	736	4.5%	0.20	2.2 MB	4.6%	0.63
Sep 17 11:00:00 - 11:59:59	832	5.1%	0.23	2.2 MB	4.6%	0.64
Sep 17 12:00:00 - 12:59:59	681	4.2%	0.19	1.7 MB	3.5%	0.48
Sep 17 13:00:00 - 13:59:59	815	5.0%	0.23	2.1 MB	4.3%	0.60
Sep 17 14:00:00 - 14:59:59	841	5.2%	0.23	2.4 MB	5.0%	0.68
Sep 17 15:00:00 - 15:59:59	1024	6.3%	0.28	3.2 MB	6.6%	0.92
Sep 17 16:00:00 - 16:59:59	954	5.9%	0.27	2.9 MB	6.0%	0.83
Sep 17 17:00:00 - 17:59:59	953	5.9%	0.26	3.1 MB	6.4%	0.89
Sep 17 18:00:00 - 18:59:59	878	5.4%	0.24	2.4 MB	5.0%	0.69
Sep 17 19:00:00 - 19:59:59	890	5.5%	0.25	2.2 MB	4.5%	0.62
Sep 17 20:00:00 - 20:59:59	898	5.5%	0.25	2.5 MB	5.1%	0.70
Sep 17 21:00:00 - 21:59:59	606	3.7%	0.17	1.7 MB	3.4%	0.47
Sep 17 22:00:00 - 22:59:59	516	3.2%	0.14	1.7 MB	3.5%	0.49
Sep 17 23:00:00 - 23:59:59	488	3.0%	0.14	1.4 MB	2.9%	0.40
Sep 18 00:00:00 - 00:59:59	366	2.3%	0.10	1.1 MB	2.2%	0.31
Sep 18 01:00:00 - 01:59:59	386	2.4%	0.11	1.1 MB	2.3%	0.32
Sep 18 02:00:00 - 02:59:59	348	2.1%	0.10	1.3 MB	2.7%	0.37
Sep 18 03:00:00 - 03:59:59	326	2.0%	0.09	968.3 KB	2.0%	0.27
Sep 18 04:00:00 - 04:15:00	87	0.5%	0.10	589.3 KB	1.2%	0.65
TOTAL: 24:00:00	16262	100.0%	0.19	48.4 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1110	27	0	1083
2	news.freedyn.net	921	10	1	910
3	newsfeed.fu-berlin.de	436	20	0	416
4	csiph-out.feeder.erje.net	363	58	1	304
5	newsfeed.neva.ru	249	47	0	202
6	csiph-out.news.weretis.net	244	83	1	160
7	news-out.mixmin.net	202	1	0	201
8	csiph-out.feed.uzoreto.com	190	3	0	187
9	news-out.aioe.org	50	12	0	38
10	feeder.eternal-september.org	39	11	0	28
11	news.samoylyk.net	18	0	0	18
12	news.swapon.de	5	0	0	5
13	newsfeeds.xmission.com	4	0	0	4
14	news.fcku.it	2	2	0	0
15	nntp.pasdenom.info	2	0	0	2
TOTAL: 15		3835	274	3	3558

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	21
alt.binaries.pictures.purebeauty	20
lada.talk	18
lada.auto	16
spline.eisfair	13
mozilla.dev.security.policy	12
mozilla.support.seamonkey	9
mozilla.dev.platform	8
mozilla.support.firefox	8
mozilla.general	8
alt.survival	8
alt.computer.workshop	7
mozilla.support.thunderbird	7
lada.photo	6
oesterreich.tratsch	6
mozilla.dev.media	5
alt.testing	5
alt.windows7.general	5
alt.math.undergrad	5
alt.politics.usa	5
TOTAL: 76	274

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1753
EMP (phn nph)	91
EMP (phl)	61
EMP (phn path)	54
HTML Multipart	29
Too many newsgroups	17
User-issued cancel	15
Binary: misplaced rar	11
Binary Payload	8
Scoring filter (10)	6
Scoring filter (17)	5
Scoring filter (16)	4
Scoring filter (8)	4
HTML Image Tags	3
Too many newsgroups (low_xpost)	2
Scoring filter (9)	2
HTML post	1
Scoring filter (14)	1
Binary: misplaced txt	1
Scoring filter (13)	1
TOTAL: 23	2072

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	30	0	31	31
TOTAL: 1	30	17	31	31

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	498
	csiph-out.feeder.erje.net	217
	csiph-out.news.weretis.net	100
	csiph-out.news.xs4all.nl	90
	csiph-out.feed.uzoreto.com	50
	news.samoylyk.net	17
	feeder.eternal-september.org	3
	newsfeeds.xmission.com	3
	news-out.mixmin.net	2
	TOTAL: 9	980
Including strange strings
	newsfeed.fu-berlin.de	411
	csiph-out.news.xs4all.nl	54
	csiph-out.news.weretis.net	20
	csiph-out.feeder.erje.net	6
	news.swapon.de	5
	news-out.mixmin.net	5
	csiph-out.feed.uzoreto.com	2
	news.freedyn.net	1
	news.samoylyk.net	1
	feeder.eternal-september.org	1
	TOTAL: 10	506
TOTAL: 3		1531

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	18515	901	15359	0	0	0	4%	23:59:55
2	news.samoylyk.net	16954	476	15736	1	0	41	2%	23:53:34
3	news.freedyn.net	15554	469	14686	40	0	0	3%	23:59:02
4	fu-berlin.de	15249	457	14792	0	0	0	2%	23:59:48
5	news.bbs.nz	30449	330	15430	17	0	0	1%	23:59:55
6	usenet.pasdenom.info	16663	111	15627	488	1	0	0%	23:59:55
7	erje	20055	50	14664	67	0	13	0%	23:59:55
8	newsfeed.xs4all.nl	14897	46	13991	4	0	44	0%	23:56:30
9	neva.ru	15453	45	14585	92	0	1865	0%	23:59:55
10	news.uzoreto.com	15940	16	15602	92	0	1	0%	23:52:04
11	aioe	14347	13	13818	0	0	0	0%	23:59:55
12	news.mixmin.net	14972	12	14534	0	0	0	0%	23:59:48
13	xmission	18325	7	13932	1	0	0	0%	23:59:31
14	news.swapon.de	1567	6	1530	0	0	0	0%	23:58:15
15	weretis.net	12306	5	12149	1	0	0	0%	23:59:55
16	eternal-september	14062	5	13571	0	0	0	0%	23:59:55
17	news.redatomik.org	0	0	0	0	0	15780	0%	23:59:55
18	news.fcku.it	2109	0	2104	0	0	0	0%	22:58:14
TOTAL: 18		257417	2949	222110	803	1	17744	1%	430:36:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	0.0 KB	7.2 MB	0.1 KB/s	8.2 KB	23:59:55
2	news.freedyn.net	6.2 MB	80.3 KB	6.3 MB	0.1 KB/s	12.6 KB	23:59:02
3	fu-berlin.de	6.1 MB	0.0 KB	6.1 MB	0.1 KB/s	13.7 KB	23:59:48
4	news.samoylyk.net	6.1 MB	38.7 KB	6.1 MB	0.1 KB/s	13.1 KB	23:53:34
5	news.bbs.nz	780.6 KB	88.2 KB	868.8 KB	0.0 KB/s	2.5 KB	23:59:55
6	usenet.pasdenom.info	237.2 KB	6.2 MB	6.4 MB	0.1 KB/s	11.0 KB	23:59:55
7	erje	216.7 KB	213.5 KB	430.2 KB	0.0 KB/s	3.7 KB	23:59:55
8	newsfeed.xs4all.nl	128.8 KB	15.7 KB	144.5 KB	0.0 KB/s	2.9 KB	23:56:30
9	neva.ru	111.1 KB	203.1 KB	314.3 KB	0.0 KB/s	2.3 KB	23:59:55
10	news.uzoreto.com	24.9 KB	297.0 KB	321.9 KB	0.0 KB/s	3.0 KB	23:52:04
11	aioe	20.0 KB	0.0 KB	20.0 KB	0.0 KB/s	1.5 KB	23:59:55
12	news.mixmin.net	18.4 KB	0.0 KB	18.4 KB	0.0 KB/s	1.5 KB	23:59:48
13	xmission	10.8 KB	2.1 KB	13.0 KB	0.0 KB/s	1.6 KB	23:59:31
14	weretis.net	7.6 KB	5.4 KB	13.0 KB	0.0 KB/s	2.2 KB	23:59:55
15	news.swapon.de	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	1.2 KB	23:58:15
16	eternal-september	6.2 KB	0.0 KB	6.2 KB	0.0 KB/s	1.2 KB	23:59:55
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:58:14
TOTAL: 18		27.1 MB	7.1 MB	34.2 MB	0.0 KB/s	9.3 KB	430:36:01

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	38	10	16.1 KB	3	0	0	10:23:27
2	24.178.44.209	14	4	4.0 KB	4	4	4	04:18:28
3	91.218.217.131.siec.idealan.pl	1	2	3.3 KB	1	0	0	00:30:19
TOTAL: 3		53	16	23.4 KB	8	4	4	17:13:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	38	10	16.1 KB	3	0	0	10:23:27
2	unresolved	25	4	4.0 KB	4	4	4	04:19:35
3	*.218.217.131.siec.idealan.pl	1	2	3.3 KB	1	0	0	00:30:19
4	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:12
5	*.sfj.corp.censys.io	1	0	0.0 KB	0	0	0	00:00:02
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
7	*.binaryedge.ninja	1	0	0.0 KB	0	0	0	00:00:02
TOTAL: 7		71	16	23.4 KB	8	4	4	17:13:42

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
ctomczyk	1
TOTAL: 2	5

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.789	0.534	0.000	10:23:27
24.178.44.209	1.117	0.245	0.013	04:18:28
ip72-222-200-117.ph.ph.cox.net	0.441	0.104	0.000	02:00:12
91.218.217.131.siec.idealan.pl	0.117	0.017	0.003	00:30:19
172.221.41.102	0.106	0.093	0.000	00:00:56
94.162.47.184	0.201	0.040	0.000	00:00:03
94.163.244.232	0.192	0.055	0.000	00:00:03
worker-18.sfj.corp.censys.io	0.065	0.015	0.000	00:00:02
min-li-usnj-08-01-40186-y-prod.binaryedge.ninja	0.075	0.008	0.000	00:00:02
94.162.66.120	0.072	0.008	0.000	00:00:01
94.163.208.57	0.067	0.015	0.000	00:00:01
94.162.32.200	0.065	0.016	0.000	00:00:01
scan-141.security.ipip.net	0.065	0.016	0.000	00:00:01
TOTAL: 13	5.372	1.166	0.016	17:13:42

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	4
94.163.244.232	3
94.162.47.184	3
172.221.41.102	2
min-li-usnj-08-01-40186-y-prod.binaryedge.ninja	1
94.162.32.200	1
94.163.208.57	1
worker-18.sfj.corp.censys.io	1
94.162.66.120	1
scan-141.security.ipip.net	1
TOTAL: 10	18

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
24.178.44.209	6	0
ip72-222-200-117.ph.ph.cox.net	4	0
worker-18.sfj.corp.censys.io	1	1
91.218.217.131.siec.idealan.pl	1	0
TOTAL: 4	12	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	14
2	comp.lang.javascript	2
TOTAL: 2		16

Daily Usenet report for csiph.com

Sep 17 04:15:00 -- Sep 18 04:15:00