{"level":"warn","ts":"2026-03-17T21:55:13.221116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:55:13.221251Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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://162.253.55.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-03-17T21:55:13.221349Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:55:13.221366Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-17T21:55:13.221401Z","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-03-17T21:55:13.22236Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-03-17T21:55:13.222514Z","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":16,"max-cpu-available":16,"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://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-03-17T21:55:13.225878Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.069026ms"} {"level":"info","ts":"2026-03-17T21:55:13.232472Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-03-17T21:55:13.232764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T21:55:13.232813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-03-17T21:55:13.232838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T21:55:13.232857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-03-17T21:55:13.232927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-03-17T21:55:13.238623Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T21:55:13.24004Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T21:55:13.241686Z","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-03-17T21:55:13.244115Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T21:55:13.244347Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T21:55:13.244559Z","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-03-17T21:55:13.244689Z","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-03-17T21:55:13.244707Z","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-03-17T21:55:13.246691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-03-17T21:55:13.247188Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-17T21:55:13.249269Z","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-03-17T21:55:13.249396Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-17T21:55:13.249414Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-17T21:55:13.250296Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T21:55:13.250345Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T21:55:14.233453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T21:55:14.233527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T21:55:14.233573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-03-17T21:55:14.233594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-03-17T21:55:14.233605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-17T21:55:14.23362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-03-17T21:55:14.233635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-17T21:55:14.234824Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:14.235827Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T21:55:14.235862Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:55:14.235851Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:55:14.236417Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:14.236432Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T21:55:14.236577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:14.23659Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T21:55:14.23669Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:14.238243Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T21:55:14.239407Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-03-17T21:55:23.404784Z","caller":"traceutil/trace.go:171","msg":"trace[1649523736] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"174.761746ms","start":"2026-03-17T21:55:23.229977Z","end":"2026-03-17T21:55:23.404739Z","steps":["trace[1649523736] 'process raft request' (duration: 174.53113ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:23.404797Z","caller":"traceutil/trace.go:171","msg":"trace[1137556885] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"132.544521ms","start":"2026-03-17T21:55:23.272154Z","end":"2026-03-17T21:55:23.404699Z","steps":["trace[1137556885] 'read index received' (duration: 132.360216ms)","trace[1137556885] 'applied index is now lower than readState.Index' (duration: 182.865µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:55:23.404958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.768796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-17T21:55:23.405091Z","caller":"traceutil/trace.go:171","msg":"trace[376273800] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:302; }","duration":"132.969121ms","start":"2026-03-17T21:55:23.272107Z","end":"2026-03-17T21:55:23.405076Z","steps":["trace[376273800] 'agreement among raft nodes before linearized reading' (duration: 132.732775ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:23.545983Z","caller":"traceutil/trace.go:171","msg":"trace[290389925] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"132.973571ms","start":"2026-03-17T21:55:23.412977Z","end":"2026-03-17T21:55:23.54595Z","steps":["trace[290389925] 'process raft request' (duration: 94.55522ms)","trace[290389925] 'compare' (duration: 38.255847ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:55:23.807347Z","caller":"traceutil/trace.go:171","msg":"trace[1187659782] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:312; }","duration":"386.360253ms","start":"2026-03-17T21:55:23.420956Z","end":"2026-03-17T21:55:23.807316Z","steps":["trace[1187659782] 'read index received' (duration: 86.556371ms)","trace[1187659782] 'applied index is now lower than readState.Index' (duration: 299.800342ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:55:23.80748Z","caller":"traceutil/trace.go:171","msg":"trace[833745207] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"393.283336ms","start":"2026-03-17T21:55:23.414151Z","end":"2026-03-17T21:55:23.807434Z","steps":["trace[833745207] 'process raft request' (duration: 338.829789ms)","trace[833745207] 'compare' (duration: 54.198941ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:55:23.807786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.789715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-17T21:55:23.807843Z","caller":"traceutil/trace.go:171","msg":"trace[853930732] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:304; }","duration":"386.921709ms","start":"2026-03-17T21:55:23.420909Z","end":"2026-03-17T21:55:23.807831Z","steps":["trace[853930732] 'agreement among raft nodes before linearized reading' (duration: 386.55265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:23.807883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:55:23.420898Z","time spent":"386.97438ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-17T21:55:23.80803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:55:23.414127Z","time spent":"393.456381ms","remote":"127.0.0.1:51468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:55:23.816076Z","caller":"traceutil/trace.go:171","msg":"trace[1230157653] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"266.055226ms","start":"2026-03-17T21:55:23.550004Z","end":"2026-03-17T21:55:23.816059Z","steps":["trace[1230157653] 'process raft request' (duration: 265.981595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:23.817317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.0052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-17T21:55:23.817432Z","caller":"traceutil/trace.go:171","msg":"trace[2064213466] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:305; }","duration":"145.184854ms","start":"2026-03-17T21:55:23.672207Z","end":"2026-03-17T21:55:23.817392Z","steps":["trace[2064213466] 'agreement among raft nodes before linearized reading' (duration: 143.989845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:23.817545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.063298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-17T21:55:23.81773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.694339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-17T21:55:23.817739Z","caller":"traceutil/trace.go:171","msg":"trace[210330209] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:305; }","duration":"195.285625ms","start":"2026-03-17T21:55:23.622421Z","end":"2026-03-17T21:55:23.817706Z","steps":["trace[210330209] 'agreement among raft nodes before linearized reading' (duration: 193.794658ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:23.817768Z","caller":"traceutil/trace.go:171","msg":"trace[1465372581] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:305; }","duration":"295.767592ms","start":"2026-03-17T21:55:23.521982Z","end":"2026-03-17T21:55:23.81775Z","steps":["trace[1465372581] 'agreement among raft nodes before linearized reading' (duration: 294.246495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:23.81811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.204232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-17T21:55:23.818159Z","caller":"traceutil/trace.go:171","msg":"trace[935123832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:305; }","duration":"113.296954ms","start":"2026-03-17T21:55:23.704839Z","end":"2026-03-17T21:55:23.818136Z","steps":["trace[935123832] 'agreement among raft nodes before linearized reading' (duration: 111.287215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:23.818166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.895508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-17T21:55:23.8183Z","caller":"traceutil/trace.go:171","msg":"trace[1427791422] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:305; }","duration":"346.96793ms","start":"2026-03-17T21:55:23.471212Z","end":"2026-03-17T21:55:23.81818Z","steps":["trace[1427791422] 'agreement among raft nodes before linearized reading' (duration: 345.063424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:23.818327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:55:23.471153Z","time spent":"347.166935ms","remote":"127.0.0.1:45362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-03-17T21:55:23.819498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.010605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-17T21:55:23.819883Z","caller":"traceutil/trace.go:171","msg":"trace[542672436] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:305; }","duration":"247.468446ms","start":"2026-03-17T21:55:23.572287Z","end":"2026-03-17T21:55:23.819755Z","steps":["trace[542672436] 'agreement among raft nodes before linearized reading' (duration: 244.066462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:23.820794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.239844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-17T21:55:23.820914Z","caller":"traceutil/trace.go:171","msg":"trace[220388341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:305; }","duration":"233.381827ms","start":"2026-03-17T21:55:23.587476Z","end":"2026-03-17T21:55:23.820858Z","steps":["trace[220388341] 'agreement among raft nodes before linearized reading' (duration: 228.674361ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:52.192284Z","caller":"traceutil/trace.go:171","msg":"trace[1768439505] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"179.579268ms","start":"2026-03-17T21:55:52.012671Z","end":"2026-03-17T21:55:52.19225Z","steps":["trace[1768439505] 'process raft request' (duration: 117.166753ms)","trace[1768439505] 'compare' (duration: 62.262422ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:55:52.557729Z","caller":"traceutil/trace.go:171","msg":"trace[648955669] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"253.684578ms","start":"2026-03-17T21:55:52.303933Z","end":"2026-03-17T21:55:52.557618Z","steps":["trace[648955669] 'read index received' (duration: 251.850554ms)","trace[648955669] 'applied index is now lower than readState.Index' (duration: 1.831774ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:55:52.557988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.019027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:55:52.558046Z","caller":"traceutil/trace.go:171","msg":"trace[1532148738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"254.10839ms","start":"2026-03-17T21:55:52.303904Z","end":"2026-03-17T21:55:52.558013Z","steps":["trace[1532148738] 'agreement among raft nodes before linearized reading' (duration: 253.933644ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:55:52.558583Z","caller":"traceutil/trace.go:171","msg":"trace[601750812] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"272.963611ms","start":"2026-03-17T21:55:52.285591Z","end":"2026-03-17T21:55:52.558555Z","steps":["trace[601750812] 'process raft request' (duration: 271.71883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:52.809494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.629025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-17T21:55:52.809584Z","caller":"traceutil/trace.go:171","msg":"trace[960837410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"142.790969ms","start":"2026-03-17T21:55:52.666773Z","end":"2026-03-17T21:55:52.809564Z","steps":["trace[960837410] 'range keys from in-memory index tree' (duration: 142.42223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:55:52.809566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.564255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T21:55:52.809673Z","caller":"traceutil/trace.go:171","msg":"trace[6076409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"104.752539ms","start":"2026-03-17T21:55:52.704894Z","end":"2026-03-17T21:55:52.809646Z","steps":["trace[6076409] 'range keys from in-memory index tree' (duration: 104.288878ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:10.629983Z","caller":"traceutil/trace.go:171","msg":"trace[91355298] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"179.940168ms","start":"2026-03-17T21:56:10.450003Z","end":"2026-03-17T21:56:10.629943Z","steps":["trace[91355298] 'process raft request' (duration: 91.968488ms)","trace[91355298] 'compare' (duration: 87.808156ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:10.75575Z","caller":"traceutil/trace.go:171","msg":"trace[1351590140] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"305.403203ms","start":"2026-03-17T21:56:10.450313Z","end":"2026-03-17T21:56:10.755716Z","steps":["trace[1351590140] 'process raft request' (duration: 282.37723ms)","trace[1351590140] 'compare' (duration: 22.870609ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:56:10.756124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:56:10.450288Z","time spent":"305.541847ms","remote":"127.0.0.1:45438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:56:59.232147Z","caller":"traceutil/trace.go:171","msg":"trace[2035971493] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"107.267401ms","start":"2026-03-17T21:56:59.124848Z","end":"2026-03-17T21:56:59.232115Z","steps":["trace[2035971493] 'process raft request' (duration: 103.90708ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:59.235208Z","caller":"traceutil/trace.go:171","msg":"trace[1693653986] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"109.874244ms","start":"2026-03-17T21:56:59.125287Z","end":"2026-03-17T21:56:59.235161Z","steps":["trace[1693653986] 'process raft request' (duration: 109.585567ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:59.235294Z","caller":"traceutil/trace.go:171","msg":"trace[1494903239] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"110.376785ms","start":"2026-03-17T21:56:59.12488Z","end":"2026-03-17T21:56:59.235257Z","steps":["trace[1494903239] 'process raft request' (duration: 109.7386ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:59.235427Z","caller":"traceutil/trace.go:171","msg":"trace[990395393] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"108.991543ms","start":"2026-03-17T21:56:59.126415Z","end":"2026-03-17T21:56:59.235407Z","steps":["trace[990395393] 'process raft request' (duration: 108.518322ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:59.235264Z","caller":"traceutil/trace.go:171","msg":"trace[305766388] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"110.204111ms","start":"2026-03-17T21:56:59.125006Z","end":"2026-03-17T21:56:59.23521Z","steps":["trace[305766388] 'process raft request' (duration: 109.72642ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:59.235594Z","caller":"traceutil/trace.go:171","msg":"trace[1178976334] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"110.364015ms","start":"2026-03-17T21:56:59.125218Z","end":"2026-03-17T21:56:59.235583Z","steps":["trace[1178976334] 'process raft request' (duration: 109.573146ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:59.235675Z","caller":"traceutil/trace.go:171","msg":"trace[1122813936] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"109.126556ms","start":"2026-03-17T21:56:59.12653Z","end":"2026-03-17T21:56:59.235657Z","steps":["trace[1122813936] 'process raft request' (duration: 108.4611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:07.667564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.361764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:57:07.66781Z","caller":"traceutil/trace.go:171","msg":"trace[1556322495] linearizableReadLoop","detail":"{readStateIndex:1626; appliedIndex:1624; }","duration":"329.310525ms","start":"2026-03-17T21:57:07.33846Z","end":"2026-03-17T21:57:07.667771Z","steps":["trace[1556322495] 'read index received' (duration: 61.357567ms)","trace[1556322495] 'applied index is now lower than readState.Index' (duration: 267.949848ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:07.667848Z","caller":"traceutil/trace.go:171","msg":"trace[309304253] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"357.955915ms","start":"2026-03-17T21:57:07.309813Z","end":"2026-03-17T21:57:07.667769Z","steps":["trace[309304253] 'process raft request' (duration: 208.071088ms)","trace[309304253] 'compare' (duration: 149.211111ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:07.668119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.628812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-17T21:57:07.668123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:07.309791Z","time spent":"358.193661ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:57:07.668168Z","caller":"traceutil/trace.go:171","msg":"trace[1631271647] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:1568; }","duration":"329.724925ms","start":"2026-03-17T21:57:07.33843Z","end":"2026-03-17T21:57:07.668155Z","steps":["trace[1631271647] 'agreement among raft nodes before linearized reading' (duration: 329.472989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:07.66821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:07.338416Z","time spent":"329.784786ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"warn","ts":"2026-03-17T21:57:08.191433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.11462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:57:08.191677Z","caller":"traceutil/trace.go:171","msg":"trace[1119399573] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1630; }","duration":"472.407359ms","start":"2026-03-17T21:57:07.71925Z","end":"2026-03-17T21:57:08.191658Z","steps":["trace[1119399573] 'read index received' (duration: 56.862829ms)","trace[1119399573] 'applied index is now lower than readState.Index' (duration: 415.54268ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:08.191642Z","caller":"traceutil/trace.go:171","msg":"trace[1050464257] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"488.369263ms","start":"2026-03-17T21:57:07.703228Z","end":"2026-03-17T21:57:08.191597Z","steps":["trace[1050464257] 'process raft request' (duration: 72.965577ms)","trace[1050464257] 'compare' (duration: 414.913225ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:57:08.191961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.707895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7d26a5c-1087-4a8c-8a70-b65670855619\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-17T21:57:08.191993Z","caller":"traceutil/trace.go:171","msg":"trace[379329795] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7d26a5c-1087-4a8c-8a70-b65670855619; range_end:; response_count:1; response_revision:1572; }","duration":"472.804197ms","start":"2026-03-17T21:57:07.719181Z","end":"2026-03-17T21:57:08.191985Z","steps":["trace[379329795] 'agreement among raft nodes before linearized reading' (duration: 472.598253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:08.191998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.527431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.189dbfb7c428fb35\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-03-17T21:57:08.192041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:07.703213Z","time spent":"488.715581ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:57:08.192065Z","caller":"traceutil/trace.go:171","msg":"trace[359083375] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.189dbfb7c428fb35; range_end:; response_count:1; response_revision:1572; }","duration":"440.640104ms","start":"2026-03-17T21:57:07.751406Z","end":"2026-03-17T21:57:08.192046Z","steps":["trace[359083375] 'agreement among raft nodes before linearized reading' (duration: 440.404428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:08.192014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:07.719157Z","time spent":"472.851649ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7d26a5c-1087-4a8c-8a70-b65670855619\" "} {"level":"warn","ts":"2026-03-17T21:57:08.192116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:07.751379Z","time spent":"440.723626ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1006,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.189dbfb7c428fb35\" "} {"level":"info","ts":"2026-03-17T21:57:08.192481Z","caller":"traceutil/trace.go:171","msg":"trace[1031341904] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"309.464587ms","start":"2026-03-17T21:57:07.882256Z","end":"2026-03-17T21:57:08.191721Z","steps":["trace[1031341904] 'process raft request' (duration: 309.293153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:08.192635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:57:07.882223Z","time spent":"310.37068ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:57:52.676489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.743114ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-17T21:57:59.043777Z","caller":"traceutil/trace.go:171","msg":"trace[946962972] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"105.203879ms","start":"2026-03-17T21:57:58.938542Z","end":"2026-03-17T21:57:59.043745Z","steps":["trace[946962972] 'read index received' (duration: 104.909802ms)","trace[946962972] 'applied index is now lower than readState.Index' (duration: 293.057µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:57:59.043885Z","caller":"traceutil/trace.go:171","msg":"trace[1944198217] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"129.960526ms","start":"2026-03-17T21:57:58.913866Z","end":"2026-03-17T21:57:59.043827Z","steps":["trace[1944198217] 'process raft request' (duration: 129.656979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:59.044035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.469214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-17T21:57:59.044078Z","caller":"traceutil/trace.go:171","msg":"trace[645288840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1880; }","duration":"105.543976ms","start":"2026-03-17T21:57:58.938521Z","end":"2026-03-17T21:57:59.044065Z","steps":["trace[645288840] 'agreement among raft nodes before linearized reading' (duration: 105.345441ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:32.330894Z","caller":"traceutil/trace.go:171","msg":"trace[2065313973] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"103.199053ms","start":"2026-03-17T21:59:32.227643Z","end":"2026-03-17T21:59:32.330842Z","steps":["trace[2065313973] 'process raft request' (duration: 102.865255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:03.209885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.470738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-17T22:02:03.209988Z","caller":"traceutil/trace.go:171","msg":"trace[1292895403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3447; }","duration":"122.581821ms","start":"2026-03-17T22:02:03.08739Z","end":"2026-03-17T22:02:03.209972Z","steps":["trace[1292895403] 'range keys from in-memory index tree' (duration: 122.318245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:03.20997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.990882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:02:03.210068Z","caller":"traceutil/trace.go:171","msg":"trace[1799412097] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3447; }","duration":"193.159966ms","start":"2026-03-17T22:02:03.016885Z","end":"2026-03-17T22:02:03.210045Z","steps":["trace[1799412097] 'range keys from in-memory index tree' (duration: 192.87788ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:22.742402Z","caller":"traceutil/trace.go:171","msg":"trace[1003523414] transaction","detail":"{read_only:false; response_revision:4369; number_of_response:1; }","duration":"148.038494ms","start":"2026-03-17T22:03:22.59434Z","end":"2026-03-17T22:03:22.742378Z","steps":["trace[1003523414] 'process raft request' (duration: 131.615596ms)","trace[1003523414] 'compare' (duration: 16.317725ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:22.763909Z","caller":"traceutil/trace.go:171","msg":"trace[1781079675] transaction","detail":"{read_only:false; response_revision:4370; number_of_response:1; }","duration":"109.488462ms","start":"2026-03-17T22:03:22.654391Z","end":"2026-03-17T22:03:22.76388Z","steps":["trace[1781079675] 'process raft request' (duration: 109.313668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:29.003056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.458041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:03:29.003208Z","caller":"traceutil/trace.go:171","msg":"trace[1743181543] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"165.275313ms","start":"2026-03-17T22:03:28.837921Z","end":"2026-03-17T22:03:29.003196Z","steps":["trace[1743181543] 'process raft request' (duration: 165.212182ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:29.00333Z","caller":"traceutil/trace.go:171","msg":"trace[819320846] transaction","detail":"{read_only:false; response_revision:4591; number_of_response:1; }","duration":"323.124419ms","start":"2026-03-17T22:03:28.680198Z","end":"2026-03-17T22:03:29.003322Z","steps":["trace[819320846] 'process raft request' (duration: 36.33022ms)","trace[819320846] 'compare' (duration: 286.239116ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:29.00337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:28.680181Z","time spent":"323.17244ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:03:29.003623Z","caller":"traceutil/trace.go:171","msg":"trace[926559570] linearizableReadLoop","detail":"{readStateIndex:4768; appliedIndex:4767; }","duration":"185.671396ms","start":"2026-03-17T22:03:28.817932Z","end":"2026-03-17T22:03:29.003604Z","steps":["trace[926559570] 'read index received' (duration: 28.26µs)","trace[926559570] 'applied index is now lower than readState.Index' (duration: 185.641416ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:29.003733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.804069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-17T22:03:29.003755Z","caller":"traceutil/trace.go:171","msg":"trace[1953197643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4592; }","duration":"185.85324ms","start":"2026-03-17T22:03:28.817894Z","end":"2026-03-17T22:03:29.003748Z","steps":["trace[1953197643] 'agreement among raft nodes before linearized reading' (duration: 185.780208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:32.697205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.497394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf970f98-76dc-4a96-9317-ca9ffcaa31d2\" ","response":"range_response_count:1 size:3909"} {"level":"info","ts":"2026-03-17T22:03:32.697308Z","caller":"traceutil/trace.go:171","msg":"trace[1531420595] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf970f98-76dc-4a96-9317-ca9ffcaa31d2; range_end:; response_count:1; response_revision:4663; }","duration":"115.651968ms","start":"2026-03-17T22:03:32.581634Z","end":"2026-03-17T22:03:32.697286Z","steps":["trace[1531420595] 'range keys from in-memory index tree' (duration: 115.219447ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:40.141373Z","caller":"traceutil/trace.go:171","msg":"trace[112700300] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4996; }","duration":"170.777923ms","start":"2026-03-17T22:03:39.970568Z","end":"2026-03-17T22:03:40.141346Z","steps":["trace[112700300] 'read index received' (duration: 170.374874ms)","trace[112700300] 'applied index is now lower than readState.Index' (duration: 401.879µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:40.141587Z","caller":"traceutil/trace.go:171","msg":"trace[969200589] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"172.583455ms","start":"2026-03-17T22:03:39.968853Z","end":"2026-03-17T22:03:40.141437Z","steps":["trace[969200589] 'process raft request' (duration: 172.218007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:40.141651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.069959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-17T22:03:40.14172Z","caller":"traceutil/trace.go:171","msg":"trace[1084637445] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4813; }","duration":"171.185652ms","start":"2026-03-17T22:03:39.970519Z","end":"2026-03-17T22:03:40.141705Z","steps":["trace[1084637445] 'agreement among raft nodes before linearized reading' (duration: 170.928666ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:49.064697Z","caller":"traceutil/trace.go:171","msg":"trace[1070059775] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5141; }","duration":"187.400176ms","start":"2026-03-17T22:03:48.877274Z","end":"2026-03-17T22:03:49.064674Z","steps":["trace[1070059775] 'read index received' (duration: 167.27243ms)","trace[1070059775] 'applied index is now lower than readState.Index' (duration: 20.126736ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:49.064848Z","caller":"traceutil/trace.go:171","msg":"trace[1716766816] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"245.161173ms","start":"2026-03-17T22:03:48.819614Z","end":"2026-03-17T22:03:49.064775Z","steps":["trace[1716766816] 'process raft request' (duration: 225.028917ms)","trace[1716766816] 'compare' (duration: 19.8764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:49.064935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.051993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8ff471f-d969-4ecf-ad5c-b6cc0646ec10\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-03-17T22:03:49.064919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.616841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8ff471f-d969-4ecf-ad5c-b6cc0646ec10\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-17T22:03:49.064975Z","caller":"traceutil/trace.go:171","msg":"trace[1426607655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8ff471f-d969-4ecf-ad5c-b6cc0646ec10; range_end:; response_count:1; response_revision:4956; }","duration":"169.145425ms","start":"2026-03-17T22:03:48.89582Z","end":"2026-03-17T22:03:49.064965Z","steps":["trace[1426607655] 'agreement among raft nodes before linearized reading' (duration: 168.988491ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:49.065026Z","caller":"traceutil/trace.go:171","msg":"trace[1251661797] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8ff471f-d969-4ecf-ad5c-b6cc0646ec10; range_end:; response_count:1; response_revision:4956; }","duration":"187.757115ms","start":"2026-03-17T22:03:48.877239Z","end":"2026-03-17T22:03:49.064996Z","steps":["trace[1251661797] 'agreement among raft nodes before linearized reading' (duration: 187.510449ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:49.297064Z","caller":"traceutil/trace.go:171","msg":"trace[543905462] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"150.018661ms","start":"2026-03-17T22:03:49.147007Z","end":"2026-03-17T22:03:49.297026Z","steps":["trace[543905462] 'process raft request' (duration: 149.643522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.65939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.350241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:03:49.659674Z","caller":"traceutil/trace.go:171","msg":"trace[1527951375] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"355.152007ms","start":"2026-03-17T22:03:49.304503Z","end":"2026-03-17T22:03:49.659655Z","steps":["trace[1527951375] 'process raft request' (duration: 355.010024ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:49.659764Z","caller":"traceutil/trace.go:171","msg":"trace[1914460334] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"357.221106ms","start":"2026-03-17T22:03:49.302498Z","end":"2026-03-17T22:03:49.659719Z","steps":["trace[1914460334] 'process raft request' (duration: 94.453945ms)","trace[1914460334] 'compare' (duration: 262.230768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:03:49.659967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:49.302476Z","time spent":"357.40454ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T22:03:49.659795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:49.304485Z","time spent":"355.24417ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:03:49.660113Z","caller":"traceutil/trace.go:171","msg":"trace[1427549440] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"348.059809ms","start":"2026-03-17T22:03:49.312041Z","end":"2026-03-17T22:03:49.660101Z","steps":["trace[1427549440] 'process raft request' (duration: 347.552617ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:03:49.66012Z","caller":"traceutil/trace.go:171","msg":"trace[1716728510] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5144; }","duration":"355.575328ms","start":"2026-03-17T22:03:49.30452Z","end":"2026-03-17T22:03:49.660095Z","steps":["trace[1716728510] 'read index received' (duration: 92.444519ms)","trace[1716728510] 'applied index is now lower than readState.Index' (duration: 263.129349ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:03:49.660157Z","caller":"traceutil/trace.go:171","msg":"trace[1503482061] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"345.1401ms","start":"2026-03-17T22:03:49.314997Z","end":"2026-03-17T22:03:49.660138Z","steps":["trace[1503482061] 'process raft request' (duration: 344.965916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.660201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:49.312021Z","time spent":"348.135001ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T22:03:49.660235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:49.314983Z","time spent":"345.214442ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T22:03:49.660406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.780632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:03:49.660456Z","caller":"traceutil/trace.go:171","msg":"trace[1246725573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4962; }","duration":"355.974787ms","start":"2026-03-17T22:03:49.304469Z","end":"2026-03-17T22:03:49.660444Z","steps":["trace[1246725573] 'agreement among raft nodes before linearized reading' (duration: 355.747902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:49.66049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:03:49.304446Z","time spent":"356.035429ms","remote":"127.0.0.1:45148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-17T22:03:49.660647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.235205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z8ptk\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-17T22:03:49.6608Z","caller":"traceutil/trace.go:171","msg":"trace[1730487438] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z8ptk; range_end:; response_count:1; response_revision:4962; }","duration":"283.43496ms","start":"2026-03-17T22:03:49.377345Z","end":"2026-03-17T22:03:49.66078Z","steps":["trace[1730487438] 'agreement among raft nodes before linearized reading' (duration: 283.0009ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:05:04.17911Z","caller":"traceutil/trace.go:171","msg":"trace[411255217] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"104.235649ms","start":"2026-03-17T22:05:04.074839Z","end":"2026-03-17T22:05:04.179075Z","steps":["trace[411255217] 'process raft request' (duration: 34.869581ms)","trace[411255217] 'compare' (duration: 69.253346ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:05:04.423245Z","caller":"traceutil/trace.go:171","msg":"trace[1049055349] linearizableReadLoop","detail":"{readStateIndex:5825; appliedIndex:5824; }","duration":"120.041847ms","start":"2026-03-17T22:05:04.30317Z","end":"2026-03-17T22:05:04.423212Z","steps":["trace[1049055349] 'read index received' (duration: 32.641926ms)","trace[1049055349] 'applied index is now lower than readState.Index' (duration: 87.398801ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:05:04.423269Z","caller":"traceutil/trace.go:171","msg":"trace[1695624639] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"171.714317ms","start":"2026-03-17T22:05:04.251518Z","end":"2026-03-17T22:05:04.423233Z","steps":["trace[1695624639] 'process raft request' (duration: 84.310376ms)","trace[1695624639] 'compare' (duration: 87.259388ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:05:04.423376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.189971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:05:04.423427Z","caller":"traceutil/trace.go:171","msg":"trace[2120808456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5618; }","duration":"120.303973ms","start":"2026-03-17T22:05:04.303112Z","end":"2026-03-17T22:05:04.423416Z","steps":["trace[2120808456] 'agreement among raft nodes before linearized reading' (duration: 120.206251ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:05:04.639005Z","caller":"traceutil/trace.go:171","msg":"trace[734522236] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"167.494829ms","start":"2026-03-17T22:05:04.471487Z","end":"2026-03-17T22:05:04.638982Z","steps":["trace[734522236] 'process raft request' (duration: 167.356477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:05:05.403683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.961547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:05:05.403782Z","caller":"traceutil/trace.go:171","msg":"trace[386141888] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5620; }","duration":"234.10895ms","start":"2026-03-17T22:05:05.169653Z","end":"2026-03-17T22:05:05.403762Z","steps":["trace[386141888] 'range keys from in-memory index tree' (duration: 233.850785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:05:05.403955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.884656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:05:05.404123Z","caller":"traceutil/trace.go:171","msg":"trace[1661328507] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5620; }","duration":"235.112601ms","start":"2026-03-17T22:05:05.168984Z","end":"2026-03-17T22:05:05.404096Z","steps":["trace[1661328507] 'range keys from in-memory index tree' (duration: 234.741543ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:05:14.269844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2736} {"level":"info","ts":"2026-03-17T22:05:14.322826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2736,"took":"51.870755ms","hash":1928907552,"current-db-size-bytes":40603648,"current-db-size":"41 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-17T22:05:14.322957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1928907552,"revision":2736,"compact-revision":-1} {"level":"info","ts":"2026-03-17T22:10:04.991486Z","caller":"traceutil/trace.go:171","msg":"trace[850778254] transaction","detail":"{read_only:false; response_revision:7692; number_of_response:1; }","duration":"250.257376ms","start":"2026-03-17T22:10:04.741201Z","end":"2026-03-17T22:10:04.991459Z","steps":["trace[850778254] 'process raft request' (duration: 250.109592ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:05.095224Z","caller":"traceutil/trace.go:171","msg":"trace[1528109969] linearizableReadLoop","detail":"{readStateIndex:7973; appliedIndex:7971; }","duration":"116.758438ms","start":"2026-03-17T22:10:04.978434Z","end":"2026-03-17T22:10:05.095193Z","steps":["trace[1528109969] 'read index received' (duration: 12.885139ms)","trace[1528109969] 'applied index is now lower than readState.Index' (duration: 103.871649ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:05.095369Z","caller":"traceutil/trace.go:171","msg":"trace[1148079826] transaction","detail":"{read_only:false; response_revision:7693; number_of_response:1; }","duration":"215.438822ms","start":"2026-03-17T22:10:04.879886Z","end":"2026-03-17T22:10:05.095325Z","steps":["trace[1148079826] 'process raft request' (duration: 204.592393ms)","trace[1148079826] 'compare' (duration: 10.45891ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:10:05.095471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.026654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T22:10:05.095554Z","caller":"traceutil/trace.go:171","msg":"trace[404855980] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:7693; }","duration":"117.156138ms","start":"2026-03-17T22:10:04.978383Z","end":"2026-03-17T22:10:05.095539Z","steps":["trace[404855980] 'agreement among raft nodes before linearized reading' (duration: 116.947872ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:14.275116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5691} {"level":"info","ts":"2026-03-17T22:10:14.357303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5691,"took":"80.558341ms","hash":1746725486,"current-db-size-bytes":42549248,"current-db-size":"42 MB","current-db-size-in-use-bytes":23183360,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-17T22:10:14.35736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1746725486,"revision":5691,"compact-revision":2736} {"level":"warn","ts":"2026-03-17T22:10:15.832288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.856636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-17T22:10:15.832386Z","caller":"traceutil/trace.go:171","msg":"trace[2134937605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7744; }","duration":"101.02065ms","start":"2026-03-17T22:10:15.731349Z","end":"2026-03-17T22:10:15.832369Z","steps":["trace[2134937605] 'range keys from in-memory index tree' (duration: 100.684332ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:17.48904Z","caller":"traceutil/trace.go:171","msg":"trace[322261318] linearizableReadLoop","detail":"{readStateIndex:8035; appliedIndex:8034; }","duration":"108.315895ms","start":"2026-03-17T22:10:17.3807Z","end":"2026-03-17T22:10:17.489016Z","steps":["trace[322261318] 'read index received' (duration: 91.83439ms)","trace[322261318] 'applied index is now lower than readState.Index' (duration: 16.480585ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:17.489068Z","caller":"traceutil/trace.go:171","msg":"trace[1686434425] transaction","detail":"{read_only:false; response_revision:7752; number_of_response:1; }","duration":"196.483879ms","start":"2026-03-17T22:10:17.292548Z","end":"2026-03-17T22:10:17.489032Z","steps":["trace[1686434425] 'process raft request' (duration: 179.995634ms)","trace[1686434425] 'compare' (duration: 16.2725ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:10:17.489219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.50758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-17T22:10:17.489249Z","caller":"traceutil/trace.go:171","msg":"trace[1867924568] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:7752; }","duration":"108.593422ms","start":"2026-03-17T22:10:17.380647Z","end":"2026-03-17T22:10:17.489241Z","steps":["trace[1867924568] 'agreement among raft nodes before linearized reading' (duration: 108.456869ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:45.297697Z","caller":"traceutil/trace.go:171","msg":"trace[595829213] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"189.478219ms","start":"2026-03-17T22:10:45.10819Z","end":"2026-03-17T22:10:45.297669Z","steps":["trace[595829213] 'process raft request' (duration: 142.544545ms)","trace[595829213] 'compare' (duration: 46.76863ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:45.301543Z","caller":"traceutil/trace.go:171","msg":"trace[1430617411] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"115.067097ms","start":"2026-03-17T22:10:45.186459Z","end":"2026-03-17T22:10:45.301526Z","steps":["trace[1430617411] 'process raft request' (duration: 114.986195ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:49.533937Z","caller":"traceutil/trace.go:171","msg":"trace[1566764591] linearizableReadLoop","detail":"{readStateIndex:8257; appliedIndex:8255; }","duration":"109.656338ms","start":"2026-03-17T22:10:49.42426Z","end":"2026-03-17T22:10:49.533916Z","steps":["trace[1566764591] 'read index received' (duration: 1.714171ms)","trace[1566764591] 'applied index is now lower than readState.Index' (duration: 107.938967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:10:49.534049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.771571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:10:49.534071Z","caller":"traceutil/trace.go:171","msg":"trace[1392287711] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:7965; }","duration":"109.834193ms","start":"2026-03-17T22:10:49.424229Z","end":"2026-03-17T22:10:49.534063Z","steps":["trace[1392287711] 'agreement among raft nodes before linearized reading' (duration: 109.756871ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:49.534166Z","caller":"traceutil/trace.go:171","msg":"trace[1273908970] transaction","detail":"{read_only:false; response_revision:7965; number_of_response:1; }","duration":"122.880795ms","start":"2026-03-17T22:10:49.411271Z","end":"2026-03-17T22:10:49.534151Z","steps":["trace[1273908970] 'process raft request' (duration: 89.208968ms)","trace[1273908970] 'compare' (duration: 33.284498ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:59.552161Z","caller":"traceutil/trace.go:171","msg":"trace[1942491317] transaction","detail":"{read_only:false; response_revision:8060; number_of_response:1; }","duration":"115.954739ms","start":"2026-03-17T22:10:59.436187Z","end":"2026-03-17T22:10:59.552142Z","steps":["trace[1942491317] 'process raft request' (duration: 115.831026ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:59.596772Z","caller":"traceutil/trace.go:171","msg":"trace[1407699724] linearizableReadLoop","detail":"{readStateIndex:8358; appliedIndex:8356; }","duration":"135.321373ms","start":"2026-03-17T22:10:59.461433Z","end":"2026-03-17T22:10:59.596754Z","steps":["trace[1407699724] 'read index received' (duration: 90.52017ms)","trace[1407699724] 'applied index is now lower than readState.Index' (duration: 44.800303ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:59.597109Z","caller":"traceutil/trace.go:171","msg":"trace[1831495819] transaction","detail":"{read_only:false; response_revision:8061; number_of_response:1; }","duration":"142.72197ms","start":"2026-03-17T22:10:59.454348Z","end":"2026-03-17T22:10:59.59707Z","steps":["trace[1831495819] 'process raft request' (duration: 142.31491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:59.597508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.05621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-03-17T22:10:59.597545Z","caller":"traceutil/trace.go:171","msg":"trace[1691581868] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8061; }","duration":"136.126472ms","start":"2026-03-17T22:10:59.461409Z","end":"2026-03-17T22:10:59.597535Z","steps":["trace[1691581868] 'agreement among raft nodes before linearized reading' (duration: 135.411835ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:59.803275Z","caller":"traceutil/trace.go:171","msg":"trace[88126844] linearizableReadLoop","detail":"{readStateIndex:8359; appliedIndex:8358; }","duration":"149.052391ms","start":"2026-03-17T22:10:59.654176Z","end":"2026-03-17T22:10:59.803229Z","steps":["trace[88126844] 'read index received' (duration: 141.07051ms)","trace[88126844] 'applied index is now lower than readState.Index' (duration: 7.979821ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:59.803308Z","caller":"traceutil/trace.go:171","msg":"trace[2116012] transaction","detail":"{read_only:false; response_revision:8062; number_of_response:1; }","duration":"171.339055ms","start":"2026-03-17T22:10:59.631936Z","end":"2026-03-17T22:10:59.803275Z","steps":["trace[2116012] 'process raft request' (duration: 163.428636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:59.803506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.316368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-03-17T22:10:59.803545Z","caller":"traceutil/trace.go:171","msg":"trace[522301429] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8062; }","duration":"149.42263ms","start":"2026-03-17T22:10:59.654111Z","end":"2026-03-17T22:10:59.803533Z","steps":["trace[522301429] 'agreement among raft nodes before linearized reading' (duration: 149.262036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:59.80358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.563699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-17T22:10:59.803664Z","caller":"traceutil/trace.go:171","msg":"trace[2107936982] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8062; }","duration":"100.735734ms","start":"2026-03-17T22:10:59.702914Z","end":"2026-03-17T22:10:59.80365Z","steps":["trace[2107936982] 'agreement among raft nodes before linearized reading' (duration: 100.557858ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:59.9554Z","caller":"traceutil/trace.go:171","msg":"trace[1950722289] transaction","detail":"{read_only:false; response_revision:8063; number_of_response:1; }","duration":"106.161363ms","start":"2026-03-17T22:10:59.849217Z","end":"2026-03-17T22:10:59.955379Z","steps":["trace[1950722289] 'process raft request' (duration: 98.236823ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:12:15.964925Z","caller":"traceutil/trace.go:171","msg":"trace[1991188680] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"124.483761ms","start":"2026-03-17T22:12:15.84041Z","end":"2026-03-17T22:12:15.964894Z","steps":["trace[1991188680] 'process raft request' (duration: 124.251026ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:12:15.980015Z","caller":"traceutil/trace.go:171","msg":"trace[2133539298] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"108.478957ms","start":"2026-03-17T22:12:15.871514Z","end":"2026-03-17T22:12:15.979993Z","steps":["trace[2133539298] 'process raft request' (duration: 108.383875ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:14:28.436095Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-17T22:14:28.444013Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-17T22:14:28.444116Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-17T22:15:14.280608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7738} {"level":"info","ts":"2026-03-17T22:15:14.342943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7738,"took":"60.360203ms","hash":38930607,"current-db-size-bytes":42549248,"current-db-size":"42 MB","current-db-size-in-use-bytes":26284032,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-17T22:15:14.343066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":38930607,"revision":7738,"compact-revision":5691} {"level":"info","ts":"2026-03-17T22:20:14.285835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9989} {"level":"info","ts":"2026-03-17T22:20:14.360276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9989,"took":"72.071817ms","hash":337906022,"current-db-size-bytes":42549248,"current-db-size":"42 MB","current-db-size-in-use-bytes":17735680,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-17T22:20:14.36035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":337906022,"revision":9989,"compact-revision":7738}