Daily Usenet report: Feb 12 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Feb 12 10:16:14 newsfeed overchan[2239]: input line too long, skipping
Feb 12 12:32:22 newsfeed nnrpd[12516]: SERVER perl filter not defined
Feb 12 13:39:39 newsfeed nnrpd[14750]: SERVER perl filter not defined
Feb 12 13:43:31 newsfeed nnrpd[14821]: SERVER perl filter not defined
Feb 12 13:44:43 newsfeed nnrpd[14848]: SERVER perl filter not defined
Feb 12 14:02:31 newsfeed nnrpd[15497]: SERVER perl filter not defined
Feb 12 15:29:30 newsfeed nnrpd[18372]: SERVER perl filter not defined
Feb 12 16:52:14 newsfeed nnrpd[21043]: SERVER perl filter not defined
Feb 12 17:02:16 newsfeed nnrpd[21390]: SERVER perl filter not defined
Feb 12 18:00:33 newsfeed nnrpd[23325]: SERVER perl filter not defined
Feb 12 18:23:06 newsfeed nnrpd[23993]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1684410	98.1%	191.5 MB	97.7%
innd	14499	0.8%	2.4 MB	1.2%
nnrpd	10274	0.6%	1.0 MB	0.5%
innfeed	7153	0.4%	1.1 MB	0.5%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	1716337	100.0%	196.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1678016	94.9%
Positive hits	45335	2.6%
Negative hits	44485	2.5%
Cache misses	298	0.0%
TOTAL: 4	1768134	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:16.308	0.0%	465	0.000	35.071	814.000
article cleanup	00:00:26.377	0.0%	1683290	0.002	0.016	0.029
article logging	00:00:49.440	0.1%	1683462	0.011	0.029	0.644
article parse	01:07:55.315	4.7%	146095977	0.010	0.028	0.068
article write	00:16:03.405	1.1%	1676512	0.332	0.575	2.408
artlog/artcncl	00:00:00.006	0.0%	430	0.000	0.014	1.000
artlog/artparse	00:00:00.002	0.0%	15	0.000	0.133	1.000
data move	00:03:31.875	0.2%	157022381	0.001	0.001	0.029
hisgrep/artcncl	00:00:02.235	0.0%	328	0.000	6.814	69.250
hishave/artcncl	00:00:02.550	0.0%	465	0.000	5.484	263.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:58:06.903	12.4%	1768807	0.096	6.042	15.461
history sync	00:00:00.033	0.0%	293	0.000	0.113	19.000
history write	03:49:38.681	16.0%	1678934	1.605	8.207	24.675
hiswrite/artcncl	00:00:00.961	0.0%	137	0.000	7.015	126.000
idle	09:53:01.351	41.2%	99085559	0.176	0.359	2.619
nntp read	00:57:58.985	4.0%	144785439	0.013	0.024	0.041
overview write	00:00:10.723	0.0%	1676512	0.000	0.006	0.016
perl filter	04:05:59.551	17.1%	1678919	5.833	8.791	10.274
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:18.490	0.2%	3397873	0.022	0.058	0.091
TOTAL: 23:59:44.186	23:17:23.191	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.209	0.0%	44875	0.000	0.005	0.167
article prepare	00:00:00.011	0.0%	43452	0.000	0.000	0.125
article read	00:00:01.930	0.0%	10464	0.000	0.184	30.000
backlog stats	00:00:00.227	0.0%	346971	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.245	0.0%	201145	0.000	0.011	1.000
data write	00:00:07.433	0.0%	176789	0.000	0.042	2.000
idle	23:59:42.563	100.0%	346971	0.000	248.962	15125.000
status file	00:00:01.279	0.0%	235	0.000	5.443	122.600
TOTAL: 24:00:04.114	23:59:55.897	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	43:16:35.730	100.0%	7026	0.000	22174.172	600176.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.407	0.0%	16815	0.000	0.024	1.111
readart	00:00:06.089	0.0%	714	0.000	8.528	91.231
TOTAL: 43:17:29.988	43:16:42.226	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	448	1671191	1666231	599	4361	99%	255:33:00
2	news.task.gda.pl	15	31808	6297	24026	1485	19%	44:55:48
3	news.nask.pl	3	21426	4842	15666	918	22%	24:53:45
4	localhost	11	10	10	0	0	100%	00:00:01
5	news.icm.edu.pl	37	376	5	342	29	1%	19:40:39
TOTAL: 5		514	1724811	1677385	40633	6793	97%	345:03:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	573.7 GB	18.9 MB	43.6 MB	573.7 GB	99%	360.1 KB
2	news.nask.pl	22.9 MB	3.0 MB	3.0 MB	28.9 MB	79%	5.1 KB
3	news.task.gda.pl	14.8 MB	68.0 KB	4.4 MB	19.3 MB	76%	2.5 KB
4	news.icm.edu.pl	364.9 KB	151.0 KB	862.8 KB	1.3 MB	26%	40.5 KB
5	localhost	16.8 KB	0.0 KB	0.0 KB	16.8 KB	100%	1.7 KB
TOTAL: 5		573.7 GB	22.2 MB	51.9 MB	573.8 GB	99%	357.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 12 04:15:03 - 04:59:59	61088	3.6%	22.65	21.8 GB	3.8%	8489.72
Feb 12 05:00:00 - 05:59:59	81464	4.9%	22.63	28.8 GB	5.0%	8379.39
Feb 12 06:00:00 - 06:59:59	82074	4.9%	22.80	29.5 GB	5.1%	8587.31
Feb 12 07:00:00 - 07:59:59	76792	4.6%	21.33	27.8 GB	4.8%	8094.16
Feb 12 08:00:00 - 08:59:59	79757	4.8%	22.15	29.1 GB	5.1%	8462.78
Feb 12 09:00:00 - 09:59:59	79376	4.7%	22.05	28.9 GB	5.0%	8403.98
Feb 12 10:00:00 - 10:59:59	94308	5.6%	26.20	32.4 GB	5.6%	9430.54
Feb 12 11:00:00 - 11:59:59	101470	6.1%	28.19	33.4 GB	5.8%	9730.40
Feb 12 12:00:00 - 12:59:59	99844	6.0%	27.73	33.5 GB	5.8%	9759.88
Feb 12 13:00:00 - 13:59:59	76079	4.5%	21.13	27.1 GB	4.7%	7891.67
Feb 12 14:00:00 - 14:59:59	71186	4.2%	19.77	24.4 GB	4.3%	7101.77
Feb 12 15:00:00 - 15:59:59	72357	4.3%	20.10	24.6 GB	4.3%	7158.65
Feb 12 16:00:00 - 16:59:59	47044	2.8%	13.07	15.7 GB	2.7%	4585.85
Feb 12 17:00:00 - 17:59:59	44971	2.7%	12.49	15.2 GB	2.6%	4418.23
Feb 12 18:00:00 - 18:59:59	52584	3.1%	14.61	16.6 GB	2.9%	4837.36
Feb 12 19:00:00 - 19:59:59	48141	2.9%	13.37	15.9 GB	2.8%	4625.00
Feb 12 20:00:00 - 20:59:59	49374	2.9%	13.71	16.0 GB	2.8%	4651.90
Feb 12 21:00:00 - 21:59:59	30076	1.8%	8.35	9.4 GB	1.6%	2743.63
Feb 12 22:00:00 - 22:59:59	28568	1.7%	7.94	8.8 GB	1.5%	2574.71
Feb 12 23:00:00 - 23:59:59	39676	2.4%	11.02	14.0 GB	2.4%	4083.47
Feb 13 00:00:00 - 00:59:59	77901	4.6%	21.64	25.9 GB	4.5%	7553.31
Feb 13 01:00:00 - 01:59:59	76388	4.6%	21.22	26.1 GB	4.5%	7596.66
Feb 13 02:00:00 - 02:59:59	94599	5.6%	26.28	31.1 GB	5.4%	9057.39
Feb 13 03:00:00 - 03:59:59	88564	5.3%	24.60	29.9 GB	5.2%	8716.70
Feb 13 04:00:00 - 04:15:03	23330	1.4%	25.84	7.6 GB	1.3%	8868.64
TOTAL: 24:00:00	1677011	100.0%	19.41	573.5 GB	100.0%	6959.56

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4512	243	3471	2	796
2	news.task.gda.pl	1520	1310	35	0	175
3	news.nask.pl	908	12	862	0	34
4	news.icm.edu.pl	29	0	3	0	26
TOTAL: 4		6969	1565	4371	2	1031

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	207
mailing.freebsd.svn-ports-head	205
uk.railway	97
mailing.freebsd.ports-bugs	91
gmane.linux.alsa.devel	74
alt.windows7.general	61
it.tlc.cellulari.android	44
comp.mobile.ipad	43
mozilla.dev.gaia	41
mailing.unix.samba	40
mailing.freebsd.ports	37
mailing.openbsd.source-changes	33
mozilla.dev.tree-management	33
mailing.gcc.gcc-cvs	32
mailing.unix.dovecot	32
mailing.freebsd.svn-src-all	32
fa.freebsd.cvs-all	31
mailing.postfix.users	28
alt.politics.scorched-earth	28
it.comp.os.win.windows7	27
TOTAL: 74	1565

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

Groups	Count
pl.sci.historia	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	587
EMP (fsl)	159
Attached HTML file	50
EMP (md5)	38
HTML post	6
Scoring filter (8)	1
TOTAL: 6	841

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	68491	4932	35808	3185	4	17	7%	23:59:59
2	panorama	46547	2195	42135	435	0	0	4%	23:54:23
3	poznan	12681	43	10852	18	0	0	0%	24:06:19
4	nask	26060	38	17109	0	0	0	0%	23:59:21
5	task	2678	7	1401	4	0	0	0%	22:15:08
6	okapi	0	0	0	0	0	3343	0%	24:00:01
TOTAL: 6		156457	7215	107305	3642	4	3360	4%	142:15:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	448.4 MB	827.7 MB	1.2 GB	15.1 KB/s	161.0 KB	23:59:59
2	panorama	9.4 MB	2.4 MB	11.8 MB	0.1 KB/s	4.6 KB	23:54:23
3	poznan	397.1 KB	80.6 KB	477.8 KB	0.0 KB/s	7.8 KB	24:06:19
4	nask	147.7 KB	0.0 KB	147.7 KB	0.0 KB/s	3.9 KB	23:59:21
5	task	33.2 KB	7.0 KB	40.2 KB	0.0 KB/s	3.7 KB	22:15:08
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		458.4 MB	830.2 MB	1.3 GB	2.6 KB/s	121.5 KB	142:15:11

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	398	613.4 KB	288	0	00:00:15
2	lze200.lze.lodz.pl	14	212	407.9 KB	11	0	02:12:33
3	web.man.lodz.pl	288	92	107.6 KB	38	0	00:00:27
4	212.191.78.190	187	11	19.4 KB	2	0	30:59:23
5	ip-176.105.135.22.tvsat364.lodz.pl	5	1	1.2 KB	1	0	01:57:04
6	kujawiak.man.lodz.pl	10	0	0.0 KB	0	10	00:00:01
TOTAL: 6		792	714	1.1 MB	340	10	43:17:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	398	613.4 KB	288	0	00:00:15
2	*.lze.lodz.pl	14	212	407.9 KB	11	0	02:12:33
3	*.man.lodz.pl	682	92	107.6 KB	38	10	00:00:36
4	unresolved	187	11	19.4 KB	2	0	30:59:23
5	*.105.135.22.tvsat364.lodz.pl	5	1	1.2 KB	1	0	01:57:04
6	*.pa.nsw.optusnet.com.au	91	0	0.0 KB	0	0	00:02:49
7	*.cbmm.lodz.pl	4	0	0.0 KB	0	0	00:38:10
8	*.4web.pl	36	0	0.0 KB	0	0	07:21:35
9	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:04:32
10	*.toya.net.pl	88	0	0.0 KB	0	0	00:00:25
TOTAL: 10		1397	714	1.1 MB	340	10	43:17:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.528	1.928	30:59:23
net133-43.4web.pl	0.104	0.336	07:21:35
lze200.lze.lodz.pl	0.064	0.208	02:12:33
ip-176.105.135.22.tvsat364.lodz.pl	0.012	0.048	01:57:04
router2.cbmm.lodz.pl	0.004	0.048	00:38:10
ebq202.neoplus.adsl.tpnet.pl	0.008	0.016	00:04:32
pa49-181-246-168.pa.nsw.optusnet.com.au	0.212	0.924	00:02:49
web.man.lodz.pl	0.604	2.852	00:00:27
staticline41435.toya.net.pl	0.220	0.904	00:00:25
vegas.cm.p.lodz.pl	0.600	2.896	00:00:15
mon.man.lodz.pl	0.704	2.820	00:00:04
kujawiak.man.lodz.pl	0.060	0.128	00:00:01
david.man.lodz.pl	0.208	0.788	00:00:01
?	0.004	0.056	00:00:00
TOTAL: 14	3.332	13.952	43:17:28

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
pa49-181-246-168.pa.nsw.optusnet.com.au	91
staticline41435.toya.net.pl	88
net133-43.4web.pl	36
router2.cbmm.lodz.pl	4
ebq202.neoplus.adsl.tpnet.pl	2
TOTAL: 7	605

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
net133-43.4web.pl	4	0
lze200.lze.lodz.pl	3	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
router2.cbmm.lodz.pl	1	1
TOTAL: 4	10	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	398	55.7%
2	pl	316	44.3%
TOTAL: 2		714	100.0%

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

Newsgroup		Count
1	lodman.info	398
2	pl.rec.kuchnia	95
3	pl.comp.pecet	85
4	pl.misc.telefonia.gsm	79
5	pl.comp.os.ms-windows.winnt	38
6	pl.rec.rowery	9
7	pl.comp.os.linux	3
8	pl.news.admin	2
9	pl.rec.gory	2
10	pl.internet.polip	2
11	pl.soc.dzieci	1
TOTAL: 11		714

Daily Usenet report

Feb 12 04:15:03 -- Feb 13 04:15:03