Daily Usenet report

Sep 1 04:15:02 -- Sep 2 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Sep 1 07:54:27 newsfeed nnrpd[483978]: SERVER perl filter not defined
Sep 1 08:02:53 newsfeed overchan[472072]: input line too long, skipping
Sep 1 10:00:01 newsfeed overchan[472072]: input line too long, skipping
Sep 1 10:51:33 newsfeed overchan[472072]: input line too long, skipping
Sep 1 15:27:58 newsfeed overchan[472072]: input line too long, skipping
Sep 1 15:46:32 newsfeed nnrpd[508183]: SERVER perl filter not defined
Sep 1 15:47:19 newsfeed nnrpd[508193]: SERVER perl filter not defined
Sep 1 20:42:32 newsfeed overchan[472072]: input line too long, skipping
Sep 1 23:00:01 newsfeed pgpverify[530018]: pgpverify: /usr/bin/gpg1: no such file
Sep 2 01:51:15 newsfeed overchan[472072]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 596152 94.6%66.4 MB 94.1%
innfeed 21814 3.5%2.6 MB 3.7%
innd 8603 1.4%1.3 MB 1.8%
nnrpd 3380 0.5%319.1 KB 0.4%
controlchan 12 0.0%2.4 KB 0.0%
overchan 7 0.0%1.0 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 629969 100.0%70.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 591435 99.6%
Negative hits 1938 0.3%
Positive hits 217 0.0%
Cache misses 39 0.0%
TOTAL: 4 593629 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.349 0.0%77 0.000 17.519 89.000
article cleanup00:00:04.131 0.0%595948 0.003 0.007 0.011
article logging00:00:13.244 0.0%596269 0.015 0.022 0.031
article parse00:06:41.183 0.5%116118049 0.003 0.003 0.004
article write00:04:32.235 0.3%595810 0.299 0.457 0.782
artlog/artcncl00:00:00.001 0.0%77 0.000 0.013 0.077
data move00:00:37.670 0.0%120871396 0.000 0.000 0.001
hisgrep/artcncl00:00:00.199 0.0%76 0.000 2.618 16.500
hishave/artcncl00:00:00.007 0.0%77 0.000 0.091 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:07:29.504 0.5%598310 0.240 0.751 3.753
history sync00:00:00.006 0.0%290 0.000 0.021 0.500
history write00:20:33.118 1.4%595940 0.349 2.069 6.183
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle22:15:15.566 92.7%115956656 0.533 0.691 1.119
nntp read00:22:31.155 1.6%115817395 0.010 0.012 0.014
overview write00:00:01.619 0.0%595810 0.001 0.003 0.005
perl filter00:30:04.770 2.1%595940 2.426 3.028 3.806
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:16.199 0.0%1202360 0.009 0.013 0.017
TOTAL: 24:00:03.21323:48:21.956 99.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.052 0.0%31307 0.000 0.002 0.016
article prepare00:00:00.003 0.0%15582 0.000 0.000 0.011
article read00:00:08.454 0.0%3925 0.000 2.154 25.103
backlog stats00:00:00.038 0.0%88788 0.000 0.000 0.005
callbacks00:00:00.000 0.0%26 0.000 0.000 0.000
data read00:00:00.412 0.0%43013 0.000 0.010 0.024
data write00:00:00.975 0.0%32616 0.008 0.030 0.086
idle23:55:22.741100.0%88788 566.644 969.982 2061.969
status file00:00:04.249 0.0%7163 0.350 0.593 5.950
TOTAL: 23:55:38.95023:55:36.924100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:29.512 60.5%2776 0.000 10.631 10048.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.071 0.1%5883 0.000 0.012 0.500
readart00:00:00.000 0.0%24 0.000 0.000 0.000
TOTAL: 00:00:48.74500:00:29.583 60.7%----

Control commands to INND:

CommandNumber
flush 27
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 193

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 114 595542 595412 0 130 99%66:09:58
2news.icm.edu.pl 50 196 8 180 8 4%16:16:29
3localhost 1444 3 3 0 0 100%00:00:00
TOTAL: 3 1608 595741 595423 180 138 99%82:26:27
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl270.1 GB730.3 KB1.1 MB270.1 GB 99%475.6 KB
2news.icm.edu.pl729.5 KB0.0 KB247.5 KB977.0 KB 74%61.1 KB
3localhost4.7 KB0.0 KB0.0 KB4.7 KB100%1.6 KB
TOTAL: 3270.1 GB730.3 KB1.3 MB270.1 GB 99%475.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 1 04:15:02 - 04:59:59 17820 3.0% 6.609.1 GB 3.4%3541.22
Sep 1 05:00:00 - 05:59:59 18934 3.2% 5.2610.1 GB 3.7%2939.35
Sep 1 06:00:00 - 06:59:59 19563 3.3% 5.4311.1 GB 4.1%3240.96
Sep 1 07:00:00 - 07:59:59 21174 3.6% 5.8810.7 GB 4.0%3129.23
Sep 1 08:00:00 - 08:59:59 24295 4.1% 6.7511.9 GB 4.4%3475.42
Sep 1 09:00:00 - 09:59:59 28038 4.7% 7.7911.7 GB 4.3%3400.03
Sep 1 10:00:00 - 10:59:59 23919 4.0% 6.6411.3 GB 4.2%3282.93
Sep 1 11:00:00 - 11:59:59 26226 4.4% 7.2911.9 GB 4.4%3474.99
Sep 1 12:00:00 - 12:59:59 27371 4.6% 7.6011.9 GB 4.4%3451.59
Sep 1 13:00:00 - 13:59:59 25544 4.3% 7.1010.9 GB 4.0%3187.92
Sep 1 14:00:00 - 14:59:59 28995 4.9% 8.0512.5 GB 4.6%3636.12
Sep 1 15:00:00 - 15:59:59 23845 4.0% 6.6211.7 GB 4.3%3396.53
Sep 1 16:00:00 - 16:59:59 21789 3.7% 6.0510.6 GB 3.9%3098.25
Sep 1 17:00:00 - 17:59:59 27296 4.6% 7.5811.7 GB 4.3%3420.92
Sep 1 18:00:00 - 18:59:59 22585 3.8% 6.2711.0 GB 4.1%3208.51
Sep 1 19:00:00 - 19:59:59 22467 3.8% 6.2411.2 GB 4.1%3260.68
Sep 1 20:00:00 - 20:59:59 27863 4.7% 7.7411.5 GB 4.3%3353.31
Sep 1 21:00:00 - 21:59:59 35556 6.0% 9.8811.1 GB 4.1%3239.83
Sep 1 22:00:00 - 22:59:59 29611 5.0% 8.2312.5 GB 4.6%3641.10
Sep 1 23:00:00 - 23:59:59 25834 4.3% 7.1811.4 GB 4.2%3314.46
Sep 2 00:00:00 - 00:59:59 21672 3.6% 6.0210.2 GB 3.8%2980.85
Sep 2 01:00:00 - 01:59:59 26484 4.4% 7.3610.3 GB 3.8%2992.49
Sep 2 02:00:00 - 02:59:59 23637 4.0% 6.5711.7 GB 4.3%3412.86
Sep 2 03:00:00 - 03:59:59 19440 3.3% 5.409.7 GB 3.6%2834.15
Sep 2 04:00:00 - 04:15:02 5658 0.9% 6.272.9 GB 1.1%3319.66
TOTAL: 24:00:00 595616 100.0% 6.89270.7 GB 100.0%3285.76
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 452 122 0 8 0 0 0 0 322
2news.icm.edu.pl 7 0 0 0 0 0 0 0 7
TOTAL: 2 459 122 0 8 0 0 0 0 329

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 81
alt.comp.software.firefox 11
comp.mobile.android 7
it.tlc.cellulari.android 6
it.comp.os.linux.ubuntu 5
alt.comp.software.seamonkey 4
comp.mobile.ipad 2
it.discussioni.energia 2
de.rec.sf.startrek 2
it.comp.os.win.windows10 1
it.comp.os.win.windows7 1
TOTAL: 11 122

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 4
Scoring filter (9) 2
Too many newsgroups 1
TOTAL: 3 7

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 17189 3029 7061 227 3 0 17%23:59:57
2panorama 20956 156 8868 153 56 22109 0%23:59:58
3okapi 0 0 0 0 0 688 0%23:57:48
4poznan 6 0 4 0 0 0 0%00:22:32
5nask 0 0 0 0 0 7054 0%23:57:48
TOTAL: 5 38151 3185 15933 380 59 29851 8%96:18:03
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm11.7 MB1.1 MB12.7 MB0.2 KB/s4.0 KB23:59:57
2panorama470.6 KB691.7 KB1.1 MB0.0 KB/s3.8 KB23:59:58
3okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:22:32
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
TOTAL: 512.1 MB1.7 MB13.9 MB0.0 KB/s4.0 KB96:18:03
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 2422.7 KB 12 0 000:00:09
2isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:00
TOTAL: 2 291 2422.7 KB 12 3 000:00:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 291 2422.7 KB 12 3 000:00:09
2unresolved 108 00.0 KB 0 0 000:00:38
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 3 400 2422.7 KB 12 3 000:00:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
23.225.163.217 0.010 0.003 0.00000:00:15
45.79.173.230 0.007 0.000 0.00000:00:10
web.man.lodz.pl 0.848 1.398 0.00000:00:09
185.156.72.10 0.011 0.010 0.00000:00:09
80.111.142.213 0.003 0.029 0.00000:00:01
212.51.192.185 0.279 0.414 0.00000:00:01
scanner-25.ch1.censys-scanner.com 0.000 0.012 0.00000:00:00
141.98.9.13 0.000 0.008 0.00000:00:00
198.199.95.12 0.003 0.003 0.00000:00:00
isvcs01.man.lodz.pl 0.016 0.016 0.00000:00:00
TOTAL: 10 1.177 1.893 0.00000:00:48

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
80.111.142.213 4
185.156.72.10 3
23.225.163.217 2
198.199.95.12 1
scanner-25.ch1.censys-scanner.com 1
141.98.9.13 1
45.79.173.230 1
TOTAL: 8 109

NNRP unrecognized commands (by host):

SystemConn
185.156.72.10 3
23.225.163.217 2
141.98.9.13 1
198.199.95.12 1
scanner-25.ch1.censys-scanner.com 1
TOTAL: 5 8

NNRP unrecognized commands (by command):

CommandCount
#003 4
EHLO www.censys.io 1
MGLNDD_212.191.2.21_119 1
#026#003#001 1
#001 1
TOTAL: 5 8

NNRP client timeouts [Top 20]:

SystemConnPeer
185.156.72.10 3 3
23.225.163.217 1 1
141.98.9.13 1 1
TOTAL: 3 5 5

Newsgroup request counts (by category):

CategoryCountPct
1pl 24100.0%
TOTAL: 1 24100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 18
2pl.comp.pecet 6
TOTAL: 2 24