Daily Usenet report

Jul 5 04:15:01 -- Jul 6 04:15:01

Unknown entries from news log file:

First 30 / 30 lines (100.0%)

Jul 5 06:51:15 newsfeed overchan[309446]: input line too long, skipping
Jul 5 15:35:24 newsfeed overchan[309446]: input line too long, skipping
Jul 5 16:24:31 newsfeed overchan[309446]: input line too long, skipping
Jul 5 16:41:22 newsfeed overchan[309446]: input line too long, skipping
Jul 5 18:41:19 newsfeed nnrpd[348652]: SERVER perl filter not defined
Jul 5 20:48:36 newsfeed pgpverify[354278]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:49:18 newsfeed pgpverify[354296]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:50:00 newsfeed pgpverify[354322]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:50:42 newsfeed pgpverify[354417]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:51:24 newsfeed pgpverify[354435]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:52:06 newsfeed pgpverify[354451]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:52:48 newsfeed pgpverify[354467]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:53:30 newsfeed pgpverify[354478]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:53:50 newsfeed overchan[309446]: input line too long, skipping
Jul 5 20:54:12 newsfeed pgpverify[354493]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:54:36 newsfeed overchan[309446]: input line too long, skipping
Jul 5 20:54:54 newsfeed pgpverify[354666]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:55:36 newsfeed pgpverify[354760]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:56:18 newsfeed pgpverify[354777]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:57:00 newsfeed pgpverify[354794]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:57:42 newsfeed pgpverify[354805]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:58:24 newsfeed pgpverify[354823]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:59:06 newsfeed pgpverify[354838]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 20:59:48 newsfeed pgpverify[354866]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 21:00:30 newsfeed pgpverify[354986]: pgpverify: /usr/bin/gpg1: no such file
Jul 5 21:12:03 newsfeed pgpverify[355455]: pgpverify: /usr/bin/gpg1: no such file
Jul 6 01:02:23 newsfeed overchan[309446]: input line too long, skipping
Jul 6 01:11:55 newsfeed nnrpd[366215]: SERVER perl filter not defined
Jul 6 01:57:43 newsfeed overchan[309446]: input line too long, skipping
Jul 6 02:46:26 newsfeed nnrpd[370448]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn 70652 87.0%8.1 MB 88.6%
nnrpd 7737 9.5%762.8 KB 8.2%
innd 1426 1.8%176.7 KB 1.9%
innfeed 1291 1.6%122.5 KB 1.3%
controlchan 30 0.0%4.2 KB 0.0%
pgpverify 19 0.0%2.3 KB 0.0%
overchan 9 0.0%1.2 KB 0.0%
TOTAL: 7 81164 100.0%9.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 70237 98.4%
Negative hits 847 1.2%
Positive hits 265 0.4%
Cache misses 25 0.0%
TOTAL: 4 71374 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.863 0.0%58 0.000 14.879 56.000
article cleanup00:00:00.316 0.0%70280 0.000 0.004 0.022
article logging00:00:01.264 0.0%70598 0.004 0.018 0.043
article parse00:00:01.488 0.0%476712 0.000 0.003 0.010
article write00:00:11.206 0.0%70185 0.035 0.160 2.071
artlog/artcncl00:00:00.000 0.0%58 0.000 0.000 0.000
data move00:00:00.468 0.0%644856 0.000 0.001 0.006
hisgrep/artcncl00:00:00.210 0.0%53 0.000 3.962 25.000
hishave/artcncl00:00:00.108 0.0%58 0.000 1.862 8.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:07:05.321 0.5%71359 3.602 5.960 49.641
history sync00:00:00.007 0.0%299 0.000 0.023 0.500
history write00:04:33.847 0.3%70275 2.353 3.897 41.480
hiswrite/artcncl00:00:00.014 0.0%5 0.000 2.800 3.500
idle23:46:41.444 99.1%541921 21.078 157.959 904.856
nntp read00:00:04.588 0.0%474518 0.004 0.010 0.019
overview write00:00:00.137 0.0%70185 0.000 0.002 0.014
perl filter00:01:01.537 0.1%70275 0.630 0.876 1.649
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.503 0.0%151459 0.003 0.010 0.030
TOTAL: 24:00:12.44323:59:44.321100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.015 0.0%11069 0.000 0.001 0.022
article prepare00:00:00.000 0.0%144 0.000 0.000 0.000
article read00:00:00.001 0.0%36 0.000 0.028 0.500
backlog stats00:00:00.025 0.0%49501 0.000 0.001 0.006
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.191 0.0%25969 0.000 0.007 0.032
data write00:00:00.413 0.0%15074 0.000 0.027 0.133
idle23:59:16.700100.0%49501 985.491 1744.545 5039.760
status file00:00:00.218 0.0%333 0.000 0.655 75.000
TOTAL: 23:59:18.14023:59:17.563100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:05.411 39.1%4217 0.000 15.511 10021.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.117 0.1%10190 0.000 0.011 0.333
readart00:00:00.000 0.0%6 0.000 0.000 0.000
TOTAL: 00:02:47.12800:01:05.528 39.2%----

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
control@usenet-fr.news.eu.org0011901
TOTAL0011901

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 60 70097 70009 7 81 99%38:42:42
2news.icm.edu.pl 45 253 12 227 14 4%16:58:25
3localhost 4 3 3 0 0 100%00:00:01
TOTAL: 3 109 70353 70024 234 95 99%55:41:08
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl1.1 GB103.8 KB283.5 KB1.1 GB 99%16.1 KB
2news.icm.edu.pl935.2 KB0.0 KB185.2 KB1.1 MB 83%43.1 KB
3localhost4.4 KB0.0 KB0.0 KB4.4 KB100%1.5 KB
TOTAL: 31.1 GB103.8 KB468.7 KB1.1 GB 99%16.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 5 04:15:01 - 04:59:59 1307 1.9% 0.4814.8 MB 1.3% 5.63
Jul 5 05:00:00 - 05:59:59 2334 3.3% 0.6546.3 MB 4.2% 13.17
Jul 5 06:00:00 - 06:59:59 1985 2.8% 0.5524.8 MB 2.2% 7.04
Jul 5 07:00:00 - 07:59:59 1441 2.1% 0.4019.9 MB 1.8% 5.65
Jul 5 08:00:00 - 08:59:59 3012 4.3% 0.8480.4 MB 7.3% 22.86
Jul 5 09:00:00 - 09:59:59 2910 4.1% 0.8136.9 MB 3.3% 10.48
Jul 5 10:00:00 - 10:59:59 3128 4.5% 0.8741.0 MB 3.7% 11.66
Jul 5 11:00:00 - 11:59:59 2868 4.1% 0.8034.1 MB 3.1% 9.70
Jul 5 12:00:00 - 12:59:59 2753 3.9% 0.7633.9 MB 3.1% 9.64
Jul 5 13:00:00 - 13:59:59 4105 5.8% 1.14131.2 MB 11.9% 37.33
Jul 5 14:00:00 - 14:59:59 4216 6.0% 1.1762.4 MB 5.6% 17.75
Jul 5 15:00:00 - 15:59:59 3179 4.5% 0.8844.9 MB 4.1% 12.76
Jul 5 16:00:00 - 16:59:59 2411 3.4% 0.6740.0 MB 3.6% 11.38
Jul 5 17:00:00 - 17:59:59 5184 7.4% 1.4470.9 MB 6.4% 20.18
Jul 5 18:00:00 - 18:59:59 3108 4.4% 0.8650.7 MB 4.6% 14.41
Jul 5 19:00:00 - 19:59:59 3364 4.8% 0.9348.8 MB 4.4% 13.89
Jul 5 20:00:00 - 20:59:59 2655 3.8% 0.7444.3 MB 4.0% 12.61
Jul 5 21:00:00 - 21:59:59 3914 5.6% 1.0953.9 MB 4.9% 15.34
Jul 5 22:00:00 - 22:59:59 2695 3.8% 0.7534.4 MB 3.1% 9.79
Jul 5 23:00:00 - 23:59:59 2557 3.6% 0.7134.9 MB 3.2% 9.94
Jul 6 00:00:00 - 00:59:59 3561 5.1% 0.9941.0 MB 3.7% 11.66
Jul 6 01:00:00 - 01:59:59 3406 4.9% 0.9561.5 MB 5.6% 17.50
Jul 6 02:00:00 - 02:59:59 2324 3.3% 0.6533.7 MB 3.1% 9.60
Jul 6 03:00:00 - 03:59:59 1501 2.1% 0.4218.5 MB 1.7% 5.26
Jul 6 04:00:00 - 04:15:01 263 0.4% 0.292.6 MB 0.2% 2.94
TOTAL: 24:00:00 70181 100.0% 0.811.1 GB 100.0% 13.11
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 399 75 0 5 0 0 0 0 319
2news.icm.edu.pl 14 0 0 0 0 0 0 0 14
TOTAL: 2 413 75 0 5 0 0 0 0 333

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 30
comp.mobile.android 18
alt.comp.software.firefox 10
it.comp.os.win.windows10 8
alt.comp.software.seamonkey 5
de.comm.provider.telefonie 2
comp.mobile.ipad 1
comp.sys.raspberry-pi 1
TOTAL: 8 75

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 13
EMP (md5) 1
Scoring filter (10) 1
TOTAL: 3 15

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 14899 25 10878 11 0 0 0%23:55:49
2panorama 0 0 0 0 0 11083 0%23:57:48
3nask 0 0 0 0 0 7380 0%23:57:48
4okapi 0 0 0 0 0 798 0%23:57:48
5poznan 18 0 12 0 0 0 0%01:46:19
TOTAL: 5 14917 25 10890 11 0 19261 0%97:35:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm138.8 KB157.8 KB296.7 KB0.0 KB/s8.2 KB23:55:49
2panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
5poznan0.0 KB0.0 KB0.0 KB0.0 KB/s001:46:19
TOTAL: 5138.8 KB157.8 KB296.7 KB0.0 KB/s8.2 KB97:35:32
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama976.7 KB
okapi488.4 KB
TOTAL: 21.4 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 65.5 KB 3 0 000:00:08
2isvcs01.man.lodz.pl 3 00.0 KB 0 3 000:00:00
TOTAL: 2 291 65.5 KB 3 3 000:02:47

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1731 65.5 KB 3 3 000:00:21
2*.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
3*.us-west-2.compute.amazonaws.com 1 00.0 KB 0 0 000:00:00
4*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
5*.ip.linodeusercontent.com 28 00.0 KB 0 0 000:02:19
6unresolved 102 00.0 KB 0 0 000:00:04
TOTAL: 61864 65.5 KB 3 3 000:02:47

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
172-104-137-47.ip.linodeusercontent.com 0.058 0.395 0.00000:02:19
nms2.man.lodz.pl 2.392 20.151 0.00000:00:12
web.man.lodz.pl 0.588 4.194 0.00000:00:08
80.111.142.213 0.005 0.061 0.00000:00:02
tranquil.monitoring.internet-measurement.com 0.004 0.011 0.00000:00:01
212.51.192.185 0.192 1.314 0.00000:00:00
194.165.16.72 0.004 0.011 0.00000:00:00
192.241.222.20 0.000 0.014 0.00000:00:00
41.149.216.162.bc.googleusercontent.com 0.001 0.015 0.00000:00:00
ec2-100-21-18-199.us-west-2.compute.amazonaws.com 0.000 0.018 0.00000:00:00
isvcs01.man.lodz.pl 0.015 0.042 0.00000:00:00
TOTAL: 11 3.259 26.226 0.00000:02:47

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
172-104-137-47.ip.linodeusercontent.com 28
80.111.142.213 4
192.241.222.20 1
ec2-100-21-18-199.us-west-2.compute.amazonaws.com 1
41.149.216.162.bc.googleusercontent.com 1
194.165.16.72 1
tranquil.monitoring.internet-measurement.com 1
TOTAL: 9 1573

NNRP unrecognized commands (by host):

SystemConn
41.149.216.162.bc.googleusercontent.com 3
192.241.222.20 1
194.165.16.72 1
TOTAL: 3 5

NNRP unrecognized commands (by command):

CommandCount
#003 1
User-Agent: Expanse, a Palo Alto Network... 1
MGLNDD_212.191.2.21_119 1
Host: 212.191.2.21:119 1
GET / HTTP/1.1 1
TOTAL: 5 5

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.72 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 6
TOTAL: 1 6
Valid XHTML 1.1 Strict CSS is valid!
innreport 3.1.0 (c) 1996-1999 by Fabien Tassin <fta@sofaraway.org>