Daily Usenet report: Feb 6 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Feb 6 10:31:38 newsfeed overchan[12367]: input line too long, skipping
Feb 6 10:59:15 newsfeed overchan[12367]: input line too long, skipping
Feb 6 15:08:59 newsfeed overchan[12367]: input line too long, skipping
Feb 6 15:53:19 newsfeed overchan[12367]: input line too long, skipping
Feb 6 17:33:26 newsfeed nnrpd[7984]: SERVER perl filter not defined
Feb 6 17:34:28 newsfeed nnrpd[7993]: SERVER perl filter not defined
Feb 6 20:35:26 newsfeed overchan[12367]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1629673	96.3%	188.5 MB	96.0%
innfeed	44647	2.6%	5.3 MB	2.7%
innd	12354	0.7%	2.0 MB	1.0%
nnrpd	5872	0.3%	631.9 KB	0.3%
overchan	6	0.0%	0.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1692553	100.0%	196.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1611014	98.2%
Negative hits	18544	1.1%
Positive hits	10705	0.7%
Cache misses	380	0.0%
TOTAL: 4	1640643	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.763	0.0%	68	0.000	11.221	33.000
article cleanup	00:00:22.344	0.0%	1614639	0.000	0.014	0.065
article logging	00:01:10.914	0.1%	1615104	0.000	0.044	0.138
article parse	00:30:34.438	2.1%	68791088	0.000	0.027	0.086
article write	00:12:16.700	0.9%	1609237	0.136	0.458	42.705
artlog/artcncl	00:00:00.001	0.0%	67	0.000	0.015	0.059
data move	00:01:12.425	0.1%	77800874	0.000	0.001	0.022
hisgrep/artcncl	00:00:00.206	0.0%	56	0.000	3.679	8.667
hishave/artcncl	00:00:00.036	0.0%	68	0.000	0.529	1.941
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:58.674	2.3%	1640668	0.015	1.206	81.025
history sync	00:00:00.020	0.0%	318	0.000	0.063	1.000
history write	00:25:26.418	1.8%	1611056	0.156	0.947	15.933
hiswrite/artcncl	00:00:00.033	0.0%	12	0.000	2.750	3.556
idle	18:17:47.629	76.2%	54015457	0.535	1.219	7988.400
nntp read	00:50:37.057	3.5%	67417722	0.020	0.045	0.235
overview write	00:00:09.088	0.0%	1609237	0.000	0.006	0.032
perl filter	02:47:50.014	11.7%	1611056	1.350	6.251	12.156
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:31.055	0.2%	3236712	0.023	0.065	0.129
TOTAL: 24:00:21.230	23:43:57.815	98.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.163	0.0%	56720	0.000	0.003	0.015
article prepare	00:00:00.006	0.0%	19304	0.000	0.000	0.009
article read	00:00:31.760	0.0%	4793	0.000	6.626	83.542
backlog stats	00:00:00.151	0.0%	154214	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.654	0.0%	81117	0.004	0.020	0.095
data write	00:00:06.659	0.0%	64375	0.025	0.103	0.300
idle	23:53:20.130	99.9%	154214	237.328	557.667	1534.717
status file	00:00:16.595	0.0%	16153	0.664	1.027	4.200
TOTAL: 23:54:34.206	23:54:17.118	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.907	0.0%	9733	0.059	0.607	2.350
idle	07:57:23.970	99.9%	13095	0.000	2187.397	8173.071
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.177	0.0%	27022	0.000	0.044	2.000
readart	00:00:00.041	0.0%	69	0.000	0.594	38.000
TOTAL: 07:57:50.961	07:57:31.095	99.9%	-	-	-	-

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	423	1623423	1620913	1382	1128	99%	166:28:06
2	news.nask.pl	2	12574	2427	5847	4300	19%	24:27:58
3	news.icm.edu.pl	42	257	48	208	1	18%	17:38:28
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		470	1636256	1623390	7437	5429	99%	208:34:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	432.9 GB	11.7 MB	12.2 MB	432.9 GB	99%	279.8 KB
2	news.nask.pl	16.4 MB	12.2 MB	22.8 MB	51.5 MB	31%	7.8 KB
3	news.icm.edu.pl	964.1 KB	0.0 KB	39.5 KB	1003.7 KB	96%	20.5 KB
4	localhost	2.9 KB	0.0 KB	0.0 KB	2.9 KB	100%	1.4 KB
TOTAL: 4		432.9 GB	24.0 MB	35.0 MB	432.9 GB	99%	278.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 6 04:15:02 - 04:59:59	7378	0.5%	2.73	1.0 GB	0.2%	397.98
Feb 6 05:00:00 - 05:59:59	12041	0.7%	3.34	3.3 GB	0.8%	967.30
Feb 6 06:00:00 - 06:59:59	224	0.0%	0.06	882.9 KB	0.0%	0.25
Feb 6 07:00:00 - 07:59:59	263	0.0%	0.07	820.3 KB	0.0%	0.23
Feb 6 08:00:00 - 08:59:59	297	0.0%	0.08	1.1 MB	0.0%	0.33
Feb 6 09:00:00 - 09:59:59	2845	0.2%	0.79	263.6 MB	0.1%	74.97
Feb 6 10:00:00 - 10:59:59	31801	2.0%	8.83	8.5 GB	2.0%	2464.49
Feb 6 11:00:00 - 11:59:59	41682	2.6%	11.58	11.8 GB	2.7%	3447.81
Feb 6 12:00:00 - 12:59:59	82489	5.1%	22.91	22.8 GB	5.3%	6632.62
Feb 6 13:00:00 - 13:59:59	88853	5.5%	24.68	24.5 GB	5.7%	7148.83
Feb 6 14:00:00 - 14:59:59	106478	6.6%	29.58	27.3 GB	6.3%	7963.23
Feb 6 15:00:00 - 15:59:59	116896	7.2%	32.47	26.2 GB	6.1%	7623.66
Feb 6 16:00:00 - 16:59:59	92860	5.7%	25.79	26.0 GB	6.0%	7558.74
Feb 6 17:00:00 - 17:59:59	85758	5.3%	23.82	24.7 GB	5.7%	7199.85
Feb 6 18:00:00 - 18:59:59	84058	5.2%	23.35	24.5 GB	5.7%	7130.95
Feb 6 19:00:00 - 19:59:59	84738	5.2%	23.54	24.8 GB	5.7%	7231.51
Feb 6 20:00:00 - 20:59:59	79104	4.9%	21.97	23.9 GB	5.5%	6963.95
Feb 6 21:00:00 - 21:59:59	82915	5.1%	23.03	23.3 GB	5.4%	6801.10
Feb 6 22:00:00 - 22:59:59	93157	5.7%	25.88	24.4 GB	5.6%	7093.28
Feb 6 23:00:00 - 23:59:59	92631	5.7%	25.73	28.2 GB	6.5%	8207.67
Feb 7 00:00:00 - 00:59:59	65269	4.0%	18.13	17.6 GB	4.1%	5134.56
Feb 7 01:00:00 - 01:59:59	111723	6.9%	31.03	26.7 GB	6.2%	7773.80
Feb 7 02:00:00 - 02:59:59	128443	7.9%	35.68	26.5 GB	6.1%	7711.18
Feb 7 03:00:00 - 03:59:59	108134	6.7%	30.04	29.6 GB	6.8%	8619.32
Feb 7 04:00:00 - 04:15:02	23688	1.5%	26.26	6.7 GB	1.6%	7814.87
TOTAL: 24:00:00	1623725	100.0%	18.79	432.6 GB	100.0%	5250.25

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4325	7	2675	5	1638
2	newsfeed.pionier.net.pl	1555	203	923	3	426
3	news.icm.edu.pl	1	0	0	0	1
TOTAL: 3		5881	210	3598	8	2065

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	73
it.comp.os.win.windows10	50
comp.sys.raspberry-pi	31
comp.mobile.android	27
it.tlc.cellulari.android	24
it.comp.os.win.windows7	3
it.discussioni.energia	2
TOTAL: 7	210

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1486
Binary in non-binary group	74
EMP (phl)	14
Scoring filter (8)	6
Scoring filter (10)	6
Scoring filter (9)	6
Too many newsgroups	5
HTML post	2
Scoring filter (11)	1
TOTAL: 9	1600

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	426
	news.nask.pl	39
	TOTAL: 2	465
TOTAL: 1		465

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	42097	1377	13459	427	3	40079	3%	23:59:58
2	poznan	3020	167	2442	13	1	1321	5%	23:47:53
3	icm	25120	82	16282	1667	0	1	0%	23:59:41
4	nask	9619	3	8281	0	0	0	0%	19:50:29
5	okapi	0	0	0	0	0	1205	0%	23:57:50
TOTAL: 5		79856	1629	40464	2107	4	42606	2%	115:35:51

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	4.0 MB	2.3 MB	6.3 MB	0.1 KB/s	3.6 KB	23:59:58
2	poznan	1.0 MB	38.8 KB	1.0 MB	0.0 KB/s	5.9 KB	23:47:53
3	icm	303.5 KB	553.0 MB	553.3 MB	6.6 KB/s	324.0 KB	23:59:41
4	nask	84.6 KB	0.0 KB	84.6 KB	0.0 KB/s	28.2 KB	19:50:29
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		5.4 MB	555.4 MB	560.8 MB	1.4 KB/s	153.7 KB	115:35:51

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	68	76.4 KB	26	0	00:00:22
2	az1-ss13.a2hosting.com	2	1	2.1 KB	1	0	00:00:12
3	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 3		292	69	78.5 KB	27	2	07:57:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	578	68	76.4 KB	26	2	00:00:26
2	*.a2hosting.com	2	1	2.1 KB	1	0	00:00:12
3	unresolved	286	0	0.0 KB	0	0	00:00:10
4	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:01
5	*.consolidated.net	6	0	0.0 KB	0	0	07:56:58
6	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
TOTAL: 6		874	69	78.5 KB	27	2	07:57:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
client-66-116-2-88.consolidated.net	0.828	1.948	07:56:58
web.man.lodz.pl	1.628	3.896	00:00:22
az1-ss13.a2hosting.com	0.028	0.040	00:00:12
92.116.19.171	0.984	2.068	00:00:09
mon.man.lodz.pl	1.300	2.804	00:00:03
212.51.192.185	0.340	0.812	00:00:01
scan-141.security.ipip.net	0.000	0.016	00:00:01
isvcs01.man.lodz.pl	0.004	0.048	00:00:00
ec2-34-220-101-13.us-west-2.compute.amazonaws.com	0.008	0.020	00:00:00
TOTAL: 9	5.120	11.652	07:57:49

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
92.116.19.171	190
212.51.192.185	96
client-66-116-2-88.consolidated.net	6
scan-141.security.ipip.net	1
ec2-34-220-101-13.us-west-2.compute.amazonaws.com	1
TOTAL: 6	582

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
client-66-116-2-88.consolidated.net	4	0
scan-141.security.ipip.net	1	1
TOTAL: 2	5	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	68	98.6%
2	lodman	1	1.4%
TOTAL: 2		69	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	68
2	lodman.test	1
TOTAL: 2		69

Daily Usenet report

Feb 6 04:15:02 -- Feb 7 04:15:02