Daily Usenet report: Jan 15 04:15:01

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jan 15 08:55:46 newsfeed nnrpd[29215]: SERVER perl filter not defined
Jan 15 08:59:13 newsfeed nnrpd[29397]: SERVER perl filter not defined
Jan 15 09:00:05 newsfeed nnrpd[29483]: SERVER perl filter not defined
Jan 15 09:04:22 newsfeed nnrpd[29524]: SERVER perl filter not defined
Jan 15 11:09:36 newsfeed nnrpd[1609]: SERVER perl filter not defined
Jan 15 17:00:05 newsfeed pgpverify[13899]: pgpverify: verification failed
Jan 16 01:05:19 newsfeed nnrpd[30876]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	772980	93.1%	99.5 MB	93.4%
innfeed	45622	5.5%	5.4 MB	5.1%
innd	6327	0.8%	1.1 MB	1.0%
nnrpd	5003	0.6%	541.0 KB	0.5%
controlchan	4	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	829937	100.0%	106.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	766822	97.6%
Negative hits	18819	2.4%
Positive hits	293	0.0%
Cache misses	83	0.0%
TOTAL: 4	786017	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.738	0.0%	172	0.000	10.105	52.900
article cleanup	00:00:08.975	0.0%	771362	0.006	0.012	0.018
article logging	00:00:33.836	0.0%	771830	0.032	0.044	0.059
article parse	00:11:47.404	0.8%	25740999	0.016	0.027	0.037
article write	00:04:50.183	0.3%	771156	0.133	0.376	0.898
artlog/artcncl	00:00:00.005	0.0%	167	0.000	0.030	0.250
data move	00:00:30.256	0.0%	29983463	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.620	0.0%	169	0.000	3.669	18.000
hishave/artcncl	00:00:00.435	0.0%	172	0.000	2.529	24.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:58:19.354	4.0%	790551	0.592	4.426	15.487
history sync	00:00:00.032	0.0%	315	0.000	0.102	1.000
history write	01:15:04.951	5.2%	771337	1.859	5.840	14.355
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
idle	19:42:11.116	82.1%	24100792	1.213	2.943	96.620
nntp read	00:19:28.488	1.4%	25334838	0.031	0.046	0.058
overview write	00:00:03.762	0.0%	771156	0.002	0.005	0.008
perl filter	01:17:19.246	5.4%	771337	2.564	6.015	9.011
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:39.948	0.0%	1557003	0.018	0.026	0.033
TOTAL: 24:00:20.942	23:51:00.349	99.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.228	0.0%	120324	0.000	0.002	0.013
article prepare	00:00:00.007	0.0%	62784	0.000	0.000	0.031
article read	00:01:12.619	0.1%	15698	0.000	4.626	415.400
backlog stats	00:00:00.128	0.0%	212048	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	12	0.000	0.000	0.000
data read	00:00:01.652	0.0%	106121	0.000	0.016	0.057
data write	00:00:04.141	0.0%	91533	0.017	0.045	0.127
idle	23:52:35.824	99.9%	212048	6.686	405.360	1545.809
status file	00:00:13.325	0.0%	16153	0.613	0.825	2.789
TOTAL: 23:54:15.421	23:54:07.924	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:28:08.516	99.8%	3155	0.000	1676.233	300102.833
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.257	0.0%	6979	0.000	0.037	0.667
readart	00:00:00.010	0.0%	51	0.000	0.196	1.125
TOTAL: 01:28:20.193	01:28:08.783	99.8%	-	-	-	-

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
group-admin@isc.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	384	772454	772259	0	195	99%	144:31:15
2	news.icm.edu.pl	48	206	19	176	11	9%	16:07:06
3	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 3		439	772666	772284	176	206	99%	160:38:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	196.8 GB	435.8 KB	780.5 KB	196.8 GB	99%	267.2 KB
2	news.icm.edu.pl	421.0 KB	25.5 KB	170.7 KB	617.1 KB	68%	20.6 KB
3	localhost	9.9 KB	0.0 KB	0.0 KB	9.9 KB	100%	1.6 KB
TOTAL: 3		196.8 GB	461.3 KB	951.1 KB	196.8 GB	99%	267.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:01 - 04:59:59	21113	2.7%	7.82	7.8 GB	3.9%	3011.18
Jan 15 05:00:00 - 05:59:59	17511	2.3%	4.86	7.4 GB	3.8%	2169.25
Jan 15 06:00:00 - 06:59:59	37375	4.8%	10.38	10.4 GB	5.3%	3025.67
Jan 15 07:00:00 - 07:59:59	37174	4.8%	10.33	7.9 GB	4.0%	2291.46
Jan 15 08:00:00 - 08:59:59	54896	7.1%	15.25	12.5 GB	6.3%	3650.00
Jan 15 09:00:00 - 09:59:59	55846	7.2%	15.51	10.4 GB	5.2%	3017.05
Jan 15 10:00:00 - 10:59:59	45969	6.0%	12.77	11.2 GB	5.7%	3253.61
Jan 15 11:00:00 - 11:59:59	41744	5.4%	11.60	8.7 GB	4.4%	2532.55
Jan 15 12:00:00 - 12:59:59	30744	4.0%	8.54	8.5 GB	4.3%	2470.71
Jan 15 13:00:00 - 13:59:59	34396	4.5%	9.55	7.0 GB	3.5%	2035.34
Jan 15 14:00:00 - 14:59:59	32751	4.2%	9.10	8.5 GB	4.3%	2474.20
Jan 15 15:00:00 - 15:59:59	30593	4.0%	8.50	10.3 GB	5.2%	3007.52
Jan 15 16:00:00 - 16:59:59	20422	2.6%	5.67	6.3 GB	3.2%	1838.50
Jan 15 17:00:00 - 17:59:59	18277	2.4%	5.08	7.4 GB	3.7%	2143.22
Jan 15 18:00:00 - 18:59:59	19467	2.5%	5.41	7.5 GB	3.8%	2186.88
Jan 15 19:00:00 - 19:59:59	23628	3.1%	6.56	7.3 GB	3.7%	2118.16
Jan 15 20:00:00 - 20:59:59	28360	3.7%	7.88	13.7 GB	6.9%	3976.70
Jan 15 21:00:00 - 21:59:59	19061	2.5%	5.29	3.2 GB	1.6%	928.97
Jan 15 22:00:00 - 22:59:59	19326	2.5%	5.37	992.7 MB	0.5%	282.36
Jan 15 23:00:00 - 23:59:59	34307	4.4%	9.53	10.7 GB	5.4%	3104.87
Jan 16 00:00:00 - 00:59:59	35227	4.6%	9.79	9.3 GB	4.7%	2720.24
Jan 16 01:00:00 - 01:59:59	38024	4.9%	10.56	8.0 GB	4.0%	2327.74
Jan 16 02:00:00 - 02:59:59	37576	4.9%	10.44	5.3 GB	2.7%	1529.34
Jan 16 03:00:00 - 03:59:59	33715	4.4%	9.37	5.4 GB	2.8%	1583.62
Jan 16 04:00:00 - 04:15:02	4637	0.6%	5.14	1.9 GB	1.0%	2224.13
TOTAL: 24:00:01	772139	100.0%	8.94	197.4 GB	100.0%	2395.09

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	662	171	23	1	467
2	news.icm.edu.pl	12	0	2	0	10
TOTAL: 2		674	171	25	1	477

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	73
it.tlc.cellulari.android	42
it.comp.os.win.windows7	16
comp.mobile.android	14
nl.comp.os.linux.techniek	8
it.comp.os.win.windows10	7
uk.rec.cycling.moderated	6
de.rec.sf.startrek	4
comp.sys.raspberry-pi	1
TOTAL: 9	171

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

Groups	Count
pl.sci.matematyka	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	6
Too many newsgroups	2
EMP (md5)	1
Scoring filter (8)	1
TOTAL: 4	10

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	467
	TOTAL: 1	467
TOTAL: 1		467

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	109850	2872	68390	11845	29	37216	2%	23:59:58
2	icm	21434	405	14170	16	1	0	1%	23:59:50
3	poznan	25	0	18	0	0	0	0%	02:55:21
4	nask	0	0	0	0	0	9278	0%	23:57:51
5	okapi	0	0	0	0	0	993	0%	23:57:51
TOTAL: 5		131309	3277	82578	11861	30	47487	2%	98:50:51

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	7.4 MB	60.4 MB	67.7 MB	0.8 KB/s	4.7 KB	23:59:58
2	icm	1.1 MB	33.7 KB	1.2 MB	0.0 KB/s	2.8 KB	23:59:50
3	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	02:55:21
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		8.5 MB	60.4 MB	68.9 MB	0.2 KB/s	4.7 KB	98:50:51

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	289	38	41.0 KB	19	0	00:00:16
2	user-5-173-178-253.play-internet.pl	6	13	19.6 KB	3	0	01:27:54
3	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 3		301	51	60.6 KB	22	6	01:28:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	583	38	41.0 KB	19	6	00:00:20
2	*.play-internet.pl	6	13	19.6 KB	3	0	01:27:54
3	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:01
4	unresolved	102	0	0.0 KB	0	0	00:00:03
TOTAL: 4		692	51	60.6 KB	22	6	01:28:19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user-5-173-178-253.play-internet.pl	0.032	0.048	01:27:54
web.man.lodz.pl	1.532	3.764	00:00:16
mon.man.lodz.pl	1.196	3.096	00:00:03
80.111.142.213	0.020	0.040	00:00:02
scan-141.security.ipip.net	0.004	0.016	00:00:01
212.51.192.185	0.452	1.024	00:00:00
isvcs01.man.lodz.pl	0.044	0.060	00:00:00
185.175.93.106	0.004	0.028	00:00:00
?	0.000	0.012	00:00:00
TOTAL: 9	3.284	8.088	01:28:19

Curious NNRP server explorers [Top 20]:

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

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
185.175.93.106	2
TOTAL: 2	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
user-5-173-178-253.play-internet.pl	2	0
185.175.93.106	2	2
TOTAL: 2	4	2

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	38	74.5%
2	lodman	13	25.5%
TOTAL: 2		51	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	24
2	pl.comp.os.ms-windows.winnt	14
3	lodman.info	13
TOTAL: 3		51

Daily Usenet report

Jan 15 04:15:01 -- Jan 16 04:15:02