Jump to content


Photo
- - - - -

Playback Reporting - New Media Report werkt niet meer


  • Please log in to reply
22 replies to this topic

#1 IsHome OFFLINE  

IsHome

    Member

  • Members
  • 13 posts
  • Local time: 12:44 PM

Posted 04 April 2020 - 04:51 AM

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.
 

Attached Files



#2 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 04 April 2020 - 06:45 PM


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?

#3 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 04 April 2020 - 06:56 PM

Attached File  DateAdded.png   104.26KB   0 downloads

This setting is in the library advanced settings, make sure it is set as above

#4 IsHome OFFLINE  

IsHome

    Member

  • Members
  • 13 posts
  • Local time: 12:44 PM

Posted 05 April 2020 - 04:31 AM

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

Attached Files


Edited by Luke, 05 April 2020 - 12:14 PM.


#5 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 05 April 2020 - 05:14 PM

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
 


 



#6 IsHome OFFLINE  

IsHome

    Member

  • Members
  • 13 posts
  • Local time: 12:44 PM

Posted 06 April 2020 - 03:29 AM

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.



#7 IsHome OFFLINE  

IsHome

    Member

  • Members
  • 13 posts
  • Local time: 12:44 PM

Posted 06 April 2020 - 07:56 AM

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.



#8 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 06 April 2020 - 08:09 AM

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



#9 IsHome OFFLINE  

IsHome

    Member

  • Members
  • 13 posts
  • Local time: 12:44 PM

Posted 06 April 2020 - 08:46 AM

Hi,

Yes, thats correct.

 



#10 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 06 April 2020 - 04:48 PM

@Luke is date added correct



#11 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 06:44 AM

Posted 06 April 2020 - 04:57 PM

Yes, make sure the time zone of the server machine is set correctly.



#12 IsHome OFFLINE  

IsHome

    Member

  • Members
  • 13 posts
  • Local time: 12:44 PM

Posted 07 April 2020 - 02:24 AM

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.

 

Attached Files



#13 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 07 April 2020 - 11:33 PM

@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, 07 April 2020 - 11:33 PM.


#14 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 06:44 AM

Posted 07 April 2020 - 11:40 PM

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



#15 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 07 April 2020 - 11:51 PM

@Luke

I am using:

item.DateCreated.DateTime.ToString("yyyy-MM-dd HH:mm:ss zzz")

https://github.com/f...rt.cs#L112-L150


Edited by TeamB, 07 April 2020 - 11:52 PM.


#16 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 08 April 2020 - 12:33 AM

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.



#17 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 06:44 AM

Posted 08 April 2020 - 12:52 AM

@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")


#18 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 08 April 2020 - 01:11 AM

@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, 08 April 2020 - 02:01 AM.


#19 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 08 April 2020 - 02:25 AM

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



#20 TeamB OFFLINE  

TeamB

    Advanced Member

  • Developers
  • 465 posts
  • Local time: 08:44 PM

Posted 08 April 2020 - 02:33 AM

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.






0 user(s) are reading this topic

0 members, 0 guests, 0 anonymous users