Daily Usenet report

Sep 29 04:15:03 -- Sep 30 04:15:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Sep 29 08:27:36 newsfeed nnrpd[26659]: SERVER perl filter not defined
Sep 29 08:28:22 newsfeed nnrpd[26666]: SERVER perl filter not defined
Sep 29 11:16:49 newsfeed nnrpd[430]: SERVER perl filter not defined
Sep 29 15:11:52 newsfeed nnrpd[9165]: SERVER perl filter not defined
Sep 29 15:13:27 newsfeed nnrpd[9179]: SERVER perl filter not defined
Sep 29 16:08:33 newsfeed overchan[16854]: input line too long, skipping
Sep 29 16:10:40 newsfeed overchan[16854]: input line too long, skipping
Sep 29 16:34:29 newsfeed overchan[16854]: input line too long, skipping
Sep 29 16:48:11 newsfeed overchan[16854]: input line too long, skipping
Sep 29 17:45:19 newsfeed overchan[16854]: input line too long, skipping
Sep 30 03:35:50 newsfeed overchan[16854]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2887736 95.3%336.8 MB 94.9%
innd 91746 3.0%12.1 MB 3.4%
innfeed 46217 1.5%5.4 MB 1.5%
nnrpd 4816 0.2%501.1 KB 0.1%
overchan 7 0.0%1.1 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 63030523 100.0%354.8 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2825414 75.1%
Negative hits 825623 21.9%
Positive hits 111270 3.0%
Cache misses 324 0.0%
TOTAL: 4 3762631 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.959 0.0%223 0.000 8.785 182.000
article cleanup00:00:32.860 0.0%2883809 0.009 0.011 0.016
article logging00:01:23.146 0.1%2884370 0.023 0.029 0.111
article parse01:06:19.604 4.6%242091347 0.011 0.016 0.025
article write00:19:56.977 1.4%2777432 0.198 0.431 1.018
artlog/artcncl00:00:00.006 0.0%220 0.000 0.027 0.111
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:05:13.854 0.4%261019496 0.001 0.001 0.002
hisgrep/artcncl00:00:00.330 0.0%222 0.000 1.486 34.000
hishave/artcncl00:00:00.064 0.0%223 0.000 0.287 2.750
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:52:22.136 3.6%3803446 0.052 0.826 3.507
history sync00:00:00.006 0.0%290 0.000 0.021 0.500
history write01:57:44.955 8.2%2850208 0.256 2.479 7.118
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle12:04:18.601 50.3%177685798 0.172 0.245 0.348
nntp read01:36:54.103 6.7%240206002 0.015 0.024 0.034
overview write00:00:12.494 0.0%2777432 0.003 0.004 0.006
perl filter05:04:33.762 21.1%2850208 3.942 6.411 8.189
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:54.047 0.3%5683680 0.034 0.041 0.057
TOTAL: 24:00:02.86923:13:28.904 96.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.660 0.0%319494 0.000 0.002 0.015
article prepare00:00:00.012 0.0%46442 0.000 0.000 0.009
article read00:00:30.336 0.0%10967 0.000 2.766 1034.500
backlog stats00:00:00.428 0.0%820372 0.000 0.001 0.004
callbacks00:00:00.000 0.0%5 0.000 0.000 0.000
data read00:00:05.967 0.0%496464 0.002 0.012 0.034
data write00:00:16.848 0.0%385294 0.016 0.044 0.079
idle24:02:05.080 99.9%820372 24.396 105.471 836.632
status file00:00:13.146 0.0%16557 0.533 0.794 1.483
TOTAL: 24:03:33.29024:03:12.477100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:13:37.571 97.5%3070 0.000 266.310153953.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.186 0.0%6775 0.000 0.027 0.500
readart00:00:00.000 0.0%30 0.000 0.000 0.000
TOTAL: 00:13:58.32500:13:37.757 97.5%----

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 1000 28505832751587 23422 75574 96%437:41:55
2news.nask.pl 3 115816 29259 56262 30295 25%23:52:11
3news.icm.edu.pl 37 347 67 233 47 19%17:33:05
4localhost 6 5 5 0 0 100%00:00:01
TOTAL: 4 1046 29667512780918 79917 105916 93%479:07:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl871.8 GB7.0 GB15.8 GB894.5 GB 97%331.8 KB
2news.nask.pl6.7 GB615.9 MB5.4 GB12.7 GB 52%223.7 KB
3news.icm.edu.pl2.0 MB12.1 KB2.1 MB4.1 MB 48%37.1 KB
4localhost8.0 KB0.0 KB0.0 KB8.0 KB100%1.6 KB
TOTAL: 4878.5 GB7.6 GB21.1 GB907.2 GB 96%329.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 29 04:15:03 - 04:59:59 66820 2.4% 24.7825.5 GB 2.9%9928.38
Sep 29 05:00:00 - 05:59:59 95092 3.4% 26.4135.7 GB 4.1%10403.21
Sep 29 06:00:00 - 06:59:59 112755 4.1% 31.3239.4 GB 4.5%11490.57
Sep 29 07:00:00 - 07:59:59 118393 4.3% 32.8938.5 GB 4.4%11212.91
Sep 29 08:00:00 - 08:59:59 108450 3.9% 30.1237.5 GB 4.3%10933.47
Sep 29 09:00:00 - 09:59:59 105055 3.8% 29.1832.4 GB 3.7%9444.36
Sep 29 10:00:00 - 10:59:59 100435 3.6% 27.9033.5 GB 3.8%9765.87
Sep 29 11:00:00 - 11:59:59 104224 3.7% 28.9535.7 GB 4.1%10384.61
Sep 29 12:00:00 - 12:59:59 119851 4.3% 33.2941.1 GB 4.7%11963.19
Sep 29 13:00:00 - 13:59:59 136859 4.9% 38.0238.3 GB 4.4%11143.13
Sep 29 14:00:00 - 14:59:59 119974 4.3% 33.3337.3 GB 4.2%10851.88
Sep 29 15:00:00 - 15:59:59 117291 4.2% 32.5839.7 GB 4.5%11575.36
Sep 29 16:00:00 - 16:59:59 109231 3.9% 30.3434.3 GB 3.9%9994.68
Sep 29 17:00:00 - 17:59:59 100098 3.6% 27.8033.0 GB 3.8%9621.19
Sep 29 18:00:00 - 18:59:59 99783 3.6% 27.7237.7 GB 4.3%10972.34
Sep 29 19:00:00 - 19:59:59 134172 4.8% 37.2738.6 GB 4.4%11253.54
Sep 29 20:00:00 - 20:59:59 125338 4.5% 34.8240.3 GB 4.6%11726.14
Sep 29 21:00:00 - 21:59:59 115848 4.2% 32.1839.2 GB 4.5%11430.33
Sep 29 22:00:00 - 22:59:59 144187 5.2% 40.0539.6 GB 4.5%11547.08
Sep 29 23:00:00 - 23:59:59 161207 5.8% 44.7837.4 GB 4.3%10898.00
Sep 30 00:00:00 - 00:59:59 114289 4.1% 31.7528.5 GB 3.2%8291.62
Sep 30 01:00:00 - 01:59:59 100629 3.6% 27.9535.5 GB 4.0%10345.21
Sep 30 02:00:00 - 02:59:59 117942 4.2% 32.7639.9 GB 4.5%11630.57
Sep 30 03:00:00 - 03:59:59 124914 4.5% 34.7030.7 GB 3.5%8953.07
Sep 30 04:00:00 - 04:15:02 28111 1.0% 31.178.9 GB 1.0%10294.69
TOTAL: 23:59:59 2780948 100.0% 32.19878.4 GB 100.0%10660.53
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 75906 157 0 30878 3 0 0 044868
2news.nask.pl 30610 24 0 2321 3 0 0 028262
3news.icm.edu.pl 52 0 0 1 0 0 0 0 51
TOTAL: 3106568 181 0 33200 6 0 0 073181

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 84
it.tlc.cellulari.android 31
it.comp.os.win.windows10 14
comp.mobile.android 14
de.rec.sf.startrek 12
comp.sys.raspberry-pi 5
it.comp.os.win.windows7 5
it.discussioni.energia 4
uk.rec.cycling.moderated 4
de.comp.office-pakete.ms-office.outlook 3
hr.rec.glazba.hifi 3
nl.comp.os.linux.techniek 1
de.comp.office-pakete.ms-office.word 1
TOTAL: 13 181

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

GroupsCount
pl.praca.oferowana 5
bit.listserv.catholic 1
TOTAL: 2 6

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 69930
EMP (md5) 2584
EMP (fsl) 24
Scoring filter (9) 24
Poison newsgroup 18
Scoring filter (8) 17
Scoring filter (10) 7
HTML post 7
Scoring filter (14) 3
Scoring filter (12) 3
Too many newsgroups 2
Scoring filter (11) 1
TOTAL: 12 72620

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 333
news.nask.pl 224
news.icm.edu.pl 4
TOTAL: 3 561
TOTAL: 1 561

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 120227 7862 80111 1 0 0 6%23:58:43
2panorama 194693 1145 165594 334 18 146128 0%23:59:57
3poznan 15830 232 9215 39 0 0 1%23:59:49
4icm 140913 87 123118 1208 0 0 0%23:59:57
5okapi 0 0 0 0 0 1820 0%23:57:50
TOTAL: 5 471663 9326 378038 1582 18 147948 1%119:56:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask2.6 GB354.2 KB2.6 GB32.2 KB/s353.3 KB23:58:43
2panorama11.8 MB6.4 MB18.2 MB0.2 KB/s12.6 KB23:59:57
3icm2.0 MB411.4 MB413.4 MB4.9 KB/s326.9 KB23:59:57
4poznan925.7 KB236.3 KB1.1 MB0.0 KB/s4.3 KB23:59:49
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 52.7 GB418.4 MB3.1 GB7.5 KB/s295.3 KB119:56:16
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama976.7 KB
TOTAL: 1976.7 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3034.9 KB 14 0 000:00:33
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 2 293 3034.9 KB 14 5 000:13:57

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 677 3034.9 KB 14 5 000:00:47
2*.internetdsl.tpnet.pl 8 00.0 KB 0 0 000:13:10
3unresolved 1 00.0 KB 0 0 000:00:00
TOTAL: 3 686 3034.9 KB 14 5 000:13:57

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
hge218.internetdsl.tpnet.pl 0.064 0.148 0.00000:13:10
web.man.lodz.pl 1.164 3.640 0.00000:00:33
david.man.lodz.pl 0.324 1.212 0.00000:00:06
mon.man.lodz.pl 1.052 3.812 0.00000:00:05
isvcs01.man.lodz.pl 0.040 0.076 0.00000:00:00
202.168.148.82 0.000 0.016 0.00000:00:00
TOTAL: 6 2.644 8.904 0.00000:13:57

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
hge218.internetdsl.tpnet.pl 8
202.168.148.82 1
TOTAL: 4 393

NNRP client timeouts [Top 20]:

SystemConnPeer
hge218.internetdsl.tpnet.pl 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 30100.0%
TOTAL: 1 30100.0%

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

NewsgroupCount
1pl.comp.pecet 26
2pl.comp.os.ms-windows.winnt 4
TOTAL: 2 30