FAILED: test_interceptor_shouldInterceptSignals

[ktoso]

on mac CI, rarely but need to investigate.

18:09:05 - 2020-05-12 09:09:05.706 - Test Case '-[DistributedActorsTests.InterceptorTests test_interceptor_shouldInterceptSignals]' started.
18:09:05 - 2020-05-12 09:09:05.706 - /otmp/od.H38mS6/lw.nDRNYt/d3ur-a786hf9dii16f_3-80462/t.jqEwpo/0~qwXLonhiD-Fy9BX64NPkHMuLN9QlRYsvmBdzN2jMfrcDdQs_r3c2bqQ7PDPL9fyORy66ROt2HNeS95gJR6zs3g==/SRCROOT/Tests/DistributedActorsTests/InterceptorTests.swift:117: error: -[DistributedActorsTests.InterceptorTests test_interceptor_shouldInterceptSignals] : failed - 
18:09:05 - 2020-05-12 09:09:05.706 -         try shouldNotThrow {
18:09:05 - 2020-05-12 09:09:05.706 -             ^~~~~~~~~~~~~~~
18:09:05 - 2020-05-12 09:09:05.706 - error: Unexpected throw captured
18:09:05 - 2020-05-12 09:09:05.706 -             try p.expectNoMessage(for: .milliseconds(100))
18:09:05 - 2020-05-12 09:09:05.706 -                  ^~~~~~~~~~~~~~~~
18:09:05 - 2020-05-12 09:09:05.706 - error: Received unexpected message [Terminated(/user/theWallsHaveEarsForTermination/stopperTwo, existenceConfirmed: true)]:Terminated. Did not expect to receive any messages for [100ms].
18:09:05 - 2020-05-12 09:09:05.706 - <unknown>:0: error: -[DistributedActorsTests.InterceptorTests test_interceptor_shouldInterceptSignals] : failed: caught error: "The operation couldn’t be completed. (DistributedActorsTestKit.ShouldMatcherError error 1.)"
18:09:05 - 2020-05-12 09:09:05.706 - Test Case '-[DistributedActorsTests.InterceptorTests test_interceptor_shouldInterceptSignals]' failed (0.031 seconds).

[ktoso]

Again on CI

00:07:42.498 Test Case 'InterceptorTests.test_interceptor_shouldInterceptSignals' started at 2020-06-19 08:32:15.698
00:07:42.506 <EXPR>:0: error: InterceptorTests.test_interceptor_shouldInterceptSignals : threw error "
00:07:42.506         try p.expectNoMessage(for: .milliseconds(100))
00:07:42.506              �[0;31m^~~~~~~~~~~~~~~~
00:07:42.506 error: Received unexpected message [Terminated(/user/theWallsHaveEarsForTermination/stopperTwo, existenceConfirmed: true)]:Terminated. Did not expect to receive any messages for [100ms].�[0;0m"
00:07:42.515 ------------------------------------- ActorSystem(InterceptorTests) ------------------------------------------------
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7010] [ActorSystem.swift:306] [info] Actor System [InterceptorTests] initialized.
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [OperationLogClusterReceptionist.swift:225][/system/receptionist] [debug] Initialized receptionist
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [ClusterEventStream.swift:43][/system/clusterEvents] [trace] Successfully subscribed [ActorRef<Cluster.Event>(/system/receptionist/$sub-DistributedActors.Cluster.Event-y)], offering membership snapshot
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [Gossip+Shell.swift:303][/system/replicator/gossip] [debug] Registered with receptionist key: RegistrationKey(id: crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [ClusterEventStream.swift:43][/system/clusterEvents] [trace] Successfully subscribed [ActorRef<Cluster.Event>(/system/replicator/$sub-DistributedActors.Cluster.Event-y)], offering membership snapshot
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [OperationLogClusterReceptionist.swift:313][/system/receptionist] [debug] Registered [/system/replicator/gossip] for key [AnyRegistrationKey(crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)]
00:07:42.515 // metadata:
00:07:42.515 // "receptionist/key": AnyRegistrationKey(crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)
00:07:42.515 // "receptionist/opLog/maxSeqNr": 1
00:07:42.515 // "receptionist/registered": /system/replicator/gossip
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [OperationLogClusterReceptionist.swift:284][/system/receptionist] [trace] Subscribed /system/replicator/gossip/$sub-DistributedActors.Receptionist.Listing<DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message>-y to AnyRegistrationKey(crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [ActorShell.swift:439][/user/theWallsHaveEarsForTermination] [error] DeathPactError: Unhandled [ChildTerminated(/user/theWallsHaveEarsForTermination/stopperOne)] signal about watched actor [/user/theWallsHaveEarsForTermination/stopperOne]. Handle the `.terminated` signal in `.receiveSignal()` in order react to this situation differently than termination.
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7020] [Gossip+Shell.swift:306][/system/replicator/gossip] [trace] Peer listing update via receptionist
00:07:42.515 // metadata:
00:07:42.515 // "peer/listing": 
00:07:42.515 //   ActorRef<GossipShell<DistributedActors.CRDT.Gossip>.Message>(/system/replicator/gossip)
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7030] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [terminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperOne), existenceConfirmed: true, addressTerminated: false)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#2713420077"].
00:07:42.515 // metadata:
00:07:42.515 // "deadLetter/location": /code/Sources/DistributedActors/Mailbox.swift:409
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7030] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [childTerminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperTwo), DistributedActors.TerminationCircumstances.stopped)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#2713420077"].
00:07:42.515 // metadata:
00:07:42.515 // "deadLetter/location": /code/Sources/DistributedActors/ActorShell.swift:609
00:07:42.515 Captured log [InterceptorTests][2020-06-19 8:32:15.7030] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [childTerminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperTwo), DistributedActors.TerminationCircumstances.stopped)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#2713420077"].
00:07:42.517 // metadata:
00:07:42.517 // "deadLetter/location": /code/Sources/DistributedActors/Mailbox.swift:409
00:07:42.517 Captured log [InterceptorTests][2020-06-19 8:32:15.7030] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [terminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperTwo), existenceConfirmed: true, addressTerminated: false)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#2713420077"].
00:07:42.517 // metadata:
00:07:42.517 // "deadLetter/location": /code/Sources/DistributedActors/DeathWatch.swift:183
00:07:42.517 ========================================================================================================================
00:07:42.517 Test Case 'InterceptorTests.test_interceptor_shouldInterceptSignals' failed (0.019 seconds)

Good, was wondering if it's still a thing. Will look into it, likely a test problem not infra but we'll see

[ktoso]

00:07:49.422 <EXPR>:0: error: InterceptorTests.test_interceptor_shouldInterceptSignals : threw error "
00:07:49.422         try p.expectNoMessage(for: .milliseconds(100))
00:07:49.422              �[0;31m^~~~~~~~~~~~~~~~
00:07:49.422 error: Received unexpected message [Terminated(/user/theWallsHaveEarsForTermination/stopperTwo, existenceConfirmed: true)]:Terminated. Did not expect to receive any messages for [100ms].�[0;0m"
00:07:49.432 ------------------------------------- ActorSystem(InterceptorTests) ------------------------------------------------
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5800] [ActorSystem.swift:306] [info] Actor System [InterceptorTests] initialized.
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5800] [OperationLogClusterReceptionist.swift:225][/system/receptionist] [debug] Initialized receptionist
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5800] [ClusterEventStream.swift:43][/system/clusterEvents] [trace] Successfully subscribed [ActorRef<Cluster.Event>(/system/replicator/$sub-DistributedActors.Cluster.Event-y)], offering membership snapshot
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5800] [Gossip+Shell.swift:303][/system/replicator/gossip] [debug] Registered with receptionist key: RegistrationKey(id: crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5810] [ClusterEventStream.swift:43][/system/clusterEvents] [trace] Successfully subscribed [ActorRef<Cluster.Event>(/system/receptionist/$sub-DistributedActors.Cluster.Event-y)], offering membership snapshot
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5810] [OperationLogClusterReceptionist.swift:313][/system/receptionist] [debug] Registered [/system/replicator/gossip] for key [AnyRegistrationKey(crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)]
00:07:49.432 // metadata:
00:07:49.432 // "receptionist/key": AnyRegistrationKey(crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)
00:07:49.432 // "receptionist/opLog/maxSeqNr": 1
00:07:49.432 // "receptionist/registered": /system/replicator/gossip
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5810] [OperationLogClusterReceptionist.swift:284][/system/receptionist] [trace] Subscribed /system/replicator/gossip/$sub-DistributedActors.Receptionist.Listing<DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message>-y to AnyRegistrationKey(crdt-gossip-replicator, typeHint: DistributedActors.GossipShell<DistributedActors.CRDT.Gossip>.Message)
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5810] [Gossip+Shell.swift:306][/system/replicator/gossip] [trace] Peer listing update via receptionist
00:07:49.432 // metadata:
00:07:49.432 // "peer/listing": 
00:07:49.432 //   ActorRef<GossipShell<DistributedActors.CRDT.Gossip>.Message>(/system/replicator/gossip)
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5810] [ActorShell.swift:439][/user/theWallsHaveEarsForTermination] [error] DeathPactError: Unhandled [ChildTerminated(/user/theWallsHaveEarsForTermination/stopperOne)] signal about watched actor [/user/theWallsHaveEarsForTermination/stopperOne]. Handle the `.terminated` signal in `.receiveSignal()` in order react to this situation differently than termination.
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5810] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [terminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperOne), existenceConfirmed: true, addressTerminated: false)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#3036775771"].
00:07:49.432 // metadata:
00:07:49.432 // "deadLetter/location": /code/Sources/DistributedActors/Mailbox.swift:409
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5820] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [childTerminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperTwo), DistributedActors.TerminationCircumstances.stopped)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#3036775771"].
00:07:49.432 // metadata:
00:07:49.432 // "deadLetter/location": /code/Sources/DistributedActors/ActorShell.swift:609
00:07:49.432 Captured log [InterceptorTests][2020-06-22 3:33:49.5820] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [childTerminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperTwo), DistributedActors.TerminationCircumstances.stopped)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#3036775771"].
00:07:49.434 // metadata:
00:07:49.434 // "deadLetter/location": /code/Sources/DistributedActors/Mailbox.swift:409
00:07:49.434 Captured log [InterceptorTests][2020-06-22 3:33:49.5820] [DeadLetters.swift:229][/user/theWallsHaveEarsForTermination] [info] Dead letter: [terminated(ref: AddressableActorRef(/user/theWallsHaveEarsForTermination/stopperTwo), existenceConfirmed: true, addressTerminated: false)]:DistributedActors._SystemMessage was not delivered to ["/user/theWallsHaveEarsForTermination#3036775771"].
00:07:49.434 // metadata:
00:07:49.434 // "deadLetter/location": /code/Sources/DistributedActors/DeathWatch.swift:183
00:07:49.434 ========================================================================================================================
00:07:49.434 Test Case 'InterceptorTests.test_interceptor_shouldInterceptSignals' failed (0.02 seconds)

[ktoso]

Duplicate of #556