Daily Usenet report

Oct 15 04:15:02 -- Oct 16 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Oct 15 04:17:23 newsfeed innd: message repeated 2 times: [ ctlinnd command s]
Oct 15 04:47:36 newsfeed overchan[241090]: input line too long, skipping
Oct 15 06:37:31 newsfeed overchan[241090]: input line too long, skipping
Oct 15 09:11:30 newsfeed nnrpd[256555]: SERVER perl filter not defined
Oct 15 09:12:55 newsfeed nnrpd[256569]: SERVER perl filter not defined
Oct 15 12:01:12 newsfeed overchan[241090]: input line too long, skipping
Oct 15 14:32:38 newsfeed nnrpd[272578]: SERVER perl filter not defined
Oct 15 16:09:03 newsfeed nnrpd[277404]: SERVER perl filter not defined
Oct 15 17:00:04 newsfeed pgpverify[280046]: pgpverify: /usr/bin/gpg1: no such file
Oct 15 17:00:05 newsfeed pgpverify[280058]: pgpverify: /usr/bin/gpg1: no such file
Oct 16 01:05:42 newsfeed overchan[241090]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 654996 94.3%72.7 MB 93.6%
innfeed 28979 4.2%3.5 MB 4.4%
innd 7544 1.1%1.2 MB 1.5%
nnrpd 3362 0.5%327.7 KB 0.4%
controlchan 23 0.0%5.0 KB 0.0%
overchan 5 0.0%0.9 KB 0.0%
pgpverify 2 0.0%0.4 KB 0.0%
TOTAL: 7 694911 100.0%77.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 650559 99.6%
Negative hits 2223 0.3%
Positive hits 170 0.0%
Cache misses 16 0.0%
TOTAL: 4 652968 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.751 0.0%49 0.000 15.327 159.000
article cleanup00:00:04.770 0.0%654477 0.004 0.007 0.011
article logging00:00:15.651 0.0%654761 0.018 0.024 0.032
article parse00:08:11.916 0.6%88196451 0.004 0.006 0.008
article write00:05:17.654 0.4%654204 0.218 0.486 0.856
artlog/artcncl00:00:00.000 0.0%49 0.000 0.000 0.000
data move00:01:05.968 0.1%93420619 0.000 0.001 0.001
hisgrep/artcncl00:00:00.181 0.0%49 0.000 3.694 73.000
hishave/artcncl00:00:00.019 0.0%49 0.000 0.388 13.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:07:09.717 0.5%657088 0.169 0.654 3.275
history sync00:00:00.006 0.0%290 0.000 0.021 0.500
history write00:07:03.453 0.5%654472 0.279 0.647 2.826
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle22:18:45.805 93.0%87783369 0.565 0.915 1.740
nntp read00:24:46.404 1.7%87804681 0.013 0.017 0.021
overview write00:00:01.923 0.0%654204 0.000 0.003 0.005
perl filter00:36:48.295 2.6%654472 1.645 3.374 4.165
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:18.091 0.0%1317698 0.011 0.014 0.018
TOTAL: 24:00:05.13723:49:50.604 99.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.032 0.0%33162 0.000 0.001 0.008
article prepare00:00:00.001 0.0%12362 0.000 0.000 0.007
article read00:00:13.241 0.0%3105 0.000 4.264 34.000
backlog stats00:00:00.046 0.0%86238 0.000 0.001 0.004
callbacks00:00:00.000 0.0%26 0.000 0.000 0.000
data read00:00:00.409 0.0%41005 0.000 0.010 0.033
data write00:00:00.929 0.0%32168 0.004 0.029 0.073
idle23:55:46.146100.0%86238 673.562 998.935 2273.375
status file00:00:07.109 0.0%9812 0.367 0.725 7.328
TOTAL: 23:56:10.49323:56:07.913100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:27.347 29.2%2769 0.000 9.876 9157.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.069 0.1%5874 0.000 0.012 0.500
readart00:00:00.000 0.0%12 0.000 0.000 0.000
TOTAL: 00:01:33.75300:00:27.416 29.2%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org002202
TOTAL002202

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 102 654589 654337 0 252 99%74:32:13
2news.icm.edu.pl 46 177 23 132 22 12%13:16:33
3localhost 1013 4 4 0 0 100%00:00:00
TOTAL: 3 1161 654770 654364 132 274 99%87:48:46
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl319.2 GB277.4 KB1.0 MB319.2 GB 99%511.3 KB
2news.icm.edu.pl1.6 MB0.0 KB1.3 MB2.9 MB 55%66.6 KB
3localhost6.0 KB0.0 KB0.0 KB6.0 KB100%1.5 KB
TOTAL: 3319.2 GB277.4 KB2.3 MB319.2 GB 99%511.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 15 04:15:02 - 04:59:59 16932 2.6% 6.288.9 GB 2.8%3476.42
Oct 15 05:00:00 - 05:59:59 21045 3.2% 5.8512.4 GB 3.9%3607.16
Oct 15 06:00:00 - 06:59:59 22761 3.5% 6.3213.0 GB 4.1%3795.85
Oct 15 07:00:00 - 07:59:59 21959 3.4% 6.1012.7 GB 4.0%3699.60
Oct 15 08:00:00 - 08:59:59 21533 3.3% 5.9811.9 GB 3.7%3470.00
Oct 15 09:00:00 - 09:59:59 23203 3.5% 6.4511.8 GB 3.7%3447.24
Oct 15 10:00:00 - 10:59:59 25168 3.8% 6.9913.9 GB 4.3%4040.98
Oct 15 11:00:00 - 11:59:59 26520 4.1% 7.3714.5 GB 4.5%4231.17
Oct 15 12:00:00 - 12:59:59 27458 4.2% 7.6314.1 GB 4.4%4117.16
Oct 15 13:00:00 - 13:59:59 24795 3.8% 6.8913.3 GB 4.2%3881.51
Oct 15 14:00:00 - 14:59:59 25050 3.8% 6.9613.2 GB 4.1%3858.95
Oct 15 15:00:00 - 15:59:59 26370 4.0% 7.3313.7 GB 4.3%3977.36
Oct 15 16:00:00 - 16:59:59 52192 8.0% 14.5015.0 GB 4.7%4355.67
Oct 15 17:00:00 - 17:59:59 28091 4.3% 7.8014.4 GB 4.5%4196.35
Oct 15 18:00:00 - 18:59:59 24855 3.8% 6.9013.9 GB 4.4%4057.78
Oct 15 19:00:00 - 19:59:59 41226 6.3% 11.4513.5 GB 4.2%3945.41
Oct 15 20:00:00 - 20:59:59 26572 4.1% 7.3813.1 GB 4.1%3825.49
Oct 15 21:00:00 - 21:59:59 44232 6.8% 12.2913.3 GB 4.2%3871.92
Oct 15 22:00:00 - 22:59:59 24947 3.8% 6.9312.9 GB 4.0%3761.06
Oct 15 23:00:00 - 23:59:59 26210 4.0% 7.2813.3 GB 4.2%3872.27
Oct 16 00:00:00 - 00:59:59 26034 4.0% 7.2314.4 GB 4.5%4185.55
Oct 16 01:00:00 - 01:59:59 24602 3.8% 6.8313.2 GB 4.1%3831.56
Oct 16 02:00:00 - 02:59:59 24663 3.8% 6.8513.2 GB 4.1%3832.05
Oct 16 03:00:00 - 03:59:59 22279 3.4% 6.1912.5 GB 3.9%3635.56
Oct 16 04:00:00 - 04:15:02 5688 0.9% 6.313.3 GB 1.0%3845.50
TOTAL: 24:00:00 654385 100.0% 7.57319.5 GB 100.0%3877.77
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 538 239 0 5 8 0 0 0 286
2news.icm.edu.pl 22 0 0 0 0 0 0 0 22
TOTAL: 2 560 239 0 5 8 0 0 0 308

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 150
it.tlc.cellulari.android 20
comp.mobile.android 18
alt.comp.software.firefox 18
it.comp.os.linux.ubuntu 14
it.comp.os.win.windows10 10
alt.comp.software.seamonkey 8
nl.comp.os.linux.techniek 1
TOTAL: 8 239

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

GroupsCount
pl.praca.oferowana 8
TOTAL: 1 8

INND Perl filter [Top 20]:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 15291 2288 6570 140 0 1 14%23:59:56
2panorama 25459 172 8978 169 41 23875 0%23:59:33
3okapi 0 0 0 0 0 1173 0%23:57:47
4nask 0 0 0 0 0 6589 0%23:57:47
5poznan 25 0 22 0 0 0 0%01:55:13
TOTAL: 5 40775 2460 15570 309 41 31638 6%97:50:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm9.0 MB850.0 KB9.8 MB0.1 KB/s4.2 KB23:59:56
2panorama1002.7 KB2.2 MB3.2 MB0.0 KB/s9.6 KB23:59:33
3okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
5poznan0.0 KB0.0 KB0.0 KB0.0 KB/s001:55:13
TOTAL: 510.0 MB3.0 MB13.0 MB0.0 KB/s4.8 KB97:50:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1211.9 KB 5 0 000:00:09
2isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 2 292 1211.9 KB 5 4 000:01:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 292 1211.9 KB 5 4 000:00:09
2unresolved 107 00.0 KB 0 0 000:01:23
3*.ch1.censys-scanner.com 2 00.0 KB 0 0 000:00:00
TOTAL: 3 401 1211.9 KB 5 4 000:01:33

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
23.225.180.204 0.035 0.011 0.00000:01:20
web.man.lodz.pl 0.959 1.357 0.00000:00:09
80.111.142.213 0.020 0.018 0.00000:00:01
212.51.192.185 0.295 0.401 0.00000:00:01
scanner-25.ch1.censys-scanner.com 0.000 0.018 0.00000:00:00
209.126.151.122 0.002 0.010 0.00000:00:00
isvcs01.man.lodz.pl 0.033 0.012 0.00000:00:00
TOTAL: 7 1.344 1.827 0.00000:01:33

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 95
23.225.180.204 6
80.111.142.213 4
scanner-25.ch1.censys-scanner.com 2
209.126.151.122 2
TOTAL: 5 109

NNRP unrecognized commands (by host):

SystemConn
23.225.180.204 5
scanner-25.ch1.censys-scanner.com 1
TOTAL: 2 6

NNRP unrecognized commands (by command):

CommandCount
#001 1
Host: 212.191.2.21:119 1
EHLO www.censys.io 1
#026#003#001 1
GET / HTTP/1.1 1
Cookie: rememberMe=1 1
TOTAL: 6 6

NNRP client timeouts [Top 20]:

SystemConnPeer
23.225.180.204 1 1
TOTAL: 1 1 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 12100.0%
TOTAL: 1 12100.0%

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

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