Daily Usenet report: Nov 21 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Nov 21 04:39:07 newsfeed pgpverify[22140]: pgpverify: verification failed
Nov 21 09:03:59 newsfeed nnrpd[31374]: SERVER perl filter not defined
Nov 21 09:37:38 newsfeed nnrpd[32436]: SERVER perl filter not defined
Nov 21 09:41:49 newsfeed nnrpd[32637]: SERVER perl filter not defined
Nov 21 10:44:26 newsfeed nnrpd[2250]: SERVER perl filter not defined
Nov 21 15:14:21 newsfeed nnrpd[11234]: SERVER perl filter not defined
Nov 21 16:36:20 newsfeed nnrpd[13908]: SERVER perl filter not defined
Nov 21 16:55:42 newsfeed nnrpd[14560]: SERVER perl filter not defined
Nov 21 17:00:54 newsfeed nnrpd[14820]: SERVER perl filter not defined
Nov 21 18:33:36 newsfeed nnrpd[17824]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	873176	96.7%	98.6 MB	96.0%
innd	16542	1.8%	2.5 MB	2.4%
nnrpd	7043	0.8%	739.2 KB	0.7%
innfeed	6513	0.7%	929.8 KB	0.9%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	903278	100.0%	102.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	868789	92.5%
Negative hits	52755	5.6%
Positive hits	18017	1.9%
Cache misses	167	0.0%
TOTAL: 4	939728	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.272	0.0%	318	0.000	32.302	592.000
article cleanup	00:00:13.643	0.0%	872991	0.000	0.016	0.054
article logging	00:00:24.115	0.0%	873102	0.000	0.028	0.317
article parse	00:29:39.492	2.1%	88532452	0.005	0.020	0.047
article write	00:10:43.470	0.7%	862774	0.215	0.746	3.445
artlog/artcncl	00:00:00.009	0.0%	318	0.000	0.028	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:01:18.326	0.1%	94060143	0.000	0.001	0.006
hisgrep/artcncl	00:00:02.044	0.0%	284	0.000	7.197	51.800
hishave/artcncl	00:00:00.732	0.0%	318	0.000	2.302	91.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:45:28.296	3.2%	940884	0.014	2.900	10.720
history sync	00:00:00.026	0.0%	390	0.000	0.067	2.000
history write	01:58:28.273	8.2%	870078	0.049	8.170	18.999
hiswrite/artcncl	00:00:00.261	0.0%	34	0.000	7.676	21.000
idle	17:46:38.824	74.1%	68000549	0.286	0.941	7.321
nntp read	00:30:43.205	2.1%	87873174	0.006	0.021	0.035
overview write	00:00:05.364	0.0%	862774	0.000	0.006	0.044
perl filter	01:48:36.056	7.5%	870073	3.660	7.489	12.192
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:43.772	0.1%	1774025	0.027	0.058	0.289
TOTAL: 23:59:58.201	23:34:16.180	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.169	0.0%	48508	0.000	0.003	1.000
article prepare	00:00:00.011	0.0%	28128	0.000	0.000	0.250
article read	00:00:00.856	0.0%	6993	0.000	0.122	2.000
backlog stats	00:00:00.143	0.0%	279759	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.627	0.0%	175727	0.000	0.009	1.000
data write	00:00:09.493	0.0%	134282	0.000	0.071	20.429
idle	24:00:09.112	100.0%	279759	0.000	308.870	16425.000
status file	00:00:00.543	0.0%	192	0.000	2.828	102.750
TOTAL: 24:00:27.601	24:00:21.954	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:05:00.841	99.8%	23441	0.000	473.565	363986.600
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.839	0.0%	48864	0.000	0.017	2.000
readart	00:00:01.170	0.0%	566	0.000	2.067	21.929
TOTAL: 03:05:20.574	03:05:02.850	99.8%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
nl-admin@nic.surfnet.nl	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	362	868948	859368	343	9237	98%	193:37:16
2	news.nask.pl	2	17774	2643	14162	969	14%	23:17:16
3	news.icm.edu.pl	44	360	93	258	9	25%	17:16:08
4	localhost	10	9	9	0	0	100%	00:00:02
TOTAL: 4		418	887091	862113	14763	10215	97%	234:10:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	261.1 GB	24.9 MB	906.9 MB	262.1 GB	99%	316.3 KB
2	news.nask.pl	16.9 MB	2.7 MB	2.9 MB	22.6 MB	74%	6.4 KB
3	news.icm.edu.pl	7.4 MB	156.8 KB	273.8 KB	7.8 MB	94%	78.7 KB
4	localhost	16.9 KB	0.0 KB	0.0 KB	16.9 KB	100%	1.9 KB
TOTAL: 4		261.2 GB	27.7 MB	910.1 MB	262.1 GB	99%	315.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 21 04:15:02 - 04:59:59	56663	6.6%	21.00	18.3 GB	7.0%	7096.91
Nov 21 05:00:00 - 05:59:59	52370	6.1%	14.55	17.8 GB	6.8%	5181.93
Nov 21 06:00:00 - 06:59:59	36745	4.3%	10.21	13.1 GB	5.0%	3812.47
Nov 21 07:00:00 - 07:59:59	40127	4.7%	11.15	14.8 GB	5.7%	4324.50
Nov 21 08:00:00 - 08:59:59	60375	7.0%	16.77	20.7 GB	7.9%	6032.70
Nov 21 09:00:00 - 09:59:59	68278	7.9%	18.97	23.3 GB	8.9%	6772.91
Nov 21 10:00:00 - 10:59:59	30058	3.5%	8.35	9.5 GB	3.6%	2758.80
Nov 21 11:00:00 - 11:59:59	10498	1.2%	2.92	2.2 GB	0.8%	646.71
Nov 21 12:00:00 - 12:59:59	9562	1.1%	2.66	2.2 GB	0.8%	628.95
Nov 21 13:00:00 - 13:59:59	9784	1.1%	2.72	2.0 GB	0.8%	588.65
Nov 21 14:00:00 - 14:59:59	10557	1.2%	2.93	2.4 GB	0.9%	711.41
Nov 21 15:00:00 - 15:59:59	10273	1.2%	2.85	2.3 GB	0.9%	681.23
Nov 21 16:00:00 - 16:59:59	12945	1.5%	3.60	2.9 GB	1.1%	844.27
Nov 21 17:00:00 - 17:59:59	12617	1.5%	3.50	3.0 GB	1.1%	871.33
Nov 21 18:00:00 - 18:59:59	12466	1.4%	3.46	3.0 GB	1.1%	870.64
Nov 21 19:00:00 - 19:59:59	37820	4.4%	10.51	12.3 GB	4.7%	3582.01
Nov 21 20:00:00 - 20:59:59	47318	5.5%	13.14	13.7 GB	5.3%	4000.02
Nov 21 21:00:00 - 21:59:59	47921	5.6%	13.31	13.1 GB	5.0%	3803.77
Nov 21 22:00:00 - 22:59:59	50146	5.8%	13.93	13.3 GB	5.1%	3870.51
Nov 21 23:00:00 - 23:59:59	51135	5.9%	14.20	14.3 GB	5.5%	4154.67
Nov 22 00:00:00 - 00:59:59	40203	4.7%	11.17	11.1 GB	4.3%	3238.44
Nov 22 01:00:00 - 01:59:59	44932	5.2%	12.48	12.9 GB	5.0%	3768.28
Nov 22 02:00:00 - 02:59:59	49875	5.8%	13.85	14.6 GB	5.6%	4248.93
Nov 22 03:00:00 - 03:59:59	55921	6.5%	15.53	17.4 GB	6.6%	5059.81
Nov 22 04:00:00 - 04:15:02	3937	0.5%	4.36	1.1 GB	0.4%	1291.95
TOTAL: 24:00:00	862526	100.0%	9.98	261.3 GB	100.0%	3170.64

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9348	319	1994	0	7035
2	news.nask.pl	975	49	916	1	9
3	news.icm.edu.pl	9	0	7	0	2
TOTAL: 3		10332	368	2917	1	7046

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	149
it.tlc.cellulari.android	41
alt.sex.telephones	37
comp.mobile.android	31
alt.windows7.general	22
it.comp.os.win.windows8	18
comp.mobile.ipad	12
de.comp.office-pakete.ms-office.excel	10
it.comp.os.win.windows7	8
hr.ponuda.hardver.komponente	8
de.comm.software.mailreader	6
nl.comp.os.linux.techniek	6
de.comp.lang.vbclassic	4
de.rec.sf.startrek	3
it.tlc.cellulari.iphone	2
rec.arts.tv.x-factor	2
hr.ponuda.hardver.periferije	2
de.comm.provider.telefonie	2
hr.alt.cellular.gsm.iphone	2
de.comp.office-pakete.ms-office.word	1
TOTAL: 22	368

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

Groups	Count
pl.sci.historia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	6921
HTML post	6
EMP (md5)	2
Scoring filter (8)	1
TOTAL: 4	6930

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	43313	4000	29129	866	25	9%	23:56:43
2	panorama	49097	1108	47161	169	0	2%	23:53:32
3	nask	26942	433	16374	472	0	1%	23:59:44
4	poznan	3417	8	2506	1	0	0%	23:50:18
5	okapi	0	0	0	0	2831	0%	24:00:00
TOTAL: 5		122769	5549	95170	1508	2856	4%	119:40:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	686.7 MB	228.0 MB	914.7 MB	10.9 KB/s	192.5 KB	23:56:43
2	nask	59.5 MB	112.5 MB	172.0 MB	2.0 KB/s	194.6 KB	23:59:44
3	panorama	4.7 MB	752.0 KB	5.5 MB	0.1 KB/s	4.4 KB	23:53:32
4	poznan	26.0 KB	1.6 KB	27.7 KB	0.0 KB/s	3.1 KB	23:50:18
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		751.0 MB	341.2 MB	1.1 GB	2.6 KB/s	158.5 KB	119:40:17

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	396	821.8 KB	288	0	00:00:07
2	web.man.lodz.pl	288	114	566.1 KB	48	0	00:00:23
3	lze200.lze.lodz.pl	10	56	102.3 KB	11	0	01:47:09
4	isvcs01.man.lodz.pl	9	0	0.0 KB	0	9	00:00:00
TOTAL: 4		595	566	1.5 MB	347	9	03:05:20

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	396	821.8 KB	288	0	00:00:07
2	*.man.lodz.pl	681	114	566.1 KB	48	9	00:00:30
3	*.lze.lodz.pl	10	56	102.3 KB	11	0	01:47:09
4	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:12
5	unresolved	1	0	0.0 KB	0	0	00:30:20
6	*.206.15.62.static.jazztel.es	3	0	0.0 KB	0	0	00:47:00
7	*.toya.net.pl	2	0	0.0 KB	0	0	00:00:00
TOTAL: 7		989	566	1.5 MB	347	9	03:05:20

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.055	0.135	01:47:09
252.206.15.62.static.jazztel.es	0.773	1.127	00:47:00
212.191.172.130	0.010	0.007	00:30:20
web.man.lodz.pl	1.132	3.439	00:00:23
host227.elsnet.pl	0.017	0.045	00:00:12
pc-skrzep2.cm.p.lodz.pl	1.000	3.467	00:00:07
mon.man.lodz.pl	0.961	3.293	00:00:04
david.man.lodz.pl	0.298	1.079	00:00:01
isvcs01.man.lodz.pl	0.064	0.106	00:00:00
staticline41435.toya.net.pl	0.010	0.020	00:00:00
TOTAL: 10	4.320	12.718	03:05:20

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
host227.elsnet.pl	4
252.206.15.62.static.jazztel.es	3
staticline41435.toya.net.pl	2
212.191.172.130	1
TOTAL: 6	394

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	1
212.191.172.130	1	0
TOTAL: 2	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	396	70.0%
2	pl	170	30.0%
TOTAL: 2		566	100.0%

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

Newsgroup		Count
1	lodman.info	396
2	pl.comp.pecet	110
3	pl.rec.kuchnia	19
4	pl.comp.os.ms-windows.winnt	15
5	pl.comp.os.linux	14
6	pl.misc.telefonia.gsm	8
7	pl.internet.polip	4
TOTAL: 7		566

Daily Usenet report

Nov 21 04:15:02 -- Nov 22 04:15:02