{"level":"warn","ts":"2026-03-04T01:24:13.364284Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T01:24:13.364384Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-04T01:24:13.364452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T01:24:13.364465Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-04T01:24:13.364484Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-04T01:24:13.365087Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-03-04T01:24:13.365163Z","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://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-04T01:24:13.368453Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.087912ms"} {"level":"info","ts":"2026-03-04T01:24:13.373809Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-03-04T01:24:13.373888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T01:24:13.373917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-03-04T01:24:13.37393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T01:24:13.373945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-03-04T01:24:13.373979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-03-04T01:24:13.382139Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T01:24:13.383692Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T01:24:13.385925Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-04T01:24:13.389077Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T01:24:13.389193Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T01:24:13.389358Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-04T01:24:13.389418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-04T01:24:13.389434Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-04T01:24:13.389962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-03-04T01:24:13.390091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-04T01:24:13.395027Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-04T01:24:13.395186Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-04T01:24:13.395271Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-04T01:24:13.395434Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T01:24:13.395495Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T01:24:13.775049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T01:24:13.775117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T01:24:13.775153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-03-04T01:24:13.775199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-03-04T01:24:13.775267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-04T01:24:13.775338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-03-04T01:24:13.775369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-04T01:24:13.776583Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:24:13.777485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T01:24:13.777483Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T01:24:13.777528Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T01:24:13.778025Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T01:24:13.778127Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T01:24:13.779708Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:24:13.779889Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:24:13.779972Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:24:13.781055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T01:24:13.781076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-03-04T01:24:29.791898Z","caller":"traceutil/trace.go:171","msg":"trace[848422848] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"240.587258ms","start":"2026-03-04T01:24:29.551274Z","end":"2026-03-04T01:24:29.791861Z","steps":["trace[848422848] 'read index received' (duration: 199.574692ms)","trace[848422848] 'applied index is now lower than readState.Index' (duration: 41.010896ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:29.791994Z","caller":"traceutil/trace.go:171","msg":"trace[531208004] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"428.899872ms","start":"2026-03-04T01:24:29.363048Z","end":"2026-03-04T01:24:29.791947Z","steps":["trace[531208004] 'process raft request' (duration: 387.766333ms)","trace[531208004] 'compare' (duration: 40.915304ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:24:29.792188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.896665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:24:29.792724Z","caller":"traceutil/trace.go:171","msg":"trace[1652796478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:439; }","duration":"241.485542ms","start":"2026-03-04T01:24:29.551217Z","end":"2026-03-04T01:24:29.792702Z","steps":["trace[1652796478] 'agreement among raft nodes before linearized reading' (duration: 240.879696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:29.793504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:29.363033Z","time spent":"429.544609ms","remote":"127.0.0.1:43362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:24:35.249534Z","caller":"traceutil/trace.go:171","msg":"trace[506925680] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"141.311659ms","start":"2026-03-04T01:24:35.10817Z","end":"2026-03-04T01:24:35.249482Z","steps":["trace[506925680] 'process raft request' (duration: 141.114624ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:45.495112Z","caller":"traceutil/trace.go:171","msg":"trace[615694177] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"152.690791ms","start":"2026-03-04T01:24:45.342387Z","end":"2026-03-04T01:24:45.495078Z","steps":["trace[615694177] 'read index received' (duration: 152.406434ms)","trace[615694177] 'applied index is now lower than readState.Index' (duration: 282.857µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:45.495234Z","caller":"traceutil/trace.go:171","msg":"trace[1891170192] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"170.05619ms","start":"2026-03-04T01:24:45.325135Z","end":"2026-03-04T01:24:45.495191Z","steps":["trace[1891170192] 'process raft request' (duration: 169.749753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:45.495435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.005919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:24:45.495503Z","caller":"traceutil/trace.go:171","msg":"trace[1501185520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"153.124443ms","start":"2026-03-04T01:24:45.34236Z","end":"2026-03-04T01:24:45.495484Z","steps":["trace[1501185520] 'agreement among raft nodes before linearized reading' (duration: 152.861275ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:45.863266Z","caller":"traceutil/trace.go:171","msg":"trace[1563738613] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"362.593317ms","start":"2026-03-04T01:24:45.500651Z","end":"2026-03-04T01:24:45.863244Z","steps":["trace[1563738613] 'process raft request' (duration: 348.331768ms)","trace[1563738613] 'compare' (duration: 14.172686ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:24:45.863391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:45.500633Z","time spent":"362.69299ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:24:46.522568Z","caller":"traceutil/trace.go:171","msg":"trace[1816699022] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"231.22299ms","start":"2026-03-04T01:24:46.291298Z","end":"2026-03-04T01:24:46.522521Z","steps":["trace[1816699022] 'process raft request' (duration: 229.613947ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:46.522631Z","caller":"traceutil/trace.go:171","msg":"trace[935855027] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"231.21862ms","start":"2026-03-04T01:24:46.291385Z","end":"2026-03-04T01:24:46.522604Z","steps":["trace[935855027] 'process raft request' (duration: 231.086696ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:46.522766Z","caller":"traceutil/trace.go:171","msg":"trace[840271068] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:550; }","duration":"178.34016ms","start":"2026-03-04T01:24:46.344412Z","end":"2026-03-04T01:24:46.522752Z","steps":["trace[840271068] 'read index received' (duration: 7.127508ms)","trace[840271068] 'applied index is now lower than readState.Index' (duration: 171.211612ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:24:46.522913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.497805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-03-04T01:24:46.522956Z","caller":"traceutil/trace.go:171","msg":"trace[1335184665] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:537; }","duration":"178.611897ms","start":"2026-03-04T01:24:46.344328Z","end":"2026-03-04T01:24:46.522939Z","steps":["trace[1335184665] 'agreement among raft nodes before linearized reading' (duration: 178.466123ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:46.52298Z","caller":"traceutil/trace.go:171","msg":"trace[840757156] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"229.500745ms","start":"2026-03-04T01:24:46.293401Z","end":"2026-03-04T01:24:46.522902Z","steps":["trace[840757156] 'process raft request' (duration: 229.153636ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:49.764585Z","caller":"traceutil/trace.go:171","msg":"trace[1627826247] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"253.291703ms","start":"2026-03-04T01:24:49.511259Z","end":"2026-03-04T01:24:49.76455Z","steps":["trace[1627826247] 'process raft request' (duration: 252.990135ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:50.252783Z","caller":"traceutil/trace.go:171","msg":"trace[1246306712] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"375.860518ms","start":"2026-03-04T01:24:49.876902Z","end":"2026-03-04T01:24:50.252763Z","steps":["trace[1246306712] 'read index received' (duration: 375.667162ms)","trace[1246306712] 'applied index is now lower than readState.Index' (duration: 192.716µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:50.252857Z","caller":"traceutil/trace.go:171","msg":"trace[1738354040] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"470.578204ms","start":"2026-03-04T01:24:49.78224Z","end":"2026-03-04T01:24:50.252818Z","steps":["trace[1738354040] 'process raft request' (duration: 470.4119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:50.252921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.293009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T01:24:50.252947Z","caller":"traceutil/trace.go:171","msg":"trace[275221067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"308.36378ms","start":"2026-03-04T01:24:49.944576Z","end":"2026-03-04T01:24:50.25294Z","steps":["trace[275221067] 'agreement among raft nodes before linearized reading' (duration: 308.302058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:50.252976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:49.944556Z","time spent":"308.415352ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:24:50.253019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:49.782222Z","time spent":"470.717828ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:24:50.253021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.103353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:24:50.253098Z","caller":"traceutil/trace.go:171","msg":"trace[49105549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"376.246007ms","start":"2026-03-04T01:24:49.876834Z","end":"2026-03-04T01:24:50.25308Z","steps":["trace[49105549] 'agreement among raft nodes before linearized reading' (duration: 376.005681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:50.253134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.092236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-04T01:24:50.253155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:49.87681Z","time spent":"376.334149ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-04T01:24:50.253193Z","caller":"traceutil/trace.go:171","msg":"trace[1806603166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"307.199859ms","start":"2026-03-04T01:24:49.945978Z","end":"2026-03-04T01:24:50.253178Z","steps":["trace[1806603166] 'agreement among raft nodes before linearized reading' (duration: 307.100856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:50.253278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:49.94592Z","time spent":"307.305973ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-04T01:24:52.288784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.253016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:24:52.289112Z","caller":"traceutil/trace.go:171","msg":"trace[621530940] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"212.039381ms","start":"2026-03-04T01:24:52.077061Z","end":"2026-03-04T01:24:52.289101Z","steps":["trace[621530940] 'process raft request' (duration: 212.00857ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289256Z","caller":"traceutil/trace.go:171","msg":"trace[556774475] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"213.47624ms","start":"2026-03-04T01:24:52.075774Z","end":"2026-03-04T01:24:52.28925Z","steps":["trace[556774475] 'process raft request' (duration: 213.262833ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289239Z","caller":"traceutil/trace.go:171","msg":"trace[1958787216] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"214.338213ms","start":"2026-03-04T01:24:52.074852Z","end":"2026-03-04T01:24:52.28919Z","steps":["trace[1958787216] 'process raft request' (duration: 214.112977ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289341Z","caller":"traceutil/trace.go:171","msg":"trace[2112520853] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"214.413306ms","start":"2026-03-04T01:24:52.074915Z","end":"2026-03-04T01:24:52.289328Z","steps":["trace[2112520853] 'process raft request' (duration: 214.066516ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289422Z","caller":"traceutil/trace.go:171","msg":"trace[905276515] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"214.738874ms","start":"2026-03-04T01:24:52.074665Z","end":"2026-03-04T01:24:52.289404Z","steps":["trace[905276515] 'process raft request' (duration: 214.24406ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289445Z","caller":"traceutil/trace.go:171","msg":"trace[1061077835] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"214.636731ms","start":"2026-03-04T01:24:52.074798Z","end":"2026-03-04T01:24:52.289435Z","steps":["trace[1061077835] 'process raft request' (duration: 214.151178ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289457Z","caller":"traceutil/trace.go:171","msg":"trace[528256666] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"213.990784ms","start":"2026-03-04T01:24:52.075441Z","end":"2026-03-04T01:24:52.289432Z","steps":["trace[528256666] 'process raft request' (duration: 213.571693ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289477Z","caller":"traceutil/trace.go:171","msg":"trace[906340867] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"215.170015ms","start":"2026-03-04T01:24:52.074282Z","end":"2026-03-04T01:24:52.289452Z","steps":["trace[906340867] 'process raft request' (duration: 106.076937ms)","trace[906340867] 'compare' (duration: 108.07013ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:52.289393Z","caller":"traceutil/trace.go:171","msg":"trace[1887635609] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"214.257601ms","start":"2026-03-04T01:24:52.075101Z","end":"2026-03-04T01:24:52.289359Z","steps":["trace[1887635609] 'process raft request' (duration: 213.8947ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289358Z","caller":"traceutil/trace.go:171","msg":"trace[39545639] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"214.944259ms","start":"2026-03-04T01:24:52.074393Z","end":"2026-03-04T01:24:52.289337Z","steps":["trace[39545639] 'process raft request' (duration: 214.477516ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.289679Z","caller":"traceutil/trace.go:171","msg":"trace[2012474563] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"214.926709ms","start":"2026-03-04T01:24:52.074674Z","end":"2026-03-04T01:24:52.289601Z","steps":["trace[2012474563] 'process raft request' (duration: 214.254131ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.317772Z","caller":"traceutil/trace.go:171","msg":"trace[101593572] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"239.331434ms","start":"2026-03-04T01:24:52.078412Z","end":"2026-03-04T01:24:52.317743Z","steps":["trace[101593572] 'process raft request' (duration: 239.086418ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.317821Z","caller":"traceutil/trace.go:171","msg":"trace[1099045827] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"239.065637ms","start":"2026-03-04T01:24:52.078737Z","end":"2026-03-04T01:24:52.317803Z","steps":["trace[1099045827] 'process raft request' (duration: 238.867092ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:52.318019Z","caller":"traceutil/trace.go:171","msg":"trace[965637280] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"238.858152ms","start":"2026-03-04T01:24:52.079084Z","end":"2026-03-04T01:24:52.317942Z","steps":["trace[965637280] 'process raft request' (duration: 238.649526ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:53.104626Z","caller":"traceutil/trace.go:171","msg":"trace[323198818] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"134.745376ms","start":"2026-03-04T01:24:52.96985Z","end":"2026-03-04T01:24:53.104595Z","steps":["trace[323198818] 'process raft request' (duration: 114.644355ms)","trace[323198818] 'compare' (duration: 19.951207ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:54.446358Z","caller":"traceutil/trace.go:171","msg":"trace[1173666670] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"382.612227ms","start":"2026-03-04T01:24:54.063699Z","end":"2026-03-04T01:24:54.446311Z","steps":["trace[1173666670] 'process raft request' (duration: 382.442231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:54.446567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:54.063673Z","time spent":"382.807702ms","remote":"127.0.0.1:42966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T01:24:54.594679Z","caller":"traceutil/trace.go:171","msg":"trace[815041469] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"144.099424ms","start":"2026-03-04T01:24:54.45055Z","end":"2026-03-04T01:24:54.594649Z","steps":["trace[815041469] 'process raft request' (duration: 79.957435ms)","trace[815041469] 'compare' (duration: 63.963243ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:54.594956Z","caller":"traceutil/trace.go:171","msg":"trace[1323380622] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"144.358841ms","start":"2026-03-04T01:24:54.450568Z","end":"2026-03-04T01:24:54.594927Z","steps":["trace[1323380622] 'process raft request' (duration: 144.034732ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:54.595062Z","caller":"traceutil/trace.go:171","msg":"trace[416265849] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"144.210576ms","start":"2026-03-04T01:24:54.45083Z","end":"2026-03-04T01:24:54.595041Z","steps":["trace[416265849] 'process raft request' (duration: 144.006441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:54.901213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.814859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:24:54.901228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.801838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:24:54.901223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.01141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-04T01:24:54.901294Z","caller":"traceutil/trace.go:171","msg":"trace[766557051] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"148.916142ms","start":"2026-03-04T01:24:54.752362Z","end":"2026-03-04T01:24:54.901278Z","steps":["trace[766557051] 'agreement among raft nodes before linearized reading' (duration: 148.809118ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:54.901307Z","caller":"traceutil/trace.go:171","msg":"trace[1440557906] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"148.924912ms","start":"2026-03-04T01:24:54.752363Z","end":"2026-03-04T01:24:54.901288Z","steps":["trace[1440557906] 'agreement among raft nodes before linearized reading' (duration: 148.795268ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:54.901315Z","caller":"traceutil/trace.go:171","msg":"trace[1018391765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"150.114564ms","start":"2026-03-04T01:24:54.751184Z","end":"2026-03-04T01:24:54.901299Z","steps":["trace[1018391765] 'agreement among raft nodes before linearized reading' (duration: 149.886857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:54.901399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.967663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:24:54.901367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.084221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:54.901437Z","caller":"traceutil/trace.go:171","msg":"trace[931804412] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"149.008764ms","start":"2026-03-04T01:24:54.752418Z","end":"2026-03-04T01:24:54.901426Z","steps":["trace[931804412] 'agreement among raft nodes before linearized reading' (duration: 148.959992ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:54.901491Z","caller":"traceutil/trace.go:171","msg":"trace[1970575632] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"145.230504ms","start":"2026-03-04T01:24:54.756225Z","end":"2026-03-04T01:24:54.901456Z","steps":["trace[1970575632] 'agreement among raft nodes before linearized reading' (duration: 145.009629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:54.901591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.221729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:54.90164Z","caller":"traceutil/trace.go:171","msg":"trace[1083505468] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"141.28251ms","start":"2026-03-04T01:24:54.760344Z","end":"2026-03-04T01:24:54.901626Z","steps":["trace[1083505468] 'agreement among raft nodes before linearized reading' (duration: 141.220258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:54.901691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.404899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:54.901727Z","caller":"traceutil/trace.go:171","msg":"trace[998690233] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"145.45836ms","start":"2026-03-04T01:24:54.756259Z","end":"2026-03-04T01:24:54.901718Z","steps":["trace[998690233] 'agreement among raft nodes before linearized reading' (duration: 144.865664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:54.901731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.281265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:24:54.901759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.624181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:54.901771Z","caller":"traceutil/trace.go:171","msg":"trace[2014021599] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"145.318036ms","start":"2026-03-04T01:24:54.756432Z","end":"2026-03-04T01:24:54.90175Z","steps":["trace[2014021599] 'agreement among raft nodes before linearized reading' (duration: 145.279805ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:54.901788Z","caller":"traceutil/trace.go:171","msg":"trace[2104409521] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"145.309426ms","start":"2026-03-04T01:24:54.756471Z","end":"2026-03-04T01:24:54.90178Z","steps":["trace[2104409521] 'process raft request' (duration: 144.157146ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:54.90179Z","caller":"traceutil/trace.go:171","msg":"trace[1130093870] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"112.675993ms","start":"2026-03-04T01:24:54.789104Z","end":"2026-03-04T01:24:54.90178Z","steps":["trace[1130093870] 'agreement among raft nodes before linearized reading' (duration: 112.454607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:54.902125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.622491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:54.902208Z","caller":"traceutil/trace.go:171","msg":"trace[1907843597] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:697; }","duration":"149.802055ms","start":"2026-03-04T01:24:54.752391Z","end":"2026-03-04T01:24:54.902193Z","steps":["trace[1907843597] 'agreement among raft nodes before linearized reading' (duration: 149.61052ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:55.270833Z","caller":"traceutil/trace.go:171","msg":"trace[1857499373] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"116.071831ms","start":"2026-03-04T01:24:55.154733Z","end":"2026-03-04T01:24:55.270805Z","steps":["trace[1857499373] 'process raft request' (duration: 115.925447ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:56.935274Z","caller":"traceutil/trace.go:171","msg":"trace[418912215] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"127.836324ms","start":"2026-03-04T01:24:56.807394Z","end":"2026-03-04T01:24:56.935231Z","steps":["trace[418912215] 'process raft request' (duration: 127.558826ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:57.012381Z","caller":"traceutil/trace.go:171","msg":"trace[1345113813] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"153.515902ms","start":"2026-03-04T01:24:56.858842Z","end":"2026-03-04T01:24:57.012358Z","steps":["trace[1345113813] 'process raft request' (duration: 153.40595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:57.012792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.178564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-04T01:24:57.012871Z","caller":"traceutil/trace.go:171","msg":"trace[1535947715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"102.314838ms","start":"2026-03-04T01:24:56.91054Z","end":"2026-03-04T01:24:57.012855Z","steps":["trace[1535947715] 'agreement among raft nodes before linearized reading' (duration: 102.008829ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:57.092732Z","caller":"traceutil/trace.go:171","msg":"trace[953428970] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"107.848294ms","start":"2026-03-04T01:24:56.984857Z","end":"2026-03-04T01:24:57.092706Z","steps":["trace[953428970] 'process raft request' (duration: 107.615607ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:06.759348Z","caller":"traceutil/trace.go:171","msg":"trace[1023424063] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"108.250875ms","start":"2026-03-04T01:25:06.651065Z","end":"2026-03-04T01:25:06.759316Z","steps":["trace[1023424063] 'process raft request' (duration: 102.410981ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:06.759459Z","caller":"traceutil/trace.go:171","msg":"trace[1156605401] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"107.799603ms","start":"2026-03-04T01:25:06.651628Z","end":"2026-03-04T01:25:06.759428Z","steps":["trace[1156605401] 'process raft request' (duration: 107.634518ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:08.818541Z","caller":"traceutil/trace.go:171","msg":"trace[1753202220] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"109.250632ms","start":"2026-03-04T01:25:08.70927Z","end":"2026-03-04T01:25:08.818521Z","steps":["trace[1753202220] 'process raft request' (duration: 109.076046ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:11.337737Z","caller":"traceutil/trace.go:171","msg":"trace[1113724085] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"153.569924ms","start":"2026-03-04T01:25:11.184135Z","end":"2026-03-04T01:25:11.337705Z","steps":["trace[1113724085] 'read index received' (duration: 153.438201ms)","trace[1113724085] 'applied index is now lower than readState.Index' (duration: 130.123µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:11.337786Z","caller":"traceutil/trace.go:171","msg":"trace[1136350011] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"470.625745ms","start":"2026-03-04T01:25:10.867114Z","end":"2026-03-04T01:25:11.33774Z","steps":["trace[1136350011] 'process raft request' (duration: 470.40043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:11.337945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.384261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:25:11.33797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:10.867089Z","time spent":"470.781109ms","remote":"127.0.0.1:43384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3644,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:25:11.337989Z","caller":"traceutil/trace.go:171","msg":"trace[1475867656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"113.470193ms","start":"2026-03-04T01:25:11.224507Z","end":"2026-03-04T01:25:11.337977Z","steps":["trace[1475867656] 'agreement among raft nodes before linearized reading' (duration: 113.380121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:11.337998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.849911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T01:25:11.338085Z","caller":"traceutil/trace.go:171","msg":"trace[926701367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"154.047057ms","start":"2026-03-04T01:25:11.184019Z","end":"2026-03-04T01:25:11.338067Z","steps":["trace[926701367] 'agreement among raft nodes before linearized reading' (duration: 153.80022ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:16.225169Z","caller":"traceutil/trace.go:171","msg":"trace[126794428] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"131.297074ms","start":"2026-03-04T01:25:16.093841Z","end":"2026-03-04T01:25:16.225139Z","steps":["trace[126794428] 'process raft request' (duration: 112.505247ms)","trace[126794428] 'compare' (duration: 18.580402ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:38.108758Z","caller":"traceutil/trace.go:171","msg":"trace[656322759] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"112.59097ms","start":"2026-03-04T01:25:37.996132Z","end":"2026-03-04T01:25:38.108723Z","steps":["trace[656322759] 'process raft request' (duration: 93.538036ms)","trace[656322759] 'compare' (duration: 18.88635ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:38.789999Z","caller":"traceutil/trace.go:171","msg":"trace[1957701537] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"126.465797ms","start":"2026-03-04T01:25:38.663504Z","end":"2026-03-04T01:25:38.78997Z","steps":["trace[1957701537] 'process raft request' (duration: 125.905102ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:38.79004Z","caller":"traceutil/trace.go:171","msg":"trace[1986919521] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"125.993215ms","start":"2026-03-04T01:25:38.663995Z","end":"2026-03-04T01:25:38.789989Z","steps":["trace[1986919521] 'process raft request' (duration: 125.79677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:39.097212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.853633ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:25:39.097407Z","caller":"traceutil/trace.go:171","msg":"trace[1632885118] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1139; }","duration":"148.065488ms","start":"2026-03-04T01:25:38.949321Z","end":"2026-03-04T01:25:39.097387Z","steps":["trace[1632885118] 'range keys from in-memory index tree' (duration: 147.834852ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:39.097795Z","caller":"traceutil/trace.go:171","msg":"trace[1826872256] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"227.930093ms","start":"2026-03-04T01:25:38.869824Z","end":"2026-03-04T01:25:39.097754Z","steps":["trace[1826872256] 'process raft request' (duration: 139.655787ms)","trace[1826872256] 'compare' (duration: 88.02844ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:39.097865Z","caller":"traceutil/trace.go:171","msg":"trace[1872712460] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"227.008688ms","start":"2026-03-04T01:25:38.870815Z","end":"2026-03-04T01:25:39.097824Z","steps":["trace[1872712460] 'process raft request' (duration: 226.882645ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:39.097812Z","caller":"traceutil/trace.go:171","msg":"trace[829512413] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1178; }","duration":"133.00648ms","start":"2026-03-04T01:25:38.96478Z","end":"2026-03-04T01:25:39.097786Z","steps":["trace[829512413] 'read index received' (duration: 44.716494ms)","trace[829512413] 'applied index is now lower than readState.Index' (duration: 88.289026ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:39.098001Z","caller":"traceutil/trace.go:171","msg":"trace[1910487380] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"227.021288ms","start":"2026-03-04T01:25:38.87094Z","end":"2026-03-04T01:25:39.097962Z","steps":["trace[1910487380] 'process raft request' (duration: 226.808473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:39.098222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.38594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-04T01:25:39.098303Z","caller":"traceutil/trace.go:171","msg":"trace[536614070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1142; }","duration":"133.528234ms","start":"2026-03-04T01:25:38.964761Z","end":"2026-03-04T01:25:39.098289Z","steps":["trace[536614070] 'agreement among raft nodes before linearized reading' (duration: 133.107073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:39.098854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.984195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:25:39.098921Z","caller":"traceutil/trace.go:171","msg":"trace[1722478546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1142; }","duration":"134.09679ms","start":"2026-03-04T01:25:38.964805Z","end":"2026-03-04T01:25:39.098901Z","steps":["trace[1722478546] 'agreement among raft nodes before linearized reading' (duration: 133.919314ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:40.630098Z","caller":"traceutil/trace.go:171","msg":"trace[1611484617] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"146.342443ms","start":"2026-03-04T01:25:40.483725Z","end":"2026-03-04T01:25:40.630067Z","steps":["trace[1611484617] 'process raft request' (duration: 146.198308ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:08.31484Z","caller":"traceutil/trace.go:171","msg":"trace[649066432] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"158.359595ms","start":"2026-03-04T01:26:08.156465Z","end":"2026-03-04T01:26:08.314824Z","steps":["trace[649066432] 'process raft request' (duration: 158.257503ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:08.59489Z","caller":"traceutil/trace.go:171","msg":"trace[523600889] linearizableReadLoop","detail":"{readStateIndex:1694; appliedIndex:1692; }","duration":"294.243662ms","start":"2026-03-04T01:26:08.300623Z","end":"2026-03-04T01:26:08.594867Z","steps":["trace[523600889] 'read index received' (duration: 14.177772ms)","trace[523600889] 'applied index is now lower than readState.Index' (duration: 280.06491ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:26:08.594991Z","caller":"traceutil/trace.go:171","msg":"trace[1741886210] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"379.579603ms","start":"2026-03-04T01:26:08.215378Z","end":"2026-03-04T01:26:08.594958Z","steps":["trace[1741886210] 'process raft request' (duration: 340.570553ms)","trace[1741886210] 'compare' (duration: 38.811295ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:26:08.595061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.435917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:26:08.595088Z","caller":"traceutil/trace.go:171","msg":"trace[2075762562] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1627; }","duration":"294.537429ms","start":"2026-03-04T01:26:08.300542Z","end":"2026-03-04T01:26:08.59508Z","steps":["trace[2075762562] 'agreement among raft nodes before linearized reading' (duration: 294.443817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:08.595511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:26:08.215344Z","time spent":"379.718046ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:26:08.60077Z","caller":"traceutil/trace.go:171","msg":"trace[1429947109] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"251.827488ms","start":"2026-03-04T01:26:08.348921Z","end":"2026-03-04T01:26:08.600749Z","steps":["trace[1429947109] 'process raft request' (duration: 251.666194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:08.60322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.469707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18997effb7b2d508\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-03-04T01:26:08.603259Z","caller":"traceutil/trace.go:171","msg":"trace[814458991] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18997effb7b2d508; range_end:; response_count:1; response_revision:1630; }","duration":"263.55708ms","start":"2026-03-04T01:26:08.339691Z","end":"2026-03-04T01:26:08.603249Z","steps":["trace[814458991] 'agreement among raft nodes before linearized reading' (duration: 263.427877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:08.603407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.328134ms","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-03-04T01:26:08.604266Z","caller":"traceutil/trace.go:171","msg":"trace[914384598] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1630; }","duration":"292.658654ms","start":"2026-03-04T01:26:08.31101Z","end":"2026-03-04T01:26:08.603668Z","steps":["trace[914384598] 'agreement among raft nodes before linearized reading' (duration: 292.257744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:08.605205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.077091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:26:08.605274Z","caller":"traceutil/trace.go:171","msg":"trace[1226806699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1630; }","duration":"181.226044ms","start":"2026-03-04T01:26:08.424025Z","end":"2026-03-04T01:26:08.605251Z","steps":["trace[1226806699] 'agreement among raft nodes before linearized reading' (duration: 179.094111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:12.832392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.728324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:26:12.832588Z","caller":"traceutil/trace.go:171","msg":"trace[787480936] linearizableReadLoop","detail":"{readStateIndex:1739; appliedIndex:1738; }","duration":"195.581641ms","start":"2026-03-04T01:26:12.636976Z","end":"2026-03-04T01:26:12.832557Z","steps":["trace[787480936] 'read index received' (duration: 28.536749ms)","trace[787480936] 'applied index is now lower than readState.Index' (duration: 167.042702ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:26:12.832666Z","caller":"traceutil/trace.go:171","msg":"trace[1420869889] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"204.727608ms","start":"2026-03-04T01:26:12.627878Z","end":"2026-03-04T01:26:12.832605Z","steps":["trace[1420869889] 'process raft request' (duration: 37.619564ms)","trace[1420869889] 'compare' (duration: 166.451357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:26:12.832841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.863527ms","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-03-04T01:26:12.832907Z","caller":"traceutil/trace.go:171","msg":"trace[369941561] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1670; }","duration":"195.952769ms","start":"2026-03-04T01:26:12.636935Z","end":"2026-03-04T01:26:12.832888Z","steps":["trace[369941561] 'agreement among raft nodes before linearized reading' (duration: 195.702753ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:13.589232Z","caller":"traceutil/trace.go:171","msg":"trace[383345527] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"104.61185ms","start":"2026-03-04T01:26:13.484589Z","end":"2026-03-04T01:26:13.589201Z","steps":["trace[383345527] 'process raft request' (duration: 97.745689ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:13.589521Z","caller":"traceutil/trace.go:171","msg":"trace[818620337] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"102.167239ms","start":"2026-03-04T01:26:13.487302Z","end":"2026-03-04T01:26:13.589469Z","steps":["trace[818620337] 'process raft request' (duration: 101.857612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:21.135197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.534921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:26:21.13531Z","caller":"traceutil/trace.go:171","msg":"trace[1606313574] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1726; }","duration":"317.680494ms","start":"2026-03-04T01:26:20.81761Z","end":"2026-03-04T01:26:21.135291Z","steps":["trace[1606313574] 'count revisions from in-memory index tree' (duration: 317.412498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:21.135359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:26:20.817522Z","time spent":"317.825798ms","remote":"127.0.0.1:43284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":28,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-04T01:26:21.135581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.105538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:26:21.135701Z","caller":"traceutil/trace.go:171","msg":"trace[64957261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1726; }","duration":"269.285042ms","start":"2026-03-04T01:26:20.866392Z","end":"2026-03-04T01:26:21.135677Z","steps":["trace[64957261] 'range keys from in-memory index tree' (duration: 268.899542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:21.135929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.061086ms","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-03-04T01:26:21.136123Z","caller":"traceutil/trace.go:171","msg":"trace[896866141] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1726; }","duration":"242.23191ms","start":"2026-03-04T01:26:20.893803Z","end":"2026-03-04T01:26:21.136035Z","steps":["trace[896866141] 'range keys from in-memory index tree' (duration: 241.807299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:25.070633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.036402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:26:25.070738Z","caller":"traceutil/trace.go:171","msg":"trace[1883273161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1755; }","duration":"396.219527ms","start":"2026-03-04T01:26:24.674498Z","end":"2026-03-04T01:26:25.070717Z","steps":["trace[1883273161] 'range keys from in-memory index tree' (duration: 395.844388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:25.070787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:26:24.674463Z","time spent":"396.313869ms","remote":"127.0.0.1:42874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T01:26:25.071024Z","caller":"traceutil/trace.go:171","msg":"trace[351933431] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"355.928934ms","start":"2026-03-04T01:26:24.715057Z","end":"2026-03-04T01:26:25.070986Z","steps":["trace[351933431] 'process raft request' (duration: 353.72398ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:25.071032Z","caller":"traceutil/trace.go:171","msg":"trace[726730134] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"340.791949ms","start":"2026-03-04T01:26:24.730216Z","end":"2026-03-04T01:26:25.071007Z","steps":["trace[726730134] 'process raft request' (duration: 340.685516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:25.071165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:26:24.715023Z","time spent":"356.065468ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:26:25.071181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:26:24.730176Z","time spent":"340.923302ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:26:57.878776Z","caller":"traceutil/trace.go:171","msg":"trace[630246785] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"156.565652ms","start":"2026-03-04T01:26:57.722166Z","end":"2026-03-04T01:26:57.878732Z","steps":["trace[630246785] 'process raft request' (duration: 156.424019ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:58.219442Z","caller":"traceutil/trace.go:171","msg":"trace[761640962] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"179.560603ms","start":"2026-03-04T01:26:58.039844Z","end":"2026-03-04T01:26:58.219404Z","steps":["trace[761640962] 'process raft request' (duration: 147.366461ms)","trace[761640962] 'compare' (duration: 32.071048ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:27:04.624259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.525333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:27:04.624384Z","caller":"traceutil/trace.go:171","msg":"trace[245264649] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"400.889078ms","start":"2026-03-04T01:27:04.223472Z","end":"2026-03-04T01:27:04.624361Z","steps":["trace[245264649] 'read index received' (duration: 213.127379ms)","trace[245264649] 'applied index is now lower than readState.Index' (duration: 187.759919ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:27:04.624503Z","caller":"traceutil/trace.go:171","msg":"trace[1545835816] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"536.797219ms","start":"2026-03-04T01:27:04.087688Z","end":"2026-03-04T01:27:04.624486Z","steps":["trace[1545835816] 'process raft request' (duration: 348.939127ms)","trace[1545835816] 'compare' (duration: 187.215216ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:27:04.624595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:27:04.087658Z","time spent":"536.889502ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:27:04.62476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.265918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:27:04.624874Z","caller":"traceutil/trace.go:171","msg":"trace[2005719405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1951; }","duration":"401.393491ms","start":"2026-03-04T01:27:04.223431Z","end":"2026-03-04T01:27:04.624825Z","steps":["trace[2005719405] 'agreement among raft nodes before linearized reading' (duration: 401.224847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:27:04.624835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.807737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-04T01:27:04.624878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.076348ms","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":"warn","ts":"2026-03-04T01:27:04.624958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:27:04.22341Z","time spent":"401.510973ms","remote":"127.0.0.1:42874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T01:27:04.624975Z","caller":"traceutil/trace.go:171","msg":"trace[299859747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1951; }","duration":"382.000501ms","start":"2026-03-04T01:27:04.242942Z","end":"2026-03-04T01:27:04.624942Z","steps":["trace[299859747] 'agreement among raft nodes before linearized reading' (duration: 381.606882ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:27:04.625045Z","caller":"traceutil/trace.go:171","msg":"trace[1917912197] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1951; }","duration":"167.316103ms","start":"2026-03-04T01:27:04.457677Z","end":"2026-03-04T01:27:04.624993Z","steps":["trace[1917912197] 'agreement among raft nodes before linearized reading' (duration: 166.982465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:27:04.625049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:27:04.242932Z","time spent":"382.104893ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-04T01:27:04.944997Z","caller":"traceutil/trace.go:171","msg":"trace[779637057] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"228.884997ms","start":"2026-03-04T01:27:04.716093Z","end":"2026-03-04T01:27:04.944978Z","steps":["trace[779637057] 'process raft request' (duration: 228.798255ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:27:04.945031Z","caller":"traceutil/trace.go:171","msg":"trace[96467425] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"315.924804ms","start":"2026-03-04T01:27:04.629074Z","end":"2026-03-04T01:27:04.944999Z","steps":["trace[96467425] 'process raft request' (duration: 273.997806ms)","trace[96467425] 'compare' (duration: 41.613151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:27:04.945176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:27:04.629042Z","time spent":"316.053838ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:27:08.767216Z","caller":"traceutil/trace.go:171","msg":"trace[322551230] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"108.370195ms","start":"2026-03-04T01:27:08.658813Z","end":"2026-03-04T01:27:08.767183Z","steps":["trace[322551230] 'process raft request' (duration: 47.728214ms)","trace[322551230] 'compare' (duration: 60.431516ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:27:10.754507Z","caller":"traceutil/trace.go:171","msg":"trace[1115483064] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"100.808444ms","start":"2026-03-04T01:27:10.653678Z","end":"2026-03-04T01:27:10.754486Z","steps":["trace[1115483064] 'process raft request' (duration: 100.230419ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:28:11.512701Z","caller":"traceutil/trace.go:171","msg":"trace[1260058282] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2580; }","duration":"333.60613ms","start":"2026-03-04T01:28:11.179075Z","end":"2026-03-04T01:28:11.512681Z","steps":["trace[1260058282] 'read index received' (duration: 333.459496ms)","trace[1260058282] 'applied index is now lower than readState.Index' (duration: 146.164µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:28:11.512756Z","caller":"traceutil/trace.go:171","msg":"trace[1738998414] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"334.118103ms","start":"2026-03-04T01:28:11.178609Z","end":"2026-03-04T01:28:11.512727Z","steps":["trace[1738998414] 'process raft request' (duration: 333.973029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:11.512898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.819726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-03-04T01:28:11.512905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:11.178583Z","time spent":"334.226335ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:28:11.512943Z","caller":"traceutil/trace.go:171","msg":"trace[383016529] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2479; }","duration":"333.899898ms","start":"2026-03-04T01:28:11.179031Z","end":"2026-03-04T01:28:11.512931Z","steps":["trace[383016529] 'agreement among raft nodes before linearized reading' (duration: 333.757793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:11.513014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:11.179013Z","time spent":"333.954819ms","remote":"127.0.0.1:43096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-03-04T01:28:11.513492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.760342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-04T01:28:11.513581Z","caller":"traceutil/trace.go:171","msg":"trace[1089291404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2479; }","duration":"264.895227ms","start":"2026-03-04T01:28:11.248667Z","end":"2026-03-04T01:28:11.513562Z","steps":["trace[1089291404] 'agreement among raft nodes before linearized reading' (duration: 264.67026ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:28:11.901211Z","caller":"traceutil/trace.go:171","msg":"trace[956843596] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"270.63386ms","start":"2026-03-04T01:28:11.630553Z","end":"2026-03-04T01:28:11.901186Z","steps":["trace[956843596] 'process raft request' (duration: 270.466736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:11.901567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.231403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:28:11.90164Z","caller":"traceutil/trace.go:171","msg":"trace[704798293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2482; }","duration":"219.347636ms","start":"2026-03-04T01:28:11.682274Z","end":"2026-03-04T01:28:11.901622Z","steps":["trace[704798293] 'agreement among raft nodes before linearized reading' (duration: 219.109451ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:28:11.901757Z","caller":"traceutil/trace.go:171","msg":"trace[1049696288] linearizableReadLoop","detail":"{readStateIndex:2584; appliedIndex:2583; }","duration":"218.826152ms","start":"2026-03-04T01:28:11.682329Z","end":"2026-03-04T01:28:11.901155Z","steps":["trace[1049696288] 'read index received' (duration: 218.624367ms)","trace[1049696288] 'applied index is now lower than readState.Index' (duration: 200.905µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:28:12.644602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.055454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:28:12.645276Z","caller":"traceutil/trace.go:171","msg":"trace[421648057] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"739.602637ms","start":"2026-03-04T01:28:11.905626Z","end":"2026-03-04T01:28:12.645228Z","steps":["trace[421648057] 'process raft request' (duration: 316.810706ms)","trace[421648057] 'compare' (duration: 421.357895ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:28:12.645562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:11.905604Z","time spent":"739.800822ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:28:12.660855Z","caller":"traceutil/trace.go:171","msg":"trace[1431467758] linearizableReadLoop","detail":"{readStateIndex:2586; appliedIndex:2584; }","duration":"441.75679ms","start":"2026-03-04T01:28:12.219069Z","end":"2026-03-04T01:28:12.660826Z","steps":["trace[1431467758] 'read index received' (duration: 3.381795ms)","trace[1431467758] 'applied index is now lower than readState.Index' (duration: 438.372475ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:28:12.661069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.962075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:28:12.661124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.817411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:28:12.661164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.436422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:28:12.661178Z","caller":"traceutil/trace.go:171","msg":"trace[317078904] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2484; }","duration":"442.117839ms","start":"2026-03-04T01:28:12.21903Z","end":"2026-03-04T01:28:12.661148Z","steps":["trace[317078904] 'agreement among raft nodes before linearized reading' (duration: 441.924044ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:28:12.661205Z","caller":"traceutil/trace.go:171","msg":"trace[1215066760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2484; }","duration":"435.942144ms","start":"2026-03-04T01:28:12.225246Z","end":"2026-03-04T01:28:12.661188Z","steps":["trace[1215066760] 'agreement among raft nodes before linearized reading' (duration: 435.790211ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:28:12.661218Z","caller":"traceutil/trace.go:171","msg":"trace[1822257838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2484; }","duration":"378.552085ms","start":"2026-03-04T01:28:12.282651Z","end":"2026-03-04T01:28:12.661203Z","steps":["trace[1822257838] 'agreement among raft nodes before linearized reading' (duration: 378.390501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:12.661239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:12.225234Z","time spent":"436.000785ms","remote":"127.0.0.1:42874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:28:12.661237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:12.219008Z","time spent":"442.217762ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":26,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-04T01:28:12.661262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:12.282631Z","time spent":"378.624317ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T01:28:12.661246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.589238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T01:28:12.661355Z","caller":"traceutil/trace.go:171","msg":"trace[2073462960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2484; }","duration":"199.707701ms","start":"2026-03-04T01:28:12.461623Z","end":"2026-03-04T01:28:12.661331Z","steps":["trace[2073462960] 'agreement among raft nodes before linearized reading' (duration: 199.435074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:12.662332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.498383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18997f18aad9b821\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-03-04T01:28:12.662395Z","caller":"traceutil/trace.go:171","msg":"trace[14652155] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18997f18aad9b821; range_end:; response_count:1; response_revision:2484; }","duration":"401.599297ms","start":"2026-03-04T01:28:12.260782Z","end":"2026-03-04T01:28:12.662381Z","steps":["trace[14652155] 'agreement among raft nodes before linearized reading' (duration: 400.257493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:12.662431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:12.26076Z","time spent":"401.663399ms","remote":"127.0.0.1:42966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":786,"request content":"key:\"/registry/events/openstack/valkey-node-0.18997f18aad9b821\" "} {"level":"info","ts":"2026-03-04T01:28:18.927786Z","caller":"traceutil/trace.go:171","msg":"trace[128848093] linearizableReadLoop","detail":"{readStateIndex:2618; appliedIndex:2617; }","duration":"238.081649ms","start":"2026-03-04T01:28:18.68968Z","end":"2026-03-04T01:28:18.927762Z","steps":["trace[128848093] 'read index received' (duration: 237.884384ms)","trace[128848093] 'applied index is now lower than readState.Index' (duration: 196.095µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:28:18.927847Z","caller":"traceutil/trace.go:171","msg":"trace[1412708608] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"257.249403ms","start":"2026-03-04T01:28:18.670567Z","end":"2026-03-04T01:28:18.927816Z","steps":["trace[1412708608] 'process raft request' (duration: 257.045678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:18.928001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.313235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-04T01:28:18.928045Z","caller":"traceutil/trace.go:171","msg":"trace[426522392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2514; }","duration":"238.418317ms","start":"2026-03-04T01:28:18.689614Z","end":"2026-03-04T01:28:18.928033Z","steps":["trace[426522392] 'agreement among raft nodes before linearized reading' (duration: 238.266594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:18.928131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.449758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:28:18.928163Z","caller":"traceutil/trace.go:171","msg":"trace[1867692306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2514; }","duration":"238.54163ms","start":"2026-03-04T01:28:18.689614Z","end":"2026-03-04T01:28:18.928156Z","steps":["trace[1867692306] 'agreement among raft nodes before linearized reading' (duration: 238.379746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:19.271545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.585791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:28:19.271737Z","caller":"traceutil/trace.go:171","msg":"trace[465465634] linearizableReadLoop","detail":"{readStateIndex:2620; appliedIndex:2618; }","duration":"196.871889ms","start":"2026-03-04T01:28:19.074847Z","end":"2026-03-04T01:28:19.271719Z","steps":["trace[465465634] 'read index received' (duration: 71.498225ms)","trace[465465634] 'applied index is now lower than readState.Index' (duration: 125.372504ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:28:19.271835Z","caller":"traceutil/trace.go:171","msg":"trace[1548615330] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"339.93611ms","start":"2026-03-04T01:28:18.93189Z","end":"2026-03-04T01:28:19.271826Z","steps":["trace[1548615330] 'process raft request' (duration: 339.776966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:28:19.271901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:18.931875Z","time spent":"339.994011ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:28:19.271955Z","caller":"traceutil/trace.go:171","msg":"trace[1463108153] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"340.085443ms","start":"2026-03-04T01:28:18.931829Z","end":"2026-03-04T01:28:19.271914Z","steps":["trace[1463108153] 'process raft request' (duration: 105.039871ms)","trace[1463108153] 'compare' (duration: 234.458218ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:28:19.272181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:28:18.931801Z","time spent":"340.233998ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:28:19.272279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.435353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-04T01:28:19.272324Z","caller":"traceutil/trace.go:171","msg":"trace[966432866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2516; }","duration":"197.516825ms","start":"2026-03-04T01:28:19.074797Z","end":"2026-03-04T01:28:19.272314Z","steps":["trace[966432866] 'agreement among raft nodes before linearized reading' (duration: 197.390852ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:48.34444Z","caller":"traceutil/trace.go:171","msg":"trace[1252633357] transaction","detail":"{read_only:false; response_revision:4131; number_of_response:1; }","duration":"119.158877ms","start":"2026-03-04T01:31:48.225253Z","end":"2026-03-04T01:31:48.344412Z","steps":["trace[1252633357] 'process raft request' (duration: 118.978053ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:48.350221Z","caller":"traceutil/trace.go:171","msg":"trace[867678642] transaction","detail":"{read_only:false; response_revision:4132; number_of_response:1; }","duration":"114.547678ms","start":"2026-03-04T01:31:48.235646Z","end":"2026-03-04T01:31:48.350193Z","steps":["trace[867678642] 'process raft request' (duration: 114.385423ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:48.352034Z","caller":"traceutil/trace.go:171","msg":"trace[446024784] linearizableReadLoop","detail":"{readStateIndex:4304; appliedIndex:4302; }","duration":"100.114323ms","start":"2026-03-04T01:31:48.251898Z","end":"2026-03-04T01:31:48.352012Z","steps":["trace[446024784] 'read index received' (duration: 92.482018ms)","trace[446024784] 'applied index is now lower than readState.Index' (duration: 7.630875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:31:48.352381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.459243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:31:48.352441Z","caller":"traceutil/trace.go:171","msg":"trace[217265386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4135; }","duration":"100.551895ms","start":"2026-03-04T01:31:48.251873Z","end":"2026-03-04T01:31:48.352425Z","steps":["trace[217265386] 'agreement among raft nodes before linearized reading' (duration: 100.302728ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:50.973985Z","caller":"traceutil/trace.go:171","msg":"trace[480657641] linearizableReadLoop","detail":"{readStateIndex:4370; appliedIndex:4369; }","duration":"179.019409ms","start":"2026-03-04T01:31:50.794946Z","end":"2026-03-04T01:31:50.973965Z","steps":["trace[480657641] 'read index received' (duration: 177.767963ms)","trace[480657641] 'applied index is now lower than readState.Index' (duration: 1.250406ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:31:50.974061Z","caller":"traceutil/trace.go:171","msg":"trace[1436634919] transaction","detail":"{read_only:false; response_revision:4198; number_of_response:1; }","duration":"199.69562ms","start":"2026-03-04T01:31:50.774336Z","end":"2026-03-04T01:31:50.974032Z","steps":["trace[1436634919] 'process raft request' (duration: 198.404923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:31:50.974139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.144432ms","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:7"} {"level":"info","ts":"2026-03-04T01:31:50.97422Z","caller":"traceutil/trace.go:171","msg":"trace[1878177410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4198; }","duration":"179.280116ms","start":"2026-03-04T01:31:50.794906Z","end":"2026-03-04T01:31:50.974187Z","steps":["trace[1878177410] 'agreement among raft nodes before linearized reading' (duration: 179.128942ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:54.169241Z","caller":"traceutil/trace.go:171","msg":"trace[1534665828] transaction","detail":"{read_only:false; response_revision:4293; number_of_response:1; }","duration":"117.571683ms","start":"2026-03-04T01:31:54.051604Z","end":"2026-03-04T01:31:54.169176Z","steps":["trace[1534665828] 'process raft request' (duration: 94.099354ms)","trace[1534665828] 'compare' (duration: 23.234093ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:31:56.160037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.270013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:31:56.160122Z","caller":"traceutil/trace.go:171","msg":"trace[109594007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4351; }","duration":"113.396545ms","start":"2026-03-04T01:31:56.046711Z","end":"2026-03-04T01:31:56.160107Z","steps":["trace[109594007] 'range keys from in-memory index tree' (duration: 113.131689ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:56.163122Z","caller":"traceutil/trace.go:171","msg":"trace[640846353] linearizableReadLoop","detail":"{readStateIndex:4529; appliedIndex:4528; }","duration":"111.127092ms","start":"2026-03-04T01:31:56.051975Z","end":"2026-03-04T01:31:56.163103Z","steps":["trace[640846353] 'read index received' (duration: 111.02541ms)","trace[640846353] 'applied index is now lower than readState.Index' (duration: 100.902µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:31:56.163147Z","caller":"traceutil/trace.go:171","msg":"trace[289756804] transaction","detail":"{read_only:false; response_revision:4352; number_of_response:1; }","duration":"111.918684ms","start":"2026-03-04T01:31:56.051214Z","end":"2026-03-04T01:31:56.163133Z","steps":["trace[289756804] 'process raft request' (duration: 111.746379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:31:56.163229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.260605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xfxxj\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-04T01:31:56.163248Z","caller":"traceutil/trace.go:171","msg":"trace[1446244000] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xfxxj; range_end:; response_count:1; response_revision:4352; }","duration":"111.323627ms","start":"2026-03-04T01:31:56.05192Z","end":"2026-03-04T01:31:56.163244Z","steps":["trace[1446244000] 'agreement among raft nodes before linearized reading' (duration: 111.253045ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:57.081177Z","caller":"traceutil/trace.go:171","msg":"trace[1557366878] linearizableReadLoop","detail":"{readStateIndex:4548; appliedIndex:4547; }","duration":"207.510931ms","start":"2026-03-04T01:31:56.873637Z","end":"2026-03-04T01:31:57.081147Z","steps":["trace[1557366878] 'read index received' (duration: 206.894363ms)","trace[1557366878] 'applied index is now lower than readState.Index' (duration: 615.428µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:31:57.081243Z","caller":"traceutil/trace.go:171","msg":"trace[181679154] transaction","detail":"{read_only:false; response_revision:4371; number_of_response:1; }","duration":"266.293462ms","start":"2026-03-04T01:31:56.814919Z","end":"2026-03-04T01:31:57.081212Z","steps":["trace[181679154] 'process raft request' (duration: 265.668555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:31:57.081408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.764198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T01:31:57.081447Z","caller":"traceutil/trace.go:171","msg":"trace[1468596024] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:4371; }","duration":"207.85781ms","start":"2026-03-04T01:31:56.87358Z","end":"2026-03-04T01:31:57.081437Z","steps":["trace[1468596024] 'agreement among raft nodes before linearized reading' (duration: 207.694076ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:31:59.996401Z","caller":"traceutil/trace.go:171","msg":"trace[1072200045] linearizableReadLoop","detail":"{readStateIndex:4675; appliedIndex:4674; }","duration":"185.038879ms","start":"2026-03-04T01:31:59.811336Z","end":"2026-03-04T01:31:59.996374Z","steps":["trace[1072200045] 'read index received' (duration: 184.892274ms)","trace[1072200045] 'applied index is now lower than readState.Index' (duration: 145.445µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:31:59.996508Z","caller":"traceutil/trace.go:171","msg":"trace[1007178764] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"344.017156ms","start":"2026-03-04T01:31:59.652469Z","end":"2026-03-04T01:31:59.996486Z","steps":["trace[1007178764] 'process raft request' (duration: 343.780598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:31:59.996615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:31:59.65245Z","time spent":"344.108408ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:31:59.996912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.793544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-68a35dd8-caf7-464a-a656-0c81437c8dd0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-04T01:31:59.996986Z","caller":"traceutil/trace.go:171","msg":"trace[102467751] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-68a35dd8-caf7-464a-a656-0c81437c8dd0; range_end:; response_count:1; response_revision:4494; }","duration":"151.914658ms","start":"2026-03-04T01:31:59.845053Z","end":"2026-03-04T01:31:59.996968Z","steps":["trace[102467751] 'agreement among raft nodes before linearized reading' (duration: 151.667901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:31:59.99758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.242983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-68a35dd8-caf7-464a-a656-0c81437c8dd0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-04T01:31:59.997632Z","caller":"traceutil/trace.go:171","msg":"trace[1416682862] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-68a35dd8-caf7-464a-a656-0c81437c8dd0; range_end:; response_count:1; response_revision:4494; }","duration":"186.325805ms","start":"2026-03-04T01:31:59.811293Z","end":"2026-03-04T01:31:59.997619Z","steps":["trace[1416682862] 'agreement among raft nodes before linearized reading' (duration: 185.249375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:00.31871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.714509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:32:00.318908Z","caller":"traceutil/trace.go:171","msg":"trace[75775971] linearizableReadLoop","detail":"{readStateIndex:4676; appliedIndex:4675; }","duration":"316.298487ms","start":"2026-03-04T01:32:00.00258Z","end":"2026-03-04T01:32:00.318879Z","steps":["trace[75775971] 'read index received' (duration: 204.2836ms)","trace[75775971] 'applied index is now lower than readState.Index' (duration: 112.012577ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:32:00.319037Z","caller":"traceutil/trace.go:171","msg":"trace[191922941] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"317.11391ms","start":"2026-03-04T01:32:00.001853Z","end":"2026-03-04T01:32:00.318967Z","steps":["trace[191922941] 'process raft request' (duration: 205.00712ms)","trace[191922941] 'compare' (duration: 111.336808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:32:00.319237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:32:00.001838Z","time spent":"317.312646ms","remote":"127.0.0.1:43252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":921,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-04T01:32:00.319328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.72103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-nxm7r-tls\" ","response":"range_response_count:1 size:4428"} {"level":"info","ts":"2026-03-04T01:32:00.319399Z","caller":"traceutil/trace.go:171","msg":"trace[2115122810] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-nxm7r-tls; range_end:; response_count:1; response_revision:4495; }","duration":"316.810502ms","start":"2026-03-04T01:32:00.002565Z","end":"2026-03-04T01:32:00.319376Z","steps":["trace[2115122810] 'agreement among raft nodes before linearized reading' (duration: 316.43156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:00.31946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:32:00.002556Z","time spent":"316.889823ms","remote":"127.0.0.1:42990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":4449,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-nxm7r-tls\" "} {"level":"warn","ts":"2026-03-04T01:32:00.343015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.297188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T01:32:00.34307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.315018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-04T01:32:00.343118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.035387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xfxxj\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-04T01:32:00.343197Z","caller":"traceutil/trace.go:171","msg":"trace[1835784411] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xfxxj; range_end:; response_count:1; response_revision:4496; }","duration":"160.125449ms","start":"2026-03-04T01:32:00.183047Z","end":"2026-03-04T01:32:00.343172Z","steps":["trace[1835784411] 'agreement among raft nodes before linearized reading' (duration: 159.936394ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:32:00.343096Z","caller":"traceutil/trace.go:171","msg":"trace[1788130285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4496; }","duration":"116.443223ms","start":"2026-03-04T01:32:00.226636Z","end":"2026-03-04T01:32:00.34308Z","steps":["trace[1788130285] 'agreement among raft nodes before linearized reading' (duration: 116.275718ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:32:00.343296Z","caller":"traceutil/trace.go:171","msg":"trace[1823203026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4496; }","duration":"164.432371ms","start":"2026-03-04T01:32:00.178692Z","end":"2026-03-04T01:32:00.343125Z","steps":["trace[1823203026] 'agreement among raft nodes before linearized reading' (duration: 164.189993ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:32:00.343012Z","caller":"traceutil/trace.go:171","msg":"trace[202760137] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"337.981366ms","start":"2026-03-04T01:32:00.004974Z","end":"2026-03-04T01:32:00.342955Z","steps":["trace[202760137] 'process raft request' (duration: 337.714188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:00.343504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:32:00.004967Z","time spent":"338.459289ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3706,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:32:00.765276Z","caller":"traceutil/trace.go:171","msg":"trace[1305796616] linearizableReadLoop","detail":"{readStateIndex:4694; appliedIndex:4691; }","duration":"262.346381ms","start":"2026-03-04T01:32:00.502905Z","end":"2026-03-04T01:32:00.765251Z","steps":["trace[1305796616] 'read index received' (duration: 10.253429ms)","trace[1305796616] 'applied index is now lower than readState.Index' (duration: 252.092102ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:32:00.765771Z","caller":"traceutil/trace.go:171","msg":"trace[966832071] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"271.806836ms","start":"2026-03-04T01:32:00.493932Z","end":"2026-03-04T01:32:00.765739Z","steps":["trace[966832071] 'process raft request' (duration: 231.825383ms)","trace[966832071] 'compare' (duration: 39.314185ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:32:00.766503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.587266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777457"} {"level":"info","ts":"2026-03-04T01:32:00.766587Z","caller":"traceutil/trace.go:171","msg":"trace[386841840] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4513; }","duration":"263.684118ms","start":"2026-03-04T01:32:00.502889Z","end":"2026-03-04T01:32:00.766573Z","steps":["trace[386841840] 'agreement among raft nodes before linearized reading' (duration: 262.943038ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:32:00.769519Z","caller":"traceutil/trace.go:171","msg":"trace[487504520] transaction","detail":"{read_only:false; response_revision:4514; number_of_response:1; }","duration":"251.64353ms","start":"2026-03-04T01:32:00.517849Z","end":"2026-03-04T01:32:00.769492Z","steps":["trace[487504520] 'process raft request' (duration: 251.497466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:00.771603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.557972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:32:00.771672Z","caller":"traceutil/trace.go:171","msg":"trace[691321314] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4515; }","duration":"238.651575ms","start":"2026-03-04T01:32:00.533001Z","end":"2026-03-04T01:32:00.771653Z","steps":["trace[691321314] 'agreement among raft nodes before linearized reading' (duration: 238.549302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:00.771657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.262271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-54grj\" ","response":"range_response_count:1 size:4949"} {"level":"warn","ts":"2026-03-04T01:32:00.771685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.836658ms","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-03-04T01:32:00.77173Z","caller":"traceutil/trace.go:171","msg":"trace[2119805737] range","detail":"{range_begin:/registry/pods/monitoring/vector-54grj; range_end:; response_count:1; response_revision:4515; }","duration":"250.382354ms","start":"2026-03-04T01:32:00.521331Z","end":"2026-03-04T01:32:00.771713Z","steps":["trace[2119805737] 'agreement among raft nodes before linearized reading' (duration: 250.156238ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:32:00.771746Z","caller":"traceutil/trace.go:171","msg":"trace[1152628733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4515; }","duration":"150.943621ms","start":"2026-03-04T01:32:00.620786Z","end":"2026-03-04T01:32:00.771729Z","steps":["trace[1152628733] 'agreement among raft nodes before linearized reading' (duration: 150.750126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:53.193721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.871936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:32:53.193843Z","caller":"traceutil/trace.go:171","msg":"trace[1557339473] linearizableReadLoop","detail":"{readStateIndex:5269; appliedIndex:5268; }","duration":"226.894564ms","start":"2026-03-04T01:32:52.966931Z","end":"2026-03-04T01:32:53.193825Z","steps":["trace[1557339473] 'read index received' (duration: 75.783342ms)","trace[1557339473] 'applied index is now lower than readState.Index' (duration: 151.107522ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:32:53.193949Z","caller":"traceutil/trace.go:171","msg":"trace[1754537130] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"237.233519ms","start":"2026-03-04T01:32:52.956704Z","end":"2026-03-04T01:32:53.193938Z","steps":["trace[1754537130] 'process raft request' (duration: 86.046405ms)","trace[1754537130] 'compare' (duration: 150.714841ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:32:53.194262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.485561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-04T01:32:53.194378Z","caller":"traceutil/trace.go:171","msg":"trace[898270712] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:1; response_revision:5069; }","duration":"167.644085ms","start":"2026-03-04T01:32:53.026715Z","end":"2026-03-04T01:32:53.194359Z","steps":["trace[898270712] 'agreement among raft nodes before linearized reading' (duration: 167.46282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:53.194458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.246948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-04T01:32:53.194558Z","caller":"traceutil/trace.go:171","msg":"trace[2122933196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5069; }","duration":"155.394752ms","start":"2026-03-04T01:32:53.039143Z","end":"2026-03-04T01:32:53.194538Z","steps":["trace[2122933196] 'agreement among raft nodes before linearized reading' (duration: 155.113054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:32:53.194694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.763106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:87 size:64740"} {"level":"info","ts":"2026-03-04T01:32:53.194734Z","caller":"traceutil/trace.go:171","msg":"trace[1729901313] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:87; response_revision:5069; }","duration":"227.831449ms","start":"2026-03-04T01:32:52.966893Z","end":"2026-03-04T01:32:53.194724Z","steps":["trace[1729901313] 'agreement among raft nodes before linearized reading' (duration: 227.348086ms)"],"step_count":1}