Daily Usenet report for csiph.com: May 4 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 4 07:50:02 csiph controlchan[41832]: Unknown "tags" control by robomod@news.nic.it

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	75038	57.0%	7.5 MB	43.0%
inn	35278	26.8%	7.3 MB	42.1%
innd	18045	13.7%	2.1 MB	11.8%
innfeed	3186	2.4%	526.2 KB	3.0%
nocem	75	0.1%	7.9 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	131624	100.0%	17.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	399656	56.1%
Negative hits	277732	39.0%
Do not exist	34053	4.8%
Cache misses	565	0.1%
TOTAL: 4	712006	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.558	0.0%	234	0.000	6.658	63.000
article cleanup	00:00:00.272	0.0%	33756	0.000	0.008	0.034
article logging	00:00:04.970	0.0%	34343	0.000	0.145	2.457
article parse	00:00:03.857	0.0%	96352	0.002	0.040	0.541
article write	00:02:10.553	0.2%	22627	0.207	5.770	42.826
artlog/artcncl	00:00:00.019	0.0%	212	0.000	0.090	1.250
artlog/artparse	00:00:00.065	0.0%	554	0.000	0.117	2.944
data move	00:00:00.401	0.0%	672980	0.000	0.001	0.046
hisgrep/artcncl	00:00:00.561	0.0%	229	0.000	2.450	23.000
hishave/artcncl	00:00:00.131	0.0%	234	0.000	0.560	8.000
hishave/artparse	00:00:00.001	0.0%	554	0.000	0.002	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:45.865	0.1%	714710	0.003	0.148	1.272
history sync	00:00:02.748	0.0%	345	0.000	7.965	154.000
history write	00:03:12.087	0.2%	33736	0.601	5.694	51.806
hiswrite/artcncl	00:00:00.004	0.0%	5	0.000	0.800	1.000
hiswrite/artparse	00:00:02.498	0.0%	554	0.000	4.509	69.333
idle	23:33:57.134	98.8%	667018	66.807	127.189	358.897
nntp read	00:00:03.219	0.0%	685178	0.002	0.005	0.020
overview write	00:06:35.961	0.5%	22627	0.369	17.499	299.404
perl filter	00:01:12.381	0.1%	33619	1.532	2.153	3.479
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.419	0.0%	45323	0.000	0.009	0.028
TOTAL: 23:50:56.738	23:49:14.704	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.071	0.0%	22734	0.000	0.003	0.029
article prepare	00:00:00.003	0.0%	22940	0.000	0.000	0.011
article read	00:00:01.491	0.0%	3527	0.000	0.423	12.000
backlog stats	00:00:00.269	0.0%	411976	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.184	0.0%	321775	0.001	0.007	0.012
data write	00:00:01.812	0.0%	304636	0.002	0.006	0.011
idle	23:53:29.457	100.0%	411976	115.058	208.773	596.794
status file	00:00:00.414	0.0%	283	0.000	1.463	36.000
TOTAL: 23:53:46.212	23:53:35.701	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:07.204	0.0%	2350	1.096	3.066	6.090
idle	67:42:30.507	98.9%	1145636	78.714	212.764	600050.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:18.808	0.0%	3277384	0.000	0.006	0.167
readart	00:40:36.938	1.0%	1054215	0.000	2.312	630.000
TOTAL: 68:29:18.253	68:23:33.457	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	4	45965	7485	37596	884	16%	48:39:48
2	csiph-out.feeder.erje.net	1440	133986	3481	123866	6639	2%	118:43:57
3	feeder.eternal-september.org	5	29891	2929	25472	1490	9%	24:39:06
4	news-out.aioe.org	4	22879	1925	20894	60	8%	24:15:42
5	news-out.mixmin.net	3	24062	1574	21918	570	6%	24:08:53
6	newsfeed.neva.ru	147	33049	1372	30081	1596	4%	72:22:53
7	newsfeeds.xmission.com	3	22724	1336	21377	11	5%	25:51:10
8	newsfeed.fu-berlin.de	35	23665	1213	22173	279	5%	31:03:17
9	news.redatomik.org	1	19373	499	18874	0	2%	21:49:49
10	news.freedyn.net	3	24916	447	24148	321	1%	24:09:46
11	news.fcku.it	2	9777	194	9527	56	1%	23:59:50
12	xanadu-bbs.net	7	815	189	620	6	23%	20:25:16
13	news.swapon.de	1	1893	54	1838	1	2%	20:58:25
14	nntp.pasdenom.info	3	21370	50	21320	0	0%	24:08:03
15	localhost	35	2	2	0	0	100%	00:00:00
16	news.bbs.geek.nz	1	20802	1	20801	0	0%	23:59:50
TOTAL: 16		1694	435169	22751	400505	11913	5%	529:15:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	21.6 MB	7.3 KB	7.4 MB	29.0 MB	74%	3.5 KB
2	csiph-out.feeder.erje.net	14.4 MB	104.2 KB	45.4 MB	59.9 MB	24%	6.1 KB
3	feeder.eternal-september.org	7.7 MB	0.0 KB	17.2 MB	24.9 MB	31%	5.8 KB
4	news-out.mixmin.net	5.7 MB	0.0 KB	1.6 MB	7.4 MB	77%	3.5 KB
5	news-out.aioe.org	4.6 MB	0.0 KB	103.6 KB	4.7 MB	97%	2.4 KB
6	newsfeeds.xmission.com	4.0 MB	0.0 KB	547.1 KB	4.5 MB	88%	3.4 KB
7	newsfeed.neva.ru	3.1 MB	21.7 KB	7.5 MB	10.6 MB	28%	3.7 KB
8	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	710.5 KB	3.5 MB	80%	2.4 KB
9	news.freedyn.net	1.6 MB	0.0 KB	2.4 MB	4.0 MB	39%	5.3 KB
10	news.redatomik.org	1.5 MB	0.0 KB	0.0 KB	1.5 MB	100%	3.1 KB
11	xanadu-bbs.net	549.9 KB	0.0 KB	106.2 KB	656.1 KB	83%	3.4 KB
12	news.fcku.it	324.9 KB	0.0 KB	557.6 KB	882.5 KB	36%	3.5 KB
13	news.swapon.de	146.9 KB	0.0 KB	242.4 KB	389.2 KB	37%	7.1 KB
14	nntp.pasdenom.info	91.8 KB	0.0 KB	0.0 KB	91.8 KB	100%	1.8 KB
15	localhost	3.0 KB	0.0 KB	0.0 KB	3.0 KB	100%	1.5 KB
16	news.bbs.geek.nz	1.8 KB	0.0 KB	0.0 KB	1.8 KB	100%	1.8 KB
TOTAL: 16		68.2 MB	133.2 KB	83.8 MB	152.1 MB	44%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 4 04:15:00 - 04:59:59	404	1.8%	0.15	1.7 MB	2.5%	0.64
May 4 05:00:00 - 05:59:59	574	2.5%	0.16	2.2 MB	3.3%	0.64
May 4 06:00:00 - 06:59:59	657	2.9%	0.18	2.1 MB	3.1%	0.59
May 4 07:00:00 - 07:59:59	954	4.2%	0.27	3.4 MB	4.9%	0.96
May 4 08:00:00 - 08:59:59	955	4.2%	0.27	2.6 MB	3.8%	0.73
May 4 09:00:00 - 09:59:59	1092	4.8%	0.30	3.1 MB	4.6%	0.88
May 4 10:00:00 - 10:59:59	1003	4.4%	0.28	2.8 MB	4.1%	0.80
May 4 11:00:00 - 11:59:59	1015	4.5%	0.28	2.5 MB	3.6%	0.70
May 4 12:00:00 - 12:59:59	1080	4.8%	0.30	3.1 MB	4.6%	0.89
May 4 13:00:00 - 13:59:59	1208	5.3%	0.34	3.4 MB	5.0%	0.98
May 4 14:00:00 - 14:59:59	1356	6.0%	0.38	4.1 MB	6.0%	1.17
May 4 15:00:00 - 15:59:59	1476	6.5%	0.41	4.3 MB	6.3%	1.23
May 4 16:00:00 - 16:59:59	1402	6.2%	0.39	4.7 MB	6.9%	1.34
May 4 17:00:00 - 17:59:59	1397	6.2%	0.39	3.8 MB	5.6%	1.08
May 4 18:00:00 - 18:59:59	1195	5.3%	0.33	3.1 MB	4.5%	0.87
May 4 19:00:00 - 19:59:59	1152	5.1%	0.32	3.2 MB	4.7%	0.90
May 4 20:00:00 - 20:59:59	1200	5.3%	0.33	3.9 MB	5.8%	1.12
May 4 21:00:00 - 21:59:59	1016	4.5%	0.28	2.9 MB	4.3%	0.83
May 4 22:00:00 - 22:59:59	817	3.6%	0.23	2.7 MB	4.0%	0.78
May 4 23:00:00 - 23:59:59	564	2.5%	0.16	1.8 MB	2.6%	0.51
May 5 00:00:00 - 00:59:59	609	2.7%	0.17	1.7 MB	2.5%	0.48
May 5 01:00:00 - 01:59:59	537	2.4%	0.15	1.5 MB	2.2%	0.43
May 5 02:00:00 - 02:59:59	458	2.0%	0.13	1.6 MB	2.4%	0.46
May 5 03:00:00 - 03:59:59	444	2.0%	0.12	1.4 MB	2.1%	0.41
May 5 04:00:00 - 04:15:00	146	0.6%	0.16	534.8 KB	0.8%	0.59
TOTAL: 24:00:00	22711	100.0%	0.26	68.2 MB	100.0%	0.81

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	6687	229	9	0	6449
2	newsfeed.neva.ru	1567	284	3	0	1280
3	feeder.eternal-september.org	1401	34	2	0	1365
4	csiph-out.news.weretis.net	894	303	3	0	588
5	newsfeed.fu-berlin.de	703	23	0	0	680
6	news-out.mixmin.net	607	2	1	0	604
7	news.freedyn.net	314	23	0	0	291
8	news-out.aioe.org	72	44	1	0	27
9	news.fcku.it	71	1	0	1	69
10	newsfeeds.xmission.com	14	0	0	0	14
11	news.redatomik.org	6	0	1	0	5
12	xanadu-bbs.net	6	0	0	0	6
13	news.swapon.de	4	0	0	0	4
TOTAL: 13		12346	943	20	1	11382

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	128
lada.talk	110
it-alt.sport.calcio.juventus	110
lada.auto	64
mpc.lists.freebsd.ports.bugs	35
it.annunci.immobiliari	32
it.annunci.varie	32
it.annunci.commerciali	31
mozilla.support.thunderbird	22
mozilla.support.firefox	20
mpc.lists.nanog	20
mpc.lists.freebsd.gnome	18
lada.commerce	18
gmane.comp.gis.openstreetmap	18
mozilla.support.seamonkey	17
spline.eisfair	13
oesterreich.tratsch	11
mozilla.dev.security.policy	10
alt.politics	10
alt.tv.star-trek	10
TOTAL: 116	943

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

Groups	Count
alt.culture.china	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8926
EMP (phn path)	879
EMP (phl)	152
Too many newsgroups	34
HTML Multipart	27
User-issued cancel	16
Binary Payload	16
Scoring filter (8)	15
EMP (phn nph)	13
Scoring filter (10)	11
Scoring filter (11)	8
Scoring filter (14)	8
Scoring filter (12)	4
HTML post	3
Scoring filter (9)	3
Too many newsgroups (low_xpost)	2
Scoring filter (16)	2
Binary: non-binary distribution	1
Cancel for rejected article	1
TOTAL: 19	10121

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	68	0	73	73
TOTAL: 1	68	0	73	73

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	116
	TOTAL: 1	116
Huge articles
	csiph-out.feeder.erje.net	385
	feeder.eternal-september.org	81
	csiph-out.news.weretis.net	52
	news-out.mixmin.net	11
	news.freedyn.net	11
	newsfeeds.xmission.com	9
	newsfeed.fu-berlin.de	3
	news.fcku.it	1
	xanadu-bbs.net	1
	news.swapon.de	1
	TOTAL: 10	555
Including strange strings
	newsfeed.fu-berlin.de	437
	csiph-out.news.weretis.net	53
	news-out.mixmin.net	38
	csiph-out.feeder.erje.net	32
	newsfeed.neva.ru	11
	news-out.aioe.org	7
	news.redatomik.org	5
	news.swapon.de	3
	newsfeeds.xmission.com	3
	TOTAL: 9	589
TOTAL: 3		1260

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	41518	1288	20763	68	0	3%	23:59:53
2	news.freedyn.net	23096	668	21585	4	0	2%	23:59:49
3	xanadu-bbs	23609	626	21861	3	25	2%	23:59:54
4	fu-berlin.de	21254	542	20712	0	0	2%	23:59:51
5	erje	20639	514	18436	234	0	2%	23:59:54
6	usenet.pasdenom.info	24378	164	20936	1559	11	0%	23:59:53
7	aioe	20281	37	19336	0	0	0%	23:59:53
8	news.redatomik.org	22346	17	21542	0	0	0%	23:59:53
9	xmission	22087	7	20744	4	0	0%	23:59:54
10	weretis.net	14542	7	14417	1	0	0%	23:59:54
11	news.mixmin.net	20961	4	20315	0	0	0%	23:59:53
12	eternal-september	19984	4	18861	0	0	0%	23:59:53
13	neva.ru	21050	3	20490	7	48	0%	23:59:53
14	news.swapon.de	2197	2	2158	0	0	0%	23:59:53
15	news.fcku.it	2793	0	2751	0	0	0%	22:29:50
16	bwh	0	0	0	0	22119	0%	23:58:55
17	au2pb	0	0	0	0	22121	0%	23:58:42
TOTAL: 17		300735	3883	264907	1880	44324	1%	406:25:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.0 MB	38.1 KB	7.0 MB	0.1 KB/s	10.7 KB	23:59:49
2	xanadu-bbs	6.9 MB	5.0 KB	6.9 MB	0.1 KB/s	11.2 KB	23:59:54
3	fu-berlin.de	6.7 MB	0.0 KB	6.7 MB	0.1 KB/s	12.6 KB	23:59:51
4	news.bbs.geek.nz	3.5 MB	327.4 KB	3.8 MB	0.0 KB/s	2.9 KB	23:59:53
5	erje	1.6 MB	1.5 MB	3.1 MB	0.0 KB/s	4.3 KB	23:59:54
6	usenet.pasdenom.info	642.2 KB	8.3 MB	8.9 MB	0.1 KB/s	5.3 KB	23:59:53
7	aioe	82.0 KB	0.0 KB	82.0 KB	0.0 KB/s	2.2 KB	23:59:53
8	news.redatomik.org	40.3 KB	0.0 KB	40.3 KB	0.0 KB/s	2.4 KB	23:59:53
9	weretis.net	12.7 KB	1.3 KB	14.0 KB	0.0 KB/s	1.7 KB	23:59:54
10	xmission	11.8 KB	8.5 KB	20.3 KB	0.0 KB/s	1.8 KB	23:59:54
11	news.mixmin.net	8.7 KB	0.0 KB	8.7 KB	0.0 KB/s	2.2 KB	23:59:53
12	eternal-september	8.7 KB	0.0 KB	8.7 KB	0.0 KB/s	2.2 KB	23:59:53
13	neva.ru	4.0 KB	12.4 KB	16.4 KB	0.0 KB/s	1.6 KB	23:59:53
14	news.swapon.de	3.1 KB	0.0 KB	3.1 KB	0.0 KB/s	1.5 KB	23:59:53
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:29:50
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:55
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		26.5 MB	10.2 MB	36.6 MB	0.0 KB/s	6.5 KB	406:25:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	3085	492582	1.6 GB	2198	0	0	32:18:47
2	144.138.90.254	287	77	184.3 KB	70	0	0	03:50:35
3	dhcp-089-099-203-254.chello.nl	80	12	16.8 KB	4	0	0	25:22:43
4	204-73-239-109.cust.centrio.cz	8	8	13.2 KB	6	0	0	00:28:20
5	68-185-181-64.dhcp.mdsn.wi.charter.com	12	3	5.0 KB	3	2	2	03:00:27
TOTAL: 5		3472	492682	1.6 GB	2281	2	2	68:28:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	3085	492582	1.6 GB	2198	0	0	32:18:47
2	unresolved	287	77	184.3 KB	70	0	0	03:50:35
3	*.chello.nl	80	12	16.8 KB	4	0	0	25:22:43
4	*.cust.centrio.cz	8	8	13.2 KB	6	0	0	00:28:20
5	*.dhcp.mdsn.wi.charter.com	12	3	5.0 KB	3	2	2	03:00:27
6	*.103.20.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	02:01:38
TOTAL: 6		3473	492682	1.6 GB	2281	2	2	68:28:51

NNRP auth users [Top 20]:

User	Conn
moussa	287
ctomczyk	7
ohlandl	2
TOTAL: 3	296

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	318.818	163.765	0.000	32:18:47
dhcp-089-099-203-254.chello.nl	6.133	1.291	0.000	25:22:43
144.138.90.254	25.119	6.336	1.134	03:50:35
68-185-181-64.dhcp.mdsn.wi.charter.com	1.262	0.173	0.007	03:00:27
h59.103.20.98.dynamic.ip.windstream.net	0.090	0.141	0.000	02:01:38
h63.2.89.75.dynamic.ip.windstream.net	0.073	0.107	0.000	01:26:16
204-73-239-109.cust.centrio.cz	0.736	0.152	0.023	00:28:20
TOTAL: 7	352.231	171.965	1.164	68:28:51

Curious NNRP server explorers [Top 20]:

System	Conn
h59.103.20.98.dynamic.ip.windstream.net	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
h63.2.89.75.dynamic.ip.windstream.net	1	0
h59.103.20.98.dynamic.ip.windstream.net	1	0
TOTAL: 3	5	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	492576	100.0%
2	comp	100	0.0%
3	uk	6	0.0%
TOTAL: 3		492682	100.0%

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

Newsgroup		Count
1	alt.fan.rush-limbaugh	401086
2	alt.usenet.kooks	91490
3	comp.arch	23
4	comp.arch.fpga	17
5	comp.sys.ibm.ps2.hardware	17
6	comp.arch.embedded	15
7	comp.lang.javascript	8
8	comp.os.vms	8
9	uk.rec.drugs.cannabis	6
10	comp.sys.ibm.pc.games.action	5
11	comp.ai.philosophy	5
12	comp.os.qnx	1
13	comp.os.linux.networking	1
TOTAL: 13		492682

Daily Usenet report for csiph.com

May 4 04:15:00 -- May 5 04:15:00