Daily Usenet report

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

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

May 17 08:18:45 newsfeed overchan[16729]: input line too long, skipping
May 17 08:37:39 newsfeed nnrpd[27198]: SERVER perl filter not defined
May 17 08:37:48 newsfeed nnrpd[27199]: SERVER perl filter not defined
May 17 08:38:03 newsfeed nnrpd[27223]: SERVER perl filter not defined
May 17 08:38:49 newsfeed nnrpd[27241]: SERVER perl filter not defined
May 17 13:10:02 newsfeed pgpverify[4710]: pgpverify: verification failed
May 17 13:29:06 newsfeed nnrpd[5400]: SERVER perl filter not defined
May 17 13:31:50 newsfeed nnrpd[5468]: SERVER perl filter not defined
May 17 13:33:42 newsfeed nnrpd[5499]: SERVER perl filter not defined
May 17 15:42:20 newsfeed nnrpd[10157]: SERVER perl filter not defined
May 17 20:56:17 newsfeed overchan[16729]: input line too long, skipping
May 17 21:00:59 newsfeed overchan[16729]: input line too long, skipping
May 17 21:18:53 newsfeed overchan[16729]: input line too long, skipping
May 17 21:20:48 newsfeed overchan[16729]: input line too long, skipping
May 17 21:31:35 newsfeed overchan[16729]: input line too long, skipping
May 17 21:53:54 newsfeed overchan[16729]: input line too long, skipping
May 17 21:57:31 newsfeed nnrpd[23855]: SERVER perl filter not defined
May 17 22:13:34 newsfeed nnrpd[24380]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn2596847 97.3%290.0 MB 96.9%
innfeed 46885 1.8%5.6 MB 1.9%
innd 19119 0.7%3.1 MB 1.0%
nnrpd 5008 0.2%536.2 KB 0.2%
overchan 8 0.0%1.4 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 72667872 100.0%299.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2588967 97.0%
Negative hits 70160 2.6%
Positive hits 10649 0.4%
Cache misses 122 0.0%
TOTAL: 4 2669898 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.285 0.0%268 0.000 8.526 66.100
article cleanup00:00:23.118 0.0%2602692 0.005 0.009 0.016
article logging00:01:12.748 0.1%2603320 0.017 0.028 0.050
article parse00:22:16.076 1.5%96396363 0.008 0.014 0.029
article write00:09:26.769 0.7%2596756 0.062 0.218 0.828
artlog/artcncl00:00:00.002 0.0%268 0.000 0.007 0.071
artlog/artparse00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:02:15.537 0.2%108584254 0.001 0.001 0.002
hisgrep/artcncl00:00:00.398 0.0%188 0.000 2.117 17.000
hishave/artcncl00:00:00.688 0.0%268 0.000 2.567 43.200
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:54:08.399 3.8%2680407 0.008 1.212 8.432
history sync00:00:00.016 0.0%290 0.000 0.055 1.000
history write01:47:48.567 7.5%2599058 0.076 2.489 14.671
hiswrite/artcncl00:00:00.249 0.0%80 1.750 3.112 3.184
idle17:11:37.995 71.6%84739757 0.278 0.730 2.526
nntp read00:41:28.611 2.9%94604548 0.019 0.026 0.045
overview write00:00:08.914 0.0%2596756 0.002 0.003 0.008
perl filter02:20:53.780 9.8%2599047 1.335 3.253 6.461
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:29.882 0.2%5212096 0.025 0.040 0.067
TOTAL: 24:00:11.07123:35:14.034 98.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.101 0.0%56107 0.000 0.002 0.010
article prepare00:00:00.001 0.0%7614 0.000 0.000 0.017
article read00:00:52.145 0.1%1895 0.000 27.517 387.833
backlog stats00:00:00.112 0.0%162845 0.000 0.001 0.003
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:01.013 0.0%85277 0.003 0.012 0.026
data write00:00:02.628 0.0%66745 0.015 0.039 0.076
idle23:53:04.643 99.9%162845 346.905 528.015 940.508
status file00:00:19.593 0.0%16444 0.467 1.191 24.467
TOTAL: 23:54:28.45623:54:20.236100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:07:42.444 97.2%4464 0.000 103.594 11944.214
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.263 0.1%9590 0.000 0.027 0.500
readart00:00:00.002 0.0%36 0.000 0.056 0.500
TOTAL: 00:07:55.78000:07:42.709 97.3%----

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
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 865 25903772582875 3881 3621 99%289:48:01
2news.nask.pl 2 12641 7543 2768 2330 59%23:36:51
3news.icm.edu.pl 36 310 16 284 10 5%17:40:30
4localhost 11 10 10 0 0 100%00:00:00
TOTAL: 4 914 26033382590444 6933 5961 99%331:05:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl431.4 GB20.9 MB25.9 MB431.4 GB 99%174.9 KB
2news.nask.pl31.8 MB1.3 MB18.8 MB51.9 MB 61%5.4 KB
3news.icm.edu.pl187.6 KB19.4 KB170.1 KB377.1 KB 49%14.5 KB
4localhost14.3 KB0.0 KB0.0 KB14.3 KB100%1.4 KB
TOTAL: 4431.4 GB22.2 MB44.9 MB431.5 GB 99%174.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 17 04:15:02 - 04:59:59 88540 3.4% 32.8225.6 GB 5.9%9958.92
May 17 05:00:00 - 05:59:59 118685 4.6% 32.9733.2 GB 7.7%9657.71
May 17 06:00:00 - 06:59:59 22511 0.9% 6.257.9 GB 1.8%2311.57
May 17 07:00:00 - 07:59:59 18728 0.7% 5.207.1 GB 1.6%2064.60
May 17 08:00:00 - 08:59:59 21628 0.8% 6.017.3 GB 1.7%2118.76
May 17 09:00:00 - 09:59:59 21307 0.8% 5.927.3 GB 1.7%2127.17
May 17 10:00:00 - 10:59:59 21545 0.8% 5.987.5 GB 1.7%2189.53
May 17 11:00:00 - 11:59:59 19877 0.8% 5.526.7 GB 1.5%1942.11
May 17 12:00:00 - 12:59:59 20781 0.8% 5.776.9 GB 1.6%2003.74
May 17 13:00:00 - 13:59:59 22197 0.9% 6.177.2 GB 1.7%2095.33
May 17 14:00:00 - 14:59:59 22923 0.9% 6.377.5 GB 1.7%2182.93
May 17 15:00:00 - 15:59:59 23208 0.9% 6.457.6 GB 1.8%2205.34
May 17 16:00:00 - 16:59:59 85689 3.3% 23.8023.3 GB 5.4%6786.23
May 17 17:00:00 - 17:59:59 89589 3.5% 24.8924.9 GB 5.8%7255.32
May 17 18:00:00 - 18:59:59 105834 4.1% 29.4023.9 GB 5.5%6957.14
May 17 19:00:00 - 19:59:59 247980 9.6% 68.8830.2 GB 7.0%8806.19
May 17 20:00:00 - 20:59:59 514134 19.9% 142.8124.2 GB 5.6%7049.42
May 17 21:00:00 - 21:59:59 318036 12.3% 88.3419.1 GB 4.4%5575.65
May 17 22:00:00 - 22:59:59 138676 5.4% 38.5224.5 GB 5.7%7125.65
May 17 23:00:00 - 23:59:59 141044 5.4% 39.1827.9 GB 6.5%8116.92
May 18 00:00:00 - 00:59:59 79537 3.1% 22.0922.0 GB 5.1%6412.52
May 18 01:00:00 - 01:59:59 160933 6.2% 44.7030.3 GB 7.0%8820.68
May 18 02:00:00 - 02:59:59 173608 6.7% 48.2229.5 GB 6.8%8600.67
May 18 03:00:00 - 03:59:59 106059 4.1% 29.4618.6 GB 4.3%5423.60
May 18 04:00:00 - 04:15:02 6952 0.3% 7.711.8 GB 0.4%2127.90
TOTAL: 24:00:00 2590001 100.0% 29.98432.0 GB 100.0%5242.73
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 4076 250 0 3295 2 0 0 0 529
2news.nask.pl 2484 28 0 357 5 0 0 0 2094
3news.icm.edu.pl 18 0 0 2 0 0 0 0 16
TOTAL: 3 6578 278 0 3654 7 0 0 0 2639

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 191
it.comp.os.win.windows10 32
comp.sys.raspberry-pi 30
comp.mobile.android 13
it.tlc.cellulari.android 7
it.comp.os.win.windows7 4
uk.rec.cycling.moderated 1
TOTAL: 7 278

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

GroupsCount
pl.sci.matematyka 5
bit.listserv.catholic 1
pl.praca.oferowana 1
TOTAL: 3 7

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1707
Binary in non-binary group 159
EMP (phl) 56
Scoring filter (8) 30
Scoring filter (9) 16
HTML post 12
Scoring filter (10) 9
Scoring filter (18) 5
Too many newsgroups 2
Scoring filter (13) 2
EMP (fsl) 1
Scoring filter (16) 1
TOTAL: 12 2000

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 8
TOTAL: 1 8
Including strange strings
newsfeed.pionier.net.pl 452
news.nask.pl 168
news.icm.edu.pl 8
TOTAL: 3 628
TOTAL: 2 636

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 41253 590 13129 155 13 42436 1%23:59:58
2poznan 11681 330 7161 18 0 1 2%23:59:51
3icm 26251 69 18155 8 0 0 0%23:59:56
4nask 5656 5 4028 0 0 0 0%23:52:23
5okapi 0 0 0 0 0 1148 0%23:57:50
TOTAL: 5 84841 994 42473 181 13 43585 1%119:49:58
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm2.2 MB17.4 KB2.2 MB0.0 KB/s29.7 KB23:59:56
2panorama1.7 MB649.7 KB2.3 MB0.0 KB/s3.2 KB23:59:58
3poznan1.2 MB96.2 KB1.3 MB0.0 KB/s3.9 KB23:59:51
4nask258.9 KB0.0 KB258.9 KB0.0 KB/s51.8 KB23:52:23
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 55.4 MB763.3 KB6.1 MB0.0 KB/s5.3 KB119:49:58
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1625.4 KB 7 0 000:00:19
20890788407.static.corbina.ru 13 1014.3 KB 5 0 000:01:54
3isvcs01.man.lodz.pl 10 00.0 KB 0 10 000:00:02
TOTAL: 3 311 2639.7 KB 12 10 000:07:55

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 682 1625.4 KB 7 10 000:00:25
2*.static.corbina.ru 13 1014.3 KB 5 0 000:01:54
3*.49.rev.vline.pl 3 00.0 KB 0 0 000:00:39
4unresolved 5 00.0 KB 0 0 000:04:55
TOTAL: 4 703 2639.7 KB 12 10 000:07:55

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
12.37.173.34 0.032 0.036 0.00000:02:47
89.38.40.118 0.052 0.160 0.00000:02:08
0890788407.static.corbina.ru 0.048 0.156 0.00000:01:54
119.49.rev.vline.pl 0.020 0.024 0.00000:00:39
web.man.lodz.pl 1.036 4.092 0.00000:00:19
mon.man.lodz.pl 0.984 3.568 0.00000:00:03
isvcs01.man.lodz.pl 0.068 0.152 0.00000:00:02
david.man.lodz.pl 0.388 1.268 0.00000:00:01
85.93.20.6 0.012 0.008 0.00000:00:00
TOTAL: 9 2.640 9.464 0.00000:07:55

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
119.49.rev.vline.pl 3
12.37.173.34 2
89.38.40.118 2
85.93.20.6 1
TOTAL: 6 392

NNRP unrecognized commands (by host):

SystemConn
12.37.173.34 2
85.93.20.6 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

CommandCount
who 1
? 1
#003 1
TOTAL: 3 3

NNRP client timeouts [Top 20]:

SystemConnPeer
85.93.20.6 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 16 61.5%
2lodman 10 38.5%
TOTAL: 2 26100.0%

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

NewsgroupCount
1pl.comp.pecet 16
2lodman.info 10
TOTAL: 2 26