Daily Usenet report: Nov 8 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Nov 8 12:05:17 newsfeed nnrpd[4171]: SERVER perl filter not defined
Nov 8 15:06:04 newsfeed nnrpd[10856]: SERVER perl filter not defined
Nov 8 16:16:52 newsfeed overchan[1080]: input line too long, skipping
Nov 8 16:30:16 newsfeed overchan[1080]: input line too long, skipping
Nov 8 16:42:42 newsfeed overchan[1080]: input line too long, skipping
Nov 8 23:41:07 newsfeed overchan[1080]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	5260873	98.0%	592.0 MB	97.6%
innd	61546	1.1%	9.0 MB	1.5%
innfeed	41227	0.8%	4.8 MB	0.8%
nnrpd	4872	0.1%	512.6 KB	0.1%
overchan	5	0.0%	0.9 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	5368525	100.0%	606.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	5198546	96.2%
Negative hits	175430	3.2%
Positive hits	29747	0.6%
Cache misses	184	0.0%
TOTAL: 4	5403907	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.413	0.0%	326	0.000	13.537	346.000
article cleanup	00:00:56.955	0.1%	5249974	0.007	0.011	0.017
article logging	00:03:09.947	0.2%	5250651	0.023	0.036	0.107
article parse	01:00:05.590	4.2%	154699858	0.015	0.023	0.042
article write	00:24:56.153	1.7%	5202157	0.141	0.288	0.509
artlog/artcncl	00:00:00.007	0.0%	309	0.000	0.023	0.333
data move	00:02:58.350	0.2%	177398867	0.001	0.001	0.003
hisgrep/artcncl	00:00:00.252	0.0%	316	0.000	0.797	19.000
hishave/artcncl	00:00:00.214	0.0%	326	0.000	0.656	13.800
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:51:13.022	3.6%	5439094	0.007	0.565	5.046
history sync	00:00:00.010	0.0%	289	0.000	0.035	0.500
history write	01:41:28.816	7.0%	5231889	0.055	1.164	8.566
hiswrite/artcncl	00:00:00.016	0.0%	10	0.000	1.600	8.000
idle	10:38:10.620	44.3%	112952055	0.212	0.339	0.602
nntp read	00:52:58.574	3.7%	150413673	0.015	0.021	0.036
overview write	00:00:24.381	0.0%	5202157	0.004	0.005	0.007
perl filter	07:47:02.581	32.4%	5231867	3.617	5.356	7.513
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:34.579	0.5%	10423989	0.034	0.044	0.059
TOTAL: 24:00:06.654	23:31:04.480	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.254	0.0%	121238	0.000	0.002	0.017
article prepare	00:00:00.019	0.0%	84888	0.000	0.000	0.036
article read	00:03:35.125	0.3%	21729	0.000	9.900	284.429
backlog stats	00:00:00.164	0.0%	249755	0.000	0.001	0.004
callbacks	00:00:00.002	0.0%	369	0.000	0.005	0.007
data read	00:00:02.207	0.0%	131119	0.003	0.017	0.055
data write	00:00:03.647	0.0%	112804	0.008	0.032	0.092
idle	23:41:48.965	99.7%	249755	5.415	341.571	1127.511
status file	00:00:14.824	0.0%	15041	0.500	0.986	11.258
TOTAL: 23:46:01.030	23:45:45.207	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:10:18.347	99.7%	3102	0.000	1359.880	450072.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.168	0.0%	6889	0.000	0.024	1.000
readart	00:00:00.428	0.0%	51	0.000	8.392	57.000
TOTAL: 01:10:32.608	01:10:18.943	99.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1251	5233828	5198253	4325	31250	99%	450:50:06
2	news.nask.pl	2	34434	10625	7056	16753	30%	24:01:05
3	news.icm.edu.pl	42	177	17	156	4	9%	14:35:18
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1298	5268441	5208897	11537	48007	98%	489:26:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	884.5 GB	2.1 GB	4.5 GB	891.1 GB	99%	178.7 KB
2	news.nask.pl	78.6 MB	39.1 MB	2.2 GB	2.3 GB	3%	90.0 KB
3	news.icm.edu.pl	468.9 KB	0.0 KB	120.9 KB	589.8 KB	79%	28.1 KB
4	localhost	2.9 KB	0.0 KB	0.0 KB	2.9 KB	100%	1.5 KB
TOTAL: 4		884.6 GB	2.1 GB	6.8 GB	893.5 GB	99%	178.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 8 04:15:03 - 04:59:59	101132	1.9%	37.50	32.1 GB	3.6%	12487.99
Nov 8 05:00:00 - 05:59:59	136962	2.6%	38.05	40.1 GB	4.5%	11677.11
Nov 8 06:00:00 - 06:59:59	138174	2.7%	38.38	39.6 GB	4.5%	11546.46
Nov 8 07:00:00 - 07:59:59	141054	2.7%	39.18	42.3 GB	4.8%	12314.87
Nov 8 08:00:00 - 08:59:59	142733	2.7%	39.65	41.0 GB	4.6%	11928.09
Nov 8 09:00:00 - 09:59:59	140447	2.7%	39.01	30.8 GB	3.5%	8977.20
Nov 8 10:00:00 - 10:59:59	163713	3.1%	45.48	35.9 GB	4.0%	10447.79
Nov 8 11:00:00 - 11:59:59	199369	3.8%	55.38	36.4 GB	4.1%	10605.04
Nov 8 12:00:00 - 12:59:59	255388	4.9%	70.94	38.5 GB	4.3%	11206.76
Nov 8 13:00:00 - 13:59:59	238461	4.6%	66.24	38.7 GB	4.4%	11264.34
Nov 8 14:00:00 - 14:59:59	265703	5.1%	73.81	35.5 GB	4.0%	10325.94
Nov 8 15:00:00 - 15:59:59	294319	5.6%	81.76	37.4 GB	4.2%	10906.57
Nov 8 16:00:00 - 16:59:59	281355	5.4%	78.15	36.8 GB	4.2%	10727.19
Nov 8 17:00:00 - 17:59:59	339196	6.5%	94.22	35.4 GB	4.0%	10296.94
Nov 8 18:00:00 - 18:59:59	384789	7.4%	106.89	32.9 GB	3.7%	9568.54
Nov 8 19:00:00 - 19:59:59	336393	6.5%	93.44	33.2 GB	3.8%	9684.24
Nov 8 20:00:00 - 20:59:59	230898	4.4%	64.14	35.3 GB	4.0%	10295.25
Nov 8 21:00:00 - 21:59:59	174047	3.3%	48.35	39.6 GB	4.5%	11529.17
Nov 8 22:00:00 - 22:59:59	165264	3.2%	45.91	41.1 GB	4.6%	11973.56
Nov 8 23:00:00 - 23:59:59	290483	5.6%	80.69	39.1 GB	4.4%	11384.63
Nov 9 00:00:00 - 00:59:59	214156	4.1%	59.49	29.3 GB	3.3%	8530.39
Nov 9 01:00:00 - 01:59:59	242096	4.6%	67.25	32.9 GB	3.7%	9580.43
Nov 9 02:00:00 - 02:59:59	172417	3.3%	47.89	38.7 GB	4.4%	11286.04
Nov 9 03:00:00 - 03:59:59	114576	2.2%	31.83	34.6 GB	3.9%	10088.64
Nov 9 04:00:00 - 04:15:02	48622	0.9%	53.90	8.8 GB	1.0%	10173.62
TOTAL: 23:59:59	5211747	100.0%	60.32	885.9 GB	100.0%	10752.20

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	31653	146	17772	4	13731
2	news.nask.pl	17155	21	469	13	16652
3	news.icm.edu.pl	9	0	0	0	9
TOTAL: 3		48817	167	18241	17	30392

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	63
it.comp.os.win.windows10	36
it.tlc.cellulari.android	30
comp.mobile.ipad	8
de.comp.lang.vbclassic	5
nl.comp.sys.android	4
it.comp.os.win.windows7	3
comp.mobile.android	3
de.comm.provider.telefonie	3
uk.rec.cycling.moderated	3
it.discussioni.energia	2
it.comp.os.linux.ubuntu	1
uk.net.providers.gradwell	1
de.comm.software.mailreader	1
de.rec.sf.startrek	1
alt.sex.telephones	1
hr.ponuda.hardver.komponente	1
comp.sys.raspberry-pi	1
TOTAL: 18	167

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

Groups	Count
pl.praca.oferowana	16
bit.listserv.catholic	1
TOTAL: 2	17

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	26188
EMP (md5)	3371
Scoring filter (9)	68
Scoring filter (8)	27
HTML post	16
Scoring filter (10)	15
Scoring filter (11)	4
Scoring filter (12)	2
TOTAL: 8	29691

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	385
	news.nask.pl	289
	news.icm.edu.pl	5
	TOTAL: 3	679
TOTAL: 1		679

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	108222	9033	62842	10205	1163	37839	8%	23:58:04
2	poznan	17041	386	10033	49	1	0	2%	23:51:10
3	icm	22201	78	17928	220	0	0	0%	23:51:10
4	nask	2229	11	1661	0	0	0	0%	23:08:10
5	okapi	0	0	0	0	0	1234	0%	23:49:04
TOTAL: 5		149693	9508	92464	10474	1164	39073	6%	118:37:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	37.4 MB	78.2 MB	115.6 MB	1.4 KB/s	6.2 KB	23:58:04
2	icm	4.9 MB	74.5 MB	79.5 MB	0.9 KB/s	273.1 KB	23:51:10
3	poznan	1.5 MB	532.3 KB	2.0 MB	0.0 KB/s	4.8 KB	23:51:10
4	nask	22.7 KB	0.0 KB	22.7 KB	0.0 KB/s	2.1 KB	23:08:10
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:49:04
TOTAL: 5		43.9 MB	153.2 MB	197.1 MB	0.5 KB/s	10.1 KB	118:37:38

Backlog files shrunk by innfeed:

Server	Size
panorama	976.7 KB
TOTAL: 1	976.7 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	32	34.1 KB	15	0	00:00:25
2	user56-083.satfilm.com.pl	18	18	18.1 KB	7	0	01:01:06
3	aakt63.neoplus.adsl.tpnet.pl	1	1	1.5 KB	1	0	00:01:07
4	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 4		309	51	53.8 KB	23	2	01:10:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	674	32	34.1 KB	15	2	00:00:30
2	*.satfilm.com.pl	18	18	18.1 KB	7	0	01:01:06
3	*.neoplus.adsl.tpnet.pl	1	1	1.5 KB	1	0	00:01:07
4	*.toya.net.pl	3	0	0.0 KB	0	0	00:07:47
TOTAL: 4		696	51	53.8 KB	23	2	01:10:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user56-083.satfilm.com.pl	0.068	0.408	01:01:06
staticline-31-182-254-20.toya.net.pl	0.016	0.032	00:07:47
aakt63.neoplus.adsl.tpnet.pl	0.024	0.020	00:01:07
web.man.lodz.pl	1.388	5.140	00:00:25
mon.man.lodz.pl	0.976	4.200	00:00:03
david.man.lodz.pl	0.296	1.508	00:00:01
isvcs01.man.lodz.pl	0.020	0.012	00:00:00
TOTAL: 7	2.788	11.320	01:10:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline-31-182-254-20.toya.net.pl	3
TOTAL: 3	387

NNRP client timeouts [Top 20]:

System	Conn	Peer
user56-083.satfilm.com.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	32	62.7%
2	lodz	19	37.3%
TOTAL: 2		51	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	28
2	lodz.test	18
3	pl.comp.pecet	4
4	lodz.ogloszenia	1
TOTAL: 4		51

Daily Usenet report

Nov 8 04:15:03 -- Nov 9 04:15:02