Daily Usenet report

May 17 04:15:02 -- May 18 04:15:05

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

May 17 06:35:42 newsfeed overchan[19061]: input line too long, skipping
May 17 08:13:22 newsfeed nnrpd[28511]: SERVER perl filter not defined
May 17 08:48:36 newsfeed nnrpd[29862]: SERVER perl filter not defined
May 17 08:54:36 newsfeed nnrpd[29987]: SERVER perl filter not defined
May 17 13:10:03 newsfeed pgpverify[7052]: pgpverify: verification failed
May 17 14:19:14 newsfeed overchan[19061]: input line too long, skipping
May 17 14:19:35 newsfeed overchan[19061]: input line too long, skipping
May 17 14:21:23 newsfeed overchan[19061]: input line too long, skipping
May 17 14:22:09 newsfeed overchan[19061]: input line too long, skipping
May 17 14:27:07 newsfeed overchan[19061]: input line too long, skipping
May 17 14:27:51 newsfeed overchan[19061]: input line too long, skipping
May 17 22:10:02 newsfeed controlchan[19059]: Multiple control headers in article <tIdqG-7Gg-27@gated-at.bofh.it>
May 18 00:10:11 newsfeed nnrpd[31418]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn2093261 96.7%242.7 MB 96.4%
innfeed 47460 2.2%5.6 MB 2.2%
innd 18439 0.9%2.9 MB 1.2%
nnrpd 4764 0.2%501.2 KB 0.2%
overchan 8 0.0%1.2 KB 0.0%
controlchan 5 0.0%0.6 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 72163938 100.0%251.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2071510 96.6%
Negative hits 57936 2.7%
Positive hits 13846 0.6%
Cache misses 147 0.0%
TOTAL: 4 2143439 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:06.957 0.0%232 0.000 29.987 813.000
article cleanup00:00:27.804 0.0%2092281 0.010 0.013 0.017
article logging00:01:22.115 0.1%2092923 0.031 0.039 0.174
article parse00:49:55.001 3.5%149641597 0.012 0.020 0.031
article write00:19:08.506 1.3%2082840 0.360 0.551 4.391
artlog/artcncl00:00:00.006 0.0%223 0.000 0.027 0.500
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:02:55.566 0.2%162917780 0.000 0.001 0.002
hisgrep/artcncl00:00:02.528 0.0%212 0.000 11.925 98.227
hishave/artcncl00:00:00.132 0.0%232 0.000 0.569 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:01:04.237 4.2%2161693 0.218 1.695 5.153
history sync00:00:00.013 0.0%290 0.000 0.045 0.500
history write02:19:24.433 9.7%2087708 0.871 4.007 8.249
hiswrite/artcncl00:00:00.074 0.0%20 0.000 3.700 5.750
idle13:47:30.142 57.5%114002606 0.206 0.436 1.319
nntp read01:05:36.666 4.6%148050906 0.017 0.027 0.038
overview write00:00:10.792 0.0%2082840 0.003 0.005 0.007
perl filter03:46:09.900 15.7%2087707 5.378 6.500 8.045
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:22.836 0.2%4197787 0.037 0.048 0.065
TOTAL: 24:00:02.71623:17:17.708 97.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.231 0.0%72382 0.000 0.003 0.013
article prepare00:00:00.018 0.0%46590 0.000 0.000 0.025
article read00:00:37.251 0.0%11641 0.000 3.200 300.000
backlog stats00:00:00.193 0.0%231074 0.000 0.001 0.004
callbacks00:00:00.001 0.0%5 0.000 0.200 1.000
data read00:00:02.318 0.0%125844 0.005 0.018 0.047
data write00:00:11.849 0.0%95435 0.016 0.124 0.433
idle23:52:11.990 99.9%231074 60.265 371.881 807.964
status file00:00:14.659 0.0%16684 0.615 0.879 4.861
TOTAL: 23:53:31.30823:53:18.510100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:20.496 63.3%3046 0.000 6.729 282.556
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.161 0.5%6720 0.000 0.024 0.667
readart00:00:00.000 0.0%38 0.000 0.000 0.000
TOTAL: 00:00:32.40200:00:20.657 63.8%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 192

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 654 20832292075811 2891 4527 99%375:50:36
2news.nask.pl 2 17937 7145 5967 4825 39%23:55:39
3news.icm.edu.pl 35 500 15 390 95 3%18:58:00
4localhost 5 4 4 0 0 100%00:00:00
TOTAL: 4 696 21016702082975 9248 9447 99%418:44:15
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl644.8 GB22.3 MB68.3 MB644.9 GB 99%325.1 KB
2news.nask.pl34.7 MB1.3 MB33.9 MB69.9 MB 49%6.0 KB
3news.icm.edu.pl248.5 KB158.9 KB1.2 MB1.6 MB 15%14.5 KB
4localhost7.2 KB0.0 KB0.0 KB7.2 KB100%1.8 KB
TOTAL: 4644.9 GB23.8 MB103.3 MB645.0 GB 99%323.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 17 04:15:02 - 04:59:59 86724 4.2% 32.1429.5 GB 4.6%11473.28
May 17 05:00:00 - 05:59:59 111760 5.4% 31.0438.5 GB 6.0%11223.19
May 17 06:00:00 - 06:59:59 112630 5.4% 31.2939.5 GB 6.1%11513.51
May 17 07:00:00 - 07:59:59 120621 5.8% 33.5141.4 GB 6.4%12060.12
May 17 08:00:00 - 08:59:59 124687 6.0% 34.6441.2 GB 6.4%11997.65
May 17 09:00:00 - 09:59:59 131227 6.3% 36.4538.0 GB 5.9%11062.30
May 17 10:00:00 - 10:59:59 99479 4.8% 27.6327.6 GB 4.3%8051.30
May 17 11:00:00 - 11:59:59 68167 3.3% 18.9419.4 GB 3.0%5642.04
May 17 12:00:00 - 12:59:59 69105 3.3% 19.2019.8 GB 3.1%5764.05
May 17 13:00:00 - 13:59:59 64941 3.1% 18.0419.6 GB 3.0%5710.82
May 17 14:00:00 - 14:59:59 64166 3.1% 17.8219.8 GB 3.1%5753.32
May 17 15:00:00 - 15:59:59 59518 2.9% 16.5319.0 GB 2.9%5530.09
May 17 16:00:00 - 16:59:59 64881 3.1% 18.0219.2 GB 3.0%5577.98
May 17 17:00:00 - 17:59:59 65988 3.2% 18.3318.9 GB 2.9%5498.67
May 17 18:00:00 - 18:59:59 59213 2.8% 16.4517.1 GB 2.7%4986.60
May 17 19:00:00 - 19:59:59 65444 3.1% 18.1820.0 GB 3.1%5832.01
May 17 20:00:00 - 20:59:59 57635 2.8% 16.0117.2 GB 2.7%5010.00
May 17 21:00:00 - 21:59:59 53354 2.6% 14.8214.9 GB 2.3%4343.96
May 17 22:00:00 - 22:59:59 60121 2.9% 16.7017.9 GB 2.8%5226.83
May 17 23:00:00 - 23:59:59 57729 2.8% 16.0417.0 GB 2.6%4940.82
May 18 00:00:00 - 00:59:59 101618 4.9% 28.2329.3 GB 4.6%8541.71
May 18 01:00:00 - 01:59:59 122967 5.9% 34.1637.2 GB 5.8%10839.21
May 18 02:00:00 - 02:59:59 120258 5.8% 33.4137.9 GB 5.9%11048.24
May 18 03:00:00 - 03:59:59 110240 5.3% 30.6234.9 GB 5.4%10173.62
May 18 04:00:00 - 04:15:05 30490 1.5% 33.699.6 GB 1.5%11145.69
TOTAL: 24:00:03 2082963 100.0% 24.11644.5 GB 100.0%7821.75
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 5036 144 0 4003 3 0 0 0 886
2news.nask.pl 4939 29 0 556 7 0 0 0 4347
3news.icm.edu.pl 100 0 0 10 0 0 0 0 90
TOTAL: 3 10075 173 0 4569 10 0 0 0 5323

Unwanted newsgroups [Top 20]:

NewsgroupCount
comp.mobile.android 62
uk.railway 58
it.tlc.cellulari.android 40
it.comp.os.win.windows10 10
de.comp.os.os2 2
it.comp.os.win.windows8 1
TOTAL: 6 173

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

GroupsCount
pl.praca.oferowana 6
pl.sci.historia 2
pl.sci.matematyka 1
bit.listserv.catholic 1
TOTAL: 4 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3764
Binary in non-binary group 509
EMP (phl) 306
Scoring filter (8) 31
Poison newsgroup 29
Scoring filter (9) 13
Too many newsgroups 12
HTML post 4
Scoring filter (10) 3
Scoring filter (12) 3
Scoring filter (11) 3
Scoring filter (14) 1
Scoring filter (13) 1
Attached HTML file 1
TOTAL: 14 4680

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 513
news.nask.pl 125
news.icm.edu.pl 4
TOTAL: 3 642
TOTAL: 1 642

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 39927 9842 21497 134 0 0 24%24:00:00
2panorama 44535 615 15897 272 11 55826 1%24:00:00
3poznan 10791 67 7034 59 0 0 0%24:00:00
4nask 11128 18 7399 0 0 0 0%24:00:00
5okapi 0 0 0 0 0 1873 0%23:57:53
TOTAL: 5 106381 10542 51827 465 11 57699 9%119:57:53
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm1.7 GB397.4 KB1.7 GB21.1 KB/s182.5 KB24:00:00
2panorama1.6 MB723.1 KB2.3 MB0.0 KB/s2.6 KB24:00:00
3poznan222.2 KB163.5 KB385.7 KB0.0 KB/s3.1 KB24:00:00
4nask30.9 KB0.0 KB30.9 KB0.0 KB/s1.7 KB24:00:00
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:53
TOTAL: 51.7 GB1.3 MB1.7 GB4.2 KB/s165.6 KB119:57:53
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3855.8 KB 14 0 000:00:25
2isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:01
TOTAL: 2 292 3855.8 KB 14 4 000:00:31

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 676 3855.8 KB 14 4 000:00:31
TOTAL: 1 676 3855.8 KB 14 4 000:00:31

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.216 4.704 0.00000:00:25
mon.man.lodz.pl 1.092 4.452 0.00000:00:03
isvcs01.man.lodz.pl 0.036 0.100 0.00000:00:01
david.man.lodz.pl 0.320 1.452 0.00000:00:01
TOTAL: 4 2.664 10.708 0.00000:00:31

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
TOTAL: 2 384

Newsgroup request counts (by category):

CategoryCountPct
1pl 38100.0%
TOTAL: 1 38100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 36
2pl.comp.pecet 2
TOTAL: 2 38