Daily Usenet report: Jan 16 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jan 16 09:42:56 newsfeed overchan[17085]: input line too long, skipping
Jan 16 10:14:48 newsfeed nnrpd[29622]: SERVER perl filter not defined
Jan 16 10:15:16 newsfeed nnrpd[29670]: SERVER perl filter not defined
Jan 16 12:12:17 newsfeed pgpverify[1155]: pgpverify: verification failed
Jan 16 20:04:25 newsfeed overchan[17085]: input line too long, skipping
Jan 16 20:11:02 newsfeed overchan[17085]: input line too long, skipping
Jan 16 22:48:12 newsfeed pgpverify[22495]: pgpverify: verification failed
Jan 16 22:48:12 newsfeed pgpverify[22507]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2000491	96.9%	229.7 MB	96.5%
innfeed	43223	2.1%	5.1 MB	2.2%
innd	16297	0.8%	2.6 MB	1.1%
nnrpd	4875	0.2%	521.9 KB	0.2%
controlchan	8	0.0%	1.0 KB	0.0%
overchan	4	0.0%	0.8 KB	0.0%
pgpverify	3	0.0%	0.6 KB	0.0%
TOTAL: 7	2064901	100.0%	238.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1996243	98.5%
Negative hits	19780	1.0%
Positive hits	10358	0.5%
Cache misses	448	0.0%
TOTAL: 4	2026829	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.575	0.0%	80	0.000	44.688	220.250
article cleanup	00:00:25.095	0.0%	1999870	0.000	0.013	0.033
article logging	00:01:24.810	0.1%	2000403	0.010	0.042	0.107
article parse	00:29:28.298	2.0%	68249220	0.000	0.026	0.052
article write	00:13:14.490	0.9%	1994443	0.155	0.398	1.099
artlog/artcncl	00:00:00.004	0.0%	80	0.000	0.050	1.000
data move	00:01:12.274	0.1%	78661178	0.000	0.001	0.500
hisgrep/artcncl	00:00:00.673	0.0%	80	0.000	8.412	30.000
hishave/artcncl	00:00:00.021	0.0%	80	0.000	0.263	2.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:09:07.758	0.6%	2026783	0.069	0.270	0.865
history sync	00:00:00.024	0.0%	308	0.000	0.078	1.000
history write	00:30:53.070	2.1%	1996247	0.118	0.928	6.288
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	18:22:54.365	76.6%	53837955	0.632	1.229	5356.125
nntp read	00:47:21.658	3.3%	66571348	0.024	0.043	0.095
overview write	00:00:10.139	0.0%	1994443	0.000	0.005	0.023
perl filter	03:00:05.136	12.5%	1996247	1.265	5.413	25.509
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:06.699	0.3%	4008446	0.015	0.062	0.079
TOTAL: 24:00:11.375	23:40:28.089	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.250	0.0%	137905	0.000	0.002	0.034
article prepare	00:00:00.013	0.0%	38452	0.000	0.000	0.012
article read	00:01:09.607	0.1%	9588	0.015	7.260	136.750
backlog stats	00:00:00.359	0.0%	691723	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	8	0.000	0.000	0.000
data read	00:00:05.032	0.0%	348281	0.006	0.014	0.036
data write	00:00:11.929	0.0%	331154	0.009	0.036	0.271
idle	23:51:57.586	99.9%	691723	16.141	124.208	890.023
status file	00:00:15.747	0.0%	15173	0.000	1.038	3.186
TOTAL: 23:53:58.338	23:53:40.523	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	00:00:31.506	68.7%	3042	0.000	10.357	2364.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.310	0.7%	6743	0.000	0.046	1.667
readart	00:00:00.021	0.0%	37	0.000	0.568	17.000
TOTAL: 00:00:45.855	00:00:31.837	69.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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
control@usenet.org.uk	0	0	0	1	0	0
gcn@news.nic.it	0	0	0	1	0	0
linux-admin@bofh.it	0	0	0	1	0	0
TOTAL	0	0	0	3	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	965	1994222	1991966	919	1337	99%	237:45:16
2	news.nask.pl	2	12607	2537	5991	4079	20%	25:11:17
3	news.icm.edu.pl	46	247	39	195	13	15%	17:41:50
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1016	2007078	1994544	7105	5429	99%	280:38:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	472.8 GB	9.2 MB	9.8 MB	472.8 GB	99%	248.7 KB
2	news.nask.pl	14.7 MB	10.7 MB	20.9 MB	46.3 MB	31%	7.2 KB
3	news.icm.edu.pl	764.4 KB	42.1 KB	206.7 KB	1013.2 KB	75%	19.5 KB
4	localhost	3.1 KB	0.0 KB	0.0 KB	3.1 KB	100%	1.6 KB
TOTAL: 4		472.8 GB	20.0 MB	30.9 MB	472.9 GB	99%	247.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 16 04:15:02 - 04:59:59	312	0.0%	0.12	1.8 MB	0.0%	0.67
Jan 16 05:00:00 - 05:59:59	319	0.0%	0.09	1.3 MB	0.0%	0.37
Jan 16 06:00:00 - 06:59:59	25405	1.3%	7.06	8.1 GB	1.7%	2359.11
Jan 16 07:00:00 - 07:59:59	68122	3.4%	18.92	21.9 GB	4.6%	6367.89
Jan 16 08:00:00 - 08:59:59	74339	3.7%	20.65	23.1 GB	4.9%	6740.33
Jan 16 09:00:00 - 09:59:59	68824	3.5%	19.12	24.1 GB	5.1%	7018.50
Jan 16 10:00:00 - 10:59:59	148445	7.4%	41.23	17.4 GB	3.7%	5063.00
Jan 16 11:00:00 - 11:59:59	87197	4.4%	24.22	15.2 GB	3.2%	4423.02
Jan 16 12:00:00 - 12:59:59	83948	4.2%	23.32	24.2 GB	5.1%	7049.04
Jan 16 13:00:00 - 13:59:59	94871	4.8%	26.35	23.7 GB	5.0%	6891.29
Jan 16 14:00:00 - 14:59:59	97307	4.9%	27.03	25.2 GB	5.3%	7340.55
Jan 16 15:00:00 - 15:59:59	169944	8.5%	47.21	26.8 GB	5.7%	7798.03
Jan 16 16:00:00 - 16:59:59	111724	5.6%	31.03	28.8 GB	6.1%	8391.58
Jan 16 17:00:00 - 17:59:59	91051	4.6%	25.29	26.9 GB	5.7%	7829.03
Jan 16 18:00:00 - 18:59:59	83099	4.2%	23.08	26.6 GB	5.6%	7755.12
Jan 16 19:00:00 - 19:59:59	80078	4.0%	22.24	26.2 GB	5.5%	7633.95
Jan 16 20:00:00 - 20:59:59	113205	5.7%	31.45	27.2 GB	5.8%	7931.53
Jan 16 21:00:00 - 21:59:59	123245	6.2%	34.23	27.8 GB	5.9%	8102.81
Jan 16 22:00:00 - 22:59:59	94322	4.7%	26.20	26.7 GB	5.6%	7770.62
Jan 16 23:00:00 - 23:59:59	74091	3.7%	20.58	23.5 GB	5.0%	6832.15
Jan 17 00:00:00 - 00:59:59	84472	4.2%	23.46	13.5 GB	2.8%	3925.91
Jan 17 01:00:00 - 01:59:59	125748	6.3%	34.93	13.9 GB	2.9%	4047.94
Jan 17 02:00:00 - 02:59:59	76879	3.9%	21.36	19.7 GB	4.2%	5733.03
Jan 17 03:00:00 - 03:59:59	17410	0.9%	4.84	2.8 GB	0.6%	821.91
Jan 17 04:00:00 - 04:15:02	92	0.0%	0.10	264.5 KB	0.0%	0.29
TOTAL: 24:00:00	1994449	100.0%	23.08	473.2 GB	100.0%	5742.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4122	11	2473	1	1637
2	newsfeed.pionier.net.pl	1828	181	1148	0	499
3	news.icm.edu.pl	12	0	2	0	10
TOTAL: 3		5962	192	3623	1	2146

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	97
comp.mobile.android	37
it.tlc.cellulari.android	16
it.comp.os.win.windows10	13
it.comp.os.win.windows7	11
nl.comp.os.linux.techniek	8
comp.sys.raspberry-pi	6
it.discussioni.energia	2
de.rec.sf.startrek	1
uk.rec.cycling.moderated	1
TOTAL: 10	192

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1493
Binary in non-binary group	61
Scoring filter (8)	32
Scoring filter (10)	10
Scoring filter (9)	8
Scoring filter (18)	3
Too many newsgroups	2
Scoring filter (13)	1
Scoring filter (11)	1
TOTAL: 9	1611

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	491
	news.nask.pl	43
	news.icm.edu.pl	1
	TOTAL: 3	535
TOTAL: 1		535

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	58764	2789	28146	2687	36	38634	4%	23:59:57
2	poznan	426386	152	2296	10	0	66525	0%	23:54:58
3	icm	27108	86	16516	2801	0	0	0%	23:59:53
4	nask	9920	4	8473	3	0	72	0%	21:40:08
5	okapi	0	0	0	0	0	1128	0%	23:57:50
TOTAL: 5		522178	3031	55431	5501	36	106359	0%	117:32:46

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	9.7 MB	10.6 MB	20.3 MB	0.2 KB/s	3.8 KB	23:59:57
2	icm	888.5 KB	1.0 GB	1.0 GB	12.5 KB/s	375.4 KB	23:59:53
3	poznan	849.0 KB	28.5 KB	877.5 KB	0.0 KB/s	5.4 KB	23:54:58
4	nask	9.5 KB	5.2 KB	14.7 KB	0.0 KB/s	2.1 KB	21:40:08
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		11.4 MB	1.0 GB	1.1 GB	2.6 KB/s	129.6 KB	117:32:46

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	36	36.4 KB	16	0	00:00:20
2	az1-ss13.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
3	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 3		292	37	37.0 KB	17	2	00:00:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	578	36	36.4 KB	16	2	00:00:25
2	*.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
3	*.consolidated.net	1	0	0.0 KB	0	0	00:00:05
4	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
5	unresolved	96	0	0.0 KB	0	0	00:00:01
TOTAL: 5		678	37	37.0 KB	17	2	00:00:45

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.368	3.524	00:00:20
az1-ss13.a2hosting.com	0.032	0.044	00:00:12
client-66-116-14-174.consolidated.net	0.004	0.004	00:00:05
mon.man.lodz.pl	1.244	3.428	00:00:05
212.51.192.185	0.376	1.020	00:00:01
scan-141.security.ipip.net	0.016	0.012	00:00:00
isvcs01.man.lodz.pl	0.016	0.012	00:00:00
TOTAL: 7	3.056	8.044	00:00:45

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
scan-141.security.ipip.net	1
client-66-116-14-174.consolidated.net	1
TOTAL: 4	386

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	36	97.3%
2	lodz	1	2.7%
TOTAL: 2		37	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	24
2	pl.comp.os.ms-windows.winnt	12
3	lodz.test	1
TOTAL: 3		37

Daily Usenet report

Jan 16 04:15:02 -- Jan 17 04:15:02