{"level":"warn","ts":"2026-09-10T10:58:29.868803Z","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-10T10:58:29.869024Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.208: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.208:2380","--initial-cluster=instance=https://162.253.55.208:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.208:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.208: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-10T10:58:29.869188Z","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-10T10:58:29.86921Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.208:2380"]} {"level":"info","ts":"2026-09-10T10:58:29.869258Z","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-10T10:58:29.870293Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.208:2379"]} {"level":"info","ts":"2026-09-10T10:58:29.870405Z","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.208:2380"],"listen-peer-urls":["https://162.253.55.208:2380"],"advertise-client-urls":["https://162.253.55.208:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.208:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.208: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-10T10:58:29.879684Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.917964ms"} {"level":"info","ts":"2026-09-10T10:58:29.888208Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5019b1666ea94ec4","cluster-id":"5aff54f2fc6d3e3d"} {"level":"info","ts":"2026-09-10T10:58:29.888327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T10:58:29.88836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became follower at term 0"} {"level":"info","ts":"2026-09-10T10:58:29.888378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5019b1666ea94ec4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T10:58:29.888393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became follower at term 1"} {"level":"info","ts":"2026-09-10T10:58:29.888457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 switched to configuration voters=(5771839450953371332)"} {"level":"warn","ts":"2026-09-10T10:58:29.897453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T10:58:29.899301Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T10:58:29.900821Z","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-10T10:58:29.904229Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5019b1666ea94ec4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T10:58:29.904807Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5019b1666ea94ec4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T10:58:29.90524Z","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-10T10:58:29.906452Z","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-10T10:58:29.906464Z","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-10T10:58:29.907251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 switched to configuration voters=(5771839450953371332)"} {"level":"info","ts":"2026-09-10T10:58:29.907379Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5aff54f2fc6d3e3d","local-member-id":"5019b1666ea94ec4","added-peer-id":"5019b1666ea94ec4","added-peer-peer-urls":["https://162.253.55.208:2380"]} {"level":"info","ts":"2026-09-10T10:58:29.91307Z","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-10T10:58:29.913277Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.208:2380"} {"level":"info","ts":"2026-09-10T10:58:29.913319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.208:2380"} {"level":"info","ts":"2026-09-10T10:58:29.913584Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5019b1666ea94ec4","initial-advertise-peer-urls":["https://162.253.55.208:2380"],"listen-peer-urls":["https://162.253.55.208:2380"],"advertise-client-urls":["https://162.253.55.208:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.208:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T10:58:29.913648Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T10:58:30.889414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T10:58:30.889485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T10:58:30.889563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 received MsgPreVoteResp from 5019b1666ea94ec4 at term 1"} {"level":"info","ts":"2026-09-10T10:58:30.88958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became candidate at term 2"} {"level":"info","ts":"2026-09-10T10:58:30.889589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 received MsgVoteResp from 5019b1666ea94ec4 at term 2"} {"level":"info","ts":"2026-09-10T10:58:30.8896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5019b1666ea94ec4 became leader at term 2"} {"level":"info","ts":"2026-09-10T10:58:30.88961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5019b1666ea94ec4 elected leader 5019b1666ea94ec4 at term 2"} {"level":"info","ts":"2026-09-10T10:58:30.893439Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:30.894393Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5019b1666ea94ec4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.208:2379]}","request-path":"/0/members/5019b1666ea94ec4/attributes","cluster-id":"5aff54f2fc6d3e3d","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T10:58:30.894435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T10:58:30.894516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T10:58:30.894774Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5aff54f2fc6d3e3d","local-member-id":"5019b1666ea94ec4","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:30.894783Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T10:58:30.894811Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T10:58:30.894894Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:30.894934Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T10:58:30.896519Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T10:58:30.897739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.208:2379"} {"level":"info","ts":"2026-09-10T10:58:33.830917Z","caller":"traceutil/trace.go:171","msg":"trace[1839901758] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"139.53902ms","start":"2026-09-10T10:58:33.691329Z","end":"2026-09-10T10:58:33.830868Z","steps":["trace[1839901758] 'process raft request' (duration: 123.734978ms)","trace[1839901758] 'compare' (duration: 15.616716ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:58:38.889719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.64245ms","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-09-10T10:58:38.889901Z","caller":"traceutil/trace.go:171","msg":"trace[639438472] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:343; }","duration":"137.896309ms","start":"2026-09-10T10:58:38.751975Z","end":"2026-09-10T10:58:38.889872Z","steps":["trace[639438472] 'range keys from in-memory index tree' (duration: 137.248677ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:39.159339Z","caller":"traceutil/trace.go:171","msg":"trace[360699177] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"108.194338ms","start":"2026-09-10T10:58:39.051125Z","end":"2026-09-10T10:58:39.15932Z","steps":["trace[360699177] 'read index received' (duration: 107.967311ms)","trace[360699177] 'applied index is now lower than readState.Index' (duration: 226.197µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:58:39.159378Z","caller":"traceutil/trace.go:171","msg":"trace[813096190] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"149.428182ms","start":"2026-09-10T10:58:39.00993Z","end":"2026-09-10T10:58:39.159359Z","steps":["trace[813096190] 'process raft request' (duration: 149.252496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:39.159504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.363693ms","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-09-10T10:58:39.159532Z","caller":"traceutil/trace.go:171","msg":"trace[1942659580] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"108.454537ms","start":"2026-09-10T10:58:39.051067Z","end":"2026-09-10T10:58:39.159522Z","steps":["trace[1942659580] 'agreement among raft nodes before linearized reading' (duration: 108.357324ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:39.286026Z","caller":"traceutil/trace.go:171","msg":"trace[1053100515] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:352; }","duration":"117.101283ms","start":"2026-09-10T10:58:39.168899Z","end":"2026-09-10T10:58:39.286Z","steps":["trace[1053100515] 'read index received' (duration: 60.690947ms)","trace[1053100515] 'applied index is now lower than readState.Index' (duration: 56.409436ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:58:39.286318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.394523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-09-10T10:58:39.286407Z","caller":"traceutil/trace.go:171","msg":"trace[1458379856] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:347; }","duration":"117.528987ms","start":"2026-09-10T10:58:39.168855Z","end":"2026-09-10T10:58:39.286384Z","steps":["trace[1458379856] 'agreement among raft nodes before linearized reading' (duration: 117.33881ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:39.286533Z","caller":"traceutil/trace.go:171","msg":"trace[870939493] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"117.94684ms","start":"2026-09-10T10:58:39.168535Z","end":"2026-09-10T10:58:39.286482Z","steps":["trace[870939493] 'process raft request' (duration: 117.419892ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:39.287212Z","caller":"traceutil/trace.go:171","msg":"trace[8216742] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"118.724377ms","start":"2026-09-10T10:58:39.167319Z","end":"2026-09-10T10:58:39.286043Z","steps":["trace[8216742] 'process raft request' (duration: 118.517209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:40.29256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.136572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-09-10T10:58:40.292663Z","caller":"traceutil/trace.go:171","msg":"trace[1447502780] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:388; }","duration":"370.313539ms","start":"2026-09-10T10:58:39.922328Z","end":"2026-09-10T10:58:40.292642Z","steps":["trace[1447502780] 'range keys from in-memory index tree' (duration: 370.010068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:40.292703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:39.922295Z","time spent":"370.400802ms","remote":"127.0.0.1:41284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-10T10:58:40.293156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.7548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-10T10:58:40.293315Z","caller":"traceutil/trace.go:171","msg":"trace[2107829111] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"203.768989ms","start":"2026-09-10T10:58:40.089525Z","end":"2026-09-10T10:58:40.293294Z","steps":["trace[2107829111] 'read index received' (duration: 33.211042ms)","trace[2107829111] 'applied index is now lower than readState.Index' (duration: 170.556707ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:58:40.293478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.943304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-jjnz2\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-09-10T10:58:40.293415Z","caller":"traceutil/trace.go:171","msg":"trace[830257494] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"334.138933ms","start":"2026-09-10T10:58:39.959215Z","end":"2026-09-10T10:58:40.293354Z","steps":["trace[830257494] 'process raft request' (duration: 163.602587ms)","trace[830257494] 'compare' (duration: 169.590094ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:58:40.293507Z","caller":"traceutil/trace.go:171","msg":"trace[1725294190] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-jjnz2; range_end:; response_count:1; response_revision:389; }","duration":"204.022797ms","start":"2026-09-10T10:58:40.089474Z","end":"2026-09-10T10:58:40.293497Z","steps":["trace[1725294190] 'agreement among raft nodes before linearized reading' (duration: 203.923284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:40.293662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:39.959191Z","time spent":"334.381391ms","remote":"127.0.0.1:41182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:58:40.751831Z","caller":"traceutil/trace.go:171","msg":"trace[568936070] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"453.779386ms","start":"2026-09-10T10:58:40.298022Z","end":"2026-09-10T10:58:40.751801Z","steps":["trace[568936070] 'process raft request' (duration: 453.546209ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:40.752179Z","caller":"traceutil/trace.go:171","msg":"trace[1699691177] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:397; }","duration":"424.848186ms","start":"2026-09-10T10:58:40.327308Z","end":"2026-09-10T10:58:40.752156Z","steps":["trace[1699691177] 'read index received' (duration: 25.150817ms)","trace[1699691177] 'applied index is now lower than readState.Index' (duration: 399.694959ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:58:40.752274Z","caller":"traceutil/trace.go:171","msg":"trace[674338856] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"449.433264ms","start":"2026-09-10T10:58:40.30279Z","end":"2026-09-10T10:58:40.752223Z","steps":["trace[674338856] 'process raft request' (duration: 448.959227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:40.75234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.010021ms","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-10T10:58:40.752363Z","caller":"traceutil/trace.go:171","msg":"trace[43258957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:392; }","duration":"425.090934ms","start":"2026-09-10T10:58:40.327266Z","end":"2026-09-10T10:58:40.752357Z","steps":["trace[43258957] 'agreement among raft nodes before linearized reading' (duration: 425.018352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:58:40.752387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:40.327229Z","time spent":"425.152356ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-10T10:58:40.752595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:40.342719Z","time spent":"409.870793ms","remote":"127.0.0.1:41602","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-10T10:58:40.752981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:40.298014Z","time spent":"454.005444ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T10:58:40.753323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:58:40.30278Z","time spent":"449.588998ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T10:58:41.040897Z","caller":"traceutil/trace.go:171","msg":"trace[829071950] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"285.067414ms","start":"2026-09-10T10:58:40.755809Z","end":"2026-09-10T10:58:41.040876Z","steps":["trace[829071950] 'process raft request' (duration: 284.876938ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:41.041204Z","caller":"traceutil/trace.go:171","msg":"trace[2020882723] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"283.645488ms","start":"2026-09-10T10:58:40.757533Z","end":"2026-09-10T10:58:41.041178Z","steps":["trace[2020882723] 'process raft request' (duration: 283.515064ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:41.041177Z","caller":"traceutil/trace.go:171","msg":"trace[749399569] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"284.746983ms","start":"2026-09-10T10:58:40.756409Z","end":"2026-09-10T10:58:41.041156Z","steps":["trace[749399569] 'process raft request' (duration: 284.425213ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:58:41.050723Z","caller":"traceutil/trace.go:171","msg":"trace[2011785602] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"248.4894ms","start":"2026-09-10T10:58:40.802178Z","end":"2026-09-10T10:58:41.050668Z","steps":["trace[2011785602] 'process raft request' (duration: 248.338255ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:09.895039Z","caller":"traceutil/trace.go:171","msg":"trace[1999543447] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"234.643052ms","start":"2026-09-10T10:59:09.660372Z","end":"2026-09-10T10:59:09.895015Z","steps":["trace[1999543447] 'process raft request' (duration: 234.485307ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.34448Z","caller":"traceutil/trace.go:171","msg":"trace[1577838076] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"666.533406ms","start":"2026-09-10T10:59:09.677917Z","end":"2026-09-10T10:59:10.344451Z","steps":["trace[1577838076] 'process raft request' (duration: 666.369242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.344637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.677897Z","time spent":"666.655639ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.344641Z","caller":"traceutil/trace.go:171","msg":"trace[1902226158] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"635.614632ms","start":"2026-09-10T10:59:09.708997Z","end":"2026-09-10T10:59:10.344612Z","steps":["trace[1902226158] 'process raft request' (duration: 635.405477ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.344719Z","caller":"traceutil/trace.go:171","msg":"trace[388851655] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"505.056322ms","start":"2026-09-10T10:59:09.839654Z","end":"2026-09-10T10:59:10.34471Z","steps":["trace[388851655] 'process raft request' (duration: 505.008301ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.344752Z","caller":"traceutil/trace.go:171","msg":"trace[873584773] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"678.721691ms","start":"2026-09-10T10:59:09.66602Z","end":"2026-09-10T10:59:10.344742Z","steps":["trace[873584773] 'process raft request' (duration: 677.823885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.344784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.666001Z","time spent":"678.768352ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:59:10.344789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.708978Z","time spent":"635.760956ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:59:10.344788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.839627Z","time spent":"505.113704ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.344823Z","caller":"traceutil/trace.go:171","msg":"trace[2019043442] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"654.823065ms","start":"2026-09-10T10:59:09.689995Z","end":"2026-09-10T10:59:10.344819Z","steps":["trace[2019043442] 'process raft request' (duration: 654.325612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.344847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.689977Z","time spent":"654.861067ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.34489Z","caller":"traceutil/trace.go:171","msg":"trace[175795654] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"615.939316ms","start":"2026-09-10T10:59:09.728945Z","end":"2026-09-10T10:59:10.344884Z","steps":["trace[175795654] 'process raft request' (duration: 615.609767ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.344899Z","caller":"traceutil/trace.go:171","msg":"trace[182928253] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"628.768038ms","start":"2026-09-10T10:59:09.716124Z","end":"2026-09-10T10:59:10.344892Z","steps":["trace[182928253] 'process raft request' (duration: 628.397288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.344913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.728923Z","time spent":"615.980527ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:59:10.344937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.716112Z","time spent":"628.8053ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.344967Z","caller":"traceutil/trace.go:171","msg":"trace[347539109] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"632.554036ms","start":"2026-09-10T10:59:09.712407Z","end":"2026-09-10T10:59:10.344962Z","steps":["trace[347539109] 'process raft request' (duration: 632.073423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.344991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.712404Z","time spent":"632.577087ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.344976Z","caller":"traceutil/trace.go:171","msg":"trace[1209551481] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"650.951866ms","start":"2026-09-10T10:59:09.69397Z","end":"2026-09-10T10:59:10.344922Z","steps":["trace[1209551481] 'process raft request' (duration: 650.38139ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.34496Z","caller":"traceutil/trace.go:171","msg":"trace[501861817] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"632.240388ms","start":"2026-09-10T10:59:09.712338Z","end":"2026-09-10T10:59:10.344579Z","steps":["trace[501861817] 'process raft request' (duration: 632.111074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.345137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.693951Z","time spent":"651.10612ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.345171Z","caller":"traceutil/trace.go:171","msg":"trace[671755299] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"649.424743ms","start":"2026-09-10T10:59:09.69572Z","end":"2026-09-10T10:59:10.345144Z","steps":["trace[671755299] 'process raft request' (duration: 648.664862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.345253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.695694Z","time spent":"649.534845ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:59:10.345263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.712319Z","time spent":"632.845464ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.409147Z","caller":"traceutil/trace.go:171","msg":"trace[1249285059] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:550; }","duration":"159.062176ms","start":"2026-09-10T10:59:10.250064Z","end":"2026-09-10T10:59:10.409127Z","steps":["trace[1249285059] 'read index received' (duration: 94.086938ms)","trace[1249285059] 'applied index is now lower than readState.Index' (duration: 64.974568ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:59:10.409265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.18905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T10:59:10.409326Z","caller":"traceutil/trace.go:171","msg":"trace[804126159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"159.260521ms","start":"2026-09-10T10:59:10.250024Z","end":"2026-09-10T10:59:10.409284Z","steps":["trace[804126159] 'agreement among raft nodes before linearized reading' (duration: 159.183699ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.409654Z","caller":"traceutil/trace.go:171","msg":"trace[1169693864] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"507.321468ms","start":"2026-09-10T10:59:09.902326Z","end":"2026-09-10T10:59:10.409647Z","steps":["trace[1169693864] 'process raft request' (duration: 506.594088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.409708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.902306Z","time spent":"507.367259ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T10:59:10.409859Z","caller":"traceutil/trace.go:171","msg":"trace[1047310259] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"504.55873ms","start":"2026-09-10T10:59:09.905295Z","end":"2026-09-10T10:59:10.409853Z","steps":["trace[1047310259] 'process raft request' (duration: 503.734617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.409898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.905236Z","time spent":"504.638001ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T10:59:10.410018Z","caller":"traceutil/trace.go:171","msg":"trace[500662959] transaction","detail":"{read_only:false; number_of_response:1; response_revision:547; }","duration":"504.424027ms","start":"2026-09-10T10:59:09.905588Z","end":"2026-09-10T10:59:10.410013Z","steps":["trace[500662959] 'process raft request' (duration: 503.507111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.410051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:09.905571Z","time spent":"504.462928ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T10:59:10.500782Z","caller":"traceutil/trace.go:171","msg":"trace[631221207] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"149.799206ms","start":"2026-09-10T10:59:10.350952Z","end":"2026-09-10T10:59:10.500752Z","steps":["trace[631221207] 'process raft request' (duration: 111.230141ms)","trace[631221207] 'compare' (duration: 38.058351ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:10.500886Z","caller":"traceutil/trace.go:171","msg":"trace[1661092922] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"145.594557ms","start":"2026-09-10T10:59:10.355262Z","end":"2026-09-10T10:59:10.500857Z","steps":["trace[1661092922] 'process raft request' (duration: 145.151895ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.500889Z","caller":"traceutil/trace.go:171","msg":"trace[1517185870] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"135.197274ms","start":"2026-09-10T10:59:10.365671Z","end":"2026-09-10T10:59:10.500868Z","steps":["trace[1517185870] 'process raft request' (duration: 134.904566ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.501024Z","caller":"traceutil/trace.go:171","msg":"trace[1248163197] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"130.116962ms","start":"2026-09-10T10:59:10.370882Z","end":"2026-09-10T10:59:10.500999Z","steps":["trace[1248163197] 'process raft request' (duration: 129.809262ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.501186Z","caller":"traceutil/trace.go:171","msg":"trace[345884273] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"144.60329ms","start":"2026-09-10T10:59:10.356575Z","end":"2026-09-10T10:59:10.501178Z","steps":["trace[345884273] 'process raft request' (duration: 143.93499ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.501212Z","caller":"traceutil/trace.go:171","msg":"trace[17010071] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"139.636889ms","start":"2026-09-10T10:59:10.361556Z","end":"2026-09-10T10:59:10.501193Z","steps":["trace[17010071] 'process raft request' (duration: 138.98857ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.501193Z","caller":"traceutil/trace.go:171","msg":"trace[1272016701] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"128.524257ms","start":"2026-09-10T10:59:10.372652Z","end":"2026-09-10T10:59:10.501176Z","steps":["trace[1272016701] 'process raft request' (duration: 128.105644ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.502012Z","caller":"traceutil/trace.go:171","msg":"trace[442380002] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"134.097242ms","start":"2026-09-10T10:59:10.367902Z","end":"2026-09-10T10:59:10.501999Z","steps":["trace[442380002] 'process raft request' (duration: 132.720563ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.552697Z","caller":"traceutil/trace.go:171","msg":"trace[1127829785] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"124.621897ms","start":"2026-09-10T10:59:10.428063Z","end":"2026-09-10T10:59:10.552685Z","steps":["trace[1127829785] 'process raft request' (duration: 124.558475ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.552772Z","caller":"traceutil/trace.go:171","msg":"trace[430457688] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"135.881725ms","start":"2026-09-10T10:59:10.416882Z","end":"2026-09-10T10:59:10.552763Z","steps":["trace[430457688] 'process raft request' (duration: 135.671639ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.552795Z","caller":"traceutil/trace.go:171","msg":"trace[417726093] transaction","detail":"{read_only:false; number_of_response:1; response_revision:557; }","duration":"133.988931ms","start":"2026-09-10T10:59:10.418802Z","end":"2026-09-10T10:59:10.552791Z","steps":["trace[417726093] 'process raft request' (duration: 133.785564ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.552823Z","caller":"traceutil/trace.go:171","msg":"trace[259728378] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"138.853419ms","start":"2026-09-10T10:59:10.413965Z","end":"2026-09-10T10:59:10.552819Z","steps":["trace[259728378] 'process raft request' (duration: 138.518859ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.716078Z","caller":"traceutil/trace.go:171","msg":"trace[488642699] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"157.292256ms","start":"2026-09-10T10:59:10.558769Z","end":"2026-09-10T10:59:10.716061Z","steps":["trace[488642699] 'process raft request' (duration: 100.644012ms)","trace[488642699] 'compare' (duration: 56.247963ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:10.716849Z","caller":"traceutil/trace.go:171","msg":"trace[1418115078] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"148.654033ms","start":"2026-09-10T10:59:10.568185Z","end":"2026-09-10T10:59:10.716839Z","steps":["trace[1418115078] 'process raft request' (duration: 147.8478ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.716951Z","caller":"traceutil/trace.go:171","msg":"trace[1983877635] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"113.073432ms","start":"2026-09-10T10:59:10.60387Z","end":"2026-09-10T10:59:10.716944Z","steps":["trace[1983877635] 'read index received' (duration: 55.43704ms)","trace[1983877635] 'applied index is now lower than readState.Index' (duration: 57.635562ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:10.717045Z","caller":"traceutil/trace.go:171","msg":"trace[334549469] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"109.192592ms","start":"2026-09-10T10:59:10.607846Z","end":"2026-09-10T10:59:10.717039Z","steps":["trace[334549469] 'process raft request' (duration: 108.74683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.717228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.34204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-10T10:59:10.717256Z","caller":"traceutil/trace.go:171","msg":"trace[526863913] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:564; }","duration":"113.379291ms","start":"2026-09-10T10:59:10.603865Z","end":"2026-09-10T10:59:10.717244Z","steps":["trace[526863913] 'agreement among raft nodes before linearized reading' (duration: 113.266738ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.71748Z","caller":"traceutil/trace.go:171","msg":"trace[1910007367] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"105.613211ms","start":"2026-09-10T10:59:10.611859Z","end":"2026-09-10T10:59:10.717472Z","steps":["trace[1910007367] 'process raft request' (duration: 105.145248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:10.717837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.881184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-10T10:59:10.717961Z","caller":"traceutil/trace.go:171","msg":"trace[1555272717] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:566; }","duration":"113.938876ms","start":"2026-09-10T10:59:10.603923Z","end":"2026-09-10T10:59:10.717862Z","steps":["trace[1555272717] 'agreement among raft nodes before linearized reading' (duration: 113.850763ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.978962Z","caller":"traceutil/trace.go:171","msg":"trace[45315219] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"202.094117ms","start":"2026-09-10T10:59:10.776834Z","end":"2026-09-10T10:59:10.978928Z","steps":["trace[45315219] 'process raft request' (duration: 112.888916ms)","trace[45315219] 'compare' (duration: 88.8147ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:10.978939Z","caller":"traceutil/trace.go:171","msg":"trace[1699591976] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"201.533791ms","start":"2026-09-10T10:59:10.777376Z","end":"2026-09-10T10:59:10.97891Z","steps":["trace[1699591976] 'process raft request' (duration: 201.359626ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:10.982325Z","caller":"traceutil/trace.go:171","msg":"trace[362241289] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"168.599534ms","start":"2026-09-10T10:59:10.813707Z","end":"2026-09-10T10:59:10.982306Z","steps":["trace[362241289] 'process raft request' (duration: 168.179023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:15.380102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.881225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T10:59:15.3803Z","caller":"traceutil/trace.go:171","msg":"trace[868656998] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"327.661335ms","start":"2026-09-10T10:59:15.052618Z","end":"2026-09-10T10:59:15.380279Z","steps":["trace[868656998] 'process raft request' (duration: 204.493502ms)","trace[868656998] 'compare' (duration: 122.228408ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:59:15.380369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:15.052585Z","time spent":"327.751768ms","remote":"127.0.0.1:41200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T10:59:16.529954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.297685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T10:59:16.530019Z","caller":"traceutil/trace.go:171","msg":"trace[63590782] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:636; }","duration":"118.401088ms","start":"2026-09-10T10:59:16.411601Z","end":"2026-09-10T10:59:16.530003Z","steps":["trace[63590782] 'range keys from in-memory index tree' (duration: 118.235063ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:17.287678Z","caller":"traceutil/trace.go:171","msg":"trace[355144556] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"224.592697ms","start":"2026-09-10T10:59:17.063059Z","end":"2026-09-10T10:59:17.287652Z","steps":["trace[355144556] 'read index received' (duration: 223.712302ms)","trace[355144556] 'applied index is now lower than readState.Index' (duration: 879.395µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:17.288115Z","caller":"traceutil/trace.go:171","msg":"trace[1853293407] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"372.249951ms","start":"2026-09-10T10:59:16.915849Z","end":"2026-09-10T10:59:17.288099Z","steps":["trace[1853293407] 'process raft request' (duration: 371.222272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:17.288331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:16.915832Z","time spent":"372.317672ms","remote":"127.0.0.1:41200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T10:59:17.288303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.187162ms","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-10T10:59:17.288413Z","caller":"traceutil/trace.go:171","msg":"trace[360721680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"225.358257ms","start":"2026-09-10T10:59:17.06303Z","end":"2026-09-10T10:59:17.288389Z","steps":["trace[360721680] 'agreement among raft nodes before linearized reading' (duration: 224.988657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:17.288881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.54489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lzrb2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-10T10:59:17.288914Z","caller":"traceutil/trace.go:171","msg":"trace[1477060971] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lzrb2; range_end:; response_count:1; response_revision:642; }","duration":"110.580051ms","start":"2026-09-10T10:59:17.178327Z","end":"2026-09-10T10:59:17.288907Z","steps":["trace[1477060971] 'agreement among raft nodes before linearized reading' (duration: 110.502129ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:17.456121Z","caller":"traceutil/trace.go:171","msg":"trace[493585255] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"147.032907ms","start":"2026-09-10T10:59:17.309066Z","end":"2026-09-10T10:59:17.456099Z","steps":["trace[493585255] 'process raft request' (duration: 142.942616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:18.116221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.428885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-10T10:59:18.116317Z","caller":"traceutil/trace.go:171","msg":"trace[717587617] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"720.727318ms","start":"2026-09-10T10:59:17.395575Z","end":"2026-09-10T10:59:18.116302Z","steps":["trace[717587617] 'read index received' (duration: 56.445112ms)","trace[717587617] 'applied index is now lower than readState.Index' (duration: 664.280736ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T10:59:18.116353Z","caller":"traceutil/trace.go:171","msg":"trace[1087349055] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"749.566567ms","start":"2026-09-10T10:59:17.366769Z","end":"2026-09-10T10:59:18.116335Z","steps":["trace[1087349055] 'process raft request' (duration: 508.974726ms)","trace[1087349055] 'compare' (duration: 240.266491ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T10:59:18.116422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:17.366744Z","time spent":"749.644629ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T10:59:18.11652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.937624ms","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-09-10T10:59:18.116548Z","caller":"traceutil/trace.go:171","msg":"trace[2012894124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"721.008476ms","start":"2026-09-10T10:59:17.39553Z","end":"2026-09-10T10:59:18.116539Z","steps":["trace[2012894124] 'agreement among raft nodes before linearized reading' (duration: 720.848282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:18.116572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:17.395516Z","time spent":"721.049858ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-10T10:59:18.116753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.173444ms","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-10T10:59:18.11678Z","caller":"traceutil/trace.go:171","msg":"trace[1519070831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"720.227955ms","start":"2026-09-10T10:59:17.396544Z","end":"2026-09-10T10:59:18.116772Z","steps":["trace[1519070831] 'agreement among raft nodes before linearized reading' (duration: 720.152113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T10:59:18.116802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T10:59:17.39653Z","time spent":"720.266677ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-10T10:59:19.468031Z","caller":"traceutil/trace.go:171","msg":"trace[656335229] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"116.097437ms","start":"2026-09-10T10:59:19.351904Z","end":"2026-09-10T10:59:19.468001Z","steps":["trace[656335229] 'process raft request' (duration: 112.292274ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T10:59:34.976777Z","caller":"traceutil/trace.go:171","msg":"trace[851497001] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"120.835882ms","start":"2026-09-10T10:59:34.855923Z","end":"2026-09-10T10:59:34.976759Z","steps":["trace[851497001] 'process raft request' (duration: 120.709519ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:22.656424Z","caller":"traceutil/trace.go:171","msg":"trace[452938367] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"145.194675ms","start":"2026-09-10T11:00:22.511199Z","end":"2026-09-10T11:00:22.656394Z","steps":["trace[452938367] 'process raft request' (duration: 130.594191ms)","trace[452938367] 'compare' (duration: 14.289737ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:00:22.656599Z","caller":"traceutil/trace.go:171","msg":"trace[1757820689] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"121.34387ms","start":"2026-09-10T11:00:22.535241Z","end":"2026-09-10T11:00:22.656584Z","steps":["trace[1757820689] 'process raft request' (duration: 121.070944ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:26.047402Z","caller":"traceutil/trace.go:171","msg":"trace[1231273199] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1415; }","duration":"114.507459ms","start":"2026-09-10T11:00:25.932869Z","end":"2026-09-10T11:00:26.047377Z","steps":["trace[1231273199] 'read index received' (duration: 103.129161ms)","trace[1231273199] 'applied index is now lower than readState.Index' (duration: 11.375938ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:00:26.047791Z","caller":"traceutil/trace.go:171","msg":"trace[956754800] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"156.347069ms","start":"2026-09-10T11:00:25.891427Z","end":"2026-09-10T11:00:26.047774Z","steps":["trace[956754800] 'process raft request' (duration: 144.620854ms)","trace[956754800] 'compare' (duration: 11.00558ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:00:26.048297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.397878ms","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-10T11:00:26.048433Z","caller":"traceutil/trace.go:171","msg":"trace[884629468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"115.551542ms","start":"2026-09-10T11:00:25.932859Z","end":"2026-09-10T11:00:26.048411Z","steps":["trace[884629468] 'agreement among raft nodes before linearized reading' (duration: 114.612601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:58.215254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.978387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:00:58.215573Z","caller":"traceutil/trace.go:171","msg":"trace[1900874502] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"453.673155ms","start":"2026-09-10T11:00:57.761874Z","end":"2026-09-10T11:00:58.215547Z","steps":["trace[1900874502] 'read index received' (duration: 292.291508ms)","trace[1900874502] 'applied index is now lower than readState.Index' (duration: 161.380307ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:00:58.215586Z","caller":"traceutil/trace.go:171","msg":"trace[666317036] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"547.277352ms","start":"2026-09-10T11:00:57.668257Z","end":"2026-09-10T11:00:58.215534Z","steps":["trace[666317036] 'process raft request' (duration: 385.877855ms)","trace[666317036] 'compare' (duration: 160.504137ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:00:58.215811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:00:57.668238Z","time spent":"547.439305ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10929,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:00:58.215886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.013102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-10T11:00:58.215937Z","caller":"traceutil/trace.go:171","msg":"trace[1195628928] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1900; }","duration":"454.114454ms","start":"2026-09-10T11:00:57.761806Z","end":"2026-09-10T11:00:58.21592Z","steps":["trace[1195628928] 'agreement among raft nodes before linearized reading' (duration: 453.882019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:58.216164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:00:57.761763Z","time spent":"454.39152ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-10T11:00:58.216573Z","caller":"traceutil/trace.go:171","msg":"trace[227487706] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"146.255109ms","start":"2026-09-10T11:00:58.070302Z","end":"2026-09-10T11:00:58.216557Z","steps":["trace[227487706] 'process raft request' (duration: 145.115154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:58.216618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.282484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:00:58.216696Z","caller":"traceutil/trace.go:171","msg":"trace[1678305070] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1900; }","duration":"207.369987ms","start":"2026-09-10T11:00:58.009281Z","end":"2026-09-10T11:00:58.216651Z","steps":["trace[1678305070] 'agreement among raft nodes before linearized reading' (duration: 207.242844ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:58.399406Z","caller":"traceutil/trace.go:171","msg":"trace[889372962] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"177.13636ms","start":"2026-09-10T11:00:58.222236Z","end":"2026-09-10T11:00:58.399373Z","steps":["trace[889372962] 'process raft request' (duration: 171.988227ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:58.47848Z","caller":"traceutil/trace.go:171","msg":"trace[71304887] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"254.521921ms","start":"2026-09-10T11:00:58.223915Z","end":"2026-09-10T11:00:58.478437Z","steps":["trace[71304887] 'process raft request' (duration: 254.373787ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:58.478606Z","caller":"traceutil/trace.go:171","msg":"trace[1165016967] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"255.955183ms","start":"2026-09-10T11:00:58.222596Z","end":"2026-09-10T11:00:58.478551Z","steps":["trace[1165016967] 'process raft request' (duration: 255.567524ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:58.478756Z","caller":"traceutil/trace.go:171","msg":"trace[1588318214] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"254.564072ms","start":"2026-09-10T11:00:58.224161Z","end":"2026-09-10T11:00:58.478725Z","steps":["trace[1588318214] 'process raft request' (duration: 254.217785ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:58.479038Z","caller":"traceutil/trace.go:171","msg":"trace[12887963] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"254.772317ms","start":"2026-09-10T11:00:58.224257Z","end":"2026-09-10T11:00:58.47903Z","steps":["trace[12887963] 'process raft request' (duration: 254.146134ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:00:58.47918Z","caller":"traceutil/trace.go:171","msg":"trace[1133108626] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1973; }","duration":"256.332461ms","start":"2026-09-10T11:00:58.222837Z","end":"2026-09-10T11:00:58.47917Z","steps":["trace[1133108626] 'read index received' (duration: 171.548639ms)","trace[1133108626] 'applied index is now lower than readState.Index' (duration: 84.782482ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:00:58.479208Z","caller":"traceutil/trace.go:171","msg":"trace[1457765954] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"254.881249ms","start":"2026-09-10T11:00:58.224317Z","end":"2026-09-10T11:00:58.479198Z","steps":["trace[1457765954] 'process raft request' (duration: 254.636034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:58.479313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.471273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-319483b5-99f1-4e1e-bcbf-30fad9fb5b6f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-10T11:00:58.479335Z","caller":"traceutil/trace.go:171","msg":"trace[156892957] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-319483b5-99f1-4e1e-bcbf-30fad9fb5b6f; range_end:; response_count:1; response_revision:1906; }","duration":"256.536625ms","start":"2026-09-10T11:00:58.222792Z","end":"2026-09-10T11:00:58.479329Z","steps":["trace[156892957] 'agreement among raft nodes before linearized reading' (duration: 256.426903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:58.479607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.20989ms","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-10T11:00:58.479633Z","caller":"traceutil/trace.go:171","msg":"trace[182989479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1906; }","duration":"240.271741ms","start":"2026-09-10T11:00:58.239355Z","end":"2026-09-10T11:00:58.479626Z","steps":["trace[182989479] 'agreement among raft nodes before linearized reading' (duration: 240.170019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:58.479781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.751302ms","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-10T11:00:58.479804Z","caller":"traceutil/trace.go:171","msg":"trace[775524872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1906; }","duration":"153.809583ms","start":"2026-09-10T11:00:58.325989Z","end":"2026-09-10T11:00:58.479798Z","steps":["trace[775524872] 'agreement among raft nodes before linearized reading' (duration: 153.750842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:00:58.479916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.252404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:00:58.479937Z","caller":"traceutil/trace.go:171","msg":"trace[922775245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1906; }","duration":"230.316816ms","start":"2026-09-10T11:00:58.249615Z","end":"2026-09-10T11:00:58.479932Z","steps":["trace[922775245] 'agreement among raft nodes before linearized reading' (duration: 230.285845ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:46.892988Z","caller":"traceutil/trace.go:171","msg":"trace[1466683129] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"158.898552ms","start":"2026-09-10T11:01:46.734057Z","end":"2026-09-10T11:01:46.892956Z","steps":["trace[1466683129] 'process raft request' (duration: 158.647387ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:47.189161Z","caller":"traceutil/trace.go:171","msg":"trace[913348557] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2304; }","duration":"201.941505ms","start":"2026-09-10T11:01:46.987189Z","end":"2026-09-10T11:01:47.18913Z","steps":["trace[913348557] 'read index received' (duration: 200.252469ms)","trace[913348557] 'applied index is now lower than readState.Index' (duration: 1.688306ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:47.189217Z","caller":"traceutil/trace.go:171","msg":"trace[675316918] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"212.466015ms","start":"2026-09-10T11:01:46.976727Z","end":"2026-09-10T11:01:47.189193Z","steps":["trace[675316918] 'process raft request' (duration: 210.803199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:47.18929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.083677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:01:47.189318Z","caller":"traceutil/trace.go:171","msg":"trace[226789238] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2220; }","duration":"202.21052ms","start":"2026-09-10T11:01:46.987094Z","end":"2026-09-10T11:01:47.189305Z","steps":["trace[226789238] 'agreement among raft nodes before linearized reading' (duration: 202.138019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:47.599809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.81452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:01:47.59993Z","caller":"traceutil/trace.go:171","msg":"trace[795449790] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2305; }","duration":"349.322584ms","start":"2026-09-10T11:01:47.25059Z","end":"2026-09-10T11:01:47.599913Z","steps":["trace[795449790] 'read index received' (duration: 168.206447ms)","trace[795449790] 'applied index is now lower than readState.Index' (duration: 181.115027ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:47.600133Z","caller":"traceutil/trace.go:171","msg":"trace[434805473] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"425.225485ms","start":"2026-09-10T11:01:47.174898Z","end":"2026-09-10T11:01:47.600123Z","steps":["trace[434805473] 'process raft request' (duration: 243.966226ms)","trace[434805473] 'compare' (duration: 180.258288ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:01:47.600191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:01:47.174871Z","time spent":"425.292606ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:01:47.600399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.818134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:01:47.600422Z","caller":"traceutil/trace.go:171","msg":"trace[1670425080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2221; }","duration":"349.891685ms","start":"2026-09-10T11:01:47.250521Z","end":"2026-09-10T11:01:47.600413Z","steps":["trace[1670425080] 'agreement among raft nodes before linearized reading' (duration: 349.845754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:47.600443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:01:47.250493Z","time spent":"349.945906ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T11:01:47.600683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.020855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T11:01:47.600713Z","caller":"traceutil/trace.go:171","msg":"trace[643717155] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2221; }","duration":"120.114377ms","start":"2026-09-10T11:01:47.480589Z","end":"2026-09-10T11:01:47.600703Z","steps":["trace[643717155] 'agreement among raft nodes before linearized reading' (duration: 120.057976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:47.600798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.002587ms","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-10T11:01:47.600925Z","caller":"traceutil/trace.go:171","msg":"trace[1703668151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2221; }","duration":"308.177151ms","start":"2026-09-10T11:01:47.292714Z","end":"2026-09-10T11:01:47.600892Z","steps":["trace[1703668151] 'agreement among raft nodes before linearized reading' (duration: 307.865743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:01:47.600993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:01:47.292689Z","time spent":"308.290163ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-10T11:01:47.60108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.948714ms","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-09-10T11:01:47.601119Z","caller":"traceutil/trace.go:171","msg":"trace[694310795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2221; }","duration":"244.038027ms","start":"2026-09-10T11:01:47.35707Z","end":"2026-09-10T11:01:47.601108Z","steps":["trace[694310795] 'agreement among raft nodes before linearized reading' (duration: 243.909944ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:01:47.863466Z","caller":"traceutil/trace.go:171","msg":"trace[1620769110] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"257.891076ms","start":"2026-09-10T11:01:47.605533Z","end":"2026-09-10T11:01:47.863424Z","steps":["trace[1620769110] 'process raft request' (duration: 207.570111ms)","trace[1620769110] 'compare' (duration: 50.013778ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:01:47.864011Z","caller":"traceutil/trace.go:171","msg":"trace[808116656] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"257.330285ms","start":"2026-09-10T11:01:47.606651Z","end":"2026-09-10T11:01:47.863981Z","steps":["trace[808116656] 'process raft request' (duration: 257.235912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:05.916346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.07235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d3f0a90a34f28c\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-10T11:02:05.916423Z","caller":"traceutil/trace.go:171","msg":"trace[126216243] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d3f0a90a34f28c; range_end:; response_count:1; response_revision:2320; }","duration":"108.202853ms","start":"2026-09-10T11:02:05.808203Z","end":"2026-09-10T11:02:05.916406Z","steps":["trace[126216243] 'range keys from in-memory index tree' (duration: 107.879295ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:06.128305Z","caller":"traceutil/trace.go:171","msg":"trace[1436481209] linearizableReadLoop","detail":"{readStateIndex:2411; appliedIndex:2410; }","duration":"177.858908ms","start":"2026-09-10T11:02:05.950426Z","end":"2026-09-10T11:02:06.128285Z","steps":["trace[1436481209] 'read index received' (duration: 88.467147ms)","trace[1436481209] 'applied index is now lower than readState.Index' (duration: 89.390201ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:06.128337Z","caller":"traceutil/trace.go:171","msg":"trace[367431971] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"180.027577ms","start":"2026-09-10T11:02:05.948281Z","end":"2026-09-10T11:02:06.128308Z","steps":["trace[367431971] 'process raft request' (duration: 90.693358ms)","trace[367431971] 'compare' (duration: 89.234007ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:06.128596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.307397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T11:02:06.128627Z","caller":"traceutil/trace.go:171","msg":"trace[1533133004] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2322; }","duration":"138.398649ms","start":"2026-09-10T11:02:05.990221Z","end":"2026-09-10T11:02:06.12862Z","steps":["trace[1533133004] 'agreement among raft nodes before linearized reading' (duration: 138.301046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:06.128737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.210678ms","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-09-10T11:02:06.128779Z","caller":"traceutil/trace.go:171","msg":"trace[1904676568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2322; }","duration":"178.381922ms","start":"2026-09-10T11:02:05.95039Z","end":"2026-09-10T11:02:06.128772Z","steps":["trace[1904676568] 'agreement among raft nodes before linearized reading' (duration: 178.004982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:06.495345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.815447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:02:06.495437Z","caller":"traceutil/trace.go:171","msg":"trace[388823907] linearizableReadLoop","detail":"{readStateIndex:2413; appliedIndex:2412; }","duration":"304.666796ms","start":"2026-09-10T11:02:06.190757Z","end":"2026-09-10T11:02:06.495424Z","steps":["trace[388823907] 'read index received' (duration: 55.677683ms)","trace[388823907] 'applied index is now lower than readState.Index' (duration: 248.987833ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:06.495508Z","caller":"traceutil/trace.go:171","msg":"trace[973080802] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"363.443303ms","start":"2026-09-10T11:02:06.131997Z","end":"2026-09-10T11:02:06.49544Z","steps":["trace[973080802] 'process raft request' (duration: 114.452321ms)","trace[973080802] 'compare' (duration: 248.467578ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:06.495574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.82491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-09-10T11:02:06.49559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.633138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:02:06.495643Z","caller":"traceutil/trace.go:171","msg":"trace[714416731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2324; }","duration":"246.71262ms","start":"2026-09-10T11:02:06.24892Z","end":"2026-09-10T11:02:06.495633Z","steps":["trace[714416731] 'agreement among raft nodes before linearized reading' (duration: 246.643148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:06.495744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:06.13198Z","time spent":"363.622367ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:06.495601Z","caller":"traceutil/trace.go:171","msg":"trace[383565903] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2324; }","duration":"304.902151ms","start":"2026-09-10T11:02:06.190691Z","end":"2026-09-10T11:02:06.495593Z","steps":["trace[383565903] 'agreement among raft nodes before linearized reading' (duration: 304.800809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:06.496424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:06.190675Z","time spent":"305.699574ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-10T11:02:11.423764Z","caller":"traceutil/trace.go:171","msg":"trace[1305800678] linearizableReadLoop","detail":"{readStateIndex:2451; appliedIndex:2450; }","duration":"305.287548ms","start":"2026-09-10T11:02:11.118455Z","end":"2026-09-10T11:02:11.423743Z","steps":["trace[1305800678] 'read index received' (duration: 304.908258ms)","trace[1305800678] 'applied index is now lower than readState.Index' (duration: 378.37µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:11.424074Z","caller":"traceutil/trace.go:171","msg":"trace[1598464606] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"332.585522ms","start":"2026-09-10T11:02:11.091478Z","end":"2026-09-10T11:02:11.424063Z","steps":["trace[1598464606] 'process raft request' (duration: 332.10655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:11.424236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:11.091343Z","time spent":"332.757937ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:11.424614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.166813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-10T11:02:11.424666Z","caller":"traceutil/trace.go:171","msg":"trace[2147000215] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2361; }","duration":"306.259505ms","start":"2026-09-10T11:02:11.118395Z","end":"2026-09-10T11:02:11.424655Z","steps":["trace[2147000215] 'agreement among raft nodes before linearized reading' (duration: 306.107371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:11.42474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:11.118319Z","time spent":"306.413509ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-10T11:02:11.424896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.943128ms","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-10T11:02:11.425001Z","caller":"traceutil/trace.go:171","msg":"trace[2082265595] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2361; }","duration":"302.114022ms","start":"2026-09-10T11:02:11.122858Z","end":"2026-09-10T11:02:11.424972Z","steps":["trace[2082265595] 'agreement among raft nodes before linearized reading' (duration: 301.850955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:11.425067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:11.122827Z","time spent":"302.225345ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-10T11:02:11.42558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.933885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-10T11:02:11.425646Z","caller":"traceutil/trace.go:171","msg":"trace[726733507] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2361; }","duration":"240.068119ms","start":"2026-09-10T11:02:11.185559Z","end":"2026-09-10T11:02:11.425627Z","steps":["trace[726733507] 'agreement among raft nodes before linearized reading' (duration: 239.895233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:11.425685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.420973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:02:11.425741Z","caller":"traceutil/trace.go:171","msg":"trace[229802028] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2361; }","duration":"143.517135ms","start":"2026-09-10T11:02:11.282196Z","end":"2026-09-10T11:02:11.425713Z","steps":["trace[229802028] 'agreement among raft nodes before linearized reading' (duration: 143.418903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:11.425854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.38056ms","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-10T11:02:11.425894Z","caller":"traceutil/trace.go:171","msg":"trace[2111138872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2361; }","duration":"298.443071ms","start":"2026-09-10T11:02:11.127439Z","end":"2026-09-10T11:02:11.425882Z","steps":["trace[2111138872] 'agreement among raft nodes before linearized reading' (duration: 298.312967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:11.425927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.441711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:02:11.425955Z","caller":"traceutil/trace.go:171","msg":"trace[2129796798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2361; }","duration":"177.597296ms","start":"2026-09-10T11:02:11.248349Z","end":"2026-09-10T11:02:11.425946Z","steps":["trace[2129796798] 'agreement among raft nodes before linearized reading' (duration: 177.534194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:11.425574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.029349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5460"} {"level":"info","ts":"2026-09-10T11:02:11.426111Z","caller":"traceutil/trace.go:171","msg":"trace[556356588] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2361; }","duration":"232.760039ms","start":"2026-09-10T11:02:11.193335Z","end":"2026-09-10T11:02:11.426095Z","steps":["trace[556356588] 'agreement among raft nodes before linearized reading' (duration: 232.094241ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:11.576116Z","caller":"traceutil/trace.go:171","msg":"trace[1668146039] transaction","detail":"{read_only:false; response_revision:2362; number_of_response:1; }","duration":"143.131255ms","start":"2026-09-10T11:02:11.432935Z","end":"2026-09-10T11:02:11.576066Z","steps":["trace[1668146039] 'process raft request' (duration: 129.347298ms)","trace[1668146039] 'compare' (duration: 13.52408ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:11.576185Z","caller":"traceutil/trace.go:171","msg":"trace[347437622] transaction","detail":"{read_only:false; response_revision:2363; number_of_response:1; }","duration":"142.889318ms","start":"2026-09-10T11:02:11.433271Z","end":"2026-09-10T11:02:11.57616Z","steps":["trace[347437622] 'process raft request' (duration: 142.694083ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:11.576299Z","caller":"traceutil/trace.go:171","msg":"trace[1376088981] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"142.916098ms","start":"2026-09-10T11:02:11.433363Z","end":"2026-09-10T11:02:11.576279Z","steps":["trace[1376088981] 'process raft request' (duration: 142.663872ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:11.576341Z","caller":"traceutil/trace.go:171","msg":"trace[1709055378] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"139.564917ms","start":"2026-09-10T11:02:11.436768Z","end":"2026-09-10T11:02:11.576333Z","steps":["trace[1709055378] 'process raft request' (duration: 139.405223ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:11.576528Z","caller":"traceutil/trace.go:171","msg":"trace[134323744] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"140.896993ms","start":"2026-09-10T11:02:11.435615Z","end":"2026-09-10T11:02:11.576512Z","steps":["trace[134323744] 'process raft request' (duration: 140.499702ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:31.999728Z","caller":"traceutil/trace.go:171","msg":"trace[775032610] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"316.680608ms","start":"2026-09-10T11:02:31.683003Z","end":"2026-09-10T11:02:31.999683Z","steps":["trace[775032610] 'process raft request' (duration: 316.210205ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:31.999756Z","caller":"traceutil/trace.go:171","msg":"trace[263999637] linearizableReadLoop","detail":"{readStateIndex:2651; appliedIndex:2650; }","duration":"316.331549ms","start":"2026-09-10T11:02:31.683403Z","end":"2026-09-10T11:02:31.999735Z","steps":["trace[263999637] 'read index received' (duration: 315.767474ms)","trace[263999637] 'applied index is now lower than readState.Index' (duration: 562.145µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:31.999899Z","caller":"traceutil/trace.go:171","msg":"trace[1746590026] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"223.088468ms","start":"2026-09-10T11:02:31.776791Z","end":"2026-09-10T11:02:31.99988Z","steps":["trace[1746590026] 'process raft request' (duration: 222.977475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.000014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.682985Z","time spent":"316.835362ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:32.000945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.001196ms","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-10T11:02:32.001017Z","caller":"traceutil/trace.go:171","msg":"trace[2136573564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2557; }","duration":"317.089619ms","start":"2026-09-10T11:02:31.683909Z","end":"2026-09-10T11:02:32.000998Z","steps":["trace[2136573564] 'agreement among raft nodes before linearized reading' (duration: 316.913245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.001086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.683903Z","time spent":"317.153611ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-10T11:02:32.001566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.908738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-10T11:02:32.001653Z","caller":"traceutil/trace.go:171","msg":"trace[360786747] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2557; }","duration":"310.056963ms","start":"2026-09-10T11:02:31.691572Z","end":"2026-09-10T11:02:32.001629Z","steps":["trace[360786747] 'agreement among raft nodes before linearized reading' (duration: 309.804826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.00276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.691562Z","time spent":"310.111984ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-10T11:02:32.003764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.344295ms","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-10T11:02:32.003835Z","caller":"traceutil/trace.go:171","msg":"trace[1366207059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2556; }","duration":"320.435167ms","start":"2026-09-10T11:02:31.683381Z","end":"2026-09-10T11:02:32.003816Z","steps":["trace[1366207059] 'agreement among raft nodes before linearized reading' (duration: 316.40941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:32.003883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:31.683374Z","time spent":"320.496389ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-10T11:02:36.422688Z","caller":"traceutil/trace.go:171","msg":"trace[1895582156] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"141.701971ms","start":"2026-09-10T11:02:36.280965Z","end":"2026-09-10T11:02:36.422667Z","steps":["trace[1895582156] 'process raft request' (duration: 126.77445ms)","trace[1895582156] 'compare' (duration: 14.583302ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:03:06.255618Z","caller":"traceutil/trace.go:171","msg":"trace[1315570453] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"118.952481ms","start":"2026-09-10T11:03:06.136644Z","end":"2026-09-10T11:03:06.255596Z","steps":["trace[1315570453] 'process raft request' (duration: 118.761806ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:16.517457Z","caller":"traceutil/trace.go:171","msg":"trace[601831886] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"128.254987ms","start":"2026-09-10T11:07:16.389168Z","end":"2026-09-10T11:07:16.517423Z","steps":["trace[601831886] 'process raft request' (duration: 122.289726ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:21.822633Z","caller":"traceutil/trace.go:171","msg":"trace[2079559521] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"178.690539ms","start":"2026-09-10T11:07:21.643904Z","end":"2026-09-10T11:07:21.822594Z","steps":["trace[2079559521] 'process raft request' (duration: 178.148736ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:21.82287Z","caller":"traceutil/trace.go:171","msg":"trace[1024523277] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4950; }","duration":"156.281693ms","start":"2026-09-10T11:07:21.666214Z","end":"2026-09-10T11:07:21.822496Z","steps":["trace[1024523277] 'read index received' (duration: 31.707649ms)","trace[1024523277] 'applied index is now lower than readState.Index' (duration: 124.572654ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:07:21.823452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.232383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-10T11:07:21.823509Z","caller":"traceutil/trace.go:171","msg":"trace[1594331319] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4767; }","duration":"157.353196ms","start":"2026-09-10T11:07:21.666142Z","end":"2026-09-10T11:07:21.823495Z","steps":["trace[1594331319] 'agreement among raft nodes before linearized reading' (duration: 156.638761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:21.82961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.730826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-09-10T11:07:21.829679Z","caller":"traceutil/trace.go:171","msg":"trace[405244743] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"123.716066ms","start":"2026-09-10T11:07:21.705931Z","end":"2026-09-10T11:07:21.829647Z","steps":["trace[405244743] 'process raft request' (duration: 123.296426ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:21.829696Z","caller":"traceutil/trace.go:171","msg":"trace[2058943163] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4768; }","duration":"123.862109ms","start":"2026-09-10T11:07:21.705816Z","end":"2026-09-10T11:07:21.829678Z","steps":["trace[2058943163] 'agreement among raft nodes before linearized reading' (duration: 123.569562ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:22.535603Z","caller":"traceutil/trace.go:171","msg":"trace[1311778489] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"139.280953ms","start":"2026-09-10T11:07:22.39629Z","end":"2026-09-10T11:07:22.535571Z","steps":["trace[1311778489] 'process raft request' (duration: 139.018947ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:22.535673Z","caller":"traceutil/trace.go:171","msg":"trace[394441901] linearizableReadLoop","detail":"{readStateIndex:4970; appliedIndex:4968; }","duration":"111.45464ms","start":"2026-09-10T11:07:22.424195Z","end":"2026-09-10T11:07:22.53565Z","steps":["trace[394441901] 'read index received' (duration: 5.080831ms)","trace[394441901] 'applied index is now lower than readState.Index' (duration: 106.372889ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:07:22.535782Z","caller":"traceutil/trace.go:171","msg":"trace[578846782] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"110.622671ms","start":"2026-09-10T11:07:22.425135Z","end":"2026-09-10T11:07:22.535758Z","steps":["trace[578846782] 'process raft request' (duration: 110.306844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:22.535805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.588012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:07:22.535928Z","caller":"traceutil/trace.go:171","msg":"trace[166044419] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:4787; }","duration":"111.757916ms","start":"2026-09-10T11:07:22.424158Z","end":"2026-09-10T11:07:22.535916Z","steps":["trace[166044419] 'agreement among raft nodes before linearized reading' (duration: 111.572752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:28.314276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.058628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:07:28.314402Z","caller":"traceutil/trace.go:171","msg":"trace[1561234028] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"361.24017ms","start":"2026-09-10T11:07:27.953134Z","end":"2026-09-10T11:07:28.314374Z","steps":["trace[1561234028] 'process raft request' (duration: 82.961548ms)","trace[1561234028] 'compare' (duration: 277.813923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:07:28.314497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:07:27.953106Z","time spent":"361.334783ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:07:28.335534Z","caller":"traceutil/trace.go:171","msg":"trace[803699084] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5012; }","duration":"293.906382ms","start":"2026-09-10T11:07:28.041607Z","end":"2026-09-10T11:07:28.335513Z","steps":["trace[803699084] 'read index received' (duration: 293.81501ms)","trace[803699084] 'applied index is now lower than readState.Index' (duration: 90.552µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:07:28.335627Z","caller":"traceutil/trace.go:171","msg":"trace[55645343] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"311.264177ms","start":"2026-09-10T11:07:28.024335Z","end":"2026-09-10T11:07:28.335599Z","steps":["trace[55645343] 'process raft request' (duration: 311.063613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:28.335721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.096956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-10T11:07:28.335738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:07:28.024316Z","time spent":"311.361209ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:07:28.335756Z","caller":"traceutil/trace.go:171","msg":"trace[757997402] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4827; }","duration":"294.181987ms","start":"2026-09-10T11:07:28.041565Z","end":"2026-09-10T11:07:28.335747Z","steps":["trace[757997402] 'agreement among raft nodes before linearized reading' (duration: 294.054594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:28.335885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.819326ms","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-10T11:07:28.335906Z","caller":"traceutil/trace.go:171","msg":"trace[1058362962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4827; }","duration":"291.869217ms","start":"2026-09-10T11:07:28.044032Z","end":"2026-09-10T11:07:28.335901Z","steps":["trace[1058362962] 'agreement among raft nodes before linearized reading' (duration: 291.789775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:28.335904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.043431ms","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-10T11:07:28.335928Z","caller":"traceutil/trace.go:171","msg":"trace[1444074406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4827; }","duration":"239.089063ms","start":"2026-09-10T11:07:28.096832Z","end":"2026-09-10T11:07:28.335922Z","steps":["trace[1444074406] 'agreement among raft nodes before linearized reading' (duration: 239.020161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:28.33601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.906262ms","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-10T11:07:28.336027Z","caller":"traceutil/trace.go:171","msg":"trace[318689438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4827; }","duration":"258.950844ms","start":"2026-09-10T11:07:28.077072Z","end":"2026-09-10T11:07:28.336023Z","steps":["trace[318689438] 'agreement among raft nodes before linearized reading' (duration: 258.908213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:28.336065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.377628ms","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":"warn","ts":"2026-09-10T11:07:28.336108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.129839ms","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-09-10T11:07:28.336119Z","caller":"traceutil/trace.go:171","msg":"trace[928491074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4827; }","duration":"147.160961ms","start":"2026-09-10T11:07:28.188955Z","end":"2026-09-10T11:07:28.336116Z","steps":["trace[928491074] 'agreement among raft nodes before linearized reading' (duration: 147.1255ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.33613Z","caller":"traceutil/trace.go:171","msg":"trace[297181691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4827; }","duration":"286.469241ms","start":"2026-09-10T11:07:28.049644Z","end":"2026-09-10T11:07:28.336113Z","steps":["trace[297181691] 'agreement among raft nodes before linearized reading' (duration: 286.341408ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.456986Z","caller":"traceutil/trace.go:171","msg":"trace[611303507] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"115.34745ms","start":"2026-09-10T11:07:28.341619Z","end":"2026-09-10T11:07:28.456967Z","steps":["trace[611303507] 'process raft request' (duration: 82.259072ms)","trace[611303507] 'compare' (duration: 32.998736ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:07:28.476757Z","caller":"traceutil/trace.go:171","msg":"trace[794844444] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"134.673929ms","start":"2026-09-10T11:07:28.342065Z","end":"2026-09-10T11:07:28.476739Z","steps":["trace[794844444] 'process raft request' (duration: 134.514576ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.477042Z","caller":"traceutil/trace.go:171","msg":"trace[1582134250] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"134.606128ms","start":"2026-09-10T11:07:28.342415Z","end":"2026-09-10T11:07:28.477021Z","steps":["trace[1582134250] 'process raft request' (duration: 134.281191ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.477169Z","caller":"traceutil/trace.go:171","msg":"trace[327199921] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"134.645459ms","start":"2026-09-10T11:07:28.342505Z","end":"2026-09-10T11:07:28.47715Z","steps":["trace[327199921] 'process raft request' (duration: 134.445105ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.477321Z","caller":"traceutil/trace.go:171","msg":"trace[468174442] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"133.791499ms","start":"2026-09-10T11:07:28.343524Z","end":"2026-09-10T11:07:28.477316Z","steps":["trace[468174442] 'process raft request' (duration: 133.571925ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.477367Z","caller":"traceutil/trace.go:171","msg":"trace[199296948] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"133.017833ms","start":"2026-09-10T11:07:28.344343Z","end":"2026-09-10T11:07:28.477361Z","steps":["trace[199296948] 'process raft request' (duration: 132.91244ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.754699Z","caller":"traceutil/trace.go:171","msg":"trace[1406013865] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"123.491507ms","start":"2026-09-10T11:07:28.631182Z","end":"2026-09-10T11:07:28.754674Z","steps":["trace[1406013865] 'process raft request' (duration: 80.507166ms)","trace[1406013865] 'compare' (duration: 42.847098ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:07:32.196688Z","caller":"traceutil/trace.go:171","msg":"trace[1056649878] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"101.54619ms","start":"2026-09-10T11:07:32.095118Z","end":"2026-09-10T11:07:32.196664Z","steps":["trace[1056649878] 'read index received' (duration: 24.875859ms)","trace[1056649878] 'applied index is now lower than readState.Index' (duration: 76.669361ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:07:32.196725Z","caller":"traceutil/trace.go:171","msg":"trace[233851278] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"129.564547ms","start":"2026-09-10T11:07:32.067129Z","end":"2026-09-10T11:07:32.196694Z","steps":["trace[233851278] 'process raft request' (duration: 52.854626ms)","trace[233851278] 'compare' (duration: 76.551198ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:07:32.196902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.751594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-09-10T11:07:32.196929Z","caller":"traceutil/trace.go:171","msg":"trace[428468171] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:4928; }","duration":"101.839456ms","start":"2026-09-10T11:07:32.095083Z","end":"2026-09-10T11:07:32.196923Z","steps":["trace[428468171] 'agreement among raft nodes before linearized reading' (duration: 101.707423ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:42.213765Z","caller":"traceutil/trace.go:171","msg":"trace[165535289] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"110.433788ms","start":"2026-09-10T11:07:42.103311Z","end":"2026-09-10T11:07:42.213745Z","steps":["trace[165535289] 'process raft request' (duration: 110.270135ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:42.381476Z","caller":"traceutil/trace.go:171","msg":"trace[903914100] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"115.715503ms","start":"2026-09-10T11:07:42.265724Z","end":"2026-09-10T11:07:42.38144Z","steps":["trace[903914100] 'process raft request' (duration: 79.009869ms)","trace[903914100] 'compare' (duration: 36.52739ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:08:12.672202Z","caller":"traceutil/trace.go:171","msg":"trace[476584186] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"117.594432ms","start":"2026-09-10T11:08:12.554582Z","end":"2026-09-10T11:08:12.672177Z","steps":["trace[476584186] 'process raft request' (duration: 117.396718ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:30.92498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2881} {"level":"info","ts":"2026-09-10T11:08:31.207314Z","caller":"traceutil/trace.go:171","msg":"trace[1153049886] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"146.758432ms","start":"2026-09-10T11:08:31.060517Z","end":"2026-09-10T11:08:31.207276Z","steps":["trace[1153049886] 'process raft request' (duration: 80.152492ms)","trace[1153049886] 'compare' (duration: 27.770086ms)","trace[1153049886] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:488; } (duration: 38.574429ms)"],"step_count":3} {"level":"info","ts":"2026-09-10T11:08:31.207515Z","caller":"traceutil/trace.go:171","msg":"trace[2080849791] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"146.601888ms","start":"2026-09-10T11:08:31.060907Z","end":"2026-09-10T11:08:31.207509Z","steps":["trace[2080849791] 'process raft request' (duration: 146.287192ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:31.207504Z","caller":"traceutil/trace.go:171","msg":"trace[158771032] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"146.908116ms","start":"2026-09-10T11:08:31.060576Z","end":"2026-09-10T11:08:31.207484Z","steps":["trace[158771032] 'process raft request' (duration: 146.566279ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:31.297239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2881,"took":"370.917866ms","hash":301575446,"current-db-size-bytes":53923840,"current-db-size":"54 MB","current-db-size-in-use-bytes":29188096,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-10T11:08:31.29732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":301575446,"revision":2881,"compact-revision":-1} {"level":"warn","ts":"2026-09-10T11:11:40.333503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.395149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-10T11:11:40.334794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.908094ms","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-10T11:11:40.334854Z","caller":"traceutil/trace.go:171","msg":"trace[2108202211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6811; }","duration":"161.318046ms","start":"2026-09-10T11:11:40.173521Z","end":"2026-09-10T11:11:40.334839Z","steps":["trace[2108202211] 'range keys from in-memory index tree' (duration: 159.769631ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:40.335895Z","caller":"traceutil/trace.go:171","msg":"trace[511771629] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:6811; }","duration":"103.585894ms","start":"2026-09-10T11:11:40.230048Z","end":"2026-09-10T11:11:40.333634Z","steps":["trace[511771629] 'count revisions from in-memory index tree' (duration: 103.334818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:40.715525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.645939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:11:40.715644Z","caller":"traceutil/trace.go:171","msg":"trace[1105229000] transaction","detail":"{read_only:false; response_revision:6815; number_of_response:1; }","duration":"373.504349ms","start":"2026-09-10T11:11:40.342128Z","end":"2026-09-10T11:11:40.715633Z","steps":["trace[1105229000] 'process raft request' (duration: 373.482748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:40.715681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:11:40.342121Z","time spent":"373.54522ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:11:40.71574Z","caller":"traceutil/trace.go:171","msg":"trace[900646640] transaction","detail":"{read_only:false; response_revision:6813; number_of_response:1; }","duration":"377.633933ms","start":"2026-09-10T11:11:40.338092Z","end":"2026-09-10T11:11:40.715726Z","steps":["trace[900646640] 'process raft request' (duration: 165.739068ms)","trace[900646640] 'compare' (duration: 211.396644ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:40.715838Z","caller":"traceutil/trace.go:171","msg":"trace[1613012585] transaction","detail":"{read_only:false; response_revision:6814; number_of_response:1; }","duration":"373.765215ms","start":"2026-09-10T11:11:40.342043Z","end":"2026-09-10T11:11:40.715808Z","steps":["trace[1613012585] 'process raft request' (duration: 373.53868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:40.715888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:11:40.338076Z","time spent":"377.755326ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:11:40.715934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:11:40.342028Z","time spent":"373.836316ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:13:30.936118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5485} {"level":"info","ts":"2026-09-10T11:13:31.034124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5485,"took":"96.685385ms","hash":3677194840,"current-db-size-bytes":53923840,"current-db-size":"54 MB","current-db-size-in-use-bytes":18464768,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-10T11:13:31.034193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3677194840,"revision":5485,"compact-revision":2881}