Daily Usenet report for csiph.com: Oct 17 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 17 11:10:03 csiph pgpverify[54474]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	62628	53.6%	9.2 MB	64.9%
nnrpd	31829	27.3%	2.6 MB	18.7%
innd	20432	17.5%	2.0 MB	14.4%
innfeed	1863	1.6%	298.0 KB	2.1%
controlchan	4	0.0%	0.6 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	116757	100.0%	14.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	257988	44.8%
Negative hits	252343	43.8%
Do not exist	54682	9.5%
Cache misses	11157	1.9%
TOTAL: 4	576170	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:56.164	0.1%	9163	0.000	6.129	26.500
article cleanup	00:00:00.295	0.0%	50051	0.000	0.006	0.022
article logging	00:00:00.559	0.0%	50521	0.000	0.011	0.039
article parse	00:00:10.196	0.0%	771819	0.003	0.013	0.140
article write	00:01:02.207	0.1%	32296	0.126	1.926	10.571
artlog/artcncl	00:00:00.064	0.0%	9020	0.000	0.007	0.091
artlog/artparse	00:00:00.034	0.0%	4305	0.000	0.008	0.125
data move	00:00:00.594	0.0%	1210648	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.398	0.0%	7999	0.000	0.050	30.000
hishave/artcncl	00:00:00.101	0.0%	9163	0.000	0.011	0.167
hishave/artparse	00:00:00.011	0.0%	4305	0.000	0.003	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.686	0.0%	571434	0.001	0.005	0.080
history sync	00:00:02.011	0.0%	329	0.667	6.112	63.000
history write	00:00:46.518	0.1%	50041	0.181	0.930	6.616
hiswrite/artcncl	00:00:00.088	0.0%	1164	0.000	0.076	3.000
hiswrite/artparse	00:00:04.604	0.0%	4305	0.000	1.069	35.000
idle	23:54:43.234	99.6%	1193490	9.596	72.127	426.794
nntp read	00:00:03.672	0.0%	1195012	0.002	0.003	0.007
overview write	00:01:07.225	0.1%	32296	0.150	2.082	9.176
perl filter	00:01:13.086	0.1%	50041	0.638	1.461	2.963
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.562	0.0%	64593	0.000	0.009	0.029
TOTAL: 24:00:46.703	24:00:14.309	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.094	0.0%	31688	0.000	0.003	0.019
article prepare	00:00:00.002	0.0%	7326	0.000	0.000	0.014
article read	00:00:00.242	0.0%	1508	0.000	0.160	45.000
backlog stats	00:00:00.108	0.0%	328310	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.369	0.0%	252672	0.001	0.005	0.011
data write	00:00:01.881	0.0%	239896	0.003	0.008	0.016
idle	23:54:11.174	100.0%	328310	12.754	262.103	797.914
status file	00:00:00.089	0.0%	188	0.000	0.473	1.000
TOTAL: 23:54:19.203	23:54:14.959	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.708	0.0%	12386	0.009	0.057	0.209
idle	44:51:40.303	100.0%	43707	0.000	3695.067	600198.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.256	0.0%	88227	0.000	0.014	69.900
readart	00:00:00.012	0.0%	51	0.000	0.235	2.000
TOTAL: 44:52:56.057	44:51:42.279	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	147
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	166

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	5	45613	12866	29166	3581	28%	25:06:57
2	au2pb.net	1	26111	4358	21029	724	16%	24:23:55
3	newsfeed.neva.ru	149	52703	4095	44613	3995	7%	72:22:18
4	news-out.mixmin.net	3	30559	3294	24407	2858	10%	23:53:53
5	xanadu-bbs.net	5	52901	3051	39617	10233	5%	30:55:32
6	newsfeeds.xmission.com	1	24167	2349	21656	162	9%	24:34:02
7	newsfeed.fu-berlin.de	52	34949	1307	33432	210	3%	34:51:14
8	news.swapon.de	3	2090	237	1844	9	11%	24:17:43
9	nntp.pasdenom.info	4	30229	230	29936	63	0%	22:41:58
10	localhost	50	11606	0	11606	0	0%	00:01:03
TOTAL: 10		273	310928	31787	257306	21835	10%	283:08:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	25.7 MB	41.1 KB	14.1 MB	39.8 MB	64%	2.5 KB
2	au2pb.net	11.3 MB	0.0 KB	3.3 MB	14.6 MB	77%	2.9 KB
3	news-out.mixmin.net	9.9 MB	10.9 KB	6.0 MB	16.0 MB	62%	2.7 KB
4	newsfeed.neva.ru	9.1 MB	4.0 KB	19.0 MB	28.1 MB	32%	3.6 KB
5	xanadu-bbs.net	8.4 MB	18.0 KB	1011.4 MB	1019.9 MB	0%	78.6 KB
6	newsfeeds.xmission.com	7.0 MB	0.0 KB	3.7 MB	10.7 MB	65%	4.4 KB
7	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	714.4 KB	3.5 MB	80%	2.4 KB
8	news.swapon.de	545.6 KB	0.0 KB	64.0 KB	609.5 KB	89%	2.5 KB
9	nntp.pasdenom.info	479.2 KB	2.7 KB	559.3 KB	1.0 MB	46%	3.6 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		75.3 MB	76.7 KB	1.0 GB	1.1 GB	6%	21.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 17 04:15:00 - 04:59:59	8681	27.4%	3.22	11.3 MB	15.0%	4.29
Oct 17 05:00:00 - 05:59:59	539	1.7%	0.15	1.5 MB	2.0%	0.42
Oct 17 06:00:00 - 06:59:59	652	2.1%	0.18	1.9 MB	2.6%	0.55
Oct 17 07:00:00 - 07:59:59	883	2.8%	0.25	2.6 MB	3.4%	0.73
Oct 17 08:00:00 - 08:59:59	1043	3.3%	0.29	2.7 MB	3.6%	0.77
Oct 17 09:00:00 - 09:59:59	1247	3.9%	0.35	3.5 MB	4.7%	1.00
Oct 17 10:00:00 - 10:59:59	1026	3.2%	0.28	2.6 MB	3.4%	0.73
Oct 17 11:00:00 - 11:59:59	1071	3.4%	0.30	2.9 MB	3.8%	0.82
Oct 17 12:00:00 - 12:59:59	1164	3.7%	0.32	2.9 MB	3.8%	0.82
Oct 17 13:00:00 - 13:59:59	1158	3.7%	0.32	3.2 MB	4.2%	0.90
Oct 17 14:00:00 - 14:59:59	1238	3.9%	0.34	3.1 MB	4.2%	0.89
Oct 17 15:00:00 - 15:59:59	1399	4.4%	0.39	3.7 MB	4.9%	1.06
Oct 17 16:00:00 - 16:59:59	1481	4.7%	0.41	3.9 MB	5.2%	1.12
Oct 17 17:00:00 - 17:59:59	1320	4.2%	0.37	3.7 MB	4.9%	1.04
Oct 17 18:00:00 - 18:59:59	1160	3.7%	0.32	3.1 MB	4.1%	0.88
Oct 17 19:00:00 - 19:59:59	1215	3.8%	0.34	3.5 MB	4.6%	0.98
Oct 17 20:00:00 - 20:59:59	1193	3.8%	0.33	3.1 MB	4.2%	0.90
Oct 17 21:00:00 - 21:59:59	1087	3.4%	0.30	2.8 MB	3.7%	0.80
Oct 17 22:00:00 - 22:59:59	885	2.8%	0.25	2.7 MB	3.6%	0.78
Oct 17 23:00:00 - 23:59:59	611	1.9%	0.17	1.9 MB	2.5%	0.53
Oct 18 00:00:00 - 00:59:59	765	2.4%	0.21	2.4 MB	3.2%	0.68
Oct 18 01:00:00 - 01:59:59	500	1.6%	0.14	1.5 MB	2.0%	0.42
Oct 18 02:00:00 - 02:59:59	564	1.8%	0.16	2.0 MB	2.7%	0.58
Oct 18 03:00:00 - 03:59:59	636	2.0%	0.18	2.1 MB	2.8%	0.61
Oct 18 04:00:00 - 04:15:00	192	0.6%	0.21	773.3 KB	1.0%	0.86
TOTAL: 24:00:00	31710	100.0%	0.37	75.3 MB	100.0%	0.89

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	10369	459	1	9909
2	newsfeed.neva.ru	4042	1553	1	2488
3	feeder.eternal-september.org	3621	414	3	3204
4	news-out.mixmin.net	2873	47	1	2825
5	au2pb.net	728	681	0	47
6	newsfeed.fu-berlin.de	585	24	0	561
7	newsfeeds.xmission.com	164	121	0	43
8	nntp.pasdenom.info	85	65	0	20
9	news.swapon.de	33	0	0	33
TOTAL: 9		22500	3364	6	19130

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	669
alt.politics.scorched-earth	322
lada.talk	205
lada.test	179
pld.cvs.commit	150
mailing.freebsd.pkg-fallout	123
ut.test	114
mailing.freebsd.ports-bugs	99
mailing.freebsd.svn-ports-all	78
mailing.freebsd.svn-ports-head	78
alt.politics	71
mailing.openbsd.source-changes	66
it-alt.sport.calcio.juventus	56
alt.windows7.general	55
mailing.freebsd.svn-src-all	52
mailing.freebsd.svn-src-head	51
fa.openbsd.source-changes	49
fa.freebsd.cvs-all	45
lada.auto	39
alt.survival	38
TOTAL: 207	3364

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10094
EMP (phn path)	2331
EMP (phl)	1073
EMP (phn nph)	298
Binary: misplaced PAR2	183
PFM bot misc	113
Too many newsgroups (low_xpost)	69
Too many newsgroups	67
HTML Multipart	55
HTML post	34
Binary Image: misplaced jpg	30
Binary: misplaced flac	2
User-issued spam cancel	2
Binary Image: misplaced png	1
Too many newsgroups (meow)	1
Binary: misplaced par2	1
Binary: misplaced zip	1
HTML Image Tags	1
Binary Payload	1
TOTAL: 19	14357

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	4174
	feeder.eternal-september.org	45
	newsfeeds.xmission.com	32
	au2pb.net	24
	news-out.mixmin.net	15
	nntp.pasdenom.info	9
	newsfeed.fu-berlin.de	3
	news.swapon.de	1
	TOTAL: 8	4303
Including strange strings
	newsfeed.fu-berlin.de	387
	feeder.eternal-september.org	24
	news.swapon.de	24
	news-out.mixmin.net	15
	au2pb.net	12
	newsfeeds.xmission.com	5
	newsfeed.neva.ru	3
	xanadu-bbs.net	1
	TOTAL: 8	471
TOTAL: 2		4774

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	usenet.pasdenom.info	38385	190	30281	985	0	4	0%	23:59:57
2	au2pb	28387	189	27062	139	0	0	0%	23:59:57
3	xanadu-bbs	32305	102	28431	78	1	0	0%	23:59:53
4	fu-berlin.de	30092	30	30041	21	0	0	0%	23:59:57
5	news.mixmin.net	29407	17	28331	11	0	10	0%	23:59:56
6	xmission	36520	11	28982	2	0	0	0%	23:59:58
7	eternal-september	16965	5	16110	3	0	0	0%	23:59:39
8	neva.ru	28893	4	27573	44	0	21	0%	23:59:57
9	news.swapon.de	1873	1	1861	1	0	0	0%	23:55:49
TOTAL: 9		242827	549	218672	1284	1	35	0%	215:55:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	887.9 KB	303.3 KB	1.2 MB	3.6 KB	23:59:57
2	usenet.pasdenom.info	821.1 KB	1.6 MB	2.4 MB	2.1 KB	23:59:57
3	xanadu-bbs	603.3 KB	188.6 KB	791.9 KB	4.4 KB	23:59:53
4	fu-berlin.de	159.1 KB	29.5 KB	188.7 KB	3.7 KB	23:59:57
5	news.mixmin.net	95.5 KB	22.5 KB	118.0 KB	4.2 KB	23:59:56
6	eternal-september	33.2 KB	4.1 KB	37.3 KB	4.7 KB	23:59:39
7	neva.ru	31.7 KB	252.9 KB	284.6 KB	5.9 KB	23:59:57
8	xmission	17.3 KB	5.6 KB	23.0 KB	1.8 KB	23:59:58
9	news.swapon.de	2.2 KB	1.7 KB	3.9 KB	2.0 KB	23:55:49
TOTAL: 9		2.6 MB	2.4 MB	5.0 MB	2.8 KB	215:55:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	47	113.5 KB	46	04:13:21
2	challenger.nielsenmedia.com	2	2	3.2 KB	1	00:30:12
3	c-73-195-247-24.hsd1.nj.comcast.net	2	2	3.2 KB	1	01:01:37
TOTAL: 3		292	51	120.0 KB	48	44:52:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	313	47	113.5 KB	46	26:45:00
2	*.nielsenmedia.com	2	2	3.2 KB	1	00:30:12
3	*.hsd1.nj.comcast.net	2	2	3.2 KB	1	01:01:37
4	*.chello.nl	16	0	0.0 KB	0	05:53:43
5	*.bbox.io	3	0	0.0 KB	0	00:30:52
6	*.tu.ok.cox.net	15	0	0.0 KB	0	10:11:13
7	*.124-45-179.ppp.wakwak.ne.jp	1	0	0.0 KB	0	00:00:12
TOTAL: 7		352	51	120.0 KB	48	44:52:52

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.214	0.429	0.337	0.000	22:31:36
ip72-222-107-224.tu.ok.cox.net	1.307	0.481	0.000	10:11:13
dhcp-089-099-203-254.chello.nl	1.283	0.203	0.000	05:53:43
202.89.176.220	26.278	6.275	0.715	04:13:21
c-73-195-247-24.hsd1.nj.comcast.net	0.205	0.048	0.000	01:01:37
tempe0.bbox.io	0.252	0.032	0.000	00:30:52
challenger.nielsenmedia.com	0.159	0.040	0.000	00:30:12
z87.124-45-179.ppp.wakwak.ne.jp	0.122	0.041	0.000	00:00:12
69.160.37.54	1.773	0.434	0.000	00:00:01
TOTAL: 9	31.808	7.891	0.715	44:52:52

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	16
ip72-222-107-224.tu.ok.cox.net	15
tempe0.bbox.io	3
z87.124-45-179.ppp.wakwak.ne.jp	1
TOTAL: 5	60

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-107-224.tu.ok.cox.net	15	0
dhcp-089-099-203-254.chello.nl	10	0
c-73-195-247-24.hsd1.nj.comcast.net	2	0
tempe0.bbox.io	1	0
challenger.nielsenmedia.com	1	0
TOTAL: 5	29	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.os.vms	17
2	comp.arch	10
3	comp.sys.ibm.pc.games.action	8
4	comp.sys.ibm.pc.games.war-historical	5
5	comp.lang.awk	4
6	comp.os.linux.networking	2
7	comp.arch.embedded	2
8	comp.sys.ibm.pc.hardware.storage	2
9	comp.sys.ibm.as400.misc	1
TOTAL: 9		51

Daily Usenet report for csiph.com

Oct 17 04:15:00 -- Oct 18 04:15:00