Daily Usenet report: Nov 16 04:15:02

Unknown entries from news log file:

First 22 / 22 lines (100.0%)

Nov 16 11:10:17 newsfeed nnrpd[31183]: message repeated 8 times: [ pc-212-191-92-111.p.lodz.pl group pl.test 0]
Nov 16 11:13:13 newsfeed nnrpd[31183]: message repeated 8 times: [ pc-212-191-92-111.p.lodz.pl group pl.test 0]
Nov 16 11:14:16 newsfeed nnrpd[31488]: message repeated 2 times: [ pc-212-191-92-111.p.lodz.pl group pl.test 0]
Nov 16 11:14:51 newsfeed nnrpd[31488]: SERVER perl filter not defined
Nov 16 11:16:50 newsfeed nnrpd[31183]: SERVER perl filter not defined
Nov 16 11:19:00 newsfeed nnrpd[31616]: SERVER perl filter not defined
Nov 16 11:19:55 newsfeed nnrpd[31624]: SERVER perl filter not defined
Nov 16 11:32:27 newsfeed nnrpd[31995]: SERVER perl filter not defined
Nov 16 11:32:56 newsfeed nnrpd[31995]: message repeated 5 times: [ pc-212-191-92-111.p.lodz.pl group pl.test 0]
Nov 16 11:33:42 newsfeed nnrpd[31995]: message repeated 3 times: [ pc-212-191-92-111.p.lodz.pl group pl.test 0]
Nov 16 11:46:17 newsfeed nnrpd[32622]: SERVER perl filter not defined
Nov 16 14:35:36 newsfeed nnrpd[6186]: SERVER perl filter not defined
Nov 16 14:37:15 newsfeed nnrpd[6267]: SERVER perl filter not defined
Nov 16 14:38:58 newsfeed nnrpd[6254]: SERVER perl filter not defined
Nov 16 14:40:19 newsfeed nnrpd[6280]: SERVER perl filter not defined
Nov 16 14:41:09 newsfeed nnrpd[6357]: SERVER perl filter not defined
Nov 16 14:43:15 newsfeed nnrpd[6505]: SERVER perl filter not defined
Nov 16 14:43:51 newsfeed nnrpd[6543]: SERVER perl filter not defined
Nov 16 14:47:27 newsfeed nnrpd[6621]: SERVER perl filter not defined
Nov 16 14:47:58 newsfeed nnrpd[6563]: SERVER perl filter not defined
Nov 16 15:02:23 newsfeed nnrpd[7224]: SERVER perl filter not defined
Nov 16 14:44:46 newsfeed nnrpd[6534]: message repeated 4 times: [ pc-212-191-92-111.p.lodz.pl group pl.test 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3035071	98.6%	352.7 MB	98.4%
innd	32197	1.0%	4.7 MB	1.3%
nnrpd	5298	0.2%	553.0 KB	0.2%
innfeed	4855	0.2%	681.9 KB	0.2%
TOTAL: 4	3077421	100.0%	358.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3029094	96.1%
Negative hits	108945	3.5%
Positive hits	14192	0.5%
Cache misses	117	0.0%
TOTAL: 4	3152348	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.647	0.0%	188	0.000	19.399	627.667
article cleanup	00:00:47.989	0.1%	3034856	0.008	0.016	0.027
article logging	00:01:41.640	0.1%	3035557	0.019	0.033	0.998
article parse	01:26:54.502	6.0%	147366873	0.017	0.035	0.063
article write	00:28:56.120	2.0%	3020788	0.369	0.575	2.558
artlog/artcncl	00:00:00.001	0.0%	178	0.000	0.006	0.500
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:03:32.581	0.2%	164962489	0.001	0.001	0.004
hisgrep/artcncl	00:00:00.498	0.0%	175	0.000	2.846	121.333
hishave/artcncl	00:00:00.914	0.0%	188	0.000	4.862	284.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:01:08.505	4.2%	3155152	0.171	1.163	7.859
history sync	00:00:00.018	0.0%	290	0.000	0.062	2.000
history write	01:26:20.444	6.0%	3031766	0.285	1.709	12.297
hiswrite/artcncl	00:00:00.023	0.0%	13	0.000	1.769	17.000
idle	11:00:39.385	45.9%	78211957	0.338	0.507	1.147
nntp read	01:04:51.436	4.5%	144776680	0.017	0.027	0.037
overview write	00:00:19.327	0.0%	3020788	0.001	0.006	0.014
perl filter	06:37:57.142	27.6%	3031765	6.082	7.876	9.801
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:19.052	0.4%	6076862	0.033	0.053	0.081
TOTAL: 24:00:30.160	23:18:33.224	97.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.197	0.0%	59887	0.000	0.003	0.250
article prepare	00:00:00.015	0.0%	57508	0.000	0.000	0.250
article read	00:00:42.210	0.0%	14835	0.000	2.845	280.667
backlog stats	00:00:00.231	0.0%	231008	0.000	0.001	0.022
callbacks	00:00:00.001	0.0%	337	0.000	0.003	0.111
data read	00:00:01.788	0.0%	120583	0.000	0.015	0.382
data write	00:00:09.298	0.0%	110419	0.000	0.084	0.271
idle	23:58:49.754	99.9%	231008	22.741	373.709	5861.909
status file	00:00:00.726	0.0%	881	0.000	0.824	6.000
TOTAL: 23:59:52.859	23:59:44.220	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	07:20:28.719	99.8%	3615	0.000	7310.849	450027.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:35.150	0.1%	915678	0.000	0.038	6.500
readart	00:00:02.758	0.0%	259	0.000	10.649	377.500
TOTAL: 07:21:27.474	07:21:06.627	99.9%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	747	3020473	3008660	8004	3809	99%	493:03:36
2	news.nask.pl	2	23997	11073	2719	10205	46%	23:56:26
3	localhost	24	23	23	0	0	100%	00:00:01
4	news.icm.edu.pl	36	364	10	334	20	2%	17:50:31
TOTAL: 4		809	3044857	3019766	11057	14034	99%	534:50:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	910.0 GB	30.0 MB	225.1 MB	910.2 GB	99%	316.8 KB
2	news.nask.pl	59.0 MB	1.5 MB	150.0 MB	210.6 MB	28%	10.1 KB
3	news.icm.edu.pl	267.5 KB	31.5 KB	483.7 KB	782.6 KB	34%	26.1 KB
4	localhost	18.1 KB	0.0 KB	0.0 KB	18.1 KB	100%	0.8 KB
TOTAL: 4		910.1 GB	31.5 MB	375.5 MB	910.5 GB	99%	314.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 16 04:15:02 - 04:59:59	65579	2.2%	24.31	21.9 GB	2.4%	8529.90
Nov 16 05:00:00 - 05:59:59	84950	2.8%	23.60	27.4 GB	3.0%	7983.73
Nov 16 06:00:00 - 06:59:59	93509	3.1%	25.97	31.0 GB	3.4%	9037.81
Nov 16 07:00:00 - 07:59:59	95988	3.2%	26.66	32.3 GB	3.6%	9407.18
Nov 16 08:00:00 - 08:59:59	92819	3.1%	25.78	31.2 GB	3.4%	9088.58
Nov 16 09:00:00 - 09:59:59	107487	3.6%	29.86	33.1 GB	3.6%	9647.89
Nov 16 10:00:00 - 10:59:59	101844	3.4%	28.29	31.2 GB	3.4%	9088.25
Nov 16 11:00:00 - 11:59:59	133207	4.4%	37.00	39.4 GB	4.3%	11462.30
Nov 16 12:00:00 - 12:59:59	134464	4.5%	37.35	41.4 GB	4.6%	12057.15
Nov 16 13:00:00 - 13:59:59	133739	4.4%	37.15	36.6 GB	4.0%	10661.43
Nov 16 14:00:00 - 14:59:59	146616	4.9%	40.73	42.0 GB	4.6%	12239.23
Nov 16 15:00:00 - 15:59:59	148767	4.9%	41.32	43.2 GB	4.7%	12580.50
Nov 16 16:00:00 - 16:59:59	153140	5.1%	42.54	44.2 GB	4.9%	12887.62
Nov 16 17:00:00 - 17:59:59	141129	4.7%	39.20	44.7 GB	4.9%	13012.35
Nov 16 18:00:00 - 18:59:59	135078	4.5%	37.52	42.8 GB	4.7%	12475.17
Nov 16 19:00:00 - 19:59:59	134265	4.4%	37.30	42.6 GB	4.7%	12401.19
Nov 16 20:00:00 - 20:59:59	143289	4.7%	39.80	42.5 GB	4.7%	12390.65
Nov 16 21:00:00 - 21:59:59	141282	4.7%	39.24	41.3 GB	4.5%	12031.08
Nov 16 22:00:00 - 22:59:59	138550	4.6%	38.49	39.8 GB	4.4%	11598.68
Nov 16 23:00:00 - 23:59:59	135365	4.5%	37.60	38.9 GB	4.3%	11333.20
Nov 17 00:00:00 - 00:59:59	120856	4.0%	33.57	35.4 GB	3.9%	10324.44
Nov 17 01:00:00 - 01:59:59	149465	4.9%	41.52	42.9 GB	4.7%	12494.19
Nov 17 02:00:00 - 02:59:59	138177	4.6%	38.38	40.3 GB	4.4%	11746.80
Nov 17 03:00:00 - 03:59:59	117664	3.9%	32.68	34.0 GB	3.7%	9893.17
Nov 17 04:00:00 - 04:15:02	32901	1.1%	36.48	9.3 GB	1.0%	10803.06
TOTAL: 24:00:00	3020130	100.0%	34.96	909.6 GB	100.0%	11039.25

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	10538	15	501	5	10017
2	newsfeed.pionier.net.pl	4202	194	2588	0	1420
3	news.icm.edu.pl	21	0	2	0	19
TOTAL: 3		14761	209	3091	5	11456

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	116
it.tlc.cellulari.android	28
it.comp.os.win.windows10	22
comp.mobile.android	14
it.comp.os.win.windows7	9
de.comp.office-pakete.ms-office.excel	4
hr.ponuda.hardver.periferije	3
uk.rec.cycling.moderated	3
nl.comp.os.linux.techniek	3
comp.mobile.ipad	2
uk.net.providers.gradwell	2
it.comp.os.win.windows8	1
alt.sex.telephones	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 14	209

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

Groups	Count
pl.praca.oferowana	3
pl.sci.historia	1
bit.listserv.catholic	1
TOTAL: 3	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8749
Binary in non-binary group	1665
Poison newsgroup	122
Scoring filter (8)	89
EMP (phl)	60
Scoring filter (9)	31
HTML post	9
Too many newsgroups	8
Scoring filter (10)	7
Scoring filter (13)	5
EMP (fsl)	5
Angle-bracket bot	3
Scoring filter (11)	2
TOTAL: 13	10755

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	25195	1868	20186	761	1006	36040	7%	23:59:50
2	icm	40135	116	23385	10809	0	0	0%	23:55:31
3	nask	6908	75	5006	78	0	0	1%	23:54:53
4	poznan	15422	56	10851	21	0	0	0%	24:04:06
5	okapi	0	0	0	0	0	2409	0%	24:00:01
TOTAL: 5		87660	2115	59428	11669	1006	38449	2%	119:54:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	4.9 MB	2.6 GB	2.6 GB	32.1 KB/s	253.0 KB	23:55:31
2	panorama	4.7 MB	4.4 MB	9.1 MB	0.1 KB/s	3.6 KB	23:59:50
3	nask	214.6 KB	41.5 MB	41.7 MB	0.5 KB/s	279.1 KB	23:54:53
4	poznan	162.7 KB	92.7 KB	255.4 KB	0.0 KB/s	3.3 KB	24:04:06
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		10.0 MB	2.7 GB	2.7 GB	6.5 KB/s	204.3 KB	119:54:21

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
okapi	488.4 KB
TOTAL: 2	2.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	pc-212-191-92-111.p.lodz.pl	60	121	238.2 KB	37	23	16	07:02:23
2	web.man.lodz.pl	288	92	94.0 KB	44	0	0	00:00:24
3	lze200.lze.lodz.pl	4	46	93.1 KB	6	0	0	00:18:28
TOTAL: 3		352	259	425.3 KB	87	23	16	07:21:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.p.lodz.pl	60	121	238.2 KB	37	23	16	07:02:23
2	*.man.lodz.pl	672	92	94.0 KB	44	0	0	00:00:29
3	*.lze.lodz.pl	4	46	93.1 KB	6	0	0	00:18:28
4	unresolved	2	0	0.0 KB	0	0	0	00:00:04
TOTAL: 4		738	259	425.3 KB	87	23	16	07:21:27

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-191-92-111.p.lodz.pl	3.363	3.505	07:02:23
lze200.lze.lodz.pl	0.026	0.109	00:18:28
web.man.lodz.pl	1.017	6.500	00:00:24
mon.man.lodz.pl	0.979	6.164	00:00:03
196.52.20.28	0.004	0.026	00:00:02
196.52.18.8	0.004	0.025	00:00:01
david.man.lodz.pl	0.234	2.050	00:00:01
TOTAL: 7	5.627	18.379	07:21:27

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
196.52.18.8	1
196.52.20.28	1
TOTAL: 4	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-191-92-111.p.lodz.pl	7	0
TOTAL: 1	7	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.test	121
2	pl.comp.os.ms-windows.winnt	60
3	pl.comp.pecet	54
4	pl.rec.kuchnia	13
5	pl.comp.os.linux	5
6	pl.misc.telefonia.gsm	4
7	pl.rec.dom	2
TOTAL: 7		259

Daily Usenet report

Nov 16 04:15:02 -- Nov 17 04:15:02