Daily Usenet report

Oct 11 04:15:02 -- Oct 12 04:15:03

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Oct 11 09:28:38 newsfeed overchan[27477]: input line too long, skipping
Oct 11 10:51:06 newsfeed nnrpd[10423]: SERVER perl filter not defined
Oct 11 10:53:41 newsfeed nnrpd[10448]: SERVER perl filter not defined
Oct 11 11:12:27 newsfeed nnrpd[11180]: SERVER perl filter not defined
Oct 11 12:05:32 newsfeed nnrpd[13088]: SERVER perl filter not defined
Oct 11 13:24:33 newsfeed nnrpd[16052]: SERVER perl filter not defined
Oct 11 15:03:14 newsfeed nnrpd[19666]: SERVER perl filter not defined
Oct 11 15:19:42 newsfeed nnrpd[20181]: SERVER perl filter not defined
Oct 11 17:00:24 newsfeed nnrpd[23876]: SERVER perl filter not defined
Oct 11 17:48:40 newsfeed overchan[1048]: input line too long, skipping
Oct 11 18:03:23 newsfeed overchan[1048]: input line too long, skipping
Oct 11 18:19:21 newsfeed overchan[1048]: input line too long, skipping
Oct 11 18:36:30 newsfeed overchan[1048]: input line too long, skipping
Oct 11 22:00:17 newsfeed nnrpd[18934]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn4462565 98.3%505.9 MB 97.9%
innfeed 42169 0.9%5.0 MB 1.0%
innd 31763 0.7%5.1 MB 1.0%
nnrpd 4849 0.1%515.2 KB 0.1%
overchan 6 0.0%1.1 KB 0.0%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 64541354 100.0%516.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 4359463 91.8%
Negative hits 253936 5.3%
Positive hits 133708 2.8%
Cache misses 236 0.0%
TOTAL: 4 4747343 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.596 0.0%226 0.000 7.062 90.625
article cleanup00:00:58.416 0.1%4444205 0.009 0.013 0.024
article logging00:02:51.476 0.2%4444751 0.025 0.039 0.128
article parse01:11:44.683 5.0%197235055 0.015 0.022 0.040
article write00:27:07.914 1.9%4385706 0.082 0.371 1.006
artlog/artcncl00:00:00.003 0.0%191 0.000 0.016 0.500
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:04:32.420 0.3%216751038 0.001 0.001 0.003
hisgrep/artcncl00:00:00.626 0.0%220 0.000 2.845 63.625
hishave/artcncl00:00:00.079 0.0%226 0.000 0.350 2.350
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:44:06.133 3.1%4779523 0.018 0.554 5.633
history sync00:00:00.013 0.0%287 0.000 0.045 0.500
history write01:46:43.194 7.5%4389799 0.062 1.459 7.144
hiswrite/artcncl00:00:00.066 0.0%6 0.000 11.000 44.000
idle10:49:50.220 45.4%135113125 0.205 0.289 0.767
nntp read01:26:29.045 6.0%194595139 0.019 0.027 0.048
overview write00:00:21.336 0.0%4385706 0.003 0.005 0.007
perl filter06:25:19.191 26.9%4389799 1.675 5.267 10.226
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:07:19.656 0.5%8959761 0.039 0.049 0.080
TOTAL: 23:50:03.23923:07:26.067 97.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.931 0.0%316813 0.000 0.003 0.019
article prepare00:00:00.030 0.0%75820 0.000 0.000 0.022
article read00:01:48.550 0.1%18202 0.064 5.964 363.615
backlog stats00:00:00.761 0.0%1103610 0.000 0.001 0.004
callbacks00:00:00.000 0.0%19 0.000 0.000 0.000
data read00:00:11.888 0.0%677012 0.008 0.018 0.047
data write00:00:33.344 0.0%504899 0.020 0.066 0.325
idle23:49:03.991 99.8%1103610 26.992 77.694 1009.918
status file00:00:16.171 0.0%15811 0.621 1.023 5.403
TOTAL: 23:52:29.66223:51:55.666100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle13:27:35.082 99.9%270636 0.000 179.042 47249.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:14.102 0.0%541824 0.000 0.026 0.667
readart00:00:00.008 0.0%30 0.000 0.267 2.500
TOTAL: 13:28:26.25713:27:49.192 99.9%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1219 44005534342537 4101 53915 98%430:29:36
2news.nask.pl 2 139692 58778 75641 5273 42%23:52:26
3news.icm.edu.pl 39 306 41 241 24 13%17:34:31
4localhost 10 9 9 0 0 100%00:00:00
TOTAL: 4 1270 45405604401365 79983 59212 96%471:56:33
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl838.2 GB16.3 GB16.4 GB870.9 GB 96%207.7 KB
2news.nask.pl16.8 GB370.2 MB395.2 MB17.6 GB 95%287.5 KB
3news.icm.edu.pl805.6 KB0.0 KB758.4 KB1.5 MB 51%24.1 KB
4localhost14.2 KB0.0 KB0.0 KB14.2 KB100%1.6 KB
TOTAL: 4855.0 GB16.7 GB16.7 GB888.5 GB 96%208.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 11 04:15:02 - 04:59:59 86300 2.0% 31.9926.0 GB 3.0%10100.06
Oct 11 05:00:00 - 05:59:59 122217 2.8% 33.9538.7 GB 4.5%11262.38
Oct 11 06:00:00 - 06:59:59 127465 2.9% 35.4140.2 GB 4.7%11708.80
Oct 11 07:00:00 - 07:59:59 135860 3.1% 37.7442.9 GB 5.0%12488.23
Oct 11 08:00:00 - 08:59:59 107302 2.4% 29.8136.1 GB 4.2%10520.25
Oct 11 09:00:00 - 09:59:59 87578 2.0% 24.3333.0 GB 3.9%9612.24
Oct 11 10:00:00 - 10:59:59 115591 2.6% 32.1140.2 GB 4.7%11711.13
Oct 11 11:00:00 - 11:59:59 113458 2.6% 31.5238.5 GB 4.5%11213.67
Oct 11 12:00:00 - 12:59:59 112932 2.6% 31.3740.6 GB 4.8%11830.86
Oct 11 13:00:00 - 13:59:59 109462 2.5% 30.4139.4 GB 4.6%11484.90
Oct 11 14:00:00 - 14:59:59 97497 2.2% 27.0834.8 GB 4.1%10138.73
Oct 11 15:00:00 - 15:59:59 90786 2.1% 25.2233.4 GB 3.9%9718.25
Oct 11 16:00:00 - 16:59:59 119352 2.7% 33.1540.9 GB 4.8%11910.83
Oct 11 17:00:00 - 17:59:59 100198 2.3% 27.8334.1 GB 4.0%9936.82
Oct 11 18:00:00 - 18:59:59 131273 3.0% 36.4642.2 GB 4.9%12297.18
Oct 11 19:00:00 - 19:59:59 130266 3.0% 36.1941.8 GB 4.9%12174.46
Oct 11 20:00:00 - 20:59:59 114882 2.6% 31.9139.1 GB 4.6%11386.64
Oct 11 21:00:00 - 21:59:59 498117 11.3% 138.3722.1 GB 2.6%6433.72
Oct 11 22:00:00 - 22:59:59 601487 13.7% 167.0818.9 GB 2.2%5509.63
Oct 11 23:00:00 - 23:59:59 509756 11.6% 141.6024.1 GB 2.8%7021.62
Oct 12 00:00:00 - 00:59:59 387020 8.8% 107.5125.3 GB 3.0%7378.56
Oct 12 01:00:00 - 01:59:59 205025 4.7% 56.9537.1 GB 4.3%10807.52
Oct 12 02:00:00 - 02:59:59 134233 3.0% 37.2940.9 GB 4.8%11899.44
Oct 12 03:00:00 - 03:59:59 133466 3.0% 37.0734.9 GB 4.1%10177.39
Oct 12 04:00:00 - 04:15:03 31050 0.7% 34.399.5 GB 1.1%11025.01
TOTAL: 24:00:01 4402573 100.0% 50.96854.7 GB 100.0%10373.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 54305 219 0 53641 4 0 0 0 441
2news.nask.pl 5469 31 0 1459 4 0 0 0 3975
3news.icm.edu.pl 26 0 0 0 0 0 0 0 26
TOTAL: 3 59800 250 0 55100 8 0 0 0 4442

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 121
it.comp.os.win.windows10 66
de.rec.sf.startrek 15
it.tlc.cellulari.android 15
comp.mobile.ipad 10
comp.mobile.android 7
it.comp.os.win.windows7 4
uk.rec.cycling.moderated 4
nl.comp.sys.android 4
nl.comp.os.linux.techniek 2
de.comp.lang.php 2
TOTAL: 11 250

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

GroupsCount
pl.praca.oferowana 7
bit.listserv.catholic 1
TOTAL: 2 8

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3603
Binary in non-binary group 190
Scoring filter (8) 35
Scoring filter (9) 26
Scoring filter (11) 18
Scoring filter (10) 6
HTML post 5
Scoring filter (16) 5
Scoring filter (14) 2
Scoring filter (12) 2
Attached HTML file 1
TOTAL: 11 3893

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 369
news.nask.pl 177
news.icm.edu.pl 2
TOTAL: 3 548
TOTAL: 1 548

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 188042 11196 108721 2 0 0 5%23:49:19
2panorama 146843 2074 117889 492 48 197547 1%23:59:05
3poznan 14532 778 8214 27 0 1 5%23:49:18
4icm 216324 45 183330 3707 0 0 0%23:49:19
5okapi 0 0 0 0 0 556 0%23:47:15
TOTAL: 5 565741 14093 418154 4228 48 198104 2%119:14:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask3.8 GB4.9 KB3.8 GB46.2 KB/s353.5 KB23:49:19
2panorama12.8 MB5.8 MB18.6 MB0.2 KB/s7.4 KB23:59:05
3icm6.5 MB1.2 GB1.2 GB15.3 KB/s348.9 KB23:49:19
4poznan2.5 MB113.5 KB2.7 MB0.0 KB/s3.4 KB23:49:18
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:47:15
TOTAL: 53.8 GB1.2 GB5.0 GB12.3 KB/s288.7 KB119:14:16
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama3.8 MB
TOTAL: 13.8 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3027.1 KB 15 0 000:00:24
2isvcs01.man.lodz.pl 9 00.0 KB 0 9 000:00:01
TOTAL: 2 297 3027.1 KB 15 9 013:28:25

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 681 3027.1 KB 15 9 000:00:32
2unresolved 7 00.0 KB 0 0 013:27:53
TOTAL: 2 688 3027.1 KB 15 9 013:28:25

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
185.48.193.25 16.088 30.140 0.00013:27:40
web.man.lodz.pl 1.428 8.156 0.00000:00:24
45.55.15.248 0.004 0.028 0.00000:00:09
mon.man.lodz.pl 1.332 9.732 0.00000:00:04
46.11.47.133 0.020 0.016 0.00000:00:02
isvcs01.man.lodz.pl 0.052 0.392 0.00000:00:01
david.man.lodz.pl 0.420 2.856 0.00000:00:01
TOTAL: 7 19.344 51.320 0.00013:28:25

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
46.11.47.133 3
185.48.193.25 3
45.55.15.248 1
TOTAL: 5 391

NNRP client timeouts [Top 20]:

SystemConnPeer
185.48.193.25 3 3
TOTAL: 1 3 3

Newsgroup request counts (by category):

CategoryCountPct
1pl 30100.0%
TOTAL: 1 30100.0%

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

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