Daily Usenet report: Oct 11 15:00:01

Unknown entries from news log file:

First 50 / 193 lines (25.9%)

Oct 11 15:01:09 colo-sc-1 innd: message repeated 2 times: [ ctlinnd command s]
Oct 11 16:00:10 colo-sc-1 innd: Perl function filter_art died on article <1bce178d-91ff-4310-8e57-faee38324f04n@googlegroups.com>: Can't call method "delete_header" on unblessed reference at /usr/share/perl5/Mail/SpamAssassin/PerMsgStatus.pm line 362.
Oct 11 16:19:21 colo-sc-1 innd: Perl function filter_art died on article <926026ae-3a8a-47cc-8fb9-45e51a463763n@googlegroups.com>: Can't use string ("4Lij4LmI4Lin4Lih4LmA4Lib4LmH4LiZ"...) as a HASH ref while "strict refs" in use at /usr/share/perl5/Mail/SpamAssassin/PerMsgStatus.pm line 270.
Oct 11 16:22:52 colo-sc-1 innd: Perl function filter_art died on article <2e6f9a5d-0a2c-4798-a434-23b766ff25b7n@googlegroups.com>: Can't call method "finish" on an undefined value at (eval 1220) line 28.
Oct 12 04:00:43 colo-sc-1 innd: Perl function filter_art died on article <204f3ea5-8d64-4b4f-b50c-d15276c2343cn@googlegroups.com>: Can't use string ("Subject: =?UTF-8?B?4pq9ICDguYHgu"...) as a HASH ref while "strict refs" in use at /usr/share/perl5/Mail/SpamAssassin/PerMsgStatus.pm line 270.
Content-Type: text/plain; charset="UTF-8"
Date: Wed, 11 Oct 2023 09:25:10 -0700 (PDT)
Injection-Info: google-groups.googlegroups.co
Content-Type: text/plain; charset="UTF-8"
Date: Wed, 11 Oct 2023 09:25:10 -0700 (PDT)
Xref: feeder8.news.weretis.net bit.listserv.i
Content-Transfer-Encoding: base64
NNTP-Posting-Host: 2a0e:a942:8c01:c6c8:4561:aa02:92f5:a8ff
Injection-Date: Wed, 11 Oct 2023 16:25:15 +0000
Lines: 114
Subject: =?UTF-8?B?8J+SmiDguKL
Message-ID: <cdef9663-13bd-4878-8ed4-7d465cf8f854n@googlegroups.com>
Content-Type: text/plain; charset="UTF-8"
Date: Wed, 11 Oct 2023 09:25:23 -0700 (PDT)
User-Agent: G2/1.0
Path: usenet.
Message-ID: <4c644c30-33d3-46d5-8ab1-f1813abd7e09n@googlegroups.com>
Content-Type: text/plain; charset="UTF-8"
Newsgroups: uk.transport.buses
From: =?UTF-8?
Lines: 91
Content-Transfer-Encoding: quoted-printable
MIME-Version: 1.0
Injection-Date: Wed, 11 Oct 2023 16:25:27 +0000
NNTP
Message-ID: <1736f4f0-6d58-493a-83aa-b7e8e05853ecn@googlegroups.com>
Content-Type: text/plain; charset="UTF-8"
Newsgroups: comp.protocols.dicom
From: =?UTF-
Lines: 108
Injection-Date: Wed, 11 Oct 2023 16:25:35 +0000
NNTP-Posting-Host: 5.28.
Content-Type: text/plain; charset="UTF-8"
Date: Wed, 11 Oct 2023 09:25:38 -0700 (PDT)
User-Agent: G2/1.0
Injection-Info: google-gro
From: =?UTF-8?B?4LmA4LiC4LmJ4Liy4Liq4Li54LmI4Lij4Liw4Lia4Lia4LmA4Lin4LmH4Lia4LiV4Lij4LiHIA==?=\x0d
\x09=?UTF-8?B?4Lih4Liy4LmB4Lij4LiH?= <nishaburihorsuwanlr@gmail.com>
User
Subject: =?UTF-8?B?4pyFIOC4o+C4suC4hOC4suC4muC4reC4peC4peC4ueC4geC4hOC4o+C4tuC5iOC4h+C4hA==?=\x0d
\x09=?UTF-8?B?4Lin4Lia4Liq4Lit4LiHIOC5gOC4geC4oeC4hOC4suC4quC4tOC5guC4meC4quC4lOC4ouC4reC4lA==?=\x0d
Content-Type: text/plain; charset="UTF-8"
Date: Wed, 11 Oct 2023 09:25:51 -0700 (PDT)
Path: weretis.net!feeder8.news.weretis.net!3.
MIME-Version: 1.0

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53413	58.3%	7.8 MB	58.0%
innd	33804	36.9%	5.0 MB	37.4%
innfeed	2083	2.3%	303.9 KB	2.2%
cnfsstat	1848	2.0%	287.5 KB	2.1%
nnrpd	393	0.4%	47.5 KB	0.3%
controlchan	23	0.0%	1.6 KB	0.0%
overchan	21	0.0%	3.5 KB	0.0%
TOTAL: 7	91585	100.0%	13.5 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	97557	42.3%
Positive hits	81969	35.6%
Do not exist	50707	22.0%
Cache misses	189	0.1%
TOTAL: 4	230422	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.422	0.0%	99	0.000	4.263	268.000
article cleanup	00:00:00.387	0.0%	52787	0.000	0.007	0.030
article logging	00:00:01.480	0.0%	52805	0.005	0.028	0.137
article parse	00:00:01.130	0.0%	160225	0.000	0.007	0.030
article write	00:00:01.016	0.0%	22165	0.000	0.046	0.453
artlog/artcncl	00:00:00.000	0.0%	78	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.287	0.0%	355278	0.000	0.001	0.003
hisgrep/artcncl	00:00:00.001	0.0%	73	0.000	0.014	0.500
hishave/artcncl	00:00:00.361	0.0%	99	0.000	3.646	266.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:48.154	0.1%	240057	0.004	0.201	2.376
history sync	00:00:00.011	0.0%	373	0.000	0.029	1.000
history write	00:00:02.201	0.0%	52769	0.008	0.042	0.240
hiswrite/artcncl	00:00:00.000	0.0%	26	0.000	0.000	0.000
idle	22:59:51.708	97.1%	327281	113.924	252.968	1040.000
nntp read	00:00:02.565	0.0%	330350	0.001	0.008	0.014
overview write	00:00:00.075	0.0%	22165	0.000	0.003	0.034
perl filter	00:39:13.919	2.8%	52769	0.000	44.608	202.182
python filter	00:00:01.436	0.0%	52772	0.008	0.027	0.065
site send	00:00:03.663	0.0%	66495	0.032	0.055	0.105
TOTAL: 23:40:52.521	23:40:08.816	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	20393	0.000	0.002	0.019
article prepare	00:00:00.006	0.0%	13575	0.000	0.000	0.013
article read	00:00:00.402	0.0%	6661	0.000	0.060	1.532
backlog stats	00:00:00.077	0.0%	125687	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.589	0.0%	79054	0.000	0.007	0.020
data write	00:00:01.647	0.0%	58918	0.007	0.028	0.066
idle	22:26:02.150	100.0%	125687	208.359	642.566	2386.399
status file	00:00:00.062	0.0%	242	0.000	0.256	1.000
TOTAL: 22:26:11.221	22:26:04.972	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:09:08.949	97.4%	1564	531.286	2652.781	62158.900
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:44.945	1.1%	2273160	0.000	0.020	6.135
readart	00:00:04.819	0.1%	1189	0.000	4.053	5.897
TOTAL: 01:10:57.703	01:09:58.713	98.6%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	153
name	2
pause	2
paused	2
perl	5
reload	89
reserve	2
shutdown	12
TOTAL: 13	279

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	hasname-out.news.weretis.net	19	61331	16973	33193	11165	27%	47:33:04
2	usenet.blueworldhosting.com	725	30056	2922	18977	8157	9%	23:40:55
3	news.nntp4.net	7	47384	2236	33594	11554	4%	24:00:56
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		755	138774	22134	85764	30876	15%	95:14:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	hasname-out.news.weretis.net	77.1 MB	179.3 KB	84.4 MB	161.6 MB	47%	5.9 KB
2	usenet.blueworldhosting.com	13.0 MB	0.0 KB	44.2 MB	57.2 MB	22%	5.3 KB
3	news.nntp4.net	8.0 MB	0.0 KB	95.9 MB	103.9 MB	7%	7.7 KB
4	localhost	6.9 KB	0.0 KB	0.0 KB	6.9 KB	100%	2.3 KB
TOTAL: 4		98.1 MB	179.3 KB	224.4 MB	322.7 MB	30%	6.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 11 15:00:01 - 15:59:59	1578	7.1%	0.44	9.5 MB	9.5%	2.69
Oct 11 16:00:00 - 16:59:59	1650	7.4%	0.46	9.1 MB	9.2%	2.59
Oct 11 17:00:00 - 17:59:59	1314	5.9%	0.36	6.4 MB	6.5%	1.82
Oct 11 18:00:00 - 18:59:59	1472	6.6%	0.41	9.5 MB	9.6%	2.71
Oct 11 19:00:00 - 19:59:59	1572	7.0%	0.44	10.8 MB	10.9%	3.07
Oct 11 20:00:00 - 20:59:59	1395	6.2%	0.39	8.3 MB	8.4%	2.37
Oct 11 21:00:00 - 21:59:59	897	4.0%	0.25	4.1 MB	4.1%	1.16
Oct 11 22:00:00 - 22:59:59	752	3.4%	0.21	3.3 MB	3.3%	0.94
Oct 11 23:00:00 - 23:59:59	576	2.6%	0.16	2.8 MB	2.9%	0.81
Oct 12 00:00:00 - 00:59:59	468	2.1%	0.13	2.1 MB	2.1%	0.59
Oct 12 01:00:00 - 01:59:59	1137	5.1%	0.32	2.6 MB	2.6%	0.74
Oct 12 02:00:00 - 02:59:59	632	2.8%	0.18	2.3 MB	2.3%	0.65
Oct 12 03:00:00 - 03:59:59	784	3.5%	0.22	4.0 MB	4.0%	1.13
Oct 12 04:00:00 - 04:59:59	821	3.7%	0.23	4.8 MB	4.9%	1.37
Oct 12 05:00:00 - 05:59:59	313	1.4%	0.09	1.1 MB	1.1%	0.32
Oct 12 06:00:00 - 06:59:59	771	3.5%	0.21	1.7 MB	1.7%	0.48
Oct 12 07:00:00 - 07:59:59	522	2.3%	0.14	1.4 MB	1.5%	0.41
Oct 12 08:00:00 - 08:59:59	532	2.4%	0.15	1.7 MB	1.7%	0.49
Oct 12 09:00:00 - 09:59:59	739	3.3%	0.21	2.3 MB	2.4%	0.67
Oct 12 10:00:00 - 10:59:59	934	4.2%	0.26	2.6 MB	2.6%	0.73
Oct 12 11:00:00 - 11:59:59	562	2.5%	0.16	1.6 MB	1.6%	0.45
Oct 12 12:00:00 - 12:59:59	1245	5.6%	0.35	2.8 MB	2.8%	0.80
Oct 12 13:00:00 - 13:59:59	738	3.3%	0.20	2.0 MB	2.0%	0.56
Oct 12 14:00:00 - 14:59:59	931	4.2%	0.26	2.3 MB	2.4%	0.67
Oct 12 15:00:00 - 15:00:02	2	0.0%	1.00	7.1 KB	0.0%	3.57
TOTAL: 24:00:01	22337	100.0%	0.26	99.2 MB	100.0%	1.18

CNFS buffer status:

Buffer	Class	Size	Used	%Used	Cycles	KB/sec	Days
EIGHT	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
FIVE	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
FOUR	DEFAULT	1024.0 MB	325.9 MB	31.8%	24	0.41	29.91
ONE	DEFAULT	1024.0 MB	488.0 MB	47.7%	26	0.41	29.74
SEVEN	BIG	4.0 GB	2.9 GB	72.6%	1	0.00	0.00
SIX	BIG	4.0 GB	2.9 GB	72.8%	1	0.00	0.00
THREE	DEFAULT	1024.0 MB	331.8 MB	32.4%	24	0.40	30.18
TWO	DEFAULT	1024.0 MB	318.8 MB	31.1%	24	0.39	31.05
TOTAL: 8	-	20.0 GB	13.1 GB	65.3%	102	0.40	-

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.nntp4.net	11645	1	0	11644
2	hasname-out.news.weretis.net	11181	3	18	11160
3	usenet.blueworldhosting.com	8170	2258	0	5912
TOTAL: 3		30996	2262	18	28716

Unwanted newsgroups [Top 20]:

Newsgroup	Count
de.sci.announce	529
de.sci.medizin	367
linux.kernel.netdev	300
kraft.politics	277
perl.cpan	159
kraft.talks	133
kraft.competent	113
linux.kernel.git	96
fido7.ukrnews	63
fido7.fidonet.online	36
fido7.pushkin.local	34
odessa.news	21
kraft.vehicles	19
kraft.blacklog	18
fido7.lorapvt.horo	12
alt.stupid	11
fido7.f715.techinfo	6
kraft.video	6
fido7.altyn.local	5
fido7.odessa.news	5
TOTAL: 52	2262

INND Perl filter [Top 20]:

Reason	Count
Reject Google Groups posting to comp.lang.c	8627
Reject Google Groups posting to comp.protocols.dicom	2979
Reject Google Groups posting to sci.crypt	2474
Reject Google Groups posting to comp.protocols.dicom by SpamAssassin	1814
Reject Google Groups posting to comp.lang.c by SpamAssassin	1729
EMP (md5)	1640
Reject Google Groups posting to bit.listserv.ibm-main	774
Reject Google Groups posting to sci.crypt by SpamAssassin	568
Too many newsgroups	487
Reject Google Groups posting to comp.lang.c by SpamAssassin (score 6.623)	438
Reject Google Groups posting to bit.listserv.ibm-main by SpamAssassin	396
Reject Google Groups posting to soc.culture.punjab	391
Reject Google Groups posting to uk.sci.weather	336
Reject Google Groups posting to comp.lang.c by SpamAssassin (score 6.622)	270
Reject Google Groups posting to uk.sci.weather by SpamAssassin (score 6.623)	268
Reject Google Groups posting to comp.lang.clipper.visual-objects	228
Reject Google Groups posting to uk.sci.weather by SpamAssassin	178
Reject Google Groups posting to comp.lang.clipper.visual-objects by SpamAssassin	168
Reject Google Groups posting to bit.listserv.ibm-main by SpamAssassin (score 6.622)	144
Reject Google Groups posting to alt.drugs.dealers.playground.bert-hoff	124
TOTAL: 872	28698

INND no permission servers:

System	Conn
94.142.138.104	1
45.227.254.48	1
167.248.133.188	1
125.56.68.101	1
TOTAL: 4	4

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	hasname-out.news.weretis.net	14
	usenet.blueworldhosting.com	4
	TOTAL: 2	18
TOTAL: 1		18

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	bbsgslincom	5888	5885	0	1	38	99%	23:51:04
2	nntp4	21282	1577	18299	65	12	7%	23:51:13
3	weretis	6598	166	4203	345	3	2%	23:49:31
4	blueworldhosting	22562	3	18724	0	12	0%	23:51:16
5	aioe	0	0	0	0	22072	0%	23:50:54
TOTAL: 5		56330	7631	41226	411	22137	13%	119:13:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	bbsgslincom	35.0 MB	6.7 KB	35.1 MB	0.4 KB/s	6.1 KB	23:51:04
2	nntp4	7.7 MB	219.9 KB	7.9 MB	0.1 KB/s	5.0 KB	23:51:13
3	weretis	841.1 KB	1.1 MB	1.9 MB	0.0 KB/s	3.8 KB	23:49:31
4	blueworldhosting	6.9 KB	0.0 KB	6.9 KB	0.0 KB/s	2.3 KB	23:51:16
5	aioe	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:54
TOTAL: 5		43.6 MB	1.3 MB	44.9 MB	0.1 KB/s	5.7 KB	119:13:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	114-34-121-114.hinet-ip.hinet.net	26	1189	7.8 MB	35	3	0	01:10:56
TOTAL: 1		26	1189	7.8 MB	35	3	0	01:10:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.hinet-ip.hinet.net	26	1189	7.8 MB	35	3	0	01:10:56
TOTAL: 1		26	1189	7.8 MB	35	3	0	01:10:56

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
114-34-121-114.hinet-ip.hinet.net	6.849	19.531	01:10:56
125-56-68-101.ppp.bbiq.jp	0.002	0.002	00:00:01
ec2-13-52-249-41.us-west-1.compute.amazonaws.com	0.000	0.002	00:00:00
60.149.216.162.bc.googleusercontent.com	0.000	0.003	00:00:00
107.170.255.29	0.001	0.001	00:00:00
scanner-08.ch1.censys-scanner.com	0.000	0.002	00:00:00
198.199.93.38	0.000	0.002	00:00:00
TOTAL: 7	6.852	19.543	01:10:57

NNRP no permission clients:

System	Conn
125-56-68-101.ppp.bbiq.jp	2
60.149.216.162.bc.googleusercontent.com	1
ec2-13-52-249-41.us-west-1.compute.amazonaws.com	1
107.170.255.29	1
198.199.93.38	1
scanner-08.ch1.censys-scanner.com	1
TOTAL: 6	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
114-34-121-114.hinet-ip.hinet.net	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	sci	598	50.3%
2	comp	563	47.4%
3	news	17	1.4%
4	tw	11	0.9%
TOTAL: 4		1189	100.0%

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

Newsgroup		Count
1	sci.crypt	598
2	comp.lang.c	505
3	comp.lang.python	56
4	news.software.nntp	7
5	tw.bbs.talk	6
6	news.software.nn	5
7	tw.bbs.comp.lang.perl	4
8	news.software.readers	3
9	news.software.misc	2
10	comp.lang.php	2
11	tw.bbs.test	1
TOTAL: 11		1189

Daily Usenet report

Oct 11 15:00:01 -- Oct 12 15:00:02