search

spacemeshos / smapp

Spacemesh App (Smesher + Wallet) 🏦📊
https://spacemesh.io
Apache License 2.0
133 stars 41 forks source link

UPDATING POS PROVING OPTS #1801

Closed Ramiro2-del closed 17 hours ago

Ramiro2-del commented 5 days ago

Environment

Describe the bug: When UPDATING POS PROVING OPTS

Error: PoS profiler exited with code 101: thread 'main' panicked at src\pow\mod.rs:25:1: MockProver::par(): No matching expectation found note: run with RUST_BACKTRACE=1 environment variable to display a backtrace

Attach screenshots or GIF’s of what’s going on if it makes the issue more illustrative.

Screenshot 2024-11-21 165459

Logs

2024-11-21T16:01:18.034-0500 INFO node App version: v1.7.7. Git: e5c138c - e5c138cc699f6bf20a07ea69f95f5478479db96f . Go Version: go1.23.2. OS: windows-amd64 . Genesis 9eebff023abb17ccb775c602daade8ed708f0a50 2024-11-21T16:01:18.034-0500 INFO node Welcome to Spacemesh. Spacemesh full node is starting... 2024-11-21T16:01:18.034-0500 INFO node Identity file not found. Creating new identity... 2024-11-21T16:01:18.034-0500 INFO node Created new identity {"filename": "local.key", "public_key": "54139", "name": ""} 2024-11-21T16:01:18.034-0500 INFO node starting spacemesh {"data-dir": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b", "post-dir": "C:\Users\HPDRAGONELITE\post\7c8cef2b", "hostname": "DESKTOP-JHRUD43", "name": ""} 2024-11-21T16:01:18.034-0500 INFO node.clock converting genesis time to local time {"genesis": "2023-07-14T08:00:00.000Z", "local": "2023-07-14T04:00:00.000-0400"} 2024-11-21T16:01:18.034-0500 INFO node initializing p2p services 2024-11-21T16:01:18.034-0500 INFO node.clock starting global clock {"now": "2024-11-21T16:01:18.034-0500", "genesis": "2023-07-14T04:00:00.000-0400", "layer_duration": "5m0s", "tick_interval": "1s"} 2024-11-21T16:01:18.121-0500 INFO node starting cache warmup 2024-11-21T16:01:18.123-0500 INFO node.warmup Reading ATXs from DB {"from epoch": 0, "to epoch": 0} 2024-11-21T16:01:18.123-0500 INFO node.warmup Finished reading ATXs. Starting reading malfeasance {"duration": "0s"} 2024-11-21T16:01:18.123-0500 INFO node.warmup Finished reading malfeasance {"duration": "0s"} 2024-11-21T16:01:18.123-0500 INFO node cache warmup {"duration": "511.2µs", "name": ""} 2024-11-21T16:01:18.123-0500 INFO node.cachedDB initialized datastore {"config": {"ATXSize":1000,"MalfeasanceSize":1000}} 2024-11-21T16:01:18.486-0500 INFO node.nipostValidator scaling post verifier {"current": 0, "new": 4} 2024-11-21T16:01:18.497-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqylyl2l0zsmmax0wnutt4dwnrkcwef5eeq3xladz", "next nonce": 0, "balance": 2743200000000000} 2024-11-21T16:01:18.498-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqyp8ueuuh2dgrc2g6ps4xvueyjpky6rfaqnxdy97", "next nonce": 0, "balance": 5867100000000000} 2024-11-21T16:01:18.498-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqrhftrq9knsetema7dt0qfzgd5a20m9rcczk0gk5", "next nonce": 0, "balance": 2933540000000000} 2024-11-21T16:01:18.498-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqy3fc8nvdetan6qjz5cju7h4c60mjyvdlqnlqpxu", "next nonce": 0, "balance": 15688500000000000} 2024-11-21T16:01:18.498-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq99klpy92mwlfcft5lmz8q5sef2v2qvtucd9y55v", "next nonce": 0, "balance": 2933540000000000} 2024-11-21T16:01:18.498-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqyfq5f522mmrzs4lczhaf30jh4pmqyfrzcg8vrpc", "next nonce": 0, "balance": 3303792000000000} 2024-11-21T16:01:18.498-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqzgqpjxdw77aw74f8mz540rykda4x2jgjgaca7z5", "next nonce": 0, "balance": 184375000000000} 2024-11-21T16:01:18.498-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqpf6djx3axy7aag8zhyf84ljsulhfypfxgpw5y0u", "next nonce": 0, "balance": 1990600000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq9s5l9tc87wspycr68dfagmzxplzdn7zlcymnkup", "next nonce": 0, "balance": 15000000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqptx3mdg4gm67arv4ykau6nfy6w9v03x9s49wmru", "next nonce": 0, "balance": 100000000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq9fwfymdr7qv0tfc3ppa4q8ara6qm7kwugw9gdme", "next nonce": 0, "balance": 500000000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqzgmt5vv4jgucas8vvrlu4daa4r29cunwqpv0trt", "next nonce": 0, "balance": 1022800000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqyw9lvmmayckrxlnf8u7850tsjdg8zz6dg956gxg", "next nonce": 0, "balance": 270600000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq9a8g5act6ewmmmmmux8l570kr6l68htzsq94wg4", "next nonce": 0, "balance": 4090900000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq827v998nt99vupxlrfucdk0tapp2hjyygmn3kyd", "next nonce": 0, "balance": 409100000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqyjpjgup8fz32cufcv2nlqrr3nyvge7akqt0daea", "next nonce": 0, "balance": 2933540000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqx55z5795569fq5kym3gw2h6zp6ajeh46c5wtrzf", "next nonce": 0, "balance": 455300000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqpc55ghjq6sxf5k77yc8n82fkwhlj0jedcgw2zck", "next nonce": 0, "balance": 4909100000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqyf5uc2n8mutm3tuateu5efcm9awvrclmcm5mhdf", "next nonce": 0, "balance": 2933540000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq80we5pmwztmqgpxu6xasapgn65r4xjczqxu39a2", "next nonce": 0, "balance": 409000000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqpc4ppx8s4gmdaa5tzg35s6l3v6ujg6hmqz3s4lc", "next nonce": 0, "balance": 859100000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq8za0geafhj4avegdwhtaw9fmgjh07s55cufk695", "next nonce": 0, "balance": 293300000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqxq54zvz484hhcnrghnqrjlw26twwld32slz3lxa", "next nonce": 0, "balance": 191800000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqy6anfdew2sdtvuuaffjy0l7ssu9r8vjsss5c442", "next nonce": 0, "balance": 2045400000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqrgqc65x5q6exujgjs970fvcakd790na3gsr3uu7", "next nonce": 0, "balance": 333300000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqq8zukfwtggnfq4jaqpv6m8xgtg5ay2ezaqpr2w6y", "next nonce": 0, "balance": 2933540000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqyvet26gqsxjt6w50nnp80jvajr3n25xzsdpxn65", "next nonce": 0, "balance": 831250000000000} 2024-11-21T16:01:18.499-0500 INFO node.vm genesis account {"layer": "0", "principal": "sm1qqqqqqrt64knhuxu3kzq50ak04nrkk9yf2zxprshmvkcy", "next nonce": 0, "balance": 88818783000000000} 2024-11-21T16:01:18.499-0500 INFO node.beacon registered signing key {"node_id": "54139d"} 2024-11-21T16:01:18.499-0500 INFO node initializing tortoise 2024-11-21T16:01:18.503-0500 INFO node tortoise initialized {"duration": "4.2423ms", "name": ""} 2024-11-21T16:01:18.506-0500 INFO node.proposalStore proposals store created {"capacity": 3, "evicted": 143004} 2024-11-21T16:01:18.506-0500 INFO node.atxHandler atx handler created {"supported ATX versions": ["vV1 from epoch 0"]} 2024-11-21T16:01:18.506-0500 INFO node.atxHandler registered signing key {"id": "54139d"} 2024-11-21T16:01:18.506-0500 INFO node.hareOracle hare oracle initialized {"epoch size": 4032, "confidence param": 200} 2024-11-21T16:01:18.506-0500 INFO node.blockCert registered signing key {"id": "54139d"} 2024-11-21T16:01:18.506-0500 INFO node.hare registered signing key {"id": "54139d"} 2024-11-21T16:01:18.506-0500 INFO node.hare started {"enabled": true, "enabled layer": 35117, "disabled layer": 4294967295, "committee": 400, "committee upgrade layer": 105720, "committee upgrade size": 50, "leaders": 5, "iterations limit": 4, "preround delay": "25s", "round duration": "12s", "log stats": false, "p2p protocol": "/h/3.0", "enabled layer": 143005, "disabled layer": 4294967295} 2024-11-21T16:01:18.506-0500 INFO node.proposalBuilder registered signing key {"id": "54139d"} 2024-11-21T16:01:18.506-0500 WARN node coinbase account is not set, connections from remote post services will be rejected 2024-11-21T16:01:18.506-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-0.spacemesh.network", "pubkey": "cFnqCS5oER7GOX576oPtahlxB/1y95aDibdK7RHQFVg=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:01:18.506-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-0.spacemesh.network/v1/info"} 2024-11-21T16:01:18.677-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-0.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:01:18.678-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-1.spacemesh.network", "pubkey": "Qh1efxY4YhoYBEXKPTiHJ/a7n1GsllRSyweQKO3j7m0=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:01:18.678-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-1.spacemesh.network/v1/info"} 2024-11-21T16:01:18.958-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-1.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:01:18.958-0500 INFO node.poet created poet client {"url": "https://poet-110.spacemesh.network", "pubkey": "8Qqgid+37eyY7ik+EA47Nd5TrQjXolbv2Mdgir243No=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:01:18.958-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-110.spacemesh.network/v1/info"} 2024-11-21T16:01:19.250-0500 DEBUG node.poet response received {"url": "https://poet-110.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:01:19.250-0500 INFO node.poet created poet client {"url": "https://poet-111.spacemesh.network", "pubkey": "caIV0Ym59L3RqbVAL6UrCPwr+z+lwe2TBj57QWnAgtM=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:01:19.250-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-111.spacemesh.network/v1/info"} 2024-11-21T16:01:19.416-0500 DEBUG node.poet response received {"url": "https://poet-111.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:01:19.417-0500 INFO node.poet created poet client {"url": "https://poet-112.spacemesh.network", "pubkey": "5p/mPvmqhwdvf8U0GVrNq/9IN/HmZj5hCkFLAN04g1E=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:01:19.417-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-112.spacemesh.network/v1/info"} 2024-11-21T16:01:19.573-0500 DEBUG node.poet response received {"url": "https://poet-112.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:01:19.594-0500 INFO node.sync starting syncer loop 2024-11-21T16:01:19.594-0500 INFO node.sync starting syncer layer processing loop 2024-11-21T16:01:19.594-0500 INFO node.beacon beacon protocol disabled 2024-11-21T16:01:19.594-0500 INFO node no need to preserve data after recovery 2024-11-21T16:01:19.594-0500 WARN node.grpc unsecured grpc server is listening on a public IP address {"address": "0.0.0.0:9092"} 2024-11-21T16:01:19.595-0500 INFO node.proposalBuilder started {"layer size": 50, "epoch size": 4032, "hdist": 10, "network delay": "30m0s", "good atx percent": 90, "active set window": "1h0m0s", "active set retry interval": "1m0s", "active set tries": 20, "next": 143005} 2024-11-21T16:01:19.595-0500 INFO node smeshing not started, waiting to be triggered via smesher api 2024-11-21T16:01:19.595-0500 INFO node app started 2024-11-21T16:01:19.595-0500 INFO node.bootstrap start listening to update {"source": "https://bootstrap.spacemesh.network/mainnet", "interval": "1m0s"} 2024-11-21T16:02:00.026-0500 INFO node.bootstrap new bootstrap file {"sessionId": "1d22743e", "persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\34\epoch-34-update-bs", "epoch": "34", "beacon": "9cc502f2", "activeset_size": 5400442} 2024-11-21T16:02:00.026-0500 INFO node.beacon using fallback beacon {"epoch": 34, "beacon": "9cc502f2"} 2024-11-21T16:02:02.050-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 34, "size": 5400442} 2024-11-21T16:02:19.596-0500 INFO node.sync node is too far behind {"sessionId": "859a9150", "current": "143004", "last synced": "8063", "behind threshold": 36} 2024-11-21T16:02:19.597-0500 INFO node.sync starting atx sync {"sessionId": "859a9150", "epoch_id": 1} 2024-11-21T16:02:21.516-0500 INFO node.sync downloaded epoch info {"sessionId": "859a9150", "epoch_id": 1, "peer": "12D3KooWAArzkTFKEqvQEEvFAzVjbYvtD396azxUYtFDncNTLs6U", "atxs": 2395} 2024-11-21T16:02:22.376-0500 INFO node.sync downloaded epoch info {"sessionId": "859a9150", "epoch_id": 1, "peer": "12D3KooWAsMgXLpyGdsRNjHBF3FaXwnXhyMEqWQYBXUpvCHNzFNK", "atxs": 2395} 2024-11-21T16:02:45.847-0500 INFO node.bootstrap new bootstrap file {"sessionId": "1d22743e", "persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\35\epoch-35-update-bs", "epoch": "35", "beacon": "07a1d4a6", "activeset_size": 4965738} 2024-11-21T16:02:45.847-0500 INFO node.beacon using fallback beacon {"epoch": 35, "beacon": "07a1d4a6"} 2024-11-21T16:02:49.287-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 35, "size": 4965738} 2024-11-21T16:02:58.660-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 1, "downloaded": 992, "total": 2395, "progress": 992, "rate per sec": 25.39455061461481} 2024-11-21T16:03:34.018-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 1, "downloaded": 1992, "total": 2395, "progress": 1000, "rate per sec": 28.282802331214505} 2024-11-21T16:03:48.205-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 1, "downloaded": 2395, "total": 2395, "progress": 403, "rate per sec": 28.405243698206817} 2024-11-21T16:03:48.206-0500 INFO node.sync atx sync completed {"sessionId": "859a9150", "epoch_id": 1, "downloaded": 2395, "total": 2395, "unavailable": 0, "duration": "1m28.6092112s"} 2024-11-21T16:03:48.207-0500 INFO node.sync starting atx sync {"sessionId": "859a9150", "epoch_id": 2} 2024-11-21T16:03:49.725-0500 INFO node.sync downloaded epoch info {"sessionId": "859a9150", "epoch_id": 2, "peer": "12D3KooWDAFRuFrMNgVQMDy8cgD71GLtPyYyfQzFxMZr2yUBgjHK", "atxs": 4019} 2024-11-21T16:03:50.232-0500 INFO node.sync downloaded epoch info {"sessionId": "859a9150", "epoch_id": 2, "peer": "12D3KooWRcTWDHzptnhJn5h6CtwnokzzMaDLcXv6oM9CxQEXd5FL", "atxs": 4019} 2024-11-21T16:04:36.962-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 2, "downloaded": 968, "total": 4019, "progress": 968, "rate per sec": 19.85453598821297} 2024-11-21T16:04:53.074-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 2, "downloaded": 1431, "total": 4019, "progress": 463, "rate per sec": 28.736165801544637} 2024-11-21T16:05:56.759-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 2, "downloaded": 3652, "total": 4019, "progress": 2221, "rate per sec": 34.874525336276704} 2024-11-21T16:06:05.322-0500 INFO node.sync atx sync completed {"sessionId": "859a9150", "epoch_id": 2, "downloaded": 4019, "total": 4019, "unavailable": 0, "duration": "2m17.1148829s"} 2024-11-21T16:06:05.323-0500 INFO node.sync starting atx sync {"sessionId": "859a9150", "epoch_id": 3} 2024-11-21T16:06:07.290-0500 INFO node.sync downloaded epoch info {"sessionId": "859a9150", "epoch_id": 3, "peer": "12D3KooWRcTWDHzptnhJn5h6CtwnokzzMaDLcXv6oM9CxQEXd5FL", "atxs": 16676} 2024-11-21T16:06:08.226-0500 INFO node.sync downloaded epoch info {"sessionId": "859a9150", "epoch_id": 3, "peer": "12D3KooWDAFRuFrMNgVQMDy8cgD71GLtPyYyfQzFxMZr2yUBgjHK", "atxs": 16676} 2024-11-21T16:11:50.273-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 3, "downloaded": 2665, "total": 16676, "progress": 2665, "rate per sec": 7.725759651805665} 2024-11-21T16:13:07.895-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 3, "downloaded": 4900, "total": 16676, "progress": 2235, "rate per sec": 28.79316453829527} 2024-11-21T16:14:17.563-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 3, "downloaded": 7858, "total": 16676, "progress": 2958, "rate per sec": 42.45833531833467} 2024-11-21T16:14:50.806-0500 INFO node.sync atx sync progress {"sessionId": "859a9150", "epoch_id": 3, "downloaded": 12698, "total": 16676, "progress": 4840, "rate per sec": 145.59529705531708} 2024-11-21T16:19:10.104-0500 INFO node App version: v1.7.7. Git: e5c138c - e5c138cc699f6bf20a07ea69f95f5478479db96f . Go Version: go1.23.2. OS: windows-amd64 . Genesis 9eebff023abb17ccb775c602daade8ed708f0a50 2024-11-21T16:19:10.105-0500 INFO node Welcome to Spacemesh. Spacemesh full node is starting... 2024-11-21T16:19:10.106-0500 INFO node Loaded existing identity {"filename": "local.key", "public_key": "54139", "name": ""} 2024-11-21T16:19:10.116-0500 INFO node starting spacemesh {"data-dir": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b", "post-dir": "C:\Users\HPDRAGONELITE\post\7c8cef2b", "hostname": "DESKTOP-JHRUD43", "name": ""} 2024-11-21T16:19:10.116-0500 INFO node.clock converting genesis time to local time {"genesis": "2023-07-14T08:00:00.000Z", "local": "2023-07-14T04:00:00.000-0400"} 2024-11-21T16:19:10.116-0500 INFO node initializing p2p services 2024-11-21T16:19:10.117-0500 INFO node.clock starting global clock {"now": "2024-11-21T16:19:10.117-0500", "genesis": "2023-07-14T04:00:00.000-0400", "layer_duration": "5m0s", "tick_interval": "1s"} 2024-11-21T16:19:10.199-0500 INFO node starting cache warmup 2024-11-21T16:19:10.199-0500 INFO node.warmup Reading ATXs from DB {"from epoch": 0, "to epoch": 3} 2024-11-21T16:19:10.288-0500 INFO node.warmup Finished reading ATXs. Starting reading malfeasance {"duration": "88.4826ms"} 2024-11-21T16:19:10.288-0500 INFO node.warmup Finished reading malfeasance {"duration": "578.4µs"} 2024-11-21T16:19:10.288-0500 INFO node cache warmup {"duration": "89.5737ms", "name": ""} 2024-11-21T16:19:10.288-0500 INFO node.cachedDB initialized datastore {"config": {"ATXSize":1000,"MalfeasanceSize":1000}} 2024-11-21T16:19:10.657-0500 INFO node.nipostValidator scaling post verifier {"current": 0, "new": 4} 2024-11-21T16:19:10.664-0500 INFO node.beacon registered signing key {"node_id": "54139d"} 2024-11-21T16:19:10.664-0500 INFO node initializing tortoise 2024-11-21T16:19:10.680-0500 INFO node tortoise initialized {"duration": "15.745ms", "name": ""} 2024-11-21T16:19:10.683-0500 INFO node.proposalStore proposals store created {"capacity": 3, "evicted": 143007} 2024-11-21T16:19:10.683-0500 INFO node.atxHandler atx handler created {"supported ATX versions": ["vV1 from epoch 0"]} 2024-11-21T16:19:10.683-0500 INFO node.atxHandler registered signing key {"id": "54139d"} 2024-11-21T16:19:10.683-0500 INFO node.hareOracle hare oracle initialized {"epoch size": 4032, "confidence param": 200} 2024-11-21T16:19:10.684-0500 INFO node.blockCert registered signing key {"id": "54139d"} 2024-11-21T16:19:10.684-0500 INFO node.hare registered signing key {"id": "54139d"} 2024-11-21T16:19:10.684-0500 INFO node.hare started {"enabled": true, "enabled layer": 35117, "disabled layer": 4294967295, "committee": 400, "committee upgrade layer": 105720, "committee upgrade size": 50, "leaders": 5, "iterations limit": 4, "preround delay": "25s", "round duration": "12s", "log stats": false, "p2p protocol": "/h/3.0", "enabled layer": 143008, "disabled layer": 4294967295} 2024-11-21T16:19:10.684-0500 INFO node.proposalBuilder registered signing key {"id": "54139d"} 2024-11-21T16:19:10.684-0500 WARN node coinbase account is not set, connections from remote post services will be rejected 2024-11-21T16:19:10.684-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-0.spacemesh.network", "pubkey": "cFnqCS5oER7GOX576oPtahlxB/1y95aDibdK7RHQFVg=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:19:10.684-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-0.spacemesh.network/v1/info"} 2024-11-21T16:19:10.999-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-0.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:19:11.001-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-1.spacemesh.network", "pubkey": "Qh1efxY4YhoYBEXKPTiHJ/a7n1GsllRSyweQKO3j7m0=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:19:11.001-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-1.spacemesh.network/v1/info"} 2024-11-21T16:19:11.259-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-1.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:19:11.259-0500 INFO node.poet created poet client {"url": "https://poet-110.spacemesh.network", "pubkey": "8Qqgid+37eyY7ik+EA47Nd5TrQjXolbv2Mdgir243No=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:19:11.259-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-110.spacemesh.network/v1/info"} 2024-11-21T16:19:11.540-0500 DEBUG node.poet response received {"url": "https://poet-110.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:19:11.540-0500 INFO node.poet created poet client {"url": "https://poet-111.spacemesh.network", "pubkey": "caIV0Ym59L3RqbVAL6UrCPwr+z+lwe2TBj57QWnAgtM=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:19:11.540-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-111.spacemesh.network/v1/info"} 2024-11-21T16:19:11.721-0500 DEBUG node.poet response received {"url": "https://poet-111.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:19:11.721-0500 INFO node.poet created poet client {"url": "https://poet-112.spacemesh.network", "pubkey": "5p/mPvmqhwdvf8U0GVrNq/9IN/HmZj5hCkFLAN04g1E=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:19:11.721-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-112.spacemesh.network/v1/info"} 2024-11-21T16:19:11.906-0500 DEBUG node.poet response received {"url": "https://poet-112.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:19:11.949-0500 INFO node.sync starting syncer loop 2024-11-21T16:19:11.949-0500 INFO node.sync starting syncer layer processing loop 2024-11-21T16:19:11.949-0500 INFO node.beacon beacon protocol disabled 2024-11-21T16:19:11.949-0500 INFO node no need to preserve data after recovery 2024-11-21T16:19:11.950-0500 INFO node.proposalBuilder started {"layer size": 50, "epoch size": 4032, "hdist": 10, "network delay": "30m0s", "good atx percent": 90, "active set window": "1h0m0s", "active set retry interval": "1m0s", "active set tries": 20, "next": 143008} 2024-11-21T16:19:11.950-0500 WARN node.grpc unsecured grpc server is listening on a public IP address {"address": "0.0.0.0:9092"} 2024-11-21T16:19:11.950-0500 INFO node smeshing not started, waiting to be triggered via smesher api 2024-11-21T16:19:11.951-0500 INFO node app started 2024-11-21T16:19:33.911-0500 INFO node.bootstrap loaded bootstrap file {"persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\34\epoch-34-update-bs", "epoch": "34", "beacon": "9cc502f2", "activeset_size": 5400442} 2024-11-21T16:19:33.911-0500 INFO node.bootstrap loaded bootstrap file {"persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\35\epoch-35-update-bs", "epoch": "35", "beacon": "07a1d4a6", "activeset_size": 4965738} 2024-11-21T16:19:33.911-0500 INFO node.bootstrap start listening to update {"source": "https://bootstrap.spacemesh.network/mainnet", "interval": "1m0s"} 2024-11-21T16:19:33.911-0500 INFO node.beacon using fallback beacon {"epoch": 34, "beacon": "9cc502f2"} 2024-11-21T16:19:35.036-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 34, "size": 5400442} 2024-11-21T16:19:35.039-0500 INFO node.beacon using fallback beacon {"epoch": 35, "beacon": "07a1d4a6"} 2024-11-21T16:19:35.911-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 35, "size": 4965738} 2024-11-21T16:20:11.950-0500 INFO node.sync node is too far behind {"sessionId": "54019561", "current": "143008", "last synced": "8063", "behind threshold": 36} 2024-11-21T16:20:11.974-0500 INFO node.sync starting atx sync {"sessionId": "54019561", "epoch_id": 3} 2024-11-21T16:20:12.137-0500 INFO node.sync atx sync progress {"sessionId": "54019561", "epoch_id": 3, "downloaded": 7910, "total": 16676, "progress": 7910, "rate per sec": 48490.68899077451} 2024-11-21T16:20:37.779-0500 INFO node.sync atx sync progress {"sessionId": "54019561", "epoch_id": 3, "downloaded": 15682, "total": 16676, "progress": 7772, "rate per sec": 303.1008405459757} 2024-11-21T16:23:22.891-0500 INFO node App version: v1.7.7. Git: e5c138c - e5c138cc699f6bf20a07ea69f95f5478479db96f . Go Version: go1.23.2. OS: windows-amd64 . Genesis 9eebff023abb17ccb775c602daade8ed708f0a50 2024-11-21T16:23:22.892-0500 INFO node Welcome to Spacemesh. Spacemesh full node is starting... 2024-11-21T16:23:22.893-0500 INFO node Loaded existing identity {"filename": "local.key", "public_key": "54139", "name": ""} 2024-11-21T16:23:22.908-0500 INFO node starting spacemesh {"data-dir": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b", "post-dir": "C:\Users\HPDRAGONELITE\post\7c8cef2b", "hostname": "DESKTOP-JHRUD43", "name": ""} 2024-11-21T16:23:22.908-0500 INFO node.clock converting genesis time to local time {"genesis": "2023-07-14T08:00:00.000Z", "local": "2023-07-14T04:00:00.000-0400"} 2024-11-21T16:23:22.908-0500 INFO node initializing p2p services 2024-11-21T16:23:22.908-0500 INFO node.clock starting global clock {"now": "2024-11-21T16:23:22.908-0500", "genesis": "2023-07-14T04:00:00.000-0400", "layer_duration": "5m0s", "tick_interval": "1s"} 2024-11-21T16:23:22.994-0500 INFO node starting cache warmup 2024-11-21T16:23:22.994-0500 INFO node.warmup Reading ATXs from DB {"from epoch": 0, "to epoch": 3} 2024-11-21T16:23:23.092-0500 INFO node.warmup Finished reading ATXs. Starting reading malfeasance {"duration": "98.6814ms"} 2024-11-21T16:23:23.092-0500 INFO node.warmup Finished reading malfeasance {"duration": "0s"} 2024-11-21T16:23:23.092-0500 INFO node cache warmup {"duration": "98.6814ms", "name": ""} 2024-11-21T16:23:23.092-0500 INFO node.cachedDB initialized datastore {"config": {"ATXSize":1000,"MalfeasanceSize":1000}} 2024-11-21T16:23:23.457-0500 INFO node.nipostValidator scaling post verifier {"current": 0, "new": 4} 2024-11-21T16:23:23.468-0500 INFO node.beacon registered signing key {"node_id": "54139d"} 2024-11-21T16:23:23.468-0500 INFO node initializing tortoise 2024-11-21T16:23:23.483-0500 INFO node tortoise initialized {"duration": "14.7336ms", "name": ""} 2024-11-21T16:23:23.499-0500 INFO node.proposalStore proposals store created {"capacity": 3, "evicted": 143008} 2024-11-21T16:23:23.500-0500 INFO node.atxHandler atx handler created {"supported ATX versions": ["vV1 from epoch 0"]} 2024-11-21T16:23:23.500-0500 INFO node.atxHandler registered signing key {"id": "54139d"} 2024-11-21T16:23:23.500-0500 INFO node.hareOracle hare oracle initialized {"epoch size": 4032, "confidence param": 200} 2024-11-21T16:23:23.500-0500 INFO node.blockCert registered signing key {"id": "54139d"} 2024-11-21T16:23:23.500-0500 INFO node.hare registered signing key {"id": "54139d"} 2024-11-21T16:23:23.500-0500 INFO node.hare started {"enabled": true, "enabled layer": 35117, "disabled layer": 4294967295, "committee": 400, "committee upgrade layer": 105720, "committee upgrade size": 50, "leaders": 5, "iterations limit": 4, "preround delay": "25s", "round duration": "12s", "log stats": false, "p2p protocol": "/h/3.0", "enabled layer": 143009, "disabled layer": 4294967295} 2024-11-21T16:23:23.500-0500 INFO node.proposalBuilder registered signing key {"id": "54139d"} 2024-11-21T16:23:23.500-0500 WARN node coinbase account is not set, connections from remote post services will be rejected 2024-11-21T16:23:23.500-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-0.spacemesh.network", "pubkey": "cFnqCS5oER7GOX576oPtahlxB/1y95aDibdK7RHQFVg=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:23:23.500-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-0.spacemesh.network/v1/info"} 2024-11-21T16:23:23.758-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-0.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:23:23.759-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-1.spacemesh.network", "pubkey": "Qh1efxY4YhoYBEXKPTiHJ/a7n1GsllRSyweQKO3j7m0=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:23:23.760-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-1.spacemesh.network/v1/info"} 2024-11-21T16:23:24.000-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-1.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:23:24.008-0500 INFO node.poet created poet client {"url": "https://poet-110.spacemesh.network", "pubkey": "8Qqgid+37eyY7ik+EA47Nd5TrQjXolbv2Mdgir243No=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:23:24.008-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-110.spacemesh.network/v1/info"} 2024-11-21T16:23:24.336-0500 DEBUG node.poet response received {"url": "https://poet-110.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:23:24.336-0500 INFO node.poet created poet client {"url": "https://poet-111.spacemesh.network", "pubkey": "caIV0Ym59L3RqbVAL6UrCPwr+z+lwe2TBj57QWnAgtM=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:23:24.336-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-111.spacemesh.network/v1/info"} 2024-11-21T16:23:24.548-0500 DEBUG node.poet response received {"url": "https://poet-111.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:23:24.548-0500 INFO node.poet created poet client {"url": "https://poet-112.spacemesh.network", "pubkey": "5p/mPvmqhwdvf8U0GVrNq/9IN/HmZj5hCkFLAN04g1E=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:23:24.548-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-112.spacemesh.network/v1/info"} 2024-11-21T16:23:24.675-0500 DEBUG node.poet response received {"url": "https://poet-112.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:23:24.706-0500 INFO node.sync starting syncer loop 2024-11-21T16:23:24.706-0500 INFO node.sync starting syncer layer processing loop 2024-11-21T16:23:24.706-0500 INFO node.beacon beacon protocol disabled 2024-11-21T16:23:24.706-0500 INFO node no need to preserve data after recovery 2024-11-21T16:23:24.706-0500 WARN node.grpc unsecured grpc server is listening on a public IP address {"address": "0.0.0.0:9092"} 2024-11-21T16:23:24.707-0500 INFO node smeshing not started, waiting to be triggered via smesher api 2024-11-21T16:23:24.707-0500 INFO node app started 2024-11-21T16:23:24.707-0500 INFO node.proposalBuilder started {"layer size": 50, "epoch size": 4032, "hdist": 10, "network delay": "30m0s", "good atx percent": 90, "active set window": "1h0m0s", "active set retry interval": "1m0s", "active set tries": 20, "next": 143009} 2024-11-21T16:23:48.431-0500 INFO node.bootstrap loaded bootstrap file {"persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\34\epoch-34-update-bs", "epoch": "34", "beacon": "9cc502f2", "activeset_size": 5400442} 2024-11-21T16:23:48.431-0500 INFO node.bootstrap loaded bootstrap file {"persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\35\epoch-35-update-bs", "epoch": "35", "beacon": "07a1d4a6", "activeset_size": 4965738} 2024-11-21T16:23:48.431-0500 INFO node.bootstrap start listening to update {"source": "https://bootstrap.spacemesh.network/mainnet", "interval": "1m0s"} 2024-11-21T16:23:48.431-0500 INFO node.beacon using fallback beacon {"epoch": 34, "beacon": "9cc502f2"} 2024-11-21T16:23:49.486-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 34, "size": 5400442} 2024-11-21T16:23:49.489-0500 INFO node.beacon using fallback beacon {"epoch": 35, "beacon": "07a1d4a6"} 2024-11-21T16:23:50.456-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 35, "size": 4965738} 2024-11-21T16:24:24.708-0500 INFO node.sync node is too far behind {"sessionId": "79112c62", "current": "143008", "last synced": "8063", "behind threshold": 36} 2024-11-21T16:24:24.744-0500 INFO node.sync starting atx sync {"sessionId": "79112c62", "epoch_id": 3} 2024-11-21T16:24:25.046-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 3, "downloaded": 15946, "total": 16676, "progress": 15946, "rate per sec": 52870.721702260846} 2024-11-21T16:24:47.277-0500 INFO node.sync atx sync completed {"sessionId": "79112c62", "epoch_id": 3, "downloaded": 16676, "total": 16676, "unavailable": 0, "duration": "22.5334502s"} 2024-11-21T16:24:47.278-0500 INFO node.sync starting atx sync {"sessionId": "79112c62", "epoch_id": 4} 2024-11-21T16:24:49.603-0500 INFO node.sync downloaded epoch info {"sessionId": "79112c62", "epoch_id": 4, "peer": "12D3KooWRcTWDHzptnhJn5h6CtwnokzzMaDLcXv6oM9CxQEXd5FL", "atxs": 26329} 2024-11-21T16:24:50.461-0500 INFO node.sync downloaded epoch info {"sessionId": "79112c62", "epoch_id": 4, "peer": "12D3KooWDAFRuFrMNgVQMDy8cgD71GLtPyYyfQzFxMZr2yUBgjHK", "atxs": 26329} 2024-11-21T16:30:29.350-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 4544, "total": 26329, "progress": 4544, "rate per sec": 13.283738984957148} 2024-11-21T16:31:41.107-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 7241, "total": 26329, "progress": 2697, "rate per sec": 37.58518636501081} 2024-11-21T16:32:50.600-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 11241, "total": 26329, "progress": 4000, "rate per sec": 57.560720923220885} 2024-11-21T16:34:09.543-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 14222, "total": 26329, "progress": 2981, "rate per sec": 37.76127795859081} 2024-11-21T16:35:28.560-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 17735, "total": 26329, "progress": 3513, "rate per sec": 44.45898416483365} 2024-11-21T16:36:17.717-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 22848, "total": 26329, "progress": 5113, "rate per sec": 104.01398703119514} 2024-11-21T16:37:05.404-0500 INFO node.sync atx sync progress {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 26329, "total": 26329, "progress": 3481, "rate per sec": 72.99644394499582} 2024-11-21T16:37:05.406-0500 INFO node.sync atx sync completed {"sessionId": "79112c62", "epoch_id": 4, "downloaded": 26329, "total": 26329, "unavailable": 0, "duration": "12m18.1283933s"} 2024-11-21T16:37:05.407-0500 INFO node.sync starting atx sync {"sessionId": "79112c62", "epoch_id": 5} 2024-11-21T16:37:07.325-0500 INFO node.sync downloaded epoch info {"sessionId": "79112c62", "epoch_id": 5, "peer": "12D3KooWDAFRuFrMNgVQMDy8cgD71GLtPyYyfQzFxMZr2yUBgjHK", "atxs": 29822} 2024-11-21T16:37:09.488-0500 INFO node.sync downloaded epoch info {"sessionId": "79112c62", "epoch_id": 5, "peer": "12D3KooWRcTWDHzptnhJn5h6CtwnokzzMaDLcXv6oM9CxQEXd5FL", "atxs": 29822} 2024-11-21T16:39:52.947-0500 INFO node.nipostValidator selecting 0 provider from 2 available {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 380} 2024-11-21T16:39:52.947-0500 INFO node.nipostValidator Using provider: [GPU] Intel(R) OpenCL HD Graphics/Intel(R) UHD Graphics 620 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 393} 2024-11-21T16:39:52.947-0500 INFO node.nipostValidator device memory: 9767 MB, max_mem_alloc_size: 4095 MB, max_compute_units: 24, max_wg_size: 256 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 159} 2024-11-21T16:40:01.930-0500 INFO node.nipostValidator preferred_wg_size_multiple: 8, kernel_wg_size: 256 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 199} 2024-11-21T16:40:01.930-0500 INFO node.nipostValidator Using: global_work_size: 8184, local_work_size: 8 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 212} 2024-11-21T16:40:01.930-0500 INFO node.nipostValidator Allocating buffer for input: 32 bytes {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 216} 2024-11-21T16:40:01.930-0500 INFO node.nipostValidator Allocating buffer for output: 261888 bytes {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 224} 2024-11-21T16:40:01.930-0500 INFO node.nipostValidator Allocating buffer for lookup: 4290772992 bytes {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 237} 2024-11-21T16:40:21.835-0500 INFO node.nipostValidator selecting 1 provider from 2 available {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 380} 2024-11-21T16:40:21.835-0500 INFO node.nipostValidator Using provider: [GPU] AMD Accelerated Parallel Processing/gfx803 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 393} 2024-11-21T16:40:21.835-0500 INFO node.nipostValidator device memory: 2048 MB, max_mem_alloc_size: 1523 MB, max_compute_units: 8, max_wg_size: 256 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 159} 2024-11-21T16:40:28.277-0500 INFO node.nipostValidator preferred_wg_size_multiple: 64, kernel_wg_size: 256 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 199} 2024-11-21T16:40:28.277-0500 INFO node.nipostValidator Using: global_work_size: 3008, local_work_size: 64 {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 212} 2024-11-21T16:40:28.277-0500 INFO node.nipostValidator Allocating buffer for input: 32 bytes {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 216} 2024-11-21T16:40:28.277-0500 INFO node.nipostValidator Allocating buffer for output: 96256 bytes {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 224} 2024-11-21T16:40:28.278-0500 INFO node.nipostValidator Allocating buffer for lookup: 1577058304 bytes {"module": "scrypt_ocl", "file": "scrypt-ocl\src\lib.rs", "line": 237} 2024-11-21T16:40:52.592-0500 INFO node App version: v1.7.7. Git: e5c138c - e5c138cc699f6bf20a07ea69f95f5478479db96f . Go Version: go1.23.2. OS: windows-amd64 . Genesis 9eebff023abb17ccb775c602daade8ed708f0a50 2024-11-21T16:40:52.592-0500 INFO node Welcome to Spacemesh. Spacemesh full node is starting... 2024-11-21T16:40:52.593-0500 INFO node Loaded existing identity {"filename": "local.key", "public_key": "54139", "name": ""} 2024-11-21T16:40:52.606-0500 INFO node starting spacemesh {"data-dir": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b", "post-dir": "C:\Users\HPDRAGONELITE\post\7c8cef2b", "hostname": "DESKTOP-JHRUD43", "name": ""} 2024-11-21T16:40:52.606-0500 INFO node.clock converting genesis time to local time {"genesis": "2023-07-14T08:00:00.000Z", "local": "2023-07-14T04:00:00.000-0400"} 2024-11-21T16:40:52.606-0500 INFO node initializing p2p services 2024-11-21T16:40:52.606-0500 INFO node.clock starting global clock {"now": "2024-11-21T16:40:52.606-0500", "genesis": "2023-07-14T04:00:00.000-0400", "layer_duration": "5m0s", "tick_interval": "1s"} 2024-11-21T16:40:52.709-0500 INFO node starting cache warmup 2024-11-21T16:40:52.709-0500 INFO node.warmup Reading ATXs from DB {"from epoch": 0, "to epoch": 5} 2024-11-21T16:40:53.136-0500 INFO node.warmup Finished reading ATXs. Starting reading malfeasance {"duration": "426.8416ms"} 2024-11-21T16:40:53.136-0500 INFO node.warmup Finished reading malfeasance {"duration": "0s"} 2024-11-21T16:40:53.136-0500 INFO node cache warmup {"duration": "426.8416ms", "name": ""} 2024-11-21T16:40:53.136-0500 INFO node.cachedDB initialized datastore {"config": {"ATXSize":1000,"MalfeasanceSize":1000}} 2024-11-21T16:40:53.599-0500 INFO node.nipostValidator scaling post verifier {"current": 0, "new": 4} 2024-11-21T16:40:53.601-0500 INFO node.beacon registered signing key {"node_id": "54139d"} 2024-11-21T16:40:53.601-0500 INFO node initializing tortoise 2024-11-21T16:40:53.637-0500 INFO node tortoise initialized {"duration": "35.3542ms", "name": ""} 2024-11-21T16:40:53.654-0500 INFO node.proposalStore proposals store created {"capacity": 3, "evicted": 143012} 2024-11-21T16:40:53.654-0500 INFO node.atxHandler atx handler created {"supported ATX versions": ["vV1 from epoch 0"]} 2024-11-21T16:40:53.654-0500 INFO node.atxHandler registered signing key {"id": "54139d"} 2024-11-21T16:40:53.654-0500 INFO node.hareOracle hare oracle initialized {"epoch size": 4032, "confidence param": 200} 2024-11-21T16:40:53.654-0500 INFO node.blockCert registered signing key {"id": "54139d"} 2024-11-21T16:40:53.654-0500 INFO node.hare registered signing key {"id": "54139d"} 2024-11-21T16:40:53.654-0500 INFO node.hare started {"enabled": true, "enabled layer": 35117, "disabled layer": 4294967295, "committee": 400, "committee upgrade layer": 105720, "committee upgrade size": 50, "leaders": 5, "iterations limit": 4, "preround delay": "25s", "round duration": "12s", "log stats": false, "p2p protocol": "/h/3.0", "enabled layer": 143013, "disabled layer": 4294967295} 2024-11-21T16:40:53.654-0500 INFO node.proposalBuilder registered signing key {"id": "54139d"} 2024-11-21T16:40:53.655-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-0.spacemesh.network", "pubkey": "cFnqCS5oER7GOX576oPtahlxB/1y95aDibdK7RHQFVg=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:40:53.655-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-0.spacemesh.network/v1/info"} 2024-11-21T16:40:53.870-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-0.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:40:53.871-0500 INFO node.poet created poet client {"url": "https://mainnet-poet-1.spacemesh.network", "pubkey": "Qh1efxY4YhoYBEXKPTiHJ/a7n1GsllRSyweQKO3j7m0=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:40:53.871-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://mainnet-poet-1.spacemesh.network/v1/info"} 2024-11-21T16:40:54.026-0500 DEBUG node.poet response received {"url": "https://mainnet-poet-1.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:40:54.026-0500 INFO node.poet created poet client {"url": "https://poet-110.spacemesh.network", "pubkey": "8Qqgid+37eyY7ik+EA47Nd5TrQjXolbv2Mdgir243No=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:40:54.026-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-110.spacemesh.network/v1/info"} 2024-11-21T16:40:54.193-0500 DEBUG node.poet response received {"url": "https://poet-110.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:40:54.195-0500 INFO node.poet created poet client {"url": "https://poet-111.spacemesh.network", "pubkey": "caIV0Ym59L3RqbVAL6UrCPwr+z+lwe2TBj57QWnAgtM=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:40:54.195-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-111.spacemesh.network/v1/info"} 2024-11-21T16:40:54.397-0500 DEBUG node.poet response received {"url": "https://poet-111.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:40:54.398-0500 INFO node.poet created poet client {"url": "https://poet-112.spacemesh.network", "pubkey": "5p/mPvmqhwdvf8U0GVrNq/9IN/HmZj5hCkFLAN04g1E=", "default max retries": 10, "submit challenge max retries": 9223372036854775807, "min retry wait": "10s", "max retry wait": "20s"} 2024-11-21T16:40:54.398-0500 DEBUG node.poet performing request {"method": "GET", "url": "https://poet-112.spacemesh.network/v1/info"} 2024-11-21T16:40:54.615-0500 DEBUG node.poet response received {"url": "https://poet-112.spacemesh.network/v1/info", "status": 200} 2024-11-21T16:40:54.708-0500 INFO node.sync starting syncer loop 2024-11-21T16:40:54.708-0500 INFO node.sync starting syncer layer processing loop 2024-11-21T16:40:54.708-0500 INFO node.beacon beacon protocol disabled 2024-11-21T16:40:54.708-0500 INFO node no need to preserve data after recovery 2024-11-21T16:40:54.708-0500 WARN node.grpc unsecured grpc server is listening on a public IP address {"address": "0.0.0.0:9092"} 2024-11-21T16:40:54.710-0500 INFO node app started 2024-11-21T16:40:54.710-0500 INFO node.post preparing post initializer {"opts": {"DataDir":"C:\Users\HPDRAGONELITE\post\7c8cef2b","NumUnits":8,"MaxFileSize":2147483648,"ProviderID":{},"Throttle":false,"Scrypt":{"N":8192,"R":1,"P":1},"ComputeBatchSize":1048576}} 2024-11-21T16:40:54.710-0500 INFO node.proposalBuilder started {"layer size": 50, "epoch size": 4032, "hdist": 10, "network delay": "30m0s", "good atx percent": 90, "active set window": "1h0m0s", "active set retry interval": "1m0s", "active set tries": 20, "next": 143013} 2024-11-21T16:40:54.710-0500 INFO node.post waiting for ATXs to sync before selecting commitment ATX 2024-11-21T16:41:19.646-0500 INFO node.bootstrap loaded bootstrap file {"persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\34\epoch-34-update-bs", "epoch": "34", "beacon": "9cc502f2", "activeset_size": 5400442} 2024-11-21T16:41:19.646-0500 INFO node.bootstrap loaded bootstrap file {"persisted": "C:\Users\HPDRAGONELITE\AppData\Roaming\Spacemesh\node-data\7c8cef2b\bootstrap\35\epoch-35-update-bs", "epoch": "35", "beacon": "07a1d4a6", "activeset_size": 4965738} 2024-11-21T16:41:19.646-0500 INFO node.bootstrap start listening to update {"source": "https://bootstrap.spacemesh.network/mainnet", "interval": "1m0s"} 2024-11-21T16:41:19.646-0500 INFO node.beacon using fallback beacon {"epoch": 34, "beacon": "9cc502f2"} 2024-11-21T16:41:20.729-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 34, "size": 5400442} 2024-11-21T16:41:20.729-0500 INFO node.beacon using fallback beacon {"epoch": 35, "beacon": "07a1d4a6"} 2024-11-21T16:41:21.524-0500 INFO node.proposalBuilder received trusted activeset update {"epoch_id": 35, "size": 4965738} 2024-11-21T16:41:54.703-0500 INFO node.sync node is too far behind {"sessionId": "c5249bca", "current": "143012", "last synced": "8063", "behind threshold": 36} 2024-11-21T16:41:54.784-0500 INFO node.sync starting atx sync {"sessionId": "c5249bca", "epoch_id": 5} 2024-11-21T16:42:32.622-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 3371, "total": 29822, "progress": 3371, "rate per sec": 89.08281441536887} 2024-11-21T16:45:29.339-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 6847, "total": 29822, "progress": 3476, "rate per sec": 19.667900217074205} 2024-11-21T16:46:43.741-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 10289, "total": 29822, "progress": 3442, "rate per sec": 46.25764404376019} 2024-11-21T16:47:22.674-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 13583, "total": 29822, "progress": 3294, "rate per sec": 84.59638270952563} 2024-11-21T16:48:03.489-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 17440, "total": 29822, "progress": 3857, "rate per sec": 94.49066274481827} 2024-11-21T16:48:44.446-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 20424, "total": 29822, "progress": 2984, "rate per sec": 72.85048183709071} 2024-11-21T16:49:46.739-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 27731, "total": 29822, "progress": 7307, "rate per sec": 117.28801994979491} 2024-11-21T16:50:19.951-0500 INFO node.sync atx sync completed {"sessionId": "c5249bca", "epoch_id": 5, "downloaded": 29822, "total": 29822, "unavailable": 0, "duration": "8m25.2175142s"} 2024-11-21T16:50:19.951-0500 INFO node.sync starting atx sync {"sessionId": "c5249bca", "epoch_id": 6} 2024-11-21T16:50:22.078-0500 INFO node.sync downloaded epoch info {"sessionId": "c5249bca", "epoch_id": 6, "peer": "12D3KooWDAFRuFrMNgVQMDy8cgD71GLtPyYyfQzFxMZr2yUBgjHK", "atxs": 49810} 2024-11-21T16:50:23.281-0500 INFO node.sync downloaded epoch info {"sessionId": "c5249bca", "epoch_id": 6, "peer": "12D3KooWFxqvP8jw8TAZo7T4ns9pJoneUt35Kz3zwUxUAGuw4xNm", "atxs": 49810} 2024-11-21T16:56:10.885-0500 INFO node.sync atx sync progress {"sessionId": "c5249bca", "epoch_id": 6, "downloaded": 6114, "total": 49810, "progress": 6114, "rate per sec": 17.42043422952781}

brusherru commented 5 days ago

Looks like a bug in the profiler tool. @poszu could you take a look?

brusherru commented 5 days ago

@Ramiro2-del thanks for the report. Could you try out this version of post-service, please: https://github.com/spacemeshos/post-rs/actions/runs/11976245536/artifacts/2225314219

Here is a guide what to do:

  1. Download that archive and unpack
  2. Open the Smapp program directory (C:\Users\{USERNAME}\AppData\Local\Programs\Spacemesh or your installation path, you can also click with right mouse button on the link and pick "Open File Location")
  3. Go to node directory there
  4. Replace current post-service.exe with the downloaded one
  5. Run Smapp again

Please let me know does it help or not 🙏

Ramiro2-del commented 4 days ago

@brusherru replaced the post-service.exe and ran the Smap again and give me the same error:

PoS profiler exited with code 101: thread 'main' panicked at src\pow\mod.rs:25:1: MockProver::par(): No matching expectation found note: run with RUST_BACKTRACE=1 environment variable to display a backtrace

brusherru commented 1 day ago

@Ramiro2-del sorry, I understood that I forgot to give you another one important file. Please download that archive: https://github.com/spacemeshos/post-rs/actions/runs/11976245536/artifacts/2225311696 It contains fixed profiler.exe, please copy it to the same location as mentioned above (next to go-spacemesh.exe). Please, check it out (using the same guide above), and let me know if it helps 🙏

p.s. A little explanation: post-service that you have already updated will be used later to generate a proof, but particularly for running that benchmarks, where you get an error, you need to update profiler.exe which is located in the same path

Ramiro2-del commented 18 hours ago

@brusherru nice that fixed the issue!