Daily Usenet report: Jul 4 04:15:02

Unknown entries from news log file:

First 23 / 23 lines (100.0%)

Jul 4 04:39:10 newsfeed pgpverify[16006]: pgpverify: verification failed
Jul 4 04:39:10 newsfeed controlchan[2333]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1404439441.10693@news.a3.xs4all.nl>
Jul 4 04:39:10 newsfeed pgpverify[16012]: pgpverify: verification failed
Jul 4 04:39:10 newsfeed controlchan[2333]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1404439441.10647@news.a3.xs4all.nl>
Jul 4 04:39:11 newsfeed pgpverify[16018]: pgpverify: verification failed
Jul 4 04:39:11 newsfeed controlchan[2333]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1404439441.10739@news.a3.xs4all.nl>
Jul 4 04:39:11 newsfeed pgpverify[16024]: pgpverify: verification failed
Jul 4 04:39:11 newsfeed controlchan[2333]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1404439441.10601@news.a3.xs4all.nl>
Jul 4 04:43:16 newsfeed pgpverify[16248]: pgpverify: verification failed
Jul 4 04:43:16 newsfeed controlchan[2333]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1404439441.10785@news.a3.xs4all.nl>
Jul 4 06:24:10 newsfeed nnrpd[19790]: SERVER perl filter not defined
Jul 4 07:18:59 newsfeed nnrpd[22001]: SERVER perl filter not defined
Jul 4 07:40:38 newsfeed nnrpd[22739]: SERVER perl filter not defined
Jul 4 08:08:18 newsfeed nnrpd[23677]: SERVER perl filter not defined
Jul 4 10:22:07 newsfeed nnrpd[28142]: SERVER perl filter not defined
Jul 4 11:50:07 newsfeed pgpverify[31199]: pgpverify: verification failed
Jul 4 12:25:12 newsfeed nnrpd[32467]: SERVER perl filter not defined
Jul 4 15:51:37 newsfeed nnrpd[6890]: SERVER perl filter not defined
Jul 4 15:54:15 newsfeed nnrpd[7056]: SERVER perl filter not defined
Jul 4 16:19:58 newsfeed nnrpd[7869]: SERVER perl filter not defined
Jul 4 16:39:55 newsfeed nnrpd[8532]: SERVER perl filter not defined
Jul 4 17:59:33 newsfeed nnrpd[11202]: SERVER perl filter not defined
Jul 4 20:07:05 newsfeed nnrpd[15576]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1844851	96.9%	208.4 MB	96.4%
innd	25722	1.4%	4.0 MB	1.8%
innfeed	25530	1.3%	3.0 MB	1.4%
nnrpd	7584	0.4%	826.1 KB	0.4%
controlchan	8	0.0%	0.8 KB	0.0%
pgpverify	6	0.0%	1.0 KB	0.0%
TOTAL: 6	1903701	100.0%	216.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1838753	95.6%
Negative hits	67194	3.5%
Positive hits	17498	0.9%
Cache misses	351	0.0%
TOTAL: 4	1923796	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.047	0.0%	321	0.000	31.299	586.000
article cleanup	00:00:30.881	0.0%	1843593	0.005	0.017	0.031
article logging	00:00:51.334	0.1%	1843747	0.011	0.028	0.429
article parse	01:21:33.977	5.7%	194292850	0.007	0.025	0.058
article write	00:21:15.191	1.5%	1828880	0.353	0.697	2.164
artlog/artcncl	00:00:00.010	0.0%	320	0.000	0.031	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:05:12.396	0.4%	206838966	0.000	0.002	0.059
hisgrep/artcncl	00:00:01.150	0.0%	228	0.000	5.044	168.000
hishave/artcncl	00:00:01.724	0.0%	321	0.000	5.371	80.750
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:41:05.032	11.2%	1924030	0.014	5.023	12.424
history sync	00:00:00.011	0.0%	290	0.000	0.038	1.000
history write	04:21:20.428	18.2%	1839127	0.694	8.526	37.162
hiswrite/artcncl	00:00:00.750	0.0%	93	0.000	8.065	75.333
idle	08:38:57.508	36.0%	131746139	0.160	0.236	1.059
nntp read	01:11:41.864	5.0%	192836714	0.009	0.022	0.054
overview write	00:00:11.251	0.0%	1828880	0.000	0.006	0.015
perl filter	04:27:28.828	18.6%	1839126	5.756	8.726	12.462
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:33.490	0.2%	3713702	0.037	0.057	0.305
TOTAL: 23:59:38.853	23:13:55.872	96.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.196	0.0%	58725	0.000	0.003	0.125
article prepare	00:00:00.018	0.0%	51088	0.000	0.000	0.125
article read	00:00:20.571	0.0%	12405	0.000	1.658	110.000
backlog stats	00:00:00.289	0.0%	394195	0.000	0.001	0.014
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.287	0.0%	208554	0.000	0.011	0.060
data write	00:00:14.115	0.0%	197734	0.000	0.071	51.000
idle	23:57:53.664	99.9%	394195	0.000	218.860	13916.000
status file	00:01:07.313	0.1%	8148	0.000	8.261	147.333
TOTAL: 23:59:50.191	23:59:38.453	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:37:48.960	99.7%	7967	0.000	1640.387	450059.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.385	0.0%	17891	0.000	0.022	1.111
readart	00:00:01.035	0.0%	475	0.000	2.179	34.077
TOTAL: 03:38:28.386	03:37:50.380	99.7%	-	-	-	-

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
admin@openwatcom.news-admin.org	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	776	1839385	1825675	815	12895	99%	283:30:28
2	news.nask.pl	3	18118	4096	12228	1794	22%	24:09:26
3	localhost	13	12	12	0	0	100%	00:00:02
4	news.icm.edu.pl	50	213	9	167	37	4%	16:43:03
TOTAL: 4		842	1857728	1829792	13210	14726	98%	324:22:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	653.3 GB	14.1 MB	1.5 GB	654.7 GB	99%	373.4 KB
2	news.nask.pl	17.4 MB	4.9 MB	5.0 MB	27.3 MB	63%	4.7 KB
3	news.icm.edu.pl	605.6 KB	0.0 KB	518.7 KB	1.1 MB	53%	24.4 KB
4	localhost	17.7 KB	0.0 KB	0.0 KB	17.7 KB	100%	1.5 KB
TOTAL: 4		653.3 GB	19.0 MB	1.5 GB	654.8 GB	99%	372.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 4 04:15:02 - 04:59:59	48494	2.7%	17.97	17.2 GB	2.6%	6703.26
Jul 4 05:00:00 - 05:59:59	92872	5.1%	25.80	32.1 GB	4.9%	9341.15
Jul 4 06:00:00 - 06:59:59	73601	4.0%	20.44	26.9 GB	4.1%	7844.11
Jul 4 07:00:00 - 07:59:59	78820	4.3%	21.89	27.7 GB	4.2%	8069.23
Jul 4 08:00:00 - 08:59:59	78682	4.3%	21.86	26.8 GB	4.1%	7796.76
Jul 4 09:00:00 - 09:59:59	74465	4.1%	20.68	26.1 GB	4.0%	7603.14
Jul 4 10:00:00 - 10:59:59	88344	4.8%	24.54	31.8 GB	4.9%	9250.88
Jul 4 11:00:00 - 11:59:59	62869	3.4%	17.46	22.5 GB	3.4%	6552.21
Jul 4 12:00:00 - 12:59:59	62840	3.4%	17.46	24.3 GB	3.7%	7065.29
Jul 4 13:00:00 - 13:59:59	60510	3.3%	16.81	22.2 GB	3.4%	6478.29
Jul 4 14:00:00 - 14:59:59	79874	4.4%	22.19	28.6 GB	4.4%	8319.56
Jul 4 15:00:00 - 15:59:59	88699	4.8%	24.64	31.4 GB	4.8%	9132.46
Jul 4 16:00:00 - 16:59:59	75900	4.1%	21.08	27.2 GB	4.2%	7918.48
Jul 4 17:00:00 - 17:59:59	62497	3.4%	17.36	23.2 GB	3.6%	6767.24
Jul 4 18:00:00 - 18:59:59	75862	4.1%	21.07	28.4 GB	4.3%	8271.29
Jul 4 19:00:00 - 19:59:59	72159	3.9%	20.04	25.3 GB	3.9%	7362.02
Jul 4 20:00:00 - 20:59:59	93228	5.1%	25.90	32.7 GB	5.0%	9521.65
Jul 4 21:00:00 - 21:59:59	89667	4.9%	24.91	31.5 GB	4.8%	9177.21
Jul 4 22:00:00 - 22:59:59	84685	4.6%	23.52	28.6 GB	4.4%	8342.07
Jul 4 23:00:00 - 23:59:59	76360	4.2%	21.21	25.3 GB	3.9%	7373.44
Jul 5 00:00:00 - 00:59:59	67970	3.7%	18.88	25.2 GB	3.9%	7329.92
Jul 5 01:00:00 - 01:59:59	85713	4.7%	23.81	31.1 GB	4.8%	9063.43
Jul 5 02:00:00 - 02:59:59	65930	3.6%	18.31	25.0 GB	3.8%	7269.46
Jul 5 03:00:00 - 03:59:59	67192	3.7%	18.66	24.9 GB	3.8%	7252.84
Jul 5 04:00:00 - 04:15:03	22427	1.2%	24.84	7.4 GB	1.1%	8631.82
TOTAL: 24:00:01	1829660	100.0%	21.18	653.3 GB	100.0%	7928.70

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.pionier.net.pl	13027	216	2701	10110
2	news.nask.pl	1807	9	1769	29
3	news.icm.edu.pl	37	0	0	37
TOTAL: 3		14871	225	4470	10176

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	113
uk.rec.cycling.moderated	31
it.tlc.cellulari.android	22
comp.mobile.android	13
comp.sys.raspberry-pi	11
it.comp.os.win.windows7	7
hr.alt.cellular.gsm.android	6
it.comp.os.win.windows8	4
it.discussioni.energia	3
hr.ponuda.hardver.periferije	2
de.comp.office-pakete.ms-office.excel	2
hr.alt.cellular.gsm.iphone	2
it.comp.virtualizzazione	1
de.rec.sf.startrek	1
alt.sex.telephones	1
de.comp.lang.vbclassic	1
it.comp.os.linux.ubuntu	1
hr.rec.glazba.hifi	1
de.comm.software.mailreader	1
hr.ponuda.hardver.komponente	1
TOTAL: 21	225

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	9977
EMP (md5)	36
Scoring filter (11)	3
Too many newsgroups	2
HTML post	1
Scoring filter (8)	1
Scoring filter (12)	1
TOTAL: 7	10021

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	53955	3361	26111	6817	8	91	6%	24:00:00
2	panorama	59205	1371	54317	257	0	0	2%	24:02:00
3	nask	24060	825	14488	47	0	0	3%	24:01:17
4	poznan	5317	55	3774	25	0	0	1%	23:57:21
5	okapi	0	0	0	0	0	2370	0%	24:00:00
6	task	2693	0	0	0	0	3505	0%	24:00:04
TOTAL: 6		145230	5612	98690	7146	8	5966	3%	144:00:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	302.1 MB	2.4 GB	2.7 GB	32.2 KB/s	273.5 KB	24:00:00
2	nask	137.0 MB	22.4 MB	159.3 MB	1.9 KB/s	187.1 KB	24:01:17
3	panorama	5.1 MB	1.1 MB	6.3 MB	0.1 KB/s	3.9 KB	24:02:00
4	poznan	179.6 KB	124.5 KB	304.0 KB	0.0 KB/s	3.8 KB	23:57:21
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
6	task	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
TOTAL: 6		444.4 MB	2.4 GB	2.8 GB	5.7 KB/s	231.5 KB	144:00:42

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	pc-skrzep2.cm.p.lodz.pl	288	432	617.5 KB	288	0	00:00:13
2	web.man.lodz.pl	288	42	32.3 KB	21	0	00:00:26
3	ip-176.105.135.22.tvsat364.lodz.pl	2	1	1.6 KB	1	0	00:25:27
4	isvcs01.man.lodz.pl	12	0	0.0 KB	0	12	00:00:03
TOTAL: 4		590	475	651.5 KB	310	12	03:38:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	432	617.5 KB	288	0	00:00:13
2	*.man.lodz.pl	685	42	32.3 KB	21	12	00:30:36
3	*.105.135.22.tvsat364.lodz.pl	2	1	1.6 KB	1	0	00:25:27
4	*.pa.nsw.optusnet.com.au	120	0	0.0 KB	0	0	00:03:19
5	unresolved	3	0	0.0 KB	0	0	00:01:07
6	*.wroclaw.mm.pl	16	0	0.0 KB	0	0	02:30:52
7	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	00:06:02
8	*.mofnet.gov.pl	1	0	0.0 KB	0	0	00:00:41
9	*.toya.net.pl	30	0	0.0 KB	0	0	00:00:03
TOTAL: 9		1147	475	651.5 KB	310	12	03:38:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.036	0.160	02:30:52
pc-vicki.man.lodz.pl	0.008	0.008	00:30:00
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.028	00:25:27
4.191.14.62.static.jazztel.es	0.188	0.288	00:06:02
pa49-181-246-51.pa.nsw.optusnet.com.au	0.268	1.196	00:03:19
194.33.77.50	0.004	0.016	00:00:52
ip-34-4.mofnet.gov.pl	0.008	0.008	00:00:41
web.man.lodz.pl	0.660	3.180	00:00:26
212.51.203.199	0.000	0.028	00:00:14
pc-skrzep2.cm.p.lodz.pl	0.668	3.076	00:00:13
mon.man.lodz.pl	0.608	2.696	00:00:04
staticline41435.toya.net.pl	0.052	0.316	00:00:03
isvcs01.man.lodz.pl	0.072	0.160	00:00:03
david.man.lodz.pl	0.272	0.868	00:00:01
TOTAL: 14	2.844	12.028	03:38:26

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-246-51.pa.nsw.optusnet.com.au	120
david.man.lodz.pl	96
staticline41435.toya.net.pl	30
host-89-231-50-15.wroclaw.mm.pl	16
4.191.14.62.static.jazztel.es	2
212.51.203.199	2
ip-34-4.mofnet.gov.pl	1
pc-vicki.man.lodz.pl	1
194.33.77.50	1
TOTAL: 10	557

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-vicki.man.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	432	90.9%
2	pl	43	9.1%
TOTAL: 2		475	100.0%

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

Newsgroup		Count
1	lodman.info	432
2	pl.comp.os.ms-windows.winnt	30
3	pl.comp.pecet	12
4	pl.regionalne.lodz	1
TOTAL: 4		475

Daily Usenet report

Jul 4 04:15:02 -- Jul 5 04:15:03