Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Consenter error when calling peer channel fetch #650

Closed
YoungHypo opened this issue Oct 25, 2024 · 5 comments
Closed

Consenter error when calling peer channel fetch #650

YoungHypo opened this issue Oct 25, 2024 · 5 comments
Labels
bug

Comments

@YoungHypo
Copy link
Contributor

YoungHypo commented Oct 25, 2024 •
edited
Loading

Bug

The org1.cello.com organization has a peer node and an orderer node, forming the containers order.cello.com and peer.org1.cello.com
When I call "peer channel fetch", the Orderer is unable to service the Peer's request, and a SERVICE_UNAVAILABLE error is reported.

Logs

Orderer Container

It shows that "Rejecting deliver request for XXX because of consenter error".

2024-11-08 19:43:58 Store data in HLF_NODE_MSP to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/config.yaml  
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/keystore/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/admincerts/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/tlscacerts/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/cacerts/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/signcerts/
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/keystore/priv_sk  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/tlscacerts/tlsca.cello.com-cert.pem  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/cacerts/ca.cello.com-cert.pem  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/signcerts/orderer.cello.com-cert.pem  
2024-11-08 19:43:58 msp
2024-11-08 19:43:58 Store data in HLF_NODE_TLS to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/tls/server.key  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/tls/ca.crt  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/tls/server.crt  
2024-11-08 19:43:58 msp  tls
2024-11-08 19:43:58 Store data in HLF_NODE_PEER_CONFIG to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/orderer.yaml  
2024-11-08 19:43:58 msp  orderer.yaml  tls
2024-11-08 19:43:58 Store data in HLF_NODE_ORDERER_CONFIG to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/orderer.yaml  
2024-11-08 19:43:58 msp  orderer.yaml  tls
2024-11-08 19:43:58 Run orderer
2024-11-08 19:43:58 2024-11-09 03:43:58.874 UTC 0001 INFO [localconfig] completeInitialization -> Kafka.Version unset, setting to 0.10.2.0
2024-11-08 19:43:58 2024-11-09 03:43:58.874 UTC 0002 INFO [orderer.common.server] prettyPrintStruct -> Orderer config values:
2024-11-08 19:43:58 General.ListenAddress = "0.0.0.0"
2024-11-08 19:43:58 General.ListenPort = 7050
2024-11-08 19:43:58 General.TLS.Enabled = true
2024-11-08 19:43:58 General.TLS.PrivateKey = "/etc/hyperledger/fabric/tls/server.key"
2024-11-08 19:43:58 General.TLS.Certificate = "/etc/hyperledger/fabric/tls/server.crt"
2024-11-08 19:43:58 General.TLS.RootCAs = [/etc/hyperledger/fabric/tls/ca.crt]
2024-11-08 19:43:58 General.TLS.ClientAuthRequired = false
2024-11-08 19:43:58 General.TLS.ClientRootCAs = []
2024-11-08 19:43:58 General.TLS.TLSHandshakeTimeShift = 0s
2024-11-08 19:43:58 General.Cluster.ListenAddress = ""
2024-11-08 19:43:58 General.Cluster.ListenPort = 0
2024-11-08 19:43:58 General.Cluster.ServerCertificate = ""
2024-11-08 19:43:58 General.Cluster.ServerPrivateKey = ""
2024-11-08 19:43:58 General.Cluster.ClientCertificate = "/etc/hyperledger/fabric/tls/server.crt"
2024-11-08 19:43:58 General.Cluster.ClientPrivateKey = "/etc/hyperledger/fabric/tls/server.key"
2024-11-08 19:43:58 General.Cluster.RootCAs = [/etc/hyperledger/fabric/tls/ca.crt]
2024-11-08 19:43:58 General.Cluster.DialTimeout = 5s
2024-11-08 19:43:58 General.Cluster.RPCTimeout = 7s
2024-11-08 19:43:58 General.Cluster.ReplicationBufferSize = 20971520
2024-11-08 19:43:58 General.Cluster.ReplicationPullTimeout = 5s
2024-11-08 19:43:58 General.Cluster.ReplicationRetryTimeout = 5s
2024-11-08 19:43:58 General.Cluster.ReplicationBackgroundRefreshInterval = 5m0s
2024-11-08 19:43:58 General.Cluster.ReplicationMaxRetries = 12
2024-11-08 19:43:58 General.Cluster.SendBufferSize = 100
2024-11-08 19:43:58 General.Cluster.CertExpirationWarningThreshold = 168h0m0s
2024-11-08 19:43:58 General.Cluster.TLSHandshakeTimeShift = 0s
2024-11-08 19:43:58 General.Keepalive.ServerMinInterval = 1m0s
2024-11-08 19:43:58 General.Keepalive.ServerInterval = 2h0m0s
2024-11-08 19:43:58 General.Keepalive.ServerTimeout = 20s
2024-11-08 19:43:58 General.ConnectionTimeout = 0s
2024-11-08 19:43:58 General.GenesisMethod = ""
2024-11-08 19:43:58 General.GenesisFile = ""
2024-11-08 19:43:58 General.BootstrapMethod = "none"
2024-11-08 19:43:58 General.BootstrapFile = "/etc/hyperledger/fabric/genesisblock"
2024-11-08 19:43:58 General.Profile.Enabled = false
2024-11-08 19:43:58 General.Profile.Address = "0.0.0.0:6060"
2024-11-08 19:43:58 General.LocalMSPDir = "/etc/hyperledger/fabric/msp"
2024-11-08 19:43:58 General.LocalMSPID = "OrdererMSP"
2024-11-08 19:43:58 General.BCCSP.Default = "SW"
2024-11-08 19:43:58 General.BCCSP.SW.Security = 256
2024-11-08 19:43:58 General.BCCSP.SW.Hash = "SHA2"
2024-11-08 19:43:58 General.BCCSP.SW.FileKeystore.KeyStorePath = ""
2024-11-08 19:43:58 General.Authentication.TimeWindow = 15m0s
2024-11-08 19:43:58 General.Authentication.NoExpirationChecks = false
2024-11-08 19:43:58 General.MaxRecvMsgSize = 104857600
2024-11-08 19:43:58 General.MaxSendMsgSize = 104857600
2024-11-08 19:43:58 FileLedger.Location = "/var/hyperledger/production/orderer"
2024-11-08 19:43:58 FileLedger.Prefix = ""
2024-11-08 19:43:58 Kafka.Retry.ShortInterval = 5s
2024-11-08 19:43:58 Kafka.Retry.ShortTotal = 10m0s
2024-11-08 19:43:58 Kafka.Retry.LongInterval = 5m0s
2024-11-08 19:43:58 Kafka.Retry.LongTotal = 12h0m0s
2024-11-08 19:43:58 Kafka.Retry.NetworkTimeouts.DialTimeout = 10s
2024-11-08 19:43:58 Kafka.Retry.NetworkTimeouts.ReadTimeout = 10s
2024-11-08 19:43:58 Kafka.Retry.NetworkTimeouts.WriteTimeout = 10s
2024-11-08 19:43:58 Kafka.Retry.Metadata.RetryMax = 3
2024-11-08 19:43:58 Kafka.Retry.Metadata.RetryBackoff = 250ms
2024-11-08 19:43:58 Kafka.Retry.Producer.RetryMax = 3
2024-11-08 19:43:58 Kafka.Retry.Producer.RetryBackoff = 100ms
2024-11-08 19:43:58 Kafka.Retry.Consumer.RetryBackoff = 2s
2024-11-08 19:43:58 Kafka.Verbose = false
2024-11-08 19:43:58 Kafka.Version = 0.10.2.0
2024-11-08 19:43:58 Kafka.TLS.Enabled = false
2024-11-08 19:43:58 Kafka.TLS.PrivateKey = ""
2024-11-08 19:43:58 Kafka.TLS.Certificate = ""
2024-11-08 19:43:58 Kafka.TLS.RootCAs = []
2024-11-08 19:43:58 Kafka.TLS.ClientAuthRequired = false
2024-11-08 19:43:58 Kafka.TLS.ClientRootCAs = []
2024-11-08 19:43:58 Kafka.TLS.TLSHandshakeTimeShift = 0s
2024-11-08 19:43:58 Kafka.SASLPlain.Enabled = false
2024-11-08 19:43:58 Kafka.SASLPlain.User = ""
2024-11-08 19:43:58 Kafka.SASLPlain.Password = ""
2024-11-08 19:43:58 Kafka.Topic.ReplicationFactor = 3
2024-11-08 19:43:58 Debug.BroadcastTraceDir = ""
2024-11-08 19:43:58 Debug.DeliverTraceDir = ""
2024-11-08 19:43:58 Consensus = map[SnapDir:/var/hyperledger/production/orderer/etcdraft/snapshot WALDir:/var/hyperledger/production/orderer/etcdraft/wal]
2024-11-08 19:43:58 Operations.ListenAddress = "orderer.cello.com:9443"
2024-11-08 19:43:58 Operations.TLS.Enabled = false
2024-11-08 19:43:58 Operations.TLS.PrivateKey = ""
2024-11-08 19:43:58 Operations.TLS.Certificate = ""
2024-11-08 19:43:58 Operations.TLS.RootCAs = []
2024-11-08 19:43:58 Operations.TLS.ClientAuthRequired = false
2024-11-08 19:43:58 Operations.TLS.ClientRootCAs = []
2024-11-08 19:43:58 Operations.TLS.TLSHandshakeTimeShift = 0s
2024-11-08 19:43:58 Metrics.Provider = "prometheus"
2024-11-08 19:43:58 Metrics.Statsd.Network = "udp"
2024-11-08 19:43:58 Metrics.Statsd.Address = "127.0.0.1:8125"
2024-11-08 19:43:58 Metrics.Statsd.WriteInterval = 30s
2024-11-08 19:43:58 Metrics.Statsd.Prefix = ""
2024-11-08 19:43:58 ChannelParticipation.Enabled = true
2024-11-08 19:43:58 ChannelParticipation.MaxRequestBodySize = 1048576
2024-11-08 19:43:58 Admin.ListenAddress = "0.0.0.0:7053"
2024-11-08 19:43:58 Admin.TLS.Enabled = true
2024-11-08 19:43:58 Admin.TLS.PrivateKey = "/etc/hyperledger/fabric/tls/server.key"
2024-11-08 19:43:58 Admin.TLS.Certificate = "/etc/hyperledger/fabric/tls/server.crt"
2024-11-08 19:43:58 Admin.TLS.RootCAs = [/etc/hyperledger/fabric/tls/ca.crt]
2024-11-08 19:43:58 Admin.TLS.ClientAuthRequired = true
2024-11-08 19:43:58 Admin.TLS.ClientRootCAs = [/etc/hyperledger/fabric/tls/ca.crt]
2024-11-08 19:43:58 Admin.TLS.TLSHandshakeTimeShift = 0s
2024-11-08 19:43:58 2024-11-09 03:43:58.883 UTC 0003 INFO [orderer.common.server] initializeServerConfig -> Starting orderer with TLS enabled
2024-11-08 19:43:58 2024-11-09 03:43:58.894 UTC 0004 INFO [blkstorage] NewProvider -> Creating new file ledger directory at /var/hyperledger/production/orderer/chains
2024-11-08 19:43:58 2024-11-09 03:43:58.897 UTC 0005 INFO [orderer.commmon.multichannel] InitJoinBlockFileRepo -> Channel Participation API enabled, registrar initializing with file repo /var/hyperledger/production/orderer/pendingops
2024-11-08 19:43:58 2024-11-09 03:43:58.901 UTC 0006 INFO [orderer.common.server] Main -> Starting without a system channel
2024-11-08 19:43:58 2024-11-09 03:43:58.901 UTC 0007 INFO [orderer.common.server] Main -> Setting up cluster
2024-11-08 19:43:58 2024-11-09 03:43:58.902 UTC 0008 INFO [orderer.common.server] reuseListener -> Cluster listener is not configured, defaulting to use the general listener on port 7050
2024-11-08 19:43:58 2024-11-09 03:43:58.903 UTC 0009 INFO [certmonitor] trackCertExpiration -> The enrollment certificate will expire on 2034-11-07 03:39:00 +0000 UTC
2024-11-08 19:43:58 2024-11-09 03:43:58.903 UTC 000a INFO [certmonitor] trackCertExpiration -> The server TLS certificate will expire on 2034-11-07 03:39:00 +0000 UTC
2024-11-08 19:43:58 2024-11-09 03:43:58.903 UTC 000b INFO [certmonitor] trackCertExpiration -> The client TLS certificate will expire on 2034-11-07 03:39:00 +0000 UTC
2024-11-08 19:43:58 2024-11-09 03:43:58.903 UTC 000c INFO [orderer.commmon.multichannel] InitJoinBlockFileRepo -> Channel Participation API enabled, registrar initializing with file repo /var/hyperledger/production/orderer/pendingops
2024-11-08 19:43:58 2024-11-09 03:43:58.906 UTC 000d INFO [orderer.commmon.multichannel] startChannels -> Registrar initializing without a system channel, number of application channels: 0, with 0 consensus.Chain(s) and 0 follower.Chain(s)
2024-11-08 19:43:58 2024-11-09 03:43:58.909 UTC 000e INFO [orderer.common.server] Main -> Starting orderer:
2024-11-08 19:43:58  Version: 2.5.10
2024-11-08 19:43:58  Commit SHA: development build
2024-11-08 19:43:58  Go version: go1.23.1
2024-11-08 19:43:58  OS/Arch: linux/arm64
2024-11-08 19:43:58 2024-11-09 03:43:58.909 UTC 000f INFO [orderer.common.server] Main -> Beginning to serve requests
2024-11-08 19:44:21 2024-11-09 03:44:21.763 UTC 0010 INFO [blkstorage] newBlockfileMgr -> Getting block information from block storage
2024-11-08 19:44:21 2024-11-09 03:44:21.767 UTC 0011 INFO [orderer.consensus.etcdraft] HandleChain -> EvictionSuspicion not set, defaulting to 10m0s
2024-11-08 19:44:21 2024-11-09 03:44:21.768 UTC 0012 INFO [orderer.consensus.etcdraft] HandleChain -> Without system channel: after eviction Registrar.SwitchToFollower will be called
2024-11-08 19:44:21 2024-11-09 03:44:21.768 UTC 0013 INFO [orderer.consensus.etcdraft] createOrReadWAL -> No WAL data found, creating new WAL at path '/var/hyperledger/production/orderer/etcdraft/wal/channel' channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.796 UTC 0014 INFO [orderer.commmon.multichannel] createAsMember -> Joining channel: {channel  consenter active 1}
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 0015 INFO [orderer.consensus.etcdraft] Start -> Starting Raft node channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 0016 INFO [orderer.common.cluster] Configure -> Entering, channel: channel, nodes: []
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 0017 INFO [orderer.common.cluster] Configure -> Exiting
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 0018 INFO [orderer.consensus.etcdraft] start -> Starting raft node as part of a new channel channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 0019 INFO [orderer.consensus.etcdraft] switchToConfig -> 1 switched to configuration voters=() channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 001a INFO [orderer.consensus.etcdraft] becomeFollower -> 1 became follower at term 0 channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 001b INFO [orderer.consensus.etcdraft] newRaft -> newRaft 1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0] channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 001c INFO [orderer.consensus.etcdraft] becomeFollower -> 1 became follower at term 1 channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.804 UTC 001d INFO [orderer.consensus.etcdraft] switchToConfig -> 1 switched to configuration voters=(1) channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.805 UTC 001e INFO [orderer.consensus.etcdraft] run -> This node is picked to start campaign channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.813 UTC 001f INFO [orderer.consensus.etcdraft] switchToConfig -> 1 switched to configuration voters=(1) channel=channel node=1
2024-11-08 19:44:21 2024-11-09 03:44:21.813 UTC 0020 INFO [orderer.consensus.etcdraft] apply -> Applied config change to add node 1, current nodes in channel: [1] channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.145 UTC 0021 WARN [common.deliver] deliverBlocks -> [channel: channel] Rejecting deliver request for 172.18.0.7:53066 because of consenter error
2024-11-08 19:44:22 2024-11-09 03:44:22.146 UTC 0022 INFO [comm.grpc.server] 1 -> streaming call completed grpc.service=orderer.AtomicBroadcast grpc.method=Deliver grpc.peer_address=172.18.0.7:53066 grpc.code=OK grpc.call_duration=549.291µs
2024-11-08 19:44:22 2024-11-09 03:44:22.250 UTC 0023 WARN [common.deliver] deliverBlocks -> [channel: channel] Rejecting deliver request for 172.18.0.7:53080 because of consenter error
2024-11-08 19:44:22 2024-11-09 03:44:22.250 UTC 0024 INFO [comm.grpc.server] 1 -> streaming call completed grpc.service=orderer.AtomicBroadcast grpc.method=Deliver grpc.peer_address=172.18.0.7:53080 grpc.code=OK grpc.call_duration=169.375µs
2024-11-08 19:44:22 2024-11-09 03:44:22.373 UTC 0025 WARN [common.deliver] deliverBlocks -> [channel: channel] Rejecting deliver request for 172.18.0.7:53096 because of consenter error
2024-11-08 19:44:22 2024-11-09 03:44:22.373 UTC 0026 INFO [comm.grpc.server] 1 -> streaming call completed grpc.service=orderer.AtomicBroadcast grpc.method=Deliver grpc.peer_address=172.18.0.7:53096 grpc.code=OK grpc.call_duration=121.334µs
2024-11-08 19:44:22 2024-11-09 03:44:22.407 UTC 0027 WARN [common.deliver] deliverBlocks -> [channel: channel] Rejecting deliver request for 172.18.0.4:39968 because of consenter error
2024-11-08 19:44:22 2024-11-09 03:44:22.407 UTC 0028 INFO [comm.grpc.server] 1 -> streaming call completed grpc.service=orderer.AtomicBroadcast grpc.method=Deliver grpc.peer_address=172.18.0.4:39968 grpc.code=OK grpc.call_duration=3.103625ms
2024-11-08 19:44:22 2024-11-09 03:44:22.521 UTC 0029 WARN [common.deliver] deliverBlocks -> [channel: channel] Rejecting deliver request for 172.18.0.7:53098 because of consenter error
2024-11-08 19:44:22 2024-11-09 03:44:22.521 UTC 002a INFO [comm.grpc.server] 1 -> streaming call completed grpc.service=orderer.AtomicBroadcast grpc.method=Deliver grpc.peer_address=172.18.0.7:53098 grpc.code=OK grpc.call_duration=182.25µs
2024-11-08 19:44:22 2024-11-09 03:44:22.702 UTC 002b WARN [common.deliver] deliverBlocks -> [channel: channel] Rejecting deliver request for 172.18.0.7:53110 because of consenter error
2024-11-08 19:44:22 2024-11-09 03:44:22.702 UTC 002c INFO [comm.grpc.server] 1 -> streaming call completed grpc.service=orderer.AtomicBroadcast grpc.method=Deliver grpc.peer_address=172.18.0.7:53110 grpc.code=OK grpc.call_duration=452.375µs
2024-11-08 19:44:22 2024-11-09 03:44:22.807 UTC 002d INFO [orderer.consensus.etcdraft] hup -> 1 is starting a new election at term 1 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.807 UTC 002e INFO [orderer.consensus.etcdraft] becomePreCandidate -> 1 became pre-candidate at term 1 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.807 UTC 002f INFO [orderer.consensus.etcdraft] poll -> 1 received MsgPreVoteResp from 1 at term 1 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.807 UTC 0030 INFO [orderer.consensus.etcdraft] becomeCandidate -> 1 became candidate at term 2 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.807 UTC 0031 INFO [orderer.consensus.etcdraft] poll -> 1 received MsgVoteResp from 1 at term 2 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.807 UTC 0032 INFO [orderer.consensus.etcdraft] becomeLeader -> 1 became leader at term 2 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.807 UTC 0033 INFO [orderer.consensus.etcdraft] run -> raft.node: 1 elected leader 1 at term 2 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.809 UTC 0034 INFO [orderer.consensus.etcdraft] run -> Leader 1 is present, quit campaign channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.809 UTC 0035 INFO [orderer.consensus.etcdraft] run -> Raft leader changed: 0 -> 1 channel=channel node=1
2024-11-08 19:44:22 2024-11-09 03:44:22.809 UTC 0036 INFO [orderer.consensus.etcdraft] run -> Start accepting requests as Raft leader at block [0] channel=channel node=1

Peer Container

It shows that " Got error while attempting to receive blocks: received bad status SERVICE_UNAVAILABLE".

2024-11-08 19:43:58 Store data in HLF_NODE_MSP to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/config.yaml  
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/keystore/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/admincerts/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/tlscacerts/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/cacerts/
2024-11-08 19:43:58    creating: /etc/hyperledger/fabric/msp/signcerts/
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/keystore/priv_sk  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/tlscacerts/tlsca.org1.cello.com-cert.pem  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/cacerts/ca.org1.cello.com-cert.pem  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/msp/signcerts/peer.org1.cello.com-cert.pem  
2024-11-08 19:43:58 msp
2024-11-08 19:43:58 Store data in HLF_NODE_TLS to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/tls/server.key  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/tls/ca.crt  
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/tls/server.crt  
2024-11-08 19:43:58 msp  tls
2024-11-08 19:43:58 Store data in HLF_NODE_PEER_CONFIG to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/core.yaml  
2024-11-08 19:43:58 core.yaml  msptls
2024-11-08 19:43:58 Store data in HLF_NODE_ORDERER_CONFIG to /etc/hyperledger/fabric
2024-11-08 19:43:58 Archive:  /tmp/1.zip
2024-11-08 19:43:58  extracting: /etc/hyperledger/fabric/core.yaml  
2024-11-08 19:43:58 core.yaml  msptls
2024-11-08 19:43:58 Run peer node start
2024-11-08 19:43:58 2024-11-09 03:43:58.886 UTC 0001 INFO [nodeCmd] serve -> Starting peer:
2024-11-08 19:43:58  Version: 2.5.10
2024-11-08 19:43:58  Commit SHA: development build
2024-11-08 19:43:58  Go version: go1.23.1
2024-11-08 19:43:58  OS/Arch: linux/arm64
2024-11-08 19:43:58  Chaincode:
2024-11-08 19:43:58   Base Docker Label: org.hyperledger.fabric
2024-11-08 19:43:58   Docker Namespace: hyperledger
2024-11-08 19:43:58 2024-11-09 03:43:58.887 UTC 0002 INFO [nodeCmd] serve -> Peer config with combined core.yaml settings and environment variable overrides:
2024-11-08 19:43:58 chaincode:
2024-11-08 19:43:58   builder: $(DOCKER_NS)/fabric-ccenv:$(TWO_DIGIT_VERSION)
2024-11-08 19:43:58   executetimeout: 30s
2024-11-08 19:43:58   externalbuilders:
2024-11-08 19:43:58   - name: ccaas_builder
2024-11-08 19:43:58     path: /opt/hyperledger/ccaas_builder
2024-11-08 19:43:58     propagateEnvironment:
2024-11-08 19:43:58     - CHAINCODE_AS_A_SERVICE_BUILDER_CONFIG
2024-11-08 19:43:58   golang:
2024-11-08 19:43:58     dynamiclink: false
2024-11-08 19:43:58     runtime: $(DOCKER_NS)/fabric-baseos:$(TWO_DIGIT_VERSION)
2024-11-08 19:43:58   installtimeout: 300s
2024-11-08 19:43:58   java:
2024-11-08 19:43:58     runtime: $(DOCKER_NS)/fabric-javaenv:$(TWO_DIGIT_VERSION)
2024-11-08 19:43:58   keepalive: 0
2024-11-08 19:43:58   logging:
2024-11-08 19:43:58     format: '%{color}%{time:2006-01-02 15:04:05.000 MST} [%{module}] %{shortfunc}
2024-11-08 19:43:58       -> %{level:.4s} %{id:03x}%{color:reset} %{message}'
2024-11-08 19:43:58     level: info
2024-11-08 19:43:58     shim: warning
2024-11-08 19:43:58   mode: net
2024-11-08 19:43:58   node:
2024-11-08 19:43:58     runtime: $(DOCKER_NS)/fabric-nodeenv:$(TWO_DIGIT_VERSION)
2024-11-08 19:43:58   pull: false
2024-11-08 19:43:58   startuptimeout: 300s
2024-11-08 19:43:58   system:
2024-11-08 19:43:58     _lifecycle: enable
2024-11-08 19:43:58     cscc: enable
2024-11-08 19:43:58     lscc: enable
2024-11-08 19:43:58     qscc: enable
2024-11-08 19:43:58 ledger:
2024-11-08 19:43:58   history:
2024-11-08 19:43:58     enablehistorydatabase: true
2024-11-08 19:43:58   pvtdatastore:
2024-11-08 19:43:58     collelgprocdbbatchesinterval: 1000
2024-11-08 19:43:58     collelgprocmaxdbbatchsize: 5000
2024-11-08 19:43:58     deprioritizeddatareconcilerinterval: 60m
2024-11-08 19:43:58     purgedkeyauditlogging: true
2024-11-08 19:43:58     purgeinterval: 100
2024-11-08 19:43:58   snapshots:
2024-11-08 19:43:58     rootdir: /var/hyperledger/production/snapshots
2024-11-08 19:43:58   state:
2024-11-08 19:43:58     couchdbconfig:
2024-11-08 19:43:58       cachesize: 64
2024-11-08 19:43:58       couchdbaddress: 127.0.0.1:5984
2024-11-08 19:43:58       createglobalchangesdb: false
2024-11-08 19:43:58       internalquerylimit: 1000
2024-11-08 19:43:58       maxbatchupdatesize: 1000
2024-11-08 19:43:58       maxretries: 3
2024-11-08 19:43:58       maxretriesonstartup: 10
2024-11-08 19:43:58       requesttimeout: 35s
2024-11-08 19:43:58     statedatabase: goleveldb
2024-11-08 19:43:58     totalquerylimit: 100000
2024-11-08 19:43:58 logging_level: ""
2024-11-08 19:43:58 metrics:
2024-11-08 19:43:58   provider: prometheus
2024-11-08 19:43:58   statsd:
2024-11-08 19:43:58     address: 127.0.0.1:8125
2024-11-08 19:43:58     network: udp
2024-11-08 19:43:58     writeinterval: 10s
2024-11-08 19:43:58 operations:
2024-11-08 19:43:58   listenaddress: peer.org1.cello.com:9444
2024-11-08 19:43:58   tls:
2024-11-08 19:43:58     clientauthrequired: false
2024-11-08 19:43:58     clientrootcas:
2024-11-08 19:43:58       files: []
2024-11-08 19:43:58     enabled: false
2024-11-08 19:43:58 peer:
2024-11-08 19:43:58   address: peer.org1.cello.com:7051
2024-11-08 19:43:58   addressautodetect: false
2024-11-08 19:43:58   authentication:
2024-11-08 19:43:58     timewindow: 15m
2024-11-08 19:43:58   bccsp:
2024-11-08 19:43:58     default: SW
2024-11-08 19:43:58     sw:
2024-11-08 19:43:58       hash: SHA2
2024-11-08 19:43:58       security: 256
2024-11-08 19:43:58   client:
2024-11-08 19:43:58     conntimeout: 3s
2024-11-08 19:43:58   deliveryclient:
2024-11-08 19:43:58     blockgossipenabled: true
2024-11-08 19:43:58     conntimeout: 3s
2024-11-08 19:43:58     reconnectbackoffthreshold: 3600s
2024-11-08 19:43:58     reconnecttotaltimethreshold: 3600s
2024-11-08 19:43:58   discovery:
2024-11-08 19:43:58     authcacheenabled: true
2024-11-08 19:43:58     authcachemaxsize: 1000
2024-11-08 19:43:58     authcachepurgeretentionratio: 0.75
2024-11-08 19:43:58     enabled: true
2024-11-08 19:43:58     orgmembersallowedaccess: false
2024-11-08 19:43:58   filesystempath: /var/hyperledger/production
2024-11-08 19:43:58   gateway:
2024-11-08 19:43:58     broadcasttimeout: 30s
2024-11-08 19:43:58     dialtimeout: 2m
2024-11-08 19:43:58     enabled: true
2024-11-08 19:43:58     endorsementtimeout: 30s
2024-11-08 19:43:58   gossip:
2024-11-08 19:43:58     aliveexpirationtimeout: 25s
2024-11-08 19:43:58     alivetimeinterval: 5s
2024-11-08 19:43:58     bootstrap: peer.org1.cello.com:7051
2024-11-08 19:43:58     conntimeout: 2s
2024-11-08 19:43:58     dialtimeout: 3s
2024-11-08 19:43:58     digestwaittime: 1s
2024-11-08 19:43:58     election:
2024-11-08 19:43:58       leaderalivethreshold: 10s
2024-11-08 19:43:58       leaderelectionduration: 5s
2024-11-08 19:43:58       membershipsampleinterval: 1s
2024-11-08 19:43:58       startupgraceperiod: 15s
2024-11-08 19:43:58     externalendpoint: peer.org1.cello.com:7051
2024-11-08 19:43:58     maxblockcounttostore: 10
2024-11-08 19:43:58     maxconnectionattempts: 120
2024-11-08 19:43:58     maxpropagationburstlatency: 10ms
2024-11-08 19:43:58     maxpropagationburstsize: 10
2024-11-08 19:43:58     membershiptrackerinterval: 5s
2024-11-08 19:43:58     msgexpirationfactor: 20
2024-11-08 19:43:58     orgleader: true
2024-11-08 19:43:58     propagateiterations: 1
2024-11-08 19:43:58     propagatepeernum: 3
2024-11-08 19:43:58     publishcertperiod: 10s
2024-11-08 19:43:58     publishstateinfointerval: 4s
2024-11-08 19:43:58     pullinterval: 4s
2024-11-08 19:43:58     pullpeernum: 3
2024-11-08 19:43:58     pvtdata:
2024-11-08 19:43:58       btlpullmargin: 10
2024-11-08 19:43:58       implicitcollectiondisseminationpolicy:
2024-11-08 19:43:58         maxpeercount: 1
2024-11-08 19:43:58         requiredpeercount: 0
2024-11-08 19:43:58       pullretrythreshold: 60s
2024-11-08 19:43:58       pushacktimeout: 3s
2024-11-08 19:43:58       reconcilebatchsize: 10
2024-11-08 19:43:58       reconcilesleepinterval: 1m
2024-11-08 19:43:58       reconciliationenabled: true
2024-11-08 19:43:58       skippullinginvalidtransactionsduringcommit: false
2024-11-08 19:43:58       transientstoremaxblockretention: 1000
2024-11-08 19:43:58     reconnectinterval: 25s
2024-11-08 19:43:58     recvbuffsize: 20
2024-11-08 19:43:58     requeststateinfointerval: 4s
2024-11-08 19:43:58     requestwaittime: 1500ms
2024-11-08 19:43:58     responsewaittime: 2s
2024-11-08 19:43:58     sendbuffsize: 200
2024-11-08 19:43:58     skipblockverification: false
2024-11-08 19:43:58     state:
2024-11-08 19:43:58       batchsize: 10
2024-11-08 19:43:58       blockbuffersize: 20
2024-11-08 19:43:58       checkinterval: 10s
2024-11-08 19:43:58       enabled: false
2024-11-08 19:43:58       maxretries: 3
2024-11-08 19:43:58       responsetimeout: 3s
2024-11-08 19:43:58     useleaderelection: false
2024-11-08 19:43:58   handlers:
2024-11-08 19:43:58     authfilters:
2024-11-08 19:43:58     - name: DefaultAuth
2024-11-08 19:43:58     - name: ExpirationCheck
2024-11-08 19:43:58     decorators:
2024-11-08 19:43:58     - name: DefaultDecorator
2024-11-08 19:43:58     endorsers:
2024-11-08 19:43:58       escc:
2024-11-08 19:43:58         name: DefaultEndorsement
2024-11-08 19:43:58     validators:
2024-11-08 19:43:58       vscc:
2024-11-08 19:43:58         name: DefaultValidation
2024-11-08 19:43:58   id: peer.org1.cello.com
2024-11-08 19:43:58   keepalive:
2024-11-08 19:43:58     client:
2024-11-08 19:43:58       interval: 60s
2024-11-08 19:43:58       timeout: 20s
2024-11-08 19:43:58     deliveryclient:
2024-11-08 19:43:58       interval: 60s
2024-11-08 19:43:58       timeout: 20s
2024-11-08 19:43:58     interval: 7200s
2024-11-08 19:43:58     mininterval: 60s
2024-11-08 19:43:58     timeout: 20s
2024-11-08 19:43:58   limits:
2024-11-08 19:43:58     concurrency:
2024-11-08 19:43:58       deliverservice: 2500
2024-11-08 19:43:58       endorserservice: 2500
2024-11-08 19:43:58       gatewayservice: 500
2024-11-08 19:43:58   listenaddress: 0.0.0.0:7051
2024-11-08 19:43:58   localmspid: Org1MSP
2024-11-08 19:43:58   localmsptype: bccsp
2024-11-08 19:43:58   maxrecvmsgsize: 104857600
2024-11-08 19:43:58   maxsendmsgsize: 104857600
2024-11-08 19:43:58   mspconfigpath: /etc/hyperledger/fabric/msp
2024-11-08 19:43:58   networkid: dev
2024-11-08 19:43:58   profile:
2024-11-08 19:43:58     enabled: "false"
2024-11-08 19:43:58     listenaddress: 0.0.0.0:6060
2024-11-08 19:43:58   tls:
2024-11-08 19:43:58     cert:
2024-11-08 19:43:58       file: /etc/hyperledger/fabric/tls/server.crt
2024-11-08 19:43:58     clientauthrequired: false
2024-11-08 19:43:58     clientrootcas:
2024-11-08 19:43:58       files:
2024-11-08 19:43:58       - tls/ca.crt
2024-11-08 19:43:58     enabled: "true"
2024-11-08 19:43:58     key:
2024-11-08 19:43:58       file: /etc/hyperledger/fabric/tls/server.key
2024-11-08 19:43:58     rootcert:
2024-11-08 19:43:58       file: /etc/hyperledger/fabric/tls/ca.crt
2024-11-08 19:43:58 vm:
2024-11-08 19:43:58   docker:
2024-11-08 19:43:58     attachstdout: false
2024-11-08 19:43:58     hostconfig:
2024-11-08 19:43:58       logconfig:
2024-11-08 19:43:58         config:
2024-11-08 19:43:58           max-file: "5"
2024-11-08 19:43:58           max-size: 50m
2024-11-08 19:43:58         type: json-file
2024-11-08 19:43:58       memory: 2147483648
2024-11-08 19:43:58       networkmode: cello-net
2024-11-08 19:43:58     tls:
2024-11-08 19:43:58       ca:
2024-11-08 19:43:58         file: docker/ca.crt
2024-11-08 19:43:58       cert:
2024-11-08 19:43:58         file: docker/tls.crt
2024-11-08 19:43:58       enabled: false
2024-11-08 19:43:58       key:
2024-11-08 19:43:58         file: docker/tls.key
2024-11-08 19:43:58   endpoint: unix:///host/var/run/docker.sock
2024-11-08 19:43:58 2024-11-09 03:43:58.887 UTC 0003 INFO [peer] getLocalAddress -> Auto-detected peer address: 172.18.0.7:7051
2024-11-08 19:43:58 2024-11-09 03:43:58.887 UTC 0004 INFO [peer] getLocalAddress -> Returning peer.org1.cello.com:7051
2024-11-08 19:43:58 2024-11-09 03:43:58.891 UTC 0005 INFO [nodeCmd] initGrpcSemaphores -> concurrency limit for endorser service is 2500
2024-11-08 19:43:58 2024-11-09 03:43:58.891 UTC 0006 INFO [nodeCmd] initGrpcSemaphores -> concurrency limit for deliver service is 2500
2024-11-08 19:43:58 2024-11-09 03:43:58.891 UTC 0007 INFO [nodeCmd] initGrpcSemaphores -> concurrency limit for gateway service is 500
2024-11-08 19:43:58 2024-11-09 03:43:58.891 UTC 0008 INFO [nodeCmd] serve -> Starting peer with TLS enabled
2024-11-08 19:43:58 2024-11-09 03:43:58.907 UTC 0009 INFO [certmonitor] trackCertExpiration -> The enrollment certificate will expire on 2034-11-07 03:39:00 +0000 UTC
2024-11-08 19:43:58 2024-11-09 03:43:58.907 UTC 000a INFO [certmonitor] trackCertExpiration -> The server TLS certificate will expire on 2034-11-07 03:39:00 +0000 UTC
2024-11-08 19:43:58 2024-11-09 03:43:58.909 UTC 000b INFO [ledgermgmt] NewLedgerMgr -> Initializing LedgerMgr
2024-11-08 19:43:58 2024-11-09 03:43:58.932 UTC 000c INFO [leveldbhelper] openDBAndCheckFormat -> DB is empty Setting db format as 2.0
2024-11-08 19:43:58 2024-11-09 03:43:58.933 UTC 000d INFO [blkstorage] NewProvider -> Creating new file ledger directory at /var/hyperledger/production/ledgersData/chains/chains
2024-11-08 19:43:58 2024-11-09 03:43:58.953 UTC 000e INFO [leveldbhelper] openDBAndCheckFormat -> DB is empty Setting db format as 2.5
2024-11-08 19:43:58 2024-11-09 03:43:58.962 UTC 000f INFO [leveldbhelper] openDBAndCheckFormat -> DB is empty Setting db format as 2.0
2024-11-08 19:43:58 2024-11-09 03:43:58.981 UTC 0010 INFO [leveldbhelper] openDBAndCheckFormat -> DB is empty Setting db format as 2.0
2024-11-08 19:43:58 2024-11-09 03:43:58.983 UTC 0011 INFO [ledgermgmt] NewLedgerMgr -> Initialized LedgerMgr
2024-11-08 19:43:58 2024-11-09 03:43:58.984 UTC 0012 INFO [gossip.service] New -> Initialize gossip with endpoint peer.org1.cello.com:7051
2024-11-08 19:43:58 2024-11-09 03:43:58.986 UTC 0013 INFO [gossip.gossip] New -> Creating gossip service with self membership of Endpoint: peer.org1.cello.com:7051, InternalEndpoint: peer.org1.cello.com:7051, PKI-ID: e8c1c840d73ee1fb8e3aace8dcf19831db615d17d713401c904c7df149dc96e9, Metadata: 
2024-11-08 19:43:58 2024-11-09 03:43:58.986 UTC 0014 INFO [lifecycle] InitializeLocalChaincodes -> Initialized lifecycle cache with 0 already installed chaincodes
2024-11-08 19:43:58 2024-11-09 03:43:58.987 UTC 0015 INFO [nodeCmd] computeChaincodeEndpoint -> Entering computeChaincodeEndpoint with peerHostname: peer.org1.cello.com
2024-11-08 19:43:58 2024-11-09 03:43:58.987 UTC 0016 INFO [nodeCmd] computeChaincodeEndpoint -> Exit with ccEndpoint: peer.org1.cello.com:7052
2024-11-08 19:43:58 2024-11-09 03:43:58.987 UTC 0017 INFO [gossip.gossip] start -> Gossip instance peer.org1.cello.com:7051 started
2024-11-08 19:43:58 2024-11-09 03:43:58.989 UTC 0018 INFO [sccapi] DeploySysCC -> deploying system chaincode 'lscc'
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 0019 INFO [sccapi] DeploySysCC -> deploying system chaincode 'cscc'
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 001a INFO [sccapi] DeploySysCC -> deploying system chaincode 'qscc'
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 001b INFO [sccapi] DeploySysCC -> deploying system chaincode '_lifecycle'
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 001c INFO [nodeCmd] serve -> Deployed system chaincodes
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 001d INFO [discovery] NewService -> Created with config TLS: true, authCacheMaxSize: 1000, authCachePurgeRatio: 0.750000
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 001e INFO [nodeCmd] serve -> Discovery service activated
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 001f INFO [nodeCmd] serve -> Starting peer with Gateway enabled
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 0020 INFO [nodeCmd] serve -> Starting peer with ID=[peer.org1.cello.com], network ID=[dev], address=[peer.org1.cello.com:7051]
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 0021 INFO [nodeCmd] serve -> Started peer with ID=[peer.org1.cello.com], network ID=[dev], address=[peer.org1.cello.com:7051]
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 0022 INFO [kvledger] LoadPreResetHeight -> Loading prereset height from path [/var/hyperledger/production/ledgersData/chains]
2024-11-08 19:43:58 2024-11-09 03:43:58.990 UTC 0023 INFO [blkstorage] preResetHtFiles -> No active channels passed
2024-11-08 19:44:22 2024-11-09 03:44:22.130 UTC 0024 INFO [ledgermgmt] CreateLedger -> Creating ledger [channel] with genesis block
2024-11-08 19:44:22 2024-11-09 03:44:22.131 UTC 0025 INFO [blkstorage] newBlockfileMgr -> Getting block information from block storage
2024-11-08 19:44:22 2024-11-09 03:44:22.135 UTC 0026 INFO [kvledger] commit -> [channel] Committed block [0] with 1 transaction(s) in 2ms (state_validation=0ms block_and_pvtdata_commit=0ms state_commit=0ms) commitHash=[]
2024-11-08 19:44:22 2024-11-09 03:44:22.135 UTC 0027 INFO [kvledger] updateLedgerStatus -> Updating ledger [channel] status to [ACTIVE]
2024-11-08 19:44:22 2024-11-09 03:44:22.135 UTC 0028 INFO [ledgermgmt] CreateLedger -> Created ledger [channel] with genesis block
2024-11-08 19:44:22 2024-11-09 03:44:22.139 UTC 0029 WARN [peer.orderers] Update -> Config defines both orderer org specific endpoints and global endpoints, global endpoints will be ignored channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.139 UTC 002a INFO [gossip.gossip] JoinChan -> Joining gossip network of channel channel with 1 organizations
2024-11-08 19:44:22 2024-11-09 03:44:22.139 UTC 002b INFO [gossip.gossip] learnAnchorPeers -> No configured anchor peers of Org1MSP for channel channel to learn about
2024-11-08 19:44:22 2024-11-09 03:44:22.139 UTC 002c INFO [gateway] configUpdate -> Updating orderer config channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.140 UTC 002d INFO [gossip.state] NewGossipStateProvider -> Updating metadata information for channel channel, current ledger sequence is at = 0, next expected block is = 1
2024-11-08 19:44:22 2024-11-09 03:44:22.140 UTC 002e INFO [deliveryClient] StartDeliverForChannel -> This peer will retrieve blocks from ordering service and disseminate to other peers in the organization channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.141 UTC 002f INFO [endorser] callChaincode -> finished chaincode: cscc duration: 16ms channel= txID=03eed81d
2024-11-08 19:44:22 2024-11-09 03:44:22.141 UTC 0030 INFO [comm.grpc.server] 1 -> unary call completed grpc.service=protos.Endorser grpc.method=ProcessProposal grpc.peer_address=172.18.0.4:58104 grpc.code=OK grpc.call_duration=26.309792ms
2024-11-08 19:44:22 2024-11-09 03:44:22.145 UTC 0031 INFO [peer.blocksprovider] DeliverBlocks -> Pulling next blocks from ordering service channel=channel orderer-address=orderer.cello.com:7050 nextBlock=1
2024-11-08 19:44:22 2024-11-09 03:44:22.146 UTC 0032 WARN [peer.blocksprovider] func1 -> Encountered an error reading from deliver stream: EOF channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.146 UTC 0033 WARN [peer.blocksprovider] DeliverBlocks -> Got error while attempting to receive blocks: received bad status SERVICE_UNAVAILABLE from orderer channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.146 UTC 0034 WARN [peer.blocksprovider] DeliverBlocks -> Disconnected from ordering service. Attempt to re-connect in 100ms channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.250 UTC 0035 INFO [peer.blocksprovider] DeliverBlocks -> Pulling next blocks from ordering service channel=channel orderer-address=orderer.cello.com:7050 nextBlock=1
2024-11-08 19:44:22 2024-11-09 03:44:22.250 UTC 0036 WARN [peer.blocksprovider] func1 -> Encountered an error reading from deliver stream: EOF channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.250 UTC 0037 WARN [peer.blocksprovider] DeliverBlocks -> Got error while attempting to receive blocks: received bad status SERVICE_UNAVAILABLE from orderer channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.250 UTC 0038 WARN [peer.blocksprovider] DeliverBlocks -> Disconnected from ordering service. Attempt to re-connect in 120ms channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.373 UTC 0039 INFO [peer.blocksprovider] DeliverBlocks -> Pulling next blocks from ordering service channel=channel orderer-address=orderer.cello.com:7050 nextBlock=1
2024-11-08 19:44:22 2024-11-09 03:44:22.373 UTC 003a WARN [peer.blocksprovider] func1 -> Encountered an error reading from deliver stream: EOF channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.373 UTC 003b WARN [peer.blocksprovider] DeliverBlocks -> Got error while attempting to receive blocks: received bad status SERVICE_UNAVAILABLE from orderer channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.373 UTC 003c WARN [peer.blocksprovider] DeliverBlocks -> Disconnected from ordering service. Attempt to re-connect in 144ms channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.521 UTC 003d INFO [peer.blocksprovider] DeliverBlocks -> Pulling next blocks from ordering service channel=channel orderer-address=orderer.cello.com:7050 nextBlock=1
2024-11-08 19:44:22 2024-11-09 03:44:22.521 UTC 003e WARN [peer.blocksprovider] func1 -> Encountered an error reading from deliver stream: EOF channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.522 UTC 003f WARN [peer.blocksprovider] DeliverBlocks -> Got error while attempting to receive blocks: received bad status SERVICE_UNAVAILABLE from orderer channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.522 UTC 0040 WARN [peer.blocksprovider] DeliverBlocks -> Disconnected from ordering service. Attempt to re-connect in 172ms channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.701 UTC 0041 INFO [peer.blocksprovider] DeliverBlocks -> Pulling next blocks from ordering service channel=channel orderer-address=orderer.cello.com:7050 nextBlock=1
2024-11-08 19:44:22 2024-11-09 03:44:22.702 UTC 0042 WARN [peer.blocksprovider] DeliverBlocks -> Got error while attempting to receive blocks: received bad status SERVICE_UNAVAILABLE from orderer channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.702 UTC 0043 WARN [peer.blocksprovider] func1 -> Encountered an error reading from deliver stream: EOF channel=channel orderer-address=orderer.cello.com:7050
2024-11-08 19:44:22 2024-11-09 03:44:22.702 UTC 0044 WARN [peer.blocksprovider] DeliverBlocks -> Disconnected from ordering service. Attempt to re-connect in 207ms channel=channel
2024-11-08 19:44:22 2024-11-09 03:44:22.925 UTC 0045 INFO [peer.blocksprovider] DeliverBlocks -> Pulling next blocks from ordering service channel=channel orderer-address=orderer.cello.com:7050 nextBlock=1

cello-api-engine

When peer channel fetch, it shows that "can't read the block: &{SERVICE_UNAVAILABLE}".

2024-11-08 19:43:08 Generating the settings.py for api_engine
2024-11-08 19:43:11 Migrations for 'api':
2024-11-08 19:43:11   api/migrations/0001_initial.py
2024-11-08 19:43:11     - Create model UserProfile
2024-11-08 19:43:11     - Create model Agent
2024-11-08 19:43:11     - Create model ChainCode
2024-11-08 19:43:11     - Create model FabricCA
2024-11-08 19:43:11     - Create model FabricPeer
2024-11-08 19:43:11     - Create model Govern
2024-11-08 19:43:11     - Create model Network
2024-11-08 19:43:11     - Create model Node
2024-11-08 19:43:11     - Create model NodeUser
2024-11-08 19:43:11     - Create model Organization
2024-11-08 19:43:11     - Create model PeerCa
2024-11-08 19:43:11     - Create model User
2024-11-08 19:43:11     - Create model Port
2024-11-08 19:43:11     - Create model PeerCaUser
2024-11-08 19:43:11     - Add field organization to node
2024-11-08 19:43:11     - Add field user to node
2024-11-08 19:43:11     - Create model KubernetesConfig
2024-11-08 19:43:11     - Create model File
2024-11-08 19:43:11     - Create model Channel
2024-11-08 19:43:11     - Add field organization to agent
2024-11-08 19:43:11     - Add field organization to userprofile
2024-11-08 19:43:11     - Add field user_permissions to userprofile
2024-11-08 19:43:12 Operations to perform:
2024-11-08 19:43:12   Apply all migrations: account, api, auth, authtoken, contenttypes, sessions, sites, socialaccount
2024-11-08 19:43:12 Running migrations:
2024-11-08 19:43:12   Applying contenttypes.0001_initial... OK
2024-11-08 19:43:12   Applying contenttypes.0002_remove_content_type_name... OK
2024-11-08 19:43:12   Applying auth.0001_initial... OK
2024-11-08 19:43:12   Applying auth.0002_alter_permission_name_max_length... OK
2024-11-08 19:43:12   Applying auth.0003_alter_user_email_max_length... OK
2024-11-08 19:43:12   Applying auth.0004_alter_user_username_opts... OK
2024-11-08 19:43:12   Applying auth.0005_alter_user_last_login_null... OK
2024-11-08 19:43:12   Applying auth.0006_require_contenttypes_0002... OK
2024-11-08 19:43:12   Applying auth.0007_alter_validators_add_error_messages... OK
2024-11-08 19:43:12   Applying auth.0008_alter_user_username_max_length... OK
2024-11-08 19:43:12   Applying auth.0009_alter_user_last_name_max_length... OK
2024-11-08 19:43:12   Applying auth.0010_alter_group_name_max_length... OK
2024-11-08 19:43:12   Applying auth.0011_update_proxy_permissions... OK
2024-11-08 19:43:12   Applying auth.0012_alter_user_first_name_max_length... OK
2024-11-08 19:43:13   Applying api.0001_initial... OK
2024-11-08 19:43:13   Applying account.0001_initial... OK
2024-11-08 19:43:13   Applying account.0002_email_max_length... OK
2024-11-08 19:43:13   Applying account.0003_alter_emailaddress_create_unique_verified_email... OK
2024-11-08 19:43:13   Applying account.0004_alter_emailaddress_drop_unique_email... OK
2024-11-08 19:43:13   Applying authtoken.0001_initial... OK
2024-11-08 19:43:13   Applying authtoken.0002_auto_20160226_1747... OK
2024-11-08 19:43:13   Applying authtoken.0003_tokenproxy... OK
2024-11-08 19:43:13   Applying authtoken.0004_alter_tokenproxy_options... OK
2024-11-08 19:43:13   Applying sessions.0001_initial... OK
2024-11-08 19:43:13   Applying sites.0001_initial... OK
2024-11-08 19:43:13   Applying sites.0002_alter_domain_unique... OK
2024-11-08 19:43:13   Applying socialaccount.0001_initial... OK
2024-11-08 19:43:13   Applying socialaccount.0002_token_max_lengths... OK
2024-11-08 19:43:13   Applying socialaccount.0003_extra_data_default_dict... OK
2024-11-08 19:43:13   Applying socialaccount.0004_app_provider_id_settings... OK
2024-11-08 19:43:14 Create user successfully 0e13ce7d-244e-4e9c-b07f-4c3e27db2f8e
2024-11-08 19:43:54 org1.cello.com
2024-11-08 19:44:21 Status: 201
2024-11-08 19:44:21 {
2024-11-08 19:44:21 "name": "channel",
2024-11-08 19:44:21 "url": "/participation/v1/channels/channel",
2024-11-08 19:44:21 "consensusRelation": "consenter",
2024-11-08 19:44:21 "status": "active",
2024-11-08 19:44:21 "height": 1
2024-11-08 19:44:21 }
2024-11-08 19:44:21 
2024-11-08 19:43:54 INFO Running command: /opt/bin/cryptogen generate --output=/opt/cello/org1.cello.com/crypto-config --config=/opt/cello/org1.cello.com/crypto-config.yaml
2024-11-08 19:43:55 INFO Running command: /opt/bin/cryptogen extend --input=/opt/cello/org1.cello.com/crypto-config --config=/opt/cello/org1.cello.com/crypto-config.yaml
2024-11-08 19:43:55 INFO Running command: /opt/bin/cryptogen extend --input=/opt/cello/org1.cello.com/crypto-config --config=/opt/cello/org1.cello.com/crypto-config.yaml
2024-11-08 19:44:21 INFO Running command: /opt/bin/configtxgen -configPath /opt/cello/net/ -profile channel -outputBlock /opt/cello/net/channel.block -channelID channel
2024-11-08 19:44:21 2024-11-09 03:44:21.544 UTC 0001 INFO [common.tools.configtxgen] main -> Loading configuration
2024-11-08 19:44:21 2024-11-09 03:44:21.570 UTC 0002 INFO [common.tools.configtxgen.localconfig] completeInitialization -> orderer type: etcdraft
2024-11-08 19:44:21 2024-11-09 03:44:21.570 UTC 0003 INFO [common.tools.configtxgen.localconfig] Load -> Loaded configuration: /opt/cello/net/configtx.yaml
2024-11-08 19:44:21 2024-11-09 03:44:21.587 UTC 0004 INFO [common.tools.configtxgen] doOutputBlock -> Generating genesis block
2024-11-08 19:44:21 2024-11-09 03:44:21.587 UTC 0005 INFO [common.tools.configtxgen] doOutputBlock -> Creating application channel genesis block
2024-11-08 19:44:21 2024-11-09 03:44:21.591 UTC 0006 INFO [common.tools.configtxgen] doOutputBlock -> Writing genesis block
2024-11-08 19:44:21 INFO /opt/bin/osnadmin channel join --channelID channel --config-block /opt/cello/net/channel.block -o orderer.cello.com:7053 --ca-file /opt/cello/org1.cello.com/crypto-config/ordererOrganizations/cello.com/orderers/orderer.cello.com/msp/tlscacerts/tlsca.cello.com-cert.pem --client-cert /opt/cello/org1.cello.com/crypto-config/ordererOrganizations/cello.com/orderers/orderer.cello.com/tls/server.crt --client-key /opt/cello/org1.cello.com/crypto-config/ordererOrganizations/cello.com/orderers/orderer.cello.com/tls/server.key
2024-11-08 19:44:21 INFO /opt/bin/peer channel join -b /opt/cello/net/channel.block 
2024-11-08 19:44:22 2024-11-09 03:44:22.106 UTC 0001 INFO [channelCmd] InitCmdFactory -> Endorser and orderer connections initialized
2024-11-08 19:44:22 2024-11-09 03:44:22.145 UTC 0002 INFO [channelCmd] executeJoin -> Successfully submitted proposal to join channel
2024-11-08 19:44:22 INFO /opt/bin/peer channel fetch config /opt/cello/net/config_block.pb -o orderer.cello.com:7050 --ordererTLSHostnameOverride orderer.cello.com -c channel --tls --cafile /opt/cello/org1.cello.com/crypto-config/ordererOrganizations/cello.com/orderers/orderer.cello.com/msp/tlscacerts/tlsca.cello.com-cert.pem
2024-11-08 19:44:22 2024-11-09 03:44:22.403 UTC 0001 INFO [channelCmd] InitCmdFactory -> Endorser and orderer connections initialized
2024-11-08 19:44:22 2024-11-09 03:44:22.411 UTC 0002 INFO [cli.common] readBlock -> Expect block, but got status: &{SERVICE_UNAVAILABLE}
2024-11-08 19:44:22 Error: can't read the block: &{SERVICE_UNAVAILABLE}
@YoungHypo
Copy link
Contributor Author

@yeasy

@YoungHypo YoungHypo added the bug label Nov 9, 2024
@yeasy
Copy link
Contributor

yeasy commented Nov 15, 2024

It seems the OSN's election is done before being pulled from the peer.

Can you pull the block manually from the OSN after the election is done?

@YoungHypo

@YoungHypo
Copy link
Contributor Author

I found the error. When "peer channel fetch” is called, "osnadmin channel join" is not completed.
So we need to add time.sleep() in django to block the process, so that the "osn join” can be fully established before running the subsequent tasks, I will submit the pr later! Thank you so much.
@yeasy

@yeasy
Copy link
Contributor

yeasy commented Nov 20, 2024

For most case, it's not a good idea to use time.sleep().
Instead, we can add more tries at peer channel fetch, till it's completed or timeout to throw error.

@YoungHypo
Copy link
Contributor Author

#659

@yeasy yeasy closed this as completed Dec 1, 2024
@YoungHypo YoungHypo mentioned this issue Dec 11, 2024
Merged
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@yeasy @YoungHypo