Daily Usenet report for csiph.com: Jan 29 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	29609	51.4%	6.6 MB	66.1%
nnrpd	12366	21.5%	1.3 MB	13.4%
innd	12324	21.4%	1.5 MB	15.3%
innfeed	3195	5.6%	528.8 KB	5.2%
nocem	56	0.1%	6.3 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	57551	100.0%	9.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	356452	56.1%
Negative hits	249945	39.3%
Do not exist	28638	4.5%
Cache misses	435	0.1%
TOTAL: 4	635470	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.600	0.0%	232	0.000	2.586	113.000
article cleanup	00:00:00.174	0.0%	28698	0.000	0.006	0.024
article logging	00:00:03.584	0.0%	29319	0.000	0.122	0.674
article parse	00:00:01.541	0.0%	73420	0.000	0.021	0.216
article write	00:02:51.094	0.2%	22079	0.174	7.749	31.641
artlog/artcncl	00:00:00.069	0.0%	227	0.000	0.304	68.000
artlog/artparse	00:00:00.011	0.0%	64	0.000	0.172	3.667
data move	00:00:00.158	0.0%	592593	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.117	0.0%	230	0.000	0.509	26.000
hishave/artcncl	00:00:00.040	0.0%	232	0.000	0.172	2.056
hishave/artparse	00:00:00.001	0.0%	63	0.000	0.016	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:20.295	0.2%	637471	0.002	0.220	0.529
history sync	00:00:04.543	0.0%	355	0.000	12.797	74.500
history write	00:03:04.410	0.2%	28676	0.068	6.431	23.171
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.625	0.0%	63	0.000	9.921	108.000
idle	23:44:52.544	98.9%	576718	84.614	148.240	499.067
nntp read	00:00:02.651	0.0%	603797	0.002	0.004	0.011
overview write	00:05:17.924	0.4%	22079	0.778	14.399	114.570
perl filter	00:00:58.143	0.1%	28675	1.437	2.028	4.066
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.448	0.0%	44208	0.000	0.010	0.029
TOTAL: 24:00:57.990	23:59:38.972	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.083	0.0%	22043	0.000	0.004	0.035
article prepare	00:00:00.002	0.0%	43668	0.000	0.000	0.005
article read	00:00:01.784	0.0%	7891	0.016	0.226	14.357
backlog stats	00:00:00.194	0.0%	403443	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.118	0.0%	313438	0.002	0.007	0.012
data write	00:00:01.830	0.0%	294989	0.002	0.006	0.014
idle	23:54:41.173	100.0%	403443	135.390	213.366	620.403
status file	00:00:00.252	0.0%	298	0.000	0.846	15.000
TOTAL: 23:54:59.308	23:54:47.436	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	35:50:39.169	99.9%	73674	79.161	1751.489	600165.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.076	0.0%	147609	0.000	0.007	0.457
readart	00:00:48.067	0.0%	9541	0.000	5.038	61.000
TOTAL: 35:52:44.036	35:51:28.312	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	101953	5952	91016	4985	5%	94:57:40
2	csiph-out.news.weretis.net	4	42943	5258	37419	266	12%	47:49:07
3	feeder.eternal-september.org	2	25191	3406	21314	471	13%	23:42:18
4	news-out.aioe.org	3	21115	2967	18105	43	14%	24:09:53
5	newsfeed.fu-berlin.de	28	22456	1341	20792	323	5%	29:26:20
6	newsfeed.neva.ru	149	34294	867	32295	1132	2%	71:51:30
7	news.redatomik.org	3	23195	781	22353	61	3%	25:54:42
8	newsfeeds.xmission.com	3	19599	588	19010	1	3%	21:50:53
9	news-out.mixmin.net	3	24070	489	23316	265	2%	24:11:54
10	nntp.pasdenom.info	3	20559	208	20338	13	1%	24:08:02
11	news.fcku.it	2	9285	98	9163	24	1%	23:59:42
12	news.freedyn.net	4	23946	71	23785	90	0%	24:09:17
13	xanadu-bbs.net	16	824	21	801	2	2%	22:56:39
14	localhost	51	18	18	0	0	100%	00:00:02
15	news.bbs.geek.nz	1	7799	6	7792	1	0%	12:38:10
16	news.swapon.de	4	54	1	53	0	1%	02:00:17
TOTAL: 16		1428	377301	22072	347552	7677	5%	473:46:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.4 MB	34.9 KB	12.3 MB	31.7 MB	61%	3.0 KB
2	csiph-out.news.weretis.net	16.0 MB	0.0 KB	4.3 MB	20.4 MB	78%	3.8 KB
3	feeder.eternal-september.org	9.3 MB	0.0 KB	5.6 MB	15.0 MB	62%	4.0 KB
4	news-out.aioe.org	7.2 MB	4.1 KB	88.9 KB	7.3 MB	98%	2.5 KB
5	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	673.8 KB	3.7 MB	82%	2.3 KB
6	news-out.mixmin.net	2.8 MB	1.5 KB	1.1 MB	3.9 MB	70%	5.3 KB
7	newsfeed.neva.ru	2.1 MB	41.2 KB	2.1 MB	4.2 MB	49%	2.2 KB
8	news.redatomik.org	2.0 MB	1.6 KB	241.3 KB	2.2 MB	89%	2.7 KB
9	newsfeeds.xmission.com	1.5 MB	0.0 KB	98.2 KB	1.6 MB	93%	2.7 KB
10	nntp.pasdenom.info	480.8 KB	1.7 KB	31.0 KB	513.5 KB	93%	2.3 KB
11	news.freedyn.net	187.3 KB	0.0 KB	242.8 KB	430.1 KB	43%	2.7 KB
12	news.fcku.it	167.2 KB	2.3 KB	1.5 MB	1.7 MB	9%	14.2 KB
13	xanadu-bbs.net	125.5 KB	0.0 KB	14.3 KB	139.8 KB	89%	6.1 KB
14	localhost	25.1 KB	0.0 KB	0.0 KB	25.1 KB	100%	1.4 KB
15	news.bbs.geek.nz	10.4 KB	0.0 KB	0.8 KB	11.1 KB	92%	1.6 KB
16	news.swapon.de	2.6 KB	0.0 KB	0.0 KB	2.6 KB	100%	2.6 KB
TOTAL: 16		64.3 MB	87.3 KB	28.4 MB	92.8 MB	69%	3.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 29 04:15:00 - 04:59:59	337	1.5%	0.12	1016.8 KB	1.5%	0.38
Jan 29 05:00:00 - 05:59:59	370	1.7%	0.10	1.2 MB	1.8%	0.33
Jan 29 06:00:00 - 06:59:59	424	1.9%	0.12	2.0 MB	3.1%	0.57
Jan 29 07:00:00 - 07:59:59	541	2.5%	0.15	1.6 MB	2.5%	0.45
Jan 29 08:00:00 - 08:59:59	677	3.1%	0.19	2.1 MB	3.3%	0.60
Jan 29 09:00:00 - 09:59:59	859	3.9%	0.24	2.4 MB	3.8%	0.69
Jan 29 10:00:00 - 10:59:59	1039	4.7%	0.29	3.2 MB	5.0%	0.91
Jan 29 11:00:00 - 11:59:59	1124	5.1%	0.31	3.3 MB	5.1%	0.94
Jan 29 12:00:00 - 12:59:59	1071	4.9%	0.30	3.0 MB	4.6%	0.84
Jan 29 13:00:00 - 13:59:59	1173	5.3%	0.33	3.3 MB	5.1%	0.94
Jan 29 14:00:00 - 14:59:59	1021	4.6%	0.28	2.8 MB	4.3%	0.79
Jan 29 15:00:00 - 15:59:59	1264	5.7%	0.35	3.5 MB	5.5%	1.00
Jan 29 16:00:00 - 16:59:59	1292	5.9%	0.36	3.6 MB	5.6%	1.03
Jan 29 17:00:00 - 17:59:59	1342	6.1%	0.37	3.7 MB	5.8%	1.07
Jan 29 18:00:00 - 18:59:59	1303	5.9%	0.36	3.7 MB	5.7%	1.04
Jan 29 19:00:00 - 19:59:59	1372	6.2%	0.38	3.7 MB	5.7%	1.05
Jan 29 20:00:00 - 20:59:59	1298	5.9%	0.36	3.4 MB	5.2%	0.96
Jan 29 21:00:00 - 21:59:59	1116	5.1%	0.31	2.9 MB	4.5%	0.82
Jan 29 22:00:00 - 22:59:59	1008	4.6%	0.28	2.9 MB	4.5%	0.81
Jan 29 23:00:00 - 23:59:59	968	4.4%	0.27	3.1 MB	4.8%	0.87
Jan 30 00:00:00 - 00:59:59	827	3.7%	0.23	3.0 MB	4.7%	0.85
Jan 30 01:00:00 - 01:59:59	566	2.6%	0.16	1.6 MB	2.5%	0.45
Jan 30 02:00:00 - 02:59:59	484	2.2%	0.13	1.4 MB	2.2%	0.40
Jan 30 03:00:00 - 03:59:59	476	2.2%	0.13	1.7 MB	2.7%	0.49
Jan 30 04:00:00 - 04:15:00	125	0.6%	0.14	332.5 KB	0.5%	0.37
TOTAL: 24:00:00	22077	100.0%	0.26	64.3 MB	100.0%	0.76

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	4037	203	11	3823
2	newsfeed.neva.ru	1142	195	6	941
3	newsfeed.fu-berlin.de	787	22	0	765
4	feeder.eternal-september.org	497	45	0	452
5	news-out.mixmin.net	274	3	1	270
6	csiph-out.news.weretis.net	267	99	0	168
7	news.freedyn.net	90	3	0	87
8	news-out.aioe.org	83	30	2	51
9	news.redatomik.org	68	0	1	67
10	news.fcku.it	24	0	1	23
11	news.swapon.de	15	0	0	15
12	nntp.pasdenom.info	14	5	1	8
13	newsfeeds.xmission.com	4	0	0	4
14	xanadu-bbs.net	2	0	0	2
15	news.bbs.geek.nz	1	1	0	0
TOTAL: 15		7305	606	23	6676

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	102
it-alt.sport.calcio.juventus	63
lada.auto	55
ut.test	47
gmane.linux.alsa.devel	36
mozilla.support.firefox	24
alt.survival	19
mozilla.support.seamonkey	13
spline.eisfair	13
alt.computer.workshop	11
alt.windows7.general	9
lada.kitchen	8
alt.politics.scorched-earth	7
mpc.lists.freebsd.gecko	7
gmane.linux.ports.arm.kernel	7
lada.commerce	7
it-alt.comp.software.mozilla	6
lada.soft	6
mozilla.dev.l10n.web	5
mozilla.dev.platform	5
TOTAL: 109	606

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4453
EMP (phn path)	721
EMP (phl)	528
EMP (phn nph)	154
Too many newsgroups	78
User-issued cancel	13
HTML Multipart	13
Scoring filter (13)	9
Binary Payload	8
Scoring filter (14)	4
User-issued spam cancel	3
Scoring filter (12)	3
HTML post	2
Scoring filter (18)	1
HTML Image Tags	1
U2 violation - invalid distribution	1
TOTAL: 16	5992

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	50	0	67	67
TOTAL: 1	50	0	67	67

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.news.weretis.net	37
	feeder.eternal-september.org	9
	news-out.mixmin.net	9
	news.fcku.it	3
	news.redatomik.org	2
	newsfeed.fu-berlin.de	1
	news.freedyn.net	1
	newsfeeds.xmission.com	1
	TOTAL: 8	63
Including strange strings
	newsfeed.fu-berlin.de	464
	news-out.aioe.org	40
	csiph-out.feeder.erje.net	38
	feeder.eternal-september.org	25
	news.swapon.de	14
	csiph-out.news.weretis.net	10
	newsfeed.neva.ru	9
	news-out.mixmin.net	9
	news.redatomik.org	8
	newsfeeds.xmission.com	3
	TOTAL: 10	620
TOTAL: 2		683

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	35743	6194	14993	58	0	17%	23:53:57
2	news.freedyn.net	22745	927	21026	6	0	4%	23:54:23
3	xanadu-bbs	24207	867	21184	0	0	3%	23:59:52
4	fu-berlin.de	20479	708	19771	0	0	3%	23:59:52
5	erje	16636	286	15590	23	0	1%	23:52:23
6	xmission	23090	99	20505	7	0	0%	23:59:52
7	usenet.pasdenom.info	23760	84	20344	1438	4	0%	23:59:52
8	aioe	17925	51	17045	12	0	0%	23:50:08
9	news.redatomik.org	21009	42	20461	9	0	0%	23:53:25
10	weretis.net	15284	25	15063	0	0	0%	23:51:58
11	news.mixmin.net	20176	17	19561	0	0	0%	23:59:49
12	eternal-september	18859	17	17576	0	0	0%	23:59:52
13	news.swapon.de	2395	15	2355	4	0	0%	23:50:55
14	neva.ru	21155	6	20156	20	0	0%	23:59:52
15	news.fcku.it	1973	3	1933	0	0	0%	21:20:05
16	bwh	0	0	0	0	21248	0%	23:50:10
17	au2pb	0	0	0	0	21320	0%	23:58:42
TOTAL: 17		285436	9341	247563	1577	42572	3%	404:15:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	16.6 MB	609.2 KB	17.2 MB	0.2 KB/s	2.8 KB	23:53:57
2	news.freedyn.net	5.6 MB	12.1 KB	5.6 MB	0.1 KB/s	6.1 KB	23:54:23
3	xanadu-bbs	5.4 MB	0.0 KB	5.4 MB	0.1 KB/s	6.4 KB	23:59:52
4	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	7.3 KB	23:59:52
5	erje	1.2 MB	175.7 KB	1.4 MB	0.0 KB/s	4.7 KB	23:52:23
6	xmission	206.7 KB	21.2 KB	227.9 KB	0.0 KB/s	2.1 KB	23:59:52
7	usenet.pasdenom.info	197.9 KB	6.3 MB	6.5 MB	0.1 KB/s	4.4 KB	23:59:52
8	aioe	126.8 KB	31.4 KB	158.2 KB	0.0 KB/s	2.5 KB	23:50:08
9	news.redatomik.org	76.0 KB	28.9 KB	105.0 KB	0.0 KB/s	2.1 KB	23:53:25
10	weretis.net	42.8 KB	0.0 KB	42.8 KB	0.0 KB/s	1.7 KB	23:51:58
11	eternal-september	27.3 KB	0.0 KB	27.3 KB	0.0 KB/s	1.6 KB	23:59:52
12	news.mixmin.net	24.3 KB	0.0 KB	24.3 KB	0.0 KB/s	1.4 KB	23:59:49
13	news.swapon.de	19.9 KB	8.3 KB	28.2 KB	0.0 KB/s	1.5 KB	23:50:55
14	neva.ru	8.4 KB	41.6 KB	50.0 KB	0.0 KB/s	1.9 KB	23:59:52
15	news.fcku.it	5.6 KB	0.0 KB	5.6 KB	0.0 KB/s	1.9 KB	21:20:05
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:10
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		34.6 MB	7.2 MB	41.8 MB	0.0 KB/s	3.9 KB	404:15:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	250	46	202.4 KB	35	0	0	01:53:14
2	68-185-181-64.dhcp.mdsn.wi.charter.com	34	29	50.8 KB	15	18	13	08:20:31
3	dhcp-089-099-203-254.chello.nl	58	28	47.8 KB	12	0	0	15:49:29
4	194-73-239-109.cust.centrio.cz	28	17	35.2 KB	10	0	0	06:32:32
5	tempe0.bbox.io	18	2	3.6 KB	2	0	0	03:16:45
TOTAL: 5		388	122	339.7 KB	74	18	13	35:52:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	250	46	202.4 KB	35	0	0	01:53:14
2	*.dhcp.mdsn.wi.charter.com	34	29	50.8 KB	15	18	13	08:20:31
3	*.chello.nl	58	28	47.8 KB	12	0	0	15:49:29
4	*.cust.centrio.cz	28	17	35.2 KB	10	0	0	06:32:32
5	*.bbox.io	18	2	3.6 KB	2	0	0	03:16:45
6	*.lightspeed.gdrpmi.sbcglobal.net	1	0	0.0 KB	0	0	0	00:00:05
7	unresolved	1	0	0.0 KB	0	0	0	00:00:02
TOTAL: 7		390	122	339.7 KB	74	18	13	35:52:40

NNRP auth users [Top 20]:

User	Conn
ctomczyk	16
ohlandl	13
TOTAL: 2	29

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	5.400	0.953	0.000	15:49:29
68-185-181-64.dhcp.mdsn.wi.charter.com	3.501	0.612	0.047	08:20:31
194-73-239-109.cust.centrio.cz	2.532	0.421	0.083	06:32:32
tempe0.bbox.io	1.338	0.244	0.000	03:16:45
mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	26.491	6.458	0.000	01:53:14
99-44-178-117.lightspeed.gdrpmi.sbcglobal.net	0.090	0.008	0.000	00:00:05
94.160.149.10	0.061	0.038	0.000	00:00:02
TOTAL: 7	39.413	8.734	0.130	35:52:40

Curious NNRP server explorers [Top 20]:

System	Conn
94.160.149.10	1
99-44-178-117.lightspeed.gdrpmi.sbcglobal.net	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
194-73-239-109.cust.centrio.cz	12	0
68-185-181-64.dhcp.mdsn.wi.charter.com	7	0
tempe0.bbox.io	6	0
dhcp-089-099-203-254.chello.nl	2	0
TOTAL: 4	27	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	73	59.8%
2	alt	30	24.6%
3	talk	6	4.9%
4	fr	5	4.1%
5	linux	3	2.5%
6	pl	3	2.5%
7	rec	1	0.8%
8	uk	1	0.8%
TOTAL: 8		122	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	59
2	comp.lang.javascript	14
3	alt.atheism	10
4	talk.politics.misc	6
5	alt.society.liberalism	5
6	fr.soc.politique	4
7	alt.comp.os.windows-10	3
8	alt.california	3
9	linux.kernel	3
10	pl.comp.www	2
11	alt.home.repair	2
12	alt.global-warming	2
13	alt.politics.economics	2
14	alt.christnet.christianlife	1
15	uk.politics.misc	1
16	alt.conspiracy	1
17	pl.comp.lang.javascript	1
18	fr.rec.photo	1
19	rec.arts.tv	1
20	alt.astronomy	1
TOTAL: 20		122

Daily Usenet report for csiph.com

Jan 29 04:15:00 -- Jan 30 04:15:00