Skip to content

Instantly share code, notes, and snippets.

@subhodi

subhodi/orderer.log

Created July 21, 2017 04:34
Show Gist options
  • Save subhodi/4f8527d44558262bc3473418fee80536 to your computer and use it in GitHub Desktop.
Save subhodi/4f8527d44558262bc3473418fee80536 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
orderer.log
2017-07-21 04:28:58.581 UTC [orderer/main] Deliver -> DEBU 26a Starting new Deliver handler
2017-07-21 04:28:58.581 UTC [orderer/common/deliver] Handle -> DEBU 26b Starting new deliver loop
2017-07-21 04:28:58.581 UTC [orderer/common/deliver] Handle -> DEBU 26c Attempting to read seek info mes sage
2017-07-21 04:28:58.582 UTC [policies] GetPolicy -> DEBU 26d Returning policy Readers for evaluation
2017-07-21 04:28:58.582 UTC [cauthdsl] func1 -> DEBU 26e 0xc420026050 gate 1500611338582209665 evaluatio n starts
2017-07-21 04:28:58.582 UTC [cauthdsl] func2 -> DEBU 26f 0xc420026050 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:28:58.582 UTC [cauthdsl] func2 -> DEBU 270 0xc420026050 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:28:58.583 UTC [msp/identity] newIdentity -> DEBU 271 Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:28:58.583 UTC [cauthdsl] func2 -> DEBU 272 0xc420026050 identity 0 does not satisfy princi pal: The identity is a member of a different MSP (expected OrdererMSP, got Org1MSP)
2017-07-21 04:28:58.583 UTC [cauthdsl] func2 -> DEBU 273 0xc420026050 principal evaluation fails
2017-07-21 04:28:58.583 UTC [cauthdsl] func1 -> DEBU 274 0xc420026050 gate 1500611338582209665 evaluatio n fails
2017-07-21 04:28:58.584 UTC [cauthdsl] func1 -> DEBU 275 0xc420026050 gate 1500611338584566577 evaluatio n starts
2017-07-21 04:28:58.584 UTC [cauthdsl] func2 -> DEBU 276 0xc420026050 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:28:58.584 UTC [cauthdsl] func2 -> DEBU 277 0xc420026050 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:28:58.585 UTC [msp/identity] newIdentity -> DEBU 278 Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:28:58.585 UTC [msp] SatisfiesPrincipal -> DEBU 279 Checking if identity satisfies MEMBER r ole for Org1MSP
2017-07-21 04:28:58.585 UTC [msp] Validate -> DEBU 27a MSP Org1MSP validating identity
2017-07-21 04:28:58.586 UTC [cauthdsl] func2 -> DEBU 27b 0xc420026050 principal matched by identity 0
2017-07-21 04:28:58.586 UTC [msp/identity] Verify -> DEBU 27c Verify: digest = 00000000 aa 99 11 2a b6 21 79 d4 29 3c 1a 29 e1 74 92 6e |...*.!y.)<.).t.n|
00000010 f8 d0 a0 b9 0b ee 86 f2 86 06 14 9c 6c c0 9b fa |............l...|
2017-07-21 04:28:58.586 UTC [msp/identity] Verify -> DEBU 27d Verify: sig = 00000000 30 45 02 21 00 9d 23 a6 3f c7 40 70 86 83 8f 5d |0E.!..#.?.@p...]|
00000010 7a bd 1c 4d dd e2 cb 35 6e 78 27 8d 54 39 4a d3 |z..M...5nx'.T9J.|
00000020 be 89 91 79 fa 02 20 71 9e 78 9e 8b ab 0c 60 8e |...y.. q.x....`.|
00000030 52 c1 34 37 aa a5 d3 d8 59 bd 76 13 3c f0 24 ea |R.47....Y.v.<.$.|
00000040 30 0f 09 ce c1 a6 71 |0.....q|
2017-07-21 04:28:58.586 UTC [cauthdsl] func2 -> DEBU 27e 0xc420026050 principal evaluation succeeds for identity 0
2017-07-21 04:28:58.586 UTC [cauthdsl] func1 -> DEBU 27f 0xc420026050 gate 1500611338584566577 evaluatio n succeeds
2017-07-21 04:28:58.586 UTC [orderer/common/sigfilter] Apply -> DEBU 280 Forwarding validly signed messa ge for policy &{%!s(*common.ImplicitMetaPolicy=&{Readers 0}) %!s(int=1) [%!s(*policies.implicitMetaPolic y=&{0xc420363600 1 [0xc420146620]}) %!s(*policies.implicitMetaPolicy=&{0xc420363e20 1 [0xc4201466e0 0xc4 201467d8]})]}
2017-07-21 04:28:58.587 UTC [orderer/common/deliver] Handle -> DEBU 281 [channel: business] Received see kInfo (0xc420380c40) start:<newest:<> > stop:<newest:<> >
2017-07-21 04:28:58.587 UTC [fsblkstorage] retrieveBlockByNumber -> DEBU 282 retrieveBlockByNumber() - b lockNum = [12]
2017-07-21 04:28:58.587 UTC [fsblkstorage] newBlockfileStream -> DEBU 283 newBlockfileStream(): filePath =[/var/hyperledger/production/orderer/chains/business/blockfile_000000], startOffset=[79923]
2017-07-21 04:28:58.588 UTC [fsblkstorage] nextBlockBytesAndPlacementInfo -> DEBU 284 Remaining bytes=[6 195], Going to peek [8] bytes
2017-07-21 04:28:58.588 UTC [fsblkstorage] nextBlockBytesAndPlacementInfo -> DEBU 285 Returning blockbyt es - length=[6193], placementInfo={fileNum=[0], startOffset=[79923], bytesOffset=[79925]}
2017-07-21 04:28:58.588 UTC [orderer/common/deliver] Handle -> DEBU 286 [channel: business] Delivering b lock for (0xc420380c40)
2017-07-21 04:28:58.588 UTC [orderer/common/deliver] Handle -> DEBU 287 [channel: business] Done deliver ing for (0xc420380c40), waiting for new SeekInfo
2017-07-21 04:28:58.588 UTC [orderer/common/deliver] Handle -> DEBU 288 Attempting to read seek info mes sage
2017-07-21 04:28:58.599 UTC [orderer/common/deliver] Handle -> DEBU 289 Received EOF, hangup
2017-07-21 04:28:58.599 UTC [orderer/main] func1 -> DEBU 28a Closing Deliver stream
2017-07-21 04:28:58.619 UTC [orderer/main] Deliver -> DEBU 28b Starting new Deliver handler
2017-07-21 04:28:58.619 UTC [orderer/common/deliver] Handle -> DEBU 28c Starting new deliver loop
2017-07-21 04:28:58.619 UTC [orderer/common/deliver] Handle -> DEBU 28d Attempting to read seek info mes sage
2017-07-21 04:28:58.619 UTC [policies] GetPolicy -> DEBU 28e Returning policy Readers for evaluation
2017-07-21 04:28:58.619 UTC [cauthdsl] func1 -> DEBU 28f 0xc420146328 gate 1500611338619877372 evaluatio n starts
2017-07-21 04:28:58.619 UTC [cauthdsl] func2 -> DEBU 290 0xc420146328 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:28:58.619 UTC [cauthdsl] func2 -> DEBU 291 0xc420146328 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:28:58.620 UTC [msp/identity] newIdentity -> DEBU 292 Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:28:58.620 UTC [cauthdsl] func2 -> DEBU 293 0xc420146328 identity 0 does not satisfy princi pal: The identity is a member of a different MSP (expected OrdererMSP, got Org1MSP)
2017-07-21 04:28:58.620 UTC [cauthdsl] func2 -> DEBU 294 0xc420146328 principal evaluation fails
2017-07-21 04:28:58.620 UTC [cauthdsl] func1 -> DEBU 295 0xc420146328 gate 1500611338619877372 evaluatio n fails
2017-07-21 04:28:58.620 UTC [cauthdsl] func1 -> DEBU 296 0xc420146328 gate 1500611338620669733 evaluatio n starts
2017-07-21 04:28:58.620 UTC [cauthdsl] func2 -> DEBU 297 0xc420146328 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:28:58.620 UTC [cauthdsl] func2 -> DEBU 298 0xc420146328 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:28:58.621 UTC [msp/identity] newIdentity -> DEBU 299 Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:28:58.621 UTC [msp] SatisfiesPrincipal -> DEBU 29a Checking if identity satisfies MEMBER r ole for Org1MSP
2017-07-21 04:28:58.621 UTC [msp] Validate -> DEBU 29b MSP Org1MSP validating identity
2017-07-21 04:28:58.622 UTC [cauthdsl] func2 -> DEBU 29c 0xc420146328 principal matched by identity 0
2017-07-21 04:28:58.622 UTC [msp/identity] Verify -> DEBU 29d Verify: digest = 00000000 ae 9b ca 7f 29 1f de 18 55 fe c4 8a 2c ba 80 18 |....)...U...,...|
00000010 21 42 a0 d1 7a d8 d4 b0 00 70 ce 5a ad 6a 8a 02 |!B..z....p.Z.j..|
2017-07-21 04:28:58.622 UTC [msp/identity] Verify -> DEBU 29e Verify: sig = 00000000 30 44 02 20 41 a0 3e 0b 41 b9 92 85 ae d8 51 e9 |0D. A.>.A.....Q.|
00000010 9b d3 46 f8 4c b8 7a 30 24 96 21 e9 5f b9 b1 0a |..F.L.z0$.!._...|
00000020 b7 f6 e7 b7 02 20 41 84 12 23 e9 a7 22 3e 4b b3 |..... A..#..">K.|
00000030 9c 91 0f 17 0c 63 fe 75 45 45 0a f8 0f 96 11 23 |.....c.uEE.....#|
00000040 d0 2a 86 07 42 01 |.*..B.|
2017-07-21 04:28:58.630 UTC [cauthdsl] func2 -> DEBU 29f 0xc420146328 principal evaluation succeeds for identity 0
2017-07-21 04:28:58.630 UTC [cauthdsl] func1 -> DEBU 2a0 0xc420146328 gate 1500611338620669733 evaluatio n succeeds
2017-07-21 04:28:58.630 UTC [orderer/common/sigfilter] Apply -> DEBU 2a1 Forwarding validly signed messa ge for policy &{%!s(*common.ImplicitMetaPolicy=&{Readers 0}) %!s(int=1) [%!s(*policies.implicitMetaPolic y=&{0xc420363600 1 [0xc420146620]}) %!s(*policies.implicitMetaPolicy=&{0xc420363e20 1 [0xc4201466e0 0xc4 201467d8]})]}
2017-07-21 04:28:58.630 UTC [orderer/common/deliver] Handle -> DEBU 2a2 [channel: business] Received see kInfo (0xc420362ca0) start:<specified:<> > stop:<specified:<> >
2017-07-21 04:28:58.630 UTC [fsblkstorage] retrieveBlockByNumber -> DEBU 2a3 retrieveBlockByNumber() - b lockNum = [0]
2017-07-21 04:28:58.631 UTC [fsblkstorage] newBlockfileStream -> DEBU 2a4 newBlockfileStream(): filePath =[/var/hyperledger/production/orderer/chains/business/blockfile_000000], startOffset=[0]
2017-07-21 04:28:58.631 UTC [fsblkstorage] nextBlockBytesAndPlacementInfo -> DEBU 2a5 Remaining bytes=[8 6118], Going to peek [8] bytes
2017-07-21 04:28:58.631 UTC [fsblkstorage] nextBlockBytesAndPlacementInfo -> DEBU 2a6 Returning blockbyt es - length=[11983], placementInfo={fileNum=[0], startOffset=[0], bytesOffset=[2]}
2017-07-21 04:28:58.631 UTC [orderer/common/deliver] Handle -> DEBU 2a7 [channel: business] Delivering b lock for (0xc420362ca0)
2017-07-21 04:28:58.631 UTC [orderer/common/deliver] Handle -> DEBU 2a8 [channel: business] Done deliver ing for (0xc420362ca0), waiting for new SeekInfo
2017-07-21 04:28:58.632 UTC [orderer/common/deliver] Handle -> DEBU 2a9 Attempting to read seek info mes sage
2017-07-21 04:28:58.635 UTC [orderer/common/deliver] Handle -> DEBU 2aa Received EOF, hangup
2017-07-21 04:28:58.635 UTC [orderer/main] func1 -> DEBU 2ab Closing Deliver stream
2017-07-21 04:29:27.980 UTC [orderer/main] Broadcast -> DEBU 2ac Starting new Broadcast handler
2017-07-21 04:29:27.980 UTC [orderer/common/broadcast] Handle -> DEBU 2ad Starting new broadcast loop
2017-07-21 04:29:27.985 UTC [orderer/common/broadcast] Handle -> DEBU 2ae [channel: business] Broadcast is filtering message of type ENDORSER_TRANSACTION
2017-07-21 04:29:27.985 UTC [policies] GetPolicy -> DEBU 2af Returning policy Writers for evaluation
2017-07-21 04:29:27.985 UTC [cauthdsl] func1 -> DEBU 2b0 0xc420026028 gate 1500611367985235201 evaluatio n starts
2017-07-21 04:29:27.985 UTC [cauthdsl] func2 -> DEBU 2b1 0xc420026028 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:29:27.985 UTC [cauthdsl] func2 -> DEBU 2b2 0xc420026028 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:29:27.985 UTC [msp/identity] newIdentity -> DEBU 2b3 Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:29:27.986 UTC [cauthdsl] func2 -> DEBU 2b4 0xc420026028 identity 0 does not satisfy princi pal: The identity is a member of a different MSP (expected OrdererMSP, got Org1MSP)
2017-07-21 04:29:27.986 UTC [cauthdsl] func2 -> DEBU 2b5 0xc420026028 principal evaluation fails
2017-07-21 04:29:27.986 UTC [cauthdsl] func1 -> DEBU 2b6 0xc420026028 gate 1500611367985235201 evaluatio n fails
2017-07-21 04:29:27.986 UTC [cauthdsl] func1 -> DEBU 2b7 0xc420026028 gate 1500611367986539018 evaluatio n starts
2017-07-21 04:29:27.986 UTC [cauthdsl] func2 -> DEBU 2b8 0xc420026028 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:29:27.986 UTC [cauthdsl] func2 -> DEBU 2b9 0xc420026028 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:29:27.986 UTC [msp/identity] newIdentity -> DEBU 2ba Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:29:27.986 UTC [msp] SatisfiesPrincipal -> DEBU 2bb Checking if identity satisfies MEMBER r ole for Org1MSP
2017-07-21 04:29:27.986 UTC [msp] Validate -> DEBU 2bc MSP Org1MSP validating identity
2017-07-21 04:29:27.987 UTC [cauthdsl] func2 -> DEBU 2bd 0xc420026028 principal matched by identity 0
2017-07-21 04:29:27.987 UTC [msp/identity] Verify -> DEBU 2be Verify: digest = 00000000 f1 45 32 b9 ba 8a d7 16 a6 1f ba ea c0 25 a9 2e |.E2..........%..|
00000010 2e 5a d2 ae d8 61 fc ac 00 cc 35 fa fe 8d 81 0e |.Z...a....5.....|
2017-07-21 04:29:27.987 UTC [msp/identity] Verify -> DEBU 2bf Verify: sig = 00000000 30 44 02 20 47 02 8e 47 74 1d 48 d4 70 49 b7 13 |0D. G..Gt.H.pI..|
00000010 06 f6 2a 9f fe 48 59 db 51 99 ef 48 ff 57 c0 90 |..*..HY.Q..H.W..|
00000020 10 16 8e 37 02 20 33 24 b0 b2 f3 4f da 76 81 ca |...7. 3$...O.v..|
00000030 68 86 0b 34 98 bc 7c 91 b1 64 c5 4c 50 0d ce 66 |h..4..|..d.LP..f|
00000040 e3 16 f9 b3 45 bc |....E.|
2017-07-21 04:29:27.987 UTC [cauthdsl] func2 -> DEBU 2c0 0xc420026028 principal evaluation succeeds for identity 0
2017-07-21 04:29:27.987 UTC [cauthdsl] func1 -> DEBU 2c1 0xc420026028 gate 1500611367986539018 evaluatio n succeeds
2017-07-21 04:29:27.987 UTC [orderer/common/sigfilter] Apply -> DEBU 2c2 Forwarding validly signed messa ge for policy &{%!s(*common.ImplicitMetaPolicy=&{Writers 0}) %!s(int=1) [%!s(*policies.implicitMetaPolic y=&{0xc4203636c0 1 [0xc420146648]}) %!s(*policies.implicitMetaPolicy=&{0xc420363d80 1 [0xc420146740 0xc4 20146820]})]}
2017-07-21 04:29:27.987 UTC [orderer/common/broadcast] Handle -> DEBU 2c3 [channel: business] Broadcast has successfully enqueued message of type ENDORSER_TRANSACTION
2017-07-21 04:29:27.988 UTC [policies] GetPolicy -> DEBU 2c4 Returning policy Writers for evaluation
2017-07-21 04:29:27.988 UTC [cauthdsl] func1 -> DEBU 2c5 0xc420026078 gate 1500611367988403779 evaluatio n starts
2017-07-21 04:29:27.988 UTC [cauthdsl] func2 -> DEBU 2c6 0xc420026078 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:29:27.988 UTC [cauthdsl] func2 -> DEBU 2c7 0xc420026078 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:29:27.989 UTC [msp/identity] newIdentity -> DEBU 2c8 Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:29:27.989 UTC [cauthdsl] func2 -> DEBU 2c9 0xc420026078 identity 0 does not satisfy princi pal: The identity is a member of a different MSP (expected OrdererMSP, got Org1MSP)
2017-07-21 04:29:27.991 UTC [cauthdsl] func2 -> DEBU 2ca 0xc420026078 principal evaluation fails
2017-07-21 04:29:27.991 UTC [cauthdsl] func1 -> DEBU 2cb 0xc420026078 gate 1500611367988403779 evaluatio n fails
2017-07-21 04:29:27.991 UTC [cauthdsl] func1 -> DEBU 2cc 0xc420026078 gate 1500611367991676862 evaluatio n starts
2017-07-21 04:29:27.991 UTC [cauthdsl] func2 -> DEBU 2cd 0xc420026078 signed by 0 principal evaluation s tarts (used [false])
2017-07-21 04:29:27.991 UTC [cauthdsl] func2 -> DEBU 2ce 0xc420026078 processing identity 0 with bytes o f 0a074f7267314d53501296062d2d2d2d2d424547494e2043455254494649434154452d2d2d2d2d0a4d4949434754434341622b 6741774942416749514b4b4b6451537a73446f55596e2f4c50417552574754414b42676771686b6a4f50515144416a427a4d5173 770a435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131 554542784d4e5532467549455a790a5957356a61584e6a627a455a4d4263474131554543684d5162334a6e4d53356c6547467463 47786c4c6d4e76625445634d426f474131554541784d54593245750a62334a6e4d53356c654746746347786c4c6d4e7662544165 467730784e7a41324d6a4d784d6a4d7a4d546c61467730794e7a41324d6a45784d6a4d7a4d546c610a4d467378437a414a42674e 5642415954416c56544d524d77455159445651514945777044595778705a6d3979626d6c684d5259774641594456515148457731 540a59573467526e4a68626d4e7063324e764d523877485159445651514444425a425a473170626b4276636d63784c6d56345957 317762475575593239744d466b770a457759484b6f5a497a6a3043415159494b6f5a497a6a30444151634451674145436d627a55 446f7a49724c4b6a70334f417a49745347376d37466c77373672540a38564f3845366f746c4377784b7442526b50705a4c376e6f 7243334e736a79453333394a354f347058437168494170517952527352714e4e4d457377446759440a565230504151482f424151 44416765414d41774741315564457745422f7751434d4141774b7759445652306a42435177496f4167446e4b534a4f697a387865 450a794b6b3857343732394d484a485a357556337846777a466a594a2f6b41424577436759494b6f5a497a6a3045417749445341 417752514968414c54303270632f0a7966452f3477554a665542513332476966554568384a6b7441587a4c2f37335330726a5941 6941434e5370367a4151425839534278544f474d6b3463474741790a434b7166383035324e565573324376507a413d3d0a2d2d2d 2d2d454e442043455254494649434154452d2d2d2d2d0a
2017-07-21 04:29:27.992 UTC [msp/identity] newIdentity -> DEBU 2cf Creating identity instance for ID &{O rg1MSP ed2817b918dbc4f74484cbd8e71e3be1b36c2d22adb217bc2e377301518d0ea9}
2017-07-21 04:29:27.992 UTC [msp] SatisfiesPrincipal -> DEBU 2d0 Checking if identity satisfies MEMBER r ole for Org1MSP
2017-07-21 04:29:27.992 UTC [msp] Validate -> DEBU 2d1 MSP Org1MSP validating identity
2017-07-21 04:29:27.993 UTC [cauthdsl] func2 -> DEBU 2d2 0xc420026078 principal matched by identity 0
2017-07-21 04:29:27.993 UTC [msp/identity] Verify -> DEBU 2d3 Verify: digest = 00000000 f1 45 32 b9 ba 8a d7 16 a6 1f ba ea c0 25 a9 2e |.E2..........%..|
00000010 2e 5a d2 ae d8 61 fc ac 00 cc 35 fa fe 8d 81 0e |.Z...a....5.....|
2017-07-21 04:29:27.994 UTC [msp/identity] Verify -> DEBU 2d4 Verify: sig = 00000000 30 44 02 20 47 02 8e 47 74 1d 48 d4 70 49 b7 13 |0D. G..Gt.H.pI..|
00000010 06 f6 2a 9f fe 48 59 db 51 99 ef 48 ff 57 c0 90 |..*..HY.Q..H.W..|
00000020 10 16 8e 37 02 20 33 24 b0 b2 f3 4f da 76 81 ca |...7. 3$...O.v..|
00000030 68 86 0b 34 98 bc 7c 91 b1 64 c5 4c 50 0d ce 66 |h..4..|..d.LP..f|
00000040 e3 16 f9 b3 45 bc |....E.|
2017-07-21 04:29:27.994 UTC [cauthdsl] func2 -> DEBU 2d5 0xc420026078 principal evaluation succeeds for identity 0
2017-07-21 04:29:27.995 UTC [cauthdsl] func1 -> DEBU 2d6 0xc420026078 gate 1500611367991676862 evaluatio n succeeds
2017-07-21 04:29:27.995 UTC [orderer/common/sigfilter] Apply -> DEBU 2d7 Forwarding validly signed messa ge for policy &{%!s(*common.ImplicitMetaPolicy=&{Writers 0}) %!s(int=1) [%!s(*policies.implicitMetaPolic y=&{0xc4203636c0 1 [0xc420146648]}) %!s(*policies.implicitMetaPolicy=&{0xc420363d80 1 [0xc420146740 0xc4 20146820]})]}
2017-07-21 04:29:27.995 UTC [orderer/common/blockcutter] Ordered -> DEBU 2d8 Enqueuing message into batc h
2017-07-21 04:29:27.996 UTC [orderer/common/broadcast] Handle -> DEBU 2d9 Received EOF, hangup
2017-07-21 04:29:27.996 UTC [orderer/main] func1 -> DEBU 2da Closing Broadcast stream
2017-07-21 04:29:29.996 UTC [orderer/solo] main -> DEBU 2db Batch timer expired, creating block
2017-07-21 04:29:29.996 UTC [fsblkstorage] retrieveBlockByNumber -> DEBU 2dc retrieveBlockByNumber() - b lockNum = [12]
2017-07-21 04:29:29.997 UTC [fsblkstorage] newBlockfileStream -> DEBU 2dd newBlockfileStream(): filePath =[/var/hyperledger/production/orderer/chains/business/blockfile_000000], startOffset=[79923]
2017-07-21 04:29:29.998 UTC [fsblkstorage] nextBlockBytesAndPlacementInfo -> DEBU 2de Remaining bytes=[6 195], Going to peek [8] bytes
2017-07-21 04:29:29.998 UTC [fsblkstorage] nextBlockBytesAndPlacementInfo -> DEBU 2df Returning blockbyt es - length=[6193], placementInfo={fileNum=[0], startOffset=[79923], bytesOffset=[79925]}
2017-07-21 04:29:29.999 UTC [orderer/multichain] addBlockSignature -> DEBU 2e0 &{ledgerResources:0xc4201 2a300 chain:0xc42012c120 cutter:0xc420371ea0 filters:0xc42012a3a0 signer:0x126ee88 lastConfig:0 lastConf igSeq:1}
2017-07-21 04:29:29.999 UTC [orderer/multichain] addBlockSignature -> DEBU 2e1 &{}
2017-07-21 04:29:30.000 UTC [msp] GetLocalMSP -> DEBU 2e2 Returning existing local MSP
2017-07-21 04:29:30.000 UTC [msp] GetDefaultSigningIdentity -> DEBU 2e3 Obtaining default signing identi ty
2017-07-21 04:29:30.000 UTC [msp] GetLocalMSP -> DEBU 2e4 Returning existing local MSP
2017-07-21 04:29:30.000 UTC [msp] GetDefaultSigningIdentity -> DEBU 2e5 Obtaining default signing identi ty
2017-07-21 04:29:30.000 UTC [msp/identity] Sign -> DEBU 2e6 Sign: plaintext: 0AFF050A0A4F7264657265724D5 35012...893C5BFA5088291C3C1EBA07A79416E4
2017-07-21 04:29:30.000 UTC [msp/identity] Sign -> DEBU 2e7 Sign: digest: A7380C41FB474526CEE0D55D985A71 4AB162BD7103F85B3F2144935D3166223F
2017-07-21 04:29:30.001 UTC [msp] GetLocalMSP -> DEBU 2e8 Returning existing local MSP
2017-07-21 04:29:30.001 UTC [msp] GetDefaultSigningIdentity -> DEBU 2e9 Obtaining default signing identi ty
2017-07-21 04:29:30.001 UTC [orderer/multichain] addLastConfigSignature -> DEBU 2ea [channel: business] About to write block, setting its LAST_CONFIG to 0
2017-07-21 04:29:30.001 UTC [msp] GetLocalMSP -> DEBU 2eb Returning existing local MSP
2017-07-21 04:29:30.001 UTC [msp] GetDefaultSigningIdentity -> DEBU 2ec Obtaining default signing identi ty
2017-07-21 04:29:30.001 UTC [msp/identity] Sign -> DEBU 2ed Sign: plaintext: 0AFF050A0A4F7264657265724D5 35012...893C5BFA5088291C3C1EBA07A79416E4
2017-07-21 04:29:30.001 UTC [msp/identity] Sign -> DEBU 2ee Sign: digest: C19FFC1618A57D9BA7D5FC295E50DD 17A0DB490DBA37699CC3395F17A2FA487C
2017-07-21 04:29:30.006 UTC [fsblkstorage] indexBlock -> DEBU 2ef Indexing block [blockNum=13, blockHash =[]byte{0x50, 0xa8, 0x5d, 0xd7, 0x1c, 0xcd, 0x8a, 0x91, 0x26, 0x65, 0x73, 0x4d, 0x8c, 0xb3, 0xa, 0xf9, 0 x9a, 0xdc, 0x68, 0x52, 0x20, 0x90, 0x1f, 0xda, 0xed, 0xe8, 0xa, 0x48, 0x2c, 0xe2, 0xd5, 0x6f} txOffsets=
txId=1cf6e664211ac9a7d8ad3bf8123a7fdee2e77a8c6b8ad60b7c18865e754ff865 locPointer=offset=70, bytesLength= 4399
]
2017-07-21 04:29:30.007 UTC [fsblkstorage] updateCheckpoint -> DEBU 2f0 Broadcasting about update checkp ointInfo: latestFileChunkSuffixNum=[0], latestFileChunksize=[92342], isChainEmpty=[false], lastBlockNumb er=[13]
2017-07-21 04:29:30.007 UTC [orderer/multichain] WriteBlock -> DEBU 2f1 [channel: business] Wrote block 13 13
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment