Daily Usenet report

May 4 04:15:02 -- May 5 04:15:02

Unknown entries from news log file:

First 25 / 25 lines (100.0%)

May 4 05:25:43 newsfeed overchan[13650]: input line too long, skipping
May 4 05:44:09 newsfeed overchan[13650]: input line too long, skipping
May 4 05:44:31 newsfeed overchan[13650]: input line too long, skipping
May 4 05:44:51 newsfeed overchan[13650]: input line too long, skipping
May 4 05:45:21 newsfeed overchan[13650]: input line too long, skipping
May 4 05:45:44 newsfeed overchan[13650]: input line too long, skipping
May 4 05:46:28 newsfeed overchan[13650]: input line too long, skipping
May 4 05:46:53 newsfeed overchan[13650]: input line too long, skipping
May 4 05:47:19 newsfeed overchan[13650]: input line too long, skipping
May 4 05:47:43 newsfeed overchan[13650]: input line too long, skipping
May 4 05:51:18 newsfeed overchan[13650]: input line too long, skipping
May 4 05:51:38 newsfeed overchan[13650]: input line too long, skipping
May 4 05:51:57 newsfeed overchan[13650]: input line too long, skipping
May 4 08:33:10 newsfeed nnrpd[23726]: SERVER perl filter not defined
May 4 08:33:21 newsfeed nnrpd[23727]: SERVER perl filter not defined
May 4 08:33:31 newsfeed nnrpd[23729]: SERVER perl filter not defined
May 4 09:50:02 newsfeed controlchan[13647]: Unknown "tags" control by robomod@news.nic.it
May 4 11:05:37 newsfeed nnrpd[29269]: SERVER perl filter not defined
May 4 14:04:19 newsfeed nnrpd[3412]: SERVER perl filter not defined
May 4 16:17:10 newsfeed overchan[13650]: input line too long, skipping
May 4 16:27:23 newsfeed overchan[13650]: input line too long, skipping
May 4 19:07:29 newsfeed overchan[13650]: input line too long, skipping
May 4 19:08:27 newsfeed overchan[13650]: input line too long, skipping
May 4 19:12:49 newsfeed overchan[13650]: input line too long, skipping
May 5 00:59:04 newsfeed nnrpd[27803]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn2594644 97.4%307.2 MB 97.2%
innfeed 43064 1.6%5.0 MB 1.6%
innd 22330 0.8%3.5 MB 1.1%
nnrpd 4791 0.2%501.9 KB 0.2%
overchan 19 0.0%3.1 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 62664850 100.0%316.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2571810 96.7%
Negative hits 74807 2.8%
Positive hits 12473 0.5%
Cache misses 343 0.0%
TOTAL: 4 2659433 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.853 0.0%205 0.000 18.795 465.000
article cleanup00:00:31.273 0.0%2594298 0.000 0.012 0.042
article logging00:01:35.364 0.1%2594855 0.019 0.037 0.134
article parse00:55:10.829 3.8%189127900 0.000 0.018 0.033
article write00:26:51.170 1.9%2582540 0.170 0.624 8.015
artlog/artcncl00:00:00.007 0.0%190 0.000 0.037 1.000
data move00:02:32.233 0.2%205192386 0.000 0.001 0.036
hisgrep/artcncl00:00:00.953 0.0%181 0.000 5.265 245.000
hishave/artcncl00:00:00.168 0.0%205 0.000 0.820 16.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:40:57.191 2.8%2680550 0.134 0.917 9.568
history sync00:00:00.020 0.0%304 0.000 0.066 1.000
history write01:45:19.144 7.3%2590161 0.340 2.440 22.632
hiswrite/artcncl00:00:00.086 0.0%24 0.000 3.583 10.333
idle13:48:23.490 57.5%138980260 0.199 0.358 3038.645
nntp read01:07:30.213 4.7%187055597 0.007 0.022 0.041
overview write00:00:13.280 0.0%2582540 0.000 0.005 0.033
perl filter04:31:41.164 18.9%2590161 1.268 6.293 7.115
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:44.689 0.3%5206694 0.032 0.043 0.107
TOTAL: 24:00:10.45223:24:35.127 97.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.294 0.0%134193 0.000 0.002 0.011
article prepare00:00:00.019 0.0%91072 0.000 0.000 0.014
article read00:01:10.395 0.1%22761 0.134 3.093 97.750
backlog stats00:00:00.230 0.0%292513 0.000 0.001 0.004
callbacks00:00:00.000 0.0%14 0.000 0.000 0.000
data read00:00:03.271 0.0%161053 0.004 0.020 0.056
data write00:00:20.732 0.0%131270 0.014 0.158 0.502
idle23:51:37.200 99.8%292513 25.188 293.653 806.489
status file00:00:18.938 0.0%16210 0.592 1.168 8.333
TOTAL: 23:53:49.42023:53:31.079100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:30:28.031 99.2%3052 0.000 598.962129324.929
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.878 0.2%67196 0.000 0.043 0.500
readart00:00:00.007 0.0%22 0.000 0.318 3.500
TOTAL: 00:30:43.06400:30:30.916 99.3%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 192

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 256 25822432572614 5473 4156 99%214:43:28
2news.nask.pl 2 19812 9397 2805 7610 47%24:01:37
3news.icm.edu.pl 51 300 7 279 14 2%17:35:01
4localhost 7 6 6 0 0 100%00:00:00
TOTAL: 4 316 26023612582024 8557 11780 99%256:20:06
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl750.2 GB40.9 MB83.2 MB750.4 GB 99%305.3 KB
2news.nask.pl37.0 MB1.4 MB73.5 MB111.8 MB 33%6.7 KB
3news.icm.edu.pl137.8 KB9.3 KB242.1 KB389.2 KB 35%18.5 KB
4localhost8.2 KB0.0 KB0.0 KB8.2 KB100%1.4 KB
TOTAL: 4750.3 GB42.3 MB156.9 MB750.5 GB 99%303.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 4 04:15:02 - 04:59:59 89271 3.5% 33.0927.6 GB 3.7%10729.58
May 4 05:00:00 - 05:59:59 97247 3.8% 27.0131.8 GB 4.2%9248.37
May 4 06:00:00 - 06:59:59 102598 4.0% 28.5031.5 GB 4.2%9166.66
May 4 07:00:00 - 07:59:59 40978 1.6% 11.3811.9 GB 1.6%3463.48
May 4 08:00:00 - 08:59:59 395 0.0% 0.112.2 MB 0.0% 0.62
May 4 09:00:00 - 09:59:59 859 0.0% 0.243.3 MB 0.0% 0.93
May 4 10:00:00 - 10:59:59 49977 1.9% 13.8814.8 GB 2.0%4323.81
May 4 11:00:00 - 11:59:59 97476 3.8% 27.0828.1 GB 3.8%8196.72
May 4 12:00:00 - 12:59:59 135405 5.2% 37.6139.0 GB 5.2%11345.34
May 4 13:00:00 - 13:59:59 105367 4.1% 29.2729.2 GB 3.9%8495.82
May 4 14:00:00 - 14:59:59 117417 4.5% 32.6234.2 GB 4.6%9961.92
May 4 15:00:00 - 15:59:59 138988 5.4% 38.6142.7 GB 5.7%12441.16
May 4 16:00:00 - 16:59:59 139053 5.4% 38.6343.5 GB 5.8%12681.01
May 4 17:00:00 - 17:59:59 141243 5.5% 39.2341.8 GB 5.6%12169.42
May 4 18:00:00 - 18:59:59 141735 5.5% 39.3741.2 GB 5.5%12009.65
May 4 19:00:00 - 19:59:59 135789 5.3% 37.7238.8 GB 5.2%11291.16
May 4 20:00:00 - 20:59:59 133779 5.2% 37.1638.7 GB 5.2%11273.59
May 4 21:00:00 - 21:59:59 133427 5.2% 37.0636.2 GB 4.8%10541.98
May 4 22:00:00 - 22:59:59 122960 4.8% 34.1633.8 GB 4.5%9850.27
May 4 23:00:00 - 23:59:59 122380 4.7% 33.9934.4 GB 4.6%10011.65
May 5 00:00:00 - 00:59:59 114931 4.5% 31.9331.7 GB 4.2%9222.90
May 5 01:00:00 - 01:59:59 125003 4.8% 34.7234.5 GB 4.6%10051.96
May 5 02:00:00 - 02:59:59 143726 5.6% 39.9240.4 GB 5.4%11778.03
May 5 03:00:00 - 03:59:59 122740 4.8% 34.0934.5 GB 4.6%10059.09
May 5 04:00:00 - 04:15:02 29410 1.1% 32.618.3 GB 1.1%9688.55
TOTAL: 24:00:00 2582154 100.0% 29.89748.6 GB 100.0%9085.60
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 7786 20 0 475 8 0 0 0 7283
2newsfeed.pionier.net.pl 4499 145 0 3663 2 0 0 0 689
3news.icm.edu.pl 15 0 0 1 0 0 0 0 14
TOTAL: 3 12300 165 0 4139 10 0 0 0 7986

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 70
it.comp.os.win.windows10 35
it.tlc.cellulari.android 31
comp.mobile.android 11
comp.sys.raspberry-pi 6
de.comp.lang.vbclassic 5
comp.mobile.ipad 2
hr.ponuda.hardver.komponente 1
alt.sex.telephones 1
de.comp.office-pakete.ms-office.misc 1
it.discussioni.energia 1
it.comp.os.win.windows7 1
TOTAL: 12 165

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

GroupsCount
pl.praca.oferowana 8
pl.sci.matematyka 1
bit.listserv.catholic 1
TOTAL: 3 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6826
Binary in non-binary group 464
EMP (phl) 61
Scoring filter (8) 37
Scoring filter (9) 14
Too many newsgroups 12
Scoring filter (10) 9
EMP (fsl) 3
Scoring filter (11) 2
Adult group ECP 1
TOTAL: 10 7429

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 333
news.nask.pl 223
news.icm.edu.pl 1
TOTAL: 3 557
TOTAL: 1 557

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 45773 14651 20229 5612 0 190 32%23:59:56
2panorama 100589 1219 71963 419 28 59339 1%23:59:57
3poznan 13397 278 9004 46 0 1244 2%23:59:57
4nask 5886 7 4080 1 0 0 0%21:33:45
5okapi 0 0 0 0 0 1721 0%23:57:51
TOTAL: 5 165645 16155 105276 6078 28 62494 9%117:31:26
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm2.6 GB1.1 GB3.7 GB44.6 KB/s190.2 KB23:59:56
2panorama3.5 MB1.1 MB4.6 MB0.1 KB/s2.9 KB23:59:57
3poznan1006.3 KB147.6 KB1.1 MB0.0 KB/s3.6 KB23:59:57
4nask13.7 KB0.8 KB14.4 KB0.0 KB/s1.8 KB21:33:45
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:51
TOTAL: 52.6 GB1.1 GB3.7 GB9.1 KB/s173.6 KB117:31:26
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama488.3 KB
TOTAL: 1488.3 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 291 2017.5 KB 9 0 000:00:22
2212.191.87.58 1 21.6 KB 2 1 000:30:14
3isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 3 297 2219.0 KB 11 6 000:30:42

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 680 2017.5 KB 9 5 000:00:28
2unresolved 1 21.6 KB 2 1 000:30:14
TOTAL: 2 681 2219.0 KB 11 6 000:30:42

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.191.87.58 0.288 0.148 0.00000:30:14
web.man.lodz.pl 1.144 3.696 0.00000:00:22
mon.man.lodz.pl 1.020 3.588 0.00000:00:03
david.man.lodz.pl 0.356 1.176 0.00000:00:01
isvcs01.man.lodz.pl 0.032 0.076 0.00000:00:00
TOTAL: 5 2.840 8.684 0.00000:30:42

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
TOTAL: 2 384

Newsgroup request counts (by category):

CategoryCountPct
1pl 20 90.9%
2alt 2 9.1%
TOTAL: 2 22100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 18
2alt.test.pl 2
3pl.comp.pecet 2
TOTAL: 3 22