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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42428	61.0%	8.0 MB	72.2%
innd	23385	33.6%	2.5 MB	22.5%
innfeed	3183	4.6%	536.8 KB	4.7%
nnrpd	458	0.7%	49.2 KB	0.4%
nocem	97	0.1%	10.5 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	69552	100.0%	11.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	429087	57.0%
Negative hits	281180	37.4%
Do not exist	41454	5.5%
Cache misses	557	0.1%
TOTAL: 4	752278	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.815	0.0%	303	0.000	2.690	26.000
article cleanup	00:00:00.258	0.0%	41408	0.000	0.006	0.026
article logging	00:00:03.329	0.0%	41946	0.000	0.079	1.106
article parse	00:00:02.751	0.0%	139795	0.003	0.020	0.184
article write	00:02:33.907	0.2%	23417	0.174	6.572	30.478
artlog/artcncl	00:00:00.009	0.0%	256	0.000	0.035	1.000
artlog/artparse	00:00:00.036	0.0%	237	0.000	0.152	4.000
data move	00:00:00.420	0.0%	702681	0.000	0.001	0.024
hisgrep/artcncl	00:00:00.164	0.0%	302	0.000	0.543	14.000
hishave/artcncl	00:00:00.075	0.0%	303	0.000	0.248	4.562
hishave/artparse	00:00:00.001	0.0%	233	0.000	0.004	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:38.532	0.1%	754260	0.003	0.131	0.628
history sync	00:00:05.260	0.0%	363	0.000	14.490	85.667
history write	00:03:18.736	0.2%	41364	0.092	4.805	25.258
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.162	0.0%	233	0.000	4.987	39.000
idle	23:46:25.934	99.0%	687931	78.902	124.411	377.743
nntp read	00:00:03.388	0.0%	715375	0.003	0.005	0.018
overview write	00:03:48.575	0.3%	23417	0.924	9.761	79.543
perl filter	00:01:44.338	0.1%	41359	1.617	2.523	3.653
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.407	0.0%	46920	0.000	0.009	0.023
TOTAL: 24:01:02.585	23:59:48.097	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.080	0.0%	23417	0.000	0.003	0.023
article prepare	00:00:00.006	0.0%	45288	0.000	0.000	0.019
article read	00:00:01.534	0.0%	9001	0.047	0.170	2.294
backlog stats	00:00:00.251	0.0%	446732	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.259	0.0%	338412	0.002	0.007	0.013
data write	00:00:01.984	0.0%	321491	0.003	0.006	0.013
idle	23:54:04.481	100.0%	446732	114.516	192.609	569.868
status file	00:00:00.198	0.0%	279	0.000	0.710	4.000
TOTAL: 23:54:20.572	23:54:10.793	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	13:20:23.579	99.9%	636	172.000	75508.772	600102.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.260	0.0%	731	0.000	0.356	7.208
readart	00:00:00.185	0.0%	29	0.000	6.379	13.000
TOTAL: 13:20:49.809	13:20:24.024	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	132930	8010	114343	10577	6%	95:02:50
2	csiph-out.news.weretis.net	3	49111	4221	44131	759	8%	46:38:20
3	feeder.eternal-september.org	5	37974	3496	30212	4266	9%	25:15:37
4	news.redatomik.org	1	20053	1856	18196	1	9%	22:02:44
5	newsfeed.neva.ru	151	56712	1348	52416	2948	2%	71:53:21
6	newsfeed.fu-berlin.de	48	24659	1072	22917	670	4%	32:05:38
7	news-out.mixmin.net	3	26065	939	24948	178	3%	24:08:53
8	newsfeeds.xmission.com	4	21941	847	21083	11	3%	23:27:07
9	news-out.aioe.org	2	22900	729	22134	37	3%	24:09:57
10	news.freedyn.net	3	26574	258	26141	175	0%	24:09:50
11	xanadu-bbs.net	7	915	181	725	9	19%	24:27:20
12	news.fcku.it	2	10743	160	10549	34	1%	23:59:55
13	nntp.pasdenom.info	2	22724	130	22573	21	0%	23:58:51
14	news.swapon.de	1	2654	82	2530	42	3%	24:28:22
15	news.bbs.geek.nz	1	17018	15	17002	1	0%	23:59:32
16	localhost	43	6	5	0	1	83%	00:00:02
TOTAL: 16		1428	472979	23349	429900	19730	4%	509:48:19

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.1 MB	394.7 KB	62.9 MB	87.4 MB	27%	4.8 KB
2	csiph-out.news.weretis.net	13.4 MB	43.5 KB	11.0 MB	24.5 MB	54%	5.0 KB
3	feeder.eternal-september.org	9.2 MB	28.1 KB	58.4 MB	67.7 MB	13%	8.9 KB
4	news-out.mixmin.net	4.9 MB	28.1 KB	1.3 MB	6.3 MB	78%	5.7 KB
5	news.redatomik.org	4.3 MB	28.1 KB	28.1 KB	4.3 MB	98%	2.4 KB
6	newsfeed.neva.ru	3.2 MB	22.9 KB	24.2 MB	27.4 MB	11%	6.5 KB
7	newsfeeds.xmission.com	3.0 MB	0.0 KB	188.2 KB	3.1 MB	94%	3.8 KB
8	news-out.aioe.org	2.7 MB	0.0 KB	58.2 KB	2.8 MB	97%	3.7 KB
9	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	2.1 MB	4.4 MB	52%	2.6 KB
10	news.freedyn.net	1.1 MB	43.5 KB	1.9 MB	3.0 MB	35%	7.1 KB
11	xanadu-bbs.net	506.9 KB	0.0 KB	47.6 KB	554.5 KB	91%	2.9 KB
12	news.fcku.it	342.2 KB	0.0 KB	1.1 MB	1.4 MB	24%	7.3 KB
13	nntp.pasdenom.info	317.4 KB	0.0 KB	46.2 KB	363.6 KB	87%	2.4 KB
14	news.swapon.de	210.9 KB	0.0 KB	136.9 KB	347.8 KB	60%	2.8 KB
15	localhost	77.7 KB	0.0 KB	69.1 KB	146.8 KB	52%	24.5 KB
16	news.bbs.geek.nz	26.9 KB	0.0 KB	1.1 KB	28.0 KB	96%	1.8 KB
TOTAL: 16		69.5 MB	588.9 KB	163.6 MB	233.7 MB	29%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 21 04:15:00 - 04:59:59	353	1.5%	0.13	1.4 MB	2.0%	0.53
Feb 21 05:00:00 - 05:59:59	535	2.3%	0.15	1.7 MB	2.4%	0.48
Feb 21 06:00:00 - 06:59:59	506	2.2%	0.14	1.5 MB	2.1%	0.42
Feb 21 07:00:00 - 07:59:59	780	3.3%	0.22	2.6 MB	3.7%	0.74
Feb 21 08:00:00 - 08:59:59	982	4.2%	0.27	3.2 MB	4.6%	0.92
Feb 21 09:00:00 - 09:59:59	980	4.2%	0.27	2.8 MB	4.0%	0.79
Feb 21 10:00:00 - 10:59:59	1058	4.5%	0.29	3.0 MB	4.3%	0.86
Feb 21 11:00:00 - 11:59:59	1048	4.5%	0.29	3.0 MB	4.4%	0.86
Feb 21 12:00:00 - 12:59:59	997	4.3%	0.28	2.5 MB	3.6%	0.71
Feb 21 13:00:00 - 13:59:59	1244	5.3%	0.35	3.3 MB	4.7%	0.94
Feb 21 14:00:00 - 14:59:59	1374	5.9%	0.38	3.8 MB	5.5%	1.08
Feb 21 15:00:00 - 15:59:59	1429	6.1%	0.40	3.8 MB	5.5%	1.08
Feb 21 16:00:00 - 16:59:59	1355	5.8%	0.38	3.7 MB	5.3%	1.05
Feb 21 17:00:00 - 17:59:59	1271	5.4%	0.35	3.7 MB	5.4%	1.07
Feb 21 18:00:00 - 18:59:59	1312	5.6%	0.36	3.6 MB	5.1%	1.01
Feb 21 19:00:00 - 19:59:59	1256	5.4%	0.35	3.6 MB	5.2%	1.03
Feb 21 20:00:00 - 20:59:59	1267	5.4%	0.35	3.7 MB	5.4%	1.06
Feb 21 21:00:00 - 21:59:59	1256	5.4%	0.35	4.3 MB	6.2%	1.23
Feb 21 22:00:00 - 22:59:59	1094	4.7%	0.30	3.1 MB	4.5%	0.89
Feb 21 23:00:00 - 23:59:59	847	3.6%	0.24	2.5 MB	3.6%	0.72
Feb 22 00:00:00 - 00:59:59	770	3.3%	0.21	3.2 MB	4.6%	0.91
Feb 22 01:00:00 - 01:59:59	573	2.4%	0.16	1.9 MB	2.8%	0.55
Feb 22 02:00:00 - 02:59:59	593	2.5%	0.16	1.8 MB	2.6%	0.52
Feb 22 03:00:00 - 03:59:59	427	1.8%	0.12	1.3 MB	1.9%	0.37
Feb 22 04:00:00 - 04:15:00	108	0.5%	0.12	321.9 KB	0.5%	0.36
TOTAL: 24:00:00	23415	100.0%	0.27	69.5 MB	100.0%	0.82

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9078	462	37	1	8578
2	feeder.eternal-september.org	4333	91	1	1	4240
3	newsfeed.neva.ru	2941	282	4	0	2655
4	newsfeed.fu-berlin.de	1068	24	0	0	1044
5	csiph-out.news.weretis.net	781	171	2	0	608
6	news-out.mixmin.net	185	3	1	0	181
7	news.freedyn.net	177	17	2	0	158
8	news.swapon.de	54	0	0	0	54
9	news-out.aioe.org	38	32	0	0	6
10	news.fcku.it	34	0	0	0	34
11	nntp.pasdenom.info	26	10	0	0	16
12	newsfeeds.xmission.com	22	8	0	0	14
13	news.redatomik.org	10	0	1	0	9
14	xanadu-bbs.net	9	0	0	0	9
15	localhost	1	0	0	0	1
TOTAL: 15		18757	1100	48	2	17607

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	166
lada.talk	99
it-alt.sport.calcio.juventus	82
lada.auto	67
it.annunci.immobiliari	51
it.annunci.varie	50
it.annunci.commerciali	49
gmane.linux.alsa.devel	36
alt.politics.scorched-earth	35
alt.windows7.general	30
alt.survival	23
mpc.lists.freebsd.ports.bugs	23
mozilla.support.thunderbird	16
gmane.linux.kernel	16
eagle.userchat.eng	15
gmane.comp.gis.openstreetmap.tagging	14
mozilla.dev.platform	13
mpc.lists.nanog	11
fido.enet.sysop	11
alt.computer.workshop	10
TOTAL: 133	1100

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

Groups	Count
nz.politics.announce	1
muc.lists.freebsd.questions	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14110
EMP (phn path)	1756
EMP (phl)	555
EMP (phn nph)	223
Too many newsgroups	59
User-issued cancel	21
HTML Multipart	17
Scoring filter (20)	16
Binary Payload	14
Scoring filter (10)	11
Scoring filter (8)	9
Scoring filter (16)	6
Scoring filter (9)	6
HTML post	6
Scoring filter (12)	5
HTML Image Tags	4
Scoring filter (22)	3
Scoring filter (21)	2
Scoring filter (18)	2
Scoring filter (19)	2
TOTAL: 24	16832

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	86	0	104	104
TOTAL: 1	86	0	104	104

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	feeder.eternal-september.org	118
	csiph-out.news.weretis.net	72
	news-out.mixmin.net	16
	news.fcku.it	9
	news.freedyn.net	9
	newsfeed.fu-berlin.de	4
	newsfeeds.xmission.com	4
	localhost	1
	TOTAL: 8	233
Including strange strings
	newsfeed.fu-berlin.de	401
	csiph-out.feeder.erje.net	40
	feeder.eternal-september.org	32
	news.swapon.de	12
	csiph-out.news.weretis.net	11
	newsfeeds.xmission.com	10
	news.redatomik.org	9
	newsfeed.neva.ru	8
	news-out.mixmin.net	8
	nntp.pasdenom.info	4
	TOTAL: 12	537
No colon-space in header
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
TOTAL: 3		774

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37620	7556	15180	147	0	20%	23:58:30
2	news.freedyn.net	24418	727	22406	13	0	2%	23:59:20
3	xanadu-bbs	26327	597	22627	0	0	2%	23:59:55
4	fu-berlin.de	22017	482	21535	0	0	2%	23:50:04
5	usenet.pasdenom.info	26682	269	21877	1137	10	1%	23:59:54
6	xmission	27337	180	21732	12	29	0%	23:59:55
7	news.redatomik.org	22224	78	20860	16	0	0%	23:58:37
8	aioe	20943	61	19426	0	0	0%	23:53:53
9	weretis.net	17978	11	17070	1	0	0%	23:53:36
10	eternal-september	21329	10	19113	0	0	0%	23:59:54
11	news.swapon.de	2241	6	2133	0	0	0%	23:50:04
12	news.mixmin.net	22114	5	20953	0	0	0%	23:50:04
13	neva.ru	22886	3	21143	7	0	0%	23:59:54
14	news.fcku.it	3082	0	2863	0	0	0%	22:39:45
15	bwh	0	0	0	0	22839	0%	23:50:10
16	au2pb	0	0	0	0	22851	0%	23:50:10
17	erje	15426	0	15199	19	2	0%	23:54:26
TOTAL: 17		312624	9985	264117	1352	45731	3%	405:28:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	20.8 MB	892.9 KB	21.7 MB	0.3 KB/s	2.9 KB	23:58:30
2	news.freedyn.net	5.7 MB	54.0 KB	5.7 MB	0.1 KB/s	7.9 KB	23:59:20
3	xanadu-bbs	5.2 MB	0.0 KB	5.2 MB	0.1 KB/s	9.0 KB	23:59:55
4	fu-berlin.de	4.9 MB	0.0 KB	4.9 MB	0.1 KB/s	10.5 KB	23:50:04
5	usenet.pasdenom.info	881.8 KB	6.0 MB	6.9 MB	0.1 KB/s	5.0 KB	23:59:54
6	xmission	491.1 KB	23.0 KB	514.1 KB	0.0 KB/s	2.7 KB	23:59:55
7	news.redatomik.org	259.8 KB	75.4 KB	335.2 KB	0.0 KB/s	3.6 KB	23:58:37
8	aioe	184.9 KB	0.0 KB	184.9 KB	0.0 KB/s	3.0 KB	23:53:53
9	eternal-september	95.0 KB	0.0 KB	95.0 KB	0.0 KB/s	9.5 KB	23:59:54
10	weretis.net	90.1 KB	2.7 KB	92.8 KB	0.0 KB/s	7.7 KB	23:53:36
11	news.swapon.de	79.8 KB	0.0 KB	79.8 KB	0.0 KB/s	13.3 KB	23:50:04
12	news.mixmin.net	77.7 KB	0.0 KB	77.7 KB	0.0 KB/s	15.5 KB	23:50:04
13	neva.ru	7.7 KB	80.6 KB	88.3 KB	0.0 KB/s	8.8 KB	23:59:54
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:39:45
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:10
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:10
17	erje	0.0 KB	178.3 KB	178.3 KB	0.0 KB/s	9.4 KB	23:54:26
TOTAL: 17		38.8 MB	7.3 MB	46.1 MB	0.0 KB/s	4.2 KB	405:28:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	20	21	109.7 KB	3	0	0	04:04:01
2	68-185-181-64.dhcp.mdsn.wi.charter.com	13	5	77.7 KB	5	5	6	03:54:34
3	dhcp-089-099-203-254.chello.nl	24	3	73.7 KB	1	0	0	03:39:52
TOTAL: 3		57	29	261.1 KB	9	5	6	13:20:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	20	21	109.7 KB	3	0	0	04:04:01
2	*.dhcp.mdsn.wi.charter.com	13	5	77.7 KB	5	5	6	03:54:34
3	*.chello.nl	24	3	73.7 KB	1	0	0	03:39:52
4	*.cust.centrio.cz	11	0	0.0 KB	0	0	0	01:42:20
5	*.shodan.io	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 5		69	29	261.1 KB	9	5	6	13:20:49

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
ctomczyk	4
TOTAL: 2	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
tempe0.bbox.io	1.788	0.276	0.000	04:04:01
68-185-181-64.dhcp.mdsn.wi.charter.com	1.253	0.278	0.022	03:54:34
dhcp-089-099-203-254.chello.nl	1.786	0.411	0.000	03:39:52
194-73-239-109.cust.centrio.cz	0.770	0.233	0.015	01:42:20
census6.shodan.io	0.061	0.000	0.000	00:00:00
TOTAL: 5	5.658	1.198	0.037	13:20:49

Curious NNRP server explorers [Top 20]:

System	Conn
194-73-239-109.cust.centrio.cz	11
census6.shodan.io	1
TOTAL: 2	12

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	6	0
68-185-181-64.dhcp.mdsn.wi.charter.com	5	0
194-73-239-109.cust.centrio.cz	3	0
TOTAL: 3	14	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	29	100.0%
TOTAL: 1		29	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	21
2	comp.arch	8
TOTAL: 2		29

Daily Usenet report for csiph.com

Feb 21 04:15:00 -- Feb 22 04:15:00