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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	40770	46.9%	4.4 MB	34.0%
inn	29762	34.2%	6.4 MB	49.6%
innd	13099	15.1%	1.6 MB	12.1%
innfeed	3263	3.8%	547.8 KB	4.1%
nocem	53	0.1%	5.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	86948	100.0%	12.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	327951	54.5%
Negative hits	244594	40.7%
Do not exist	28607	4.8%
Cache misses	401	0.1%
TOTAL: 4	601553	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.542	0.0%	246	0.000	6.268	53.000
article cleanup	00:00:00.206	0.0%	28600	0.000	0.007	0.028
article logging	00:00:00.517	0.0%	29221	0.000	0.018	0.051
article parse	00:00:01.347	0.0%	73113	0.000	0.018	0.153
article write	00:01:54.299	0.1%	21066	0.620	5.426	34.382
artlog/artcncl	00:00:00.005	0.0%	237	0.000	0.021	0.500
artlog/artparse	00:00:00.003	0.0%	301	0.000	0.010	1.000
data move	00:00:00.167	0.0%	579842	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.024	0.0%	230	0.000	0.104	1.000
hishave/artcncl	00:00:00.044	0.0%	246	0.000	0.179	2.923
hishave/artparse	00:00:00.001	0.0%	301	0.000	0.003	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:14.924	0.0%	607620	0.005	0.025	0.195
history sync	00:00:01.885	0.0%	366	0.000	5.150	318.000
history write	00:00:35.116	0.0%	28595	0.364	1.228	11.734
hiswrite/artcncl	00:00:00.021	0.0%	16	0.000	1.312	3.000
hiswrite/artparse	00:00:00.387	0.0%	301	0.000	1.286	15.000
idle	23:49:09.459	99.2%	584102	82.603	146.806	615.200
nntp read	00:00:02.894	0.0%	588469	0.003	0.005	0.011
overview write	00:07:21.549	0.5%	21066	0.429	20.960	2030.797
perl filter	00:00:54.677	0.1%	28593	1.041	1.912	2.600
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.378	0.0%	42177	0.000	0.009	0.038
TOTAL: 24:01:23.889	24:00:19.445	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.068	0.0%	21671	0.000	0.003	0.021
article prepare	00:00:00.003	0.0%	21764	0.000	0.000	0.011
article read	00:00:05.137	0.0%	2494	0.000	2.060	16.600
backlog stats	00:00:00.261	0.0%	387503	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.021	0.0%	298814	0.003	0.007	0.012
data write	00:00:01.700	0.0%	281647	0.000	0.006	0.013
idle	23:54:06.377	100.0%	387503	126.660	222.053	863.345
status file	00:00:00.988	0.0%	332	0.600	2.976	154.000
TOTAL: 23:54:26.441	23:54:16.555	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	16:39:20.045	99.4%	54093	69.737	1108.462	600222.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.565	0.0%	111826	0.000	0.005	0.167
readart	00:02:20.875	0.2%	14335	0.000	9.827	424.000
TOTAL: 16:45:26.119	16:41:41.485	99.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	91769	4304	84481	2984	4%	94:57:32
2	newsfeeds.xmission.com	4	19593	3587	16002	4	18%	24:00:18
3	csiph-out.news.weretis.net	6	43093	2830	39897	366	6%	49:40:52
4	feeder.eternal-september.org	1	24647	2436	21062	1149	9%	23:38:07
5	news.redatomik.org	1	19799	1526	18267	6	7%	24:24:24
6	news-out.aioe.org	2	20482	1391	19037	54	6%	24:09:02
7	news-out.mixmin.net	3	21957	1224	20297	436	5%	24:08:54
8	newsfeed.neva.ru	141	24490	1215	21274	2001	4%	72:11:35
9	newsfeed.fu-berlin.de	20	22279	1179	20789	311	5%	30:49:25
10	news.fcku.it	1	7871	634	7036	201	8%	19:52:41
11	news.freedyn.net	2	22677	427	21966	284	1%	23:57:55
12	news.swapon.de	6	1659	59	1600	0	3%	23:55:18
13	nntp.pasdenom.info	6	19747	51	19684	12	0%	23:57:46
14	news.bbs.geek.nz	2	20124	32	20088	4	0%	23:59:46
15	xanadu-bbs.net	18	616	5	608	3	0%	22:21:31
16	localhost	32	0	0	0	0	0%	00:00:04
TOTAL: 16		1397	360803	20900	332088	7815	5%	506:05:10

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.4 MB	9.1 KB	15.2 MB	34.7 MB	56%	4.9 KB
2	newsfeeds.xmission.com	9.8 MB	0.0 KB	103.7 KB	9.9 MB	98%	2.8 KB
3	csiph-out.news.weretis.net	9.6 MB	0.0 KB	6.5 MB	16.1 MB	59%	5.2 KB
4	feeder.eternal-september.org	6.4 MB	0.0 KB	4.8 MB	11.2 MB	56%	3.2 KB
5	news-out.mixmin.net	4.3 MB	0.0 KB	1.7 MB	6.0 MB	71%	3.7 KB
6	news-out.aioe.org	3.8 MB	0.0 KB	91.7 KB	3.9 MB	97%	2.8 KB
7	news.redatomik.org	3.4 MB	0.0 KB	17.0 KB	3.4 MB	99%	2.3 KB
8	newsfeed.neva.ru	2.9 MB	0.0 KB	6.1 MB	9.0 MB	32%	2.9 KB
9	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	526.2 KB	3.1 MB	83%	2.1 KB
10	news.fcku.it	1.4 MB	0.0 KB	2.1 MB	3.5 MB	40%	4.3 KB
11	news.freedyn.net	1.4 MB	0.0 KB	1002.3 KB	2.4 MB	58%	3.4 KB
12	news.swapon.de	157.5 KB	0.0 KB	0.0 KB	157.5 KB	100%	2.7 KB
13	nntp.pasdenom.info	123.1 KB	0.0 KB	22.0 KB	145.1 KB	84%	2.3 KB
14	news.bbs.geek.nz	65.1 KB	4.1 KB	47.1 KB	116.3 KB	56%	3.2 KB
15	xanadu-bbs.net	9.9 KB	0.0 KB	7.2 KB	17.1 KB	57%	2.1 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		65.4 MB	13.2 KB	38.1 MB	103.5 MB	63%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 12 04:15:00 - 04:59:59	258	1.2%	0.10	820.0 KB	1.2%	0.30
Dec 12 05:00:00 - 05:59:59	496	2.4%	0.14	2.5 MB	3.8%	0.72
Dec 12 06:00:00 - 06:59:59	452	2.1%	0.13	1.4 MB	2.2%	0.41
Dec 12 07:00:00 - 07:59:59	663	3.1%	0.18	2.4 MB	3.7%	0.69
Dec 12 08:00:00 - 08:59:59	788	3.7%	0.22	2.4 MB	3.6%	0.67
Dec 12 09:00:00 - 09:59:59	825	3.9%	0.23	2.1 MB	3.2%	0.60
Dec 12 10:00:00 - 10:59:59	966	4.6%	0.27	2.9 MB	4.4%	0.83
Dec 12 11:00:00 - 11:59:59	911	4.3%	0.25	2.8 MB	4.3%	0.80
Dec 12 12:00:00 - 12:59:59	978	4.6%	0.27	2.6 MB	4.0%	0.75
Dec 12 13:00:00 - 13:59:59	1079	5.1%	0.30	2.9 MB	4.3%	0.81
Dec 12 14:00:00 - 14:59:59	1034	4.9%	0.29	2.8 MB	4.3%	0.79
Dec 12 15:00:00 - 15:59:59	1238	5.9%	0.34	4.1 MB	6.2%	1.17
Dec 12 16:00:00 - 16:59:59	1287	6.1%	0.36	5.1 MB	7.8%	1.46
Dec 12 17:00:00 - 17:59:59	1223	5.8%	0.34	3.1 MB	4.6%	0.87
Dec 12 18:00:00 - 18:59:59	1295	6.1%	0.36	4.2 MB	6.5%	1.21
Dec 12 19:00:00 - 19:59:59	1260	6.0%	0.35	4.4 MB	6.7%	1.24
Dec 12 20:00:00 - 20:59:59	1171	5.6%	0.33	3.3 MB	5.1%	0.95
Dec 12 21:00:00 - 21:59:59	1169	5.5%	0.32	3.5 MB	5.3%	1.00
Dec 12 22:00:00 - 22:59:59	1087	5.2%	0.30	3.2 MB	4.9%	0.91
Dec 12 23:00:00 - 23:59:59	705	3.3%	0.20	2.4 MB	3.6%	0.68
Dec 13 00:00:00 - 00:59:59	594	2.8%	0.17	2.0 MB	3.0%	0.56
Dec 13 01:00:00 - 01:59:59	534	2.5%	0.15	1.5 MB	2.3%	0.43
Dec 13 02:00:00 - 02:59:59	426	2.0%	0.12	1.2 MB	1.8%	0.34
Dec 13 03:00:00 - 03:59:59	497	2.4%	0.14	1.6 MB	2.4%	0.45
Dec 13 04:00:00 - 04:15:00	147	0.7%	0.16	394.5 KB	0.6%	0.44
TOTAL: 24:00:00	21083	100.0%	0.24	65.6 MB	100.0%	0.78

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	3039	319	2	3	2715
2	newsfeed.neva.ru	1949	188	0	0	1761
3	feeder.eternal-september.org	1183	44	0	0	1139
4	newsfeed.fu-berlin.de	794	24	0	0	770
5	news-out.mixmin.net	448	4	0	0	444
6	csiph-out.news.weretis.net	366	185	0	0	181
7	news.freedyn.net	284	18	0	0	266
8	news.fcku.it	238	0	0	0	238
9	news-out.aioe.org	61	34	0	0	27
10	news.redatomik.org	39	0	0	0	39
11	news.swapon.de	13	0	0	0	13
12	nntp.pasdenom.info	12	7	0	0	5
13	newsfeeds.xmission.com	4	3	0	0	1
14	news.bbs.geek.nz	3	0	3	0	0
15	xanadu-bbs.net	3	0	0	0	3
TOTAL: 15		8436	826	5	3	7602

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	109
lada.auto	49
it.annunci.immobiliari	48
it.annunci.commerciali	48
it.annunci.varie	48
gmane.linux.alsa.devel	36
it-alt.sport.calcio.juventus	34
mozilla.dev.security.policy	24
alt.survival	22
mozilla.support.firefox	20
alt.windows7.general	20
gmane.linux.kernel	17
alt.computer.workshop	16
mozilla.dev.l10n	16
mozilla.general	16
mozilla.support.seamonkey	14
oesterreich.tratsch	11
spline.eisfair.dev	11
alt.politics.usa	11
mozilla.support.thunderbird	11
TOTAL: 129	826

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5730
EMP (phn path)	432
EMP (phn nph)	288
Binary: non-binary distribution	51
Too many newsgroups	42
Scoring filter (10)	23
HTML Multipart	20
User-issued cancel	16
Binary Payload	10
Too many newsgroups (meow)	10
HTML post	8
Scoring filter (14)	7
Scoring filter (16)	6
Binary: misplaced asc	6
Scoring filter (13)	6
Too many newsgroups (low_xpost)	6
Scoring filter (12)	4
Scoring filter (8)	3
User-issued spam cancel	2
Binary: misplaced 006	1
TOTAL: 30	6681

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	48	0	65	65
TOTAL: 1	48	0	65	65

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.feeder.erje.net	182
	csiph-out.news.weretis.net	61
	feeder.eternal-september.org	27
	news.fcku.it	19
	news-out.mixmin.net	6
	news.freedyn.net	3
	newsfeeds.xmission.com	1
	TOTAL: 7	299
Including strange strings
	newsfeed.fu-berlin.de	488
	csiph-out.feeder.erje.net	51
	news.redatomik.org	34
	news-out.mixmin.net	20
	news.swapon.de	13
	csiph-out.news.weretis.net	7
	news-out.aioe.org	6
	news.fcku.it	1
	TOTAL: 8	620
TOTAL: 3		923

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37998	984	19288	52	2	2%	23:59:54
2	news.freedyn.net	21185	933	19717	6	2	4%	23:59:46
3	xanadu-bbs	24049	831	20245	0	1	3%	23:59:30
4	fu-berlin.de	19752	672	19079	0	0	3%	23:59:54
5	usenet.pasdenom.info	22501	150	19636	1240	518	0%	23:59:54
6	aioe	18205	147	17481	4	0	0%	23:59:29
7	weretis.net	17354	56	16540	5	0	0%	23:59:51
8	erje	17549	55	16598	3	4	0%	23:59:54
9	news.redatomik.org	19549	51	18740	2	0	0%	23:59:54
10	xmission	19077	47	16581	2	128	0%	23:59:55
11	news.swapon.de	1763	32	1676	1	0	1%	23:29:12
12	eternal-september	18511	27	17681	1	0	0%	23:59:53
13	news.mixmin.net	19255	21	18776	0	0	0%	23:59:17
14	neva.ru	19201	2	18791	122	0	0%	23:59:50
15	bwh	0	0	0	0	20354	0%	23:58:42
16	au2pb	0	0	0	0	20384	0%	23:58:42
17	news.fcku.it	2019	0	2016	0	0	0%	22:37:24
TOTAL: 17		277968	4008	242845	1438	41393	1%	406:01:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.5 MB	46.1 KB	9.5 MB	0.1 KB/s	10.4 KB	23:59:46
2	xanadu-bbs	9.0 MB	0.0 KB	9.0 MB	0.1 KB/s	11.1 KB	23:59:30
3	fu-berlin.de	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	13.2 KB	23:59:54
4	news.bbs.geek.nz	2.8 MB	173.9 KB	2.9 MB	0.0 KB/s	2.9 KB	23:59:54
5	usenet.pasdenom.info	552.0 KB	9.6 MB	10.1 MB	0.1 KB/s	7.5 KB	23:59:54
6	aioe	358.0 KB	9.3 KB	367.4 KB	0.0 KB/s	2.4 KB	23:59:29
7	erje	135.2 KB	17.6 KB	152.8 KB	0.0 KB/s	2.6 KB	23:59:54
8	news.redatomik.org	123.5 KB	6.8 KB	130.3 KB	0.0 KB/s	2.5 KB	23:59:54
9	weretis.net	116.0 KB	10.9 KB	126.9 KB	0.0 KB/s	2.1 KB	23:59:51
10	xmission	108.6 KB	16.7 KB	125.3 KB	0.0 KB/s	2.6 KB	23:59:55
11	news.swapon.de	56.4 KB	1.6 KB	58.0 KB	0.0 KB/s	1.8 KB	23:29:12
12	eternal-september	47.0 KB	1.5 KB	48.6 KB	0.0 KB/s	1.7 KB	23:59:53
13	news.mixmin.net	36.5 KB	0.0 KB	36.5 KB	0.0 KB/s	1.7 KB	23:59:17
14	neva.ru	5.0 KB	338.3 KB	343.3 KB	0.0 KB/s	2.8 KB	23:59:50
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:37:24
TOTAL: 17		31.4 MB	10.2 MB	41.6 MB	0.0 KB/s	7.8 KB	406:01:01

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	mtrlpq2314w-lp130-05-70-55-71-106.dsl.bell.ca	2638	1576	5.6 MB	96	02:41:27
2	141.168.117.108	48	106	261.3 KB	57	00:45:08
3	a131033.upc-a.chello.nl	42	1	1.8 KB	1	07:06:38
4	tempe0.bbox.io	6	1	1.8 KB	1	03:00:20
TOTAL: 4		2734	1684	5.8 MB	155	16:45:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.dsl.bell.ca	2638	1576	5.6 MB	96	02:41:27
2	unresolved	48	106	261.3 KB	57	00:45:08
3	*.upc-a.chello.nl	42	1	1.8 KB	1	07:06:38
4	*.bbox.io	6	1	1.8 KB	1	03:00:20
5	*.nielsenmedia.com	2	0	0.0 KB	0	00:33:13
6	*.dhcp.ftbg.wi.charter.com	8	0	0.0 KB	0	02:38:19
TOTAL: 6		2744	1684	5.8 MB	155	16:45:06

NNRP auth users [Top 20]:

User	Conn
moussa	48
TOTAL: 1	48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	3.204	0.642	0.000	07:06:38
tempe0.bbox.io	0.595	0.116	0.000	03:00:20
mtrlpq2314w-lp130-05-70-55-71-106.dsl.bell.ca	225.049	35.711	0.000	02:41:27
47-32-34-30.dhcp.ftbg.wi.charter.com	0.595	0.120	0.000	02:38:13
141.168.117.108	5.314	1.145	0.191	00:45:08
challenger.nielsenmedia.com	0.180	0.024	0.000	00:33:13
71-90-72-179.dhcp.ftbg.wi.charter.com	0.047	0.016	0.000	00:00:05
TOTAL: 7	234.984	37.774	0.191	16:45:06

Curious NNRP server explorers [Top 20]:

System	Conn
47-32-34-30.dhcp.ftbg.wi.charter.com	7
challenger.nielsenmedia.com	2
71-90-72-179.dhcp.ftbg.wi.charter.com	1
TOTAL: 3	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	6	0
47-32-34-30.dhcp.ftbg.wi.charter.com	5	0
challenger.nielsenmedia.com	1	0
TOTAL: 3	12	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	1578	93.7%
2	comp	42	2.5%
3	rec	22	1.3%
4	uk	20	1.2%
5	misc	8	0.5%
6	fr	5	0.3%
7	soc	4	0.2%
8	sci	2	0.1%
9	talk	1	0.1%
10	can	1	0.1%
11	aus	1	0.1%
TOTAL: 11		1684	100.0%

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

Newsgroup		Count
1	alt.education	433
2	alt.bible	375
3	alt.energy.renewable	315
4	alt.animals.ethics.vegetarian	228
5	alt.books.arthur-clarke	117
6	alt.comp.freeware	85
7	comp.arch	17
8	rec.boats	16
9	alt.home.repair	12
10	comp.sys.ibm.pc.games.action	8
11	misc.survivalism	8
12	fr.soc.politique	5
13	alt.politics.economics	4
14	comp.sys.hp.hardware	3
15	alt.comp.os.windows-10	3
16	alt.fan.rush-limbaugh	3
17	uk.rec.sheds	3
18	uk.tech.digital-tv	3
19	uk.politics.misc	3
20	comp.sys.mac.apps	3
21	uk.legal	3
22	comp.sys.mac.system	2
23	comp.lang.c	2
24	comp.sys.ibm.ps2.hardware	2
25	rec.arts.tv	2
26	uk.radio.amateur	2
27	soc.culture.usa	2
28	comp.arch.fpga	1
29	comp.windows.misc	1
30	sci.physics	1
31	sci.military.naval	1
32	rec.sport.tennis	1
33	alt.politics.trump	1
34	can.politics	1
35	soc.culture.lebanon	1
36	uk.rec.cycling	1
37	comp.lang.fortran	1
38	uk.people.silversurfers	1
39	aus.politics	1
40	rec.outdoors.rv-travel	1
41	talk.politics.misc	1
42	uk.media.tv.misc	1
43	rec.food.cooking	1
44	alt.conspiracy	1
45	uk.d-i-y	1
46	uk.rec.driving	1
47	soc.culture.singapore	1
48	comp.ai.philosophy	1
49	comp.os.vms	1
50	rec.music.beatles	1
51	uk.media.radio.archers	1
52	alt.california	1
TOTAL: 52		1684

Daily Usenet report for csiph.com

Dec 12 04:15:00 -- Dec 13 04:15:00