Daily Usenet report: Feb 16 04:15:03

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 16 09:14:31 newsfeed nnrpd[28812]: SERVER perl filter not defined
Feb 16 09:15:36 newsfeed nnrpd[28859]: SERVER perl filter not defined
Feb 16 11:27:52 newsfeed nnrpd[3713]: SERVER perl filter not defined
Feb 16 11:28:40 newsfeed nnrpd[3861]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	475330	95.6%	55.5 MB	95.0%
innd	8703	1.7%	1.4 MB	2.4%
nnrpd	7092	1.4%	719.1 KB	1.2%
innfeed	6209	1.2%	866.9 KB	1.4%
TOTAL: 4	497334	100.0%	58.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	457890	91.8%
Negative hits	24276	4.9%
Positive hits	14804	3.0%
Cache misses	1745	0.3%
TOTAL: 4	498715	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.333	0.0%	274	0.000	26.763	595.000
article cleanup	00:00:05.736	0.0%	461283	0.000	0.012	0.048
article logging	00:00:11.649	0.0%	474682	0.000	0.025	0.417
article parse	00:11:49.852	0.8%	32953541	0.008	0.022	0.075
article write	00:07:05.415	0.5%	457066	0.132	0.931	8.052
artlog/artcncl	00:00:00.004	0.0%	246	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:35.782	0.0%	35532365	0.000	0.001	0.004
hisgrep/artcncl	00:00:01.585	0.0%	267	0.000	5.936	42.600
hishave/artcncl	00:00:00.988	0.0%	274	0.000	3.606	110.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:43:22.312	3.0%	498779	0.015	5.217	23.500
history sync	00:00:00.023	0.0%	586	0.000	0.039	1.000
history write	01:00:40.800	4.2%	458106	0.060	7.948	34.135
hiswrite/artcncl	00:00:00.027	0.0%	7	0.000	3.857	12.000
idle	20:39:43.582	86.1%	27609711	0.284	2.694	12.583
nntp read	00:12:06.184	0.8%	32714365	0.011	0.022	0.037
overview write	00:00:02.843	0.0%	457066	0.000	0.006	0.039
perl filter	00:53:19.739	3.7%	458105	3.959	6.985	13.049
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:58.015	0.1%	962903	0.024	0.060	0.139
TOTAL: 23:59:41.131	23:50:11.869	99.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.165	0.0%	48660	0.000	0.003	1.000
article prepare	00:00:00.003	0.0%	12240	0.000	0.000	0.050
article read	00:00:00.284	0.0%	3013	0.000	0.094	1.000
backlog stats	00:00:00.125	0.0%	242238	0.000	0.001	0.040
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.481	0.0%	161085	0.000	0.009	1.000
data write	00:00:04.175	0.0%	113595	0.000	0.037	10.000
idle	23:59:01.167	100.0%	242238	0.000	356.431	29250.000
status file	00:00:00.741	0.0%	207	0.000	3.580	120.000
TOTAL: 23:59:13.507	23:59:08.141	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.121	0.0%	24	5.042	5.042	5.042
idle	04:10:18.621	99.9%	4686	0.000	3204.998	450032.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.281	0.0%	11461	0.000	0.025	1.444
readart	00:00:02.242	0.0%	544	0.000	4.121	40.333
TOTAL: 04:10:40.225	04:10:21.265	99.9%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	254	459330	454267	1706	3357	98%	124:02:55
2	news.nask.pl	1	14602	2861	10942	799	19%	23:48:17
3	news.icm.edu.pl	31	410	36	318	56	8%	20:44:45
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		291	474346	457168	12966	4212	96%	168:35:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	98.4 GB	51.3 MB	122.7 MB	98.5 GB	99%	225.8 KB
2	news.nask.pl	9.8 MB	1.9 MB	1.9 MB	13.5 MB	72%	3.8 KB
3	news.icm.edu.pl	1.2 MB	85.7 KB	2.5 MB	3.8 MB	30%	42.3 KB
4	localhost	7.9 KB	0.0 KB	0.0 KB	7.9 KB	100%	2.0 KB
TOTAL: 4		98.4 GB	53.3 MB	127.2 MB	98.6 GB	99%	224.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 16 04:15:03 - 04:59:59	6126	1.3%	2.27	1.2 GB	1.2%	474.34
Feb 16 05:00:00 - 05:59:59	6962	1.5%	1.93	1.1 GB	1.1%	311.85
Feb 16 06:00:00 - 06:59:59	8759	1.9%	2.43	1.1 GB	1.1%	309.10
Feb 16 07:00:00 - 07:59:59	7594	1.7%	2.11	911.9 MB	0.9%	259.40
Feb 16 08:00:00 - 08:59:59	8118	1.8%	2.25	885.4 MB	0.9%	251.85
Feb 16 09:00:00 - 09:59:59	9792	2.1%	2.72	1.1 GB	1.2%	334.89
Feb 16 10:00:00 - 10:59:59	12598	2.8%	3.50	1.8 GB	1.8%	521.82
Feb 16 11:00:00 - 11:59:59	11139	2.4%	3.09	2.1 GB	2.1%	604.18
Feb 16 12:00:00 - 12:59:59	8849	1.9%	2.46	1.2 GB	1.2%	348.11
Feb 16 13:00:00 - 13:59:59	9595	2.1%	2.67	1.1 GB	1.1%	325.12
Feb 16 14:00:00 - 14:59:59	9933	2.2%	2.76	1.2 GB	1.2%	335.64
Feb 16 15:00:00 - 15:59:59	11116	2.4%	3.09	1.3 GB	1.3%	368.74
Feb 16 16:00:00 - 16:59:59	12605	2.8%	3.50	1.4 GB	1.5%	416.32
Feb 16 17:00:00 - 17:59:59	10462	2.3%	2.91	1.4 GB	1.5%	421.02
Feb 16 18:00:00 - 18:59:59	15051	3.3%	4.18	3.3 GB	3.3%	948.79
Feb 16 19:00:00 - 19:59:59	11562	2.5%	3.21	1.3 GB	1.4%	390.91
Feb 16 20:00:00 - 20:59:59	11848	2.6%	3.29	1.5 GB	1.5%	433.18
Feb 16 21:00:00 - 21:59:59	13119	2.9%	3.64	1.5 GB	1.6%	446.18
Feb 16 22:00:00 - 22:59:59	10348	2.3%	2.87	1.3 GB	1.3%	378.87
Feb 16 23:00:00 - 23:59:59	11493	2.5%	3.19	1.3 GB	1.3%	381.95
Feb 17 00:00:00 - 00:59:59	41721	9.1%	11.59	11.3 GB	11.5%	3294.54
Feb 17 01:00:00 - 01:59:59	82701	18.1%	22.97	23.3 GB	23.6%	6776.15
Feb 17 02:00:00 - 02:59:59	73665	16.1%	20.46	21.3 GB	21.7%	6213.32
Feb 17 03:00:00 - 03:59:59	40020	8.7%	11.12	10.9 GB	11.1%	3177.92
Feb 17 04:00:00 - 04:15:02	12243	2.7%	13.57	3.7 GB	3.7%	4254.50
TOTAL: 23:59:59	457419	100.0%	5.29	98.4 GB	100.0%	1194.65

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	16769	235	2352	5	14177
2	news.nask.pl	853	6	826	3	18
3	news.icm.edu.pl	43	0	6	0	37
TOTAL: 3		17665	241	3184	8	14232

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	92
it.tlc.cellulari.android	34
uk.rec.cycling.moderated	25
it.comp.os.win.windows8	20
it.comp.os.win.windows7	19
nl.wetenschap.regulier	9
comp.mobile.android	8
hr.ponuda.hardver.komponente	8
comp.sys.raspberry-pi	5
hr.ponuda.hardver.konfiguracije	4
alt.sex.telephones	3
de.comp.office-pakete.ms-office.word	3
hr.alt.cellular.gsm.android	3
de.rec.sf.startrek	2
it.comp.os.linux.ubuntu	2
it.tlc.cellulari.iphone	1
it.comp.virtualizzazione	1
hr.rec.glazba.hifi	1
nl.comp.os.linux.techniek	1
TOTAL: 19	241

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

Groups	Count
pl.sci.historia	6
pl.praca.oferowana	1
bit.listserv.catholic	1
TOTAL: 3	8

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	754
EMP (md5)	34
Scoring filter (8)	1
Scoring filter (12)	1
TOTAL: 4	790

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	48436	1198	46453	190	0	2%	24:00:15
2	icm	39424	625	31219	940	15	1%	24:03:23
3	nask	18751	59	12898	8	0	0%	24:02:23
4	poznan	3457	13	2611	20	0	0%	24:01:58
5	okapi	0	0	0	0	2392	0%	24:00:05
TOTAL: 5		110068	1895	93181	1158	2407	1%	120:08:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	82.0 MB	176.1 MB	258.1 MB	3.1 KB/s	168.9 KB	24:03:23
2	panorama	3.8 MB	1.1 MB	4.8 MB	0.1 KB/s	3.6 KB	24:00:15
3	nask	283.7 KB	2.4 MB	2.7 MB	0.0 KB/s	41.0 KB	24:02:23
4	poznan	40.4 KB	201.4 KB	241.7 KB	0.0 KB/s	7.3 KB	24:01:58
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:05
TOTAL: 5		86.1 MB	179.8 MB	265.9 MB	0.6 KB/s	89.2 KB	120:08:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	316	524.0 KB	288	0	00:00:07
2	pc-212-51-218-225.p.lodz.pl	18	182	386.6 KB	16	0	04:05:24
3	web.man.lodz.pl	288	46	43.0 KB	22	0	00:00:17
4	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 4		598	544	953.7 KB	326	4	04:10:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	316	524.0 KB	288	0	00:00:07
2	*.p.lodz.pl	18	182	386.6 KB	16	0	04:05:24
3	*.man.lodz.pl	676	46	43.0 KB	22	4	00:00:22
4	*.51c.belchatow.pilicka.pl	1	0	0.0 KB	0	0	00:03:14
5	unresolved	3	0	0.0 KB	0	0	00:01:04
6	*.toya.net.pl	39	0	0.0 KB	0	0	00:00:25
TOTAL: 6		1025	544	953.7 KB	326	4	04:10:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.063	0.237	04:05:24
wipll-118-13-static.51c.belchatow.pilicka.pl	0.170	0.016	00:03:14
89.38.40.182	0.016	0.043	00:01:03
staticline41435.toya.net.pl	0.159	0.361	00:00:25
web.man.lodz.pl	1.113	2.675	00:00:17
pc-skrzep2.cm.p.lodz.pl	1.081	2.905	00:00:07
mon.man.lodz.pl	1.078	2.745	00:00:04
david.man.lodz.pl	0.349	0.876	00:00:01
212.51.203.199	0.018	0.009	00:00:00
isvcs01.man.lodz.pl	0.029	0.041	00:00:00
?	0.005	0.005	00:00:00
TOTAL: 11	4.081	9.913	04:10:39

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	39
89.38.40.182	2
wipll-118-13-static.51c.belchatow.pilicka.pl	1
212.51.203.199	1
TOTAL: 6	427

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	6	0
TOTAL: 1	6	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	316	58.1%
2	pl	228	41.9%
TOTAL: 2		544	100.0%

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

Newsgroup		Count
1	lodman.info	316
2	pl.misc.kolej	115
3	pl.misc.elektronika	67
4	pl.comp.pecet	32
5	pl.comp.os.ms-windows.winnt	14
TOTAL: 5		544

Daily Usenet report

Feb 16 04:15:03 -- Feb 17 04:15:02