Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Nodes not comming up "Monitoring failed" #5956

Open
K0KKeL opened this issue Jan 24, 2024 · 4 comments
Open

Nodes not comming up "Monitoring failed" #5956

K0KKeL opened this issue Jan 24, 2024 · 4 comments
Labels
bug

Comments

@K0KKeL
Copy link

K0KKeL commented Jan 24, 2024 •
edited
Loading

Describe the bug
Multiple of my Nodes are not operational

  • My nodes overvieuw these nodes have an status of "Monitoring failed" for multiple days.

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

Environment (please complete the following information):

  • Node version: 1.29.1 and 1.29.2 and 1.29.3 and 1.29.4 and 1.29.6 and 129.7
  • OS: Debian 10 and 11 (VPS)
  • Docker (if applicable): 25.0.0 (mysteriumnetwork/myst latest ce74807a40a8 2 days ago 65.7MB)

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
@K0KKeL K0KKeL added the bug label Jan 24, 2024
@stale Stale
Copy link

stale bot commented May 13, 2024

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.

@stale stale bot added the stale label May 13, 2024
@DonVitja
Copy link

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

@stale stale bot removed the stale label Jun 26, 2024
@Killerbeans
Copy link

Killerbeans commented Aug 21, 2024 •
edited
Loading

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
Copy link

DonVitja commented Aug 21, 2024 via email

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@Killerbeans @DonVitja @K0KKeL