Daily Usenet report: Aug 21 04:15:03

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Aug 21 04:39:14 newsfeed pgpverify[3471]: pgpverify: verification failed
Aug 21 09:16:22 newsfeed nnrpd[13058]: SERVER perl filter not defined
Aug 21 09:28:39 newsfeed nnrpd[13560]: SERVER perl filter not defined
Aug 21 20:32:34 newsfeed innfeed[2220]: icm:0 connect: Network is unreachable
Aug 21 20:38:34 newsfeed innfeed[2220]: icm:0 connect: Network is unreachable
Aug 21 21:02:34 newsfeed innfeed[2220]: icm:0 connect: Network is unreachable
Aug 21 22:34:34 newsfeed innfeed[2220]: icm:0 connect: Network is unreachable
Aug 22 00:34:34 newsfeed innfeed[2220]: icm:0 connect: Network is unreachable

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1168174	97.6%	132.2 MB	97.0%
innd	15002	1.3%	2.4 MB	1.7%
nnrpd	7382	0.6%	770.3 KB	0.6%
innfeed	6555	0.5%	990.7 KB	0.7%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1197117	100.0%	136.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1163954	94.5%
Negative hits	49285	4.0%
Positive hits	17646	1.4%
Cache misses	590	0.0%
TOTAL: 4	1231475	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:16.102	0.0%	493	0.000	32.661	450.000
article cleanup	00:00:18.550	0.0%	1167788	0.000	0.016	0.038
article logging	00:00:34.330	0.0%	1167922	0.009	0.029	0.487
article parse	00:49:00.155	3.4%	144174911	0.009	0.020	0.042
article write	00:12:50.351	0.9%	1160989	0.155	0.664	2.425
artlog/artcncl	00:00:00.008	0.0%	462	0.000	0.017	1.000
artlog/artparse	00:00:00.001	0.0%	6	0.000	0.167	1.000
data move	00:01:56.384	0.1%	152259046	0.000	0.001	0.002
hisgrep/artcncl	00:00:02.366	0.0%	364	0.000	6.500	32.000
hishave/artcncl	00:00:00.609	0.0%	493	0.000	1.235	15.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:40:07.389	2.8%	1232746	0.011	1.953	8.193
history sync	00:00:00.024	0.0%	292	0.000	0.082	1.000
history write	02:32:23.590	10.6%	1165208	0.072	7.847	30.986
hiswrite/artcncl	00:00:00.715	0.0%	129	0.000	5.543	15.000
idle	15:26:33.049	64.3%	104427367	0.299	0.532	1.301
nntp read	00:52:40.944	3.7%	143250278	0.012	0.022	0.036
overview write	00:00:07.238	0.0%	1160989	0.000	0.006	0.028
perl filter	02:43:57.671	11.4%	1165202	4.212	8.443	10.885
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:24.011	0.2%	2358454	0.010	0.061	0.097
TOTAL: 24:00:18.683	23:23:13.487	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.149	0.0%	46724	0.000	0.003	0.118
article prepare	00:00:00.011	0.0%	39118	0.000	0.000	0.036
article read	00:01:50.082	0.1%	9654	0.000	11.403	189.333
backlog stats	00:00:00.134	0.0%	256893	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:01.420	0.0%	136452	0.000	0.010	1.000
data write	00:00:09.670	0.0%	131748	0.000	0.073	5.000
idle	23:58:09.545	99.9%	256893	0.000	335.897	10417.000
status file	00:00:01.099	0.0%	206	0.000	5.335	535.000
TOTAL: 24:00:17.612	24:00:12.110	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.041	0.0%	1	41.000	41.000	41.000
idle	02:52:00.422	99.8%	4483	0.000	2302.124	138499.385
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.251	0.0%	11046	0.000	0.023	0.556
readart	00:00:01.312	0.0%	493	0.000	2.661	29.545
TOTAL: 02:52:23.351	02:52:02.026	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
nl-admin@nic.surfnet.nl	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	459	1163211	1155955	1232	6024	99%	235:59:22
2	news.nask.pl	2	18523	4232	13728	563	22%	24:34:09
3	news.icm.edu.pl	55	1398	699	472	227	50%	15:14:45
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		519	1183134	1160888	15432	6814	98%	275:48:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	428.6 GB	10.7 MB	476.5 MB	429.1 GB	99%	387.2 KB
2	news.icm.edu.pl	43.8 MB	292.1 KB	14.6 MB	58.7 MB	74%	64.9 KB
3	news.nask.pl	16.7 MB	1.4 MB	1.6 MB	19.7 MB	85%	4.2 KB
4	localhost	3.2 KB	0.0 KB	0.0 KB	3.2 KB	100%	1.6 KB
TOTAL: 4		428.7 GB	12.3 MB	492.6 MB	429.2 GB	99%	385.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 21 04:15:03 - 04:59:59	45288	3.9%	16.79	16.6 GB	3.9%	6437.08
Aug 21 05:00:00 - 05:59:59	62837	5.4%	17.45	23.5 GB	5.5%	6848.54
Aug 21 06:00:00 - 06:59:59	68328	5.9%	18.98	25.8 GB	6.0%	7513.07
Aug 21 07:00:00 - 07:59:59	65452	5.6%	18.18	24.9 GB	5.8%	7266.63
Aug 21 08:00:00 - 08:59:59	61458	5.3%	17.07	22.4 GB	5.2%	6538.15
Aug 21 09:00:00 - 09:59:59	69663	6.0%	19.35	24.7 GB	5.8%	7201.37
Aug 21 10:00:00 - 10:59:59	32896	2.8%	9.14	14.4 GB	3.4%	4184.33
Aug 21 11:00:00 - 11:59:59	33816	2.9%	9.39	12.7 GB	3.0%	3690.25
Aug 21 12:00:00 - 12:59:59	46132	4.0%	12.81	13.8 GB	3.2%	4018.66
Aug 21 13:00:00 - 13:59:59	43588	3.8%	12.11	12.8 GB	3.0%	3729.30
Aug 21 14:00:00 - 14:59:59	33479	2.9%	9.30	12.9 GB	3.0%	3745.34
Aug 21 15:00:00 - 15:59:59	35174	3.0%	9.77	13.9 GB	3.2%	4043.78
Aug 21 16:00:00 - 16:59:59	31394	2.7%	8.72	12.7 GB	3.0%	3712.81
Aug 21 17:00:00 - 17:59:59	27906	2.4%	7.75	11.8 GB	2.8%	3451.16
Aug 21 18:00:00 - 18:59:59	38735	3.3%	10.76	12.3 GB	2.9%	3572.96
Aug 21 19:00:00 - 19:59:59	34959	3.0%	9.71	14.0 GB	3.3%	4091.36
Aug 21 20:00:00 - 20:59:59	32561	2.8%	9.04	12.5 GB	2.9%	3630.05
Aug 21 21:00:00 - 21:59:59	41869	3.6%	11.63	16.4 GB	3.8%	4781.42
Aug 21 22:00:00 - 22:59:59	44335	3.8%	12.32	15.9 GB	3.7%	4626.95
Aug 21 23:00:00 - 23:59:59	39633	3.4%	11.01	15.1 GB	3.5%	4392.24
Aug 22 00:00:00 - 00:59:59	59970	5.2%	16.66	21.4 GB	5.0%	6222.46
Aug 22 01:00:00 - 01:59:59	51113	4.4%	14.20	19.4 GB	4.5%	5648.44
Aug 22 02:00:00 - 02:59:59	59383	5.1%	16.50	22.6 GB	5.3%	6586.97
Aug 22 03:00:00 - 03:59:59	79822	6.9%	22.17	28.5 GB	6.6%	8291.45
Aug 22 04:00:00 - 04:15:04	20974	1.8%	23.20	7.6 GB	1.8%	8785.09
TOTAL: 24:00:01	1160765	100.0%	13.43	428.5 GB	100.0%	5200.61

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6146	249	2086	1	3810
2	news.nask.pl	574	24	491	5	54
3	news.icm.edu.pl	227	0	7	0	220
TOTAL: 3		6947	273	2584	6	4084

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	163
it.tlc.cellulari.android	36
alt.sex.telephones	16
comp.mobile.android	16
uk.rec.cycling.moderated	12
comp.sys.raspberry-pi	10
it.comp.os.win.windows7	5
comp.mobile.ipad	4
hr.alt.cellular.gsm.iphone	3
de.comp.lang.vbclassic	2
it.comp.os.linux.ubuntu	2
rec.arts.tv.x-factor	1
hr.alt.cellular.gsm.android	1
hr.ponuda.hardver.komponente	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 15	273

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

Groups	Count
pl.sci.matematyka	3
pl.sci.historia	2
bit.listserv.catholic	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3675
EMP (md5)	218
EMP (fsl)	36
HTML post	12
Scoring filter (10)	3
TOTAL: 5	3944

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	38544	2657	26724	3732	7	9535	6%	24:08:54
2	panorama	37604	1390	34953	234	0	0	3%	24:07:49
3	nask	27068	176	15844	1523	0	714	0%	24:01:15
4	poznan	5978	39	4480	56	0	0	0%	24:02:10
5	okapi	0	0	0	0	0	2319	0%	24:00:00
TOTAL: 5		109194	4262	82001	5545	7	12568	3%	120:20:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	600.5 MB	874.1 MB	1.4 GB	17.4 KB/s	236.3 KB	24:08:54
2	nask	15.1 MB	609.7 MB	624.8 MB	7.4 KB/s	376.6 KB	24:01:15
3	panorama	6.1 MB	828.7 KB	6.9 MB	0.1 KB/s	4.3 KB	24:07:49
4	poznan	125.3 KB	1.6 MB	1.7 MB	0.0 KB/s	18.2 KB	24:02:10
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		621.8 MB	1.5 GB	2.1 GB	5.0 KB/s	220.1 KB	120:20:08

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	312	472.8 KB	288	0	00:00:07
2	web.man.lodz.pl	288	120	1.1 MB	44	0	00:00:23
3	lze200.lze.lodz.pl	14	45	80.5 KB	9	0	02:17:52
4	ip-176.105.135.22.tvsat364.lodz.pl	4	16	21.2 KB	3	0	00:32:33
5	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 5		596	493	1.7 MB	344	2	02:52:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	312	472.8 KB	288	0	00:00:07
2	*.man.lodz.pl	674	120	1.1 MB	44	2	00:00:29
3	*.lze.lodz.pl	14	45	80.5 KB	9	0	02:17:52
4	*.105.135.22.tvsat364.lodz.pl	4	16	21.2 KB	3	0	00:32:33
5	*.shodan.io	1	0	0.0 KB	0	0	00:00:01
6	unresolved	3	0	0.0 KB	0	0	00:01:11
7	*.toya.net.pl	99	0	0.0 KB	0	0	00:00:07
TOTAL: 7		1083	493	1.7 MB	344	2	02:52:22

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.072	0.189	02:17:52
ip-176.105.135.22.tvsat364.lodz.pl	0.017	0.047	00:32:33
37.248.255.119	0.005	0.013	00:00:37
194.33.77.50	0.005	0.013	00:00:30
web.man.lodz.pl	1.301	3.969	00:00:23
staticline41435.toya.net.pl	0.470	1.371	00:00:07
pc-skrzep2.cm.p.lodz.pl	1.304	3.697	00:00:07
mon.man.lodz.pl	1.298	3.672	00:00:03
74.205.216.161	0.005	0.014	00:00:03
census6.shodan.io	0.005	0.013	00:00:01
david.man.lodz.pl	0.436	1.247	00:00:01
isvcs01.man.lodz.pl	0.018	0.037	00:00:00
TOTAL: 12	4.936	14.282	02:52:22

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	99
david.man.lodz.pl	96
census6.shodan.io	1
37.248.255.119	1
74.205.216.161	1
194.33.77.50	1
TOTAL: 7	487

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	1
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	3	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	63.3%
2	pl	181	36.7%
TOTAL: 2		493	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.os.ms-windows.winnt	65
3	pl.comp.pecet	40
4	pl.misc.telefonia.gsm	29
5	pl.internet.polip	22
6	pl.regionalne.lodz	9
7	pl.soc.prawo	6
8	pl.comp.os.linux	6
9	pl.rec.dom	3
10	pl.soc.dzieci	1
TOTAL: 10		493

Daily Usenet report

Aug 21 04:15:03 -- Aug 22 04:15:04