Daily Usenet report: Nov 1 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Nov 1 09:50:01 newsfeed controlchan[7935]: Multiple control headers in article <yXAN3-6XZ-1@gated-at.bofh.it>
Nov 1 12:45:02 newsfeed pgpverify[25879]: pgpverify: verification failed
Nov 1 14:21:32 newsfeed overchan[7937]: input line too long, skipping
Nov 1 15:31:22 newsfeed nnrpd[31540]: SERVER perl filter not defined
Nov 1 23:30:29 newsfeed nnrpd[15276]: SERVER perl filter not defined
Nov 2 03:44:27 newsfeed overchan[7937]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1470240	96.2%	162.6 MB	95.7%
innfeed	44172	2.9%	5.1 MB	3.0%
innd	9311	0.6%	1.6 MB	0.9%
nnrpd	4906	0.3%	532.1 KB	0.3%
controlchan	8	0.0%	1.1 KB	0.0%
overchan	3	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	1528641	100.0%	169.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1459502	99.3%
Negative hits	10682	0.7%
Positive hits	209	0.0%
Cache misses	34	0.0%
TOTAL: 4	1470427	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.768	0.0%	86	0.000	8.930	63.000
article cleanup	00:00:18.496	0.0%	1463915	0.006	0.013	0.016
article logging	00:01:02.531	0.1%	1464390	0.034	0.043	0.062
article parse	00:14:13.476	1.0%	28923441	0.016	0.030	0.040
article write	00:06:52.919	0.5%	1463745	0.154	0.282	0.606
artlog/artcncl	00:00:00.002	0.0%	86	0.000	0.023	1.000
data move	00:00:38.427	0.0%	35682874	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.172	0.0%	82	0.000	2.098	28.000
hishave/artcncl	00:00:00.235	0.0%	86	0.000	2.733	27.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:36:33.598	2.5%	1474934	0.039	1.487	11.970
history sync	00:00:00.024	0.0%	318	0.000	0.075	0.667
history write	00:09:30.349	0.7%	1463896	0.148	0.390	1.041
hiswrite/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
idle	20:19:18.478	84.6%	26130723	0.861	2.800	66.275
nntp read	00:22:46.674	1.6%	28015868	0.035	0.049	0.058
overview write	00:00:07.486	0.0%	1463745	0.002	0.005	0.010
perl filter	01:54:53.603	8.0%	1463896	2.836	4.709	7.585
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:18.896	0.1%	2941708	0.020	0.027	0.037
TOTAL: 24:00:25.327	23:47:36.134	99.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.123	0.0%	53255	0.000	0.002	0.012
article prepare	00:00:00.001	0.0%	6274	0.000	0.000	0.008
article read	00:00:08.680	0.0%	1568	0.000	5.536	141.000
backlog stats	00:00:00.105	0.0%	127472	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.286	0.0%	62219	0.005	0.021	0.078
data write	00:00:03.006	0.0%	48086	0.027	0.063	0.107
idle	23:53:27.998	100.0%	127472	176.704	674.721	1330.350
status file	00:00:14.518	0.0%	16531	0.000	0.878	1.163
TOTAL: 23:54:01.704	23:53:55.717	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:31:47.048	99.5%	3058	0.000	623.626	900006.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.224	0.0%	6777	0.000	0.033	0.667
readart	00:00:00.001	0.0%	24	0.000	0.042	0.500
TOTAL: 00:31:55.901	00:31:47.273	99.5%	-	-	-	-

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	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	1	0	0
usenet@zybrkat.org	1	0	1	1
TOTAL	1	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	237	1469443	1469339	0	104	99%	101:22:58
2	news.icm.edu.pl	44	227	19	143	65	8%	15:48:05
3	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 3		284	1469672	1469360	143	169	99%	117:11:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	219.9 GB	296.2 KB	481.9 KB	219.9 GB	99%	156.9 KB
2	news.icm.edu.pl	307.1 KB	16.6 KB	1.2 MB	1.5 MB	20%	18.2 KB
3	localhost	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	1.4 KB
TOTAL: 3		219.9 GB	312.9 KB	1.6 MB	219.9 GB	99%	156.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 1 04:15:01 - 04:59:59	13118	0.9%	4.86	1.7 GB	0.8%	678.00
Nov 1 05:00:00 - 05:59:59	28217	1.9%	7.84	2.8 GB	1.3%	828.25
Nov 1 06:00:00 - 06:59:59	27043	1.8%	7.51	2.3 GB	1.0%	667.37
Nov 1 07:00:00 - 07:59:59	46884	3.2%	13.02	3.6 GB	1.6%	1040.86
Nov 1 08:00:00 - 08:59:59	44556	3.0%	12.38	3.6 GB	1.6%	1043.70
Nov 1 09:00:00 - 09:59:59	107853	7.3%	29.96	15.3 GB	7.0%	4469.96
Nov 1 10:00:00 - 10:59:59	58843	4.0%	16.35	13.5 GB	6.1%	3930.36
Nov 1 11:00:00 - 11:59:59	65747	4.5%	18.26	14.0 GB	6.3%	4071.25
Nov 1 12:00:00 - 12:59:59	79616	5.4%	22.12	15.9 GB	7.2%	4629.68
Nov 1 13:00:00 - 13:59:59	59650	4.1%	16.57	6.6 GB	3.0%	1924.91
Nov 1 14:00:00 - 14:59:59	120498	8.2%	33.47	10.9 GB	4.9%	3171.74
Nov 1 15:00:00 - 15:59:59	108080	7.4%	30.02	16.8 GB	7.6%	4901.76
Nov 1 16:00:00 - 16:59:59	94310	6.4%	26.20	21.7 GB	9.8%	6307.96
Nov 1 17:00:00 - 17:59:59	99498	6.8%	27.64	24.3 GB	11.0%	7087.15
Nov 1 18:00:00 - 18:59:59	51299	3.5%	14.25	7.6 GB	3.5%	2227.67
Nov 1 19:00:00 - 19:59:59	109222	7.4%	30.34	12.4 GB	5.6%	3624.46
Nov 1 20:00:00 - 20:59:59	33165	2.3%	9.21	3.4 GB	1.5%	995.11
Nov 1 21:00:00 - 21:59:59	66628	4.5%	18.51	6.2 GB	2.8%	1809.28
Nov 1 22:00:00 - 22:59:59	39857	2.7%	11.07	6.3 GB	2.9%	1832.28
Nov 1 23:00:00 - 23:59:59	56129	3.8%	15.59	15.6 GB	7.1%	4553.74
Nov 2 00:00:00 - 00:59:59	53538	3.6%	14.87	7.9 GB	3.6%	2303.03
Nov 2 01:00:00 - 01:59:59	65748	4.5%	18.26	4.6 GB	2.1%	1336.71
Nov 2 02:00:00 - 02:59:59	21371	1.5%	5.94	1.8 GB	0.8%	537.32
Nov 2 03:00:00 - 03:59:59	6806	0.5%	1.89	589.4 MB	0.3%	167.66
Nov 2 04:00:00 - 04:15:02	11834	0.8%	13.12	828.3 MB	0.4%	940.37
TOTAL: 24:00:01	1469510	100.0%	17.01	220.4 GB	100.0%	2675.23

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	579	84	18	2	475
2	news.icm.edu.pl	65	0	1	0	64
TOTAL: 2		644	84	19	2	539

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	32
it.tlc.cellulari.android	17
it.comp.os.win.windows10	15
nl.comp.os.linux.techniek	13
it.comp.os.win.windows7	2
comp.sys.raspberry-pi	2
comp.mobile.android	1
de.comp.office-pakete.ms-office.misc	1
it.comp.os.linux.ubuntu	1
TOTAL: 9	84

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

Groups	Count
pl.praca.oferowana	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	61
Binary in non-binary group	2
TOTAL: 2	63

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	23416	472	13326	262	0	1	2%	23:59:50
2	panorama	43053	322	15093	251	1	37626	0%	23:59:58
3	okapi	0	0	0	0	0	802	0%	23:57:51
4	nask	0	0	0	0	0	9815	0%	23:57:51
5	poznan	26	0	19	0	0	0	0%	03:03:52
TOTAL: 5		66495	794	28438	513	1	48244	1%	98:59:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.3 MB	14.0 MB	15.3 MB	0.2 KB/s	21.3 KB	23:59:50
2	panorama	981.0 KB	1.0 MB	2.0 MB	0.0 KB/s	3.6 KB	23:59:58
3	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
5	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	03:03:52
TOTAL: 5		2.3 MB	15.0 MB	17.3 MB	0.0 KB/s	13.5 KB	98:59:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	291	24	24.8 KB	11	0	00:00:14
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		293	24	24.8 KB	11	2	00:31:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	581	24	24.8 KB	11	2	00:00:17
2	*.hawaii.res.rr.com	1	0	0.0 KB	0	0	00:00:31
3	*.115.240.202.ip.netsystem.net.pl	2	0	0.0 KB	0	0	00:01:03
4	unresolved	101	0	0.0 KB	0	0	00:30:03
TOTAL: 4		685	24	24.8 KB	11	2	00:31:55

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
183.129.154.155	0.008	0.004	00:30:00
176.115.240.202.ip.netsystem.net.pl	0.052	0.024	00:01:03
cpe-98-151-112-44.hawaii.res.rr.com	0.008	0.008	00:00:31
web.man.lodz.pl	1.440	3.348	00:00:14
mon.man.lodz.pl	1.300	2.876	00:00:02
80.111.142.213	0.016	0.056	00:00:02
212.51.192.185	0.416	0.932	00:00:00
isvcs01.man.lodz.pl	0.020	0.036	00:00:00
TOTAL: 8	3.260	7.284	00:31:55

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
176.115.240.202.ip.netsystem.net.pl	2
183.129.154.155	1
cpe-98-151-112-44.hawaii.res.rr.com	1
TOTAL: 6	392

NNRP unrecognized commands (by host):

System	Conn
cpe-98-151-112-44.hawaii.res.rr.com	10
183.129.154.155	1
TOTAL: 2	11

NNRP unrecognized commands (by command):

Command	Count
Accept: /	1
Authorization: Basic YWRtaW46ezEyMjEzQkQ...	1
Content-Type: text/xml	1
Host: 101.118.255.61	1
Accept-Encoding: identity	1
Cache-Control: max-age=0	1
Accept-Language: en-us	1
User-Agent: ApiTool	1
Content-Length: 586	1
GET / HTTP/1.0	1
Connection: close	1
TOTAL: 11	11

NNRP client timeouts [Top 20]:

System	Conn	Peer
183.129.154.155	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Nov 1 04:15:01 -- Nov 2 04:15:02