Daily Usenet report

Mar 31 04:15:02 -- Apr 1 04:15:01

Unknown entries from news log file:

First 26 / 26 lines (100.0%)

Mar 31 08:26:13 newsfeed overchan[1642849]: input line too long, skipping
Mar 31 10:24:44 newsfeed nnrpd[1659827]: SERVER perl filter not defined
Mar 31 10:28:13 newsfeed nnrpd[1659960]: SERVER perl filter not defined
Mar 31 11:56:44 newsfeed overchan[1642849]: input line too long, skipping
Mar 31 12:07:44 newsfeed nnrpd[1664387]: SERVER perl filter not defined
Mar 31 12:34:02 newsfeed nnrpd[1665578]: SERVER perl filter not defined
Mar 31 13:24:57 newsfeed nnrpd[1667830]: SERVER perl filter not defined
Mar 31 15:11:07 newsfeed innfeed[1450]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Mar 31 15:11:07 newsfeed innfeed[1450]: SM: storage method 'cnfs' failed initialization
Mar 31 15:11:07 newsfeed innfeed[1450]: SM: one or more storage methods failed initialization
Mar 31 15:11:07 newsfeed innfeed[1450]: Storage manager initialization failed -- it is OK after a change in storage methods
Mar 31 15:11:07 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Mar 31 15:11:07 newsfeed innd: SM: storage method 'cnfs' failed initialization
Mar 31 15:11:07 newsfeed innd: SM: one or more storage methods failed initialization
Mar 31 15:14:50 newsfeed overchan[1452]: input line too long, skipping
Mar 31 16:11:29 newsfeed overchan[1452]: input line too long, skipping
Mar 31 16:13:15 newsfeed overchan[1452]: input line too long, skipping
Mar 31 16:46:08 newsfeed overchan[1452]: input line too long, skipping
Mar 31 16:47:23 newsfeed overchan[1452]: input line too long, skipping
Mar 31 16:51:42 newsfeed overchan[1452]: input line too long, skipping
Mar 31 16:54:42 newsfeed overchan[1452]: input line too long, skipping
Mar 31 17:14:07 newsfeed overchan[1452]: input line too long, skipping
Mar 31 19:14:57 newsfeed overchan[1452]: input line too long, skipping
Mar 31 19:36:35 newsfeed overchan[1452]: input line too long, skipping
Mar 31 19:54:37 newsfeed overchan[1452]: input line too long, skipping
Apr 1 02:04:02 newsfeed pgpverify[31973]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program nameLines%LinesSize%Size
inn1010609 96.3%110.8 MB 95.9%
innfeed 24301 2.3%2.8 MB 2.5%
nnrpd 7684 0.7%850.2 KB 0.7%
innd 6389 0.6%1.0 MB 0.9%
controlchan 24 0.0%5.5 KB 0.0%
overchan 15 0.0%2.4 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 71049023 100.0%115.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1003231 99.6%
Negative hits 3932 0.4%
Positive hits 232 0.0%
Cache misses 23 0.0%
TOTAL: 4 1007418 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.335 0.0%56 0.000 5.982 30.000
article cleanup00:00:04.202 0.0%1005602 0.002 0.004 0.007
article logging00:00:14.258 0.0%1005940 0.010 0.014 0.022
article parse00:06:16.019 0.4%192489791 0.002 0.002 0.002
article write00:04:18.623 0.3%1005368 0.119 0.257 0.433
artlog/artcncl00:00:00.001 0.0%55 0.000 0.018 0.500
data move00:00:44.280 0.1%199730383 0.000 0.000 0.000
hisgrep/artcncl00:00:00.074 0.0%56 0.000 1.321 9.000
hishave/artcncl00:00:00.004 0.0%56 0.000 0.071 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:10:27.997 0.7%1010361 0.109 0.622 10.412
history sync00:00:00.011 0.0%288 0.000 0.038 1.000
history write00:16:13.091 1.1%1005560 0.150 0.968 10.792
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle22:17:13.981 93.5%191397724 0.299 0.419 0.551
nntp read00:18:10.997 1.3%191805076 0.005 0.006 0.007
overview write00:00:01.886 0.0%1005368 0.001 0.002 0.004
perl filter00:28:15.382 2.0%1005560 1.305 1.686 2.296
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:16.072 0.0%2019819 0.006 0.008 0.013
TOTAL: 23:50:02.19823:42:17.213 99.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.029 0.0%29273 0.000 0.001 0.015
article prepare00:00:00.000 0.0%3398 0.000 0.000 0.000
article read00:00:11.368 0.0%875 0.000 12.992 126.222
backlog stats00:00:00.042 0.0%76973 0.000 0.001 0.009
callbacks00:00:00.000 0.0%26 0.000 0.000 0.000
data read00:00:00.211 0.0%36500 0.000 0.006 0.035
data write00:00:00.472 0.0%27556 0.000 0.017 0.058
idle23:45:30.361100.0%76973 716.691 1111.174 2335.605
status file00:00:03.813 0.0%7971 0.183 0.478 6.650
TOTAL: 23:45:48.14223:45:46.296100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:09.978 39.5%4235 0.000 2.356 169.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.106 0.4%10263 0.000 0.010 0.500
readart00:00:00.003 0.0%40 0.000 0.075 0.500
TOTAL: 00:00:25.23800:00:10.087 40.0%----

Control commands to INND:

CommandNumber
flush 27
flushlogs 2
go 2
logmode 2
lowmark 1
mode 152
name 3
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 197

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
us-control@lists.killfile.org000100
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 114 10097741009572 0 202 99%71:30:07
2news.icm.edu.pl 49 280 73 160 47 26%15:34:31
3localhost 6 5 5 0 0 100%00:00:00
TOTAL: 3 169 10100591009650 160 249 99%87:04:38
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl375.9 GB3.2 MB3.7 MB375.9 GB 99%390.3 KB
2news.icm.edu.pl3.7 MB65.2 KB820.5 KB4.6 MB 81%39.4 KB
3localhost8.2 KB0.0 KB0.0 KB8.2 KB100%1.6 KB
TOTAL: 3375.9 GB3.2 MB4.5 MB375.9 GB 99%390.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Mar 31 04:15:02 - 04:59:59 31265 3.1% 11.5913.5 GB 3.6%5236.13
Mar 31 05:00:00 - 05:59:59 37924 3.8% 10.5317.5 GB 4.7%5102.82
Mar 31 06:00:00 - 06:59:59 83692 8.3% 23.2518.7 GB 5.0%5447.91
Mar 31 07:00:00 - 07:59:59 41205 4.1% 11.4516.7 GB 4.4%4866.34
Mar 31 08:00:00 - 08:59:59 35901 3.6% 9.9715.8 GB 4.2%4592.49
Mar 31 09:00:00 - 09:59:59 37389 3.7% 10.3916.1 GB 4.3%4686.06
Mar 31 10:00:00 - 10:59:59 54241 5.4% 15.0717.2 GB 4.6%4998.62
Mar 31 11:00:00 - 11:59:59 40730 4.0% 11.3117.3 GB 4.6%5032.48
Mar 31 12:00:00 - 12:59:59 38083 3.8% 10.5815.8 GB 4.2%4588.13
Mar 31 13:00:00 - 13:59:59 37743 3.7% 10.4815.7 GB 4.2%4562.31
Mar 31 14:00:00 - 14:59:59 37883 3.8% 10.5215.7 GB 4.2%4565.64
Mar 31 15:00:00 - 15:59:59 35761 3.5% 9.9313.8 GB 3.7%4033.53
Mar 31 16:00:00 - 16:59:59 37938 3.8% 10.5415.9 GB 4.2%4621.73
Mar 31 17:00:00 - 17:59:59 46828 4.6% 13.0116.4 GB 4.4%4786.46
Mar 31 18:00:00 - 18:59:59 44379 4.4% 12.3315.6 GB 4.1%4546.60
Mar 31 19:00:00 - 19:59:59 57352 5.7% 15.9314.8 GB 3.9%4323.08
Mar 31 20:00:00 - 20:59:59 36114 3.6% 10.0314.2 GB 3.8%4122.34
Mar 31 21:00:00 - 21:59:59 31736 3.1% 8.8213.6 GB 3.6%3974.59
Mar 31 22:00:00 - 22:59:59 31453 3.1% 8.7413.7 GB 3.7%4003.11
Mar 31 23:00:00 - 23:59:59 30473 3.0% 8.4614.0 GB 3.7%4079.20
Apr 1 00:00:00 - 00:59:59 30614 3.0% 8.5013.7 GB 3.6%3982.90
Apr 1 01:00:00 - 01:59:59 41838 4.1% 11.6214.8 GB 3.9%4313.13
Apr 1 02:00:00 - 02:59:59 46160 4.6% 12.8214.9 GB 4.0%4353.03
Apr 1 03:00:00 - 03:59:59 52960 5.2% 14.7117.0 GB 4.5%4959.61
Apr 1 04:00:00 - 04:15:01 10313 1.0% 11.454.1 GB 1.1%4764.15
TOTAL: 23:59:59 1009975 100.0% 11.69376.5 GB 100.0%4569.16
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 540 150 0 41 11 0 0 0 338
2news.icm.edu.pl 39 0 0 1 0 0 0 0 38
TOTAL: 2 579 150 0 42 11 0 0 0 376

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 77
it.comp.os.win.windows10 37
comp.mobile.android 20
it.tlc.cellulari.android 6
alt.comp.software.seamonkey 6
it.discussioni.energia 2
de.rec.sf.startrek 1
alt.comp.software.firefox 1
TOTAL: 8 150

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

GroupsCount
pl.praca.oferowana 10
alt.penthouse.sex.spanking 1
TOTAL: 2 11

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 29
Binary in non-binary group 5
TOTAL: 2 34

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 20879 183 7435 165 53 21663 0%23:56:25
2icm 15098 124 8644 5 0 0 0%23:51:13
3poznan 51 1 44 0 0 0 1%02:38:47
4nask 0 0 0 0 0 6334 0%23:49:02
5okapi 0 0 0 0 0 552 0%23:49:02
TOTAL: 5 36028 308 16123 170 53 28549 0%98:04:29
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama666.3 KB644.4 KB1.3 MB0.0 KB/s3.8 KB23:56:25
2icm564.5 KB19.1 KB583.5 KB0.0 KB/s4.5 KB23:51:13
3poznan8.3 KB0.0 KB8.3 KB0.0 KB/s8.3 KB02:38:47
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:49:02
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:49:02
TOTAL: 51.2 MB663.5 KB1.9 MB0.0 KB/s4.0 KB98:04:29
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 4040.0 KB 20 0 000:00:09
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 2 293 4040.0 KB 20 5 000:00:25

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1730 4040.0 KB 20 5 000:00:22
2*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
3unresolved 102 00.0 KB 0 0 000:00:03
TOTAL: 31833 4040.0 KB 20 5 000:00:25

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms2.man.lodz.pl 2.460 10.159 0.00000:00:12
web.man.lodz.pl 0.639 2.128 0.00000:00:09
80.111.142.213 0.011 0.027 0.00000:00:02
212.51.192.185 0.172 0.650 0.00000:00:00
scanner-07.ch1.censys-scanner.com 0.000 0.015 0.00000:00:00
isvcs01.man.lodz.pl 0.013 0.065 0.00000:00:00
94.102.61.41 0.003 0.003 0.00000:00:00
185.73.124.50 0.005 0.000 0.00000:00:00
TOTAL: 8 3.303 13.047 0.00000:00:25

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1437
212.51.192.185 96
80.111.142.213 4
94.102.61.41 1
scanner-07.ch1.censys-scanner.com 1
185.73.124.50 1
TOTAL: 6 1540

NNRP unrecognized commands (by host):

SystemConn
94.102.61.41 5
scanner-07.ch1.censys-scanner.com 1
185.73.124.50 1
TOTAL: 3 7

NNRP unrecognized commands (by command):

CommandCount
#003 1
EHLO www.censys.io 1
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
Accept: */* 1
Accept-Encoding: gzip 1
GET / HTTP/1.1 1
Host: 212.191.2.21:119 1
TOTAL: 7 7

NNRP client timeouts [Top 20]:

SystemConnPeer
185.73.124.50 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 40100.0%
TOTAL: 1 40100.0%

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

NewsgroupCount
1pl.comp.pecet 22
2pl.comp.os.ms-windows.winnt 18
TOTAL: 2 40