Same artist back to back - History check

[hdradio]

Global song history is set to 40.
The files are here:
https://we.tl/JHdVliIFy3

[hdradio]

https://www.dropbox.com/s/zfarp7jn32c2v ... 0.pfs?dl=0
line 1,2 = 530 entries
line 3,4,5 = 115 entries

[hdradio]

Bernd I am also trying to find what is wrong.
I test on Find Track window the script find expression.
It returns a number of tracks for example 115.
I add the column "Last Played At" and sort on it.
I see for example a song last played 05/04/2016.
But I check my log and I see the same song played 3 times yesterday !

Also looking at the debug log I 've found the following exception (maybe it's not related to this case):

Code: Select all

13/04/2016 06:59:17: CheckForNextTrackToSchedule1 error: 13/04/2016 06:59:17 [v4.0.1.21]:********** Error/Exception Information **********
Error Message: Object reference not set to an instance of an object.
Error Type: System.NullReferenceException
Error Source: ProppFrexx ONAIR.Void gGqb(System.DateTime, System.DateTime, Byte, ProppFrexx.MediaEntry)
********** System Stack Trace **********
   at ProppFrexx.Playlist.gGqb(DateTime  , DateTime  , Byte  , MediaEntry  )
********** Environment Stack Trace **********
   at System.Environment.GetStackTrace(Exception e, Boolean needFileInfo)
   at System.Environment.get_StackTrace()
   at ProppFrexx.ErrorDialog.GetExceptionInfo(Exception e, Boolean full)
   at ProppFrexx.Playlist.gGqb(DateTime  , DateTime  , Byte  , MediaEntry  )
   at ProppFrexx.Playlist.cGqa(Boolean  , Byte  )
   at ProppFrexx.Playlist.CGqM(Byte  )
   at ProppFrexx.Playlist.<>c__DisplayClass19b.<MarkTrackAsPlayed>b__19a()
   at System.Threading.Tasks.Task.Execute()
   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
   at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
   at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot)
   at System.Threading.Tasks.Task.ExecuteEntry(Boolean bPreventDoubleExecution)
   at System.Threading.ThreadPoolWorkQueue.Dispatch()
********** End **********

[hdradio]

Yes it's the same physical song from the same media lib.

I don't know how often you save the "ProppFrexx MediaLibraryServer.entrystats" to disk but it seems that not all the played items are updated into the file.
For example a file :

Code: Select all

File: \\ONAIR1\Music\OnAir\Ελληνικά\Έντεχνα\Περίδης Ορφέας\Για που το 'βαλες καρδιά μου\Περίδης Ορφέας - Παραμύθι.mp3
Name: Περίδης Ορφέας - Παραμύθι
  Tempo=0,00, Gain=1,00, ReplayGain=0,54 (1,08)
  Length:    4:54
  Duration:  4:54 (Effective: 4:54)
  CueIn:     --
  FullLevel: --
  Ramp:      --  Ramp2: --
  Outro:     --
  FadeOut:   --
  Next:      --
  CueOut:    --

  Played:    9 (13/04/2016 14:20:17) - 0 days ago
  Created:   19/04/2013 20:13:48 - 1090 days ago
  Modified:  29/12/2014 01:17:43 - 472 days ago

on .entrystats file is:

Code: Select all

    <entry>
        <id>ΠΕΡΊΔΗΣ ΟΡΦΈΑΣ - ΠΑΡΑΜΎΘΙ</id>
        <playCounter>8</playCounter>
        <lastPlayed>2016-03-11T13:12:16.6685881+02:00</lastPlayed>
    </entry>

[radio42]

I save the entry statistic file with every 10 updates...
An update happens depending on the general settings, section 'Logging', see the 'Update Statistic only when' option!

However, in your example it looks like the item was JUST played (that why the in memory statistic show the correct and new value of 9 play counts); but the entry statistic file wasn't written yet (the 10 updates) haven't happened yet...e.g. when your average track length is 4 minutes, 10 updates might mean a delay of 40 minutes until the next save is issued to the entry statistic file.

[radio42]

I tested you tracks here - but they perfectly match and 'violate' any history check.
So it is really strange.
Do you happen to have a Debug-log file available from the time when two Artists play back to back...as the debug log should actually also contain some more info from where and why they are scheduled....

[hdradio]

Look again:

2016-04-13_192000.png (325.4 KiB) Viewed 10741 times

Look the LastPlayedAt.
But my playback log show that this file played many times:

2016-04-13_192100.png (213.34 KiB) Viewed 10741 times

This is yesterday's debug log:
https://www.dropbox.com/s/cenuwyshoyzpj ... 2.log?dl=0
You can find the titles by looking at the screenshot times.
And today's log (until now).
https://www.dropbox.com/s/ul7j8bpdzpjjq ... 3.log?dl=0

Also search for "Γλυκερία" in debug log to see how many times this entry exist in it.

[hdradio]

And the story goes on:

2016-04-13_194700.png (275.55 KiB) Viewed 10739 times

2016-04-13_195000.png (215.74 KiB) Viewed 10739 times

I have updated the debug log file:
https://www.dropbox.com/s/ul7j8bpdzpjjq ... 3.log?dl=0

[radio42]

As said I cannot reproduce that here and maybe there is (as already explained) something more in general not okay...
...I cannot do much more as to look in the logs to see, if I can find anything!
I also contacted a few other users, but they don't have any such issues at all.
So I assume it must be something specific with your config/setup - as said, I don't know yet.

What really wonders me is, why you have 2 entries subsequently from exactly the same script-line!
This should never happen and can even programmatically not happen, and I never saw this happening! See your last screenshot!
Player C & B both seems to contain an entry derived from script-line number 7 ?!
How could this be? Are you sure no one manually adds tracks to the playlist?!

I might need to investigate your log file, so give me some time!

[hdradio]

What really wonders me is, why you have 2 entries subsequently from exactly the same script-line!

Because I have count of 2 in that script line.