{"level":"warn","ts":"2026-09-12T04:08:47.908298Z","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-12T04:08:47.908466Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.129: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.129:2380","--initial-cluster=instance=https://162.253.55.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.129: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-12T04:08:47.908585Z","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-12T04:08:47.908612Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.129:2380"]} {"level":"info","ts":"2026-09-12T04:08:47.908665Z","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-12T04:08:47.910099Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"]} {"level":"info","ts":"2026-09-12T04:08:47.910265Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.129:2380"],"listen-peer-urls":["https://162.253.55.129:2380"],"advertise-client-urls":["https://162.253.55.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.129: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-12T04:08:47.9161Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.453706ms"} {"level":"info","ts":"2026-09-12T04:08:47.923593Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d232699cb6c87299","cluster-id":"f2b9115890c3fc7f"} {"level":"info","ts":"2026-09-12T04:08:47.923721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=()"} {"level":"info","ts":"2026-09-12T04:08:47.923759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became follower at term 0"} {"level":"info","ts":"2026-09-12T04:08:47.923781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d232699cb6c87299 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-12T04:08:47.923794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became follower at term 1"} {"level":"info","ts":"2026-09-12T04:08:47.924013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=(15146284618602803865)"} {"level":"warn","ts":"2026-09-12T04:08:47.930816Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-12T04:08:47.933809Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-12T04:08:47.935879Z","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-12T04:08:47.940608Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d232699cb6c87299","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-12T04:08:47.940755Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d232699cb6c87299","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-12T04:08:47.941031Z","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-12T04:08:47.941239Z","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-12T04:08:47.94126Z","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-12T04:08:47.943303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=(15146284618602803865)"} {"level":"info","ts":"2026-09-12T04:08:47.943838Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f2b9115890c3fc7f","local-member-id":"d232699cb6c87299","added-peer-id":"d232699cb6c87299","added-peer-peer-urls":["https://162.253.55.129:2380"]} {"level":"info","ts":"2026-09-12T04:08:47.947028Z","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-12T04:08:47.947166Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.129:2380"} {"level":"info","ts":"2026-09-12T04:08:47.947217Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.129:2380"} {"level":"info","ts":"2026-09-12T04:08:47.947461Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d232699cb6c87299","initial-advertise-peer-urls":["https://162.253.55.129:2380"],"listen-peer-urls":["https://162.253.55.129:2380"],"advertise-client-urls":["https://162.253.55.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-12T04:08:47.947525Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-12T04:08:48.124466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 is starting a new election at term 1"} {"level":"info","ts":"2026-09-12T04:08:48.124557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-12T04:08:48.12458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 received MsgPreVoteResp from d232699cb6c87299 at term 1"} {"level":"info","ts":"2026-09-12T04:08:48.124597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became candidate at term 2"} {"level":"info","ts":"2026-09-12T04:08:48.124603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 received MsgVoteResp from d232699cb6c87299 at term 2"} {"level":"info","ts":"2026-09-12T04:08:48.124614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became leader at term 2"} {"level":"info","ts":"2026-09-12T04:08:48.124623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d232699cb6c87299 elected leader d232699cb6c87299 at term 2"} {"level":"info","ts":"2026-09-12T04:08:48.12677Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d232699cb6c87299","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.129:2379]}","request-path":"/0/members/d232699cb6c87299/attributes","cluster-id":"f2b9115890c3fc7f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-12T04:08:48.127059Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T04:08:48.128196Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T04:08:48.128322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T04:08:48.128388Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-12T04:08:48.128438Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-12T04:08:48.128858Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f2b9115890c3fc7f","local-member-id":"d232699cb6c87299","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T04:08:48.128961Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T04:08:48.128992Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T04:08:48.131723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.129:2379"} {"level":"info","ts":"2026-09-12T04:08:48.131804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-12T04:09:07.312598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.349489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T04:09:07.312781Z","caller":"traceutil/trace.go:171","msg":"trace[2075208523] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"278.28869ms","start":"2026-09-12T04:09:07.034469Z","end":"2026-09-12T04:09:07.312757Z","steps":["trace[2075208523] 'process raft request' (duration: 94.96786ms)","trace[2075208523] 'compare' (duration: 182.137095ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:09:36.449416Z","caller":"traceutil/trace.go:171","msg":"trace[823860179] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"184.919054ms","start":"2026-09-12T04:09:36.264469Z","end":"2026-09-12T04:09:36.449388Z","steps":["trace[823860179] 'read index received' (duration: 184.174279ms)","trace[823860179] 'applied index is now lower than readState.Index' (duration: 743.745µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:09:36.449607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.116258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qtj94\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-12T04:09:36.449557Z","caller":"traceutil/trace.go:171","msg":"trace[350790072] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"201.376576ms","start":"2026-09-12T04:09:36.24812Z","end":"2026-09-12T04:09:36.449497Z","steps":["trace[350790072] 'process raft request' (duration: 200.742162ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:09:36.449634Z","caller":"traceutil/trace.go:171","msg":"trace[979920229] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qtj94; range_end:; response_count:1; response_revision:642; }","duration":"185.2198ms","start":"2026-09-12T04:09:36.264405Z","end":"2026-09-12T04:09:36.449625Z","steps":["trace[979920229] 'agreement among raft nodes before linearized reading' (duration: 185.080637ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:09:36.776971Z","caller":"traceutil/trace.go:171","msg":"trace[1636300346] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"257.539947ms","start":"2026-09-12T04:09:36.519392Z","end":"2026-09-12T04:09:36.776932Z","steps":["trace[1636300346] 'process raft request' (duration: 256.142227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:09:53.62181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.785105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m7vkt\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-12T04:09:53.621947Z","caller":"traceutil/trace.go:171","msg":"trace[509305661] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m7vkt; range_end:; response_count:1; response_revision:808; }","duration":"238.014379ms","start":"2026-09-12T04:09:53.383905Z","end":"2026-09-12T04:09:53.621919Z","steps":["trace[509305661] 'range keys from in-memory index tree' (duration: 237.436357ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:10:24.040684Z","caller":"traceutil/trace.go:171","msg":"trace[2123489594] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"139.101777ms","start":"2026-09-12T04:10:23.901553Z","end":"2026-09-12T04:10:24.040655Z","steps":["trace[2123489594] 'read index received' (duration: 138.906053ms)","trace[2123489594] 'applied index is now lower than readState.Index' (duration: 194.224µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:10:24.04086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.289841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:10:24.040808Z","caller":"traceutil/trace.go:171","msg":"trace[1043249585] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"165.245168ms","start":"2026-09-12T04:10:23.87548Z","end":"2026-09-12T04:10:24.040725Z","steps":["trace[1043249585] 'process raft request' (duration: 164.948042ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:10:24.040902Z","caller":"traceutil/trace.go:171","msg":"trace[61826444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"139.396083ms","start":"2026-09-12T04:10:23.901489Z","end":"2026-09-12T04:10:24.040885Z","steps":["trace[61826444] 'agreement among raft nodes before linearized reading' (duration: 139.305601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:11:13.787475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.158625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-12T04:11:13.787577Z","caller":"traceutil/trace.go:171","msg":"trace[989009970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1903; }","duration":"338.307009ms","start":"2026-09-12T04:11:13.449244Z","end":"2026-09-12T04:11:13.787551Z","steps":["trace[989009970] 'range keys from in-memory index tree' (duration: 337.966811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:11:13.787617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:11:13.449215Z","time spent":"338.39229ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-12T04:11:13.788157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.317278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-12T04:11:13.788335Z","caller":"traceutil/trace.go:171","msg":"trace[1633842885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1903; }","duration":"315.524002ms","start":"2026-09-12T04:11:13.472769Z","end":"2026-09-12T04:11:13.788293Z","steps":["trace[1633842885] 'range keys from in-memory index tree' (duration: 314.673264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:11:13.788447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:11:13.472757Z","time spent":"315.673145ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-12T04:11:14.135724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.65934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T04:11:14.135967Z","caller":"traceutil/trace.go:171","msg":"trace[2118076238] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"275.211471ms","start":"2026-09-12T04:11:13.860732Z","end":"2026-09-12T04:11:14.135944Z","steps":["trace[2118076238] 'process raft request' (duration: 275.124809ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:11:14.136007Z","caller":"traceutil/trace.go:171","msg":"trace[1671103619] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"287.746729ms","start":"2026-09-12T04:11:13.848181Z","end":"2026-09-12T04:11:14.135928Z","steps":["trace[1671103619] 'process raft request' (duration: 48.795243ms)","trace[1671103619] 'compare' (duration: 238.541887ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:11:27.249746Z","caller":"traceutil/trace.go:171","msg":"trace[480689577] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"157.298671ms","start":"2026-09-12T04:11:27.092426Z","end":"2026-09-12T04:11:27.249725Z","steps":["trace[480689577] 'process raft request' (duration: 157.130858ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:11:53.226269Z","caller":"traceutil/trace.go:171","msg":"trace[1643397793] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"123.350496ms","start":"2026-09-12T04:11:53.102879Z","end":"2026-09-12T04:11:53.22623Z","steps":["trace[1643397793] 'process raft request' (duration: 123.201503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:11:58.965316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.693442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T04:11:58.965499Z","caller":"traceutil/trace.go:171","msg":"trace[1732117675] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"152.763555ms","start":"2026-09-12T04:11:58.812711Z","end":"2026-09-12T04:11:58.965474Z","steps":["trace[1732117675] 'process raft request' (duration: 21.806046ms)","trace[1732117675] 'compare' (duration: 130.564069ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:12:04.745492Z","caller":"traceutil/trace.go:171","msg":"trace[1580784831] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"106.356062ms","start":"2026-09-12T04:12:04.639107Z","end":"2026-09-12T04:12:04.745463Z","steps":["trace[1580784831] 'process raft request' (duration: 106.167349ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:12:05.141035Z","caller":"traceutil/trace.go:171","msg":"trace[967340117] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"153.350587ms","start":"2026-09-12T04:12:04.987653Z","end":"2026-09-12T04:12:05.141004Z","steps":["trace[967340117] 'process raft request' (duration: 152.841237ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:12:05.299837Z","caller":"traceutil/trace.go:171","msg":"trace[383625834] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"153.068511ms","start":"2026-09-12T04:12:05.14675Z","end":"2026-09-12T04:12:05.299819Z","steps":["trace[383625834] 'process raft request' (duration: 136.833314ms)","trace[383625834] 'compare' (duration: 16.082144ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:12:05.299831Z","caller":"traceutil/trace.go:171","msg":"trace[1217384545] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"121.723342ms","start":"2026-09-12T04:12:05.178088Z","end":"2026-09-12T04:12:05.299812Z","steps":["trace[1217384545] 'read index received' (duration: 105.648258ms)","trace[1217384545] 'applied index is now lower than readState.Index' (duration: 16.074314ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:12:05.300106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.095607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-12T04:12:05.300153Z","caller":"traceutil/trace.go:171","msg":"trace[1510583457] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2236; }","duration":"127.191378ms","start":"2026-09-12T04:12:05.17295Z","end":"2026-09-12T04:12:05.300141Z","steps":["trace[1510583457] 'agreement among raft nodes before linearized reading' (duration: 126.934653ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:16:58.443257Z","caller":"traceutil/trace.go:171","msg":"trace[1473842346] transaction","detail":"{read_only:false; response_revision:4180; number_of_response:1; }","duration":"146.297366ms","start":"2026-09-12T04:16:58.29693Z","end":"2026-09-12T04:16:58.443227Z","steps":["trace[1473842346] 'process raft request' (duration: 144.788794ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:16:58.596291Z","caller":"traceutil/trace.go:171","msg":"trace[992795929] transaction","detail":"{read_only:false; response_revision:4181; number_of_response:1; }","duration":"147.420529ms","start":"2026-09-12T04:16:58.448834Z","end":"2026-09-12T04:16:58.596254Z","steps":["trace[992795929] 'process raft request' (duration: 137.993288ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:16:58.600591Z","caller":"traceutil/trace.go:171","msg":"trace[146267911] transaction","detail":"{read_only:false; response_revision:4182; number_of_response:1; }","duration":"138.798976ms","start":"2026-09-12T04:16:58.461775Z","end":"2026-09-12T04:16:58.600574Z","steps":["trace[146267911] 'process raft request' (duration: 138.660013ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:18:27.547934Z","caller":"traceutil/trace.go:171","msg":"trace[1720293153] linearizableReadLoop","detail":"{readStateIndex:5434; appliedIndex:5433; }","duration":"116.745313ms","start":"2026-09-12T04:18:27.431148Z","end":"2026-09-12T04:18:27.547894Z","steps":["trace[1720293153] 'read index received' (duration: 65.929128ms)","trace[1720293153] 'applied index is now lower than readState.Index' (duration: 50.814385ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:18:27.548074Z","caller":"traceutil/trace.go:171","msg":"trace[1168390375] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"117.032421ms","start":"2026-09-12T04:18:27.431025Z","end":"2026-09-12T04:18:27.548058Z","steps":["trace[1168390375] 'process raft request' (duration: 65.9793ms)","trace[1168390375] 'compare' (duration: 50.705164ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:18:27.548334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.165904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zs9h5-tls\" ","response":"range_response_count:1 size:2130"} {"level":"info","ts":"2026-09-12T04:18:27.548447Z","caller":"traceutil/trace.go:171","msg":"trace[2135179703] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zs9h5-tls; range_end:; response_count:1; response_revision:5236; }","duration":"117.269486ms","start":"2026-09-12T04:18:27.431127Z","end":"2026-09-12T04:18:27.548396Z","steps":["trace[2135179703] 'agreement among raft nodes before linearized reading' (duration: 116.959569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:27.561168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.68678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-12T04:18:27.561241Z","caller":"traceutil/trace.go:171","msg":"trace[45580824] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5237; }","duration":"129.793713ms","start":"2026-09-12T04:18:27.43143Z","end":"2026-09-12T04:18:27.561224Z","steps":["trace[45580824] 'agreement among raft nodes before linearized reading' (duration: 129.548037ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:18:27.561317Z","caller":"traceutil/trace.go:171","msg":"trace[1809682430] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"130.13018ms","start":"2026-09-12T04:18:27.431163Z","end":"2026-09-12T04:18:27.561293Z","steps":["trace[1809682430] 'process raft request' (duration: 129.698531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:27.561559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.260772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62987"} {"level":"info","ts":"2026-09-12T04:18:27.561602Z","caller":"traceutil/trace.go:171","msg":"trace[1629058849] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5237; }","duration":"122.306133ms","start":"2026-09-12T04:18:27.439285Z","end":"2026-09-12T04:18:27.561591Z","steps":["trace[1629058849] 'agreement among raft nodes before linearized reading' (duration: 122.115689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:27.561832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.048134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-12T04:18:27.561881Z","caller":"traceutil/trace.go:171","msg":"trace[12171918] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5237; }","duration":"127.136456ms","start":"2026-09-12T04:18:27.434731Z","end":"2026-09-12T04:18:27.561868Z","steps":["trace[12171918] 'agreement among raft nodes before linearized reading' (duration: 126.972143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:33.207634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.713101ms","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-12T04:18:33.207725Z","caller":"traceutil/trace.go:171","msg":"trace[131689514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5396; }","duration":"106.880834ms","start":"2026-09-12T04:18:33.100825Z","end":"2026-09-12T04:18:33.207706Z","steps":["trace[131689514] 'range keys from in-memory index tree' (duration: 106.482415ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:18:33.41033Z","caller":"traceutil/trace.go:171","msg":"trace[1546736698] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"165.230951ms","start":"2026-09-12T04:18:33.245066Z","end":"2026-09-12T04:18:33.410297Z","steps":["trace[1546736698] 'process raft request' (duration: 116.733315ms)","trace[1546736698] 'compare' (duration: 48.23134ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:18:33.610506Z","caller":"traceutil/trace.go:171","msg":"trace[1514120091] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"105.684838ms","start":"2026-09-12T04:18:33.504783Z","end":"2026-09-12T04:18:33.610468Z","steps":["trace[1514120091] 'process raft request' (duration: 13.972689ms)","trace[1514120091] 'compare' (duration: 91.29854ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:18:33.610665Z","caller":"traceutil/trace.go:171","msg":"trace[1729533980] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"104.826279ms","start":"2026-09-12T04:18:33.505798Z","end":"2026-09-12T04:18:33.610624Z","steps":["trace[1729533980] 'process raft request' (duration: 104.554313ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:18:48.831315Z","caller":"traceutil/trace.go:171","msg":"trace[1476547776] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"244.863695ms","start":"2026-09-12T04:18:48.586419Z","end":"2026-09-12T04:18:48.831283Z","steps":["trace[1476547776] 'process raft request' (duration: 224.634652ms)","trace[1476547776] 'compare' (duration: 20.019498ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:18:49.158231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.348511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T04:18:49.158465Z","caller":"traceutil/trace.go:171","msg":"trace[155052249] linearizableReadLoop","detail":"{readStateIndex:5889; appliedIndex:5887; }","duration":"570.46877ms","start":"2026-09-12T04:18:48.587978Z","end":"2026-09-12T04:18:49.158447Z","steps":["trace[155052249] 'read index received' (duration: 223.080408ms)","trace[155052249] 'applied index is now lower than readState.Index' (duration: 347.387162ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:18:49.158534Z","caller":"traceutil/trace.go:171","msg":"trace[1935110123] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"563.078684ms","start":"2026-09-12T04:18:48.595446Z","end":"2026-09-12T04:18:49.158525Z","steps":["trace[1935110123] 'process raft request' (duration: 562.8852ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:18:49.158578Z","caller":"traceutil/trace.go:171","msg":"trace[546574987] transaction","detail":"{read_only:false; response_revision:5678; number_of_response:1; }","duration":"536.529677ms","start":"2026-09-12T04:18:48.622029Z","end":"2026-09-12T04:18:49.158559Z","steps":["trace[546574987] 'process raft request' (duration: 536.377903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:49.1586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:18:48.595431Z","time spent":"563.125405ms","remote":"127.0.0.1:57156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8038,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T04:18:49.158597Z","caller":"traceutil/trace.go:171","msg":"trace[1880119183] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"572.126058ms","start":"2026-09-12T04:18:48.586443Z","end":"2026-09-12T04:18:49.158569Z","steps":["trace[1880119183] 'process raft request' (duration: 441.319296ms)","trace[1880119183] 'compare' (duration: 130.097636ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:18:49.158659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:18:48.622012Z","time spent":"536.604429ms","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T04:18:49.158679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.663675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-12T04:18:49.158718Z","caller":"traceutil/trace.go:171","msg":"trace[1024617220] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5678; }","duration":"570.744158ms","start":"2026-09-12T04:18:48.587963Z","end":"2026-09-12T04:18:49.158707Z","steps":["trace[1024617220] 'agreement among raft nodes before linearized reading' (duration: 570.607175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:49.158721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:18:48.586428Z","time spent":"572.23707ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T04:18:49.158746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:18:48.587955Z","time spent":"570.783208ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-09-12T04:18:49.20582Z","caller":"traceutil/trace.go:171","msg":"trace[361623376] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"187.712435ms","start":"2026-09-12T04:18:49.018088Z","end":"2026-09-12T04:18:49.205801Z","steps":["trace[361623376] 'process raft request' (duration: 187.671354ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:18:49.206223Z","caller":"traceutil/trace.go:171","msg":"trace[733654030] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"300.635575ms","start":"2026-09-12T04:18:48.905575Z","end":"2026-09-12T04:18:49.206211Z","steps":["trace[733654030] 'process raft request' (duration: 300.082742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:49.206311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:18:48.905535Z","time spent":"300.713076ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T04:18:49.207614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.949367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:18:49.207663Z","caller":"traceutil/trace.go:171","msg":"trace[1131435334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5680; }","duration":"306.038329ms","start":"2026-09-12T04:18:48.901604Z","end":"2026-09-12T04:18:49.207642Z","steps":["trace[1131435334] 'agreement among raft nodes before linearized reading' (duration: 305.946647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:18:49.207702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:18:48.901584Z","time spent":"306.10179ms","remote":"127.0.0.1:56968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-12T04:18:49.218358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-09-12T04:18:49.305659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"84.766248ms","hash":2274496983,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-12T04:18:49.306597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2274496983,"revision":2986,"compact-revision":-1} {"level":"warn","ts":"2026-09-12T04:19:09.511382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.027904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6bf380fd-a011-48e4-b976-dc678dcbcfbd\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-12T04:19:09.511548Z","caller":"traceutil/trace.go:171","msg":"trace[421119987] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6bf380fd-a011-48e4-b976-dc678dcbcfbd; range_end:; response_count:1; response_revision:5970; }","duration":"138.27697ms","start":"2026-09-12T04:19:09.373229Z","end":"2026-09-12T04:19:09.511506Z","steps":["trace[421119987] 'range keys from in-memory index tree' (duration: 137.580235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:19:09.511808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.978415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-12T04:19:09.511977Z","caller":"traceutil/trace.go:171","msg":"trace[1089318404] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5970; }","duration":"116.996506ms","start":"2026-09-12T04:19:09.394947Z","end":"2026-09-12T04:19:09.511943Z","steps":["trace[1089318404] 'count revisions from in-memory index tree' (duration: 115.846972ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:19:21.371831Z","caller":"traceutil/trace.go:171","msg":"trace[1391563237] transaction","detail":"{read_only:false; response_revision:6069; number_of_response:1; }","duration":"110.166531ms","start":"2026-09-12T04:19:21.261639Z","end":"2026-09-12T04:19:21.371806Z","steps":["trace[1391563237] 'process raft request' (duration: 110.023718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:19:21.611294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.677688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-12T04:19:21.611389Z","caller":"traceutil/trace.go:171","msg":"trace[56865173] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6072; }","duration":"100.837891ms","start":"2026-09-12T04:19:21.510529Z","end":"2026-09-12T04:19:21.611367Z","steps":["trace[56865173] 'range keys from in-memory index tree' (duration: 100.410292ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:22:22.628757Z","caller":"traceutil/trace.go:171","msg":"trace[1801650618] transaction","detail":"{read_only:false; response_revision:7295; number_of_response:1; }","duration":"147.221281ms","start":"2026-09-12T04:22:22.481507Z","end":"2026-09-12T04:22:22.628729Z","steps":["trace[1801650618] 'process raft request' (duration: 147.066427ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:22:22.649335Z","caller":"traceutil/trace.go:171","msg":"trace[762871717] transaction","detail":"{read_only:false; response_revision:7296; number_of_response:1; }","duration":"155.284992ms","start":"2026-09-12T04:22:22.494028Z","end":"2026-09-12T04:22:22.649313Z","steps":["trace[762871717] 'process raft request' (duration: 155.147589ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:23:49.225585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5678} {"level":"info","ts":"2026-09-12T04:23:49.31605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5678,"took":"88.374475ms","hash":3603823576,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":28815360,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-12T04:23:49.316125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3603823576,"revision":5678,"compact-revision":2986} {"level":"info","ts":"2026-09-12T04:25:31.646762Z","caller":"traceutil/trace.go:171","msg":"trace[1310921912] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"294.527334ms","start":"2026-09-12T04:25:31.352191Z","end":"2026-09-12T04:25:31.646718Z","steps":["trace[1310921912] 'process raft request' (duration: 294.224688ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:25:31.795987Z","caller":"traceutil/trace.go:171","msg":"trace[1027090569] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"145.406383ms","start":"2026-09-12T04:25:31.65054Z","end":"2026-09-12T04:25:31.795947Z","steps":["trace[1027090569] 'process raft request' (duration: 145.133057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:25:43.993844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.177198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-12T04:25:43.993918Z","caller":"traceutil/trace.go:171","msg":"trace[1061355632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8872; }","duration":"123.27008ms","start":"2026-09-12T04:25:43.870633Z","end":"2026-09-12T04:25:43.993903Z","steps":["trace[1061355632] 'range keys from in-memory index tree' (duration: 122.997284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:25:43.994023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.814563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:25:43.994116Z","caller":"traceutil/trace.go:171","msg":"trace[1217382689] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8872; }","duration":"124.949376ms","start":"2026-09-12T04:25:43.869141Z","end":"2026-09-12T04:25:43.99409Z","steps":["trace[1217382689] 'count revisions from in-memory index tree' (duration: 124.748692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:25:43.994118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.543124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-12T04:25:43.994212Z","caller":"traceutil/trace.go:171","msg":"trace[197738485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8872; }","duration":"137.674407ms","start":"2026-09-12T04:25:43.856515Z","end":"2026-09-12T04:25:43.99419Z","steps":["trace[197738485] 'range keys from in-memory index tree' (duration: 137.317979ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:25:45.264615Z","caller":"traceutil/trace.go:171","msg":"trace[1300706386] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"153.037165ms","start":"2026-09-12T04:25:45.111549Z","end":"2026-09-12T04:25:45.264586Z","steps":["trace[1300706386] 'process raft request' (duration: 152.851171ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:26:14.404596Z","caller":"traceutil/trace.go:171","msg":"trace[993127256] linearizableReadLoop","detail":"{readStateIndex:9415; appliedIndex:9414; }","duration":"223.58623ms","start":"2026-09-12T04:26:14.18098Z","end":"2026-09-12T04:26:14.404566Z","steps":["trace[993127256] 'read index received' (duration: 223.479968ms)","trace[993127256] 'applied index is now lower than readState.Index' (duration: 104.902µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:26:14.404748Z","caller":"traceutil/trace.go:171","msg":"trace[250437327] transaction","detail":"{read_only:false; response_revision:9088; number_of_response:1; }","duration":"231.96758ms","start":"2026-09-12T04:26:14.172766Z","end":"2026-09-12T04:26:14.404734Z","steps":["trace[250437327] 'process raft request' (duration: 231.580631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:26:14.405136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.136101ms","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-12T04:26:14.405226Z","caller":"traceutil/trace.go:171","msg":"trace[1179275908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9088; }","duration":"224.257324ms","start":"2026-09-12T04:26:14.180947Z","end":"2026-09-12T04:26:14.405204Z","steps":["trace[1179275908] 'agreement among raft nodes before linearized reading' (duration: 224.06268ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:26:14.606405Z","caller":"traceutil/trace.go:171","msg":"trace[953566191] transaction","detail":"{read_only:false; response_revision:9089; number_of_response:1; }","duration":"242.478333ms","start":"2026-09-12T04:26:14.363859Z","end":"2026-09-12T04:26:14.606338Z","steps":["trace[953566191] 'process raft request' (duration: 192.985007ms)","trace[953566191] 'compare' (duration: 49.241921ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:26:14.606445Z","caller":"traceutil/trace.go:171","msg":"trace[461315434] transaction","detail":"{read_only:false; response_revision:9090; number_of_response:1; }","duration":"197.739209ms","start":"2026-09-12T04:26:14.408682Z","end":"2026-09-12T04:26:14.606421Z","steps":["trace[461315434] 'process raft request' (duration: 197.653637ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:26:14.606599Z","caller":"traceutil/trace.go:171","msg":"trace[1795505199] linearizableReadLoop","detail":"{readStateIndex:9416; appliedIndex:9415; }","duration":"201.861486ms","start":"2026-09-12T04:26:14.404686Z","end":"2026-09-12T04:26:14.606547Z","steps":["trace[1795505199] 'read index received' (duration: 152.263439ms)","trace[1795505199] 'applied index is now lower than readState.Index' (duration: 49.596987ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:26:14.606787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.072747ms","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-12T04:26:14.606821Z","caller":"traceutil/trace.go:171","msg":"trace[975818563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9090; }","duration":"281.159179ms","start":"2026-09-12T04:26:14.325653Z","end":"2026-09-12T04:26:14.606812Z","steps":["trace[975818563] 'agreement among raft nodes before linearized reading' (duration: 281.015226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:26:14.606817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.169485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:26:14.606885Z","caller":"traceutil/trace.go:171","msg":"trace[1131134135] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:9090; }","duration":"144.284288ms","start":"2026-09-12T04:26:14.462575Z","end":"2026-09-12T04:26:14.606859Z","steps":["trace[1131134135] 'agreement among raft nodes before linearized reading' (duration: 144.179656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:26:14.607003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.313594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-12T04:26:14.607102Z","caller":"traceutil/trace.go:171","msg":"trace[993598790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9090; }","duration":"410.453467ms","start":"2026-09-12T04:26:14.196624Z","end":"2026-09-12T04:26:14.607077Z","steps":["trace[993598790] 'agreement among raft nodes before linearized reading' (duration: 410.17088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:26:14.60715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:26:14.196613Z","time spent":"410.523339ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-12T04:26:27.416441Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002515.wal"} {"level":"warn","ts":"2026-09-12T04:26:27.960777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.992706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T04:26:27.960857Z","caller":"traceutil/trace.go:171","msg":"trace[1436080735] linearizableReadLoop","detail":"{readStateIndex:9502; appliedIndex:9501; }","duration":"128.130143ms","start":"2026-09-12T04:26:27.832712Z","end":"2026-09-12T04:26:27.960842Z","steps":["trace[1436080735] 'read index received' (duration: 18.899783ms)","trace[1436080735] 'applied index is now lower than readState.Index' (duration: 109.22907ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:26:27.961059Z","caller":"traceutil/trace.go:171","msg":"trace[961588513] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9172; }","duration":"130.433172ms","start":"2026-09-12T04:26:27.8306Z","end":"2026-09-12T04:26:27.961033Z","steps":["trace[961588513] 'compare' (duration: 108.809671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:26:27.961309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.577903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4l7qn\" ","response":"range_response_count:1 size:4673"} {"level":"info","ts":"2026-09-12T04:26:27.961439Z","caller":"traceutil/trace.go:171","msg":"trace[174794476] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4l7qn; range_end:; response_count:1; response_revision:9172; }","duration":"128.723216ms","start":"2026-09-12T04:26:27.832694Z","end":"2026-09-12T04:26:27.961418Z","steps":["trace[174794476] 'agreement among raft nodes before linearized reading' (duration: 128.423209ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:26:28.107194Z","caller":"traceutil/trace.go:171","msg":"trace[1198349406] transaction","detail":"{read_only:false; response_revision:9174; number_of_response:1; }","duration":"134.332595ms","start":"2026-09-12T04:26:27.972825Z","end":"2026-09-12T04:26:28.107157Z","steps":["trace[1198349406] 'process raft request' (duration: 104.637882ms)","trace[1198349406] 'compare' (duration: 29.480589ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:27:21.384169Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d232699cb6c87299","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T04:27:21.390662Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-12T04:27:21.390792Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-12T04:27:41.303143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.936474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hzb97\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-12T04:27:41.303276Z","caller":"traceutil/trace.go:171","msg":"trace[1812906765] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hzb97; range_end:; response_count:1; response_revision:9930; }","duration":"103.134789ms","start":"2026-09-12T04:27:41.200113Z","end":"2026-09-12T04:27:41.303248Z","steps":["trace[1812906765] 'range keys from in-memory index tree' (duration: 102.642548ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:27:55.044095Z","caller":"traceutil/trace.go:171","msg":"trace[901592605] transaction","detail":"{read_only:false; response_revision:10006; number_of_response:1; }","duration":"210.163862ms","start":"2026-09-12T04:27:54.833902Z","end":"2026-09-12T04:27:55.044066Z","steps":["trace[901592605] 'process raft request' (duration: 209.975468ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:27:55.050956Z","caller":"traceutil/trace.go:171","msg":"trace[624397549] linearizableReadLoop","detail":"{readStateIndex:10362; appliedIndex:10360; }","duration":"148.557668ms","start":"2026-09-12T04:27:54.902371Z","end":"2026-09-12T04:27:55.050928Z","steps":["trace[624397549] 'read index received' (duration: 141.512187ms)","trace[624397549] 'applied index is now lower than readState.Index' (duration: 7.044481ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:27:55.051104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.715641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:27:55.051148Z","caller":"traceutil/trace.go:171","msg":"trace[1005627866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10007; }","duration":"148.821503ms","start":"2026-09-12T04:27:54.902316Z","end":"2026-09-12T04:27:55.051138Z","steps":["trace[1005627866] 'agreement among raft nodes before linearized reading' (duration: 148.723371ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:27:55.051076Z","caller":"traceutil/trace.go:171","msg":"trace[702317421] transaction","detail":"{read_only:false; response_revision:10007; number_of_response:1; }","duration":"161.390281ms","start":"2026-09-12T04:27:54.889659Z","end":"2026-09-12T04:27:55.051049Z","steps":["trace[702317421] 'process raft request' (duration: 161.120886ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:28:49.233157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7993} {"level":"info","ts":"2026-09-12T04:28:49.334463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7993,"took":"97.882537ms","hash":2444809219,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":24879104,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-12T04:28:49.33456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2444809219,"revision":7993,"compact-revision":5678} {"level":"warn","ts":"2026-09-12T04:33:16.140812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.551772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T04:33:16.140943Z","caller":"traceutil/trace.go:171","msg":"trace[1030839159] linearizableReadLoop","detail":"{readStateIndex:12767; appliedIndex:12766; }","duration":"175.342027ms","start":"2026-09-12T04:33:15.965585Z","end":"2026-09-12T04:33:16.140927Z","steps":["trace[1030839159] 'read index received' (duration: 14.447407ms)","trace[1030839159] 'applied index is now lower than readState.Index' (duration: 160.89341ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:33:16.141007Z","caller":"traceutil/trace.go:171","msg":"trace[1643499259] transaction","detail":"{read_only:false; response_revision:12338; number_of_response:1; }","duration":"189.866017ms","start":"2026-09-12T04:33:15.951099Z","end":"2026-09-12T04:33:16.140965Z","steps":["trace[1643499259] 'process raft request' (duration: 29.026098ms)","trace[1643499259] 'compare' (duration: 160.41378ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:33:16.141118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.52686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-12T04:33:16.141447Z","caller":"traceutil/trace.go:171","msg":"trace[592114521] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12338; }","duration":"175.872718ms","start":"2026-09-12T04:33:15.965556Z","end":"2026-09-12T04:33:16.141429Z","steps":["trace[592114521] 'agreement among raft nodes before linearized reading' (duration: 175.413949ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:33:40.683454Z","caller":"traceutil/trace.go:171","msg":"trace[676848868] transaction","detail":"{read_only:false; response_revision:12471; number_of_response:1; }","duration":"236.398699ms","start":"2026-09-12T04:33:40.447017Z","end":"2026-09-12T04:33:40.683415Z","steps":["trace[676848868] 'process raft request' (duration: 221.419189ms)","trace[676848868] 'compare' (duration: 14.686513ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:33:49.240372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10386} {"level":"info","ts":"2026-09-12T04:33:49.323238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10386,"took":"80.259011ms","hash":3821924046,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":25100288,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-12T04:33:49.323324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3821924046,"revision":10386,"compact-revision":7993} {"level":"info","ts":"2026-09-12T04:38:49.24926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12542} {"level":"info","ts":"2026-09-12T04:38:49.332682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12542,"took":"80.977726ms","hash":3965238787,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":27672576,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-12T04:38:49.332743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3965238787,"revision":12542,"compact-revision":10386} {"level":"info","ts":"2026-09-12T04:39:37.952868Z","caller":"traceutil/trace.go:171","msg":"trace[2014602624] transaction","detail":"{read_only:false; response_revision:14897; number_of_response:1; }","duration":"107.589174ms","start":"2026-09-12T04:39:37.84525Z","end":"2026-09-12T04:39:37.952839Z","steps":["trace[2014602624] 'process raft request' (duration: 78.3681ms)","trace[2014602624] 'compare' (duration: 28.491697ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:40:18.585474Z","caller":"traceutil/trace.go:171","msg":"trace[694562670] transaction","detail":"{read_only:false; response_revision:15125; number_of_response:1; }","duration":"117.512175ms","start":"2026-09-12T04:40:18.467941Z","end":"2026-09-12T04:40:18.585453Z","steps":["trace[694562670] 'process raft request' (duration: 117.367982ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:40:18.716005Z","caller":"traceutil/trace.go:171","msg":"trace[717089815] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"103.914704ms","start":"2026-09-12T04:40:18.612064Z","end":"2026-09-12T04:40:18.715979Z","steps":["trace[717089815] 'process raft request' (duration: 94.995943ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:40:42.23563Z","caller":"traceutil/trace.go:171","msg":"trace[532671411] transaction","detail":"{read_only:false; response_revision:15441; number_of_response:1; }","duration":"256.301393ms","start":"2026-09-12T04:40:41.979299Z","end":"2026-09-12T04:40:42.2356Z","steps":["trace[532671411] 'process raft request' (duration: 256.110919ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:40:42.237574Z","caller":"traceutil/trace.go:171","msg":"trace[922868312] transaction","detail":"{read_only:false; response_revision:15442; number_of_response:1; }","duration":"161.632045ms","start":"2026-09-12T04:40:42.075905Z","end":"2026-09-12T04:40:42.237537Z","steps":["trace[922868312] 'process raft request' (duration: 161.306489ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:43:49.259701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14576} {"level":"info","ts":"2026-09-12T04:43:49.347831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14576,"took":"85.35834ms","hash":39747759,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-12T04:43:49.347909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":39747759,"revision":14576,"compact-revision":12542} {"level":"info","ts":"2026-09-12T04:48:15.997294Z","caller":"traceutil/trace.go:171","msg":"trace[1623822463] transaction","detail":"{read_only:false; response_revision:18934; number_of_response:1; }","duration":"214.3892ms","start":"2026-09-12T04:48:15.782878Z","end":"2026-09-12T04:48:15.997268Z","steps":["trace[1623822463] 'process raft request' (duration: 214.170656ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:48:22.486074Z","caller":"traceutil/trace.go:171","msg":"trace[375782216] transaction","detail":"{read_only:false; response_revision:18972; number_of_response:1; }","duration":"141.865093ms","start":"2026-09-12T04:48:22.344172Z","end":"2026-09-12T04:48:22.486037Z","steps":["trace[375782216] 'process raft request' (duration: 87.489264ms)","trace[375782216] 'compare' (duration: 54.105453ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:48:22.486361Z","caller":"traceutil/trace.go:171","msg":"trace[1535741078] transaction","detail":"{read_only:false; response_revision:18973; number_of_response:1; }","duration":"125.216878ms","start":"2026-09-12T04:48:22.361112Z","end":"2026-09-12T04:48:22.486329Z","steps":["trace[1535741078] 'process raft request' (duration: 124.771228ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:48:22.736618Z","caller":"traceutil/trace.go:171","msg":"trace[1972434957] transaction","detail":"{read_only:false; response_revision:18975; number_of_response:1; }","duration":"245.35023ms","start":"2026-09-12T04:48:22.491234Z","end":"2026-09-12T04:48:22.736584Z","steps":["trace[1972434957] 'process raft request' (duration: 155.226179ms)","trace[1972434957] 'compare' (duration: 89.961277ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:48:22.736637Z","caller":"traceutil/trace.go:171","msg":"trace[1191437822] linearizableReadLoop","detail":"{readStateIndex:19632; appliedIndex:19631; }","duration":"243.799046ms","start":"2026-09-12T04:48:22.492806Z","end":"2026-09-12T04:48:22.736605Z","steps":["trace[1191437822] 'read index received' (duration: 153.572882ms)","trace[1191437822] 'applied index is now lower than readState.Index' (duration: 90.223794ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:48:22.736811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.993731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-12T04:48:22.736842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.713308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-12T04:48:22.736867Z","caller":"traceutil/trace.go:171","msg":"trace[892769390] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:18976; }","duration":"244.102362ms","start":"2026-09-12T04:48:22.492752Z","end":"2026-09-12T04:48:22.736855Z","steps":["trace[892769390] 'agreement among raft nodes before linearized reading' (duration: 243.939439ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:48:22.736871Z","caller":"traceutil/trace.go:171","msg":"trace[1591088109] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18976; }","duration":"109.783639ms","start":"2026-09-12T04:48:22.627078Z","end":"2026-09-12T04:48:22.736861Z","steps":["trace[1591088109] 'agreement among raft nodes before linearized reading' (duration: 109.674577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:48:23.052523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.22941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T04:48:23.052595Z","caller":"traceutil/trace.go:171","msg":"trace[1229844073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18977; }","duration":"149.311862ms","start":"2026-09-12T04:48:22.903267Z","end":"2026-09-12T04:48:23.052579Z","steps":["trace[1229844073] 'range keys from in-memory index tree' (duration: 149.177459ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:48:23.653585Z","caller":"traceutil/trace.go:171","msg":"trace[1741409220] linearizableReadLoop","detail":"{readStateIndex:19635; appliedIndex:19634; }","duration":"418.948061ms","start":"2026-09-12T04:48:23.234617Z","end":"2026-09-12T04:48:23.653565Z","steps":["trace[1741409220] 'read index received' (duration: 418.91102ms)","trace[1741409220] 'applied index is now lower than readState.Index' (duration: 36.161µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:48:23.653641Z","caller":"traceutil/trace.go:171","msg":"trace[899893584] transaction","detail":"{read_only:false; response_revision:18978; number_of_response:1; }","duration":"465.794908ms","start":"2026-09-12T04:48:23.187816Z","end":"2026-09-12T04:48:23.653611Z","steps":["trace[899893584] 'process raft request' (duration: 465.637714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:48:23.653777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:48:23.187795Z","time spent":"465.90431ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T04:48:23.653899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.243867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T04:48:23.654023Z","caller":"traceutil/trace.go:171","msg":"trace[444932751] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:18978; }","duration":"419.42209ms","start":"2026-09-12T04:48:23.234581Z","end":"2026-09-12T04:48:23.654003Z","steps":["trace[444932751] 'agreement among raft nodes before linearized reading' (duration: 419.085383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:48:23.654065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:48:23.234565Z","time spent":"419.489282ms","remote":"127.0.0.1:57278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":76,"response size":32,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-09-12T04:48:23.744636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.337313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-12T04:48:23.744692Z","caller":"traceutil/trace.go:171","msg":"trace[492053404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18979; }","duration":"247.429345ms","start":"2026-09-12T04:48:23.497249Z","end":"2026-09-12T04:48:23.744678Z","steps":["trace[492053404] 'agreement among raft nodes before linearized reading' (duration: 247.270141ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:48:23.744721Z","caller":"traceutil/trace.go:171","msg":"trace[589562687] transaction","detail":"{read_only:false; response_revision:18979; number_of_response:1; }","duration":"104.536749ms","start":"2026-09-12T04:48:23.640092Z","end":"2026-09-12T04:48:23.744629Z","steps":["trace[589562687] 'process raft request' (duration: 59.624571ms)","trace[589562687] 'compare' (duration: 44.698092ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:48:34.636313Z","caller":"traceutil/trace.go:171","msg":"trace[1125494319] transaction","detail":"{read_only:false; response_revision:19186; number_of_response:1; }","duration":"101.002254ms","start":"2026-09-12T04:48:34.535285Z","end":"2026-09-12T04:48:34.636287Z","steps":["trace[1125494319] 'process raft request' (duration: 100.796168ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:48:49.268565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16902} {"level":"info","ts":"2026-09-12T04:48:49.38955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16902,"took":"117.609717ms","hash":597986789,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":32370688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-12T04:48:49.38963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":597986789,"revision":16902,"compact-revision":14576} {"level":"info","ts":"2026-09-12T04:48:58.216484Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d232699cb6c87299","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T04:48:58.224256Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-12T04:48:58.224363Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-12T04:53:49.275735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19274} {"level":"info","ts":"2026-09-12T04:53:49.383729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19274,"took":"105.440877ms","hash":3779987404,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":33325056,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-12T04:53:49.383801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3779987404,"revision":19274,"compact-revision":16902} {"level":"info","ts":"2026-09-12T04:54:04.120028Z","caller":"traceutil/trace.go:171","msg":"trace[1051642341] linearizableReadLoop","detail":"{readStateIndex:22380; appliedIndex:22379; }","duration":"192.993843ms","start":"2026-09-12T04:54:03.927008Z","end":"2026-09-12T04:54:04.120002Z","steps":["trace[1051642341] 'read index received' (duration: 192.753058ms)","trace[1051642341] 'applied index is now lower than readState.Index' (duration: 239.495µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:54:04.120068Z","caller":"traceutil/trace.go:171","msg":"trace[614223460] transaction","detail":"{read_only:false; response_revision:21635; number_of_response:1; }","duration":"199.628674ms","start":"2026-09-12T04:54:03.920413Z","end":"2026-09-12T04:54:04.120042Z","steps":["trace[614223460] 'process raft request' (duration: 199.365938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:54:04.120405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.364211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-12T04:54:04.120525Z","caller":"traceutil/trace.go:171","msg":"trace[1656761420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21635; }","duration":"193.528895ms","start":"2026-09-12T04:54:03.926974Z","end":"2026-09-12T04:54:04.120503Z","steps":["trace[1656761420] 'agreement among raft nodes before linearized reading' (duration: 193.174957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:54:04.128748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.473931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-12T04:54:04.128837Z","caller":"traceutil/trace.go:171","msg":"trace[743134083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21637; }","duration":"199.605174ms","start":"2026-09-12T04:54:03.92921Z","end":"2026-09-12T04:54:04.128816Z","steps":["trace[743134083] 'agreement among raft nodes before linearized reading' (duration: 199.364609ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:54:04.129588Z","caller":"traceutil/trace.go:171","msg":"trace[1207902067] transaction","detail":"{read_only:false; response_revision:21636; number_of_response:1; }","duration":"164.101127ms","start":"2026-09-12T04:54:03.965468Z","end":"2026-09-12T04:54:04.129569Z","steps":["trace[1207902067] 'process raft request' (duration: 162.933022ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:54:19.826538Z","caller":"traceutil/trace.go:171","msg":"trace[2115277237] transaction","detail":"{read_only:false; response_revision:21717; number_of_response:1; }","duration":"145.95875ms","start":"2026-09-12T04:54:19.680554Z","end":"2026-09-12T04:54:19.826513Z","steps":["trace[2115277237] 'process raft request' (duration: 145.799117ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:54:19.828761Z","caller":"traceutil/trace.go:171","msg":"trace[1210436570] transaction","detail":"{read_only:false; response_revision:21718; number_of_response:1; }","duration":"147.502042ms","start":"2026-09-12T04:54:19.681156Z","end":"2026-09-12T04:54:19.828658Z","steps":["trace[1210436570] 'process raft request' (duration: 147.294418ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:54:25.241128Z","caller":"traceutil/trace.go:171","msg":"trace[412590451] transaction","detail":"{read_only:false; response_revision:21746; number_of_response:1; }","duration":"139.314099ms","start":"2026-09-12T04:54:25.101732Z","end":"2026-09-12T04:54:25.241046Z","steps":["trace[412590451] 'process raft request' (duration: 138.972901ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:54:26.402348Z","caller":"traceutil/trace.go:171","msg":"trace[2056889936] transaction","detail":"{read_only:false; response_revision:21749; number_of_response:1; }","duration":"197.367446ms","start":"2026-09-12T04:54:26.204962Z","end":"2026-09-12T04:54:26.40233Z","steps":["trace[2056889936] 'process raft request' (duration: 197.243884ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:54:26.404615Z","caller":"traceutil/trace.go:171","msg":"trace[1168944253] transaction","detail":"{read_only:false; response_revision:21750; number_of_response:1; }","duration":"186.067166ms","start":"2026-09-12T04:54:26.218474Z","end":"2026-09-12T04:54:26.404541Z","steps":["trace[1168944253] 'process raft request' (duration: 185.475742ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:56:03.122268Z","caller":"traceutil/trace.go:171","msg":"trace[867832752] transaction","detail":"{read_only:false; response_revision:22628; number_of_response:1; }","duration":"154.881671ms","start":"2026-09-12T04:56:02.967328Z","end":"2026-09-12T04:56:03.122209Z","steps":["trace[867832752] 'process raft request' (duration: 154.606035ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:56:17.769811Z","caller":"traceutil/trace.go:171","msg":"trace[2019200273] transaction","detail":"{read_only:false; response_revision:22727; number_of_response:1; }","duration":"195.957386ms","start":"2026-09-12T04:56:17.573833Z","end":"2026-09-12T04:56:17.76979Z","steps":["trace[2019200273] 'process raft request' (duration: 188.13301ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:56:17.779202Z","caller":"traceutil/trace.go:171","msg":"trace[894165826] linearizableReadLoop","detail":"{readStateIndex:23513; appliedIndex:23511; }","duration":"158.5943ms","start":"2026-09-12T04:56:17.620567Z","end":"2026-09-12T04:56:17.779161Z","steps":["trace[894165826] 'read index received' (duration: 141.525056ms)","trace[894165826] 'applied index is now lower than readState.Index' (duration: 17.067754ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:56:17.779656Z","caller":"traceutil/trace.go:171","msg":"trace[1312065644] transaction","detail":"{read_only:false; response_revision:22728; number_of_response:1; }","duration":"179.063756ms","start":"2026-09-12T04:56:17.600578Z","end":"2026-09-12T04:56:17.779642Z","steps":["trace[1312065644] 'process raft request' (duration: 178.2986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:56:17.780141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.54399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T04:56:17.780313Z","caller":"traceutil/trace.go:171","msg":"trace[194491833] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:22729; }","duration":"159.746115ms","start":"2026-09-12T04:56:17.620539Z","end":"2026-09-12T04:56:17.780285Z","steps":["trace[194491833] 'agreement among raft nodes before linearized reading' (duration: 159.50904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:56:17.780797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.163703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:1 size:12687"} {"level":"info","ts":"2026-09-12T04:56:17.780851Z","caller":"traceutil/trace.go:171","msg":"trace[649285036] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:1; response_revision:22729; }","duration":"160.250385ms","start":"2026-09-12T04:56:17.620587Z","end":"2026-09-12T04:56:17.780838Z","steps":["trace[649285036] 'agreement among raft nodes before linearized reading' (duration: 160.055041ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:58:27.011852Z","caller":"traceutil/trace.go:171","msg":"trace[2027863038] transaction","detail":"{read_only:false; response_revision:24258; number_of_response:1; }","duration":"167.894358ms","start":"2026-09-12T04:58:26.843927Z","end":"2026-09-12T04:58:27.011822Z","steps":["trace[2027863038] 'process raft request' (duration: 167.807316ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:58:27.012028Z","caller":"traceutil/trace.go:171","msg":"trace[1033282201] transaction","detail":"{read_only:false; response_revision:24257; number_of_response:1; }","duration":"297.03819ms","start":"2026-09-12T04:58:26.714945Z","end":"2026-09-12T04:58:27.011983Z","steps":["trace[1033282201] 'process raft request' (duration: 287.832084ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:58:27.012279Z","caller":"traceutil/trace.go:171","msg":"trace[1733326499] linearizableReadLoop","detail":"{readStateIndex:25082; appliedIndex:25081; }","duration":"206.125193ms","start":"2026-09-12T04:58:26.806131Z","end":"2026-09-12T04:58:27.012256Z","steps":["trace[1733326499] 'read index received' (duration: 196.237912ms)","trace[1733326499] 'applied index is now lower than readState.Index' (duration: 9.885061ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:58:27.012462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.946732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T04:58:27.012521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.372968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-12T04:58:27.012601Z","caller":"traceutil/trace.go:171","msg":"trace[1381973106] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24258; }","duration":"206.512881ms","start":"2026-09-12T04:58:26.806071Z","end":"2026-09-12T04:58:27.012583Z","steps":["trace[1381973106] 'agreement among raft nodes before linearized reading' (duration: 206.253155ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:58:27.012867Z","caller":"traceutil/trace.go:171","msg":"trace[1078453469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24258; }","duration":"110.045915ms","start":"2026-09-12T04:58:26.902455Z","end":"2026-09-12T04:58:27.012501Z","steps":["trace[1078453469] 'agreement among raft nodes before linearized reading' (duration: 109.923602ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:58:49.283033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21560} {"level":"info","ts":"2026-09-12T04:58:49.400822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21560,"took":"111.919745ms","hash":138404995,"current-db-size-bytes":56414208,"current-db-size":"56 MB","current-db-size-in-use-bytes":42242048,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-12T04:58:49.400908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":138404995,"revision":21560,"compact-revision":19274} {"level":"info","ts":"2026-09-12T05:01:27.377239Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006962.wal"} {"level":"warn","ts":"2026-09-12T05:01:53.571188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.480813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:01:53.571516Z","caller":"traceutil/trace.go:171","msg":"trace[222224433] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"164.798022ms","start":"2026-09-12T05:01:53.406687Z","end":"2026-09-12T05:01:53.571485Z","steps":["trace[222224433] 'process raft request' (duration: 39.930871ms)","trace[222224433] 'compare' (duration: 124.32645ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:01:53.571562Z","caller":"traceutil/trace.go:171","msg":"trace[1302214149] linearizableReadLoop","detail":"{readStateIndex:27309; appliedIndex:27308; }","duration":"149.323862ms","start":"2026-09-12T05:01:53.422212Z","end":"2026-09-12T05:01:53.571536Z","steps":["trace[1302214149] 'read index received' (duration: 24.386829ms)","trace[1302214149] 'applied index is now lower than readState.Index' (duration: 124.934843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:01:53.571771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.539457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-12T05:01:53.571817Z","caller":"traceutil/trace.go:171","msg":"trace[1194171638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26429; }","duration":"149.627609ms","start":"2026-09-12T05:01:53.422178Z","end":"2026-09-12T05:01:53.571805Z","steps":["trace[1194171638] 'agreement among raft nodes before linearized reading' (duration: 149.470586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:01:53.571939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.285847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-12T05:01:53.572032Z","caller":"traceutil/trace.go:171","msg":"trace[1766820584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26430; }","duration":"146.41178ms","start":"2026-09-12T05:01:53.425601Z","end":"2026-09-12T05:01:53.572012Z","steps":["trace[1766820584] 'agreement among raft nodes before linearized reading' (duration: 146.198706ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:01:59.108477Z","caller":"traceutil/trace.go:171","msg":"trace[318432656] linearizableReadLoop","detail":"{readStateIndex:27346; appliedIndex:27345; }","duration":"249.012767ms","start":"2026-09-12T05:01:58.85943Z","end":"2026-09-12T05:01:59.108443Z","steps":["trace[318432656] 'read index received' (duration: 248.668799ms)","trace[318432656] 'applied index is now lower than readState.Index' (duration: 342.438µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:01:59.108509Z","caller":"traceutil/trace.go:171","msg":"trace[1239750337] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"423.072846ms","start":"2026-09-12T05:01:58.685407Z","end":"2026-09-12T05:01:59.10848Z","steps":["trace[1239750337] 'process raft request' (duration: 422.778309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:01:59.108646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.185401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T05:01:59.108701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:58.685385Z","time spent":"423.23688ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:01:59.108712Z","caller":"traceutil/trace.go:171","msg":"trace[1512403867] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26464; }","duration":"249.308614ms","start":"2026-09-12T05:01:58.859388Z","end":"2026-09-12T05:01:59.108696Z","steps":["trace[1512403867] 'agreement among raft nodes before linearized reading' (duration: 249.174141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:01:59.43914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.184916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:01:59.439261Z","caller":"traceutil/trace.go:171","msg":"trace[357712951] linearizableReadLoop","detail":"{readStateIndex:27347; appliedIndex:27346; }","duration":"330.652377ms","start":"2026-09-12T05:01:59.108571Z","end":"2026-09-12T05:01:59.439224Z","steps":["trace[357712951] 'read index received' (duration: 176.218305ms)","trace[357712951] 'applied index is now lower than readState.Index' (duration: 154.432921ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:01:59.43933Z","caller":"traceutil/trace.go:171","msg":"trace[394848534] transaction","detail":"{read_only:false; response_revision:26465; number_of_response:1; }","duration":"389.552581ms","start":"2026-09-12T05:01:59.049766Z","end":"2026-09-12T05:01:59.439319Z","steps":["trace[394848534] 'process raft request' (duration: 235.09491ms)","trace[394848534] 'compare' (duration: 154.030023ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:01:59.439391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.049738Z","time spent":"389.619783ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:01:59.439428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.893392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:01:59.439523Z","caller":"traceutil/trace.go:171","msg":"trace[87803806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26465; }","duration":"537.029205ms","start":"2026-09-12T05:01:58.902473Z","end":"2026-09-12T05:01:59.439502Z","steps":["trace[87803806] 'agreement among raft nodes before linearized reading' (duration: 536.870302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:01:59.439575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:58.902452Z","time spent":"537.111837ms","remote":"127.0.0.1:56968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-12T05:01:59.5291Z","caller":"traceutil/trace.go:171","msg":"trace[385652791] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"195.907855ms","start":"2026-09-12T05:01:59.333168Z","end":"2026-09-12T05:01:59.529076Z","steps":["trace[385652791] 'process raft request' (duration: 195.775162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:01:59.810225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.051173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:01:59.810341Z","caller":"traceutil/trace.go:171","msg":"trace[1144494189] linearizableReadLoop","detail":"{readStateIndex:27349; appliedIndex:27347; }","duration":"371.038007ms","start":"2026-09-12T05:01:59.439287Z","end":"2026-09-12T05:01:59.810325Z","steps":["trace[1144494189] 'read index received' (duration: 89.698571ms)","trace[1144494189] 'applied index is now lower than readState.Index' (duration: 281.338456ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:01:59.810476Z","caller":"traceutil/trace.go:171","msg":"trace[194125958] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"474.255997ms","start":"2026-09-12T05:01:59.336161Z","end":"2026-09-12T05:01:59.810417Z","steps":["trace[194125958] 'process raft request' (duration: 342.934668ms)","trace[194125958] 'compare' (duration: 130.706256ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:01:59.810572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.124289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-12T05:01:59.810637Z","caller":"traceutil/trace.go:171","msg":"trace[1287887977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26467; }","duration":"424.229601ms","start":"2026-09-12T05:01:59.386389Z","end":"2026-09-12T05:01:59.810618Z","steps":["trace[1287887977] 'agreement among raft nodes before linearized reading' (duration: 424.051007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:01:59.810648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.336131Z","time spent":"474.432081ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:01:59.810678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.386363Z","time spent":"424.305442ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-12T05:02:00.138289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.950841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:00.13873Z","caller":"traceutil/trace.go:171","msg":"trace[1236998280] linearizableReadLoop","detail":"{readStateIndex:27352; appliedIndex:27349; }","duration":"328.343927ms","start":"2026-09-12T05:01:59.810363Z","end":"2026-09-12T05:02:00.138707Z","steps":["trace[1236998280] 'read index received' (duration: 104.760753ms)","trace[1236998280] 'applied index is now lower than readState.Index' (duration: 223.582104ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:00.138972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.128105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-12T05:02:00.138959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.797555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-12T05:02:00.139026Z","caller":"traceutil/trace.go:171","msg":"trace[519366294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26472; }","duration":"536.203697ms","start":"2026-09-12T05:01:59.602807Z","end":"2026-09-12T05:02:00.139011Z","steps":["trace[519366294] 'agreement among raft nodes before linearized reading' (duration: 536.017393ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:00.139047Z","caller":"traceutil/trace.go:171","msg":"trace[399172172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26472; }","duration":"532.925748ms","start":"2026-09-12T05:01:59.606103Z","end":"2026-09-12T05:02:00.139029Z","steps":["trace[399172172] 'agreement among raft nodes before linearized reading' (duration: 532.708813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.139086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.606094Z","time spent":"532.983449ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-12T05:02:00.139075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.602798Z","time spent":"536.261848ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-12T05:02:00.139276Z","caller":"traceutil/trace.go:171","msg":"trace[860237906] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"307.463443ms","start":"2026-09-12T05:01:59.831758Z","end":"2026-09-12T05:02:00.139222Z","steps":["trace[860237906] 'process raft request' (duration: 306.89238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.13929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.435076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T05:02:00.139334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.083042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:00.139371Z","caller":"traceutil/trace.go:171","msg":"trace[68701654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26472; }","duration":"698.550307ms","start":"2026-09-12T05:01:59.440796Z","end":"2026-09-12T05:02:00.139347Z","steps":["trace[68701654] 'agreement among raft nodes before linearized reading' (duration: 698.399234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.139376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.548372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-12T05:02:00.139406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.440779Z","time spent":"698.620269ms","remote":"127.0.0.1:56966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-12T05:02:00.139395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.575937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:00.139434Z","caller":"traceutil/trace.go:171","msg":"trace[361107488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26472; }","duration":"541.633374ms","start":"2026-09-12T05:01:59.597787Z","end":"2026-09-12T05:02:00.139421Z","steps":["trace[361107488] 'agreement among raft nodes before linearized reading' (duration: 541.500371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.139449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.831729Z","time spent":"307.625886ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:00.139467Z","caller":"traceutil/trace.go:171","msg":"trace[1718825038] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26472; }","duration":"552.655198ms","start":"2026-09-12T05:01:59.586792Z","end":"2026-09-12T05:02:00.139448Z","steps":["trace[1718825038] 'agreement among raft nodes before linearized reading' (duration: 552.520386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.13948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.597779Z","time spent":"541.692615ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-12T05:02:00.139463Z","caller":"traceutil/trace.go:171","msg":"trace[1397060251] transaction","detail":"{read_only:false; response_revision:26471; number_of_response:1; }","duration":"323.501385ms","start":"2026-09-12T05:01:59.815929Z","end":"2026-09-12T05:02:00.13943Z","steps":["trace[1397060251] 'process raft request' (duration: 322.670577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.139512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.586744Z","time spent":"552.756961ms","remote":"127.0.0.1:35870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-09-12T05:02:00.13941Z","caller":"traceutil/trace.go:171","msg":"trace[1584198301] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26472; }","duration":"198.157693ms","start":"2026-09-12T05:01:59.941233Z","end":"2026-09-12T05:02:00.13939Z","steps":["trace[1584198301] 'agreement among raft nodes before linearized reading' (duration: 198.050481ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:00.139522Z","caller":"traceutil/trace.go:171","msg":"trace[1440359903] transaction","detail":"{read_only:false; response_revision:26470; number_of_response:1; }","duration":"396.484161ms","start":"2026-09-12T05:01:59.742729Z","end":"2026-09-12T05:02:00.139214Z","steps":["trace[1440359903] 'process raft request' (duration: 395.804655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.139606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.815906Z","time spent":"323.630688ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:00.139656Z","caller":"traceutil/trace.go:171","msg":"trace[503030124] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"542.441801ms","start":"2026-09-12T05:01:59.597194Z","end":"2026-09-12T05:02:00.139636Z","steps":["trace[503030124] 'process raft request' (duration: 541.209255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.139703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.742699Z","time spent":"396.90358ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:00.139729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.052021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-12T05:02:00.139767Z","caller":"traceutil/trace.go:171","msg":"trace[56494980] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26472; }","duration":"122.128833ms","start":"2026-09-12T05:02:00.017628Z","end":"2026-09-12T05:02:00.139757Z","steps":["trace[56494980] 'agreement among raft nodes before linearized reading' (duration: 121.98348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.13976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.597183Z","time spent":"542.527233ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:00.140151Z","caller":"traceutil/trace.go:171","msg":"trace[1411704970] transaction","detail":"{read_only:false; response_revision:26468; number_of_response:1; }","duration":"546.155479ms","start":"2026-09-12T05:01:59.593908Z","end":"2026-09-12T05:02:00.140064Z","steps":["trace[1411704970] 'process raft request' (duration: 321.247726ms)","trace[1411704970] 'compare' (duration: 222.654145ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:00.140308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:01:59.593893Z","time spent":"546.336233ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:00.419745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.231387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:00.419856Z","caller":"traceutil/trace.go:171","msg":"trace[960505115] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"275.128323ms","start":"2026-09-12T05:02:00.144708Z","end":"2026-09-12T05:02:00.419837Z","steps":["trace[960505115] 'process raft request' (duration: 151.698552ms)","trace[960505115] 'compare' (duration: 123.067903ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:00.601039Z","caller":"traceutil/trace.go:171","msg":"trace[500291199] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"456.183431ms","start":"2026-09-12T05:02:00.144828Z","end":"2026-09-12T05:02:00.601011Z","steps":["trace[500291199] 'process raft request' (duration: 455.961757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.601205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:00.144798Z","time spent":"456.329284ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:00.60119Z","caller":"traceutil/trace.go:171","msg":"trace[1122527234] linearizableReadLoop","detail":"{readStateIndex:27357; appliedIndex:27354; }","duration":"452.735118ms","start":"2026-09-12T05:02:00.148419Z","end":"2026-09-12T05:02:00.601154Z","steps":["trace[1122527234] 'read index received' (duration: 147.965194ms)","trace[1122527234] 'applied index is now lower than readState.Index' (duration: 304.767294ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:00.601333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.924742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:00.601391Z","caller":"traceutil/trace.go:171","msg":"trace[204792257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26475; }","duration":"453.037736ms","start":"2026-09-12T05:02:00.148339Z","end":"2026-09-12T05:02:00.601377Z","steps":["trace[204792257] 'agreement among raft nodes before linearized reading' (duration: 452.931503ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:00.601398Z","caller":"traceutil/trace.go:171","msg":"trace[542050676] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"456.06667ms","start":"2026-09-12T05:02:00.145297Z","end":"2026-09-12T05:02:00.601363Z","steps":["trace[542050676] 'process raft request' (duration: 455.66887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.601559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:00.145282Z","time spent":"456.191552ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:00.601434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:00.148324Z","time spent":"453.102017ms","remote":"127.0.0.1:56968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-12T05:02:00.611343Z","caller":"traceutil/trace.go:171","msg":"trace[520385683] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"104.626139ms","start":"2026-09-12T05:02:00.506678Z","end":"2026-09-12T05:02:00.611304Z","steps":["trace[520385683] 'process raft request' (duration: 104.181019ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:00.611571Z","caller":"traceutil/trace.go:171","msg":"trace[787144715] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"102.625326ms","start":"2026-09-12T05:02:00.508933Z","end":"2026-09-12T05:02:00.611559Z","steps":["trace[787144715] 'process raft request' (duration: 102.055194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.611757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.725453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:00.611804Z","caller":"traceutil/trace.go:171","msg":"trace[1917413554] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26477; }","duration":"227.818025ms","start":"2026-09-12T05:02:00.383972Z","end":"2026-09-12T05:02:00.61179Z","steps":["trace[1917413554] 'agreement among raft nodes before linearized reading' (duration: 227.724713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.612002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.7284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:00.612042Z","caller":"traceutil/trace.go:171","msg":"trace[1956835795] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26477; }","duration":"248.815342ms","start":"2026-09-12T05:02:00.363215Z","end":"2026-09-12T05:02:00.61203Z","steps":["trace[1956835795] 'agreement among raft nodes before linearized reading' (duration: 248.754731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.612308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.780515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T05:02:00.612351Z","caller":"traceutil/trace.go:171","msg":"trace[629843105] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:26477; }","duration":"342.827996ms","start":"2026-09-12T05:02:00.269513Z","end":"2026-09-12T05:02:00.612341Z","steps":["trace[629843105] 'agreement among raft nodes before linearized reading' (duration: 342.687363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:00.612386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:00.269454Z","time spent":"342.921187ms","remote":"127.0.0.1:57116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":14,"response size":32,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-09-12T05:02:01.367066Z","caller":"traceutil/trace.go:171","msg":"trace[1874944140] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"251.365667ms","start":"2026-09-12T05:02:01.115676Z","end":"2026-09-12T05:02:01.367042Z","steps":["trace[1874944140] 'process raft request' (duration: 251.232564ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:01.593839Z","caller":"traceutil/trace.go:171","msg":"trace[575507330] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"148.603877ms","start":"2026-09-12T05:02:01.44521Z","end":"2026-09-12T05:02:01.593814Z","steps":["trace[575507330] 'process raft request' (duration: 50.371593ms)","trace[575507330] 'compare' (duration: 97.999129ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:01.876946Z","caller":"traceutil/trace.go:171","msg":"trace[1403713721] linearizableReadLoop","detail":"{readStateIndex:27362; appliedIndex:27361; }","duration":"336.842778ms","start":"2026-09-12T05:02:01.540074Z","end":"2026-09-12T05:02:01.876916Z","steps":["trace[1403713721] 'read index received' (duration: 261.509583ms)","trace[1403713721] 'applied index is now lower than readState.Index' (duration: 75.331865ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:01.877036Z","caller":"traceutil/trace.go:171","msg":"trace[789395572] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"339.940925ms","start":"2026-09-12T05:02:01.537052Z","end":"2026-09-12T05:02:01.876993Z","steps":["trace[789395572] 'process raft request' (duration: 264.461636ms)","trace[789395572] 'compare' (duration: 75.138252ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:01.877094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.994972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:01.877132Z","caller":"traceutil/trace.go:171","msg":"trace[499951680] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26480; }","duration":"337.098713ms","start":"2026-09-12T05:02:01.540019Z","end":"2026-09-12T05:02:01.877118Z","steps":["trace[499951680] 'agreement among raft nodes before linearized reading' (duration: 336.995601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:01.877173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:01.539998Z","time spent":"337.165135ms","remote":"127.0.0.1:59252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-09-12T05:02:01.877221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:01.537028Z","time spent":"340.092648ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:02.257871Z","caller":"traceutil/trace.go:171","msg":"trace[1815507775] linearizableReadLoop","detail":"{readStateIndex:27364; appliedIndex:27363; }","duration":"112.504817ms","start":"2026-09-12T05:02:02.145342Z","end":"2026-09-12T05:02:02.257847Z","steps":["trace[1815507775] 'read index received' (duration: 112.383965ms)","trace[1815507775] 'applied index is now lower than readState.Index' (duration: 119.482µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:02.257931Z","caller":"traceutil/trace.go:171","msg":"trace[1790795794] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"113.065361ms","start":"2026-09-12T05:02:02.144835Z","end":"2026-09-12T05:02:02.2579Z","steps":["trace[1790795794] 'process raft request' (duration: 112.844516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.258106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.741202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-12T05:02:02.258159Z","caller":"traceutil/trace.go:171","msg":"trace[1965608553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26482; }","duration":"112.844465ms","start":"2026-09-12T05:02:02.145298Z","end":"2026-09-12T05:02:02.258143Z","steps":["trace[1965608553] 'agreement among raft nodes before linearized reading' (duration: 112.64223ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.41409Z","caller":"traceutil/trace.go:171","msg":"trace[1170360147] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"222.118885ms","start":"2026-09-12T05:02:02.19195Z","end":"2026-09-12T05:02:02.414069Z","steps":["trace[1170360147] 'process raft request' (duration: 222.075724ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.414146Z","caller":"traceutil/trace.go:171","msg":"trace[1982078514] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"266.9704ms","start":"2026-09-12T05:02:02.147135Z","end":"2026-09-12T05:02:02.414105Z","steps":["trace[1982078514] 'process raft request' (duration: 266.695505ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.414282Z","caller":"traceutil/trace.go:171","msg":"trace[1503044391] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"264.813094ms","start":"2026-09-12T05:02:02.149356Z","end":"2026-09-12T05:02:02.414169Z","steps":["trace[1503044391] 'process raft request' (duration: 264.62484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.689481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.413895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:02.6896Z","caller":"traceutil/trace.go:171","msg":"trace[1449244237] linearizableReadLoop","detail":"{readStateIndex:27368; appliedIndex:27364; }","duration":"347.776732ms","start":"2026-09-12T05:02:02.341802Z","end":"2026-09-12T05:02:02.689579Z","steps":["trace[1449244237] 'read index received' (duration: 72.023686ms)","trace[1449244237] 'applied index is now lower than readState.Index' (duration: 275.751366ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:02.689705Z","caller":"traceutil/trace.go:171","msg":"trace[1463537418] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"426.59227ms","start":"2026-09-12T05:02:02.263096Z","end":"2026-09-12T05:02:02.689688Z","steps":["trace[1463537418] 'process raft request' (duration: 283.877689ms)","trace[1463537418] 'compare' (duration: 142.216061ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:02.689787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:02.263069Z","time spent":"426.670882ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:02.689858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.045117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.129\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-12T05:02:02.689923Z","caller":"traceutil/trace.go:171","msg":"trace[235462601] range","detail":"{range_begin:/registry/masterleases/162.253.55.129; range_end:; response_count:1; response_revision:26486; }","duration":"348.15074ms","start":"2026-09-12T05:02:02.341755Z","end":"2026-09-12T05:02:02.689906Z","steps":["trace[235462601] 'agreement among raft nodes before linearized reading' (duration: 347.933035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.689975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:02.341723Z","time spent":"348.242161ms","remote":"127.0.0.1:56994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/162.253.55.129\" "} {"level":"warn","ts":"2026-09-12T05:02:02.710838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.662693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-12T05:02:02.711781Z","caller":"traceutil/trace.go:171","msg":"trace[304504852] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26488; }","duration":"305.652564ms","start":"2026-09-12T05:02:02.406107Z","end":"2026-09-12T05:02:02.71176Z","steps":["trace[304504852] 'agreement among raft nodes before linearized reading' (duration: 304.55005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.711877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:02.406089Z","time spent":"305.769446ms","remote":"127.0.0.1:57156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-12T05:02:02.711143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.746253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-12T05:02:02.71198Z","caller":"traceutil/trace.go:171","msg":"trace[476043288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26488; }","duration":"106.627582ms","start":"2026-09-12T05:02:02.605337Z","end":"2026-09-12T05:02:02.711964Z","steps":["trace[476043288] 'agreement among raft nodes before linearized reading' (duration: 105.680932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.711273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.261242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T05:02:02.712312Z","caller":"traceutil/trace.go:171","msg":"trace[1648440687] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26488; }","duration":"158.19772ms","start":"2026-09-12T05:02:02.553924Z","end":"2026-09-12T05:02:02.712122Z","steps":["trace[1648440687] 'agreement among raft nodes before linearized reading' (duration: 157.199599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.711306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.917366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-12T05:02:02.713067Z","caller":"traceutil/trace.go:171","msg":"trace[2127951490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26488; }","duration":"289.710764ms","start":"2026-09-12T05:02:02.423334Z","end":"2026-09-12T05:02:02.713045Z","steps":["trace[2127951490] 'agreement among raft nodes before linearized reading' (duration: 287.817394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.711363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.832036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-12T05:02:02.713177Z","caller":"traceutil/trace.go:171","msg":"trace[941382390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26488; }","duration":"107.669914ms","start":"2026-09-12T05:02:02.605489Z","end":"2026-09-12T05:02:02.713159Z","steps":["trace[941382390] 'agreement among raft nodes before linearized reading' (duration: 105.700113ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.864071Z","caller":"traceutil/trace.go:171","msg":"trace[1155075113] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"148.931264ms","start":"2026-09-12T05:02:02.715113Z","end":"2026-09-12T05:02:02.864044Z","steps":["trace[1155075113] 'process raft request' (duration: 148.605197ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.864429Z","caller":"traceutil/trace.go:171","msg":"trace[1985786526] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"148.470534ms","start":"2026-09-12T05:02:02.715923Z","end":"2026-09-12T05:02:02.864394Z","steps":["trace[1985786526] 'process raft request' (duration: 148.072626ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.864556Z","caller":"traceutil/trace.go:171","msg":"trace[1301475397] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"146.943761ms","start":"2026-09-12T05:02:02.717599Z","end":"2026-09-12T05:02:02.864543Z","steps":["trace[1301475397] 'process raft request' (duration: 146.720217ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.864556Z","caller":"traceutil/trace.go:171","msg":"trace[2078693958] linearizableReadLoop","detail":"{readStateIndex:27373; appliedIndex:27371; }","duration":"148.965554ms","start":"2026-09-12T05:02:02.715568Z","end":"2026-09-12T05:02:02.864534Z","steps":["trace[2078693958] 'read index received' (duration: 11.012154ms)","trace[2078693958] 'applied index is now lower than readState.Index' (duration: 137.95214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:02.864766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.18997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-12T05:02:02.864805Z","caller":"traceutil/trace.go:171","msg":"trace[1546925596] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26493; }","duration":"149.266371ms","start":"2026-09-12T05:02:02.715529Z","end":"2026-09-12T05:02:02.864795Z","steps":["trace[1546925596] 'agreement among raft nodes before linearized reading' (duration: 149.104427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.864941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.636092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-09-12T05:02:02.865018Z","caller":"traceutil/trace.go:171","msg":"trace[482106228] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26493; }","duration":"136.797316ms","start":"2026-09-12T05:02:02.728203Z","end":"2026-09-12T05:02:02.865Z","steps":["trace[482106228] 'agreement among raft nodes before linearized reading' (duration: 136.512309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:02.86481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.503032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:02.865098Z","caller":"traceutil/trace.go:171","msg":"trace[566046286] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26493; }","duration":"146.924922ms","start":"2026-09-12T05:02:02.71815Z","end":"2026-09-12T05:02:02.865075Z","steps":["trace[566046286] 'agreement among raft nodes before linearized reading' (duration: 146.512942ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:02.865324Z","caller":"traceutil/trace.go:171","msg":"trace[1634257079] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"146.850779ms","start":"2026-09-12T05:02:02.717724Z","end":"2026-09-12T05:02:02.864575Z","steps":["trace[1634257079] 'process raft request' (duration: 146.745867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:03.088432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.84051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:03.088555Z","caller":"traceutil/trace.go:171","msg":"trace[636766558] linearizableReadLoop","detail":"{readStateIndex:27377; appliedIndex:27376; }","duration":"141.891973ms","start":"2026-09-12T05:02:02.94664Z","end":"2026-09-12T05:02:03.088532Z","steps":["trace[636766558] 'read index received' (duration: 1.748296ms)","trace[636766558] 'applied index is now lower than readState.Index' (duration: 140.141817ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:03.08865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.000806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:03.088688Z","caller":"traceutil/trace.go:171","msg":"trace[1907014572] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26494; }","duration":"142.040817ms","start":"2026-09-12T05:02:02.946633Z","end":"2026-09-12T05:02:03.088674Z","steps":["trace[1907014572] 'agreement among raft nodes before linearized reading' (duration: 141.956915ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:03.088691Z","caller":"traceutil/trace.go:171","msg":"trace[1555001839] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"145.992911ms","start":"2026-09-12T05:02:02.942668Z","end":"2026-09-12T05:02:03.088661Z","steps":["trace[1555001839] 'compare' (duration: 139.537144ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:03.813554Z","caller":"traceutil/trace.go:171","msg":"trace[1776957901] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"214.722076ms","start":"2026-09-12T05:02:03.598805Z","end":"2026-09-12T05:02:03.813528Z","steps":["trace[1776957901] 'process raft request' (duration: 214.512971ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:03.993855Z","caller":"traceutil/trace.go:171","msg":"trace[1127317913] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"111.041367ms","start":"2026-09-12T05:02:03.882746Z","end":"2026-09-12T05:02:03.993787Z","steps":["trace[1127317913] 'process raft request' (duration: 96.904475ms)","trace[1127317913] 'compare' (duration: 13.507388ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:04.29611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.48538ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:04.296317Z","caller":"traceutil/trace.go:171","msg":"trace[851688839] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26499; }","duration":"107.724925ms","start":"2026-09-12T05:02:04.188567Z","end":"2026-09-12T05:02:04.296292Z","steps":["trace[851688839] 'range keys from in-memory index tree' (duration: 107.464659ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:04.661427Z","caller":"traceutil/trace.go:171","msg":"trace[2042872275] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"241.396954ms","start":"2026-09-12T05:02:04.419982Z","end":"2026-09-12T05:02:04.661379Z","steps":["trace[2042872275] 'process raft request' (duration: 213.646513ms)","trace[2042872275] 'compare' (duration: 27.425174ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:04.871759Z","caller":"traceutil/trace.go:171","msg":"trace[429462577] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"172.749302ms","start":"2026-09-12T05:02:04.69899Z","end":"2026-09-12T05:02:04.871739Z","steps":["trace[429462577] 'process raft request' (duration: 127.752023ms)","trace[429462577] 'compare' (duration: 44.613201ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:04.966995Z","caller":"traceutil/trace.go:171","msg":"trace[1620779591] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"249.425436ms","start":"2026-09-12T05:02:04.717544Z","end":"2026-09-12T05:02:04.966969Z","steps":["trace[1620779591] 'process raft request' (duration: 249.256362ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:04.967087Z","caller":"traceutil/trace.go:171","msg":"trace[143636251] linearizableReadLoop","detail":"{readStateIndex:27388; appliedIndex:27385; }","duration":"140.693609ms","start":"2026-09-12T05:02:04.826368Z","end":"2026-09-12T05:02:04.967061Z","steps":["trace[143636251] 'read index received' (duration: 386.479µs)","trace[143636251] 'applied index is now lower than readState.Index' (duration: 140.30488ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:04.967128Z","caller":"traceutil/trace.go:171","msg":"trace[1115051880] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"249.074629ms","start":"2026-09-12T05:02:04.718033Z","end":"2026-09-12T05:02:04.967108Z","steps":["trace[1115051880] 'process raft request' (duration: 248.893415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:04.967257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.834412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:04.967291Z","caller":"traceutil/trace.go:171","msg":"trace[666261557] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:26505; }","duration":"140.956083ms","start":"2026-09-12T05:02:04.826325Z","end":"2026-09-12T05:02:04.967281Z","steps":["trace[666261557] 'agreement among raft nodes before linearized reading' (duration: 140.834791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:05.166694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.03376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:05.166887Z","caller":"traceutil/trace.go:171","msg":"trace[1289777713] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"194.074535ms","start":"2026-09-12T05:02:04.972795Z","end":"2026-09-12T05:02:05.16687Z","steps":["trace[1289777713] 'process raft request' (duration: 194.035895ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:05.166991Z","caller":"traceutil/trace.go:171","msg":"trace[1699423455] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"195.541228ms","start":"2026-09-12T05:02:04.971432Z","end":"2026-09-12T05:02:05.166973Z","steps":["trace[1699423455] 'process raft request' (duration: 20.15371ms)","trace[1699423455] 'compare' (duration: 174.918558ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:05.167204Z","caller":"traceutil/trace.go:171","msg":"trace[1059513786] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"195.281191ms","start":"2026-09-12T05:02:04.971891Z","end":"2026-09-12T05:02:05.167172Z","steps":["trace[1059513786] 'process raft request' (duration: 194.895193ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:16.007822Z","caller":"traceutil/trace.go:171","msg":"trace[346046058] linearizableReadLoop","detail":"{readStateIndex:27468; appliedIndex:27467; }","duration":"105.675452ms","start":"2026-09-12T05:02:15.902121Z","end":"2026-09-12T05:02:16.007797Z","steps":["trace[346046058] 'read index received' (duration: 105.470628ms)","trace[346046058] 'applied index is now lower than readState.Index' (duration: 203.974µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:16.007967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.824785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:16.008015Z","caller":"traceutil/trace.go:171","msg":"trace[436582214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26583; }","duration":"105.949828ms","start":"2026-09-12T05:02:15.902039Z","end":"2026-09-12T05:02:16.007989Z","steps":["trace[436582214] 'agreement among raft nodes before linearized reading' (duration: 105.848696ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:16.008054Z","caller":"traceutil/trace.go:171","msg":"trace[897364767] transaction","detail":"{read_only:false; response_revision:26583; number_of_response:1; }","duration":"151.192052ms","start":"2026-09-12T05:02:15.856823Z","end":"2026-09-12T05:02:16.008015Z","steps":["trace[897364767] 'process raft request' (duration: 150.828914ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:16.454589Z","caller":"traceutil/trace.go:171","msg":"trace[806792710] transaction","detail":"{read_only:false; response_revision:26584; number_of_response:1; }","duration":"419.995681ms","start":"2026-09-12T05:02:16.034562Z","end":"2026-09-12T05:02:16.454558Z","steps":["trace[806792710] 'process raft request' (duration: 419.825207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:16.454759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.034539Z","time spent":"420.143193ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:16.886377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.270539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:16.886669Z","caller":"traceutil/trace.go:171","msg":"trace[991709162] transaction","detail":"{read_only:false; response_revision:26587; number_of_response:1; }","duration":"384.500464ms","start":"2026-09-12T05:02:16.502146Z","end":"2026-09-12T05:02:16.886646Z","steps":["trace[991709162] 'process raft request' (duration: 384.436733ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:16.886711Z","caller":"traceutil/trace.go:171","msg":"trace[1519464819] transaction","detail":"{read_only:false; response_revision:26586; number_of_response:1; }","duration":"530.150088ms","start":"2026-09-12T05:02:16.356527Z","end":"2026-09-12T05:02:16.886677Z","steps":["trace[1519464819] 'process raft request' (duration: 529.963534ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:16.886716Z","caller":"traceutil/trace.go:171","msg":"trace[1365354809] transaction","detail":"{read_only:false; response_revision:26585; number_of_response:1; }","duration":"843.285642ms","start":"2026-09-12T05:02:16.043397Z","end":"2026-09-12T05:02:16.886682Z","steps":["trace[1365354809] 'process raft request' (duration: 542.602494ms)","trace[1365354809] 'compare' (duration: 299.783749ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:16.886716Z","caller":"traceutil/trace.go:171","msg":"trace[902280578] linearizableReadLoop","detail":"{readStateIndex:27470; appliedIndex:27468; }","duration":"723.173992ms","start":"2026-09-12T05:02:16.163512Z","end":"2026-09-12T05:02:16.886686Z","steps":["trace[902280578] 'read index received' (duration: 290.810958ms)","trace[902280578] 'applied index is now lower than readState.Index' (duration: 432.360564ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:16.886766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.50212Z","time spent":"384.603486ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:16.886876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.356504Z","time spent":"530.270751ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:16.886878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.043383Z","time spent":"843.403774ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:16.886895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.364326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:16.886934Z","caller":"traceutil/trace.go:171","msg":"trace[1636436249] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:26587; }","duration":"723.458288ms","start":"2026-09-12T05:02:16.163461Z","end":"2026-09-12T05:02:16.88692Z","steps":["trace[1636436249] 'agreement among raft nodes before linearized reading' (duration: 723.356356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:16.886973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.163441Z","time spent":"723.519329ms","remote":"127.0.0.1:49546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"info","ts":"2026-09-12T05:02:17.202137Z","caller":"traceutil/trace.go:171","msg":"trace[644888356] transaction","detail":"{read_only:false; response_revision:26588; number_of_response:1; }","duration":"491.447553ms","start":"2026-09-12T05:02:16.710654Z","end":"2026-09-12T05:02:17.202102Z","steps":["trace[644888356] 'process raft request' (duration: 440.498118ms)","trace[644888356] 'compare' (duration: 50.746921ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:17.202287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.861671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T05:02:17.202317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.710628Z","time spent":"491.569975ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:17.20234Z","caller":"traceutil/trace.go:171","msg":"trace[224973563] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26588; }","duration":"947.960813ms","start":"2026-09-12T05:02:16.254363Z","end":"2026-09-12T05:02:17.202323Z","steps":["trace[224973563] 'agreement among raft nodes before linearized reading' (duration: 947.812939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.202374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.25434Z","time spent":"948.022075ms","remote":"127.0.0.1:40802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-09-12T05:02:17.20213Z","caller":"traceutil/trace.go:171","msg":"trace[2594426] linearizableReadLoop","detail":"{readStateIndex:27473; appliedIndex:27472; }","duration":"315.291509ms","start":"2026-09-12T05:02:16.886792Z","end":"2026-09-12T05:02:17.202083Z","steps":["trace[2594426] 'read index received' (duration: 264.422845ms)","trace[2594426] 'applied index is now lower than readState.Index' (duration: 50.867654ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:17.202699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.050036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:17.202801Z","caller":"traceutil/trace.go:171","msg":"trace[1646737915] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26588; }","duration":"339.166359ms","start":"2026-09-12T05:02:16.863605Z","end":"2026-09-12T05:02:17.202772Z","steps":["trace[1646737915] 'agreement among raft nodes before linearized reading' (duration: 338.982875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.202867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.863582Z","time spent":"339.27183ms","remote":"127.0.0.1:59324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-09-12T05:02:17.479687Z","caller":"traceutil/trace.go:171","msg":"trace[1218760419] transaction","detail":"{read_only:false; response_revision:26589; number_of_response:1; }","duration":"470.734944ms","start":"2026-09-12T05:02:17.008927Z","end":"2026-09-12T05:02:17.479662Z","steps":["trace[1218760419] 'process raft request' (duration: 470.549419ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:17.479825Z","caller":"traceutil/trace.go:171","msg":"trace[1249863774] transaction","detail":"{read_only:false; response_revision:26590; number_of_response:1; }","duration":"463.351896ms","start":"2026-09-12T05:02:17.016459Z","end":"2026-09-12T05:02:17.479811Z","steps":["trace[1249863774] 'process raft request' (duration: 463.163722ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:17.47983Z","caller":"traceutil/trace.go:171","msg":"trace[1858628936] linearizableReadLoop","detail":"{readStateIndex:27475; appliedIndex:27473; }","duration":"277.187858ms","start":"2026-09-12T05:02:17.202615Z","end":"2026-09-12T05:02:17.479803Z","steps":["trace[1858628936] 'read index received' (duration: 276.814789ms)","trace[1858628936] 'applied index is now lower than readState.Index' (duration: 370.809µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:17.479904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:17.01643Z","time spent":"463.419107ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:17.480028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.000393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-12T05:02:17.480024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.381085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:17.480061Z","caller":"traceutil/trace.go:171","msg":"trace[782312211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26590; }","duration":"543.067684ms","start":"2026-09-12T05:02:16.936981Z","end":"2026-09-12T05:02:17.480049Z","steps":["trace[782312211] 'agreement among raft nodes before linearized reading' (duration: 542.90913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.48009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.936963Z","time spent":"543.119715ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-12T05:02:17.480092Z","caller":"traceutil/trace.go:171","msg":"trace[199925197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26590; }","duration":"577.482287ms","start":"2026-09-12T05:02:16.902588Z","end":"2026-09-12T05:02:17.48007Z","steps":["trace[199925197] 'agreement among raft nodes before linearized reading' (duration: 577.377485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.480134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:16.902571Z","time spent":"577.552978ms","remote":"127.0.0.1:56968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-12T05:02:17.480191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:17.008859Z","time spent":"470.890476ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:17.482108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.965324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-12T05:02:17.482192Z","caller":"traceutil/trace.go:171","msg":"trace[1454610721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26591; }","duration":"255.100576ms","start":"2026-09-12T05:02:17.227071Z","end":"2026-09-12T05:02:17.482172Z","steps":["trace[1454610721] 'agreement among raft nodes before linearized reading' (duration: 254.836771ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.482304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.854475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T05:02:17.482394Z","caller":"traceutil/trace.go:171","msg":"trace[268283043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26591; }","duration":"257.982329ms","start":"2026-09-12T05:02:17.224386Z","end":"2026-09-12T05:02:17.482368Z","steps":["trace[268283043] 'agreement among raft nodes before linearized reading' (duration: 257.732073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.482497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.855902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-12T05:02:17.482528Z","caller":"traceutil/trace.go:171","msg":"trace[934913122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26591; }","duration":"254.905152ms","start":"2026-09-12T05:02:17.227614Z","end":"2026-09-12T05:02:17.482519Z","steps":["trace[934913122] 'agreement among raft nodes before linearized reading' (duration: 254.819891ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:17.925109Z","caller":"traceutil/trace.go:171","msg":"trace[1845007779] transaction","detail":"{read_only:false; response_revision:26593; number_of_response:1; }","duration":"437.997615ms","start":"2026-09-12T05:02:17.487085Z","end":"2026-09-12T05:02:17.925083Z","steps":["trace[1845007779] 'process raft request' (duration: 437.77921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.925294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:17.487059Z","time spent":"438.122678ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:17.925541Z","caller":"traceutil/trace.go:171","msg":"trace[2020645644] transaction","detail":"{read_only:false; response_revision:26594; number_of_response:1; }","duration":"437.339231ms","start":"2026-09-12T05:02:17.488155Z","end":"2026-09-12T05:02:17.925494Z","steps":["trace[2020645644] 'process raft request' (duration: 436.883491ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:17.925602Z","caller":"traceutil/trace.go:171","msg":"trace[1577809572] linearizableReadLoop","detail":"{readStateIndex:27480; appliedIndex:27477; }","duration":"433.155092ms","start":"2026-09-12T05:02:17.492425Z","end":"2026-09-12T05:02:17.92558Z","steps":["trace[1577809572] 'read index received' (duration: 78.82779ms)","trace[1577809572] 'applied index is now lower than readState.Index' (duration: 354.326532ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:17.925715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:17.488138Z","time spent":"437.480803ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:17.925929Z","caller":"traceutil/trace.go:171","msg":"trace[331481212] transaction","detail":"{read_only:false; response_revision:26595; number_of_response:1; }","duration":"437.76612ms","start":"2026-09-12T05:02:17.488149Z","end":"2026-09-12T05:02:17.925915Z","steps":["trace[331481212] 'process raft request' (duration: 437.241299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.926024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:17.488124Z","time spent":"437.838921ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T05:02:17.926268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.120587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-12T05:02:17.926332Z","caller":"traceutil/trace.go:171","msg":"trace[1995309188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26595; }","duration":"423.306681ms","start":"2026-09-12T05:02:17.503009Z","end":"2026-09-12T05:02:17.926316Z","steps":["trace[1995309188] 'agreement among raft nodes before linearized reading' (duration: 423.102257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.92637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:17.502996Z","time spent":"423.365423ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-12T05:02:17.926422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.999339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:17.926465Z","caller":"traceutil/trace.go:171","msg":"trace[852429811] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26595; }","duration":"434.099162ms","start":"2026-09-12T05:02:17.492354Z","end":"2026-09-12T05:02:17.926453Z","steps":["trace[852429811] 'agreement among raft nodes before linearized reading' (duration: 434.0204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:17.926498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:17.492339Z","time spent":"434.146702ms","remote":"127.0.0.1:49452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"info","ts":"2026-09-12T05:02:18.254506Z","caller":"traceutil/trace.go:171","msg":"trace[1594847185] linearizableReadLoop","detail":"{readStateIndex:27484; appliedIndex:27483; }","duration":"207.718947ms","start":"2026-09-12T05:02:18.046764Z","end":"2026-09-12T05:02:18.254483Z","steps":["trace[1594847185] 'read index received' (duration: 207.482222ms)","trace[1594847185] 'applied index is now lower than readState.Index' (duration: 235.815µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:18.254627Z","caller":"traceutil/trace.go:171","msg":"trace[1337718523] transaction","detail":"{read_only:false; response_revision:26598; number_of_response:1; }","duration":"242.208741ms","start":"2026-09-12T05:02:18.012387Z","end":"2026-09-12T05:02:18.254595Z","steps":["trace[1337718523] 'process raft request' (duration: 241.912925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:18.254701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.91409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:18.256164Z","caller":"traceutil/trace.go:171","msg":"trace[1592204] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26598; }","duration":"209.427504ms","start":"2026-09-12T05:02:18.046718Z","end":"2026-09-12T05:02:18.256145Z","steps":["trace[1592204] 'agreement among raft nodes before linearized reading' (duration: 207.89583ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:18.466463Z","caller":"traceutil/trace.go:171","msg":"trace[1615120508] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"189.706153ms","start":"2026-09-12T05:02:18.276734Z","end":"2026-09-12T05:02:18.46644Z","steps":["trace[1615120508] 'process raft request' (duration: 189.54634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:19.890397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.711118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7640f4b2-3e1c-4e8a-90a9-09314df5f8c6\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-09-12T05:02:19.890507Z","caller":"traceutil/trace.go:171","msg":"trace[47087799] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7640f4b2-3e1c-4e8a-90a9-09314df5f8c6; range_end:; response_count:1; response_revision:26664; }","duration":"169.86492ms","start":"2026-09-12T05:02:19.720615Z","end":"2026-09-12T05:02:19.89048Z","steps":["trace[47087799] 'range keys from in-memory index tree' (duration: 169.511962ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:22.059913Z","caller":"traceutil/trace.go:171","msg":"trace[327198605] transaction","detail":"{read_only:false; response_revision:26682; number_of_response:1; }","duration":"107.269447ms","start":"2026-09-12T05:02:21.952616Z","end":"2026-09-12T05:02:22.059885Z","steps":["trace[327198605] 'process raft request' (duration: 95.037956ms)","trace[327198605] 'compare' (duration: 12.083287ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:26.392366Z","caller":"traceutil/trace.go:171","msg":"trace[346718158] transaction","detail":"{read_only:false; response_revision:26737; number_of_response:1; }","duration":"108.188136ms","start":"2026-09-12T05:02:26.284141Z","end":"2026-09-12T05:02:26.392329Z","steps":["trace[346718158] 'process raft request' (duration: 107.565643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:26.841726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.551665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:26.841828Z","caller":"traceutil/trace.go:171","msg":"trace[99533833] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:26737; }","duration":"354.71848ms","start":"2026-09-12T05:02:26.487088Z","end":"2026-09-12T05:02:26.841806Z","steps":["trace[99533833] 'count revisions from in-memory index tree' (duration: 354.456274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:26.841874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:26.487062Z","time spent":"354.798572ms","remote":"127.0.0.1:49594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-09-12T05:02:26.84243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:26.842516Z","caller":"traceutil/trace.go:171","msg":"trace[387559208] linearizableReadLoop","detail":"{readStateIndex:27626; appliedIndex:27625; }","duration":"270.910574ms","start":"2026-09-12T05:02:26.571591Z","end":"2026-09-12T05:02:26.842501Z","steps":["trace[387559208] 'read index received' (duration: 110.67838ms)","trace[387559208] 'applied index is now lower than readState.Index' (duration: 160.231044ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:26.842609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.015586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:26.842577Z","caller":"traceutil/trace.go:171","msg":"trace[228728864] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"348.548929ms","start":"2026-09-12T05:02:26.493984Z","end":"2026-09-12T05:02:26.842533Z","steps":["trace[228728864] 'process raft request' (duration: 188.339465ms)","trace[228728864] 'compare' (duration: 159.211382ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:26.842642Z","caller":"traceutil/trace.go:171","msg":"trace[931840099] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26738; }","duration":"271.052897ms","start":"2026-09-12T05:02:26.57158Z","end":"2026-09-12T05:02:26.842633Z","steps":["trace[931840099] 'agreement among raft nodes before linearized reading' (duration: 270.987886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:26.842838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T05:02:26.493967Z","time spent":"348.691172ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T05:02:31.683834Z","caller":"traceutil/trace.go:171","msg":"trace[314520510] transaction","detail":"{read_only:false; response_revision:26778; number_of_response:1; }","duration":"137.691704ms","start":"2026-09-12T05:02:31.546095Z","end":"2026-09-12T05:02:31.683786Z","steps":["trace[314520510] 'process raft request' (duration: 73.514026ms)","trace[314520510] 'compare' (duration: 64.055906ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:51.806863Z","caller":"traceutil/trace.go:171","msg":"trace[835991055] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"147.758108ms","start":"2026-09-12T05:02:51.659072Z","end":"2026-09-12T05:02:51.80683Z","steps":["trace[835991055] 'process raft request' (duration: 83.024308ms)","trace[835991055] 'compare' (duration: 64.532386ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:51.880949Z","caller":"traceutil/trace.go:171","msg":"trace[1522973171] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"131.8192ms","start":"2026-09-12T05:02:51.749106Z","end":"2026-09-12T05:02:51.880925Z","steps":["trace[1522973171] 'process raft request' (duration: 131.629136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:52.512447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.08048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.129\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-09-12T05:02:52.512495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.735553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-12T05:02:52.512495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.133272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-12T05:02:52.512559Z","caller":"traceutil/trace.go:171","msg":"trace[243917276] range","detail":"{range_begin:/registry/masterleases/162.253.55.129; range_end:; response_count:1; response_revision:26946; }","duration":"167.254474ms","start":"2026-09-12T05:02:52.345276Z","end":"2026-09-12T05:02:52.512531Z","steps":["trace[243917276] 'range keys from in-memory index tree' (duration: 166.869257ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:52.51257Z","caller":"traceutil/trace.go:171","msg":"trace[314013506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26946; }","duration":"250.847426ms","start":"2026-09-12T05:02:52.261704Z","end":"2026-09-12T05:02:52.512552Z","steps":["trace[314013506] 'range keys from in-memory index tree' (duration: 250.60204ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:52.51257Z","caller":"traceutil/trace.go:171","msg":"trace[978479146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26946; }","duration":"251.237585ms","start":"2026-09-12T05:02:52.261317Z","end":"2026-09-12T05:02:52.512555Z","steps":["trace[978479146] 'range keys from in-memory index tree' (duration: 251.005039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:52.512488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.086441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-12T05:02:52.512647Z","caller":"traceutil/trace.go:171","msg":"trace[2055238098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26946; }","duration":"259.268424ms","start":"2026-09-12T05:02:52.253361Z","end":"2026-09-12T05:02:52.51263Z","steps":["trace[2055238098] 'range keys from in-memory index tree' (duration: 258.972678ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:52.756676Z","caller":"traceutil/trace.go:171","msg":"trace[1639158263] transaction","detail":"{read_only:false; response_revision:26949; number_of_response:1; }","duration":"240.356352ms","start":"2026-09-12T05:02:52.51629Z","end":"2026-09-12T05:02:52.756647Z","steps":["trace[1639158263] 'process raft request' (duration: 240.209959ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:52.756824Z","caller":"traceutil/trace.go:171","msg":"trace[509059853] transaction","detail":"{read_only:false; response_revision:26951; number_of_response:1; }","duration":"238.963323ms","start":"2026-09-12T05:02:52.517834Z","end":"2026-09-12T05:02:52.756797Z","steps":["trace[509059853] 'process raft request' (duration: 238.883691ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:52.756987Z","caller":"traceutil/trace.go:171","msg":"trace[1655287540] transaction","detail":"{read_only:false; response_revision:26948; number_of_response:1; }","duration":"240.966106ms","start":"2026-09-12T05:02:52.515967Z","end":"2026-09-12T05:02:52.756933Z","steps":["trace[1655287540] 'process raft request' (duration: 164.489836ms)","trace[1655287540] 'compare' (duration: 75.684303ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:52.757182Z","caller":"traceutil/trace.go:171","msg":"trace[1539935251] transaction","detail":"{read_only:false; response_revision:26950; number_of_response:1; }","duration":"240.200389ms","start":"2026-09-12T05:02:52.516967Z","end":"2026-09-12T05:02:52.757167Z","steps":["trace[1539935251] 'process raft request' (duration: 239.639297ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:53.266151Z","caller":"traceutil/trace.go:171","msg":"trace[424236942] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"195.591469ms","start":"2026-09-12T05:02:53.070535Z","end":"2026-09-12T05:02:53.266127Z","steps":["trace[424236942] 'process raft request' (duration: 195.392904ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:53.266427Z","caller":"traceutil/trace.go:171","msg":"trace[1774628066] transaction","detail":"{read_only:false; response_revision:26954; number_of_response:1; }","duration":"193.967554ms","start":"2026-09-12T05:02:53.072448Z","end":"2026-09-12T05:02:53.266415Z","steps":["trace[1774628066] 'process raft request' (duration: 193.640317ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:53.26655Z","caller":"traceutil/trace.go:171","msg":"trace[454703498] linearizableReadLoop","detail":"{readStateIndex:27850; appliedIndex:27847; }","duration":"177.86522ms","start":"2026-09-12T05:02:53.088665Z","end":"2026-09-12T05:02:53.26653Z","steps":["trace[454703498] 'read index received' (duration: 177.341049ms)","trace[454703498] 'applied index is now lower than readState.Index' (duration: 523.001µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:53.266649Z","caller":"traceutil/trace.go:171","msg":"trace[1211393119] transaction","detail":"{read_only:false; response_revision:26955; number_of_response:1; }","duration":"192.634844ms","start":"2026-09-12T05:02:53.073999Z","end":"2026-09-12T05:02:53.266634Z","steps":["trace[1211393119] 'process raft request' (duration: 192.363769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:53.266676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.995242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:53.266724Z","caller":"traceutil/trace.go:171","msg":"trace[61116773] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26955; }","duration":"178.088445ms","start":"2026-09-12T05:02:53.088619Z","end":"2026-09-12T05:02:53.266708Z","steps":["trace[61116773] 'agreement among raft nodes before linearized reading' (duration: 177.994953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:53.730967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.03053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:53.731047Z","caller":"traceutil/trace.go:171","msg":"trace[1380848193] linearizableReadLoop","detail":"{readStateIndex:27852; appliedIndex:27851; }","duration":"101.031602ms","start":"2026-09-12T05:02:53.630004Z","end":"2026-09-12T05:02:53.731036Z","steps":["trace[1380848193] 'read index received' (duration: 45.511µs)","trace[1380848193] 'applied index is now lower than readState.Index' (duration: 100.985121ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:53.731098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.091475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:02:53.731113Z","caller":"traceutil/trace.go:171","msg":"trace[666220552] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:26957; }","duration":"101.111005ms","start":"2026-09-12T05:02:53.629997Z","end":"2026-09-12T05:02:53.731108Z","steps":["trace[666220552] 'agreement among raft nodes before linearized reading' (duration: 101.068314ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:53.731094Z","caller":"traceutil/trace.go:171","msg":"trace[832481821] transaction","detail":"{read_only:false; response_revision:26957; number_of_response:1; }","duration":"198.182253ms","start":"2026-09-12T05:02:53.532891Z","end":"2026-09-12T05:02:53.731074Z","steps":["trace[832481821] 'process raft request' (duration: 37.965889ms)","trace[832481821] 'compare' (duration: 159.597041ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:02:54.016856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.642455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:02:54.016953Z","caller":"traceutil/trace.go:171","msg":"trace[714385070] transaction","detail":"{read_only:false; response_revision:26958; number_of_response:1; }","duration":"204.280194ms","start":"2026-09-12T05:02:53.812657Z","end":"2026-09-12T05:02:54.016937Z","steps":["trace[714385070] 'process raft request' (duration: 95.475605ms)","trace[714385070] 'compare' (duration: 108.451031ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:54.033964Z","caller":"traceutil/trace.go:171","msg":"trace[604570541] linearizableReadLoop","detail":"{readStateIndex:27854; appliedIndex:27852; }","duration":"131.117685ms","start":"2026-09-12T05:02:53.902831Z","end":"2026-09-12T05:02:54.033949Z","steps":["trace[604570541] 'read index received' (duration: 5.252423ms)","trace[604570541] 'applied index is now lower than readState.Index' (duration: 125.864692ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:02:54.034017Z","caller":"traceutil/trace.go:171","msg":"trace[1015422686] transaction","detail":"{read_only:false; response_revision:26960; number_of_response:1; }","duration":"130.504821ms","start":"2026-09-12T05:02:53.903487Z","end":"2026-09-12T05:02:54.033992Z","steps":["trace[1015422686] 'process raft request' (duration: 130.43939ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:54.034021Z","caller":"traceutil/trace.go:171","msg":"trace[1426158869] transaction","detail":"{read_only:false; response_revision:26959; number_of_response:1; }","duration":"148.142598ms","start":"2026-09-12T05:02:53.885839Z","end":"2026-09-12T05:02:54.033982Z","steps":["trace[1426158869] 'process raft request' (duration: 148.016415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:54.034154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.800013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-12T05:02:54.03418Z","caller":"traceutil/trace.go:171","msg":"trace[1460882724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26960; }","duration":"127.856774ms","start":"2026-09-12T05:02:53.906314Z","end":"2026-09-12T05:02:54.034171Z","steps":["trace[1460882724] 'agreement among raft nodes before linearized reading' (duration: 127.722241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:02:54.034158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.302529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-12T05:02:54.034186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.84756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-12T05:02:54.034259Z","caller":"traceutil/trace.go:171","msg":"trace[2050011130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26960; }","duration":"131.456672ms","start":"2026-09-12T05:02:53.902781Z","end":"2026-09-12T05:02:54.034238Z","steps":["trace[2050011130] 'agreement among raft nodes before linearized reading' (duration: 131.264348ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:54.034269Z","caller":"traceutil/trace.go:171","msg":"trace[697544962] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:26960; }","duration":"124.959562ms","start":"2026-09-12T05:02:53.909292Z","end":"2026-09-12T05:02:54.034251Z","steps":["trace[697544962] 'agreement among raft nodes before linearized reading' (duration: 124.82748ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:54.927055Z","caller":"traceutil/trace.go:171","msg":"trace[1152329652] transaction","detail":"{read_only:false; response_revision:26965; number_of_response:1; }","duration":"160.939859ms","start":"2026-09-12T05:02:54.766091Z","end":"2026-09-12T05:02:54.927031Z","steps":["trace[1152329652] 'process raft request' (duration: 159.626722ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:02:54.927314Z","caller":"traceutil/trace.go:171","msg":"trace[1002693257] transaction","detail":"{read_only:false; response_revision:26966; number_of_response:1; }","duration":"161.125644ms","start":"2026-09-12T05:02:54.766174Z","end":"2026-09-12T05:02:54.9273Z","steps":["trace[1002693257] 'process raft request' (duration: 160.766116ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:00.440412Z","caller":"traceutil/trace.go:171","msg":"trace[1478400935] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"146.334128ms","start":"2026-09-12T05:03:00.294054Z","end":"2026-09-12T05:03:00.440389Z","steps":["trace[1478400935] 'process raft request' (duration: 146.139694ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:05.882923Z","caller":"traceutil/trace.go:171","msg":"trace[837545481] transaction","detail":"{read_only:false; response_revision:27045; number_of_response:1; }","duration":"144.299845ms","start":"2026-09-12T05:03:05.738597Z","end":"2026-09-12T05:03:05.882897Z","steps":["trace[837545481] 'process raft request' (duration: 144.134722ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:06.238984Z","caller":"traceutil/trace.go:171","msg":"trace[1677271118] transaction","detail":"{read_only:false; response_revision:27046; number_of_response:1; }","duration":"182.790455ms","start":"2026-09-12T05:03:06.056168Z","end":"2026-09-12T05:03:06.238959Z","steps":["trace[1677271118] 'process raft request' (duration: 182.637132ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:06.356019Z","caller":"traceutil/trace.go:171","msg":"trace[316762793] linearizableReadLoop","detail":"{readStateIndex:27944; appliedIndex:27943; }","duration":"195.312073ms","start":"2026-09-12T05:03:06.160674Z","end":"2026-09-12T05:03:06.355986Z","steps":["trace[316762793] 'read index received' (duration: 78.426601ms)","trace[316762793] 'applied index is now lower than readState.Index' (duration: 116.884092ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:06.356049Z","caller":"traceutil/trace.go:171","msg":"trace[1277434465] transaction","detail":"{read_only:false; response_revision:27047; number_of_response:1; }","duration":"283.857409ms","start":"2026-09-12T05:03:06.072153Z","end":"2026-09-12T05:03:06.35601Z","steps":["trace[1277434465] 'process raft request' (duration: 223.89052ms)","trace[1277434465] 'compare' (duration: 59.435018ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:03:06.356329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.647499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-12T05:03:06.356389Z","caller":"traceutil/trace.go:171","msg":"trace[964950963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27047; }","duration":"195.772072ms","start":"2026-09-12T05:03:06.160596Z","end":"2026-09-12T05:03:06.356368Z","steps":["trace[964950963] 'agreement among raft nodes before linearized reading' (duration: 195.506136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:06.653154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.936915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-12T05:03:06.653296Z","caller":"traceutil/trace.go:171","msg":"trace[1372486618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27048; }","duration":"188.103749ms","start":"2026-09-12T05:03:06.465166Z","end":"2026-09-12T05:03:06.653269Z","steps":["trace[1372486618] 'range keys from in-memory index tree' (duration: 187.755532ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:06.870088Z","caller":"traceutil/trace.go:171","msg":"trace[5495331] linearizableReadLoop","detail":"{readStateIndex:27947; appliedIndex:27946; }","duration":"210.906594ms","start":"2026-09-12T05:03:06.659156Z","end":"2026-09-12T05:03:06.870062Z","steps":["trace[5495331] 'read index received' (duration: 133.907223ms)","trace[5495331] 'applied index is now lower than readState.Index' (duration: 76.996241ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:06.87011Z","caller":"traceutil/trace.go:171","msg":"trace[836267540] transaction","detail":"{read_only:false; response_revision:27050; number_of_response:1; }","duration":"211.535677ms","start":"2026-09-12T05:03:06.658543Z","end":"2026-09-12T05:03:06.870079Z","steps":["trace[836267540] 'process raft request' (duration: 134.527196ms)","trace[836267540] 'compare' (duration: 76.879518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:03:06.87038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.20317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-12T05:03:06.870426Z","caller":"traceutil/trace.go:171","msg":"trace[824780848] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27050; }","duration":"211.288182ms","start":"2026-09-12T05:03:06.659125Z","end":"2026-09-12T05:03:06.870414Z","steps":["trace[824780848] 'agreement among raft nodes before linearized reading' (duration: 211.101358ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:11.068668Z","caller":"traceutil/trace.go:171","msg":"trace[1409652717] transaction","detail":"{read_only:false; response_revision:27081; number_of_response:1; }","duration":"156.161508ms","start":"2026-09-12T05:03:10.912449Z","end":"2026-09-12T05:03:11.068611Z","steps":["trace[1409652717] 'process raft request' (duration: 82.641012ms)","trace[1409652717] 'compare' (duration: 73.354663ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:11.310093Z","caller":"traceutil/trace.go:171","msg":"trace[61544715] transaction","detail":"{read_only:false; response_revision:27084; number_of_response:1; }","duration":"117.76625ms","start":"2026-09-12T05:03:11.192293Z","end":"2026-09-12T05:03:11.310059Z","steps":["trace[61544715] 'process raft request' (duration: 117.539815ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:13.305434Z","caller":"traceutil/trace.go:171","msg":"trace[1458098176] transaction","detail":"{read_only:false; response_revision:27097; number_of_response:1; }","duration":"148.67458ms","start":"2026-09-12T05:03:13.156741Z","end":"2026-09-12T05:03:13.305416Z","steps":["trace[1458098176] 'process raft request' (duration: 56.203229ms)","trace[1458098176] 'compare' (duration: 92.228314ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:13.305673Z","caller":"traceutil/trace.go:171","msg":"trace[1075811472] transaction","detail":"{read_only:false; response_revision:27098; number_of_response:1; }","duration":"148.28722ms","start":"2026-09-12T05:03:13.157362Z","end":"2026-09-12T05:03:13.305649Z","steps":["trace[1075811472] 'process raft request' (duration: 147.982304ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:13.54257Z","caller":"traceutil/trace.go:171","msg":"trace[670795765] linearizableReadLoop","detail":"{readStateIndex:27999; appliedIndex:27998; }","duration":"140.241578ms","start":"2026-09-12T05:03:13.402301Z","end":"2026-09-12T05:03:13.542542Z","steps":["trace[670795765] 'read index received' (duration: 64.374352ms)","trace[670795765] 'applied index is now lower than readState.Index' (duration: 75.865956ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:13.542654Z","caller":"traceutil/trace.go:171","msg":"trace[1879761729] transaction","detail":"{read_only:false; response_revision:27100; number_of_response:1; }","duration":"143.073959ms","start":"2026-09-12T05:03:13.399539Z","end":"2026-09-12T05:03:13.542613Z","steps":["trace[1879761729] 'process raft request' (duration: 67.11715ms)","trace[1879761729] 'compare' (duration: 75.669113ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:03:13.542724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.393142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:03:13.542859Z","caller":"traceutil/trace.go:171","msg":"trace[1300155442] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:27100; }","duration":"140.562675ms","start":"2026-09-12T05:03:13.40227Z","end":"2026-09-12T05:03:13.542832Z","steps":["trace[1300155442] 'agreement among raft nodes before linearized reading' (duration: 140.372251ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:13.62322Z","caller":"traceutil/trace.go:171","msg":"trace[1504475363] transaction","detail":"{read_only:false; response_revision:27102; number_of_response:1; }","duration":"193.425062ms","start":"2026-09-12T05:03:13.429738Z","end":"2026-09-12T05:03:13.623163Z","steps":["trace[1504475363] 'process raft request' (duration: 193.197487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:13.623297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.009373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:03:13.62337Z","caller":"traceutil/trace.go:171","msg":"trace[1578014170] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:27102; }","duration":"192.122845ms","start":"2026-09-12T05:03:13.431228Z","end":"2026-09-12T05:03:13.623351Z","steps":["trace[1578014170] 'agreement among raft nodes before linearized reading' (duration: 192.007232ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:13.62376Z","caller":"traceutil/trace.go:171","msg":"trace[616694226] transaction","detail":"{read_only:false; response_revision:27101; number_of_response:1; }","duration":"194.808791ms","start":"2026-09-12T05:03:13.428926Z","end":"2026-09-12T05:03:13.623735Z","steps":["trace[616694226] 'process raft request' (duration: 193.836831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:13.7828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.971794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-12T05:03:13.782909Z","caller":"traceutil/trace.go:171","msg":"trace[1633176181] linearizableReadLoop","detail":"{readStateIndex:28002; appliedIndex:28001; }","duration":"159.908078ms","start":"2026-09-12T05:03:13.622987Z","end":"2026-09-12T05:03:13.782895Z","steps":["trace[1633176181] 'read index received' (duration: 26.720739ms)","trace[1633176181] 'applied index is now lower than readState.Index' (duration: 133.186279ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:03:13.782974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.012387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:03:13.782999Z","caller":"traceutil/trace.go:171","msg":"trace[1795551896] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:27103; }","duration":"234.075219ms","start":"2026-09-12T05:03:13.548914Z","end":"2026-09-12T05:03:13.782989Z","steps":["trace[1795551896] 'agreement among raft nodes before linearized reading' (duration: 234.022208ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:13.783121Z","caller":"traceutil/trace.go:171","msg":"trace[892164201] transaction","detail":"{read_only:false; response_revision:27103; number_of_response:1; }","duration":"198.764676ms","start":"2026-09-12T05:03:13.584334Z","end":"2026-09-12T05:03:13.783099Z","steps":["trace[892164201] 'process raft request' (duration: 65.422464ms)","trace[892164201] 'compare' (duration: 132.870232ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:14.497529Z","caller":"traceutil/trace.go:171","msg":"trace[408176929] transaction","detail":"{read_only:false; response_revision:27106; number_of_response:1; }","duration":"115.104633ms","start":"2026-09-12T05:03:14.382379Z","end":"2026-09-12T05:03:14.497484Z","steps":["trace[408176929] 'process raft request' (duration: 114.849537ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:15.525034Z","caller":"traceutil/trace.go:171","msg":"trace[1298292018] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"125.811862ms","start":"2026-09-12T05:03:15.399183Z","end":"2026-09-12T05:03:15.524995Z","steps":["trace[1298292018] 'process raft request' (duration: 125.513066ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:20.829931Z","caller":"traceutil/trace.go:171","msg":"trace[1739052346] transaction","detail":"{read_only:false; response_revision:27158; number_of_response:1; }","duration":"107.383039ms","start":"2026-09-12T05:03:20.72251Z","end":"2026-09-12T05:03:20.829893Z","steps":["trace[1739052346] 'process raft request' (duration: 107.122323ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:21.019837Z","caller":"traceutil/trace.go:171","msg":"trace[331888090] linearizableReadLoop","detail":"{readStateIndex:28059; appliedIndex:28058; }","duration":"132.653157ms","start":"2026-09-12T05:03:20.887157Z","end":"2026-09-12T05:03:21.01981Z","steps":["trace[331888090] 'read index received' (duration: 132.388871ms)","trace[331888090] 'applied index is now lower than readState.Index' (duration: 263.146µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:21.019999Z","caller":"traceutil/trace.go:171","msg":"trace[897689394] transaction","detail":"{read_only:false; response_revision:27159; number_of_response:1; }","duration":"160.643553ms","start":"2026-09-12T05:03:20.85934Z","end":"2026-09-12T05:03:21.019984Z","steps":["trace[897689394] 'process raft request' (duration: 160.275026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:21.020286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.8491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-12T05:03:21.020364Z","caller":"traceutil/trace.go:171","msg":"trace[1272491008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27159; }","duration":"116.975543ms","start":"2026-09-12T05:03:20.903371Z","end":"2026-09-12T05:03:21.020346Z","steps":["trace[1272491008] 'agreement among raft nodes before linearized reading' (duration: 116.809449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:21.020717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.546806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-12T05:03:21.020875Z","caller":"traceutil/trace.go:171","msg":"trace[49811977] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:27159; }","duration":"133.757051ms","start":"2026-09-12T05:03:20.8871Z","end":"2026-09-12T05:03:21.020857Z","steps":["trace[49811977] 'agreement among raft nodes before linearized reading' (duration: 133.353772ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:21.278905Z","caller":"traceutil/trace.go:171","msg":"trace[1927154173] transaction","detail":"{read_only:false; response_revision:27161; number_of_response:1; }","duration":"252.171824ms","start":"2026-09-12T05:03:21.0267Z","end":"2026-09-12T05:03:21.278872Z","steps":["trace[1927154173] 'process raft request' (duration: 251.927819ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:21.279229Z","caller":"traceutil/trace.go:171","msg":"trace[1017853393] transaction","detail":"{read_only:false; response_revision:27163; number_of_response:1; }","duration":"251.165092ms","start":"2026-09-12T05:03:21.027998Z","end":"2026-09-12T05:03:21.279163Z","steps":["trace[1017853393] 'process raft request' (duration: 250.993669ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:21.279269Z","caller":"traceutil/trace.go:171","msg":"trace[215191111] transaction","detail":"{read_only:false; response_revision:27162; number_of_response:1; }","duration":"251.614722ms","start":"2026-09-12T05:03:21.027633Z","end":"2026-09-12T05:03:21.279248Z","steps":["trace[215191111] 'process raft request' (duration: 251.154933ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:21.279136Z","caller":"traceutil/trace.go:171","msg":"trace[715885946] linearizableReadLoop","detail":"{readStateIndex:28063; appliedIndex:28059; }","duration":"234.16625ms","start":"2026-09-12T05:03:21.044945Z","end":"2026-09-12T05:03:21.279111Z","steps":["trace[715885946] 'read index received' (duration: 42.960396ms)","trace[715885946] 'applied index is now lower than readState.Index' (duration: 191.204794ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T05:03:21.279439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.478648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-12T05:03:21.279521Z","caller":"traceutil/trace.go:171","msg":"trace[660510680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27163; }","duration":"234.60481ms","start":"2026-09-12T05:03:21.044898Z","end":"2026-09-12T05:03:21.279503Z","steps":["trace[660510680] 'agreement among raft nodes before linearized reading' (duration: 234.397365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:21.279538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.000657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-09-12T05:03:21.279585Z","caller":"traceutil/trace.go:171","msg":"trace[1928102375] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:27163; }","duration":"143.091899ms","start":"2026-09-12T05:03:21.136478Z","end":"2026-09-12T05:03:21.27957Z","steps":["trace[1928102375] 'agreement among raft nodes before linearized reading' (duration: 142.971026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:21.279569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.838299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19030"} {"level":"info","ts":"2026-09-12T05:03:21.279682Z","caller":"traceutil/trace.go:171","msg":"trace[2096457812] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:27163; }","duration":"193.023873ms","start":"2026-09-12T05:03:21.086645Z","end":"2026-09-12T05:03:21.279668Z","steps":["trace[2096457812] 'agreement among raft nodes before linearized reading' (duration: 192.745097ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:21.4153Z","caller":"traceutil/trace.go:171","msg":"trace[294255105] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"130.172315ms","start":"2026-09-12T05:03:21.285092Z","end":"2026-09-12T05:03:21.415264Z","steps":["trace[294255105] 'process raft request' (duration: 105.124821ms)","trace[294255105] 'compare' (duration: 24.733156ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:21.466997Z","caller":"traceutil/trace.go:171","msg":"trace[41934239] transaction","detail":"{read_only:false; response_revision:27165; number_of_response:1; }","duration":"181.841685ms","start":"2026-09-12T05:03:21.285128Z","end":"2026-09-12T05:03:21.46697Z","steps":["trace[41934239] 'process raft request' (duration: 181.634661ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:21.467277Z","caller":"traceutil/trace.go:171","msg":"trace[1525031420] linearizableReadLoop","detail":"{readStateIndex:28065; appliedIndex:28063; }","duration":"181.937898ms","start":"2026-09-12T05:03:21.285305Z","end":"2026-09-12T05:03:21.467243Z","steps":["trace[1525031420] 'read index received' (duration: 104.855365ms)","trace[1525031420] 'applied index is now lower than readState.Index' (duration: 77.079503ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T05:03:21.46731Z","caller":"traceutil/trace.go:171","msg":"trace[618122125] transaction","detail":"{read_only:false; response_revision:27166; number_of_response:1; }","duration":"178.894623ms","start":"2026-09-12T05:03:21.288398Z","end":"2026-09-12T05:03:21.467293Z","steps":["trace[618122125] 'process raft request' (duration: 178.509975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T05:03:21.467465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.146272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-09-12T05:03:21.467499Z","caller":"traceutil/trace.go:171","msg":"trace[756176971] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:27166; }","duration":"182.214663ms","start":"2026-09-12T05:03:21.285274Z","end":"2026-09-12T05:03:21.467489Z","steps":["trace[756176971] 'agreement among raft nodes before linearized reading' (duration: 182.07237ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T05:03:49.289892Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24497} {"level":"info","ts":"2026-09-12T05:03:49.447402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24497,"took":"153.41974ms","hash":2868671172,"current-db-size-bytes":56836096,"current-db-size":"57 MB","current-db-size-in-use-bytes":37240832,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-12T05:03:49.447469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2868671172,"revision":24497,"compact-revision":21560} {"level":"info","ts":"2026-09-12T05:06:24.516174Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d232699cb6c87299","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T05:06:24.523086Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-12T05:06:24.523177Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-12T05:08:49.299059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27537} {"level":"info","ts":"2026-09-12T05:08:49.458989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27537,"took":"154.659247ms","hash":1596156766,"current-db-size-bytes":56836096,"current-db-size":"57 MB","current-db-size-in-use-bytes":35160064,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-12T05:08:49.459076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1596156766,"revision":27537,"compact-revision":24497} {"level":"info","ts":"2026-09-12T05:13:49.307025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30286} {"level":"info","ts":"2026-09-12T05:13:49.461607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30286,"took":"150.541419ms","hash":3794213992,"current-db-size-bytes":56836096,"current-db-size":"57 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-12T05:13:49.46168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3794213992,"revision":30286,"compact-revision":27537} {"level":"info","ts":"2026-09-12T05:18:49.321806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32541} {"level":"info","ts":"2026-09-12T05:18:49.483406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32541,"took":"158.065889ms","hash":1535436734,"current-db-size-bytes":57880576,"current-db-size":"58 MB","current-db-size-in-use-bytes":30187520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-12T05:18:49.483553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1535436734,"revision":32541,"compact-revision":30286} {"level":"info","ts":"2026-09-12T05:23:49.357214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34765} {"level":"info","ts":"2026-09-12T05:23:49.536731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34765,"took":"175.057572ms","hash":2864406019,"current-db-size-bytes":57880576,"current-db-size":"58 MB","current-db-size-in-use-bytes":30756864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-12T05:23:49.53688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2864406019,"revision":34765,"compact-revision":32541} {"level":"info","ts":"2026-09-12T05:27:33.845188Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d232699cb6c87299","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-12T05:27:33.852342Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-12T05:27:33.852458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-12T05:28:49.371659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37061} {"level":"info","ts":"2026-09-12T05:28:49.505795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37061,"took":"131.162976ms","hash":2419655144,"current-db-size-bytes":60010496,"current-db-size":"60 MB","current-db-size-in-use-bytes":32616448,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-12T05:28:49.505871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2419655144,"revision":37061,"compact-revision":34765} {"level":"info","ts":"2026-09-12T05:33:49.381276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39333} {"level":"info","ts":"2026-09-12T05:33:49.518738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39333,"took":"131.79832ms","hash":905483319,"current-db-size-bytes":61288448,"current-db-size":"61 MB","current-db-size-in-use-bytes":31248384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-12T05:33:49.518798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":905483319,"revision":39333,"compact-revision":37061}