Daily Usenet report

Sep 4 04:15:02 -- Sep 5 04:15:02

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Sep 4 06:41:15 newsfeed overchan[8080]: input line too long, skipping
Sep 4 07:39:19 newsfeed nnrpd[8057]: SERVER perl filter not defined
Sep 4 11:15:02 newsfeed pgpverify[8825]: pgpverify: verification failed
Sep 4 14:11:46 newsfeed nnrpd[5242]: SERVER perl filter not defined
Sep 4 14:58:37 newsfeed nnrpd[12442]: SERVER perl filter not defined
Sep 4 15:19:00 newsfeed overchan[8080]: input line too long, skipping
Sep 4 15:34:23 newsfeed nnrpd[17759]: SERVER perl filter not defined
Sep 4 15:56:15 newsfeed overchan[8080]: input line too long, skipping
Sep 4 16:06:20 newsfeed overchan[8080]: input line too long, skipping
Sep 4 16:14:31 newsfeed overchan[8080]: input line too long, skipping
Sep 4 19:38:36 newsfeed nnrpd[22901]: SERVER perl filter not defined
Sep 4 20:07:31 newsfeed overchan[8080]: input line too long, skipping
Sep 4 20:26:09 newsfeed overchan[8080]: input line too long, skipping
Sep 4 20:27:15 newsfeed overchan[8080]: input line too long, skipping
Sep 4 20:36:04 newsfeed overchan[8080]: input line too long, skipping
Sep 4 20:36:56 newsfeed overchan[8080]: input line too long, skipping
Sep 4 21:40:29 newsfeed nnrpd[9242]: SERVER perl filter not defined
Sep 5 01:48:13 newsfeed overchan[8080]: input line too long, skipping
Sep 5 03:11:51 newsfeed overchan[8080]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 541254 92.1%59.4 MB 91.3%
innfeed 33787 5.7%4.0 MB 6.1%
innd 8422 1.4%1.3 MB 1.9%
nnrpd 4220 0.7%427.4 KB 0.6%
overchan 13 0.0%1.4 KB 0.0%
controlchan 12 0.0%2.4 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 587709 100.0%65.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 539619 99.4%
Negative hits 2688 0.5%
Positive hits 284 0.1%
Cache misses 31 0.0%
TOTAL: 4 542622 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.766 0.0%89 0.000 31.079 256.000
article cleanup00:00:08.682 0.0%540972 0.011 0.016 0.022
article logging00:00:30.990 0.0%541362 0.046 0.057 0.085
article parse00:16:51.719 1.2%25612994 0.032 0.040 0.045
article write00:07:26.442 0.5%540635 0.558 0.826 1.198
artlog/artcncl00:00:00.002 0.0%89 0.000 0.022 1.000
data move00:00:30.940 0.0%29391978 0.001 0.001 0.001
hisgrep/artcncl00:00:00.554 0.0%85 0.000 6.518 42.500
hishave/artcncl00:00:00.184 0.0%89 0.000 2.067 12.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:24:34.114 5.9%544355 7.448 9.321 17.727
history sync00:00:00.022 0.0%291 0.000 0.076 1.000
history write01:33:01.680 6.5%540926 8.331 10.319 19.472
hiswrite/artcncl00:00:00.036 0.0%4 6.000 9.000 18.000
idle19:02:15.884 79.3%25316890 1.994 2.707 4.543
nntp read00:23:09.072 1.6%25360083 0.047 0.055 0.062
overview write00:00:03.426 0.0%540635 0.003 0.006 0.010
perl filter01:00:21.458 4.2%540926 4.842 6.695 8.564
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:34.839 0.0%1094477 0.027 0.032 0.038
TOTAL: 24:00:08.42423:49:32.810 99.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.152 0.0%42448 0.000 0.004 0.016
article prepare00:00:00.000 0.0%2838 0.000 0.000 0.000
article read00:00:09.246 0.0%708 0.000 13.059 294.000
backlog stats00:00:00.089 0.0%89177 0.000 0.001 0.005
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:00.961 0.0%45672 0.006 0.021 0.048
data write00:00:02.289 0.0%32603 0.027 0.070 0.129
idle23:54:13.827 99.9%89177 563.267 964.978 1766.706
status file00:01:28.145 0.1%11271 0.950 7.821 246.270
TOTAL: 23:56:00.32423:55:54.709100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle02:09:16.546 99.8%3049 0.000 2543.964900069.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.221 0.0%6673 0.000 0.033 0.667
readart00:00:00.000 0.0%10 0.000 0.000 0.000
TOTAL: 02:09:31.38702:09:16.767 99.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
moderator@dana.de001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 123 540425 540146 0 279 99%70:28:52
2news.icm.edu.pl 48 286 41 187 58 14%16:35:08
3localhost 1447 6 6 0 0 100%00:00:00
TOTAL: 3 1618 540717 540193 187 337 99%87:04:00
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl234.2 GB1.9 MB2.6 MB234.2 GB 99%454.4 KB
2news.icm.edu.pl2.0 MB94.2 KB2.4 MB4.5 MB 43%46.1 KB
3localhost8.5 KB0.0 KB0.0 KB8.5 KB100%1.4 KB
TOTAL: 3234.2 GB2.0 MB5.0 MB234.2 GB 99%454.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 4 04:15:02 - 04:59:59 13749 2.5% 5.105.8 GB 2.5%2248.40
Sep 4 05:00:00 - 05:59:59 19171 3.5% 5.339.1 GB 3.9%2661.92
Sep 4 06:00:00 - 06:59:59 20087 3.7% 5.589.0 GB 3.8%2611.82
Sep 4 07:00:00 - 07:59:59 18169 3.4% 5.059.7 GB 4.1%2826.97
Sep 4 08:00:00 - 08:59:59 22077 4.1% 6.139.3 GB 4.0%2718.48
Sep 4 09:00:00 - 09:59:59 18779 3.5% 5.229.1 GB 3.9%2655.00
Sep 4 10:00:00 - 10:59:59 22536 4.2% 6.269.4 GB 4.0%2751.41
Sep 4 11:00:00 - 11:59:59 23438 4.3% 6.519.3 GB 3.9%2695.34
Sep 4 12:00:00 - 12:59:59 23727 4.4% 6.5910.4 GB 4.4%3023.25
Sep 4 13:00:00 - 13:59:59 25710 4.8% 7.1410.2 GB 4.3%2968.53
Sep 4 14:00:00 - 14:59:59 26639 4.9% 7.4010.5 GB 4.5%3071.23
Sep 4 15:00:00 - 15:59:59 22153 4.1% 6.159.8 GB 4.2%2861.08
Sep 4 16:00:00 - 16:59:59 21605 4.0% 6.009.3 GB 4.0%2704.01
Sep 4 17:00:00 - 17:59:59 22893 4.2% 6.3610.5 GB 4.5%3044.38
Sep 4 18:00:00 - 18:59:59 25176 4.7% 6.999.5 GB 4.1%2773.37
Sep 4 19:00:00 - 19:59:59 23253 4.3% 6.4610.2 GB 4.4%2981.74
Sep 4 20:00:00 - 20:59:59 25917 4.8% 7.2011.1 GB 4.7%3237.84
Sep 4 21:00:00 - 21:59:59 26144 4.8% 7.2611.8 GB 5.0%3437.80
Sep 4 22:00:00 - 22:59:59 23685 4.4% 6.5810.4 GB 4.4%3033.96
Sep 4 23:00:00 - 23:59:59 24393 4.5% 6.7811.5 GB 4.9%3353.40
Sep 5 00:00:00 - 00:59:59 22022 4.1% 6.1210.1 GB 4.3%2942.61
Sep 5 01:00:00 - 01:59:59 22233 4.1% 6.189.9 GB 4.2%2880.00
Sep 5 02:00:00 - 02:59:59 21448 4.0% 5.968.6 GB 3.7%2497.30
Sep 5 03:00:00 - 03:59:59 19978 3.7% 5.557.8 GB 3.3%2276.34
Sep 5 04:00:00 - 04:15:02 5456 1.0% 6.052.5 GB 1.0%2851.00
TOTAL: 24:00:00 540438 100.0% 6.26234.9 GB 100.0%2850.30
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 669 233 0 37 9 0 0 0 390
2news.icm.edu.pl 58 0 0 9 0 0 0 0 49
TOTAL: 2 727 233 0 46 9 0 0 0 439

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 186
it.tlc.cellulari.android 21
it.comp.os.win.windows10 17
comp.sys.raspberry-pi 8
comp.mobile.android 1
TOTAL: 5 233

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

GroupsCount
pl.praca.oferowana 8
pl.sci.matematyka 1
TOTAL: 2 9

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 47
Binary in non-binary group 1
Too many newsgroups 1
TOTAL: 3 49

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 30225 278 11323 126 3 30788 0%23:59:57
2icm 15857 45 12943 0 0 0 0%23:59:57
3poznan 29 0 20 0 0 0 0%03:01:37
4okapi 0 0 0 0 0 784 0%23:57:47
5nask 0 0 0 0 0 9163 0%23:57:47
TOTAL: 5 46111 323 24286 126 3 40735 0%98:57:05
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama701.1 KB554.3 KB1.2 MB0.0 KB/s3.1 KB23:59:57
2icm234.7 KB0.0 KB234.7 KB0.0 KB/s5.2 KB23:59:57
3poznan0.0 KB0.0 KB0.0 KB0.0 KB/s003:01:37
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 5935.8 KB554.3 KB1.5 MB0.0 KB/s3.3 KB98:57:05
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 109.0 KB 5 0 000:00:18
2isvcs01.man.lodz.pl 6 00.0 KB 0 6 000:00:01
TOTAL: 2 294 109.0 KB 5 6 002:09:31

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 582 109.0 KB 5 6 000:00:26
2unresolved 99 00.0 KB 0 0 000:00:12
3*.neoplus.adsl.tpnet.pl 1 00.0 KB 0 0 000:30:00
4*.dynamic.chello.pl 5 00.0 KB 0 0 001:38:51
TOTAL: 4 687 109.0 KB 5 6 002:09:31

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
89-64-149-5.dynamic.chello.pl 0.023 0.042 0.00001:38:51
acxx97.neoplus.adsl.tpnet.pl 0.038 0.016 0.00000:30:00
web.man.lodz.pl 1.679 2.996 0.00000:00:18
192.241.230.83 0.005 0.009 0.00000:00:09
mon.man.lodz.pl 1.688 2.872 0.00000:00:06
212.51.192.185 0.528 0.983 0.00000:00:02
isvcs01.man.lodz.pl 0.057 0.119 0.00000:00:01
185.202.2.32 0.016 0.016 0.00000:00:00
TOTAL: 8 4.034 7.053 0.00002:09:31

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
89-64-149-5.dynamic.chello.pl 5
185.202.2.32 2
192.241.230.83 1
acxx97.neoplus.adsl.tpnet.pl 1
TOTAL: 6 393

NNRP unrecognized commands (by host):

SystemConn
185.202.2.32 2
TOTAL: 1 2

NNRP unrecognized commands (by command):

CommandCount
#003 2
TOTAL: 1 2

NNRP client timeouts [Top 20]:

SystemConnPeer
89-64-149-5.dynamic.chello.pl 3 0
185.202.2.32 2 2
acxx97.neoplus.adsl.tpnet.pl 1 0
TOTAL: 3 6 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 10100.0%
TOTAL: 1 10100.0%

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

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