Daily Usenet report

Jun 25 04:15:02 -- Jun 26 04:15:02

Unknown entries from news log file:

First 50 / 370 lines (13.5%)

Jun 25 05:05:39 newsfeed nnrpd[287411]: SERVER perl filter not defined
Jun 25 05:05:39 newsfeed nnrpd[287411]: SERVER perl filter not defined
Jun 25 07:41:47 newsfeed nnrpd[306451]: SERVER perl filter not defined
Jun 25 07:41:47 newsfeed nnrpd[306451]: SERVER perl filter not defined
Jun 25 12:09:00 newsfeed nnrpd[338848]: SERVER perl filter not defined
Jun 25 12:09:00 newsfeed nnrpd[338848]: SERVER perl filter not defined
Jun 25 12:10:51 newsfeed nnrpd[339176]: SERVER perl filter not defined
Jun 25 12:10:51 newsfeed nnrpd[339176]: SERVER perl filter not defined
Jun 25 22:00:04 newsfeed pgpverify[409853]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:00:04 newsfeed pgpverify[409853]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:00:08 newsfeed pgpverify[409860]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:00:08 newsfeed pgpverify[409860]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:01:10 newsfeed pgpverify[410129]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:01:10 newsfeed pgpverify[410129]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:01:14 newsfeed pgpverify[410138]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:01:14 newsfeed pgpverify[410138]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:02:14 newsfeed pgpverify[410152]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:02:14 newsfeed pgpverify[410152]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:02:16 newsfeed pgpverify[410159]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:02:16 newsfeed pgpverify[410159]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:03:17 newsfeed pgpverify[410355]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:03:17 newsfeed pgpverify[410355]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:03:18 newsfeed pgpverify[410384]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:03:18 newsfeed pgpverify[410384]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:04:18 newsfeed pgpverify[410453]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:04:18 newsfeed pgpverify[410453]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:04:20 newsfeed pgpverify[410460]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:04:20 newsfeed pgpverify[410460]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:05:20 newsfeed pgpverify[410536]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:05:20 newsfeed pgpverify[410536]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:05:22 newsfeed pgpverify[410543]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:05:22 newsfeed pgpverify[410543]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:06:22 newsfeed pgpverify[410799]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:06:22 newsfeed pgpverify[410799]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:06:24 newsfeed pgpverify[410819]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:06:24 newsfeed pgpverify[410819]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:07:24 newsfeed pgpverify[410833]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:07:24 newsfeed pgpverify[410833]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:07:26 newsfeed pgpverify[410840]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:07:26 newsfeed pgpverify[410840]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:08:27 newsfeed pgpverify[411000]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:08:27 newsfeed pgpverify[411000]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:08:29 newsfeed pgpverify[411007]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:08:29 newsfeed pgpverify[411007]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:09:29 newsfeed pgpverify[411278]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:09:29 newsfeed pgpverify[411278]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:09:31 newsfeed pgpverify[411285]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:09:31 newsfeed pgpverify[411285]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:10:32 newsfeed pgpverify[411358]: pgpverify: /usr/bin/gpg1: no such file
Jun 25 22:10:32 newsfeed pgpverify[411358]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
inn 378036 89.7%41.5 MB 90.7%
innd 24614 5.8%2.4 MB 5.3%
nnrpd 16608 3.9%1.6 MB 3.5%
innfeed 1238 0.3%166.6 KB 0.4%
controlchan 362 0.1%39.3 KB 0.1%
pgpverify 360 0.1%39.4 KB 0.1%
overchan 2 0.0%0.3 KB 0.0%
TOTAL: 7 421220 100.0%45.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 751352 99.6%
Negative hits 1576 0.2%
Cache misses 1124 0.1%
Positive hits 84 0.0%
TOTAL: 4 754136 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:19.952 0.0%1028 0.000 19.409 23.000
article cleanup00:00:03.774 0.0%758120 0.002 0.005 0.009
article logging00:00:15.238 0.0%758142 0.012 0.020 0.038
article parse00:00:44.864 0.0%2848130 0.011 0.016 0.023
article write00:12:52.098 0.4%757368 0.484 1.019 3.412
artlog/artcncl00:00:00.004 0.0%284 0.000 0.014 0.015
data move00:00:03.070 0.0%4142290 0.000 0.001 0.002
hisgrep/artcncl00:00:14.832 0.0%1312 0.000 11.305 13.000
hishave/artcncl00:00:00.004 0.0%284 0.000 0.014 0.015
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:17:15.736 0.6%759880 0.003 1.363 12.039
history sync00:00:00.042 0.0%758 0.000 0.055 1.000
history write00:24:27.268 0.8%757368 0.037 1.937 14.138
idle46:48:20.358 97.5%3103696 31.754 54.290 242.720
nntp read00:00:40.502 0.0%2628414 0.010 0.015 0.024
overview write00:00:02.094 0.0%757368 0.000 0.003 0.006
perl filter00:14:44.046 0.5%757368 0.886 1.167 1.527
python filter00:00:00.214 0.0%757378 0.000 0.000 0.001
site send00:00:17.996 0.0%1528872 0.007 0.012 0.018
TOTAL: 48:01:44.25048:00:22.092100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.036 0.0%13742 0.000 0.003 0.050
article prepare00:00:00.000 0.0%1048 0.000 0.000 0.000
article read00:00:00.008 0.0%262 0.000 0.031 1.000
backlog stats00:00:00.016 0.0%76056 0.000 0.000 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.380 0.0%36402 0.000 0.010 0.050
data write00:00:01.162 0.0%22728 0.000 0.051 0.273
idle47:40:01.578100.0%76056 411.128 2256.253 8230.137
status file00:00:00.138 0.0%318 0.000 0.434 2.000
TOTAL: 47:40:04.26647:40:03.318100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:02:50.794 68.2%9106 0.000 18.756 9781.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.368 0.1%30546 0.000 0.012 0.500
readart00:00:00.004 0.0%96 0.000 0.042 0.500
TOTAL: 00:04:10.49600:02:51.166 68.3%----

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 76 756180 755312 120 748 99%65:44:00
2localhost 12 10 10 0 0 100%00:00:00
3news.icm.edu.pl 54 68 4 64 0 5%10:22:44
TOTAL: 3 142 756258 755326 184 748 99%76:06:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl9.2 GB7.3 MB0.0 KB9.2 GB 99%12.7 KB
2news.icm.edu.pl36.0 KB0.0 KB0.0 KB36.0 KB100%9.0 KB
3localhost13.8 KB0.0 KB0.0 KB13.8 KB100%1.4 KB
TOTAL: 39.2 GB7.3 MB0.0 KB9.2 GB 99%12.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 25 04:15:02 - 04:59:59 11509 3.0% 4.27149.4 MB 3.2% 56.72
Jun 25 05:00:00 - 05:59:59 21375 5.7% 5.94244.0 MB 5.2% 69.39
Jun 25 06:00:00 - 06:59:59 16781 4.4% 4.66203.3 MB 4.3% 57.82
Jun 25 07:00:00 - 07:59:59 17136 4.5% 4.76200.8 MB 4.3% 57.13
Jun 25 08:00:00 - 08:59:59 13338 3.5% 3.71172.4 MB 3.7% 49.04
Jun 25 09:00:00 - 09:59:59 15673 4.2% 4.35186.1 MB 4.0% 52.92
Jun 25 10:00:00 - 10:59:59 16533 4.4% 4.59210.5 MB 4.5% 59.89
Jun 25 11:00:00 - 11:59:59 16099 4.3% 4.47212.9 MB 4.5% 60.57
Jun 25 12:00:00 - 12:59:59 18358 4.9% 5.10242.0 MB 5.2% 68.84
Jun 25 13:00:00 - 13:59:59 16631 4.4% 4.62199.9 MB 4.3% 56.87
Jun 25 14:00:00 - 14:59:59 15552 4.1% 4.32194.8 MB 4.2% 55.42
Jun 25 15:00:00 - 15:59:59 16126 4.3% 4.48196.3 MB 4.2% 55.84
Jun 25 16:00:00 - 16:59:59 14131 3.7% 3.93179.8 MB 3.8% 51.15
Jun 25 17:00:00 - 17:59:59 19567 5.2% 5.44249.5 MB 5.3% 70.96
Jun 25 18:00:00 - 18:59:59 17812 4.7% 4.95223.6 MB 4.8% 63.60
Jun 25 19:00:00 - 19:59:59 17937 4.8% 4.98222.3 MB 4.7% 63.24
Jun 25 20:00:00 - 20:59:59 14995 4.0% 4.17199.8 MB 4.3% 56.83
Jun 25 21:00:00 - 21:59:59 16071 4.3% 4.46217.8 MB 4.6% 61.97
Jun 25 22:00:00 - 22:59:59 19501 5.2% 5.42236.7 MB 5.0% 67.34
Jun 25 23:00:00 - 23:59:59 13333 3.5% 3.70148.3 MB 3.2% 42.19
Jun 26 00:00:00 - 00:59:59 9824 2.6% 2.73123.9 MB 2.6% 35.25
Jun 26 01:00:00 - 01:59:59 14030 3.7% 3.90176.7 MB 3.8% 50.25
Jun 26 02:00:00 - 02:59:59 12021 3.2% 3.34138.2 MB 2.9% 39.30
Jun 26 03:00:00 - 03:59:59 10464 2.8% 2.91126.6 MB 2.7% 36.02
Jun 26 04:00:00 - 04:15:02 2716 0.7% 3.0134.8 MB 0.7% 39.48
TOTAL: 24:00:00 377513 100.0% 4.374.6 GB 100.0% 55.59
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 381 0 0 370 0 0 0 0 11
TOTAL: 1 381 0 0 370 0 0 0 0 11

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 23130 138 13508 124 0 0 0%47:59:56
2poznan 4 0 4 0 0 0 0%01:00:02
TOTAL: 2 23134 138 13512 124 0 0 0%48:59:58
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm436.1 KB2.2 MB2.7 MB0.0 KB/s10.4 KB47:59:56
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s001:00:02
TOTAL: 2436.1 KB2.2 MB2.7 MB0.0 KB/s10.4 KB48:59:58
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1172.16.2.4 576 76119.6 KB 36 0 000:00:36
295.163.5.83 4 1013.4 KB 2 0 000:01:49
3isvcs01.man.lodz.pl 10 00.0 KB 0 10 000:00:00
TOTAL: 3 590 86133.0 KB 38 10 000:04:11

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 796 86133.0 KB 38 0 000:03:19
2*.man.lodz.pl2878 00.0 KB 0 10 000:00:31
3*.stretchoid.com 4 00.0 KB 0 0 000:00:20
TOTAL: 33678 86133.0 KB 38 10 000:04:11

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
95.163.5.83 0.016 0.044 0.00000:01:49
172.16.2.4 2.238 4.438 0.00000:00:36
nms1.man.lodz.pl 10.804 19.766 0.00000:00:30
83.222.190.170 0.010 0.014 0.00000:00:19
azpdcsl3sa4k.stretchoid.com 0.022 0.008 0.00000:00:19
167.94.138.54 0.112 0.022 0.00000:00:09
167.94.146.56 0.080 0.042 0.00000:00:06
80.111.142.213 0.046 0.052 0.00000:00:04
205.210.31.19 0.006 0.020 0.00000:00:04
205.210.31.136 0.018 0.012 0.00000:00:04
212.51.192.185 0.750 1.390 0.00000:00:02
123.138.79.101 0.004 0.010 0.00000:00:01
isvcs01.man.lodz.pl 0.048 0.100 0.00000:00:00
azpdwgrapaud.stretchoid.com 0.008 0.008 0.00000:00:00
TOTAL: 14 14.162 25.926 0.00000:04:11

Curious NNRP server explorers [Top 20]:

SystemConn
nms1.man.lodz.pl 2868
212.51.192.185 192
80.111.142.213 8
167.94.146.56 6
azpdwgrapaud.stretchoid.com 2
azpdcsl3sa4k.stretchoid.com 2
205.210.31.136 2
123.138.79.101 2
205.210.31.19 2
167.94.138.54 2
83.222.190.170 2
TOTAL: 11 3088

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

SystemConn
83.222.190.170 4
167.94.138.54 2
167.94.146.56 2
azpdwgrapaud.stretchoid.com 2
TOTAL: 4 10

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

CommandCount
EHLO www.censys.io 4
À+Ì©À#011À0À#024À/̨À#023 2
MGLNDD_212.191.2.21_119 2
#026#003#001 2
TOTAL: 4 10

NNRP client timeouts [Top 20]:

SystemConnPeer
167.94.146.56 2 4
TOTAL: 1 2 8

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1pl 76 88.4%
2lodman 10 11.6%
TOTAL: 2 86100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 64
2pl.comp.pecet 12
3lodman.info 10
TOTAL: 3 86