search

mysteriumnetwork / node

Mysterium Network Node - official implementation of distributed VPN network (dVPN) protocol
https://mysterium.network
GNU General Public License v3.0
1.1k stars 313 forks source link

Nodes not comming up "Monitoring failed" #5956

Open K0KKeL opened 8 months ago

K0KKeL commented 8 months ago

Describe the bug Multiple of my Nodes are not operational

Expected behavior That the node would have become operational after a reinstall.

Environment (please complete the following information):

Additional context

IP forward is enabled.

iptables basepath detected: /sbin 2024-01-22T21:33:45.944 INF ../../config/urfavecli/clicontext/user.go:76 > Config file does not exist, attempting to create: /var/lib/mysterium-node/config-mainnet.toml 2024-01-22T21:33:45.946 DBG ../../config/config.go:81 > Loading user configuration: /var/lib/mysterium-node/config-mainnet.toml 2024-01-22T21:33:45.947 INF ../../config/config.go:93 > User configuration loaded: {} 2024-01-22T21:33:45.962 DBG ../../config/config.go:216 > Returning CLI value data-dir:/var/lib/mysterium-node 2024-01-22T21:33:45.963 DBG ../../config/config.go:216 > Returning CLI value data-dir:/var/lib/mysterium-node 2024-01-22T21:33:45.963 DBG ../../config/config.go:216 > Returning CLI value script-dir:/etc/mysterium-node 2024-01-22T21:33:45.963 DBG ../../config/config.go:216 > Returning CLI value runtime-dir:/var/run/mysterium-node 2024-01-22T21:33:45.964 INF ../../logconfig/config.go:74 > Log level: debug 2024-01-22T21:33:45.964 INF ../../logconfig/config.go:76 > Log file path: /root/.mysterium/logs/mysterium-node 2024-01-22T21:33:45.966 DBG ../../logconfig/rollingwriter/rollingwriter.go:80 > Found 0 old log files in log directory, skipping cleanup 2024-01-22T21:33:45.966 INF ../../cmd/di.go:226 > Starting Mysterium Node 1.29.3 2024-01-22T21:33:45.967 INF ../../core/node/options_directory.go:104 > Directory does not exist, creating a new one: /var/lib/mysterium-node/mainnet/db 2024-01-22T21:33:45.968 INF ../../firewall/incoming_firewall_noop.go:32 > Rules bootstrap was requested 2024-01-22T21:33:45.974 INF ../../core/storage/boltdb/migrator/migrator.go:68 > Running migration session-to-session-history 2024-01-22T21:33:45.976 INF ../../core/storage/boltdb/migrator/migrator.go:78 > Saving migration session-to-session-history 2024-01-22T21:33:45.978 INF ../../core/storage/boltdb/migrator/migrator.go:68 > Running migration settlements-to-rows 2024-01-22T21:33:45.980 INF ../../core/storage/boltdb/migrator/migrator.go:78 > Saving migration settlements-to-rows 2024-01-22T21:33:45.982 INF ../../core/storage/boltdb/migrator/migrator.go:68 > Running migration registration-status-to-new 2024-01-22T21:33:45.983 INF ../../core/storage/boltdb/migrator/migrator.go:78 > Saving migration registration-status-to-new 2024-01-22T21:33:45.985 INF ../../core/storage/boltdb/migrator/migrator.go:68 > Running migration registration-status-to-new-mainnet 2024-01-22T21:33:45.987 INF ../../core/storage/boltdb/migrator/migrator.go:78 > Saving migration registration-status-to-new-mainnet 2024-01-22T21:33:45.989 INF ../../cmd/di.go:702 > Using local DNS: discovery.mysterium.network -> [51.158.204.30 51.158.204.75 51.158.204.9 51.158.204.23] 2024-01-22T21:33:45.990 INF ../../cmd/di.go:702 > Using local DNS: trust.mysterium.network -> [51.158.204.30 51.158.204.75 51.158.204.9 51.158.204.23] 2024-01-22T21:33:45.990 INF ../../cmd/di.go:702 > Using local DNS: transactor.mysterium.network -> [51.158.204.30 51.158.204.75 51.158.204.9 51.158.204.23] 2024-01-22T21:33:45.991 INF ../../cmd/di.go:702 > Using local DNS: pilvytis.mysterium.network -> [51.158.204.30 51.158.204.75 51.158.204.9 51.158.204.23] 2024-01-22T21:33:45.992 INF ../../cmd/di.go:702 > Using local DNS: location.mysterium.network -> [51.158.129.204] 2024-01-22T21:33:45.992 INF ../../cmd/di.go:702 > Using local DNS: quality.mysterium.network -> [51.158.129.204] 2024-01-22T21:33:45.993 INF ../../cmd/di.go:702 > 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] 2024-01-22T21:33:45.994 INF ../../cmd/di.go:702 > Using local DNS: badupnp.benjojo.co.uk -> [104.22.70.70 104.22.71.70 172.67.25.154] 2024-01-22T21:33:45.995 INF ../../cmd/di.go:702 > Using local DNS: observer.mysterium.network -> [51.158.204.30 51.158.204.75 51.158.204.9 51.158.204.23] 2024-01-22T21:33:45.995 INF ../../cmd/di.go:702 > Using local DNS: feedback.mysterium.network -> [116.203.17.150] 2024-01-22T21:33:45.996 INF ../../cmd/di.go:702 > Using local DNS: broker.mysterium.network -> [51.158.204.30 51.158.204.75 51.158.204.9 51.158.204.23] 2024-01-22T21:33:45.997 INF ../../cmd/di.go:702 > Using local DNS: affiliator.mysterium.network -> [51.158.204.30 51.158.204.75 51.158.204.9 51.158.204.23] 2024-01-22T21:33:45.999 DBG ../../communication/nats/connector.go:79 > Connecting to NATS servers: [nats://broker.mysterium.network:4222] 2024-01-22T21:33:45.999 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL nats://broker.mysterium.network:4222 access 2024-01-22T21:33:46.000 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL nats://broker.mysterium.network:4222 access 2024-01-22T21:33:46.000 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL nats://51.158.204.30:4222 access 2024-01-22T21:33:46.001 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL nats://51.158.204.75:4222 access 2024-01-22T21:33:46.001 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL nats://51.158.204.9:4222 access 2024-01-22T21:33:46.001 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL nats://51.158.204.23:4222 access 2024-01-22T21:33:47.784 INF ../../cmd/di.go:731 > Using L1 Eth endpoints: [https://ethereum1.mysterium.network/ https://cloudflare-eth.com/] 2024-01-22T21:33:47.785 INF ../../cmd/di.go:732 > Using L2 Eth endpoints: [https://polygon1.mysterium.network/ https://polygon-rpc.com/] 2024-01-22T21:33:48.291 INF ../../cmd/di.go:521 > Node chain id 137 2024-01-22T21:33:48.618 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://discovery.mysterium.network/api/v4 access 2024-01-22T21:33:48.618 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://transactor.mysterium.network/api/v1 access 2024-01-22T21:33:48.619 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://affiliator.mysterium.network/api/v1 access 2024-01-22T21:33:48.619 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://hermes3.mysterium.network/api/v2 access 2024-01-22T21:33:48.620 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://pilvytis.mysterium.network access 2024-01-22T21:33:48.620 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://ethereum1.mysterium.network/ access 2024-01-22T21:33:48.621 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://cloudflare-eth.com/ access 2024-01-22T21:33:48.621 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://polygon1.mysterium.network/ access 2024-01-22T21:33:48.621 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://polygon-rpc.com/ access 2024-01-22T21:33:48.622 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://discovery.mysterium.network/api/v4 access 2024-01-22T21:33:48.622 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://transactor.mysterium.network/api/v1 access 2024-01-22T21:33:48.623 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://affiliator.mysterium.network/api/v1 access 2024-01-22T21:33:48.623 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://hermes3.mysterium.network/api/v2 access 2024-01-22T21:33:48.624 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://pilvytis.mysterium.network access 2024-01-22T21:33:48.624 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://ethereum1.mysterium.network/ access 2024-01-22T21:33:48.625 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://cloudflare-eth.com/ access 2024-01-22T21:33:48.625 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://polygon1.mysterium.network/ access 2024-01-22T21:33:48.625 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://polygon-rpc.com/ access 2024-01-22T21:33:48.626 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://location.mysterium.network/api/v1/location access 2024-01-22T21:33:48.626 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://location.mysterium.network/api/v1/location access 2024-01-22T21:33:48.627 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://location.mysterium.network/api/v1/location access 2024-01-22T21:33:48.627 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://location.mysterium.network/api/v1/location access 2024-01-22T21:33:48.628 DBG ../../cmd/di.go:858 > Using lightweight keystore 2024-01-22T21:33:48.630 DBG ../../config/config.go:216 > Returning CLI value data-dir:/var/lib/mysterium-node 2024-01-22T21:33:48.631 DBG ../../core/ip/cached_resolver.go:59 > Outbound IP cache is empty, fetching IP 2024-01-22T21:33:48.632 WRN ../../ui/server.go:94 > could not read node ui version config, falling back to bundled version 2024-01-22T21:33:48.633 DBG ../../config/config.go:216 > Returning CLI value local-service-discovery:false 2024-01-22T21:33:48.661 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables --new MYST --table nat" output:

2024-01-22T21:33:48.667 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -A MYST --destination 10.0.0.0/8 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-22T21:33:48.673 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -A MYST --destination 172.16.0.0/12 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-22T21:33:48.678 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -A MYST --destination 192.168.0.0/16 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-22T21:33:48.684 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -A MYST --destination 127.0.0.0/8 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-22T21:33:48.687 INF ../../nat/service_ipforward.go:46 > IP forwarding already enabled 2024-01-22T21:33:48.688 INF ../../firewall/outgoing_firewall_noop.go:57 > Allow URL https://quality.mysterium.network/api/v3 access 2024-01-22T21:33:48.688 INF ../../firewall/incoming_firewall_noop.go:53 > Allow URL https://quality.mysterium.network/api/v3 access 2024-01-22T21:33:48.693 INF ../../feedback/reporter.go:48 > Using feedback API at: https://feedback.mysterium.network 2024-01-22T21:33:48.696 INF ../../tequilapi/http_api_server.go:135 > API started on: 127.0.0.1:4050 2024-01-22T21:33:48.696 DBG ../../eventbus/event_bus.go:101 > Published topic="Node" event={Status:Started} 2024-01-22T21:33:48.696 DBG ../../core/ip/cached_resolver.go:55 > Found cached outbound IP 2024-01-22T21:33:48.697 DBG ../../core/ip/cached_resolver.go:79 > Public IP cache is empty, fetching IP 2024-01-22T21:33:48.697 INF ../../firewall/outgoing_firewall_noop.go:48 > Allow IP access 2024-01-22T21:33:48.698 DBG ../../sleep/sleep_noop.go:28 > Register for noop sleep events 2024-01-22T21:33:48.699 INF ../../ui/discovery/discovery.go:66 > LAN discovery disabled. Starting noop local service discovery. 2024-01-22T21:33:48.699 INF ../../ui/server.go:158 > UI starting on: 172.17.0.3:4449 2024-01-22T21:33:48.700 INF ../../ui/server.go:158 > UI starting on: 127.0.0.1:4449 2024-01-22T21:33:48.700 INF ../../identity/registry/registry_contract.go:321 > Starting registry... 2024-01-22T21:33:48.701 DBG ../../identity/registry/registry_contract.go:329 > Loading initial state 2024-01-22T21:33:48.701 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:33:48.702 DBG ../../core/ip/cached_resolver.go:55 > Found cached outbound IP 2024-01-22T21:33:48.702 INF ../../session/pingpong/hermes_promise_settler.go:384 > Listening for settlement events 2024-01-22T21:33:49.024 DBG ../../core/location/cache.go:131 > original location detected: US (hosting) 2024-01-22T21:33:49.036 INF ../../cmd/di.go:313 > Mysterium node started! 2024-01-22T21:33:49.040 DBG ../../identity/selector/handler.go:60 > Creating new identity 2024-01-22T21:33:49.041 DBG ../../identity/selector/handler.go:114 > Attempting to use new identity 2024-01-22T21:33:49.063 DBG ../../tequilapi/endpoints/terms.go:89 > Setting user config value: "terms.consumer-agreed" = %!q(bool=true) 2024-01-22T21:33:49.064 DBG ../../eventbus/event_bus.go:101 > Published topic="config:terms.consumer-agreed" event=true 2024-01-22T21:33:49.064 DBG ../../tequilapi/endpoints/terms.go:89 > Setting user config value: "terms.provider-agreed" = %!q(bool=true) 2024-01-22T21:33:49.064 DBG ../../eventbus/event_bus.go:101 > Published topic="config:terms.provider-agreed" event=true 2024-01-22T21:33:49.065 DBG ../../tequilapi/endpoints/terms.go:89 > Setting user config value: "terms.version" = "0.0.53" 2024-01-22T21:33:49.065 DBG ../../eventbus/event_bus.go:101 > Published topic="config:terms.version" event=0.0.53 2024-01-22T21:33:49.066 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:33:49.067 INF ../../config/config.go:118 > User configuration written: {"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:33:49.322 INF ../../session/pingpong/pricing.go:223 > pricing info loaded. expires @ 2024-01-22 21:38:38.370191715 +0000 UTC 2024-01-22T21:33:49.418 DBG ../../eventbus/event_bus.go:101 > Published topic="identity-created" event=0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:33:49.567 DBG ../../identity/manager.go:166 > Caching unlocked address: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:33:49.570 DBG ../../eventbus/event_bus.go:101 > Published topic="identity-unlocked" event={ChainID:137 ID:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9}} 2024-01-22T21:33:49.570 DBG ../../eventbus/event_bus.go:101 > Published topic="resident-country" event={ID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Country:US} 2024-01-22T21:33:49.575 INF ../../cmd/commands/service/command.go:125 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:33:49.576 DBG ../../eventbus/event_bus.go:101 > Published topic="config:node.version" event=1146206456 2024-01-22T21:33:49.577 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:33:49.577 INF ../../config/config.go:118 > User configuration written: {"node":{"version":"1146206456"},"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:33:49.580 INF ../../tequilapi/endpoints/service.go:183 > Service start options: {ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:dvpn AccessPolicies:0xc000f50f48 Options:{Subnet:{IP:10.182.0.0 Mask:ffff0000}}} 2024-01-22T21:33:49.581 DBG ../../core/service/manager.go:133 > Starting service options={"subnet":"10.182.0.0/16"} policyIDs=["mysterium"] providerID=0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 serviceType=dvpn 2024-01-22T21:33:49.582 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:33:49.582 INF ../../tequilapi/endpoints/service.go:183 > Service start options: {ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:scraping AccessPolicies:0xc000f51650 Options:{Subnet:{IP:10.182.0.0 Mask:ffff0000}}} 2024-01-22T21:33:49.583 DBG ../../core/service/manager.go:133 > Starting service options={"subnet":"10.182.0.0/16"} policyIDs=["mysterium"] providerID=0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 serviceType=scraping 2024-01-22T21:33:49.585 INF ../../tequilapi/endpoints/service.go:183 > Service start options: {ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:data_transfer AccessPolicies:0xc000f51de8 Options:{Subnet:{IP:10.182.0.0 Mask:ffff0000}}} 2024-01-22T21:33:49.585 DBG ../../core/service/manager.go:133 > Starting service options={"subnet":"10.182.0.0/16"} policyIDs=["mysterium"] providerID=0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 serviceType=data_transfer 2024-01-22T21:33:49.586 DBG ../../session/pingpong/hermes_promise_handler.go:231 > hermes promise handler started 2024-01-22T21:33:49.886 DBG ../../eventbus/event_bus.go:101 > Published topic="location-update-event" event={IP: ASN:36352 ISP:RackNerd LLC Continent:NA Country:US Region:California City:Rancho Cucamonga IPType:hosting} 2024-01-22T21:33:49.887 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:33:49.936 DBG ../../session/pingpong/consumer_balance_tracker.go:646 > No previous invoice grand total, assuming zero 2024-01-22T21:33:49.937 DBG ../../session/pingpong/consumer_balance_tracker.go:666 > Loaded hermes state: already promised: 0 2024-01-22T21:33:49.938 DBG ../../eventbus/event_bus.go:101 > Published topic="consumer_grand_total_change" event={Current:+0 ChainID:137 HermesID:0x80Ed28d84792d8b153bf2F25F0C4B7a1381dE4ab ConsumerID:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9}} 2024-01-22T21:33:50.194 DBG ../../eventbus/event_bus.go:101 > Published topic="location-update-event" event={IP: ASN:36352 ISP:RackNerd LLC Continent:NA Country:US Region:California City:Rancho Cucamonga IPType:hosting} 2024-01-22T21:33:50.195 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:33:50.195 INF ../../core/discovery/discovery.go:92 > Starting discovery... 2024-01-22T21:33:50.198 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:2f4ae4ae-71da-4ffe-8dd5-33a99719b897 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:dvpn Status:Running} 2024-01-22T21:33:50.198 INF ../../services/wireguard/service/service.go:247 > Wireguard: starting 2024-01-22T21:33:50.198 DBG ../../core/ip/cached_resolver.go:55 > Found cached outbound IP 2024-01-22T21:33:50.199 INF ../../dns/proxy.go:67 > Starting DNS proxy on: :11253 2024-01-22T21:33:50.200 INF ../../services/wireguard/service/service.go:264 > Wireguard: started 2024-01-22T21:33:50.201 DBG ../../mmn/mmn.go:100 > Identity unlocked, registration to MMN disabled because the API key missing in config. 2024-01-22T21:33:50.203 DBG ../../tequilapi/endpoints/config.go:165 > Setting user config value: "active-services" = "dvpn" 2024-01-22T21:33:50.203 DBG ../../eventbus/event_bus.go:101 > Published topic="config:active-services" event=dvpn 2024-01-22T21:33:50.203 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:33:50.204 INF ../../config/config.go:118 > User configuration written: {"active-services":"dvpn","node":{"version":"1146206456"},"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:33:50.285 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:33:50.506 DBG ../../eventbus/event_bus.go:101 > Published topic="location-update-event" event={IP: ASN:36352 ISP:RackNerd LLC Continent:NA Country:US Region:California City:Rancho Cucamonga IPType:hosting} 2024-01-22T21:33:50.506 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:33:50.529 INF ../../core/discovery/discovery.go:241 > Identity 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 not registered, delaying proposal registration until identity is registered 2024-01-22T21:33:50.530 INF ../../core/discovery/discovery.go:180 > Waiting for registration success event 2024-01-22T21:33:50.641 INF ../../session/pingpong/hermes_promise_settler.go:238 > Provider {0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} not registered, skipping 2024-01-22T21:33:50.798 INF ../../nat/upnp/discover.go:58 > UPnP gateways detected: 0 2024-01-22T21:33:50.824 DBG ../../eventbus/event_bus.go:101 > Published topic="location-update-event" event={IP: ASN:36352 ISP:RackNerd LLC Continent:NA Country:US Region:California City:Rancho Cucamonga IPType:hosting} 2024-01-22T21:33:50.825 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:33:50.870 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:33:51.117 DBG ../../eventbus/event_bus.go:101 > Published topic="location-update-event" event={IP: ASN:36352 ISP:RackNerd LLC Continent:NA Country:US Region:California City:Rancho Cucamonga IPType:hosting} 2024-01-22T21:33:51.118 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:33:51.118 INF ../../core/discovery/discovery.go:92 > Starting discovery... 2024-01-22T21:33:51.120 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:77beec94-33fd-49c0-9a43-1a4c8bddd7d3 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:data_transfer Status:Running} 2024-01-22T21:33:51.121 INF ../../services/wireguard/service/service.go:247 > Wireguard: starting 2024-01-22T21:33:51.121 DBG ../../core/ip/cached_resolver.go:55 > Found cached outbound IP 2024-01-22T21:33:51.121 INF ../../services/wireguard/service/service.go:264 > Wireguard: started 2024-01-22T21:33:51.122 DBG ../../mmn/mmn.go:100 > Identity unlocked, registration to MMN disabled because the API key missing in config. 2024-01-22T21:33:51.123 DBG ../../tequilapi/endpoints/config.go:165 > Setting user config value: "active-services" = "dvpn,data_transfer" 2024-01-22T21:33:51.123 DBG ../../eventbus/event_bus.go:101 > Published topic="config:active-services" event=dvpn,data_transfer 2024-01-22T21:33:51.123 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:33:51.124 INF ../../config/config.go:118 > User configuration written: {"active-services":"dvpn,data_transfer","node":{"version":"1146206456"},"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:33:51.431 DBG ../../eventbus/event_bus.go:101 > Published topic="location-update-event" event={IP: ASN:36352 ISP:RackNerd LLC Continent:NA Country:US Region:California City:Rancho Cucamonga IPType:hosting} 2024-01-22T21:33:51.432 INF ../../core/discovery/discovery.go:92 > Starting discovery... 2024-01-22T21:33:51.434 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:14409a9e-82a9-41e0-b4b6-95f756e96753 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:scraping Status:Running} 2024-01-22T21:33:51.434 INF ../../services/wireguard/service/service.go:247 > Wireguard: starting 2024-01-22T21:33:51.434 DBG ../../core/ip/cached_resolver.go:55 > Found cached outbound IP 2024-01-22T21:33:51.435 INF ../../services/wireguard/service/service.go:264 > Wireguard: started 2024-01-22T21:33:51.435 DBG ../../mmn/mmn.go:100 > Identity unlocked, registration to MMN disabled because the API key missing in config. 2024-01-22T21:33:51.436 DBG ../../tequilapi/endpoints/config.go:165 > Setting user config value: "active-services" = "dvpn,data_transfer,scraping" 2024-01-22T21:33:51.437 DBG ../../eventbus/event_bus.go:101 > Published topic="config:active-services" event=dvpn,data_transfer,scraping 2024-01-22T21:33:51.437 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:33:51.438 INF ../../config/config.go:118 > User configuration written: {"active-services":"dvpn,data_transfer,scraping","node":{"version":"1146206456"},"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:33:51.673 INF ../../core/discovery/discovery.go:241 > Identity 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 not registered, delaying proposal registration until identity is registered 2024-01-22T21:33:51.674 INF ../../core/discovery/discovery.go:180 > Waiting for registration success event 2024-01-22T21:33:51.675 INF ../../session/pingpong/hermes_promise_settler.go:238 > Provider {0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} not registered, skipping 2024-01-22T21:33:51.677 INF ../../core/discovery/discovery.go:241 > Identity 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 not registered, delaying proposal registration until identity is registered 2024-01-22T21:33:51.678 INF ../../core/discovery/discovery.go:180 > Waiting for registration success event 2024-01-22T21:33:51.789 INF ../../session/pingpong/hermes_promise_settler.go:238 > Provider {0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} not registered, skipping 2024-01-22T21:34:57.808 INF ../../core/auth/credentials.go:90 > CredentialsManager not found, initializing to default error="password or password file doesn't exist" 2024-01-22T21:34:58.264 DBG ../../config/config.go:221 > Returning user config value terms.provider-agreed:true 2024-01-22T21:34:58.265 DBG ../../config/config.go:221 > Returning user config value terms.consumer-agreed:true 2024-01-22T21:34:58.266 DBG ../../config/config.go:221 > Returning user config value terms.version:0.0.53 2024-01-22T21:34:58.395 DBG ../../identity/selector/handler.go:98 > Attempting to use last identity 2024-01-22T21:34:58.396 DBG ../../identity/selector/handler.go:103 > Found identity in cache: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:34:58.396 DBG ../../identity/manager.go:153 > Unlocked identity found in cache, skipping keystore: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:34:58.397 DBG ../../identity/selector/handler.go:108 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:34:59.310 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:35:00.485 DBG ../../eventbus/event_bus.go:101 > Published topic="NAT-type-detected" event=prcone 2024-01-22T21:35:11.658 DBG ../../config/config.go:221 > Returning user config value terms.provider-agreed:true 2024-01-22T21:35:11.660 DBG ../../config/config.go:221 > Returning user config value terms.consumer-agreed:true 2024-01-22T21:35:11.660 DBG ../../config/config.go:221 > Returning user config value terms.version:0.0.53 2024-01-22T21:35:11.792 DBG ../../identity/selector/handler.go:98 > Attempting to use last identity 2024-01-22T21:35:11.793 DBG ../../identity/selector/handler.go:103 > Found identity in cache: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:35:11.793 DBG ../../identity/manager.go:153 > Unlocked identity found in cache, skipping keystore: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:35:11.794 DBG ../../identity/selector/handler.go:108 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:35:13.900 DBG ../../eventbus/event_bus.go:101 > Published topic="NAT-type-detected" event=prcone 2024-01-22T21:35:47.783 DBG ../../tequilapi/endpoints/terms.go:89 > Setting user config value: "terms.provider-agreed" = %!q(bool=true) 2024-01-22T21:35:47.784 DBG ../../eventbus/event_bus.go:101 > Published topic="config:terms.provider-agreed" event=true 2024-01-22T21:35:47.785 DBG ../../tequilapi/endpoints/terms.go:89 > Setting user config value: "terms.version" = "0.0.53" 2024-01-22T21:35:47.785 DBG ../../eventbus/event_bus.go:101 > Published topic="config:terms.version" event=0.0.53 2024-01-22T21:35:47.786 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:35:47.787 INF ../../config/config.go:118 > User configuration written: {"active-services":"dvpn,data_transfer,scraping","node":{"version":"1146206456"},"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:35:47.933 DBG ../../eventbus/event_bus.go:101 > Published topic="config:mmn.api-key" event=524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2 2024-01-22T21:35:47.934 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:35:47.935 INF ../../config/config.go:118 > User configuration written: {"active-services":"dvpn,data_transfer,scraping","mmn":{"api-key":"524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2"},"node":{"version":"1146206456"},"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:35:47.936 DBG ../../config/config.go:221 > Returning user config value mmn.api-key:524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2 2024-01-22T21:35:47.941 DBG ../../mmn/client.go:67 > Registering node to MMN: {LocalIP:172.17.0.3 Identity:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 APIKey:524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2 VendorID: OS:Alpine Linux v3.14(docker) Arch:linux(docker)/amd64 NodeVersion:1.29.3 RedirectURL:} 2024-01-22T21:35:48.589 INF ../../core/auth/auth.go:57 > "myst" user password changed successfully 2024-01-22T21:35:48.994 DBG ../../core/location/oracle_resolver.go:67 > Detecting with oracle resolver 2024-01-22T21:35:49.303 DBG ../../eventbus/event_bus.go:101 > Published topic="location-update-event" event={IP: ASN:36352 ISP:RackNerd LLC Continent:NA Country:US Region:California City:Rancho Cucamonga IPType:hosting} 2024-01-22T21:36:00.434 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:37:05.865 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:38:11.021 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:39:16.162 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:40:18.375 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:41:23.752 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:42:28.937 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:43:30.477 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:44:36.302 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T21:44:36.415 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-22T21:44:54.510 DBG ../../eventbus/event_bus.go:101 > Published topic="transactor_identity_registration" event={RegistryAddress:0x87F0F4b7e0FAb14A565C87BAbbA6c40c92281b51 HermesID:0x80Ed28d84792d8b153bf2F25F0C4B7a1381dE4ab Stake:+0 Fee:+1686893538950200000 Beneficiary:0xBc05B248016473b7ef0D18e4c9FCE39449A9ab5a Signature:0x429b09d5b0ee4900f6aad9d698f94908df1845c09f2d51374383d0d0f311b2e33bb4c2fab5096215359005e5deddd5e18b8cc4be1114083ae01f197aef23a98b1b Identity:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 ChainID:137} 2024-01-22T21:44:54.513 DBG ../../eventbus/event_bus.go:101 > Published topic="registration_event_topic" event={ID:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Status:InProgress ChainID:137} 2024-01-22T21:44:54.513 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:44:54.514 INF ../../core/discovery/discovery.go:175 > Received status InProgress ignoring 2024-01-22T21:44:54.514 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:44:54.514 INF ../../core/discovery/discovery.go:175 > Received status InProgress ignoring 2024-01-22T21:44:54.515 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T21:44:54.515 INF ../../core/discovery/discovery.go:175 > Received status InProgress ignoring 2024-01-22T21:44:54.516 DBG ../../session/pingpong/hermes_promise_settler.go:318 > Ignoring event InProgress for provider {"0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9"} 2024-01-22T21:44:57.469 INF ../../tequilapi/endpoints/service.go:183 > Service start options: {ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:wireguard AccessPolicies:0xc000b54a20 Options:{Subnet:{IP:10.182.0.0 Mask:ffff0000}}} 2024-01-22T21:44:57.470 DBG ../../core/service/manager.go:133 > Starting service options={"subnet":"10.182.0.0/16"} policyIDs=[] providerID=0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 serviceType=wireguard 2024-01-22T21:44:57.470 INF ../../core/discovery/discovery.go:92 > Starting discovery... 2024-01-22T21:44:57.478 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:cbc3a70f-8750-40e4-904b-92ea0ecde021 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:wireguard Status:Running} 2024-01-22T21:44:57.479 INF ../../services/wireguard/service/service.go:247 > Wireguard: starting 2024-01-22T21:44:57.480 DBG ../../core/ip/cached_resolver.go:59 > Outbound IP cache is empty, fetching IP 2024-01-22T21:44:57.480 INF ../../services/wireguard/service/service.go:264 > Wireguard: started 2024-01-22T21:44:57.482 DBG ../../config/config.go:221 > Returning user config value mmn.api-key:524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2 2024-01-22T21:44:57.482 DBG ../../config/config.go:221 > Returning user config value mmn.api-key:524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2 2024-01-22T21:44:57.483 DBG ../../mmn/client.go:67 > Registering node to MMN: {LocalIP:172.17.0.3 Identity:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 APIKey:524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2 VendorID: OS:Alpine Linux v3.14(docker) Arch:linux(docker)/amd64 NodeVersion:1.29.3 RedirectURL:} 2024-01-22T21:44:57.910 INF ../../session/pingpong/pricing.go:223 > pricing info loaded. expires @ 2024-01-22 21:48:38.560484625 +0000 UTC 2024-01-22T21:44:57.912 DBG ../../tequilapi/endpoints/config.go:165 > Setting user config value: "active-services" = "data_transfer,scraping,wireguard,dvpn" 2024-01-22T21:44:57.912 DBG ../../eventbus/event_bus.go:101 > Published topic="config:active-services" event=data_transfer,scraping,wireguard,dvpn 2024-01-22T21:44:57.913 INF ../../config/config.go:99 > Saving user configuration 2024-01-22T21:44:57.913 INF ../../config/config.go:118 > User configuration written: {"active-services":"data_transfer,scraping,wireguard,dvpn","mmn":{"api-key":"524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2"},"node":{"version":"1146206456"},"terms":{"consumer-agreed":true,"provider-agreed":true,"version":"0.0.53"}} 2024-01-22T21:44:58.072 INF ../../core/discovery/discovery.go:241 > Identity 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 not registered, delaying proposal registration until identity is registered 2024-01-22T21:44:58.073 INF ../../core/discovery/discovery.go:180 > Waiting for registration success event 2024-01-22T21:44:58.074 INF ../../session/pingpong/hermes_promise_settler.go:238 > Provider {0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} not registered, skipping 2024-01-22T21:45:34.143 DBG ../../config/config.go:221 > Returning user config value mmn.api-key:524usO4QSNM9sTvEzMdQZ9ON0zOeREtaPacBaEw2 2024-01-22T22:00:11.118 ERR ../../identity/registry/registry_contract.go:266 > registration reported as failed by transactor, will check in bc just in case 2024-01-22T22:00:11.542 DBG ../../eventbus/event_bus.go:101 > Published topic="registration_event_topic" event={ID:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Status:RegistrationError ChainID:137} 2024-01-22T22:00:11.543 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T22:00:11.544 INF ../../core/discovery/discovery.go:172 > Cancelled identity registration 2024-01-22T22:00:11.544 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T22:00:11.544 INF ../../core/discovery/discovery.go:172 > Cancelled identity registration 2024-01-22T22:00:11.545 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T22:00:11.545 INF ../../core/discovery/discovery.go:172 > Cancelled identity registration 2024-01-22T22:00:11.545 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-22T22:00:11.546 INF ../../core/discovery/discovery.go:172 > Cancelled identity registration 2024-01-22T22:00:11.546 DBG ../../session/pingpong/hermes_promise_settler.go:318 > Ignoring event RegistrationError for provider {"0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9"} 2024-01-22T22:15:19.294 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-22T22:15:27.783 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-22T22:33:51.995 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T22:33:52.109 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-22T23:15:19.989 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-22T23:15:22.889 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-22T23:33:53.068 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-22T23:33:53.193 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T00:15:18.937 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T00:15:20.933 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T00:33:54.089 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T00:33:54.213 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T01:15:19.489 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T01:15:23.529 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T01:33:55.243 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T01:33:55.355 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T01:36:03.773 WRN ../../requests/dialer_swarm.go:293 > Failed to lookup host: "quality.mysterium.network" error="lookup quality.mysterium.network on 8.8.4.4:53: dial udp 8.8.4.4:53: operation was canceled" 2024-01-23T02:15:18.981 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T02:15:21.019 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T02:33:56.260 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T02:33:56.378 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T03:15:19.520 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T03:15:24.842 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T03:33:57.281 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T03:33:57.398 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T04:15:19.576 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T04:15:28.477 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T04:28:10.469 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T04:28:12.526 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T04:33:58.313 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T04:33:58.429 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T05:15:19.118 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T05:15:25.489 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T05:33:59.304 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T05:33:59.416 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T06:15:19.283 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T06:15:25.165 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T06:34:00.349 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T06:34:00.477 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T07:01:59.537 DBG ../../config/config.go:221 > Returning user config value terms.provider-agreed:true 2024-01-23T07:01:59.538 DBG ../../config/config.go:221 > Returning user config value terms.consumer-agreed:true 2024-01-23T07:01:59.538 DBG ../../config/config.go:221 > Returning user config value terms.version:0.0.53 2024-01-23T07:01:59.796 DBG ../../identity/selector/handler.go:98 > Attempting to use last identity 2024-01-23T07:01:59.797 DBG ../../identity/selector/handler.go:103 > Found identity in cache: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:01:59.798 DBG ../../identity/manager.go:153 > Unlocked identity found in cache, skipping keystore: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:01:59.798 DBG ../../identity/selector/handler.go:108 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:00.511 WRN ../../session/pingpong/consumer_balance_tracker.go:480 > Could not get consumer channel error="no contract code at given address" 2024-01-23T07:02:00.624 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+0 Current:+150000000000000000} 2024-01-23T07:02:01.775 DBG ../../eventbus/event_bus.go:101 > Published topic="NAT-type-detected" event=prcone 2024-01-23T07:02:02.805 INF ../../session/pingpong/pricing.go:223 > pricing info loaded. expires @ 2024-01-23 07:03:43.039348695 +0000 UTC 2024-01-23T07:02:22.811 DBG ../../eventbus/event_bus.go:101 > Published topic="transactor_identity_registration" event={RegistryAddress:0x87F0F4b7e0FAb14A565C87BAbbA6c40c92281b51 HermesID:0x80Ed28d84792d8b153bf2F25F0C4B7a1381dE4ab Stake:+0 Fee:+28296326930900000 Beneficiary:0xBc05B248016473b7ef0D18e4c9FCE39449A9ab5a Signature:0xd40a3335591345c87393509cc41dba92e78313676a3fa2c089219c6af2115afc54ec74fa0636e53355c4df38f24981b3b10293b9f110a33facabc846ad2a540b1b Identity:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 ChainID:137} 2024-01-23T07:02:22.813 DBG ../../eventbus/event_bus.go:101 > Published topic="registration_event_topic" event={ID:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Status:InProgress ChainID:137} 2024-01-23T07:02:22.813 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:22.814 INF ../../core/discovery/discovery.go:175 > Received status InProgress ignoring 2024-01-23T07:02:22.814 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:22.815 INF ../../core/discovery/discovery.go:175 > Received status InProgress ignoring 2024-01-23T07:02:22.816 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:22.816 INF ../../core/discovery/discovery.go:175 > Received status InProgress ignoring 2024-01-23T07:02:22.817 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:22.817 INF ../../core/discovery/discovery.go:175 > Received status InProgress ignoring 2024-01-23T07:02:22.819 DBG ../../session/pingpong/hermes_promise_settler.go:318 > Ignoring event InProgress for provider {"0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9"} 2024-01-23T07:02:26.849 DBG ../../config/config.go:221 > Returning user config value terms.provider-agreed:true 2024-01-23T07:02:26.850 DBG ../../config/config.go:221 > Returning user config value terms.consumer-agreed:true 2024-01-23T07:02:26.851 DBG ../../config/config.go:221 > Returning user config value terms.version:0.0.53 2024-01-23T07:02:26.981 DBG ../../identity/selector/handler.go:98 > Attempting to use last identity 2024-01-23T07:02:26.982 DBG ../../identity/selector/handler.go:103 > Found identity in cache: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:26.982 DBG ../../identity/manager.go:153 > Unlocked identity found in cache, skipping keystore: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:26.983 DBG ../../identity/selector/handler.go:108 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:02:29.068 DBG ../../eventbus/event_bus.go:101 > Published topic="NAT-type-detected" event=prcone 2024-01-23T07:03:05.610 DBG ../../config/config.go:221 > Returning user config value terms.provider-agreed:true 2024-01-23T07:03:05.611 DBG ../../config/config.go:221 > Returning user config value terms.consumer-agreed:true 2024-01-23T07:03:05.611 DBG ../../config/config.go:221 > Returning user config value terms.version:0.0.53 2024-01-23T07:03:05.740 DBG ../../identity/selector/handler.go:98 > Attempting to use last identity 2024-01-23T07:03:05.741 DBG ../../identity/selector/handler.go:103 > Found identity in cache: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:03:05.741 DBG ../../identity/manager.go:153 > Unlocked identity found in cache, skipping keystore: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:03:05.741 DBG ../../identity/selector/handler.go:108 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:03:06.543 DBG ../../eventbus/event_bus.go:101 > Published topic="balance_change" event={Identity:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Previous:+150000000000000000 Current:+121703673069100000} 2024-01-23T07:03:07.830 DBG ../../eventbus/event_bus.go:101 > Published topic="NAT-type-detected" event=prcone 2024-01-23T07:03:44.552 DBG ../../eventbus/event_bus.go:101 > Published topic="registration_event_topic" event={ID:{Address:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9} Status:Registered ChainID:137} 2024-01-23T07:03:44.554 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:03:44.554 INF ../../core/discovery/discovery.go:169 > Identity registered, proceeding with proposal registration 2024-01-23T07:03:44.555 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:03:44.556 INF ../../core/discovery/discovery.go:169 > Identity registered, proceeding with proposal registration 2024-01-23T07:03:44.559 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:03:44.560 INF ../../core/discovery/discovery.go:169 > Identity registered, proceeding with proposal registration 2024-01-23T07:03:44.560 DBG ../../core/discovery/discovery.go:161 > Registration event received for 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:03:44.561 INF ../../core/discovery/discovery.go:169 > Identity registered, proceeding with proposal registration 2024-01-23T07:03:44.557 INF ../../session/pingpong/hermes_promise_settler.go:321 > Identity registration event received for provider {"0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9"} 2024-01-23T07:03:44.562 INF ../../session/pingpong/hermes_promise_settler.go:326 > Identity registration event handled for provider {"0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9"} 2024-01-23T07:15:19.736 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T07:15:21.750 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T07:40:02.315 DBG ../../config/config.go:221 > Returning user config value terms.provider-agreed:true 2024-01-23T07:40:02.318 DBG ../../config/config.go:221 > Returning user config value terms.consumer-agreed:true 2024-01-23T07:40:02.321 DBG ../../config/config.go:221 > Returning user config value terms.version:0.0.53 2024-01-23T07:40:02.489 DBG ../../identity/selector/handler.go:98 > Attempting to use last identity 2024-01-23T07:40:02.490 DBG ../../identity/selector/handler.go:103 > Found identity in cache: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:40:02.491 DBG ../../identity/manager.go:153 > Unlocked identity found in cache, skipping keystore: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:40:02.491 DBG ../../identity/selector/handler.go:108 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T07:40:04.582 DBG ../../eventbus/event_bus.go:101 > Published topic="NAT-type-detected" event=prcone 2024-01-23T07:40:05.303 INF ../../session/pingpong/pricing.go:223 > pricing info loaded. expires @ 2024-01-23 07:43:43.47511673 +0000 UTC 2024-01-23T08:15:18.820 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T08:15:25.509 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T08:45:21.599 DBG ../../config/config.go:221 > Returning user config value terms.provider-agreed:true 2024-01-23T08:45:21.601 DBG ../../config/config.go:221 > Returning user config value terms.consumer-agreed:true 2024-01-23T08:45:21.609 DBG ../../config/config.go:221 > Returning user config value terms.version:0.0.53 2024-01-23T08:45:21.753 DBG ../../identity/selector/handler.go:98 > Attempting to use last identity 2024-01-23T08:45:21.754 DBG ../../identity/selector/handler.go:103 > Found identity in cache: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T08:45:21.755 DBG ../../identity/manager.go:153 > Unlocked identity found in cache, skipping keystore: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T08:45:21.755 DBG ../../identity/selector/handler.go:108 > Unlocked identity: 0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 2024-01-23T08:45:23.833 DBG ../../eventbus/event_bus.go:101 > Published topic="NAT-type-detected" event=prcone 2024-01-23T08:45:24.524 INF ../../session/pingpong/pricing.go:223 > pricing info loaded. expires @ 2024-01-23 08:48:44.004520681 +0000 UTC 2024-01-23T09:15:20.133 WRN ../../communication/nats/connection_wrap.go:105 > NATS: disconnected error=EOF 2024-01-23T09:15:29.199 WRN ../../communication/nats/connection_wrap.go:106 > NATS: reconnected 2024-01-23T10:29:52.517 INF ../../cmd/commands/service/command.go:98 > Stopping application 2024-01-23T10:29:52.520 INF ../../cmd/node.go:92 > Connection closed 2024-01-23T10:29:52.522 DBG ../../eventbus/event_bus.go:101 > Published topic="Node" event={Status:Stopped} 2024-01-23T10:29:52.523 INF ../../cmd/node.go:96 > API stopped 2024-01-23T10:29:52.525 INF ../../ui/discovery/discovery.go:71 > LAN discovery disabled. Stopping noop local service discovery. 2024-01-23T10:29:52.541 INF ../../identity/registry/registry_contract.go:315 > Stopping registry... 2024-01-23T10:29:52.543 DBG ../../session/pingpong/hermes_promise_handler.go:236 > hermes promise handler stopped 2024-01-23T10:29:52.544 INF ../../session/pingpong/hermes_promise_settler.go:390 > Stopped listening for settlement events 2024-01-23T10:29:52.546 INF ../../ui/server.go:177 > Server stopped 2024-01-23T10:29:52.546 INF ../../ui/server.go:177 > Server stopped 2024-01-23T10:29:52.546 INF ../../cmd/node.go:99 > Web UI server stopped 2024-01-23T10:29:52.549 DBG ../../sleep/sleep_noop.go:33 > Unregister noop sleep events 2024-01-23T10:29:52.549 INF ../../cmd/node.go:102 > Sleep notifier stopped 2024-01-23T10:29:52.550 INF ../../services/wireguard/service/service.go:271 > Wireguard: stopping 2024-01-23T10:29:52.550 INF ../../services/wireguard/service/service.go:293 > Wireguard: stopped 2024-01-23T10:29:52.550 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:2f4ae4ae-71da-4ffe-8dd5-33a99719b897 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:dvpn Status:NotRunning} 2024-01-23T10:29:52.554 INF ../../services/wireguard/service/service.go:271 > Wireguard: stopping 2024-01-23T10:29:52.554 INF ../../services/wireguard/service/service.go:293 > Wireguard: stopped 2024-01-23T10:29:52.554 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:77beec94-33fd-49c0-9a43-1a4c8bddd7d3 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:data_transfer Status:NotRunning} 2024-01-23T10:29:52.600 DBG ../../session/pingpong/hermes_promise_settler.go:297 > Ignoring service event with status NotRunning 2024-01-23T10:29:52.610 INF ../../services/wireguard/service/service.go:271 > Wireguard: stopping 2024-01-23T10:29:52.611 INF ../../services/wireguard/service/service.go:293 > Wireguard: stopped 2024-01-23T10:29:52.613 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:14409a9e-82a9-41e0-b4b6-95f756e96753 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:scraping Status:NotRunning} 2024-01-23T10:29:52.615 INF ../../services/wireguard/service/service.go:271 > Wireguard: stopping 2024-01-23T10:29:52.617 INF ../../services/wireguard/service/service.go:293 > Wireguard: stopped 2024-01-23T10:29:52.621 DBG ../../eventbus/event_bus.go:101 > Published topic="Service status" event={ID:cbc3a70f-8750-40e4-904b-92ea0ecde021 ProviderID:0x829a161dc3b882cac1eefe0a76b4ba4d4182f4b9 Type:wireguard Status:NotRunning} 2024-01-23T10:29:52.627 INF ../../nat/service_iptables.go:80 > Deleting NAT/Firewall rules 2024-01-23T10:29:52.631 ERR ../../core/service/manager.go:222 > Service stop failed error="no such instance" 2024-01-23T10:29:52.632 ERR ../../core/service/manager.go:222 > Service stop failed error="no such instance" 2024-01-23T10:29:52.633 DBG ../../session/pingpong/hermes_promise_settler.go:297 > Ignoring service event with status NotRunning 2024-01-23T10:29:52.636 ERR ../../core/service/manager.go:222 > Service stop failed error="no such instance" 2024-01-23T10:29:52.636 DBG ../../session/pingpong/hermes_promise_settler.go:297 > Ignoring service event with status NotRunning 2024-01-23T10:29:52.638 ERR ../../core/service/manager.go:222 > Service stop failed error="no such instance" 2024-01-23T10:29:52.638 DBG ../../session/pingpong/hermes_promise_settler.go:297 > Ignoring service event with status NotRunning 2024-01-23T10:29:52.702 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -D MYST --destination 10.0.0.0/8 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-23T10:29:52.715 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -D MYST --destination 172.16.0.0/12 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-23T10:29:52.728 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -D MYST --destination 192.168.0.0/16 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-23T10:29:52.742 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables -D MYST --destination 127.0.0.0/8 --jump DNAT --to-destination 240.0.0.1 --table nat" output:

2024-01-23T10:29:52.743 INF ../../nat/service_iptables.go:92 > Deleting NAT/Firewall rules... done 2024-01-23T10:29:52.756 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables --flush MYST --table nat" output:

2024-01-23T10:29:52.771 DBG ../../nat/service_iptables.go:229 > "/usr/sbin/iptables --delete-chain MYST --table nat" output:

2024-01-23T10:29:52.775 DBG ../../firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://broker.mysterium.network:4222 removed 2024-01-23T10:29:52.776 DBG ../../firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://broker.mysterium.network:4222 removed 2024-01-23T10:29:52.776 DBG ../../firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://51.158.204.30:4222 removed 2024-01-23T10:29:52.776 DBG ../../firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://51.158.204.75:4222 removed 2024-01-23T10:29:52.776 DBG ../../firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://51.158.204.9:4222 removed 2024-01-23T10:29:52.777 DBG ../../firewall/outgoing_firewall_noop.go:61 > Rule for URL: nats://51.158.204.23:4222 removed 2024-01-23T10:29:52.777 INF ../../firewall/incoming_firewall_noop.go:38 > Rules reset was requested 2024-01-23T10:29:52.777 INF ../../firewall/outgoing_firewall_noop.go:35 > Rules reset was requested

stale[bot] commented 4 months ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

DonVitja commented 3 months ago

For me, this helped:

  1. Have your internet provider unlock the ports (in Germany they are closed by default ((Vodafone)))
  2. For the modem, make sure that the EXTERNAL port forwarding is at UDP 66000-66100, the INTERNAL (LOCAL) must remain at UDP 56000-56100.
  3. Restart all nodes, restart modem
Killerbeans commented 1 month ago

Most likely your identity hasn't been registered.

Type the following in your console:

myst cli

Followed by this:

identities list

Copy, paste the identity and run in this:

identities register <identity>

Exit Myst CLI with Ctrl + C

restart myst node with:

sudo systemctl restart mysterium-node.service Wait for about 15 to 30 minutes. If the node doesn't go online, repeat the process.

DonVitja commented 1 month ago

I run myst as docker. What are the commands?Mit freundlichen Grüßen Torno ViktorVon: Killerbeans @.>Gesendet: Mittwoch, August 21, 2024 1:15:44 PMAn: mysteriumnetwork/node @.>Cc: DonVitja @.>; Comment @.>Betreff: Re: [mysteriumnetwork/node] Nodes not comming up "Monitoring failed" (Issue #5956) Most likely your identity hasn't been registered. Type the following in your console: myst cli Followed by this: identities list Copy, paste the identity and run in this: identities register Exit Myst CLI with Ctrl + C restart myst node with: sudo systemctl restart mysterium-node.service Wait for about 15 to 30 minutes. If it node doesn't go online, repeat the process.

—Reply to this email directly, view it on GitHub, or unsubscribe.You are receiving this because you commented.Message ID: @.***>