{"level":"warn","ts":"2026-04-01T15:38:52.324741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T15:38:52.324826Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-01T15:38:52.32488Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T15:38:52.32489Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-01T15:38:52.324906Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-01T15:38:52.325545Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-01T15:38:52.325616Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-01T15:38:52.346013Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.524172ms"} {"level":"info","ts":"2026-04-01T15:38:52.364327Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-01T15:38:52.364443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T15:38:52.364493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-01T15:38:52.364521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T15:38:52.364557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-01T15:38:52.364641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-01T15:38:52.395597Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T15:38:52.404062Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T15:38:52.410399Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-01T15:38:52.418415Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T15:38:52.418681Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-01T15:38:52.418791Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-01T15:38:52.418813Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-01T15:38:52.418864Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T15:38:52.423502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-01T15:38:52.423626Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-01T15:38:52.423795Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-01T15:38:52.423877Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-01T15:38:52.423912Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-01T15:38:52.424078Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T15:38:52.424129Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T15:38:52.869828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T15:38:52.869885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T15:38:52.869924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-01T15:38:52.869937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-01T15:38:52.869943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-01T15:38:52.869952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-01T15:38:52.86996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-01T15:38:52.906863Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:38:52.911928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T15:38:52.912126Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:38:52.912276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T15:38:52.912348Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T15:38:52.912155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:38:52.914735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:38:52.915246Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:38:52.915554Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:38:52.916053Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T15:38:52.922495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-01T15:38:55.508066Z","caller":"traceutil/trace.go:171","msg":"trace[299054716] linearizableReadLoop","detail":"{readStateIndex:100; appliedIndex:99; }","duration":"121.616408ms","start":"2026-04-01T15:38:55.386422Z","end":"2026-04-01T15:38:55.508038Z","steps":["trace[299054716] 'read index received' (duration: 33.418208ms)","trace[299054716] 'applied index is now lower than readState.Index' (duration: 88.197109ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:38:55.508205Z","caller":"traceutil/trace.go:171","msg":"trace[567723980] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"122.759228ms","start":"2026-04-01T15:38:55.385433Z","end":"2026-04-01T15:38:55.508192Z","steps":["trace[567723980] 'process raft request' (duration: 34.396627ms)","trace[567723980] 'compare' (duration: 88.084722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:38:55.508391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.93284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T15:38:55.508705Z","caller":"traceutil/trace.go:171","msg":"trace[26247272] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:95; }","duration":"122.285464ms","start":"2026-04-01T15:38:55.3864Z","end":"2026-04-01T15:38:55.508686Z","steps":["trace[26247272] 'agreement among raft nodes before linearized reading' (duration: 121.837393ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:38:55.510707Z","caller":"traceutil/trace.go:171","msg":"trace[840893277] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"123.21585ms","start":"2026-04-01T15:38:55.387458Z","end":"2026-04-01T15:38:55.510674Z","steps":["trace[840893277] 'process raft request' (duration: 123.032447ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:38:55.96665Z","caller":"traceutil/trace.go:171","msg":"trace[556085848] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"138.693198ms","start":"2026-04-01T15:38:55.827917Z","end":"2026-04-01T15:38:55.96661Z","steps":["trace[556085848] 'process raft request' (duration: 107.092928ms)","trace[556085848] 'compare' (duration: 31.434988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:38:56.193543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.42406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:38:56.193654Z","caller":"traceutil/trace.go:171","msg":"trace[407336141] linearizableReadLoop","detail":"{readStateIndex:133; appliedIndex:132; }","duration":"134.16012ms","start":"2026-04-01T15:38:56.059477Z","end":"2026-04-01T15:38:56.193637Z","steps":["trace[407336141] 'read index received' (duration: 56.624µs)","trace[407336141] 'applied index is now lower than readState.Index' (duration: 134.102365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:38:56.193754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.267598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-dfbvq\" ","response":"range_response_count:1 size:858"} {"level":"info","ts":"2026-04-01T15:38:56.193789Z","caller":"traceutil/trace.go:171","msg":"trace[1567694827] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-dfbvq; range_end:; response_count:1; response_revision:128; }","duration":"134.327502ms","start":"2026-04-01T15:38:56.059451Z","end":"2026-04-01T15:38:56.193779Z","steps":["trace[1567694827] 'agreement among raft nodes before linearized reading' (duration: 134.232695ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:38:56.193749Z","caller":"traceutil/trace.go:171","msg":"trace[344252350] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"134.259207ms","start":"2026-04-01T15:38:56.059438Z","end":"2026-04-01T15:38:56.193698Z","steps":["trace[344252350] 'process raft request' (duration: 26.196371ms)","trace[344252350] 'compare' (duration: 107.268969ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:38:56.961408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764428763155776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:38:57.366231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103763395s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-01T15:38:58.45073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084175341s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:38:58.450872Z","caller":"traceutil/trace.go:171","msg":"trace[1844715761] linearizableReadLoop","detail":"{readStateIndex:135; appliedIndex:134; }","duration":"1.989545545s","start":"2026-04-01T15:38:56.461305Z","end":"2026-04-01T15:38:58.450851Z","steps":["trace[1844715761] 'read index received' (duration: 905.100254ms)","trace[1844715761] 'applied index is now lower than readState.Index' (duration: 1.08444291s)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:38:58.450977Z","caller":"traceutil/trace.go:171","msg":"trace[604814842] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"2.188572843s","start":"2026-04-01T15:38:56.262374Z","end":"2026-04-01T15:38:58.450947Z","steps":["trace[604814842] 'process raft request' (duration: 1.104065058s)","trace[604814842] 'compare' (duration: 1.083767002s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:38:58.451081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:38:56.262349Z","time spent":"2.188680769s","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":620,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:38:58.451216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.989894789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:38:58.45127Z","caller":"traceutil/trace.go:171","msg":"trace[459842126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:130; }","duration":"1.989980146s","start":"2026-04-01T15:38:56.461271Z","end":"2026-04-01T15:38:58.451251Z","steps":["trace[459842126] 'agreement among raft nodes before linearized reading' (duration: 1.989788513s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:38:58.451261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.866625413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:38:58.451769Z","caller":"traceutil/trace.go:171","msg":"trace[1843038946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:130; }","duration":"1.867133289s","start":"2026-04-01T15:38:56.584608Z","end":"2026-04-01T15:38:58.451741Z","steps":["trace[1843038946] 'agreement among raft nodes before linearized reading' (duration: 1.86659042s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:38:58.451871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:38:56.58455Z","time spent":"1.86727935s","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T15:38:58.451965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:38:56.461248Z","time spent":"1.990691786s","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T15:38:58.452855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.733577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:38:58.452924Z","caller":"traceutil/trace.go:171","msg":"trace[973494919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:130; }","duration":"576.401086ms","start":"2026-04-01T15:38:57.876498Z","end":"2026-04-01T15:38:58.452899Z","steps":["trace[973494919] 'agreement among raft nodes before linearized reading' (duration: 574.697794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:38:58.452982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:38:57.876479Z","time spent":"576.488642ms","remote":"127.0.0.1:59352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T15:39:01.339966Z","caller":"traceutil/trace.go:171","msg":"trace[459907189] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"191.613104ms","start":"2026-04-01T15:39:01.148308Z","end":"2026-04-01T15:39:01.339921Z","steps":["trace[459907189] 'process raft request' (duration: 185.064994ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:01.340075Z","caller":"traceutil/trace.go:171","msg":"trace[1592688880] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"191.424921ms","start":"2026-04-01T15:39:01.148631Z","end":"2026-04-01T15:39:01.340056Z","steps":["trace[1592688880] 'process raft request' (duration: 191.209916ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:01.340186Z","caller":"traceutil/trace.go:171","msg":"trace[1580727450] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"179.347814ms","start":"2026-04-01T15:39:01.160813Z","end":"2026-04-01T15:39:01.340161Z","steps":["trace[1580727450] 'process raft request' (duration: 179.069714ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:01.340326Z","caller":"traceutil/trace.go:171","msg":"trace[1301599073] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"178.460239ms","start":"2026-04-01T15:39:01.161809Z","end":"2026-04-01T15:39:01.340269Z","steps":["trace[1301599073] 'process raft request' (duration: 178.149217ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:01.340671Z","caller":"traceutil/trace.go:171","msg":"trace[2138063607] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"178.807435ms","start":"2026-04-01T15:39:01.161854Z","end":"2026-04-01T15:39:01.340662Z","steps":["trace[2138063607] 'process raft request' (duration: 178.140247ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:01.340818Z","caller":"traceutil/trace.go:171","msg":"trace[2070974006] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"178.539025ms","start":"2026-04-01T15:39:01.162247Z","end":"2026-04-01T15:39:01.340786Z","steps":["trace[2070974006] 'process raft request' (duration: 178.406006ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:01.341103Z","caller":"traceutil/trace.go:171","msg":"trace[1414346198] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"179.130888ms","start":"2026-04-01T15:39:01.16194Z","end":"2026-04-01T15:39:01.341071Z","steps":["trace[1414346198] 'process raft request' (duration: 178.573568ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:01.341034Z","caller":"traceutil/trace.go:171","msg":"trace[93778522] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"121.882025ms","start":"2026-04-01T15:39:01.219134Z","end":"2026-04-01T15:39:01.341016Z","steps":["trace[93778522] 'process raft request' (duration: 121.557902ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.121285Z","caller":"traceutil/trace.go:171","msg":"trace[1722960651] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"176.379302ms","start":"2026-04-01T15:39:25.944878Z","end":"2026-04-01T15:39:26.121257Z","steps":["trace[1722960651] 'process raft request' (duration: 176.240456ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.127821Z","caller":"traceutil/trace.go:171","msg":"trace[483333813] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"174.278955ms","start":"2026-04-01T15:39:25.953514Z","end":"2026-04-01T15:39:26.127793Z","steps":["trace[483333813] 'process raft request' (duration: 173.947255ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.127974Z","caller":"traceutil/trace.go:171","msg":"trace[887514586] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"182.058392ms","start":"2026-04-01T15:39:25.94591Z","end":"2026-04-01T15:39:26.127968Z","steps":["trace[887514586] 'process raft request' (duration: 181.381001ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128029Z","caller":"traceutil/trace.go:171","msg":"trace[608013123] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"181.79216ms","start":"2026-04-01T15:39:25.946232Z","end":"2026-04-01T15:39:26.128024Z","steps":["trace[608013123] 'process raft request' (duration: 181.179398ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128011Z","caller":"traceutil/trace.go:171","msg":"trace[706560784] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"120.167067ms","start":"2026-04-01T15:39:26.007809Z","end":"2026-04-01T15:39:26.127976Z","steps":["trace[706560784] 'process raft request' (duration: 120.04459ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128191Z","caller":"traceutil/trace.go:171","msg":"trace[1325964655] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"168.198557ms","start":"2026-04-01T15:39:25.959981Z","end":"2026-04-01T15:39:26.12818Z","steps":["trace[1325964655] 'process raft request' (duration: 167.590354ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128317Z","caller":"traceutil/trace.go:171","msg":"trace[1590423875] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"165.914106ms","start":"2026-04-01T15:39:25.962397Z","end":"2026-04-01T15:39:26.128311Z","steps":["trace[1590423875] 'process raft request' (duration: 165.286864ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128382Z","caller":"traceutil/trace.go:171","msg":"trace[934489108] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"167.362102ms","start":"2026-04-01T15:39:25.961016Z","end":"2026-04-01T15:39:26.128378Z","steps":["trace[934489108] 'process raft request' (duration: 166.594934ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128421Z","caller":"traceutil/trace.go:171","msg":"trace[1882508475] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"166.199678ms","start":"2026-04-01T15:39:25.962218Z","end":"2026-04-01T15:39:26.128418Z","steps":["trace[1882508475] 'process raft request' (duration: 165.43154ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128461Z","caller":"traceutil/trace.go:171","msg":"trace[2082684458] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"164.11338ms","start":"2026-04-01T15:39:25.964334Z","end":"2026-04-01T15:39:26.128448Z","steps":["trace[2082684458] 'process raft request' (duration: 163.405031ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:26.128473Z","caller":"traceutil/trace.go:171","msg":"trace[162015574] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"141.40248ms","start":"2026-04-01T15:39:25.987065Z","end":"2026-04-01T15:39:26.128467Z","steps":["trace[162015574] 'process raft request' (duration: 140.736329ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:31.455328Z","caller":"traceutil/trace.go:171","msg":"trace[1850045690] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"186.171934ms","start":"2026-04-01T15:39:31.269134Z","end":"2026-04-01T15:39:31.455306Z","steps":["trace[1850045690] 'process raft request' (duration: 185.503478ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:32.170114Z","caller":"traceutil/trace.go:171","msg":"trace[543339884] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"216.61534ms","start":"2026-04-01T15:39:31.952951Z","end":"2026-04-01T15:39:32.169567Z","steps":["trace[543339884] 'process raft request' (duration: 215.420424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:39:32.665522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.464109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:39:32.665866Z","caller":"traceutil/trace.go:171","msg":"trace[697179852] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"430.806331ms","start":"2026-04-01T15:39:32.235016Z","end":"2026-04-01T15:39:32.665822Z","steps":["trace[697179852] 'process raft request' (duration: 430.676144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:39:32.666006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:39:32.234986Z","time spent":"430.958228ms","remote":"127.0.0.1:59604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:39:32.666711Z","caller":"traceutil/trace.go:171","msg":"trace[1643755572] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"479.609975ms","start":"2026-04-01T15:39:32.187064Z","end":"2026-04-01T15:39:32.666674Z","steps":["trace[1643755572] 'process raft request' (duration: 361.795224ms)","trace[1643755572] 'compare' (duration: 116.38301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:39:32.666854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:39:32.187034Z","time spent":"479.771552ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:39:34.063845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764428763156774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:39:34.471953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.388240787s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-01T15:39:34.476807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410719528s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:39:34.478714Z","caller":"traceutil/trace.go:171","msg":"trace[1859869477] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.534997617s","start":"2026-04-01T15:39:32.943677Z","end":"2026-04-01T15:39:34.478675Z","steps":["trace[1859869477] 'process raft request' (duration: 122.344363ms)","trace[1859869477] 'compare' (duration: 1.410287596s)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:39:34.47875Z","caller":"traceutil/trace.go:171","msg":"trace[1574581576] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.396433071s","start":"2026-04-01T15:39:33.082299Z","end":"2026-04-01T15:39:34.478733Z","steps":["trace[1574581576] 'process raft request' (duration: 1.39476647s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:39:34.478772Z","caller":"traceutil/trace.go:171","msg":"trace[2084322456] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"916.008554ms","start":"2026-04-01T15:39:33.562729Z","end":"2026-04-01T15:39:34.478737Z","steps":["trace[2084322456] 'read index received' (duration: 909.38175ms)","trace[2084322456] 'applied index is now lower than readState.Index' (duration: 6.624544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:39:34.478862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:39:32.943649Z","time spent":"1.535128835s","remote":"127.0.0.1:59352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:39:34.478863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:39:33.082268Z","time spent":"1.396535399s","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:39:34.491172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.434024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T15:39:34.491232Z","caller":"traceutil/trace.go:171","msg":"trace[260910594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"928.542243ms","start":"2026-04-01T15:39:33.562675Z","end":"2026-04-01T15:39:34.491217Z","steps":["trace[260910594] 'agreement among raft nodes before linearized reading' (duration: 916.163301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:39:34.491264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:39:33.562654Z","time spent":"928.603311ms","remote":"127.0.0.1:59352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T15:39:35.530605Z","caller":"traceutil/trace.go:171","msg":"trace[224648830] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"184.455747ms","start":"2026-04-01T15:39:35.346086Z","end":"2026-04-01T15:39:35.530542Z","steps":["trace[224648830] 'process raft request' (duration: 181.579434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:39:49.733563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.978045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-01T15:39:49.733308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.40888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fcwl9\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-01T15:39:49.733715Z","caller":"traceutil/trace.go:171","msg":"trace[706287509] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:786; }","duration":"545.189124ms","start":"2026-04-01T15:39:49.188511Z","end":"2026-04-01T15:39:49.733701Z","steps":["trace[706287509] 'range keys from in-memory index tree' (duration: 544.086545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:39:49.733744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:39:49.188493Z","time spent":"545.244264ms","remote":"127.0.0.1:59080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-04-01T15:39:49.733742Z","caller":"traceutil/trace.go:171","msg":"trace[1722933919] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fcwl9; range_end:; response_count:1; response_revision:786; }","duration":"480.896519ms","start":"2026-04-01T15:39:49.252817Z","end":"2026-04-01T15:39:49.733714Z","steps":["trace[1722933919] 'range keys from in-memory index tree' (duration: 479.7274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:39:49.733807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:39:49.252794Z","time spent":"480.996109ms","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fcwl9\" "} {"level":"info","ts":"2026-04-01T15:41:40.757773Z","caller":"traceutil/trace.go:171","msg":"trace[653927072] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"100.718969ms","start":"2026-04-01T15:41:40.657032Z","end":"2026-04-01T15:41:40.757751Z","steps":["trace[653927072] 'read index received' (duration: 100.678887ms)","trace[653927072] 'applied index is now lower than readState.Index' (duration: 39.132µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:41:40.75787Z","caller":"traceutil/trace.go:171","msg":"trace[660936439] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"148.89211ms","start":"2026-04-01T15:41:40.608943Z","end":"2026-04-01T15:41:40.757835Z","steps":["trace[660936439] 'process raft request' (duration: 148.524621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:41:40.757883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.830035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:41:40.758096Z","caller":"traceutil/trace.go:171","msg":"trace[613202248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"101.053547ms","start":"2026-04-01T15:41:40.657026Z","end":"2026-04-01T15:41:40.758079Z","steps":["trace[613202248] 'agreement among raft nodes before linearized reading' (duration: 100.808314ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:41:41.168318Z","caller":"traceutil/trace.go:171","msg":"trace[826081918] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"408.983836ms","start":"2026-04-01T15:41:40.759256Z","end":"2026-04-01T15:41:41.16824Z","steps":["trace[826081918] 'read index received' (duration: 408.829578ms)","trace[826081918] 'applied index is now lower than readState.Index' (duration: 153.208µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:41:41.16844Z","caller":"traceutil/trace.go:171","msg":"trace[1917821683] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"505.708537ms","start":"2026-04-01T15:41:40.662719Z","end":"2026-04-01T15:41:41.168428Z","steps":["trace[1917821683] 'process raft request' (duration: 505.38664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:41:41.168534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:41:40.662701Z","time spent":"505.76339ms","remote":"127.0.0.1:59352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:41:41.16876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.484113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:41:41.168836Z","caller":"traceutil/trace.go:171","msg":"trace[1539754840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"409.594838ms","start":"2026-04-01T15:41:40.759218Z","end":"2026-04-01T15:41:41.168813Z","steps":["trace[1539754840] 'agreement among raft nodes before linearized reading' (duration: 409.472982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:41:41.168885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:41:40.759202Z","time spent":"409.675462ms","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}