[
https://issues.apache.org/jira/browse/SLING-10254?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Timothee Maret updated SLING-10254:
-----------------------------------
Description:
The test SubscriberTest#testReceiveDelete regularly fails in our Jenkins
infrastructure. See for instance
[here|https://ci-builds.apache.org/job/Sling/job/modules/job/sling-org-apache-sling-distribution-journal/job/master/99/testReport/org.apache.sling.distribution.journal.impl.subscriber/SubscriberTest/testReceiveDelete/].
It fails with
{code}
Error Message
Lambda expression in
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest that uses
org.apache.sling.distribution.journal.impl.subscriber.DistributionSubscriber:
expected <RUNNING> but was <IDLE> within 30 seconds.
Stacktrace
org.awaitility.core.ConditionTimeoutException: Lambda expression in
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest that uses
org.apache.sling.distribution.journal.impl.subscriber.DistributionSubscriber:
expected <RUNNING> but was <IDLE> within 30 seconds.
at
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest.waitSubscriber(SubscriberTest.java:465)
at
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest.testReceiveDelete(SubscriberTest.java:304)
Standard Output
2021-03-23 11:22:26,835 INFO [main] o.a.s.d.j.b.BookKeeper [BookKeeper.java :
122] Started bookkeeper subAgentName=SUB1AGENT, subSlingId=sub1sling,
editable=false, maxRetries=-1, packageHandling=Off,
packageNodeName=myserver.apache.org_aemdistribution_package. -
2021-03-23 11:22:26,835 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.i.s.DistributionSubscriber [DistributionSubscriber.java : 283]
Started Queue processor -
2021-03-23 11:22:26,836 INFO [main] o.a.s.d.j.i.s.DistributionSubscriber
[DistributionSubscriber.java : 197] Started Subscriber agent sub1agent at
offset 0, subscribed to agent names [pub1agent] -
2021-03-23 11:22:26,841 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.b.BookKeeper [BookKeeper.java : 142] Importing distribution package
PackageMessage(pubSlingId=pub1sling, reqType=DELETE, pkgId=myid,
pkgType=journal, pkgLength=0, pubAgentName=pub1agent, userId=null,
paths=[/test], deepPaths=[]) at offset=0 -
2021-03-23 11:22:26,843 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.b.PackageHandler [PackageHandler.java : 102] Deleting paths [/test] -
retries=0, paths=/test, sub-sling-id=sub1sling, module=distribution,
sub-agent-name=sub1agent, pub-sling-id=pub1sling,
distribution-message-type=DELETE, package-id=myid, pub-agent-name=pub1agent
2021-03-23 11:22:56,983 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.i.s.DistributionSubscriber [DistributionSubscriber.java : 304]
Stopped Queue processor -
2021-03-23 11:22:56,984 INFO [main] o.a.s.d.j.i.s.DistributionSubscriber
[DistributionSubscriber.java : 227] Stopped Subscriber agent sub1agent,
subscribed to Publisher agent names [pub1agent] with package builder journal -
{code}
was:
The test SubscriberTest#testReceiveDelete regularly fails in our Jenkins
infrastructure. See for instance
[here|https://ci-builds.apache.org/job/Sling/job/modules/job/sling-org-apache-sling-distribution-journal/job/master/99/testReport/org.apache.sling.distribution.journal.impl.subscriber/SubscriberTest/testReceiveDelete/].
It fails with
{code}
Error Message
Lambda expression in
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest that uses
org.apache.sling.distribution.journal.impl.subscriber.DistributionSubscriber:
expected <RUNNING> but was <IDLE> within 30 seconds.
Stacktrace
org.awaitility.core.ConditionTimeoutException: Lambda expression in
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest that uses
org.apache.sling.distribution.journal.impl.subscriber.DistributionSubscriber:
expected <RUNNING> but was <IDLE> within 30 seconds.
at
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest.waitSubscriber(SubscriberTest.java:465)
at
org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest.testReceiveDelete(SubscriberTest.java:304)
Standard Output
2021-03-23 11:22:26,835 INFO [main] o.a.s.d.j.b.BookKeeper [BookKeeper.java :
122] Started bookkeeper subAgentName=SUB1AGENT, subSlingId=sub1sling,
editable=false, maxRetries=-1, packageHandling=Off,
packageNodeName=myserver.apache.org_aemdistribution_package. -
2021-03-23 11:22:26,835 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.i.s.DistributionSubscriber [DistributionSubscriber.java : 283]
Started Queue processor -
2021-03-23 11:22:26,836 INFO [main] o.a.s.d.j.i.s.DistributionSubscriber
[DistributionSubscriber.java : 197] Started Subscriber agent sub1agent at
offset 0, subscribed to agent names [pub1agent] -
2021-03-23 11:22:26,841 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.b.BookKeeper [BookKeeper.java : 142] Importing distribution package
PackageMessage(pubSlingId=pub1sling, reqType=DELETE, pkgId=myid,
pkgType=journal, pkgLength=0, pubAgentName=pub1agent, userId=null,
paths=[/test], deepPaths=[]) at offset=0 -
2021-03-23 11:22:26,843 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.b.PackageHandler [PackageHandler.java : 102] Deleting paths [/test] -
retries=0, paths=/test, sub-sling-id=sub1sling, module=distribution,
sub-agent-name=sub1agent, pub-sling-id=pub1sling,
distribution-message-type=DELETE, package-id=myid, pub-agent-name=pub1agent
2021-03-23 11:22:56,983 INFO [Queue Processor for Subscriber agent sub1agent]
o.a.s.d.j.i.s.DistributionSubscriber [DistributionSubscriber.java : 304]
Stopped Queue processor -
2021-03-23 11:22:56,984 INFO [main] o.a.s.d.j.i.s.DistributionSubscriber
[DistributionSubscriber.java : 227] Stopped Subscriber agent sub1agent,
subscribed to Publisher agent names [pub1agent] with package builder journal -
{code}
The test fails because it's checking states based on wrong assumptions. The
test checks for the consecutive state of the queue (e.g. RUNNING then IDLE) but
the queue may move between states faster than the checks themselves.
> SubscriberTest#testReceiveDelete fails randomly
> -----------------------------------------------
>
> Key: SLING-10254
> URL: https://issues.apache.org/jira/browse/SLING-10254
> Project: Sling
> Issue Type: Bug
> Components: Content Distribution
> Affects Versions: Content Distribution Journal Core 0.1.0
> Reporter: Timothee Maret
> Priority: Major
> Fix For: Content Distribution Journal Core 0.1.18
>
>
> The test SubscriberTest#testReceiveDelete regularly fails in our Jenkins
> infrastructure. See for instance
> [here|https://ci-builds.apache.org/job/Sling/job/modules/job/sling-org-apache-sling-distribution-journal/job/master/99/testReport/org.apache.sling.distribution.journal.impl.subscriber/SubscriberTest/testReceiveDelete/].
> It fails with
> {code}
> Error Message
> Lambda expression in
> org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest that
> uses
> org.apache.sling.distribution.journal.impl.subscriber.DistributionSubscriber:
> expected <RUNNING> but was <IDLE> within 30 seconds.
> Stacktrace
> org.awaitility.core.ConditionTimeoutException: Lambda expression in
> org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest that
> uses
> org.apache.sling.distribution.journal.impl.subscriber.DistributionSubscriber:
> expected <RUNNING> but was <IDLE> within 30 seconds.
> at
> org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest.waitSubscriber(SubscriberTest.java:465)
> at
> org.apache.sling.distribution.journal.impl.subscriber.SubscriberTest.testReceiveDelete(SubscriberTest.java:304)
> Standard Output
> 2021-03-23 11:22:26,835 INFO [main] o.a.s.d.j.b.BookKeeper [BookKeeper.java :
> 122] Started bookkeeper subAgentName=SUB1AGENT, subSlingId=sub1sling,
> editable=false, maxRetries=-1, packageHandling=Off,
> packageNodeName=myserver.apache.org_aemdistribution_package. -
> 2021-03-23 11:22:26,835 INFO [Queue Processor for Subscriber agent sub1agent]
> o.a.s.d.j.i.s.DistributionSubscriber [DistributionSubscriber.java : 283]
> Started Queue processor -
> 2021-03-23 11:22:26,836 INFO [main] o.a.s.d.j.i.s.DistributionSubscriber
> [DistributionSubscriber.java : 197] Started Subscriber agent sub1agent at
> offset 0, subscribed to agent names [pub1agent] -
> 2021-03-23 11:22:26,841 INFO [Queue Processor for Subscriber agent sub1agent]
> o.a.s.d.j.b.BookKeeper [BookKeeper.java : 142] Importing distribution package
> PackageMessage(pubSlingId=pub1sling, reqType=DELETE, pkgId=myid,
> pkgType=journal, pkgLength=0, pubAgentName=pub1agent, userId=null,
> paths=[/test], deepPaths=[]) at offset=0 -
> 2021-03-23 11:22:26,843 INFO [Queue Processor for Subscriber agent sub1agent]
> o.a.s.d.j.b.PackageHandler [PackageHandler.java : 102] Deleting paths [/test]
> - retries=0, paths=/test, sub-sling-id=sub1sling, module=distribution,
> sub-agent-name=sub1agent, pub-sling-id=pub1sling,
> distribution-message-type=DELETE, package-id=myid, pub-agent-name=pub1agent
> 2021-03-23 11:22:56,983 INFO [Queue Processor for Subscriber agent sub1agent]
> o.a.s.d.j.i.s.DistributionSubscriber [DistributionSubscriber.java : 304]
> Stopped Queue processor -
> 2021-03-23 11:22:56,984 INFO [main] o.a.s.d.j.i.s.DistributionSubscriber
> [DistributionSubscriber.java : 227] Stopped Subscriber agent sub1agent,
> subscribed to Publisher agent names [pub1agent] with package builder journal
> -
> {code}
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
