Daily Usenet report: Sep 9 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Sep 9 04:15:01 newsfeed nnrpd[7832]: web.man.lodz.pl rejected Flushing log and syslog files
Sep 9 09:28:46 newsfeed nnrpd[19301]: SERVER perl filter not defined
Sep 9 09:35:11 newsfeed nnrpd[19612]: SERVER perl filter not defined
Sep 9 16:43:47 newsfeed nnrpd[2003]: SERVER perl filter not defined
Sep 9 16:43:58 newsfeed nnrpd[2005]: SERVER perl filter not defined
Sep 10 00:17:46 newsfeed nnrpd[17767]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2534566	97.3%	292.4 MB	96.9%
innfeed	43785	1.7%	5.2 MB	1.7%
innd	20909	0.8%	3.6 MB	1.2%
nnrpd	5423	0.2%	585.9 KB	0.2%
TOTAL: 4	2604683	100.0%	301.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2528784	98.2%
Negative hits	27927	1.1%
Positive hits	19185	0.7%
Cache misses	101	0.0%
TOTAL: 4	2575997	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.654	0.0%	235	0.000	11.294	737.000
article cleanup	00:00:28.563	0.0%	2534394	0.003	0.011	0.026
article logging	00:01:01.775	0.1%	2534788	0.012	0.024	1.030
article parse	00:55:26.096	3.8%	153148631	0.010	0.022	0.056
article write	00:24:21.410	1.7%	2529708	0.194	0.578	3.374
artlog/artcncl	00:00:00.003	0.0%	200	0.000	0.015	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:01:39.809	0.1%	169085398	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.060	0.0%	193	0.000	0.311	12.000
hishave/artcncl	00:00:00.031	0.0%	235	0.000	0.132	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:19:13.666	1.3%	2577392	0.063	0.448	6.219
history sync	00:00:00.011	0.0%	292	0.000	0.038	1.000
history write	00:26:40.194	1.9%	2530332	0.116	0.632	9.886
hiswrite/artcncl	00:00:00.003	0.0%	42	0.000	0.071	1.000
idle	15:46:45.048	65.7%	128487895	0.295	0.442	3.022
nntp read	00:44:56.783	3.1%	151251998	0.009	0.018	0.028
overview write	00:00:12.039	0.0%	2529708	0.000	0.005	0.011
perl filter	04:35:54.947	19.2%	2530330	3.318	6.543	9.007
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:00.273	0.3%	5117046	0.027	0.047	0.075
TOTAL: 24:00:15.972	23:20:43.365	97.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.274	0.0%	125063	0.000	0.002	0.083
article prepare	00:00:00.026	0.0%	79434	0.000	0.000	0.083
article read	00:01:12.859	0.1%	19857	0.000	3.669	611.000
backlog stats	00:00:00.209	0.0%	334704	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	22	0.000	0.000	0.000
data read	00:00:01.824	0.0%	184574	0.000	0.010	0.373
data write	00:00:09.585	0.0%	129982	0.000	0.074	0.414
idle	23:58:49.586	99.9%	334704	53.611	257.928	1303.596
status file	00:00:09.951	0.0%	15852	0.000	0.628	2.333
TOTAL: 24:00:35.146	24:00:24.314	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:15:12.006	99.9%	23280	0.000	657.732	450498.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.749	0.0%	47657	0.000	0.016	0.600
readart	00:00:00.648	0.0%	121	0.000	5.355	373.000
TOTAL: 04:15:26.470	04:15:13.403	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1167	2529190	2524445	460	4285	99%	278:33:56
2	news.nask.pl	2	18923	4680	13954	289	24%	23:06:13
3	news.icm.edu.pl	34	459	63	257	139	13%	21:11:35
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1209	2548577	2529193	14671	4713	99%	322:51:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	754.3 GB	18.3 MB	32.8 MB	754.3 GB	99%	312.8 KB
2	news.nask.pl	17.0 MB	911.3 KB	1.0 MB	18.8 MB	89%	3.9 KB
3	news.icm.edu.pl	4.1 MB	156.7 KB	4.4 MB	8.6 MB	47%	43.6 KB
4	localhost	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	1.5 KB
TOTAL: 4		754.3 GB	19.4 MB	38.2 MB	754.3 GB	99%	312.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 9 04:15:01 - 04:59:59	92804	3.7%	34.38	25.3 GB	3.4%	9816.85
Sep 9 05:00:00 - 05:59:59	108155	4.3%	30.04	33.3 GB	4.4%	9713.60
Sep 9 06:00:00 - 06:59:59	94816	3.7%	26.34	31.8 GB	4.2%	9250.72
Sep 9 07:00:00 - 07:59:59	102417	4.0%	28.45	32.3 GB	4.3%	9393.78
Sep 9 08:00:00 - 08:59:59	100164	4.0%	27.82	33.8 GB	4.5%	9852.78
Sep 9 09:00:00 - 09:59:59	122778	4.9%	34.10	35.8 GB	4.8%	10428.86
Sep 9 10:00:00 - 10:59:59	107724	4.3%	29.92	33.7 GB	4.5%	9807.48
Sep 9 11:00:00 - 11:59:59	107819	4.3%	29.95	33.3 GB	4.4%	9686.29
Sep 9 12:00:00 - 12:59:59	119390	4.7%	33.16	30.8 GB	4.1%	8966.66
Sep 9 13:00:00 - 13:59:59	83109	3.3%	23.09	26.0 GB	3.4%	7559.85
Sep 9 14:00:00 - 14:59:59	116122	4.6%	32.26	31.3 GB	4.2%	9127.47
Sep 9 15:00:00 - 15:59:59	109879	4.3%	30.52	32.4 GB	4.3%	9449.82
Sep 9 16:00:00 - 16:59:59	100928	4.0%	28.04	31.5 GB	4.2%	9177.84
Sep 9 17:00:00 - 17:59:59	122368	4.8%	33.99	35.8 GB	4.7%	10418.31
Sep 9 18:00:00 - 18:59:59	120634	4.8%	33.51	35.3 GB	4.7%	10285.29
Sep 9 19:00:00 - 19:59:59	99874	3.9%	27.74	31.9 GB	4.2%	9277.10
Sep 9 20:00:00 - 20:59:59	72540	2.9%	20.15	25.3 GB	3.4%	7358.60
Sep 9 21:00:00 - 21:59:59	121894	4.8%	33.86	35.5 GB	4.7%	10327.90
Sep 9 22:00:00 - 22:59:59	101971	4.0%	28.33	29.4 GB	3.9%	8560.81
Sep 9 23:00:00 - 23:59:59	137008	5.4%	38.06	34.9 GB	4.6%	10177.96
Sep 10 00:00:00 - 00:59:59	82142	3.2%	22.82	24.7 GB	3.3%	7187.22
Sep 10 01:00:00 - 01:59:59	89823	3.6%	24.95	25.7 GB	3.4%	7481.86
Sep 10 02:00:00 - 02:59:59	104996	4.2%	29.17	30.6 GB	4.1%	8914.27
Sep 10 03:00:00 - 03:59:59	89077	3.5%	24.74	26.9 GB	3.6%	7820.96
Sep 10 04:00:00 - 04:15:02	20852	0.8%	23.12	6.4 GB	0.8%	7420.83
TOTAL: 24:00:01	2529284	100.0%	29.27	753.4 GB	100.0%	9143.42

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4615	276	3806	0	533
2	news.nask.pl	358	15	257	14	72
3	news.icm.edu.pl	109	0	2	0	107
TOTAL: 3		5082	291	4065	14	712

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	105
it.comp.os.win.windows10	71
it.tlc.cellulari.android	62
comp.mobile.ipad	20
comp.sys.raspberry-pi	11
it.comp.os.win.windows7	6
it.discussioni.energia	4
comp.sys.mac.vintage	4
it.comp.os.win.windows8	2
comp.mobile.android	2
de.comp.lang.perl	2
hr.ponuda.hardver.komponente	1
hr.alt.cellular.gsm.iphone	1
TOTAL: 13	291

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

Groups	Count
pl.praca.oferowana	13
bit.listserv.catholic	1
TOTAL: 2	14

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	193
EMP (md5)	103
HTML post	12
Scoring filter (8)	4
Attached HTML file	3
Scoring filter (9)	1
TOTAL: 6	316

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	63385	7609	28986	7729	0	8	12%	24:07:51
2	panorama	75602	2405	45997	872	55	75789	3%	24:02:23
3	poznan	7403	54	4772	16	0	0	0%	24:01:53
4	okapi	0	0	0	0	0	2523	0%	24:00:00
5	nask	0	0	0	0	0	14887	0%	24:00:00
TOTAL: 5		146390	10068	79755	8617	55	93207	6%	120:12:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.3 GB	1.7 GB	3.0 GB	35.8 KB/s	202.5 KB	24:07:51
2	panorama	11.6 MB	7.4 MB	19.0 MB	0.2 KB/s	5.9 KB	24:02:23
3	poznan	526.4 KB	65.7 KB	592.1 KB	0.0 KB/s	8.5 KB	24:01:53
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		1.3 GB	1.7 GB	3.0 GB	7.2 KB/s	167.3 KB	120:12:07

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	pc-212-51-218-225.p.lodz.pl	11	75	287.1 KB	8	0	03:03:32
2	web.man.lodz.pl	290	46	162.0 KB	22	0	00:00:31
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 3		306	121	449.1 KB	30	5	04:15:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	11	75	287.1 KB	8	0	03:03:32
2	*.man.lodz.pl	679	46	162.0 KB	22	5	00:00:35
3	*.toya.net.pl	129	0	0.0 KB	0	0	00:00:07
4	unresolved	3	0	0.0 KB	0	0	01:10:56
5	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:06
6	*.assecobs.pl	1	0	0.0 KB	0	0	00:00:07
TOTAL: 6		825	121	449.1 KB	30	5	04:15:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.050	0.193	03:03:32
185.48.192.92	0.932	1.153	01:10:56
web.man.lodz.pl	0.822	3.996	00:00:31
staticline41435.toya.net.pl	0.441	1.795	00:00:07
lubfw-ext.assecobs.pl	0.014	0.009	00:00:07
host227.elsnet.pl	0.008	0.032	00:00:06
mon.man.lodz.pl	0.904	4.024	00:00:02
david.man.lodz.pl	0.280	1.373	00:00:00
isvcs01.man.lodz.pl	0.030	0.078	00:00:00
TOTAL: 9	3.481	12.653	04:15:26

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
staticline41435.toya.net.pl	129
david.man.lodz.pl	96
185.48.192.92	3
host227.elsnet.pl	2
lubfw-ext.assecobs.pl	1
TOTAL: 6	519

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	4	2
185.48.192.92	1	0
TOTAL: 2	5	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	45
2	pl.misc.kolej	30
3	pl.comp.pecet	30
4	pl.comp.os.ms-windows.winnt	14
5	pl.internet.polip	2
TOTAL: 5		121

Daily Usenet report

Sep 9 04:15:01 -- Sep 10 04:15:02