Daily Usenet report

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

Unknown entries from news log file:

First 50 / 360 lines (13.9%)

Jun 19 22:00:04 newsfeed pgpverify[1785482]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:00:04 newsfeed pgpverify[1785482]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:00:09 newsfeed pgpverify[1785489]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:00:09 newsfeed pgpverify[1785489]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:01:11 newsfeed pgpverify[1785760]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:01:11 newsfeed pgpverify[1785760]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:01:14 newsfeed pgpverify[1785769]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:01:14 newsfeed pgpverify[1785769]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:02:15 newsfeed pgpverify[1785783]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:02:15 newsfeed pgpverify[1785783]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:02:17 newsfeed pgpverify[1785790]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:02:17 newsfeed pgpverify[1785790]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:03:17 newsfeed pgpverify[1785997]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:03:17 newsfeed pgpverify[1785997]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:03:19 newsfeed pgpverify[1786015]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:03:19 newsfeed pgpverify[1786015]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:04:19 newsfeed pgpverify[1786223]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:04:19 newsfeed pgpverify[1786223]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:04:21 newsfeed pgpverify[1786230]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:04:21 newsfeed pgpverify[1786230]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:05:22 newsfeed pgpverify[1786309]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:05:22 newsfeed pgpverify[1786309]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:05:23 newsfeed pgpverify[1786316]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:05:23 newsfeed pgpverify[1786316]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:06:24 newsfeed pgpverify[1786585]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:06:24 newsfeed pgpverify[1786585]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:06:25 newsfeed pgpverify[1786594]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:06:25 newsfeed pgpverify[1786594]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:07:26 newsfeed pgpverify[1786608]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:07:26 newsfeed pgpverify[1786608]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:07:28 newsfeed pgpverify[1786615]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:07:28 newsfeed pgpverify[1786615]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:08:28 newsfeed pgpverify[1786637]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:08:28 newsfeed pgpverify[1786637]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:08:30 newsfeed pgpverify[1786644]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:08:30 newsfeed pgpverify[1786644]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:09:30 newsfeed pgpverify[1786914]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:09:30 newsfeed pgpverify[1786914]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:09:32 newsfeed pgpverify[1786921]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:09:32 newsfeed pgpverify[1786921]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:10:32 newsfeed pgpverify[1786996]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:10:32 newsfeed pgpverify[1786996]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:10:34 newsfeed pgpverify[1787003]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:10:34 newsfeed pgpverify[1787003]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:11:35 newsfeed pgpverify[1787019]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:11:35 newsfeed pgpverify[1787019]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:11:36 newsfeed pgpverify[1787026]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:11:36 newsfeed pgpverify[1787026]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:12:37 newsfeed pgpverify[1787297]: pgpverify: /usr/bin/gpg1: no such file
Jun 19 22:12:37 newsfeed pgpverify[1787297]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
inn 322552 88.1%35.5 MB 89.4%
innd 24828 6.8%2.4 MB 6.1%
nnrpd 16634 4.5%1.6 MB 4.0%
innfeed 1218 0.3%142.5 KB 0.4%
controlchan 362 0.1%39.7 KB 0.1%
pgpverify 360 0.1%40.5 KB 0.1%
overchan 2 0.0%0.3 KB 0.0%
TOTAL: 7 365956 100.0%39.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 638494 98.2%
Negative hits 8098 1.2%
Cache misses 3620 0.6%
Positive hits 104 0.0%
TOTAL: 4 650316 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.030 0.0%120 0.000 8.583 27.667
article cleanup00:00:03.466 0.0%645568 0.002 0.005 0.010
article logging00:00:13.662 0.0%645612 0.010 0.021 0.033
article parse00:00:37.684 0.0%2243616 0.010 0.017 0.024
article write00:11:04.488 0.4%642370 0.411 1.034 3.606
artlog/artcncl00:00:00.000 0.0%14 0.000 0.000 0.000
data move00:00:03.290 0.0%3333212 0.000 0.001 0.003
hisgrep/artcncl00:00:00.300 0.0%134 0.000 2.239 12.333
hishave/artcncl00:00:00.000 0.0%14 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:11:24.612 0.4%654428 0.046 1.046 7.557
history sync00:00:00.028 0.0%792 0.000 0.035 0.500
history write00:21:16.302 0.7%642370 0.063 1.987 12.546
idle47:01:43.922 97.9%2500936 37.317 67.696 125.916
nntp read00:00:33.208 0.0%2083806 0.010 0.016 0.022
overview write00:00:01.876 0.0%642370 0.000 0.003 0.006
perl filter00:13:04.214 0.5%642370 0.929 1.221 1.551
python filter00:00:00.184 0.0%642370 0.000 0.000 0.001
site send00:00:15.220 0.0%1297556 0.006 0.012 0.016
TOTAL: 48:01:58.18448:00:23.486 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.034 0.0%12428 0.000 0.003 0.067
article prepare00:00:00.000 0.0%696 0.000 0.000 0.000
article read00:00:00.004 0.0%174 0.000 0.023 0.143
backlog stats00:00:00.014 0.0%69248 0.000 0.000 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.362 0.0%32858 0.000 0.011 0.150
data write00:00:01.056 0.0%20498 0.000 0.052 0.207
idle47:41:04.748100.0%69248 553.678 2478.985 10033.800
status file00:00:00.150 0.0%314 0.000 0.478 2.000
TOTAL: 47:41:07.05847:41:06.368100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:46:16.066 97.7%16682 0.000 166.411 6251.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.916 0.2%61052 0.000 0.081 10.783
readart00:00:17.938 0.6%7592 0.000 2.363 2.837
TOTAL: 00:47:22.49000:46:38.920 98.5%----

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 84 645454 641826 426 3202 99%66:21:54
2news.icm.edu.pl 68 96 2 94 0 2%14:26:50
3localhost 2 0 0 0 0 0%00:00:00
TOTAL: 3 154 645550 641828 520 3202 99%80:48:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl7.4 GB41.4 MB0.0 KB7.4 GB 99%12.1 KB
2news.icm.edu.pl16.1 KB0.0 KB0.0 KB16.1 KB100%8.0 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 37.4 GB41.4 MB0.0 KB7.4 GB 99%12.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 19 04:15:01 - 04:59:59 11297 3.5% 4.19148.4 MB 3.9% 56.29
Jun 19 05:00:00 - 05:59:59 12389 3.9% 3.44155.2 MB 4.1% 44.16
Jun 19 06:00:00 - 06:59:59 9882 3.1% 2.75101.2 MB 2.7% 28.78
Jun 19 07:00:00 - 07:59:59 9870 3.1% 2.74107.1 MB 2.8% 30.47
Jun 19 08:00:00 - 08:59:59 11593 3.6% 3.22140.5 MB 3.7% 39.96
Jun 19 09:00:00 - 09:59:59 11049 3.4% 3.07124.5 MB 3.3% 35.41
Jun 19 10:00:00 - 10:59:59 14440 4.5% 4.01180.5 MB 4.8% 51.36
Jun 19 11:00:00 - 11:59:59 14034 4.4% 3.90161.2 MB 4.3% 45.86
Jun 19 12:00:00 - 12:59:59 12974 4.0% 3.60160.2 MB 4.2% 45.57
Jun 19 13:00:00 - 13:59:59 11831 3.7% 3.29148.1 MB 3.9% 42.12
Jun 19 14:00:00 - 14:59:59 15263 4.8% 4.24182.0 MB 4.8% 51.77
Jun 19 15:00:00 - 15:59:59 15430 4.8% 4.29174.2 MB 4.6% 49.55
Jun 19 16:00:00 - 16:59:59 15545 4.8% 4.32170.8 MB 4.5% 48.60
Jun 19 17:00:00 - 17:59:59 16632 5.2% 4.62193.0 MB 5.1% 54.88
Jun 19 18:00:00 - 18:59:59 15446 4.8% 4.29199.9 MB 5.3% 56.85
Jun 19 19:00:00 - 19:59:59 12121 3.8% 3.37135.2 MB 3.6% 38.45
Jun 19 20:00:00 - 20:59:59 12217 3.8% 3.39145.9 MB 3.8% 41.51
Jun 19 21:00:00 - 21:59:59 14078 4.4% 3.91168.2 MB 4.4% 47.84
Jun 19 22:00:00 - 22:59:59 15957 5.0% 4.43164.3 MB 4.3% 46.72
Jun 19 23:00:00 - 23:59:59 13410 4.2% 3.73144.9 MB 3.8% 41.22
Jun 20 00:00:00 - 00:59:59 12682 4.0% 3.52158.2 MB 4.2% 44.99
Jun 20 01:00:00 - 01:59:59 13635 4.2% 3.79156.4 MB 4.1% 44.49
Jun 20 02:00:00 - 02:59:59 14372 4.5% 3.99187.4 MB 4.9% 53.31
Jun 20 03:00:00 - 03:59:59 11194 3.5% 3.11139.7 MB 3.7% 39.74
Jun 20 04:00:00 - 04:15:02 3582 1.1% 3.9746.3 MB 1.2% 52.53
TOTAL: 24:00:01 320923 100.0% 3.713.7 GB 100.0% 44.96
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 1622 0 0 1601 0 0 0 0 21
TOTAL: 1 1622 0 0 1601 0 0 0 0 21

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 20948 164 12268 10 0 0 0%47:59:58
2poznan 2 0 2 0 0 0 0%00:40:02
TOTAL: 2 20950 164 12270 10 0 0 0%48:40:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm755.9 KB107.1 KB863.0 KB0.0 KB/s5.0 KB47:59:58
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:40:02
TOTAL: 2755.9 KB107.1 KB863.0 KB0.0 KB/s5.0 KB48:40:00
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1syn-066-061-096-239.res.spectrum.com 32 75848.3 MB 4 0 000:45:28
2172.16.2.4 576 810.2 KB 4 0 000:00:34
TOTAL: 2 608 75928.3 MB 8 0 000:47:22

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.res.spectrum.com 32 75848.3 MB 4 0 000:45:28
2unresolved 796 810.2 KB 4 0 000:01:23
3*.stretchoid.com 2 00.0 KB 0 0 000:00:00
4*.man.lodz.pl2880 00.0 KB 0 0 000:00:30
TOTAL: 43710 75928.3 MB 8 0 000:47:22

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
syn-066-061-096-239.res.spectrum.com 0.552 1.416 0.00000:45:28
172.16.2.4 2.340 4.504 0.00000:00:34
nms2.man.lodz.pl 10.762 21.462 0.00000:00:30
167.94.146.48 0.128 0.058 0.00000:00:17
167.94.138.199 0.078 0.036 0.00000:00:12
167.94.138.124 0.102 0.022 0.00000:00:06
80.111.142.213 0.052 0.058 0.00000:00:04
198.235.24.50 0.012 0.022 0.00000:00:04
212.51.192.185 0.760 1.476 0.00000:00:02
198.235.24.253 0.006 0.020 0.00000:00:00
azpdwgk5ivy6.stretchoid.com 0.002 0.026 0.00000:00:00
203.55.131.4 0.002 0.014 0.00000:00:00
TOTAL: 12 14.796 29.114 0.00000:47:22

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 2880
212.51.192.185 192
80.111.142.213 8
167.94.138.199 6
167.94.146.48 6
azpdwgk5ivy6.stretchoid.com 2
203.55.131.4 2
167.94.138.124 2
198.235.24.50 2
198.235.24.253 2
TOTAL: 10 3102

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

SystemConn
167.94.138.124 2
167.94.146.48 2
azpdwgk5ivy6.stretchoid.com 2
167.94.138.199 2
TOTAL: 4 8

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

CommandCount
EHLO www.censys.io 6
MGLNDD_212.191.2.21_119 2
TOTAL: 2 8

NNRP client timeouts [Top 20]:

SystemConnPeer
167.94.138.199 2 4
167.94.146.48 2 4
TOTAL: 2 4 12

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1lodman 7584 99.9%
2pl 8 0.1%
TOTAL: 2 7592100.0%

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

NewsgroupCount
1lodman.info 7584
2pl.comp.os.ms-windows.winnt 8
TOTAL: 2 7592