Daily Usenet report

Dec 4 04:15:02 -- Dec 5 04:15:01

Unknown entries from news log file:

First 26 / 26 lines (100.0%)

Dec 4 06:45:01 newsfeed pgpverify[1512]: pgpverify: verification failed
Dec 4 07:01:30 newsfeed overchan[20857]: input line too long, skipping
Dec 4 10:45:29 newsfeed overchan[20857]: input line too long, skipping
Dec 4 11:21:47 newsfeed nnrpd[10787]: SERVER perl filter not defined
Dec 4 11:40:50 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 7 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13516800 duplicate size 0 rejected size 0]
Dec 4 11:41:08 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 6 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13516800 duplicate size 0 rejected size 0]
Dec 4 12:59:04 newsfeed innd: message repeated 4 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 6 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13516800 duplicate size 0 rejected size 0]
Dec 4 12:59:34 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 6 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13516800 duplicate size 0 rejected size 0]
Dec 4 13:04:31 newsfeed nnrpd[14370]: SERVER perl filter not defined
Dec 4 13:14:55 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 6 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13516800 duplicate size 0 rejected size 0]
Dec 4 13:39:32 newsfeed nnrpd[15469]: SERVER perl filter not defined
Dec 4 15:04:09 newsfeed nnrpd[18417]: SERVER perl filter not defined
Dec 4 15:06:16 newsfeed nnrpd[18485]: SERVER perl filter not defined
Dec 4 15:07:10 newsfeed nnrpd[18494]: SERVER perl filter not defined
Dec 4 15:08:01 newsfeed nnrpd[18496]: SERVER perl filter not defined
Dec 4 15:10:31 newsfeed nnrpd[18568]: SERVER perl filter not defined
Dec 4 15:15:40 newsfeed nnrpd[18816]: SERVER perl filter not defined
Dec 4 15:16:08 newsfeed nnrpd[18823]: SERVER perl filter not defined
Dec 4 15:19:59 newsfeed nnrpd[18867]: SERVER perl filter not defined
Dec 4 15:20:53 newsfeed nnrpd[18918]: SERVER perl filter not defined
Dec 4 15:37:24 newsfeed nnrpd[19502]: SERVER perl filter not defined
Dec 4 18:25:38 newsfeed overchan[20857]: input line too long, skipping
Dec 4 22:45:39 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 4 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13107200 duplicate size 0 rejected size 0]
Dec 4 23:59:19 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 4 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13107200 duplicate size 0 rejected size 0]
Dec 5 01:23:22 newsfeed innd: message repeated 2 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 4 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13107200 duplicate size 0 rejected size 0]
Dec 5 01:36:19 newsfeed innd: message repeated 3 times: [ newsfeed.pionier.net.pl:198 checkpoint seconds 3 accepted 200 refused 0 rejected 0 duplicate 0 accepted size 13107200 duplicate size 0 rejected size 0]

Log entries by program:

Program nameLines%LinesSize%Size
inn1085854 95.1%119.4 MB 94.5%
innfeed 43484 3.8%5.1 MB 4.1%
innd 7638 0.7%1.3 MB 1.0%
nnrpd 5011 0.4%542.5 KB 0.4%
overchan 4 0.0%0.5 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 71141996 100.0%126.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1082927 98.9%
Negative hits 11419 1.0%
Positive hits 218 0.0%
Cache misses 40 0.0%
TOTAL: 4 1094604 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.976 0.0%79 0.000 12.354 81.000
article cleanup00:00:13.648 0.0%1085309 0.004 0.013 0.019
article logging00:00:42.182 0.0%1085695 0.027 0.039 0.062
article parse00:04:18.118 0.3%11837493 0.013 0.022 0.031
article write00:03:00.459 0.2%1084989 0.144 0.166 0.497
artlog/artcncl00:00:00.002 0.0%79 0.000 0.025 1.000
data move00:00:19.873 0.0%15892733 0.001 0.001 0.002
hisgrep/artcncl00:00:00.521 0.0%79 0.000 6.595 39.833
hishave/artcncl00:00:00.006 0.0%79 0.000 0.076 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:04:01.791 0.3%1096929 0.013 0.220 2.318
history sync00:00:00.015 0.0%354 0.000 0.042 0.500
history write00:21:33.141 1.5%1085290 0.104 1.192 10.293
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle21:50:48.316 91.0%10823287 0.842 7.267 134.930
nntp read00:07:38.954 0.5%11193726 0.029 0.041 0.049
overview write00:00:05.066 0.0%1084989 0.000 0.005 0.013
perl filter01:20:00.655 5.6%1085290 2.713 4.423 5.003
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:56.205 0.1%2184992 0.019 0.026 0.037
TOTAL: 24:00:47.85523:53:39.928 99.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.146 0.0%60146 0.000 0.002 0.017
article prepare00:00:00.002 0.0%13302 0.000 0.000 0.000
article read00:00:17.025 0.0%3325 0.000 5.120 77.600
backlog stats00:00:00.111 0.0%141222 0.000 0.001 0.004
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:01.333 0.0%69572 0.000 0.019 0.039
data write00:00:03.512 0.0%54545 0.029 0.064 0.170
idle23:54:20.480100.0%141222 50.781 609.399 1149.935
status file00:00:13.287 0.0%16361 0.000 0.812 1.750
TOTAL: 23:55:02.14023:54:55.896100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:31:59.593 99.4%3143 0.000 610.752360230.400
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.287 0.0%6948 0.000 0.041 0.500
readart00:00:00.030 0.0%39 0.000 0.769 27.000
TOTAL: 00:32:11.67700:31:59.910 99.4%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 247 10806491080391 0 258 99%92:24:12
2news.icm.edu.pl 45 264 42 160 62 15%17:21:25
3localhost 14 13 13 0 0 100%00:00:00
TOTAL: 3 306 10809261080446 160 320 99%109:45:37
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl64.9 GB242.9 KB1001.8 KB64.9 GB 99%63.0 KB
2news.icm.edu.pl821.8 KB19.5 KB1.2 MB2.0 MB 40%19.5 KB
3localhost16.0 KB0.0 KB0.0 KB16.0 KB100%1.2 KB
TOTAL: 364.9 GB262.4 KB2.1 MB65.0 GB 99%63.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 4 04:15:02 - 04:59:59 7921 0.7% 2.94451.6 MB 0.7% 171.41
Dec 4 05:00:00 - 05:59:59 15565 1.4% 4.32892.6 MB 1.3% 253.90
Dec 4 06:00:00 - 06:59:59 7447 0.7% 2.07388.2 MB 0.6% 110.43
Dec 4 07:00:00 - 07:59:59 28478 2.6% 7.911.7 GB 2.6% 503.65
Dec 4 08:00:00 - 08:59:59 23632 2.2% 6.561.4 GB 2.2% 419.09
Dec 4 09:00:00 - 09:59:59 34062 3.1% 9.462.0 GB 3.1% 595.86
Dec 4 10:00:00 - 10:59:59 20099 1.9% 5.581.2 GB 1.8% 343.09
Dec 4 11:00:00 - 11:59:59 124299 11.5% 34.537.7 GB 11.8%2248.70
Dec 4 12:00:00 - 12:59:59 29979 2.8% 8.331.9 GB 2.8% 543.83
Dec 4 13:00:00 - 13:59:59 19460 1.8% 5.411.2 GB 1.8% 335.16
Dec 4 14:00:00 - 14:59:59 5024 0.5% 1.40248.2 MB 0.4% 70.59
Dec 4 15:00:00 - 15:59:59 28434 2.6% 7.901.7 GB 2.6% 490.38
Dec 4 16:00:00 - 16:59:59 18340 1.7% 5.091.1 GB 1.6% 308.42
Dec 4 17:00:00 - 17:59:59 57878 5.3% 16.083.4 GB 5.2%1000.79
Dec 4 18:00:00 - 18:59:59 16843 1.6% 4.68910.2 MB 1.4% 258.90
Dec 4 19:00:00 - 19:59:59 7783 0.7% 2.16312.7 MB 0.5% 88.96
Dec 4 20:00:00 - 20:59:59 70350 6.5% 19.544.3 GB 6.5%1242.35
Dec 4 21:00:00 - 21:59:59 130169 12.0% 36.168.0 GB 12.2%2331.93
Dec 4 22:00:00 - 22:59:59 129582 11.9% 35.998.0 GB 12.2%2333.44
Dec 4 23:00:00 - 23:59:59 174038 16.0% 48.3410.8 GB 16.4%3135.02
Dec 5 00:00:00 - 00:59:59 85727 7.9% 23.815.3 GB 8.0%1529.45
Dec 5 01:00:00 - 01:59:59 33006 3.0% 9.172.0 GB 3.0% 573.69
Dec 5 02:00:00 - 02:59:59 9547 0.9% 2.65551.8 MB 0.8% 156.94
Dec 5 03:00:00 - 03:59:59 5445 0.5% 1.51274.7 MB 0.4% 78.13
Dec 5 04:00:00 - 04:15:01 1961 0.2% 2.18101.9 MB 0.2% 115.83
TOTAL: 23:59:59 1085069 100.0% 12.5665.6 GB 100.0% 796.27
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 644 232 0 17 4 0 0 0 391
2news.icm.edu.pl 62 0 0 2 0 0 0 0 60
TOTAL: 2 706 232 0 19 4 0 0 0 451

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 136
it.tlc.cellulari.android 45
it.comp.os.win.windows10 17
comp.sys.raspberry-pi 15
comp.mobile.android 10
it.comp.os.win.windows7 5
de.comm.software.mailreader 4
TOTAL: 7 232

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

GroupsCount
pl.praca.oferowana 4
TOTAL: 1 4

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 48
Binary in non-binary group 16
Scoring filter (8) 1
TOTAL: 3 65

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 49445 976 19883 1463 5 37848 1%23:59:57
2icm 25505 331 14465 76 0 1 1%23:59:35
3nask 0 0 0 0 0 10099 0%23:57:50
4poznan 53 0 42 0 0 0 0%04:11:43
5okapi 0 0 0 0 0 1040 0%23:57:50
TOTAL: 5 75003 1307 34390 1539 5 48988 1%100:06:55
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama3.0 MB6.4 MB9.4 MB0.1 KB/s3.9 KB23:59:57
2icm942.1 KB3.7 MB4.6 MB0.1 KB/s11.6 KB23:59:35
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s004:11:43
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 53.9 MB10.1 MB14.0 MB0.0 KB/s5.0 KB100:06:55
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 3830.3 KB 17 0 000:00:14
2pc-tom.man.lodz.pl 1 11.6 KB 1 0 000:30:01
3isvcs01.man.lodz.pl 13 00.0 KB 0 13 000:00:00
TOTAL: 3 301 3931.9 KB 18 13 000:32:11

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 589 3931.9 KB 18 13 000:30:19
2*.business.broadband.hu 1 00.0 KB 0 0 000:00:31
3*.hinet-ip.hinet.net 1 00.0 KB 0 0 000:00:32
4*.us-west-2.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
5unresolved 102 00.0 KB 0 0 000:00:15
6*.hfc.comcastbusiness.net 1 00.0 KB 0 0 000:00:32
TOTAL: 6 695 3931.9 KB 18 13 000:32:11

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
pc-tom.man.lodz.pl 0.004 0.016 0.00000:30:01
122-116-28-86.hinet-ip.hinet.net 0.008 0.012 0.00000:00:32
173-13-34-61-pennsylvania.hfc.comcastbusiness.net 0.004 0.008 0.00000:00:32
business-89-135-167-108.business.broadband.hu 0.004 0.008 0.00000:00:31
web.man.lodz.pl 1.512 3.360 0.00000:00:14
159.203.193.253 0.004 0.012 0.00000:00:09
80.111.142.213 0.028 0.036 0.00000:00:03
mon.man.lodz.pl 1.272 2.756 0.00000:00:02
109.206.201.189 0.004 0.012 0.00000:00:01
isvcs01.man.lodz.pl 0.112 0.140 0.00000:00:00
212.51.192.185 0.372 0.960 0.00000:00:00
ec2-52-26-102-229.us-west-2.compute.amazonaws.com 0.004 0.012 0.00000:00:00
TOTAL: 12 3.328 7.332 0.00000:32:11

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
80.111.142.213 4
122-116-28-86.hinet-ip.hinet.net 1
ec2-52-26-102-229.us-west-2.compute.amazonaws.com 1
173-13-34-61-pennsylvania.hfc.comcastbusiness.net 1
109.206.201.189 1
business-89-135-167-108.business.broadband.hu 1
159.203.193.253 1
TOTAL: 9 394

NNRP unrecognized commands (by host):

SystemConn
173-13-34-61-pennsylvania.hfc.comcastbusiness.net 10
business-89-135-167-108.business.broadband.hu 10
122-116-28-86.hinet-ip.hinet.net 10
TOTAL: 3 30

NNRP unrecognized commands (by command):

CommandCount
Content-Type: text/xml 3
Accept-Language: en-us 3
Cache-Control: max-age=0 3
Connection: close 3
Accept-Encoding: identity 3
Authorization: Basic YWRtaW46ezEyMjEzQkQ... 3
Accept: */* 3
User-Agent: ApiTool 3
Content-Length: 586 3
Host: 212.191.2.21 2
Host: 180.253.154.23 1
TOTAL: 11 30

NNRP client timeouts [Top 20]:

SystemConnPeer
pc-tom.man.lodz.pl 1 0
TOTAL: 1 1 0

Newsgroup request counts (by category):

CategoryCountPct
1pl 30 76.9%
2lodman 9 23.1%
TOTAL: 2 39100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 16
2pl.comp.pecet 14
3lodman.test 8
4lodman.info 1
TOTAL: 4 39