[jira] [Commented] (DISPATCH-1772) Fallback destination test prints insufficient data when it fails

["Charles E. Rolke (Jira)" <ji...@apache.org>]

    [ https://issues.apache.org/jira/browse/DISPATCH-1772?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17200379#comment-17200379 ] 

Charles E. Rolke commented on DISPATCH-1772:
--------------------------------------------

Adding some logging to the SwitchoverMix test and running it enough times leads to this instance of failure;

Time of day is just in seconds.
 Test 35. Sender: INT.A, Primary receiver: INT.B, Fallback receiver: EA1
 Test agent is named "peer_5".
{code:java}
Time(S)  What
-------  ----
00.3450  sender link open
00.3470  rx link open
00.3480  rx link open

         --- sender sends, all messages accepted

00.7241  B0_11 all accepted, detach (closes one minute later after TMO)

         --- release storm as A sends to B ---

01.2191  A -> B add MFdest.35   (this seem way late)
01.2522  B <- A add MFdest.35
01.3446  B -> A del M0dest.35
01.3473  A <- B del M0 dest.35
01.4448  A -> peer_5 FLOW with drain

         peer_5 sends 95 messages that consumes all the available credit
         peer_5 never sends a FLOW with drain set.
 {code}
There are a couple of odd things in the logs.

1. By the time that A notifies B of the fallback destination mobile address, the sender has already sent all of the Phase 0 messages. 
 2. The drain cycle is satisfied by having the sender send the full amount of credit's worth of transfers. Peer_5 never sends a FLOW with drain set back to INT.A.

> Fallback destination test prints insufficient data when it fails
> ----------------------------------------------------------------
>
>                 Key: DISPATCH-1772
>                 URL: https://issues.apache.org/jira/browse/DISPATCH-1772
>             Project: Qpid Dispatch
>          Issue Type: Bug
>          Components: Tests
>    Affects Versions: 1.13.0
>            Reporter: Charles E. Rolke
>            Assignee: Charles E. Rolke
>            Priority: Major
>
> Occasionally the Switchover_mix test fails in CI. When this happens then the self test prints only a single line describing the failure such as:
> {code:java}
>         "63: ======================================================================", 
>         "63: FAIL: test_35_switchover_mix_1 (system_tests_fallback_dest.RouterTest)", 
>         "63: ----------------------------------------------------------------------", 
>         "63: Traceback (most recent call last):", 
>         "63:   File \"/opt/qpid-dispatch-src/tests/system_tests_fallback_dest.py\", line 353, in test_35_switchover_mix_1", 
>         "63:     self.assertEqual(None, test.error)", 
>         "63: AssertionError: None != u'Timeout Expired - n_tx=0, n_rx=0, n_rel=1046, phase=1, local_rel=0'", 
>         "63: ", 
>         "63: ----------------------------------------------------------------------", 
> {code}
> This error message says that (phase=1) all the messages were sent successfully before the switchover was initiated. Then 1046 messages were released by the locally connected router until the test timed out.
> Class system_test/Logger could be used to generate a much more detailed narrative describing how the client test event stream played out. Further, the Logger log marks the client events with microsecond resolution so that when possible the log can be correlated with qdrouterd logs.



--
This message was sent by Atlassian Jira
(v8.3.4#803005)

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@qpid.apache.org
For additional commands, e-mail: dev-help@qpid.apache.org