Missing activity notifications in dashboard, rss etc.

[cdw]

Hello all,

We recently had the problem that the activity feed list remained empty although we made some uploads, changes etc. Then we found some error messages in the log about invalid nodes. As I read there is a known problem of 4.2.c around this topic. According to some hints in this forum, I removed those buggy nodes and wiped the whole content of alf_activity_feed and alf_activity_post.
Then the error messages disappeared and I can also see those tables being filled properly (as I understand it) again.

And now:
* We see the updates on Wiki pages (new pages, updated pages)
* The activity dashlet shows stuff like UPDATED blog entries
* BUT: We do NOT see any CREATED blog entries; the activity dashlet remains empty, same for RSS feed

What could be the reason for this? And might this problem be related to our former problem?

We have an installation of Alfresco 4.2.c community edition on a Linux machine, Tomcat 7.0.39, Java 7u25 and a MySQL database.

[cdw]

In the meanwhile I found another strange behaviour: If I create a blog post as a regular user (coming in via LDAP sync), I cannot see this blog post in any notification dashlet. It's also not displayed on other user's dashboards.
If I do the same stuff with a local administrator account, it seems to work.

I also tried to set the debug level of this module to "debug", but did not spot anything special yet. Could anyone please give me a hint where I should continue debugging?

[cdw]

In the meanwhile I switched on tracing and found the following log entries…

I created a blog post and got:

2013-07-12 09:41:46,900  DEBUG [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-5] lock aquired:  0bfcaac6-43cd-4df7-93d1-b05ff38148d5
 2013-07-12 09:41:46,900  TRACE [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-5] Activities feed generator started
 2013-07-12 09:41:46,907  DEBUG [feed.local.LocalFeedGenerator] [DefaultScheduler_Worker-5] >>> Execute job cycle: local (maxSeq: 348)
 2013-07-12 09:41:46,908  DEBUG [activities.feed.FeedTaskSplit] [DefaultScheduler_Worker-5] split: start - gridSize = 1, maxSequence = 348
 2013-07-12 09:41:46,909  DEBUG [feed.local.LocalFeedGridJob] [DefaultScheduler_Worker-5] >>> Execute: nodehash '1' from seq '249' to seq '348' on this node
 2013-07-12 09:41:46,911  DEBUG [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-5] Process: jobTaskNode '1' from seq '249' to seq '348' on this node from grid job.
 2013-07-12 09:41:46,916  DEBUG [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-5] Process: 1 activity posts
 2013-07-12 09:41:46,922  TRACE [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-5] Process: 4 candidate connections for activity post 348
 2013-07-12 09:41:46,927  DEBUG [feed.local.LocalFeedTaskProcessor] [DefaultScheduler_Worker-5] canRead: workspace://SpacesStore/6785ddf9-8b46-4bfe-9796-67e136af61f5 in 4 msecs
 2013-07-12 09:41:46,942  DEBUG [feed.local.LocalFeedTaskProcessor] [DefaultScheduler_Worker-5] canRead: workspace://SpacesStore/6785ddf9-8b46-4bfe-9796-67e136af61f5 in 10 msecs
 2013-07-12 09:41:46,967  DEBUG [feed.local.LocalFeedTaskProcessor] [DefaultScheduler_Worker-5] canRead: workspace://SpacesStore/6785ddf9-8b46-4bfe-9796-67e136af61f5 in 10 msecs
 2013-07-12 09:41:46,982  DEBUG [feed.local.LocalFeedTaskProcessor] [DefaultScheduler_Worker-5] canRead: workspace://SpacesStore/6785ddf9-8b46-4bfe-9796-67e136af61f5 in 4 msecs
 2013-07-12 09:41:46,994  DEBUG [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-5] Processed: 3 connections for activity post 348 (excluded 1)
 2013-07-12 09:41:46,994  INFO  [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-5] Generated 3 activity feed entries for 1 activity post (in 83 msecs)
 2013-07-12 09:41:46,994  DEBUG [feed.local.LocalFeedGenerator] [DefaultScheduler_Worker-5] >>> Finish job cycle: local (in 87 msecs)
 2013-07-12 09:41:46,994  TRACE [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-5] Activities feed generator completed
 2013-07-12 09:41:47,004  DEBUG [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-5] Lock released (refresh failed): {http://www.alfresco.org/model/system/1.0}ActivityFeedGenerator, lock token 0bfcaac6-43cd-4df7-93d1-b05ff38148d5
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

So, why did the job exclude one connection? The missing one is even Manager of that Site, so there shouldnt be any permission issue.

On the other side, if I change a Wiki page, I see the following:

 2013-07-12 09:42:46,901  DEBUG [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-8] lock aquired:  fc0792e8-a03e-45c3-b0b0-44aac293289e
 2013-07-12 09:42:46,901  TRACE [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-8] Activities feed generator started
 2013-07-12 09:42:46,907  DEBUG [feed.local.LocalFeedGenerator] [DefaultScheduler_Worker-8] >>> Execute job cycle: local (maxSeq: 349)
 2013-07-12 09:42:46,908  DEBUG [activities.feed.FeedTaskSplit] [DefaultScheduler_Worker-8] split: start - gridSize = 1, maxSequence = 349
 2013-07-12 09:42:46,908  DEBUG [feed.local.LocalFeedGridJob] [DefaultScheduler_Worker-8] >>> Execute: nodehash '1' from seq '250' to seq '349' on this node
 2013-07-12 09:42:46,908  DEBUG [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-8] Process: jobTaskNode '1' from seq '250' to seq '349' on this node from grid job.
 2013-07-12 09:42:46,914  DEBUG [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-8] Process: 1 activity posts
 2013-07-12 09:42:46,923  TRACE [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-8] Process: 4 candidate connections for activity post 349
 2013-07-12 09:42:46,974  DEBUG [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-8] Processed: 4 connections for activity post 349 (excluded 0)
 2013-07-12 09:42:46,975  INFO  [activities.feed.FeedTaskProcessor] [DefaultScheduler_Worker-8] Generated 4 activity feed entries for 1 activity post (in 67 msecs)
 2013-07-12 09:42:46,975  DEBUG [feed.local.LocalFeedGenerator] [DefaultScheduler_Worker-8] >>> Finish job cycle: local (in 68 msecs)
 2013-07-12 09:42:46,975  TRACE [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-8] Activities feed generator completed
 2013-07-12 09:42:46,985  DEBUG [activities.feed.AbstractFeedGenerator] [DefaultScheduler_Worker-8] Lock released (refresh failed): {http://www.alfresco.org/model/system/1.0}ActivityFeedGenerator, lock token fc0792e8-a03e-45c3-b0b0-44aac293289e
‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Here everyone gets the notification. But, why … ?

[cdw]

After installing Alfresco 4.2.e this error dissolved…