Daily Usenet report: Apr 4 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Apr 4 08:05:18 newsfeed nnrpd[17521]: SERVER perl filter not defined
Apr 4 09:27:28 newsfeed nnrpd[20421]: SERVER perl filter not defined
Apr 4 14:56:37 newsfeed overchan[9114]: input line too long, skipping
Apr 4 15:07:50 newsfeed overchan[9114]: input line too long, skipping
Apr 4 17:56:26 newsfeed overchan[9114]: input line too long, skipping
Apr 4 20:09:41 newsfeed overchan[9114]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1970602	97.0%	219.2 MB	96.5%
innfeed	43979	2.2%	5.2 MB	2.3%
innd	13024	0.6%	2.2 MB	1.0%
nnrpd	4937	0.2%	520.4 KB	0.2%
overchan	5	0.0%	0.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2032548	100.0%	227.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1962716	98.7%
Negative hits	16230	0.8%
Positive hits	10221	0.5%
Cache misses	54	0.0%
TOTAL: 4	1989221	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.099	0.0%	120	0.000	9.158	42.400
article cleanup	00:00:26.868	0.0%	1971717	0.010	0.014	0.017
article logging	00:01:27.782	0.1%	1972298	0.033	0.045	0.067
article parse	00:31:15.403	2.2%	61962930	0.021	0.030	0.042
article write	00:12:31.798	0.9%	1966513	0.232	0.382	0.558
artlog/artcncl	00:00:00.003	0.0%	112	0.000	0.027	1.000
data move	00:01:15.614	0.1%	72529002	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.242	0.0%	115	0.000	2.104	19.250
hishave/artcncl	00:00:00.029	0.0%	120	0.000	0.242	2.600
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:17:58.108	1.2%	1994629	0.040	0.541	4.009
history sync	00:00:00.018	0.0%	292	0.000	0.062	1.000
history write	00:45:39.569	3.2%	1968189	0.192	1.392	5.305
hiswrite/artcncl	00:00:00.004	0.0%	5	0.000	0.800	1.333
idle	17:57:37.118	74.8%	47290943	0.890	1.367	2.423
nntp read	00:46:11.463	3.2%	60245595	0.034	0.046	0.059
overview write	00:00:10.415	0.0%	1966513	0.003	0.005	0.008
perl filter	03:03:12.138	12.7%	1968189	4.038	5.585	7.443
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:00.908	0.3%	3949418	0.047	0.061	0.082
TOTAL: 24:00:01.731	23:41:48.579	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.117	0.0%	52510	0.000	0.002	0.008
article prepare	00:00:00.003	0.0%	6140	0.000	0.000	0.050
article read	00:00:22.900	0.0%	1526	0.000	15.007	460.000
backlog stats	00:00:00.121	0.0%	144900	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	4	0.000	0.000	0.000
data read	00:00:01.388	0.0%	76005	0.006	0.018	0.037
data write	00:00:03.371	0.0%	59698	0.024	0.056	0.105
idle	23:52:47.204	99.9%	144900	351.243	593.286	1125.589
status file	00:00:20.390	0.0%	16615	0.817	1.227	2.101
TOTAL: 23:53:52.108	23:53:35.494	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:38.046	99.3%	3115	0.000	590.063	900050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.375	0.0%	6953	0.000	0.054	5.526
readart	00:00:00.016	0.0%	63	0.000	0.254	11.000
TOTAL: 00:30:51.484	00:30:38.437	99.3%	-	-	-	-

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	195	1964751	1963435	24	1292	99%	133:47:16
2	news.nask.pl	2	11552	1330	6304	3918	11%	23:38:09
3	news.icm.edu.pl	43	257	43	195	19	16%	15:39:34
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		243	1976562	1964810	6523	5229	99%	173:04:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	525.4 GB	16.8 MB	17.1 MB	525.4 GB	99%	280.4 KB
2	news.nask.pl	14.8 MB	11.4 MB	27.1 MB	53.3 MB	27%	10.4 KB
3	news.icm.edu.pl	2.0 MB	19.7 KB	984.3 KB	3.0 MB	67%	49.4 KB
4	localhost	2.9 KB	0.0 KB	0.0 KB	2.9 KB	100%	1.4 KB
TOTAL: 4		525.4 GB	28.2 MB	45.2 MB	525.4 GB	99%	279.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 4 04:15:02 - 04:59:59	64538	3.3%	23.92	18.3 GB	3.5%	7118.48
Apr 4 05:00:00 - 05:59:59	96402	4.9%	26.78	27.2 GB	5.2%	7925.34
Apr 4 06:00:00 - 06:59:59	95812	4.9%	26.61	26.3 GB	5.0%	7663.53
Apr 4 07:00:00 - 07:59:59	100298	5.1%	27.86	27.1 GB	5.2%	7888.66
Apr 4 08:00:00 - 08:59:59	96916	4.9%	26.92	26.5 GB	5.1%	7731.51
Apr 4 09:00:00 - 09:59:59	97182	4.9%	27.00	27.1 GB	5.2%	7893.67
Apr 4 10:00:00 - 10:59:59	105228	5.4%	29.23	29.3 GB	5.6%	8539.23
Apr 4 11:00:00 - 11:59:59	110651	5.6%	30.74	28.0 GB	5.3%	8169.23
Apr 4 12:00:00 - 12:59:59	71993	3.7%	20.00	21.2 GB	4.0%	6167.49
Apr 4 13:00:00 - 13:59:59	65739	3.3%	18.26	19.5 GB	3.7%	5692.89
Apr 4 14:00:00 - 14:59:59	39690	2.0%	11.03	11.4 GB	2.2%	3333.28
Apr 4 15:00:00 - 15:59:59	38049	1.9%	10.57	11.6 GB	2.2%	3370.23
Apr 4 16:00:00 - 16:59:59	39378	2.0%	10.94	11.5 GB	2.2%	3341.83
Apr 4 17:00:00 - 17:59:59	59262	3.0%	16.46	15.0 GB	2.9%	4377.96
Apr 4 18:00:00 - 18:59:59	58727	3.0%	16.31	14.8 GB	2.8%	4301.81
Apr 4 19:00:00 - 19:59:59	40955	2.1%	11.38	11.5 GB	2.2%	3345.30
Apr 4 20:00:00 - 20:59:59	123457	6.3%	34.29	25.9 GB	4.9%	7556.59
Apr 4 21:00:00 - 21:59:59	106864	5.4%	29.68	29.8 GB	5.7%	8685.22
Apr 4 22:00:00 - 22:59:59	72703	3.7%	20.20	20.8 GB	4.0%	6054.33
Apr 4 23:00:00 - 23:59:59	90725	4.6%	25.20	23.2 GB	4.4%	6753.47
Apr 5 00:00:00 - 00:59:59	75011	3.8%	20.84	16.1 GB	3.1%	4699.24
Apr 5 01:00:00 - 01:59:59	96432	4.9%	26.79	26.4 GB	5.0%	7702.46
Apr 5 02:00:00 - 02:59:59	98950	5.0%	27.49	24.4 GB	4.6%	7100.67
Apr 5 03:00:00 - 03:59:59	100173	5.1%	27.83	26.5 GB	5.1%	7727.18
Apr 5 04:00:00 - 04:15:02	19584	1.0%	21.71	5.1 GB	1.0%	5982.15
TOTAL: 24:00:00	1964719	100.0%	22.74	524.8 GB	100.0%	6368.95

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3906	0	2362	2	1542
2	newsfeed.pionier.net.pl	1846	131	1153	1	561
3	news.icm.edu.pl	19	0	2	0	17
TOTAL: 3		5771	131	3517	3	2120

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	74
it.tlc.cellulari.android	34
it.comp.os.win.windows10	13
comp.sys.raspberry-pi	7
comp.mobile.ipad	1
de.comp.office-pakete.ms-office.word	1
comp.mobile.android	1
TOTAL: 7	131

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1360
Binary in non-binary group	106
Scoring filter (8)	34
EMP (phl)	15
Scoring filter (10)	12
Scoring filter (9)	9
Too many newsgroups	3
Scoring filter (11)	3
TOTAL: 8	1542

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	555
	news.nask.pl	23
	TOTAL: 2	578
TOTAL: 1		578

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	41802	664	13247	195	6	38580	1%	23:59:55
2	icm	24284	86	15803	20	0	0	0%	23:50:09
3	nask	9907	4	8605	2	0	0	0%	23:55:49
4	poznan	2010	3	1380	4	0	0	0%	23:50:03
5	okapi	0	0	0	0	0	976	0%	23:50:05
TOTAL: 5		78003	757	39035	221	6	39556	0%	119:26:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	2.1 MB	967.5 KB	3.1 MB	0.0 KB/s	3.7 KB	23:59:55
2	icm	784.3 KB	5.3 MB	6.1 MB	0.1 KB/s	59.0 KB	23:50:09
3	nask	102.7 KB	3.1 KB	105.8 KB	0.0 KB/s	17.6 KB	23:55:49
4	poznan	19.8 KB	8.8 KB	28.7 KB	0.0 KB/s	4.1 KB	23:50:03
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
TOTAL: 5		3.0 MB	6.3 MB	9.3 MB	0.0 KB/s	9.8 KB	119:26:01

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	62	65.9 KB	28	0	00:00:19
2	az1-ss13.a2hosting.com	2	1	0.6 KB	1	0	00:00:12
3	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 3		292	63	66.5 KB	29	2	00:30:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	578	62	65.9 KB	28	2	00:00:23
2	*.a2hosting.com	2	1	0.6 KB	1	0	00:00:12
3	*.us-west-1.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
4	unresolved	106	0	0.0 KB	0	0	00:30:14
TOTAL: 4		687	63	66.5 KB	29	2	00:30:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
115.238.34.19	0.000	0.008	00:30:00
web.man.lodz.pl	1.268	3.376	00:00:19
az1-ss13.a2hosting.com	0.020	0.036	00:00:12
192.241.193.219	0.004	0.004	00:00:09
80.111.142.213	0.036	0.112	00:00:03
mon.man.lodz.pl	1.216	3.316	00:00:03
212.51.192.185	0.368	1.044	00:00:01
isvcs01.man.lodz.pl	0.016	0.032	00:00:00
ec2-13-57-19-241.us-west-1.compute.amazonaws.com	0.008	0.012	00:00:00
TOTAL: 9	2.936	7.940	00:30:50

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	8
115.238.34.19	1
ec2-13-57-19-241.us-west-1.compute.amazonaws.com	1
192.241.193.219	1
TOTAL: 6	395

NNRP unrecognized commands (by host):

System	Conn
115.238.34.19	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.0	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
115.238.34.19	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	62	98.4%
2	lodman	1	1.6%
TOTAL: 2		63	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	50
2	pl.comp.os.ms-windows.winnt	12
3	lodman.info	1
TOTAL: 3		63

Daily Usenet report

Apr 4 04:15:02 -- Apr 5 04:15:02