Daily Usenet report: Nov 30 04:15:05

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Nov 30 05:26:23 newsfeed overchan[21098]: input line too long, skipping
Nov 30 09:20:14 newsfeed nnrpd[20456]: SERVER perl filter not defined
Nov 30 10:52:39 newsfeed nnrpd[23548]: SERVER perl filter not defined
Nov 30 11:01:15 newsfeed nnrpd[23916]: SERVER perl filter not defined
Nov 30 14:18:45 newsfeed nnrpd[30638]: SERVER perl filter not defined
Nov 30 19:10:53 newsfeed nnrpd[8198]: message repeated 2 times: [ 79.162.235.209 group lodzman.test 0]
Dec 1 04:15:02 newsfeed nnrpd[27474]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2972582	97.4%	343.1 MB	97.0%
innfeed	46761	1.5%	5.6 MB	1.6%
innd	25907	0.8%	4.4 MB	1.2%
nnrpd	5284	0.2%	571.4 KB	0.2%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	3050535	100.0%	353.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2960346	97.0%
Negative hits	68872	2.3%
Positive hits	22909	0.8%
Cache misses	237	0.0%
TOTAL: 4	3052364	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.054	0.0%	352	0.000	20.040	1237.000
article cleanup	00:00:49.318	0.1%	2972258	0.004	0.017	0.032
article logging	00:01:26.816	0.1%	2972807	0.014	0.029	0.586
article parse	01:22:39.994	5.7%	262714858	0.006	0.019	0.087
article write	00:54:56.101	3.8%	2960013	0.344	1.114	3.169
artlog/artcncl	00:00:00.004	0.0%	329	0.000	0.012	1.000
artlog/artparse	00:00:00.000	0.0%	30	0.000	0.000	0.000
data move	00:03:48.018	0.3%	281290782	0.000	0.001	0.008
hisgrep/artcncl	00:00:01.268	0.0%	342	0.000	3.708	11.634
hishave/artcncl	00:00:00.311	0.0%	352	0.000	0.884	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:35:19.863	2.5%	3054872	0.120	0.694	21.245
history sync	00:00:00.016	0.0%	295	0.000	0.054	1.000
history write	00:48:54.844	3.4%	2962965	0.218	0.991	56.374
hiswrite/artcncl	00:00:00.024	0.0%	10	0.000	2.400	10.000
idle	11:57:30.728	49.8%	173815555	0.111	0.248	0.586
nntp read	01:17:59.894	5.4%	260334681	0.008	0.018	0.046
overview write	00:00:17.255	0.0%	2960013	0.001	0.006	0.014
perl filter	05:51:04.108	24.4%	2962935	4.476	7.109	10.405
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:09.392	0.4%	6125622	0.029	0.060	0.118
TOTAL: 24:00:21.842	23:01:05.008	95.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.885	0.0%	312936	0.000	0.003	0.077
article prepare	00:00:00.013	0.0%	55422	0.000	0.000	0.250
article read	00:01:07.509	0.1%	13809	0.000	4.889	733.000
backlog stats	00:00:00.411	0.0%	519197	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	11	0.000	0.000	0.000
data read	00:00:04.172	0.0%	348321	0.000	0.012	0.158
data write	00:00:10.955	0.0%	152643	0.000	0.072	2.179
idle	23:57:41.418	99.9%	519197	30.221	166.144	855.648
status file	00:00:12.843	0.0%	15903	0.167	0.808	67.333
TOTAL: 23:59:35.113	23:59:18.206	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:03:53.218	99.7%	5366	0.000	2056.135	517106.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:09.351	0.1%	193425	0.000	0.048	1.171
readart	00:00:00.724	0.0%	115	0.000	6.296	65.400
TOTAL: 03:04:24.964	03:04:03.293	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1304	2968090	2953809	2590	11691	99%	381:41:09
2	news.nask.pl	2	16263	5159	10640	464	31%	23:50:07
3	news.icm.edu.pl	44	340	64	174	102	18%	17:40:57
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		1355	2984697	2959036	13404	12257	99%	423:12:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	840.4 GB	1.2 GB	1.7 GB	843.3 GB	99%	298.2 KB
2	news.nask.pl	17.6 MB	1.3 MB	1.3 MB	20.1 MB	87%	3.7 KB
3	news.icm.edu.pl	2.3 MB	156.9 KB	3.0 MB	5.5 MB	42%	34.1 KB
4	localhost	4.1 KB	0.0 KB	0.0 KB	4.1 KB	100%	1.0 KB
TOTAL: 4		840.4 GB	1.2 GB	1.7 GB	843.3 GB	99%	297.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 30 04:15:05 - 04:59:59	68776	2.3%	25.52	20.9 GB	2.5%	8135.15
Nov 30 05:00:00 - 05:59:59	113539	3.8%	31.54	33.1 GB	3.9%	9626.59
Nov 30 06:00:00 - 06:59:59	116714	3.9%	32.42	34.0 GB	4.0%	9904.96
Nov 30 07:00:00 - 07:59:59	123862	4.2%	34.41	36.5 GB	4.4%	10642.39
Nov 30 08:00:00 - 08:59:59	122732	4.1%	34.09	35.5 GB	4.2%	10348.01
Nov 30 09:00:00 - 09:59:59	122623	4.1%	34.06	36.3 GB	4.3%	10559.24
Nov 30 10:00:00 - 10:59:59	113091	3.8%	31.41	36.2 GB	4.3%	10535.88
Nov 30 11:00:00 - 11:59:59	125809	4.3%	34.95	39.1 GB	4.7%	11397.77
Nov 30 12:00:00 - 12:59:59	148185	5.0%	41.16	40.1 GB	4.8%	11672.56
Nov 30 13:00:00 - 13:59:59	129539	4.4%	35.98	37.4 GB	4.5%	10891.94
Nov 30 14:00:00 - 14:59:59	121258	4.1%	33.68	36.7 GB	4.4%	10678.56
Nov 30 15:00:00 - 15:59:59	140471	4.7%	39.02	36.3 GB	4.3%	10583.71
Nov 30 16:00:00 - 16:59:59	109457	3.7%	30.40	32.7 GB	3.9%	9512.13
Nov 30 17:00:00 - 17:59:59	111213	3.8%	30.89	33.4 GB	4.0%	9714.16
Nov 30 18:00:00 - 18:59:59	111405	3.8%	30.95	33.9 GB	4.0%	9881.06
Nov 30 19:00:00 - 19:59:59	106103	3.6%	29.47	30.9 GB	3.7%	8998.72
Nov 30 20:00:00 - 20:59:59	119065	4.0%	33.07	33.8 GB	4.0%	9839.45
Nov 30 21:00:00 - 21:59:59	123604	4.2%	34.33	36.4 GB	4.3%	10603.04
Nov 30 22:00:00 - 22:59:59	122461	4.1%	34.02	31.7 GB	3.8%	9236.30
Nov 30 23:00:00 - 23:59:59	123959	4.2%	34.43	34.0 GB	4.0%	9901.24
Dec 1 00:00:00 - 00:59:59	108820	3.7%	30.23	30.0 GB	3.6%	8736.93
Dec 1 01:00:00 - 01:59:59	149968	5.1%	41.66	38.2 GB	4.6%	11140.16
Dec 1 02:00:00 - 02:59:59	147071	5.0%	40.85	38.1 GB	4.5%	11099.57
Dec 1 03:00:00 - 03:59:59	142870	4.8%	39.69	35.7 GB	4.2%	10390.76
Dec 1 04:00:00 - 04:15:02	36834	1.2%	40.84	9.0 GB	1.1%	10454.65
TOTAL: 23:59:57	2959429	100.0%	34.25	839.8 GB	100.0%	10192.22

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	12156	340	8860	2	2954
2	news.nask.pl	555	10	459	1	85
3	news.icm.edu.pl	113	0	4	0	109
TOTAL: 3		12824	350	9323	3	3148

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	201
it.comp.os.win.windows10	44
it.tlc.cellulari.android	32
comp.mobile.ipad	15
uk.rec.cycling.moderated	13
it.comp.os.win.windows7	11
comp.mobile.android	7
it.comp.os.win.windows8	7
comp.sys.raspberry-pi	3
alt.sex.telephones	2
hr.alt.cellular.gsm.android	2
hr.ponuda.hardver.konfiguracije	2
hr.ponuda.hardver.komponente	2
de.rec.sf.startrek	2
rec.arts.tv.x-factor	2
comp.sys.mac.vintage	1
de.comp.lang.php	1
it.discussioni.energia	1
de.comp.lang.vbclassic	1
nl.comp.sys.android	1
TOTAL: 20	350

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

Groups	Count
hr.alt.hackers	1
bit.listserv.catholic	1
pl.sci.historia	1
TOTAL: 3	3

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	2063
Binary in non-binary group	394
EMP (md5)	104
HTML post	4
Too many newsgroups	1
Scoring filter (8)	1
Attached HTML file	1
Angle-bracket bot	1
TOTAL: 8	2569

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	129613	2518	99563	1097	25	209855	1%	23:59:42
2	icm	63901	1700	40451	7693	0	7	2%	23:57:32
3	poznan	7926	97	5100	35	0	0	1%	23:55:40
4	okapi	0	0	0	0	0	2678	0%	24:00:01
5	nask	0	0	0	0	0	26351	0%	24:00:01
TOTAL: 5		201440	4315	145114	8825	25	238891	2%	119:52:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	74.3 MB	2.2 GB	2.3 GB	27.7 KB/s	254.6 KB	23:57:32
2	panorama	14.5 MB	15.6 MB	30.1 MB	0.4 KB/s	8.5 KB	23:59:42
3	poznan	342.5 KB	62.1 KB	404.6 KB	0.0 KB/s	3.1 KB	23:55:40
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		89.1 MB	2.2 GB	2.3 GB	5.6 KB/s	184.3 KB	119:52:56

Backlog files shrunk by innfeed:

Server	Size
panorama	3.3 MB
okapi	488.4 KB
TOTAL: 2	3.8 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	90	96.5 KB	42	0	0	00:00:36
2	aaim105.neoplus.adsl.tpnet.pl	15	20	17.4 KB	5	0	12	00:24:37
3	radius2.p.lodz.pl	3	3	2.4 KB	2	1	0	00:36:01
4	pc-212-51-221-187.p.lodz.pl	6	2	1.6 KB	2	2	0	00:47:27
5	79.162.235.209	8	0	0.0 KB	0	0	5	00:52:07
6	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	0	00:00:00
TOTAL: 6		321	115	117.8 KB	51	4	17	03:04:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	673	90	96.5 KB	42	1	0	00:00:40
2	*.neoplus.adsl.tpnet.pl	15	20	17.4 KB	5	0	12	00:24:37
3	*.p.lodz.pl	9	5	4.0 KB	4	3	0	01:23:29
4	*.elsnet.pl	2	0	0.0 KB	0	0	0	00:01:22
5	*.adsl.inetia.pl	1	0	0.0 KB	0	0	0	00:00:03
6	unresolved	16	0	0.0 KB	0	0	5	00:58:02
7	*.toya.net.pl	54	0	0.0 KB	0	0	0	00:16:09
TOTAL: 7		770	115	117.8 KB	51	4	17	03:04:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
79.162.235.209	0.731	0.191	00:52:07
pc-212-51-221-187.p.lodz.pl	0.542	0.399	00:47:27
radius2.p.lodz.pl	0.297	0.141	00:36:01
aaim105.neoplus.adsl.tpnet.pl	0.125	0.211	00:24:37
staticline-31-183-65-154.toya.net.pl	0.025	0.066	00:15:49
89.38.41.217	0.089	0.190	00:05:31
host227.elsnet.pl	0.008	0.033	00:01:22
web.man.lodz.pl	0.929	4.460	00:00:36
212.51.203.199	0.003	0.028	00:00:22
staticline17008.toya.net.pl	0.012	0.042	00:00:09
staticline41435.toya.net.pl	0.141	0.632	00:00:05
staticline-31-183-40-197.toya.net.pl	0.003	0.017	00:00:04
178-37-243-175.adsl.inetia.pl	0.010	0.010	00:00:03
mon.man.lodz.pl	0.874	4.560	00:00:02
david.man.lodz.pl	0.273	1.297	00:00:00
212.244.241.25	0.009	0.033	00:00:00
isvcs01.man.lodz.pl	0.000	0.011	00:00:00
TOTAL: 17	4.071	12.321	03:04:24

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	46
staticline-31-183-65-154.toya.net.pl	4
212.244.241.25	3
staticline17008.toya.net.pl	3
89.38.41.217	3
host227.elsnet.pl	2
212.51.203.199	2
178-37-243-175.adsl.inetia.pl	1
staticline-31-183-40-197.toya.net.pl	1
TOTAL: 11	449

NNRP client timeouts [Top 20]:

System	Conn	Peer
79.162.235.209	2	0
radius2.p.lodz.pl	1	0
pc-212-51-221-187.p.lodz.pl	1	0
TOTAL: 3	4	3

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	95	82.6%
2	lodz	14	12.2%
3	lodzman	5	4.3%
4	lodman	1	0.9%
TOTAL: 4		115	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	64
2	pl.comp.os.ms-windows.winnt	26
3	lodz.test	14
4	pl.test	5
5	lodzman.test	5
6	lodman.test	1
TOTAL: 6		115

Daily Usenet report

Nov 30 04:15:05 -- Dec 1 04:15:02