Daily Usenet report: Aug 17 04:15:01

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Aug 17 09:02:19 newsfeed nnrpd[2344]: SERVER perl filter not defined
Aug 17 09:46:36 newsfeed nnrpd[4063]: SERVER perl filter not defined
Aug 17 10:26:51 newsfeed nnrpd[5566]: SERVER perl filter not defined
Aug 17 13:10:01 newsfeed pgpverify[11552]: pgpverify: verification failed
Aug 17 13:44:11 newsfeed nnrpd[12833]: SERVER perl filter not defined
Aug 17 14:56:58 newsfeed nnrpd[15332]: SERVER perl filter not defined
Aug 17 14:59:03 newsfeed nnrpd[15351]: SERVER perl filter not defined
Aug 17 15:04:50 newsfeed nnrpd[15622]: SERVER perl filter not defined
Aug 17 17:09:59 newsfeed nnrpd[19886]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2730541	97.3%	312.8 MB	96.9%
innfeed	47384	1.7%	5.6 MB	1.7%
innd	22828	0.8%	3.9 MB	1.2%
nnrpd	5285	0.2%	572.7 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2806042	100.0%	322.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2722560	97.8%
Negative hits	42249	1.5%
Positive hits	18319	0.7%
Cache misses	137	0.0%
TOTAL: 4	2783265	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.705	0.0%	312	0.000	8.670	600.000
article cleanup	00:00:38.501	0.0%	2730377	0.005	0.014	0.031
article logging	00:01:28.910	0.1%	2730689	0.017	0.033	0.643
article parse	01:07:49.616	4.7%	113365090	0.024	0.036	0.109
article write	00:30:19.027	2.1%	2722800	0.357	0.668	4.281
artlog/artcncl	00:00:00.006	0.0%	259	0.000	0.023	1.000
data move	00:04:54.648	0.3%	129748150	0.001	0.002	0.012
hisgrep/artcncl	00:00:00.176	0.0%	251	0.000	0.701	60.000
hishave/artcncl	00:00:00.125	0.0%	312	0.000	0.401	12.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:30:11.323	2.1%	2785788	0.086	0.650	7.082
history sync	00:00:00.021	0.0%	290	0.000	0.072	1.000
history write	00:56:10.465	3.9%	2725200	0.084	1.237	14.827
hiswrite/artcncl	00:00:00.102	0.0%	61	0.000	1.672	17.000
idle	12:59:10.770	54.1%	75561695	0.221	0.619	3.130
nntp read	01:02:50.386	4.4%	111150503	0.021	0.034	0.062
overview write	00:00:17.906	0.0%	2722800	0.000	0.007	0.016
perl filter	06:10:37.448	25.7%	2725200	6.694	8.160	12.580
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:27.252	0.4%	5507009	0.041	0.059	0.113
TOTAL: 24:00:12.308	23:29:59.387	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.339	0.0%	121000	0.000	0.003	0.182
article prepare	00:00:00.027	0.0%	80822	0.000	0.000	0.083
article read	00:01:01.716	0.1%	20181	0.000	3.058	1182.500
backlog stats	00:00:00.277	0.0%	335219	0.000	0.001	0.021
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:02.785	0.0%	183636	0.000	0.015	0.094
data write	00:00:14.872	0.0%	132119	0.000	0.113	1.369
idle	23:58:45.188	99.9%	335219	26.722	257.519	1781.868
status file	00:00:13.155	0.0%	16134	0.312	0.815	4.083
TOTAL: 24:00:33.466	24:00:18.359	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:43:58.087	99.9%	5879	0.000	4122.825	600043.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.293	0.0%	12726	0.000	0.023	0.737
readart	00:00:01.548	0.0%	142	0.000	10.901	46.667
TOTAL: 06:44:12.736	06:43:59.928	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1142	2723487	2716565	920	6002	99%	358:53:24
2	news.nask.pl	2	19285	5635	12174	1476	29%	24:39:30
3	news.icm.edu.pl	45	327	37	198	92	11%	17:31:33
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1198	2743107	2722245	13292	7570	99%	401:04:27

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	883.7 GB	197.2 MB	471.8 MB	884.4 GB	99%	340.6 KB
2	news.nask.pl	30.5 MB	1.9 MB	3.9 MB	36.2 MB	84%	5.2 KB
3	news.icm.edu.pl	1.4 MB	200.1 KB	4.8 MB	6.3 MB	21%	50.3 KB
4	localhost	12.1 KB	0.0 KB	0.0 KB	12.1 KB	100%	1.5 KB
TOTAL: 4		883.7 GB	199.3 MB	480.4 MB	884.4 GB	99%	339.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 17 04:15:01 - 04:59:59	78554	2.9%	29.10	28.4 GB	3.2%	11019.77
Aug 17 05:00:00 - 05:59:59	100484	3.7%	27.91	36.4 GB	4.1%	10608.68
Aug 17 06:00:00 - 06:59:59	89085	3.3%	24.75	31.8 GB	3.6%	9266.25
Aug 17 07:00:00 - 07:59:59	90930	3.3%	25.26	27.5 GB	3.1%	8006.02
Aug 17 08:00:00 - 08:59:59	110854	4.1%	30.79	35.5 GB	4.0%	10328.64
Aug 17 09:00:00 - 09:59:59	96557	3.5%	26.82	35.8 GB	4.1%	10437.00
Aug 17 10:00:00 - 10:59:59	107073	3.9%	29.74	39.1 GB	4.4%	11395.23
Aug 17 11:00:00 - 11:59:59	130926	4.8%	36.37	41.6 GB	4.7%	12121.34
Aug 17 12:00:00 - 12:59:59	128687	4.7%	35.75	41.4 GB	4.7%	12057.92
Aug 17 13:00:00 - 13:59:59	141540	5.2%	39.32	41.2 GB	4.7%	12004.19
Aug 17 14:00:00 - 14:59:59	125971	4.6%	34.99	40.2 GB	4.6%	11710.71
Aug 17 15:00:00 - 15:59:59	121611	4.5%	33.78	40.2 GB	4.5%	11699.20
Aug 17 16:00:00 - 16:59:59	120669	4.4%	33.52	40.0 GB	4.5%	11646.94
Aug 17 17:00:00 - 17:59:59	116029	4.3%	32.23	38.1 GB	4.3%	11083.14
Aug 17 18:00:00 - 18:59:59	108195	4.0%	30.05	37.3 GB	4.2%	10870.84
Aug 17 19:00:00 - 19:59:59	116729	4.3%	32.42	35.2 GB	4.0%	10257.61
Aug 17 20:00:00 - 20:59:59	131780	4.8%	36.61	38.3 GB	4.3%	11165.32
Aug 17 21:00:00 - 21:59:59	131627	4.8%	36.56	39.2 GB	4.4%	11422.69
Aug 17 22:00:00 - 22:59:59	90590	3.3%	25.16	28.0 GB	3.2%	8144.76
Aug 17 23:00:00 - 23:59:59	129369	4.8%	35.94	37.0 GB	4.2%	10790.10
Aug 18 00:00:00 - 00:59:59	96476	3.5%	26.80	33.0 GB	3.7%	9615.40
Aug 18 01:00:00 - 01:59:59	112515	4.1%	31.25	37.7 GB	4.3%	10972.66
Aug 18 02:00:00 - 02:59:59	121169	4.5%	33.66	37.4 GB	4.2%	10897.83
Aug 18 03:00:00 - 03:59:59	100308	3.7%	27.86	34.1 GB	3.9%	9920.26
Aug 18 04:00:00 - 04:15:02	24665	0.9%	27.34	9.1 GB	1.0%	10542.34
TOTAL: 24:00:01	2722393	100.0%	31.51	883.5 GB	100.0%	10721.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6266	289	4510	3	1464
2	news.nask.pl	1532	786	666	19	61
3	news.icm.edu.pl	92	0	2	0	90
TOTAL: 3		7890	1075	5178	22	1615

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.sys.raspberry-pi	779
it.comp.os.win.windows10	107
uk.railway	80
it.comp.os.win.windows7	38
comp.mobile.ipad	28
it.tlc.cellulari.android	17
comp.mobile.android	9
alt.sex.telephones	4
hr.alt.cellular.gsm.iphone	4
it.discussioni.energia	3
it.comp.os.win.windows8	3
alt.windows7.general	2
de.comp.lang.vbclassic	1
TOTAL: 13	1075

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

Groups	Count
pl.praca.oferowana	17
pl.sci.matematyka	4
bit.listserv.catholic	1
TOTAL: 3	22

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	1097
Binary in non-binary group	107
EMP (md5)	93
Too many newsgroups	4
Attached HTML file	2
TOTAL: 5	1303

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	64946	805	29681	17731	0	45	1%	24:04:05
2	panorama	64529	694	36877	223	28	83060	1%	23:56:38
3	poznan	8223	135	5344	47	0	0	1%	24:06:38
4	okapi	0	0	0	0	0	2274	0%	24:00:04
5	nask	0	0	0	0	0	13034	0%	24:00:04
TOTAL: 5		137698	1634	71902	18001	28	98413	1%	120:07:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	poznan	7.1 MB	1.0 MB	8.2 MB	0.1 KB/s	45.9 KB	24:06:38
2	icm	3.4 MB	4.3 GB	4.3 GB	51.6 KB/s	241.2 KB	24:04:05
3	panorama	2.4 MB	654.5 KB	3.1 MB	0.0 KB/s	3.4 KB	23:56:38
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
TOTAL: 5		12.9 MB	4.3 GB	4.3 GB	10.4 KB/s	228.3 KB	120:07:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	22	122	263.9 KB	14	0	04:05:05
2	web.man.lodz.pl	289	20	15.9 KB	9	0	00:00:31
3	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 3		319	142	279.8 KB	23	8	06:44:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	22	122	263.9 KB	14	0	04:05:05
2	*.man.lodz.pl	681	20	15.9 KB	9	8	00:00:36
3	*.toya.net.pl	53	0	0.0 KB	0	0	00:00:03
4	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:08
5	unresolved	2	0	0.0 KB	0	0	00:12:38
6	*.neoplus.adsl.tpnet.pl	18	0	0.0 KB	0	0	02:25:38
TOTAL: 6		778	142	279.8 KB	23	8	06:44:12

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.113	0.319	04:05:05
bpb173.neoplus.adsl.tpnet.pl	0.114	0.239	02:25:38
185.48.193.134	0.181	0.199	00:12:38
web.man.lodz.pl	1.050	4.413	00:00:31
host227.elsnet.pl	0.005	0.024	00:00:08
staticline41435.toya.net.pl	0.196	0.768	00:00:03
mon.man.lodz.pl	0.819	3.708	00:00:03
david.man.lodz.pl	0.332	1.272	00:00:00
isvcs01.man.lodz.pl	0.061	0.127	00:00:00
?	0.007	0.024	00:00:00
TOTAL: 10	2.878	11.093	06:44:12

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	53
bpb173.neoplus.adsl.tpnet.pl	18
185.48.193.134	2
host227.elsnet.pl	2
TOTAL: 6	459

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
bpb173.neoplus.adsl.tpnet.pl	4	0
lze200.lze.lodz.pl	4	2
TOTAL: 2	8	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	142	100.0%
TOTAL: 1		142	100.0%

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

Newsgroup		Count
1	pl.rec.kuchnia	35
2	pl.comp.os.linux	31
3	pl.comp.pecet	29
4	pl.comp.os.ms-windows.winnt	25
5	pl.misc.telefonia.gsm	16
6	pl.rec.dom	6
TOTAL: 6		142

Daily Usenet report

Aug 17 04:15:01 -- Aug 18 04:15:02