Daily Usenet report for csiph.com: Apr 14 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42921	37.9%	7.8 MB	53.0%
nnrpd	41917	37.0%	3.8 MB	25.7%
innd	25215	22.2%	2.6 MB	17.9%
innfeed	3190	2.8%	505.1 KB	3.4%
nocem	88	0.1%	9.1 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	113332	100.0%	14.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	406548	55.5%
Negative hits	283551	38.7%
Do not exist	41314	5.6%
Cache misses	563	0.1%
TOTAL: 4	731976	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.446	0.0%	306	0.000	4.725	152.000
article cleanup	00:00:00.284	0.0%	41845	0.000	0.007	0.028
article logging	00:00:04.489	0.0%	42426	0.000	0.106	1.234
article parse	00:00:02.573	0.0%	139075	0.000	0.019	0.186
article write	00:02:35.440	0.2%	21594	0.223	7.198	44.341
artlog/artcncl	00:00:00.012	0.0%	220	0.000	0.055	0.667
artlog/artparse	00:00:00.043	0.0%	197	0.000	0.218	39.000
data move	00:00:00.308	0.0%	672937	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.576	0.0%	271	0.000	2.125	137.000
hishave/artcncl	00:00:00.222	0.0%	306	0.000	0.725	7.000
hishave/artparse	00:00:00.002	0.0%	197	0.000	0.010	0.222
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:21.419	0.2%	737480	0.003	0.192	1.599
history sync	00:00:03.860	0.0%	374	0.000	10.321	103.000
history write	00:03:27.835	0.2%	41494	0.082	5.009	35.962
hiswrite/artcncl	00:00:00.065	0.0%	35	0.000	1.857	1.939
hiswrite/artparse	00:00:00.909	0.0%	197	0.000	4.614	68.000
idle	23:44:07.219	98.8%	659085	67.334	129.645	488.183
nntp read	00:00:03.236	0.0%	685823	0.002	0.005	0.013
overview write	00:05:03.495	0.4%	21594	1.207	14.055	513.706
perl filter	00:01:46.838	0.1%	41492	1.149	2.575	12.058
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.375	0.0%	43270	0.000	0.009	0.024
TOTAL: 24:01:03.159	23:59:40.646	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.072	0.0%	21729	0.000	0.003	0.020
article prepare	00:00:00.001	0.0%	32684	0.000	0.000	0.004
article read	00:00:01.455	0.0%	5467	0.000	0.266	10.500
backlog stats	00:00:00.273	0.0%	404492	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.158	0.0%	309040	0.001	0.007	0.017
data write	00:00:01.832	0.0%	291623	0.002	0.006	0.012
idle	23:53:06.098	100.0%	404492	131.917	212.578	787.496
status file	00:00:00.692	0.0%	284	0.000	2.437	67.000
TOTAL: 23:53:22.020	23:53:12.581	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	26:40:28.879	99.6%	307071	80.533	312.725	600441.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.427	0.0%	757283	0.000	0.006	1.944
readart	00:03:18.945	0.2%	226219	0.000	0.879	141.000
TOTAL: 26:46:07.135	26:43:52.251	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	130651	6627	112210	11814	5%	95:03:06
2	csiph-out.news.weretis.net	3	47052	5046	40770	1236	10%	49:15:18
3	feeder.eternal-september.org	5	36972	2619	29339	5014	7%	24:41:05
4	news-out.aioe.org	4	21831	1859	19943	29	8%	24:09:41
5	news-out.mixmin.net	3	23156	1731	20761	664	7%	24:11:57
6	newsfeed.neva.ru	140	54235	1222	50506	2507	2%	73:57:29
7	newsfeed.fu-berlin.de	35	23192	1075	21856	261	4%	30:40:59
8	newsfeeds.xmission.com	3	22667	832	21813	22	3%	28:02:02
9	news.redatomik.org	1	20101	505	19580	16	2%	23:40:14
10	news.fcku.it	2	9351	75	9181	95	0%	23:59:44
11	news.freedyn.net	3	25200	69	25037	94	0%	24:09:44
12	news.swapon.de	2	2055	61	1974	20	2%	23:09:35
13	nntp.pasdenom.info	3	20152	49	20089	14	0%	23:57:32
14	news.bbs.geek.nz	2	19279	15	19264	0	0%	23:59:47
15	xanadu-bbs.net	9	181	9	172	0	4%	08:26:21
16	localhost	36	2	2	0	0	100%	00:00:01
TOTAL: 16		1403	456077	21796	412495	21786	4%	501:24:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	22.3 MB	1.3 MB	67.3 MB	91.0 MB	24%	5.1 KB
2	csiph-out.news.weretis.net	15.8 MB	12.6 KB	15.2 MB	31.0 MB	50%	5.1 KB
3	feeder.eternal-september.org	6.7 MB	2.7 MB	63.2 MB	72.6 MB	9%	9.7 KB
4	news-out.mixmin.net	6.3 MB	2.4 KB	1.7 MB	8.0 MB	78%	3.4 KB
5	news-out.aioe.org	4.7 MB	0.0 KB	48.0 KB	4.8 MB	99%	2.6 KB
6	newsfeed.neva.ru	2.8 MB	354.9 KB	22.2 MB	25.3 MB	11%	6.9 KB
7	newsfeeds.xmission.com	2.7 MB	0.0 KB	249.3 KB	2.9 MB	91%	3.5 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	539.4 KB	2.8 MB	81%	2.2 KB
9	news.redatomik.org	1.4 MB	0.0 KB	20.7 KB	1.4 MB	98%	2.8 KB
10	news.freedyn.net	282.7 KB	0.0 KB	2.0 MB	2.3 MB	12%	14.2 KB
11	news.swapon.de	164.2 KB	0.0 KB	1.0 MB	1.2 MB	13%	15.2 KB
12	nntp.pasdenom.info	144.6 KB	0.0 KB	27.5 KB	172.1 KB	84%	2.7 KB
13	news.fcku.it	113.8 KB	37.6 KB	1013.6 KB	1.1 MB	9%	6.9 KB
14	news.bbs.geek.nz	38.5 KB	0.0 KB	0.0 KB	38.5 KB	100%	2.6 KB
15	xanadu-bbs.net	23.8 KB	0.0 KB	0.0 KB	23.8 KB	100%	2.6 KB
16	localhost	3.0 KB	0.0 KB	0.0 KB	3.0 KB	100%	1.5 KB
TOTAL: 16		65.8 MB	4.4 MB	174.4 MB	244.7 MB	26%	5.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 14 04:15:00 - 04:59:59	333	1.5%	0.12	1.3 MB	1.9%	0.48
Apr 14 05:00:00 - 05:59:59	488	2.3%	0.14	2.0 MB	3.1%	0.58
Apr 14 06:00:00 - 06:59:59	708	3.3%	0.20	2.2 MB	3.3%	0.61
Apr 14 07:00:00 - 07:59:59	912	4.2%	0.25	2.7 MB	4.1%	0.77
Apr 14 08:00:00 - 08:59:59	982	4.5%	0.27	2.8 MB	4.3%	0.79
Apr 14 09:00:00 - 09:59:59	1156	5.4%	0.32	3.3 MB	5.0%	0.93
Apr 14 10:00:00 - 10:59:59	965	4.5%	0.27	2.6 MB	3.9%	0.73
Apr 14 11:00:00 - 11:59:59	873	4.0%	0.24	2.2 MB	3.4%	0.64
Apr 14 12:00:00 - 12:59:59	1022	4.7%	0.28	3.1 MB	4.7%	0.87
Apr 14 13:00:00 - 13:59:59	1228	5.7%	0.34	3.8 MB	5.8%	1.07
Apr 14 14:00:00 - 14:59:59	1245	5.8%	0.35	4.2 MB	6.4%	1.18
Apr 14 15:00:00 - 15:59:59	1303	6.0%	0.36	4.1 MB	6.3%	1.17
Apr 14 16:00:00 - 16:59:59	1196	5.5%	0.33	3.4 MB	5.2%	0.96
Apr 14 17:00:00 - 17:59:59	1222	5.7%	0.34	3.8 MB	5.8%	1.07
Apr 14 18:00:00 - 18:59:59	1208	5.6%	0.34	3.7 MB	5.7%	1.05
Apr 14 19:00:00 - 19:59:59	1075	5.0%	0.30	2.9 MB	4.5%	0.84
Apr 14 20:00:00 - 20:59:59	973	4.5%	0.27	3.3 MB	5.1%	0.94
Apr 14 21:00:00 - 21:59:59	917	4.2%	0.25	2.4 MB	3.7%	0.68
Apr 14 22:00:00 - 22:59:59	939	4.4%	0.26	3.2 MB	4.9%	0.90
Apr 14 23:00:00 - 23:59:59	739	3.4%	0.21	1.9 MB	3.0%	0.55
Apr 15 00:00:00 - 00:59:59	577	2.7%	0.16	1.9 MB	2.9%	0.54
Apr 15 01:00:00 - 01:59:59	529	2.5%	0.15	1.5 MB	2.3%	0.43
Apr 15 02:00:00 - 02:59:59	456	2.1%	0.13	1.6 MB	2.4%	0.45
Apr 15 03:00:00 - 03:59:59	407	1.9%	0.11	1.2 MB	1.8%	0.33
Apr 15 04:00:00 - 04:15:00	130	0.6%	0.14	388.7 KB	0.6%	0.43
TOTAL: 24:00:00	21583	100.0%	0.25	65.3 MB	100.0%	0.77

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	10641	359	111	1	10170
2	feeder.eternal-september.org	5010	60	212	0	4738
3	newsfeed.neva.ru	2545	479	29	0	2037
4	csiph-out.news.weretis.net	1250	229	2	0	1019
5	news-out.mixmin.net	709	0	1	0	708
6	newsfeed.fu-berlin.de	645	32	0	0	613
7	news.fcku.it	95	0	1	0	94
8	news.freedyn.net	94	9	0	0	85
9	news-out.aioe.org	38	22	0	0	16
10	newsfeeds.xmission.com	29	1	0	0	28
11	news.swapon.de	23	0	0	0	23
12	news.redatomik.org	22	0	0	0	22
13	nntp.pasdenom.info	14	3	0	0	11
14	news.bbs.geek.nz	5	1	0	0	4
15	xanadu-bbs.net	1	0	0	0	1
TOTAL: 15		21121	1195	356	1	19569

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	278
lada.auto	144
ut.test	90
it-alt.sport.calcio.juventus	49
mozilla.support.thunderbird	39
it.annunci.commerciali	38
it.annunci.varie	37
it.annunci.immobiliari	36
alt.windows7.general	30
gmane.linux.alsa.devel	30
mozilla.support.firefox	20
gmane.comp.gis.openstreetmap.tagging	17
mpc.lists.nanog	16
alt.computer.workshop	16
mozilla.support.seamonkey	14
alt.survival	13
spline.eisfair	13
mozilla.dev.l10n	13
lada.commerce	12
mpc.lists.freebsd.gnome	11
TOTAL: 135	1195

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

Groups	Count
it.scienza.astronomia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16654
EMP (phn path)	1383
User-issued cancel	326
EMP (phn nph)	167
Too many newsgroups	54
HTML Multipart	34
Scoring filter (10)	27
Scoring filter (11)	22
EMP (phl)	19
Binary Payload	18
Scoring filter (13)	14
Scoring filter (8)	14
Scoring filter (9)	11
HTML post	8
Too many newsgroups (low_xpost)	7
Binary Image: misplaced jpg	6
User-issued spam cancel	5
Scoring filter (14)	5
Scoring filter (12)	5
Scoring filter (15)	4
TOTAL: 25	18790

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	81	0	91	91
TOTAL: 1	81	0	91	91

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.news.weretis.net	91
	feeder.eternal-september.org	69
	news.fcku.it	12
	news-out.mixmin.net	9
	news.freedyn.net	8
	news.swapon.de	3
	news.bbs.geek.nz	2
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	1
	TOTAL: 9	197
Including strange strings
	newsfeed.fu-berlin.de	392
	csiph-out.feeder.erje.net	54
	csiph-out.news.weretis.net	54
	news-out.mixmin.net	42
	news-out.aioe.org	10
	feeder.eternal-september.org	8
	newsfeed.neva.ru	7
	news.redatomik.org	6
	newsfeeds.xmission.com	4
	news.swapon.de	2
	TOTAL: 11	580
TOTAL: 3		781

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37883	3061	17551	117	0	8%	23:50:03
2	xanadu-bbs	23890	890	20651	2	6	3%	23:59:54
3	news.freedyn.net	22247	860	20554	5	0	3%	23:50:03
4	fu-berlin.de	20225	625	19600	0	0	3%	23:50:03
5	usenet.pasdenom.info	23535	254	19245	2034	146	1%	23:59:54
6	xmission	22762	103	19948	8	1	0%	23:59:54
7	erje	15708	88	14674	43	0	0%	23:50:03
8	aioe	19132	36	18012	4	0	0%	23:49:33
9	news.redatomik.org	20981	18	20212	2	55	0%	23:51:11
10	eternal-september	19583	10	17938	0	1	0%	23:59:38
11	news.mixmin.net	19572	5	18746	0	0	0%	23:51:33
12	weretis.net	15292	5	14785	3	0	0%	23:50:03
13	news.swapon.de	1950	2	1905	0	0	0%	23:52:45
14	news.fcku.it	2396	0	2326	0	0	0%	22:25:56
15	neva.ru	19954	0	19380	8	2	0%	23:59:35
16	bwh	0	0	0	0	20740	0%	23:50:11
17	au2pb	0	0	0	0	20749	0%	23:50:11
TOTAL: 17		285110	5957	245527	2226	41700	2%	404:40:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	7.5 MB	414.4 KB	7.9 MB	0.1 KB/s	2.6 KB	23:50:03
2	xanadu-bbs	6.6 MB	7.5 KB	6.6 MB	0.1 KB/s	7.6 KB	23:59:54
3	news.freedyn.net	6.5 MB	19.8 KB	6.5 MB	0.1 KB/s	7.7 KB	23:50:03
4	fu-berlin.de	5.9 MB	0.0 KB	5.9 MB	0.1 KB/s	9.7 KB	23:50:03
5	usenet.pasdenom.info	904.1 KB	8.2 MB	9.1 MB	0.1 KB/s	4.1 KB	23:59:54
6	erje	769.6 KB	290.0 KB	1.0 MB	0.0 KB/s	8.1 KB	23:50:03
7	xmission	222.5 KB	13.5 KB	236.0 KB	0.0 KB/s	2.1 KB	23:59:54
8	aioe	102.2 KB	14.1 KB	116.4 KB	0.0 KB/s	2.9 KB	23:49:33
9	news.redatomik.org	59.3 KB	5.7 KB	65.0 KB	0.0 KB/s	3.2 KB	23:51:11
10	eternal-september	23.4 KB	0.0 KB	23.4 KB	0.0 KB/s	2.3 KB	23:59:38
11	news.mixmin.net	12.3 KB	0.0 KB	12.3 KB	0.0 KB/s	2.5 KB	23:51:33
12	weretis.net	10.1 KB	25.9 KB	36.0 KB	0.0 KB/s	4.5 KB	23:50:03
13	news.swapon.de	3.0 KB	0.0 KB	3.0 KB	0.0 KB/s	1.5 KB	23:52:45
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:25:56
15	neva.ru	0.0 KB	19.7 KB	19.7 KB	0.0 KB/s	2.5 KB	23:59:35
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
TOTAL: 17		28.6 MB	9.0 MB	37.6 MB	0.0 KB/s	4.7 KB	404:40:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-04-70-53-246-3.dsl.bell.ca	840	71133	152.2 MB	183	0	0	08:46:04
2	144.138.90.254	273	67	226.9 KB	65	0	0	03:32:20
3	68-185-181-64.dhcp.mdsn.wi.charter.com	15	7	14.8 KB	4	2	2	03:37:24
4	dhcp-089-099-203-254.chello.nl	33	6	11.5 KB	6	0	0	09:02:47
TOTAL: 4		1161	71213	152.5 MB	258	2	2	26:45:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	840	71133	152.2 MB	183	0	0	08:46:04
2	unresolved	274	67	226.9 KB	65	0	0	03:32:31
3	*.dhcp.mdsn.wi.charter.com	15	7	14.8 KB	4	2	2	03:37:24
4	*.chello.nl	33	6	11.5 KB	6	0	0	09:02:47
5	*.bbox.io	5	0	0.0 KB	0	0	0	01:47:10
TOTAL: 5		1167	71213	152.5 MB	258	2	2	26:45:57

NNRP auth users [Top 20]:

User	Conn
moussa	273
ohlandl	2
TOTAL: 2	275

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	2.792	0.550	0.000	09:02:47
mtrlpq2314w-lp140-04-70-53-246-3.dsl.bell.ca	83.493	36.400	0.000	08:46:04
68-185-181-64.dhcp.mdsn.wi.charter.com	1.178	0.227	0.010	03:37:24
144.138.90.254	24.028	6.048	1.000	03:32:20
tempe0.bbox.io	0.513	0.088	0.000	01:47:10
94.102.48.193	0.093	0.008	0.000	00:00:10
TOTAL: 6	112.097	43.321	1.010	26:45:57

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	5
94.102.48.193	1
TOTAL: 2	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
tempe0.bbox.io	3	0
dhcp-089-099-203-254.chello.nl	2	0
144.138.90.254	1	1
TOTAL: 4	10	1

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	69956	98.2%
2	linux	470	0.7%
3	fr	453	0.6%
4	rec	212	0.3%
5	comp	80	0.1%
6	talk	21	0.0%
7	uk	13	0.0%
8	aus	3	0.0%
9	soc	3	0.0%
10	can	2	0.0%
TOTAL: 10		71213	100.0%

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

Newsgroup		Count
1	alt.test	69121
2	alt.atheism	645
3	linux.kernel	470
4	fr.soc.politique	453
5	rec.arts.tv	193
6	alt.usage.english	44
7	alt.home.repair	41
8	alt.society.liberalism	28
9	alt.comp.freeware	26
10	alt.politics.trump	25
11	comp.arch	24
12	talk.politics.guns	20
13	comp.sys.ibm.ps2.hardware	19
14	rec.arts.drwho	16
15	alt.bible.prophecy	14
16	uk.rec.drugs.cannabis	13
17	comp.os.vms	12
18	comp.arch.embedded	12
19	alt.comp.os.windows-10	9
20	comp.sys.ibm.pc.games.action	7
21	comp.arch.fpga	5
22	rec.sport.pro-wrestling	3
23	soc.culture.usa	3
24	alt.christnet.christianlife	3
25	aus.politics	3
26	can.politics	2
27	comp.os.cpm	1
28	talk.euthanasia	1
TOTAL: 28		71213

Daily Usenet report for csiph.com

Apr 14 04:15:00 -- Apr 15 04:15:00