Daily Usenet report

Sep 26 04:15:03 -- Sep 27 04:15:02

Unknown entries from news log file:

First 22 / 22 lines (100.0%)

Sep 26 04:18:12 newsfeed overchan[28294]: input line too long, skipping
Sep 26 04:20:00 newsfeed overchan[28294]: input line too long, skipping
Sep 26 04:20:21 newsfeed overchan[28294]: input line too long, skipping
Sep 26 08:29:32 newsfeed nnrpd[6035]: SERVER perl filter not defined
Sep 26 08:37:23 newsfeed overchan[28294]: input line too long, skipping
Sep 26 08:38:35 newsfeed overchan[28294]: input line too long, skipping
Sep 26 08:38:54 newsfeed overchan[28294]: input line too long, skipping
Sep 26 11:34:40 newsfeed nnrpd[13151]: SERVER perl filter not defined
Sep 26 14:29:21 newsfeed overchan[28294]: input line too long, skipping
Sep 26 15:29:53 newsfeed nnrpd[22170]: SERVER perl filter not defined
Sep 26 15:47:27 newsfeed nnrpd[22712]: SERVER perl filter not defined
Sep 26 16:29:03 newsfeed overchan[28294]: input line too long, skipping
Sep 26 17:01:27 newsfeed nnrpd[25629]: SERVER perl filter not defined
Sep 26 17:03:47 newsfeed overchan[28294]: input line too long, skipping
Sep 26 17:04:05 newsfeed overchan[28294]: input line too long, skipping
Sep 26 18:39:32 newsfeed overchan[28294]: input line too long, skipping
Sep 26 21:23:20 newsfeed nnrpd[3080]: SERVER perl filter not defined
Sep 27 00:18:00 newsfeed overchan[28294]: input line too long, skipping
Sep 27 00:20:51 newsfeed overchan[28294]: input line too long, skipping
Sep 27 00:21:12 newsfeed overchan[28294]: input line too long, skipping
Sep 27 03:30:32 newsfeed overchan[28294]: input line too long, skipping
Sep 27 03:30:44 newsfeed overchan[28294]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2311560 97.4%273.3 MB 97.2%
innfeed 39401 1.7%4.7 MB 1.7%
innd 17160 0.7%2.8 MB 1.0%
nnrpd 4815 0.2%506.5 KB 0.2%
overchan 17 0.0%2.8 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 62372954 100.0%281.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2288713 86.3%
Positive hits 253756 9.6%
Negative hits 105950 4.0%
Cache misses 2546 0.1%
TOTAL: 4 2650965 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:04.334 0.0%117 0.000 37.043 619.000
article cleanup00:00:32.629 0.0%2310495 0.009 0.014 0.018
article logging00:02:25.320 0.2%2311057 0.033 0.063 4.207
article parse00:56:00.394 3.9%89855887 0.021 0.037 0.062
article write00:20:58.613 1.5%2292135 0.357 0.549 11.690
artlog/artcncl00:00:00.006 0.0%113 0.000 0.053 1.000
artlog/artparse00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:02:46.146 0.2%104224279 0.001 0.002 0.003
hisgrep/artcncl00:00:00.996 0.0%114 0.000 8.737 54.500
hishave/artcncl00:00:00.672 0.0%117 0.000 5.744 69.125
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:02:39.616 8.5%2666043 0.531 2.761 64.935
history sync00:00:00.013 0.0%290 0.000 0.045 0.500
history write04:13:57.164 17.6%2294778 2.095 6.640 109.687
hiswrite/artcncl00:00:00.023 0.0%3 1.000 7.667 21.000
idle09:33:15.326 39.8%60694043 0.085 0.567 1.094
nntp read01:15:27.735 5.2%88293031 0.033 0.051 0.062
overview write00:00:12.596 0.0%2292135 0.004 0.005 0.008
perl filter04:57:57.949 20.7%2294771 5.832 7.791 9.471
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:17.937 0.4%4922856 0.042 0.065 0.076
TOTAL: 24:00:03.64923:31:37.469 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.926 0.0%591611 0.001 0.003 0.007
article prepare00:00:00.096 0.0%365420 0.000 0.000 0.002
article read00:09:24.416 0.7%81960 0.277 6.886 4542.980
backlog stats00:00:01.966 0.0%2233401 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:28.918 0.0%1259945 0.013 0.023 0.044
data write00:05:03.487 0.4%1122696 0.096 0.270 0.496
idle23:32:07.312 98.7%2233401 18.202 37.936 132.529
status file00:02:24.906 0.2%15259 0.000 9.496 548.329
TOTAL: 23:50:42.35323:49:33.027 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:26.578 63.2%3089 0.000 8.604 9769.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.381 0.9%6854 0.000 0.056 1.368
readart00:00:00.005 0.0%66 0.000 0.076 0.500
TOTAL: 00:00:42.05000:00:26.964 64.1%----

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 599 22917472284461 3271 4015 99%512:38:08
2news.nask.pl 14 259271 9030 236022 14219 3%24:02:34
3news.icm.edu.pl 49 264 143 97 24 54%17:59:59
4localhost 7 6 6 0 0 100%00:00:00
TOTAL: 4 669 25512882293640 239390 18258 89%554:40:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl777.5 GB824.1 MB830.4 MB779.1 GB 99%357.0 KB
2news.nask.pl1.8 GB5.0 GB5.0 GB11.8 GB 15%530.9 KB
3news.icm.edu.pl3.1 MB99.0 KB447.0 KB3.6 MB 85%22.2 KB
4localhost9.2 KB0.0 KB0.0 KB9.2 KB100%1.5 KB
TOTAL: 4779.4 GB5.8 GB5.8 GB790.9 GB 98%358.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 26 04:15:03 - 04:59:59 72723 3.2% 26.9626.0 GB 3.3%10120.98
Sep 26 05:00:00 - 05:59:59 116425 5.1% 32.3440.8 GB 5.2%11893.20
Sep 26 06:00:00 - 06:59:59 108140 4.7% 30.0441.0 GB 5.3%11942.34
Sep 26 07:00:00 - 07:59:59 100814 4.4% 28.0035.6 GB 4.6%10374.66
Sep 26 08:00:00 - 08:59:59 91193 4.0% 25.3332.2 GB 4.1%9390.48
Sep 26 09:00:00 - 09:59:59 97381 4.2% 27.0531.7 GB 4.1%9223.79
Sep 26 10:00:00 - 10:59:59 87397 3.8% 24.2830.0 GB 3.9%8747.02
Sep 26 11:00:00 - 11:59:59 106530 4.6% 29.5935.8 GB 4.6%10425.78
Sep 26 12:00:00 - 12:59:59 107155 4.7% 29.7734.9 GB 4.5%10176.69
Sep 26 13:00:00 - 13:59:59 74132 3.2% 20.5924.5 GB 3.1%7143.14
Sep 26 14:00:00 - 14:59:59 80060 3.5% 22.2425.9 GB 3.3%7544.55
Sep 26 15:00:00 - 15:59:59 73360 3.2% 20.3825.8 GB 3.3%7523.64
Sep 26 16:00:00 - 16:59:59 95724 4.2% 26.5932.4 GB 4.2%9448.32
Sep 26 17:00:00 - 17:59:59 99274 4.3% 27.5832.0 GB 4.1%9335.00
Sep 26 18:00:00 - 18:59:59 100692 4.4% 27.9734.1 GB 4.4%9941.06
Sep 26 19:00:00 - 19:59:59 94008 4.1% 26.1133.3 GB 4.3%9690.66
Sep 26 20:00:00 - 20:59:59 83327 3.6% 23.1529.2 GB 3.8%8511.01
Sep 26 21:00:00 - 21:59:59 121110 5.3% 33.6437.9 GB 4.9%11035.70
Sep 26 22:00:00 - 22:59:59 129162 5.6% 35.8841.7 GB 5.4%12151.18
Sep 26 23:00:00 - 23:59:59 98929 4.3% 27.4828.8 GB 3.7%8393.39
Sep 27 00:00:00 - 00:59:59 53323 2.3% 14.8117.4 GB 2.2%5067.85
Sep 27 01:00:00 - 01:59:59 124119 5.4% 34.4837.8 GB 4.9%11010.00
Sep 27 02:00:00 - 02:59:59 70303 3.1% 19.5326.1 GB 3.3%7595.48
Sep 27 03:00:00 - 03:59:59 85120 3.7% 23.6434.8 GB 4.5%10140.82
Sep 27 04:00:00 - 04:15:02 22116 1.0% 24.528.6 GB 1.1%10026.68
TOTAL: 23:59:59 2292517 100.0% 26.53778.7 GB 100.0%9450.12
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 14481 9 0 12060 13 0 0 0 2399
2newsfeed.pionier.net.pl 4417 230 0 3659 10 0 0 0 518
3news.icm.edu.pl 32 0 0 2 0 0 0 0 30
TOTAL: 3 18930 239 0 15721 23 0 0 0 2947

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 142
it.comp.os.win.windows10 49
comp.sys.raspberry-pi 13
it.tlc.cellulari.android 13
uk.rec.cycling.moderated 8
comp.mobile.android 7
it.comp.os.linux.ubuntu 6
nl.comp.os.linux.techniek 1
TOTAL: 8 239

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

GroupsCount
pl.praca.oferowana 9
pl.sci.matematyka 8
clari.usa.law.supreme 4
bit.listserv.catholic 1
courts.usa.federal.supreme 1
TOTAL: 5 23

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2178
Binary in non-binary group 140
Scoring filter (8) 26
EMP (fsl) 8
HTML post 5
Scoring filter (9) 5
Scoring filter (16) 4
Attached HTML file 2
EMP (phl) 2
Scoring filter (18) 2
Too many newsgroups 1
Scoring filter (10) 1
Scoring filter (17) 1
TOTAL: 13 2375

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
news.nask.pl 95
TOTAL: 1 95
Including strange strings
newsfeed.pionier.net.pl 404
news.nask.pl 150
news.icm.edu.pl 8
TOTAL: 3 562
TOTAL: 2 657

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 475880 58150 262308 1368 0 4875 12%23:59:57
2panorama 319291 5229 287320 213 0 299981 1%23:59:57
3poznan 6969 82 5199 21 0 0 1%23:47:58
4icm 382081 15 311263 26868 0 4 0%23:59:57
5okapi 0 0 0 0 0 1013 0%23:57:50
TOTAL: 51184221 63476 866090 28470 0 305873 5%119:45:39
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask28.0 GB680.4 MB28.7 GB348.2 KB/s505.5 KB23:59:57
2panorama2.4 GB76.4 MB2.4 GB29.6 KB/s469.7 KB23:59:57
3icm3.8 MB12.3 GB12.3 GB149.1 KB/s479.0 KB23:59:57
4poznan334.6 KB148.8 KB483.4 KB0.0 KB/s4.7 KB23:47:58
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 530.4 GB13.0 GB43.4 GB105.6 KB/s495.1 KB119:45:39
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 6668.4 KB 29 0 000:00:23
2isvcs01.man.lodz.pl 6 00.0 KB 0 6 000:00:02
TOTAL: 2 294 6668.4 KB 29 6 000:00:41

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 678 6668.4 KB 29 6 000:00:31
2unresolved 1 00.0 KB 0 0 000:00:09
TOTAL: 2 679 6668.4 KB 29 6 000:00:41

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.520 5.448 0.00000:00:23
107.170.228.32 0.004 0.012 0.00000:00:09
mon.man.lodz.pl 1.492 5.836 0.00000:00:04
isvcs01.man.lodz.pl 0.040 0.152 0.00000:00:02
david.man.lodz.pl 0.496 1.836 0.00000:00:01
TOTAL: 5 3.552 13.284 0.00000:00:41

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
107.170.228.32 1
TOTAL: 3 385

Newsgroup request counts (by category):

CategoryCountPct
1pl 66100.0%
TOTAL: 1 66100.0%

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

NewsgroupCount
1pl.comp.pecet 52
2pl.comp.os.ms-windows.winnt 14
TOTAL: 2 66