Daily Usenet report: Apr 2 04:15:03

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Apr 2 06:22:59 newsfeed pgpverify[2801]: pgpverify: verification failed
Apr 2 10:47:08 newsfeed nnrpd[12284]: SERVER perl filter not defined
Apr 2 11:23:02 newsfeed innfeed[2440]: task:0 connect: Network is unreachable
Apr 2 12:26:21 newsfeed nnrpd[4937]: SERVER perl filter not defined
Apr 2 12:27:05 newsfeed nnrpd[4957]: SERVER perl filter not defined
Apr 2 12:28:46 newsfeed nnrpd[4968]: SERVER perl filter not defined
Apr 2 12:29:22 newsfeed nnrpd[4980]: SERVER perl filter not defined
Apr 2 17:17:56 newsfeed nnrpd[14629]: SERVER perl filter not defined
Apr 2 22:00:31 newsfeed nnrpd[24091]: SERVER perl filter not defined
Apr 2 22:43:31 newsfeed nnrpd[25594]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1189110	97.6%	135.2 MB	97.1%
innd	13930	1.1%	2.2 MB	1.6%
nnrpd	7528	0.6%	785.4 KB	0.6%
innfeed	7314	0.6%	1.1 MB	0.8%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1217887	100.0%	139.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1183049	93.3%
Negative hits	42545	3.4%
Positive hits	42431	3.3%
Cache misses	634	0.0%
TOTAL: 4	1268659	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:24.134	0.0%	756	0.000	31.923	658.000
article cleanup	00:00:17.425	0.0%	1187631	0.000	0.015	0.032
article logging	00:00:37.553	0.0%	1187788	0.007	0.032	0.499
article parse	00:47:35.325	3.3%	198706469	0.008	0.014	0.070
article write	00:11:57.527	0.8%	1179261	0.358	0.608	2.117
artlog/artcncl	00:00:00.018	0.0%	735	0.000	0.024	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:32.847	0.2%	207323948	0.000	0.001	0.002
hisgrep/artcncl	00:00:05.482	0.0%	676	0.000	8.109	298.000
hishave/artcncl	00:00:04.591	0.0%	756	0.000	6.073	291.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:57:31.637	8.2%	1268874	0.898	5.557	16.536
history sync	00:00:00.027	0.0%	293	0.000	0.092	3.000
history write	02:12:32.258	9.2%	1183887	2.734	6.717	16.885
hiswrite/artcncl	00:00:00.450	0.0%	80	0.000	5.625	183.000
idle	14:20:39.618	59.8%	170887789	0.167	0.302	2.650
nntp read	00:55:22.193	3.8%	197933171	0.011	0.017	0.042
overview write	00:00:07.832	0.0%	1179261	0.000	0.007	0.018
perl filter	02:52:04.112	12.0%	1183886	5.781	8.721	10.942
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:38.095	0.2%	2401642	0.023	0.066	0.109
TOTAL: 23:58:30.734	23:24:31.124	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.170	0.0%	43210	0.000	0.004	0.125
article prepare	00:00:00.019	0.0%	67328	0.000	0.000	0.062
article read	00:00:01.792	0.0%	15986	0.000	0.112	18.424
backlog stats	00:00:00.216	0.0%	353079	0.000	0.001	0.012
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.199	0.0%	205912	0.000	0.011	1.000
data write	00:00:06.123	0.0%	175352	0.000	0.035	0.500
idle	23:58:52.760	100.0%	353079	0.000	244.514	9216.000
status file	00:00:01.611	0.0%	283	0.000	5.693	182.000
TOTAL: 23:59:12.932	23:59:04.890	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:28:40.602	99.7%	5609	0.000	4157.711	600073.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.165	0.0%	73931	0.000	0.056	3.778
readart	00:00:19.750	0.1%	5382	0.000	3.670	177.000
TOTAL: 06:29:48.089	06:29:04.517	99.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
hun-mnt@news.sztaki.hu	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	newsfeed.pionier.net.pl	272	1176669	1169425	882	6362	99%	146:21:52
2	news.task.gda.pl	13	30970	5718	24024	1228	18%	41:37:29
3	news.nask.pl	3	19109	4488	13871	750	23%	24:06:46
4	news.icm.edu.pl	42	314	24	252	38	7%	17:51:21
5	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 5		339	1227070	1179663	39029	8378	96%	229:57:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	414.9 GB	82.1 MB	368.6 MB	415.3 GB	99%	370.4 KB
2	news.nask.pl	43.2 MB	3.0 MB	3.2 MB	49.3 MB	87%	9.6 KB
3	news.task.gda.pl	12.3 MB	64.7 KB	3.8 MB	16.2 MB	76%	2.4 KB
4	news.icm.edu.pl	232.9 KB	17.8 KB	482.2 KB	732.9 KB	31%	11.8 KB
5	localhost	12.1 KB	0.0 KB	0.0 KB	12.1 KB	100%	1.5 KB
TOTAL: 5		414.9 GB	85.1 MB	376.0 MB	415.4 GB	99%	366.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 2 04:15:03 - 04:59:59	63548	5.4%	23.56	21.0 GB	5.1%	8182.33
Apr 2 05:00:00 - 05:59:59	41528	3.5%	11.54	15.3 GB	3.7%	4452.53
Apr 2 06:00:00 - 06:59:59	45682	3.9%	12.69	16.9 GB	4.1%	4907.97
Apr 2 07:00:00 - 07:59:59	42397	3.6%	11.78	17.1 GB	4.1%	4969.51
Apr 2 08:00:00 - 08:59:59	44048	3.7%	12.24	16.0 GB	3.9%	4673.58
Apr 2 09:00:00 - 09:59:59	43311	3.7%	12.03	16.8 GB	4.1%	4902.52
Apr 2 10:00:00 - 10:59:59	43367	3.7%	12.05	17.5 GB	4.2%	5089.23
Apr 2 11:00:00 - 11:59:59	41545	3.5%	11.54	15.5 GB	3.7%	4512.27
Apr 2 12:00:00 - 12:59:59	48541	4.1%	13.48	16.8 GB	4.1%	4902.56
Apr 2 13:00:00 - 13:59:59	47174	4.0%	13.10	16.3 GB	3.9%	4737.49
Apr 2 14:00:00 - 14:59:59	49299	4.2%	13.69	17.4 GB	4.2%	5079.94
Apr 2 15:00:00 - 15:59:59	43793	3.7%	12.16	15.7 GB	3.8%	4573.94
Apr 2 16:00:00 - 16:59:59	40836	3.5%	11.34	14.6 GB	3.5%	4242.42
Apr 2 17:00:00 - 17:59:59	40803	3.5%	11.33	14.4 GB	3.5%	4184.18
Apr 2 18:00:00 - 18:59:59	42139	3.6%	11.71	15.1 GB	3.6%	4384.61
Apr 2 19:00:00 - 19:59:59	37858	3.2%	10.52	12.7 GB	3.1%	3699.53
Apr 2 20:00:00 - 20:59:59	38406	3.3%	10.67	12.5 GB	3.0%	3650.53
Apr 2 21:00:00 - 21:59:59	39297	3.3%	10.92	11.7 GB	2.8%	3409.40
Apr 2 22:00:00 - 22:59:59	29716	2.5%	8.25	8.7 GB	2.1%	2523.17
Apr 2 23:00:00 - 23:59:59	37268	3.2%	10.35	13.2 GB	3.2%	3841.76
Apr 3 00:00:00 - 00:59:59	63402	5.4%	17.61	21.2 GB	5.1%	6166.39
Apr 3 01:00:00 - 01:59:59	63213	5.4%	17.56	22.3 GB	5.4%	6494.17
Apr 3 02:00:00 - 02:59:59	80022	6.8%	22.23	28.3 GB	6.8%	8235.25
Apr 3 03:00:00 - 03:59:59	90058	7.6%	25.02	30.4 GB	7.3%	8854.66
Apr 3 04:00:00 - 04:15:03	22596	1.9%	25.02	7.8 GB	1.9%	9005.77
TOTAL: 24:00:00	1179847	100.0%	13.66	415.0 GB	100.0%	5036.52

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6506	210	2995	0	3301
2	news.task.gda.pl	1215	968	34	8	205
3	news.nask.pl	760	15	715	1	29
4	news.icm.edu.pl	47	0	2	0	45
TOTAL: 4		8528	1193	3746	9	3580

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	87
mailing.freebsd.current	71
mailing.freebsd.svn-ports-all	67
mailing.freebsd.svn-ports-head	66
mailing.database.pgsql-general	57
mailing.unix.samba	46
mailing.freebsd.svn-src-all	45
alt.politics.scorched-earth	44
mailing.unix.samba-technical	42
it.tlc.cellulari.android	41
mailing.freebsd.svn-src-head	40
mailing.gcc.gcc-cvs	39
mailing.freebsd.ports-bugs	39
fa.freebsd.cvs-all	38
alt.windows7.general	37
mozilla.dev.tree-management	34
mozilla.dev.platform	22
mailing.openbsd.ports-changes	20
24hoursupport.helpdesk	20
mailing.openbsd.source-changes	19
TOTAL: 71	1193

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

Groups	Count
fr.comp.applications.libres	6
fr.comp.lang.php	1
fr.bienvenue	1
bit.listserv.catholic	1
TOTAL: 4	9

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3052
EMP (fsl)	172
Attached HTML file	106
EMP (md5)	85
HTML post	4
Scoring filter (8)	3
TOTAL: 6	3422

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	63353	9651	28834	4043	8	63	15%	23:49:57
2	panorama	49974	2543	40273	393	0	0	5%	24:00:02
3	poznan	11601	64	9819	40	0	0	0%	24:00:02
4	task	1841	58	936	6	0	7	3%	19:45:18
5	nask	28711	41	18388	7	0	0	0%	24:00:01
6	okapi	0	0	0	0	0	2468	0%	23:50:00
TOTAL: 6		155480	12357	98250	4489	8	2538	7%	139:25:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	470.7 MB	341.6 MB	812.3 MB	9.7 KB/s	60.7 KB	23:49:57
2	panorama	9.6 MB	1.4 MB	11.0 MB	0.1 KB/s	3.8 KB	24:00:02
3	poznan	190.3 KB	262.2 KB	452.5 KB	0.0 KB/s	4.4 KB	24:00:02
4	task	129.0 KB	8.5 KB	137.5 KB	0.0 KB/s	2.1 KB	19:45:18
5	nask	109.7 KB	761.3 KB	871.0 KB	0.0 KB/s	18.1 KB	24:00:01
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:00
TOTAL: 6		480.7 MB	344.0 MB	824.7 MB	1.7 KB/s	50.1 KB	139:25:20

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	342	539.3 KB	288	0	00:00:24
2	pc225.geo.uni.lodz.pl	3	110	199.0 KB	4	0	02:06:26
3	web.man.lodz.pl	287	90	83.3 KB	40	0	00:00:23
4	lze200.lze.lodz.pl	10	38	75.3 KB	8	0	01:55:57
5	ip-176.105.135.22.tvsat364.lodz.pl	5	8	10.4 KB	4	0	01:35:01
6	ebx89.neoplus.adsl.tpnet.pl	1	5	7.8 KB	1	0	00:00:32
7	pc24.zsk.p.lodz.pl	2	1	0.7 KB	1	1	00:28:01
8	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 8		603	594	915.9 KB	346	8	06:29:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	342	539.3 KB	288	0	00:00:24
2	*.geo.uni.lodz.pl	3	110	199.0 KB	4	0	02:06:26
3	*.man.lodz.pl	678	90	83.3 KB	40	7	00:00:30
4	*.lze.lodz.pl	10	38	75.3 KB	8	0	01:55:57
5	*.105.135.22.tvsat364.lodz.pl	5	8	10.4 KB	4	0	01:35:01
6	*.neoplus.adsl.tpnet.pl	1	5	7.8 KB	1	0	00:00:32
7	*.zsk.p.lodz.pl	2	1	0.7 KB	1	1	00:28:01
8	*.pa.nsw.optusnet.com.au	26	0	0.0 KB	0	0	00:01:17
9	*.filmschool.lodz.pl	1	0	0.0 KB	0	0	00:07:26
10	*.207.15.62.static.jazztel.es	2	0	0.0 KB	0	0	00:08:32
11	*.205.15.62.static.jazztel.es	2	0	0.0 KB	0	0	00:05:28
12	*.toya.net.pl	89	0	0.0 KB	0	0	00:00:07
TOTAL: 12		1107	594	915.9 KB	346	8	06:29:47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc225.geo.uni.lodz.pl	0.080	0.204	02:06:26
lze200.lze.lodz.pl	0.040	0.144	01:55:57
ip-176.105.135.22.tvsat364.lodz.pl	0.012	0.056	01:35:01
pc24.zsk.p.lodz.pl	0.288	0.156	00:28:01
182.207.15.62.static.jazztel.es	0.056	0.056	00:08:32
proxy.filmschool.lodz.pl	0.000	0.008	00:07:26
44.205.15.62.static.jazztel.es	0.020	0.032	00:05:28
pa49-181-246-131.pa.nsw.optusnet.com.au	0.028	0.184	00:01:17
ebx89.neoplus.adsl.tpnet.pl	0.000	0.012	00:00:32
vegas.cm.p.lodz.pl	0.668	2.900	00:00:24
web.man.lodz.pl	0.704	2.968	00:00:23
staticline41435.toya.net.pl	0.196	0.740	00:00:07
mon.man.lodz.pl	0.556	2.792	00:00:04
david.man.lodz.pl	0.204	0.940	00:00:01
isvcs01.man.lodz.pl	0.040	0.076	00:00:00
?	0.000	0.048	00:00:00
TOTAL: 16	2.892	11.316	06:29:47

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	89
pa49-181-246-131.pa.nsw.optusnet.com.au	26
44.205.15.62.static.jazztel.es	2
182.207.15.62.static.jazztel.es	2
proxy.filmschool.lodz.pl	1
TOTAL: 7	504

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	3
TOTAL: 1	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	3	0
pc225.geo.uni.lodz.pl	3	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
TOTAL: 3	8	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	347	58.4%
2	pl	246	41.4%
3	alt	1	0.2%
TOTAL: 3		594	100.0%

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

Newsgroup		Count
1	lodman.info	347
2	pl.comp.pecet	74
3	pl.comp.www	60
4	pl.rec.rowery	50
5	pl.comp.os.ms-windows.winnt	28
6	pl.misc.telefonia.gsm	26
7	pl.soc.prawo	7
8	alt.test.pl	1
9	pl.regionalne.lodz	1
TOTAL: 9		594

Daily Usenet report

Apr 2 04:15:03 -- Apr 3 04:15:03