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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 22 12:48:58 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[67530]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 22 12:48:58 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[67530]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	32639	49.7%	6.4 MB	55.0%
innd	27018	41.1%	4.2 MB	36.1%
innfeed	3742	5.7%	736.9 KB	6.2%
nnrpd	2252	3.4%	321.0 KB	2.7%
nocem	13	0.0%	1.9 KB	0.0%
TOTAL: 5	65664	100.0%	11.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	279765	51.0%
Negative hits	235107	42.8%
Do not exist	31931	5.8%
Cache misses	2057	0.4%
TOTAL: 4	548860	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.494	0.0%	30	0.000	16.467	185.000
article cleanup	00:00:00.170	0.0%	30458	0.000	0.006	0.022
article logging	00:00:01.322	0.0%	30835	0.000	0.043	0.739
article parse	00:00:04.925	0.0%	251149	0.002	0.020	0.113
article write	00:01:07.200	0.1%	14851	0.130	4.525	49.170
artlog/artcncl	00:00:00.001	0.0%	30	0.000	0.033	1.000
artlog/artparse	00:00:00.080	0.0%	1733	0.000	0.046	12.000
data move	00:00:00.313	0.0%	703997	0.000	0.000	0.007
hisgrep/artcncl	00:00:00.071	0.0%	30	0.000	2.367	60.000
hishave/artcncl	00:00:00.003	0.0%	30	0.000	0.100	1.000
hishave/artparse	00:00:00.001	0.0%	1732	0.000	0.001	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:24.768	0.1%	550309	0.000	0.154	1.763
history sync	00:00:01.704	0.0%	361	0.000	4.720	72.500
history write	00:01:35.808	0.1%	30402	0.223	3.151	19.265
hiswrite/artparse	00:00:02.935	0.0%	1731	0.000	1.696	17.000
idle	23:39:10.926	98.4%	690762	10.746	123.271	467.117
nntp read	00:00:02.997	0.0%	701566	0.001	0.004	0.008
overview write	00:16:50.335	1.2%	14851	0.165	68.031	104809.667
perl filter	00:00:30.144	0.0%	30399	0.752	0.992	1.456
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.266	0.0%	29716	0.000	0.009	0.035
TOTAL: 24:01:47.900	24:00:54.463	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	15095	0.000	0.002	0.027
article prepare	00:00:00.001	0.0%	6560	0.000	0.000	0.011
article read	00:00:00.357	0.0%	1252	0.000	0.285	43.000
backlog stats	00:00:00.178	0.0%	291138	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.972	0.0%	225594	0.001	0.004	0.014
data write	00:00:01.362	0.0%	211788	0.001	0.006	0.015
idle	23:54:08.023	100.0%	291138	165.514	295.558	1397.365
status file	00:00:00.683	0.0%	427	0.000	1.600	20.000
TOTAL: 23:54:22.357	23:54:11.608	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	53:31:38.012	99.5%	1324	0.000	145542.305	600096.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.199	0.0%	3050	0.000	0.065	2.571
readart	00:00:00.458	0.0%	36	0.000	12.722	41.000
TOTAL: 53:46:24.647	53:31:38.669	99.5%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	127
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	147

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	13837	2235	11590	12	16%	25:15:34
2	feeder.eternal-september.org	14	6899	2194	3454	1251	31%	27:40:05
3	news-out.mixmin.net	3	15200	1997	12985	218	13%	24:08:50
4	csiph-out.news.xs4all.nl	0	8167	1810	5527	830	22%	86:11:11
5	csiph-out.news.weretis.net	4	31774	1425	30156	193	4%	52:30:39
6	csiph-out.feeder.erje.net	1442	76230	1176	74676	378	1%	118:26:50
7	news.freedyn.net	3	16950	1087	15496	367	6%	24:09:02
8	newsfeed.fu-berlin.de	11	27311	873	13601	12837	3%	25:10:46
9	csiph-out.feed.uzoreto.com	867	25139	649	23863	627	2%	71:12:33
10	news-out.aioe.org	2	14446	608	13343	495	4%	24:54:30
11	newsfeed.neva.ru	141	21868	408	21334	126	1%	72:20:13
12	news-out.neodome.net	2	15355	403	14899	53	2%	23:59:56
13	news.fcku.it	9	1721	270	1443	8	15%	21:58:28
14	nntp.pasdenom.info	3	9363	79	9282	2	0%	23:54:30
15	news.swapon.de	11	1478	58	1419	1	3%	23:19:29
16	csiph-out.news.tnetconsulting.net	2	5584	7	5574	3	0%	12:36:30
17	news.bbs.nz	2	5921	4	5917	0	0%	13:25:19
18	localhost	27	1	1	0	0	100%	00:00:00
TOTAL: 18		2546	297244	15284	264559	17401	5%	671:14:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	7.3 MB	31.9 KB	2.7 MB	10.0 MB	72%	6.6 KB
2	feeder.eternal-september.org	6.5 MB	0.0 KB	393.5 MB	400.0 MB	1%	118.9 KB
3	newsfeeds.xmission.com	6.4 MB	3.6 KB	735.1 KB	7.1 MB	89%	3.3 KB
4	csiph-out.news.xs4all.nl	4.8 MB	79.7 KB	20.8 MB	25.7 MB	18%	10.0 KB
5	news-out.mixmin.net	4.3 MB	4.7 KB	708.8 KB	5.0 MB	85%	2.3 KB
6	csiph-out.news.weretis.net	4.0 MB	7.1 KB	2.2 MB	6.3 MB	64%	4.0 KB
7	news.freedyn.net	2.8 MB	14.0 KB	5.3 MB	8.1 MB	34%	5.7 KB
8	csiph-out.feed.uzoreto.com	2.3 MB	4.5 KB	21.9 MB	24.1 MB	9%	19.4 KB
9	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	37.2 MB	39.4 MB	5%	2.9 KB
10	news-out.aioe.org	1.9 MB	20.7 KB	1.7 MB	3.6 MB	52%	3.4 KB
11	news-out.neodome.net	1.4 MB	22.4 KB	560.4 KB	1.9 MB	70%	4.4 KB
12	newsfeed.neva.ru	1.1 MB	0.0 KB	417.3 KB	1.5 MB	72%	2.9 KB
13	news.fcku.it	540.0 KB	0.0 KB	8.4 KB	548.4 KB	98%	2.0 KB
14	nntp.pasdenom.info	193.6 KB	0.0 KB	2.5 KB	196.1 KB	98%	2.4 KB
15	news.swapon.de	124.7 KB	0.0 KB	88.9 KB	213.5 KB	58%	3.6 KB
16	csiph-out.news.tnetconsulting.net	21.8 KB	0.0 KB	14.7 KB	36.4 KB	59%	3.6 KB
17	news.bbs.nz	6.5 KB	0.0 KB	0.0 KB	6.5 KB	100%	1.6 KB
18	localhost	3.8 KB	0.0 KB	0.0 KB	3.8 KB	100%	3.8 KB
TOTAL: 18		45.7 MB	188.7 KB	487.8 MB	533.7 MB	8%	16.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 22 04:15:00 - 04:59:59	211	1.4%	0.08	724.1 KB	1.6%	0.27
Feb 22 05:00:00 - 05:59:59	428	2.9%	0.12	2.4 MB	5.5%	0.69
Feb 22 06:00:00 - 06:59:59	285	1.9%	0.08	905.5 KB	2.0%	0.25
Feb 22 07:00:00 - 07:59:59	436	2.9%	0.12	1.4 MB	3.2%	0.40
Feb 22 08:00:00 - 08:59:59	603	4.1%	0.17	1.8 MB	4.2%	0.52
Feb 22 09:00:00 - 09:59:59	705	4.7%	0.20	2.0 MB	4.6%	0.58
Feb 22 10:00:00 - 10:59:59	637	4.3%	0.18	1.6 MB	3.7%	0.46
Feb 22 11:00:00 - 11:59:59	796	5.4%	0.22	2.0 MB	4.6%	0.58
Feb 22 12:00:00 - 12:59:59	698	4.7%	0.19	1.8 MB	4.0%	0.51
Feb 22 13:00:00 - 13:59:59	754	5.1%	0.21	2.3 MB	5.1%	0.64
Feb 22 14:00:00 - 14:59:59	750	5.1%	0.21	2.0 MB	4.5%	0.57
Feb 22 15:00:00 - 15:59:59	939	6.3%	0.26	3.4 MB	7.6%	0.96
Feb 22 16:00:00 - 16:59:59	931	6.3%	0.26	2.6 MB	5.8%	0.73
Feb 22 17:00:00 - 17:59:59	868	5.8%	0.24	2.4 MB	5.3%	0.67
Feb 22 18:00:00 - 18:59:59	881	5.9%	0.24	2.5 MB	5.7%	0.72
Feb 22 19:00:00 - 19:59:59	819	5.5%	0.23	2.1 MB	4.7%	0.60
Feb 22 20:00:00 - 20:59:59	791	5.3%	0.22	2.7 MB	6.1%	0.77
Feb 22 21:00:00 - 21:59:59	660	4.4%	0.18	1.7 MB	3.9%	0.49
Feb 22 22:00:00 - 22:59:59	541	3.6%	0.15	1.5 MB	3.5%	0.44
Feb 22 23:00:00 - 23:59:59	557	3.8%	0.15	1.7 MB	3.9%	0.49
Feb 23 00:00:00 - 00:59:59	443	3.0%	0.12	1.4 MB	3.2%	0.40
Feb 23 01:00:00 - 01:59:59	359	2.4%	0.10	1.1 MB	2.5%	0.31
Feb 23 02:00:00 - 02:59:59	381	2.6%	0.11	1.1 MB	2.4%	0.31
Feb 23 03:00:00 - 03:59:59	308	2.1%	0.09	894.8 KB	2.0%	0.25
Feb 23 04:00:00 - 04:15:00	67	0.5%	0.07	176.5 KB	0.4%	0.20
TOTAL: 24:00:00	14848	100.0%	0.17	44.3 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.fu-berlin.de	13161	46	0	13115
2	feeder.eternal-september.org	1257	36	0	1221
3	csiph-out.news.xs4all.nl	802	167	26	609
4	csiph-out.feed.uzoreto.com	635	39	2	594
5	news-out.aioe.org	541	527	3	11
6	news.freedyn.net	364	15	1	348
7	news-out.mixmin.net	327	118	2	207
8	csiph-out.news.weretis.net	222	87	3	132
9	csiph-out.feeder.erje.net	185	55	13	117
10	newsfeed.neva.ru	124	24	0	100
11	news-out.neodome.net	89	3	5	81
12	news.swapon.de	24	0	0	24
13	newsfeeds.xmission.com	13	1	2	10
14	news.fcku.it	8	7	0	1
15	csiph-out.news.tnetconsulting.net	6	6	0	0
16	nntp.pasdenom.info	3	0	0	3
TOTAL: 16		17761	1131	57	16573

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.politics.scorched-earth	754
alt.computer.workshop	66
spline.eisfair	53
it-alt.sport.calcio.juventus	36
scinet.stats	26
alt.bbs.synchronet	23
alt.binaries.pictures.purebeauty	20
lada.talk	16
alt.job	14
mozilla.support.seamonkey	10
at.jobs	9
mozilla.general	9
alt.politics	6
alt.philosophy	5
mozilla.dev.platform	5
rec.pets.cats	5
alt.survival	5
mozilla.test	4
lada.movies	4
mozilla.support.firefox	4
TOTAL: 68	1131

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	12736
EMP (md5)	1315
Too many newsgroups	306
EMP (phl)	60
0	28
HTML Multipart	8
Scoring filter	6
Too many newsgroups (meow)	2
U2 violation - invalid distribution	1
TOTAL: 9	14462

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	1173
	csiph-out.feed.uzoreto.com	260
	csiph-out.news.xs4all.nl	94
	newsfeed.fu-berlin.de	68
	news.freedyn.net	51
	csiph-out.feeder.erje.net	48
	csiph-out.news.weretis.net	18
	news-out.neodome.net	8
	newsfeeds.xmission.com	5
	news-out.mixmin.net	5
	TOTAL: 11	1731
Including strange strings
	newsfeed.fu-berlin.de	303
	news.swapon.de	23
	csiph-out.news.xs4all.nl	20
	csiph-out.news.weretis.net	8
	csiph-out.feed.uzoreto.com	7
	newsfeeds.xmission.com	5
	csiph-out.feeder.erje.net	5
	news-out.mixmin.net	4
	nntp.pasdenom.info	1
	news-out.neodome.net	1
	TOTAL: 10	377
No colon-space in header
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
TOTAL: 5		2155

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	13684	552	13131	0	0	4%	23:53:56
2	newsfeed.xs4all.nl	14886	206	13047	4	148	1%	23:53:08
3	news.bbs.nz	27676	193	14033	21	0	0%	23:54:02
4	usenet.pasdenom.info	15791	150	14011	28	17	0%	23:59:21
5	tnet	15203	61	14189	7	0	0%	23:54:44
6	xmission	13785	52	11817	10	0	0%	23:54:03
7	erje	13561	21	13229	21	0	0%	23:53:57
8	news.freedyn.net	13901	6	13493	53	0	0%	23:53:57
9	neva.ru	13723	6	13393	218	0	0%	23:53:57
10	neodome.net	15017	5	14509	0	107	0%	23:51:25
11	news.uzoreto.com	13781	5	13411	8	0	0%	23:53:57
12	news.mixmin.net	12291	4	11899	0	0	0%	23:53:57
13	news.swapon.de	1537	2	1524	0	0	0%	23:21:44
14	aioe	12634	2	12228	0	0	0%	23:53:57
15	weretis.net	12928	2	12699	4	0	0%	23:49:56
16	eternal-september	0	0	0	0	11852	0%	23:50:07
17	xanadu-bbs	0	0	0	0	14808	0%	23:50:05
18	news.fcku.it	1384	0	1379	0	0	0%	21:35:06
19	news.samoylyk.net	0	0	0	0	14808	0%	23:50:10
TOTAL: 19		211782	1267	187992	374	41740	0%	451:01:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	fu-berlin.de	5.4 MB	0.0 KB	5.4 MB	0.1 KB/s	10.0 KB	23:53:56
2	newsfeed.xs4all.nl	562.3 KB	20.3 KB	582.6 KB	0.0 KB/s	2.8 KB	23:53:08
3	news.bbs.nz	520.8 KB	80.8 KB	601.6 KB	0.0 KB/s	2.8 KB	23:54:02
4	usenet.pasdenom.info	407.3 KB	53.1 KB	460.4 KB	0.0 KB/s	2.6 KB	23:59:21
5	tnet	130.5 KB	28.6 KB	159.1 KB	0.0 KB/s	2.3 KB	23:54:44
6	xmission	113.3 KB	23.3 KB	136.6 KB	0.0 KB/s	2.2 KB	23:54:03
7	news.uzoreto.com	39.1 KB	14.2 KB	53.3 KB	0.0 KB/s	4.1 KB	23:53:57
8	erje	37.7 KB	50.7 KB	88.4 KB	0.0 KB/s	2.1 KB	23:53:57
9	neva.ru	22.9 KB	471.2 KB	494.1 KB	0.0 KB/s	2.2 KB	23:53:57
10	neodome.net	20.3 KB	0.0 KB	20.3 KB	0.0 KB/s	4.1 KB	23:51:25
11	news.freedyn.net	16.0 KB	813.7 KB	829.7 KB	0.0 KB/s	14.1 KB	23:53:57
12	news.mixmin.net	8.4 KB	0.0 KB	8.4 KB	0.0 KB/s	2.1 KB	23:53:57
13	aioe	6.6 KB	0.0 KB	6.6 KB	0.0 KB/s	3.3 KB	23:53:57
14	news.swapon.de	6.2 KB	0.0 KB	6.2 KB	0.0 KB/s	3.1 KB	23:21:44
15	weretis.net	5.1 KB	32.5 KB	37.6 KB	0.0 KB/s	6.3 KB	23:49:56
16	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
17	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:35:06
19	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:10
TOTAL: 19		7.2 MB	1.6 MB	8.8 MB	0.0 KB/s	5.5 KB	451:01:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	192.163.24.10.ip.us.as30241.net	33	31	240.2 KB	30	1	1	10:00:34
2	a131033.upc-a.chello.nl	46	3	9.9 KB	3	0	0	09:16:40
3	91.187.33.87	238	2	7.3 KB	2	0	0	30:45:51
TOTAL: 3		317	36	257.4 KB	35	1	1	53:46:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.163.24.10.ip.us.as30241.net	33	31	240.2 KB	30	1	1	10:00:34
2	*.upc-a.chello.nl	46	3	9.9 KB	3	0	0	09:16:40
3	unresolved	241	2	7.3 KB	2	0	0	30:46:03
4	*.net.upcbroadband.cz	40	0	0.0 KB	0	0	0	03:17:29
5	*.starnet.cz	3	0	0.0 KB	0	0	0	00:24:58
6	*.range31-49.btcentralplus.com	1	0	0.0 KB	0	0	0	00:00:19
TOTAL: 6		364	36	257.4 KB	35	1	1	53:46:05

NNRP auth users [Top 20]:

User	Conn
kev009	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	14.354	3.306	0.000	30:45:51
192.163.24.10.ip.us.as30241.net	2.387	0.486	0.003	10:00:34
a131033.upc-a.chello.nl	2.959	0.544	0.000	09:16:40
ip-78-102-198-192.net.upcbroadband.cz	2.441	0.510	0.000	03:17:29
nat-147.starnet.cz	0.246	0.016	0.000	00:24:58
host31-49-229-214.range31-49.btcentralplus.com	0.060	0.016	0.000	00:00:19
142.93.133.74	0.073	0.000	0.000	00:00:10
91.241.19.21	0.056	0.016	0.000	00:00:00
71.6.135.131	0.062	0.008	0.000	00:00:00
TOTAL: 9	22.638	4.902	0.003	53:46:05

Curious NNRP server explorers [Top 20]:

System	Conn
ip-78-102-198-192.net.upcbroadband.cz	40
nat-147.starnet.cz	3
71.6.135.131	1
142.93.133.74	1
91.241.19.21	1
host31-49-229-214.range31-49.btcentralplus.com	1
TOTAL: 6	47

NNRP unrecognized commands (by host):

System	Conn
91.241.19.21	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
^C	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
192.163.24.10.ip.us.as30241.net	11	0
91.241.19.21	1	1
TOTAL: 2	12	1

Newsgroup request counts (by category):

Category		Count	Pct
1	muc	24	61.5%
2	comp	15	38.5%
TOTAL: 2		39	100.0%

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

Newsgroup		Count
1	muc.lists.freebsd.stable	24
2	comp.sys.ibm.ps2.hardware	8
3	comp.sys.sgi.marketplace	7
TOTAL: 3		39

Daily Usenet report for csiph.com

Feb 22 04:15:00 -- Feb 23 04:15:00