Skip to content

[MacOS] getting 422 (Unprocessable Entity) when trying to register identity #2937

Description

@cvl

node 0.40.6-pre (testnet2), getting:

POST http://127.0.0.1:4050/identities/0x664bc9375ff52862bae613063d3ca325139be44a/register 422 (Unprocessable Entity)

supervisor logs:
2020-12-15T19:41:00.642 DBG source/logconfig/rollingwriter/rollingwriter.go:76 > Found 0 old log files in log directory, skipping cleanup
2020-12-15T19:41:00.642 INF source/cmd/di.go:192 > Starting Mysterium Node 0.40.6-pre
2020-12-15T19:41:00.643 INF source/core/node/options_directory.go:78 > Directory does not exist, creating a new one: /Users/contra/.mysterium/testnet2/db
2020-12-15T19:41:00.643 DBG source/config/config.go:196 > Returning default value outgoing-firewall:false
2020-12-15T19:41:00.643 DBG source/config/config.go:196 > Returning default value incoming-firewall:false
2020-12-15T19:41:00.643 INF source/firewall/incoming_firewall_noop.go:32 > Rules bootstrap was requested
2020-12-15T19:41:00.702 INF source/core/storage/boltdb/migrator/migrator.go:66 > Running migration session-to-session-history
2020-12-15T19:41:00.738 INF source/core/storage/boltdb/migrator/migrator.go:71 > Saving migration session-to-session-history
2020-12-15T19:41:00.785 INF source/core/storage/boltdb/migrator/migrator.go:66 > Running migration settlements-to-rows
2020-12-15T19:41:00.818 INF source/core/storage/boltdb/migrator/migrator.go:71 > Saving migration settlements-to-rows
2020-12-15T19:41:00.850 DBG source/config/config.go:187 > Returning CLI value usermode:true
2020-12-15T19:41:00.850 INF source/cmd/di.go:643 > Using local DNS: testnet2-location.mysterium.network -> [95.216.204.232]
2020-12-15T19:41:00.850 INF source/cmd/di.go:643 > Using local DNS: feedback.mysterium.network -> [116.203.17.150]
2020-12-15T19:41:00.850 INF source/cmd/di.go:643 > Using local DNS: api.ipify.org -> [54.204.14.42 54.225.153.147 54.235.83.248 54.243.161.145 23.21.109.69 23.21.126.66 50.19.252.36 174.129.214.20]
2020-12-15T19:41:00.850 INF source/cmd/di.go:643 > Using local DNS: testnet2-trust.mysterium.network -> [95.216.204.232]
2020-12-15T19:41:00.851 INF source/cmd/di.go:643 > Using local DNS: testnet2.mysterium.network -> [138.201.174.94]
2020-12-15T19:41:00.851 INF source/cmd/di.go:643 > Using local DNS: testnet-location.mysterium.network -> [82.196.15.9]
2020-12-15T19:41:00.851 INF source/cmd/di.go:643 > Using local DNS: testnet2-quality.mysterium.network -> [116.202.100.246]
2020-12-15T19:41:00.851 INF source/cmd/di.go:643 > Using local DNS: badupnp.benjojo.co.uk -> [104.22.70.70 104.22.71.70 172.67.25.154]
2020-12-15T19:41:00.851 INF source/cmd/di.go:643 > Using local DNS: testnet2-api.mysterium.network -> [78.47.55.197]
2020-12-15T19:41:00.851 INF source/cmd/di.go:643 > Using local DNS: testnet2-broker.mysterium.network -> [95.216.204.232]
2020-12-15T19:41:00.851 INF source/cmd/di.go:643 > Using local DNS: testnet2-transactor.mysterium.network -> [135.181.82.67]
2020-12-15T19:41:00.851 DBG source/communication/nats/connector.go:65 > Connecting to NATS servers: [nats://testnet2-broker.mysterium.network:4222]
2020-12-15T19:41:00.851 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL nats://testnet2-broker.mysterium.network:4222 access
2020-12-15T19:41:00.851 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL nats://testnet2-broker.mysterium.network:4222 access
2020-12-15T19:41:00.853 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL nats://95.216.204.232:4222 access
2020-12-15T19:41:01.011 INF source/cmd/di.go:668 > Using Eth endpoint: wss://goerli.infura.io/ws/v3/c2c7da73fcc84ec5885a7bb0eb3c3637
2020-12-15T19:41:02.090 INF source/identity/registry/registry_contract.go:56 > Using registryAddress 0x15B1281F4e58215b2c3243d864BdF8b9ddDc0DA2 hermesAddress 0xD5d2f5729D4581dfacEBedF46C7014DeFda43585
2020-12-15T19:41:02.090 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:02.299 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL wss://goerli.infura.io/ws/v3/c2c7da73fcc84ec5885a7bb0eb3c3637 access
2020-12-15T19:41:02.300 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL https://testnet2-api.mysterium.network/v1 access
2020-12-15T19:41:02.300 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL https://testnet2-transactor.mysterium.network/api/v1 access
2020-12-15T19:41:02.300 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL https://betanet-hermes.mysterium.network/api/v2 access
2020-12-15T19:41:02.300 INF source/firewall/incoming_firewall_noop.go:53 > Allow URL wss://goerli.infura.io/ws/v3/c2c7da73fcc84ec5885a7bb0eb3c3637 access
2020-12-15T19:41:02.300 INF source/firewall/incoming_firewall_noop.go:53 > Allow URL https://testnet2-api.mysterium.network/v1 access
2020-12-15T19:41:02.300 INF source/firewall/incoming_firewall_noop.go:53 > Allow URL https://testnet2-transactor.mysterium.network/api/v1 access
2020-12-15T19:41:02.300 INF source/firewall/incoming_firewall_noop.go:53 > Allow URL https://betanet-hermes.mysterium.network/api/v2 access
2020-12-15T19:41:02.300 DBG source/cmd/di.go:718 > Using lightweight keystore
2020-12-15T19:41:02.533 INF source/core/discovery/dhtdiscovery/node.go:86 > DHT node started on [/ip4/127.0.0.1/tcp/60973 /ip4/192.168.0.104/tcp/60973] with ID=QmVytpxT6qmFspRNHoGbfjRUpF6GkWVo38Q6UYSXa1dN1C
2020-12-15T19:41:02.534 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL https://testnet-location.mysterium.network/api/v1/location access
2020-12-15T19:41:02.534 INF source/firewall/incoming_firewall_noop.go:53 > Allow URL https://testnet-location.mysterium.network/api/v1/location access
2020-12-15T19:41:02.534 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL https://testnet-location.mysterium.network/api/v1/location access
2020-12-15T19:41:02.534 INF source/firewall/incoming_firewall_noop.go:53 > Allow URL https://testnet-location.mysterium.network/api/v1/location access
2020-12-15T19:41:02.564 DBG source/config/config.go:196 > Returning default value mmn.api-address:https://testnet2.mysterium.network/api/v1
2020-12-15T19:41:02.564 DBG source/cmd/di.go:859 > Experimental NAT punching enabled, creating a pinger
2020-12-15T19:41:02.564 DBG source/config/config.go:196 > Returning default value nat-port-mapping:true
2020-12-15T19:41:02.564 DBG source/cmd/di_desktop.go:59 > Skipping services bootstrap for consumer mode
2020-12-15T19:41:02.564 INF source/firewall/outgoing_firewall_noop.go:57 > Allow URL https://testnet2-quality.mysterium.network/api/v1 access
2020-12-15T19:41:02.564 INF source/firewall/incoming_firewall_noop.go:53 > Allow URL https://testnet2-quality.mysterium.network/api/v1 access
2020-12-15T19:41:02.565 DBG source/cmd/di_desktop.go:195 > Skipping hermes promise settler for consumer mode
2020-12-15T19:41:02.565 DBG source/cmd/di_desktop.go:165 > Skipping provider registrar for consumer mode
2020-12-15T19:41:02.565 INF source/firewall/outgoing_firewall_noop.go:48 > Allow IP 239.255.255.250 access
2020-12-15T19:41:02.565 INF source/feedback/reporter.go:40 > Using feedback API at: https://feedback.mysterium.network
2020-12-15T19:41:02.565 DBG source/config/config.go:196 > Returning default value access-policy.address:https://testnet2-trust.mysterium.network/api/v1/access-policies/
2020-12-15T19:41:02.565 INF source/pilvytis/order_status_tracker.go:102 > Tracking order statuses...
2020-12-15T19:41:02.565 DBG source/config/config.go:196 > Returning default value pprof.enable:false
2020-12-15T19:41:02.565 INF source/tequilapi/http_api_server.go:77 > API started on: 127.0.0.1:4050
2020-12-15T19:41:02.565 DBG source/sleep/sleep_darwin.go:31 > Register for sleep events
2020-12-15T19:41:02.565 DBG source/ui/noop/noop.go:35 > Start: NOOP UI server
2020-12-15T19:41:02.565 DBG source/eventbus/event_bus.go:81 > Published topic="Node" event={Status:Started}
2020-12-15T19:41:02.566 DBG source/core/location/oracle_resolver.go:42 > Detecting with oracle resolver
2020-12-15T19:41:02.566 INF source/cmd/di.go:273 > Mysterium node started!
2020-12-15T19:41:02.566 DBG source/core/ip/cached_resolver.go:59 > Outbound IP cache is empty, fetching IP
2020-12-15T19:41:02.566 DBG source/identity/registry/registry_contract.go:104 > event received {Started}
2020-12-15T19:41:02.573 INF source/identity/registry/registry_contract.go:258 > Starting registry...
2020-12-15T19:41:02.573 DBG source/identity/registry/registry_contract.go:269 > Loading initial state
2020-12-15T19:41:02.566 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:02.580 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:41:02.595 DBG source/tequilapi/endpoints/config.go:143 > Setting user config value: "feedback" = map["url":"https://portals.network/api-porx/api-diagnostics-report.php"]
2020-12-15T19:41:02.595 DBG source/eventbus/event_bus.go:81 > Published topic="config:feedback" event=map[url:https://portals.network/api-porx/api-diagnostics-report.php]
2020-12-15T19:41:02.595 INF source/config/config.go:90 > Saving user configuration
2020-12-15T19:41:02.596 INF source/config/config.go:107 > User configuration written:
{"feedback":{"url":"https://portals.network/api-porx/api-diagnostics-report.php"}}
2020-12-15T19:41:02.807 DBG source/market/mysterium/mysterium_api.go:271 > Total proposals: 9 supported: 9
2020-12-15T19:41:02.807 INF source/core/discovery/repository.go:91 > Returning 9 unique proposals
2020-12-15T19:41:02.854 DBG source/eventbus/event_bus.go:81 > Published topic="identity-created" event=0x664bc9375ff52862bae613063d3ca325139be44a
2020-12-15T19:41:03.146 DBG source/core/location/cache.go:123 > original location detected: LT (cellular)
2020-12-15T19:41:03.257 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:03.278 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:03.383 DBG source/identity/manager.go:148 > Caching unlocked address: 0x664bc9375ff52862bae613063d3ca325139be44a
2020-12-15T19:41:03.383 DBG source/eventbus/event_bus.go:81 > Published topic="identity-unlocked" event={ChainID:5 ID:{Address:0x664bc9375ff52862bae613063d3ca325139be44a}}
2020-12-15T19:41:03.387 DBG source/identity/manager.go:135 > Unlocked identity found in cache, skipping keystore: 0x664bc9375ff52862bae613063d3ca325139be44a
2020-12-15T19:41:03.389 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:03.394 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:03.759 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:03.761 DBG source/config/config.go:196 > Returning default value chain-id:5
2020-12-15T19:41:04.798 DBG source/nat/upnp/discover.go:90 > not a gateway device: map[deviceType:urn:dial-multiscreen-org:device:dialreceiver:1 friendlyName:[TV]Samsung LED48 manufacturer:Samsung Electronics modelName:UE48H6200 modelNo:1.0 server:SHP, UPnP/1.0, Samsung UPnP SDK/1.0]
2020-12-15T19:41:05.101 DBG source/nat/upnp/discover.go:90 > not a gateway device: map[deviceType:urn:schemas-upnp-org:device:MediaRenderer:1 friendlyName:[TV]Samsung LED48 manufacturer:Samsung Electronics modelName:UE48H6200 modelNo:AllShare1.0 server:SHP, UPnP/1.0, Samsung UPnP SDK/1.0]
2020-12-15T19:41:05.287 DBG source/nat/upnp/discover.go:90 > not a gateway device: map[deviceType:urn:samsung.com:device:RemoteControlReceiver:1 friendlyName:[TV]Samsung LED48 manufacturer:Samsung Electronics modelName:UE48H6200 modelNo:1.0 server:SHP, UPnP/1.0, Samsung UPnP SDK/1.0]
2020-12-15T19:41:05.515 DBG source/nat/upnp/discover.go:90 > not a gateway device: map[deviceType:urn:samsung.com:device:MainTVServer2:1 friendlyName:[TV]Samsung LED48 manufacturer:Samsung Electronics modelName:UE48H6200 modelNo:1.0 server:SHP, UPnP/1.0, Samsung UPnP SDK/1.0]
2020-12-15T19:41:05.515 INF source/nat/upnp/discover.go:58 > UPnP gateways detected: 1
2020-12-15T19:41:05.515 INF source/nat/upnp/discover.go:60 > UPnP gateway detected map[deviceType:urn:schemas-upnp-org:device:InternetGatewayDevice:1 friendlyName:TL-WR840N manufacturer:TP-Link modelName:TL-WR840N modelNo:6.0 server:Linux/2.6.36, UPnP/1.0, Portable SDK for UPnP devices/1.6.19]
2020-12-15T19:41:09.623 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:41:09.820 INF source/core/discovery/repository.go:91 > Returning 9 unique proposals
2020-12-15T19:41:19.624 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:41:19.831 INF source/core/discovery/repository.go:91 > Returning 9 unique proposals
2020-12-15T19:41:27.119 ERR source/session/pingpong/consumer_balance_tracker.go:151 > Could not recover Grand Total Promised error="could not request consumer data from hermes: resource not found"
2020-12-15T19:41:27.461 WRN github.com/mysteriumnetwork/payments@v0.0.14-0.20201207082756-32a074b1cc0b/client/retryable_client.go:160 > retry 2 of 3 error="could not get consumers channel: no contract code at given address"
2020-12-15T19:41:28.082 WRN github.com/mysteriumnetwork/payments@v0.0.14-0.20201207082756-32a074b1cc0b/client/retryable_client.go:160 > retry 3 of 3 error="could not get consumers channel: no contract code at given address"
2020-12-15T19:41:28.709 ERR source/session/pingpong/consumer_balance_tracker.go:277 > Could not get consumer channel error="could not get consumers channel: no contract code at given address"
2020-12-15T19:41:28.881 DBG source/eventbus/event_bus.go:81 > Published topic="balance_change" event={Identity:{Address:0x664bc9375ff52862bae613063d3ca325139be44a} Previous:+0 Current:+0}
2020-12-15T19:41:29.096 INF source/session/pingpong/consumer_balance_tracker.go:213 > Subscribed to channel 0xF4796a24dc44bc1f346c4Fb3E796b46978ADa18A balance events
2020-12-15T19:41:29.622 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:41:29.817 INF source/core/discovery/repository.go:91 > Returning 10 unique proposals
2020-12-15T19:41:32.750 INF source/pilvytis/order_status_tracker.go:126 > Pausing order status tracking
2020-12-15T19:41:39.623 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:41:39.861 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:41:49.622 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:41:49.850 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:41:59.623 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:41:59.837 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:42:09.623 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:42:09.841 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:42:19.622 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:42:19.842 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:42:29.622 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:42:29.866 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:42:39.624 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:42:39.864 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:42:49.622 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:42:50.334 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:42:59.623 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:42:59.846 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:43:09.624 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:43:09.831 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:43:19.622 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:43:19.836 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:43:29.623 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:43:29.845 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:43:39.625 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:43:39.836 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:43:49.622 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:43:49.846 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:43:59.623 DBG source/core/discovery/repository.go:61 > Retrieving proposals from 3 repositories
2020-12-15T19:43:59.836 INF source/core/discovery/repository.go:91 > Returning 11 unique proposals
2020-12-15T19:44:08.160 INF source/tequilapi/endpoints/stop.go:44 > Application stop requested
2020-12-15T19:44:08.160 INF source/cmd/node.go:89 > No active connection - proceeding
2020-12-15T19:44:08.161 INF source/cmd/node.go:98 > API stopped
2020-12-15T19:44:08.161 DBG source/ui/noop/noop.go:40 > Stop: NOOP UI server
2020-12-15T19:44:08.161 DBG source/eventbus/event_bus.go:81 > Published topic="Node" event={Status:Stopped}
2020-12-15T19:44:08.161 INF source/cmd/node.go:101 > Web UI server stopped
2020-12-15T19:44:08.161 ERR source/cmd/commands/daemon/command.go:68 > Terminating application due to error error="accept tcp 127.0.0.1:4050: use of closed network connection"
2020-12-15T19:44:08.161 DBG source/identity/registry/registry_contract.go:104 > event received {Stopped}
2020-12-15T19:44:08.161 INF source/cmd/node.go:104 > NAT pinger stopped
2020-12-15T19:44:08.161 INF source/cmd/node.go:89 > No active connection - proceeding
2020-12-15T19:44:08.161 INF source/identity/registry/registry_contract.go:252 > Stopping registry...
2020-12-15T19:44:08.161 DBG source/sleep/sleep_darwin.go:45 > Unregister sleep events
2020-12-15T19:44:08.161 INF source/cmd/node.go:98 > API stopped
2020-12-15T19:44:08.161 DBG source/ui/noop/noop.go:40 > Stop: NOOP UI server
2020-12-15T19:44:08.161 INF source/cmd/node.go:101 > Web UI server stopped
2020-12-15T19:44:08.161 INF source/cmd/node.go:107 > Sleep notifier stopped
2020-12-15T19:44:08.162 INF source/communication/nats/receiver.go:99 > Unsubscribed from *.proposal-ping
2020-12-15T19:44:08.161 INF source/cmd/node.go:104 > NAT pinger stopped
2020-12-15T19:44:08.162 INF source/communication/nats/receiver.go:99 > Unsubscribed from *.proposal-unregister
2020-12-15T19:44:08.162 INF source/cmd/node.go:107 > Sleep notifier stopped
2020-12-15T19:44:08.162 INF source/communication/nats/receiver.go:99 > Unsubscribed from *.proposal-register
2020-12-15T19:44:08.162 INF source/firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://testnet2-broker.mysterium.network:4222 removed
2020-12-15T19:44:08.162 INF source/firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://testnet2-broker.mysterium.network:4222 removed
2020-12-15T19:44:08.163 INF source/firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://95.216.204.232:4222 removed
2020-12-15T19:44:08.163 WRN source/communication/nats/connection_wrap.go:95 > NATS: disconnected
2020-12-15T19:44:08.163 WRN source/communication/nats/connection_wrap.go:94 > NATS: connection closed
2020-12-15T19:44:08.163 INF source/firewall/incoming_firewall_noop.go:38 > Rules reset was requested
2020-12-15T19:44:08.163 INF source/firewall/outgoing_firewall_noop.go:35 > Rules reset was requested
2020-12-15T19:44:08.162 INF source/firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://testnet2-broker.mysterium.network:4222 removed
2020-12-15T19:44:08.163 INF source/firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://testnet2-broker.mysterium.network:4222 removed
2020-12-15T19:44:08.163 INF source/utils/stopper.go:58 > Good bye
2020-12-15T19:44:08.163 INF source/firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://95.216.204.232:4222 removed
2020-12-15T19:44:08.163 INF source/firewall/incoming_firewall_noop.go:38 > Rules reset was requested
2020-12-15T19:44:08.164 INF source/firewall/outgoing_firewall_noop.go:35 > Rules reset was requested
2020-12-15T19:44:08.164 ERR source/cmd/mysterium_node/mysterium_node.go:68 > Failed to execute command: error="accept tcp 127.0.0.1:4050: use of closed network connection"

Metadata

Metadata

Assignees

No one assigned

    Labels

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions