Daily Usenet report

Jan 7 00:01:01 -- Jan 8 00:01:01

Unknown entries from news log file:

First 29 / 29 lines (100.0%)

Jan 7 00:53:35 www innd: message repeated 2 times: [ ctlinnd command s]
Jan 7 18:07:40 www nnrpd[3592793]: peer.alt119.net load 16.67 > 16
Jan 7 18:08:40 www nnrpd[3593012]: peer.alt119.net load 16.93 > 16
Jan 7 18:10:40 www nnrpd[3593175]: peer.alt119.net load 17.86 > 16
Jan 7 18:12:07 www nnrpd[3593314]: peer.alt119.net load 18.73 > 16
Jan 7 18:12:38 www nnrpd[3593352]: peer.alt119.net load 18.85 > 16
Jan 7 18:13:38 www nnrpd[3593399]: peer.alt119.net load 19.58 > 16
Jan 7 18:15:38 www nnrpd[3593569]: peer.alt119.net load 20.58 > 16
Jan 7 18:19:39 www nnrpd[3594088]: peer.alt119.net load 22.53 > 16
Jan 7 18:20:36 www nnrpd[3594195]: supporting.monitoring.internet-measurement.com load 22.81 > 16
Jan 7 18:27:40 www nnrpd[3594594]: peer.alt119.net load 24.55 > 16
Jan 7 18:43:41 www nnrpd[3595713]: peer.alt119.net load 28.74 > 16
Jan 7 19:15:41 www nnrpd[3597800]: peer.alt119.net load 20.73 > 16
Jan 7 19:38:09 www nnrpd[3599255]: peer.alt119.net load 19.23 > 16
Jan 7 19:38:40 www nnrpd[3599286]: peer.alt119.net load 19.14 > 16
Jan 7 19:39:41 www nnrpd[3599428]: peer.alt119.net load 19.25 > 16
Jan 7 19:41:41 www nnrpd[3599538]: peer.alt119.net load 19.01 > 16
Jan 7 19:45:41 www nnrpd[3599691]: peer.alt119.net load 19.11 > 16
Jan 7 19:53:42 www nnrpd[3600164]: peer.alt119.net load 18.93 > 16
Jan 7 20:02:16 www nnrpd[3600675]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:02:49 www nnrpd[3600700]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:03:49 www nnrpd[3600791]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:04:40 www nnrpd[3600835]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:05:12 www nnrpd[3600876]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:06:13 www nnrpd[3600938]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:08:14 www nnrpd[3601225]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:12:14 www nnrpd[3601541]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:20:15 www nnrpd[3602071]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500
Jan 7 20:36:15 www nnrpd[3603019]: peer.alt119.net rejected loadav [innwatch:load] 1667 gt 1500

Log entries by program:

Program nameLines%LinesSize%Size
inn 97321 74.9%22.6 MB 84.6%
innd 21312 16.4%2.6 MB 9.9%
nnrpd 7507 5.8%767.5 KB 2.8%
innfeed 3842 3.0%735.3 KB 2.7%
rnews 2 0.0%0.3 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 6 129985 100.0%26.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 425961 62.0%
Negative hits 208719 30.4%
Do not exist 44957 6.5%
Cache misses 7221 1.1%
TOTAL: 4 686858 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:26.818 0.0%62329 0.233 0.430 1.807
article cleanup00:00:00.920 0.0%42619 0.000 0.022 0.060
article logging00:00:02.225 0.0%42638 0.025 0.052 0.104
article parse00:00:05.121 0.0%235579 0.007 0.022 0.037
article write00:00:38.292 0.0%31780 0.837 1.205 2.486
artlog/artcncl00:00:00.856 0.0%54573 0.000 0.016 0.054
artlog/artparse00:00:00.001 0.0%4 0.000 0.250 1.000
data move00:00:02.303 0.0%873090 0.000 0.003 0.006
hisgrep/artcncl00:00:05.659 0.0%59584 0.024 0.095 0.973
hishave/artcncl00:00:01.051 0.0%62213 0.000 0.017 0.119
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:26.994 0.0%630462 0.011 0.043 0.128
history sync00:00:00.079 0.0%308 0.000 0.256 1.500
history write00:00:27.066 0.0%42521 0.235 0.637 2.193
hiswrite/artcncl00:00:00.718 0.0%2749 0.000 0.261 1.000
idle23:46:45.844 98.9%818118 41.865 104.638 4917.697
nntp read00:00:45.341 0.1%826532 0.023 0.055 0.139
overview write00:03:37.393 0.3%31780 0.500 6.841 742.196
perl filter00:04:35.361 0.3%42517 3.824 6.476 12.602
python filter00:00:06.254 0.0%42517 0.071 0.147 0.289
site send00:00:15.729 0.0%64828 0.103 0.243 1.875
TOTAL: 24:02:29.13223:58:24.025 99.7%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.389 0.0%37010 0.000 0.011 0.064
article prepare00:00:00.064 0.0%365916 0.000 0.000 0.001
article read00:00:11.227 0.0%31597 0.162 0.355 0.585
backlog stats00:00:02.440 0.0%1058928 0.000 0.002 0.148
callbacks00:00:00.000 0.0%22 0.000 0.000 0.000
data read00:00:24.400 0.0%795427 0.000 0.031 0.208
data write00:01:55.772 0.1%730358 0.047 0.159 0.284
idle23:49:34.842 99.8%1058928 34.029 81.002 22304.333
status file00:00:00.458 0.0%463 0.000 0.989 7.000
TOTAL: 23:52:45.77923:52:09.592100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:15:13.518 69.5%7395 110.333 123.532 602.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.492 0.1%14818 0.000 0.101 2.500
TOTAL: 00:21:53.64900:15:15.010 69.7%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 3
logmode 2
lowmark 1
mode 143
name 2
pause 3
paused 3
reload 2
reserve 2
throttle 2
TOTAL: 12 170

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 19 56153 11939 42296 1918 21%44:26:24
2usenet.blueworldhosting.com 807 22320 6009 14776 1535 26%22:02:09
3newsfeed.xs3.de 4 26960 2351 24453 156 8%21:12:45
4news.nntp4.net 3 30755 2012 28617 126 6%21:05:25
5news.hispagatos.org 6 16524 1899 14622 3 11%21:51:20
6nntp.comgw.net 5 33871 1470 31629 772 4%22:57:01
7endoftheline-out.news.chmurka.net 4 9511 1140 8320 51 11%22:28:03
8usenet.goja.nl.eu.org 12 32632 961 31418 253 2%21:29:58
9endofthelinebbs.peers.news.panix.com 32 30538 909 29480 149 2%22:09:33
10paganini.bofh.team 4 36652 880 35367 405 2%21:16:47
11usenet.network 4 24740 733 23902 105 2%23:39:15
12outgoing.news.lysator.liu.se 2 9340 429 8674 237 4%22:20:39
13glockenspiel.complete.org 5 41439 363 41057 19 0%35:00:17
14194.87.241.33 26 533 293 115 125 54%18:42:34
15i2pn.org 2 10778 284 10488 6 2%20:56:22
16news.netfront.net 14 5575 20 646 4909 0%19:56:14
17news.bbs.nz 3 27979 16 27962 1 0%37:18:50
18news.furie.org.uk 2 12546 14 12531 1 0%20:56:18
19news.samoylyk.net 3 8542 14 8527 1 0%21:26:54
20news.nk.ca 2 0 0 0 0 0%00:20:00
21eotl-out.news.tnetconsulting.net 1 0 0 0 0 0%00:10:05
22localhost 25 0 0 0 0 0%00:00:00
TOTAL: 22 985 437388 31736 394880 10772 7%461:46:53
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net122.1 MB57.4 KB3.4 MB125.5 MB 97%9.3 KB
2usenet.blueworldhosting.com41.7 MB8.2 KB4.0 MB45.7 MB 91%6.2 KB
3news.nntp4.net28.7 MB14.1 KB1.2 MB29.9 MB 95%14.3 KB
4newsfeed.xs3.de26.3 MB27.2 KB1.5 MB27.8 MB 94%11.4 KB
5nntp.comgw.net7.3 MB18.6 KB2.3 MB9.6 MB 76%4.4 KB
6endofthelinebbs.peers.news.panix.com7.1 MB11.6 KB333.5 KB7.4 MB 95%7.2 KB
7paganini.bofh.team7.0 MB32.7 KB2.3 MB9.3 MB 75%7.4 KB
8endoftheline-out.news.chmurka.net6.0 MB140.7 KB34.7 KB6.2 MB 97%5.3 KB
9news.hispagatos.org5.5 MB13.7 KB0.8 KB5.5 MB 99%3.0 KB
10usenet.goja.nl.eu.org4.9 MB2.2 KB642.3 KB5.5 MB 88%4.7 KB
11usenet.network4.3 MB9.3 KB491.3 KB4.8 MB 89%5.9 KB
12glockenspiel.complete.org3.5 MB9.2 KB78.6 KB3.5 MB 97%9.5 KB
13i2pn.org1.9 MB8.3 KB6.6 KB2.0 MB 99%6.9 KB
14outgoing.news.lysator.liu.se1.1 MB9.3 KB360.6 KB1.5 MB 75%2.2 KB
15194.87.241.331.0 MB58.7 KB143.1 KB1.2 MB 83%2.9 KB
16news.netfront.net737.3 KB0.0 KB1.5 GB1.5 GB 0%326.1 KB
17news.bbs.nz81.3 KB9.2 KB0.0 KB90.5 KB 89%5.3 KB
18news.furie.org.uk79.8 KB9.2 KB0.0 KB89.0 KB 89%5.9 KB
19news.samoylyk.net47.6 KB9.2 KB0.0 KB56.8 KB 83%3.8 KB
20news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
21eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 22269.3 MB448.8 KB1.5 GB1.8 GB 14%44.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 7 00:01:01 - 00:59:59 1274 4.0% 0.3613.0 MB 0.0% 3.77
Jan 7 01:00:00 - 01:59:59 1291 4.1% 0.3612.8 MB 7.8% 3.63
Jan 7 02:00:00 - 02:59:59 1544 4.9% 0.4314.2 MB 8.7% 4.05
Jan 7 03:00:00 - 03:59:59 1407 4.4% 0.3912.6 MB 7.7% 3.57
Jan 7 04:00:00 - 04:59:59 1315 4.1% 0.3712.5 MB 7.6% 3.55
Jan 7 05:00:00 - 05:59:59 1382 4.3% 0.3813.2 MB 8.0% 3.75
Jan 7 06:00:00 - 06:59:59 1538 4.8% 0.4312.5 MB 7.6% 3.57
Jan 7 07:00:00 - 07:59:59 1650 5.2% 0.4614.5 MB 8.8% 4.12
Jan 7 08:00:00 - 08:59:59 1826 5.7% 0.5115.9 MB 9.7% 4.53
Jan 7 09:00:00 - 09:59:59 1493 4.7% 0.4112.8 MB 7.8% 3.63
Jan 7 10:00:00 - 10:59:59 1399 4.4% 0.3912.8 MB 7.8% 3.64
Jan 7 11:00:00 - 11:59:59 1308 4.1% 0.3612.0 MB 7.3% 3.42
Jan 7 12:00:00 - 12:59:59 1311 4.1% 0.3610.0 MB 6.1% 2.84
Jan 7 13:00:00 - 13:59:59 1317 4.1% 0.379.2 MB 5.6% 2.61
Jan 7 14:00:00 - 14:59:59 960 3.0% 0.277.2 MB 4.4% 2.04
Jan 7 15:00:00 - 15:59:59 1212 3.8% 0.349.3 MB 5.7% 2.64
Jan 7 16:00:00 - 16:59:59 1264 4.0% 0.359.4 MB 5.7% 2.67
Jan 7 17:00:00 - 17:59:59 1210 3.8% 0.349.4 MB 5.7% 2.66
Jan 7 18:00:00 - 18:59:59 104 0.3% 0.03844.6 KB 0.5% 0.23
Jan 7 19:00:00 - 19:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jan 7 20:00:00 - 20:59:59 1847 5.8% 0.5111.3 MB 6.9% 3.22
Jan 7 21:00:00 - 21:59:59 1537 4.8% 0.4311.5 MB 7.0% 3.26
Jan 7 22:00:00 - 22:59:59 1567 4.9% 0.4414.0 MB 8.5% 3.99
Jan 7 23:00:00 - 23:59:59 1989 6.3% 0.5518.4 MB 11.2% 5.25
Jan 8 00:00:00 - 00:01:01 39 0.1% 0.64352.8 KB 0.2% 5.78
TOTAL: 24:00:00 31784 100.0% 0.37164.0 MB 100.0% 1.94
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.netfront.net 4907 0 0 0 0 0 0 0 4907
2endofthelinebbs-out.news.weretis.net 1930 0 0 13 0 0 0 0 1917
3usenet.blueworldhosting.com 1541 0 0 2 0 0 0 0 1539
4nntp.comgw.net 787 0 0 6 0 0 0 0 781
5paganini.bofh.team 445 0 0 12 0 0 0 0 433
6outgoing.news.lysator.liu.se 271 0 0 1 0 0 0 0 270
7usenet.goja.nl.eu.org 253 0 0 1 0 0 0 0 252
8newsfeed.xs3.de 149 0 0 8 0 0 0 0 141
9endofthelinebbs.peers.news.panix.com 143 1 0 2 0 0 0 0 140
10news.nntp4.net 127 0 0 2 0 0 0 0 125
11194.87.241.33 125 0 0 27 0 0 0 0 98
12usenet.network 97 0 0 1 0 0 0 0 96
13endoftheline-out.news.chmurka.net 52 0 0 19 0 0 0 0 33
14i2pn.org 13 0 0 2 0 0 0 0 11
15glockenspiel.complete.org 10 0 0 1 0 0 0 0 9
16news.samoylyk.net 9 0 0 1 0 0 0 0 8
17news.hispagatos.org 8 0 0 2 0 0 0 0 6
18news.furie.org.uk 2 0 0 1 0 0 0 0 1
19news.bbs.nz 1 0 0 1 0 0 0 0 0
TOTAL: 19 10870 1 0 102 0 0 0 010767

Unwanted newsgroups [Top 20]:

NewsgroupCount
ee.arvutid 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Binary: misplaced binary 4703
EMP (phn path) 3879
EMP (md5) 1009
Too many newsgroups 368
EMP (phr path) 325
User-issued cancel 33
EMP (fsl) 28
Subject (Buy Xanax) 14
Subject (lSDguY) 12
Subject (lSDguKvguKfguKIg4Lih4Lix4LmI4LiZ4LiE4LiH4LiX4Li) 11
HTML Multipart 9
Subject (LSDguJzguKXguJrguK3guKXguKr) 9
Subject (LSDguYDguKfguY) 6
User-issued spam cancel 5
Subject (lSDguYDguITguKPguJTguL) 5
Subject (lSDguJfguJTguKXguK3guIfguYDguKXguYjguJn) 4
Subject (lSDguJ3guLLguIE) 4
Subject (lSDguYD) 4
Subject (LSDguY) 3
Subject (lSDguYDguKfguYfguJr) 3
TOTAL: 281 10744

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
usenet.blueworldhosting.com 6
endofthelinebbs-out.news.weretis.net 5
news.hispagatos.org 5
endofthelinebbs.peers.news.panix.com 1
nntp.comgw.net 1
paganini.bofh.team 1
TOTAL: 6 19
TOTAL: 1 19

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 31767 31410 63 33 258 493 98%21:39:31
2nyheter.lysator.liu.se 31700 22217 8226 742 146 459 70%21:39:31
3usenet.network 37782 7157 23245 206 0 73 18%21:39:31
4endofthelinebbs.peers.news.panix.com 33043 6021 25717 44 0 4 18%21:39:31
5news.quux.org 34203 2953 27983 539 0 5 8%21:39:31
6news.hispagatos.org 34980 1451 27328 1106 0 443 4%21:39:31
7news.bbs.nz 56352 1043 28006 2720 0 640 1%21:39:35
8news.nntp4.net 34603 982 28355 117 0 328 2%21:39:11
9news.furie.org.uk 13991 818 11076 23 0 158 5%21:39:23
10paganini.bofh.team 37886 607 31174 0 0 606 1%21:39:31
11nntp.comgw.net 37338 444 30944 397 0 304 1%21:39:31
12usenet.goja.nl.eu.org 35933 432 29838 455 0 387 1%21:39:31
13news.weretis.net 32632 419 30446 917 0 41 1%21:39:31
14news.nk.ca 41026 362 27350 4035 0 216 0%21:39:31
15news.chmurka.net 13947 227 11560 17 0 59 1%21:39:23
16newsfeed.xs3.de 34741 168 28741 413 0 481 0%21:39:30
17i2pn.org 38556 157 30256 929 0 183 0%21:39:31
18usenet.blueworldhosting.com 24933 104 24069 0 0 0 0%21:38:09
19ddt.demos.su 676 101 566 0 0 1 14%20:17:52
20news.tnetconsulting.net 35631 83 29620 2077 0 0 0%21:39:30
21news.samoylyk.net 41299 6 31579 172 0 571 0%21:39:31
TOTAL: 21 683019 77162 486142 14942 404 5452 11%453:26:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net266.5 MB162.3 KB266.7 MB3.5 KB/s8.7 KB21:39:31
2nyheter.lysator.liu.se236.3 MB3.6 MB239.9 MB3.2 KB/s10.7 KB21:39:31
3usenet.network91.3 MB1.2 MB92.5 MB1.2 KB/s12.9 KB21:39:31
4endofthelinebbs.peers.news.panix.com68.5 MB505.8 KB69.0 MB0.9 KB/s11.7 KB21:39:31
5news.quux.org36.0 MB2.5 MB38.4 MB0.5 KB/s11.3 KB21:39:31
6news.hispagatos.org25.5 MB10.3 MB35.7 MB0.5 KB/s14.3 KB21:39:31
7news.nntp4.net10.8 MB352.4 KB11.1 MB0.1 KB/s10.4 KB21:39:11
8news.bbs.nz7.6 MB21.0 MB28.6 MB0.4 KB/s7.8 KB21:39:35
9news.furie.org.uk6.8 MB187.6 KB6.9 MB0.1 KB/s8.5 KB21:39:23
10paganini.bofh.team4.2 MB0.0 KB4.2 MB0.1 KB/s7.1 KB21:39:31
11nntp.comgw.net4.2 MB2.4 MB6.7 MB0.1 KB/s8.1 KB21:39:31
12usenet.goja.nl.eu.org4.1 MB2.9 MB7.0 MB0.1 KB/s8.0 KB21:39:31
13news.weretis.net3.2 MB7.8 MB11.0 MB0.1 KB/s8.4 KB21:39:31
14news.chmurka.net2.1 MB766.6 KB2.9 MB0.0 KB/s12.1 KB21:39:23
15newsfeed.xs3.de1.8 MB2.4 MB4.3 MB0.1 KB/s7.5 KB21:39:30
16news.nk.ca923.4 KB29.5 MB30.4 MB0.4 KB/s7.1 KB21:39:31
17i2pn.org420.3 KB8.2 MB8.6 MB0.1 KB/s8.1 KB21:39:31
18ddt.demos.su222.8 KB0.0 KB222.8 KB0.0 KB/s2.2 KB20:17:52
19news.tnetconsulting.net222.1 KB23.4 MB23.6 MB0.3 KB/s11.2 KB21:39:30
20usenet.blueworldhosting.com204.3 KB0.0 KB204.3 KB0.0 KB/s2.0 KB21:38:09
21news.samoylyk.net22.2 KB1.6 MB1.6 MB0.0 KB/s9.4 KB21:39:31
TOTAL: 21770.8 MB118.7 MB889.6 MB0.6 KB/s9.9 KB453:26:16
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

ReasonConn
400 loadav [innwatch:load] 1667 gt 1500 2
TOTAL: 1 2

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1supporting.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
2peer.alt119.net2492 00.0 KB 0 0 000:21:39
TOTAL: 22493 00.0 KB 0 0 000:21:39

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:00
2*.alt119.net2492 00.0 KB 0 0 000:21:39
TOTAL: 22493 00.0 KB 0 0 000:21:39

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
peer.alt119.net 259.038 131.708 0.00000:21:39
supporting.monitoring.internet-measurement.com 0.083 0.055 0.00000:00:00
TOTAL: 2 259.121 131.763 0.00000:21:39