``` 2020-11-23 12:07:44.695 UTC [deliveryClient] StartDeliverForChannel -> INFO 2be This peer will retrieve blocks from ordering service and disseminate to other peers in the organization for channel somenewchannel 2020-11-23 12:07:44.695 UTC [gossip.election] leaderElection -> DEBU 2bf 5fecb3feb72c3c20fcd74030e3f53f2c0ca1216284a649557be1564be748a0a6 : Exiting 2020-11-23 12:07:44.695 UTC [gossip.election] IsLeader -> DEBU 2c0 5fecb3feb72c3c20fcd74030e3f53f2c0ca1216284a649557be1564be748a0a6 : Returning true 2020-11-23 12:07:44.696 UTC [msp.identity] Sign -> DEBU 2c1 Sign: plaintext: 120E736F6D656E65776368616E6E656C...120C08A7E2A8D386B688A51610021801 2020-11-23 12:07:44.696 UTC [msp.identity] Sign -> DEBU 2c2 Sign: digest: 7395B8C8CA8DBEB31D89BFA8963AEF9FC66496C77BDADCCB14D3AB37D3E9C539 2020-11-23 12:07:44.696 UTC [gossip.election] waitForInterrupt -> DEBU 2c3 5fecb3feb72c3c20fcd74030e3f53f2c0ca1216284a649557be1564be748a0a6 : Entering 2020-11-23 12:07:44.696 UTC [msp.identity] Sign -> DEBU 2c4 Sign: plaintext: 0AED060A1A08051A060890CCEEFD0522...01120D1A0B08FFFFFFFFFFFFFFFFFF01 2020-11-23 12:07:44.697 UTC [msp.identity] Sign -> DEBU 2c5 Sign: digest: 463BCADB605776F7A0FBD956D5C4A96EE872E47CEAFF4531C2D0A1AC1854CFBD 2020-11-23 12:07:44.697 UTC [grpc] Infof -> DEBU 2c6 parsed scheme: "" 2020-11-23 12:07:44.697 UTC [grpc] Infof -> DEBU 2c7 scheme "" not registered, fallback to default scheme 2020-11-23 12:07:44.697 UTC [grpc] Infof -> DEBU 2c8 ccResolverWrapper: sending update to cc: {[{nd-229-063-752.rg-488-419.p2pify.com:7050 0 }] } 2020-11-23 12:07:44.697 UTC [grpc] Infof -> DEBU 2c9 ClientConn switching balancer to "pick_first" 2020-11-23 12:07:44.698 UTC [grpc] Infof -> DEBU 2ca Channel switches to new LB policy "pick_first" 2020-11-23 12:07:44.698 UTC [grpc] Infof -> DEBU 2cb Subchannel Connectivity change to CONNECTING 2020-11-23 12:07:44.698 UTC [grpc] Infof -> DEBU 2cc Subchannel picks a new address "nd-229-063-752.rg-488-419.p2pify.com:7050" to connect 2020-11-23 12:07:44.698 UTC [grpc] UpdateSubConnState -> DEBU 2cd pickfirstBalancer: HandleSubConnStateChange: 0xc003747460, {CONNECTING } 2020-11-23 12:07:44.698 UTC [grpc] Infof -> DEBU 2ce Channel Connectivity change to CONNECTING 2020-11-23 12:07:44.767 UTC [grpc] Infof -> DEBU 2cf Subchannel Connectivity change to READY 2020-11-23 12:07:44.767 UTC [grpc] Infof -> DEBU 2d0 Subchannel Connectivity change to READY 2020-11-23 12:07:44.768 UTC [grpc] UpdateSubConnState -> DEBU 2d2 pickfirstBalancer: HandleSubConnStateChange: 0xc00388f550, {READY } 2020-11-23 12:07:44.768 UTC [grpc] Infof -> DEBU 2d3 Channel Connectivity change to READY 2020-11-23 12:07:44.768 UTC [grpc] UpdateSubConnState -> DEBU 2d1 pickfirstBalancer: HandleSubConnStateChange: 0xc003747460, {READY } 2020-11-23 12:07:44.768 UTC [grpc] Infof -> DEBU 2d4 Channel Connectivity change to READY 2020-11-23 12:07:44.775 UTC [peer.gossip.mcs] VerifyBlock -> DEBU 2d5 Got policy manager for channel [defaultchannel] 2020-11-23 12:07:44.775 UTC [peer.gossip.mcs] VerifyBlock -> DEBU 2d6 Got block validation policy for channel [defaultchannel] with flag [true] 2020-11-23 12:07:44.775 UTC [policies] EvaluateSignedData -> DEBU 2d7 == Evaluating *policies.ImplicitMetaPolicy Policy /Channel/Orderer/BlockValidation == 2020-11-23 12:07:44.775 UTC [policies] EvaluateSignedData -> DEBU 2d8 This is an implicit meta policy, it will trigger other policy evaluations, whose failures may be benign 2020-11-23 12:07:44.775 UTC [policies] EvaluateSignedData -> DEBU 2d9 == Evaluating *cauthdsl.policy Policy /Channel/Orderer/rg-488-419-ord/Writers == 2020-11-23 12:07:44.775 UTC [msp] DeserializeIdentity -> DEBU 2da Obtaining identity 2020-11-23 12:07:44.775 UTC [msp.identity] newIdentity -> DEBU 2db Creating identity instance for cert -----BEGIN CERTIFICATE----- MIICIDCCAcagAwIBAgIRAK/3vdpqBV8pOASN/A+GiKswCgYIKoZIzj0EAwIwdDEL MAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9ydGxhbmQx HjAcBgNVBAoTFXJnLTQ4OC00MTkucDJwaWZ5LmNvbTEhMB8GA1UEAxMYY2Eucmct NDg4LTQxOS5wMnBpZnkuY29tMB4XDTIwMTExMTE1MzgwMFoXDTMwMTEwOTE1Mzgw MFowYDELMAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9y dGxhbmQxLTArBgNVBAMTJG5kLTIyOS0wNjMtNzUyLnJnLTQ4OC00MTkucDJwaWZ5 LmNvbTBZMBMGByqGSM49AgEGCCqGSM49AwEHA0IABFQDR9luj8Qf2qMsbbdEuiG4 l1R4LPqromtv72pv/ngU+6w2Nqi1qJpN9GtcfomgWg9jyWLsQRBSco6TY5GYGwOj TTBLMA4GA1UdDwEB/wQEAwIHgDAMBgNVHRMBAf8EAjAAMCsGA1UdIwQkMCKAIMpJ OVX5wniU/iSW+IyKx6Kz1+NvcV1yz/BRnsFtQFHjMAoGCCqGSM49BAMCA0gAMEUC IQD/v+H20bRsedxeCC2V0L4UjqMSQthv4MX223lDGulbBQIgbVHQzDmMJ1/4TrZQ ijfG9x2DOv3zPTCBIBkQ+JjuUak= -----END CERTIFICATE----- 2020-11-23 12:07:44.776 UTC [msp.identity] Verify -> DEBU 2dc Verify: digest = 00000000 0d 32 98 13 15 3f a4 98 f7 3b 4f 0e 54 d7 c7 f9 |.2...?...;O.T...| 00000010 c6 93 dc dc d3 f9 55 28 58 b2 e0 18 48 d5 a9 d4 |......U(X...H...| 2020-11-23 12:07:44.776 UTC [msp.identity] Verify -> DEBU 2dd Verify: sig = 00000000 30 44 02 20 79 16 a5 64 8d dc 2f bc f3 76 1a 69 |0D. y..d../..v.i| 00000010 e9 06 3a bf 37 fa 02 68 b8 f9 2d 7d 97 2c 90 34 |..:.7..h..-}.,.4| 00000020 cd 3a 11 58 02 20 47 ab 27 9a 70 5a 0a f9 b2 aa |.:.X. G.'.pZ....| 00000030 37 d8 c9 67 c4 60 36 3d 92 6d 0b 92 88 43 08 5d |7..g.`6=.m...C.]| 00000040 73 e6 6e ba 46 17 |s.n.F.| 2020-11-23 12:07:44.776 UTC [policies] SignatureSetToValidIdentities -> DEBU 2de signature for identity 0 validated 2020-11-23 12:07:44.776 UTC [cauthdsl] func1 -> DEBU 2df 0xc002a62c70 gate 1606133264776428095 evaluation starts 2020-11-23 12:07:44.776 UTC [cauthdsl] func2 -> DEBU 2e0 0xc002a62c70 signed by 0 principal evaluation starts (used [false]) 2020-11-23 12:07:44.776 UTC [cauthdsl] func2 -> DEBU 2e1 0xc002a62c70 processing identity 0 - &{RG-488-419-ORD-MSP 2338fa9cdcb18871e05995bf43856f33be5c87385e5ca3f15b5d6285c3036ee3} 2020-11-23 12:07:44.776 UTC [msp] satisfiesPrincipalInternalPreV13 -> DEBU 2e2 Checking if identity satisfies MEMBER role for RG-488-419-ORD-MSP 2020-11-23 12:07:44.776 UTC [msp] Validate -> DEBU 2e3 MSP RG-488-419-ORD-MSP validating identity 2020-11-23 12:07:44.776 UTC [cauthdsl] func2 -> DEBU 2e4 0xc002a62c70 principal evaluation succeeds for identity 0 2020-11-23 12:07:44.776 UTC [cauthdsl] func1 -> DEBU 2e5 0xc002a62c70 gate 1606133264776428095 evaluation succeeds 2020-11-23 12:07:44.776 UTC [policies] EvaluateSignedData -> DEBU 2e6 Signature set satisfies policy /Channel/Orderer/rg-488-419-ord/Writers 2020-11-23 12:07:44.776 UTC [policies] EvaluateSignedData -> DEBU 2e7 == Done Evaluating *cauthdsl.policy Policy /Channel/Orderer/rg-488-419-ord/Writers 2020-11-23 12:07:44.776 UTC [policies] EvaluateSignedData -> DEBU 2e8 Signature set satisfies policy /Channel/Orderer/BlockValidation 2020-11-23 12:07:44.776 UTC [policies] EvaluateSignedData -> DEBU 2e9 == Done Evaluating *policies.ImplicitMetaPolicy Policy /Channel/Orderer/BlockValidation 2020-11-23 12:07:44.777 UTC [peer.blocksprovider] processMsg -> DEBU 2ea Adding payload to local buffer, blockNum = [6138] channel=defaultchannel 2020-11-23 12:07:44.777 UTC [gossip.state] addPayload -> DEBU 2eb [defaultchannel] Adding payload to local buffer, blockNum = [6138] 2020-11-23 12:07:44.777 UTC [gossip.state] addPayload -> DEBU 2ec Blocks payloads buffer size for channel [defaultchannel] is 1 blocks 2020-11-23 12:07:44.777 UTC [peer.blocksprovider] processMsg -> DEBU 2ed Gossiping block [6138] channel=defaultchannel 2020-11-23 12:07:44.777 UTC [gossip.channel] AddToMsgStore -> DEBU 2ee Adding GossipMessage: Channel: defaultchannel, nonce: 0, tag: CHAN_AND_ORG Block message: {Data: 52080 bytes, seq: 6138}, Envelope: 52113 bytes, Signature: 0 bytes to the block puller 2020-11-23 12:07:44.777 UTC [gossip.pull] Add -> DEBU 2ef Added 6138, total items: 1 2020-11-23 12:07:44.777 UTC [gossip.state] deliverPayloads -> DEBU 2f0 [defaultchannel] Ready to transfer payloads (blocks) to the ledger, next block number is = [6138] 2020-11-23 12:07:44.777 UTC [gossip.state] deliverPayloads -> DEBU 2f1 [defaultchannel] Transferring block [6138] with 1 transaction(s) to the ledger 2020-11-23 12:07:44.777 UTC [gossip.privdata] StoreBlock -> INFO 2f2 [defaultchannel] Received block [6138] from buffer 2020-11-23 12:07:44.777 UTC [gossip.privdata] StoreBlock -> DEBU 2f3 [defaultchannel] Validating block [6138] 2020-11-23 12:07:44.777 UTC [committer.txvalidator] Validate -> DEBU 2f4 [defaultchannel] START Block Validation for block [6138] 2020-11-23 12:07:44.777 UTC [committer.txvalidator] Validate -> DEBU 2f5 expecting 1 block validation responses 2020-11-23 12:07:44.777 UTC [committer.txvalidator] validateTx -> DEBU 2f6 [defaultchannel] validateTx starts for block 0xc0003f5e00 env 0xc002a65180 txn 0 2020-11-23 12:07:44.777 UTC [protoutils] ValidateTransaction -> DEBU 2f7 ValidateTransactionEnvelope starts for envelope 0xc002a65180 2020-11-23 12:07:44.778 UTC [protoutils] ValidateTransaction -> DEBU 2f8 Header is channel_header:"\010\003\020\001\032\014\010\261\342\333\375\005\020\200\273\313\217\003\"\016defaultchannel*@b04315b1467322edb74296405c36480b2eb0d98b129bce081bf7a81397457792:\030\022\026\022\024wcc-smart-contract_0B \262\017m:\317(\233R\362\367\017\262\366\204`%g!\370\3274\317\246G\313\"\347PE\371\327[" signature_header:"\n\245\006\n\016RG-488-419-MSP\022\222\006-----BEGIN CERTIFICATE-----\nMIICFjCCAb2gAwIBAgIRANRNvwjK5f8ziGknL5BQ3xMwCgYIKoZIzj0EAwIwdDEL\nMAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9ydGxhbmQx\nHjAcBgNVBAoTFXJnLTQ4OC00MTkucDJwaWZ5LmNvbTEhMB8GA1UEAxMYY2Eucmct\nNDg4LTQxOS5wMnBpZnkuY29tMB4XDTIwMTExMTE1MzgwMFoXDTMwMTEwOTE1Mzgw\nMFowVzELMAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9y\ndGxhbmQxJDAiBgNVBAMMG0FkbWluQHJnLTQ4OC00MTkucDJwaWZ5LmNvbTBZMBMG\nByqGSM49AgEGCCqGSM49AwEHA0IABChqCtDw7kJW2moGlvX2rfWQTO6w0+mbYySE\n1tAd1TeAMC1uJPLY5mzhieIi4aV01cOD7X8M5u2t/l9mJ206zPSjTTBLMA4GA1Ud\nDwEB/wQEAwIHgDAMBgNVHRMBAf8EAjAAMCsGA1UdIwQkMCKAIECNQmx9w23JPryj\ncCp7KYhZ1wUk92blQK/i1KZqiDEsMAoGCCqGSM49BAMCA0cAMEQCIHyyeYyTtSEt\nclW/zsGBN4n2yJgpFVNZ1YYeMW7Dr9/QAiBZObUvzmpwEPKRpsNwybsu9TZQkUMP\nqKFsufn/Id/QSA==\n-----END CERTIFICATE-----\n\022\030-T\014\335{\254\366\267\327b\3626o\333\377J\032\n\224W\224\213\037\205" 2020-11-23 12:07:44.778 UTC [protoutils] validateChannelHeader -> DEBU 2f9 validateChannelHeader info: header type 3 2020-11-23 12:07:44.778 UTC [protoutils] checkSignatureFromCreator -> DEBU 2fa begin 2020-11-23 12:07:44.778 UTC [msp] GetManagerForChain -> DEBU 2fb Returning existing manager for channel 'defaultchannel' 2020-11-23 12:07:44.778 UTC [msp] DeserializeIdentity -> DEBU 2fc Obtaining identity 2020-11-23 12:07:44.778 UTC [msp.identity] newIdentity -> DEBU 2fd Creating identity instance for cert -----BEGIN CERTIFICATE----- MIICFjCCAb2gAwIBAgIRANRNvwjK5f8ziGknL5BQ3xMwCgYIKoZIzj0EAwIwdDEL MAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9ydGxhbmQx HjAcBgNVBAoTFXJnLTQ4OC00MTkucDJwaWZ5LmNvbTEhMB8GA1UEAxMYY2Eucmct NDg4LTQxOS5wMnBpZnkuY29tMB4XDTIwMTExMTE1MzgwMFoXDTMwMTEwOTE1Mzgw MFowVzELMAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9y dGxhbmQxJDAiBgNVBAMMG0FkbWluQHJnLTQ4OC00MTkucDJwaWZ5LmNvbTBZMBMG ByqGSM49AgEGCCqGSM49AwEHA0IABChqCtDw7kJW2moGlvX2rfWQTO6w0+mbYySE 1tAd1TeAMC1uJPLY5mzhieIi4aV01cOD7X8M5u2t/l9mJ206zPSjTTBLMA4GA1Ud DwEB/wQEAwIHgDAMBgNVHRMBAf8EAjAAMCsGA1UdIwQkMCKAIECNQmx9w23JPryj cCp7KYhZ1wUk92blQK/i1KZqiDEsMAoGCCqGSM49BAMCA0cAMEQCIHyyeYyTtSEt clW/zsGBN4n2yJgpFVNZ1YYeMW7Dr9/QAiBZObUvzmpwEPKRpsNwybsu9TZQkUMP qKFsufn/Id/QSA== -----END CERTIFICATE----- 2020-11-23 12:07:44.778 UTC [protoutils] checkSignatureFromCreator -> DEBU 2fe creator is &{RG-488-419-MSP c79a85a6475ea912c230a63affcbe3ced0674cdd8e2ffb56f00b8f3d48ae88a1} 2020-11-23 12:07:44.778 UTC [msp] Validate -> DEBU 2ff MSP RG-488-419-MSP validating identity 2020-11-23 12:07:44.779 UTC [protoutils] checkSignatureFromCreator -> DEBU 300 creator is valid 2020-11-23 12:07:44.779 UTC [msp.identity] Verify -> DEBU 301 Verify: digest = 00000000 1a 48 b8 24 d8 ee 6f ff 06 94 1a cf 0d 71 31 6a |.H.$..o......q1j| 00000010 3f 4c 07 4d 4d 7a c9 98 12 ae 09 0f 19 92 3b 19 |?L.MMz........;.| 2020-11-23 12:07:44.779 UTC [msp.identity] Verify -> DEBU 302 Verify: sig = 00000000 30 45 02 21 00 82 0b aa 79 f1 e9 bf 24 32 bf 97 |0E.!....y...$2..| 00000010 36 e5 eb 8a 48 04 d4 73 d5 66 8e 2c 97 9d 4b c0 |6...H..s.f.,..K.| 00000020 cc f4 e6 c7 57 02 20 54 66 5e 75 1f 02 a5 fa fb |....W. Tf^u.....| 00000030 0f a2 f5 d9 22 07 a0 d1 3a ac 75 ec 48 0a d7 ca |...."...:.u.H...| 00000040 af 31 52 86 1f 86 4b |.1R...K| 2020-11-23 12:07:44.779 UTC [protoutils] checkSignatureFromCreator -> DEBU 303 exits successfully 2020-11-23 12:07:44.779 UTC [protoutils] validateEndorserTransaction -> DEBU 304 validateEndorserTransaction starts for data 0xc003baa000, header channel_header:"\010\003\020\001\032\014\010\261\342\333\375\005\020\200\273\313\217\003\"\016defaultchannel*@b04315b1467322edb74296405c36480b2eb0d98b129bce081bf7a81397457792:\030\022\026\022\024wcc-smart-contract_0B \262\017m:\317(\233R\362\367\017\262\366\204`%g!\370\3274\317\246G\313\"\347PE\371\327[" signature_header:"\n\245\006\n\016RG-488-419-MSP\022\222\006-----BEGIN CERTIFICATE-----\nMIICFjCCAb2gAwIBAgIRANRNvwjK5f8ziGknL5BQ3xMwCgYIKoZIzj0EAwIwdDEL\nMAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9ydGxhbmQx\nHjAcBgNVBAoTFXJnLTQ4OC00MTkucDJwaWZ5LmNvbTEhMB8GA1UEAxMYY2Eucmct\nNDg4LTQxOS5wMnBpZnkuY29tMB4XDTIwMTExMTE1MzgwMFoXDTMwMTEwOTE1Mzgw\nMFowVzELMAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9y\ndGxhbmQxJDAiBgNVBAMMG0FkbWluQHJnLTQ4OC00MTkucDJwaWZ5LmNvbTBZMBMG\nByqGSM49AgEGCCqGSM49AwEHA0IABChqCtDw7kJW2moGlvX2rfWQTO6w0+mbYySE\n1tAd1TeAMC1uJPLY5mzhieIi4aV01cOD7X8M5u2t/l9mJ206zPSjTTBLMA4GA1Ud\nDwEB/wQEAwIHgDAMBgNVHRMBAf8EAjAAMCsGA1UdIwQkMCKAIECNQmx9w23JPryj\ncCp7KYhZ1wUk92blQK/i1KZqiDEsMAoGCCqGSM49BAMCA0cAMEQCIHyyeYyTtSEt\nclW/zsGBN4n2yJgpFVNZ1YYeMW7Dr9/QAiBZObUvzmpwEPKRpsNwybsu9TZQkUMP\nqKFsufn/Id/QSA==\n-----END CERTIFICATE-----\n\022\030-T\014\335{\254\366\267\327b\3626o\333\377J\032\n\224W\224\213\037\205" 2020-11-23 12:07:44.779 UTC [protoutils] validateEndorserTransaction -> DEBU 305 validateEndorserTransaction info: there are 1 actions 2020-11-23 12:07:44.779 UTC [protoutils] validateEndorserTransaction -> DEBU 306 validateEndorserTransaction info: signature header is valid 2020-11-23 12:07:44.780 UTC [protoutils] ValidateTransaction -> DEBU 307 ValidateTransactionEnvelope returns err %!s() 2020-11-23 12:07:44.780 UTC [committer.txvalidator] validateTx -> DEBU 308 Transaction is for channel defaultchannel 2020-11-23 12:07:44.780 UTC [blkstorage] retrieveTransactionByID -> DEBU 309 retrieveTransactionByID() - txId = [b04315b1467322edb74296405c36480b2eb0d98b129bce081bf7a81397457792] 2020-11-23 12:07:44.780 UTC [leveldbhelper] GetIterator -> DEBU 30a Getting iterator for range [[]byte{0x64, 0x65, 0x66, 0x61, 0x75, 0x6c, 0x74, 0x63, 0x68, 0x61, 0x6e, 0x6e, 0x65, 0x6c, 0x0, 0x74, 0x1, 0x40, 0x62, 0x30, 0x34, 0x33, 0x31, 0x35, 0x62, 0x31, 0x34, 0x36, 0x37, 0x33, 0x32, 0x32, 0x65, 0x64, 0x62, 0x37, 0x34, 0x32, 0x39, 0x36, 0x34, 0x30, 0x35, 0x63, 0x33, 0x36, 0x34, 0x38, 0x30, 0x62, 0x32, 0x65, 0x62, 0x30, 0x64, 0x39, 0x38, 0x62, 0x31, 0x32, 0x39, 0x62, 0x63, 0x65, 0x30, 0x38, 0x31, 0x62, 0x66, 0x37, 0x61, 0x38, 0x31, 0x33, 0x39, 0x37, 0x34, 0x35, 0x37, 0x37, 0x39, 0x32}] - [[]byte{0x64, 0x65, 0x66, 0x61, 0x75, 0x6c, 0x74, 0x63, 0x68, 0x61, 0x6e, 0x6e, 0x65, 0x6c, 0x0, 0x74, 0x1, 0x40, 0x62, 0x30, 0x34, 0x33, 0x31, 0x35, 0x62, 0x31, 0x34, 0x36, 0x37, 0x33, 0x32, 0x32, 0x65, 0x64, 0x62, 0x37, 0x34, 0x32, 0x39, 0x36, 0x34, 0x30, 0x35, 0x63, 0x33, 0x36, 0x34, 0x38, 0x30, 0x62, 0x32, 0x65, 0x62, 0x30, 0x64, 0x39, 0x38, 0x62, 0x31, 0x32, 0x39, 0x62, 0x63, 0x65, 0x30, 0x38, 0x31, 0x62, 0x66, 0x37, 0x61, 0x38, 0x31, 0x33, 0x39, 0x37, 0x34, 0x35, 0x37, 0x37, 0x39, 0x32, 0xff}] 2020-11-23 12:07:44.780 UTC [committer.txvalidator] validateTx -> DEBU 30b Validating transaction with plugins 2020-11-23 12:07:44.781 UTC [committer.txvalidator] Dispatch -> DEBU 30c [defaultchannel] Dispatch starts for bytes 0xc003b80000 2020-11-23 12:07:44.781 UTC [lockbasedtxmgr] newQueryExecutor -> DEBU 30d constructing new query executor txid = [2eddaefa-9606-47b6-a1b0-7a02c7e5003d] 2020-11-23 12:07:44.781 UTC [stateleveldb] GetState -> DEBU 30e GetState(). ns=_lifecycle, key=namespaces/metadata/wcc-smart-contract_0 2020-11-23 12:07:44.781 UTC [stateleveldb] GetState -> DEBU 30f GetState(). ns=_lifecycle, key=namespaces/fields/wcc-smart-contract_0/Sequence 2020-11-23 12:07:44.781 UTC [stateleveldb] GetState -> DEBU 310 GetState(). ns=_lifecycle, key=namespaces/fields/wcc-smart-contract_0/EndorsementInfo 2020-11-23 12:07:44.781 UTC [stateleveldb] GetState -> DEBU 311 GetState(). ns=_lifecycle, key=namespaces/fields/wcc-smart-contract_0/ValidationInfo 2020-11-23 12:07:44.781 UTC [stateleveldb] GetState -> DEBU 312 GetState(). ns=_lifecycle, key=namespaces/fields/wcc-smart-contract_0/Collections 2020-11-23 12:07:44.781 UTC [lockbasedtxmgr] Done -> DEBU 313 Done with transaction simulation / query execution [2eddaefa-9606-47b6-a1b0-7a02c7e5003d] 2020-11-23 12:07:44.781 UTC [committer.txvalidator] invokeValidationPlugin -> DEBU 314 Validating Tx b04315b1467322edb74296405c36480b2eb0d98b129bce081bf7a81397457792, seq 0 out of 1 in block 6138 for channel defaultchannel with validation plugin vscc with plugin 2020-11-23 12:07:44.782 UTC [lockbasedtxmgr] newQueryExecutor -> DEBU 315 constructing new query executor txid = [8340a152-b7b9-442d-abeb-7a1514ef64cf] 2020-11-23 12:07:44.782 UTC [lockbasedtxmgr] Done -> DEBU 316 Done with transaction simulation / query execution [8340a152-b7b9-442d-abeb-7a1514ef64cf] 2020-11-23 12:07:44.782 UTC [policies] EvaluateSignedData -> DEBU 317 == Evaluating *policies.ImplicitMetaPolicy Policy /Channel/Application/Endorsement == 2020-11-23 12:07:44.782 UTC [policies] EvaluateSignedData -> DEBU 318 This is an implicit meta policy, it will trigger other policy evaluations, whose failures may be benign 2020-11-23 12:07:44.782 UTC [policies] EvaluateSignedData -> DEBU 319 == Evaluating *cauthdsl.policy Policy /Channel/Application/rg-488-419/Endorsement == 2020-11-23 12:07:44.782 UTC [msp] DeserializeIdentity -> DEBU 31a Obtaining identity 2020-11-23 12:07:44.782 UTC [msp.identity] newIdentity -> DEBU 31b Creating identity instance for cert -----BEGIN CERTIFICATE----- MIICIDCCAcagAwIBAgIRAM9TMKO5v1VqIzXfNwQqcPAwCgYIKoZIzj0EAwIwdDEL MAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9ydGxhbmQx HjAcBgNVBAoTFXJnLTQ4OC00MTkucDJwaWZ5LmNvbTEhMB8GA1UEAxMYY2Eucmct NDg4LTQxOS5wMnBpZnkuY29tMB4XDTIwMTExMTE1MzgwMFoXDTMwMTEwOTE1Mzgw MFowYDELMAkGA1UEBhMCVVMxDzANBgNVBAgTBk9yZWdvbjERMA8GA1UEBxMIUG9y dGxhbmQxLTArBgNVBAMTJG5kLTIwNi05NzQtMzUxLnJnLTQ4OC00MTkucDJwaWZ5 LmNvbTBZMBMGByqGSM49AgEGCCqGSM49AwEHA0IABCVBOQOuA7OL14eCyTvZvvUh Zd26m365Iwre940bt18b3A4CrVXMT4J1uV+FA07Ce/5qVMGB5J9BsjmQA70gqtyj TTBLMA4GA1UdDwEB/wQEAwIHgDAMBgNVHRMBAf8EAjAAMCsGA1UdIwQkMCKAIECN Qmx9w23JPryjcCp7KYhZ1wUk92blQK/i1KZqiDEsMAoGCCqGSM49BAMCA0gAMEUC IQD7yPnHH/QaNJyCW5UuJNUqg+vHUDWv0olaCQ5xXUpGIwIgLt1OHe6QcY6QEfga EnyzGX56hZXaLcCCpFa/Bl062Bc= -----END CERTIFICATE----- 2020-11-23 12:07:44.783 UTC [msp.identity] Verify -> DEBU 31c Verify: digest = 00000000 2b 11 62 4a 81 75 1f d1 db f9 51 8b 07 76 b9 4d |+.bJ.u....Q..v.M| 00000010 63 bd 21 c9 bf ec 34 50 b9 ae 57 38 37 c8 16 20 |c.!...4P..W87.. | 2020-11-23 12:07:44.783 UTC [msp.identity] Verify -> DEBU 31d Verify: sig = 00000000 30 44 02 20 28 40 ab 73 7b fc 95 e3 3a f4 21 47 |0D. (@.s{...:.!G| 00000010 ef 07 5b e3 38 7e 69 0a 48 62 96 05 4f a5 9a 2b |..[.8~i.Hb..O..+| 00000020 db 85 6d 01 02 20 45 24 a8 c9 7d 6c e8 5d 4f 88 |..m.. E$..}l.]O.| 00000030 fd 69 01 f7 e4 cb b7 66 dc 57 5e 78 86 9b 5a cb |.i.....f.W^x..Z.| 00000040 ee 57 39 5a 9e 4c |.W9Z.L| 2020-11-23 12:07:44.783 UTC [policies] SignatureSetToValidIdentities -> DEBU 31e signature for identity 0 validated 2020-11-23 12:07:44.783 UTC [cauthdsl] func1 -> DEBU 31f 0xc003bcdb80 gate 1606133264783386174 evaluation starts 2020-11-23 12:07:44.783 UTC [cauthdsl] func2 -> DEBU 320 0xc003bcdb80 signed by 0 principal evaluation starts (used [false]) 2020-11-23 12:07:44.783 UTC [cauthdsl] func2 -> DEBU 321 0xc003bcdb80 processing identity 0 - &{RG-488-419-MSP 2f74408be4d4efd3b2f17fdde4c005f48fcbd52ccc7713fbc07c3b167c80f853} 2020-11-23 12:07:44.783 UTC [msp] satisfiesPrincipalInternalPreV13 -> DEBU 322 Checking if identity satisfies role [PEER] for RG-488-419-MSP 2020-11-23 12:07:44.783 UTC [msp] Validate -> DEBU 323 MSP RG-488-419-MSP validating identity 2020-11-23 12:07:44.783 UTC [cauthdsl] func2 -> DEBU 324 0xc003bcdb80 identity 0 does not satisfy principal: The identity is not a [PEER] under this MSP [RG-488-419-MSP]: NodeOUs not activated. Cannot tell apart identities. 2020-11-23 12:07:44.783 UTC [cauthdsl] func2 -> DEBU 325 0xc003bcdb80 principal evaluation fails 2020-11-23 12:07:44.783 UTC [cauthdsl] func2 -> DEBU 326 0xc003bcdb80 signed by 1 principal evaluation starts (used [false]) 2020-11-23 12:07:44.783 UTC [cauthdsl] func2 -> DEBU 327 0xc003bcdb80 processing identity 0 - &{RG-488-419-MSP 2f74408be4d4efd3b2f17fdde4c005f48fcbd52ccc7713fbc07c3b167c80f853} 2020-11-23 12:07:44.783 UTC [msp] satisfiesPrincipalInternalPreV13 -> DEBU 328 Checking if identity satisfies MEMBER role for RG-488-419-MSP 2020-11-23 12:07:44.783 UTC [msp] Validate -> DEBU 329 MSP RG-488-419-MSP validating identity 2020-11-23 12:07:44.783 UTC [cauthdsl] func2 -> DEBU 32a 0xc003bcdb80 principal evaluation succeeds for identity 0 2020-11-23 12:07:44.783 UTC [cauthdsl] func1 -> DEBU 32b 0xc003bcdb80 gate 1606133264783386174 evaluation succeeds 2020-11-23 12:07:44.783 UTC [policies] EvaluateSignedData -> DEBU 32c Signature set satisfies policy /Channel/Application/rg-488-419/Endorsement 2020-11-23 12:07:44.783 UTC [policies] EvaluateSignedData -> DEBU 32d == Done Evaluating *cauthdsl.policy Policy /Channel/Application/rg-488-419/Endorsement 2020-11-23 12:07:44.783 UTC [policies] EvaluateSignedData -> DEBU 32e Signature set satisfies policy /Channel/Application/Endorsement 2020-11-23 12:07:44.784 UTC [policies] EvaluateSignedData -> DEBU 32f == Done Evaluating *policies.ImplicitMetaPolicy Policy /Channel/Application/Endorsement 2020-11-23 12:07:44.784 UTC [lockbasedtxmgr] newQueryExecutor -> DEBU 330 constructing new query executor txid = [708dde81-b8ea-4ea4-8caa-94d105040e38] 2020-11-23 12:07:44.784 UTC [lockbasedtxmgr] Done -> DEBU 331 Done with transaction simulation / query execution [708dde81-b8ea-4ea4-8caa-94d105040e38] 2020-11-23 12:07:44.784 UTC [vscc] Validate -> DEBU 332 block 6138, namespace: wcc-smart-contract_0, tx 0 validation results is: 2020-11-23 12:07:44.784 UTC [committer.txvalidator] ValidateWithPlugin -> DEBU 333 Transaction b04315b1467322edb74296405c36480b2eb0d98b129bce081bf7a81397457792 appears to be valid 2020-11-23 12:07:44.784 UTC [committer.txvalidator] Dispatch -> DEBU 334 [defaultchannel] Dispatch completes env bytes 0xc003b80000 2020-11-23 12:07:44.784 UTC [committer.txvalidator] validateTx -> DEBU 335 [defaultchannel] validateTx completes for block 0xc0003f5e00 env 0xc002a65180 txn 0 2020-11-23 12:07:44.784 UTC [committer.txvalidator] Validate -> DEBU 336 got result for idx 0, code 0 2020-11-23 12:07:44.784 UTC [committer.txvalidator] Validate -> INFO 337 [defaultchannel] Validated block [6138] in 6ms 2020-11-23 12:07:44.784 UTC [msp] GetManagerForChain -> DEBU 338 Returning existing manager for channel 'defaultchannel' 2020-11-23 12:07:44.784 UTC [gossip.privdata] RetrievePvtdata -> DEBU 339 No eligible collection private write sets to fetch for block [6138] channel=defaultchannel 2020-11-23 12:07:44.784 UTC [kvledger] CommitLegacy -> DEBU 33a [defaultchannel] Validating state for block [6138] 2020-11-23 12:07:44.784 UTC [lockbasedtxmgr] ValidateAndPrepare -> DEBU 33b Waiting for purge mgr to finish the background job of computing expirying keys for the block 2020-11-23 12:07:44.784 UTC [lockbasedtxmgr] ValidateAndPrepare -> DEBU 33c lock acquired on oldBlockCommit for validating read set version against the committed version 2020-11-23 12:07:44.784 UTC [lockbasedtxmgr] ValidateAndPrepare -> DEBU 33d Validating new block with num trans = [1] 2020-11-23 12:07:44.784 UTC [validation] ValidateAndPrepareBatch -> DEBU 33e ValidateAndPrepareBatch() for block number = [6138] 2020-11-23 12:07:44.784 UTC [validation] ValidateAndPrepareBatch -> DEBU 33f preprocessing ProtoBlock... 2020-11-23 12:07:44.785 UTC [validation] preprocessProtoBlock -> DEBU 340 txType=ENDORSER_TRANSACTION 2020-11-23 12:07:44.785 UTC [stateleveldb] GetState -> DEBU 341 GetState(). ns=_lifecycle, key=namespaces/fields/wcc-smart-contract_0/Sequence 2020-11-23 12:07:44.785 UTC [validation] validateKVRead -> DEBU 342 Comparing versions for key [namespaces/fields/wcc-smart-contract_0/Sequence]: committed version=&version.Height{BlockNum:0x3, TxNum:0x0} and read version=&version.Height{BlockNum:0x3, TxNum:0x0} 2020-11-23 12:07:44.785 UTC [validation] validateAndPrepareBatch -> DEBU 343 Block [6138] Transaction index [0] TxId [b04315b1467322edb74296405c36480b2eb0d98b129bce081bf7a81397457792] marked as valid by state validator. ContainsPostOrderWrites [false] 2020-11-23 12:07:44.785 UTC [validation] applyWriteSet -> DEBU 344 txops=validation.txOps{validation.compositeKey{ns:"wcc-smart-contract_0", coll:"", key:"\x00files\x003af26cc7-8ac4-40c1-9872-ed08f3394d90\x00"}:(*validation.keyOps)(0xc003be0f00), validation.compositeKey{ns:"wcc-smart-contract_0", coll:"", key:"\x00files_metadata\x003af26cc7-8ac4-40c1-9872-ed08f3394d90\x00"}:(*validation.keyOps)(0xc003be0f40)} panic: Nil value not allowed. Instead call 'Delete' function goroutine 213 [running]: github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/statedb.(*UpdateBatch).PutValAndMetadata(...) /go/src/github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/statedb/statedb.go:196 github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/validation.(*publicAndHashUpdates).applyWriteSet(0xc003ca68a0, 0xc003cc48e0, 0xc003bc7f20, 0xc0034d4ae0, 0xc003bc3600, 0x4, 0x4) /go/src/github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/validation/types.go:100 +0x9c2 github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/validation.(*validator).validateAndPrepareBatch(0xc0034e0140, 0xc003cc47c0, 0xc003bc3701, 0xc0003f5e00, 0xc003bc3701, 0xc00053ed80) /go/src/github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/validation/validator.go:105 +0x407 github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/validation.(*CommitBatchPreparer).ValidateAndPrepareBatch(0xc0034da3c0, 0xc003c7fe60, 0x18f1c01, 0x2a, 0xc003bc3870, 0x1, 0x1, 0x0, 0x0) /go/src/github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/validation/batch_preparer.go:91 +0x22d github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/txmgr.(*LockBasedTxMgr).ValidateAndPrepare(0xc003497180, 0xc003c7fe60, 0x18e7a01, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /go/src/github.com/hyperledger/fabric/core/ledger/kvledger/txmgmt/txmgr/lockbased_txmgr.go:196 +0x247 github.com/hyperledger/fabric/core/ledger/kvledger.(*kvLedger).CommitLegacy(0xc0033cd440, 0xc003c7fe60, 0xc003bc7cd4, 0x0, 0x0) /go/src/github.com/hyperledger/fabric/core/ledger/kvledger/kv_ledger.go:474 +0x1c4 github.com/hyperledger/fabric/core/committer.(*LedgerCommitter).CommitLegacy(0xc00364b7a0, 0xc003c7fe60, 0xc003bc7cd4, 0x1, 0xc003ce2410) /go/src/github.com/hyperledger/fabric/core/committer/committer_impl.go:62 +0x47 github.com/hyperledger/fabric/gossip/privdata.(*coordinator).StoreBlock(0xc0035933b0, 0xc0003f5e00, 0x0, 0x0, 0x0, 0x0, 0x0) /go/src/github.com/hyperledger/fabric/gossip/privdata/coordinator.go:224 +0xbfa github.com/hyperledger/fabric/gossip/state.(*GossipStateProviderImpl).commitBlock(0xc003515290, 0xc0003f5e00, 0x0, 0x0, 0x0, 0x3, 0x0) /go/src/github.com/hyperledger/fabric/gossip/state/state.go:786 +0xa2 github.com/hyperledger/fabric/gossip/state.(*GossipStateProviderImpl).deliverPayloads(0xc003515290) /go/src/github.com/hyperledger/fabric/gossip/state/state.go:576 +0x3a6 created by github.com/hyperledger/fabric/gossip/state.NewGossipStateProvider /go/src/github.com/hyperledger/fabric/gossip/state/state.go:273 +0x8a8 ```