Daily Usenet report: May 31 04:15:03

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 31 08:55:06 newsfeed nnrpd[17002]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1166185	96.5%	131.7 MB	95.8%
nnrpd	22580	1.9%	2.7 MB	2.0%
innd	12380	1.0%	2.0 MB	1.4%
innfeed	6978	0.6%	1.0 MB	0.8%
controlchan	4	0.0%	0.7 KB	0.0%
TOTAL: 5	1208127	100.0%	137.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1161592	94.9%
Positive hits	33650	2.7%
Negative hits	28900	2.4%
Cache misses	493	0.0%
TOTAL: 4	1224635	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.092	0.0%	462	0.000	21.844	491.000
article cleanup	00:00:18.256	0.0%	1165492	0.001	0.016	0.034
article logging	00:00:36.200	0.0%	1165562	0.014	0.031	0.321
article parse	00:48:27.780	3.4%	160441567	0.006	0.018	0.050
article write	00:11:46.593	0.8%	1160606	0.369	0.609	2.592
artlog/artcncl	00:00:00.010	0.0%	452	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:03:42.096	0.3%	168861997	0.000	0.001	0.059
hisgrep/artcncl	00:00:02.340	0.0%	370	0.000	6.324	292.000
hishave/artcncl	00:00:01.405	0.0%	462	0.000	3.041	146.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:34:06.183	10.7%	1224569	0.016	7.551	17.680
history sync	00:00:00.012	0.0%	290	0.000	0.041	4.000
history write	03:12:06.786	13.3%	1161841	1.790	9.921	33.161
hiswrite/artcncl	00:00:00.741	0.0%	92	0.000	8.054	258.000
idle	12:21:33.146	51.5%	135891505	0.195	0.327	1.073
nntp read	00:55:13.515	3.8%	159624123	0.009	0.021	0.066
overview write	00:00:07.735	0.0%	1160606	0.000	0.007	0.020
perl filter	02:57:45.414	12.3%	1161838	6.728	9.180	18.842
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:21.743	0.2%	2353990	0.010	0.060	0.102
TOTAL: 23:59:34.695	23:08:20.047	96.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.113	0.0%	32793	0.000	0.003	0.333
article prepare	00:00:00.006	0.0%	25860	0.000	0.000	0.042
article read	00:00:03.265	0.0%	6369	0.000	0.513	215.667
backlog stats	00:00:00.181	0.0%	257337	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.586	0.0%	145360	0.000	0.011	0.091
data write	00:00:05.675	0.0%	127617	0.000	0.044	1.114
idle	24:00:23.050	100.0%	257337	0.000	335.836	20951.000
status file	00:00:00.789	0.0%	239	0.000	3.301	134.333
TOTAL: 24:00:40.201	24:00:34.665	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:38:13.368	99.1%	19700	0.000	481.897	501097.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.919	0.0%	44878	0.000	0.020	1.111
readart	00:00:00.110	0.0%	334	0.000	0.329	7.077
TOTAL: 02:39:43.198	02:38:14.397	99.1%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
usenet@film.rlss.okayama-u.ac.jp	0	0	1	0	1	1
TOTAL	0	0	1	0	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	666	1156785	1153932	412	2441	99%	223:16:16
2	news.task.gda.pl	21	22899	4313	17828	758	18%	43:59:43
3	news.nask.pl	2	15069	2731	10741	1597	18%	23:31:53
4	news.icm.edu.pl	44	217	1	166	50	0%	14:20:08
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		735	1194971	1160978	29147	4846	97%	305:08:00

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	424.4 GB	7.1 MB	18.0 MB	424.5 GB	99%	384.9 KB
2	news.nask.pl	9.2 MB	4.5 MB	4.6 MB	18.2 MB	50%	4.3 KB
3	news.task.gda.pl	8.1 MB	60.9 KB	2.0 MB	10.2 MB	79%	2.1 KB
4	news.icm.edu.pl	58.3 KB	58.4 KB	689.0 KB	805.7 KB	7%	15.8 KB
5	localhost	1.5 KB	0.0 KB	0.0 KB	1.5 KB	100%	1.5 KB
TOTAL: 5		424.5 GB	11.7 MB	25.3 MB	424.5 GB	99%	381.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 31 04:15:03 - 04:59:59	28560	2.5%	10.59	10.5 GB	2.5%	4068.91
May 31 05:00:00 - 05:59:59	44503	3.8%	12.36	17.3 GB	4.1%	5042.29
May 31 06:00:00 - 06:59:59	38386	3.3%	10.66	15.2 GB	3.6%	4428.63
May 31 07:00:00 - 07:59:59	42757	3.7%	11.88	16.4 GB	3.9%	4767.04
May 31 08:00:00 - 08:59:59	47654	4.1%	13.24	17.2 GB	4.1%	5015.03
May 31 09:00:00 - 09:59:59	45060	3.9%	12.52	16.4 GB	3.9%	4767.97
May 31 10:00:00 - 10:59:59	46523	4.0%	12.92	17.2 GB	4.1%	5011.31
May 31 11:00:00 - 11:59:59	43088	3.7%	11.97	16.3 GB	3.8%	4748.90
May 31 12:00:00 - 12:59:59	42145	3.6%	11.71	15.7 GB	3.7%	4583.37
May 31 13:00:00 - 13:59:59	35550	3.1%	9.88	13.4 GB	3.2%	3904.27
May 31 14:00:00 - 14:59:59	37467	3.2%	10.41	14.5 GB	3.4%	4233.08
May 31 15:00:00 - 15:59:59	40959	3.5%	11.38	15.4 GB	3.6%	4485.66
May 31 16:00:00 - 16:59:59	37645	3.2%	10.46	14.4 GB	3.4%	4191.86
May 31 17:00:00 - 17:59:59	40596	3.5%	11.28	15.5 GB	3.7%	4527.40
May 31 18:00:00 - 18:59:59	40906	3.5%	11.36	15.4 GB	3.6%	4478.49
May 31 19:00:00 - 19:59:59	42791	3.7%	11.89	16.0 GB	3.8%	4646.72
May 31 20:00:00 - 20:59:59	44982	3.9%	12.49	16.6 GB	3.9%	4826.07
May 31 21:00:00 - 21:59:59	44397	3.8%	12.33	16.5 GB	3.9%	4792.10
May 31 22:00:00 - 22:59:59	44670	3.8%	12.41	16.5 GB	3.9%	4806.88
May 31 23:00:00 - 23:59:59	40975	3.5%	11.38	16.1 GB	3.8%	4676.54
Jun 1 00:00:00 - 00:59:59	67440	5.8%	18.73	22.8 GB	5.4%	6628.66
Jun 1 01:00:00 - 01:59:59	93948	8.1%	26.10	30.0 GB	7.1%	8732.52
Jun 1 02:00:00 - 02:59:59	89957	7.7%	24.99	30.1 GB	7.1%	8777.33
Jun 1 03:00:00 - 03:59:59	70432	6.1%	19.56	26.1 GB	6.1%	7605.22
Jun 1 04:00:00 - 04:15:04	9386	0.8%	10.38	3.4 GB	0.8%	3995.79
TOTAL: 24:00:01	1160777	100.0%	13.43	424.8 GB	100.0%	5155.32

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	2501	177	2025	3	296
2	news.nask.pl	1618	8	1589	11	10
3	news.task.gda.pl	768	572	37	9	150
4	news.icm.edu.pl	69	0	1	0	68
TOTAL: 4		4956	757	3652	23	524

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	98
mailing.freebsd.svn-ports-head	98
uk.railway	85
alt.politics.scorched-earth	46
alt.windows7.general	45
mailing.openbsd.source-changes	35
fr.usenet.abus.nocem	34
comp.mobile.ipad	27
mailing.freebsd.ports-bugs	18
mailing.freebsd.ports	16
it.tlc.cellulari.android	15
mailing.freebsd.svn-src-all	13
fa.freebsd.cvs-all	13
mailing.freebsd.svn-src-head	11
mozilla.dev.tree-management	11
comp.mobile.android	11
24hoursupport.helpdesk	10
mailing.openbsd.misc	10
comp.sys.raspberry-pi	10
it.comp.os.win.windows7	9
TOTAL: 69	757

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

Groups	Count
fr.comp.lang.php	8
pl.sci.matematyka	8
pl.sci.historia	5
fr.comp.securite	1
bit.listserv.catholic	1
TOTAL: 5	23

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	213
EMP (fsl)	139
EMP (md5)	75
Attached HTML file	20
Scoring filter (18)	2
Scoring filter (8)	2
Scoring filter (9)	1
TOTAL: 7	452

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	47378	2503	24069	2505	8	12	5%	23:59:57
2	panorama	35725	1031	31454	233	0	0	2%	23:53:53
3	nask	20477	125	12841	1	0	0	0%	23:59:02
4	task	1109	25	564	11	0	0	2%	21:14:21
5	poznan	8237	10	7080	9	0	0	0%	23:56:11
6	okapi	0	0	0	0	0	1383	0%	24:00:00
TOTAL: 6		112926	3694	76008	2759	8	1395	3%	141:03:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	32.4 MB	797.8 MB	830.1 MB	9.8 KB/s	169.7 KB	23:59:57
2	panorama	3.7 MB	903.2 KB	4.6 MB	0.1 KB/s	3.7 KB	23:53:53
3	nask	381.3 KB	192.5 KB	573.7 KB	0.0 KB/s	4.6 KB	23:59:02
4	task	57.6 KB	19.9 KB	77.5 KB	0.0 KB/s	2.2 KB	21:14:21
5	poznan	26.6 KB	29.4 KB	56.0 KB	0.0 KB/s	2.9 KB	23:56:11
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		36.5 MB	798.9 MB	835.4 MB	1.7 KB/s	132.6 KB	141:03:24

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	300	448.7 KB	288	0	00:00:07
2	web.man.lodz.pl	288	34	30.9 KB	15	0	00:00:29
3	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 3		577	334	479.6 KB	303	1	02:39:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	300	448.7 KB	288	0	00:00:07
2	*.man.lodz.pl	673	34	30.9 KB	15	1	00:00:35
3	*.pa.nsw.optusnet.com.au	3950	0	0.0 KB	0	0	02:08:35
4	*.181.14.62.static.jazztel.es	2	0	0.0 KB	0	0	00:00:10
5	unresolved	2	0	0.0 KB	0	0	00:30:09
TOTAL: 5		4915	334	479.6 KB	303	1	02:39:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pa49-181-246-53.pa.nsw.optusnet.com.au	7.444	40.192	01:43:18
194.33.77.50	0.004	0.024	00:30:09
pa49-181-246-243.pa.nsw.optusnet.com.au	1.704	9.476	00:21:27
pa49-181-246-153.pa.nsw.optusnet.com.au	0.268	1.652	00:03:49
web.man.lodz.pl	0.668	4.004	00:00:29
10.181.14.62.static.jazztel.es	0.004	0.012	00:00:10
pc-skrzep2.cm.p.lodz.pl	0.628	3.896	00:00:07
mon.man.lodz.pl	0.572	3.476	00:00:04
david.man.lodz.pl	0.236	1.168	00:00:01
isvcs01.man.lodz.pl	0.004	0.020	00:00:00
TOTAL: 10	11.532	63.920	02:39:38

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-246-53.pa.nsw.optusnet.com.au	3062
pa49-181-246-243.pa.nsw.optusnet.com.au	749
mon.man.lodz.pl	288
pa49-181-246-153.pa.nsw.optusnet.com.au	139
david.man.lodz.pl	96
10.181.14.62.static.jazztel.es	2
194.33.77.50	2
TOTAL: 7	4338

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-246-53.pa.nsw.optusnet.com.au	2	0
194.33.77.50	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	300	89.8%
2	pl	34	10.2%
TOTAL: 2		334	100.0%

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

Newsgroup		Count
1	lodman.info	300
2	pl.comp.pecet	30
3	pl.comp.os.ms-windows.winnt	4
TOTAL: 3		334

Daily Usenet report

May 31 04:15:03 -- Jun 1 04:15:04