Daily Usenet report: Sep 14 04:15:01

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Sep 14 09:36:49 newsfeed nnrpd[10982]: SERVER perl filter not defined
Sep 14 09:37:32 newsfeed nnrpd[11057]: SERVER perl filter not defined
Sep 14 12:41:45 newsfeed overchan[26784]: input line too long, skipping
Sep 14 19:01:13 newsfeed overchan[26784]: input line too long, skipping
Sep 14 19:38:05 newsfeed overchan[26784]: input line too long, skipping
Sep 14 19:57:23 newsfeed overchan[26784]: input line too long, skipping
Sep 14 20:09:42 newsfeed overchan[26784]: input line too long, skipping
Sep 14 20:13:35 newsfeed nnrpd[9325]: SERVER perl filter not defined
Sep 14 20:14:36 newsfeed nnrpd[9441]: SERVER perl filter not defined
Sep 14 20:27:49 newsfeed overchan[26784]: input line too long, skipping
Sep 14 20:31:09 newsfeed overchan[26784]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	340158	85.6%	37.4 MB	84.2%
innfeed	47121	11.9%	5.6 MB	12.7%
innd	6943	1.7%	1.1 MB	2.4%
nnrpd	3364	0.8%	316.1 KB	0.7%
overchan	8	0.0%	1.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	397595	100.0%	44.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	339322	99.6%
Negative hits	1220	0.4%
Positive hits	278	0.1%
Cache misses	34	0.0%
TOTAL: 4	340854	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.117	0.0%	92	0.000	12.141	244.000
article cleanup	00:00:02.969	0.0%	339677	0.003	0.009	0.016
article logging	00:00:08.582	0.0%	340071	0.015	0.025	0.039
article parse	00:05:26.514	0.4%	46275848	0.005	0.007	0.009
article write	00:02:39.396	0.2%	339412	0.281	0.470	3.244
artlog/artcncl	00:00:00.003	0.0%	90	0.000	0.033	1.000
data move	00:00:20.780	0.0%	48800230	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.285	0.0%	91	0.000	3.132	55.000
hishave/artcncl	00:00:00.001	0.0%	92	0.000	0.011	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:04.173	0.2%	341251	0.175	0.540	8.458
history sync	00:00:00.012	0.0%	291	0.000	0.041	0.500
history write	00:03:33.099	0.2%	339654	0.253	0.627	8.536
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	23:01:43.747	96.0%	46012201	0.870	1.802	3.269
nntp read	00:13:57.269	1.0%	46033189	0.014	0.018	0.021
overview write	00:00:01.077	0.0%	339412	0.001	0.003	0.009
perl filter	00:21:28.803	1.5%	339654	2.876	3.794	4.700
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:09.130	0.0%	690612	0.009	0.013	0.020
TOTAL: 24:00:01.081	23:52:36.957	99.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	51362	0.000	0.001	0.021
article prepare	00:00:00.000	0.0%	10358	0.000	0.000	0.000
article read	00:00:14.480	0.0%	2587	0.000	5.597	300.889
backlog stats	00:00:00.072	0.0%	117677	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:00.497	0.0%	56221	0.000	0.009	0.025
data write	00:00:01.165	0.0%	44599	0.004	0.026	0.058
idle	23:53:07.631	99.9%	117677	100.307	730.709	2311.746
status file	00:01:01.351	0.1%	16228	0.000	3.781	273.289
TOTAL: 23:54:28.418	23:54:25.258	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:15:30.522	99.4%	2787	0.000	333.879	304465.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.072	0.0%	5890	0.000	0.012	0.105
readart	00:00:00.001	0.0%	22	0.000	0.045	0.500
TOTAL: 00:15:36.248	00:15:30.595	99.4%	-	-	-	-

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	30	339419	339177	1	241	99%	51:32:46
2	news.icm.edu.pl	43	287	50	211	26	17%	18:18:58
3	localhost	1445	4	4	0	0	100%	00:00:00
TOTAL: 3		1518	339710	339231	212	267	99%	69:51:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	153.8 GB	1.2 MB	1.9 MB	153.8 GB	99%	475.0 KB
2	news.icm.edu.pl	3.3 MB	0.0 KB	618.8 KB	3.9 MB	84%	52.5 KB
3	localhost	8.5 KB	0.0 KB	0.0 KB	8.5 KB	100%	2.1 KB
TOTAL: 3		153.8 GB	1.2 MB	2.5 MB	153.8 GB	99%	474.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 14 04:15:01 - 04:59:59	10240	3.0%	3.79	4.7 GB	3.0%	1809.12
Sep 14 05:00:00 - 05:59:59	11887	3.5%	3.30	6.3 GB	4.1%	1838.92
Sep 14 06:00:00 - 06:59:59	12998	3.8%	3.61	6.3 GB	4.1%	1847.14
Sep 14 07:00:00 - 07:59:59	12857	3.8%	3.57	6.3 GB	4.1%	1843.59
Sep 14 08:00:00 - 08:59:59	13312	3.9%	3.70	6.3 GB	4.1%	1830.58
Sep 14 09:00:00 - 09:59:59	13142	3.9%	3.65	6.3 GB	4.1%	1820.51
Sep 14 10:00:00 - 10:59:59	13948	4.1%	3.87	6.3 GB	4.1%	1844.49
Sep 14 11:00:00 - 11:59:59	15122	4.5%	4.20	6.3 GB	4.1%	1836.96
Sep 14 12:00:00 - 12:59:59	13560	4.0%	3.77	6.3 GB	4.1%	1836.99
Sep 14 13:00:00 - 13:59:59	13760	4.1%	3.82	6.3 GB	4.1%	1829.89
Sep 14 14:00:00 - 14:59:59	12780	3.8%	3.55	6.1 GB	4.0%	1780.12
Sep 14 15:00:00 - 15:59:59	12902	3.8%	3.58	6.3 GB	4.1%	1847.21
Sep 14 16:00:00 - 16:59:59	14962	4.4%	4.16	6.3 GB	4.1%	1839.58
Sep 14 17:00:00 - 17:59:59	14243	4.2%	3.96	6.4 GB	4.1%	1853.83
Sep 14 18:00:00 - 18:59:59	15210	4.5%	4.22	6.4 GB	4.1%	1858.52
Sep 14 19:00:00 - 19:59:59	13254	3.9%	3.68	6.3 GB	4.1%	1841.79
Sep 14 20:00:00 - 20:59:59	13177	3.9%	3.66	6.4 GB	4.2%	1863.59
Sep 14 21:00:00 - 21:59:59	14243	4.2%	3.96	6.3 GB	4.1%	1844.17
Sep 14 22:00:00 - 22:59:59	15793	4.7%	4.39	6.3 GB	4.1%	1841.51
Sep 14 23:00:00 - 23:59:59	15361	4.5%	4.27	6.4 GB	4.2%	1862.52
Sep 15 00:00:00 - 00:59:59	16475	4.9%	4.58	6.4 GB	4.2%	1867.03
Sep 15 01:00:00 - 01:59:59	14940	4.4%	4.15	6.4 GB	4.1%	1858.61
Sep 15 02:00:00 - 02:59:59	17290	5.1%	4.80	7.1 GB	4.6%	2059.15
Sep 15 03:00:00 - 03:59:59	15021	4.4%	4.17	7.9 GB	5.2%	2313.27
Sep 15 04:00:00 - 04:15:02	2932	0.9%	3.25	1.5 GB	1.0%	1799.02
TOTAL: 24:00:01	339409	100.0%	3.93	154.0 GB	100.0%	1869.44

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	632	211	23	5	393
2	news.icm.edu.pl	27	0	0	0	27
TOTAL: 2		659	211	23	5	420

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	122
it.tlc.cellulari.android	27
it.comp.os.win.windows10	25
comp.mobile.android	10
de.comm.software.mailreader	9
alt.comp.software.seamonkey	6
de.rec.sf.startrek	3
alt.comp.software.firefox	3
it.comp.os.linux.ubuntu	3
comp.mobile.ipad	2
comp.sys.raspberry-pi	1
TOTAL: 11	211

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

Groups	Count
pl.sci.historia	4
pl.praca.oferowana	1
TOTAL: 2	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15
Binary in non-binary group	9
Scoring filter (10)	2
TOTAL: 3	26

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	19350	768	10523	45	0	1	3%	23:57:00
2	panorama	41821	626	13876	601	7	36286	1%	23:58:58
3	okapi	0	0	0	0	0	948	0%	23:57:47
4	poznan	52	0	39	0	0	0	0%	04:19:55
5	nask	0	0	0	0	0	7871	0%	23:57:47
TOTAL: 5		61223	1394	24438	646	7	45106	2%	100:11:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.3 MB	183.4 KB	2.5 MB	0.0 KB/s	3.2 KB	23:57:00
2	panorama	2.0 MB	2.5 MB	4.6 MB	0.1 KB/s	3.8 KB	23:58:58
3	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:47
4	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	04:19:55
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:47
TOTAL: 5		4.4 MB	2.7 MB	7.1 MB	0.0 KB/s	3.6 KB	100:11:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	22	20.6 KB	11	0	00:00:10
2	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 2		292	22	20.6 KB	11	4	00:15:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	292	22	20.6 KB	11	4	00:00:11
2	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:07
3	unresolved	103	0	0.0 KB	0	0	00:15:17
4	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
TOTAL: 4		398	22	20.6 KB	11	4	00:15:36

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.40.113.3	0.006	0.003	00:15:13
web.man.lodz.pl	0.950	1.418	00:00:10
acas138.neoplus.adsl.tpnet.pl	0.008	0.008	00:00:07
80.111.142.213	0.015	0.018	00:00:02
212.51.192.185	0.297	0.418	00:00:00
scan-141.security.ipip.net	0.004	0.004	00:00:00
isvcs01.man.lodz.pl	0.028	0.013	00:00:00
45.227.254.174	0.003	0.006	00:00:00
185.217.1.116	0.002	0.005	00:00:00
TOTAL: 9	1.313	1.893	00:15:36

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
acas138.neoplus.adsl.tpnet.pl	2
45.227.254.174	1
scan-141.security.ipip.net	1
185.40.113.3	1
185.217.1.116	1
TOTAL: 7	106

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
185.217.1.116	1
45.227.254.174	1
TOTAL: 3	6

NNRP unrecognized commands (by command):

Command	Count
#003	2
GET / HTTP/1.1	1
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
Host: 212.191.2.21:119	1
TOTAL: 5	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.217.1.116	1	1
45.227.254.174	1	1
TOTAL: 2	2	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	14
2	pl.comp.os.ms-windows.winnt	8
TOTAL: 2		22

Daily Usenet report

Sep 14 04:15:01 -- Sep 15 04:15:02