Daily Usenet report

Dec 19 00:01:07 -- Dec 20 00:01:01

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Dec 19 00:44:07 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/14: File exists
Dec 19 00:44:07 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/15: File exists
Dec 19 00:44:08 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/16: File exists
Dec 19 00:44:18 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/17: File exists
Dec 19 00:44:18 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/18: File exists
Dec 19 00:44:23 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/19: File exists
Dec 19 00:44:28 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/20: File exists
Dec 19 00:44:28 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/21: File exists
Dec 19 00:44:33 www innd: tradspool: could not open /news/spool/articles/fr/lettres/langue/allemande/22: File exists
Dec 19 00:50:59 www innd: message repeated 2 times: [ ctlinnd command s]
Dec 19 09:28:31 www innd: tradspool: could not symlink /news/spool/articles/alt/fan/rush-limbaugh/287697 to /news/spool/articles/rec/arts/1: File exists
Dec 19 09:28:32 www innd: tradspool: could not symlink /news/spool/articles/alt/fan/rush-limbaugh/287698 to /news/spool/articles/rec/arts/2: File exists
Dec 19 09:28:32 www innd: tradspool: could not symlink /news/spool/articles/alt/fan/rush-limbaugh/287699 to /news/spool/articles/rec/arts/3: File exists
Dec 19 09:28:33 www innd: tradspool: could not symlink /news/spool/articles/alt/fan/rush-limbaugh/287700 to /news/spool/articles/rec/arts/4: File exists
Dec 19 09:28:36 www innd: tradspool: could not symlink /news/spool/articles/alt/fan/rush-limbaugh/287702 to /news/spool/articles/rec/arts/5: File exists
Dec 19 09:28:36 www innd: tradspool: could not symlink /news/spool/articles/alt/fan/rush-limbaugh/287703 to /news/spool/articles/rec/arts/6: File exists

Log entries by program:

Program nameLines%LinesSize%Size
inn 270000 79.4%57.4 MB 87.2%
innd 65263 19.2%7.6 MB 11.6%
innfeed 4329 1.3%787.4 KB 1.2%
nnrpd 553 0.2%43.0 KB 0.1%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 5 340146 100.0%65.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 1042894 60.0%
Negative hits 560122 32.2%
Do not exist 122882 7.1%
Cache misses 13283 0.8%
TOTAL: 4 1739181 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:01:01.622 0.1%170440 0.173 0.362 1.925
article cleanup00:00:01.887 0.0%124195 0.005 0.015 0.046
article logging00:00:05.091 0.0%124218 0.020 0.041 0.078
article parse00:00:07.708 0.0%451132 0.006 0.017 0.028
article write00:01:12.886 0.1%69803 0.807 1.044 2.179
artlog/artcncl00:00:02.017 0.0%146546 0.005 0.014 0.038
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%15 0.000 0.000 0.000
data move00:00:05.016 0.0%1901266 0.001 0.003 0.018
hisgrep/artcncl00:00:15.194 0.0%170455 0.021 0.089 0.915
hishave/artcncl00:00:01.209 0.0%170318 0.000 0.007 0.097
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:01:01.444 0.1%1591803 0.009 0.039 0.125
history sync00:00:00.055 0.0%314 0.000 0.175 1.000
history write00:01:04.586 0.1%124101 0.202 0.520 1.790
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:33:04.378 98.1%1743879 9.828 48.618 106.202
nntp read00:01:14.130 0.1%1785849 0.024 0.042 0.136
overview write00:01:12.131 0.1%69788 0.570 1.034 11.018
perl filter00:11:52.689 0.8%124116 1.746 5.742 15.111
python filter00:00:14.494 0.0%124116 0.044 0.117 0.204
site send00:00:26.601 0.0%140933 0.096 0.189 0.372
TOTAL: 24:00:29.57723:53:03.138 99.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.473 0.0%109728 0.000 0.004 0.024
article prepare00:00:00.165 0.0%889330 0.000 0.000 0.001
article read00:00:25.405 0.0%69520 0.210 0.365 0.565
backlog stats00:00:05.324 0.0%2390750 0.001 0.002 0.004
callbacks00:00:00.000 0.0%13 0.000 0.000 0.000
data read00:00:58.883 0.1%1796820 0.023 0.033 0.083
data write00:03:54.071 0.3%1650167 0.091 0.142 0.227
idle23:44:30.458 99.5%2390750 12.147 35.750 62.798
status file00:00:00.476 0.0%475 0.000 1.002 5.000
TOTAL: 23:51:07.94523:49:55.255 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:01.731 58.1%9 17.500 192.333 1529.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.004 0.1%31 0.000 0.129 0.500
TOTAL: 00:00:02.97700:00:01.735 58.3%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 142
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 164

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1endofthelinebbs-out.news.weretis.net 10 140117 28215 101026 10876 20%48:40:37
2usenet.blueworldhosting.com 1440 58168 10213 41973 5982 17%23:46:38
3newsfeed.xs3.de 6 77926 7073 69518 1335 9%24:35:25
4nntp.comgw.net 4 90374 4918 80704 4752 5%24:43:00
5endofthelinebbs.peers.news.panix.com 63 82230 3656 75204 3370 4%30:44:59
6news.nntp4.net 38 64333 3293 60708 332 5%24:54:17
7usenet.goja.nl.eu.org 12 83373 3134 78412 1827 3%22:09:54
8outgoing.news.lysator.liu.se 5 37548 2009 16983 18556 5%24:15:48
9news.hispagatos.org 6 25940 1978 23958 4 7%25:20:24
10paganini.bofh.team 4 90047 1729 87440 878 1%22:34:06
11usenet.network 7 66869 1523 65162 184 2%25:41:54
12glockenspiel.complete.org 5 73040 895 72012 133 1%26:44:00
13endoftheline-out.news.chmurka.net 2 30706 643 29738 325 2%24:09:56
14i2pn.org 1 10546 261 10267 18 2%23:59:59
15194.87.241.33 35 446 180 76 190 40%20:23:32
16news.bbs.nz 1 30752 44 30695 13 0%23:59:29
17news.samoylyk.net 3 9506 27 9469 10 0%23:59:25
18news.furie.org.uk 1 48561 22 48372 167 0%23:59:56
19news.netfront.net 35 6079 11 629 5439 0%20:21:21
20news.nk.ca 2 0 0 0 0 0%00:20:01
21localhost 26 0 0 0 0 0%00:00:00
22eotl-out.news.tnetconsulting.net 2 0 0 0 0 0%00:20:06
TOTAL: 22 1708 1026561 69824 902346 54391 6%485:44:47
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1endofthelinebbs-out.news.weretis.net347.3 MB201.9 KB17.1 MB364.6 MB 95%9.6 KB
2newsfeed.xs3.de76.1 MB77.7 KB9.1 MB85.2 MB 89%10.4 KB
3usenet.blueworldhosting.com70.6 MB91.7 KB26.9 MB97.6 MB 72%6.2 KB
4news.nntp4.net40.0 MB29.4 KB6.5 MB46.6 MB 85%13.2 KB
5nntp.comgw.net37.1 MB89.2 KB34.4 MB71.6 MB 51%7.6 KB
6endofthelinebbs.peers.news.panix.com29.4 MB126.4 KB6.5 MB36.0 MB 81%5.3 KB
7usenet.goja.nl.eu.org22.8 MB116.8 KB4.4 MB27.3 MB 83%5.6 KB
8paganini.bofh.team15.3 MB124.4 KB9.5 MB24.9 MB 61%9.8 KB
9usenet.network13.6 MB70.8 KB1.6 MB15.3 MB 88%9.2 KB
10outgoing.news.lysator.liu.se12.3 MB9.8 KB51.2 MB63.6 MB 19%3.2 KB
11glockenspiel.complete.org9.6 MB3.2 KB649.0 KB10.2 MB 93%10.2 KB
12news.hispagatos.org7.4 MB0.0 KB4.9 KB7.4 MB 99%3.8 KB
13endoftheline-out.news.chmurka.net5.1 MB1.4 KB484.6 KB5.5 MB 91%5.9 KB
14i2pn.org3.7 MB3.1 KB18.7 KB3.7 MB 99%13.8 KB
15194.87.241.33758.8 KB46.3 KB247.2 KB1.0 MB 72%2.8 KB
16news.samoylyk.net526.2 KB0.0 KB14.6 KB540.8 KB 97%14.6 KB
17news.netfront.net480.3 KB0.0 KB1.8 GB1.8 GB 0%339.5 KB
18news.bbs.nz229.7 KB233.8 KB0.0 KB463.5 KB 49%8.1 KB
19news.furie.org.uk121.3 KB29.0 KB262.7 KB413.0 KB 29%2.2 KB
20news.nk.ca0.0 KB0.0 KB0.0 KB0.0 KB 0%0
21localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
22eotl-out.news.tnetconsulting.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 22692.5 MB1.2 MB1.9 GB2.6 GB 25%22.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 19 00:01:07 - 00:59:59 4375 6.3% 1.2455.0 MB 8.0% 15.93
Dec 19 01:00:00 - 01:59:59 4212 6.1% 1.1750.4 MB 7.3% 14.33
Dec 19 02:00:00 - 02:59:59 4106 5.9% 1.1444.5 MB 6.5% 12.67
Dec 19 03:00:00 - 03:59:59 3496 5.0% 0.9736.9 MB 5.4% 10.49
Dec 19 04:00:00 - 04:59:59 3509 5.0% 0.9734.9 MB 5.1% 9.92
Dec 19 05:00:00 - 05:59:59 5055 7.3% 1.4044.3 MB 6.4% 12.59
Dec 19 06:00:00 - 06:59:59 3363 4.8% 0.9333.0 MB 4.8% 9.39
Dec 19 07:00:00 - 07:59:59 3209 4.6% 0.8929.6 MB 4.3% 8.42
Dec 19 08:00:00 - 08:59:59 2597 3.7% 0.7225.2 MB 3.7% 7.16
Dec 19 09:00:00 - 09:59:59 2486 3.6% 0.6924.7 MB 3.6% 7.03
Dec 19 10:00:00 - 10:59:59 2759 4.0% 0.7725.4 MB 3.7% 7.22
Dec 19 11:00:00 - 11:59:59 2456 3.5% 0.6821.1 MB 3.1% 6.01
Dec 19 12:00:00 - 12:59:59 2692 3.9% 0.7523.3 MB 3.4% 6.62
Dec 19 13:00:00 - 13:59:59 2057 3.0% 0.5717.7 MB 2.6% 5.02
Dec 19 14:00:00 - 14:59:59 2042 2.9% 0.5715.8 MB 2.3% 4.51
Dec 19 15:00:00 - 15:59:59 2164 3.1% 0.6017.5 MB 2.5% 4.97
Dec 19 16:00:00 - 16:59:59 2387 3.4% 0.6617.7 MB 2.6% 5.04
Dec 19 17:00:00 - 17:59:59 1868 2.7% 0.5215.0 MB 2.2% 4.26
Dec 19 18:00:00 - 18:59:59 2182 3.1% 0.6120.0 MB 2.9% 5.70
Dec 19 19:00:00 - 19:59:59 1852 2.7% 0.5115.0 MB 2.2% 4.27
Dec 19 20:00:00 - 20:59:59 2241 3.2% 0.6220.9 MB 3.0% 5.95
Dec 19 21:00:00 - 21:59:59 2662 3.8% 0.7429.2 MB 4.2% 8.29
Dec 19 22:00:00 - 22:59:59 2902 4.2% 0.8137.0 MB 5.4% 10.51
Dec 19 23:00:00 - 23:59:59 2860 4.1% 0.7934.0 MB 4.9% 9.66
Dec 20 00:00:00 - 00:01:01 56 0.1% 0.92515.0 KB 0.1% 8.44
TOTAL: 23:59:54 69588 100.0% 0.81688.4 MB 100.0% 8.16
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1outgoing.news.lysator.liu.se 18557 0 0 2 0 0 0 018555
2endofthelinebbs-out.news.weretis.net 10889 0 0 9 0 0 0 010880
3usenet.blueworldhosting.com 6004 1 0 12 0 0 0 0 5991
4news.netfront.net 5439 0 0 0 0 0 0 0 5439
5nntp.comgw.net 4744 0 0 5 0 0 0 0 4739
6endofthelinebbs.peers.news.panix.com 3372 0 0 4 0 0 0 0 3368
7usenet.goja.nl.eu.org 1880 0 0 2 0 0 0 0 1878
8newsfeed.xs3.de 1331 0 0 2 0 0 0 0 1329
9paganini.bofh.team 905 0 0 5 0 0 0 0 900
10endoftheline-out.news.chmurka.net 324 0 0 1 0 0 0 0 323
11news.nntp4.net 321 0 0 1 0 0 0 0 320
12194.87.241.33 189 0 0 25 0 0 0 0 164
13usenet.network 183 0 0 3 0 0 0 0 180
14news.furie.org.uk 167 0 0 1 0 0 0 0 166
15glockenspiel.complete.org 102 0 0 2 0 0 0 0 100
16i2pn.org 18 0 0 2 0 0 0 0 16
17news.samoylyk.net 9 0 0 0 0 0 0 0 9
18news.hispagatos.org 5 0 0 0 0 0 0 0 5
19news.bbs.nz 4 0 0 3 0 0 0 0 1
TOTAL: 19 54443 1 0 79 0 0 0 054363

Unwanted newsgroups [Top 20]:

NewsgroupCount
👀alt.checkmate 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
User-issued cancel 23958
EMP (phn path) 11732
EMP (md5) 9847
Binary: misplaced binary 5408
Too many newsgroups 738
EMP (phr path) 347
EMP (phl) 228
Subject (Buy Xanax) 96
Subject (Buy Tramadol) 65
Subject (LSDY_=23TOGELMACAU_=23TOTO4D) 30
Subject (lSDguIjguYjguLI) 27
Subject (lSDguY) 22
Subject (lSDguK3guLHguJnguJTguLHguJo) 22
Cancel for rejected article 21
Subject (lSDguKrguKXguY) 19
Subject (lSDguYD) 17
Subject (BUy Xanax) 16
Subject (lSDguYDguKfguY) 16
Subject (lSDguYDguITguKPguJTguL) 16
Subject (lSDguK3guK3guJnguY) 16
TOTAL: 1057 54310

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
usenet.blueworldhosting.com 22
nntp.comgw.net 5
endofthelinebbs.peers.news.panix.com 4
endofthelinebbs-out.news.weretis.net 3
outgoing.news.lysator.liu.se 2
usenet.goja.nl.eu.org 1
usenet.network 1
TOTAL: 7 38
TOTAL: 1 38

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.netfront.net 69614 69533 3 47 30 22 99%23:59:47
2nyheter.lysator.liu.se 68201 58055 7656 1897 7 101 85%23:59:47
3usenet.network 83054 15725 51421 632 1 2 18%23:59:47
4endofthelinebbs.peers.news.panix.com 71958 15224 54285 110 5 32 21%23:59:47
5news.hispagatos.org 78038 8053 53634 5896 0 3 10%23:59:47
6news.furie.org.uk 36873 4869 27003 176 0 1 13%23:59:46
7news.quux.org 72821 3044 64769 1001 1 0 4%23:59:46
8usenet.goja.nl.eu.org 79198 2505 63112 678 0 46 3%23:59:45
9news.nntp4.net 132959 2306 60873 285 0 55209 1%23:59:47
10news.bbs.nz 124939 1872 62873 4871 0 823 1%23:59:47
11paganini.bofh.team 83031 1820 67515 131 147 1191 2%23:59:47
12nntp.comgw.net 82310 1794 66992 809 0 45 2%23:59:47
13news.weretis.net 71272 677 66795 2149 1 18 0%23:59:46
14newsfeed.xs3.de 74359 593 60468 899 0 53 0%23:59:47
15news.chmurka.net 34906 526 29218 79 0 0 1%23:59:47
16i2pn.org 93087 416 64079 4667 0 880 0%23:59:46
17news.nk.ca 91753 402 62148 7008 0 202 0%23:59:47
18ddt.demos.su 645 243 400 1 0 0 37%21:17:25
19usenet.blueworldhosting.com 56530 237 53964 413 0 11 0%23:59:46
20news.tnetconsulting.net 79041 76 66444 3104 1 19 0%23:59:47
21news.samoylyk.net 92027 31 68691 869 0 185 0%23:59:46
TOTAL: 211576616 1880011052343 35722 193 58843 11%501:12:57
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net688.0 MB265.8 KB688.2 MB8.2 KB/s10.1 KB23:59:47
2nyheter.lysator.liu.se632.1 MB11.9 MB644.0 MB7.6 KB/s11.0 KB23:59:47
3usenet.network185.7 MB4.0 MB189.7 MB2.2 KB/s11.9 KB23:59:47
4endofthelinebbs.peers.news.panix.com157.9 MB1.0 MB158.9 MB1.9 KB/s10.6 KB23:59:47
5news.hispagatos.org137.4 MB44.6 MB182.0 MB2.2 KB/s13.4 KB23:59:47
6news.furie.org.uk36.0 MB1.1 MB37.1 MB0.4 KB/s7.5 KB23:59:46
7news.quux.org27.8 MB5.7 MB33.5 MB0.4 KB/s8.5 KB23:59:46
8news.nntp4.net22.8 MB963.5 KB23.7 MB0.3 KB/s9.4 KB23:59:47
9usenet.goja.nl.eu.org18.9 MB4.1 MB23.0 MB0.3 KB/s7.4 KB23:59:45
10nntp.comgw.net18.0 MB5.3 MB23.3 MB0.3 KB/s9.2 KB23:59:47
11paganini.bofh.team17.5 MB1.8 MB19.3 MB0.2 KB/s10.1 KB23:59:47
12news.bbs.nz15.0 MB37.7 MB52.7 MB0.6 KB/s8.0 KB23:59:47
13newsfeed.xs3.de6.0 MB6.0 MB12.0 MB0.1 KB/s8.2 KB23:59:47
14news.weretis.net4.7 MB18.8 MB23.5 MB0.3 KB/s8.5 KB23:59:46
15news.chmurka.net4.5 MB1.7 MB6.2 MB0.1 KB/s10.5 KB23:59:47
16i2pn.org1.5 MB43.7 MB45.2 MB0.5 KB/s9.1 KB23:59:46
17news.nk.ca1.1 MB51.1 MB52.2 MB0.6 KB/s7.2 KB23:59:47
18ddt.demos.su519.0 KB2.6 KB521.6 KB0.0 KB/s2.1 KB21:17:25
19usenet.blueworldhosting.com480.3 KB754.8 KB1.2 MB0.0 KB/s1.9 KB23:59:46
20news.tnetconsulting.net274.3 KB24.9 MB25.2 MB0.3 KB/s8.1 KB23:59:47
21news.samoylyk.net139.8 KB6.5 MB6.7 MB0.1 KB/s7.6 KB23:59:46
TOTAL: 211.9 GB272.0 MB2.2 GB1.3 KB/s10.3 KB501:12:57
Outgoing feeds (innfeed) by Volume

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 3 00.0 KB 16 0 000:00:00
2*.monitoring.internet-measurement.com 1 00.0 KB 0 0 000:00:01
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 3 5 00.0 KB 16 0 000:00:02

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
remarkable.monitoring.internet-measurement.com 0.080 0.087 0.00000:00:01
71.6.134.233 0.316 0.216 0.00000:00:00
scanner-04.ch1.censys-scanner.com 0.121 0.079 0.00000:00:00
TOTAL: 3 0.517 0.382 0.00000:00:02

Curious NNRP server explorers [Top 20]:

SystemConn
71.6.134.233 3
remarkable.monitoring.internet-measurement.com 1
scanner-04.ch1.censys-scanner.com 1
TOTAL: 3 5

NNRP unrecognized commands (by host) [Top 20]:

SystemConn
71.6.134.233 7
scanner-04.ch1.censys-scanner.com 1
TOTAL: 2 8

NNRP unrecognized commands (by command) [Top 20]:

CommandCount
User-Agent: Mozilla/5.0 (Windows NT 10.0... 1
#023#003#023#001#023#002#001 1
#026#003#001 1
Accept: */* 1
Accept-Encoding: gzip 1
GET / HTTP/1.1 1
EHLO www.censys.io 1
Host: 192.138.210.162:119 1
TOTAL: 8 8

Newsgroup request counts (by hierarchy):

HierarchyCountPct
1news 47 97.9%
2comp 1 2.1%
TOTAL: 2 48100.0%

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

NewsgroupCount
1news.lists.filters 34
2news.admin.net-abuse.usenet 8
3news.admin.peering 3
4news.answers 1
5comp.sys.cbm 1
6news.software.nntp 1
TOTAL: 6 48