Daily Usenet report: Aug 12 04:15:04

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Aug 12 08:56:18 newsfeed nnrpd[28219]: SERVER perl filter not defined
Aug 12 11:32:29 newsfeed nnrpd[1188]: SERVER perl filter not defined
Aug 12 11:41:50 newsfeed nnrpd[1583]: SERVER perl filter not defined
Aug 12 12:20:10 newsfeed nnrpd[2981]: SERVER perl filter not defined
Aug 12 15:08:20 newsfeed nnrpd[6811]: SERVER perl filter not defined
Aug 12 16:59:34 newsfeed overchan[2674]: input line too long, skipping
Aug 12 20:00:03 newsfeed nnrpd[16988]: SERVER perl filter not defined
Aug 13 00:35:24 newsfeed nnrpd[26636]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3032076	97.7%	357.3 MB	97.3%
innfeed	44145	1.4%	5.3 MB	1.4%
innd	23365	0.8%	4.0 MB	1.1%
nnrpd	4944	0.2%	528.2 KB	0.1%
controlchan	4	0.0%	0.5 KB	0.0%
overchan	2	0.0%	0.4 KB	0.0%
TOTAL: 6	3104536	100.0%	367.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3014644	97.2%
Negative hits	51779	1.7%
Positive hits	26619	0.9%
Cache misses	9852	0.3%
TOTAL: 4	3102894	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:02:31.743	0.2%	11255	0.000	13.482	1381.000
article cleanup	00:00:42.967	0.0%	3021290	0.000	0.014	0.030
article logging	00:01:40.146	0.1%	3022077	0.017	0.033	0.437
article parse	01:05:34.865	4.6%	114620741	0.018	0.034	0.067
article write	00:38:30.795	2.7%	3016190	0.273	0.766	3.564
artlog/artcncl	00:00:00.266	0.0%	11163	0.000	0.024	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:03:52.831	0.3%	131763687	0.001	0.002	0.004
hisgrep/artcncl	00:00:57.928	0.1%	9950	0.000	5.822	143.000
hishave/artcncl	00:00:18.773	0.0%	11255	0.000	1.668	2.513
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:28:50.745	2.0%	3095258	0.085	0.559	6.391
history sync	00:00:00.008	0.0%	290	0.000	0.028	2.000
history write	00:51:55.346	3.6%	3016589	0.180	1.033	13.065
hiswrite/artcncl	00:00:06.597	0.0%	1305	0.000	5.055	268.000
idle	13:55:26.959	58.0%	78768625	0.249	0.636	7.372
nntp read	00:54:51.802	3.8%	112194614	0.020	0.029	0.044
overview write	00:00:19.796	0.0%	3016190	0.000	0.007	0.022
perl filter	05:21:39.173	22.3%	3016589	3.586	6.398	10.475
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:36.441	0.5%	6131106	0.016	0.065	0.120
TOTAL: 23:59:24.014	23:33:57.181	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.504	0.0%	164769	0.000	0.003	0.200
article prepare	00:00:00.033	0.0%	100018	0.000	0.000	0.250
article read	00:01:34.902	0.1%	24937	0.000	3.806	1100.000
backlog stats	00:00:00.353	0.0%	398051	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	7	0.000	0.000	0.000
data read	00:00:03.442	0.0%	235788	0.000	0.015	0.095
data write	00:00:15.889	0.0%	146550	0.000	0.108	0.545
idle	23:55:48.029	99.8%	398051	13.158	216.425	1582.390
status file	00:00:12.835	0.0%	15571	0.286	0.824	21.300
TOTAL: 23:58:11.770	23:57:55.987	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	12:19:42.428	99.9%	389755	0.000	113.873	150062.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:14.962	0.0%	780217	0.000	0.019	0.667
readart	00:00:00.759	0.0%	49	0.000	15.490	61.800
TOTAL: 12:20:34.699	12:19:58.149	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
newgroup	1
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	191

Newsgroups created:

Group	Mode
alt.binaries.sounds.test	y
TOTAL	1

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
153fishare@gmail.com	2	0	0	0	2	2
TOTAL	2	0	0	0	2	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1197	3013986	3006732	2646	4608	99%	358:12:30
2	news.nask.pl	4	26506	7384	18670	452	27%	23:20:54
3	news.icm.edu.pl	41	283	38	221	24	13%	17:37:01
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1250	3040782	3014161	21537	5084	99%	399:10:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	845.0 GB	21.8 MB	28.4 MB	845.0 GB	99%	294.2 KB
2	news.nask.pl	32.0 MB	1.2 MB	1.3 MB	34.5 MB	92%	4.5 KB
3	news.icm.edu.pl	1.4 MB	8.3 KB	1.2 MB	2.5 MB	53%	41.8 KB
4	localhost	10.0 KB	0.0 KB	0.0 KB	10.0 KB	100%	1.4 KB
TOTAL: 4		845.0 GB	23.0 MB	30.9 MB	845.1 GB	99%	293.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 12 04:15:04 - 04:59:59	105544	3.5%	39.15	28.6 GB	3.4%	11139.64
Aug 12 05:00:00 - 05:59:59	131334	4.4%	36.48	34.6 GB	4.1%	10075.80
Aug 12 06:00:00 - 06:59:59	126149	4.2%	35.04	35.7 GB	4.2%	10384.00
Aug 12 07:00:00 - 07:59:59	119826	4.0%	33.28	36.6 GB	4.3%	10667.27
Aug 12 08:00:00 - 08:59:59	132059	4.4%	36.68	38.7 GB	4.6%	11278.30
Aug 12 09:00:00 - 09:59:59	136301	4.5%	37.86	40.1 GB	4.7%	11668.80
Aug 12 10:00:00 - 10:59:59	125166	4.2%	34.77	39.1 GB	4.6%	11380.91
Aug 12 11:00:00 - 11:59:59	150367	5.0%	41.77	40.3 GB	4.8%	11737.05
Aug 12 12:00:00 - 12:59:59	125627	4.2%	34.90	37.3 GB	4.4%	10877.16
Aug 12 13:00:00 - 13:59:59	129539	4.3%	35.98	36.0 GB	4.3%	10479.29
Aug 12 14:00:00 - 14:59:59	149310	5.0%	41.48	38.1 GB	4.5%	11090.53
Aug 12 15:00:00 - 15:59:59	132847	4.4%	36.90	37.4 GB	4.4%	10880.78
Aug 12 16:00:00 - 16:59:59	160457	5.3%	44.57	39.1 GB	4.6%	11376.11
Aug 12 17:00:00 - 17:59:59	129406	4.3%	35.95	34.7 GB	4.1%	10120.73
Aug 12 18:00:00 - 18:59:59	128553	4.3%	35.71	34.6 GB	4.1%	10091.58
Aug 12 19:00:00 - 19:59:59	133024	4.4%	36.95	35.4 GB	4.2%	10319.52
Aug 12 20:00:00 - 20:59:59	125771	4.2%	34.94	33.2 GB	3.9%	9665.35
Aug 12 21:00:00 - 21:59:59	145429	4.8%	40.40	38.4 GB	4.5%	11173.84
Aug 12 22:00:00 - 22:59:59	143943	4.8%	39.98	38.3 GB	4.5%	11146.81
Aug 12 23:00:00 - 23:59:59	59080	2.0%	16.41	16.8 GB	2.0%	4894.74
Aug 13 00:00:00 - 00:59:59	87851	2.9%	24.40	27.4 GB	3.2%	7985.61
Aug 13 01:00:00 - 01:59:59	121007	4.0%	33.61	35.9 GB	4.2%	10444.78
Aug 13 02:00:00 - 02:59:59	112964	3.7%	31.38	34.9 GB	4.1%	10168.83
Aug 13 03:00:00 - 03:59:59	87171	2.9%	24.21	28.1 GB	3.3%	8184.90
Aug 13 04:00:00 - 04:15:02	16302	0.5%	18.07	5.5 GB	0.7%	6445.52
TOTAL: 23:59:58	3015027	100.0%	34.90	844.7 GB	100.0%	10252.32

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	5219	203	4258	1	757
2	news.nask.pl	643	11	440	6	186
3	news.icm.edu.pl	24	0	1	0	23
TOTAL: 3		5886	214	4699	7	966

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	60
it.comp.os.win.windows10	59
comp.mobile.android	25
it.tlc.cellulari.android	23
it.comp.os.win.windows7	14
uk.rec.cycling.moderated	10
de.comm.provider.telefonie	10
nl.actueel	5
alt.sex.telephones	3
comp.sys.raspberry-pi	3
comp.mobile.ipad	1
hr.rec.glazba.hifi	1
TOTAL: 12	214

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

Groups	Count
pl.praca.oferowana	5
pl.sci.matematyka	2
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	144
EMP (md5)	33
HTML post	2
TOTAL: 3	179

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	78095	2395	49114	908	12	110292	3%	23:59:11
2	icm	72666	1709	36461	18585	1	2040	2%	24:04:23
3	poznan	10221	187	6907	95	0	1	1%	23:53:17
4	nask	0	0	0	0	0	21842	0%	24:01:11
5	okapi	0	0	0	0	0	2722	0%	24:01:11
TOTAL: 5		160982	4291	92482	19588	13	136897	2%	119:59:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	11.3 MB	3.7 MB	14.9 MB	0.2 KB/s	4.6 KB	23:59:11
2	icm	8.8 MB	4.5 GB	4.5 GB	54.0 KB/s	230.6 KB	24:04:23
3	poznan	6.6 MB	858.3 KB	7.4 MB	0.1 KB/s	26.9 KB	23:53:17
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:01:11
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:01:11
TOTAL: 5		26.6 MB	4.5 GB	4.5 GB	10.9 KB/s	196.9 KB	119:59:13

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
panorama	488.3 KB
TOTAL: 2	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	8	41	70.3 KB	10	0	01:16:40
2	web.man.lodz.pl	288	8	7.0 KB	3	0	00:00:29
3	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 3		303	49	77.3 KB	13	7	12:20:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	8	41	70.3 KB	10	0	01:16:40
2	*.man.lodz.pl	678	8	7.0 KB	3	7	00:00:34
3	*.toya.net.pl	22	0	0.0 KB	0	0	00:00:01
4	unresolved	1	0	0.0 KB	0	0	11:03:02
5	*.elsnet.pl	4	0	0.0 KB	0	0	00:00:15
TOTAL: 5		713	49	77.3 KB	13	7	12:20:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.160	22.204	31.236	11:03:02
lze200.lze.lodz.pl	0.026	0.149	01:16:40
web.man.lodz.pl	1.127	4.129	00:00:29
host227.elsnet.pl	0.017	0.036	00:00:15
mon.man.lodz.pl	0.930	4.044	00:00:03
staticline41435.toya.net.pl	0.069	0.255	00:00:01
david.man.lodz.pl	0.333	1.332	00:00:00
isvcs01.man.lodz.pl	0.041	0.126	00:00:00
TOTAL: 8	24.747	41.307	12:20:34

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	287
david.man.lodz.pl	96
staticline41435.toya.net.pl	22
host227.elsnet.pl	4
185.48.195.160	1
TOTAL: 5	410

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.rec.dom	34
2	pl.rec.kuchnia	31
3	pl.comp.os.ms-windows.winnt	21
4	pl.misc.telefonia.gsm	16
5	pl.comp.os.linux.sieci	9
6	pl.comp.pecet	4
7	pl.regionalne.lodz	3
TOTAL: 7		118

Daily Usenet report

Aug 12 04:15:04 -- Aug 13 04:15:02