Daily Usenet report: Sep 22 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 23 02:32:34 newsfeed nnrpd[3896]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2043604	98.5%	231.3 MB	98.0%
innd	17623	0.8%	2.9 MB	1.2%
nnrpd	6975	0.3%	731.2 KB	0.3%
innfeed	6896	0.3%	1.0 MB	0.4%
controlchan	2	0.0%	0.4 KB	0.0%
TOTAL: 5	2075100	100.0%	236.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2038486	94.8%
Negative hits	75403	3.5%
Positive hits	35793	1.7%
Cache misses	305	0.0%
TOTAL: 4	2149987	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.033	0.0%	412	0.000	26.779	684.000
article cleanup	00:00:27.706	0.0%	2043601	0.002	0.014	0.029
article logging	00:01:10.429	0.1%	2043794	0.014	0.034	0.714
article parse	01:04:01.478	4.4%	64485331	0.014	0.060	0.098
article write	00:24:11.069	1.7%	2037920	0.349	0.712	3.162
artlog/artcncl	00:00:00.005	0.0%	391	0.000	0.013	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:59.521	0.1%	76579743	0.000	0.002	0.005
hisgrep/artcncl	00:00:02.989	0.0%	270	0.000	11.070	300.000
hishave/artcncl	00:00:02.200	0.0%	412	0.000	5.340	195.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	04:03:45.853	16.9%	2150859	1.225	6.800	40.850
history sync	00:00:00.006	0.0%	290	0.000	0.021	1.000
history write	05:06:24.287	21.3%	2039375	4.463	9.015	50.532
hiswrite/artcncl	00:00:01.156	0.0%	142	0.000	8.141	233.000
idle	06:48:06.198	28.3%	30558167	0.140	0.801	3.026
nntp read	00:38:01.995	2.6%	62786439	0.011	0.036	0.052
overview write	00:00:12.956	0.0%	2037920	0.000	0.006	0.017
perl filter	05:27:37.037	22.7%	2039373	3.687	9.639	11.634
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:45.063	0.3%	4120488	0.015	0.055	0.079
TOTAL: 24:00:20.541	23:40:00.981	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.172	0.0%	44895	0.000	0.004	1.000
article prepare	00:00:00.025	0.0%	63604	0.000	0.000	0.050
article read	00:00:09.634	0.0%	15654	0.000	0.615	28.555
backlog stats	00:00:00.253	0.0%	327063	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.434	0.0%	188279	0.000	0.013	0.068
data write	00:00:08.820	0.0%	173810	0.000	0.051	2.000
idle	23:59:29.984	100.0%	327063	0.000	264.078	14070.000
status file	00:00:00.880	0.0%	237	0.000	3.713	114.000
TOTAL: 23:59:59.549	23:59:52.202	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:07.206	0.0%	58118	0.124	0.124	0.124
idle	04:14:48.364	99.6%	73408	0.000	208.266	52186.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.631	0.0%	148552	0.000	0.018	1.333
readart	00:00:18.497	0.1%	58409	0.000	0.317	51.000
TOTAL: 04:15:47.921	04:15:16.698	99.8%	-	-	-	-

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
ba-mod@panix.com	0	0	1	0	0	1
TOTAL	0	0	1	0	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	913	2032157	2027085	1832	3240	99%	428:26:09
2	news.task.gda.pl	16	27130	5849	20280	1001	21%	43:38:54
3	news.nask.pl	2	15047	4920	8706	1421	32%	23:44:09
4	news.icm.edu.pl	42	271	13	247	11	4%	18:28:27
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		975	2074606	2037868	31065	5673	98%	514:17:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	754.6 GB	12.5 MB	45.6 MB	754.6 GB	99%	389.7 KB
2	news.nask.pl	17.1 MB	3.4 MB	3.6 MB	24.1 MB	70%	3.9 KB
3	news.task.gda.pl	10.9 MB	101.6 KB	2.5 MB	13.5 MB	81%	2.0 KB
4	news.icm.edu.pl	490.2 KB	8.5 KB	94.0 KB	592.8 KB	82%	24.7 KB
5	localhost	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
TOTAL: 5		754.6 GB	16.0 MB	51.7 MB	754.7 GB	99%	387.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 22 04:15:02 - 04:59:59	67903	3.3%	25.17	25.6 GB	3.4%	9960.08
Sep 22 05:00:00 - 05:59:59	93242	4.6%	25.90	36.0 GB	4.8%	10472.25
Sep 22 06:00:00 - 06:59:59	99061	4.9%	27.52	38.5 GB	5.1%	11216.64
Sep 22 07:00:00 - 07:59:59	84695	4.2%	23.53	34.6 GB	4.6%	10090.05
Sep 22 08:00:00 - 08:59:59	48101	2.4%	13.36	20.0 GB	2.7%	5838.38
Sep 22 09:00:00 - 09:59:59	47147	2.3%	13.10	19.6 GB	2.6%	5710.51
Sep 22 10:00:00 - 10:59:59	58623	2.9%	16.28	23.0 GB	3.0%	6691.82
Sep 22 11:00:00 - 11:59:59	65015	3.2%	18.06	22.6 GB	3.0%	6579.05
Sep 22 12:00:00 - 12:59:59	113588	5.6%	31.55	33.7 GB	4.5%	9801.88
Sep 22 13:00:00 - 13:59:59	78444	3.9%	21.79	26.6 GB	3.5%	7733.72
Sep 22 14:00:00 - 14:59:59	82593	4.1%	22.94	31.3 GB	4.2%	9113.54
Sep 22 15:00:00 - 15:59:59	82449	4.0%	22.90	30.2 GB	4.0%	8784.64
Sep 22 16:00:00 - 16:59:59	100432	4.9%	27.90	37.6 GB	5.0%	10952.61
Sep 22 17:00:00 - 17:59:59	94759	4.7%	26.32	34.8 GB	4.6%	10132.02
Sep 22 18:00:00 - 18:59:59	93330	4.6%	25.93	32.5 GB	4.3%	9478.99
Sep 22 19:00:00 - 19:59:59	95910	4.7%	26.64	34.6 GB	4.6%	10074.51
Sep 22 20:00:00 - 20:59:59	89535	4.4%	24.87	30.9 GB	4.1%	9002.03
Sep 22 21:00:00 - 21:59:59	96028	4.7%	26.67	34.4 GB	4.6%	10025.03
Sep 22 22:00:00 - 22:59:59	91504	4.5%	25.42	34.9 GB	4.6%	10154.97
Sep 22 23:00:00 - 23:59:59	97942	4.8%	27.21	35.4 GB	4.7%	10314.36
Sep 23 00:00:00 - 00:59:59	83364	4.1%	23.16	30.4 GB	4.0%	8854.59
Sep 23 01:00:00 - 01:59:59	76915	3.8%	21.37	31.4 GB	4.2%	9143.34
Sep 23 02:00:00 - 02:59:59	79145	3.9%	21.98	30.6 GB	4.1%	8903.01
Sep 23 03:00:00 - 03:59:59	91967	4.5%	25.55	35.3 GB	4.7%	10273.40
Sep 23 04:00:00 - 04:15:03	25646	1.3%	28.40	9.5 GB	1.3%	11063.66
TOTAL: 24:00:01	2037338	100.0%	23.58	753.9 GB	100.0%	9149.10

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3394	184	2782	0	428
2	news.nask.pl	1461	18	1399	0	44
3	news.task.gda.pl	1009	771	46	1	191
4	news.icm.edu.pl	11	0	1	0	10
TOTAL: 4		5875	973	4228	1	673

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	173
mailing.freebsd.svn-ports-all	55
mailing.freebsd.svn-ports-head	55
alt.windows7.general	48
mailing.freebsd.current	44
uk.railway	39
comp.mobile.ipad	37
mailing.freebsd.svn-src-all	30
mailing.freebsd.svn-src-head	29
mozilla.dev.tree-management	26
alt.politics.scorched-earth	25
fa.freebsd.cvs-all	25
mailing.dragonflybsd.commits	22
fr.rec.loisirs-creatifs	22
mailing.freebsd.ports	18
it.tlc.cellulari.android	18
mailing.unix.dovecot	17
it.tlc.cellulari.iphone	17
comp.sys.raspberry-pi	17
fr.usenet.abus.nocem	15
TOTAL: 75	973

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

Groups	Count
fr.comp.lang.php	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	276
EMP (fsl)	170
EMP (md5)	26
Angle-bracket bot	2
Attached HTML file	2
HTML post	1
Scoring filter (8)	1
TOTAL: 7	478

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	56708	2814	28566	9863	2	250	4%	23:59:16
2	panorama	45456	2512	41468	633	0	0	5%	23:58:17
3	task	1695	25	882	4	0	3	1%	20:31:19
4	poznan	12350	18	10786	9	0	0	0%	24:00:20
5	nask	16720	15	12306	1	0	0	0%	23:59:59
6	okapi	0	0	0	0	0	2009	0%	24:00:00
TOTAL: 6		132929	5384	94008	10510	2	2262	4%	140:29:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	34.5 MB	2.9 GB	2.9 GB	35.2 KB/s	239.4 KB	23:59:16
2	panorama	7.2 MB	2.5 MB	9.7 MB	0.1 KB/s	3.1 KB	23:58:17
3	poznan	181.6 KB	34.2 KB	215.8 KB	0.0 KB/s	8.0 KB	24:00:20
4	task	52.3 KB	9.4 KB	61.8 KB	0.0 KB/s	2.1 KB	20:31:19
5	nask	41.2 KB	249.0 KB	290.1 KB	0.0 KB/s	18.1 KB	23:59:59
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		42.0 MB	2.9 GB	2.9 GB	6.0 KB/s	191.6 KB	140:29:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	300	535.4 KB	288	0	00:00:09
2	web.man.lodz.pl	288	104	76.7 KB	42	0	00:00:21
3	kujawiak.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 3		577	404	612.1 KB	330	1	04:15:47

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	300	535.4 KB	288	0	00:00:09
2	*.man.lodz.pl	674	104	76.7 KB	42	1	00:00:33
3	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:03:37
4	*.181.14.62.static.jazztel.es	6	0	0.0 KB	0	0	04:11:22
5	*.toya.net.pl	32	0	0.0 KB	0	0	00:00:04
TOTAL: 5		1002	404	612.1 KB	330	1	04:15:47

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
252.181.14.62.static.jazztel.es	5.792	25.630	04:11:22
ebt83.neoplus.adsl.tpnet.pl	0.004	0.036	00:03:37
web.man.lodz.pl	0.772	4.860	00:00:21
f-oper-v100.man.lodz.pl	0.656	5.044	00:00:10
vegas.cm.p.lodz.pl	0.720	4.900	00:00:09
staticline41435.toya.net.pl	0.056	0.408	00:00:04
david.man.lodz.pl	0.268	1.624	00:00:01
kujawiak.man.lodz.pl	0.012	0.020	00:00:00
TOTAL: 8	8.280	42.522	04:15:47

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	289
david.man.lodz.pl	96
staticline41435.toya.net.pl	32
252.181.14.62.static.jazztel.es	6
ebt83.neoplus.adsl.tpnet.pl	2
TOTAL: 5	425

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	300	74.3%
2	pl	104	25.7%
TOTAL: 2		404	100.0%

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

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

Daily Usenet report

Sep 22 04:15:02 -- Sep 23 04:15:03