Daily Usenet report: Mar 19 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Mar 19 07:52:50 newsfeed nnrpd[14741]: SERVER perl filter not defined
Mar 19 12:06:40 newsfeed nnrpd[23127]: SERVER perl filter not defined
Mar 19 12:17:38 newsfeed nnrpd[23475]: SERVER perl filter not defined
Mar 19 13:16:31 newsfeed nnrpd[25439]: SERVER perl filter not defined
Mar 19 14:21:23 newsfeed nnrpd[27527]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1521801	98.2%	176.5 MB	97.8%
innd	14156	0.9%	2.3 MB	1.3%
nnrpd	7210	0.5%	765.9 KB	0.4%
innfeed	6739	0.4%	994.1 KB	0.5%
TOTAL: 4	1549906	100.0%	180.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1516430	96.4%
Negative hits	40310	2.6%
Positive hits	16636	1.1%
Cache misses	236	0.0%
TOTAL: 4	1573612	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.157	0.0%	343	0.000	32.528	627.000
article cleanup	00:00:24.744	0.0%	1521779	0.000	0.016	0.043
article logging	00:00:42.011	0.0%	1521999	0.000	0.028	0.683
article parse	01:03:58.451	4.4%	126657112	0.000	0.030	0.063
article write	00:22:13.563	1.5%	1516201	0.167	0.880	15.375
artlog/artcncl	00:00:00.005	0.0%	326	0.000	0.015	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:22.124	0.2%	136219956	0.000	0.001	0.007
hisgrep/artcncl	00:00:01.925	0.0%	300	0.000	6.417	69.333
hishave/artcncl	00:00:00.236	0.0%	343	0.000	0.688	9.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:02:43.103	4.4%	1574373	0.000	2.390	8.264
history sync	00:00:00.019	0.0%	308	0.000	0.062	2.000
history write	03:28:05.381	14.4%	1517396	4.037	8.228	26.697
hiswrite/artcncl	00:00:00.166	0.0%	43	0.000	3.860	16.000
idle	12:49:17.775	53.4%	84209144	0.296	0.548	2030.133
nntp read	00:54:46.608	3.8%	125374615	0.000	0.026	0.038
overview write	00:00:10.495	0.0%	1516201	0.000	0.007	0.036
perl filter	03:34:31.899	14.9%	1517396	1.188	8.483	10.318
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:53.250	0.2%	3111269	0.000	0.056	0.113
TOTAL: 24:00:39.828	23:22:22.912	97.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.274	0.0%	79017	0.000	0.003	0.167
article prepare	00:00:00.014	0.0%	34288	0.000	0.000	0.062
article read	00:00:01.278	0.0%	8504	0.000	0.150	7.900
backlog stats	00:00:00.169	0.0%	353815	0.000	0.000	0.028
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.252	0.0%	232470	0.000	0.010	3.000
data write	00:00:09.462	0.0%	157205	0.000	0.060	12.000
idle	23:59:18.404	100.0%	353815	0.000	244.078	23959.000
status file	00:00:01.882	0.0%	212	0.000	8.877	201.000
TOTAL: 23:59:41.909	23:59:33.735	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.162	0.0%	58	2.351	2.793	3.571
idle	13:41:05.744	99.9%	251859	0.000	195.608	301748.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.320	0.0%	505729	0.000	0.016	1.000
readart	00:00:01.935	0.0%	507	0.000	3.817	59.200
TOTAL: 13:41:53.213	13:41:16.161	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	533	1516304	1511389	1227	3688	99%	265:27:20
2	news.nask.pl	2	17191	4438	10975	1778	25%	24:29:39
3	news.icm.edu.pl	43	308	21	170	117	6%	17:14:25
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		584	1533808	1515853	12372	5583	98%	307:11:24

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	493.8 GB	13.7 MB	111.3 MB	493.9 GB	99%	341.8 KB
2	news.nask.pl	16.7 MB	4.8 MB	4.8 MB	26.3 MB	63%	4.3 KB
3	news.icm.edu.pl	1.5 MB	76.8 KB	2.2 MB	3.7 MB	39%	27.7 KB
4	localhost	8.4 KB	0.0 KB	0.0 KB	8.4 KB	100%	1.7 KB
TOTAL: 4		493.8 GB	18.6 MB	118.3 MB	493.9 GB	99%	340.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 19 04:15:02 - 04:59:59	45384	3.0%	16.82	15.8 GB	3.2%	6140.75
Mar 19 05:00:00 - 05:59:59	54830	3.6%	15.23	19.5 GB	4.0%	5685.74
Mar 19 06:00:00 - 06:59:59	63138	4.2%	17.54	25.1 GB	5.1%	7315.24
Mar 19 07:00:00 - 07:59:59	72283	4.8%	20.08	25.5 GB	5.2%	7435.09
Mar 19 08:00:00 - 08:59:59	77280	5.1%	21.47	25.2 GB	5.1%	7349.85
Mar 19 09:00:00 - 09:59:59	64864	4.3%	18.02	20.9 GB	4.2%	6097.76
Mar 19 10:00:00 - 10:59:59	73987	4.9%	20.55	25.0 GB	5.1%	7273.35
Mar 19 11:00:00 - 11:59:59	81160	5.4%	22.54	26.8 GB	5.4%	7812.53
Mar 19 12:00:00 - 12:59:59	82246	5.4%	22.85	26.8 GB	5.4%	7801.51
Mar 19 13:00:00 - 13:59:59	59890	4.0%	16.64	19.5 GB	4.0%	5682.81
Mar 19 14:00:00 - 14:59:59	57732	3.8%	16.04	17.3 GB	3.5%	5031.55
Mar 19 15:00:00 - 15:59:59	81834	5.4%	22.73	26.5 GB	5.4%	7727.67
Mar 19 16:00:00 - 16:59:59	101867	6.7%	28.30	28.9 GB	5.9%	8427.54
Mar 19 17:00:00 - 17:59:59	76173	5.0%	21.16	20.9 GB	4.2%	6092.64
Mar 19 18:00:00 - 18:59:59	73314	4.8%	20.36	22.2 GB	4.5%	6456.51
Mar 19 19:00:00 - 19:59:59	48599	3.2%	13.50	15.2 GB	3.1%	4422.91
Mar 19 20:00:00 - 20:59:59	43744	2.9%	12.15	15.4 GB	3.1%	4490.87
Mar 19 21:00:00 - 21:59:59	46328	3.1%	12.87	16.5 GB	3.3%	4793.85
Mar 19 22:00:00 - 22:59:59	71164	4.7%	19.77	22.7 GB	4.6%	6625.95
Mar 19 23:00:00 - 23:59:59	18914	1.2%	5.25	4.2 GB	0.8%	1212.51
Mar 20 00:00:00 - 00:59:59	51300	3.4%	14.25	15.1 GB	3.1%	4395.69
Mar 20 01:00:00 - 01:59:59	44264	2.9%	12.30	14.9 GB	3.0%	4331.07
Mar 20 02:00:00 - 02:59:59	64806	4.3%	18.00	22.9 GB	4.6%	6666.02
Mar 20 03:00:00 - 03:59:59	46770	3.1%	12.99	16.7 GB	3.4%	4864.33
Mar 20 04:00:00 - 04:15:03	13807	0.9%	15.29	4.3 GB	0.9%	5013.25
TOTAL: 24:00:01	1515678	100.0%	17.54	493.9 GB	100.0%	5993.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3885	207	2618	3	1057
2	news.nask.pl	1793	4	1759	6	24
3	news.icm.edu.pl	119	0	5	1	113
TOTAL: 3		5797	211	4382	10	1194

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	97
it.tlc.cellulari.android	69
comp.mobile.android	12
uk.rec.cycling.moderated	9
it.comp.os.win.windows7	4
hr.ponuda.hardver.komponente	3
it.comp.os.win.windows8	3
it.tlc.cellulari.iphone	2
de.comp.office-pakete.ms-office.word	2
comp.mobile.ipad	2
comp.sys.raspberry-pi	1
alt.sex.telephones	1
hr.ponuda.hardver.periferije	1
hr.alt.cellular.gsm.android	1
comp.sys.mac.vintage	1
hr.comp.os.linux.ubuntu	1
de.comp.lang.vbclassic	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 18	211

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

Groups	Count
pl.sci.historia	8
pl.praca.oferowana	1
pl.sci.matematyka	1
TOTAL: 3	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	856
EMP (md5)	112
HTML post	5
EMP (fsl)	1
TOTAL: 4	974

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	42217	2234	28491	4130	33	5%	24:08:22
2	panorama	79486	1853	76634	244	0	2%	23:51:24
3	poznan	5319	45	3825	19	125	0%	24:01:13
4	nask	20158	33	13689	2	0	0%	23:56:02
5	okapi	0	0	0	0	2361	0%	24:00:00
TOTAL: 5		147180	4165	122639	4395	2519	2%	119:57:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	363.3 MB	1.0 GB	1.4 GB	16.7 KB/s	227.6 KB	24:08:22
2	panorama	7.7 MB	1.2 MB	8.9 MB	0.1 KB/s	4.4 KB	23:51:24
3	nask	182.3 KB	806.3 KB	988.6 KB	0.0 KB/s	28.2 KB	23:56:02
4	poznan	145.6 KB	31.7 KB	177.3 KB	0.0 KB/s	2.8 KB	24:01:13
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		371.3 MB	1.0 GB	1.4 GB	3.4 KB/s	170.4 KB	119:57:01

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	348	577.6 KB	288	0	00:00:11
2	pc-212-51-218-225.p.lodz.pl	11	73	139.6 KB	10	0	02:11:02
3	web.man.lodz.pl	288	56	46.9 KB	26	0	00:00:24
4	auriga.rz.izeto.pl	3	25	32.3 KB	5	0	00:57:01
5	dmcs2.p.lodz.pl	2	5	11.5 KB	2	0	00:04:03
6	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 6		597	507	807.8 KB	331	5	13:41:52

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	348	577.6 KB	288	0	00:00:11
2	*.p.lodz.pl	14	78	151.1 KB	12	0	02:25:11
3	*.man.lodz.pl	677	56	46.9 KB	26	5	00:00:31
4	*.rz.izeto.pl	3	25	32.3 KB	5	0	00:57:01
5	unresolved	6	0	0.0 KB	0	0	09:29:21
6	*.toya.net.pl	45	0	0.0 KB	0	0	00:00:04
7	*.lze.lodz.pl	2	0	0.0 KB	0	0	00:00:31
8	*.ics.p.lodz.pl	1	0	0.0 KB	0	0	00:49:00
TOTAL: 8		1036	507	807.8 KB	331	5	13:41:52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.113	8.633	15.387	09:12:15
pc-212-51-218-225.p.lodz.pl	0.021	0.202	02:11:02
auriga.rz.izeto.pl	0.009	0.054	00:57:01
wireless.ics.p.lodz.pl	0.004	0.020	00:49:00
77.79.234.90	0.026	0.047	00:17:05
radius2.p.lodz.pl	0.000	0.019	00:10:06
dmcs2.p.lodz.pl	0.012	0.025	00:04:03
lze200.lze.lodz.pl	0.018	0.030	00:00:31
web.man.lodz.pl	1.059	4.216	00:00:24
pc-skrzep2.cm.p.lodz.pl	1.077	4.550	00:00:11
mon.man.lodz.pl	1.099	3.980	00:00:04
staticline41435.toya.net.pl	0.174	0.629	00:00:04
david.man.lodz.pl	0.349	1.400	00:00:01
isvcs01.man.lodz.pl	0.031	0.097	00:00:00
?	0.000	0.014	00:00:00
TOTAL: 15	12.512	30.670	13:41:52

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	45
185.48.192.113	4
77.79.234.90	2
lze200.lze.lodz.pl	2
wireless.ics.p.lodz.pl	1
radius2.p.lodz.pl	1
TOTAL: 8	439

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.192.113	2	2
wireless.ics.p.lodz.pl	1	0
pc-212-51-218-225.p.lodz.pl	1	0
TOTAL: 3	4	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	359	70.8%
2	pl	134	26.4%
3	lodz	9	1.8%
4	lodzman	5	1.0%
TOTAL: 4		507	100.0%

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

Newsgroup		Count
1	lodman.info	359
2	pl.misc.elektronika	50
3	pl.comp.os.ms-windows.winnt	46
4	pl.misc.kolej	28
5	pl.comp.pecet	10
6	lodz.test	8
7	lodzman.test	5
8	lodz.ogloszenia	1
TOTAL: 8		507

Daily Usenet report

Mar 19 04:15:02 -- Mar 20 04:15:03