Daily Usenet report: Nov 13 04:15:01

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Nov 13 10:01:10 newsfeed nnrpd[32339]: SERVER perl filter not defined
Nov 13 10:28:12 newsfeed nnrpd[828]: SERVER perl filter not defined
Nov 13 10:30:45 newsfeed nnrpd[827]: SERVER perl filter not defined
Nov 13 10:31:53 newsfeed nnrpd[831]: SERVER perl filter not defined
Nov 13 10:33:28 newsfeed nnrpd[1068]: SERVER perl filter not defined
Nov 13 10:39:41 newsfeed nnrpd[1169]: SERVER perl filter not defined
Nov 13 10:40:18 newsfeed nnrpd[1131]: SERVER perl filter not defined
Nov 13 10:50:15 newsfeed nnrpd[1407]: SERVER perl filter not defined
Nov 13 11:03:33 newsfeed nnrpd[1844]: SERVER perl filter not defined
Nov 13 11:45:41 newsfeed nnrpd[3512]: SERVER perl filter not defined
Nov 13 11:59:51 newsfeed nnrpd[3946]: SERVER perl filter not defined
Nov 13 13:42:26 newsfeed nnrpd[7507]: SERVER perl filter not defined
Nov 13 14:56:18 newsfeed nnrpd[9885]: SERVER perl filter not defined
Nov 13 15:37:32 newsfeed nnrpd[11256]: SERVER perl filter not defined
Nov 13 15:41:22 newsfeed nnrpd[11315]: SERVER perl filter not defined
Nov 13 15:43:32 newsfeed nnrpd[11494]: SERVER perl filter not defined
Nov 13 16:20:47 newsfeed nnrpd[12648]: SERVER perl filter not defined
Nov 13 16:55:40 newsfeed nnrpd[13769]: SERVER perl filter not defined
Nov 13 17:14:21 newsfeed nnrpd[14485]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1510779	96.9%	174.9 MB	96.4%
innd	34302	2.2%	4.8 MB	2.6%
nnrpd	7978	0.5%	850.6 KB	0.5%
innfeed	6745	0.4%	982.5 KB	0.5%
TOTAL: 4	1559804	100.0%	181.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1506998	91.8%
Negative hits	117164	7.1%
Positive hits	18010	1.1%
Cache misses	202	0.0%
TOTAL: 4	1642374	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.623	0.0%	267	0.000	28.551	564.000
article cleanup	00:00:24.263	0.0%	1510705	0.003	0.016	0.032
article logging	00:00:40.506	0.0%	1510919	0.009	0.027	0.761
article parse	00:48:27.952	3.4%	147770046	0.007	0.020	0.038
article write	00:14:16.216	1.0%	1486325	0.250	0.576	2.550
artlog/artcncl	00:00:00.004	0.0%	263	0.000	0.015	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:06.961	0.1%	157146397	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.641	0.0%	245	0.000	6.698	130.000
hishave/artcncl	00:00:00.283	0.0%	267	0.000	1.060	96.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:53:21.887	3.7%	1642832	0.051	1.949	7.534
history sync	00:00:00.012	0.0%	291	0.000	0.041	2.000
history write	02:33:24.218	10.7%	1507554	2.877	6.105	22.754
hiswrite/artcncl	00:00:00.028	0.0%	22	0.000	1.273	12.000
idle	14:59:25.996	62.4%	106689992	0.327	0.506	1.721
nntp read	00:48:44.593	3.4%	146543839	0.009	0.020	0.035
overview write	00:00:08.765	0.0%	1486325	0.000	0.006	0.017
perl filter	03:01:01.132	12.6%	1507552	4.708	7.204	9.621
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:45.308	0.2%	3017840	0.015	0.055	0.087
TOTAL: 24:00:19.046	23:24:57.388	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.139	0.0%	45220	0.000	0.003	0.111
article prepare	00:00:00.005	0.0%	33086	0.000	0.000	0.083
article read	00:00:02.291	0.0%	8155	0.000	0.281	73.750
backlog stats	00:00:00.155	0.0%	281090	0.000	0.001	0.020
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.649	0.0%	174562	0.000	0.009	0.079
data write	00:00:06.664	0.0%	141184	0.000	0.047	21.000
idle	23:59:36.459	100.0%	281090	0.000	307.291	15029.000
status file	00:00:00.241	0.0%	198	0.000	1.217	19.667
TOTAL: 23:59:53.315	23:59:47.603	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	13:14:33.372	99.9%	17125	0.000	2783.847	366516.800
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:34.477	0.1%	580642	0.000	0.059	39.082
readart	00:00:02.395	0.0%	702	0.000	3.412	31.000
TOTAL: 13:15:41.441	13:15:10.244	99.9%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	574	1507107	1482585	809	23713	98%	259:43:36
2	news.nask.pl	2	18256	3776	13906	574	20%	24:11:27
3	news.icm.edu.pl	37	316	48	166	102	15%	16:01:33
4	localhost	20	19	19	0	0	100%	00:00:00
TOTAL: 4		633	1525698	1486428	14881	24389	97%	299:56:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	427.9 GB	28.4 MB	2.9 GB	430.9 GB	99%	299.9 KB
2	news.nask.pl	18.7 MB	1.4 MB	1.9 MB	22.1 MB	84%	5.2 KB
3	news.icm.edu.pl	1.6 MB	199.5 KB	1.9 MB	3.7 MB	43%	25.4 KB
4	localhost	32.4 KB	0.0 KB	0.0 KB	32.4 KB	100%	1.7 KB
TOTAL: 4		428.0 GB	30.1 MB	2.9 GB	430.9 GB	99%	299.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 13 04:15:01 - 04:59:59	42648	2.9%	15.80	13.8 GB	3.2%	5356.48
Nov 13 05:00:00 - 05:59:59	67455	4.5%	18.74	21.7 GB	5.1%	6311.05
Nov 13 06:00:00 - 06:59:59	65197	4.4%	18.11	19.8 GB	4.6%	5781.03
Nov 13 07:00:00 - 07:59:59	59051	4.0%	16.40	20.1 GB	4.7%	5862.19
Nov 13 08:00:00 - 08:59:59	64376	4.3%	17.88	22.0 GB	5.1%	6400.15
Nov 13 09:00:00 - 09:59:59	59519	4.0%	16.53	16.6 GB	3.9%	4828.12
Nov 13 10:00:00 - 10:59:59	70776	4.8%	19.66	18.7 GB	4.4%	5446.54
Nov 13 11:00:00 - 11:59:59	61849	4.2%	17.18	18.8 GB	4.4%	5479.99
Nov 13 12:00:00 - 12:59:59	64696	4.4%	17.97	19.5 GB	4.6%	5671.79
Nov 13 13:00:00 - 13:59:59	79935	5.4%	22.20	18.9 GB	4.4%	5496.73
Nov 13 14:00:00 - 14:59:59	56284	3.8%	15.63	16.4 GB	3.8%	4785.19
Nov 13 15:00:00 - 15:59:59	33344	2.2%	9.26	9.5 GB	2.2%	2760.22
Nov 13 16:00:00 - 16:59:59	58718	4.0%	16.31	18.9 GB	4.4%	5498.77
Nov 13 17:00:00 - 17:59:59	49759	3.3%	13.82	14.2 GB	3.3%	4129.46
Nov 13 18:00:00 - 18:59:59	76923	5.2%	21.37	21.1 GB	4.9%	6133.62
Nov 13 19:00:00 - 19:59:59	71566	4.8%	19.88	18.4 GB	4.3%	5356.78
Nov 13 20:00:00 - 20:59:59	56665	3.8%	15.74	15.4 GB	3.6%	4497.47
Nov 13 21:00:00 - 21:59:59	66543	4.5%	18.48	18.5 GB	4.3%	5377.78
Nov 13 22:00:00 - 22:59:59	83569	5.6%	23.21	22.5 GB	5.3%	6542.18
Nov 13 23:00:00 - 23:59:59	87338	5.9%	24.26	23.3 GB	5.5%	6793.67
Nov 14 00:00:00 - 00:59:59	44525	3.0%	12.37	12.3 GB	2.9%	3576.55
Nov 14 01:00:00 - 01:59:59	49446	3.3%	13.73	13.2 GB	3.1%	3850.45
Nov 14 02:00:00 - 02:59:59	46062	3.1%	12.79	12.8 GB	3.0%	3732.91
Nov 14 03:00:00 - 03:59:59	53838	3.6%	14.96	16.5 GB	3.8%	4793.80
Nov 14 04:00:00 - 04:15:04	15838	1.1%	17.52	4.8 GB	1.1%	5592.64
TOTAL: 24:00:03	1485920	100.0%	17.20	427.5 GB	100.0%	5188.43

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	23911	126	2638	2	21145
2	news.nask.pl	583	35	510	11	27
3	news.icm.edu.pl	102	0	5	0	97
TOTAL: 3		24596	161	3153	13	21269

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	39
alt.sex.telephones	28
it.tlc.cellulari.android	17
hr.alt.cellular.gsm.iphone	17
it.comp.os.win.windows7	12
comp.mobile.android	10
nl.comp.os.linux.techniek	8
comp.sys.raspberry-pi	6
de.comp.lang.vbclassic	6
rec.arts.tv.x-factor	5
de.comm.provider.telefonie	5
de.rec.sf.startrek	4
hr.ponuda.hardver.konfiguracije	2
hr.ponuda.motocikli	1
hr.ponuda.hardver.komponente	1
TOTAL: 15	161

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

Groups	Count
pl.sci.historia	7
pl.sci.matematyka	4
bit.listserv.catholic	2
TOTAL: 3	13

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	20946
EMP (md5)	105
HTML post	2
TOTAL: 3	21053

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	43103	1604	31003	3201	1	42	3%	24:01:52
2	panorama	46218	1571	43403	246	0	0	3%	24:03:33
3	nask	29328	402	16967	1205	0	0	1%	23:59:45
4	poznan	5144	37	3578	13	0	0	0%	24:00:18
5	okapi	0	0	0	0	0	2865	0%	24:00:03
TOTAL: 5		123793	3614	94951	4665	1	2907	2%	120:05:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	192.6 MB	627.6 MB	820.3 MB	9.7 KB/s	174.8 KB	24:01:52
2	nask	49.8 MB	294.0 MB	343.8 MB	4.1 KB/s	219.1 KB	23:59:45
3	panorama	7.1 MB	1.6 MB	8.7 MB	0.1 KB/s	4.9 KB	24:03:33
4	poznan	102.7 KB	37.0 KB	139.7 KB	0.0 KB/s	2.8 KB	24:00:18
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		249.7 MB	923.2 MB	1.1 GB	2.8 KB/s	145.1 KB	120:05:31

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	410	1.2 MB	288	0	0	00:00:08
2	pc-212-51-218-225.p.lodz.pl	62	101	183.9 KB	17	0	0	07:40:37
3	web.man.lodz.pl	288	90	84.4 KB	40	0	0	00:00:25
4	pc29.zsk.p.lodz.pl	23	56	48.0 KB	15	5	0	04:00:48
5	cfb249.neoplus.adsl.tpnet.pl	2	17	34.3 KB	3	0	0	00:04:01
6	212.51.203.199	2	11	18.7 KB	1	0	0	00:02:35
7	staticline-31-182-77-237.toya.net.pl	5	9	7.0 KB	2	0	0	00:09:13
8	pc23.zsk.p.lodz.pl	3	7	7.2 KB	4	2	2	00:36:53
9	wireless.ics.p.lodz.pl	1	1	1.0 KB	1	1	0	00:15:03
10	isvcs01.man.lodz.pl	11	0	0.0 KB	0	11	0	00:00:00
TOTAL: 10		685	702	1.6 MB	371	19	2	13:15:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	410	1.2 MB	288	0	0	00:00:08
2	*.p.lodz.pl	62	101	183.9 KB	17	0	0	07:40:37
3	*.man.lodz.pl	683	90	84.4 KB	40	11	0	00:00:31
4	*.zsk.p.lodz.pl	26	63	55.2 KB	19	7	2	04:37:41
5	*.neoplus.adsl.tpnet.pl	2	17	34.3 KB	3	0	0	00:04:01
6	unresolved	2	11	18.7 KB	1	0	0	00:02:35
7	*.toya.net.pl	99	9	7.0 KB	2	0	0	00:09:21
8	*.ics.p.lodz.pl	1	1	1.0 KB	1	1	0	00:15:03
9	*.elsnet.pl	18	0	0.0 KB	0	0	0	00:01:16
10	*.206.15.62.static.jazztel.es	6	0	0.0 KB	0	0	0	00:24:23
TOTAL: 10		1187	702	1.6 MB	371	19	2	13:15:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.212	0.699	07:40:37
pc29.zsk.p.lodz.pl	1.429	0.834	04:00:48
pc23.zsk.p.lodz.pl	0.778	0.258	00:36:53
252.206.15.62.static.jazztel.es	0.515	0.735	00:24:23
wireless.ics.p.lodz.pl	0.290	0.121	00:15:03
staticline-31-182-77-237.toya.net.pl	0.019	0.056	00:09:13
cfb249.neoplus.adsl.tpnet.pl	0.066	0.046	00:04:01
212.51.203.199	0.000	0.017	00:02:35
host227.elsnet.pl	0.055	0.214	00:01:16
web.man.lodz.pl	1.046	3.202	00:00:25
staticline41435.toya.net.pl	0.330	0.993	00:00:08
pc-skrzep2.cm.p.lodz.pl	0.961	3.119	00:00:08
mon.man.lodz.pl	0.827	3.103	00:00:04
david.man.lodz.pl	0.309	1.003	00:00:01
isvcs01.man.lodz.pl	0.071	0.111	00:00:00
TOTAL: 15	6.908	14.511	13:15:41

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	94
host227.elsnet.pl	18
252.206.15.62.static.jazztel.es	6
TOTAL: 5	502

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc29.zsk.p.lodz.pl	4	0
pc-212-51-218-225.p.lodz.pl	2	0
pc23.zsk.p.lodz.pl	1	0
TOTAL: 3	7	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	425	60.5%
2	pl	266	37.9%
3	lodz	11	1.6%
TOTAL: 3		702	100.0%

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

Newsgroup		Count
1	lodman.info	425
2	pl.misc.elektronika	68
3	pl.test	64
4	pl.comp.pecet	62
5	pl.misc.kolej	33
6	pl.comp.os.ms-windows.winnt	28
7	pl.rec.radio.amatorskie	11
8	lodz.test	9
9	lodz.ogloszenia	2
TOTAL: 9		702

Daily Usenet report

Nov 13 04:15:01 -- Nov 14 04:15:04