Daily Usenet report: Nov 2 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Nov 2 14:28:41 newsfeed nnrpd[13793]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2167097	97.6%	245.0 MB	97.1%
innfeed	25512	1.1%	3.0 MB	1.2%
innd	23243	1.0%	3.7 MB	1.5%
nnrpd	5073	0.2%	539.6 KB	0.2%
TOTAL: 4	2220925	100.0%	252.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2162707	98.1%
Negative hits	24228	1.1%
Positive hits	17539	0.8%
Cache misses	104	0.0%
TOTAL: 4	2204578	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.755	0.0%	536	0.000	12.603	382.000
article cleanup	00:00:35.143	0.0%	2166104	0.002	0.016	0.038
article logging	00:01:04.313	0.1%	2166566	0.014	0.030	0.185
article parse	00:59:21.312	4.1%	267230936	0.006	0.013	0.041
article write	00:17:53.065	1.2%	2156634	0.286	0.498	1.640
artlog/artcncl	00:00:00.012	0.0%	523	0.000	0.023	1.000
artlog/artparse	00:00:00.001	0.0%	1	1.000	1.000	1.000
data move	00:04:39.693	0.3%	281685159	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.299	0.0%	187	0.000	1.599	32.000
hishave/artcncl	00:00:01.435	0.0%	536	0.000	2.677	18.286
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:38:51.957	2.7%	2204522	0.132	1.058	24.897
history sync	00:00:00.011	0.0%	291	0.000	0.038	1.000
history write	00:53:16.662	3.7%	2162786	0.043	1.478	29.211
hiswrite/artcncl	00:00:01.966	0.0%	349	0.000	5.633	19.000
idle	14:38:23.892	61.0%	205513359	0.078	0.256	2.006
nntp read	01:21:30.668	5.7%	265554609	0.009	0.018	0.050
overview write	00:00:12.659	0.0%	2156634	0.000	0.006	0.015
perl filter	03:58:08.533	16.5%	2162785	3.719	6.607	10.874
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:24.560	0.3%	4349831	0.037	0.061	0.099
TOTAL: 24:00:13.694	22:58:32.936	95.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.190	0.0%	60924	0.000	0.003	0.111
article prepare	00:00:00.008	0.0%	26226	0.000	0.000	0.031
article read	00:00:33.801	0.0%	6548	0.000	5.162	956.500
backlog stats	00:00:00.167	0.0%	201526	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.198	0.0%	108432	0.000	0.011	0.087
data write	00:00:04.726	0.0%	73705	0.000	0.064	0.938
idle	23:59:28.307	99.9%	201526	52.813	428.572	2406.731
status file	00:00:05.833	0.0%	7767	0.000	0.751	29.667
TOTAL: 24:00:21.702	24:00:14.230	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:58:17.073	99.9%	3511	0.000	4072.080	450055.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.133	0.0%	8342	0.000	0.016	0.667
readart	00:00:05.908	0.0%	312	0.000	18.936	426.000
TOTAL: 03:58:35.418	03:58:23.114	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	815	2162340	2152873	378	9089	99%	259:25:35
2	news.nask.pl	2	17339	3596	13415	328	20%	23:45:32
3	news.icm.edu.pl	44	346	33	211	102	9%	19:28:26
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		863	2180026	2156503	14004	9519	98%	302:39:33

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	659.1 GB	44.8 MB	1.4 GB	660.5 GB	99%	320.4 KB
2	news.nask.pl	14.3 MB	829.6 KB	913.8 KB	16.0 MB	89%	4.2 KB
3	news.icm.edu.pl	657.3 KB	59.7 KB	2.3 MB	3.0 MB	21%	23.1 KB
4	localhost	2.2 KB	0.0 KB	0.0 KB	2.2 KB	100%	2.2 KB
TOTAL: 4		659.1 GB	45.7 MB	1.4 GB	660.6 GB	99%	319.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 2 04:15:02 - 04:59:59	56067	2.6%	20.78	20.3 GB	3.1%	7893.28
Nov 2 05:00:00 - 05:59:59	74130	3.4%	20.59	26.5 GB	4.0%	7716.48
Nov 2 06:00:00 - 06:59:59	79980	3.7%	22.22	24.2 GB	3.7%	7034.22
Nov 2 07:00:00 - 07:59:59	113850	5.3%	31.62	33.8 GB	5.1%	9851.35
Nov 2 08:00:00 - 08:59:59	113837	5.3%	31.62	32.2 GB	4.9%	9369.62
Nov 2 09:00:00 - 09:59:59	116777	5.4%	32.44	32.6 GB	5.0%	9500.73
Nov 2 10:00:00 - 10:59:59	104399	4.8%	29.00	30.4 GB	4.6%	8855.05
Nov 2 11:00:00 - 11:59:59	69110	3.2%	19.20	19.8 GB	3.0%	5777.83
Nov 2 12:00:00 - 12:59:59	70578	3.3%	19.61	20.4 GB	3.1%	5947.41
Nov 2 13:00:00 - 13:59:59	102398	4.7%	28.44	30.1 GB	4.6%	8762.28
Nov 2 14:00:00 - 14:59:59	101606	4.7%	28.22	30.3 GB	4.6%	8824.12
Nov 2 15:00:00 - 15:59:59	102014	4.7%	28.34	30.6 GB	4.6%	8912.48
Nov 2 16:00:00 - 16:59:59	90649	4.2%	25.18	28.1 GB	4.3%	8172.97
Nov 2 17:00:00 - 17:59:59	97286	4.5%	27.02	29.9 GB	4.5%	8696.57
Nov 2 18:00:00 - 18:59:59	99166	4.6%	27.55	32.0 GB	4.9%	9334.07
Nov 2 19:00:00 - 19:59:59	72896	3.4%	20.25	20.3 GB	3.1%	5908.07
Nov 2 20:00:00 - 20:59:59	62017	2.9%	17.23	19.0 GB	2.9%	5535.62
Nov 2 21:00:00 - 21:59:59	91191	4.2%	25.33	27.5 GB	4.2%	8020.83
Nov 2 22:00:00 - 22:59:59	103025	4.8%	28.62	29.5 GB	4.5%	8595.92
Nov 2 23:00:00 - 23:59:59	73778	3.4%	20.49	23.0 GB	3.5%	6696.11
Nov 3 00:00:00 - 00:59:59	73439	3.4%	20.40	24.4 GB	3.7%	7096.59
Nov 3 01:00:00 - 01:59:59	100411	4.7%	27.89	33.3 GB	5.1%	9694.41
Nov 3 02:00:00 - 02:59:59	89975	4.2%	24.99	28.5 GB	4.3%	8294.70
Nov 3 03:00:00 - 03:59:59	80683	3.7%	22.41	26.3 GB	4.0%	7662.95
Nov 3 04:00:00 - 04:15:01	17379	0.8%	19.29	6.0 GB	0.9%	6998.40
TOTAL: 23:59:59	2156641	100.0%	24.96	658.9 GB	100.0%	7997.07

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9505	276	3017	1	6211
2	news.nask.pl	375	18	300	4	53
3	news.icm.edu.pl	53	0	2	0	51
TOTAL: 3		9933	294	3319	5	6315

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.comp.os.win.windows10	95
uk.railway	67
comp.mobile.ipad	35
comp.mobile.android	35
it.tlc.cellulari.android	14
nl.comp.os.linux.techniek	9
it.comp.os.win.windows7	8
nl.comp.sys.android	6
uk.rec.cycling.moderated	6
hr.alt.cellular.gsm.android	5
de.rec.sf.startrek	3
rec.arts.tv.x-factor	2
de.comm.provider.telefonie	2
it.comp.os.win.windows8	2
hr.alt.cellular.gsm.iphone	2
de.comm.software.mailreader	1
alt.sex.telephones	1
de.comp.lang.vbclassic	1
TOTAL: 18	294

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

Groups	Count
pl.sci.matematyka	5
TOTAL: 1	5

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	5590
Binary in non-binary group	203
EMP (md5)	46
HTML post	6
Too many newsgroups	6
Scoring filter (10)	1
TOTAL: 6	5852

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	49030	1895	27515	2754	0	10	3%	24:05:53
2	panorama	24386	895	10293	423	5	49295	3%	24:00:01
3	poznan	5604	79	3522	36	0	0	1%	23:56:22
4	okapi	0	0	0	0	0	2524	0%	24:00:00
5	nask	0	0	0	0	0	14272	0%	24:00:00
TOTAL: 5		79020	2869	41330	3213	5	66101	3%	120:02:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	266.0 MB	641.7 MB	907.7 MB	10.7 KB/s	199.9 KB	24:05:53
2	panorama	2.9 MB	3.1 MB	6.0 MB	0.1 KB/s	4.7 KB	24:00:01
3	poznan	293.8 KB	76.4 KB	370.3 KB	0.0 KB/s	3.2 KB	23:56:22
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		269.2 MB	644.9 MB	914.1 MB	2.2 KB/s	153.9 KB	120:02:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	15	207	410.8 KB	13	0	02:57:31
2	lze200.lze.lodz.pl	5	61	128.6 KB	6	0	00:28:31
3	web.man.lodz.pl	287	44	43.3 KB	20	0	00:00:23
4	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 4		308	312	582.7 KB	39	1	03:58:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	15	207	410.8 KB	13	0	02:57:31
2	*.lze.lodz.pl	5	61	128.6 KB	6	0	00:28:31
3	*.man.lodz.pl	673	44	43.3 KB	20	1	00:30:28
4	*.toya.net.pl	37	0	0.0 KB	0	0	00:00:02
5	*.elsnet.pl	6	0	0.0 KB	0	0	00:02:02
TOTAL: 5		736	312	582.7 KB	39	1	03:58:35

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.073	0.183	02:57:31
pc-vicki.man.lodz.pl	0.000	0.012	00:30:00
lze200.lze.lodz.pl	0.020	0.084	00:28:31
host227.elsnet.pl	0.016	0.065	00:02:02
web.man.lodz.pl	0.866	3.980	00:00:23
mon.man.lodz.pl	0.832	3.261	00:00:03
staticline41435.toya.net.pl	0.109	0.458	00:00:02
david.man.lodz.pl	0.263	1.100	00:00:00
isvcs01.man.lodz.pl	0.009	0.009	00:00:00
?	0.007	0.026	00:00:00
TOTAL: 10	2.195	9.178	03:58:35

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	37
host227.elsnet.pl	6
pc-vicki.man.lodz.pl	1
TOTAL: 5	428

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	2	1
pc-vicki.man.lodz.pl	1	0
TOTAL: 2	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	312	100.0%
TOTAL: 1		312	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	136
2	pl.misc.kolej	71
3	pl.rec.kuchnia	41
4	pl.comp.pecet	37
5	pl.comp.os.ms-windows.winnt	16
6	pl.misc.telefonia.gsm	11
TOTAL: 6		312

Daily Usenet report

Nov 2 04:15:02 -- Nov 3 04:15:01