Daily Usenet report

Aug 15 04:15:02 -- Aug 16 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Aug 15 06:37:14 newsfeed overchan[27741]: input line too long, skipping
Aug 15 06:37:56 newsfeed overchan[27741]: input line too long, skipping
Aug 15 11:05:46 newsfeed overchan[27741]: input line too long, skipping
Aug 15 14:58:22 newsfeed innd: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 15 14:58:22 newsfeed innd: SM: storage method 'cnfs' failed initialization
Aug 15 14:58:22 newsfeed innd: SM: one or more storage methods failed initialization
Aug 15 14:58:23 newsfeed innfeed[1080]: CNFS: Mismatch 3: read BUF05 for cycbuff BUF03
Aug 15 14:58:23 newsfeed innfeed[1080]: SM: storage method 'cnfs' failed initialization
Aug 15 14:58:23 newsfeed innfeed[1080]: SM: one or more storage methods failed initialization
Aug 15 14:58:23 newsfeed innfeed[1080]: Storage manager initialization failed -- it is OK after a change in storage methods
Aug 15 17:00:05 newsfeed pgpverify[3423]: pgpverify: verification failed
Aug 15 19:01:52 newsfeed overchan[1097]: input line too long, skipping
Aug 15 23:08:33 newsfeed overchan[1097]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn1811978 96.4%211.9 MB 96.1%
innfeed 46587 2.5%5.5 MB 2.5%
innd 17060 0.9%2.7 MB 1.2%
nnrpd 4644 0.2%491.9 KB 0.2%
overchan 7 0.0%1.2 KB 0.0%
controlchan 7 0.0%0.7 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 71880284 100.0%220.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1790048 95.2%
Negative hits 78573 4.2%
Positive hits 12515 0.7%
Cache misses 121 0.0%
TOTAL: 4 1881257 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.984 0.0%228 0.000 17.474 120.167
article cleanup00:00:25.912 0.0%1823271 0.010 0.014 0.021
article logging00:01:11.832 0.1%1823779 0.027 0.039 0.141
article parse00:46:40.241 3.3%154003264 0.013 0.018 0.030
article write00:14:52.896 1.0%1815518 0.386 0.492 0.953
artlog/artcncl00:00:00.008 0.0%228 0.000 0.035 0.500
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:02:55.224 0.2%165855354 0.001 0.001 0.002
hisgrep/artcncl00:00:00.832 0.0%220 0.000 3.782 29.250
hishave/artcncl00:00:00.119 0.0%228 0.000 0.522 5.429
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:43:15.238 3.0%1911693 0.065 1.358 4.439
history sync00:00:00.016 0.0%285 0.000 0.056 1.000
history write01:43:30.599 7.3%1819193 0.225 3.414 8.764
hiswrite/artcncl00:00:00.002 0.0%8 0.000 0.250 1.000
idle14:56:12.570 63.1%128575239 0.191 0.418 2.640
nntp read01:04:48.257 4.6%152692265 0.017 0.025 0.038
overview write00:00:09.596 0.0%1815518 0.003 0.005 0.007
perl filter03:36:55.681 15.3%1819178 5.959 7.155 8.254
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:02:54.415 0.2%3651273 0.035 0.048 0.067
TOTAL: 23:40:03.18823:13:57.422 98.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.177 0.0%60011 0.000 0.003 0.011
article prepare00:00:00.000 0.0%8076 0.000 0.000 0.000
article read00:00:46.230 0.1%2010 0.000 23.000 425.500
backlog stats00:00:00.138 0.0%175807 0.000 0.001 0.004
callbacks00:00:00.000 0.0%9 0.000 0.000 0.000
data read00:00:01.687 0.0%92994 0.006 0.018 0.049
data write00:00:03.235 0.0%73673 0.015 0.044 0.118
idle23:22:26.863 99.9%175807 283.442 478.632 844.068
status file00:00:12.321 0.0%16002 0.542 0.770 1.484
TOTAL: 23:23:46.54823:23:30.651100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:16.876 62.2%2946 0.000 5.728 123.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.164 0.6%6518 0.000 0.025 1.667
readart00:00:00.001 0.0%30 0.000 0.033 0.500
TOTAL: 00:00:27.14000:00:17.041 62.8%----

Control commands to INND:

CommandNumber
L 1
flush 26
flushlogs 2
go 2
logmode 2
mode 150
name 3
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 194

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 952 18010851791308 5727 4050 99%313:53:19
2news.nask.pl 6 16120 10167 2347 3606 63%23:16:53
3news.icm.edu.pl 49 265 19 244 2 7%16:15:39
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 1008 18174701801494 8318 7658 99%353:25:51
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl551.6 GB31.5 MB32.1 MB551.6 GB 99%322.2 KB
2news.nask.pl50.8 MB690.1 KB26.0 MB77.5 MB 65%5.8 KB
3news.icm.edu.pl722.2 KB0.0 KB17.0 KB739.2 KB 97%35.2 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4551.6 GB32.2 MB58.2 MB551.7 GB 99%319.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 15 04:15:02 - 04:59:59 85128 4.7% 31.5528.0 GB 5.1%10862.77
Aug 15 05:00:00 - 05:59:59 118224 6.6% 32.8437.4 GB 6.8%10894.06
Aug 15 06:00:00 - 06:59:59 88432 4.9% 24.5627.2 GB 4.9%7936.86
Aug 15 07:00:00 - 07:59:59 67073 3.7% 18.6320.8 GB 3.8%6069.43
Aug 15 08:00:00 - 08:59:59 73866 4.1% 20.5222.2 GB 4.0%6460.53
Aug 15 09:00:00 - 09:59:59 69180 3.8% 19.2219.1 GB 3.5%5576.91
Aug 15 10:00:00 - 10:59:59 66790 3.7% 18.5520.2 GB 3.7%5878.19
Aug 15 11:00:00 - 11:59:59 54298 3.0% 15.0817.8 GB 3.2%5173.07
Aug 15 12:00:00 - 12:59:59 56858 3.2% 15.7917.7 GB 3.2%5145.03
Aug 15 13:00:00 - 13:59:59 69439 3.9% 19.2920.5 GB 3.7%5959.13
Aug 15 14:00:00 - 14:59:59 64785 3.6% 18.0020.1 GB 3.6%5847.40
Aug 15 15:00:00 - 15:59:59 35726 2.0% 9.9210.9 GB 2.0%3167.36
Aug 15 16:00:00 - 16:59:59 68239 3.8% 18.9620.9 GB 3.8%6087.42
Aug 15 17:00:00 - 17:59:59 62412 3.5% 17.3419.4 GB 3.5%5652.07
Aug 15 18:00:00 - 18:59:59 47090 2.6% 13.0812.9 GB 2.3%3768.41
Aug 15 19:00:00 - 19:59:59 54278 3.0% 15.0815.5 GB 2.8%4524.25
Aug 15 20:00:00 - 20:59:59 57743 3.2% 16.0415.5 GB 2.8%4514.99
Aug 15 21:00:00 - 21:59:59 56478 3.1% 15.6916.3 GB 3.0%4745.80
Aug 15 22:00:00 - 22:59:59 66199 3.7% 18.3920.2 GB 3.7%5876.09
Aug 15 23:00:00 - 23:59:59 111225 6.2% 30.9032.0 GB 5.8%9323.58
Aug 16 00:00:00 - 00:59:59 99507 5.5% 27.6428.6 GB 5.2%8318.59
Aug 16 01:00:00 - 01:59:59 118441 6.6% 32.9035.9 GB 6.5%10447.20
Aug 16 02:00:00 - 02:59:59 95116 5.3% 26.4233.9 GB 6.1%9864.27
Aug 16 03:00:00 - 03:59:59 93505 5.2% 25.9731.4 GB 5.7%9135.55
Aug 16 04:00:00 - 04:15:02 23476 1.3% 26.037.3 GB 1.3%8491.33
TOTAL: 24:00:00 1803508 100.0% 20.87551.5 GB 100.0%6693.12
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 4406 161 0 3836 0 0 0 0 409
2news.nask.pl 3831 31 0 242 1 0 0 0 3557
3news.icm.edu.pl 5 0 0 0 0 0 0 0 5
TOTAL: 3 8242 192 0 4078 1 0 0 0 3971

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 111
it.comp.os.win.windows10 26
comp.sys.raspberry-pi 20
comp.mobile.android 16
it.tlc.cellulari.android 7
comp.mobile.ipad 4
it.comp.os.win.windows7 4
comp.sys.mac.vintage 1
it.discussioni.energia 1
de.comm.provider.telefonie 1
it.comp.os.linux.ubuntu 1
TOTAL: 11 192

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

GroupsCount
bit.listserv.catholic 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3146
Binary in non-binary group 206
Scoring filter (8) 52
Scoring filter (9) 28
Scoring filter (10) 11
Scoring filter (11) 2
Too many newsgroups 1
Scoring filter (12) 1
Scoring filter (13) 1
HTML post 1
TOTAL: 10 3449

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 351
news.nask.pl 156
TOTAL: 2 507
TOTAL: 1 507

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 43821 492 16372 230 32 43319 1%23:34:39
2poznan 16822 202 10051 45 0 1 1%23:30:45
3icm 27172 109 19158 496 0 1 0%23:30:45
4nask 5150 11 3508 0 0 0 0%23:30:45
5okapi 0 0 0 0 0 1885 0%23:18:36
TOTAL: 5 92965 814 49089 771 32 45206 0%117:25:30
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama1.5 MB983.0 KB2.5 MB0.0 KB/s3.5 KB23:34:39
2poznan754.1 KB155.0 KB909.1 KB0.0 KB/s3.7 KB23:30:45
3icm433.2 KB114.4 MB114.9 MB1.4 KB/s194.4 KB23:30:45
4nask178.9 KB0.0 KB178.9 KB0.0 KB/s16.3 KB23:30:45
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:18:36
TOTAL: 52.9 MB115.6 MB118.4 MB0.3 KB/s76.5 KB117:25:30
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 282 3036.0 KB 15 0 000:00:21
TOTAL: 1 282 3036.0 KB 15 0 000:00:26

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 660 3036.0 KB 15 0 000:00:26
TOTAL: 1 660 3036.0 KB 15 0 000:00:26

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.172 2.660 0.00000:00:21
mon.man.lodz.pl 1.188 2.432 0.00000:00:03
david.man.lodz.pl 0.404 0.872 0.00000:00:01
TOTAL: 3 2.764 5.964 0.00000:00:26

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 284
david.man.lodz.pl 94
TOTAL: 2 378

Newsgroup request counts (by category):

CategoryCountPct
1pl 30100.0%
TOTAL: 1 30100.0%

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

NewsgroupCount
1pl.comp.pecet 16
2pl.comp.os.ms-windows.winnt 14
TOTAL: 2 30