Jump to content

Playback Reporting - New Media Report werkt niet meer


IsHome

Recommended Posts

IsHome

Hallo,

@@TeamB

Sinds 22 maart wordt er vanuit 'Notificaties' geen 'Playback Reporting - New Media Report' meer gemaakt.
De andere, 'Playback Reporting - User Activity Reports' wordt nog wel gemaakt.
Beide 'Reports' moeten 1x per 24 uur gemaakt en per 'Email Notifications Plugin' (v3.1.6.0) voor de 'embysvr' gebruiker naar het zelfde adres verzonden worden.
Alle andere 'Notificatie - Reports' werken zoals ingesteld en versturen email voor/naar de 'embysvr' gebruiker.
Dus alleen  'Playback Reporting - New Media Report' werkt niet meer.

Tot eergisteren heb ik geen veranderingen in de Embyserver configuratie gemaakt.
Wel heb ik rond 22 maart in de Synology configuratie de gebruiker embysvr lid gemaakt van de gebruikersgroep video.
Verder heb ik eergisteren de 'Pushover' notificatie plugin geinstalleerd om te zien of 'Playback Reporting - New Media Report'  hiermee werkt.
Helaas werkte de 'Playback Reporting - New Media Report' met deze plugin ook niet.
Ook heb ik in de instellingen van de  'Geplande taken - New Media Notification' een 'New Tasktrigger' gemaakt, maar daarmee werkt het ook nog steeds niet.

Tot gisterochtend was (Package) Emby server (v4.3.1.0)  geinstalleerd op een Synology DS918+ waarop zich ook de diverse bibliotheken bevinden.
Gisteren 3 april kwam op de Synology DS918+ de melding om het Embyserver package te updaten naar v4.4.0.40-1
Na het uitvoeren van deze update lijkt alles zoals voorheen te werken, maar wordt er nog steeds geen 'Notificaties' 'Playback Reporting - New Media Report' gemaakt.
Vanochtend 'Debug' op de logboeken ingeschakeld, plugin update 'Playback Reporting' v1.6.10.4 geinstalleerd, Embyserver geherstart en de film 'Ironclad 2 Battle for Blood' in de filmbibliotheek geplaatst.
Daarna per email een melding hierover van 'Systeem - Nieuwe content toegevoegd', maar na ca 30 minuten (10:26 uur) (taak trigger ingesteld op 30 minuten) geen melding hierover van 'Playback Reporting - New Media Report' .
 
Kan iemand dit 'Report' probleem verhelpen of aangeven hoe ik dit op moet lossen?
Alvast bedankt.
 

embyserver-63721593125 4-4-2020 10.32 debug enabled.txt

Link to comment
Share on other sites

TeamB

 

From your log:

 

2020-04-04 10:26:48.264 Info NewMediaReportNotification - TaskNotifictionReport: Cutoff DateTime for new items - date: 2020/04/04 07:56:48 ago: 02:30:00.0079980
2020-04-04 10:26:48.264 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : TV series
2020-04-04 10:26:48.361 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192231) - (2020-04-02 09:56:35 +02:00)
2020-04-04 10:26:48.361 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Muziekvideo's
2020-04-04 10:26:48.362 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Films
2020-04-04 10:26:48.417 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192263) - (2020-04-02 08:28:33 +02:00)
2020-04-04 10:26:48.417 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : TvDocu_etc
2020-04-04 10:26:48.443 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192216) - (2020-04-01 21:59:35 +02:00)
2020-04-04 10:26:48.443 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Afbeeldingen
2020-04-04 10:26:48.444 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Afspeellijsten
2020-04-04 10:26:48.474 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Muziek
2020-04-04 10:26:48.486 Info NewMediaReportNotification - TaskNotifictionReport: Added Item Notification Message : ItemCount : 0
New media added since last check 2 hours 30 minutes ago.



From what I can see there were no new items added in that 2 hour 30 minute time window between 2020/04/04 07:56:48 and 2020-04-04 10:26:48

 

It uses the DateCreated to sort on to get the latest items, @@Luke is DateCreated the date it was added tot he system or is there an option somewhere to have this be the date the file was created?

Link to comment
Share on other sites

IsHome

Thanks,

I don't remember changing that scan setting, why would I do that? It worked anyway.

But, I have set the scan setting as indicated, restarted the Embyserver and added an episode of Tales From The Loop to the library.

Receive an email about this from 'System - New content added'.

After about 30 minutes (task trigger is set to 30 minutes) no email about this from 'Playback Reporting - New Media Report'.

The server log contains the entry

The added episode is listed in the log lines on 2020-04-05 09: 56: 48.687

What now?

@@TeamB

embyserver 5-4-2020 10.19.txt

Edited by Luke
Link to comment
Share on other sites

TeamB

2020-04-05 09:56:48.603 Info NewMediaReportNotification - TaskNotifictionReport: Cutoff DateTime for new items - date: 2020/04/05 09:26:48 ago: 00:30:00.0281240
2020-04-05 09:56:48.603 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : TV series
2020-04-05 09:56:48.687 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192380) - (2020-04-05 07:47:20 +02:00)
2020-04-05 09:56:48.687 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Muziekvideo's
2020-04-05 09:56:48.688 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Films
2020-04-05 09:56:48.753 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192263) - (2020-04-02 08:28:33 +02:00)
2020-04-05 09:56:48.753 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : TvDocu_etc
2020-04-05 09:56:48.784 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192216) - (2020-04-01 21:59:35 +02:00)
2020-04-05 09:56:48.784 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Afbeeldingen
2020-04-05 09:56:48.785 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Afspeellijsten
2020-04-05 09:56:48.800 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Muziek
2020-04-05 09:56:48.815 Info NewMediaReportNotification - TaskNotifictionReport: Added Item Notification Message : ItemCount : 0
New media added since last check 30 minutes ago.

There is still nothing new in the last 30 min since it last run.

 

Nothing new after the cut off date/time : 2020/04/05 09:26:48

 

 

 

Link to comment
Share on other sites

IsHome

Thanks,

I see what you mean.
This is the log line of the added file:
2020-04-05 09: 56: 48.687 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item: (192380) - (2020-04-05 07:47:20 +02: 00)
But I see that the log time of adding the file is wrong.
'Emby' does not add the +02: 00 time difference!
I did not add the file at 07:47:20 but at 09:47:20
With 09:47:20 the added file was included after the cut off time: 09:26:48.
The cut off time: 09:26:48 is correct (+02: 00 is included).

 

I have set a new 4 hour trigger for the 'Playback Reporting - New Media Report'.
After that i've updated the emby server to v4.4.1.0-1 and restarted the embyserver.
Then i added a new episode of Tales from The Loop to the library at 9:01 am.
The NewMediaReport scan will be performed around 11:30 AM.
After that, if all goes well, I must receive a 'New Media Report' by email.
I'll keep you informed.

Link to comment
Share on other sites

IsHome

Hello,

As indicated in my previous 'reply' herewith the result of '4 hours NewMediaScan'.
The 'New Media Notification Report' is made again and sent by email:)

That problem has now been resolved, thank you very much for the support.

The time difference problem (as indicated in my previous 'reply') remains.
Are you going to solve that (soon)?

 

Thanks.

Link to comment
Share on other sites

TeamB

Are you saying the date added is not correct because it does not take into account the time zone offset?

Link to comment
Share on other sites

IsHome

Hello,

 

The time zone settings of the NAS DS918 + are set correctly (I added some screenshots).
The Embyserver also displays the correct times in the various parts, only the NewMediaReportNotification part uses incorrect time displays.

As indicated earlier, the time stated at the 'Cutoff DateTime' is correct.
However, the time statements for the 'Recently added item' are incorrect.
In my case, those timings are 2 hours early.
For instance:
2020-04-05 09: 56: 48.687 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item: (192380) - (2020-04-05 07:47:20 +02: 00)
Adding this item was not at 07:47:20 but at 09:47:20
The time zone difference of +02: 00 (in my case) is shown but not included.

(With the 'Cutoff DateTime' the +02: 00 is included).

 

Therefore, during the 'NewMediaReportNotification' below, the item in line '2020-04-05 09: 56: 48.687' was not counted because it would have been added before the 'Cutoff Time', while it was after.

 

2020-04-05 09:56:48.603 Info NewMediaReportNotification - TaskNotifictionReport: Cutoff DateTime for new items - date: 2020/04/05 09:26:48 ago: 00:30:00.0281240
2020-04-05 09:56:48.603 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : TV series
2020-04-05 09:56:48.687 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192380) - (2020-04-05 07:47:20 +02:00)
2020-04-05 09:56:48.687 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Muziekvideo's
2020-04-05 09:56:48.688 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Films
2020-04-05 09:56:48.753 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192263) - (2020-04-02 08:28:33 +02:00)
2020-04-05 09:56:48.753 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : TvDocu_etc
2020-04-05 09:56:48.784 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192216) - (2020-04-01 21:59:35 +02:00)
2020-04-05 09:56:48.784 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Afbeeldingen
2020-04-05 09:56:48.785 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Afspeellijsten
2020-04-05 09:56:48.800 Info NewMediaReportNotification - TaskNotifictionReport: Checking for new items in  : Muziek
2020-04-05 09:56:48.815 Info NewMediaReportNotification - TaskNotifictionReport: Added Item Notification Message : ItemCount : 0

 

I hope it comes across clearly.

 

post-574908-0-65455000-1586240236_thumb.jpg

post-574908-0-71588000-1586240249_thumb.jpg

Link to comment
Share on other sites

TeamB

@@Luke

 

I think there is a problem with the DateAdded, I run a test by adding a new item and for some reason the DateAdded is using UTC but then applying the time zone offset after the time is set.

 

Added Bloodshot Movie
Time I added the movie 1:21 PM

From the log when I added the movie showing the time it process the add movie
2020-04-08 13:22:41.746 Info App: MovieDbProvider: Finding id for item: Bloodshot

What the DatAdded has for the movie
2020-04-08 13:23:07.132 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (345:Movie:Bloodshot) - (2020-04-08 03:21:41 +10:00)

The above time is wrong for the movie DateAdded time (2020-04-08 03:21:41 +10:00)

Something is not applying the correct time zone offset to the DateAdded, in the above example the time in DateAdded is offset my my tiome zone +10:00 incorrectly. What is used is UTC but then it has the time zone offset applied anyway incorrectly.

 

Edited by TeamB
Link to comment
Share on other sites

How are you logging the date there? Can i see the code for that log statement?

Link to comment
Share on other sites

TeamB

Also can we get the full date and time in the DateAdded info on a media items page in the Web Client. Also the current one is in US date format.

Link to comment
Share on other sites

@@TeamB

 

To match the log timestamps on the left, try this to print DateAdded in UTC time:

item.DateCreated.ToString("yyyy-MM-dd HH:mm:ss.fff")
Link to comment
Share on other sites

TeamB

@@Luke this is what I get with your ToString

 

2020-04-08 15:10:02.144 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (345:Movie:Bloodshot) - (2020-04-08 03:21:41.000)

 

The time has not changed, it just does not show the offset now in the string.

 

For that time to be correct it should be

 

2020-04-08 03:21:41 +00:00

 

but it is not, it is with the incorrect offset for that time

 

2020-04-08 03:21:41 +10:00

Edited by TeamB
Link to comment
Share on other sites

TeamB

ok more weirdness, when I look at the metadata using the browser debuger I see this of that movie:
 
"DateCreated": "2020-04-08T03:21:41.0000000+00:00",
 
With the correct Time and with he correct offset of +00:00
 
So I thought it might be that I am extracting the DateTime from the DateTimeOffset
 
i.e.
 
string item_timestamp = item.DateCreated.ToString("yyyy-MM-dd HH:mm:ss.fff zzz");
gives
2020-04-08 16:21:37.342 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (345:Movie:Bloodshot) - (2020-04-08 03:21:41.000 +00:00)
 
and
string item_timestamp = item.DateCreated.DateTime.ToString("yyyy-MM-dd HH:mm:ss zzz");
gives
2020-04-08 13:23:07.132 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (345:Movie:Bloodshot) - (2020-04-08 03:21:41 +10:00)

 

Notice the offsets are different, it looks like it is something to do with using the DateCreated.DateTime

Link to comment
Share on other sites

TeamB

It looks like doing this works and converts the DateTimeOffset to a local DateTime that I can use in the comparison

 

DateTime item_date_added = item.DateCreated.ToLocalTime().DateTime;

 

I still need to test a bit but I think this fixes it.

Link to comment
Share on other sites

IsHome

Hello,
The 'Time Zone' problem seems to be solved.
What strikes me is that the log lines of an added series 'episode' do not mention the name of the series, only the title of the 'episode' does.
The Media Report does contain the series name and episode title.
See examples below.

 

2020-04-08 11:49:50.501 Info NewMediaReportNotification - TaskNotifictionReport: Cutoff DateTime for new items - date: 2020/04/08 11:34:50 +02:00 ago: 00:14:59.7401200

2020-04-08 11:49:50.524 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192472:Episode:Aflevering 4) - (2020-04-08 11:39:08 +02:00)
2020-04-08 11:49:50.524 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192473:Episode:Aflevering 5) - (2020-04-08 11:39:08 +02:00)
TV series
 - (Episode) Dublin Murders - 01x04 - Aflevering 4
 - (Episode) Dublin Murders - 01x05 - Aflevering 5

 

2020-04-08 12:34:50.496 Info NewMediaReportNotification - TaskNotifictionReport: Cutoff DateTime for new items - date: 2020/04/08 12:19:51 +02:00 ago: 00:14:59.4393900

2020-04-08 12:34:50.545 Info NewMediaReportNotification - TaskNotifictionReport: Recently added item : (192476:Movie:Escape from Pretoria) - (2020-04-08 12:30:15 +02:00)
Films
 - (Movie) Escape from Pretoria (2020)

 

Thanks for the quick support.
 

Link to comment
Share on other sites

TeamB

thanks for reporting back.

 

the log line are just for finding issues, they are not meant to be used for any other reason.

Link to comment
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
×
×
  • Create New...