Daily Usenet report for csiph.com: Aug 12 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20422	48.4%	5.2 MB	64.5%
innd	17873	42.4%	2.2 MB	27.6%
innfeed	3369	8.0%	598.5 KB	7.3%
nnrpd	443	1.1%	48.1 KB	0.6%
nocem	54	0.1%	5.4 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	42162	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	292891	57.5%
Negative hits	196594	38.6%
Do not exist	19800	3.9%
Cache misses	465	0.1%
TOTAL: 4	509750	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.447	0.0%	116	0.000	3.853	40.667
article cleanup	00:00:00.105	0.0%	19088	0.000	0.006	0.033
article logging	00:00:00.290	0.0%	19482	0.000	0.015	0.120
article parse	00:00:01.572	0.0%	77660	0.000	0.020	0.219
article write	00:00:06.602	0.0%	15808	0.086	0.418	13.645
artlog/artcncl	00:00:00.000	0.0%	87	0.000	0.000	0.000
artlog/artparse	00:00:00.021	0.0%	892	0.000	0.024	0.333
data move	00:00:00.114	0.0%	495767	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.124	0.0%	113	0.000	1.097	27.667
hishave/artcncl	00:00:00.027	0.0%	116	0.000	0.233	1.300
hishave/artparse	00:00:00.003	0.0%	890	0.000	0.003	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.089	0.0%	512514	0.000	0.010	0.348
history sync	00:00:00.865	0.0%	380	0.000	2.276	13.000
history write	00:00:12.015	0.0%	19087	0.071	0.629	10.550
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hiswrite/artparse	00:00:00.791	0.0%	890	0.000	0.889	9.433
idle	23:58:45.828	99.8%	501418	86.406	172.163	518.897
nntp read	00:00:02.044	0.0%	501093	0.001	0.004	0.017
overview write	00:00:44.684	0.1%	15808	0.132	2.827	365.636
perl filter	00:00:32.152	0.0%	19085	1.105	1.685	2.826
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.253	0.0%	31666	0.000	0.008	0.042
TOTAL: 24:01:07.212	24:00:33.026	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	18170	0.000	0.002	0.023
article prepare	00:00:00.002	0.0%	12932	0.000	0.000	0.031
article read	00:00:00.280	0.0%	983	0.000	0.285	3.750
backlog stats	00:00:00.191	0.0%	331837	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.234	0.0%	256784	0.001	0.005	0.015
data write	00:00:01.505	0.0%	245041	0.001	0.006	0.015
idle	23:53:46.906	100.0%	331837	125.912	259.244	749.154
status file	00:00:00.197	0.0%	272	0.000	0.724	3.167
TOTAL: 23:53:57.576	23:53:50.351	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	18:38:22.530	100.0%	371	94.500	180869.353	600131.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.008	0.0%	870	0.000	0.009	0.333
readart	00:00:00.420	0.0%	30	0.000	14.000	61.000
TOTAL: 18:38:44.960	18:38:22.958	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	32383	3370	28711	302	10%	48:38:51
2	feeder.eternal-september.org	4	17149	1881	15208	60	10%	22:58:51
3	newsfeeds.xmission.com	7	14627	1783	12838	6	12%	22:46:10
4	csiph-out.feeder.erje.net	1440	76071	1607	74084	380	2%	118:42:01
5	news-out.aioe.org	2	15468	1533	13912	23	9%	24:09:02
6	csiph-out.news.xs4all.nl	863	17640	1189	15716	735	6%	71:11:39
7	news-out.mixmin.net	2	16623	1184	15058	381	7%	24:08:57
8	news.freedyn.net	2	19091	977	16891	1223	5%	24:08:53
9	newsfeed.neva.ru	143	18564	900	17084	580	4%	71:45:26
10	newsfeed.fu-berlin.de	14	15528	854	14585	89	5%	26:25:03
11	csiph-out.feed.uzoreto.com	864	25125	308	24515	302	1%	71:17:26
12	news.fcku.it	13	1424	139	1284	1	9%	21:54:09
13	news.swapon.de	2	1386	23	1362	1	1%	23:04:56
14	nntp.pasdenom.info	2	10656	19	10585	52	0%	19:56:10
15	news.samoylyk.net	1	15277	15	15260	2	0%	23:59:54
16	news.bbs.nz	2	14168	3	14165	0	0%	23:59:30
17	localhost	35	2	2	0	0	100%	00:00:01
18	xanadu-bbs.net	20	640	0	640	0	0%	22:55:43
TOTAL: 18		3419	311822	15787	291898	4137	5%	662:02:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	11.1 MB	0.0 KB	7.7 MB	18.9 MB	58%	5.3 KB
2	csiph-out.feeder.erje.net	10.1 MB	52.1 KB	9.7 MB	19.8 MB	51%	10.2 KB
3	newsfeeds.xmission.com	5.4 MB	0.0 KB	474.8 KB	5.9 MB	92%	3.4 KB
4	feeder.eternal-september.org	4.9 MB	0.0 KB	931.7 KB	5.9 MB	84%	3.1 KB
5	news-out.aioe.org	4.1 MB	0.0 KB	47.1 KB	4.2 MB	98%	2.7 KB
6	news-out.mixmin.net	3.4 MB	0.0 KB	669.2 KB	4.1 MB	83%	2.7 KB
7	csiph-out.news.xs4all.nl	3.1 MB	0.0 KB	3.1 MB	6.2 MB	49%	3.3 KB
8	news.freedyn.net	2.6 MB	24.6 KB	65.2 MB	67.8 MB	3%	31.6 KB
9	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	296.9 KB	2.6 MB	88%	2.8 KB
10	newsfeed.neva.ru	2.2 MB	0.0 KB	1.6 MB	3.8 MB	57%	2.6 KB
11	csiph-out.feed.uzoreto.com	986.6 KB	0.0 KB	4.2 MB	5.2 MB	18%	8.7 KB
12	news.fcku.it	276.7 KB	0.0 KB	1.5 KB	278.3 KB	99%	2.0 KB
13	news.swapon.de	64.0 KB	0.0 KB	112.5 KB	176.5 KB	36%	7.4 KB
14	news.samoylyk.net	50.3 KB	0.0 KB	65.9 KB	116.2 KB	43%	6.8 KB
15	nntp.pasdenom.info	35.9 KB	0.0 KB	83.5 KB	119.5 KB	30%	1.7 KB
16	news.bbs.nz	6.9 KB	0.0 KB	0.0 KB	6.9 KB	100%	2.3 KB
17	localhost	2.4 KB	0.0 KB	0.0 KB	2.4 KB	100%	1.2 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		50.7 MB	76.7 KB	94.1 MB	144.8 MB	34%	7.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 12 04:15:00 - 04:59:59	285	1.8%	0.11	1.5 MB	2.9%	0.56
Aug 12 05:00:00 - 05:59:59	380	2.4%	0.11	1.1 MB	2.2%	0.32
Aug 12 06:00:00 - 06:59:59	421	2.7%	0.12	1.9 MB	3.7%	0.53
Aug 12 07:00:00 - 07:59:59	520	3.3%	0.14	1.7 MB	3.3%	0.48
Aug 12 08:00:00 - 08:59:59	783	5.0%	0.22	3.2 MB	6.3%	0.90
Aug 12 09:00:00 - 09:59:59	674	4.3%	0.19	1.8 MB	3.7%	0.52
Aug 12 10:00:00 - 10:59:59	756	4.8%	0.21	2.3 MB	4.5%	0.65
Aug 12 11:00:00 - 11:59:59	793	5.0%	0.22	2.3 MB	4.6%	0.65
Aug 12 12:00:00 - 12:59:59	716	4.5%	0.20	1.9 MB	3.8%	0.54
Aug 12 13:00:00 - 13:59:59	803	5.1%	0.22	2.4 MB	4.7%	0.67
Aug 12 14:00:00 - 14:59:59	1012	6.4%	0.28	3.8 MB	7.6%	1.09
Aug 12 15:00:00 - 15:59:59	852	5.4%	0.24	2.5 MB	5.0%	0.71
Aug 12 16:00:00 - 16:59:59	1012	6.4%	0.28	3.1 MB	6.2%	0.89
Aug 12 17:00:00 - 17:59:59	980	6.2%	0.27	2.7 MB	5.3%	0.76
Aug 12 18:00:00 - 18:59:59	900	5.7%	0.25	2.9 MB	5.7%	0.82
Aug 12 19:00:00 - 19:59:59	861	5.5%	0.24	2.6 MB	5.2%	0.74
Aug 12 20:00:00 - 20:59:59	826	5.2%	0.23	2.5 MB	5.0%	0.71
Aug 12 21:00:00 - 21:59:59	590	3.7%	0.16	1.6 MB	3.2%	0.46
Aug 12 22:00:00 - 22:59:59	632	4.0%	0.18	2.3 MB	4.5%	0.65
Aug 12 23:00:00 - 23:59:59	451	2.9%	0.13	1.3 MB	2.6%	0.37
Aug 13 00:00:00 - 00:59:59	420	2.7%	0.12	1.3 MB	2.5%	0.36
Aug 13 01:00:00 - 01:59:59	366	2.3%	0.10	1.1 MB	2.2%	0.31
Aug 13 02:00:00 - 02:59:59	357	2.3%	0.10	1.5 MB	2.9%	0.42
Aug 13 03:00:00 - 03:59:59	311	2.0%	0.09	1015.7 KB	2.0%	0.28
Aug 13 04:00:00 - 04:15:00	93	0.6%	0.10	279.3 KB	0.5%	0.31
TOTAL: 24:00:00	15794	100.0%	0.18	50.4 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.freedyn.net	1245	12	1	1232
2	csiph-out.news.xs4all.nl	735	23	0	712
3	newsfeed.neva.ru	584	207	0	377
4	newsfeed.fu-berlin.de	450	25	0	425
5	news-out.mixmin.net	385	1	0	384
6	csiph-out.feeder.erje.net	376	100	2	274
7	csiph-out.feed.uzoreto.com	311	15	0	296
8	csiph-out.news.weretis.net	301	113	0	188
9	feeder.eternal-september.org	66	21	0	45
10	nntp.pasdenom.info	52	0	0	52
11	news-out.aioe.org	25	22	0	3
12	newsfeeds.xmission.com	7	0	0	7
13	news.swapon.de	5	0	0	5
14	news.samoylyk.net	2	2	0	0
15	news.fcku.it	2	1	0	1
TOTAL: 15		4546	542	3	4001

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	127
lada.auto	42
alt.computer.workshop	34
lada.commerce	32
alt.bbs.synchronet	28
alt.binaries.pictures.purebeauty	25
mozilla.support.seamonkey	21
it-alt.sport.calcio.juventus	20
spline.eisfair	17
rocksolid.shared.test	15
mozilla.dev.platform	11
spline.fli4l.dev	11
alt.politics.usa	10
mozilla.support.firefox	9
oesterreich.tratsch	7
alt.slack.goathead	7
test.post	6
alt.binaries.yourmom	6
alt.binaries.e-book-flood	5
alt.job	4
TOTAL: 89	542

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2193
EMP (phn path)	197
EMP (phn nph)	97
EMP (phl)	60
Too many newsgroups	50
User-issued spam cancel	44
User-issued cancel	25
Binary: misplaced rar	17
Binary Payload	15
Scoring filter (10)	10
HTML Multipart	9
Scoring filter (9)	8
Too many newsgroups (meow)	2
HTML file attachment	1
HTML post	1
TOTAL: 15	2729

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	39	0	40	40
TOTAL: 1	39	8	40	40

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	530
	csiph-out.feeder.erje.net	162
	csiph-out.news.weretis.net	97
	csiph-out.feed.uzoreto.com	49
	csiph-out.news.xs4all.nl	26
	newsfeeds.xmission.com	5
	feeder.eternal-september.org	4
	newsfeed.fu-berlin.de	2
	news.swapon.de	1
	news-out.mixmin.net	1
	TOTAL: 10	877
Including strange strings
	newsfeed.fu-berlin.de	355
	csiph-out.news.weretis.net	15
	news-out.mixmin.net	6
	news.swapon.de	4
	news-out.aioe.org	3
	csiph-out.news.xs4all.nl	2
	feeder.eternal-september.org	2
	csiph-out.feed.uzoreto.com	2
	csiph-out.feeder.erje.net	2
	newsfeed.neva.ru	1
	TOTAL: 11	393
No colon-space in header
	csiph-out.news.xs4all.nl	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	2
TOTAL: 4		1317

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18285	509	15284	1	0	2%	23:59:55
2	news.samoylyk.net	16430	476	15305	0	80	2%	23:59:54
3	news.freedyn.net	15165	475	14284	14	0	3%	23:59:52
4	fu-berlin.de	14769	464	14305	0	0	3%	23:59:46
5	news.bbs.nz	29965	327	14982	7	1	1%	23:59:54
6	usenet.pasdenom.info	16102	109	15191	481	2255	0%	23:59:54
7	newsfeed.xs4all.nl	15291	23	14402	6	50	0%	23:59:54
8	aioe	13565	21	13007	0	0	0%	23:59:48
9	neva.ru	14315	20	13986	218	0	0%	23:59:54
10	xmission	17460	12	13546	0	31	0%	23:59:55
11	news.mixmin.net	14380	5	13914	0	0	0%	23:59:54
12	erje	14242	4	14013	35	0	0%	23:59:51
13	weretis.net	11943	3	11763	1	0	0%	23:59:55
14	news.uzoreto.com	15441	3	15155	14	0	0%	23:59:54
15	news.swapon.de	1586	3	1538	0	0	0%	23:57:20
16	eternal-september	13809	2	13277	0	0	0%	23:59:46
17	news.redatomik.org	0	0	0	0	15321	0%	23:59:55
18	news.fcku.it	1109	0	1107	0	0	0%	21:24:12
TOTAL: 18		243857	2456	215059	777	17738	1%	429:19:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.6 MB	3.2 KB	6.6 MB	0.1 KB/s	13.2 KB	23:59:55
2	news.freedyn.net	6.5 MB	24.5 KB	6.5 MB	0.1 KB/s	13.7 KB	23:59:52
3	news.samoylyk.net	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	14.0 KB	23:59:54
4	fu-berlin.de	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	14.3 KB	23:59:46
5	news.bbs.nz	960.0 KB	22.6 KB	982.6 KB	0.0 KB/s	2.9 KB	23:59:54
6	usenet.pasdenom.info	312.1 KB	6.5 MB	6.8 MB	0.1 KB/s	11.8 KB	23:59:54
7	newsfeed.xs4all.nl	79.4 KB	21.1 KB	100.5 KB	0.0 KB/s	3.5 KB	23:59:54
8	aioe	44.6 KB	0.0 KB	44.6 KB	0.0 KB/s	2.1 KB	23:59:48
9	neva.ru	41.6 KB	607.6 KB	649.3 KB	0.0 KB/s	2.7 KB	23:59:54
10	xmission	36.7 KB	0.0 KB	36.7 KB	0.0 KB/s	3.1 KB	23:59:55
11	news.mixmin.net	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	1.5 KB	23:59:54
12	erje	7.0 KB	151.8 KB	158.8 KB	0.0 KB/s	4.1 KB	23:59:51
13	news.swapon.de	6.1 KB	0.0 KB	6.1 KB	0.0 KB/s	2.0 KB	23:57:20
14	news.uzoreto.com	5.3 KB	66.5 KB	71.8 KB	0.0 KB/s	4.2 KB	23:59:54
15	weretis.net	4.6 KB	1.1 KB	5.7 KB	0.0 KB/s	1.4 KB	23:59:55
16	eternal-september	2.4 KB	0.0 KB	2.4 KB	0.0 KB/s	1.2 KB	23:59:46
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	21:24:12
TOTAL: 18		27.5 MB	7.4 MB	34.9 MB	0.0 KB/s	11.1 KB	429:19:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	6	17	30.3 KB	4	0	0	03:20:40
2	68-114-223-142.dhcp.mdsn.wi.charter.com	8	7	11.7 KB	3	2	2	02:04:19
3	a131033.upc-a.chello.nl	41	5	9.3 KB	5	0	0	11:03:32
4	208-71-239-109.cust.centrio.cz	4	1	1.8 KB	1	0	0	01:09:49
TOTAL: 4		59	30	53.1 KB	13	2	2	18:38:43

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	6	17	30.3 KB	4	0	0	03:20:40
2	*.dhcp.mdsn.wi.charter.com	8	7	11.7 KB	3	2	2	02:04:19
3	*.upc-a.chello.nl	41	5	9.3 KB	5	0	0	11:03:32
4	*.cust.centrio.cz	4	1	1.8 KB	1	0	0	01:09:49
5	*.nielsenmedia.com	2	0	0.0 KB	0	0	0	01:00:16
6	unresolved	1	0	0.0 KB	0	0	0	00:00:04
7	*.sfj.corp.censys.io	2	0	0.0 KB	0	0	0	00:00:01
TOTAL: 7		64	30	53.1 KB	13	2	2	18:38:43

NNRP auth users [Top 20]:

User	Conn
ctomczyk	4
ohlandl	2
TOTAL: 2	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.982	0.593	0.000	11:03:32
ip72-222-200-117.ph.ph.cox.net	0.899	0.113	0.000	03:20:40
68-114-223-142.dhcp.mdsn.wi.charter.com	0.729	0.124	0.005	02:04:19
208-71-239-109.cust.centrio.cz	0.393	0.071	0.055	01:09:49
challenger.nielsenmedia.com	0.140	0.024	0.000	01:00:16
122.228.19.80	0.072	0.008	0.000	00:00:04
worker-18.sfj.corp.censys.io	0.144	0.021	0.000	00:00:01
TOTAL: 7	5.359	0.954	0.060	18:38:43

Curious NNRP server explorers [Top 20]:

System	Conn
challenger.nielsenmedia.com	2
worker-18.sfj.corp.censys.io	2
122.228.19.80	1
TOTAL: 3	5

NNRP unrecognized commands (by host):

System	Conn
122.228.19.80	5
worker-18.sfj.corp.censys.io	2
TOTAL: 2	7

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
Host: 69.160.37.58:119	2
Connection: keep-alive	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
Accept: /	1
TOTAL: 5	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	6	0
68-114-223-142.dhcp.mdsn.wi.charter.com	3	0
challenger.nielsenmedia.com	2	0
208-71-239-109.cust.centrio.cz	2	0
worker-18.sfj.corp.censys.io	1	1
TOTAL: 5	14	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	28	93.3%
2	pl	1	3.3%
3	news	1	3.3%
TOTAL: 3		30	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	28
2	pl.comp.www.nowe-strony	1
3	news.admin.peering	1
TOTAL: 3		30

Daily Usenet report for csiph.com

Aug 12 04:15:00 -- Aug 13 04:15:00