Daily Usenet report

Dec 1 04:15:01 -- Dec 2 04:15:02

Unknown entries from news log file:

First 24 / 24 lines (100.0%)

Dec 1 08:37:02 newsfeed nnrpd[930108]: SERVER perl filter not defined
Dec 1 08:37:02 newsfeed nnrpd[930108]: SERVER perl filter not defined
Dec 1 11:56:55 newsfeed nnrpd[953741]: SERVER perl filter not defined
Dec 1 11:56:55 newsfeed nnrpd[953741]: SERVER perl filter not defined
Dec 1 11:58:51 newsfeed nnrpd[954011]: SERVER perl filter not defined
Dec 1 11:58:51 newsfeed nnrpd[954011]: SERVER perl filter not defined
Dec 1 16:52:04 newsfeed nnrpd[988888]: SERVER perl filter not defined
Dec 1 16:52:04 newsfeed nnrpd[988888]: SERVER perl filter not defined
Dec 1 17:39:21 newsfeed nnrpd[994560]: SERVER perl filter not defined
Dec 1 17:39:21 newsfeed nnrpd[994560]: SERVER perl filter not defined
Dec 1 18:15:02 newsfeed pgpverify[998612]: pgpverify: /usr/bin/gpg1: no such file
Dec 1 18:15:02 newsfeed pgpverify[998612]: pgpverify: /usr/bin/gpg1: no such file
Dec 1 19:19:27 newsfeed nnrpd[1006447]: SERVER perl filter not defined
Dec 1 19:19:27 newsfeed nnrpd[1006447]: SERVER perl filter not defined
Dec 1 19:37:25 newsfeed nnrpd[1008631]: SERVER perl filter not defined
Dec 1 19:37:25 newsfeed nnrpd[1008631]: SERVER perl filter not defined
Dec 1 20:15:36 newsfeed nnrpd[1013168]: SERVER perl filter not defined
Dec 1 20:15:36 newsfeed nnrpd[1013168]: SERVER perl filter not defined
Dec 1 21:09:44 newsfeed nnrpd[1019638]: SERVER perl filter not defined
Dec 1 21:09:44 newsfeed nnrpd[1019638]: SERVER perl filter not defined
Dec 1 21:12:41 newsfeed nnrpd[1019972]: SERVER perl filter not defined
Dec 1 21:12:41 newsfeed nnrpd[1019972]: SERVER perl filter not defined
Dec 1 22:36:59 newsfeed nnrpd[1030076]: SERVER perl filter not defined
Dec 1 22:36:59 newsfeed nnrpd[1030076]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn 128621 76.2%14.2 MB 79.2%
innd 22848 13.5%2.1 MB 11.9%
nnrpd 15994 9.5%1.4 MB 8.1%
innfeed 1236 0.7%135.8 KB 0.7%
controlchan 44 0.0%10.2 KB 0.1%
pgpverify 2 0.0%0.2 KB 0.0%
overchan 2 0.0%0.3 KB 0.0%
TOTAL: 7 168747 100.0%17.9 MB100.0%

History cache:

ReasonCount%Count
Do not exist 255196 99.4%
Negative hits 1292 0.5%
Positive hits 160 0.1%
Cache misses 90 0.0%
TOTAL: 4 256738 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.086 0.0%408 0.000 0.211 3.000
article cleanup00:00:00.880 0.0%255948 0.000 0.003 0.010
article logging00:00:03.500 0.0%255990 0.005 0.014 0.028
article parse00:00:05.260 0.0%915760 0.002 0.006 0.011
article write00:03:00.090 0.1%255930 0.023 0.704 87.673
artlog/artcncl00:00:00.004 0.0%80 0.000 0.050 0.091
data move00:00:00.750 0.0%1361394 0.000 0.001 0.002
hisgrep/artcncl00:00:00.008 0.0%488 0.000 0.016 1.000
hishave/artcncl00:00:00.000 0.0%80 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.376 0.0%257512 0.000 0.033 0.429
history sync00:00:00.032 0.0%774 0.000 0.041 0.500
history write00:00:27.380 0.0%255930 0.022 0.107 0.556
idle47:53:29.476 99.6%1129048 73.358 152.703 339.902
nntp read00:00:08.246 0.0%891764 0.004 0.009 0.016
overview write00:00:00.414 0.0%255930 0.000 0.002 0.005
perl filter00:02:23.008 0.1%255930 0.306 0.559 0.743
python filter00:00:00.070 0.0%255952 0.000 0.000 0.002
site send00:00:03.888 0.0%530434 0.003 0.007 0.014
TOTAL: 48:03:58.35447:59:51.468 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.024 0.0%18572 0.000 0.001 0.027
article prepare00:00:00.000 0.0%1504 0.000 0.000 0.000
article read00:00:00.006 0.0%376 0.000 0.016 1.000
backlog stats00:00:00.022 0.0%105688 0.000 0.000 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.376 0.0%52504 0.000 0.007 0.024
data write00:00:00.954 0.0%32940 0.000 0.029 0.083
idle47:57:32.466100.0%105688 909.302 1633.605 4087.912
status file00:00:01.392 0.0%326 0.000 4.270 618.000
TOTAL: 47:57:36.13047:57:35.240100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:53.894 81.7%9064 0.000 12.566 10049.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.292 0.2%30464 0.000 0.010 0.333
readart00:00:00.002 0.0%80 0.000 0.025 0.500
TOTAL: 00:02:19.44400:01:54.188 81.9%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
control@eternal-september.org002002
moderator@dana.de002202
TOTAL004204

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 110 257096 257078 2 16 99%66:44:48
2localhost 24 22 22 0 0 100%00:00:00
3news.icm.edu.pl 84 154 2 150 2 1%17:45:22
TOTAL: 3 218 257272 257102 152 18 99%84:30:10
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl2.8 GB211.9 KB0.0 KB2.8 GB 99%11.3 KB
2news.icm.edu.pl332.8 KB24.4 KB0.0 KB357.1 KB 93%89.3 KB
3localhost31.5 KB0.0 KB0.0 KB31.5 KB100%1.4 KB
TOTAL: 32.8 GB236.3 KB0.0 KB2.8 GB 99%11.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 1 04:15:01 - 04:59:59 3741 2.9% 1.3942.5 MB 3.0% 16.13
Dec 1 05:00:00 - 05:59:59 3465 2.7% 0.9643.2 MB 3.0% 12.29
Dec 1 06:00:00 - 06:59:59 3688 2.9% 1.0244.3 MB 3.1% 12.59
Dec 1 07:00:00 - 07:59:59 4935 3.8% 1.3760.9 MB 4.3% 17.31
Dec 1 08:00:00 - 08:59:59 4332 3.4% 1.2052.1 MB 3.7% 14.81
Dec 1 09:00:00 - 09:59:59 4587 3.6% 1.2750.5 MB 3.6% 14.35
Dec 1 10:00:00 - 10:59:59 4506 3.5% 1.2543.1 MB 3.0% 12.27
Dec 1 11:00:00 - 11:59:59 4187 3.3% 1.1650.5 MB 3.6% 14.37
Dec 1 12:00:00 - 12:59:59 4736 3.7% 1.3254.9 MB 3.9% 15.61
Dec 1 13:00:00 - 13:59:59 6183 4.8% 1.7272.6 MB 5.1% 20.64
Dec 1 14:00:00 - 14:59:59 5953 4.6% 1.6576.8 MB 5.4% 21.83
Dec 1 15:00:00 - 15:59:59 6924 5.4% 1.9278.7 MB 5.5% 22.38
Dec 1 16:00:00 - 16:59:59 5848 4.5% 1.6263.8 MB 4.5% 18.15
Dec 1 17:00:00 - 17:59:59 6452 5.0% 1.7959.1 MB 4.2% 16.82
Dec 1 18:00:00 - 18:59:59 5230 4.1% 1.4558.7 MB 4.1% 16.69
Dec 1 19:00:00 - 19:59:59 6084 4.7% 1.6971.8 MB 5.1% 20.44
Dec 1 20:00:00 - 20:59:59 5632 4.4% 1.5667.8 MB 4.8% 19.27
Dec 1 21:00:00 - 21:59:59 6168 4.8% 1.7174.5 MB 5.2% 21.18
Dec 1 22:00:00 - 22:59:59 6380 5.0% 1.7772.9 MB 5.1% 20.74
Dec 1 23:00:00 - 23:59:59 5453 4.2% 1.5169.6 MB 4.9% 19.80
Dec 2 00:00:00 - 00:59:59 4431 3.4% 1.2341.4 MB 2.9% 11.78
Dec 2 01:00:00 - 01:59:59 4488 3.5% 1.2548.1 MB 3.4% 13.68
Dec 2 02:00:00 - 02:59:59 6280 4.9% 1.7457.7 MB 4.1% 16.41
Dec 2 03:00:00 - 03:59:59 6777 5.3% 1.8846.1 MB 3.2% 13.12
Dec 2 04:00:00 - 04:15:02 2091 1.6% 2.3218.1 MB 1.3% 20.58
TOTAL: 24:00:01 128551 100.0% 1.491.4 GB 100.0% 16.82
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 29 0 0 8 0 0 0 0 21
2news.icm.edu.pl 1 0 0 1 0 0 0 0 0
TOTAL: 2 30 0 0 9 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 35140 352 18198 24 0 10 1%47:59:58
2poznan 4 0 2 0 0 0 0%00:40:10
TOTAL: 2 35144 352 18200 24 0 10 1%48:40:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm722.6 KB319.9 KB1.0 MB0.0 KB/s2.8 KB47:59:58
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:40:10
TOTAL: 2722.6 KB319.9 KB1.0 MB0.0 KB/s2.8 KB48:40:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
166.61.98.95 32 4446.8 KB 4 0 000:00:44
2172.16.2.4 576 3661.2 KB 18 0 000:00:34
3isvcs01.man.lodz.pl 22 00.0 KB 0 22 000:00:01
TOTAL: 3 630 80108.0 KB 22 22 000:02:20

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 628 80108.0 KB 22 0 000:01:35
2*.man.lodz.pl2878 00.0 KB 0 22 000:00:24
3*.stretchoid.com 4 00.0 KB 0 0 000:00:20
TOTAL: 33510 80108.0 KB 22 22 000:02:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
66.61.98.95 0.080 0.144 0.00000:00:44
172.16.2.4 1.204 2.854 0.00000:00:34
azpdsgqlmhw6.stretchoid.com 0.012 0.014 0.00000:00:20
nms1.man.lodz.pl 4.826 10.286 0.00000:00:18
80.111.142.213 0.026 0.032 0.00000:00:04
nms2.man.lodz.pl 1.116 2.270 0.00000:00:04
147.185.132.19 0.004 0.008 0.00000:00:04
207.90.244.28 0.008 0.006 0.00000:00:02
205.210.31.26 0.004 0.010 0.00000:00:02
isvcs01.man.lodz.pl 0.060 0.126 0.00000:00:01
167.94.138.187 0.070 0.006 0.00000:00:01
147.185.132.213 0.002 0.010 0.00000:00:00
141.98.83.86 0.004 0.010 0.00000:00:00
TOTAL: 13 7.416 15.776 0.00000:02:20

Curious NNRP server explorers [Top 20]:

SystemConn
nms1.man.lodz.pl 2332
nms2.man.lodz.pl 524
80.111.142.213 8
azpdsgqlmhw6.stretchoid.com 4
205.210.31.26 2
141.98.83.86 2
147.185.132.213 2
207.90.244.28 2
167.94.138.187 2
147.185.132.19 2
TOTAL: 10 2880

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

SystemConn
167.94.138.187 2
azpdsgqlmhw6.stretchoid.com 2
141.98.83.86 2
TOTAL: 3 6

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

CommandCount
MGLNDD_212.191.2.21_119 2
EHLO www.censys.io 2
#003 2
TOTAL: 3 6

NNRP client timeouts [Top 20]:

SystemConnPeer
141.98.83.86 2 2
TOTAL: 1 2 8

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1lodman 44 55.0%
2pl 36 45.0%
TOTAL: 2 80100.0%

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

NewsgroupCount
1lodman.info 44
2pl.comp.os.ms-windows.winnt 36
TOTAL: 2 80