Daily Usenet report

May 25 04:15:02 -- May 26 04:15:01

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

May 25 05:02:10 newsfeed overchan[18178]: input line too long, skipping
May 25 06:36:19 newsfeed overchan[18178]: input line too long, skipping
May 25 07:05:29 newsfeed overchan[18178]: input line too long, skipping
May 25 07:33:26 newsfeed overchan[18178]: input line too long, skipping
May 25 09:11:18 newsfeed nnrpd[1435]: SERVER perl filter not defined
May 25 09:12:50 newsfeed nnrpd[1452]: SERVER perl filter not defined
May 25 10:38:41 newsfeed nnrpd[5321]: SERVER perl filter not defined
May 25 12:37:55 newsfeed overchan[18178]: input line too long, skipping
May 25 14:52:25 newsfeed overchan[18178]: input line too long, skipping
May 25 15:16:57 newsfeed nnrpd[18012]: SERVER perl filter not defined
May 25 16:22:58 newsfeed overchan[18178]: input line too long, skipping
May 25 19:29:02 newsfeed overchan[18178]: input line too long, skipping
May 25 19:30:45 newsfeed overchan[18178]: input line too long, skipping
May 25 20:15:02 newsfeed pgpverify[31741]: pgpverify: verification failed
May 25 20:15:02 newsfeed pgpverify[31749]: pgpverify: verification failed
May 25 20:15:02 newsfeed pgpverify[31757]: pgpverify: verification failed
May 25 22:37:39 newsfeed nnrpd[5716]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn1266431 98.5%145.5 MB 98.3%
innd 12351 1.0%1.8 MB 1.2%
innfeed 3560 0.3%453.3 KB 0.3%
nnrpd 3532 0.3%333.2 KB 0.2%
overchan 11 0.0%1.9 KB 0.0%
controlchan 4 0.0%0.3 KB 0.0%
pgpverify 3 0.0%0.5 KB 0.0%
TOTAL: 71285892 100.0%148.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1259949 99.5%
Negative hits 5388 0.4%
Positive hits 366 0.0%
Cache misses 28 0.0%
TOTAL: 4 1265731 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.436 0.0%47 0.000 9.277 27.625
article cleanup00:00:07.707 0.0%1263419 0.004 0.006 0.010
article logging00:00:25.200 0.0%1263771 0.016 0.020 0.030
article parse00:10:20.199 0.7%154791936 0.003 0.004 0.006
article write00:05:38.245 0.4%1263253 0.165 0.268 0.433
artlog/artcncl00:00:00.002 0.0%46 0.000 0.043 0.182
data move00:01:53.093 0.1%162761242 0.001 0.001 0.001
hisgrep/artcncl00:00:00.055 0.0%47 0.000 1.170 10.000
hishave/artcncl00:00:00.031 0.0%47 0.000 0.660 3.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:27:40.115 1.9%1269280 0.084 1.308 11.123
history sync00:00:00.010 0.0%290 0.000 0.034 0.500
history write00:09:56.822 0.7%1263402 0.151 0.472 6.582
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle21:20:49.153 88.9%152380655 0.375 0.504 1.060
nntp read00:28:23.451 2.0%154017861 0.010 0.011 0.014
overview write00:00:04.029 0.0%1263253 0.001 0.003 0.005
perl filter00:57:07.285 4.0%1263402 2.099 2.713 3.642
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:29.734 0.0%2537368 0.010 0.012 0.016
TOTAL: 24:00:01.78523:42:55.567 98.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.047 0.0%13289 0.000 0.004 0.034
article prepare00:00:00.005 0.0%11656 0.000 0.000 0.014
article read00:00:00.111 0.0%2889 0.000 0.038 1.000
backlog stats00:00:00.120 0.0%96984 0.000 0.001 0.007
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.478 0.0%49210 0.000 0.010 0.033
data write00:00:01.192 0.0%38781 0.000 0.031 0.072
idle23:58:00.077100.0%96984 514.071 889.632 2980.722
status file00:00:06.392 0.0%1032 0.000 6.194 122.880
TOTAL: 23:58:10.06523:58:08.422100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:15.053 17.4%2819 0.000 26.624 10048.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.099 0.0%6041 0.000 0.016 0.500
readart00:00:00.050 0.0%12 0.000 4.167 25.000
TOTAL: 00:07:10.93300:01:15.202 17.5%----

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
moderator@dana.de000300
TOTAL000300

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 210 12661041265963 0 141 99%95:08:18
2news.icm.edu.pl 42 375 25 326 24 6%20:07:05
3localhost 1443 5 5 0 0 100%00:00:00
TOTAL: 3 1695 12664841265993 326 165 99%115:15:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl367.4 GB993.2 KB1.2 MB367.4 GB 99%304.2 KB
2news.icm.edu.pl862.0 KB21.4 KB609.8 KB1.5 MB 57%30.5 KB
3localhost7.2 KB0.0 KB0.0 KB7.2 KB100%1.4 KB
TOTAL: 3367.4 GB1014.6 KB1.8 MB367.4 GB 99%304.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 25 04:15:02 - 04:59:59 23403 1.8% 8.679.2 GB 2.5%3593.69
May 25 05:00:00 - 05:59:59 30952 2.4% 8.6013.4 GB 3.6%3901.63
May 25 06:00:00 - 06:59:59 38701 3.1% 10.7515.1 GB 4.1%4411.74
May 25 07:00:00 - 07:59:59 36894 2.9% 10.2514.9 GB 4.0%4330.46
May 25 08:00:00 - 08:59:59 38110 3.0% 10.5915.6 GB 4.3%4557.24
May 25 09:00:00 - 09:59:59 68708 5.4% 19.0916.3 GB 4.4%4756.30
May 25 10:00:00 - 10:59:59 43256 3.4% 12.0216.5 GB 4.5%4802.67
May 25 11:00:00 - 11:59:59 38850 3.1% 10.7915.3 GB 4.1%4443.73
May 25 12:00:00 - 12:59:59 35892 2.8% 9.9714.9 GB 4.1%4342.00
May 25 13:00:00 - 13:59:59 34617 2.7% 9.6215.3 GB 4.2%4466.04
May 25 14:00:00 - 14:59:59 87009 6.9% 24.1717.4 GB 4.7%5070.69
May 25 15:00:00 - 15:59:59 97501 7.7% 27.0818.1 GB 4.9%5268.88
May 25 16:00:00 - 16:59:59 86963 6.9% 24.1618.1 GB 4.9%5259.60
May 25 17:00:00 - 17:59:59 43733 3.5% 12.1514.6 GB 4.0%4259.01
May 25 18:00:00 - 18:59:59 83406 6.6% 23.1716.9 GB 4.6%4919.02
May 25 19:00:00 - 19:59:59 45825 3.6% 12.7314.6 GB 4.0%4248.73
May 25 20:00:00 - 20:59:59 37793 3.0% 10.5014.1 GB 3.8%4111.90
May 25 21:00:00 - 21:59:59 54520 4.3% 15.1414.2 GB 3.9%4149.44
May 25 22:00:00 - 22:59:59 51706 4.1% 14.3614.1 GB 3.8%4107.66
May 25 23:00:00 - 23:59:59 59867 4.7% 16.6314.2 GB 3.9%4131.45
May 26 00:00:00 - 00:59:59 74213 5.9% 20.6116.2 GB 4.4%4711.98
May 26 01:00:00 - 01:59:59 55544 4.4% 15.4314.6 GB 4.0%4248.21
May 26 02:00:00 - 02:59:59 46398 3.7% 12.8915.4 GB 4.2%4476.69
May 26 03:00:00 - 03:59:59 41842 3.3% 11.6214.8 GB 4.0%4317.85
May 26 04:00:00 - 04:15:01 10167 0.8% 11.283.8 GB 1.0%4387.74
TOTAL: 23:59:59 1265870 100.0% 14.65367.6 GB 100.0%4461.90
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 491 126 0 15 1 0 0 0 349
2news.icm.edu.pl 24 0 0 2 0 0 0 0 22
TOTAL: 2 515 126 0 17 1 0 0 0 371

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 40
it.tlc.cellulari.android 27
comp.mobile.android 25
alt.comp.software.firefox 21
it.comp.os.win.windows10 5
it.comp.os.linux.ubuntu 4
alt.comp.software.seamonkey 2
comp.sys.raspberry-pi 1
comp.mobile.ipad 1
TOTAL: 9 126

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

GroupsCount
pl.praca.oferowana 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 18
Binary in non-binary group 3
Scoring filter (8) 1
TOTAL: 3 22

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 17481 2286 7708 525 0 222 13%24:08:18
2panorama 19816 71 10769 42 0 2195 0%24:00:20
3okapi 0 0 0 0 0 823 0%24:04:00
4nask 0 0 0 0 0 7224 0%24:04:00
5poznan 18 0 17 0 0 0 0%00:40:21
TOTAL: 5 37315 2357 18494 567 0 10464 6%96:56:59
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm10.1 MB93.9 MB104.0 MB1.2 KB/s37.9 KB24:08:18
2panorama544.4 KB936.1 KB1.4 MB0.0 KB/s13.1 KB24:00:20
3okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:04:00
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s024:04:00
5poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:40:21
TOTAL: 510.6 MB94.8 MB105.5 MB0.3 KB/s36.9 KB96:56:59
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
okapi488.3 KB
TOTAL: 1488.3 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1219.6 KB 6 0 000:00:11
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 2 293 1219.6 KB 6 5 000:07:10

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 293 1219.6 KB 6 5 000:00:12
2*.ip.linodeusercontent.com 37 00.0 KB 0 0 000:06:43
3unresolved 110 00.0 KB 0 0 000:00:15
TOTAL: 3 440 1219.6 KB 6 5 000:07:10

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
172-104-140-107.ip.linodeusercontent.com 0.183 0.161 0.00000:06:43
web.man.lodz.pl 0.996 1.581 0.00000:00:11
192.241.213.240 0.004 0.004 0.00000:00:09
80.111.142.213 0.015 0.023 0.00000:00:02
164.92.83.33 0.016 0.046 0.00000:00:01
212.51.192.185 0.304 0.420 0.00000:00:00
79.124.62.106 0.001 0.007 0.00000:00:00
isvcs01.man.lodz.pl 0.029 0.023 0.00000:00:00
TOTAL: 8 1.548 2.265 0.00000:07:10

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
172-104-140-107.ip.linodeusercontent.com 37
164.92.83.33 8
80.111.142.213 4
79.124.62.106 1
192.241.213.240 1
TOTAL: 6 147

NNRP unrecognized commands (by host):

SystemConn
164.92.83.33 42
172-104-140-107.ip.linodeusercontent.com 2
79.124.62.106 1
TOTAL: 3 45

NNRP unrecognized commands (by command):

CommandCount
Accept-Encoding: gzip, deflate 7
Connection: keep-alive 7
Host: 212.191.2.21:119 7
Accept: */* 7
User-Agent: Mozilla/5.0 (Windows NT 10.0... 6
À#024 1
GET /stream/live.php HTTP/1.1 1
User-Agent: Mozilla/5.0 (Windows NT 6.1;... 1
#003 1
GET /flu/403.html HTTP/1.1 1
GET /c/version.js HTTP/1.1 1
GET /system_api.php HTTP/1.1 1
GET /stalker_portal/c/version.js HTTP/1.... 1
GET /streaming/clients_live.php HTTP/1.1 1
GET / HTTP/1.1 1
#026#003#001#002 1
TOTAL: 16 45

NNRP client timeouts [Top 20]:

SystemConnPeer
79.124.62.106 1 1
172-104-140-107.ip.linodeusercontent.com 1 1
TOTAL: 2 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 12100.0%
TOTAL: 1 12100.0%

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

NewsgroupCount
1pl.comp.pecet 10
2pl.comp.os.ms-windows.winnt 2
TOTAL: 2 12