2017-10-12 09:27:09.192 UTC [orderer/main] Deliver -> DEBU 59f Starting new Deliver handler
2017-10-12 09:27:09.192 UTC [orderer/common/deliver] Handle -> DEBU 5a0 Starting new deliver loop
2017-10-12 09:27:09.192 UTC [orderer/common/deliver] Handle -> DEBU 5a1 Attempting to read seek info message
2017-10-12 09:27:09.197 UTC [orderer/main] Broadcast -> DEBU 5a2 Starting new Broadcast handler
2017-10-12 09:27:09.197 UTC [orderer/common/broadcast] Handle -> DEBU 5a3 Starting new broadcast loop
2017-10-12 09:27:09.198 UTC [orderer/common/broadcast] Handle -> DEBU 5a4 Preprocessing CONFIG_UPDATE
2017-10-12 09:27:09.198 UTC [orderer/configupdate] Process -> DEBU 5a5 Processing channel reconfiguration request for channel channel1
2017-10-12 09:27:09.198 UTC [common/configtx] addToMap -> DEBU 5a6 Adding to config map: [Groups] /Channel
2017-10-12 09:27:09.198 UTC [common/configtx] addToMap -> DEBU 5a7 Adding to config map: [Groups] /Channel/Application
2017-10-12 09:27:09.198 UTC [common/configtx] addToMap -> DEBU 5a8 Adding to config map: [Groups] /Channel/Application/Org2MSP
2017-10-12 09:27:09.198 UTC [common/configtx] addToMap -> DEBU 5a9 Adding to config map: [Groups] /Channel/Application/Org3MSP
2017-10-12 09:27:09.199 UTC [common/configtx] addToMap -> DEBU 5aa Adding to config map: [Groups] /Channel/Application/Org1MSP
2017-10-12 09:27:09.199 UTC [common/configtx] addToMap -> DEBU 5ab Adding to config map: [Policy] /Channel/Application/Readers
2017-10-12 09:27:09.199 UTC [common/configtx] addToMap -> DEBU 5ac Adding to config map: [Policy] /Channel/Application/Writers
2017-10-12 09:27:09.199 UTC [common/configtx] addToMap -> DEBU 5ad Adding to config map: [Policy] /Channel/Application/Admins
2017-10-12 09:27:09.199 UTC [common/configtx] addToMap -> DEBU 5ae Adding to config map: [Groups] /Channel
2017-10-12 09:27:09.199 UTC [common/configtx] addToMap -> DEBU 5af Adding to config map: [Groups] /Channel/Application
2017-10-12 09:27:09.200 UTC [common/configtx] addToMap -> DEBU 5b0 Adding to config map: [Groups] /Channel/Application/Org4MSP
2017-10-12 09:27:09.200 UTC [common/configtx] addToMap -> DEBU 5b1 Adding to config map: [Values] /Channel/Application/Org4MSP/MSP
2017-10-12 09:27:09.200 UTC [common/configtx] addToMap -> DEBU 5b2 Adding to config map: [Groups] /Channel/Application/Org1MSP
2017-10-12 09:27:09.200 UTC [common/configtx] addToMap -> DEBU 5b3 Adding to config map: [Groups] /Channel/Application/Org2MSP
2017-10-12 09:27:09.200 UTC [common/configtx] addToMap -> DEBU 5b4 Adding to config map: [Groups] /Channel/Application/Org3MSP
2017-10-12 09:27:09.200 UTC [common/configtx] addToMap -> DEBU 5b5 Adding to config map: [Policy] /Channel/Application/Admins
2017-10-12 09:27:09.201 UTC [common/configtx] addToMap -> DEBU 5b6 Adding to config map: [Policy] /Channel/Application/Readers
2017-10-12 09:27:09.201 UTC [common/configtx] addToMap -> DEBU 5b7 Adding to config map: [Policy] /Channel/Application/Writers
2017-10-12 09:27:09.201 UTC [policies] GetPolicy -> DEBU 5b8 Returning policy Admins for evaluation
2017-10-12 09:27:09.201 UTC [cauthdsl] func1 -> DEBU 5b9 0xc420024a80 gate 1507800429201462900 evaluation starts
2017-10-12 09:27:09.201 UTC [cauthdsl] func2 -> DEBU 5ba 0xc420024a80 signed by 0 principal evaluation starts (used [false])
2017-10-12 09:27:09.201 UTC [cauthdsl] func2 -> DEBU 5bb 0xc420024a80 processing identity 0 with bytes of 0a074f7267314d53501280062d2d2d2d2d424547494e202d2d2d2d2d0a4d4949434754434341634367417749424167495241506151334c2b3350726979674d647348363952354a3477436759494b6f5a497a6a304541774977637a454c0a4d416b474131554542684d4356564d78457a415242674e5642416754436b4e6862476c6d62334a7561574578466a415542674e564241635444564e68626942470a636d467559326c7a593238784754415842674e5642416f54454739795a7a45755a586868625842735a53356a623230784844416142674e5642414d5445324e680a4c6d39795a7a45755a586868625842735a53356a623230774868634e4d5463774f5449794d4463314d5451325768634e4d6a63774f5449774d4463314d5451320a576a42624d517377435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131554542784d4e0a5532467549455a795957356a61584e6a627a45664d4230474131554541777757515752746157354162334a6e4d53356c654746746347786c4c6d4e766254425a0a4d424d4742797147534d34394167454743437147534d34394177454841304941424f6a38776f617848415a4d594d43787675644e4e69664d427467343750316a0a6d4e6436433074596e37556f34464550524f3274494d394a4a39492b6f6a4e72724c6f5a33374356477a6a3250572b4c6c6743713179756a5454424c4d4134470a41315564447745422f775145417749486744414d42674e5648524d4241663845416a41414d437347413155644977516b4d434b41494c707871412b2f356447300a6d66436663554e345351543348422f456d4c703046444948634a5369705444334d416f4743437147534d343942414d43413063414d4551434945687a567669550a57435943354c45554f306a4266324d78784e6766773056544562394850484a2f7856392f416941416835536d6b507936703368386c7270354f712f6b516e5a440a6c6854757762793849674a6c776c45555a513d3d0a2d2d2d2d2d454e44202d2d2d2d2d0a
2017-10-12 09:27:09.201 UTC [msp/identity] newIdentity -> DEBU 5bc Creating identity instance for ID &{Org1MSP 8d1cb71ecb64cd2c7163f4f2f3e653663437cac5e9cbcc5cbc261dbd26166fd2}
2017-10-12 09:27:09.202 UTC [cauthdsl] func2 -> DEBU 5bd 0xc420024a80 identity 0 does not satisfy principal: The identity is a member of a different MSP (expected Org3MSP, got Org1MSP)
2017-10-12 09:27:09.202 UTC [cauthdsl] func2 -> DEBU 5be 0xc420024a80 principal evaluation fails
2017-10-12 09:27:09.202 UTC [cauthdsl] func1 -> DEBU 5bf 0xc420024a80 gate 1507800429201462900 evaluation fails
2017-10-12 09:27:09.202 UTC [cauthdsl] func1 -> DEBU 5c0 0xc420024a80 gate 1507800429202234200 evaluation starts
2017-10-12 09:27:09.202 UTC [cauthdsl] func2 -> DEBU 5c1 0xc420024a80 signed by 0 principal evaluation starts (used [false])
2017-10-12 09:27:09.202 UTC [cauthdsl] func2 -> DEBU 5c2 0xc420024a80 processing identity 0 with bytes of 0a074f7267314d53501280062d2d2d2d2d424547494e202d2d2d2d2d0a4d4949434754434341634367417749424167495241506151334c2b3350726979674d647348363952354a3477436759494b6f5a497a6a304541774977637a454c0a4d416b474131554542684d4356564d78457a415242674e5642416754436b4e6862476c6d62334a7561574578466a415542674e564241635444564e68626942470a636d467559326c7a593238784754415842674e5642416f54454739795a7a45755a586868625842735a53356a623230784844416142674e5642414d5445324e680a4c6d39795a7a45755a586868625842735a53356a623230774868634e4d5463774f5449794d4463314d5451325768634e4d6a63774f5449774d4463314d5451320a576a42624d517377435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131554542784d4e0a5532467549455a795957356a61584e6a627a45664d4230474131554541777757515752746157354162334a6e4d53356c654746746347786c4c6d4e766254425a0a4d424d4742797147534d34394167454743437147534d34394177454841304941424f6a38776f617848415a4d594d43787675644e4e69664d427467343750316a0a6d4e6436433074596e37556f34464550524f3274494d394a4a39492b6f6a4e72724c6f5a33374356477a6a3250572b4c6c6743713179756a5454424c4d4134470a41315564447745422f775145417749486744414d42674e5648524d4241663845416a41414d437347413155644977516b4d434b41494c707871412b2f356447300a6d66436663554e345351543348422f456d4c703046444948634a5369705444334d416f4743437147534d343942414d43413063414d4551434945687a567669550a57435943354c45554f306a4266324d78784e6766773056544562394850484a2f7856392f416941416835536d6b507936703368386c7270354f712f6b516e5a440a6c6854757762793849674a6c776c45555a513d3d0a2d2d2d2d2d454e44202d2d2d2d2d0a
2017-10-12 09:27:09.202 UTC [msp/identity] newIdentity -> DEBU 5c3 Creating identity instance for ID &{Org1MSP 8d1cb71ecb64cd2c7163f4f2f3e653663437cac5e9cbcc5cbc261dbd26166fd2}
2017-10-12 09:27:09.202 UTC [msp] SatisfiesPrincipal -> DEBU 5c4 Checking if identity satisfies ADMIN role for Org1MSP
2017-10-12 09:27:09.202 UTC [cauthdsl] func2 -> DEBU 5c5 0xc420024a80 principal matched by identity 0
2017-10-12 09:27:09.202 UTC [msp/identity] Verify -> DEBU 5c6 Verify: digest = 00000000  14 85 8a 00 06 74 c1 07  53 19 8c e9 34 1f f1 06  |.....t..S...4...|
00000010  e3 26 a3 1d 2d 5a 7d b3  8b a6 eb b4 0f f7 6a bd  |.&..-Z}.......j.|
2017-10-12 09:27:09.202 UTC [msp/identity] Verify -> DEBU 5c7 Verify: sig = 00000000  30 45 02 21 00 fe 75 44  37 92 00 67 9b dd 87 4b  |0E.!..uD7..g...K|
00000010  20 12 7f e6 5f 3c 05 c0  9e 85 6a b4 c0 ac 59 0d  | ..._<....j...Y.|
00000020  87 ff 19 33 a2 02 20 16  74 bf 30 3e 08 1d af e4  |...3.. .t.0>....|
00000030  bf 3b 5d 1e d3 39 8d 5b  0a b6 c6 40 14 e1 b8 d8  |.;]..9.[...@....|
00000040  2d 60 1c 78 df 43 96                              |-`.x.C.|
2017-10-12 09:27:09.203 UTC [cauthdsl] func2 -> DEBU 5c8 0xc420024a80 principal evaluation succeeds for identity 0
2017-10-12 09:27:09.203 UTC [cauthdsl] func1 -> DEBU 5c9 0xc420024a80 gate 1507800429202234200 evaluation succeeds
2017-10-12 09:27:09.203 UTC [cauthdsl] func1 -> DEBU 5ca 0xc420024a80 gate 1507800429203556600 evaluation starts
2017-10-12 09:27:09.203 UTC [cauthdsl] func2 -> DEBU 5cb 0xc420024a80 signed by 0 principal evaluation starts (used [false])
2017-10-12 09:27:09.203 UTC [cauthdsl] func2 -> DEBU 5cc 0xc420024a80 processing identity 0 with bytes of 0a074f7267314d53501280062d2d2d2d2d424547494e202d2d2d2d2d0a4d4949434754434341634367417749424167495241506151334c2b3350726979674d647348363952354a3477436759494b6f5a497a6a304541774977637a454c0a4d416b474131554542684d4356564d78457a415242674e5642416754436b4e6862476c6d62334a7561574578466a415542674e564241635444564e68626942470a636d467559326c7a593238784754415842674e5642416f54454739795a7a45755a586868625842735a53356a623230784844416142674e5642414d5445324e680a4c6d39795a7a45755a586868625842735a53356a623230774868634e4d5463774f5449794d4463314d5451325768634e4d6a63774f5449774d4463314d5451320a576a42624d517377435159445651514745774a56557a45544d4245474131554543424d4b5132467361575a76636d3570595445574d4251474131554542784d4e0a5532467549455a795957356a61584e6a627a45664d4230474131554541777757515752746157354162334a6e4d53356c654746746347786c4c6d4e766254425a0a4d424d4742797147534d34394167454743437147534d34394177454841304941424f6a38776f617848415a4d594d43787675644e4e69664d427467343750316a0a6d4e6436433074596e37556f34464550524f3274494d394a4a39492b6f6a4e72724c6f5a33374356477a6a3250572b4c6c6743713179756a5454424c4d4134470a41315564447745422f775145417749486744414d42674e5648524d4241663845416a41414d437347413155644977516b4d434b41494c707871412b2f356447300a6d66436663554e345351543348422f456d4c703046444948634a5369705444334d416f4743437147534d343942414d43413063414d4551434945687a567669550a57435943354c45554f306a4266324d78784e6766773056544562394850484a2f7856392f416941416835536d6b507936703368386c7270354f712f6b516e5a440a6c6854757762793849674a6c776c45555a513d3d0a2d2d2d2d2d454e44202d2d2d2d2d0a
2017-10-12 09:27:09.205 UTC [msp/identity] newIdentity -> DEBU 5cd Creating identity instance for ID &{Org1MSP 8d1cb71ecb64cd2c7163f4f2f3e653663437cac5e9cbcc5cbc261dbd26166fd2}
2017-10-12 09:27:09.205 UTC [cauthdsl] func2 -> DEBU 5ce 0xc420024a80 identity 0 does not satisfy principal: The identity is a member of a different MSP (expected Org2MSP, got Org1MSP)
2017-10-12 09:27:09.205 UTC [cauthdsl] func2 -> DEBU 5cf 0xc420024a80 principal evaluation fails
2017-10-12 09:27:09.205 UTC [cauthdsl] func1 -> DEBU 5d0 0xc420024a80 gate 1507800429203556600 evaluation fails
2017-10-12 09:27:09.205 UTC [orderer/common/broadcast] Handle -> WARN 5d1 Rejecting CONFIG_UPDATE because: Error authorizing update: Error validating DeltaSet: Policy for [Groups] /Channel/Application not satisfied: Failed to reach implicit threshold of 2 sub-policies, required 1 remaining
2017-10-12 09:27:09.206 UTC [orderer/main] func1 -> DEBU 5d2 Closing Broadcast stream
2017-10-12 09:27:09.210 UTC [orderer/common/deliver] Handle -> WARN 5d3 Error reading from stream: rpc error: code = Canceled desc = context canceled
2017-10-12 09:27:09.210 UTC [orderer/main] func1 -> DEBU 5d4 Closing Deliver stream