Daily Usenet report

Jun 18 04:15:02 -- Jun 19 04:15:01

Unknown entries from news log file:

First 50 / 360 lines (13.9%)

Jun 18 22:00:04 newsfeed pgpverify[1609943]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:00:04 newsfeed pgpverify[1609943]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:00:09 newsfeed pgpverify[1609950]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:00:09 newsfeed pgpverify[1609950]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:01:11 newsfeed pgpverify[1610219]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:01:11 newsfeed pgpverify[1610219]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:01:13 newsfeed pgpverify[1610226]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:01:13 newsfeed pgpverify[1610226]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:02:14 newsfeed pgpverify[1610243]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:02:14 newsfeed pgpverify[1610243]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:02:15 newsfeed pgpverify[1610250]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:02:15 newsfeed pgpverify[1610250]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:03:16 newsfeed pgpverify[1610587]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:03:16 newsfeed pgpverify[1610587]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:03:17 newsfeed pgpverify[1610605]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:03:17 newsfeed pgpverify[1610605]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:04:18 newsfeed pgpverify[1610683]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:04:18 newsfeed pgpverify[1610683]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:04:20 newsfeed pgpverify[1610690]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:04:20 newsfeed pgpverify[1610690]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:05:20 newsfeed pgpverify[1610771]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:05:20 newsfeed pgpverify[1610771]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:05:22 newsfeed pgpverify[1610778]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:05:22 newsfeed pgpverify[1610778]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:06:23 newsfeed pgpverify[1611034]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:06:23 newsfeed pgpverify[1611034]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:06:24 newsfeed pgpverify[1611054]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:06:24 newsfeed pgpverify[1611054]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:07:25 newsfeed pgpverify[1611068]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:07:25 newsfeed pgpverify[1611068]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:07:26 newsfeed pgpverify[1611075]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:07:26 newsfeed pgpverify[1611075]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:08:27 newsfeed pgpverify[1611097]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:08:27 newsfeed pgpverify[1611097]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:08:28 newsfeed pgpverify[1611104]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:08:28 newsfeed pgpverify[1611104]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:09:29 newsfeed pgpverify[1611374]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:09:29 newsfeed pgpverify[1611374]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:09:30 newsfeed pgpverify[1611381]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:09:30 newsfeed pgpverify[1611381]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:10:31 newsfeed pgpverify[1611458]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:10:31 newsfeed pgpverify[1611458]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:10:32 newsfeed pgpverify[1611465]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:10:32 newsfeed pgpverify[1611465]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:11:33 newsfeed pgpverify[1611479]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:11:33 newsfeed pgpverify[1611479]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:11:35 newsfeed pgpverify[1611486]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:11:35 newsfeed pgpverify[1611486]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:12:35 newsfeed pgpverify[1611755]: pgpverify: /usr/bin/gpg1: no such file
Jun 18 22:12:35 newsfeed pgpverify[1611755]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
inn 280436 86.6%30.8 MB 88.0%
innd 24968 7.7%2.4 MB 6.9%
nnrpd 16512 5.1%1.6 MB 4.5%
innfeed 1238 0.4%164.5 KB 0.5%
controlchan 362 0.1%39.7 KB 0.1%
pgpverify 360 0.1%39.2 KB 0.1%
overchan 4 0.0%0.5 KB 0.0%
TOTAL: 7 323880 100.0%35.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 553772 97.5%
Negative hits 10332 1.8%
Cache misses 3722 0.7%
Positive hits 138 0.0%
TOTAL: 4 567964 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.440 0.0%70 0.000 20.571 66.000
article cleanup00:00:02.778 0.0%559672 0.001 0.005 0.009
article logging00:00:11.350 0.0%559720 0.013 0.020 0.035
article parse00:00:30.880 0.0%2028256 0.009 0.015 0.023
article write00:09:55.706 0.3%556594 0.511 1.070 4.535
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:02.706 0.0%2979858 0.000 0.001 0.002
hisgrep/artcncl00:00:00.560 0.0%78 0.000 7.179 32.500
hishave/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:12:14.992 0.4%571102 0.004 1.287 9.113
history sync00:00:00.030 0.0%774 0.000 0.039 0.500
history write00:13:53.942 0.5%556594 0.028 1.498 9.041
idle47:12:05.722 98.3%2245396 31.714 75.677 152.714
nntp read00:00:27.384 0.0%1887032 0.009 0.015 0.022
overview write00:00:01.556 0.0%556594 0.000 0.003 0.006
perl filter00:10:50.010 0.4%556594 0.847 1.168 1.553
python filter00:00:00.124 0.0%556594 0.000 0.000 0.002
site send00:00:12.556 0.0%1125994 0.006 0.011 0.018
TOTAL: 48:01:54.02048:00:31.736100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.026 0.0%12450 0.000 0.002 0.036
article prepare00:00:00.000 0.0%1000 0.000 0.000 0.000
article read00:00:00.010 0.0%250 0.000 0.040 1.000
backlog stats00:00:00.020 0.0%71540 0.000 0.000 0.017
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.354 0.0%33668 0.000 0.011 0.057
data write00:00:01.046 0.0%21280 0.000 0.049 0.212
idle47:59:52.650100.0%71540 858.582 2415.329 6903.011
status file00:00:00.124 0.0%314 0.000 0.395 2.000
TOTAL: 47:59:54.98647:59:54.230100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:04:13.110 72.2%9358 0.000 27.047 4970.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.416 0.1%31658 0.000 0.013 0.500
readart00:00:01.186 0.3%412 0.000 2.879 2.965
TOTAL: 00:05:50.63000:04:14.712 72.6%----

Control commands to INND:

CommandNumber
flush 54
flushlogs 4
go 4
logmode 4
lowmark 2
mode 298
name 4
pause 4
paused 4
reload 4
reserve 4
TOTAL: 11 386

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org00036000
TOTAL00036000

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 184 561480 557750 668 3062 99%72:58:16
2news.icm.edu.pl 70 132 2 130 0 1%16:25:14
3localhost 2 0 0 0 0 0%00:00:00
TOTAL: 3 256 561612 557752 798 3062 99%89:23:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl6.5 GB36.2 MB0.0 KB6.6 GB 99%12.3 KB
2news.icm.edu.pl16.9 KB0.0 KB0.0 KB16.9 KB100%8.5 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 36.5 GB36.2 MB0.0 KB6.6 GB 99%12.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 18 04:15:02 - 04:59:59 9190 3.3% 3.41117.6 MB 3.5% 44.62
Jun 18 05:00:00 - 05:59:59 8754 3.1% 2.43119.6 MB 3.6% 34.02
Jun 18 06:00:00 - 06:59:59 11064 4.0% 3.07145.1 MB 4.3% 41.29
Jun 18 07:00:00 - 07:59:59 13156 4.7% 3.65148.5 MB 4.4% 42.25
Jun 18 08:00:00 - 08:59:59 15730 5.6% 4.37189.5 MB 5.7% 53.91
Jun 18 09:00:00 - 09:59:59 8639 3.1% 2.40105.2 MB 3.1% 29.93
Jun 18 10:00:00 - 10:59:59 11159 4.0% 3.10132.5 MB 4.0% 37.68
Jun 18 11:00:00 - 11:59:59 11075 4.0% 3.08148.6 MB 4.4% 42.28
Jun 18 12:00:00 - 12:59:59 11621 4.2% 3.23152.1 MB 4.5% 43.27
Jun 18 13:00:00 - 13:59:59 13178 4.7% 3.66162.8 MB 4.9% 46.31
Jun 18 14:00:00 - 14:59:59 12752 4.6% 3.54156.6 MB 4.7% 44.54
Jun 18 15:00:00 - 15:59:59 10573 3.8% 2.94119.7 MB 3.6% 34.04
Jun 18 16:00:00 - 16:59:59 11904 4.3% 3.31142.2 MB 4.2% 40.46
Jun 18 17:00:00 - 17:59:59 10882 3.9% 3.02135.7 MB 4.0% 38.60
Jun 18 18:00:00 - 18:59:59 13395 4.8% 3.72172.7 MB 5.2% 49.13
Jun 18 19:00:00 - 19:59:59 12443 4.5% 3.46148.0 MB 4.4% 42.09
Jun 18 20:00:00 - 20:59:59 10427 3.7% 2.90123.7 MB 3.7% 35.19
Jun 18 21:00:00 - 21:59:59 12336 4.4% 3.43139.9 MB 4.2% 39.81
Jun 18 22:00:00 - 22:59:59 12206 4.4% 3.39136.3 MB 4.1% 38.77
Jun 18 23:00:00 - 23:59:59 11807 4.2% 3.28119.5 MB 3.6% 34.00
Jun 19 00:00:00 - 00:59:59 10097 3.6% 2.80112.8 MB 3.4% 32.08
Jun 19 01:00:00 - 01:59:59 12569 4.5% 3.49154.8 MB 4.6% 44.02
Jun 19 02:00:00 - 02:59:59 9382 3.4% 2.61111.6 MB 3.3% 31.76
Jun 19 03:00:00 - 03:59:59 10418 3.7% 2.89107.2 MB 3.2% 30.49
Jun 19 04:00:00 - 04:15:01 4119 1.5% 4.5750.8 MB 1.5% 57.77
TOTAL: 23:59:59 278876 100.0% 3.233.3 GB 100.0% 39.74
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 1556 0 0 1531 0 0 0 0 25
TOTAL: 1 1556 0 0 1531 0 0 0 0 25

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 25
TOTAL: 1 25
TOTAL: 1 25

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 21730 204 12224 46 0 2 0%48:19:44
2poznan 4 0 2 0 0 0 0%00:40:10
TOTAL: 2 21734 204 12226 46 0 2 0%48:59:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm761.6 KB526.5 KB1.3 MB0.0 KB/s5.2 KB48:19:44
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:40:10
TOTAL: 2761.6 KB526.5 KB1.3 MB0.0 KB/s5.2 KB48:59:54
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 4 400440.6 KB 2 0 000:01:57
2172.16.2.4 574 1218.0 KB 6 0 000:00:34
TOTAL: 2 578 412458.6 KB 8 0 000:05:51

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 4 400440.6 KB 2 0 000:01:57
2unresolved 794 1218.0 KB 6 0 000:03:22
3*.man.lodz.pl2880 00.0 KB 0 0 000:00:30
TOTAL: 33678 412458.6 KB 8 0 000:05:51

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
syn-066-061-096-239.res.spectrum.com 0.024 0.084 0.00000:01:57
95.163.5.83 0.026 0.030 0.00000:01:46
172.16.2.4 2.110 4.350 0.00000:00:34
nms2.man.lodz.pl 10.224 20.856 0.00000:00:30
206.168.34.66 0.074 0.020 0.00000:00:25
45.142.193.185 0.014 0.018 0.00000:00:19
198.235.24.16 0.006 0.022 0.00000:00:07
80.111.142.213 0.020 0.068 0.00000:00:04
212.51.192.185 0.664 1.288 0.00000:00:02
205.210.31.173 0.004 0.010 0.00000:00:01
194.164.107.6 0.002 0.014 0.00000:00:00
185.243.96.117 0.028 0.040 0.00000:00:00
TOTAL: 12 13.196 26.800 0.00000:05:51

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 2880
212.51.192.185 192
80.111.142.213 8
185.243.96.117 6
95.163.5.83 4
205.210.31.173 2
194.164.107.6 2
45.142.193.185 2
198.235.24.16 2
206.168.34.66 2
TOTAL: 10 3100

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
45.142.193.185 6
185.243.96.117 6
206.168.34.66 2
TOTAL: 3 14

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
#003 6
#001 2
#026#003#001 2
EHLO www.censys.io 2
À+Ì©À#011À0À#024À/̨À#023 2
TOTAL: 5 14

NNRP client timeouts [Top 20]:

SystemConnPeer
185.243.96.117 6 6
TOTAL: 1 6 10

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1lodman 400 97.1%
2pl 12 2.9%
TOTAL: 2 412100.0%

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

NewsgroupCount
1lodman.info 400
2pl.comp.os.ms-windows.winnt 12
TOTAL: 2 412
innreport from INN 2.7.2 (20240212 prerelease) based on initial work (©) 1996-2001 by Fabien Tassin <fta@sofaraway.org> and enhanced by several contributors since then