Daily Usenet report for csiph.com: May 3 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	69641	49.1%	6.9 MB	38.3%
inn	42680	30.1%	7.9 MB	43.6%
innd	26196	18.5%	2.7 MB	15.0%
innfeed	3263	2.3%	540.9 KB	2.9%
nocem	97	0.1%	9.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	141878	100.0%	18.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	408654	53.6%
Negative hits	310736	40.8%
Do not exist	41890	5.5%
Cache misses	626	0.1%
TOTAL: 4	761906	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.582	0.0%	294	0.000	5.381	71.333
article cleanup	00:00:00.300	0.0%	40896	0.000	0.007	0.023
article logging	00:00:06.155	0.0%	41428	0.000	0.149	1.471
article parse	00:00:10.726	0.0%	153082	0.004	0.070	0.618
article write	00:02:44.074	0.2%	21915	0.220	7.487	27.733
artlog/artcncl	00:00:00.020	0.0%	200	0.000	0.100	0.581
artlog/artparse	00:00:00.291	0.0%	1088	0.000	0.267	3.600
data move	00:00:00.359	0.0%	727053	0.000	0.000	0.012
hisgrep/artcncl	00:00:00.430	0.0%	290	0.000	1.483	20.667
hishave/artcncl	00:00:00.243	0.0%	294	0.000	0.827	16.222
hishave/artparse	00:00:00.009	0.0%	1086	0.000	0.008	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:36.722	0.2%	761593	0.003	0.206	2.125
history sync	00:00:06.415	0.0%	365	0.000	17.575	133.000
history write	00:04:31.656	0.3%	40850	0.098	6.650	49.649
hiswrite/artcncl	00:00:00.005	0.0%	4	0.000	1.250	2.500
hiswrite/artparse	00:00:08.495	0.0%	1086	0.000	7.822	74.000
idle	23:41:21.501	98.6%	712540	68.368	119.687	382.661
nntp read	00:00:03.410	0.0%	736391	0.001	0.005	0.014
overview write	00:05:31.085	0.4%	21915	0.711	15.108	111.006
perl filter	00:01:47.505	0.1%	40592	1.663	2.648	10.010
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.408	0.0%	43920	0.000	0.009	0.027
TOTAL: 24:00:54.937	23:59:11.391	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.071	0.0%	21892	0.000	0.003	0.016
article prepare	00:00:00.006	0.0%	22092	0.000	0.000	0.007
article read	00:00:01.218	0.0%	3140	0.000	0.388	30.500
backlog stats	00:00:00.235	0.0%	391570	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.977	0.0%	308535	0.002	0.006	0.015
data write	00:00:01.766	0.0%	292673	0.002	0.006	0.012
idle	23:53:31.891	100.0%	391570	112.919	219.659	683.266
status file	00:00:00.326	0.0%	302	0.000	1.079	10.667
TOTAL: 23:53:46.271	23:53:37.490	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	39:01:58.232	99.7%	106224	79.588	1322.848	600105.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.267	0.0%	213727	0.000	0.006	0.143
readart	00:02:29.934	0.1%	9763	0.000	15.357	534.000
TOTAL: 39:09:16.915	39:04:29.433	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	45868	6361	38194	1313	13%	46:56:51
2	csiph-out.feeder.erje.net	1440	146803	4977	133657	8169	3%	118:58:18
3	feeder.eternal-september.org	5	36390	2724	27836	5830	7%	23:49:09
4	news-out.mixmin.net	3	23535	1630	21284	621	6%	24:08:42
5	news-out.aioe.org	3	21860	1607	20212	41	7%	24:09:54
6	newsfeed.neva.ru	150	33700	1287	28870	3543	3%	71:45:28
7	newsfeed.fu-berlin.de	35	19068	1054	17737	277	5%	24:13:21
8	newsfeeds.xmission.com	2	19289	801	18484	4	4%	21:04:47
9	news.redatomik.org	1	22391	582	21802	7	2%	25:53:23
10	news.fcku.it	1	9435	190	9204	41	2%	23:59:55
11	xanadu-bbs.net	6	854	162	690	2	18%	23:34:49
12	news.swapon.de	4	3322	108	3211	3	3%	36:57:22
13	news.freedyn.net	2	25032	40	24886	106	0%	23:59:46
14	nntp.pasdenom.info	4	28828	35	28789	4	0%	23:10:17
15	localhost	54	21	21	0	0	100%	00:00:02
16	news.bbs.geek.nz	2	20917	5	20912	0	0%	23:59:47
TOTAL: 16		1715	457313	21584	415768	19961	4%	536:41:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.7 MB	502.1 KB	88.6 MB	108.8 MB	18%	8.5 KB
2	csiph-out.news.weretis.net	17.8 MB	0.0 KB	13.9 MB	31.7 MB	56%	4.2 KB
3	feeder.eternal-september.org	7.2 MB	36.9 KB	77.0 MB	84.3 MB	8%	10.1 KB
4	news-out.mixmin.net	6.0 MB	0.0 KB	1.3 MB	7.3 MB	82%	3.3 KB
5	news-out.aioe.org	3.7 MB	0.0 KB	64.9 KB	3.7 MB	98%	2.3 KB
6	newsfeed.neva.ru	3.0 MB	26.8 KB	32.9 MB	35.9 MB	8%	7.6 KB
7	newsfeeds.xmission.com	2.5 MB	0.0 KB	419.5 KB	2.9 MB	85%	3.7 KB
8	newsfeed.fu-berlin.de	2.3 MB	1.5 KB	838.2 KB	3.1 MB	73%	2.4 KB
9	news.redatomik.org	1.9 MB	0.0 KB	537.8 KB	2.4 MB	78%	4.2 KB
10	xanadu-bbs.net	490.9 KB	0.0 KB	9.6 KB	500.5 KB	98%	3.1 KB
11	news.fcku.it	329.0 KB	26.8 KB	1.1 MB	1.4 MB	22%	6.3 KB
12	news.swapon.de	297.6 KB	0.0 KB	2.2 MB	2.5 MB	11%	23.3 KB
13	news.freedyn.net	227.4 KB	0.0 KB	7.4 MB	7.6 MB	2%	53.2 KB
14	nntp.pasdenom.info	69.7 KB	0.0 KB	21.0 KB	90.7 KB	76%	2.3 KB
15	localhost	35.0 KB	0.0 KB	0.0 KB	35.0 KB	100%	1.7 KB
16	news.bbs.geek.nz	11.8 KB	0.0 KB	0.0 KB	11.8 KB	100%	2.4 KB
TOTAL: 16		65.4 MB	594.1 KB	226.3 MB	292.3 MB	22%	7.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 3 04:15:00 - 04:59:59	426	1.9%	0.16	1.7 MB	2.6%	0.65
May 3 05:00:00 - 05:59:59	566	2.6%	0.16	2.2 MB	3.3%	0.61
May 3 06:00:00 - 06:59:59	759	3.5%	0.21	2.1 MB	3.2%	0.61
May 3 07:00:00 - 07:59:59	816	3.7%	0.23	2.1 MB	3.1%	0.59
May 3 08:00:00 - 08:59:59	947	4.3%	0.26	2.3 MB	3.5%	0.66
May 3 09:00:00 - 09:59:59	920	4.2%	0.26	2.4 MB	3.6%	0.69
May 3 10:00:00 - 10:59:59	949	4.3%	0.26	2.7 MB	4.0%	0.76
May 3 11:00:00 - 11:59:59	997	4.6%	0.28	2.9 MB	4.4%	0.82
May 3 12:00:00 - 12:59:59	1215	5.5%	0.34	4.2 MB	6.3%	1.19
May 3 13:00:00 - 13:59:59	1145	5.2%	0.32	3.3 MB	5.0%	0.95
May 3 14:00:00 - 14:59:59	1292	5.9%	0.36	4.2 MB	6.4%	1.20
May 3 15:00:00 - 15:59:59	1374	6.3%	0.38	4.1 MB	6.2%	1.16
May 3 16:00:00 - 16:59:59	1421	6.5%	0.39	4.5 MB	6.8%	1.28
May 3 17:00:00 - 17:59:59	1234	5.6%	0.34	4.2 MB	6.4%	1.20
May 3 18:00:00 - 18:59:59	1348	6.2%	0.37	4.8 MB	7.2%	1.36
May 3 19:00:00 - 19:59:59	1125	5.1%	0.31	2.9 MB	4.3%	0.81
May 3 20:00:00 - 20:59:59	1109	5.1%	0.31	3.1 MB	4.7%	0.88
May 3 21:00:00 - 21:59:59	951	4.3%	0.26	2.5 MB	3.8%	0.71
May 3 22:00:00 - 22:59:59	838	3.8%	0.23	2.6 MB	3.9%	0.74
May 3 23:00:00 - 23:59:59	594	2.7%	0.17	1.7 MB	2.6%	0.49
May 4 00:00:00 - 00:59:59	545	2.5%	0.15	1.5 MB	2.2%	0.42
May 4 01:00:00 - 01:59:59	454	2.1%	0.13	1.4 MB	2.1%	0.39
May 4 02:00:00 - 02:59:59	371	1.7%	0.10	1.3 MB	2.0%	0.38
May 4 03:00:00 - 03:59:59	415	1.9%	0.12	1.3 MB	1.9%	0.36
May 4 04:00:00 - 04:15:00	86	0.4%	0.10	260.8 KB	0.4%	0.29
TOTAL: 24:00:00	21897	100.0%	0.25	66.1 MB	100.0%	0.78

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	8208	269	42	1	7896
2	feeder.eternal-september.org	5937	43	3	0	5891
3	newsfeed.neva.ru	3565	330	1	0	3234
4	csiph-out.news.weretis.net	1364	263	0	0	1101
5	newsfeed.fu-berlin.de	687	50	1	0	636
6	news-out.mixmin.net	640	2	0	0	638
7	news.freedyn.net	66	1	0	0	65
8	news-out.aioe.org	55	36	0	0	19
9	news.fcku.it	37	2	1	0	34
10	news.redatomik.org	10	0	0	0	10
11	news.swapon.de	6	0	0	0	6
12	newsfeeds.xmission.com	6	1	0	0	5
13	xanadu-bbs.net	2	0	0	0	2
14	nntp.pasdenom.info	1	0	0	0	1
TOTAL: 14		20584	997	48	1	19538

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	150
ut.test	130
lada.auto	96
it-alt.sport.calcio.juventus	70
spline.eisfair	46
mpc.lists.freebsd.ports.bugs	45
it.annunci.varie	31
it.annunci.commerciali	30
cakewalk.audio	18
mozilla.support.thunderbird	18
it.annunci.immobiliari	18
alt.survival	16
lada.commerce	14
gmane.linux.alsa.devel	13
mozilla.dev.telemetry-alerts	12
mozilla.dev.security.policy	10
mozilla.support.firefox	10
alt.2600	9
mozilla.dev.apps.seamonkey	9
mpc.lists.freebsd.gnome	9
TOTAL: 124	997

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
nz.politics.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15866
EMP (phn path)	1460
EMP (phl)	117
User-issued cancel	46
Scoring filter (8)	41
Too many newsgroups	38
Binary Payload	33
HTML Multipart	20
Scoring filter (10)	11
EMP (phn nph)	8
HTML post	5
Cancel for rejected article	3
Binary Image: misplaced jpg	3
Binary: misplaced nzb	3
Scoring filter (9)	2
HTML Image Tags	2
Binary: misplaced par2	1
Binary: misplaced rar	1
Scoring filter (11)	1
Binary: misplaced PAR2	1
TOTAL: 20	17662

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	90	0	96	96
TOTAL: 1	90	0	96	96

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	255
	TOTAL: 1	255
Huge articles
	csiph-out.feeder.erje.net	859
	feeder.eternal-september.org	123
	csiph-out.news.weretis.net	59
	news.freedyn.net	22
	news.fcku.it	7
	newsfeed.fu-berlin.de	5
	news.redatomik.org	4
	newsfeeds.xmission.com	4
	news-out.mixmin.net	3
	TOTAL: 9	1086
Including strange strings
	newsfeed.fu-berlin.de	391
	csiph-out.news.weretis.net	54
	csiph-out.feeder.erje.net	32
	news-out.aioe.org	15
	news-out.mixmin.net	12
	newsfeed.neva.ru	10
	news.swapon.de	6
	feeder.eternal-september.org	5
	news.redatomik.org	5
	newsfeeds.xmission.com	1
	TOTAL: 11	532
No colon-space in header
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
TOTAL: 4		1875

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	39999	1119	20075	71	0	2%	23:59:49
2	news.freedyn.net	22615	786	21062	2	0	3%	23:59:26
3	xanadu-bbs	22615	680	21051	0	0	3%	23:59:43
4	fu-berlin.de	20446	592	19854	0	0	2%	23:59:49
5	usenet.pasdenom.info	23403	163	20019	1678	38	0%	23:59:55
6	erje	17797	123	16594	67	0	0%	23:59:40
7	aioe	19836	49	18809	3	0	0%	23:59:54
8	news.redatomik.org	21251	34	20701	7	0	0%	23:59:44
9	weretis.net	14513	25	14374	0	0	0%	23:59:55
10	xmission	21800	24	20292	2	0	0%	23:59:54
11	news.swapon.de	2110	24	2060	0	0	1%	23:46:59
12	news.mixmin.net	20031	23	19438	0	0	0%	23:59:55
13	eternal-september	19225	22	18154	0	0	0%	23:59:55
14	neva.ru	20176	20	19725	12	0	0%	23:59:23
15	au2pb	0	0	0	0	21266	0%	23:58:42
16	news.fcku.it	2666	0	2622	0	0	0%	22:14:25
17	bwh	0	0	0	0	21253	0%	23:58:41
TOTAL: 17		288483	3684	254830	1842	42557	1%	405:55:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.0 MB	8.7 KB	8.0 MB	0.1 KB/s	10.4 KB	23:59:26
2	xanadu-bbs	7.7 MB	0.0 KB	7.7 MB	0.1 KB/s	11.6 KB	23:59:43
3	fu-berlin.de	7.5 MB	0.0 KB	7.5 MB	0.1 KB/s	12.9 KB	23:59:49
4	news.bbs.geek.nz	3.0 MB	267.4 KB	3.2 MB	0.0 KB/s	2.8 KB	23:59:49
5	usenet.pasdenom.info	532.4 KB	9.3 MB	9.8 MB	0.1 KB/s	5.5 KB	23:59:55
6	erje	354.8 KB	463.3 KB	818.1 KB	0.0 KB/s	4.3 KB	23:59:40
7	aioe	96.1 KB	5.8 KB	101.9 KB	0.0 KB/s	2.0 KB	23:59:54
8	news.redatomik.org	70.5 KB	36.9 KB	107.4 KB	0.0 KB/s	2.6 KB	23:59:44
9	weretis.net	42.9 KB	0.0 KB	42.9 KB	0.0 KB/s	1.7 KB	23:59:55
10	news.swapon.de	41.4 KB	0.0 KB	41.4 KB	0.0 KB/s	1.7 KB	23:46:59
11	xmission	39.5 KB	4.3 KB	43.8 KB	0.0 KB/s	1.7 KB	23:59:54
12	news.mixmin.net	38.3 KB	0.0 KB	38.3 KB	0.0 KB/s	1.7 KB	23:59:55
13	eternal-september	37.1 KB	0.0 KB	37.1 KB	0.0 KB/s	1.7 KB	23:59:55
14	neva.ru	33.4 KB	29.9 KB	63.3 KB	0.0 KB/s	2.0 KB	23:59:23
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:14:25
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:41
TOTAL: 17		27.4 MB	10.1 MB	37.5 MB	0.0 KB/s	7.0 KB	405:55:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	144.138.90.254	287	125	320.7 KB	115	0	0	03:43:10
2	mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	2848	52	125.2 KB	18	0	0	03:53:06
3	204-73-239-109.cust.centrio.cz	34	26	56.3 KB	18	5	0	05:53:23
4	68-185-181-64.dhcp.mdsn.wi.charter.com	41	22	32.1 KB	20	16	13	10:42:07
5	dhcp-089-099-203-254.chello.nl	57	17	26.9 KB	11	0	0	14:57:02
TOTAL: 5		3267	242	561.2 KB	182	21	13	39:08:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	287	125	320.7 KB	115	0	0	03:43:10
2	*.dsl.bell.ca	2848	52	125.2 KB	18	0	0	03:53:06
3	*.cust.centrio.cz	34	26	56.3 KB	18	5	0	05:53:23
4	*.dhcp.mdsn.wi.charter.com	41	22	32.1 KB	20	16	13	10:42:07
5	*.chello.nl	57	17	26.9 KB	11	0	0	14:57:02
6	*.2.89.75.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 6		3268	242	561.2 KB	182	21	13	39:08:51

NNRP auth users [Top 20]:

User	Conn
moussa	287
ctomczyk	26
ohlandl	13
TOTAL: 3	326

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	5.023	1.000	0.000	14:57:02
68-185-181-64.dhcp.mdsn.wi.charter.com	4.016	0.748	0.046	10:42:07
204-73-239-109.cust.centrio.cz	3.183	0.656	0.084	05:53:23
mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	231.834	46.719	0.000	03:53:06
144.138.90.254	26.754	6.686	1.018	03:43:10
TOTAL: 5	270.810	55.809	1.148	39:08:51

Curious NNRP server explorers [Top 20]:

System	Conn
h63.2.89.75.dynamic.ip.windstream.net	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	10	0
204-73-239-109.cust.centrio.cz	6	0
TOTAL: 2	16	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	192	79.3%
2	alt	37	15.3%
3	uk	6	2.5%
4	rec	5	2.1%
5	aus	1	0.4%
6	soc	1	0.4%
TOTAL: 6		242	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	62
2	comp.os.vms	39
3	comp.arch	31
4	alt.atheism	27
5	comp.lang.javascript	26
6	comp.arch.fpga	8
7	uk.rec.drugs.cannabis	5
8	comp.arch.embedded	5
9	comp.sys.ibm.as400.misc	4
10	comp.ai.philosophy	4
11	comp.ai.neural-nets	3
12	comp.sys.ibm.pc.games.action	3
13	comp.os.linux.networking	2
14	comp.lang.c	2
15	rec.games.backgammon	2
16	alt.gossip.celebrities	2
17	alt.usenet.kooks	2
18	alt.russian.z1	1
19	comp.os.qnx	1
20	aus.politics	1
21	soc.culture.polish	1
22	uk.politics.misc	1
23	comp.sys.ibm.pc.hardware.chips	1
24	alt.comp.os.windows-10	1
25	alt.tv.law-and-order	1
26	rec.arts.tv	1
27	comp.ai.nat-lang	1
28	rec.arts.sf.fandom	1
29	alt.fan.rush-limbaugh	1
30	alt.life.sucks	1
31	alt.society.liberalism	1
32	rec.arts.drwho	1
TOTAL: 32		242

Daily Usenet report for csiph.com

May 3 04:15:00 -- May 4 04:15:00