Daily Usenet report

Oct 31 04:15:02 -- Nov 1 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Oct 31 04:20:44 newsfeed overchan[23983]: input line too long, skipping
Oct 31 05:36:02 newsfeed nnrpd[4247]: SERVER perl filter not defined
Oct 31 08:20:15 newsfeed nnrpd[29959]: SERVER perl filter not defined
Oct 31 08:44:21 newsfeed nnrpd[1217]: SERVER perl filter not defined
Oct 31 09:33:10 newsfeed nnrpd[8789]: SERVER perl filter not defined
Oct 31 10:05:18 newsfeed nnrpd[13817]: SERVER perl filter not defined
Oct 31 10:05:31 newsfeed nnrpd[13820]: SERVER perl filter not defined
Oct 31 12:13:08 newsfeed nnrpd[910]: SERVER perl filter not defined
Oct 31 15:19:10 newsfeed nnrpd[29423]: SERVER perl filter not defined
Oct 31 15:53:10 newsfeed overchan[23983]: input line too long, skipping
Oct 31 16:38:41 newsfeed overchan[23983]: input line too long, skipping
Oct 31 17:21:30 newsfeed overchan[23983]: input line too long, skipping
Oct 31 19:59:54 newsfeed overchan[23983]: input line too long, skipping
Oct 31 20:41:18 newsfeed overchan[23983]: input line too long, skipping
Oct 31 20:45:22 newsfeed overchan[23983]: input line too long, skipping
Nov 1 01:04:03 newsfeed pgpverify[22612]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 677243 92.0%73.9 MB 91.1%
innfeed 45332 6.2%5.3 MB 6.6%
innd 9333 1.3%1.4 MB 1.8%
nnrpd 4224 0.6%418.9 KB 0.5%
controlchan 22 0.0%5.3 KB 0.0%
overchan 8 0.0%1.2 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 7 736163 100.0%81.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 675300 99.5%
Negative hits 2813 0.4%
Positive hits 302 0.0%
Cache misses 37 0.0%
TOTAL: 4 678452 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.090 0.0%70 0.000 29.857 183.000
article cleanup00:00:09.695 0.0%676696 0.008 0.014 0.020
article logging00:00:34.436 0.0%677155 0.036 0.051 0.071
article parse00:19:01.718 1.3%32676898 0.021 0.035 0.047
article write00:08:15.593 0.6%676461 0.229 0.733 3.543
artlog/artcncl00:00:00.003 0.0%69 0.000 0.043 1.000
data move00:00:36.071 0.0%37295944 0.001 0.001 0.001
hisgrep/artcncl00:00:00.609 0.0%69 0.000 8.826 37.000
hishave/artcncl00:00:00.148 0.0%70 0.000 2.114 5.750
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:29:24.994 2.0%680036 0.229 2.595 35.350
history sync00:00:00.025 0.0%291 0.000 0.086 1.000
history write00:49:41.720 3.5%676641 0.425 4.407 44.911
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle20:24:45.981 85.0%32137234 1.206 2.287 3.817
nntp read00:27:17.829 1.9%32330931 0.035 0.051 0.062
overview write00:00:03.803 0.0%676461 0.003 0.006 0.008
perl filter01:08:54.627 4.8%676641 3.552 6.111 8.763
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:37.747 0.0%1366695 0.019 0.028 0.037
TOTAL: 24:00:06.43623:49:27.089 99.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.143 0.0%50361 0.000 0.003 0.015
article prepare00:00:00.002 0.0%6306 0.000 0.000 0.014
article read00:00:19.009 0.0%1571 0.000 12.100 567.800
backlog stats00:00:00.115 0.0%115854 0.000 0.001 0.005
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.041 0.0%56954 0.000 0.018 0.039
data write00:00:02.667 0.0%43796 0.025 0.061 0.101
idle23:50:29.237 99.7%115854 487.577 740.840 1367.651
status file00:04:12.273 0.3%16560 0.767 15.234 1087.976
TOTAL: 23:55:11.92923:55:04.487100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:34.852 71.6%3073 0.000 11.341 5041.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.248 0.5%6738 0.000 0.037 0.684
readart00:00:00.001 0.0%6 0.000 0.167 0.500
TOTAL: 00:00:48.64500:00:35.101 72.2%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
news@sfere.anjou.terraraq.org.uk001001
us-control@lists.killfile.org000100
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 170 676265 676125 1 139 99%89:05:21
2news.icm.edu.pl 50 358 47 216 95 13%16:28:47
3localhost 1449 8 8 0 0 100%00:00:01
TOTAL: 3 1669 676631 676180 217 234 99%105:34:09
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl284.1 GB2.7 MB3.0 MB284.1 GB 99%440.5 KB
2news.icm.edu.pl2.8 MB316.1 KB3.9 MB7.0 MB 39%50.5 KB
3localhost11.4 KB0.0 KB0.0 KB11.4 KB100%1.4 KB
TOTAL: 3284.1 GB3.0 MB6.9 MB284.1 GB 99%440.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 31 04:15:02 - 04:59:59 16098 2.4% 5.979.1 GB 3.2%3527.38
Oct 31 05:00:00 - 05:59:59 24691 3.6% 6.8612.0 GB 4.2%3484.87
Oct 31 06:00:00 - 06:59:59 28463 4.2% 7.9112.1 GB 4.2%3515.05
Oct 31 07:00:00 - 07:59:59 24522 3.6% 6.8110.9 GB 3.8%3181.64
Oct 31 08:00:00 - 08:59:59 22578 3.3% 6.2710.1 GB 3.5%2945.73
Oct 31 09:00:00 - 09:59:59 21180 3.1% 5.8810.0 GB 3.5%2921.00
Oct 31 10:00:00 - 10:59:59 19839 2.9% 5.5110.8 GB 3.8%3135.29
Oct 31 11:00:00 - 11:59:59 25330 3.7% 7.0410.7 GB 3.8%3119.63
Oct 31 12:00:00 - 12:59:59 28293 4.2% 7.8612.4 GB 4.3%3607.56
Oct 31 13:00:00 - 13:59:59 23031 3.4% 6.4011.7 GB 4.1%3410.95
Oct 31 14:00:00 - 14:59:59 28095 4.2% 7.8012.0 GB 4.2%3508.78
Oct 31 15:00:00 - 15:59:59 24650 3.6% 6.8512.5 GB 4.4%3636.38
Oct 31 16:00:00 - 16:59:59 25579 3.8% 7.1112.0 GB 4.2%3498.78
Oct 31 17:00:00 - 17:59:59 31538 4.7% 8.7613.7 GB 4.8%3977.68
Oct 31 18:00:00 - 18:59:59 29214 4.3% 8.1212.4 GB 4.4%3618.81
Oct 31 19:00:00 - 19:59:59 32085 4.7% 8.9112.1 GB 4.3%3536.65
Oct 31 20:00:00 - 20:59:59 45280 6.7% 12.5812.7 GB 4.4%3685.42
Oct 31 21:00:00 - 21:59:59 84861 12.5% 23.5714.8 GB 5.2%4307.52
Oct 31 22:00:00 - 22:59:59 24224 3.6% 6.7312.6 GB 4.4%3683.57
Oct 31 23:00:00 - 23:59:59 22595 3.3% 6.2811.5 GB 4.0%3346.59
Nov 1 00:00:00 - 00:59:59 20401 3.0% 5.6710.8 GB 3.8%3152.12
Nov 1 01:00:00 - 01:59:59 22592 3.3% 6.2811.4 GB 4.0%3306.32
Nov 1 02:00:00 - 02:59:59 22117 3.3% 6.1411.3 GB 3.9%3279.30
Nov 1 03:00:00 - 03:59:59 23031 3.4% 6.4012.1 GB 4.3%3533.58
Nov 1 04:00:00 - 04:15:02 6193 0.9% 6.873.5 GB 1.2%4039.30
TOTAL: 24:00:00 676480 100.0% 7.83285.1 GB 100.0%3460.37
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 595 82 0 46 12 0 0 0 455
2news.icm.edu.pl 99 0 0 9 0 0 0 0 90
TOTAL: 2 694 82 0 55 12 0 0 0 545

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 56
comp.mobile.android 9
de.rec.sf.startrek 5
it.tlc.cellulari.android 5
it.comp.os.win.windows10 4
de.comp.office-pakete.ms-office.excel 1
it.comp.os.win.windows7 1
de.comp.office-pakete.ms-office.word 1
TOTAL: 8 82

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
pl.sci.matematyka 8
pl.praca.oferowana 4
TOTAL: 2 12

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 74
Binary in non-binary group 6
HTML post 4
Scoring filter (11) 1
Too many newsgroups 1
TOTAL: 5 86

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 455
news.icm.edu.pl 4
TOTAL: 2 459
TOTAL: 1 459

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 19719 373 12772 310 0 0 1%23:50:34
2panorama 40872 337 13051 128 1 36978 0%23:59:53
3poznan 27 0 22 0 0 0 0%03:33:07
4nask 0 0 0 0 0 9063 0%23:50:02
5okapi 0 0 0 0 0 870 0%23:50:02
TOTAL: 5 60618 710 25845 438 1 46911 1%99:03:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm1.4 MB55.8 MB57.2 MB0.7 KB/s85.7 KB23:50:34
2panorama1000.0 KB745.7 KB1.7 MB0.0 KB/s3.8 KB23:59:53
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s003:33:07
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
TOTAL: 52.4 MB56.5 MB58.9 MB0.2 KB/s52.5 KB99:03:38
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 68.9 KB 3 0 000:00:25
2isvcs01.man.lodz.pl 8 00.0 KB 0 8 000:00:00
TOTAL: 2 296 68.9 KB 3 8 000:00:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 584 68.9 KB 3 8 000:00:31
2*.20.97.83.ro.ovo.sc 1 00.0 KB 0 0 000:00:10
3*.sfj.censys-scanner.com 1 00.0 KB 0 0 000:00:00
4*.census.shodan.io 1 00.0 KB 0 0 000:00:00
5*.security.ipip.net 1 00.0 KB 0 0 000:00:00
6unresolved 100 00.0 KB 0 0 000:00:04
TOTAL: 6 688 68.9 KB 3 8 000:00:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.658 3.641 0.00000:00:25
35.20.97.83.ro.ovo.sc 0.005 0.005 0.00000:00:10
mon.man.lodz.pl 1.343 3.354 0.00000:00:04
80.111.142.213 0.026 0.040 0.00000:00:03
212.51.192.185 0.477 0.939 0.00000:00:01
isvcs01.man.lodz.pl 0.067 0.154 0.00000:00:00
scan-141.security.ipip.net 0.012 0.008 0.00000:00:00
worker-18.sfj.censys-scanner.com 0.003 0.007 0.00000:00:00
red.census.shodan.io 0.011 0.007 0.00000:00:00
TOTAL: 9 3.602 8.155 0.00000:00:48

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
80.111.142.213 4
red.census.shodan.io 1
scan-141.security.ipip.net 1
worker-18.sfj.censys-scanner.com 1
35.20.97.83.ro.ovo.sc 1
TOTAL: 7 392

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
TOTAL: 1 4

NNRP unrecognized commands (by command):

CommandCount
User-Agent: HTTP Banner Detection (https... 1
Host: 212.191.2.21:119 1
GET / HTTP/1.1 1
Connection: close 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
worker-18.sfj.censys-scanner.com 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 6100.0%
TOTAL: 1 6100.0%

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

NewsgroupCount
1pl.comp.pecet 6
TOTAL: 1 6