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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	21642	48.7%	5.4 MB	64.4%
innd	18831	42.3%	2.4 MB	28.5%
innfeed	3409	7.7%	544.3 KB	6.4%
nnrpd	551	1.2%	62.8 KB	0.7%
nocem	37	0.1%	4.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	44471	100.0%	8.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	297769	58.6%
Negative hits	189091	37.2%
Do not exist	20934	4.1%
Cache misses	622	0.1%
TOTAL: 4	508416	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.149	0.0%	72	0.000	2.069	19.167
article cleanup	00:00:00.123	0.0%	19686	0.000	0.006	0.031
article logging	00:00:00.333	0.0%	20089	0.000	0.017	0.293
article parse	00:00:02.012	0.0%	97235	0.000	0.021	0.087
article write	00:01:27.157	0.1%	16058	0.500	5.428	62.148
artlog/artcncl	00:00:00.001	0.0%	51	0.000	0.020	1.000
artlog/artparse	00:00:00.014	0.0%	1471	0.000	0.010	0.333
data move	00:00:00.186	0.0%	517812	0.000	0.000	0.006
hisgrep/artcncl	00:00:00.010	0.0%	70	0.000	0.143	1.167
hishave/artcncl	00:00:00.007	0.0%	72	0.000	0.097	1.000
hishave/artparse	00:00:00.004	0.0%	1469	0.000	0.003	0.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:06.043	0.0%	511846	0.002	0.012	0.181
history sync	00:00:00.831	0.0%	373	0.000	2.228	211.000
history write	00:00:12.807	0.0%	19684	0.273	0.651	7.509
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:01.038	0.0%	1469	0.000	0.707	3.786
idle	23:56:42.383	99.7%	520398	91.792	165.647	661.342
nntp read	00:00:02.399	0.0%	523026	0.002	0.005	0.028
overview write	00:01:12.603	0.1%	16058	0.195	4.521	70.639
perl filter	00:00:34.487	0.0%	19682	1.319	1.752	4.527
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.277	0.0%	32147	0.000	0.009	0.027
TOTAL: 24:00:59.102	24:00:22.864	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.100	0.0%	24211	0.000	0.004	0.333
article prepare	00:00:00.000	0.0%	20552	0.000	0.000	0.000
article read	00:00:08.735	0.0%	1849	0.000	4.724	238.231
backlog stats	00:00:00.234	0.0%	355540	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.466	0.0%	266192	0.000	0.006	0.026
data write	00:00:01.620	0.0%	253441	0.001	0.006	0.024
idle	23:55:11.726	100.0%	355540	99.388	242.200	799.976
status file	00:00:01.683	0.0%	295	0.000	5.705	42.000
TOTAL: 23:55:33.629	23:55:25.564	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	19:55:27.503	100.0%	449	29.000	159749.450	600102.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.005	0.0%	1055	0.000	0.005	0.167
readart	00:00:00.482	0.0%	59	0.000	8.169	48.000
TOTAL: 19:55:50.837	19:55:27.990	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	31714	3029	28479	206	9%	48:10:56
2	feeder.eternal-september.org	3	17811	2166	15549	96	12%	25:27:23
3	csiph-out.feeder.erje.net	1440	77746	1872	75412	462	2%	118:41:55
4	newsfeeds.xmission.com	2	18424	1785	16630	9	9%	30:30:58
5	csiph-out.news.xs4all.nl	861	18358	1510	15321	1527	8%	71:26:18
6	news.freedyn.net	2	20188	1449	16920	1819	7%	24:09:02
7	news-out.aioe.org	3	12583	1274	11276	33	10%	17:31:47
8	news-out.mixmin.net	2	16376	1016	15181	179	6%	24:08:57
9	newsfeed.fu-berlin.de	10	16644	833	15782	29	5%	27:28:33
10	newsfeed.neva.ru	140	24093	770	22837	486	3%	71:56:29
11	news.fcku.it	6	2301	371	1929	1	16%	21:39:48
12	csiph-out.feed.uzoreto.com	852	25126	112	24788	226	0%	57:09:54
13	nntp.pasdenom.info	2	10999	29	10961	9	0%	23:58:34
14	news.swapon.de	3	1405	21	1384	0	1%	23:24:39
15	localhost	46	14	14	0	0	100%	00:00:05
16	news.samoylyk.net	2	15422	13	15402	7	0%	24:54:05
17	news.bbs.nz	1	15638	8	15630	0	0%	23:59:51
18	xanadu-bbs.net	18	522	0	522	0	0%	23:39:07
TOTAL: 18		3398	325364	16272	304003	5089	5%	658:18:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	11.6 MB	11.2 KB	12.7 MB	24.3 MB	47%	10.7 KB
2	csiph-out.news.weretis.net	9.6 MB	0.0 KB	5.9 MB	15.4 MB	62%	4.9 KB
3	feeder.eternal-september.org	5.7 MB	0.0 KB	1.2 MB	6.9 MB	81%	3.1 KB
4	newsfeeds.xmission.com	5.0 MB	0.0 KB	349.8 KB	5.4 MB	93%	3.1 KB
5	csiph-out.news.xs4all.nl	4.3 MB	0.0 KB	9.7 MB	14.0 MB	30%	4.7 KB
6	news.freedyn.net	3.8 MB	0.0 KB	113.0 MB	116.8 MB	3%	36.6 KB
7	news-out.mixmin.net	3.3 MB	0.0 KB	415.7 KB	3.7 MB	89%	3.2 KB
8	news-out.aioe.org	3.0 MB	0.0 KB	77.8 KB	3.1 MB	97%	2.4 KB
9	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	160.0 KB	2.1 MB	92%	2.5 KB
10	newsfeed.neva.ru	1.7 MB	30.0 KB	1.8 MB	3.6 MB	48%	2.9 KB
11	news.fcku.it	709.0 KB	1.8 KB	1.8 KB	712.6 KB	99%	1.9 KB
12	csiph-out.feed.uzoreto.com	337.8 KB	0.0 KB	3.4 MB	3.7 MB	8%	11.2 KB
13	nntp.pasdenom.info	77.4 KB	1.8 KB	16.7 KB	95.9 KB	80%	2.5 KB
14	news.swapon.de	55.4 KB	0.0 KB	0.0 KB	55.4 KB	100%	2.6 KB
15	news.samoylyk.net	53.5 KB	0.0 KB	878.9 KB	932.4 KB	5%	46.6 KB
16	localhost	15.2 KB	0.0 KB	0.0 KB	15.2 KB	100%	1.1 KB
17	news.bbs.nz	9.0 KB	0.0 KB	0.0 KB	9.0 KB	100%	1.1 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		51.2 MB	44.8 KB	149.5 MB	200.7 MB	25%	9.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 24 04:15:00 - 04:59:59	225	1.4%	0.08	1.2 MB	2.4%	0.47
Sep 24 05:00:00 - 05:59:59	411	2.6%	0.11	1.4 MB	2.8%	0.41
Sep 24 06:00:00 - 06:59:59	482	3.0%	0.13	1.9 MB	3.7%	0.54
Sep 24 07:00:00 - 07:59:59	660	4.1%	0.18	1.8 MB	3.5%	0.51
Sep 24 08:00:00 - 08:59:59	774	4.8%	0.21	2.2 MB	4.3%	0.63
Sep 24 09:00:00 - 09:59:59	710	4.4%	0.20	1.9 MB	3.7%	0.54
Sep 24 10:00:00 - 10:59:59	746	4.6%	0.21	2.5 MB	4.8%	0.70
Sep 24 11:00:00 - 11:59:59	692	4.3%	0.19	2.0 MB	4.0%	0.58
Sep 24 12:00:00 - 12:59:59	804	5.0%	0.22	2.0 MB	4.0%	0.58
Sep 24 13:00:00 - 13:59:59	940	5.8%	0.26	2.3 MB	4.6%	0.66
Sep 24 14:00:00 - 14:59:59	953	5.9%	0.26	3.3 MB	6.4%	0.93
Sep 24 15:00:00 - 15:59:59	932	5.8%	0.26	2.7 MB	5.3%	0.76
Sep 24 16:00:00 - 16:59:59	856	5.3%	0.24	2.8 MB	5.5%	0.80
Sep 24 17:00:00 - 17:59:59	1026	6.4%	0.28	4.1 MB	8.1%	1.17
Sep 24 18:00:00 - 18:59:59	880	5.5%	0.24	2.6 MB	5.2%	0.75
Sep 24 19:00:00 - 19:59:59	849	5.3%	0.24	2.8 MB	5.5%	0.80
Sep 24 20:00:00 - 20:59:59	797	5.0%	0.22	2.6 MB	5.1%	0.73
Sep 24 21:00:00 - 21:59:59	728	4.5%	0.20	1.9 MB	3.7%	0.54
Sep 24 22:00:00 - 22:59:59	607	3.8%	0.17	2.1 MB	4.0%	0.59
Sep 24 23:00:00 - 23:59:59	574	3.6%	0.16	1.8 MB	3.5%	0.51
Sep 25 00:00:00 - 00:59:59	335	2.1%	0.09	988.3 KB	1.9%	0.27
Sep 25 01:00:00 - 01:59:59	374	2.3%	0.10	1.2 MB	2.4%	0.35
Sep 25 02:00:00 - 02:59:59	380	2.4%	0.11	1.4 MB	2.7%	0.39
Sep 25 03:00:00 - 03:59:59	248	1.5%	0.07	723.3 KB	1.4%	0.20
Sep 25 04:00:00 - 04:15:00	100	0.6%	0.11	770.3 KB	1.5%	0.86
TOTAL: 24:00:00	16083	100.0%	0.19	51.0 MB	100.0%	0.60

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.freedyn.net	1841	34	0	1807
2	csiph-out.news.xs4all.nl	1558	40	0	1518
3	newsfeed.neva.ru	489	49	1	439
4	csiph-out.feeder.erje.net	469	98	1	370
5	newsfeed.fu-berlin.de	354	12	0	342
6	csiph-out.feed.uzoreto.com	231	7	0	224
7	csiph-out.news.weretis.net	226	78	0	148
8	news-out.mixmin.net	184	2	0	182
9	feeder.eternal-september.org	91	19	0	72
10	news-out.aioe.org	36	30	0	6
11	nntp.pasdenom.info	10	0	1	9
12	news.samoylyk.net	8	0	0	8
13	newsfeeds.xmission.com	7	3	0	4
14	news.fcku.it	1	0	1	0
TOTAL: 14		5505	372	4	5129

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bbs.synchronet	47
it-alt.sport.calcio.juventus	39
alt.binaries.pictures.purebeauty	38
lada.talk	31
mozilla.general	19
lada.auto	17
mozilla.dev.platform	13
alt.survival	12
alt.testing	8
fido7.ru.android	7
oesterreich.tratsch	7
alt.politics.usa	7
alt.politics	6
alt.binaries.ebooks	6
mozilla.support.seamonkey	5
mozilla.support.thunderbird	5
alt.music.pink-floyd.publius	4
mozilla.dev.security.policy	4
it-alt.comp.software.mozilla	4
alt.binaries.ebook	4
TOTAL: 80	372

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2876
Too many newsgroups	78
EMP (phl)	72
EMP (phn nph)	67
EMP (phn path)	52
HTML Multipart	27
User-issued cancel	20
Binary: misplaced rar	19
Scoring filter (10)	6
Binary Payload	6
Scoring filter (9)	6
Scoring filter (13)	5
Scoring filter (8)	4
Binary Image: misplaced jpg	4
User-issued spam cancel	3
Scoring filter (11)	3
Too many newsgroups (meow)	2
HTML Image Tags	1
Binary: misplaced sfv	1
Binary: misplaced com	1
TOTAL: 23	3256

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	26	0	30	30
TOTAL: 1	26	5	30	30

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	952
	csiph-out.feeder.erje.net	229
	csiph-out.news.xs4all.nl	121
	csiph-out.news.weretis.net	84
	csiph-out.feed.uzoreto.com	52
	feeder.eternal-september.org	15
	news.samoylyk.net	8
	newsfeeds.xmission.com	4
	newsfeed.fu-berlin.de	1
	news-out.mixmin.net	1
	TOTAL: 10	1467
Including strange strings
	newsfeed.fu-berlin.de	327
	csiph-out.news.xs4all.nl	39
	csiph-out.news.weretis.net	18
	news-out.mixmin.net	10
	csiph-out.feeder.erje.net	6
	news-out.aioe.org	3
	feeder.eternal-september.org	1
	TOTAL: 7	404
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		1917

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19003	966	15118	0	194	5%	24:00:58
2	news.samoylyk.net	16786	659	15405	6	0	3%	23:59:55
3	news.freedyn.net	14962	650	13976	11	0	4%	23:59:53
4	fu-berlin.de	15184	642	14542	0	27	4%	24:09:44
5	news.bbs.nz	30029	260	15228	5	0	0%	24:09:04
6	erje	18627	229	13401	486	6	1%	24:02:30
7	usenet.pasdenom.info	16455	166	15230	659	8	1%	24:01:28
8	newsfeed.xs4all.nl	14825	68	13866	7	39	0%	24:02:46
9	aioe	14956	65	14463	1	2453	0%	23:59:51
10	news.uzoreto.com	17073	28	16750	110	2905	0%	24:01:30
11	neva.ru	15105	28	14440	105	0	0%	24:03:41
12	weretis.net	12642	17	12317	1	0	0%	24:07:26
13	xmission	17821	16	13822	1	0	0%	24:09:44
14	news.mixmin.net	14775	15	14371	0	0	0%	24:07:50
15	eternal-september	13724	14	13226	4	0	0%	24:09:24
16	news.swapon.de	1500	14	1453	0	0	0%	23:55:29
17	news.redatomik.org	0	0	0	0	15505	0%	24:09:44
18	news.fcku.it	1812	0	1811	0	0	0%	22:01:15
TOTAL: 18		255279	3837	219419	1396	21137	1%	431:12:12

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	8.9 MB	0.0 KB	8.9 MB	0.1 KB/s	9.5 KB	24:00:58
2	fu-berlin.de	8.3 MB	0.0 KB	8.3 MB	0.1 KB/s	13.2 KB	24:09:44
3	news.samoylyk.net	8.2 MB	17.8 KB	8.2 MB	0.1 KB/s	12.6 KB	23:59:55
4	news.freedyn.net	8.1 MB	18.2 KB	8.2 MB	0.1 KB/s	12.6 KB	23:59:53
5	news.bbs.nz	650.0 KB	10.1 KB	660.1 KB	0.0 KB/s	2.5 KB	24:09:04
6	erje	594.5 KB	1.4 MB	2.0 MB	0.0 KB/s	2.9 KB	24:02:30
7	usenet.pasdenom.info	427.2 KB	8.2 MB	8.6 MB	0.1 KB/s	10.6 KB	24:01:28
8	newsfeed.xs4all.nl	254.1 KB	24.2 KB	278.3 KB	0.0 KB/s	3.7 KB	24:02:46
9	aioe	135.1 KB	1.5 KB	136.6 KB	0.0 KB/s	2.1 KB	23:59:51
10	neva.ru	48.0 KB	233.2 KB	281.2 KB	0.0 KB/s	2.1 KB	24:03:41
11	news.uzoreto.com	40.0 KB	284.5 KB	324.5 KB	0.0 KB/s	2.4 KB	24:01:30
12	weretis.net	25.1 KB	1.3 KB	26.3 KB	0.0 KB/s	1.5 KB	24:07:26
13	xmission	21.3 KB	1.5 KB	22.8 KB	0.0 KB/s	1.3 KB	24:09:44
14	news.mixmin.net	17.3 KB	0.0 KB	17.3 KB	0.0 KB/s	1.2 KB	24:07:50
15	eternal-september	15.2 KB	8.9 KB	24.1 KB	0.0 KB/s	1.3 KB	24:09:24
16	news.swapon.de	15.2 KB	0.0 KB	15.2 KB	0.0 KB/s	1.1 KB	23:55:29
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:09:44
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:01:15
TOTAL: 18		35.8 MB	10.2 MB	45.9 MB	0.0 KB/s	9.0 KB	431:12:12

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	208-71-239-109.cust.centrio.cz	3	23	49.6 KB	2	0	0	01:31:46
2	a131033.upc-a.chello.nl	24	22	25.2 KB	4	0	0	06:15:57
3	96-42-30-5.dhcp.mdsn.wi.charter.com	41	14	15.2 KB	11	14	11	11:07:50
TOTAL: 3		68	59	90.1 KB	17	14	11	19:55:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cust.centrio.cz	3	23	49.6 KB	2	0	0	01:31:46
2	*.upc-a.chello.nl	24	22	25.2 KB	4	0	0	06:15:57
3	*.dhcp.mdsn.wi.charter.com	41	14	15.2 KB	11	14	11	11:07:50
4	unresolved	6	0	0.0 KB	0	0	0	00:00:07
5	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:06
6	*.sfj.corp.censys.io	2	0	0.0 KB	0	0	0	00:00:01
TOTAL: 6		78	59	90.1 KB	17	14	11	19:55:49

NNRP auth users [Top 20]:

User	Conn
ohlandl	11
ctomczyk	3
TOTAL: 2	14

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
96-42-30-5.dhcp.mdsn.wi.charter.com	3.209	0.708	0.029	11:07:50
a131033.upc-a.chello.nl	1.848	0.369	0.000	06:15:57
208-71-239-109.cust.centrio.cz	0.346	0.057	0.008	01:31:46
ip72-222-200-117.ph.ph.cox.net	0.222	0.051	0.000	01:00:06
94.162.89.209	0.218	0.024	0.000	00:00:03
worker-18.sfj.corp.censys.io	0.116	0.039	0.000	00:00:01
94.164.89.13	0.066	0.016	0.000	00:00:01
94.164.165.180	0.043	0.036	0.000	00:00:01
94.160.179.72	0.061	0.023	0.000	00:00:01
TOTAL: 9	6.129	1.323	0.037	19:55:49

Curious NNRP server explorers [Top 20]:

System	Conn
94.162.89.209	3
ip72-222-200-117.ph.ph.cox.net	2
worker-18.sfj.corp.censys.io	2
94.164.165.180	1
94.160.179.72	1
94.164.89.13	1
TOTAL: 6	10

NNRP unrecognized commands (by host):

System	Conn
worker-18.sfj.corp.censys.io	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Host: 69.160.37.58:119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-30-5.dhcp.mdsn.wi.charter.com	8	0
208-71-239-109.cust.centrio.cz	3	0
ip72-222-200-117.ph.ph.cox.net	2	0
worker-18.sfj.corp.censys.io	1	1
TOTAL: 4	14	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	38	64.4%
2	pl	21	35.6%
TOTAL: 2		59	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	36
2	pl.comp.www	21
3	comp.lang.javascript	2
TOTAL: 3		59

Daily Usenet report for csiph.com

Sep 24 04:15:00 -- Sep 25 04:15:00