Daily Usenet report for csiph.com: Feb 15 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 15 09:50:03 csiph controlchan[16381]: Unknown "tags" control by robomod@news.nic.it

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43674	60.3%	8.3 MB	71.8%
innd	23309	32.2%	2.5 MB	21.5%
innfeed	4004	5.5%	642.0 KB	5.4%
nnrpd	1308	1.8%	147.6 KB	1.3%
nocem	92	0.1%	10.3 KB	0.1%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	72389	100.0%	11.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	441254	56.9%
Negative hits	291010	37.5%
Do not exist	42321	5.5%
Cache misses	633	0.1%
TOTAL: 4	775218	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.830	0.0%	447	0.000	6.331	32.000
article cleanup	00:00:00.273	0.0%	42656	0.000	0.006	0.050
article logging	00:00:04.719	0.0%	43184	0.000	0.109	1.830
article parse	00:00:02.351	0.0%	139674	0.005	0.017	0.297
article write	00:03:23.102	0.2%	24386	0.212	8.329	42.374
artlog/artcncl	00:00:00.037	0.0%	417	0.000	0.089	1.000
artlog/artparse	00:00:00.003	0.0%	94	0.000	0.032	1.000
data move	00:00:00.271	0.0%	701415	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.236	0.0%	303	0.000	0.779	11.000
hishave/artcncl	00:00:00.600	0.0%	447	0.000	1.342	9.000
hishave/artparse	00:00:00.001	0.0%	90	0.000	0.011	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:38.201	0.2%	779193	0.002	0.203	1.826
history sync	00:00:03.043	0.0%	367	0.000	8.292	41.000
history write	00:03:45.700	0.3%	42396	0.075	5.324	45.010
hiswrite/artcncl	00:00:01.151	0.0%	144	0.000	7.993	23.000
hiswrite/artparse	00:00:00.705	0.0%	90	0.000	7.833	73.000
idle	23:42:58.288	98.8%	679142	70.606	125.715	387.696
nntp read	00:00:03.278	0.0%	715072	0.002	0.005	0.015
overview write	00:04:39.791	0.3%	24386	1.307	11.473	101.476
perl filter	00:01:50.405	0.1%	42325	1.538	2.609	11.949
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.462	0.0%	48860	0.000	0.009	0.026
TOTAL: 24:00:57.702	23:59:35.447	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.076	0.0%	25554	0.000	0.003	0.024
article prepare	00:00:00.003	0.0%	39054	0.000	0.000	0.003
article read	00:00:01.494	0.0%	7577	0.000	0.197	9.500
backlog stats	00:00:00.248	0.0%	441960	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.411	0.0%	346318	0.003	0.007	0.011
data write	00:00:02.030	0.0%	330443	0.001	0.006	0.014
idle	23:53:59.697	100.0%	441960	113.724	194.678	550.868
status file	00:00:00.464	0.0%	626	0.000	0.741	20.000
TOTAL: 23:54:16.103	23:54:06.423	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.390	0.0%	95	0.000	4.105	59.000
idle	76:19:45.182	99.8%	740	540.000	371331.327	900201.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	1632	0.000	0.004	0.250
readart	00:00:00.706	0.0%	52	0.000	13.577	64.000
TOTAL: 76:28:43.786	76:19:46.285	99.8%	-	-	-	-

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	136763	9135	117543	10085	6%	94:57:47
2	csiph-out.news.weretis.net	4	57516	4119	52643	754	7%	53:25:19
3	feeder.eternal-september.org	1	38535	3438	30076	5021	8%	23:58:54
4	news-out.aioe.org	3	23626	1839	21753	34	7%	24:09:56
5	newsfeed.neva.ru	145	56539	1411	52408	2720	2%	71:44:51
6	newsfeed.fu-berlin.de	54	22612	1119	21102	391	4%	29:18:21
7	news-out.mixmin.net	3	26702	1061	25395	246	3%	24:08:39
8	news.redatomik.org	1	20390	1015	19370	5	4%	21:35:37
9	newsfeeds.xmission.com	3	23439	588	22844	7	2%	24:34:58
10	news.freedyn.net	3	27649	230	27243	176	0%	24:09:49
11	news.fcku.it	1	10512	181	10270	61	1%	23:59:57
12	nntp.pasdenom.info	6	23659	114	23535	10	0%	24:01:32
13	news.swapon.de	2	2375	86	2275	14	3%	23:49:44
14	xanadu-bbs.net	9	173	21	152	0	12%	06:45:18
15	news.bbs.geek.nz	1	16647	11	16636	0	0%	23:59:50
16	localhost	41	9	9	0	0	100%	00:00:13
TOTAL: 16		1429	487146	24377	443245	19524	5%	494:40:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	26.7 MB	1.9 MB	56.6 MB	85.2 MB	31%	4.5 KB
2	csiph-out.news.weretis.net	12.9 MB	19.3 KB	10.5 MB	23.5 MB	54%	4.9 KB
3	feeder.eternal-september.org	9.2 MB	913.4 KB	64.8 MB	75.0 MB	12%	9.1 KB
4	news-out.mixmin.net	5.0 MB	19.5 KB	977.2 KB	5.9 MB	83%	4.6 KB
5	news-out.aioe.org	4.6 MB	1.6 KB	52.0 KB	4.7 MB	98%	2.6 KB
6	newsfeed.neva.ru	3.6 MB	417.5 KB	24.6 MB	28.6 MB	12%	7.1 KB
7	news.redatomik.org	2.7 MB	0.0 KB	249.8 KB	3.0 MB	91%	3.0 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	1.1 MB	3.5 MB	69%	2.4 KB
9	newsfeeds.xmission.com	1.7 MB	18.0 KB	126.2 KB	1.9 MB	92%	3.2 KB
10	news.freedyn.net	866.7 KB	0.0 KB	1.5 MB	2.3 MB	36%	5.8 KB
11	news.fcku.it	356.7 KB	0.0 KB	283.0 KB	639.7 KB	55%	2.6 KB
12	nntp.pasdenom.info	286.3 KB	0.0 KB	19.2 KB	305.5 KB	93%	2.5 KB
13	news.swapon.de	233.0 KB	0.0 KB	44.3 KB	277.3 KB	84%	2.8 KB
14	xanadu-bbs.net	40.6 KB	0.0 KB	0.0 KB	40.6 KB	100%	1.9 KB
15	news.bbs.geek.nz	22.3 KB	0.0 KB	0.0 KB	22.3 KB	100%	2.0 KB
16	localhost	13.7 KB	0.0 KB	0.0 KB	13.7 KB	100%	1.5 KB
TOTAL: 16		70.7 MB	3.3 MB	160.7 MB	234.7 MB	30%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 15 04:15:00 - 04:59:59	352	1.4%	0.13	1.2 MB	1.7%	0.46
Feb 15 05:00:00 - 05:59:59	574	2.4%	0.16	2.1 MB	2.9%	0.59
Feb 15 06:00:00 - 06:59:59	519	2.1%	0.14	1.8 MB	2.5%	0.50
Feb 15 07:00:00 - 07:59:59	653	2.7%	0.18	1.9 MB	2.6%	0.53
Feb 15 08:00:00 - 08:59:59	900	3.7%	0.25	2.5 MB	3.5%	0.71
Feb 15 09:00:00 - 09:59:59	1084	4.4%	0.30	3.0 MB	4.3%	0.87
Feb 15 10:00:00 - 10:59:59	1223	5.0%	0.34	3.1 MB	4.3%	0.87
Feb 15 11:00:00 - 11:59:59	1048	4.3%	0.29	2.9 MB	4.0%	0.81
Feb 15 12:00:00 - 12:59:59	1178	4.8%	0.33	2.8 MB	4.0%	0.80
Feb 15 13:00:00 - 13:59:59	1215	5.0%	0.34	3.1 MB	4.4%	0.88
Feb 15 14:00:00 - 14:59:59	1215	5.0%	0.34	3.1 MB	4.4%	0.88
Feb 15 15:00:00 - 15:59:59	1247	5.1%	0.35	3.3 MB	4.7%	0.94
Feb 15 16:00:00 - 16:59:59	1490	6.1%	0.41	4.6 MB	6.6%	1.32
Feb 15 17:00:00 - 17:59:59	1395	5.7%	0.39	4.0 MB	5.7%	1.14
Feb 15 18:00:00 - 18:59:59	1536	6.3%	0.43	4.3 MB	6.1%	1.24
Feb 15 19:00:00 - 19:59:59	1289	5.3%	0.36	3.9 MB	5.5%	1.11
Feb 15 20:00:00 - 20:59:59	1300	5.3%	0.36	3.9 MB	5.5%	1.11
Feb 15 21:00:00 - 21:59:59	1165	4.8%	0.32	3.2 MB	4.6%	0.92
Feb 15 22:00:00 - 22:59:59	1238	5.1%	0.34	3.3 MB	4.7%	0.95
Feb 15 23:00:00 - 23:59:59	1154	4.7%	0.32	3.8 MB	5.3%	1.07
Feb 16 00:00:00 - 00:59:59	826	3.4%	0.23	3.1 MB	4.4%	0.89
Feb 16 01:00:00 - 01:59:59	602	2.5%	0.17	2.1 MB	2.9%	0.59
Feb 16 02:00:00 - 02:59:59	589	2.4%	0.16	1.9 MB	2.7%	0.54
Feb 16 03:00:00 - 03:59:59	473	1.9%	0.13	1.5 MB	2.1%	0.42
Feb 16 04:00:00 - 04:15:00	124	0.5%	0.14	349.9 KB	0.5%	0.39
TOTAL: 24:00:00	24389	100.0%	0.28	70.7 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	8904	397	154	1	8352
2	feeder.eternal-september.org	5125	63	69	0	4993
3	newsfeed.neva.ru	2696	308	35	0	2353
4	newsfeed.fu-berlin.de	864	28	0	0	836
5	csiph-out.news.weretis.net	690	145	2	0	543
6	news-out.mixmin.net	260	0	2	0	258
7	news.freedyn.net	176	8	0	0	168
8	news.fcku.it	63	0	0	0	63
9	news-out.aioe.org	41	33	1	0	7
10	news.swapon.de	17	0	0	0	17
11	newsfeeds.xmission.com	10	4	1	0	5
12	news.redatomik.org	9	0	0	0	9
13	nntp.pasdenom.info	8	3	0	0	5
14	xanadu-bbs.net	3	0	0	0	3
15	news.bbs.geek.nz	2	0	0	0	2
TOTAL: 15		18868	989	264	1	17614

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	147
ut.test	122
lada.auto	90
it-alt.sport.calcio.juventus	73
it.annunci.immobiliari	39
alt.windows7.general	37
it.annunci.commerciali	36
it.annunci.varie	33
gmane.linux.alsa.devel	31
alt.politics.scorched-earth	23
oesterreich.tratsch	19
lada.mobile	18
mpc.lists.freebsd.x11	17
spline.eisfair	15
mozilla.support.seamonkey	14
cakewalk.audio	11
mozilla.dev.tech.crypto	10
mozilla.dev.security.policy	10
gmane.comp.gis.openstreetmap.tagging	9
gmane.comp.gis.openstreetmap	8
TOTAL: 131	989

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14533
EMP (phn path)	1651
EMP (phl)	502
Too many newsgroups	83
EMP (phn nph)	75
Binary Payload	16
User-issued cancel	13
HTML Multipart	10
Binary Image: misplaced jpg	8
Scoring filter (8)	5
Scoring filter (16)	5
HTML post	5
Scoring filter (15)	5
Scoring filter (9)	5
Binary: misplaced PAR2	4
Scoring filter (13)	2
Binary: misplaced JPG	1
Scoring filter (10)	1
Too many newsgroups (low_xpost)	1
Binary: misplaced par2	1
TOTAL: 20	16926

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	87	0	105	105
TOTAL: 1	87	0	105	105

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	66
	TOTAL: 1	66
Huge articles
	csiph-out.news.weretis.net	45
	feeder.eternal-september.org	23
	news-out.mixmin.net	9
	news.fcku.it	3
	news.redatomik.org	3
	newsfeeds.xmission.com	3
	newsfeed.fu-berlin.de	2
	news.freedyn.net	1
	news.swapon.de	1
	TOTAL: 9	90
Including strange strings
	newsfeed.fu-berlin.de	388
	csiph-out.feeder.erje.net	62
	feeder.eternal-september.org	31
	news.swapon.de	9
	news-out.mixmin.net	8
	news-out.aioe.org	7
	newsfeed.neva.ru	6
	news.redatomik.org	5
	csiph-out.news.weretis.net	4
	newsfeeds.xmission.com	2
	TOTAL: 13	527
No colon-space in header
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
TOTAL: 4		687

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	40484	5849	17672	85	0	0	14%	23:50:11
2	news.freedyn.net	24821	787	23296	1	1	0	3%	23:50:11
3	xanadu-bbs	27379	733	23585	7	0	0	2%	23:59:56
4	fu-berlin.de	22915	516	22399	0	0	0	2%	23:59:55
5	usenet.pasdenom.info	27437	215	22849	1208	0	1198	0%	23:59:55
6	xmission	27544	100	22941	4	0	1	0%	23:59:56
7	aioe	21608	93	20147	4	0	0	0%	23:50:11
8	news.redatomik.org	23565	36	22545	5	0	0	0%	23:50:06
9	eternal-september	21983	29	19846	0	0	1	0%	23:59:55
10	weretis.net	19626	26	18644	1	0	1	0%	23:59:26
11	news.mixmin.net	23019	13	21763	0	0	1	0%	23:59:55
12	erje	15108	11	15034	4	0	0	0%	23:50:06
13	news.swapon.de	2289	9	2177	2	0	0	0%	23:53:12
14	neva.ru	23453	7	21981	8	0	1	0%	23:59:55
15	news.fcku.it	3124	2	2969	0	0	0	0%	22:55:08
16	au2pb	0	0	0	0	0	23694	0%	23:58:42
17	bwh	0	0	0	0	0	23619	0%	23:50:09
TOTAL: 17		324355	8426	277848	1329	1	48516	2%	405:46:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	14.8 MB	414.2 KB	15.2 MB	0.2 KB/s	2.6 KB	23:50:11
2	news.freedyn.net	4.9 MB	2.3 KB	4.9 MB	0.1 KB/s	6.3 KB	23:50:11
3	xanadu-bbs	4.7 MB	15.9 KB	4.7 MB	0.1 KB/s	6.5 KB	23:59:56
4	fu-berlin.de	4.3 MB	0.0 KB	4.3 MB	0.1 KB/s	8.6 KB	23:59:55
5	usenet.pasdenom.info	561.1 KB	5.6 MB	6.2 MB	0.1 KB/s	4.4 KB	23:59:55
6	aioe	329.8 KB	11.8 KB	341.6 KB	0.0 KB/s	3.5 KB	23:50:11
7	xmission	240.0 KB	6.7 KB	246.7 KB	0.0 KB/s	2.4 KB	23:59:56
8	eternal-september	83.7 KB	0.0 KB	83.7 KB	0.0 KB/s	2.9 KB	23:59:55
9	news.redatomik.org	73.0 KB	57.7 KB	130.7 KB	0.0 KB/s	3.2 KB	23:50:06
10	weretis.net	72.7 KB	5.0 KB	77.7 KB	0.0 KB/s	2.9 KB	23:59:26
11	news.mixmin.net	24.0 KB	0.0 KB	24.0 KB	0.0 KB/s	1.8 KB	23:59:55
12	erje	16.7 KB	17.0 KB	33.7 KB	0.0 KB/s	2.2 KB	23:50:06
13	news.swapon.de	13.7 KB	3.7 KB	17.4 KB	0.0 KB/s	1.6 KB	23:53:12
14	neva.ru	11.9 KB	17.8 KB	29.6 KB	0.0 KB/s	2.0 KB	23:59:55
15	news.fcku.it	3.8 KB	0.0 KB	3.8 KB	0.0 KB/s	1.9 KB	22:55:08
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
TOTAL: 17		30.1 MB	6.2 MB	36.2 MB	0.0 KB/s	3.8 KB	405:46:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	17	20	54.2 KB	6	0	0	04:47:09
2	dhcp-089-099-203-254.chello.nl	51	14	22.9 KB	8	0	0	12:03:58
3	68-185-181-64.dhcp.mdsn.wi.charter.com	28	12	19.9 KB	9	9	7	08:36:00
4	194-73-239-109.cust.centrio.cz	11	6	16.5 KB	4	0	0	04:32:12
TOTAL: 4		107	52	113.5 KB	27	9	7	76:28:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	17	20	54.2 KB	6	0	0	04:47:09
2	*.chello.nl	51	14	22.9 KB	8	0	0	12:03:58
3	*.dhcp.mdsn.wi.charter.com	28	12	19.9 KB	9	9	7	08:36:00
4	*.cust.centrio.cz	11	6	16.5 KB	4	0	0	04:32:12
5	unresolved	95	0	0.0 KB	0	0	0	45:59:16
6	*.19-1.cable.virginm.net	1	0	0.0 KB	0	0	0	00:30:02
7	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 7		204	52	113.5 KB	27	9	7	76:28:41

NNRP auth users [Top 20]:

User	Conn
ctomczyk	10
ohlandl	7
TOTAL: 2	17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.218	1.454	0.000	45:59:16
dhcp-089-099-203-254.chello.nl	4.386	0.825	0.000	12:03:58
68-185-181-64.dhcp.mdsn.wi.charter.com	2.504	0.497	0.027	08:36:00
tempe0.bbox.io	1.390	0.290	0.000	04:47:09
194-73-239-109.cust.centrio.cz	0.988	0.240	0.048	04:32:12
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.093	0.008	0.000	00:30:02
census8.shodan.io	0.074	0.025	0.000	00:00:00
TOTAL: 7	16.653	3.339	0.075	76:28:41

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	95
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1
census8.shodan.io	1
TOTAL: 3	97

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	89	0
194-73-239-109.cust.centrio.cz	9	0
68-185-181-64.dhcp.mdsn.wi.charter.com	9	0
tempe0.bbox.io	8	0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	1	0
TOTAL: 5	116	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	45	86.5%
2	muc	7	13.5%
TOTAL: 2		52	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	39
2	comp.lang.javascript	6
3	muc.lists.freebsd.stable	6
4	muc.lists.freebsd.emulation	1
TOTAL: 4		52

Daily Usenet report for csiph.com

Feb 15 04:15:00 -- Feb 16 04:15:00