Daily Usenet report: Oct 11 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Oct 11 07:10:31 newsfeed overchan[2219]: input line too long, skipping
Oct 11 11:55:23 newsfeed nnrpd[17643]: SERVER perl filter not defined
Oct 11 11:58:33 newsfeed nnrpd[17671]: SERVER perl filter not defined
Oct 11 13:59:55 newsfeed nnrpd[21621]: SERVER perl filter not defined
Oct 11 14:00:40 newsfeed nnrpd[21692]: SERVER perl filter not defined
Oct 11 15:44:37 newsfeed nnrpd[24868]: SERVER perl filter not defined
Oct 11 15:48:18 newsfeed nnrpd[24940]: SERVER perl filter not defined
Oct 11 21:44:42 newsfeed overchan[2219]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2270455	98.5%	256.0 MB	98.1%
innd	19055	0.8%	3.1 MB	1.2%
nnrpd	8701	0.4%	916.1 KB	0.3%
innfeed	6860	0.3%	1.0 MB	0.4%
overchan	2	0.0%	0.4 KB	0.0%
TOTAL: 5	2305073	100.0%	261.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2263981	95.4%
Negative hits	68372	2.9%
Positive hits	39251	1.7%
Cache misses	2107	0.1%
TOTAL: 4	2373711	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.083	0.0%	357	0.000	19.840	281.000
article cleanup	00:00:31.415	0.0%	2270872	0.004	0.014	0.026
article logging	00:01:20.622	0.1%	2271036	0.014	0.036	0.723
article parse	01:11:25.461	5.0%	73474261	0.021	0.058	0.094
article write	00:25:13.717	1.8%	2264377	0.422	0.668	1.310
artlog/artcncl	00:00:00.013	0.0%	353	0.000	0.037	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:13.103	0.2%	87013393	0.001	0.002	0.005
hisgrep/artcncl	00:00:01.970	0.0%	304	0.000	6.480	222.000
hishave/artcncl	00:00:01.651	0.0%	357	0.000	4.625	29.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:41:51.905	15.4%	2375975	0.343	5.603	41.984
history sync	00:00:00.010	0.0%	290	0.000	0.034	1.000
history write	04:57:29.184	20.7%	2266311	2.780	7.876	41.067
hiswrite/artcncl	00:00:00.215	0.0%	53	0.000	4.057	16.000
idle	06:38:31.873	27.7%	34962701	0.155	0.684	1.916
nntp read	00:41:16.706	2.9%	71582910	0.017	0.035	0.049
overview write	00:00:13.768	0.0%	2264377	0.001	0.006	0.016
perl filter	05:53:05.844	24.5%	2266310	5.757	9.348	11.300
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:10.386	0.3%	4571070	0.030	0.055	0.076
TOTAL: 24:00:31.795	23:37:34.926	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.165	0.0%	42318	0.000	0.004	0.143
article prepare	00:00:00.010	0.0%	39292	0.000	0.000	0.062
article read	00:00:01.293	0.0%	9466	0.000	0.137	3.000
backlog stats	00:00:00.224	0.0%	311491	0.000	0.001	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.256	0.0%	183774	0.000	0.012	1.000
data write	00:00:05.831	0.0%	158917	0.000	0.037	1.000
idle	24:00:21.371	100.0%	311491	0.000	277.444	15070.000
status file	00:00:00.445	0.0%	218	0.000	2.041	104.500
TOTAL: 24:00:38.647	24:00:31.595	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	25:50:23.174	100.0%	9604	0.000	9685.878	600062.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.447	0.0%	21440	0.000	0.021	1.667
readart	00:00:00.743	0.0%	529	0.000	1.405	17.950
TOTAL: 25:50:58.274	25:50:24.364	100.0%	-	-	-	-

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	941	2259213	2252120	2887	4206	99%	435:30:19
2	news.task.gda.pl	11	29876	6440	22304	1132	21%	45:13:32
3	news.nask.pl	2	17100	5141	10795	1164	30%	24:20:36
4	news.icm.edu.pl	38	313	14	293	6	4%	19:46:50
5	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 5		999	2306508	2263721	36279	6508	98%	524:51:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	840.2 GB	21.1 MB	75.1 MB	840.3 GB	99%	390.5 KB
2	news.nask.pl	17.0 MB	4.1 MB	12.8 MB	34.0 MB	50%	5.5 KB
3	news.task.gda.pl	14.0 MB	143.2 KB	3.4 MB	17.6 MB	79%	2.4 KB
4	news.icm.edu.pl	307.9 KB	55.2 KB	79.1 KB	442.2 KB	69%	22.1 KB
5	localhost	8.6 KB	0.0 KB	0.0 KB	8.6 KB	100%	1.4 KB
TOTAL: 5		840.2 GB	25.4 MB	91.4 MB	840.3 GB	99%	388.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 11 04:15:02 - 04:59:59	66589	2.9%	24.68	24.0 GB	2.9%	9337.04
Oct 11 05:00:00 - 05:59:59	98004	4.3%	27.22	36.9 GB	4.4%	10756.11
Oct 11 06:00:00 - 06:59:59	101643	4.5%	28.23	38.5 GB	4.6%	11228.16
Oct 11 07:00:00 - 07:59:59	100778	4.5%	27.99	38.3 GB	4.6%	11151.42
Oct 11 08:00:00 - 08:59:59	96773	4.3%	26.88	36.4 GB	4.3%	10591.36
Oct 11 09:00:00 - 09:59:59	91528	4.0%	25.42	36.2 GB	4.3%	10548.28
Oct 11 10:00:00 - 10:59:59	91861	4.1%	25.52	37.1 GB	4.4%	10807.79
Oct 11 11:00:00 - 11:59:59	98207	4.3%	27.28	37.1 GB	4.4%	10816.99
Oct 11 12:00:00 - 12:59:59	78173	3.5%	21.71	28.5 GB	3.4%	8291.98
Oct 11 13:00:00 - 13:59:59	89360	3.9%	24.82	31.2 GB	3.7%	9095.51
Oct 11 14:00:00 - 14:59:59	89440	4.0%	24.84	29.9 GB	3.6%	8699.68
Oct 11 15:00:00 - 15:59:59	106341	4.7%	29.54	37.2 GB	4.4%	10842.46
Oct 11 16:00:00 - 16:59:59	83641	3.7%	23.23	29.8 GB	3.5%	8676.68
Oct 11 17:00:00 - 17:59:59	87658	3.9%	24.35	30.0 GB	3.6%	8726.96
Oct 11 18:00:00 - 18:59:59	104766	4.6%	29.10	38.1 GB	4.5%	11108.59
Oct 11 19:00:00 - 19:59:59	103126	4.6%	28.65	38.0 GB	4.5%	11069.01
Oct 11 20:00:00 - 20:59:59	104841	4.6%	29.12	38.9 GB	4.6%	11320.07
Oct 11 21:00:00 - 21:59:59	101994	4.5%	28.33	38.1 GB	4.5%	11109.61
Oct 11 22:00:00 - 22:59:59	107129	4.7%	29.76	39.2 GB	4.7%	11406.96
Oct 11 23:00:00 - 23:59:59	102743	4.5%	28.54	37.4 GB	4.4%	10879.73
Oct 12 00:00:00 - 00:59:59	84414	3.7%	23.45	32.0 GB	3.8%	9313.53
Oct 12 01:00:00 - 01:59:59	79972	3.5%	22.21	30.8 GB	3.7%	8984.83
Oct 12 02:00:00 - 02:59:59	78015	3.4%	21.67	29.9 GB	3.6%	8716.43
Oct 12 03:00:00 - 03:59:59	91288	4.0%	25.36	36.1 GB	4.3%	10508.13
Oct 12 04:00:00 - 04:15:02	25159	1.1%	27.89	9.8 GB	1.2%	11400.67
TOTAL: 24:00:00	2263443	100.0%	26.20	839.4 GB	100.0%	10187.68

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4342	207	3398	0	737
2	news.nask.pl	1173	12	1086	2	73
3	news.task.gda.pl	1139	1043	74	0	22
4	news.icm.edu.pl	5	0	4	0	1
TOTAL: 4		6659	1262	4562	2	833

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	105
mailing.gcc.gcc-cvs	73
mailing.freebsd.svn-ports-all	69
mailing.freebsd.svn-ports-head	69
mailing.unix.samba	60
mailing.freebsd.ports-bugs	49
mailing.unix.samba-technical	42
alt.politics.scorched-earth	42
alt.windows7.general	38
mailing.freebsd.svn-src-all	38
mailing.openbsd.misc	38
gmane.linux.alsa.devel	35
fa.freebsd.cvs-all	35
mailing.openbsd.ports-changes	31
mailing.freebsd.current	30
mailing.database.pgsql-general	29
uk.rec.cycling.moderated	26
mozilla.dev.gaia	26
mailing.freebsd.ports	26
comp.sys.raspberry-pi	26
TOTAL: 75	1262

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

Groups	Count
alt.pl.bez.cenzury	1
bit.listserv.catholic	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	606
Attached HTML file	38
EMP (md5)	16
HTML post	8
TOTAL: 4	668

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44086	2730	38834	819	0	0	6%	24:06:36
2	icm	59537	1455	32884	4625	2	0	2%	23:56:20
3	poznan	13046	99	11154	71	0	0	0%	23:52:58
4	nask	19518	19	13998	0	0	0	0%	23:57:18
5	task	2094	11	1112	1	0	2	0%	21:26:04
6	okapi	0	0	0	0	0	2941	0%	24:00:01
TOTAL: 6		138281	4314	97982	5516	2	2943	3%	141:19:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	20.5 MB	1.3 GB	1.3 GB	16.2 KB/s	229.8 KB	23:56:20
2	panorama	7.0 MB	2.7 MB	9.7 MB	0.1 KB/s	2.8 KB	24:06:36
3	poznan	318.7 KB	138.5 KB	457.3 KB	0.0 KB/s	2.7 KB	23:52:58
4	nask	35.4 KB	0.0 KB	35.4 KB	0.0 KB/s	1.9 KB	23:57:18
5	task	19.2 KB	2.0 KB	21.2 KB	0.0 KB/s	1.8 KB	21:26:04
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		27.8 MB	1.3 GB	1.3 GB	2.8 KB/s	143.2 KB	141:19:17

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	350	474.5 KB	288	0	00:00:09
2	web.man.lodz.pl	288	88	78.0 KB	36	0	00:00:22
3	212.191.78.190	141	84	172.4 KB	20	0	22:02:26
4	lze200.lze.lodz.pl	5	6	10.6 KB	3	0	00:40:13
5	ip-176.105.135.22.tvsat364.lodz.pl	11	1	1.6 KB	1	0	02:58:32
6	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 6		739	529	737.0 KB	348	6	25:50:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	350	474.5 KB	288	0	00:00:09
2	*.man.lodz.pl	678	88	78.0 KB	36	6	00:00:38
3	unresolved	141	84	172.4 KB	20	0	22:02:26
4	*.lze.lodz.pl	5	6	10.6 KB	3	0	00:40:13
5	*.105.135.22.tvsat364.lodz.pl	11	1	1.6 KB	1	0	02:58:32
6	*.dynamic.gprs.plus.pl	1	0	0.0 KB	0	0	00:00:11
7	*.191.14.62.static.jazztel.es	8	0	0.0 KB	0	0	00:08:24
8	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:12
9	*.toya.net.pl	69	0	0.0 KB	0	0	00:00:07
TOTAL: 9		1202	529	737.0 KB	348	6	25:50:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.416	2.172	22:02:26
ip-176.105.135.22.tvsat364.lodz.pl	0.024	0.168	02:58:32
lze200.lze.lodz.pl	0.012	0.092	00:40:13
16.191.14.62.static.jazztel.es	0.224	0.588	00:08:24
web.man.lodz.pl	0.736	4.520	00:00:22
f-oper-v100.man.lodz.pl	0.736	4.860	00:00:12
ebo3.neoplus.adsl.tpnet.pl	0.000	0.020	00:00:12
apn-31-1-200-80.dynamic.gprs.plus.pl	0.032	0.020	00:00:11
vegas.cm.p.lodz.pl	0.604	4.516	00:00:09
staticline41435.toya.net.pl	0.152	1.076	00:00:07
kujawiak.man.lodz.pl	0.024	0.120	00:00:01
david.man.lodz.pl	0.216	1.440	00:00:01
TOTAL: 12	3.176	19.592	25:50:57

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	69
16.191.14.62.static.jazztel.es	8
apn-31-1-200-80.dynamic.gprs.plus.pl	1
ebo3.neoplus.adsl.tpnet.pl	1
TOTAL: 6	463

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	3	0
TOTAL: 1	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	350	66.2%
2	pl	175	33.1%
3	alt	4	0.8%
TOTAL: 3		529	100.0%

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

Newsgroup		Count
1	lodman.info	350
2	pl.comp.pecet	84
3	pl.regionalne.warszawa	34
4	pl.rec.rowery	21
5	pl.misc.kolej	19
6	alt.pl.praca.oferowana	4
7	pl.internet.polip	4
8	pl.regionalne.lodz	4
9	pl.misc.telefonia.gsm	3
10	pl.soc.prawo	2
11	pl.rec.gory	2
12	pl.comp.os.linux	2
TOTAL: 12		529

Daily Usenet report

Oct 11 04:15:02 -- Oct 12 04:15:02