{"level":"warn","ts":"2026-09-15T22:15:14.561939Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:15:14.562067Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.162: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.162:2380","--initial-cluster=instance=https://162.253.55.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.162: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-09-15T22:15:14.562248Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T22:15:14.562272Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-09-15T22:15:14.562724Z","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-09-15T22:15:14.56356Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"]} {"level":"info","ts":"2026-09-15T22:15:14.563862Z","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://162.253.55.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.162: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-09-15T22:15:14.569599Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.177899ms"} {"level":"info","ts":"2026-09-15T22:15:14.577923Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f61dce011a423b00","cluster-id":"d363ade277557413"} {"level":"info","ts":"2026-09-15T22:15:14.57803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:15:14.578056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 0"} {"level":"info","ts":"2026-09-15T22:15:14.578087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f61dce011a423b00 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:15:14.578101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 1"} {"level":"info","ts":"2026-09-15T22:15:14.57816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"warn","ts":"2026-09-15T22:15:14.585609Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:15:14.591495Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:15:14.592737Z","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-09-15T22:15:14.595024Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f61dce011a423b00","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:15:14.595194Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f61dce011a423b00","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:15:14.595281Z","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-09-15T22:15:14.596868Z","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-09-15T22:15:14.596879Z","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-09-15T22:15:14.603909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"info","ts":"2026-09-15T22:15:14.604136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","added-peer-id":"f61dce011a423b00","added-peer-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-09-15T22:15:14.60596Z","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-09-15T22:15:14.606274Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f61dce011a423b00","initial-advertise-peer-urls":["https://162.253.55.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:15:14.606325Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:15:14.606093Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-09-15T22:15:14.606713Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-09-15T22:15:14.682733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:15:14.682801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:15:14.682842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgPreVoteResp from f61dce011a423b00 at term 1"} {"level":"info","ts":"2026-09-15T22:15:14.682863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:15:14.682873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgVoteResp from f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-09-15T22:15:14.682887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became leader at term 2"} {"level":"info","ts":"2026-09-15T22:15:14.682909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f61dce011a423b00 elected leader f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-09-15T22:15:14.690943Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:15:14.691636Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f61dce011a423b00","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.162:2379]}","request-path":"/0/members/f61dce011a423b00/attributes","cluster-id":"d363ade277557413","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:15:14.691889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:15:14.692004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:15:14.695251Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.162:2379"} {"level":"info","ts":"2026-09-15T22:15:14.695941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:15:14.696008Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:15:14.695744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:15:14.69692Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:15:14.697101Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:15:14.697221Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:15:16.748505Z","caller":"traceutil/trace.go:171","msg":"trace[1003767006] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"124.874714ms","start":"2026-09-15T22:15:16.623604Z","end":"2026-09-15T22:15:16.748478Z","steps":["trace[1003767006] 'read index received' (duration: 124.820112ms)","trace[1003767006] 'applied index is now lower than readState.Index' (duration: 53.112µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:15:16.748554Z","caller":"traceutil/trace.go:171","msg":"trace[154427422] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"125.376399ms","start":"2026-09-15T22:15:16.62315Z","end":"2026-09-15T22:15:16.748527Z","steps":["trace[154427422] 'process raft request' (duration: 125.073514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:16.749026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.350327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-15T22:15:16.749199Z","caller":"traceutil/trace.go:171","msg":"trace[885993426] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:2; }","duration":"125.60663ms","start":"2026-09-15T22:15:16.623573Z","end":"2026-09-15T22:15:16.74918Z","steps":["trace[885993426] 'agreement among raft nodes before linearized reading' (duration: 125.033671ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:16.756865Z","caller":"traceutil/trace.go:171","msg":"trace[1447224781] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"133.19565ms","start":"2026-09-15T22:15:16.623589Z","end":"2026-09-15T22:15:16.756784Z","steps":["trace[1447224781] 'process raft request' (duration: 131.530259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:16.760773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.479783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-15T22:15:16.765886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.567501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-15T22:15:16.76596Z","caller":"traceutil/trace.go:171","msg":"trace[735906333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"139.681207ms","start":"2026-09-15T22:15:16.626261Z","end":"2026-09-15T22:15:16.765942Z","steps":["trace[735906333] 'agreement among raft nodes before linearized reading' (duration: 134.506835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:16.766653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.943465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-09-15T22:15:16.763803Z","caller":"traceutil/trace.go:171","msg":"trace[193934307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"137.546092ms","start":"2026-09-15T22:15:16.62623Z","end":"2026-09-15T22:15:16.763776Z","steps":["trace[193934307] 'agreement among raft nodes before linearized reading' (duration: 132.246634ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:16.766767Z","caller":"traceutil/trace.go:171","msg":"trace[1509349262] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"143.077231ms","start":"2026-09-15T22:15:16.623664Z","end":"2026-09-15T22:15:16.766742Z","steps":["trace[1509349262] 'agreement among raft nodes before linearized reading' (duration: 134.872001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:18.681619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.331086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:15:18.681979Z","caller":"traceutil/trace.go:171","msg":"trace[1663784914] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"300.557322ms","start":"2026-09-15T22:15:18.381382Z","end":"2026-09-15T22:15:18.68194Z","steps":["trace[1663784914] 'process raft request' (duration: 22.373005ms)","trace[1663784914] 'compare' (duration: 277.156338ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:15:18.682193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:18.381362Z","time spent":"300.698828ms","remote":"127.0.0.1:47384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":752,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:24.351928Z","caller":"traceutil/trace.go:171","msg":"trace[1955135340] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"160.368735ms","start":"2026-09-15T22:15:24.191533Z","end":"2026-09-15T22:15:24.351902Z","steps":["trace[1955135340] 'process raft request' (duration: 160.178677ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.613045Z","caller":"traceutil/trace.go:171","msg":"trace[399213016] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:353; }","duration":"382.57422ms","start":"2026-09-15T22:15:24.230445Z","end":"2026-09-15T22:15:24.613019Z","steps":["trace[399213016] 'read index received' (duration: 121.349468ms)","trace[399213016] 'applied index is now lower than readState.Index' (duration: 261.223712ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:15:24.613093Z","caller":"traceutil/trace.go:171","msg":"trace[163460711] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"419.755296ms","start":"2026-09-15T22:15:24.193294Z","end":"2026-09-15T22:15:24.61305Z","steps":["trace[163460711] 'process raft request' (duration: 347.971332ms)","trace[163460711] 'compare' (duration: 71.509542ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:15:24.613158Z","caller":"traceutil/trace.go:171","msg":"trace[1653053309] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"419.762536ms","start":"2026-09-15T22:15:24.193352Z","end":"2026-09-15T22:15:24.613115Z","steps":["trace[1653053309] 'process raft request' (duration: 419.588048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.613484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.077787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-09-15T22:15:24.613535Z","caller":"traceutil/trace.go:171","msg":"trace[1012202315] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:344; }","duration":"177.15377ms","start":"2026-09-15T22:15:24.436368Z","end":"2026-09-15T22:15:24.613522Z","steps":["trace[1012202315] 'agreement among raft nodes before linearized reading' (duration: 177.024715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.613482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.141487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-09-15T22:15:24.613627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:24.193319Z","time spent":"419.958424ms","remote":"127.0.0.1:47374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:15:24.613599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.053127ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:15:24.613763Z","caller":"traceutil/trace.go:171","msg":"trace[900604179] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:344; }","duration":"195.229855ms","start":"2026-09-15T22:15:24.418509Z","end":"2026-09-15T22:15:24.613739Z","steps":["trace[900604179] 'agreement among raft nodes before linearized reading' (duration: 195.020566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.613846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.574293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-15T22:15:24.613907Z","caller":"traceutil/trace.go:171","msg":"trace[120235766] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:344; }","duration":"233.696518ms","start":"2026-09-15T22:15:24.380199Z","end":"2026-09-15T22:15:24.613896Z","steps":["trace[120235766] 'agreement among raft nodes before linearized reading' (duration: 233.526079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.613941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.583859ms","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-09-15T22:15:24.613654Z","caller":"traceutil/trace.go:171","msg":"trace[396253595] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"332.351986ms","start":"2026-09-15T22:15:24.281273Z","end":"2026-09-15T22:15:24.613625Z","steps":["trace[396253595] 'agreement among raft nodes before linearized reading' (duration: 332.098725ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.614035Z","caller":"traceutil/trace.go:171","msg":"trace[554946882] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:344; }","duration":"132.725656ms","start":"2026-09-15T22:15:24.481282Z","end":"2026-09-15T22:15:24.614008Z","steps":["trace[554946882] 'agreement among raft nodes before linearized reading' (duration: 132.60644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.614059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.309665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-15T22:15:24.614092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:24.281257Z","time spent":"332.794417ms","remote":"127.0.0.1:47228","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-09-15T22:15:24.614129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.176402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-15T22:15:24.614139Z","caller":"traceutil/trace.go:171","msg":"trace[1582872894] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:344; }","duration":"283.43006ms","start":"2026-09-15T22:15:24.330681Z","end":"2026-09-15T22:15:24.614111Z","steps":["trace[1582872894] 'agreement among raft nodes before linearized reading' (duration: 283.212721ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.614171Z","caller":"traceutil/trace.go:171","msg":"trace[209555207] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:344; }","duration":"177.266676ms","start":"2026-09-15T22:15:24.436868Z","end":"2026-09-15T22:15:24.61416Z","steps":["trace[209555207] 'agreement among raft nodes before linearized reading' (duration: 177.186813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.613578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:24.193266Z","time spent":"419.912872ms","remote":"127.0.0.1:47374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:15:24.614409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.509419ms","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-09-15T22:15:24.61447Z","caller":"traceutil/trace.go:171","msg":"trace[629428624] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"184.613513ms","start":"2026-09-15T22:15:24.429844Z","end":"2026-09-15T22:15:24.614458Z","steps":["trace[629428624] 'agreement among raft nodes before linearized reading' (duration: 183.936264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.614957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.880294ms","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-09-15T22:15:24.615045Z","caller":"traceutil/trace.go:171","msg":"trace[2128575629] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"384.649772ms","start":"2026-09-15T22:15:24.230374Z","end":"2026-09-15T22:15:24.615024Z","steps":["trace[2128575629] 'agreement among raft nodes before linearized reading' (duration: 382.889254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.615092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:24.230348Z","time spent":"384.733377ms","remote":"127.0.0.1:47228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-09-15T22:15:24.831954Z","caller":"traceutil/trace.go:171","msg":"trace[42459405] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"207.887718ms","start":"2026-09-15T22:15:24.624038Z","end":"2026-09-15T22:15:24.831925Z","steps":["trace[42459405] 'process raft request' (duration: 204.477737ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.835555Z","caller":"traceutil/trace.go:171","msg":"trace[2119633526] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:356; }","duration":"204.266318ms","start":"2026-09-15T22:15:24.631234Z","end":"2026-09-15T22:15:24.835501Z","steps":["trace[2119633526] 'read index received' (duration: 197.296548ms)","trace[2119633526] 'applied index is now lower than readState.Index' (duration: 6.96821ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:15:24.836107Z","caller":"traceutil/trace.go:171","msg":"trace[302587115] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"211.942399ms","start":"2026-09-15T22:15:24.624133Z","end":"2026-09-15T22:15:24.836076Z","steps":["trace[302587115] 'process raft request' (duration: 210.825579ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.836499Z","caller":"traceutil/trace.go:171","msg":"trace[2020733088] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"211.931629ms","start":"2026-09-15T22:15:24.624543Z","end":"2026-09-15T22:15:24.836474Z","steps":["trace[2020733088] 'process raft request' (duration: 210.616431ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.836776Z","caller":"traceutil/trace.go:171","msg":"trace[1695825318] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"210.178061ms","start":"2026-09-15T22:15:24.626581Z","end":"2026-09-15T22:15:24.836759Z","steps":["trace[1695825318] 'process raft request' (duration: 208.673194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.836966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.382288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-09-15T22:15:24.837004Z","caller":"traceutil/trace.go:171","msg":"trace[738312379] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"209.71448ms","start":"2026-09-15T22:15:24.627219Z","end":"2026-09-15T22:15:24.836934Z","steps":["trace[738312379] 'process raft request' (duration: 208.179912ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.837055Z","caller":"traceutil/trace.go:171","msg":"trace[2040534543] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:350; }","duration":"156.565406ms","start":"2026-09-15T22:15:24.680471Z","end":"2026-09-15T22:15:24.837036Z","steps":["trace[2040534543] 'agreement among raft nodes before linearized reading' (duration: 156.316645ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:24.837292Z","caller":"traceutil/trace.go:171","msg":"trace[271057080] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"210.339978ms","start":"2026-09-15T22:15:24.626923Z","end":"2026-09-15T22:15:24.837263Z","steps":["trace[271057080] 'process raft request' (duration: 208.407042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.83752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.988609ms","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-09-15T22:15:24.840365Z","caller":"traceutil/trace.go:171","msg":"trace[1947129088] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:350; }","duration":"109.882518ms","start":"2026-09-15T22:15:24.730464Z","end":"2026-09-15T22:15:24.840347Z","steps":["trace[1947129088] 'agreement among raft nodes before linearized reading' (duration: 107.00506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:24.837538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.281087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-15T22:15:24.84067Z","caller":"traceutil/trace.go:171","msg":"trace[543343480] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:350; }","duration":"209.449369ms","start":"2026-09-15T22:15:24.631196Z","end":"2026-09-15T22:15:24.840645Z","steps":["trace[543343480] 'agreement among raft nodes before linearized reading' (duration: 206.262966ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:56.297202Z","caller":"traceutil/trace.go:171","msg":"trace[602506556] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"367.083038ms","start":"2026-09-15T22:15:55.93009Z","end":"2026-09-15T22:15:56.297173Z","steps":["trace[602506556] 'process raft request' (duration: 366.912343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.297403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.930066Z","time spent":"367.259834ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.350243Z","caller":"traceutil/trace.go:171","msg":"trace[1535828688] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"391.197332ms","start":"2026-09-15T22:15:55.958992Z","end":"2026-09-15T22:15:56.35019Z","steps":["trace[1535828688] 'process raft request' (duration: 390.80115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.350439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.95897Z","time spent":"391.374728ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.350706Z","caller":"traceutil/trace.go:171","msg":"trace[921302839] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"377.234881ms","start":"2026-09-15T22:15:55.973392Z","end":"2026-09-15T22:15:56.350627Z","steps":["trace[921302839] 'process raft request' (duration: 376.642882ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:56.350751Z","caller":"traceutil/trace.go:171","msg":"trace[392297441] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"389.645571ms","start":"2026-09-15T22:15:55.961059Z","end":"2026-09-15T22:15:56.350704Z","steps":["trace[392297441] 'process raft request' (duration: 388.859757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.35091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.96105Z","time spent":"389.777665ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.350913Z","caller":"traceutil/trace.go:171","msg":"trace[641891954] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"377.54562ms","start":"2026-09-15T22:15:55.973332Z","end":"2026-09-15T22:15:56.350878Z","steps":["trace[641891954] 'process raft request' (duration: 376.645581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.351073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.973314Z","time spent":"377.676214ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.351135Z","caller":"traceutil/trace.go:171","msg":"trace[197646048] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"352.212121ms","start":"2026-09-15T22:15:55.998913Z","end":"2026-09-15T22:15:56.351126Z","steps":["trace[197646048] 'process raft request' (duration: 351.234201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.351177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.998884Z","time spent":"352.271863ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.351263Z","caller":"traceutil/trace.go:171","msg":"trace[668293135] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"351.703787ms","start":"2026-09-15T22:15:55.999528Z","end":"2026-09-15T22:15:56.351232Z","steps":["trace[668293135] 'process raft request' (duration: 350.668623ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:56.351286Z","caller":"traceutil/trace.go:171","msg":"trace[1072116687] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"361.853444ms","start":"2026-09-15T22:15:55.989414Z","end":"2026-09-15T22:15:56.351268Z","steps":["trace[1072116687] 'process raft request' (duration: 360.689436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.351127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.973388Z","time spent":"377.404886ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.351473Z","caller":"traceutil/trace.go:171","msg":"trace[581288647] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"349.198195ms","start":"2026-09-15T22:15:56.002242Z","end":"2026-09-15T22:15:56.35144Z","steps":["trace[581288647] 'process raft request' (duration: 348.518783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.35157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:56.002196Z","time spent":"349.34107ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.351329Z","caller":"traceutil/trace.go:171","msg":"trace[1355589723] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"351.613063ms","start":"2026-09-15T22:15:55.999708Z","end":"2026-09-15T22:15:56.351321Z","steps":["trace[1355589723] 'process raft request' (duration: 350.739425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.351691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.999702Z","time spent":"351.972935ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:15:56.351337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.999515Z","time spent":"351.792199ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:15:56.351365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:55.989394Z","time spent":"361.933177ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:56.351754Z","caller":"traceutil/trace.go:171","msg":"trace[87603051] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"233.11902ms","start":"2026-09-15T22:15:56.118616Z","end":"2026-09-15T22:15:56.351735Z","steps":["trace[87603051] 'process raft request' (duration: 232.872043ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:15:56.351914Z","caller":"traceutil/trace.go:171","msg":"trace[2099310915] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"332.202201ms","start":"2026-09-15T22:15:56.019695Z","end":"2026-09-15T22:15:56.351897Z","steps":["trace[2099310915] 'process raft request' (duration: 331.596352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:56.35208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:56.019673Z","time spent":"332.365416ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:15:57.614122Z","caller":"traceutil/trace.go:171","msg":"trace[699557303] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"114.421067ms","start":"2026-09-15T22:15:57.499643Z","end":"2026-09-15T22:15:57.614064Z","steps":["trace[699557303] 'process raft request' (duration: 114.111718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:58.062749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.437281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:15:58.062872Z","caller":"traceutil/trace.go:171","msg":"trace[2032216953] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"415.006449ms","start":"2026-09-15T22:15:57.647856Z","end":"2026-09-15T22:15:58.062862Z","steps":["trace[2032216953] 'read index received' (duration: 18.197867ms)","trace[2032216953] 'applied index is now lower than readState.Index' (duration: 396.807942ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:15:58.06294Z","caller":"traceutil/trace.go:171","msg":"trace[1717956881] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"485.02724ms","start":"2026-09-15T22:15:57.577908Z","end":"2026-09-15T22:15:58.062935Z","steps":["trace[1717956881] 'process raft request' (duration: 88.133424ms)","trace[1717956881] 'compare' (duration: 396.064829ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:15:58.062975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:57.577887Z","time spent":"485.071851ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:15:58.063101Z","caller":"traceutil/trace.go:171","msg":"trace[317938781] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"442.384918ms","start":"2026-09-15T22:15:57.620705Z","end":"2026-09-15T22:15:58.06309Z","steps":["trace[317938781] 'process raft request' (duration: 442.11873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:58.063141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:57.620687Z","time spent":"442.436659ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:15:58.06357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.66731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-09-15T22:15:58.063683Z","caller":"traceutil/trace.go:171","msg":"trace[2130732621] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:614; }","duration":"415.832797ms","start":"2026-09-15T22:15:57.647821Z","end":"2026-09-15T22:15:58.063653Z","steps":["trace[2130732621] 'agreement among raft nodes before linearized reading' (duration: 415.403292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:58.063737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:57.647807Z","time spent":"415.919799ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29594,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-09-15T22:15:58.06607Z","caller":"traceutil/trace.go:171","msg":"trace[1164323535] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"311.528778ms","start":"2026-09-15T22:15:57.754488Z","end":"2026-09-15T22:15:58.066017Z","steps":["trace[1164323535] 'process raft request' (duration: 310.92893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:58.066235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.823397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:15:58.066317Z","caller":"traceutil/trace.go:171","msg":"trace[1953677373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"147.955492ms","start":"2026-09-15T22:15:57.918342Z","end":"2026-09-15T22:15:58.066298Z","steps":["trace[1953677373] 'agreement among raft nodes before linearized reading' (duration: 147.777146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:15:58.066333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:15:57.754467Z","time spent":"311.708314ms","remote":"127.0.0.1:47054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:16:03.314869Z","caller":"traceutil/trace.go:171","msg":"trace[330079848] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"229.726954ms","start":"2026-09-15T22:16:03.085113Z","end":"2026-09-15T22:16:03.31484Z","steps":["trace[330079848] 'read index received' (duration: 227.003082ms)","trace[330079848] 'applied index is now lower than readState.Index' (duration: 2.722772ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:16:03.315017Z","caller":"traceutil/trace.go:171","msg":"trace[1632562687] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"263.21429ms","start":"2026-09-15T22:16:03.051789Z","end":"2026-09-15T22:16:03.315004Z","steps":["trace[1632562687] 'process raft request' (duration: 260.275112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:16:03.315255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.102046ms","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-09-15T22:16:03.31531Z","caller":"traceutil/trace.go:171","msg":"trace[802178207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"230.25077ms","start":"2026-09-15T22:16:03.085044Z","end":"2026-09-15T22:16:03.315294Z","steps":["trace[802178207] 'agreement among raft nodes before linearized reading' (duration: 230.122667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:16:03.315658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.522546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-15T22:16:03.315648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.222069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-15T22:16:03.315732Z","caller":"traceutil/trace.go:171","msg":"trace[1467126013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"120.60706ms","start":"2026-09-15T22:16:03.195091Z","end":"2026-09-15T22:16:03.315698Z","steps":["trace[1467126013] 'agreement among raft nodes before linearized reading' (duration: 120.366332ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:16:03.315733Z","caller":"traceutil/trace.go:171","msg":"trace[1789352302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"105.358402ms","start":"2026-09-15T22:16:03.210352Z","end":"2026-09-15T22:16:03.315711Z","steps":["trace[1789352302] 'agreement among raft nodes before linearized reading' (duration: 105.126275ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:16:03.894175Z","caller":"traceutil/trace.go:171","msg":"trace[1684959219] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"425.47408ms","start":"2026-09-15T22:16:03.468674Z","end":"2026-09-15T22:16:03.894148Z","steps":["trace[1684959219] 'process raft request' (duration: 424.161561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:16:03.894442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:16:03.468637Z","time spent":"425.686756ms","remote":"127.0.0.1:47092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:16:19.665851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.355904ms","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-09-15T22:16:19.665946Z","caller":"traceutil/trace.go:171","msg":"trace[1645550928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"215.485268ms","start":"2026-09-15T22:16:19.450436Z","end":"2026-09-15T22:16:19.665921Z","steps":["trace[1645550928] 'range keys from in-memory index tree' (duration: 215.212922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:16:19.666452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.564914ms","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":"warn","ts":"2026-09-15T22:16:19.666457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.444413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-15T22:16:19.666505Z","caller":"traceutil/trace.go:171","msg":"trace[2024784180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"288.661047ms","start":"2026-09-15T22:16:19.377832Z","end":"2026-09-15T22:16:19.666493Z","steps":["trace[2024784180] 'range keys from in-memory index tree' (duration: 288.41293ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:16:19.666539Z","caller":"traceutil/trace.go:171","msg":"trace[1045323026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"216.579417ms","start":"2026-09-15T22:16:19.44994Z","end":"2026-09-15T22:16:19.666519Z","steps":["trace[1045323026] 'range keys from in-memory index tree' (duration: 216.278768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:16:19.667032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.394208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-15T22:16:19.667247Z","caller":"traceutil/trace.go:171","msg":"trace[1763464129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"238.648645ms","start":"2026-09-15T22:16:19.428583Z","end":"2026-09-15T22:16:19.667232Z","steps":["trace[1763464129] 'range keys from in-memory index tree' (duration: 238.038229ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:12.614082Z","caller":"traceutil/trace.go:171","msg":"trace[480240488] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"140.167262ms","start":"2026-09-15T22:17:12.47389Z","end":"2026-09-15T22:17:12.614058Z","steps":["trace[480240488] 'process raft request' (duration: 140.025999ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:39.730512Z","caller":"traceutil/trace.go:171","msg":"trace[994792311] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"154.734806ms","start":"2026-09-15T22:17:39.575736Z","end":"2026-09-15T22:17:39.73047Z","steps":["trace[994792311] 'process raft request' (duration: 154.45838ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:40.220096Z","caller":"traceutil/trace.go:171","msg":"trace[1965600629] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"101.051843ms","start":"2026-09-15T22:17:40.118993Z","end":"2026-09-15T22:17:40.220044Z","steps":["trace[1965600629] 'process raft request' (duration: 96.565268ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:52.736808Z","caller":"traceutil/trace.go:171","msg":"trace[669485606] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"107.179826ms","start":"2026-09-15T22:17:52.629598Z","end":"2026-09-15T22:17:52.736778Z","steps":["trace[669485606] 'process raft request' (duration: 106.900532ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:52.747908Z","caller":"traceutil/trace.go:171","msg":"trace[660959315] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"110.407018ms","start":"2026-09-15T22:17:52.637472Z","end":"2026-09-15T22:17:52.747879Z","steps":["trace[660959315] 'process raft request' (duration: 110.192075ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:19:42.790211Z","caller":"traceutil/trace.go:171","msg":"trace[285717472] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"218.238308ms","start":"2026-09-15T22:19:42.571942Z","end":"2026-09-15T22:19:42.79018Z","steps":["trace[285717472] 'process raft request' (duration: 218.05658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:43.219411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.675815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:23:43.219554Z","caller":"traceutil/trace.go:171","msg":"trace[1490893457] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/podmonitors.monitoring.coreos.com; range_end:; response_count:0; response_revision:4109; }","duration":"107.87894ms","start":"2026-09-15T22:23:43.111654Z","end":"2026-09-15T22:23:43.219533Z","steps":["trace[1490893457] 'agreement among raft nodes before linearized reading' (duration: 31.159901ms)","trace[1490893457] 'range keys from in-memory index tree' (duration: 76.522975ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:39.28299Z","caller":"traceutil/trace.go:171","msg":"trace[1018752596] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"147.071033ms","start":"2026-09-15T22:24:39.13589Z","end":"2026-09-15T22:24:39.282961Z","steps":["trace[1018752596] 'process raft request' (duration: 140.904205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:44.683483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.123855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-09-15T22:24:44.683584Z","caller":"traceutil/trace.go:171","msg":"trace[1529178390] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5062; }","duration":"290.274448ms","start":"2026-09-15T22:24:44.393288Z","end":"2026-09-15T22:24:44.683562Z","steps":["trace[1529178390] 'range keys from in-memory index tree' (duration: 289.8967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:44.68363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.521267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:24:44.683723Z","caller":"traceutil/trace.go:171","msg":"trace[1969771026] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5256; }","duration":"127.523376ms","start":"2026-09-15T22:24:44.556183Z","end":"2026-09-15T22:24:44.683707Z","steps":["trace[1969771026] 'read index received' (duration: 45.471µs)","trace[1969771026] 'applied index is now lower than readState.Index' (duration: 127.476715ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:24:44.683867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.681959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-15T22:24:44.683903Z","caller":"traceutil/trace.go:171","msg":"trace[720960403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5063; }","duration":"127.76129ms","start":"2026-09-15T22:24:44.556131Z","end":"2026-09-15T22:24:44.683893Z","steps":["trace[720960403] 'agreement among raft nodes before linearized reading' (duration: 127.639058ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:44.683813Z","caller":"traceutil/trace.go:171","msg":"trace[474183955] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"228.955822ms","start":"2026-09-15T22:24:44.454777Z","end":"2026-09-15T22:24:44.683732Z","steps":["trace[474183955] 'process raft request' (duration: 66.253241ms)","trace[474183955] 'compare' (duration: 162.409775ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:24:44.740397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.477214ms","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-09-15T22:24:44.740503Z","caller":"traceutil/trace.go:171","msg":"trace[2033808170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5064; }","duration":"103.623817ms","start":"2026-09-15T22:24:44.63686Z","end":"2026-09-15T22:24:44.740484Z","steps":["trace[2033808170] 'agreement among raft nodes before linearized reading' (duration: 103.422524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:44.740753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.511624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-15T22:24:44.740885Z","caller":"traceutil/trace.go:171","msg":"trace[924923446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5064; }","duration":"153.672177ms","start":"2026-09-15T22:24:44.587194Z","end":"2026-09-15T22:24:44.740866Z","steps":["trace[924923446] 'agreement among raft nodes before linearized reading' (duration: 153.421462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:44.741003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.659502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-09-15T22:24:44.741032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.779063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-15T22:24:44.741135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.865638ms","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-09-15T22:24:44.741163Z","caller":"traceutil/trace.go:171","msg":"trace[228919473] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5064; }","duration":"111.814805ms","start":"2026-09-15T22:24:44.629238Z","end":"2026-09-15T22:24:44.741053Z","steps":["trace[228919473] 'agreement among raft nodes before linearized reading' (duration: 111.661102ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:44.741227Z","caller":"traceutil/trace.go:171","msg":"trace[1484303446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5064; }","duration":"158.99571ms","start":"2026-09-15T22:24:44.582211Z","end":"2026-09-15T22:24:44.741207Z","steps":["trace[1484303446] 'agreement among raft nodes before linearized reading' (duration: 158.707384ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:44.741165Z","caller":"traceutil/trace.go:171","msg":"trace[1805044100] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5064; }","duration":"162.963126ms","start":"2026-09-15T22:24:44.578106Z","end":"2026-09-15T22:24:44.741069Z","steps":["trace[1805044100] 'agreement among raft nodes before linearized reading' (duration: 162.6339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:44.741443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.163751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-15T22:24:44.741576Z","caller":"traceutil/trace.go:171","msg":"trace[345368777] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5064; }","duration":"149.356385ms","start":"2026-09-15T22:24:44.592207Z","end":"2026-09-15T22:24:44.741563Z","steps":["trace[345368777] 'agreement among raft nodes before linearized reading' (duration: 148.978408ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:45.136542Z","caller":"traceutil/trace.go:171","msg":"trace[342402779] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"115.568965ms","start":"2026-09-15T22:24:45.020937Z","end":"2026-09-15T22:24:45.136505Z","steps":["trace[342402779] 'process raft request' (duration: 115.32963ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:45.136749Z","caller":"traceutil/trace.go:171","msg":"trace[740037196] linearizableReadLoop","detail":"{readStateIndex:5271; appliedIndex:5267; }","duration":"114.415322ms","start":"2026-09-15T22:24:45.022311Z","end":"2026-09-15T22:24:45.136726Z","steps":["trace[740037196] 'read index received' (duration: 22.910529ms)","trace[740037196] 'applied index is now lower than readState.Index' (duration: 91.503953ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:45.136809Z","caller":"traceutil/trace.go:171","msg":"trace[880512625] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"115.223898ms","start":"2026-09-15T22:24:45.021571Z","end":"2026-09-15T22:24:45.136794Z","steps":["trace[880512625] 'process raft request' (duration: 114.868071ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:45.136859Z","caller":"traceutil/trace.go:171","msg":"trace[2054483803] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"114.746549ms","start":"2026-09-15T22:24:45.022101Z","end":"2026-09-15T22:24:45.136848Z","steps":["trace[2054483803] 'process raft request' (duration: 114.531605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:45.137141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.81142ms","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-09-15T22:24:45.137177Z","caller":"traceutil/trace.go:171","msg":"trace[1635779319] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5077; }","duration":"114.880582ms","start":"2026-09-15T22:24:45.022287Z","end":"2026-09-15T22:24:45.137168Z","steps":["trace[1635779319] 'agreement among raft nodes before linearized reading' (duration: 114.677407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:45.137448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.4876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a29c7b02-3901-458e-927f-4cdfbb7664cc\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-15T22:24:45.137544Z","caller":"traceutil/trace.go:171","msg":"trace[1016765154] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a29c7b02-3901-458e-927f-4cdfbb7664cc; range_end:; response_count:1; response_revision:5077; }","duration":"106.635423ms","start":"2026-09-15T22:24:45.030874Z","end":"2026-09-15T22:24:45.137509Z","steps":["trace[1016765154] 'agreement among raft nodes before linearized reading' (duration: 106.291537ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:45.33417Z","caller":"traceutil/trace.go:171","msg":"trace[1964694661] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"120.855795ms","start":"2026-09-15T22:24:45.213281Z","end":"2026-09-15T22:24:45.334137Z","steps":["trace[1964694661] 'process raft request' (duration: 102.28635ms)","trace[1964694661] 'compare' (duration: 18.27774ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:56.020395Z","caller":"traceutil/trace.go:171","msg":"trace[1390106083] linearizableReadLoop","detail":"{readStateIndex:5409; appliedIndex:5408; }","duration":"100.651301ms","start":"2026-09-15T22:24:55.919716Z","end":"2026-09-15T22:24:56.020368Z","steps":["trace[1390106083] 'read index received' (duration: 100.385136ms)","trace[1390106083] 'applied index is now lower than readState.Index' (duration: 264.875µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:56.020567Z","caller":"traceutil/trace.go:171","msg":"trace[1160860184] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"171.870414ms","start":"2026-09-15T22:24:55.848654Z","end":"2026-09-15T22:24:56.020525Z","steps":["trace[1160860184] 'process raft request' (duration: 171.507847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:56.020626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.888806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:24:56.020681Z","caller":"traceutil/trace.go:171","msg":"trace[1416162490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5209; }","duration":"101.016598ms","start":"2026-09-15T22:24:55.919651Z","end":"2026-09-15T22:24:56.020668Z","steps":["trace[1416162490] 'agreement among raft nodes before linearized reading' (duration: 100.878115ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:56.358652Z","caller":"traceutil/trace.go:171","msg":"trace[1994159366] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5409; }","duration":"260.89869ms","start":"2026-09-15T22:24:56.097723Z","end":"2026-09-15T22:24:56.358622Z","steps":["trace[1994159366] 'read index received' (duration: 260.687226ms)","trace[1994159366] 'applied index is now lower than readState.Index' (duration: 210.554µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:56.358759Z","caller":"traceutil/trace.go:171","msg":"trace[1339868498] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"323.914886ms","start":"2026-09-15T22:24:56.034802Z","end":"2026-09-15T22:24:56.358717Z","steps":["trace[1339868498] 'process raft request' (duration: 323.674941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:56.359021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.282518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-15T22:24:56.359084Z","caller":"traceutil/trace.go:171","msg":"trace[2115673875] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5210; }","duration":"261.417721ms","start":"2026-09-15T22:24:56.097651Z","end":"2026-09-15T22:24:56.359069Z","steps":["trace[2115673875] 'agreement among raft nodes before linearized reading' (duration: 261.185996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:56.359223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:24:56.034783Z","time spent":"324.214302ms","remote":"127.0.0.1:47218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:24:56.477676Z","caller":"traceutil/trace.go:171","msg":"trace[1582321477] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"108.811225ms","start":"2026-09-15T22:24:56.368831Z","end":"2026-09-15T22:24:56.477642Z","steps":["trace[1582321477] 'process raft request' (duration: 96.232097ms)","trace[1582321477] 'compare' (duration: 12.376835ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:56.478218Z","caller":"traceutil/trace.go:171","msg":"trace[1411496468] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"109.133491ms","start":"2026-09-15T22:24:56.369066Z","end":"2026-09-15T22:24:56.478199Z","steps":["trace[1411496468] 'process raft request' (duration: 108.519408ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:56.478246Z","caller":"traceutil/trace.go:171","msg":"trace[1766080937] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"108.293216ms","start":"2026-09-15T22:24:56.369928Z","end":"2026-09-15T22:24:56.478221Z","steps":["trace[1766080937] 'process raft request' (duration: 107.990739ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:25:15.167811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2898} {"level":"info","ts":"2026-09-15T22:25:15.259331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2898,"took":"89.932731ms","hash":2390304441,"current-db-size-bytes":53350400,"current-db-size":"53 MB","current-db-size-in-use-bytes":28258304,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-15T22:25:15.259534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2390304441,"revision":2898,"compact-revision":-1} {"level":"warn","ts":"2026-09-15T22:28:26.795199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.821376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-15T22:28:26.795311Z","caller":"traceutil/trace.go:171","msg":"trace[1479230351] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6786; }","duration":"113.998875ms","start":"2026-09-15T22:28:26.681288Z","end":"2026-09-15T22:28:26.795287Z","steps":["trace[1479230351] 'range keys from in-memory index tree' (duration: 113.577554ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:30:15.175453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5372} {"level":"info","ts":"2026-09-15T22:30:15.259122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5372,"took":"81.630431ms","hash":2617547964,"current-db-size-bytes":53350400,"current-db-size":"53 MB","current-db-size-in-use-bytes":18980864,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-15T22:30:15.259184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2617547964,"revision":5372,"compact-revision":2898}