gistfile1.txt

=== CONT  TestBootstrapMineOnce
=== CONT  TestSingleMiner
2020-03-13T15:51:52.876Z	INFO	iptbtester	iptbtester/wrapper.go:38	Creating IPTB Testbed. count: 1, type: localfilecoin, dir: iptb-testnode
2020-03-13T15:51:53.027Z	INFO	badger	v2@v2.0.1-rc1.0.20200120142413-c3333a5a830e/logger.go:46	All 1 tables opened in 0s

2020-03-13T15:51:53.027Z	INFO	badger	v2@v2.0.1-rc1.0.20200120142413-c3333a5a830e/logger.go:46	Replaying file id: 0 at offset: 284

2020-03-13T15:51:53.027Z	INFO	badger	v2@v2.0.1-rc1.0.20200120142413-c3333a5a830e/logger.go:46	Replay took: 7.478µs

2020-03-13T15:51:53.029Z	INFO	test	functional-tests/mining_chain_test.go:49	Owner/worker address: t3qx4zrflimpjboxn3ivs6t4eel7frijxhcdvae6z6dtnocvz54yeop2kfdxggn7dfhl72cqqhsf6nf5fgkl3a
2020-03-13T15:51:53.029Z	INFO	chain.store	chain/store.go:143	start loading chain at tipset: { bafy2bzaceadsuxrov7li3kabt2lwk7sbtuf2qg35reecnjdkyr5ckty2feolq }, height: 0
2020-03-13T15:51:53.029Z	INFO	chain.store	chain/store.go:188	finished loading 0 tipsets from { bafy2bzaceadsuxrov7li3kabt2lwk7sbtuf2qg35reecnjdkyr5ckty2feolq }
2020-03-13T15:51:53.030Z	INFO	mining	mining/worker.go:165	Worker.Mine
2020-03-13T15:51:53.040 INFO filecoin::proofs::api > generate_candidates: start
2020-03-13T15:51:53.041 INFO filecoin_proofs::api::post > generate_candidates:start
2020-03-13T15:51:53.662Z	INFO	localfilecoin	local/localfilecoin.go:500	Node: /tmp/iptb-testnode156294798/0 Started daemon: /tmp/iptb-testnode156294798/0, pid: 23754
2020-03-13T15:51:53.662Z	WARN	util	filecoin/util.go:27	open /tmp/iptb-testnode156294798/0/repo/api: no such file or directory
2020-03-13T15:51:53.843 INFO filecoin_proofs::api::post > generate_candidates:finish
2020-03-13T15:51:53.843 INFO filecoin::proofs::api > generate_candidates: finish
2020-03-13T15:51:53.843Z	INFO	consensus.expected	consensus/election.go:66	GenerateEPoSt:
[
  {
    "RegisteredProof": 3,
    "SectorNumber": 3,
    "SealedCID": {
      "/": "bafk4ehzaqugph7z7yajugw6wz6nn7x3akr3e32wecwexllfgqndnjt3nsaya"
    }
  },
  {
    "RegisteredProof": 3,
    "SectorNumber": 4,
    "SealedCID": {
      "/": "bafk4ehza24k4j256kulsu34e4szjvmsbytibrx7kjzajc6c6rlcn2jfosada"
    }
  }
]
"W0Y3a8gFP3Ap2Xsez6iJJxx9I7k/dBXbm9PhY6QnWgA="
[
  {
    "RegisteredProof": 3,
    "PartialTicket": "BIrWDbDgx+S69/mFRy7W1ypLqEcURhWKWIDRA5FSnkU=",
    "PrivateProof": {
      "RegisteredProof": 0,
      "Externalized": null
    },
    "SectorID": {
      "Miner": 106,
      "Number": 3
    },
    "ChallengeIndex": 0
  }
]
2020-03-13T15:51:53.844 INFO filecoin::proofs::api > generate_post: start
2020-03-13T15:51:53.844 INFO filecoin_proofs::api::post > generate_post:start
2020-03-13T15:51:53.844 INFO filecoin_proofs::caches > trying parameters memory cache for: POST[2048]
2020-03-13T15:51:53.844 INFO filecoin_proofs::caches > no params in memory cache for POST[2048]
2020-03-13T15:51:56.889 INFO storage_proofs::parameter_cache > parameter set identifier for cache: ElectionPoSt::PublicParams{sector_size: 2048, count: 65, nodes: 1}
2020-03-13T15:51:56.889 INFO storage_proofs::parameter_cache > ensuring that all ancestor directories for: "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params" exist
2020-03-13T15:51:56.890 INFO storage_proofs::parameter_cache > checking cache_path: "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params" for parameters
2020-03-13T15:51:56.890 INFO storage_proofs::parameter_cache > Actually generating groth params. (id: proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef)
2020-03-13T15:52:09.413 INFO storage_proofs::parameter_cache > groth_parameter_generation_time: 12.522667011s (id: proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef)
2020-03-13T15:52:10.426 INFO storage_proofs::parameter_cache > wrote groth parameters to cache "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params"
2020-03-13T15:52:10.428 INFO storage_proofs::parameter_cache > checking cache_path: "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params" for parameters
2020-03-13T15:52:10.467 INFO storage_proofs::parameter_cache > read parameters from cache "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params"
2020-03-13T15:52:10.468 INFO storage_proofs::compound_proof > vanilla_proof:start
2020-03-13T15:52:10.468 INFO storage_proofs::proof > groth_proof_count: 1
2020-03-13T15:52:10.468 INFO storage_proofs::proof > generating 1 groth proofs.
2020-03-13T15:52:10.468 INFO storage_proofs::proof > generating groth proof 0.
2020-03-13T15:52:10.518 INFO storage_proofs::proof > groth_proof_time: 49.857434ms
2020-03-13T15:52:10.518 INFO storage_proofs::proof > total_groth_proof_time: 49.904688ms
2020-03-13T15:52:10.518 INFO storage_proofs::compound_proof > vanilla_proof:finish
2020-03-13T15:52:10.532 INFO storage_proofs::compound_proof > snark_proof:start
2020-03-13T15:52:10.533 INFO bellperson::groth16::prover > Bellperson 0.6.2 is being used!
2020-03-13T15:52:12.217 INFO bellperson::gpu::locks > GPU is available for FFT!
2020-03-13T15:52:22.218 WARN bellperson::domain > Cannot instantiate GPU FFT kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.262 INFO bellperson::gpu::locks > GPU is available for FFT!
2020-03-13T15:52:22.262 WARN bellperson::domain > Cannot instantiate GPU FFT kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.298 INFO bellperson::gpu::locks > GPU is available for FFT!
2020-03-13T15:52:22.298 WARN bellperson::domain > Cannot instantiate GPU FFT kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.339 INFO bellperson::gpu::locks > GPU is available for FFT!
2020-03-13T15:52:22.339 WARN bellperson::domain > Cannot instantiate GPU FFT kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.384 INFO bellperson::gpu::locks > GPU is available for FFT!
2020-03-13T15:52:22.384 WARN bellperson::domain > Cannot instantiate GPU FFT kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.438 INFO bellperson::gpu::locks > GPU is available for FFT!
2020-03-13T15:52:22.438 WARN bellperson::domain > Cannot instantiate GPU FFT kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.498 INFO bellperson::gpu::locks > GPU is available for FFT!
2020-03-13T15:52:22.498 WARN bellperson::domain > Cannot instantiate GPU FFT kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.580 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:22.580 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:22.838 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:22.838 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:23.008 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:23.008 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:23.009 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:23.009 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:23.131 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:23.131 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:23.131 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:23.131 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:23.280 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:23.281 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:23.281 INFO bellperson::gpu::locks > GPU is available for Multiexp!
2020-03-13T15:52:23.281 WARN bellperson::multiexp > Cannot instantiate GPU Multiexp kernel! Error: GPUError: No working GPUs found!
2020-03-13T15:52:23.627 INFO storage_proofs::compound_proof > snark_proof:finish
2020-03-13T15:52:23.627 INFO filecoin_proofs::api::post > generate_post:finish
2020-03-13T15:52:23.627 INFO filecoin::proofs::api > generate_post: finish
proofs.go VerifyPoSt:
{
  "Randomness": "W0Y3a8gFP3Ap2Xsez6iJJxx9I7k/dBXbm9PhY6QnWgA=",
  "Candidates": [
    {
      "RegisteredProof": 3,
      "PartialTicket": "BIrWDbDgx+S69/mFRy7W1ypLqEcURhWKWIDRA5FSnkU=",
      "PrivateProof": {
        "RegisteredProof": 0,
        "Externalized": null
      },
      "SectorID": {
        "Miner": 106,
        "Number": 3
      },
      "ChallengeIndex": 0
    }
  ],
  "Proofs": [
    {
      "RegisteredProof": 4,
      "ProofBytes": "jaHVOXjFxybWZa+KNgzerAS8q9Q/BldGPWvyu95P6Fp5g9d5VcogG4ebgXN8Stz7ttQDGXtwHUci9UR8ZeIRaOSs2G2/v4qe89U1ybWNEZfAbQxapP/AdA3lx9VpmagWGG+HGS378kQv1Sl2Qn7CtvxmnqMCbJvjubuNnScg0FK2OyAXrDhkynFmRTYTw+GntD/nNyZ360k5ZQ/pNCDMpuv3UwnD/eSitodW5LBYZBW7QI4WzyadcT8QRK2IlRu4"
    }
  ],
  "EligibleSectors": [
    {
      "RegisteredProof": 4,
      "SectorNumber": 3,
      "SealedCID": {
        "/": "bafk4ehzaqugph7z7yajugw6wz6nn7x3akr3e32wecwexllfgqndnjt3nsaya"
      }
    },
    {
      "RegisteredProof": 4,
      "SectorNumber": 4,
      "SealedCID": {
        "/": "bafk4ehza24k4j256kulsu34e4szjvmsbytibrx7kjzajc6c6rlcn2jfosada"
      }
    }
  ],
  "Prover": 106,
  "ChallengeCount": 1
}
2020-03-13T15:52:23.627 INFO filecoin::proofs::api > verify_post: start
2020-03-13T15:52:23.627 INFO filecoin_proofs::api::post > verify_post:start
2020-03-13T15:52:23.627 INFO filecoin_proofs::caches > trying parameters memory cache for: POST[2048]-verifying-key
2020-03-13T15:52:23.627 INFO filecoin_proofs::caches > no params in memory cache for POST[2048]-verifying-key
2020-03-13T15:52:23.627 INFO storage_proofs::parameter_cache > parameter set identifier for cache: ElectionPoSt::PublicParams{sector_size: 2048, count: 65, nodes: 1}
2020-03-13T15:52:23.627 INFO storage_proofs::parameter_cache > ensuring that all ancestor directories for: "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.vk" exist
2020-03-13T15:52:23.627 INFO storage_proofs::parameter_cache > checking cache_path: "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.vk" for verifying key
2020-03-13T15:52:23.627 INFO storage_proofs::parameter_cache > parameter set identifier for cache: ElectionPoSt::PublicParams{sector_size: 2048, count: 65, nodes: 1}
2020-03-13T15:52:23.627 INFO storage_proofs::parameter_cache > ensuring that all ancestor directories for: "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params" exist
2020-03-13T15:52:23.627 INFO storage_proofs::parameter_cache > checking cache_path: "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params" for parameters
2020-03-13T15:52:23.651 INFO storage_proofs::parameter_cache > read parameters from cache "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.params"
2020-03-13T15:52:23.651 INFO storage_proofs::parameter_cache > Getting verifying key. (id: proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef)
2020-03-13T15:52:23.651 INFO storage_proofs::parameter_cache > wrote verifying key to cache "/var/tmp/filecoin-proof-parameters/v23-proof-of-spacetime-election-PoseidonHasher-5916054ae98e28fc2f0470d1fb58eb875a6865be86f0b8c4e302d55f13217fef.vk"
2020-03-13T15:52:23.671 INFO filecoin::proofs::api > verify_post: finish
panic: proofs we just created failed to verify: {
 "Randomness": "W0Y3a8gFP3Ap2Xsez6iJJxx9I7k/dBXbm9PhY6QnWgA=",
 "Candidates": [
  {
   "RegisteredProof": 3,
   "PartialTicket": "BIrWDbDgx+S69/mFRy7W1ypLqEcURhWKWIDRA5FSnkU=",
   "PrivateProof": {
    "RegisteredProof": 0,
    "Externalized": null
   },
   "SectorID": {
    "Miner": 106,
    "Number": 3
   },
   "ChallengeIndex": 0
  }
 ],
 "Proofs": [
  {
   "RegisteredProof": 4,
   "ProofBytes": "jaHVOXjFxybWZa+KNgzerAS8q9Q/BldGPWvyu95P6Fp5g9d5VcogG4ebgXN8Stz7ttQDGXtwHUci9UR8ZeIRaOSs2G2/v4qe89U1ybWNEZfAbQxapP/AdA3lx9VpmagWGG+HGS378kQv1Sl2Qn7CtvxmnqMCbJvjubuNnScg0FK2OyAXrDhkynFmRTYTw+GntD/nNyZ360k5ZQ/pNCDMpuv3UwnD/eSitodW5LBYZBW7QI4WzyadcT8QRK2IlRu4"
  }
 ],
 "EligibleSectors": [
  {
   "RegisteredProof": 4,
   "SectorNumber": 3,
   "SealedCID": {
    "/": "bafk4ehzaqugph7z7yajugw6wz6nn7x3akr3e32wecwexllfgqndnjt3nsaya"
   }
  },
  {
   "RegisteredProof": 4,
   "SectorNumber": 4,
   "SealedCID": {
    "/": "bafk4ehza24k4j256kulsu34e4szjvmsbytibrx7kjzajc6c6rlcn2jfosada"
   }
  }
 ],
 "Prover": 106,
 "ChallengeCount": 1
}

goroutine 314 [running]:
github.com/filecoin-project/filecoin-ffi.GeneratePoSt(0x6a, 0xc00028eab0, 0x2, 0x2, 0xc000384420, 0x20, 0x20, 0xc01dba2540, 0x1, 0x1, ...)
	/go/src/github.com/filecoin-project/go-filecoin/vendors/filecoin-ffi/proofs.go:821 +0xa91
github.com/filecoin-project/go-sectorbuilder.(*SectorBuilder).ComputeElectionPoSt(0xc000210900, 0xc000489040, 0x2, 0x2, 0xc000384420, 0x20, 0x20, 0xc01dba2540, 0x1, 0x1, ...)
	/go/pkg/mod/github.com/filecoin-project/go-sectorbuilder@v0.0.2-0.20200306003553-541b1e74104d/sectorbuild_cgo.go:398 +0x191
github.com/filecoin-project/go-filecoin/internal/pkg/postgenerator.(*SectorBuilderBackEnd).ComputeElectionPoSt(0xc000344e10, 0xc000489040, 0x2, 0x2, 0xc000384420, 0x20, 0x20, 0xc01dba2540, 0x1, 0x1, ...)
	/go/src/github.com/filecoin-project/go-filecoin/internal/pkg/postgenerator/sector_builder_back_end.go:42 +0xaf
github.com/filecoin-project/go-filecoin/internal/pkg/consensus.ElectionMachine.GenerateEPoSt(0x255e640, 0xc0004a4258, 0xc000489040, 0x2, 0x2, 0xc000384420, 0x20, 0x20, 0xc01dba2540, 0x1, ...)
	/go/src/github.com/filecoin-project/go-filecoin/internal/pkg/consensus/election.go:67 +0x3c1
github.com/filecoin-project/go-filecoin/internal/pkg/mining.(*DefaultWorker).Mine.func2(0xc0002caf60, 0xc0003007d8, 0xc00020a1c0, 0xc000489040, 0x2, 0x2, 0xc000384420, 0xc01dba2540, 0x1, 0x1)
	/go/src/github.com/filecoin-project/go-filecoin/internal/pkg/mining/worker.go:303 +0x17b
created by github.com/filecoin-project/go-filecoin/internal/pkg/mining.(*DefaultWorker).Mine
	/go/src/github.com/filecoin-project/go-filecoin/internal/pkg/mining/worker.go:300 +0x16bd
FAIL	github.com/filecoin-project/go-filecoin/functional-tests	30.976s