Daily Usenet report: Aug 16 04:15:04

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Aug 16 09:39:19 newsfeed nnrpd[15454]: SERVER perl filter not defined
Aug 16 10:00:36 newsfeed nnrpd[16147]: SERVER perl filter not defined
Aug 16 11:21:45 newsfeed nnrpd[18667]: SERVER perl filter not defined
Aug 16 11:22:46 newsfeed nnrpd[18673]: SERVER perl filter not defined
Aug 16 11:23:06 newsfeed nnrpd[18707]: SERVER perl filter not defined
Aug 16 11:24:49 newsfeed nnrpd[18708]: SERVER perl filter not defined
Aug 16 16:02:10 newsfeed nnrpd[27491]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2284030	98.6%	256.6 MB	98.2%
innd	18463	0.8%	3.1 MB	1.2%
nnrpd	7197	0.3%	763.8 KB	0.3%
innfeed	6732	0.3%	1017.9 KB	0.4%
TOTAL: 4	2316422	100.0%	261.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2279437	94.9%
Negative hits	86323	3.6%
Positive hits	35397	1.5%
Cache misses	300	0.0%
TOTAL: 4	2401457	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.712	0.0%	256	0.000	37.938	580.000
article cleanup	00:00:30.385	0.0%	2283596	0.005	0.013	0.027
article logging	00:01:02.660	0.1%	2283804	0.011	0.027	0.571
article parse	01:18:49.502	5.5%	77953597	0.013	0.061	0.089
article write	00:28:39.664	2.0%	2278498	0.429	0.755	2.485
artlog/artcncl	00:00:00.007	0.0%	254	0.000	0.028	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:32.967	0.2%	91502899	0.001	0.002	0.005
hisgrep/artcncl	00:00:01.903	0.0%	224	0.000	8.496	296.000
hishave/artcncl	00:00:01.592	0.0%	256	0.000	6.219	242.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	04:24:49.060	18.4%	2402228	2.179	6.614	13.314
history sync	00:00:00.014	0.0%	291	0.000	0.048	1.000
history write	05:33:49.924	23.2%	2280201	1.807	8.784	26.315
hiswrite/artcncl	00:00:00.585	0.0%	32	0.000	18.281	261.000
idle	04:53:23.943	20.4%	38818266	0.166	0.453	1.772
nntp read	00:41:16.434	2.9%	76056179	0.013	0.033	0.042
overview write	00:00:14.101	0.0%	2278498	0.000	0.006	0.013
perl filter	06:07:55.487	25.6%	2280201	6.002	9.681	11.096
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:45.207	0.3%	4615173	0.023	0.049	0.068
TOTAL: 23:59:57.828	23:37:03.147	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.243	0.0%	59022	0.000	0.004	0.083
article prepare	00:00:00.038	0.0%	124062	0.000	0.000	0.031
article read	00:00:35.639	0.0%	30460	0.000	1.170	63.421
backlog stats	00:00:00.301	0.0%	421103	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.842	0.0%	241262	0.000	0.012	8.000
data write	00:00:12.270	0.0%	228914	0.000	0.054	1.000
idle	23:59:05.604	99.9%	421103	0.000	205.046	11835.000
status file	00:00:01.672	0.0%	236	0.000	7.085	175.000
TOTAL: 24:00:07.434	23:59:58.609	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:44.696	0.1%	201901	0.221	0.221	0.221
idle	24:11:22.997	99.8%	663001	0.000	131.347	600095.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:20.648	0.0%	1327789	0.000	0.016	1.000
readart	00:00:53.412	0.1%	202265	0.000	0.264	242.000
TOTAL: 24:14:24.066	24:13:21.753	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	760	2272554	2266190	3240	3124	99%	476:51:50
2	news.nask.pl	2	15067	6119	7863	1085	40%	24:24:26
3	news.task.gda.pl	14	26555	5567	20130	858	20%	43:58:51
4	news.icm.edu.pl	49	230	15	182	33	6%	16:33:36
5	localhost	8	7	7	0	0	100%	00:00:01
TOTAL: 5		833	2314413	2277898	31415	5100	98%	561:48:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	820.2 GB	15.3 MB	47.9 MB	820.3 GB	99%	379.0 KB
2	news.nask.pl	21.0 MB	2.7 MB	2.8 MB	26.4 MB	79%	3.8 KB
3	news.task.gda.pl	11.2 MB	34.1 KB	2.6 MB	13.8 MB	80%	2.2 KB
4	news.icm.edu.pl	661.1 KB	8.1 KB	1.1 MB	1.7 MB	37%	37.1 KB
5	localhost	16.8 KB	0.0 KB	0.0 KB	16.8 KB	100%	2.4 KB
TOTAL: 5		820.2 GB	18.0 MB	54.3 MB	820.3 GB	99%	376.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 16 04:15:04 - 04:59:59	73877	3.2%	27.40	24.3 GB	3.0%	9432.68
Aug 16 05:00:00 - 05:59:59	86406	3.8%	24.00	31.9 GB	3.9%	9302.72
Aug 16 06:00:00 - 06:59:59	82154	3.6%	22.82	30.2 GB	3.7%	8782.20
Aug 16 07:00:00 - 07:59:59	97176	4.3%	26.99	36.1 GB	4.4%	10504.49
Aug 16 08:00:00 - 08:59:59	96203	4.2%	26.72	34.9 GB	4.3%	10160.93
Aug 16 09:00:00 - 09:59:59	99335	4.4%	27.59	35.6 GB	4.3%	10376.08
Aug 16 10:00:00 - 10:59:59	102041	4.5%	28.34	36.9 GB	4.5%	10739.89
Aug 16 11:00:00 - 11:59:59	106779	4.7%	29.66	37.1 GB	4.5%	10811.55
Aug 16 12:00:00 - 12:59:59	96059	4.2%	26.68	36.6 GB	4.5%	10655.79
Aug 16 13:00:00 - 13:59:59	86728	3.8%	24.09	32.3 GB	3.9%	9395.15
Aug 16 14:00:00 - 14:59:59	99465	4.4%	27.63	36.1 GB	4.4%	10500.48
Aug 16 15:00:00 - 15:59:59	97569	4.3%	27.10	36.4 GB	4.4%	10611.71
Aug 16 16:00:00 - 16:59:59	100146	4.4%	27.82	34.8 GB	4.2%	10139.39
Aug 16 17:00:00 - 17:59:59	102249	4.5%	28.40	36.3 GB	4.4%	10578.38
Aug 16 18:00:00 - 18:59:59	103157	4.5%	28.65	36.5 GB	4.4%	10621.72
Aug 16 19:00:00 - 19:59:59	103366	4.5%	28.71	36.6 GB	4.5%	10648.02
Aug 16 20:00:00 - 20:59:59	96127	4.2%	26.70	35.6 GB	4.3%	10380.97
Aug 16 21:00:00 - 21:59:59	96666	4.2%	26.85	35.1 GB	4.3%	10228.10
Aug 16 22:00:00 - 22:59:59	97518	4.3%	27.09	35.9 GB	4.4%	10470.87
Aug 16 23:00:00 - 23:59:59	99073	4.3%	27.52	34.8 GB	4.2%	10122.12
Aug 17 00:00:00 - 00:59:59	79136	3.5%	21.98	28.4 GB	3.5%	8265.27
Aug 17 01:00:00 - 01:59:59	92201	4.0%	25.61	33.8 GB	4.1%	9854.16
Aug 17 02:00:00 - 02:59:59	99297	4.4%	27.58	34.3 GB	4.2%	9984.68
Aug 17 03:00:00 - 03:59:59	66610	2.9%	18.50	22.9 GB	2.8%	6680.27
Aug 17 04:00:00 - 04:15:02	19133	0.8%	21.21	6.8 GB	0.8%	7933.01
TOTAL: 23:59:58	2278471	100.0%	26.37	820.1 GB	100.0%	9953.01

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3249	263	2363	1	622
2	news.nask.pl	1117	22	1015	0	80
3	news.task.gda.pl	906	723	15	1	167
4	news.icm.edu.pl	33	0	1	0	32
TOTAL: 4		5305	1008	3394	2	901

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	140
mozilla.dev.identity	42
mailing.freebsd.svn-src-all	41
fa.freebsd.cvs-all	40
alt.windows7.general	39
mailing.freebsd.svn-ports-all	38
mozilla.dev.tree-management	37
mailing.freebsd.svn-ports-head	37
mailing.freebsd.svn-src-head	35
mailing.gcc.gcc-cvs	33
mailing.www.horde-bugs	31
mailing.database.pgsql-general	29
mailing.unix.dovecot	24
mailing.postfix.users	23
uk.rec.cycling.moderated	20
de.comp.office-pakete.ms-office.word	19
mailing.www.horde-general	18
gmane.linux.alsa.devel	18
mailing.openbsd.source-changes	18
it.tlc.cellulari.android	17
TOTAL: 78	1008

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

Groups	Count
fr.bienvenue	1
misc.activism.militia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	432
EMP (fsl)	138
Attached HTML file	81
EMP (md5)	32
HTML post	8
Scoring filter (12)	1
Scoring filter (10)	1
TOTAL: 7	693

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	67366	3669	27048	23592	1	836	5%	23:56:39
2	panorama	58903	2658	54558	917	0	0	4%	23:59:47
3	poznan	13326	55	11394	50	0	0	0%	23:59:24
4	task	1491	9	790	5	0	0	0%	21:32:44
5	nask	15396	6	11945	1	0	0	0%	23:56:13
6	okapi	0	0	0	0	0	2485	0%	24:00:00
TOTAL: 6		156482	6397	105735	24565	1	3321	4%	141:24:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	35.9 MB	5.3 GB	5.4 GB	65.3 KB/s	206.5 KB	23:56:39
2	panorama	7.2 MB	3.2 MB	10.5 MB	0.1 KB/s	3.0 KB	23:59:47
3	poznan	361.6 KB	88.4 KB	450.0 KB	0.0 KB/s	4.3 KB	23:59:24
4	task	19.1 KB	8.1 KB	27.2 KB	0.0 KB/s	1.9 KB	21:32:44
5	nask	15.4 KB	1.9 KB	17.3 KB	0.0 KB/s	2.5 KB	23:56:13
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		43.5 MB	5.3 GB	5.4 GB	11.1 KB/s	182.2 KB	141:24:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	287	341	559.2 KB	287	0	00:00:10
2	web.man.lodz.pl	288	74	75.5 KB	27	0	00:00:21
3	lze200.lze.lodz.pl	4	35	65.9 KB	5	0	00:46:47
4	ip-176.105.135.22.tvsat364.lodz.pl	5	2	4.2 KB	2	0	01:23:24
5	kujawiak.man.lodz.pl	7	0	0.0 KB	0	7	00:00:01
TOTAL: 5		591	452	704.9 KB	321	7	24:14:23

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	287	341	559.2 KB	287	0	00:00:10
2	*.man.lodz.pl	679	74	75.5 KB	27	7	00:00:34
3	*.lze.lodz.pl	4	35	65.9 KB	5	0	00:46:47
4	*.105.135.22.tvsat364.lodz.pl	5	2	4.2 KB	2	0	01:23:24
5	*.207.15.62.static.jazztel.es	3	0	0.0 KB	0	0	22:01:56
6	unresolved	57	0	0.0 KB	0	0	00:01:30
TOTAL: 6		1035	452	704.9 KB	321	7	24:14:23

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
190.207.15.62.static.jazztel.es	38.350	110.855	22:01:56
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.100	01:23:24
lze200.lze.lodz.pl	0.012	0.092	00:46:47
122.150.16.97	0.152	0.964	00:01:30
web.man.lodz.pl	0.768	5.004	00:00:21
vegas.cm.p.lodz.pl	0.632	4.952	00:00:10
f-oper-v100.man.lodz.pl	0.652	5.464	00:00:09
kujawiak.man.lodz.pl	0.028	0.148	00:00:01
david.man.lodz.pl	0.244	1.668	00:00:01
TOTAL: 9	40.846	129.247	24:14:23

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
122.150.16.97	57
190.207.15.62.static.jazztel.es	3
TOTAL: 4	444

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	341	75.4%
2	pl	111	24.6%
TOTAL: 2		452	100.0%

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

Newsgroup		Count
1	lodman.info	341
2	pl.comp.pecet	94
3	pl.comp.os.ms-windows.winnt	10
4	pl.regionalne.lodz	4
5	pl.comp.os.linux	2
6	pl.rec.dom	1
TOTAL: 6		452

Daily Usenet report

Aug 16 04:15:04 -- Aug 17 04:15:02