Daily Usenet report: Jun 7 04:15:03

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jun 7 08:36:13 newsfeed nnrpd[17004]: SERVER perl filter not defined
Jun 7 12:53:13 newsfeed nnrpd[25455]: SERVER perl filter not defined
Jun 7 21:00:39 newsfeed nnrpd[9483]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1381649	97.9%	154.4 MB	97.3%
innd	13699	1.0%	2.2 MB	1.4%
nnrpd	9217	0.7%	1023.4 KB	0.6%
innfeed	7390	0.5%	1.1 MB	0.7%
TOTAL: 4	1411955	100.0%	158.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1376863	94.8%
Negative hits	38920	2.7%
Positive hits	35678	2.5%
Cache misses	258	0.0%
TOTAL: 4	1451719	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.579	0.0%	371	0.000	20.429	595.000
article cleanup	00:00:24.121	0.0%	1381854	0.004	0.017	0.037
article logging	00:00:47.380	0.1%	1381936	0.012	0.034	0.535
article parse	00:58:20.947	4.1%	187612968	0.010	0.019	0.060
article write	00:13:26.760	0.9%	1377208	0.350	0.586	1.711
artlog/artcncl	00:00:00.006	0.0%	365	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:02:53.740	0.2%	197534349	0.000	0.001	0.012
hisgrep/artcncl	00:00:01.036	0.0%	217	0.000	4.774	60.400
hishave/artcncl	00:00:00.632	0.0%	371	0.000	1.704	144.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:33:09.726	6.5%	1453806	0.007	3.845	8.641
history sync	00:00:00.017	0.0%	293	0.000	0.058	1.000
history write	03:11:40.660	13.3%	1378922	0.071	8.340	17.709
hiswrite/artcncl	00:00:01.034	0.0%	154	0.000	6.714	158.000
idle	12:35:55.613	52.5%	151450998	0.175	0.299	0.724
nntp read	01:02:34.433	4.3%	186654254	0.012	0.020	0.033
overview write	00:00:09.176	0.0%	1377208	0.000	0.007	0.020
perl filter	03:34:18.868	14.9%	1378919	6.690	9.325	11.593
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:01.042	0.2%	2786899	0.028	0.065	0.095
TOTAL: 24:00:25.896	23:16:52.770	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.122	0.0%	32467	0.000	0.004	0.167
article prepare	00:00:00.005	0.0%	23648	0.000	0.000	0.125
article read	00:00:01.189	0.0%	5653	0.000	0.210	43.000
backlog stats	00:00:00.200	0.0%	271508	0.000	0.001	0.050
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.678	0.0%	147985	0.000	0.011	0.111
data write	00:00:05.027	0.0%	138620	0.000	0.036	0.222
idle	23:59:06.994	100.0%	271508	0.000	318.027	16067.000
status file	00:00:01.485	0.0%	277	0.000	5.361	170.000
TOTAL: 23:59:22.869	23:59:16.700	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:09:06.767	99.6%	6393	0.000	1211.758	208077.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.399	0.0%	15040	0.000	0.027	3.000
readart	00:00:01.552	0.0%	402	0.000	3.861	34.708
TOTAL: 02:09:37.557	02:09:08.718	99.6%	-	-	-	-

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	620	1371509	1367633	617	3259	99%	224:02:01
2	news.task.gda.pl	22	23209	4307	18088	814	18%	42:41:38
3	news.nask.pl	7	17393	3406	13458	529	19%	24:35:16
4	news.icm.edu.pl	44	187	6	174	7	3%	15:21:10
5	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 5		697	1412301	1375355	32337	4609	97%	306:40:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	498.4 GB	11.7 MB	132.5 MB	498.6 GB	99%	381.3 KB
2	news.nask.pl	17.1 MB	2.3 MB	2.4 MB	21.8 MB	78%	5.7 KB
3	news.task.gda.pl	8.6 MB	59.7 KB	2.2 MB	10.8 MB	79%	2.2 KB
4	news.icm.edu.pl	234.5 KB	9.8 KB	232.6 KB	477.0 KB	49%	36.7 KB
5	localhost	4.7 KB	0.0 KB	0.0 KB	4.7 KB	100%	1.6 KB
TOTAL: 5		498.5 GB	14.1 MB	137.3 MB	498.6 GB	99%	378.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 7 04:15:03 - 04:59:59	56423	4.1%	20.92	20.9 GB	4.2%	8113.12
Jun 7 05:00:00 - 05:59:59	83442	6.1%	23.18	30.0 GB	6.0%	8731.27
Jun 7 06:00:00 - 06:59:59	76433	5.6%	21.23	29.4 GB	5.9%	8564.81
Jun 7 07:00:00 - 07:59:59	72378	5.3%	20.11	27.3 GB	5.5%	7951.47
Jun 7 08:00:00 - 08:59:59	77297	5.6%	21.47	29.1 GB	5.8%	8473.82
Jun 7 09:00:00 - 09:59:59	47934	3.5%	13.31	17.9 GB	3.6%	5204.34
Jun 7 10:00:00 - 10:59:59	41571	3.0%	11.55	15.6 GB	3.1%	4534.28
Jun 7 11:00:00 - 11:59:59	40062	2.9%	11.13	15.7 GB	3.2%	4580.71
Jun 7 12:00:00 - 12:59:59	39298	2.9%	10.92	14.3 GB	2.9%	4154.94
Jun 7 13:00:00 - 13:59:59	50134	3.6%	13.93	17.3 GB	3.5%	5044.69
Jun 7 14:00:00 - 14:59:59	48454	3.5%	13.46	17.2 GB	3.4%	5004.11
Jun 7 15:00:00 - 15:59:59	43505	3.2%	12.08	15.2 GB	3.0%	4417.47
Jun 7 16:00:00 - 16:59:59	44073	3.2%	12.24	15.7 GB	3.1%	4574.09
Jun 7 17:00:00 - 17:59:59	43658	3.2%	12.13	16.5 GB	3.3%	4812.32
Jun 7 18:00:00 - 18:59:59	44987	3.3%	12.50	15.2 GB	3.0%	4413.62
Jun 7 19:00:00 - 19:59:59	40487	2.9%	11.25	15.2 GB	3.0%	4420.94
Jun 7 20:00:00 - 20:59:59	37676	2.7%	10.47	14.8 GB	3.0%	4317.20
Jun 7 21:00:00 - 21:59:59	44010	3.2%	12.22	15.9 GB	3.2%	4634.27
Jun 7 22:00:00 - 22:59:59	47777	3.5%	13.27	15.5 GB	3.1%	4515.83
Jun 7 23:00:00 - 23:59:59	49218	3.6%	13.67	17.1 GB	3.4%	4967.15
Jun 8 00:00:00 - 00:59:59	70469	5.1%	19.57	24.9 GB	5.0%	7242.62
Jun 8 01:00:00 - 01:59:59	90126	6.5%	25.04	31.3 GB	6.3%	9110.25
Jun 8 02:00:00 - 02:59:59	90944	6.6%	25.26	30.9 GB	6.2%	9004.80
Jun 8 03:00:00 - 03:59:59	77717	5.6%	21.59	29.4 GB	5.9%	8560.66
Jun 8 04:00:00 - 04:15:02	18484	1.3%	20.49	7.2 GB	1.4%	8403.11
TOTAL: 23:59:59	1376557	100.0%	15.93	499.3 GB	100.0%	6059.20

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3330	121	2378	0	831
2	news.task.gda.pl	852	668	27	0	157
3	news.nask.pl	538	1	526	1	10
4	news.icm.edu.pl	7	0	1	0	6
TOTAL: 4		4727	790	2932	1	1004

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	92
mailing.freebsd.svn-ports-head	86
mailing.freebsd.ports-bugs	74
uk.railway	42
mailing.freebsd.ports	32
it.tlc.cellulari.android	30
mailing.openbsd.misc	27
mailing.openbsd.source-changes	25
mailing.openssl.dev	24
mailing.unix.spamassassin-talk	21
fr.usenet.abus.nocem	21
24hoursupport.helpdesk	18
it.comp.os.win.windows8	18
alt.politics.scorched-earth	18
mailing.freebsd.svn-src-all	15
fa.freebsd.cvs-all	15
mailing.openbsd.ports-changes	14
mailing.postfix.users	13
mailing.gcc.gcc-cvs	12
mailing.unix.samba	12
TOTAL: 61	790

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	732
EMP (fsl)	138
Attached HTML file	26
EMP (md5)	20
Scoring filter (9)	1
HTML post	1
Scoring filter (8)	1
TOTAL: 7	919

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	36755	1872	30346	269	0	5%	24:00:42
2	icm	50807	1341	27063	1635	5	2%	23:58:12
3	nask	25015	292	14392	414	0	1%	23:53:21
4	poznan	9227	49	7556	15	0	0%	23:56:06
5	task	972	20	507	5	0	2%	20:40:36
6	okapi	0	0	0	0	1354	0%	24:00:01
TOTAL: 6		122776	3574	79864	2338	1359	2%	140:28:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	84.7 MB	270.9 MB	355.6 MB	4.2 KB/s	122.4 KB	23:58:12
2	panorama	11.8 MB	1.1 MB	12.9 MB	0.2 KB/s	6.2 KB	24:00:42
3	nask	3.7 MB	264.2 MB	267.9 MB	3.2 KB/s	388.5 KB	23:53:21
4	poznan	156.7 KB	25.7 KB	182.5 KB	0.0 KB/s	2.9 KB	23:56:06
5	task	55.5 KB	7.6 KB	63.1 KB	0.0 KB/s	2.5 KB	20:40:36
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		100.4 MB	536.2 MB	636.6 MB	1.3 KB/s	110.3 KB	140:28:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	324	488.5 KB	288	0	00:00:08
2	ip-176.105.135.22.tvsat364.lodz.pl	6	28	59.0 KB	1	0	00:14:06
3	web.man.lodz.pl	288	26	22.3 KB	11	0	00:00:26
4	pc-vicki.man.lodz.pl	1	24	61.7 KB	1	0	00:32:55
5	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 5		586	402	631.5 KB	301	3	02:09:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	324	488.5 KB	288	0	00:00:08
2	*.man.lodz.pl	676	50	84.0 KB	12	3	00:33:28
3	*.105.135.22.tvsat364.lodz.pl	6	28	59.0 KB	1	0	00:14:06
4	*.pa.nsw.optusnet.com.au	591	0	0.0 KB	0	0	00:37:28
5	*.wroclaw.mm.pl	8	0	0.0 KB	0	0	00:43:55
6	unresolved	1	0	0.0 KB	0	0	00:00:28
TOTAL: 6		1570	402	631.5 KB	301	3	02:09:36

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.020	0.076	00:43:55
pa49-181-246-23.pa.nsw.optusnet.com.au	1.472	5.588	00:37:28
pc-vicki.man.lodz.pl	0.000	0.020	00:32:55
ip-176.105.135.22.tvsat364.lodz.pl	0.020	0.056	00:14:06
194.33.77.50	0.000	0.012	00:00:28
web.man.lodz.pl	0.688	3.064	00:00:26
pc-skrzep2.cm.p.lodz.pl	0.764	2.700	00:00:08
mon.man.lodz.pl	0.632	2.768	00:00:04
david.man.lodz.pl	0.152	0.900	00:00:01
isvcs01.man.lodz.pl	0.020	0.028	00:00:00
TOTAL: 10	3.768	15.212	02:09:36

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-246-23.pa.nsw.optusnet.com.au	591
mon.man.lodz.pl	288
david.man.lodz.pl	96
host-89-231-50-15.wroclaw.mm.pl	8
194.33.77.50	1
TOTAL: 5	984

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-vicki.man.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	324	80.6%
2	pl	78	19.4%
TOTAL: 2		402	100.0%

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

Newsgroup		Count
1	lodman.info	324
2	pl.soc.prawo	28
3	pl.sci.medycyna	24
4	pl.comp.pecet	16
5	pl.comp.os.ms-windows.winnt	10
TOTAL: 5		402

Daily Usenet report

Jun 7 04:15:03 -- Jun 8 04:15:02