Daily Usenet report

Jun 15 04:15:01 -- Jun 16 04:15:01

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jun 15 17:00:12 newsfeed pgpverify[1049693]: pgpverify: /usr/bin/gpg1: no such file
Jun 15 17:00:12 newsfeed pgpverify[1049693]: pgpverify: /usr/bin/gpg1: no such file
Jun 15 17:00:18 newsfeed pgpverify[1049890]: pgpverify: /usr/bin/gpg1: no such file
Jun 15 17:00:18 newsfeed pgpverify[1049890]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
inn 300748 87.3%33.0 MB 88.6%
innd 25656 7.5%2.5 MB 6.7%
nnrpd 16622 4.8%1.6 MB 4.2%
innfeed 1234 0.4%154.5 KB 0.4%
controlchan 46 0.0%10.2 KB 0.0%
pgpverify 4 0.0%0.5 KB 0.0%
TOTAL: 6 344310 100.0%37.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 587948 85.9%
Negative hits 75232 11.0%
Cache misses 20778 3.0%
Positive hits 110 0.0%
TOTAL: 4 684068 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.234 0.0%94 0.000 34.404 64.000
article cleanup00:00:02.676 0.0%597482 0.001 0.004 0.009
article logging00:00:11.446 0.0%597520 0.011 0.019 0.036
article parse00:00:27.650 0.0%2142256 0.008 0.013 0.020
article write00:18:58.366 0.7%588352 0.845 1.935 10.559
artlog/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
data move00:00:02.484 0.0%3011196 0.000 0.001 0.002
hisgrep/artcncl00:00:01.832 0.0%102 0.000 17.961 38.000
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:32:15.690 1.1%684788 0.231 2.827 16.086
history sync00:00:00.034 0.0%764 0.000 0.045 0.500
history write00:36:35.560 1.3%588358 0.081 3.732 17.543
idle46:01:39.898 96.5%2299244 38.127 72.067 180.928
nntp read00:00:24.730 0.0%1976806 0.007 0.013 0.018
overview write00:00:01.500 0.0%588352 0.000 0.003 0.006
perl filter00:09:49.240 0.3%588358 0.782 1.001 1.253
python filter00:00:00.174 0.0%588358 0.000 0.000 0.002
site send00:00:12.524 0.0%1187992 0.007 0.011 0.015
TOTAL: 47:42:08.60847:40:47.038100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.018 0.0%11280 0.000 0.002 0.034
article prepare00:00:00.000 0.0%1040 0.000 0.000 0.000
article read00:00:00.006 0.0%260 0.000 0.023 1.000
backlog stats00:00:00.012 0.0%60832 0.000 0.000 0.015
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.292 0.0%28904 0.000 0.010 0.056
data write00:00:00.868 0.0%17634 0.000 0.049 0.222
idle47:40:32.516100.0%60832 1453.137 2821.418 10005.383
status file00:00:00.142 0.0%326 0.000 0.436 2.000
TOTAL: 47:40:34.52447:40:33.854100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:04:39.014 63.6%9030 0.000 30.899 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.274 0.1%30314 0.000 0.009 0.500
readart00:00:00.000 0.0%16 0.000 0.000 0.000
TOTAL: 00:07:18.67000:04:39.288 63.7%----

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.org004404
TOTAL004404

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 224 612798 591998 11670 9130 96%79:59:50
2news.icm.edu.pl 68 124 6 112 6 4%14:15:24
3localhost 2 0 0 0 0 0%00:00:00
TOTAL: 3 294 612922 592004 11782 9136 96%94:15:14
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl6.5 GB155.5 MB0.0 KB6.6 GB 97%11.5 KB
2news.icm.edu.pl302.3 KB0.0 KB638.8 KB941.1 KB 32%78.4 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 36.5 GB155.5 MB638.8 KB6.6 GB 97%11.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 15 04:15:01 - 04:59:59 8012 2.7% 2.9774.5 MB 2.3% 28.26
Jun 15 05:00:00 - 05:59:59 12664 4.3% 3.52120.1 MB 3.6% 34.17
Jun 15 06:00:00 - 06:59:59 10622 3.6% 2.95108.4 MB 3.3% 30.84
Jun 15 07:00:00 - 07:59:59 11897 4.0% 3.30131.8 MB 4.0% 37.50
Jun 15 08:00:00 - 08:59:59 12643 4.3% 3.51134.4 MB 4.1% 38.24
Jun 15 09:00:00 - 09:59:59 12417 4.2% 3.45127.1 MB 3.8% 36.16
Jun 15 10:00:00 - 10:59:59 14655 4.9% 4.07153.6 MB 4.6% 43.70
Jun 15 11:00:00 - 11:59:59 11854 4.0% 3.29128.9 MB 3.9% 36.66
Jun 15 12:00:00 - 12:59:59 12571 4.2% 3.49135.4 MB 4.1% 38.53
Jun 15 13:00:00 - 13:59:59 14297 4.8% 3.97141.8 MB 4.3% 40.34
Jun 15 14:00:00 - 14:59:59 11120 3.8% 3.09122.3 MB 3.7% 34.80
Jun 15 15:00:00 - 15:59:59 14000 4.7% 3.89172.1 MB 5.2% 48.96
Jun 15 16:00:00 - 16:59:59 13605 4.6% 3.78159.4 MB 4.8% 45.33
Jun 15 17:00:00 - 17:59:59 12071 4.1% 3.35151.2 MB 4.6% 43.02
Jun 15 18:00:00 - 18:59:59 13463 4.5% 3.74166.8 MB 5.0% 47.45
Jun 15 19:00:00 - 19:59:59 13690 4.6% 3.80185.6 MB 5.6% 52.80
Jun 15 20:00:00 - 20:59:59 11526 3.9% 3.20147.4 MB 4.5% 41.94
Jun 15 21:00:00 - 21:59:59 12580 4.2% 3.49137.1 MB 4.1% 39.00
Jun 15 22:00:00 - 22:59:59 9754 3.3% 2.71125.9 MB 3.8% 35.82
Jun 15 23:00:00 - 23:59:59 10274 3.5% 2.85111.3 MB 3.4% 31.65
Jun 16 00:00:00 - 00:59:59 11073 3.7% 3.08124.8 MB 3.8% 35.49
Jun 16 01:00:00 - 01:59:59 12103 4.1% 3.36120.5 MB 3.6% 34.27
Jun 16 02:00:00 - 02:59:59 12358 4.2% 3.43145.1 MB 4.4% 41.29
Jun 16 03:00:00 - 03:59:59 13261 4.5% 3.68137.4 MB 4.2% 39.07
Jun 16 04:00:00 - 04:15:01 3645 1.2% 4.0542.8 MB 1.3% 48.66
TOTAL: 24:00:00 296155 100.0% 3.433.2 GB 100.0% 39.18
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 4586 0 0 4567 0 0 0 0 19
2news.icm.edu.pl 3 0 0 0 0 0 0 0 3
TOTAL: 2 4589 0 0 4567 0 0 0 0 22

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 6
TOTAL: 1 6

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 17576 246 11064 14 0 0 1%47:59:40
2poznan 6 0 6 0 0 0 0%01:00:50
TOTAL: 2 17582 246 11070 14 0 0 1%49:00:30
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm653.1 KB62.8 KB715.9 KB0.0 KB/s2.8 KB47:59:40
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s001:00:50
TOTAL: 2653.1 KB62.8 KB715.9 KB0.0 KB/s2.8 KB49:00:30
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1172.16.2.4 576 1625.0 KB 8 0 000:00:34
TOTAL: 1 576 1625.0 KB 8 0 000:07:19

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 810 1625.0 KB 8 0 000:06:50
2*.man.lodz.pl2880 00.0 KB 0 0 000:00:29
TOTAL: 23690 1625.0 KB 8 0 000:07:19

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
95.163.5.83 0.050 0.094 0.00000:04:46
167.94.138.49 0.106 0.092 0.00000:00:56
172.16.2.4 1.956 4.104 0.00000:00:34
nms2.man.lodz.pl 9.494 19.318 0.00000:00:29
162.142.125.205 0.088 0.040 0.00000:00:09
198.235.24.33 0.010 0.020 0.00000:00:07
147.185.132.111 0.002 0.012 0.00000:00:07
80.111.142.213 0.034 0.036 0.00000:00:04
212.51.192.185 0.644 1.228 0.00000:00:02
185.242.226.41 0.008 0.008 0.00000:00:00
TOTAL: 10 12.392 24.952 0.00000:07:19

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 2880
212.51.192.185 192
95.163.5.83 12
167.94.138.49 10
80.111.142.213 8
162.142.125.205 6
147.185.132.111 2
198.235.24.33 2
185.242.226.41 2
TOTAL: 9 3114

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

SystemConn
185.242.226.41 4
162.142.125.205 2
167.94.138.49 2
TOTAL: 3 8

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

CommandCount
EHLO www.censys.io 4
#026#003#001 2
#001 2
TOTAL: 3 8

NNRP client timeouts [Top 20]:

SystemConnPeer
162.142.125.205 2 4
TOTAL: 1 2 14

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1pl 16100.0%
TOTAL: 1 16100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 16
TOTAL: 1 16