Daily Usenet report: Dec 2 04:15:02

Unknown entries from news log file:

First 25 / 25 lines (100.0%)

Dec 2 05:15:24 newsfeed overchan[26188]: input line too long, skipping
Dec 2 05:59:13 newsfeed overchan[26188]: input line too long, skipping
Dec 2 06:22:36 newsfeed overchan[26188]: input line too long, skipping
Dec 2 10:06:07 newsfeed nnrpd[15956]: SERVER perl filter not defined
Dec 2 11:41:51 newsfeed nnrpd[30904]: SERVER perl filter not defined
Dec 2 13:13:30 newsfeed nnrpd[14092]: SERVER perl filter not defined
Dec 2 13:43:44 newsfeed nnrpd[18721]: SERVER perl filter not defined
Dec 2 15:20:08 newsfeed nnrpd[1123]: SERVER perl filter not defined
Dec 2 15:36:15 newsfeed overchan[26188]: input line too long, skipping
Dec 2 15:58:19 newsfeed overchan[26188]: input line too long, skipping
Dec 2 16:13:05 newsfeed nnrpd[9117]: SERVER perl filter not defined
Dec 2 16:15:17 newsfeed overchan[26188]: input line too long, skipping
Dec 2 16:49:24 newsfeed overchan[26188]: input line too long, skipping
Dec 2 17:07:40 newsfeed overchan[26188]: input line too long, skipping
Dec 2 18:27:34 newsfeed nnrpd[29922]: SERVER perl filter not defined
Dec 2 19:03:46 newsfeed overchan[26188]: input line too long, skipping
Dec 2 21:04:08 newsfeed overchan[26188]: input line too long, skipping
Dec 2 21:05:52 newsfeed nnrpd[21867]: SERVER perl filter not defined
Dec 2 21:07:57 newsfeed overchan[26188]: input line too long, skipping
Dec 2 23:55:51 newsfeed overchan[26188]: input line too long, skipping
Dec 3 00:30:33 newsfeed overchan[26188]: input line too long, skipping
Dec 3 01:04:08 newsfeed overchan[26188]: input line too long, skipping
Dec 3 02:01:25 newsfeed overchan[26188]: input line too long, skipping
Dec 3 02:19:26 newsfeed overchan[26188]: input line too long, skipping
Dec 3 02:27:22 newsfeed overchan[26188]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	662558	91.6%	72.2 MB	90.7%
innfeed	47429	6.6%	5.6 MB	7.1%
innd	8750	1.2%	1.4 MB	1.7%
nnrpd	4232	0.6%	419.2 KB	0.5%
overchan	18	0.0%	3.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	722988	100.0%	79.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	657972	99.7%
Negative hits	1703	0.3%
Cache misses	319	0.0%
Positive hits	273	0.0%
TOTAL: 4	660267	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.219	0.0%	364	0.000	14.338	54.600
article cleanup	00:00:11.146	0.0%	662242	0.012	0.017	0.022
article logging	00:00:35.068	0.0%	662692	0.040	0.053	0.064
article parse	00:22:39.695	1.6%	58135734	0.014	0.023	0.032
article write	00:08:35.350	0.6%	662002	0.590	0.778	1.000
artlog/artcncl	00:00:00.014	0.0%	364	0.000	0.038	1.000
data move	00:00:56.865	0.1%	62838019	0.001	0.001	0.001
hisgrep/artcncl	00:00:01.071	0.0%	357	0.000	3.000	14.400
hishave/artcncl	00:00:01.439	0.0%	364	0.000	3.953	14.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:35:59.035	2.5%	664434	0.595	3.249	12.160
history sync	00:00:00.028	0.0%	290	0.000	0.097	0.500
history write	00:41:02.494	2.8%	662209	0.707	3.719	12.449
hiswrite/artcncl	00:00:00.008	0.0%	7	0.000	1.143	5.000
idle	19:50:36.813	82.7%	57719451	0.866	1.238	2.843
nntp read	00:41:30.736	2.9%	57771690	0.032	0.043	0.054
overview write	00:00:04.232	0.0%	662002	0.002	0.006	0.010
perl filter	01:14:13.588	5.2%	662209	5.157	6.725	8.557
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:39.451	0.0%	1339676	0.022	0.029	0.037
TOTAL: 24:00:03.852	23:37:12.252	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.171	0.0%	75447	0.000	0.002	0.017
article prepare	00:00:00.009	0.0%	26036	0.000	0.000	0.028
article read	00:00:35.932	0.0%	6505	0.000	5.524	143.000
backlog stats	00:00:00.121	0.0%	143066	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.335	0.0%	71190	0.007	0.019	0.036
data write	00:00:03.698	0.0%	55798	0.027	0.066	0.122
idle	23:52:29.550	99.9%	143066	26.836	600.769	1431.054
status file	00:01:05.496	0.1%	16533	0.927	3.962	149.903
TOTAL: 23:54:23.642	23:54:16.312	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:19.980	59.0%	3088	0.000	6.470	355.167
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.236	0.7%	6733	0.000	0.035	0.667
readart	00:00:00.000	0.0%	10	0.000	0.000	0.000
TOTAL: 00:00:33.839	00:00:20.216	59.7%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	68	661623	661392	0	231	99%	56:25:38
2	news.icm.edu.pl	51	242	34	199	9	14%	17:05:07
3	localhost	1449	8	8	0	0	100%	00:00:00
TOTAL: 3		1568	661873	661434	199	240	99%	73:30:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	290.4 GB	2.0 MB	2.5 MB	290.4 GB	99%	460.3 KB
2	news.icm.edu.pl	1.9 MB	13.9 KB	326.8 KB	2.3 MB	85%	53.8 KB
3	localhost	15.6 KB	0.0 KB	0.0 KB	15.6 KB	100%	1.9 KB
TOTAL: 3		290.4 GB	2.0 MB	2.8 MB	290.4 GB	99%	460.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 2 04:15:02 - 04:59:59	18641	2.8%	6.91	10.4 GB	3.6%	4045.79
Dec 2 05:00:00 - 05:59:59	24101	3.6%	6.69	14.2 GB	4.9%	4122.56
Dec 2 06:00:00 - 06:59:59	26446	4.0%	7.35	13.6 GB	4.7%	3965.47
Dec 2 07:00:00 - 07:59:59	27279	4.1%	7.58	14.3 GB	4.9%	4155.37
Dec 2 08:00:00 - 08:59:59	27168	4.1%	7.55	14.0 GB	4.8%	4086.50
Dec 2 09:00:00 - 09:59:59	29246	4.4%	8.12	13.4 GB	4.6%	3897.07
Dec 2 10:00:00 - 10:59:59	24636	3.7%	6.84	11.7 GB	4.0%	3405.88
Dec 2 11:00:00 - 11:59:59	22505	3.4%	6.25	10.9 GB	3.7%	3163.97
Dec 2 12:00:00 - 12:59:59	22844	3.5%	6.35	11.8 GB	4.1%	3436.55
Dec 2 13:00:00 - 13:59:59	20586	3.1%	5.72	10.5 GB	3.6%	3055.04
Dec 2 14:00:00 - 14:59:59	23048	3.5%	6.40	12.2 GB	4.2%	3564.07
Dec 2 15:00:00 - 15:59:59	27107	4.1%	7.53	11.9 GB	4.1%	3469.77
Dec 2 16:00:00 - 16:59:59	35824	5.4%	9.95	12.4 GB	4.3%	3621.91
Dec 2 17:00:00 - 17:59:59	33578	5.1%	9.33	11.8 GB	4.1%	3440.02
Dec 2 18:00:00 - 18:59:59	31188	4.7%	8.66	11.2 GB	3.8%	3260.85
Dec 2 19:00:00 - 19:59:59	30279	4.6%	8.41	11.0 GB	3.8%	3210.08
Dec 2 20:00:00 - 20:59:59	29858	4.5%	8.29	11.5 GB	4.0%	3351.73
Dec 2 21:00:00 - 21:59:59	32751	5.0%	9.10	12.6 GB	4.3%	3674.77
Dec 2 22:00:00 - 22:59:59	31438	4.8%	8.73	13.5 GB	4.6%	3944.42
Dec 2 23:00:00 - 23:59:59	30914	4.7%	8.59	11.9 GB	4.1%	3465.25
Dec 3 00:00:00 - 00:59:59	32362	4.9%	8.99	12.1 GB	4.2%	3532.00
Dec 3 01:00:00 - 01:59:59	31340	4.7%	8.71	11.3 GB	3.9%	3289.61
Dec 3 02:00:00 - 02:59:59	22045	3.3%	6.12	10.5 GB	3.6%	3060.24
Dec 3 03:00:00 - 03:59:59	20955	3.2%	5.82	9.8 GB	3.4%	2856.09
Dec 3 04:00:00 - 04:15:02	5368	0.8%	5.95	2.7 GB	0.9%	3131.92
TOTAL: 24:00:00	661507	100.0%	7.66	291.3 GB	100.0%	3535.25

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	676	189	32	10	445
2	news.icm.edu.pl	11	0	1	0	10
TOTAL: 2		687	189	33	10	455

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	96
it.tlc.cellulari.android	29
it.comp.os.win.windows10	27
nl.comp.os.linux.techniek	10
comp.mobile.android	9
it.comp.os.win.windows7	9
comp.sys.raspberry-pi	5
it.comp.os.linux.ubuntu	2
de.comp.office-pakete.ms-office.excel	2
TOTAL: 9	189

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

Groups	Count
pl.sci.matematyka	6
pl.praca.oferowana	4
TOTAL: 2	10

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	6
EMP (md5)	2
TOTAL: 2	8

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	445
	news.icm.edu.pl	2
	TOTAL: 2	447
TOTAL: 1		447

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	62965	1711	30487	3457	4	39888	2%	23:59:27
2	icm	21504	504	13707	47	0	0	2%	23:57:52
3	poznan	26	0	19	0	0	0	0%	02:32:43
4	nask	0	0	0	0	0	10374	0%	23:50:02
5	okapi	0	0	0	0	0	851	0%	23:50:02
TOTAL: 5		84495	2215	44213	3504	4	51113	2%	98:10:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	4.6 MB	16.4 MB	21.0 MB	0.2 KB/s	4.2 KB	23:59:27
2	icm	1.4 MB	1.4 MB	2.9 MB	0.0 KB/s	5.3 KB	23:57:52
3	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	02:32:43
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:02
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:02
TOTAL: 5		6.0 MB	17.8 MB	23.8 MB	0.1 KB/s	4.3 KB	98:10:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	290	10	9.3 KB	5	0	00:00:21
2	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 2		298	10	9.3 KB	5	8	00:00:33

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	586	10	9.3 KB	5	8	00:00:27
2	unresolved	100	0	0.0 KB	0	0	00:00:05
3	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
TOTAL: 3		687	10	9.3 KB	5	8	00:00:33

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.689	3.519	00:00:21
80.111.142.213	0.019	0.069	00:00:04
mon.man.lodz.pl	1.596	3.299	00:00:04
isvcs01.man.lodz.pl	0.075	0.135	00:00:01
212.51.192.185	0.493	1.148	00:00:01
scan-141.security.ipip.net	0.009	0.009	00:00:00
TOTAL: 6	3.881	8.179	00:00:33

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
scan-141.security.ipip.net	1
TOTAL: 4	389

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
Host: 212.191.2.21:119	1
Connection: close	1
User-Agent: HTTP Banner Detection (https...	1
GET / HTTP/1.1	1
TOTAL: 4	4

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	6
2	pl.comp.pecet	4
TOTAL: 2		10

Daily Usenet report

Dec 2 04:15:02 -- Dec 3 04:15:02