Daily Usenet report: Apr 11 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Apr 11 11:20:32 newsfeed nnrpd[19699]: SERVER perl filter not defined
Apr 11 14:27:42 newsfeed nnrpd[25907]: SERVER perl filter not defined
Apr 11 14:28:46 newsfeed nnrpd[25914]: SERVER perl filter not defined
Apr 11 15:43:13 newsfeed nnrpd[28486]: SERVER perl filter not defined
Apr 11 15:43:42 newsfeed nnrpd[28490]: SERVER perl filter not defined
Apr 11 19:57:08 newsfeed innfeed[2440]: icm:0 connect: Network is unreachable
Apr 11 20:32:32 newsfeed nnrpd[5790]: SERVER perl filter not defined
Apr 11 20:45:08 newsfeed innfeed[2440]: icm:0 connect: Network is unreachable
Apr 11 22:45:08 newsfeed innfeed[2440]: icm:0 connect: Network is unreachable
Apr 11 22:56:22 newsfeed nnrpd[10536]: SERVER perl filter not defined
Apr 12 00:45:08 newsfeed innfeed[2440]: icm:0 connect: Network is unreachable
Apr 12 01:52:49 newsfeed nnrpd[16872]: SERVER perl filter not defined
Apr 12 02:45:08 newsfeed innfeed[2440]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1334309	98.0%	151.6 MB	97.5%
innd	12084	0.9%	2.1 MB	1.3%
nnrpd	8396	0.6%	910.5 KB	0.6%
innfeed	6823	0.5%	1.0 MB	0.6%
TOTAL: 4	1361612	100.0%	155.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1328789	94.0%
Negative hits	44987	3.2%
Positive hits	40065	2.8%
Cache misses	304	0.0%
TOTAL: 4	1414145	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.391	0.0%	304	0.000	27.602	547.000
article cleanup	00:00:20.976	0.0%	1333028	0.003	0.016	0.033
article logging	00:00:41.333	0.0%	1333148	0.009	0.031	0.362
article parse	00:54:33.296	3.8%	156453444	0.006	0.021	0.055
article write	00:12:39.068	0.9%	1327437	0.288	0.572	1.634
artlog/artcncl	00:00:00.007	0.0%	301	0.000	0.023	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:03:52.132	0.3%	165565366	0.000	0.001	0.038
hisgrep/artcncl	00:00:01.407	0.0%	282	0.000	4.989	220.000
hishave/artcncl	00:00:00.654	0.0%	304	0.000	2.151	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:48:36.729	7.5%	1414631	0.009	4.607	13.276
history sync	00:00:00.021	0.0%	292	0.000	0.072	1.000
history write	02:39:39.521	11.1%	1329419	1.049	7.206	28.152
hiswrite/artcncl	00:00:00.307	0.0%	22	0.000	13.955	246.000
idle	13:39:40.135	56.9%	107361050	0.228	0.458	0.975
nntp read	00:48:48.419	3.4%	155415686	0.008	0.019	0.032
overview write	00:00:08.738	0.0%	1327437	0.000	0.007	0.021
perl filter	03:11:07.795	13.3%	1329415	4.901	8.626	12.024
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:44.405	0.2%	2691687	0.036	0.061	0.160
TOTAL: 23:59:25.897	23:23:03.334	97.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.118	0.0%	36830	0.000	0.003	1.000
article prepare	00:00:00.005	0.0%	24176	0.000	0.000	0.083
article read	00:00:02.505	0.0%	5837	0.000	0.429	157.700
backlog stats	00:00:00.165	0.0%	245343	0.000	0.001	0.029
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:01.632	0.0%	149042	0.000	0.011	1.000
data write	00:00:03.257	0.0%	117284	0.000	0.028	1.000
idle	23:59:43.775	100.0%	245343	0.000	352.094	17606.000
status file	00:00:00.966	0.0%	235	0.000	4.111	85.000
TOTAL: 23:59:58.425	23:59:52.423	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:36:13.967	99.8%	5854	0.000	2831.221	600065.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.280	0.0%	13922	0.000	0.020	1.889
readart	00:00:00.605	0.0%	508	0.000	1.191	8.333
TOTAL: 04:36:50.799	04:36:14.852	99.8%	-	-	-	-

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	366	1321741	1317138	1012	3591	99%	234:15:40
2	news.task.gda.pl	14	29757	6426	21867	1464	21%	44:56:19
3	news.nask.pl	2	18386	4496	13344	546	24%	23:42:12
4	localhost	9	8	8	0	0	100%	00:00:01
5	news.icm.edu.pl	23	135	0	121	14	0%	09:31:19
TOTAL: 5		414	1370027	1328068	36344	5615	96%	312:25:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	489.1 GB	18.3 MB	36.5 MB	489.2 GB	99%	388.4 KB
2	news.nask.pl	25.5 MB	2.1 MB	2.2 MB	29.8 MB	85%	6.0 KB
3	news.task.gda.pl	12.9 MB	39.8 KB	4.5 MB	17.5 MB	73%	2.3 KB
4	localhost	13.7 KB	0.0 KB	0.0 KB	13.7 KB	100%	1.7 KB
5	news.icm.edu.pl	0.0 KB	17.8 KB	202.1 KB	219.8 KB	0%	15.7 KB
TOTAL: 5		489.2 GB	20.4 MB	43.4 MB	489.2 GB	99%	384.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 11 04:15:02 - 04:59:59	42633	3.2%	15.80	17.6 GB	3.6%	6843.29
Apr 11 05:00:00 - 05:59:59	58984	4.4%	16.38	24.5 GB	5.0%	7137.97
Apr 11 06:00:00 - 06:59:59	64988	4.9%	18.05	26.5 GB	5.4%	7714.88
Apr 11 07:00:00 - 07:59:59	59379	4.5%	16.49	24.6 GB	5.0%	7156.32
Apr 11 08:00:00 - 08:59:59	57468	4.3%	15.96	26.1 GB	5.3%	7615.49
Apr 11 09:00:00 - 09:59:59	61912	4.7%	17.20	28.2 GB	5.8%	8225.20
Apr 11 10:00:00 - 10:59:59	65067	4.9%	18.07	27.4 GB	5.6%	7974.75
Apr 11 11:00:00 - 11:59:59	71967	5.4%	19.99	24.6 GB	5.0%	7162.84
Apr 11 12:00:00 - 12:59:59	87653	6.6%	24.35	30.7 GB	6.3%	8953.16
Apr 11 13:00:00 - 13:59:59	81871	6.2%	22.74	27.8 GB	5.7%	8102.44
Apr 11 14:00:00 - 14:59:59	42120	3.2%	11.70	14.4 GB	2.9%	4195.39
Apr 11 15:00:00 - 15:59:59	39275	3.0%	10.91	13.7 GB	2.8%	3992.69
Apr 11 16:00:00 - 16:59:59	39670	3.0%	11.02	14.4 GB	2.9%	4182.20
Apr 11 17:00:00 - 17:59:59	41348	3.1%	11.49	15.7 GB	3.2%	4563.28
Apr 11 18:00:00 - 18:59:59	37059	2.8%	10.29	13.9 GB	2.8%	4040.41
Apr 11 19:00:00 - 19:59:59	35693	2.7%	9.91	13.4 GB	2.7%	3898.08
Apr 11 20:00:00 - 20:59:59	35998	2.7%	10.00	13.0 GB	2.7%	3798.89
Apr 11 21:00:00 - 21:59:59	29865	2.2%	8.30	11.2 GB	2.3%	3260.09
Apr 11 22:00:00 - 22:59:59	37146	2.8%	10.32	12.2 GB	2.5%	3540.86
Apr 11 23:00:00 - 23:59:59	36292	2.7%	10.08	13.4 GB	2.7%	3889.76
Apr 12 00:00:00 - 00:59:59	57625	4.3%	16.01	19.9 GB	4.1%	5808.57
Apr 12 01:00:00 - 01:59:59	64978	4.9%	18.05	18.2 GB	3.7%	5309.45
Apr 12 02:00:00 - 02:59:59	78532	5.9%	21.81	24.3 GB	5.0%	7075.64
Apr 12 03:00:00 - 03:59:59	78525	5.9%	21.81	26.0 GB	5.3%	7584.42
Apr 12 04:00:00 - 04:15:02	22243	1.7%	24.66	7.2 GB	1.5%	8407.14
TOTAL: 24:00:00	1328291	100.0%	15.37	489.0 GB	100.0%	5934.08

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3697	157	3040	3	497
2	news.task.gda.pl	1423	1186	22	4	211
3	news.nask.pl	584	11	551	4	18
4	news.icm.edu.pl	13	0	2	0	11
TOTAL: 4		5717	1354	3615	11	737

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	182
mailing.freebsd.svn-ports-head	181
mailing.freebsd.ports-bugs	88
mozilla.dev.tree-management	64
fr.usenet.abus.nocem	53
gnu.hurd.bug	46
uk.railway	46
mailing.gcc.gcc-cvs	43
mailing.freebsd.ports	37
mailing.database.pgsql-general	35
mailing.openbsd.source-changes	34
alt.windows7.general	32
mailing.unix.samba	31
mailing.freebsd.svn-src-all	31
fa.freebsd.cvs-all	31
24hoursupport.helpdesk	29
mailing.openbsd.misc	29
mailing.freebsd.svn-src-head	27
it.comp.os.win.windows7	24
mozilla.dev.gaia	22
TOTAL: 73	1354

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

Groups	Count
pl.sci.historia	4
fr.comp.securite	3
pl.sci.matematyka	2
bit.listserv.catholic	1
christnet.religion	1
TOTAL: 5	11

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	380
EMP (fsl)	167
EMP (md5)	26
EMP (phl)	20
Attached HTML file	8
HTML post	8
Scoring filter (8)	2
Scoring filter (12)	1
Excessively crossposted test article	1
TOTAL: 9	613

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	36876	2110	19030	1589	4	14031	5%	24:03:46
2	panorama	38086	1949	34567	300	0	0	5%	23:57:46
3	nask	24649	38	15897	1	0	0	0%	24:02:12
4	poznan	12756	33	10764	8	0	0	0%	24:04:00
5	task	1907	23	989	2	0	5	1%	21:36:16
6	okapi	0	0	0	0	0	2401	0%	24:00:03
TOTAL: 6		114274	4153	81247	1900	4	16437	3%	141:44:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	64.7 MB	96.1 MB	160.7 MB	1.9 KB/s	44.5 KB	24:03:46
2	panorama	13.3 MB	850.4 KB	14.1 MB	0.2 KB/s	6.4 KB	23:57:46
3	nask	343.4 KB	2.2 KB	345.5 KB	0.0 KB/s	8.9 KB	24:02:12
4	poznan	205.4 KB	13.2 KB	218.7 KB	0.0 KB/s	5.3 KB	24:04:00
5	task	45.5 KB	4.9 KB	50.4 KB	0.0 KB/s	2.0 KB	21:36:16
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 6		78.5 MB	96.9 MB	175.5 MB	0.4 KB/s	29.7 KB	141:44:03

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	181	245	421.1 KB	181	0	00:00:05
2	vegas.cm.p.lodz.pl	105	117	249.2 KB	105	0	00:00:06
3	web.man.lodz.pl	288	108	106.1 KB	44	0	00:00:24
4	lze200.lze.lodz.pl	6	25	47.3 KB	4	0	00:26:28
5	ip-176.105.135.22.tvsat364.lodz.pl	5	13	29.2 KB	1	0	01:33:49
6	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 6		593	508	853.0 KB	335	8	04:36:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	286	362	670.3 KB	286	0	00:00:11
2	*.man.lodz.pl	680	108	106.1 KB	44	8	00:00:33
3	*.lze.lodz.pl	6	25	47.3 KB	4	0	00:26:28
4	*.105.135.22.tvsat364.lodz.pl	5	13	29.2 KB	1	0	01:33:49
5	*.pa.nsw.optusnet.com.au	349	0	0.0 KB	0	0	00:18:30
6	*.t17.ds.pwr.wroc.pl	1	0	0.0 KB	0	0	00:00:00
7	*.wroclaw.mm.pl	10	0	0.0 KB	0	0	02:16:20
8	*.206.15.62.static.jazztel.es	2	0	0.0 KB	0	0	00:00:53
9	*.toya.net.pl	1	0	0.0 KB	0	0	00:00:00
TOTAL: 9		1340	508	853.0 KB	335	8	04:36:48

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.040	0.072	02:16:20
ip-176.105.135.22.tvsat364.lodz.pl	0.012	0.044	01:33:49
lze200.lze.lodz.pl	0.024	0.060	00:26:28
pa49-181-247-69.pa.nsw.optusnet.com.au	0.768	3.076	00:18:30
130.206.15.62.static.jazztel.es	0.020	0.028	00:00:53
web.man.lodz.pl	0.556	2.784	00:00:24
vegas.cm.p.lodz.pl	0.208	0.924	00:00:06
mon.man.lodz.pl	0.624	2.580	00:00:05
pc-skrzep2.cm.p.lodz.pl	0.424	1.636	00:00:05
david.man.lodz.pl	0.208	0.856	00:00:01
isvcs01.man.lodz.pl	0.048	0.076	00:00:01
nedza.t17.ds.pwr.wroc.pl	0.032	0.012	00:00:00
staticline41435.toya.net.pl	0.000	0.004	00:00:00
TOTAL: 13	2.964	12.152	04:36:48

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-247-69.pa.nsw.optusnet.com.au	349
mon.man.lodz.pl	288
david.man.lodz.pl	96
host-89-231-50-15.wroclaw.mm.pl	10
130.206.15.62.static.jazztel.es	2
nedza.t17.ds.pwr.wroc.pl	1
staticline41435.toya.net.pl	1
TOTAL: 7	747

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	2	0
host-89-231-50-15.wroclaw.mm.pl	2	0
TOTAL: 2	4	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	362	71.3%
2	pl	146	28.7%
TOTAL: 2		508	100.0%

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

Newsgroup		Count
1	lodman.info	362
2	pl.comp.pecet	80
3	pl.comp.os.ms-windows.winnt	38
4	pl.misc.telefonia.gsm	15
5	pl.soc.prawo	13
TOTAL: 5		508

Daily Usenet report

Apr 11 04:15:02 -- Apr 12 04:15:02