Daily Usenet report

Sep 2 04:15:01 -- Sep 3 04:15:01

Unknown entries from news log file:

First 29 / 29 lines (100.0%)

Sep 2 06:15:44 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 09:37:42 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 10:35:10 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 12:15:01 newsfeed pgpverify[1060252]: pgpverify: /usr/bin/gpg1: no such file
Sep 2 13:29:14 newsfeed nnrpd[1063437]: SERVER perl filter not defined
Sep 2 13:43:47 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 13:46:23 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 13:49:15 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 13:49:41 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 13:50:13 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 14:29:23 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 15:01:14 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 15:35:56 newsfeed nnrpd[1069095]: SERVER perl filter not defined
Sep 2 15:40:53 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 15:45:31 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 15:47:02 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 15:56:05 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 16:15:13 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 17:58:03 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 18:45:12 newsfeed nnrpd[1077423]: SERVER perl filter not defined
Sep 2 19:16:29 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 19:25:03 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 21:10:13 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 21:27:21 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 23:06:28 newsfeed overchan[1037987]: input line too long, skipping
Sep 2 23:06:43 newsfeed overchan[1037987]: input line too long, skipping
Sep 3 00:07:00 newsfeed overchan[1037987]: input line too long, skipping
Sep 3 01:00:33 newsfeed overchan[1037987]: input line too long, skipping
Sep 3 03:32:05 newsfeed overchan[1037987]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 161286 93.6%18.6 MB 94.2%
nnrpd 7673 4.5%783.8 KB 3.9%
innd 2013 1.2%250.9 KB 1.2%
innfeed 1368 0.8%137.1 KB 0.7%
overchan 26 0.0%3.7 KB 0.0%
controlchan 12 0.0%2.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 172379 100.0%19.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 160765 98.2%
Negative hits 2162 1.3%
Positive hits 819 0.5%
Cache misses 36 0.0%
TOTAL: 4 163782 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.417 0.0%84 0.000 16.869 69.500
article cleanup00:00:00.873 0.0%161204 0.000 0.005 0.012
article logging00:00:03.724 0.0%161212 0.013 0.023 0.036
article parse00:00:05.581 0.0%986006 0.003 0.006 0.010
article write00:00:29.863 0.0%160924 0.059 0.186 2.790
artlog/artcncl00:00:00.000 0.0%84 0.000 0.000 0.000
data move00:00:00.917 0.0%1308801 0.000 0.001 0.003
hisgrep/artcncl00:00:00.393 0.0%65 0.000 6.046 22.750
hishave/artcncl00:00:00.169 0.0%84 0.000 2.012 10.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:08:40.880 0.6%164133 0.927 3.174 26.136
history sync00:00:00.015 0.0%300 0.000 0.050 0.500
history write00:19:27.945 1.4%161176 3.853 7.246 35.683
hiswrite/artcncl00:00:00.048 0.0%19 0.000 2.526 6.333
idle23:27:58.886 97.8%1109072 6.480 76.171 225.838
nntp read00:00:12.833 0.0%974687 0.009 0.013 0.021
overview write00:00:00.441 0.0%160924 0.000 0.003 0.008
perl filter00:02:35.773 0.2%161176 0.700 0.966 1.407
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:04.598 0.0%332842 0.007 0.014 0.020
TOTAL: 24:00:18.67923:59:44.356100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.032 0.0%10895 0.000 0.003 0.033
article prepare00:00:00.001 0.0%824 0.000 0.001 0.025
article read00:00:00.005 0.0%206 0.000 0.024 1.000
backlog stats00:00:00.020 0.0%50118 0.000 0.000 0.012
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.249 0.0%26046 0.000 0.010 0.037
data write00:00:00.659 0.0%15322 0.000 0.043 0.111
idle23:50:00.115100.0%50118 886.489 1711.962 7157.738
status file00:00:00.373 0.0%365 0.000 1.022 9.500
TOTAL: 23:50:02.21023:50:01.454100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:32.028 64.9%4192 0.000 7.640 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.134 0.3%10136 0.000 0.013 0.500
readart00:00:00.000 0.0%6 0.000 0.000 0.000
TOTAL: 00:00:49.37200:00:32.162 65.1%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 85 161096 160960 4 132 99%44:09:17
2news.icm.edu.pl 17 835 32 663 140 3%19:09:00
3localhost 4 3 3 0 0 100%00:00:00
TOTAL: 3 106 161934 160995 667 272 99%63:18:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl2.4 GB613.9 KB916.7 KB2.5 GB 99%16.0 KB
2news.icm.edu.pl892.7 KB53.1 KB3.3 MB4.2 MB 20%24.9 KB
3localhost4.3 KB0.0 KB0.0 KB4.3 KB100%1.4 KB
TOTAL: 32.4 GB667.0 KB4.2 MB2.5 GB 99%16.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 2 04:15:01 - 04:59:59 3226 2.0% 1.2035.9 MB 1.4% 13.63
Sep 2 05:00:00 - 05:59:59 3968 2.5% 1.1045.8 MB 1.8% 13.02
Sep 2 06:00:00 - 06:59:59 5456 3.4% 1.5291.3 MB 3.6% 25.96
Sep 2 07:00:00 - 07:59:59 6284 3.9% 1.7572.5 MB 2.9% 20.63
Sep 2 08:00:00 - 08:59:59 7412 4.6% 2.0694.7 MB 3.8% 26.93
Sep 2 09:00:00 - 09:59:59 7882 4.9% 2.1995.1 MB 3.8% 27.06
Sep 2 10:00:00 - 10:59:59 9159 5.7% 2.54185.6 MB 7.4% 52.79
Sep 2 11:00:00 - 11:59:59 9303 5.8% 2.58199.1 MB 7.9% 56.63
Sep 2 12:00:00 - 12:59:59 6594 4.1% 1.83165.8 MB 6.6% 47.16
Sep 2 13:00:00 - 13:59:59 9754 6.1% 2.71356.4 MB 14.2% 101.38
Sep 2 14:00:00 - 14:59:59 7383 4.6% 2.05185.2 MB 7.4% 52.67
Sep 2 15:00:00 - 15:59:59 8318 5.2% 2.31105.1 MB 4.2% 29.88
Sep 2 16:00:00 - 16:59:59 7585 4.7% 2.1183.4 MB 3.3% 23.73
Sep 2 17:00:00 - 17:59:59 8049 5.0% 2.2467.4 MB 2.7% 19.17
Sep 2 18:00:00 - 18:59:59 6634 4.1% 1.8478.6 MB 3.1% 22.35
Sep 2 19:00:00 - 19:59:59 6125 3.8% 1.7075.0 MB 3.0% 21.34
Sep 2 20:00:00 - 20:59:59 4797 3.0% 1.3350.9 MB 2.0% 14.49
Sep 2 21:00:00 - 21:59:59 7481 4.6% 2.08152.5 MB 6.1% 43.39
Sep 2 22:00:00 - 22:59:59 6171 3.8% 1.7157.8 MB 2.3% 16.44
Sep 2 23:00:00 - 23:59:59 5741 3.6% 1.5953.7 MB 2.1% 15.27
Sep 3 00:00:00 - 00:59:59 4445 2.8% 1.2341.0 MB 1.6% 11.66
Sep 3 01:00:00 - 01:59:59 6871 4.3% 1.9182.7 MB 3.3% 23.53
Sep 3 02:00:00 - 02:59:59 5294 3.3% 1.4761.1 MB 2.4% 17.38
Sep 3 03:00:00 - 03:59:59 5089 3.2% 1.4154.5 MB 2.2% 15.50
Sep 3 04:00:00 - 04:15:01 1893 1.2% 2.1017.2 MB 0.7% 19.51
TOTAL: 24:00:00 160914 100.0% 1.862.4 GB 100.0% 29.73
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.icm.edu.pl 148 0 0 3 0 0 0 0 145
2newsfeed.pionier.net.pl 140 107 0 25 0 0 0 0 8
TOTAL: 2 288 107 0 28 0 0 0 0 153

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 36
it.comp.os.win.windows10 22
comp.mobile.android 17
nl.comp.os.linux.techniek 14
it.tlc.cellulari.android 11
alt.comp.software.firefox 1
it.comp.os.win.windows8 1
nl.comp.3d-printen 1
nl.actueel 1
nl.gezondheid.alternatief-genezen 1
nl.comp.sys.android 1
nl.wetenschap.regulier 1
TOTAL: 12 107

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 98
Scoring filter (8) 30
Scoring filter (9) 13
Scoring filter (11) 2
EMP (md5) 1
Scoring filter (12) 1
TOTAL: 6 145

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 15194 205 10590 21 0 0 1%23:59:44
2poznan 51 2 33 0 0 0 3%04:07:25
3okapi 0 0 0 0 0 760 0%23:57:47
4nask 0 0 0 0 0 7738 0%23:57:47
5panorama 0 0 0 0 0 11000 0%23:57:47
TOTAL: 5 15245 207 10623 21 0 19498 1%100:00:30
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm968.8 KB68.4 KB1.0 MB0.0 KB/s4.6 KB23:59:44
2poznan29.7 KB0.0 KB29.7 KB0.0 KB/s14.9 KB04:07:25
3okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
5panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:47
TOTAL: 5998.6 KB68.4 KB1.0 MB0.0 KB/s4.7 KB100:00:30
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama488.4 KB
TOTAL: 1488.4 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 67.5 KB 3 0 000:00:09
2isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:00
TOTAL: 2 291 67.5 KB 3 3 000:00:49

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1731 67.5 KB 3 3 000:00:22
2unresolved 107 00.0 KB 0 0 000:00:24
3*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
TOTAL: 31839 67.5 KB 3 3 000:00:49

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms2.man.lodz.pl 3.607 6.395 0.00000:00:13
104.152.52.93 0.003 0.003 0.00000:00:10
192.241.232.40 0.000 0.009 0.00000:00:09
web.man.lodz.pl 0.703 1.490 0.00000:00:09
80.111.142.213 0.007 0.023 0.00000:00:02
intuitive.monitoring.internet-measurement.com 0.000 0.010 0.00000:00:01
183.136.225.32 0.000 0.011 0.00000:00:01
212.51.192.185 0.282 0.466 0.00000:00:01
192.241.207.47 0.000 0.006 0.00000:00:00
104.152.52.133 0.000 0.008 0.00000:00:00
isvcs01.man.lodz.pl 0.017 0.011 0.00000:00:00
80.66.88.211 0.007 0.004 0.00000:00:00
TOTAL: 12 4.626 8.436 0.00000:00:49

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
80.111.142.213 4
183.136.225.32 2
80.66.88.211 1
intuitive.monitoring.internet-measurement.com 1
192.241.207.47 1
104.152.52.133 1
104.152.52.93 1
192.241.232.40 1
TOTAL: 10 1548

NNRP unrecognized commands (by host):

SystemConn
183.136.225.32 10
80.66.88.211 1
192.241.207.47 1
TOTAL: 3 12

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 2
Connection: keep-alive 2
User-Agent: Mozilla/5.0 (Macintosh; Inte... 2
Accept: */* 2
Host: 212.191.2.21:119 2
#003 1
MGLNDD_212.191.2.21_119 1
TOTAL: 7 12

NNRP client timeouts [Top 20]:

SystemConnPeer
80.66.88.211 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 6100.0%
TOTAL: 1 6100.0%

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

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