Daily Usenet report

Nov 30 23:55:49 -- Dec 2 04:15:02

Unknown entries from news log file:

First 19 / 19 lines (100.0%)

Dec 1 04:15:02 newsfeed nnrpd[27477]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 1 04:15:02 newsfeed nnrpd[27485]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 1 07:04:08 newsfeed pgpverify[17655]: pgpverify: verification failed
Dec 1 09:46:00 newsfeed nnrpd[23228]: SERVER perl filter not defined
Dec 1 09:55:42 newsfeed nnrpd[23566]: SERVER perl filter not defined
Dec 1 09:57:32 newsfeed nnrpd[23580]: SERVER perl filter not defined
Dec 1 10:06:10 newsfeed nnrpd[23850]: message repeated 6 times: [ wireless.ics.p.lodz.pl group lodz.test 0]
Dec 1 10:10:22 newsfeed nnrpd[23850]: SERVER perl filter not defined
Dec 1 13:34:25 newsfeed nnrpd[31114]: message repeated 2 times: [ 212.51.207.190 group pl.test 0]
Dec 1 14:31:19 newsfeed nnrpd[793]: SERVER perl filter not defined
Dec 1 19:47:26 newsfeed nnrpd[11649]: SERVER perl filter not defined
Dec 1 19:50:02 newsfeed nnrpd[11651]: SERVER perl filter not defined
Nov 30 23:55:49 newsfeed overchan[21098]: message repeated 3 times: [ input line too long, skipping]
Dec 1 20:28:42 newsfeed nnrpd[13096]: SERVER perl filter not defined
Dec 1 20:32:49 newsfeed nnrpd[13299]: SERVER perl filter not defined
Dec 1 22:28:16 newsfeed nnrpd[17163]: message repeated 2 times: [ 194.247.62.73 group lodzman.test 0]
Dec 1 22:30:34 newsfeed nnrpd[17450]: SERVER perl filter not defined
Dec 1 23:18:39 newsfeed overchan[12360]: input line too long, skipping
Dec 2 02:26:36 newsfeed nnrpd[25440]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn2831112 97.4%327.1 MB 96.9%
innfeed 48367 1.7%5.8 MB 1.7%
innd 22599 0.8%3.9 MB 1.2%
nnrpd 5633 0.2%613.6 KB 0.2%
controlchan 6 0.0%0.8 KB 0.0%
overchan 3 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 72907721 100.0%337.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2825011 98.1%
Negative hits 38523 1.3%
Positive hits 16569 0.6%
Cache misses 135 0.0%
TOTAL: 4 2880238 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:05.078 0.0%311 0.000 16.328 247.000
article cleanup00:00:45.827 0.1%2830897 0.006 0.016 0.028
article logging00:01:18.608 0.1%2831390 0.014 0.028 0.348
article parse01:19:42.939 5.5%272079249 0.006 0.018 0.075
article write00:46:09.253 3.2%2825400 0.332 0.980 6.593
artlog/artcncl00:00:00.005 0.0%275 0.000 0.018 1.000
artlog/artparse00:00:00.000 0.0%28 0.000 0.000 0.000
data move00:03:49.481 0.3%290178692 0.000 0.001 0.006
hisgrep/artcncl00:00:00.465 0.0%268 0.000 1.735 14.909
hishave/artcncl00:00:00.069 0.0%311 0.000 0.222 17.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:35:16.155 2.4%2881823 0.122 0.734 20.350
history sync00:00:00.016 0.0%290 0.000 0.055 1.000
history write00:49:14.715 3.4%2826768 0.203 1.045 36.449
hiswrite/artcncl00:00:00.031 0.0%43 0.000 0.721 9.000
idle12:25:41.923 51.8%185014251 0.089 0.242 0.390
nntp read01:16:28.333 5.3%269809574 0.008 0.017 0.036
overview write00:00:15.990 0.0%2825400 0.001 0.006 0.017
perl filter05:41:44.913 23.7%2826741 3.930 7.254 10.627
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:26.531 0.4%5789537 0.023 0.056 0.101
TOTAL: 24:00:13.52023:06:00.332 96.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.680 0.0%311598 0.000 0.002 0.050
article prepare00:00:00.014 0.0%38262 0.000 0.000 0.250
article read00:01:02.474 0.1%9538 0.000 6.550 1048.000
backlog stats00:00:00.335 0.0%451633 0.000 0.001 0.009
callbacks00:00:00.000 0.0%5 0.000 0.000 0.000
data read00:00:03.258 0.0%284677 0.000 0.011 0.129
data write00:00:07.377 0.0%148533 0.000 0.050 0.458
idle23:58:43.731 99.9%451633 59.988 191.137 953.072
status file00:00:13.471 0.0%16394 0.231 0.822 70.333
TOTAL: 24:00:26.71124:00:11.340100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle13:29:15.593 99.9%5916 0.000 8207.504600069.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:18.770 0.0%740888 0.000 0.025 2.510
readart00:00:13.328 0.0%1229 0.000 10.845 31.077
TOTAL: 13:30:11.43113:29:47.691100.0%----

Control commands to INND:

CommandNumber
L 1
flush 24
flushlogs 2
go 2
logmode 2
mode 148
name 2
newgroup 1
pause 2
paused 2
reload 2
reserve 2
TOTAL: 12 190

Newsgroups created:

GroupMode
alt.bitcoinsy
TOTAL1

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
donot@spame.com100011
us-control@lists.killfile.org000100
usenet@ripco.com100011
TOTAL200122

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1028 28247092819022 943 4744 99%339:17:47
2news.nask.pl 2 17224 5163 11429 632 29%24:44:09
3news.icm.edu.pl 39 449 85 191 173 18%20:15:08
4localhost 12 11 11 0 0 100%00:00:00
TOTAL: 4 1081 28423932824281 12563 5549 99%384:17:04
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl826.0 GB22.9 MB200.6 MB826.2 GB 99%306.8 KB
2news.nask.pl17.0 MB1.7 MB2.0 MB20.7 MB 82%3.7 KB
3news.icm.edu.pl5.2 MB68.7 KB5.1 MB10.3 MB 50%41.0 KB
4localhost13.1 KB0.0 KB0.0 KB13.1 KB100%1.2 KB
TOTAL: 4826.0 GB24.6 MB207.7 MB826.2 GB 99%306.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 1 04:55:49 - 04:59:59 83368 3.0% 332.1420.8 GB 2.5%86914.03
Dec 1 05:00:00 - 05:59:59 122723 4.3% 34.0931.2 GB 3.8%9101.75
Dec 1 06:00:00 - 06:59:59 145527 5.2% 40.4238.5 GB 4.7%11225.54
Dec 1 07:00:00 - 07:59:59 115602 4.1% 32.1138.3 GB 4.6%11162.88
Dec 1 08:00:00 - 08:59:59 111995 4.0% 31.1137.5 GB 4.5%10916.62
Dec 1 09:00:00 - 09:59:59 114976 4.1% 31.9435.7 GB 4.3%10407.41
Dec 1 10:00:00 - 10:59:59 124155 4.4% 34.4931.9 GB 3.9%9297.35
Dec 1 11:00:00 - 11:59:59 132903 4.7% 36.9234.4 GB 4.2%10009.79
Dec 1 12:00:00 - 12:59:59 132319 4.7% 36.7636.4 GB 4.4%10593.75
Dec 1 13:00:00 - 13:59:59 85863 3.0% 23.8531.8 GB 3.9%9267.35
Dec 1 14:00:00 - 14:59:59 92928 3.3% 25.8134.7 GB 4.2%10094.22
Dec 1 15:00:00 - 15:59:59 112480 4.0% 31.2436.1 GB 4.4%10511.67
Dec 1 16:00:00 - 16:59:59 110466 3.9% 30.6835.7 GB 4.3%10391.02
Dec 1 17:00:00 - 17:59:59 96685 3.4% 26.8630.0 GB 3.6%8746.00
Dec 1 18:00:00 - 18:59:59 117944 4.2% 32.7635.8 GB 4.3%10435.69
Dec 1 19:00:00 - 19:59:59 94213 3.3% 26.1731.8 GB 3.8%9251.29
Dec 1 20:00:00 - 20:59:59 81415 2.9% 22.6228.9 GB 3.5%8405.46
Dec 1 21:00:00 - 21:59:59 131359 4.7% 36.4936.9 GB 4.5%10746.27
Dec 1 22:00:00 - 22:59:59 150069 5.3% 41.6939.1 GB 4.7%11403.22
Dec 1 23:00:00 - 23:59:59 136555 4.8% 37.9337.0 GB 4.5%10785.14
Dec 2 00:00:00 - 00:59:59 111357 3.9% 30.9329.3 GB 3.6%8541.90
Dec 2 01:00:00 - 01:59:59 137127 4.9% 38.0939.0 GB 4.7%11353.88
Dec 2 02:00:00 - 02:59:59 113792 4.0% 31.6130.9 GB 3.7%9005.19
Dec 2 03:00:00 - 03:59:59 132205 4.7% 36.7234.2 GB 4.1%9972.48
Dec 2 04:00:00 - 04:15:02 36793 1.3% 40.799.5 GB 1.1%11009.71
TOTAL: 23:19:13 2824819 100.0% 33.65825.5 GB 100.0%10310.52
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 5168 299 0 3551 3 0 0 0 1315
2news.nask.pl 684 7 0 600 7 0 0 0 70
3news.icm.edu.pl 166 0 0 5 1 0 0 0 160
TOTAL: 3 6018 306 0 4156 11 0 0 0 1545

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 148
it.tlc.cellulari.android 69
it.comp.os.win.windows10 29
it.comp.os.win.windows7 17
comp.mobile.ipad 10
uk.rec.cycling.moderated 6
alt.windows7.general 5
nl.comp.os.linux.techniek 5
hr.alt.cellular.gsm.iphone 4
comp.mobile.android 4
de.comp.lang.vbclassic 3
gmane.comp.gis.openstreetmap.devel 2
it.comp.os.win.windows8 1
de.rec.sf.startrek 1
hr.rec.glazba.hifi 1
comp.sys.raspberry-pi 1
TOTAL: 16 306

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
pl.sci.historia 8
pl.praca.oferowana 2
bit.listserv.catholic 1
TOTAL: 3 11

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 847
EMP (md5) 143
EMP (fsl) 10
Too many newsgroups 9
HTML post 8
Scoring filter (8) 6
Scoring filter (11) 1
TOTAL: 7 1024

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 184078 2175 153820 923 11 154483 1%24:00:04
2icm 47499 1041 27421 4716 0 9 2%23:59:39
3poznan 7365 135 4867 46 0 0 1%24:01:10
4okapi 0 0 0 0 0 2554 0%23:55:03
5nask 0 0 0 0 0 13554 0%23:55:03
TOTAL: 5 238942 3351 186108 5685 11 170600 1%119:50:59
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm9.8 MB1.0 GB1.0 GB12.3 KB/s184.8 KB23:59:39
2panorama7.3 MB7.2 MB14.5 MB0.2 KB/s4.8 KB24:00:04
3poznan559.9 KB120.7 KB680.6 KB0.0 KB/s3.8 KB24:01:10
4okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:03
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:55:03
TOTAL: 517.7 MB1.0 GB1.0 GB2.5 KB/s119.4 KB119:50:59
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama3.3 MB
TOTAL: 13.3 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1212.191.78.226 7 5011.8 MB 4 1 000:27:49
2dmcs2.p.lodz.pl 11 400935.9 KB 6 0 003:01:39
3lze200.lze.lodz.pl 7 126230.5 KB 9 0 001:40:43
4pc-212-51-218-225.p.lodz.pl 4 125285.1 KB 5 0 001:23:58
5web.man.lodz.pl 291 3236.1 KB 16 0 000:00:30
6212.51.207.190 10 1536.4 KB 5 0 1600:43:28
7194.247.62.73 18 1420.3 KB 4 0 601:23:05
8wireless.ics.p.lodz.pl 5 1124.4 KB 4 1 000:54:53
9212.191.87.58 12 55.5 KB 5 2 001:54:18
10staticline-31-183-0-70.toya.net.pl 3 00.0 KB 0 0 300:03:56
11isvcs01.man.lodz.pl 7 00.0 KB 0 7 000:00:01
TOTAL: 11 375 12293.3 MB 58 11 2513:30:11

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 55 5351.8 MB 18 3 2204:58:35
2*.p.lodz.pl 15 5251.2 MB 11 0 004:25:37
3*.lze.lodz.pl 7 126230.5 KB 9 0 001:40:43
4*.man.lodz.pl 682 3236.1 KB 16 7 000:00:36
5*.ics.p.lodz.pl 5 1124.4 KB 4 1 000:54:53
6*.shodan.io 1 00.0 KB 0 0 000:00:01
7*.toya.net.pl 37 00.0 KB 0 0 301:29:25
8*.elsnet.pl 2 00.0 KB 0 0 000:00:16
TOTAL: 8 804 12293.3 MB 58 11 2513:30:11

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
dmcs2.p.lodz.pl 0.068 0.261 0.00003:01:39
212.191.87.58 1.353 0.852 0.00001:54:18
lze200.lze.lodz.pl 0.038 0.136 0.00001:40:43
pc-212-51-218-225.p.lodz.pl 0.019 0.082 0.00001:23:58
194.247.62.73 0.159 0.277 0.00001:23:05
staticline-31-183-22-160.toya.net.pl 0.038 0.066 0.00001:12:31
wireless.ics.p.lodz.pl 0.235 0.135 0.00000:54:53
212.51.207.190 1.092 0.574 0.00000:43:28
212.191.78.226 0.156 0.247 0.00000:27:49
78.10.54.195 0.000 0.014 0.00000:23:59
staticline-31-183-19-236.toya.net.pl 0.000 0.014 0.00000:12:55
89.38.41.212 0.062 0.161 0.00000:05:33
staticline-31-183-0-70.toya.net.pl 0.031 0.045 0.00000:03:56
web.man.lodz.pl 0.901 4.402 0.00000:00:30
212.51.203.199 0.008 0.029 0.00000:00:16
host227.elsnet.pl 0.000 0.034 0.00000:00:16
193.106.100.10 0.004 0.017 0.00000:00:05
mon.man.lodz.pl 0.975 4.086 0.00000:00:03
staticline41435.toya.net.pl 0.106 0.443 0.00000:00:01
isvcs01.man.lodz.pl 0.034 0.105 0.00000:00:01
TOTAL: 23 5.599 13.354 0.00013:30:11

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
staticline41435.toya.net.pl 30
89.38.41.212 4
staticline-31-183-22-160.toya.net.pl 3
212.51.203.199 2
host227.elsnet.pl 2
census7.shodan.io 1
78.10.54.195 1
193.106.100.10 1
staticline-31-183-19-236.toya.net.pl 1
TOTAL: 11 429

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 3
TOTAL: 1 3

NNRP client timeouts [Top 20]:

SystemConnPeer
pc-212-51-218-225.p.lodz.pl 2 0
staticline-31-183-22-160.toya.net.pl 2 0
194.247.62.73 2 0
lze200.lze.lodz.pl 2 0
wireless.ics.p.lodz.pl 1 0
212.191.87.58 1 0
dmcs2.p.lodz.pl 1 0
TOTAL: 7 11 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 1213 98.7%
2lodz 8 0.7%
3lodman 8 0.7%
TOTAL: 3 1229100.0%

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

NewsgroupCount
1pl.test 530
2pl.misc.elektronika 272
3pl.misc.kolej 253
4pl.comp.pecet 58
5pl.comp.os.ms-windows.winnt 40
6pl.rec.kuchnia 18
7pl.rec.dom 15
8pl.misc.telefonia.gsm 15
9pl.comp.os.linux 12
10lodz.test 8
11lodman.test 7
12lodman.info 1
TOTAL: 12 1229