Daily Usenet report: Sep 30 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Sep 30 10:57:46 newsfeed nnrpd[4008]: SERVER perl filter not defined
Sep 30 13:09:47 newsfeed nnrpd[8351]: SERVER perl filter not defined
Sep 30 13:46:06 newsfeed nnrpd[9649]: SERVER perl filter not defined
Sep 30 20:53:09 newsfeed nnrpd[23826]: SERVER perl filter not defined
Sep 30 20:55:02 newsfeed nnrpd[23866]: SERVER perl filter not defined
Sep 30 22:17:11 newsfeed nnrpd[26603]: SERVER perl filter not defined
Oct 1 04:15:02 newsfeed nnrpd[6334]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 1 04:15:02 newsfeed nnrpd[6339]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 1 04:15:02 newsfeed nnrpd[6340]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 1 04:15:02 newsfeed nnrpd[6342]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 1 04:15:02 newsfeed nnrpd[6344]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 1 04:15:02 newsfeed nnrpd[6345]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 1 04:15:02 newsfeed nnrpd[6346]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1639141	97.1%	187.8 MB	96.6%
innd	34452	2.0%	4.8 MB	2.5%
nnrpd	7392	0.4%	776.6 KB	0.4%
innfeed	6586	0.4%	960.6 KB	0.5%
TOTAL: 4	1687571	100.0%	194.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1634614	93.9%
Negative hits	90540	5.2%
Positive hits	16376	0.9%
Cache misses	183	0.0%
TOTAL: 4	1741713	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:12.718	0.0%	306	0.000	41.562	1122.000
article cleanup	00:00:39.984	0.0%	1639335	0.007	0.024	0.046
article logging	00:01:09.194	0.1%	1639500	0.019	0.042	0.135
article parse	01:01:57.129	4.3%	133870020	0.011	0.028	0.051
article write	00:19:30.618	1.4%	1615427	0.411	0.725	2.639
artlog/artcncl	00:00:00.008	0.0%	299	0.000	0.027	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:51.169	0.2%	144029904	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.853	0.0%	273	0.000	6.788	306.000
hishave/artcncl	00:00:00.098	0.0%	306	0.000	0.320	13.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:34:57.530	2.4%	1744061	0.015	1.203	4.441
history sync	00:00:00.028	0.0%	292	0.000	0.096	2.000
history write	02:49:51.685	11.8%	1636755	0.085	6.227	19.584
hiswrite/artcncl	00:00:00.138	0.0%	33	0.000	4.182	29.000
idle	13:40:34.504	57.0%	86269776	0.405	0.571	2.515
nntp read	00:56:35.669	3.9%	132484104	0.013	0.026	0.037
overview write	00:00:13.125	0.0%	1615427	0.000	0.008	0.020
perl filter	03:50:10.340	16.0%	1636754	5.836	8.438	11.215
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:24.833	0.3%	3272904	0.027	0.081	0.115
TOTAL: 24:00:20.144	23:23:10.623	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.156	0.0%	42047	0.000	0.004	1.000
article prepare	00:00:00.006	0.0%	18470	0.000	0.000	0.125
article read	00:00:00.675	0.0%	4550	0.000	0.148	1.000
backlog stats	00:00:00.172	0.0%	247241	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.259	0.0%	155643	0.000	0.015	1.000
data write	00:00:06.905	0.0%	119693	0.000	0.058	13.000
idle	23:59:37.950	100.0%	247241	0.000	349.367	19119.000
status file	00:00:00.798	0.0%	199	0.000	4.010	101.333
TOTAL: 23:59:55.471	23:59:48.921	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:34:46.666	98.8%	13844	0.000	150.727	22922.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.847	0.0%	30031	0.000	0.028	2.222
readart	00:00:01.437	0.1%	648	0.000	2.218	69.400
TOTAL: 00:35:11.309	00:34:48.950	98.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	469	1636498	1611850	1079	23569	98%	273:43:19
2	news.nask.pl	3	16421	3847	12232	342	23%	23:21:48
3	news.icm.edu.pl	46	176	19	151	6	10%	14:52:14
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		525	1653101	1615722	13462	23917	97%	311:57:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	495.9 GB	12.3 MB	3.0 GB	499.0 GB	99%	319.9 KB
2	news.nask.pl	16.2 MB	752.6 KB	991.3 KB	17.9 MB	90%	4.4 KB
3	news.icm.edu.pl	352.0 KB	43.3 KB	114.8 KB	510.1 KB	69%	20.4 KB
4	localhost	8.3 KB	0.0 KB	0.0 KB	8.3 KB	100%	1.4 KB
TOTAL: 4		496.0 GB	13.1 MB	3.0 GB	499.0 GB	99%	319.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 30 04:15:02 - 04:59:59	57747	3.6%	21.40	15.9 GB	3.2%	6164.98
Sep 30 05:00:00 - 05:59:59	71269	4.4%	19.80	20.3 GB	4.1%	5920.01
Sep 30 06:00:00 - 06:59:59	67637	4.2%	18.79	21.6 GB	4.4%	6303.62
Sep 30 07:00:00 - 07:59:59	67957	4.2%	18.88	20.5 GB	4.1%	5961.01
Sep 30 08:00:00 - 08:59:59	70159	4.3%	19.49	21.4 GB	4.3%	6245.86
Sep 30 09:00:00 - 09:59:59	75104	4.7%	20.86	23.6 GB	4.8%	6875.16
Sep 30 10:00:00 - 10:59:59	70746	4.4%	19.65	23.7 GB	4.8%	6916.90
Sep 30 11:00:00 - 11:59:59	74495	4.6%	20.69	22.6 GB	4.6%	6578.74
Sep 30 12:00:00 - 12:59:59	73888	4.6%	20.52	22.9 GB	4.6%	6663.38
Sep 30 13:00:00 - 13:59:59	41110	2.5%	11.42	10.9 GB	2.2%	3160.68
Sep 30 14:00:00 - 14:59:59	53090	3.3%	14.75	15.0 GB	3.0%	4365.80
Sep 30 15:00:00 - 15:59:59	52130	3.2%	14.48	14.6 GB	2.9%	4241.42
Sep 30 16:00:00 - 16:59:59	78703	4.9%	21.86	22.7 GB	4.6%	6622.88
Sep 30 17:00:00 - 17:59:59	80063	5.0%	22.24	23.0 GB	4.6%	6707.35
Sep 30 18:00:00 - 18:59:59	80055	5.0%	22.24	22.9 GB	4.6%	6666.30
Sep 30 19:00:00 - 19:59:59	86058	5.3%	23.91	23.1 GB	4.7%	6728.98
Sep 30 20:00:00 - 20:59:59	76303	4.7%	21.20	21.8 GB	4.4%	6336.89
Sep 30 21:00:00 - 21:59:59	48887	3.0%	13.58	15.2 GB	3.1%	4417.39
Sep 30 22:00:00 - 22:59:59	66036	4.1%	18.34	20.2 GB	4.1%	5896.77
Sep 30 23:00:00 - 23:59:59	80407	5.0%	22.34	23.2 GB	4.7%	6748.62
Oct 1 00:00:00 - 00:59:59	52283	3.2%	14.52	16.6 GB	3.4%	4844.97
Oct 1 01:00:00 - 01:59:59	65276	4.0%	18.13	23.8 GB	4.8%	6931.13
Oct 1 02:00:00 - 02:59:59	56206	3.5%	15.61	22.6 GB	4.6%	6573.99
Oct 1 03:00:00 - 03:59:59	55346	3.4%	15.37	22.4 GB	4.5%	6534.13
Oct 1 04:00:00 - 04:15:02	13814	0.9%	15.31	5.0 GB	1.0%	5836.44
TOTAL: 24:00:00	1614769	100.0%	18.69	495.5 GB	100.0%	6013.53

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	23711	274	2304	1	21132
2	news.nask.pl	356	52	276	5	23
3	news.icm.edu.pl	6	0	2	0	4
TOTAL: 3		24073	326	2582	6	21159

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	122
alt.sex.telephones	41
it.comp.os.win.windows8	33
comp.mobile.ipad	21
hr.alt.cellular.gsm.iphone	21
it.tlc.cellulari.android	15
comp.sys.raspberry-pi	15
comp.mobile.android	15
it.comp.os.win.windows7	12
de.comm.provider.telefonie	7
rec.arts.tv.x-factor	5
de.comp.office-pakete.ms-office.word	4
de.comp.office-pakete.ms-office.outlook	3
de.rec.sf.startrek	3
nl.comp.os.linux.techniek	3
hr.rec.glazba.hifi	2
hr.ponuda.hardver.periferije	1
hr.alt.cellular.gsm.android	1
de.comp.lang.vbclassic	1
hr.ponuda.hardver.komponente	1
TOTAL: 20	326

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

Groups	Count
pl.sci.historia	4
fido7.ru.guitar.soft	2
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	20974
EMP (md5)	12
HTML post	6
Scoring filter (12)	1
TOTAL: 4	20993

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	42966	1628	40123	311	0	0	3%	24:01:45
2	nask	24886	698	15075	91	0	1	2%	24:00:26
3	icm	38079	517	28553	1315	1	1	1%	24:00:23
4	poznan	5617	44	3800	7	0	0	0%	24:00:10
5	okapi	0	0	0	0	0	3092	0%	24:00:00
TOTAL: 5		111548	2887	87551	1724	1	3094	2%	120:02:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	114.6 MB	22.5 MB	137.1 MB	1.6 KB/s	177.9 KB	24:00:26
2	icm	69.4 MB	340.5 MB	409.9 MB	4.9 KB/s	229.1 KB	24:00:23
3	panorama	6.1 MB	1.4 MB	7.5 MB	0.1 KB/s	3.9 KB	24:01:45
4	poznan	142.7 KB	23.3 KB	166.0 KB	0.0 KB/s	3.3 KB	24:00:10
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		190.2 MB	364.5 MB	554.6 MB	1.3 KB/s	123.2 KB	120:02:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	350	522.4 KB	288	0	0	00:00:08
2	web.man.lodz.pl	294	240	250.4 KB	94	0	0	00:00:30
3	ip-176.105.135.22.tvsat364.lodz.pl	1	29	41.5 KB	2	0	0	00:02:51
4	staticline-31-182-69-227.toya.net.pl	4	21	36.8 KB	2	0	1	00:04:27
5	lze200.lze.lodz.pl	2	5	7.1 KB	2	0	0	00:03:34
6	router2.cbmm.lodz.pl	1	3	4.0 KB	1	0	0	00:00:13
7	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	0	00:00:01
TOTAL: 7		596	648	862.2 KB	389	6	1	00:35:10

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	350	522.4 KB	288	0	0	00:00:08
2	*.man.lodz.pl	684	240	250.4 KB	94	6	0	00:00:37
3	*.105.135.22.tvsat364.lodz.pl	1	29	41.5 KB	2	0	0	00:02:51
4	*.toya.net.pl	86	21	36.8 KB	2	0	1	00:04:31
5	*.lze.lodz.pl	2	5	7.1 KB	2	0	0	00:03:34
6	*.cbmm.lodz.pl	1	3	4.0 KB	1	0	0	00:00:13
7	unresolved	3	0	0.0 KB	0	0	0	00:02:52
8	*.206.15.62.static.jazztel.es	4	0	0.0 KB	0	0	0	00:20:19
TOTAL: 8		1069	648	862.2 KB	389	6	1	00:35:10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
207.206.15.62.static.jazztel.es	0.586	0.869	00:20:19
staticline-31-182-69-227.toya.net.pl	0.057	0.059	00:04:27
lze200.lze.lodz.pl	0.018	0.029	00:03:34
ip-176.105.135.22.tvsat364.lodz.pl	0.005	0.032	00:02:51
212.51.203.199	0.003	0.033	00:02:13
194.33.77.50	0.003	0.016	00:00:39
web.man.lodz.pl	1.165	4.130	00:00:30
router2.cbmm.lodz.pl	0.006	0.014	00:00:13
pc-skrzep2.cm.p.lodz.pl	1.080	3.949	00:00:08
staticline41435.toya.net.pl	0.294	1.238	00:00:04
mon.man.lodz.pl	1.074	3.972	00:00:04
david.man.lodz.pl	0.347	1.326	00:00:01
isvcs01.man.lodz.pl	0.068	0.072	00:00:01
TOTAL: 13	4.706	15.739	00:35:10

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	82
207.206.15.62.static.jazztel.es	4
212.51.203.199	2
194.33.77.50	1
TOTAL: 6	473

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	371	57.3%
2	pl	277	42.7%
TOTAL: 2		648	100.0%

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

Newsgroup		Count
1	lodman.info	350
2	pl.comp.os.ms-windows.winnt	146
3	pl.comp.pecet	98
4	pl.soc.prawo	28
5	lodman.test	21
6	pl.regionalne.lodz	4
7	pl.rec.dom	1
TOTAL: 7		648

Daily Usenet report

Sep 30 04:15:02 -- Oct 1 04:15:02