Daily Usenet report

Sep 8 04:15:01 -- Sep 9 04:15:02

Unknown entries from news log file:

First 26 / 26 lines (100.0%)

Sep 8 04:39:23 newsfeed overchan[165578]: input line too long, skipping
Sep 8 04:46:49 newsfeed overchan[165578]: input line too long, skipping
Sep 8 04:49:06 newsfeed overchan[165578]: input line too long, skipping
Sep 8 04:50:33 newsfeed overchan[165578]: input line too long, skipping
Sep 8 05:34:00 newsfeed overchan[165578]: input line too long, skipping
Sep 8 06:46:17 newsfeed overchan[165578]: input line too long, skipping
Sep 8 06:46:54 newsfeed overchan[165578]: input line too long, skipping
Sep 8 06:47:11 newsfeed overchan[165578]: input line too long, skipping
Sep 8 07:05:37 newsfeed overchan[165578]: input line too long, skipping
Sep 8 09:04:28 newsfeed nnrpd[179327]: SERVER perl filter not defined
Sep 8 11:46:43 newsfeed nnrpd[186518]: SERVER perl filter not defined
Sep 8 15:05:28 newsfeed overchan[165578]: input line too long, skipping
Sep 8 15:39:17 newsfeed nnrpd[196891]: SERVER perl filter not defined
Sep 8 15:39:44 newsfeed nnrpd[196894]: SERVER perl filter not defined
Sep 8 16:11:36 newsfeed nnrpd[198373]: SERVER perl filter not defined
Sep 8 16:28:51 newsfeed overchan[165578]: input line too long, skipping
Sep 8 16:29:10 newsfeed overchan[165578]: input line too long, skipping
Sep 8 16:29:26 newsfeed overchan[165578]: input line too long, skipping
Sep 8 19:06:34 newsfeed overchan[165578]: input line too long, skipping
Sep 8 20:32:16 newsfeed overchan[165578]: input line too long, skipping
Sep 8 23:41:46 newsfeed overchan[165578]: input line too long, skipping
Sep 9 01:52:31 newsfeed overchan[165578]: input line too long, skipping
Sep 9 01:53:09 newsfeed overchan[165578]: input line too long, skipping
Sep 9 01:53:26 newsfeed overchan[165578]: input line too long, skipping
Sep 9 02:55:18 newsfeed overchan[165578]: input line too long, skipping
Sep 9 03:47:04 newsfeed overchan[165578]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 116748 91.0%13.5 MB 92.1%
nnrpd 8170 6.4%812.0 KB 5.4%
innd 1964 1.5%226.2 KB 1.5%
innfeed 1387 1.1%146.9 KB 1.0%
overchan 21 0.0%2.3 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 128291 100.0%14.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 116169 97.1%
Negative hits 2713 2.3%
Positive hits 677 0.6%
Cache misses 22 0.0%
TOTAL: 4 119581 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.635 0.0%39 0.000 16.282 57.000
article cleanup00:00:00.848 0.0%116688 0.001 0.007 0.016
article logging00:00:02.967 0.0%116697 0.005 0.025 0.043
article parse00:00:04.474 0.0%581023 0.003 0.008 0.012
article write00:00:21.650 0.0%116425 0.061 0.186 6.867
artlog/artcncl00:00:00.001 0.0%36 0.000 0.028 0.500
data move00:00:00.845 0.0%787953 0.000 0.001 0.004
hisgrep/artcncl00:00:00.170 0.0%39 0.000 4.359 12.100
hishave/artcncl00:00:00.085 0.0%39 0.000 2.179 5.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:08:46.942 0.6%120040 1.734 4.390 27.603
history sync00:00:00.023 0.0%369 0.000 0.062 0.500
history write00:08:01.060 0.6%116665 2.393 4.123 28.081
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:40:37.787 98.6%678515 20.086 125.624 297.694
nntp read00:00:10.662 0.0%575609 0.012 0.019 0.030
overview write00:00:00.454 0.0%116425 0.000 0.004 0.011
perl filter00:02:18.863 0.2%116665 0.790 1.190 1.807
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:03.956 0.0%244145 0.008 0.016 0.024
TOTAL: 24:00:58.38924:00:31.422100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.034 0.0%11262 0.000 0.003 0.045
article prepare00:00:00.000 0.0%124 0.000 0.000 0.000
article read00:00:00.001 0.0%31 0.000 0.032 1.000
backlog stats00:00:00.023 0.0%52033 0.000 0.000 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.302 0.0%27035 0.000 0.011 0.047
data write00:00:00.741 0.0%15917 0.000 0.047 0.134
idle23:58:09.560100.0%52033 1034.983 1658.362 4320.158
status file00:00:00.751 0.0%372 0.000 2.019 35.333
TOTAL: 23:58:12.37023:58:11.412100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:20.414 36.8%4351 0.000 18.482 10048.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.170 0.1%10550 0.000 0.016 0.500
readart00:00:00.000 0.0%6 0.000 0.000 0.000
TOTAL: 00:03:38.54900:01:20.584 36.9%----

Control commands to INND:

CommandNumber
flush 27
flushlogs 2
go 2
logmode 2
lowmark 1
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 192

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 70 116676 116486 2 188 99%40:25:47
2news.icm.edu.pl 18 622 24 529 69 3%19:31:38
3localhost 6 5 5 0 0 100%00:00:00
TOTAL: 3 94 117303 116515 531 257 99%59:57:25
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl1.5 GB393.5 KB862.2 KB1.5 GB 99%13.4 KB
2news.icm.edu.pl652.9 KB36.9 KB1.2 MB1.8 MB 34%20.1 KB
3localhost7.1 KB0.0 KB0.0 KB7.1 KB100%1.4 KB
TOTAL: 31.5 GB430.4 KB2.0 MB1.5 GB 99%13.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 8 04:15:01 - 04:59:59 2302 2.0% 0.8530.9 MB 2.0% 11.74
Sep 8 05:00:00 - 05:59:59 3681 3.2% 1.0235.7 MB 2.3% 10.14
Sep 8 06:00:00 - 06:59:59 4219 3.6% 1.1742.6 MB 2.8% 12.13
Sep 8 07:00:00 - 07:59:59 4074 3.5% 1.1354.1 MB 3.5% 15.39
Sep 8 08:00:00 - 08:59:59 4476 3.8% 1.2442.2 MB 2.8% 12.00
Sep 8 09:00:00 - 09:59:59 6176 5.3% 1.7258.8 MB 3.9% 16.72
Sep 8 10:00:00 - 10:59:59 4766 4.1% 1.3259.3 MB 3.9% 16.88
Sep 8 11:00:00 - 11:59:59 4735 4.1% 1.3265.0 MB 4.3% 18.50
Sep 8 12:00:00 - 12:59:59 6753 5.8% 1.88158.8 MB 10.4% 45.16
Sep 8 13:00:00 - 13:59:59 3675 3.2% 1.0253.6 MB 3.5% 15.26
Sep 8 14:00:00 - 14:59:59 4384 3.8% 1.2256.6 MB 3.7% 16.11
Sep 8 15:00:00 - 15:59:59 4139 3.6% 1.1556.7 MB 3.7% 16.14
Sep 8 16:00:00 - 16:59:59 4016 3.4% 1.1251.4 MB 3.4% 14.61
Sep 8 17:00:00 - 17:59:59 4820 4.1% 1.3471.8 MB 4.7% 20.41
Sep 8 18:00:00 - 18:59:59 4238 3.6% 1.1846.1 MB 3.0% 13.10
Sep 8 19:00:00 - 19:59:59 7778 6.7% 2.1673.7 MB 4.8% 20.96
Sep 8 20:00:00 - 20:59:59 6206 5.3% 1.7278.4 MB 5.1% 22.29
Sep 8 21:00:00 - 21:59:59 5025 4.3% 1.4080.5 MB 5.3% 22.89
Sep 8 22:00:00 - 22:59:59 5557 4.8% 1.5494.5 MB 6.2% 26.88
Sep 8 23:00:00 - 23:59:59 5205 4.5% 1.4592.2 MB 6.0% 26.23
Sep 9 00:00:00 - 00:59:59 4949 4.3% 1.3746.6 MB 3.1% 13.25
Sep 9 01:00:00 - 01:59:59 4375 3.8% 1.2259.2 MB 3.9% 16.85
Sep 9 02:00:00 - 02:59:59 4444 3.8% 1.2357.5 MB 3.8% 16.36
Sep 9 03:00:00 - 03:59:59 5148 4.4% 1.4346.0 MB 3.0% 13.09
Sep 9 04:00:00 - 04:15:02 1299 1.1% 1.4413.1 MB 0.9% 14.86
TOTAL: 24:00:01 116440 100.0% 1.351.5 GB 100.0% 18.08
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 197 167 0 19 0 0 0 0 11
2news.icm.edu.pl 75 0 0 4 0 0 0 0 71
TOTAL: 2 272 167 0 23 0 0 0 0 82

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 112
comp.mobile.android 20
it.tlc.cellulari.android 10
it.comp.os.win.windows10 7
de.comm.provider.telefonie 6
alt.comp.software.seamonkey 5
comp.sys.raspberry-pi 4
de.rec.sf.startrek 1
it.comp.os.linux.ubuntu 1
de.comp.lang.vbclassic 1
TOTAL: 10 167

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 39
Scoring filter (8) 18
Scoring filter (9) 9
EMP (md5) 7
TOTAL: 4 73

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 15685 11 11092 20 0 0 0%23:59:59
2poznan 42 0 30 0 0 0 0%03:44:25
3panorama 0 0 0 0 0 11263 0%23:57:48
4nask 0 0 0 0 0 8070 0%23:57:48
5okapi 0 0 0 0 0 665 0%23:57:48
TOTAL: 5 15727 11 11122 20 0 19998 0%99:37:48
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm36.8 KB342.3 KB379.1 KB0.0 KB/s12.2 KB23:59:59
2poznan0.0 KB0.0 KB0.0 KB0.0 KB/s003:44:25
3panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
TOTAL: 536.8 KB342.3 KB379.1 KB0.0 KB/s12.2 KB99:37:48
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
okapi488.4 KB
panorama488.3 KB
TOTAL: 2976.7 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 289 66.1 KB 3 0 000:00:09
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 2 294 66.1 KB 3 5 000:03:38

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1734 66.1 KB 3 5 000:00:23
2*.ip.linodeusercontent.com 58 00.0 KB 0 0 000:02:25
3unresolved 183 00.0 KB 0 0 000:00:49
TOTAL: 31975 66.1 KB 3 5 000:03:38

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
172-105-246-139.ip.linodeusercontent.com 0.231 0.288 0.00000:02:25
109.74.204.123 0.563 0.465 0.00000:00:16
nms2.man.lodz.pl 5.005 7.497 0.00000:00:14
172.105.9.235 0.006 0.006 0.00000:00:10
198.199.118.93 0.008 0.004 0.00000:00:09
192.241.195.22 0.004 0.008 0.00000:00:09
web.man.lodz.pl 1.068 1.488 0.00000:00:09
80.111.142.213 0.016 0.029 0.00000:00:02
212.51.192.185 0.383 0.461 0.00000:00:01
107.170.208.29 0.000 0.012 0.00000:00:00
isvcs01.man.lodz.pl 0.053 0.016 0.00000:00:00
45.227.254.48 0.006 0.006 0.00000:00:00
TOTAL: 12 7.343 10.280 0.00000:03:38

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
109.74.204.123 78
172-105-246-139.ip.linodeusercontent.com 58
80.111.142.213 4
172.105.9.235 1
107.170.208.29 1
192.241.195.22 1
45.227.254.48 1
198.199.118.93 1
TOTAL: 10 1681

NNRP unrecognized commands (by host):

SystemConn
172-105-246-139.ip.linodeusercontent.com 2
107.170.208.29 1
45.227.254.48 1
TOTAL: 3 4

NNRP unrecognized commands (by command):

CommandCount
#003 1
#026#003#001#002 1
MGLNDD_212.191.2.21_119 1
À#024 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
172-105-246-139.ip.linodeusercontent.com 1 1
45.227.254.48 1 1
TOTAL: 2 2 2

Newsgroup request counts (by category):

CategoryCountPct
1pl 6100.0%
TOTAL: 1 6100.0%

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

NewsgroupCount
1pl.comp.pecet 6
TOTAL: 1 6