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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	24450	44.9%	5.1 MB	58.0%
nnrpd	14611	26.8%	1.7 MB	19.0%
innd	12463	22.9%	1.5 MB	17.5%
innfeed	2930	5.4%	501.4 KB	5.6%
nocem	25	0.0%	2.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	54480	100.0%	8.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	298209	55.0%
Negative hits	220546	40.6%
Do not exist	23606	4.4%
Cache misses	259	0.0%
TOTAL: 4	542620	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.301	0.0%	51	0.000	5.902	90.000
article cleanup	00:00:00.172	0.0%	23365	0.000	0.007	0.032
article logging	00:00:00.387	0.0%	24006	0.000	0.016	0.052
article parse	00:00:01.017	0.0%	64434	0.000	0.016	0.122
article write	00:00:13.935	0.0%	18074	0.153	0.771	42.902
artlog/artcncl	00:00:00.000	0.0%	51	0.000	0.000	0.000
artlog/artparse	00:00:00.002	0.0%	368	0.000	0.005	0.500
data move	00:00:00.172	0.0%	520326	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.019	0.0%	51	0.000	0.373	2.000
hishave/artcncl	00:00:00.001	0.0%	51	0.000	0.020	1.000
hishave/artparse	00:00:00.000	0.0%	368	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.280	0.0%	545306	0.001	0.010	0.052
history sync	00:00:00.793	0.0%	370	0.000	2.143	6.333
history write	00:00:13.165	0.0%	23364	0.179	0.563	6.078
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.215	0.0%	368	0.000	0.584	16.000
idle	23:58:24.960	99.8%	527761	81.937	163.530	598.104
nntp read	00:00:02.860	0.0%	526869	0.002	0.005	0.023
overview write	00:00:19.237	0.0%	18074	0.162	1.064	36.529
perl filter	00:00:43.074	0.0%	23361	1.371	1.844	3.157
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.330	0.0%	36173	0.000	0.009	0.026
TOTAL: 24:01:00.021	24:00:05.920	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.052	0.0%	18549	0.000	0.003	0.029
article prepare	00:00:00.002	0.0%	15154	0.000	0.000	0.011
article read	00:00:00.480	0.0%	1833	0.000	0.262	7.176
backlog stats	00:00:00.213	0.0%	353716	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.858	0.0%	264818	0.001	0.007	0.016
data write	00:00:01.640	0.0%	248552	0.002	0.007	0.023
idle	23:54:06.560	100.0%	353716	121.442	243.265	719.463
status file	00:00:00.490	0.0%	281	0.000	1.744	15.750
TOTAL: 23:54:18.055	23:54:11.295	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	37:16:33.437	99.1%	341855	58.000	392.545	600211.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.839	0.0%	972420	0.000	0.009	7.875
readart	00:18:01.719	0.8%	319138	0.000	3.390	239.000
TOTAL: 37:37:27.998	37:34:43.995	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.mixmin.net	3	18976	3602	14596	778	18%	24:11:41
2	newsfeeds.xmission.com	4	16806	2752	13954	100	16%	23:23:37
3	feeder.eternal-september.org	1	20927	2378	18223	326	11%	24:46:29
4	csiph-out.feeder.erje.net	1441	94734	2282	91341	1111	2%	118:46:19
5	news-out.aioe.org	2	17903	1858	15998	47	10%	24:09:02
6	csiph-out.news.weretis.net	4	40697	1576	38624	497	3%	53:24:05
7	newsfeed.fu-berlin.de	6	16953	1078	15658	217	6%	23:19:28
8	newsfeed.neva.ru	149	20596	1056	17464	2076	5%	71:53:46
9	news.redatomik.org	18	19745	891	18378	476	4%	23:46:24
10	news.fcku.it	10	2323	424	1898	1	18%	22:28:43
11	nntp.pasdenom.info	1	16616	61	16518	37	0%	23:59:55
12	news.swapon.de	2	1500	49	1449	2	3%	24:38:45
13	news.freedyn.net	2	19319	33	19272	14	0%	23:44:58
14	news.bbs.nz	1	17760	1	17759	0	0%	23:59:54
15	xanadu-bbs.net	18	502	1	496	5	0%	22:46:30
16	localhost	26	0	0	0	0	0%	00:00:05
TOTAL: 16		1688	325357	18042	301628	5687	5%	529:19:41

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.mixmin.net	10.1 MB	3.8 KB	2.2 MB	12.3 MB	82%	2.9 KB
2	csiph-out.feeder.erje.net	9.6 MB	0.0 KB	12.0 MB	21.6 MB	44%	6.5 KB
3	newsfeeds.xmission.com	8.9 MB	0.0 KB	951.2 KB	9.9 MB	90%	3.5 KB
4	feeder.eternal-september.org	6.3 MB	0.0 KB	2.1 MB	8.4 MB	75%	3.2 KB
5	csiph-out.news.weretis.net	5.4 MB	0.0 KB	8.7 MB	14.0 MB	38%	6.9 KB
6	news-out.aioe.org	4.6 MB	0.0 KB	216.7 KB	4.8 MB	95%	2.6 KB
7	newsfeed.neva.ru	2.9 MB	0.0 KB	7.7 MB	10.6 MB	27%	3.5 KB
8	news.redatomik.org	2.7 MB	0.0 KB	4.8 MB	7.5 MB	36%	5.6 KB
9	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	484.5 KB	2.8 MB	83%	2.2 KB
10	news.fcku.it	686.3 KB	0.0 KB	96.5 KB	782.8 KB	87%	1.8 KB
11	news.swapon.de	141.5 KB	0.0 KB	12.3 KB	153.7 KB	92%	3.0 KB
12	news.freedyn.net	132.7 KB	0.0 KB	81.0 KB	213.8 KB	62%	4.5 KB
13	nntp.pasdenom.info	130.7 KB	0.0 KB	53.8 KB	184.5 KB	70%	1.9 KB
14	xanadu-bbs.net	1.6 KB	0.0 KB	14.2 KB	15.8 KB	10%	2.6 KB
15	news.bbs.nz	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		53.9 MB	3.8 KB	39.2 MB	93.1 MB	57%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 14 04:15:00 - 04:59:59	252	1.4%	0.09	824.7 KB	1.5%	0.31
Nov 14 05:00:00 - 05:59:59	409	2.3%	0.11	1.7 MB	3.1%	0.47
Nov 14 06:00:00 - 06:59:59	519	2.9%	0.14	1.5 MB	2.9%	0.44
Nov 14 07:00:00 - 07:59:59	551	3.0%	0.15	1.9 MB	3.6%	0.55
Nov 14 08:00:00 - 08:59:59	716	4.0%	0.20	2.1 MB	3.9%	0.59
Nov 14 09:00:00 - 09:59:59	738	4.1%	0.20	1.9 MB	3.5%	0.53
Nov 14 10:00:00 - 10:59:59	791	4.4%	0.22	2.0 MB	3.8%	0.58
Nov 14 11:00:00 - 11:59:59	911	5.0%	0.25	2.4 MB	4.4%	0.67
Nov 14 12:00:00 - 12:59:59	780	4.3%	0.22	2.5 MB	4.6%	0.70
Nov 14 13:00:00 - 13:59:59	929	5.1%	0.26	2.4 MB	4.5%	0.69
Nov 14 14:00:00 - 14:59:59	933	5.2%	0.26	2.5 MB	4.6%	0.70
Nov 14 15:00:00 - 15:59:59	1073	5.9%	0.30	3.3 MB	6.1%	0.93
Nov 14 16:00:00 - 16:59:59	1058	5.9%	0.29	3.1 MB	5.8%	0.89
Nov 14 17:00:00 - 17:59:59	1124	6.2%	0.31	3.1 MB	5.7%	0.87
Nov 14 18:00:00 - 18:59:59	1105	6.1%	0.31	3.3 MB	6.2%	0.95
Nov 14 19:00:00 - 19:59:59	1008	5.6%	0.28	3.0 MB	5.6%	0.85
Nov 14 20:00:00 - 20:59:59	998	5.5%	0.28	3.1 MB	5.7%	0.87
Nov 14 21:00:00 - 21:59:59	826	4.6%	0.23	2.6 MB	4.9%	0.75
Nov 14 22:00:00 - 22:59:59	689	3.8%	0.19	2.0 MB	3.8%	0.58
Nov 14 23:00:00 - 23:59:59	652	3.6%	0.18	2.6 MB	4.8%	0.74
Nov 15 00:00:00 - 00:59:59	559	3.1%	0.16	1.7 MB	3.2%	0.49
Nov 15 01:00:00 - 01:59:59	464	2.6%	0.13	1.4 MB	2.6%	0.40
Nov 15 02:00:00 - 02:59:59	465	2.6%	0.13	1.3 MB	2.5%	0.38
Nov 15 03:00:00 - 03:59:59	453	2.5%	0.13	1.4 MB	2.7%	0.41
Nov 15 04:00:00 - 04:15:00	74	0.4%	0.08	231.3 KB	0.4%	0.26
TOTAL: 24:00:00	18077	100.0%	0.21	53.9 MB	100.0%	0.64

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	2075	173	0	1902
2	csiph-out.feeder.erje.net	1115	113	0	1002
3	news-out.mixmin.net	818	3	1	814
4	newsfeed.fu-berlin.de	775	30	0	745
5	news.redatomik.org	491	0	0	491
6	csiph-out.news.weretis.net	476	114	0	362
7	feeder.eternal-september.org	347	45	0	302
8	newsfeeds.xmission.com	103	2	0	101
9	news-out.aioe.org	53	46	0	7
10	nntp.pasdenom.info	37	15	0	22
11	news.freedyn.net	14	0	0	14
12	news.swapon.de	12	0	0	12
13	xanadu-bbs.net	5	0	0	5
14	news.fcku.it	1	0	0	1
TOTAL: 14		6322	541	1	5780

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	105
lada.auto	49
gmane.linux.alsa.devel	45
alt.windows7.general	30
alt.survival	28
spline.eisfair	27
alt.politics	21
lada.commerce	17
local.spam.fr	15
mozilla.dev.platform	13
gmane.linux.kernel	12
alt.bbs.synchronet	10
oesterreich.tratsch	8
alt.music.pink-floyd.publius	7
mozilla.dev.security.policy	7
mozilla.support.thunderbird	7
lada.hard	6
gmane.comp.gis.openstreetmap.tagging	6
mozilla.general	6
fido7.ru.android	5
TOTAL: 84	541

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3882
EMP (phn path)	420
EMP (phn nph)	95
User-issued cancel	91
EMP (phl)	73
Too many newsgroups	72
Binary Payload	23
User-issued spam cancel	23
Scoring filter (8)	13
HTML Multipart	10
Scoring filter (10)	10
Scoring filter (9)	9
Scoring filter (12)	5
Scoring filter (13)	5
Binary: misplaced rar	4
Too many test groups in crosspost	2
Scoring filter (14)	2
Scoring filter (15)	2
Scoring filter (11)	2
Binary: misplaced par2	2
TOTAL: 25	4750

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	3
	TOTAL: 1	3
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	TOTAL: 1	50
Huge articles
	csiph-out.feeder.erje.net	171
	csiph-out.news.weretis.net	106
	news.redatomik.org	46
	feeder.eternal-september.org	33
	news-out.mixmin.net	14
	newsfeeds.xmission.com	11
	newsfeed.fu-berlin.de	2
	news.fcku.it	1
	news.freedyn.net	1
	TOTAL: 9	385
Including strange strings
	newsfeed.fu-berlin.de	564
	news-out.mixmin.net	39
	news.redatomik.org	15
	news.swapon.de	10
	csiph-out.news.weretis.net	4
	news-out.aioe.org	4
	csiph-out.feeder.erje.net	4
	newsfeed.neva.ru	1
	feeder.eternal-september.org	1
	TOTAL: 9	642
TOTAL: 4		1080

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	19783	633	17442	0	0	0	3%	23:59:49
2	news.freedyn.net	18831	623	17409	11	0	1	3%	23:59:56
3	fu-berlin.de	16817	510	16307	0	0	24	3%	23:59:56
4	erje	20824	357	15325	60	0	0	1%	23:59:56
5	news.bbs.nz	32049	131	17391	15	0	2	0%	23:59:46
6	usenet.pasdenom.info	18726	78	16750	1185	0	0	0%	23:59:56
7	weretis.net	15206	13	14805	4	0	0	0%	23:59:56
8	aioe	15495	10	14657	0	0	0	0%	23:59:55
9	news.mixmin.net	14399	8	13769	0	0	0	0%	23:59:56
10	neva.ru	16291	6	16055	138	0	0	0%	23:59:56
11	news.redatomik.org	17480	3	16628	0	1	404	0%	23:59:56
12	news.swapon.de	1507	1	1459	0	0	0	0%	23:53:13
13	xmission	21048	1	14572	2	0	71	0%	23:59:56
14	eternal-september	15722	0	15024	0	0	0	0%	23:59:49
15	news.fcku.it	1855	0	1851	0	0	0	0%	22:02:39
TOTAL: 15		246033	2374	209444	1415	1	502	0%	357:54:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	4.8 MB	50.4 KB	4.9 MB	0.1 KB/s	7.8 KB	23:59:56
2	xanadu-bbs	4.7 MB	0.0 KB	4.7 MB	0.1 KB/s	7.6 KB	23:59:49
3	fu-berlin.de	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	8.8 KB	23:59:56
4	erje	1.0 MB	231.4 KB	1.2 MB	0.0 KB/s	3.0 KB	23:59:56
5	news.bbs.nz	347.8 KB	97.7 KB	445.4 KB	0.0 KB/s	3.1 KB	23:59:46
6	usenet.pasdenom.info	186.9 KB	5.4 MB	5.6 MB	0.1 KB/s	4.6 KB	23:59:56
7	weretis.net	42.7 KB	6.8 KB	49.6 KB	0.0 KB/s	2.9 KB	23:59:56
8	aioe	22.9 KB	0.0 KB	22.9 KB	0.0 KB/s	2.3 KB	23:59:55
9	news.mixmin.net	15.3 KB	0.0 KB	15.3 KB	0.0 KB/s	1.9 KB	23:59:56
10	neva.ru	15.0 KB	385.9 KB	400.9 KB	0.0 KB/s	2.8 KB	23:59:56
11	xmission	8.3 KB	3.2 KB	11.5 KB	0.0 KB/s	3.8 KB	23:59:56
12	news.redatomik.org	5.5 KB	0.0 KB	5.5 KB	0.0 KB/s	1.8 KB	23:59:56
13	news.swapon.de	2.5 KB	0.0 KB	2.5 KB	0.0 KB/s	2.5 KB	23:53:13
14	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:49
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:02:39
TOTAL: 15		15.6 MB	6.2 MB	21.8 MB	0.0 KB/s	5.9 KB	357:54:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	mtrlpq2314w-lp140-05-70-55-57-179.dsl.bell.ca	1390	143520	402.3 MB	1495	18:29:03
2	167-179-154-5.a7b39a.mel.nbn.aussiebb.net	47	672	2.2 MB	79	00:58:36
3	208-71-239-109.cust.centrio.cz	3	4	9.0 KB	2	01:15:17
4	a131033.upc-a.chello.nl	35	1	3.2 KB	1	10:11:19
TOTAL: 4		1475	144197	404.4 MB	1577	37:37:04

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.dsl.bell.ca	1390	143520	402.3 MB	1495	18:29:03
2	*.a7b39a.mel.nbn.aussiebb.net	47	672	2.2 MB	79	00:58:36
3	*.cust.centrio.cz	3	4	9.0 KB	2	01:15:17
4	*.upc-a.chello.nl	35	1	3.2 KB	1	10:11:19
5	*.nielsenmedia.com	1	0	0.0 KB	0	00:30:05
6	unresolved	1	0	0.0 KB	0	00:00:09
7	*.security.ipip.net	1	0	0.0 KB	0	00:00:01
8	*.dhcp.stpt.wi.charter.com	11	0	0.0 KB	0	04:12:12
9	*.ph.ph.cox.net	2	0	0.0 KB	0	02:00:19
TOTAL: 9		1491	144197	404.4 MB	1577	37:37:04

NNRP auth users [Top 20]:

User	Conn
ctomczyk	3
TOTAL: 1	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp140-05-70-55-57-179.dsl.bell.ca	151.546	63.107	0.000	18:29:03
a131033.upc-a.chello.nl	2.701	0.404	0.000	10:11:19
71-82-224-56.dhcp.stpt.wi.charter.com	0.840	0.193	0.000	04:12:12
ip72-222-200-117.ph.ph.cox.net	0.489	0.074	0.000	02:00:19
208-71-239-109.cust.centrio.cz	0.343	0.082	0.011	01:15:17
167-179-154-5.a7b39a.mel.nbn.aussiebb.net	5.624	1.065	0.000	00:58:36
challenger.nielsenmedia.com	0.079	0.024	0.000	00:30:05
107.170.210.176	0.094	0.008	0.000	00:00:09
scan-141.security.ipip.net	0.068	0.030	0.000	00:00:01
TOTAL: 9	161.784	64.987	0.011	37:37:04

Curious NNRP server explorers [Top 20]:

System	Conn
71-82-224-56.dhcp.stpt.wi.charter.com	11
ip72-222-200-117.ph.ph.cox.net	2
107.170.210.176	1
scan-141.security.ipip.net	1
challenger.nielsenmedia.com	1
TOTAL: 5	16

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Connection: close	1
GET / HTTP/1.1	1
User-Agent: HTTP Banner Detection (https...	1
Host: 69.160.37.58:119	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
mtrlpq2314w-lp140-05-70-55-57-179.dsl.bell.ca	10	0
71-82-224-56.dhcp.stpt.wi.charter.com	7	0
ip72-222-200-117.ph.ph.cox.net	4	0
208-71-239-109.cust.centrio.cz	2	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	24	0

Newsgroup request counts (by category):

Category		Count	Pct
1	sci	73487	59.7%
2	rec	35343	28.7%
3	alt	14115	11.5%
4	comp	69	0.1%
5	uk	11	0.0%
6	perl	2	0.0%
TOTAL: 6		123027	100.0%

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

Newsgroup		Count
1	sci.physics	73487
2	rec.sport.pro-wrestling	35343
3	alt.global-warming	13489
4	alt.comp.freeware	608
5	alt.comp.os.windows-10	18
6	comp.ai.philosophy	13
7	comp.arch	11
8	uk.legal.moderated	10
9	comp.arch.embedded	7
10	comp.os.vms	5
11	comp.sys.ibm.as400.misc	4
12	comp.lang.javascript	4
13	comp.os.msdos.programmer	4
14	comp.apps.spreadsheets	3
15	comp.os.os2.announce	3
16	comp.sys.ibm.pc.hardware.chips	2
17	comp.unix.aix	2
18	comp.os.qnx	2
19	perl.cpan.uploads	2
20	uk.music.charts	1
21	comp.os.msdos.djgpp	1
22	comp.arch.storage	1
23	comp.ai.games	1
24	comp.sys.ibm.pc.games.action	1
25	comp.sys.ibm.ps2.hardware	1
26	comp.arch.fpga	1
27	comp.os.linux.networking	1
28	comp.os.cpm	1
29	comp.os.msdos.misc	1
TOTAL: 29		123027

Daily Usenet report for csiph.com

Nov 14 04:15:00 -- Nov 15 04:15:00