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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	21142	48.6%	5.3 MB	64.2%
innd	18678	42.9%	2.3 MB	28.5%
innfeed	3330	7.6%	565.5 KB	6.7%
nnrpd	319	0.7%	34.6 KB	0.4%
nocem	76	0.2%	8.0 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	43546	100.0%	8.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	290263	55.6%
Negative hits	210518	40.4%
Do not exist	20573	3.9%
Cache misses	365	0.1%
TOTAL: 4	521719	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.381	0.0%	166	0.000	2.295	19.800
article cleanup	00:00:00.117	0.0%	19737	0.000	0.006	0.037
article logging	00:00:00.306	0.0%	20126	0.000	0.015	0.068
article parse	00:00:01.321	0.0%	78290	0.000	0.017	0.063
article write	00:00:05.247	0.0%	15794	0.170	0.332	2.740
artlog/artcncl	00:00:00.002	0.0%	120	0.000	0.017	0.500
artlog/artparse	00:00:00.013	0.0%	897	0.000	0.014	0.222
data move	00:00:00.124	0.0%	502759	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.056	0.0%	165	0.000	0.339	4.700
hishave/artcncl	00:00:00.010	0.0%	166	0.000	0.060	0.833
hishave/artparse	00:00:00.005	0.0%	897	0.000	0.006	0.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:06.940	0.0%	522200	0.001	0.013	0.121
history sync	00:00:00.854	0.0%	377	0.000	2.265	6.333
history write	00:00:13.669	0.0%	19732	0.069	0.693	3.476
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.574	0.0%	896	0.000	0.641	4.000
idle	23:58:46.405	99.8%	513002	86.955	168.277	537.778
nntp read	00:00:02.018	0.0%	512206	0.002	0.004	0.011
overview write	00:00:31.695	0.0%	15794	0.100	2.007	271.221
perl filter	00:00:32.426	0.0%	19724	1.243	1.644	2.655
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.284	0.0%	31658	0.000	0.009	0.030
TOTAL: 24:00:57.698	24:00:22.447	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.042	0.0%	16105	0.000	0.003	0.038
article prepare	00:00:00.002	0.0%	23416	0.000	0.000	0.006
article read	00:00:00.445	0.0%	2757	0.000	0.161	2.833
backlog stats	00:00:00.181	0.0%	349334	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.293	0.0%	264664	0.001	0.005	0.012
data write	00:00:01.602	0.0%	252149	0.001	0.006	0.012
idle	23:54:07.215	100.0%	349334	140.179	246.318	639.336
status file	00:00:00.194	0.0%	270	0.000	0.719	6.000
TOTAL: 23:54:19.792	23:54:10.974	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	10:22:49.801	100.0%	230	29.000	162477.396	600112.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.002	0.0%	530	0.000	0.004	0.143
readart	00:00:00.000	0.0%	1	0.000	0.000	0.000
TOTAL: 10:22:59.850	10:22:49.803	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	newsfeeds.xmission.com	3	16044	2988	13046	10	18%	26:32:30
2	feeder.eternal-september.org	3	17287	2089	15060	138	12%	23:57:05
3	csiph-out.news.xs4all.nl	861	18435	1761	15159	1515	9%	71:25:34
4	news.freedyn.net	3	19847	1692	17138	1017	8%	24:08:44
5	news-out.aioe.org	1	15248	1494	13744	10	9%	23:59:58
6	csiph-out.feeder.erje.net	1441	73695	1338	71983	374	1%	118:40:40
7	news-out.mixmin.net	1	16306	911	15152	243	5%	23:59:51
8	csiph-out.news.weretis.net	4	21669	849	20654	166	3%	32:50:48
9	newsfeed.neva.ru	145	16923	813	15246	864	4%	71:46:07
10	newsfeed.fu-berlin.de	16	12143	641	11442	60	5%	20:09:58
11	csiph-out.feed.uzoreto.com	864	25953	471	25120	362	1%	71:07:10
12	news.fcku.it	13	1965	235	1715	15	11%	21:54:42
13	nntp.pasdenom.info	2	11074	74	10989	11	0%	23:58:20
14	news.swapon.de	3	1336	34	1301	1	2%	21:52:47
15	news.samoylyk.net	2	15357	13	15343	1	0%	24:54:06
16	news.bbs.nz	1	15866	1	15865	0	0%	23:59:51
17	xanadu-bbs.net	14	561	0	561	0	0%	21:57:50
18	localhost	33	0	0	0	0	0%	00:00:10
TOTAL: 18		3410	299709	15404	279518	4787	5%	647:16:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.1 MB	0.0 KB	697.3 KB	9.8 MB	93%	3.4 KB
2	csiph-out.feeder.erje.net	8.3 MB	0.0 KB	11.2 MB	19.5 MB	42%	11.7 KB
3	feeder.eternal-september.org	5.6 MB	17.6 KB	490.8 KB	6.1 MB	91%	2.8 KB
4	csiph-out.news.xs4all.nl	5.5 MB	0.0 KB	14.5 MB	20.0 MB	27%	6.3 KB
5	news.freedyn.net	4.3 MB	0.0 KB	50.2 MB	54.5 MB	7%	20.6 KB
6	news-out.aioe.org	3.6 MB	0.0 KB	17.7 KB	3.6 MB	99%	2.5 KB
7	csiph-out.news.weretis.net	3.1 MB	0.0 KB	1.9 MB	4.9 MB	61%	5.0 KB
8	news-out.mixmin.net	2.5 MB	0.0 KB	496.8 KB	3.0 MB	84%	2.7 KB
9	csiph-out.feed.uzoreto.com	1.9 MB	0.0 KB	7.6 MB	9.5 MB	20%	11.6 KB
10	newsfeed.neva.ru	1.9 MB	15.3 KB	3.1 MB	5.0 MB	38%	3.0 KB
11	newsfeed.fu-berlin.de	1.5 MB	0.0 KB	127.2 KB	1.7 MB	92%	2.4 KB
12	news.fcku.it	426.1 KB	0.0 KB	115.1 KB	541.2 KB	78%	2.2 KB
13	nntp.pasdenom.info	173.8 KB	0.0 KB	22.8 KB	196.6 KB	88%	2.3 KB
14	news.swapon.de	121.8 KB	0.0 KB	854.5 KB	976.3 KB	12%	27.9 KB
15	news.samoylyk.net	39.6 KB	0.0 KB	854.5 KB	894.2 KB	4%	63.9 KB
16	news.bbs.nz	2.2 KB	0.0 KB	0.0 KB	2.2 KB	100%	2.2 KB
17	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		48.2 MB	32.9 KB	92.0 MB	140.2 MB	34%	7.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 4 04:15:00 - 04:59:59	284	1.8%	0.11	1.2 MB	2.5%	0.46
Sep 4 05:00:00 - 05:59:59	471	3.0%	0.13	1.7 MB	3.5%	0.50
Sep 4 06:00:00 - 06:59:59	547	3.5%	0.15	1.9 MB	3.8%	0.54
Sep 4 07:00:00 - 07:59:59	612	3.9%	0.17	1.7 MB	3.4%	0.48
Sep 4 08:00:00 - 08:59:59	744	4.7%	0.21	2.2 MB	4.5%	0.64
Sep 4 09:00:00 - 09:59:59	886	5.6%	0.25	2.9 MB	5.8%	0.82
Sep 4 10:00:00 - 10:59:59	794	5.0%	0.22	2.3 MB	4.8%	0.67
Sep 4 11:00:00 - 11:59:59	693	4.4%	0.19	1.8 MB	3.6%	0.50
Sep 4 12:00:00 - 12:59:59	782	5.0%	0.22	2.3 MB	4.7%	0.66
Sep 4 13:00:00 - 13:59:59	869	5.5%	0.24	2.2 MB	4.5%	0.63
Sep 4 14:00:00 - 14:59:59	916	5.8%	0.25	3.0 MB	6.1%	0.86
Sep 4 15:00:00 - 15:59:59	981	6.2%	0.27	3.5 MB	7.2%	1.01
Sep 4 16:00:00 - 16:59:59	790	5.0%	0.22	2.3 MB	4.6%	0.64
Sep 4 17:00:00 - 17:59:59	880	5.6%	0.24	2.5 MB	5.1%	0.71
Sep 4 18:00:00 - 18:59:59	826	5.2%	0.23	2.6 MB	5.2%	0.73
Sep 4 19:00:00 - 19:59:59	846	5.4%	0.23	2.5 MB	5.2%	0.72
Sep 4 20:00:00 - 20:59:59	710	4.5%	0.20	2.2 MB	4.5%	0.63
Sep 4 21:00:00 - 21:59:59	720	4.6%	0.20	2.1 MB	4.3%	0.60
Sep 4 22:00:00 - 22:59:59	528	3.3%	0.15	2.0 MB	4.1%	0.57
Sep 4 23:00:00 - 23:59:59	452	2.9%	0.13	1.3 MB	2.7%	0.38
Sep 5 00:00:00 - 00:59:59	317	2.0%	0.09	1.0 MB	2.1%	0.29
Sep 5 01:00:00 - 01:59:59	404	2.6%	0.11	1.4 MB	2.8%	0.39
Sep 5 02:00:00 - 02:59:59	314	2.0%	0.09	1.2 MB	2.4%	0.33
Sep 5 03:00:00 - 03:59:59	305	1.9%	0.08	854.3 KB	1.7%	0.24
Sep 5 04:00:00 - 04:15:00	114	0.7%	0.13	560.2 KB	1.1%	0.62
TOTAL: 24:00:00	15785	100.0%	0.18	49.3 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1555	38	0	1517
2	news.freedyn.net	1027	27	0	1000
3	newsfeed.neva.ru	871	100	1	770
4	newsfeed.fu-berlin.de	392	15	0	377
5	csiph-out.feeder.erje.net	378	53	0	325
6	csiph-out.feed.uzoreto.com	363	8	0	355
7	news-out.mixmin.net	256	0	1	255
8	csiph-out.news.weretis.net	192	98	0	94
9	feeder.eternal-september.org	141	31	3	107
10	news.fcku.it	15	13	0	2
11	news-out.aioe.org	14	10	0	4
12	nntp.pasdenom.info	11	0	0	11
13	news.swapon.de	11	0	0	11
14	newsfeeds.xmission.com	11	0	0	11
15	news.samoylyk.net	1	0	0	1
TOTAL: 15		5238	393	5	4840

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	71
it-alt.sport.calcio.juventus	39
alt.bbs.synchronet	33
lada.auto	21
mozilla.support.seamonkey	14
alt.binaries.pictures.purebeauty	13
mozilla.support.thunderbird	13
alt.windows7.general	11
mozilla.dev.security.policy	8
mozilla.dev.telemetry-alerts	7
att.jobs	7
alt.survival	7
alt.binaries.ebooks	7
cakewalk.audio	7
mozilla.support.firefox	6
oesterreich.tratsch	6
alt.computer.workshop	6
spline.eisfair.dev	5
mozilla.general	4
alt.music.pink-floyd.publius	4
TOTAL: 78	393

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3010
EMP (phn path)	136
EMP (phl)	103
Too many newsgroups	98
EMP (phn nph)	76
User-issued cancel	35
Binary: misplaced rar	16
HTML Multipart	11
Scoring filter (8)	10
User-issued spam cancel	8
Binary Payload	7
Scoring filter (12)	7
Too many newsgroups (low_xpost)	6
Scoring filter (14)	5
Scoring filter (13)	5
Scoring filter (10)	2
Scoring filter (11)	1
HTML Image Tags	1
Scoring filter (9)	1
Binary: misplaced com	1
TOTAL: 22	3541

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	55	0	59	59
TOTAL: 1	55	15	59	59

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	6
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	8
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	410
	csiph-out.feeder.erje.net	207
	csiph-out.news.xs4all.nl	153
	csiph-out.feed.uzoreto.com	99
	csiph-out.news.weretis.net	19
	newsfeeds.xmission.com	8
	news.swapon.de	1
	newsfeed.fu-berlin.de	1
	news.samoylyk.net	1
	feeder.eternal-september.org	1
	TOTAL: 12	902
Including strange strings
	newsfeed.fu-berlin.de	315
	csiph-out.news.xs4all.nl	34
	news.swapon.de	10
	news-out.mixmin.net	9
	csiph-out.news.weretis.net	8
	news-out.aioe.org	4
	csiph-out.feeder.erje.net	4
	newsfeeds.xmission.com	3
	csiph-out.feed.uzoreto.com	1
	news.freedyn.net	1
	TOTAL: 10	389
TOTAL: 4		1344

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	erje	17846	1718	12320	261	17	9%	23:55:28
2	xanadu-bbs	19482	1034	14684	1	189	5%	23:50:22
3	news.samoylyk.net	16293	529	15235	0	93	3%	23:58:36
4	news.freedyn.net	14298	520	13412	22	0	3%	23:50:13
5	fu-berlin.de	14847	499	14348	0	0	3%	23:54:12
6	news.bbs.nz	29399	227	14953	8	0	0%	23:50:13
7	usenet.pasdenom.info	16167	86	15075	536	20	0%	23:57:31
8	newsfeed.xs4all.nl	14159	30	13466	6	37	0%	23:56:50
9	news.uzoreto.com	14950	18	14602	193	0	0%	23:59:14
10	neva.ru	14263	10	14025	135	0	0%	23:50:13
11	aioe	13707	7	13061	0	0	0%	23:56:49
12	xmission	15758	3	12141	0	0	0%	23:51:42
13	weretis.net	14380	2	14165	0	0	0%	23:58:59
14	news.mixmin.net	14661	1	14178	0	0	0%	23:50:13
15	eternal-september	13502	1	12987	0	0	0%	23:50:13
16	news.swapon.de	1458	1	1438	0	0	0%	23:58:10
17	news.redatomik.org	0	0	0	0	15188	0%	23:50:13
18	news.fcku.it	1532	0	1530	0	0	0%	20:55:25
TOTAL: 18		246702	4686	211620	1162	15544	1%	427:14:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	3.8 KB	7.2 MB	0.1 KB/s	7.1 KB	23:50:22
2	news.samoylyk.net	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	11.4 KB	23:58:36
3	news.freedyn.net	5.8 MB	43.7 KB	5.9 MB	0.1 KB/s	11.1 KB	23:50:13
4	fu-berlin.de	5.8 MB	0.0 KB	5.8 MB	0.1 KB/s	11.9 KB	23:54:12
5	erje	4.8 MB	1.2 MB	6.0 MB	0.1 KB/s	3.1 KB	23:55:28
6	news.bbs.nz	544.6 KB	27.5 KB	572.1 KB	0.0 KB/s	2.4 KB	23:50:13
7	usenet.pasdenom.info	308.7 KB	5.9 MB	6.2 MB	0.1 KB/s	10.2 KB	23:57:31
8	newsfeed.xs4all.nl	222.9 KB	17.8 KB	240.8 KB	0.0 KB/s	6.7 KB	23:56:50
9	news.uzoreto.com	77.5 KB	787.9 KB	865.4 KB	0.0 KB/s	4.1 KB	23:59:14
10	neva.ru	51.5 KB	293.2 KB	344.7 KB	0.0 KB/s	2.4 KB	23:50:13
11	aioe	13.4 KB	0.0 KB	13.4 KB	0.0 KB/s	1.9 KB	23:56:49
12	xmission	12.1 KB	0.0 KB	12.1 KB	0.0 KB/s	4.0 KB	23:51:42
13	eternal-september	9.0 KB	0.0 KB	9.0 KB	0.0 KB/s	9.0 KB	23:50:13
14	weretis.net	6.9 KB	0.0 KB	6.9 KB	0.0 KB/s	3.5 KB	23:58:59
15	news.mixmin.net	2.9 KB	0.0 KB	2.9 KB	0.0 KB/s	2.9 KB	23:50:13
16	news.swapon.de	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.9 KB	23:58:10
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:13
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:55:25
TOTAL: 18		30.7 MB	8.2 MB	38.9 MB	0.0 KB/s	6.8 KB	427:14:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	19	1	1.3 KB	1	0	0	03:53:35
TOTAL: 1		19	1	1.3 KB	1	0	0	10:22:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.upc-a.chello.nl	19	1	1.3 KB	1	03:53:35
2	unresolved	4	0	0.0 KB	0	00:00:04
3	*.sfj.corp.censys.io	1	0	0.0 KB	0	00:00:00
4	*.ph.ph.cox.net	4	0	0.0 KB	0	02:00:13
5	*.dhcp.eucl.wi.charter.com	12	0	0.0 KB	0	04:29:02
6	*.security.ipip.net	1	0	0.0 KB	0	00:00:00
7	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	00:00:01
TOTAL: 7		42	1	1.3 KB	1	10:22:59

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
68-190-164-204.dhcp.eucl.wi.charter.com	0.761	0.201	04:29:02
a131033.upc-a.chello.nl	1.266	0.280	03:53:35
ip72-222-200-117.ph.ph.cox.net	0.399	0.139	02:00:13
94.164.231.192	0.209	0.033	00:00:03
ec2-35-160-166-167.us-west-2.compute.amazonaws.com	0.063	0.016	00:00:01
worker-18.sfj.corp.censys.io	0.062	0.015	00:00:00
122.228.19.80	0.066	0.015	00:00:00
scan-141.security.ipip.net	0.056	0.024	00:00:00
TOTAL: 8	2.882	0.723	10:22:59

Curious NNRP server explorers [Top 20]:

System	Conn
68-190-164-204.dhcp.eucl.wi.charter.com	12
ip72-222-200-117.ph.ph.cox.net	4
94.164.231.192	3
ec2-35-160-166-167.us-west-2.compute.amazonaws.com	1
worker-18.sfj.corp.censys.io	1
122.228.19.80	1
scan-141.security.ipip.net	1
TOTAL: 7	23

NNRP unrecognized commands (by host):

System	Conn
122.228.19.80	5
scan-141.security.ipip.net	4
TOTAL: 2	9

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: HTTP Banner Detection (https...	1
Accept: /	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
Connection: close	1
TOTAL: 7	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-190-164-204.dhcp.eucl.wi.charter.com	6	0
ip72-222-200-117.ph.ph.cox.net	4	0
worker-18.sfj.corp.censys.io	1	1
TOTAL: 3	11	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 4 04:15:00 -- Sep 5 04:15:00