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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	30206	43.7%	6.4 MB	58.9%
nnrpd	21815	31.5%	2.3 MB	20.9%
innd	13795	19.9%	1.7 MB	15.2%
innfeed	3265	4.7%	535.9 KB	4.8%
nocem	114	0.2%	12.3 KB	0.1%
TOTAL: 5	69195	100.0%	10.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	326853	53.8%
Negative hits	250753	41.3%
Do not exist	29103	4.8%
Cache misses	337	0.1%
TOTAL: 4	607046	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.383	0.0%	300	0.000	7.943	74.900
article cleanup	00:00:00.214	0.0%	28980	0.000	0.007	0.042
article logging	00:00:02.192	0.0%	29599	0.000	0.074	3.106
article parse	00:00:06.293	0.0%	80337	0.004	0.078	1.775
article write	00:04:45.558	0.3%	20786	0.234	13.738	148.741
artlog/artcncl	00:00:00.013	0.0%	212	0.000	0.061	0.769
artlog/artparse	00:00:00.325	0.0%	409	0.000	0.795	39.167
data move	00:00:00.181	0.0%	590067	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.160	0.0%	295	0.000	0.542	11.000
hishave/artcncl	00:00:00.414	0.0%	300	0.000	1.380	20.867
hishave/artparse	00:00:00.001	0.0%	406	0.000	0.002	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:27.119	0.2%	611898	0.003	0.240	2.132
history sync	00:00:05.284	0.0%	365	0.000	14.477	153.000
history write	00:02:53.355	0.2%	28973	0.273	5.983	43.398
hiswrite/artcncl	00:00:00.004	0.0%	5	0.000	0.800	1.000
hiswrite/artparse	00:00:04.961	0.0%	406	0.000	12.219	120.000
idle	23:40:46.320	98.6%	575769	75.024	148.056	469.646
nntp read	00:00:02.797	0.0%	599263	0.002	0.005	0.008
overview write	00:07:48.714	0.5%	20786	0.696	22.550	1151.108
perl filter	00:00:52.778	0.1%	28967	1.157	1.822	3.364
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.354	0.0%	41680	0.000	0.008	0.031
TOTAL: 24:00:54.024	23:59:59.420	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.049	0.0%	20961	0.000	0.002	0.025
article prepare	00:00:00.001	0.0%	17464	0.000	0.000	0.011
article read	00:00:01.014	0.0%	2227	0.000	0.455	25.000
backlog stats	00:00:00.194	0.0%	376331	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.991	0.0%	293770	0.004	0.007	0.013
data write	00:00:01.634	0.0%	276826	0.002	0.006	0.012
idle	23:54:03.640	100.0%	376331	119.983	228.638	685.344
status file	00:00:00.528	0.0%	329	0.000	1.605	17.000
TOTAL: 23:54:16.217	23:54:09.051	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	19:04:38.543	99.7%	27459	72.929	2501.131	600218.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.302	0.0%	56661	0.000	0.005	0.167
readart	00:01:13.517	0.1%	6442	0.000	11.412	475.000
TOTAL: 19:08:04.123	19:05:52.362	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	92417	4725	85096	2596	5%	94:59:12
2	news-out.mixmin.net	3	22075	3346	17834	895	15%	24:08:55
3	feeder.eternal-september.org	2	23784	2536	20358	890	10%	23:10:32
4	newsfeeds.xmission.com	3	20270	2320	17944	6	11%	24:37:22
5	csiph-out.news.weretis.net	4	44093	2021	41664	408	4%	50:03:17
6	news.redatomik.org	1	18604	1607	16992	5	8%	23:15:48
7	newsfeed.neva.ru	153	21788	1248	17619	2921	5%	71:48:03
8	newsfeed.fu-berlin.de	23	21423	1132	19890	401	5%	28:33:45
9	news-out.aioe.org	2	20222	1057	19136	29	5%	24:09:59
10	news.fcku.it	2	9394	616	8458	320	6%	25:20:42
11	news.swapon.de	6	1863	49	1814	0	2%	24:03:36
12	nntp.pasdenom.info	3	19925	41	19794	90	0%	23:58:48
13	xanadu-bbs.net	15	764	6	758	0	0%	23:21:05
14	news.freedyn.net	1	9680	4	9675	1	0%	12:15:04
15	news.bbs.geek.nz	2	19697	2	19695	0	0%	23:59:47
16	localhost	33	0	0	0	0	0%	00:00:00
TOTAL: 16		1405	345999	20710	316727	8562	5%	497:45:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.2 MB	80.8 KB	14.6 MB	33.9 MB	56%	4.7 KB
2	news-out.mixmin.net	10.0 MB	0.0 KB	2.0 MB	12.0 MB	83%	2.9 KB
3	newsfeeds.xmission.com	6.6 MB	0.0 KB	305.7 KB	6.9 MB	95%	3.1 KB
4	feeder.eternal-september.org	6.3 MB	0.0 KB	4.1 MB	10.4 MB	60%	3.1 KB
5	csiph-out.news.weretis.net	6.3 MB	0.0 KB	14.0 MB	20.3 MB	30%	8.5 KB
6	news.redatomik.org	3.6 MB	0.0 KB	136.1 KB	3.7 MB	96%	2.4 KB
7	news-out.aioe.org	3.1 MB	0.0 KB	50.5 KB	3.2 MB	98%	3.0 KB
8	newsfeed.neva.ru	3.0 MB	9.4 KB	18.5 MB	21.6 MB	13%	5.3 KB
9	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	1.0 MB	3.6 MB	72%	2.4 KB
10	news.fcku.it	1.6 MB	0.0 KB	3.1 MB	4.7 MB	34%	5.2 KB
11	news.swapon.de	119.4 KB	0.0 KB	0.0 KB	119.4 KB	100%	2.4 KB
12	nntp.pasdenom.info	96.5 KB	0.0 KB	154.2 KB	250.8 KB	38%	1.9 KB
13	xanadu-bbs.net	17.3 KB	0.0 KB	0.0 KB	17.3 KB	100%	2.9 KB
14	news.freedyn.net	9.2 KB	0.0 KB	89.8 KB	99.0 KB	9%	19.8 KB
15	news.bbs.geek.nz	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.8 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		62.6 MB	90.2 KB	58.1 MB	120.8 MB	51%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 6 04:15:00 - 04:59:59	309	1.5%	0.11	997.6 KB	1.6%	0.37
Dec 6 05:00:00 - 05:59:59	445	2.1%	0.12	2.4 MB	3.8%	0.68
Dec 6 06:00:00 - 06:59:59	531	2.6%	0.15	2.1 MB	3.3%	0.59
Dec 6 07:00:00 - 07:59:59	651	3.1%	0.18	1.9 MB	3.1%	0.55
Dec 6 08:00:00 - 08:59:59	715	3.4%	0.20	1.8 MB	2.9%	0.52
Dec 6 09:00:00 - 09:59:59	953	4.6%	0.26	2.4 MB	3.9%	0.69
Dec 6 10:00:00 - 10:59:59	903	4.3%	0.25	2.3 MB	3.7%	0.65
Dec 6 11:00:00 - 11:59:59	868	4.2%	0.24	2.3 MB	3.7%	0.66
Dec 6 12:00:00 - 12:59:59	871	4.2%	0.24	2.2 MB	3.5%	0.61
Dec 6 13:00:00 - 13:59:59	925	4.5%	0.26	2.6 MB	4.2%	0.75
Dec 6 14:00:00 - 14:59:59	925	4.5%	0.26	2.2 MB	3.6%	0.63
Dec 6 15:00:00 - 15:59:59	1205	5.8%	0.33	4.2 MB	6.8%	1.20
Dec 6 16:00:00 - 16:59:59	1382	6.7%	0.38	5.0 MB	8.0%	1.42
Dec 6 17:00:00 - 17:59:59	1357	6.5%	0.38	4.7 MB	7.5%	1.34
Dec 6 18:00:00 - 18:59:59	1198	5.8%	0.33	3.3 MB	5.3%	0.94
Dec 6 19:00:00 - 19:59:59	1264	6.1%	0.35	3.8 MB	6.1%	1.09
Dec 6 20:00:00 - 20:59:59	1161	5.6%	0.32	2.9 MB	4.7%	0.84
Dec 6 21:00:00 - 21:59:59	1028	5.0%	0.29	3.0 MB	4.9%	0.86
Dec 6 22:00:00 - 22:59:59	969	4.7%	0.27	2.7 MB	4.4%	0.78
Dec 6 23:00:00 - 23:59:59	934	4.5%	0.26	2.9 MB	4.7%	0.83
Dec 7 00:00:00 - 00:59:59	620	3.0%	0.17	1.7 MB	2.8%	0.50
Dec 7 01:00:00 - 01:59:59	545	2.6%	0.15	1.6 MB	2.5%	0.44
Dec 7 02:00:00 - 02:59:59	448	2.2%	0.12	1.3 MB	2.1%	0.37
Dec 7 03:00:00 - 03:59:59	481	2.3%	0.13	1.6 MB	2.6%	0.47
Dec 7 04:00:00 - 04:15:00	73	0.4%	0.08	260.3 KB	0.4%	0.29
TOTAL: 24:00:00	20761	100.0%	0.24	62.5 MB	100.0%	0.74

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	2934	229	2	2703
2	csiph-out.feeder.erje.net	2640	348	8	2284
3	news-out.mixmin.net	949	10	0	939
4	feeder.eternal-september.org	929	79	0	850
5	newsfeed.fu-berlin.de	848	26	0	822
6	csiph-out.news.weretis.net	399	202	0	197
7	news.fcku.it	338	2	0	336
8	nntp.pasdenom.info	90	53	0	37
9	news.redatomik.org	51	0	0	51
10	news-out.aioe.org	39	29	0	10
11	newsfeeds.xmission.com	7	3	0	4
12	news.freedyn.net	5	0	0	5
13	news.swapon.de	4	0	0	4
TOTAL: 13		9233	981	10	8242

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	148
gmane.linux.alsa.devel	78
alt.survival	71
mpc.lists.nanog	39
gmane.linux.kernel	39
mozilla.support.seamonkey	37
it-alt.sport.calcio.juventus	36
it.annunci.varie	33
it.annunci.immobiliari	33
it.annunci.commerciali	32
alt.windows7.general	30
lada.auto	30
nemo.bavardages	28
alt.computer.workshop	26
ut.test	19
mozilla.support.firefox	18
nemo.dev.serveur	15
mozilla.general	15
mozilla.dev.platform	12
lada.commerce	11
TOTAL: 113	981

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5759
EMP (phn path)	506
Binary: misplaced rar	317
EMP (phn nph)	276
User-issued cancel	100
Too many newsgroups	44
EMP (phl)	41
Scoring filter (10)	31
User-issued spam cancel	25
Too many newsgroups (meow)	16
HTML Multipart	16
Scoring filter (11)	16
Binary Payload	14
Scoring filter (12)	11
Scoring filter (13)	7
Scoring filter (15)	6
Scoring filter (8)	5
Too many newsgroups (low_xpost)	5
Binary: misplaced par2	5
Scoring filter (14)	4
TOTAL: 25	7214

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	108	0	141	141
TOTAL: 1	108	0	141	141

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	181
	csiph-out.news.weretis.net	140
	news.fcku.it	40
	feeder.eternal-september.org	25
	news-out.mixmin.net	9
	news.redatomik.org	3
	newsfeeds.xmission.com	3
	newsfeed.fu-berlin.de	3
	news.freedyn.net	2
	TOTAL: 9	406
Including strange strings
	newsfeed.fu-berlin.de	454
	news-out.mixmin.net	56
	news.redatomik.org	45
	csiph-out.feeder.erje.net	38
	news-out.aioe.org	8
	news.swapon.de	4
	news.fcku.it	3
	csiph-out.news.weretis.net	3
	feeder.eternal-september.org	3
	newsfeeds.xmission.com	1
	TOTAL: 11	616
No colon-space in header
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
TOTAL: 4		1029

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	22640	811	19895	13	0	3%	23:53:02
2	news.bbs.geek.nz	37546	775	19282	60	0	2%	23:55:42
3	xanadu-bbs	24052	693	20058	0	10	2%	23:59:56
4	fu-berlin.de	19381	570	18810	0	0	2%	23:52:01
5	usenet.pasdenom.info	21557	61	19471	1190	10	0%	23:59:56
6	aioe	17756	51	17310	0	0	0%	23:50:04
7	news.redatomik.org	18845	20	18376	0	0	0%	23:50:04
8	erje	16746	17	15878	1	183	0%	23:58:02
9	news.swapon.de	1894	6	1879	0	0	0%	23:48:41
10	xmission	19656	5	17792	0	0	0%	23:59:56
11	eternal-september	17885	5	17323	0	1	0%	23:59:49
12	news.mixmin.net	16821	2	16578	0	0	0%	23:53:06
13	weretis.net	18071	2	17082	0	19	0%	23:55:04
14	news.fcku.it	1673	0	1670	0	0	0%	22:53:34
15	au2pb	0	0	0	0	20097	0%	23:50:06
16	neva.ru	18804	0	18615	43	0	0%	23:59:56
17	bwh	0	0	0	0	20084	0%	23:50:06
TOTAL: 17		273327	3018	240019	1307	40404	1%	405:29:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.3 MB	84.6 KB	8.4 MB	0.1 KB/s	10.4 KB	23:53:02
2	xanadu-bbs	7.9 MB	0.0 KB	7.9 MB	0.1 KB/s	11.7 KB	23:59:56
3	fu-berlin.de	7.6 MB	0.0 KB	7.6 MB	0.1 KB/s	13.7 KB	23:52:01
4	news.bbs.geek.nz	2.2 MB	158.7 KB	2.3 MB	0.0 KB/s	2.9 KB	23:55:42
5	usenet.pasdenom.info	207.7 KB	8.6 MB	8.8 MB	0.1 KB/s	7.2 KB	23:59:56
6	aioe	130.2 KB	0.0 KB	130.2 KB	0.0 KB/s	2.6 KB	23:50:04
7	erje	88.5 KB	1.7 KB	90.3 KB	0.0 KB/s	5.0 KB	23:58:02
8	news.redatomik.org	50.9 KB	0.0 KB	50.9 KB	0.0 KB/s	2.5 KB	23:50:04
9	news.swapon.de	15.0 KB	0.0 KB	15.0 KB	0.0 KB/s	2.5 KB	23:48:41
10	xmission	12.3 KB	0.0 KB	12.3 KB	0.0 KB/s	2.5 KB	23:59:56
11	eternal-september	10.1 KB	0.0 KB	10.1 KB	0.0 KB/s	2.0 KB	23:59:49
12	weretis.net	7.7 KB	0.0 KB	7.7 KB	0.0 KB/s	3.9 KB	23:55:04
13	news.mixmin.net	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	2.2 KB	23:53:06
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:53:34
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
16	neva.ru	0.0 KB	115.4 KB	115.4 KB	0.0 KB/s	2.7 KB	23:59:56
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
TOTAL: 17		26.6 MB	9.0 MB	35.6 MB	0.0 KB/s	8.4 KB	405:29:05

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	mtrlpq2314w-lp130-05-70-55-71-106.dsl.bell.ca	1200	565	1.3 MB	64	01:11:21
2	141.168.117.108	47	144	374.5 KB	82	00:42:00
3	194-73-239-109.cust.centrio.cz	4	1	1.6 KB	1	01:01:38
TOTAL: 3		1251	710	1.7 MB	147	19:07:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.dsl.bell.ca	1200	565	1.3 MB	64	01:11:21
2	unresolved	47	144	374.5 KB	82	00:42:00
3	*.cust.centrio.cz	4	1	1.6 KB	1	01:01:38
4	*.bbox.io	5	0	0.0 KB	0	02:11:50
5	*.dhcp.ftbg.wi.charter.com	12	0	0.0 KB	0	04:05:26
6	*.nielsenmedia.com	1	0	0.0 KB	0	00:30:04
7	*.upc-a.chello.nl	58	0	0.0 KB	0	09:25:31
TOTAL: 7		1327	710	1.7 MB	147	19:07:54

NNRP auth users [Top 20]:

User	Conn
moussa	47
ctomczyk	4
TOTAL: 2	51

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	4.063	0.910	0.000	09:25:31
71-90-0-54.dhcp.ftbg.wi.charter.com	0.985	0.191	0.000	04:05:26
tempe0.bbox.io	0.537	0.110	0.000	02:11:50
mtrlpq2314w-lp130-05-70-55-71-106.dsl.bell.ca	102.241	14.319	0.000	01:11:21
194-73-239-109.cust.centrio.cz	0.284	0.054	0.012	01:01:38
141.168.117.108	5.483	1.102	0.164	00:42:00
challenger.nielsenmedia.com	0.058	0.008	0.000	00:30:04
TOTAL: 7	113.651	16.694	0.176	19:07:54

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	58
71-90-0-54.dhcp.ftbg.wi.charter.com	12
tempe0.bbox.io	5
challenger.nielsenmedia.com	1
TOTAL: 4	76

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-90-0-54.dhcp.ftbg.wi.charter.com	7	0
tempe0.bbox.io	3	0
194-73-239-109.cust.centrio.cz	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 4	13	0

Newsgroup request counts (by category):

Category		Count	Pct
1	ont	410	57.7%
2	alt	150	21.1%
3	comp	94	13.2%
4	nyc	16	2.3%
5	uk	16	2.3%
6	fr	12	1.7%
7	rec	6	0.8%
8	phl	2	0.3%
9	aus	2	0.3%
10	misc	1	0.1%
11	pl	1	0.1%
TOTAL: 11		710	100.0%

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

Newsgroup		Count
1	ont.jobs	410
2	alt.comp.freeware	61
3	comp.os.vms	49
4	alt.bestjobsusa.computer.jobs	36
5	alt.jobs	29
6	comp.arch	15
7	comp.sys.ibm.pc.games.action	15
8	nyc.jobs.offered	14
9	fr.soc.politique	12
10	uk.media.radio.archers	7
11	comp.lang.python	6
12	alt.home.repair	5
13	alt.society.liberalism	4
14	uk.radio.amateur	4
15	rec.boats	4
16	alt.usage.english	3
17	alt.politics.economics	3
18	nyc.jobs.misc	2
19	aus.cars	2
20	alt.fan.rush-limbaugh	2
21	phl.jobs.offered	2
22	alt.california	2
23	comp.sys.ibm.pc.hardware.video	1
24	comp.arch.embedded	1
25	alt.activism.death-penalty	1
26	rec.photo.digital	1
27	alt.conspiracy	1
28	uk.rec.gardening	1
29	uk.politics.misc	1
30	comp.ai.nat-lang	1
31	misc.survivalism	1
32	uk.railway	1
33	comp.lang.c	1
34	comp.os.os2.announce	1
35	alt.stupidity	1
36	comp.sys.ibm.pc.games.war-historical	1
37	uk.legal	1
38	comp.sys.ibm.as400.misc	1
39	uk.rec.cycling	1
40	comp.ai.edu	1
41	alt.comp.os.windows-10	1
42	comp.ai.philosophy	1
43	rec.autos.sport.f1	1
44	alt.politics.trump	1
45	pl.comp.www	1
TOTAL: 45		710

Daily Usenet report for csiph.com

Dec 6 04:15:00 -- Dec 7 04:15:00