ServiceDiscovery Failure on handle result

[sleipnir]

During tests @ralphlaude found some errors during discovery phase.
This may be related to the issue #20

iex --name a@127.0.0.1 -S mix
Erlang/OTP 23 [erts-11.1.8] [source] [64-bit] [smp:8:8] [ds:8:8:10] [async-threads:1] [hipe] [dtrace]

2021-03-07 21:31:44.033 [a@127.0.0.1]:[pid=<0.655.0> ]:[warn]: [libcluster:dev] unable to connect to :"b@127.0.0.1"
2021-03-07 21:31:44.034 [a@127.0.0.1]:[pid=<0.655.0> ]:[warn]: [libcluster:dev] unable to connect to :"c@127.0.0.1"
2021-03-07 21:31:44.034 [a@127.0.0.1]:[pid=<0.664.0> ]:[info]: Starting Horde.RegistryImpl with name MassaProxy.GlobalRegistry
2021-03-07 21:31:44.035 [a@127.0.0.1]:[pid=<0.667.0> ]:[info]: Starting Horde.DynamicSupervisorImpl with name MassaProxy.GlobalSupervisor
2021-03-07 21:31:44.035 [a@127.0.0.1]:[pid=<0.671.0> ]:[info]: Starting Proxy Cluster...
2021-03-07 21:31:44.035 [a@127.0.0.1]:[pid=<0.671.0> ]:[info]: [massa proxy on :"a@127.0.0.1"]: Connecting Horde to :"a@127.0.0.1"
2021-03-07 21:31:44.035 [a@127.0.0.1]:[pid=<0.671.0> ]:[info]: [massa proxy on :"a@127.0.0.1"]: Connecting Horde to :"a@127.0.0.1"
2021-03-07 21:31:44.036 [a@127.0.0.1]:[pid=<0.671.0> ]:[debug]:Starting Supervisors...
2021-03-07 21:31:44.039 [a@127.0.0.1]:[pid=<0.551.0> ]:[info]: Starting Registry for EventSourced
2021-03-07 21:31:44.039 [a@127.0.0.1]:[pid=<0.677.0> ]:[info]: [MassaProxy on :"a@127.0.0.1"][EntityRegistry]: Initializing...
2021-03-07 21:31:44.039 [a@127.0.0.1]:[pid=<0.675.0> ]:[warn]: CRDT: %{}
2021-03-07 21:31:44.039 [a@127.0.0.1]:[pid=<0.675.0> ]:[warn]: Picked up [] for EventSourced
2021-03-07 21:31:44.039 [a@127.0.0.1]:[pid=<0.551.0> ]:[info]: Starting Registry for CRDT
2021-03-07 21:31:44.040 [a@127.0.0.1]:[pid=<0.678.0> ]:[info]: [MassaProxy on :"a@127.0.0.1"][EntityRegistry]: Initializing...
2021-03-07 21:31:44.040 [a@127.0.0.1]:[pid=<0.675.0> ]:[warn]: CRDT: %{}
2021-03-07 21:31:44.040 [a@127.0.0.1]:[pid=<0.675.0> ]:[warn]: Picked up [] for CRDT
2021-03-07 21:31:44.040 [a@127.0.0.1]:[pid=<0.551.0> ]:[info]: Starting Registry for Stateless
Interactive Elixir (1.11.3) - press Ctrl+C to exit (type h() ENTER for help)
2021-03-07 21:31:44.040 [a@127.0.0.1]:[pid=<0.679.0> ]:[info]: [MassaProxy on :"a@127.0.0.1"][EntityRegistry]: Initializing...
2021-03-07 21:31:44.040 [a@127.0.0.1]:[pid=<0.675.0> ]:[warn]: CRDT: %{}
2021-03-07 21:31:44.040 [a@127.0.0.1]:[pid=<0.675.0> ]:[warn]: Picked up [] for Stateless
iex(a@127.0.0.1)1> 2021-03-07 21:31:44.041 [a@127.0.0.1]:[pid=<0.551.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-07 21:31:50.043 [a@127.0.0.1]:[pid=<0.682.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-07 21:31:50.043 [a@127.0.0.1]:[pid=<0.551.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-07 21:31:56.046 [a@127.0.0.1]:[pid=<0.745.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-07 21:31:56.046 [a@127.0.0.1]:[pid=<0.551.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-07 21:32:02.049 [a@127.0.0.1]:[pid=<0.808.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []

[ralphlaude]

It was the same error I had locally. I have nothing to add. Let the fix for #20 and see how it works.

[sleipnir]

@ralphlaude your error message was slightly different from the message reported by @marcellanz test.
What I need to know about you are the versions that are using the protocol and how I can reproduce the error locally, which image used in your tests and etc ...
It would help me a lot.

[ralphlaude]

Sorry :) @sleipnir to be late with the error-log. I hope it can help.

iex --name a@127.0.0.1 -S mix
Erlang/OTP 23 [erts-11.1.8] [source] [64-bit] [smp:8:8] [ds:8:8:10] [async-threads:1] [hipe] [dtrace]

2021-03-16 22:44:29.657 [a@127.0.0.1]:[pid=<0.480.0> ]:[warn]: [libcluster:dev] unable to connect to :"b@127.0.0.1"
2021-03-16 22:44:29.658 [a@127.0.0.1]:[pid=<0.480.0> ]:[warn]: [libcluster:dev] unable to connect to :"c@127.0.0.1"
2021-03-16 22:44:29.658 [a@127.0.0.1]:[pid=<0.489.0> ]:[info]: Starting Horde.RegistryImpl with name MassaProxy.GlobalRegistry
2021-03-16 22:44:29.659 [a@127.0.0.1]:[pid=<0.492.0> ]:[info]: Starting Horde.DynamicSupervisorImpl with name MassaProxy.GlobalSupervisor
2021-03-16 22:44:29.659 [a@127.0.0.1]:[pid=<0.496.0> ]:[info]: Starting Proxy Cluster...
2021-03-16 22:44:29.659 [a@127.0.0.1]:[pid=<0.496.0> ]:[info]: [massa proxy on :"a@127.0.0.1"]: Connecting Horde to :"a@127.0.0.1"
2021-03-16 22:44:29.659 [a@127.0.0.1]:[pid=<0.496.0> ]:[info]: [massa proxy on :"a@127.0.0.1"]: Connecting Horde to :"a@127.0.0.1"
2021-03-16 22:44:29.659 [a@127.0.0.1]:[pid=<0.496.0> ]:[debug]:Starting Supervisors...
2021-03-16 22:44:29.661 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Registry for EventSourced
2021-03-16 22:44:29.662 [a@127.0.0.1]:[pid=<0.500.0> ]:[info]: [MassaProxy on :"a@127.0.0.1"][EntityRegistry]: Initializing...
2021-03-16 22:44:29.662 [a@127.0.0.1]:[pid=<0.498.0> ]:[warn]: CRDT: %{}
2021-03-16 22:44:29.662 [a@127.0.0.1]:[pid=<0.498.0> ]:[warn]: Picked up [] for EventSourced
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Registry for CRDT
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.501.0> ]:[info]: [MassaProxy on :"a@127.0.0.1"][EntityRegistry]: Initializing...
Interactive Elixir (1.11.3) - press Ctrl+C to exit (type h() ENTER for help)
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.498.0> ]:[warn]: CRDT: %{}
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.498.0> ]:[warn]: Picked up [] for CRDT
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Registry for Stateless
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.503.0> ]:[info]: [MassaProxy on :"a@127.0.0.1"][EntityRegistry]: Initializing...
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.498.0> ]:[warn]: CRDT: %{}
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.498.0> ]:[warn]: Picked up [] for Stateless
2021-03-16 22:44:29.663 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
iex(a@127.0.0.1)1> 2021-03-16 22:44:35.665 [a@127.0.0.1]:[pid=<0.504.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-16 22:44:35.666 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-16 22:44:41.669 [a@127.0.0.1]:[pid=<0.568.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-16 22:44:41.669 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-16 22:44:47.672 [a@127.0.0.1]:[pid=<0.631.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-16 22:44:47.672 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-16 22:44:53.674 [a@127.0.0.1]:[pid=<0.693.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-16 22:44:53.675 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-16 22:44:59.677 [a@127.0.0.1]:[pid=<0.755.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-16 22:44:59.677 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080
2021-03-16 22:45:05.679 [a@127.0.0.1]:[pid=<0.817.0> ]:[error]:GenServer Discovery.Worker terminating
** (stop) bad return value: %ArgumentError{message: "argument error"}
Last message: :work
State: []
2021-03-16 22:45:05.680 [a@127.0.0.1]:[pid=<0.376.0> ]:[info]: Starting Elixir.Discovery.Worker on target function address tcp://127.0.0.1:8080

[sleipnir]

Thanks @ralphlaude can you report what the proxy tested against? I'm interested on the User Function side, it was a docker image that I can download, it was a branch of some repository, what version of the protocol is used by this user function, and etc ...?
How can I reproduce your test faithfully to get to the same error?

[ralphlaude]

@sleipnir locally I ran only the following commands:

1. iex --name a@127.0.0.1 -S mix
2. iex --name b@127.0.0.1
3. iex --name c@127.0.0.1

I didn't start a docker image. If you just run all the following commands you can reproduce the errors. I would like to help more.

[sleipnir]

So there is no error, this message is normal because no user role was found. I'm closing this issue.
Thanks @ralphlaude