Daily Usenet report: May 25 04:15:04

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

May 25 09:22:22 newsfeed nnrpd[12010]: SERVER perl filter not defined
May 25 09:25:02 newsfeed nnrpd[12178]: SERVER perl filter not defined
May 25 11:54:15 newsfeed nnrpd[16707]: SERVER perl filter not defined
May 25 16:51:37 newsfeed nnrpd[26181]: SERVER perl filter not defined
May 26 04:15:05 newsfeed nnrpd[15432]: web.man.lodz.pl rejected Flushing log and syslog files
May 26 04:15:05 newsfeed nnrpd[15439]: f-oper-v100.man.lodz.pl rejected Flushing log and syslog files
May 26 04:15:05 newsfeed nnrpd[15440]: web.man.lodz.pl rejected Flushing log and syslog files
May 26 04:15:05 newsfeed nnrpd[15441]: web.man.lodz.pl rejected Flushing log and syslog files
May 26 04:15:05 newsfeed nnrpd[15442]: web.man.lodz.pl rejected Flushing log and syslog files
May 26 04:15:05 newsfeed nnrpd[15443]: web.man.lodz.pl rejected Flushing log and syslog files
May 26 04:15:05 newsfeed nnrpd[15444]: web.man.lodz.pl rejected Flushing log and syslog files
May 26 04:15:05 newsfeed nnrpd[15445]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2635331	98.2%	297.7 MB	97.8%
innd	32430	1.2%	4.9 MB	1.6%
nnrpd	7629	0.3%	804.6 KB	0.3%
innfeed	7056	0.3%	1.0 MB	0.3%
controlchan	2	0.0%	0.4 KB	0.0%
TOTAL: 5	2682448	100.0%	304.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2626357	94.8%
Negative hits	105502	3.8%
Positive hits	38151	1.4%
Cache misses	413	0.0%
TOTAL: 4	2770423	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:16.330	0.0%	569	0.000	28.699	679.500
article cleanup	00:00:36.602	0.0%	2634677	0.005	0.014	0.160
article logging	00:01:35.160	0.1%	2634849	0.015	0.036	1.399
article parse	01:20:55.459	5.6%	83287617	0.021	0.058	0.145
article write	00:29:10.949	2.0%	2615435	0.374	0.669	4.807
artlog/artcncl	00:00:00.011	0.0%	521	0.000	0.021	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:03:23.650	0.2%	97678029	0.001	0.002	0.016
hisgrep/artcncl	00:00:03.733	0.0%	329	0.000	11.347	301.000
hishave/artcncl	00:00:01.973	0.0%	569	0.000	3.467	24.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:44:45.772	11.4%	2772467	0.013	3.566	17.754
history sync	00:00:00.011	0.0%	296	0.000	0.037	1.000
history write	04:06:23.235	17.1%	2628309	0.076	5.625	28.083
hiswrite/artcncl	00:00:01.443	0.0%	240	0.000	6.013	151.500
idle	07:13:32.403	30.1%	45936019	0.142	0.566	4.716
nntp read	00:46:26.528	3.2%	81138381	0.019	0.034	0.230
overview write	00:00:18.126	0.0%	2615435	0.001	0.007	0.247
perl filter	06:41:40.377	27.9%	2628307	6.570	9.170	22.738
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:07.277	0.5%	5277835	0.017	0.081	0.457
TOTAL: 23:59:54.464	23:36:19.039	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.188	0.0%	47413	0.000	0.004	0.154
article prepare	00:00:00.020	0.0%	84312	0.000	0.000	0.025
article read	00:00:47.624	0.1%	20552	0.000	2.317	96.000
backlog stats	00:00:00.395	0.0%	354070	0.000	0.001	60.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:04.232	0.0%	203177	0.000	0.021	5.920
data write	00:00:13.874	0.0%	185276	0.000	0.075	5.120
idle	23:58:25.414	99.9%	354070	0.000	243.752	14966.000
status file	00:00:04.501	0.0%	251	1.000	17.932	248.000
TOTAL: 23:59:48.314	23:59:36.248	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.373	0.0%	636	5.303	5.303	5.303
idle	17:16:06.763	99.9%	20901	0.000	2974.344	600055.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.978	0.0%	43714	0.000	0.022	6.333
readart	00:00:01.166	0.0%	406	0.000	2.872	134.600
TOTAL: 17:16:59.097	17:16:12.280	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
news@dictatorshandbook.net	0	0	1	0	0	1
TOTAL	0	0	1	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1013	2620698	2601971	1237	17490	99%	466:04:05
2	news.nask.pl	3	18429	7121	10421	887	38%	23:59:00
3	news.task.gda.pl	17	28612	6838	20921	853	23%	46:20:12
4	localhost	5	4	4	0	0	100%	00:00:00
5	news.icm.edu.pl	37	322	2	292	28	0%	18:37:57
TOTAL: 5		1075	2668065	2615936	32871	19258	98%	555:01:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	797.0 GB	209.1 MB	2.0 GB	799.2 GB	99%	319.9 KB
2	news.nask.pl	236.7 MB	28.8 MB	28.8 MB	294.3 MB	80%	37.6 KB
3	news.task.gda.pl	12.4 MB	31.2 KB	2.7 MB	15.0 MB	82%	2.0 KB
4	news.icm.edu.pl	26.4 KB	62.6 KB	1.3 MB	1.4 MB	1%	49.0 KB
5	localhost	5.0 KB	0.0 KB	0.0 KB	5.0 KB	100%	1.3 KB
TOTAL: 5		797.2 GB	238.0 MB	2.1 GB	799.5 GB	99%	318.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 25 04:15:04 - 04:59:59	60186	2.3%	22.32	18.8 GB	2.4%	7315.83
May 25 05:00:00 - 05:59:59	94687	3.6%	26.30	29.1 GB	3.7%	8483.93
May 25 06:00:00 - 06:59:59	113412	4.3%	31.50	34.8 GB	4.4%	10131.67
May 25 07:00:00 - 07:59:59	117560	4.5%	32.66	35.9 GB	4.5%	10464.54
May 25 08:00:00 - 08:59:59	116086	4.4%	32.25	38.1 GB	4.8%	11085.18
May 25 09:00:00 - 09:59:59	120852	4.6%	33.57	37.5 GB	4.7%	10919.75
May 25 10:00:00 - 10:59:59	131345	5.0%	36.48	38.3 GB	4.8%	11145.91
May 25 11:00:00 - 11:59:59	141721	5.4%	39.37	37.6 GB	4.7%	10952.62
May 25 12:00:00 - 12:59:59	112046	4.3%	31.12	30.6 GB	3.8%	8911.21
May 25 13:00:00 - 13:59:59	108030	4.1%	30.01	31.3 GB	3.9%	9113.80
May 25 14:00:00 - 14:59:59	97684	3.7%	27.13	29.3 GB	3.7%	8530.91
May 25 15:00:00 - 15:59:59	105833	4.0%	29.40	29.2 GB	3.7%	8497.78
May 25 16:00:00 - 16:59:59	148876	5.7%	41.35	34.3 GB	4.3%	9976.68
May 25 17:00:00 - 17:59:59	124279	4.8%	34.52	34.5 GB	4.3%	10049.55
May 25 18:00:00 - 18:59:59	112325	4.3%	31.20	30.6 GB	3.8%	8925.50
May 25 19:00:00 - 19:59:59	107765	4.1%	29.93	31.5 GB	4.0%	9184.35
May 25 20:00:00 - 20:59:59	111974	4.3%	31.10	34.0 GB	4.3%	9904.27
May 25 21:00:00 - 21:59:59	79548	3.0%	22.10	28.1 GB	3.5%	8171.75
May 25 22:00:00 - 22:59:59	123833	4.7%	34.40	37.0 GB	4.6%	10768.04
May 25 23:00:00 - 23:59:59	106575	4.1%	29.60	38.1 GB	4.8%	11090.20
May 26 00:00:00 - 00:59:59	78278	3.0%	21.74	27.7 GB	3.5%	8081.98
May 26 01:00:00 - 01:59:59	96797	3.7%	26.89	36.3 GB	4.6%	10569.42
May 26 02:00:00 - 02:59:59	100721	3.9%	27.98	36.5 GB	4.6%	10637.79
May 26 03:00:00 - 03:59:59	83382	3.2%	23.16	29.7 GB	3.7%	8638.01
May 26 04:00:00 - 04:15:05	21596	0.8%	23.86	7.9 GB	1.0%	9152.54
TOTAL: 24:00:01	2615391	100.0%	30.27	796.6 GB	100.0%	9667.16

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	17615	143	5488	0	11984
2	news.nask.pl	931	16	868	1	46
3	news.task.gda.pl	840	762	13	3	62
4	news.icm.edu.pl	33	0	2	0	31
TOTAL: 4		19419	921	6371	4	12123

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	75
mailing.freebsd.svn-ports-head	75
alt.politics.scorched-earth	60
mailing.freebsd.ports-bugs	53
uk.rec.cycling.moderated	33
uk.railway	32
mozilla.dev.tree-management	28
it.comp.os.win.windows8	26
mailing.freebsd.current	25
mailing.freebsd.svn-src-all	25
fa.freebsd.cvs-all	25
fr.comp.os.android	23
mailing.freebsd.ports	23
mailing.openbsd.misc	23
mailing.freebsd.perl	23
fr.usenet.abus.nocem	21
mailing.unix.samba-technical	20
24hoursupport.helpdesk	20
mailing.freebsd.svn-src-head	20
mailing.openbsd.ports-changes	19
TOTAL: 75	921

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

Groups	Count
fr.bienvenue	3
pl.sci.matematyka	1
TOTAL: 2	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	11741
Attached HTML file	69
HTML post	51
EMP (fsl)	46
EMP (md5)	41
Angle-bracket bot	1
TOTAL: 6	11949

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	59078	6677	27742	10124	8	470	11%	24:00:00
2	panorama	50176	3152	42738	908	0	0	6%	23:53:53
3	poznan	14193	65	12107	50	0	0	0%	24:01:48
4	nask	18734	25	12946	0	0	0	0%	23:54:11
5	task	1557	21	828	2	0	1	1%	21:36:06
6	okapi	0	0	0	0	0	2051	0%	24:00:00
TOTAL: 6		143738	9940	96361	11084	8	2522	6%	141:25:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	304.9 MB	2.5 GB	2.8 GB	34.2 KB/s	176.0 KB	24:00:00
2	panorama	21.8 MB	13.7 MB	35.4 MB	0.4 KB/s	8.9 KB	23:53:53
3	nask	3.3 MB	0.0 KB	3.3 MB	0.0 KB/s	134.0 KB	23:54:11
4	poznan	464.9 KB	226.4 KB	691.3 KB	0.0 KB/s	6.0 KB	24:01:48
5	task	42.9 KB	3.0 KB	45.9 KB	0.0 KB/s	2.0 KB	21:36:06
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		330.5 MB	2.5 GB	2.9 GB	5.9 KB/s	142.6 KB	141:25:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	336	452.3 KB	288	0	00:00:15
2	web.man.lodz.pl	294	58	52.5 KB	26	0	00:00:27
3	ecj152.neoplus.adsl.tpnet.pl	11	7	8.7 KB	4	0	02:37:20
4	kujawiak.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 4		597	401	513.6 KB	318	4	30:36:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	452.3 KB	288	0	00:00:15
2	*.man.lodz.pl	683	58	52.5 KB	26	4	00:00:37
3	*.neoplus.adsl.tpnet.pl	11	7	8.7 KB	4	0	02:37:20
4	*.play-internet.pl	4	0	0.0 KB	0	0	00:07:45
5	*.207.15.62.static.jazztel.es	8	0	0.0 KB	0	0	27:47:55
6	unresolved	64	0	0.0 KB	0	0	00:01:42
7	*.toya.net.pl	87	0	0.0 KB	0	0	00:00:50
TOTAL: 7		1145	401	513.6 KB	318	4	30:36:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
123.207.15.62.static.jazztel.es	4.824	7.040	27:47:55
ecj152.neoplus.adsl.tpnet.pl	0.088	0.580	02:37:20
user-164-126-241-189.play-internet.pl	0.032	0.092	00:07:45
122.150.18.15	0.172	1.292	00:01:40
staticline41435.toya.net.pl	0.496	5.876	00:00:50
web.man.lodz.pl	1.752	15.332	00:00:27
vegas.cm.p.lodz.pl	2.088	25.656	00:00:15
f-oper-v100.man.lodz.pl	0.672	4.636	00:00:06
david.man.lodz.pl	0.416	4.080	00:00:02
91.90.59.186	0.004	0.020	00:00:01
kujawiak.man.lodz.pl	0.032	0.208	00:00:00
TOTAL: 11	10.576	64.812	30:36:26

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	87
122.150.18.15	63
123.207.15.62.static.jazztel.es	8
user-164-126-241-189.play-internet.pl	4
91.90.59.186	1
TOTAL: 7	548

NNRP client timeouts [Top 20]:

System	Conn	Peer
123.207.15.62.static.jazztel.es	7	1
ecj152.neoplus.adsl.tpnet.pl	3	0
TOTAL: 2	10	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	343	85.5%
2	pl	58	14.5%
TOTAL: 2		401	100.0%

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

Newsgroup		Count
1	lodman.info	343
2	pl.comp.pecet	36
3	pl.comp.os.ms-windows.winnt	22
TOTAL: 3		401

Daily Usenet report

May 25 04:15:04 -- May 26 04:15:05