Daily Usenet report

Jun 2 04:15:01 -- Jun 3 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Jun 2 06:48:49 newsfeed overchan[357581]: input line too long, skipping
Jun 2 09:00:02 newsfeed pgpverify[372466]: pgpverify: /usr/bin/gpg1: no such file
Jun 2 09:59:15 newsfeed overchan[357581]: input line too long, skipping
Jun 2 10:02:02 newsfeed overchan[357581]: input line too long, skipping
Jun 2 14:43:14 newsfeed nnrpd[388001]: SERVER perl filter not defined
Jun 2 15:43:38 newsfeed overchan[357581]: input line too long, skipping
Jun 2 16:50:42 newsfeed overchan[357581]: input line too long, skipping
Jun 2 18:33:55 newsfeed overchan[395943]: input line too long, skipping
Jun 2 19:26:40 newsfeed nnrpd[400476]: SERVER perl filter not defined
Jun 2 21:25:16 newsfeed innfeed[1444]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Jun 2 21:25:16 newsfeed innfeed[1444]: SM: storage method 'cnfs' failed initialization
Jun 2 21:25:16 newsfeed innfeed[1444]: SM: one or more storage methods failed initialization
Jun 2 21:25:16 newsfeed innfeed[1444]: Storage manager initialization failed -- it is OK after a change in storage methods
Jun 2 21:25:16 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
Jun 2 21:25:16 newsfeed innd: SM: storage method 'cnfs' failed initialization
Jun 2 21:25:16 newsfeed innd: SM: one or more storage methods failed initialization
Jun 3 02:04:25 newsfeed nnrpd[14675]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn 83225 69.8%9.7 MB 70.0%
innfeed 26443 22.2%3.1 MB 22.1%
nnrpd 7722 6.5%857.6 KB 6.0%
innd 1826 1.5%255.3 KB 1.8%
controlchan 20 0.0%3.2 KB 0.0%
overchan 11 0.0%1.8 KB 0.0%
pgpverify 1 0.0%0.0 KB 0.0%
TOTAL: 7 119248 100.0%13.9 MB100.0%

History cache:

ReasonCount%Count
Do not exist 82540 97.3%
Negative hits 2107 2.5%
Positive hits 200 0.2%
Cache misses 22 0.0%
TOTAL: 4 84869 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.748 0.0%61 0.000 12.262 67.000
article cleanup00:00:00.479 0.0%82830 0.000 0.006 0.020
article logging00:00:01.863 0.0%83042 0.000 0.022 0.063
article parse00:00:02.733 0.0%525325 0.002 0.005 0.011
article write00:00:17.237 0.0%82712 0.052 0.208 18.411
artlog/artcncl00:00:00.002 0.0%59 0.000 0.034 1.000
data move00:00:00.512 0.0%695879 0.000 0.001 0.003
hisgrep/artcncl00:00:00.308 0.0%59 0.000 5.220 32.000
hishave/artcncl00:00:00.055 0.0%61 0.000 0.902 11.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:04:10.558 0.3%85116 1.180 2.944 44.594
history sync00:00:00.016 0.0%325 0.000 0.049 0.500
history write00:08:14.664 0.6%82826 3.240 5.972 55.155
hiswrite/artcncl00:00:00.014 0.0%2 2.000 7.000 12.000
idle23:35:53.580 99.0%599171 17.900 141.785 581.250
nntp read00:00:07.002 0.0%522551 0.008 0.013 0.022
overview write00:00:00.237 0.0%82712 0.000 0.003 0.009
perl filter00:01:20.477 0.1%82826 0.678 0.972 1.345
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:02.331 0.0%173885 0.006 0.013 0.029
TOTAL: 23:50:31.05023:50:12.816100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.035 0.0%29903 0.000 0.001 0.012
article prepare00:00:00.001 0.0%2880 0.000 0.000 0.028
article read00:00:20.291 0.0%715 5.188 28.379 370.500
backlog stats00:00:00.029 0.0%68653 0.000 0.000 0.003
callbacks00:00:00.000 0.0%4 0.000 0.000 0.000
data read00:00:00.254 0.0%33368 0.000 0.008 0.025
data write00:00:00.653 0.0%25103 0.000 0.026 0.065
idle23:44:59.947100.0%68653 712.028 1245.393 2293.635
status file00:00:16.749 0.0%9001 0.263 1.861 92.275
TOTAL: 23:45:40.48123:45:37.959100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:04.461 11.2%4208 0.000 15.319 10051.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.113 0.0%10183 0.000 0.011 0.500
readart00:00:00.001 0.0%20 0.000 0.050 0.500
TOTAL: 00:09:33.04700:01:04.575 11.3%----

Control commands to INND:

CommandNumber
flush 27
flushlogs 2
go 2
logmode 2
lowmark 1
mode 151
name 3
newgroup 2
pause 2
paused 2
reload 2
reserve 2
rmgroup 1
TOTAL: 13 199

Newsgroups created:

GroupMode
ukr.novinyy
ukr.novynyy
TOTAL2

Newsgroups removed:

Group
ukr.noviny
TOTAL: 1

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
ay@sita.kiev.ua210033
moderator@dana.de001101
TOTAL211134

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 92 82760 82648 1 111 99%43:45:14
2news.icm.edu.pl 52 172 7 158 7 4%15:10:16
3localhost 4 3 3 0 0 100%00:00:00
TOTAL: 3 148 82935 82658 159 118 99%58:55:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl1.3 GB83.8 KB332.6 KB1.3 GB 99%16.2 KB
2news.icm.edu.pl186.4 KB10.6 KB121.6 KB318.5 KB 58%22.8 KB
3localhost4.2 KB0.0 KB0.0 KB4.2 KB100%1.4 KB
TOTAL: 31.3 GB94.4 KB454.1 KB1.3 GB 99%16.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 2 04:15:01 - 04:59:59 1086 1.3% 0.4015.3 MB 1.2% 5.82
Jun 2 05:00:00 - 05:59:59 1489 1.8% 0.4118.1 MB 1.4% 5.13
Jun 2 06:00:00 - 06:59:59 4493 5.4% 1.2550.0 MB 3.8% 14.21
Jun 2 07:00:00 - 07:59:59 3842 4.6% 1.0737.2 MB 2.8% 10.59
Jun 2 08:00:00 - 08:59:59 3116 3.8% 0.8736.9 MB 2.8% 10.49
Jun 2 09:00:00 - 09:59:59 3156 3.8% 0.8842.4 MB 3.2% 12.05
Jun 2 10:00:00 - 10:59:59 3720 4.5% 1.0361.5 MB 4.7% 17.49
Jun 2 11:00:00 - 11:59:59 5647 6.8% 1.57103.3 MB 7.9% 29.40
Jun 2 12:00:00 - 12:59:59 4942 6.0% 1.37174.2 MB 13.3% 49.55
Jun 2 13:00:00 - 13:59:59 3372 4.1% 0.9454.8 MB 4.2% 15.58
Jun 2 14:00:00 - 14:59:59 2891 3.5% 0.8048.9 MB 3.7% 13.91
Jun 2 15:00:00 - 15:59:59 3520 4.2% 0.9849.4 MB 3.8% 14.04
Jun 2 16:00:00 - 16:59:59 4823 5.8% 1.3485.8 MB 6.6% 24.40
Jun 2 17:00:00 - 17:59:59 3908 4.7% 1.0954.4 MB 4.2% 15.47
Jun 2 18:00:00 - 18:59:59 4026 4.9% 1.1261.9 MB 4.7% 17.61
Jun 2 19:00:00 - 19:59:59 3873 4.7% 1.0851.6 MB 3.9% 14.67
Jun 2 20:00:00 - 20:59:59 4061 4.9% 1.1370.6 MB 5.4% 20.07
Jun 2 21:00:00 - 21:59:59 3138 3.8% 0.8737.6 MB 2.9% 10.70
Jun 2 22:00:00 - 22:59:59 3219 3.9% 0.8943.0 MB 3.3% 12.22
Jun 2 23:00:00 - 23:59:59 3447 4.2% 0.9644.3 MB 3.4% 12.60
Jun 3 00:00:00 - 00:59:59 2666 3.2% 0.7433.0 MB 2.5% 9.37
Jun 3 01:00:00 - 01:59:59 2514 3.0% 0.7027.8 MB 2.1% 7.90
Jun 3 02:00:00 - 02:59:59 2779 3.4% 0.7733.6 MB 2.6% 9.55
Jun 3 03:00:00 - 03:59:59 2715 3.3% 0.7568.0 MB 5.2% 19.34
Jun 3 04:00:00 - 04:15:02 394 0.5% 0.444.3 MB 0.3% 4.93
TOTAL: 24:00:01 82837 100.0% 0.961.3 GB 100.0% 15.50
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 322 108 0 3 0 0 0 0 211
2news.icm.edu.pl 7 0 0 1 0 0 0 0 6
TOTAL: 2 329 108 0 4 0 0 0 0 217

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 49
alt.comp.software.firefox 20
de.comm.provider.telefonie 11
comp.mobile.android 10
it.tlc.cellulari.android 9
nl.comp.os.linux.techniek 3
it.comp.os.win.windows10 2
alt.comp.software.seamonkey 2
de.rec.sf.startrek 2
TOTAL: 9 108

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 6
TOTAL: 1 6

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 23055 103 8044 189 7 21823 0%23:55:35
2icm 11555 26 8376 6 0 1 0%23:54:03
3poznan 7 0 4 0 0 0 0%01:21:24
4nask 0 0 0 0 0 5838 0%23:36:22
5okapi 0 0 0 0 0 578 0%23:36:22
TOTAL: 5 34617 129 16424 195 7 28240 0%96:23:46
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama356.7 KB2.8 MB3.1 MB0.0 KB/s11.0 KB23:55:35
2icm73.2 KB14.6 KB87.8 KB0.0 KB/s2.7 KB23:54:03
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s001:21:24
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:36:22
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:36:22
TOTAL: 5429.9 KB2.8 MB3.2 MB0.0 KB/s10.2 KB96:23:46
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama488.4 KB
TOTAL: 1488.4 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 2021.6 KB 9 0 000:00:09
2isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:00
TOTAL: 2 290 2021.6 KB 9 3 000:09:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1726 2021.6 KB 9 3 000:00:22
2unresolved 124 00.0 KB 0 0 000:09:10
TOTAL: 21850 2021.6 KB 9 3 000:09:33

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
195.96.137.9 0.067 0.113 0.00000:09:07
nms2.man.lodz.pl 3.381 5.874 0.00000:00:13
web.man.lodz.pl 0.777 1.273 0.00000:00:09
80.111.142.213 0.012 0.023 0.00000:00:02
212.51.192.185 0.225 0.384 0.00000:00:01
194.165.16.10 0.000 0.005 0.00000:00:00
isvcs01.man.lodz.pl 0.015 0.021 0.00000:00:00
TOTAL: 7 4.477 7.693 0.00000:09:33

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1436
212.51.192.185 96
195.96.137.9 23
80.111.142.213 4
194.165.16.10 1
TOTAL: 5 1560

NNRP unrecognized commands (by host):

SystemConn
195.96.137.9 2
194.165.16.10 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

CommandCount
#026#003#001#002 1
À#024 1
#003 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.10 1 1
195.96.137.9 1 1
TOTAL: 2 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 20100.0%
TOTAL: 1 20100.0%

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

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