{"level":"warn","ts":"2026-04-27T04:00:31.736541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T04:00:31.736622Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-27T04:00:31.736694Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T04:00:31.736709Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-27T04:00:31.736733Z","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-04-27T04:00:31.737388Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-04-27T04:00:31.737478Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-27T04:00:31.742821Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.12225ms"} {"level":"info","ts":"2026-04-27T04:00:31.748917Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-04-27T04:00:31.748999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T04:00:31.749023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-04-27T04:00:31.74904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T04:00:31.749055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-04-27T04:00:31.749098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-04-27T04:00:31.755335Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T04:00:31.758361Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T04:00:31.75949Z","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-04-27T04:00:31.76161Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T04:00:31.761874Z","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-04-27T04:00:31.761981Z","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-04-27T04:00:31.761996Z","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-04-27T04:00:31.762227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T04:00:31.763085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-04-27T04:00:31.763337Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-27T04:00:31.76576Z","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-04-27T04:00:31.765925Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T04:00:31.765951Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T04:00:31.766035Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-27T04:00:31.766045Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-27T04:00:32.649967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T04:00:32.650044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T04:00:32.650087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-04-27T04:00:32.650114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-04-27T04:00:32.650128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-27T04:00:32.650317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-04-27T04:00:32.650351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-27T04:00:32.652454Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T04:00:32.653466Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T04:00:32.653451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T04:00:32.653561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T04:00:32.653889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T04:00:32.653929Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T04:00:32.65394Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T04:00:32.654063Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T04:00:32.654121Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T04:00:32.655872Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T04:00:32.656023Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"warn","ts":"2026-04-27T04:00:39.024402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.550367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:00:39.024559Z","caller":"traceutil/trace.go:171","msg":"trace[647214777] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:288; }","duration":"398.364568ms","start":"2026-04-27T04:00:38.626173Z","end":"2026-04-27T04:00:39.024538Z","steps":["trace[647214777] 'read index received' (duration: 7.504762ms)","trace[647214777] 'applied index is now lower than readState.Index' (duration: 390.858146ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:00:39.024573Z","caller":"traceutil/trace.go:171","msg":"trace[884571020] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"590.211872ms","start":"2026-04-27T04:00:38.434325Z","end":"2026-04-27T04:00:39.024537Z","steps":["trace[884571020] 'process raft request' (duration: 199.472797ms)","trace[884571020] 'compare' (duration: 389.373486ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:00:39.024698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:00:38.434296Z","time spent":"590.340393ms","remote":"127.0.0.1:58182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T04:00:39.024779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.579699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T04:00:39.024785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.936198ms","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-04-27T04:00:39.024836Z","caller":"traceutil/trace.go:171","msg":"trace[856274794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:277; }","duration":"398.68024ms","start":"2026-04-27T04:00:38.626142Z","end":"2026-04-27T04:00:39.024822Z","steps":["trace[856274794] 'agreement among raft nodes before linearized reading' (duration: 398.530409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:00:39.024816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.62369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T04:00:39.024851Z","caller":"traceutil/trace.go:171","msg":"trace[261444547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:277; }","duration":"132.037988ms","start":"2026-04-27T04:00:38.892796Z","end":"2026-04-27T04:00:39.024834Z","steps":["trace[261444547] 'agreement among raft nodes before linearized reading' (duration: 131.907238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:00:39.024889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:00:38.626116Z","time spent":"398.761021ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T04:00:39.024902Z","caller":"traceutil/trace.go:171","msg":"trace[838436509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:277; }","duration":"398.764841ms","start":"2026-04-27T04:00:38.626116Z","end":"2026-04-27T04:00:39.024881Z","steps":["trace[838436509] 'agreement among raft nodes before linearized reading' (duration: 398.519809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:00:39.024942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:00:38.626082Z","time spent":"398.852311ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T04:00:39.238079Z","caller":"traceutil/trace.go:171","msg":"trace[1167204376] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:290; }","duration":"151.787041ms","start":"2026-04-27T04:00:39.086269Z","end":"2026-04-27T04:00:39.238056Z","steps":["trace[1167204376] 'read index received' (duration: 86.136735ms)","trace[1167204376] 'applied index is now lower than readState.Index' (duration: 65.649126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:00:39.238188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.910712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T04:00:39.238168Z","caller":"traceutil/trace.go:171","msg":"trace[2104867072] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"208.626637ms","start":"2026-04-27T04:00:39.029499Z","end":"2026-04-27T04:00:39.238126Z","steps":["trace[2104867072] 'process raft request' (duration: 142.933451ms)","trace[2104867072] 'compare' (duration: 65.462645ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:00:39.238219Z","caller":"traceutil/trace.go:171","msg":"trace[1072151224] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:280; }","duration":"151.977812ms","start":"2026-04-27T04:00:39.086231Z","end":"2026-04-27T04:00:39.238209Z","steps":["trace[1072151224] 'agreement among raft nodes before linearized reading' (duration: 151.912931ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:00:39.238204Z","caller":"traceutil/trace.go:171","msg":"trace[1511349362] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"207.695541ms","start":"2026-04-27T04:00:39.030473Z","end":"2026-04-27T04:00:39.238169Z","steps":["trace[1511349362] 'process raft request' (duration: 207.540659ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:00:56.298821Z","caller":"traceutil/trace.go:171","msg":"trace[480306592] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"103.492123ms","start":"2026-04-27T04:00:56.195301Z","end":"2026-04-27T04:00:56.298793Z","steps":["trace[480306592] 'process raft request' (duration: 97.993683ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:00:56.522163Z","caller":"traceutil/trace.go:171","msg":"trace[1287698701] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"123.677241ms","start":"2026-04-27T04:00:56.398454Z","end":"2026-04-27T04:00:56.522132Z","steps":["trace[1287698701] 'process raft request' (duration: 75.682295ms)","trace[1287698701] 'compare' (duration: 47.862306ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:00:56.532845Z","caller":"traceutil/trace.go:171","msg":"trace[960808909] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"120.373083ms","start":"2026-04-27T04:00:56.412446Z","end":"2026-04-27T04:00:56.532819Z","steps":["trace[960808909] 'process raft request' (duration: 117.525718ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:01:02.774264Z","caller":"traceutil/trace.go:171","msg":"trace[826264253] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"127.902498ms","start":"2026-04-27T04:01:02.646331Z","end":"2026-04-27T04:01:02.774233Z","steps":["trace[826264253] 'process raft request' (duration: 79.592867ms)","trace[826264253] 'compare' (duration: 48.135781ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:01:03.439515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.083634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:01:03.439907Z","caller":"traceutil/trace.go:171","msg":"trace[1221973318] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"608.131103ms","start":"2026-04-27T04:01:02.831741Z","end":"2026-04-27T04:01:03.439872Z","steps":["trace[1221973318] 'process raft request' (duration: 165.541326ms)","trace[1221973318] 'compare' (duration: 441.807563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:01:03.440049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.205959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:01:03.440132Z","caller":"traceutil/trace.go:171","msg":"trace[997326486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"481.34977ms","start":"2026-04-27T04:01:02.958767Z","end":"2026-04-27T04:01:03.440117Z","steps":["trace[997326486] 'agreement among raft nodes before linearized reading' (duration: 481.203439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:01:03.440216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:01:02.958734Z","time spent":"481.4696ms","remote":"127.0.0.1:57972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T04:01:03.440218Z","caller":"traceutil/trace.go:171","msg":"trace[272425353] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"606.106132ms","start":"2026-04-27T04:01:02.83409Z","end":"2026-04-27T04:01:03.440196Z","steps":["trace[272425353] 'process raft request' (duration: 605.71706ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:01:03.440436Z","caller":"traceutil/trace.go:171","msg":"trace[1352509282] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"608.091722ms","start":"2026-04-27T04:01:02.832025Z","end":"2026-04-27T04:01:03.440117Z","steps":["trace[1352509282] 'process raft request' (duration: 607.64227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:01:03.440079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:01:02.831709Z","time spent":"608.283483ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T04:01:03.440838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:01:02.834078Z","time spent":"606.292063ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T04:01:03.439934Z","caller":"traceutil/trace.go:171","msg":"trace[1456457985] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:588; }","duration":"481.076898ms","start":"2026-04-27T04:01:02.958831Z","end":"2026-04-27T04:01:03.439908Z","steps":["trace[1456457985] 'read index received' (duration: 38.334251ms)","trace[1456457985] 'applied index is now lower than readState.Index' (duration: 442.740997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:01:03.441021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:01:02.832003Z","time spent":"608.649865ms","remote":"127.0.0.1:58178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T04:01:03.441609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.688943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T04:01:03.44166Z","caller":"traceutil/trace.go:171","msg":"trace[946870347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"168.775714ms","start":"2026-04-27T04:01:03.272873Z","end":"2026-04-27T04:01:03.441648Z","steps":["trace[946870347] 'agreement among raft nodes before linearized reading' (duration: 168.663523ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:01:18.806213Z","caller":"traceutil/trace.go:171","msg":"trace[1150418689] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"172.873388ms","start":"2026-04-27T04:01:18.633316Z","end":"2026-04-27T04:01:18.806189Z","steps":["trace[1150418689] 'process raft request' (duration: 172.720897ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:01:32.989184Z","caller":"traceutil/trace.go:171","msg":"trace[1228762376] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"117.743796ms","start":"2026-04-27T04:01:32.871421Z","end":"2026-04-27T04:01:32.989165Z","steps":["trace[1228762376] 'process raft request' (duration: 117.616526ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:01:33.029213Z","caller":"traceutil/trace.go:171","msg":"trace[792907797] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:922; }","duration":"115.448887ms","start":"2026-04-27T04:01:32.913748Z","end":"2026-04-27T04:01:33.029197Z","steps":["trace[792907797] 'read index received' (duration: 75.328289ms)","trace[792907797] 'applied index is now lower than readState.Index' (duration: 40.119938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:01:33.029343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.578728ms","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-04-27T04:01:33.02936Z","caller":"traceutil/trace.go:171","msg":"trace[1369103037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"115.658338ms","start":"2026-04-27T04:01:32.913696Z","end":"2026-04-27T04:01:33.029354Z","steps":["trace[1369103037] 'agreement among raft nodes before linearized reading' (duration: 115.558808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:01:33.029616Z","caller":"traceutil/trace.go:171","msg":"trace[452430799] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"154.466451ms","start":"2026-04-27T04:01:32.875142Z","end":"2026-04-27T04:01:33.029609Z","steps":["trace[452430799] 'process raft request' (duration: 153.946099ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:01:33.029664Z","caller":"traceutil/trace.go:171","msg":"trace[1471494660] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"153.363697ms","start":"2026-04-27T04:01:32.876295Z","end":"2026-04-27T04:01:33.029659Z","steps":["trace[1471494660] 'process raft request' (duration: 152.875305ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:07.472142Z","caller":"traceutil/trace.go:171","msg":"trace[1355074715] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"111.154619ms","start":"2026-04-27T04:03:07.360967Z","end":"2026-04-27T04:03:07.472121Z","steps":["trace[1355074715] 'read index received' (duration: 27.856143ms)","trace[1355074715] 'applied index is now lower than readState.Index' (duration: 83.297356ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:07.472263Z","caller":"traceutil/trace.go:171","msg":"trace[1038081158] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"111.656017ms","start":"2026-04-27T04:03:07.360583Z","end":"2026-04-27T04:03:07.472239Z","steps":["trace[1038081158] 'process raft request' (duration: 106.658837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:07.472657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.611926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cert-manager-controller-ingress-shim\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:03:07.472708Z","caller":"traceutil/trace.go:171","msg":"trace[1120652049] range","detail":"{range_begin:/registry/clusterroles/cert-manager-controller-ingress-shim; range_end:; response_count:0; response_revision:1251; }","duration":"111.749808ms","start":"2026-04-27T04:03:07.360944Z","end":"2026-04-27T04:03:07.472694Z","steps":["trace[1120652049] 'agreement among raft nodes before linearized reading' (duration: 111.588215ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:07.848195Z","caller":"traceutil/trace.go:171","msg":"trace[130839389] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"113.452326ms","start":"2026-04-27T04:03:07.73472Z","end":"2026-04-27T04:03:07.848172Z","steps":["trace[130839389] 'process raft request' (duration: 113.323824ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:07.848232Z","caller":"traceutil/trace.go:171","msg":"trace[1679594817] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"140.625718ms","start":"2026-04-27T04:03:07.707568Z","end":"2026-04-27T04:03:07.848193Z","steps":["trace[1679594817] 'process raft request' (duration: 140.271462ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:07.862154Z","caller":"traceutil/trace.go:171","msg":"trace[591094972] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"107.825986ms","start":"2026-04-27T04:03:07.754311Z","end":"2026-04-27T04:03:07.862137Z","steps":["trace[591094972] 'process raft request' (duration: 107.729084ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:09.177786Z","caller":"traceutil/trace.go:171","msg":"trace[585227685] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"167.066595ms","start":"2026-04-27T04:03:09.010696Z","end":"2026-04-27T04:03:09.177754Z","steps":["trace[585227685] 'read index received' (duration: 70.782488ms)","trace[585227685] 'applied index is now lower than readState.Index' (duration: 96.275267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:03:09.177907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.956309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:03:09.177932Z","caller":"traceutil/trace.go:171","msg":"trace[420310984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1400; }","duration":"220.016999ms","start":"2026-04-27T04:03:08.957907Z","end":"2026-04-27T04:03:09.177924Z","steps":["trace[420310984] 'agreement among raft nodes before linearized reading' (duration: 219.937368ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:09.177864Z","caller":"traceutil/trace.go:171","msg":"trace[23153095] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"237.366563ms","start":"2026-04-27T04:03:08.940446Z","end":"2026-04-27T04:03:09.177812Z","steps":["trace[23153095] 'process raft request' (duration: 141.070246ms)","trace[23153095] 'compare' (duration: 96.126424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:03:10.668621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.4835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:03:10.668858Z","caller":"traceutil/trace.go:171","msg":"trace[272337426] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"230.876713ms","start":"2026-04-27T04:03:10.437947Z","end":"2026-04-27T04:03:10.668824Z","steps":["trace[272337426] 'process raft request' (duration: 52.061158ms)","trace[272337426] 'compare' (duration: 178.205666ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:10.668953Z","caller":"traceutil/trace.go:171","msg":"trace[1170651787] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"223.751942ms","start":"2026-04-27T04:03:10.445179Z","end":"2026-04-27T04:03:10.668931Z","steps":["trace[1170651787] 'process raft request' (duration: 223.584999ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:17.869754Z","caller":"traceutil/trace.go:171","msg":"trace[1544012183] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"127.977387ms","start":"2026-04-27T04:03:17.741757Z","end":"2026-04-27T04:03:17.869735Z","steps":["trace[1544012183] 'process raft request' (duration: 127.867025ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:17.90621Z","caller":"traceutil/trace.go:171","msg":"trace[745923617] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"118.533849ms","start":"2026-04-27T04:03:17.787417Z","end":"2026-04-27T04:03:17.905951Z","steps":["trace[745923617] 'process raft request' (duration: 118.383536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:20.628209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.170024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T04:03:20.628265Z","caller":"traceutil/trace.go:171","msg":"trace[881418034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1498; }","duration":"458.284596ms","start":"2026-04-27T04:03:20.169968Z","end":"2026-04-27T04:03:20.628253Z","steps":["trace[881418034] 'range keys from in-memory index tree' (duration: 457.996301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:20.62829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:20.169942Z","time spent":"458.341837ms","remote":"127.0.0.1:58250","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":"warn","ts":"2026-04-27T04:03:20.628527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.066885ms","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-04-27T04:03:20.628544Z","caller":"traceutil/trace.go:171","msg":"trace[1242306390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1498; }","duration":"313.129257ms","start":"2026-04-27T04:03:20.31541Z","end":"2026-04-27T04:03:20.628539Z","steps":["trace[1242306390] 'range keys from in-memory index tree' (duration: 312.898673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:20.62856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:20.315384Z","time spent":"313.172947ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T04:03:20.818396Z","caller":"traceutil/trace.go:171","msg":"trace[702864369] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"185.657298ms","start":"2026-04-27T04:03:20.632712Z","end":"2026-04-27T04:03:20.818369Z","steps":["trace[702864369] 'process raft request' (duration: 181.752872ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:20.819051Z","caller":"traceutil/trace.go:171","msg":"trace[1937132954] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"186.079645ms","start":"2026-04-27T04:03:20.632944Z","end":"2026-04-27T04:03:20.819023Z","steps":["trace[1937132954] 'process raft request' (duration: 185.872232ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:20.987029Z","caller":"traceutil/trace.go:171","msg":"trace[856193306] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"161.24312ms","start":"2026-04-27T04:03:20.825761Z","end":"2026-04-27T04:03:20.987004Z","steps":["trace[856193306] 'process raft request' (duration: 161.082708ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:21.11052Z","caller":"traceutil/trace.go:171","msg":"trace[437501414] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"273.985934ms","start":"2026-04-27T04:03:20.836503Z","end":"2026-04-27T04:03:21.110489Z","steps":["trace[437501414] 'process raft request' (duration: 273.550567ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:21.119951Z","caller":"traceutil/trace.go:171","msg":"trace[2114226491] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1555; }","duration":"159.32208ms","start":"2026-04-27T04:03:20.960589Z","end":"2026-04-27T04:03:21.119911Z","steps":["trace[2114226491] 'read index received' (duration: 26.952606ms)","trace[2114226491] 'applied index is now lower than readState.Index' (duration: 132.367394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:03:21.120122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.511562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:03:21.120249Z","caller":"traceutil/trace.go:171","msg":"trace[1858605939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1504; }","duration":"159.680445ms","start":"2026-04-27T04:03:20.960539Z","end":"2026-04-27T04:03:21.120219Z","steps":["trace[1858605939] 'agreement among raft nodes before linearized reading' (duration: 159.494392ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:22.928072Z","caller":"traceutil/trace.go:171","msg":"trace[1097068021] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1564; }","duration":"104.957781ms","start":"2026-04-27T04:03:22.823093Z","end":"2026-04-27T04:03:22.928051Z","steps":["trace[1097068021] 'read index received' (duration: 104.87202ms)","trace[1097068021] 'applied index is now lower than readState.Index' (duration: 84.851µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:22.928154Z","caller":"traceutil/trace.go:171","msg":"trace[631604932] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"157.253869ms","start":"2026-04-27T04:03:22.770842Z","end":"2026-04-27T04:03:22.928095Z","steps":["trace[631604932] 'process raft request' (duration: 157.030105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:22.928314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.102903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T04:03:22.928359Z","caller":"traceutil/trace.go:171","msg":"trace[962413181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1512; }","duration":"105.179394ms","start":"2026-04-27T04:03:22.823163Z","end":"2026-04-27T04:03:22.928342Z","steps":["trace[962413181] 'agreement among raft nodes before linearized reading' (duration: 105.113973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:22.928252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.139754ms","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-04-27T04:03:22.928574Z","caller":"traceutil/trace.go:171","msg":"trace[1892403305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1512; }","duration":"105.493168ms","start":"2026-04-27T04:03:22.823053Z","end":"2026-04-27T04:03:22.928546Z","steps":["trace[1892403305] 'agreement among raft nodes before linearized reading' (duration: 105.107263ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:29.186343Z","caller":"traceutil/trace.go:171","msg":"trace[1659018517] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"333.231961ms","start":"2026-04-27T04:03:28.853083Z","end":"2026-04-27T04:03:29.186315Z","steps":["trace[1659018517] 'process raft request' (duration: 333.066979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:29.186522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:28.853058Z","time spent":"333.378393ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T04:03:29.201528Z","caller":"traceutil/trace.go:171","msg":"trace[746932466] linearizableReadLoop","detail":"{readStateIndex:1698; appliedIndex:1696; }","duration":"240.220754ms","start":"2026-04-27T04:03:28.961276Z","end":"2026-04-27T04:03:29.201496Z","steps":["trace[746932466] 'read index received' (duration: 224.98971ms)","trace[746932466] 'applied index is now lower than readState.Index' (duration: 15.229844ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:29.201634Z","caller":"traceutil/trace.go:171","msg":"trace[1267732433] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"330.041476ms","start":"2026-04-27T04:03:28.871546Z","end":"2026-04-27T04:03:29.201588Z","steps":["trace[1267732433] 'process raft request' (duration: 329.798163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:29.201706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.414756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:03:29.201768Z","caller":"traceutil/trace.go:171","msg":"trace[776409415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1644; }","duration":"240.516097ms","start":"2026-04-27T04:03:28.961236Z","end":"2026-04-27T04:03:29.201752Z","steps":["trace[776409415] 'agreement among raft nodes before linearized reading' (duration: 240.419266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:29.201784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:28.871521Z","time spent":"330.182549ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3148,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T04:03:29.201877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.321475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T04:03:29.20191Z","caller":"traceutil/trace.go:171","msg":"trace[1717131660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1644; }","duration":"153.397956ms","start":"2026-04-27T04:03:29.048503Z","end":"2026-04-27T04:03:29.201901Z","steps":["trace[1717131660] 'agreement among raft nodes before linearized reading' (duration: 153.288155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:29.20221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.439413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T04:03:29.202255Z","caller":"traceutil/trace.go:171","msg":"trace[691953023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1644; }","duration":"152.512254ms","start":"2026-04-27T04:03:29.049729Z","end":"2026-04-27T04:03:29.202242Z","steps":["trace[691953023] 'agreement among raft nodes before linearized reading' (duration: 152.25245ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:34.605174Z","caller":"traceutil/trace.go:171","msg":"trace[814411327] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"110.854505ms","start":"2026-04-27T04:03:34.494291Z","end":"2026-04-27T04:03:34.605145Z","steps":["trace[814411327] 'process raft request' (duration: 54.597277ms)","trace[814411327] 'compare' (duration: 56.106536ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:34.625156Z","caller":"traceutil/trace.go:171","msg":"trace[763735529] linearizableReadLoop","detail":"{readStateIndex:1775; appliedIndex:1772; }","duration":"126.616729ms","start":"2026-04-27T04:03:34.498523Z","end":"2026-04-27T04:03:34.625139Z","steps":["trace[763735529] 'read index received' (duration: 50.384972ms)","trace[763735529] 'applied index is now lower than readState.Index' (duration: 76.230977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:03:34.625277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.736461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:03:34.625339Z","caller":"traceutil/trace.go:171","msg":"trace[2125981303] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com; range_end:; response_count:0; response_revision:1719; }","duration":"126.827762ms","start":"2026-04-27T04:03:34.498495Z","end":"2026-04-27T04:03:34.625323Z","steps":["trace[2125981303] 'agreement among raft nodes before linearized reading' (duration: 126.71845ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:34.625341Z","caller":"traceutil/trace.go:171","msg":"trace[1395994161] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"128.060017ms","start":"2026-04-27T04:03:34.497252Z","end":"2026-04-27T04:03:34.625312Z","steps":["trace[1395994161] 'process raft request' (duration: 127.730343ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:34.625344Z","caller":"traceutil/trace.go:171","msg":"trace[1323793884] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"127.973927ms","start":"2026-04-27T04:03:34.497311Z","end":"2026-04-27T04:03:34.625284Z","steps":["trace[1323793884] 'process raft request' (duration: 127.779125ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:34.770654Z","caller":"traceutil/trace.go:171","msg":"trace[1859448774] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"139.367084ms","start":"2026-04-27T04:03:34.631261Z","end":"2026-04-27T04:03:34.770628Z","steps":["trace[1859448774] 'process raft request' (duration: 78.570937ms)","trace[1859448774] 'compare' (duration: 60.679766ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:34.781409Z","caller":"traceutil/trace.go:171","msg":"trace[1557338200] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"148.244629ms","start":"2026-04-27T04:03:34.633145Z","end":"2026-04-27T04:03:34.78139Z","steps":["trace[1557338200] 'process raft request' (duration: 148.038716ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:34.78138Z","caller":"traceutil/trace.go:171","msg":"trace[263671495] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"141.517542ms","start":"2026-04-27T04:03:34.639843Z","end":"2026-04-27T04:03:34.781361Z","steps":["trace[263671495] 'process raft request' (duration: 141.482752ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:34.781423Z","caller":"traceutil/trace.go:171","msg":"trace[586576070] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"144.621912ms","start":"2026-04-27T04:03:34.636785Z","end":"2026-04-27T04:03:34.781407Z","steps":["trace[586576070] 'process raft request' (duration: 144.47925ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:34.781583Z","caller":"traceutil/trace.go:171","msg":"trace[1998110796] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1722; }","duration":"142.185021ms","start":"2026-04-27T04:03:34.639386Z","end":"2026-04-27T04:03:34.781571Z","steps":["trace[1998110796] 'process raft request' (duration: 141.918408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:41.763739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.843831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:03:41.763838Z","caller":"traceutil/trace.go:171","msg":"trace[1336973746] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"431.20718ms","start":"2026-04-27T04:03:41.332615Z","end":"2026-04-27T04:03:41.763822Z","steps":["trace[1336973746] 'process raft request' (duration: 40.197977ms)","trace[1336973746] 'compare' (duration: 390.689799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:03:41.763898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:41.332588Z","time spent":"431.280681ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T04:03:41.898928Z","caller":"traceutil/trace.go:171","msg":"trace[1833217947] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"565.441008ms","start":"2026-04-27T04:03:41.333449Z","end":"2026-04-27T04:03:41.89889Z","steps":["trace[1833217947] 'process raft request' (duration: 556.429047ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:41.898962Z","caller":"traceutil/trace.go:171","msg":"trace[738078930] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"538.476385ms","start":"2026-04-27T04:03:41.360444Z","end":"2026-04-27T04:03:41.89892Z","steps":["trace[738078930] 'process raft request' (duration: 538.214922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:41.899077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.031345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T04:03:41.899086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:41.36043Z","time spent":"538.574127ms","remote":"127.0.0.1:58164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7652,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T04:03:41.899098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:41.333436Z","time spent":"565.56494ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T04:03:41.899137Z","caller":"traceutil/trace.go:171","msg":"trace[1123681944] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1962; }","duration":"443.131337ms","start":"2026-04-27T04:03:41.455992Z","end":"2026-04-27T04:03:41.899124Z","steps":["trace[1123681944] 'agreement among raft nodes before linearized reading' (duration: 443.021746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:41.899181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:41.455974Z","time spent":"443.198118ms","remote":"127.0.0.1:58442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-27T04:03:41.898822Z","caller":"traceutil/trace.go:171","msg":"trace[74019229] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2043; }","duration":"442.765842ms","start":"2026-04-27T04:03:41.456033Z","end":"2026-04-27T04:03:41.898799Z","steps":["trace[74019229] 'read index received' (duration: 433.781651ms)","trace[74019229] 'applied index is now lower than readState.Index' (duration: 8.983151ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:53.127082Z","caller":"traceutil/trace.go:171","msg":"trace[636666699] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"114.730868ms","start":"2026-04-27T04:03:53.012318Z","end":"2026-04-27T04:03:53.127049Z","steps":["trace[636666699] 'process raft request' (duration: 40.0398ms)","trace[636666699] 'compare' (duration: 74.561527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:03:53.884199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.699923ms","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-04-27T04:03:53.884305Z","caller":"traceutil/trace.go:171","msg":"trace[1987503631] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2201; }","duration":"431.862064ms","start":"2026-04-27T04:03:53.452417Z","end":"2026-04-27T04:03:53.884279Z","steps":["trace[1987503631] 'range keys from in-memory index tree' (duration: 431.328939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:53.884359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.452388Z","time spent":"431.955345ms","remote":"127.0.0.1:60374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-27T04:03:53.884381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.250458Z","time spent":"633.912857ms","remote":"127.0.0.1:50162","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T04:03:53.88495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.788638ms","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-04-27T04:03:53.884913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.784864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15c23eec-609d-4d3d-80c5-6436172b76ec\" ","response":"range_response_count:1 size:3737"} {"level":"info","ts":"2026-04-27T04:03:53.884989Z","caller":"traceutil/trace.go:171","msg":"trace[1065028617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2201; }","duration":"501.866019ms","start":"2026-04-27T04:03:53.383113Z","end":"2026-04-27T04:03:53.884979Z","steps":["trace[1065028617] 'range keys from in-memory index tree' (duration: 501.660216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:53.885009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.383091Z","time spent":"501.913699ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T04:03:53.88503Z","caller":"traceutil/trace.go:171","msg":"trace[1100105718] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-15c23eec-609d-4d3d-80c5-6436172b76ec; range_end:; response_count:1; response_revision:2201; }","duration":"605.947125ms","start":"2026-04-27T04:03:53.279055Z","end":"2026-04-27T04:03:53.885002Z","steps":["trace[1100105718] 'range keys from in-memory index tree' (duration: 605.532071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:53.885078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.279036Z","time spent":"606.031706ms","remote":"127.0.0.1:58178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3759,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15c23eec-609d-4d3d-80c5-6436172b76ec\" "} {"level":"info","ts":"2026-04-27T04:03:54.234389Z","caller":"traceutil/trace.go:171","msg":"trace[1218063167] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2292; }","duration":"425.529957ms","start":"2026-04-27T04:03:53.80883Z","end":"2026-04-27T04:03:54.23436Z","steps":["trace[1218063167] 'read index received' (duration: 425.493666ms)","trace[1218063167] 'applied index is now lower than readState.Index' (duration: 34.92µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:03:54.234585Z","caller":"traceutil/trace.go:171","msg":"trace[198678262] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"571.826929ms","start":"2026-04-27T04:03:53.662623Z","end":"2026-04-27T04:03:54.23445Z","steps":["trace[198678262] 'process raft request' (duration: 571.586996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:54.234964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.066153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c115b24f-4820-4804-81a1-42854150fcdc\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-27T04:03:54.235012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.662597Z","time spent":"572.287234ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T04:03:54.235074Z","caller":"traceutil/trace.go:171","msg":"trace[117250311] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c115b24f-4820-4804-81a1-42854150fcdc; range_end:; response_count:1; response_revision:2202; }","duration":"426.261635ms","start":"2026-04-27T04:03:53.808791Z","end":"2026-04-27T04:03:54.235053Z","steps":["trace[117250311] 'agreement among raft nodes before linearized reading' (duration: 425.718929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:54.235214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.808773Z","time spent":"426.387547ms","remote":"127.0.0.1:58178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c115b24f-4820-4804-81a1-42854150fcdc\" "} {"level":"info","ts":"2026-04-27T04:03:54.237742Z","caller":"traceutil/trace.go:171","msg":"trace[843732270] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"347.458219ms","start":"2026-04-27T04:03:53.890264Z","end":"2026-04-27T04:03:54.237723Z","steps":["trace[843732270] 'process raft request' (duration: 347.119765ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:03:54.237897Z","caller":"traceutil/trace.go:171","msg":"trace[520849109] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"102.424491ms","start":"2026-04-27T04:03:54.135453Z","end":"2026-04-27T04:03:54.237878Z","steps":["trace[520849109] 'process raft request' (duration: 102.070287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:54.238306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.350483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T04:03:54.240076Z","caller":"traceutil/trace.go:171","msg":"trace[147357215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2204; }","duration":"326.155285ms","start":"2026-04-27T04:03:53.913885Z","end":"2026-04-27T04:03:54.24004Z","steps":["trace[147357215] 'agreement among raft nodes before linearized reading' (duration: 324.249853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:54.238474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.095546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-27T04:03:54.240169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.913869Z","time spent":"326.269246ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T04:03:54.240197Z","caller":"traceutil/trace.go:171","msg":"trace[1289992357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2204; }","duration":"165.875136ms","start":"2026-04-27T04:03:54.074301Z","end":"2026-04-27T04:03:54.240176Z","steps":["trace[1289992357] 'agreement among raft nodes before linearized reading' (duration: 164.037575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:54.238555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.141807ms","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-04-27T04:03:54.240315Z","caller":"traceutil/trace.go:171","msg":"trace[1307095672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2204; }","duration":"256.966959ms","start":"2026-04-27T04:03:53.983337Z","end":"2026-04-27T04:03:54.240304Z","steps":["trace[1307095672] 'agreement among raft nodes before linearized reading' (duration: 255.114147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:03:54.238628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:03:53.890233Z","time spent":"347.55979ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T04:04:16.526499Z","caller":"traceutil/trace.go:171","msg":"trace[858453103] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"183.324026ms","start":"2026-04-27T04:04:16.343155Z","end":"2026-04-27T04:04:16.526479Z","steps":["trace[858453103] 'process raft request' (duration: 183.165024ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:16.536828Z","caller":"traceutil/trace.go:171","msg":"trace[688519379] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"150.50991ms","start":"2026-04-27T04:04:16.3863Z","end":"2026-04-27T04:04:16.53681Z","steps":["trace[688519379] 'process raft request' (duration: 150.399269ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:16.537022Z","caller":"traceutil/trace.go:171","msg":"trace[1104633037] linearizableReadLoop","detail":"{readStateIndex:2501; appliedIndex:2498; }","duration":"121.370142ms","start":"2026-04-27T04:04:16.415584Z","end":"2026-04-27T04:04:16.536954Z","steps":["trace[1104633037] 'read index received' (duration: 110.714733ms)","trace[1104633037] 'applied index is now lower than readState.Index' (duration: 10.654009ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:04:16.53706Z","caller":"traceutil/trace.go:171","msg":"trace[1707762796] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"137.76828ms","start":"2026-04-27T04:04:16.399275Z","end":"2026-04-27T04:04:16.537044Z","steps":["trace[1707762796] 'process raft request' (duration: 137.502088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:16.537176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.584744ms","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-04-27T04:04:16.537235Z","caller":"traceutil/trace.go:171","msg":"trace[560785676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2403; }","duration":"121.693885ms","start":"2026-04-27T04:04:16.415526Z","end":"2026-04-27T04:04:16.53722Z","steps":["trace[560785676] 'agreement among raft nodes before linearized reading' (duration: 121.536923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:16.537242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.32149ms","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-04-27T04:04:16.537271Z","caller":"traceutil/trace.go:171","msg":"trace[412972153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2403; }","duration":"115.410442ms","start":"2026-04-27T04:04:16.421852Z","end":"2026-04-27T04:04:16.537263Z","steps":["trace[412972153] 'agreement among raft nodes before linearized reading' (duration: 115.25872ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:25.609593Z","caller":"traceutil/trace.go:171","msg":"trace[1888514338] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"199.675436ms","start":"2026-04-27T04:04:25.409885Z","end":"2026-04-27T04:04:25.60956Z","steps":["trace[1888514338] 'process raft request' (duration: 198.477445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:28.739194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.173034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:04:28.739444Z","caller":"traceutil/trace.go:171","msg":"trace[33370427] linearizableReadLoop","detail":"{readStateIndex:2572; appliedIndex:2570; }","duration":"148.637377ms","start":"2026-04-27T04:04:28.590789Z","end":"2026-04-27T04:04:28.739427Z","steps":["trace[33370427] 'read index received' (duration: 43.180141ms)","trace[33370427] 'applied index is now lower than readState.Index' (duration: 105.456256ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:04:28.739581Z","caller":"traceutil/trace.go:171","msg":"trace[987168174] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"150.811398ms","start":"2026-04-27T04:04:28.588759Z","end":"2026-04-27T04:04:28.739571Z","steps":["trace[987168174] 'process raft request' (duration: 150.605016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:28.739817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.025961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T04:04:28.739887Z","caller":"traceutil/trace.go:171","msg":"trace[738754166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2472; }","duration":"149.134402ms","start":"2026-04-27T04:04:28.590742Z","end":"2026-04-27T04:04:28.739876Z","steps":["trace[738754166] 'agreement among raft nodes before linearized reading' (duration: 148.979421ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:28.739881Z","caller":"traceutil/trace.go:171","msg":"trace[125660869] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"168.214037ms","start":"2026-04-27T04:04:28.571633Z","end":"2026-04-27T04:04:28.739847Z","steps":["trace[125660869] 'process raft request' (duration: 62.309426ms)","trace[125660869] 'compare' (duration: 105.035333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:04:28.740114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.83844ms","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-04-27T04:04:28.740453Z","caller":"traceutil/trace.go:171","msg":"trace[633031922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2472; }","duration":"144.209264ms","start":"2026-04-27T04:04:28.596225Z","end":"2026-04-27T04:04:28.740434Z","steps":["trace[633031922] 'agreement among raft nodes before linearized reading' (duration: 143.739019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:28.740082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.251283ms","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-04-27T04:04:28.740621Z","caller":"traceutil/trace.go:171","msg":"trace[39898266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2472; }","duration":"147.824249ms","start":"2026-04-27T04:04:28.592774Z","end":"2026-04-27T04:04:28.740598Z","steps":["trace[39898266] 'agreement among raft nodes before linearized reading' (duration: 147.173143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:28.740132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.440278ms","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-04-27T04:04:28.740755Z","caller":"traceutil/trace.go:171","msg":"trace[1884967837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2472; }","duration":"140.122244ms","start":"2026-04-27T04:04:28.600616Z","end":"2026-04-27T04:04:28.740739Z","steps":["trace[1884967837] 'agreement among raft nodes before linearized reading' (duration: 139.351366ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:28.875141Z","caller":"traceutil/trace.go:171","msg":"trace[1512389032] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"130.208167ms","start":"2026-04-27T04:04:28.744894Z","end":"2026-04-27T04:04:28.875102Z","steps":["trace[1512389032] 'process raft request' (duration: 108.385294ms)","trace[1512389032] 'compare' (duration: 21.692701ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:04:28.882332Z","caller":"traceutil/trace.go:171","msg":"trace[1455569723] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"137.123244ms","start":"2026-04-27T04:04:28.745183Z","end":"2026-04-27T04:04:28.882307Z","steps":["trace[1455569723] 'process raft request' (duration: 137.079293ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:28.882635Z","caller":"traceutil/trace.go:171","msg":"trace[368165800] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"137.530358ms","start":"2026-04-27T04:04:28.745092Z","end":"2026-04-27T04:04:28.882623Z","steps":["trace[368165800] 'process raft request' (duration: 136.845681ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:28.882734Z","caller":"traceutil/trace.go:171","msg":"trace[1449255401] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"137.599078ms","start":"2026-04-27T04:04:28.745102Z","end":"2026-04-27T04:04:28.882701Z","steps":["trace[1449255401] 'process raft request' (duration: 136.962072ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:29.535582Z","caller":"traceutil/trace.go:171","msg":"trace[1628014219] transaction","detail":"{read_only:false; response_revision:2478; number_of_response:1; }","duration":"236.898807ms","start":"2026-04-27T04:04:29.298662Z","end":"2026-04-27T04:04:29.535561Z","steps":["trace[1628014219] 'process raft request' (duration: 236.703285ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:29.594651Z","caller":"traceutil/trace.go:171","msg":"trace[2131597929] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"234.05536ms","start":"2026-04-27T04:04:29.360576Z","end":"2026-04-27T04:04:29.594632Z","steps":["trace[2131597929] 'process raft request' (duration: 233.894228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:30.278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.264307ms","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-04-27T04:04:30.278068Z","caller":"traceutil/trace.go:171","msg":"trace[74038772] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2481; }","duration":"163.401709ms","start":"2026-04-27T04:04:30.11465Z","end":"2026-04-27T04:04:30.278051Z","steps":["trace[74038772] 'range keys from in-memory index tree' (duration: 163.067016ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:31.109279Z","caller":"traceutil/trace.go:171","msg":"trace[1626453896] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"459.03286ms","start":"2026-04-27T04:04:30.650223Z","end":"2026-04-27T04:04:31.109255Z","steps":["trace[1626453896] 'process raft request' (duration: 458.896389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.109442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.650185Z","time spent":"459.163791ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T04:04:31.470082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.968794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:04:31.470652Z","caller":"traceutil/trace.go:171","msg":"trace[1859953210] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"725.054927ms","start":"2026-04-27T04:04:30.745572Z","end":"2026-04-27T04:04:31.470627Z","steps":["trace[1859953210] 'process raft request' (duration: 724.702764ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:31.470585Z","caller":"traceutil/trace.go:171","msg":"trace[1338362499] transaction","detail":"{read_only:false; response_revision:2484; number_of_response:1; }","duration":"726.480851ms","start":"2026-04-27T04:04:30.74403Z","end":"2026-04-27T04:04:31.470511Z","steps":["trace[1338362499] 'process raft request' (duration: 523.970322ms)","trace[1338362499] 'compare' (duration: 201.858473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:04:31.470807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.745548Z","time spent":"725.188538ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T04:04:31.47083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.744002Z","time spent":"726.749013ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T04:04:31.496381Z","caller":"traceutil/trace.go:171","msg":"trace[303139660] linearizableReadLoop","detail":"{readStateIndex:2585; appliedIndex:2583; }","duration":"701.185035ms","start":"2026-04-27T04:04:30.795119Z","end":"2026-04-27T04:04:31.496304Z","steps":["trace[303139660] 'read index received' (duration: 314.13433ms)","trace[303139660] 'applied index is now lower than readState.Index' (duration: 387.048765ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:04:31.496738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.391165ms","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-04-27T04:04:31.49696Z","caller":"traceutil/trace.go:171","msg":"trace[45771211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2485; }","duration":"618.636028ms","start":"2026-04-27T04:04:30.878305Z","end":"2026-04-27T04:04:31.496941Z","steps":["trace[45771211] 'agreement among raft nodes before linearized reading' (duration: 618.304995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.497018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.878291Z","time spent":"618.712909ms","remote":"127.0.0.1:58250","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-04-27T04:04:31.496861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.558878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T04:04:31.496758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.419143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:04:31.497853Z","caller":"traceutil/trace.go:171","msg":"trace[591339277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2485; }","duration":"539.555144ms","start":"2026-04-27T04:04:30.958277Z","end":"2026-04-27T04:04:31.497833Z","steps":["trace[591339277] 'agreement among raft nodes before linearized reading' (duration: 538.394483ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:31.497811Z","caller":"traceutil/trace.go:171","msg":"trace[279057527] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2485; }","duration":"445.548587ms","start":"2026-04-27T04:04:31.052236Z","end":"2026-04-27T04:04:31.497785Z","steps":["trace[279057527] 'agreement among raft nodes before linearized reading' (duration: 444.563958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.497894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.958257Z","time spent":"539.627454ms","remote":"127.0.0.1:57960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T04:04:31.496819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.361524ms","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-04-27T04:04:31.498326Z","caller":"traceutil/trace.go:171","msg":"trace[1388834607] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2485; }","duration":"212.910549ms","start":"2026-04-27T04:04:31.285403Z","end":"2026-04-27T04:04:31.498313Z","steps":["trace[1388834607] 'agreement among raft nodes before linearized reading' (duration: 211.284653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.496864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.724642ms","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-04-27T04:04:31.498429Z","caller":"traceutil/trace.go:171","msg":"trace[269225765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2485; }","duration":"612.323046ms","start":"2026-04-27T04:04:30.886085Z","end":"2026-04-27T04:04:31.498408Z","steps":["trace[269225765] 'agreement among raft nodes before linearized reading' (duration: 610.64576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.49849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.886065Z","time spent":"612.412798ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T04:04:31.496865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.5398ms","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-04-27T04:04:31.498649Z","caller":"traceutil/trace.go:171","msg":"trace[933870938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2485; }","duration":"612.334647ms","start":"2026-04-27T04:04:30.886307Z","end":"2026-04-27T04:04:31.498642Z","steps":["trace[933870938] 'agreement among raft nodes before linearized reading' (duration: 610.443899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.498694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.886283Z","time spent":"612.381447ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T04:04:31.497017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.904943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T04:04:31.498766Z","caller":"traceutil/trace.go:171","msg":"trace[508274354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2485; }","duration":"612.692581ms","start":"2026-04-27T04:04:30.886063Z","end":"2026-04-27T04:04:31.498755Z","steps":["trace[508274354] 'agreement among raft nodes before linearized reading' (duration: 610.777572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.498788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.886045Z","time spent":"612.737291ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T04:04:31.496798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.65115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-04-27T04:04:31.498838Z","caller":"traceutil/trace.go:171","msg":"trace[1334604149] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2485; }","duration":"703.75697ms","start":"2026-04-27T04:04:30.795075Z","end":"2026-04-27T04:04:31.498832Z","steps":["trace[1334604149] 'agreement among raft nodes before linearized reading' (duration: 701.525529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:04:31.498859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:30.795044Z","time spent":"703.810101ms","remote":"127.0.0.1:58178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12011,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-27T04:04:31.499647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:04:31.052217Z","time spent":"445.941912ms","remote":"127.0.0.1:58236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-27T04:04:41.924398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.931916ms","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-04-27T04:04:41.92447Z","caller":"traceutil/trace.go:171","msg":"trace[436973672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2561; }","duration":"146.047197ms","start":"2026-04-27T04:04:41.778405Z","end":"2026-04-27T04:04:41.924452Z","steps":["trace[436973672] 'range keys from in-memory index tree' (duration: 145.401941ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:04:43.657407Z","caller":"traceutil/trace.go:171","msg":"trace[1497291911] linearizableReadLoop","detail":"{readStateIndex:2670; appliedIndex:2669; }","duration":"112.064405ms","start":"2026-04-27T04:04:43.545318Z","end":"2026-04-27T04:04:43.657382Z","steps":["trace[1497291911] 'read index received' (duration: 101.397216ms)","trace[1497291911] 'applied index is now lower than readState.Index' (duration: 10.666349ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:04:43.65759Z","caller":"traceutil/trace.go:171","msg":"trace[2014146857] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"138.267236ms","start":"2026-04-27T04:04:43.5193Z","end":"2026-04-27T04:04:43.657567Z","steps":["trace[2014146857] 'process raft request' (duration: 127.418906ms)","trace[2014146857] 'compare' (duration: 10.563508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:04:43.657683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.354267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:04:43.658251Z","caller":"traceutil/trace.go:171","msg":"trace[1540112250] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2567; }","duration":"112.957862ms","start":"2026-04-27T04:04:43.545267Z","end":"2026-04-27T04:04:43.658225Z","steps":["trace[1540112250] 'agreement among raft nodes before linearized reading' (duration: 112.369757ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:05:48.766223Z","caller":"traceutil/trace.go:171","msg":"trace[81869837] linearizableReadLoop","detail":"{readStateIndex:3155; appliedIndex:3153; }","duration":"326.569579ms","start":"2026-04-27T04:05:48.439622Z","end":"2026-04-27T04:05:48.766191Z","steps":["trace[81869837] 'read index received' (duration: 41.19185ms)","trace[81869837] 'applied index is now lower than readState.Index' (duration: 285.376258ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:05:48.766329Z","caller":"traceutil/trace.go:171","msg":"trace[954112518] transaction","detail":"{read_only:false; response_revision:3037; number_of_response:1; }","duration":"348.644405ms","start":"2026-04-27T04:05:48.417639Z","end":"2026-04-27T04:05:48.766284Z","steps":["trace[954112518] 'process raft request' (duration: 345.739032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:05:48.766465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.833781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-27T04:05:48.766498Z","caller":"traceutil/trace.go:171","msg":"trace[497714902] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3037; }","duration":"326.905631ms","start":"2026-04-27T04:05:48.439584Z","end":"2026-04-27T04:05:48.766489Z","steps":["trace[497714902] 'agreement among raft nodes before linearized reading' (duration: 326.74352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:05:48.766495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:05:48.417618Z","time spent":"348.796177ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T04:05:48.76652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:05:48.439567Z","time spent":"326.946272ms","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-27T04:05:48.766529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.011317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T04:05:48.76653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.202188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90d3d28e-0420-46d8-9950-320b9fd42e89\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-27T04:05:48.766573Z","caller":"traceutil/trace.go:171","msg":"trace[189694386] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:3037; }","duration":"224.120037ms","start":"2026-04-27T04:05:48.542441Z","end":"2026-04-27T04:05:48.766561Z","steps":["trace[189694386] 'agreement among raft nodes before linearized reading' (duration: 223.983036ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:05:48.766584Z","caller":"traceutil/trace.go:171","msg":"trace[1644714865] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90d3d28e-0420-46d8-9950-320b9fd42e89; range_end:; response_count:1; response_revision:3037; }","duration":"249.292689ms","start":"2026-04-27T04:05:48.517275Z","end":"2026-04-27T04:05:48.766568Z","steps":["trace[1644714865] 'agreement among raft nodes before linearized reading' (duration: 249.099877ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:05:48.960884Z","caller":"traceutil/trace.go:171","msg":"trace[47684430] linearizableReadLoop","detail":"{readStateIndex:3156; appliedIndex:3155; }","duration":"184.609681ms","start":"2026-04-27T04:05:48.776256Z","end":"2026-04-27T04:05:48.960866Z","steps":["trace[47684430] 'read index received' (duration: 183.931855ms)","trace[47684430] 'applied index is now lower than readState.Index' (duration: 676.866µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:05:48.961003Z","caller":"traceutil/trace.go:171","msg":"trace[605532721] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"185.655979ms","start":"2026-04-27T04:05:48.775332Z","end":"2026-04-27T04:05:48.960988Z","steps":["trace[605532721] 'process raft request' (duration: 184.907383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:05:48.961313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.045424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-04-27T04:05:48.961384Z","caller":"traceutil/trace.go:171","msg":"trace[2067680153] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3038; }","duration":"185.138765ms","start":"2026-04-27T04:05:48.776227Z","end":"2026-04-27T04:05:48.961366Z","steps":["trace[2067680153] 'agreement among raft nodes before linearized reading' (duration: 184.982334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:05:51.06533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.883784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-27T04:05:51.065455Z","caller":"traceutil/trace.go:171","msg":"trace[1783268987] linearizableReadLoop","detail":"{readStateIndex:3170; appliedIndex:3169; }","duration":"344.828007ms","start":"2026-04-27T04:05:50.72061Z","end":"2026-04-27T04:05:51.065438Z","steps":["trace[1783268987] 'read index received' (duration: 72.860422ms)","trace[1783268987] 'applied index is now lower than readState.Index' (duration: 271.966225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:05:51.065702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.06435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:05:51.06585Z","caller":"traceutil/trace.go:171","msg":"trace[1036167412] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:3051; }","duration":"345.222471ms","start":"2026-04-27T04:05:50.720561Z","end":"2026-04-27T04:05:51.065783Z","steps":["trace[1036167412] 'agreement among raft nodes before linearized reading' (duration: 344.947149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:05:51.065973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:05:50.72054Z","time spent":"345.410332ms","remote":"127.0.0.1:60384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-04-27T04:05:51.075687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.721203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:05:51.075802Z","caller":"traceutil/trace.go:171","msg":"trace[76633638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3052; }","duration":"116.803963ms","start":"2026-04-27T04:05:50.958928Z","end":"2026-04-27T04:05:51.075732Z","steps":["trace[76633638] 'agreement among raft nodes before linearized reading' (duration: 116.712923ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:00.837291Z","caller":"traceutil/trace.go:171","msg":"trace[890679700] linearizableReadLoop","detail":"{readStateIndex:3248; appliedIndex:3247; }","duration":"240.3382ms","start":"2026-04-27T04:06:00.596928Z","end":"2026-04-27T04:06:00.837266Z","steps":["trace[890679700] 'read index received' (duration: 240.325809ms)","trace[890679700] 'applied index is now lower than readState.Index' (duration: 10.911µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:06:00.837298Z","caller":"traceutil/trace.go:171","msg":"trace[1012431516] transaction","detail":"{read_only:false; response_revision:3128; number_of_response:1; }","duration":"288.607301ms","start":"2026-04-27T04:06:00.548666Z","end":"2026-04-27T04:06:00.837273Z","steps":["trace[1012431516] 'process raft request' (duration: 288.46701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:00.837692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.656222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-27T04:06:00.837746Z","caller":"traceutil/trace.go:171","msg":"trace[970476389] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:3128; }","duration":"240.861733ms","start":"2026-04-27T04:06:00.596872Z","end":"2026-04-27T04:06:00.837733Z","steps":["trace[970476389] 'agreement among raft nodes before linearized reading' (duration: 240.595981ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:01.044278Z","caller":"traceutil/trace.go:171","msg":"trace[831921025] linearizableReadLoop","detail":"{readStateIndex:3249; appliedIndex:3248; }","duration":"206.799854ms","start":"2026-04-27T04:06:00.837445Z","end":"2026-04-27T04:06:01.044245Z","steps":["trace[831921025] 'read index received' (duration: 113.637498ms)","trace[831921025] 'applied index is now lower than readState.Index' (duration: 93.159676ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:06:01.04435Z","caller":"traceutil/trace.go:171","msg":"trace[204411080] transaction","detail":"{read_only:false; response_revision:3129; number_of_response:1; }","duration":"194.876009ms","start":"2026-04-27T04:06:00.849442Z","end":"2026-04-27T04:06:01.044318Z","steps":["trace[204411080] 'process raft request' (duration: 194.681168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:01.044547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.798793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-27T04:06:01.044614Z","caller":"traceutil/trace.go:171","msg":"trace[897822607] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:3129; }","duration":"378.921864ms","start":"2026-04-27T04:06:00.665678Z","end":"2026-04-27T04:06:01.0446Z","steps":["trace[897822607] 'agreement among raft nodes before linearized reading' (duration: 378.718152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:01.044659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:06:00.66565Z","time spent":"378.999455ms","remote":"127.0.0.1:58138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-27T04:06:01.21979Z","caller":"traceutil/trace.go:171","msg":"trace[1963475634] transaction","detail":"{read_only:false; response_revision:3131; number_of_response:1; }","duration":"166.01846ms","start":"2026-04-27T04:06:01.053741Z","end":"2026-04-27T04:06:01.219759Z","steps":["trace[1963475634] 'process raft request' (duration: 88.088955ms)","trace[1963475634] 'compare' (duration: 77.670213ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:06:01.21997Z","caller":"traceutil/trace.go:171","msg":"trace[18211133] transaction","detail":"{read_only:false; response_revision:3133; number_of_response:1; }","duration":"165.867609ms","start":"2026-04-27T04:06:01.054056Z","end":"2026-04-27T04:06:01.219923Z","steps":["trace[18211133] 'process raft request' (duration: 165.684028ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:01.220196Z","caller":"traceutil/trace.go:171","msg":"trace[123716043] transaction","detail":"{read_only:false; response_revision:3132; number_of_response:1; }","duration":"166.085611ms","start":"2026-04-27T04:06:01.053908Z","end":"2026-04-27T04:06:01.219994Z","steps":["trace[123716043] 'process raft request' (duration: 165.780789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:34.671982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.872479ms","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-04-27T04:06:34.672043Z","caller":"traceutil/trace.go:171","msg":"trace[1717630280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3427; }","duration":"188.959869ms","start":"2026-04-27T04:06:34.483073Z","end":"2026-04-27T04:06:34.672033Z","steps":["trace[1717630280] 'range keys from in-memory index tree' (duration: 188.798588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:34.672346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.20326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-27T04:06:34.672366Z","caller":"traceutil/trace.go:171","msg":"trace[873531123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3427; }","duration":"198.257681ms","start":"2026-04-27T04:06:34.474103Z","end":"2026-04-27T04:06:34.672361Z","steps":["trace[873531123] 'range keys from in-memory index tree' (duration: 198.069579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:34.67246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.123599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-27T04:06:34.672472Z","caller":"traceutil/trace.go:171","msg":"trace[1815052303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3427; }","duration":"198.15754ms","start":"2026-04-27T04:06:34.47431Z","end":"2026-04-27T04:06:34.672468Z","steps":["trace[1815052303] 'range keys from in-memory index tree' (duration: 198.036209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:34.672558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.631568ms","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-04-27T04:06:34.672569Z","caller":"traceutil/trace.go:171","msg":"trace[2106002024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3427; }","duration":"196.656048ms","start":"2026-04-27T04:06:34.47591Z","end":"2026-04-27T04:06:34.672566Z","steps":["trace[2106002024] 'range keys from in-memory index tree' (duration: 196.571738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:34.672626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.0183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-27T04:06:34.672643Z","caller":"traceutil/trace.go:171","msg":"trace[2058101106] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3427; }","duration":"232.836146ms","start":"2026-04-27T04:06:34.439802Z","end":"2026-04-27T04:06:34.672639Z","steps":["trace[2058101106] 'range keys from in-memory index tree' (duration: 231.867448ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:34.672689Z","caller":"traceutil/trace.go:171","msg":"trace[1292968288] transaction","detail":"{read_only:false; response_revision:3428; number_of_response:1; }","duration":"115.709838ms","start":"2026-04-27T04:06:34.556946Z","end":"2026-04-27T04:06:34.672655Z","steps":["trace[1292968288] 'process raft request' (duration: 82.866436ms)","trace[1292968288] 'compare' (duration: 31.630773ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:06:34.866759Z","caller":"traceutil/trace.go:171","msg":"trace[885684059] transaction","detail":"{read_only:false; response_revision:3430; number_of_response:1; }","duration":"189.839746ms","start":"2026-04-27T04:06:34.676897Z","end":"2026-04-27T04:06:34.866737Z","steps":["trace[885684059] 'process raft request' (duration: 178.518919ms)","trace[885684059] 'compare' (duration: 11.105386ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:06:34.86706Z","caller":"traceutil/trace.go:171","msg":"trace[188746621] transaction","detail":"{read_only:false; response_revision:3431; number_of_response:1; }","duration":"189.983228ms","start":"2026-04-27T04:06:34.677065Z","end":"2026-04-27T04:06:34.867048Z","steps":["trace[188746621] 'process raft request' (duration: 189.630985ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:34.867534Z","caller":"traceutil/trace.go:171","msg":"trace[420663404] transaction","detail":"{read_only:false; response_revision:3432; number_of_response:1; }","duration":"190.36151ms","start":"2026-04-27T04:06:34.677163Z","end":"2026-04-27T04:06:34.867525Z","steps":["trace[420663404] 'process raft request' (duration: 189.904057ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:34.867512Z","caller":"traceutil/trace.go:171","msg":"trace[1818744648] linearizableReadLoop","detail":"{readStateIndex:3561; appliedIndex:3558; }","duration":"116.712805ms","start":"2026-04-27T04:06:34.750778Z","end":"2026-04-27T04:06:34.86749Z","steps":["trace[1818744648] 'read index received' (duration: 104.670863ms)","trace[1818744648] 'applied index is now lower than readState.Index' (duration: 12.040132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:06:34.867819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.003577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-04-27T04:06:34.86797Z","caller":"traceutil/trace.go:171","msg":"trace[1236181994] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3432; }","duration":"117.279189ms","start":"2026-04-27T04:06:34.750657Z","end":"2026-04-27T04:06:34.867936Z","steps":["trace[1236181994] 'agreement among raft nodes before linearized reading' (duration: 116.981107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:34.868139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.382425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:06:34.868179Z","caller":"traceutil/trace.go:171","msg":"trace[71495837] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:3432; }","duration":"111.468585ms","start":"2026-04-27T04:06:34.7567Z","end":"2026-04-27T04:06:34.868168Z","steps":["trace[71495837] 'agreement among raft nodes before linearized reading' (duration: 111.252343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:35.372999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.070955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:06:35.373168Z","caller":"traceutil/trace.go:171","msg":"trace[1604169164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3432; }","duration":"178.248136ms","start":"2026-04-27T04:06:35.1949Z","end":"2026-04-27T04:06:35.373148Z","steps":["trace[1604169164] 'range keys from in-memory index tree' (duration: 177.984504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:35.373625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.383351ms","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-04-27T04:06:35.373701Z","caller":"traceutil/trace.go:171","msg":"trace[956269241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3432; }","duration":"236.508743ms","start":"2026-04-27T04:06:35.137175Z","end":"2026-04-27T04:06:35.373683Z","steps":["trace[956269241] 'range keys from in-memory index tree' (duration: 235.611916ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:35.907993Z","caller":"traceutil/trace.go:171","msg":"trace[177874904] linearizableReadLoop","detail":"{readStateIndex:3565; appliedIndex:3564; }","duration":"145.843578ms","start":"2026-04-27T04:06:35.762062Z","end":"2026-04-27T04:06:35.907906Z","steps":["trace[177874904] 'read index received' (duration: 127.406047ms)","trace[177874904] 'applied index is now lower than readState.Index' (duration: 18.435271ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:06:35.908001Z","caller":"traceutil/trace.go:171","msg":"trace[527257709] transaction","detail":"{read_only:false; response_revision:3435; number_of_response:1; }","duration":"249.747453ms","start":"2026-04-27T04:06:35.658194Z","end":"2026-04-27T04:06:35.907941Z","steps":["trace[527257709] 'process raft request' (duration: 231.435273ms)","trace[527257709] 'compare' (duration: 17.583695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:06:35.908555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.422892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T04:06:35.908692Z","caller":"traceutil/trace.go:171","msg":"trace[874780732] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:3435; }","duration":"146.582053ms","start":"2026-04-27T04:06:35.76203Z","end":"2026-04-27T04:06:35.908612Z","steps":["trace[874780732] 'agreement among raft nodes before linearized reading' (duration: 146.077089ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:36.43041Z","caller":"traceutil/trace.go:171","msg":"trace[2091152143] linearizableReadLoop","detail":"{readStateIndex:3567; appliedIndex:3566; }","duration":"116.778734ms","start":"2026-04-27T04:06:36.313603Z","end":"2026-04-27T04:06:36.430382Z","steps":["trace[2091152143] 'read index received' (duration: 116.622693ms)","trace[2091152143] 'applied index is now lower than readState.Index' (duration: 154.721µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:06:36.43059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.968945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T04:06:36.430636Z","caller":"traceutil/trace.go:171","msg":"trace[2006788967] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3437; }","duration":"117.074667ms","start":"2026-04-27T04:06:36.313551Z","end":"2026-04-27T04:06:36.430626Z","steps":["trace[2006788967] 'agreement among raft nodes before linearized reading' (duration: 116.935826ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:36.430564Z","caller":"traceutil/trace.go:171","msg":"trace[1230030372] transaction","detail":"{read_only:false; response_revision:3437; number_of_response:1; }","duration":"136.552426ms","start":"2026-04-27T04:06:36.29397Z","end":"2026-04-27T04:06:36.430522Z","steps":["trace[1230030372] 'process raft request' (duration: 136.254164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:06:36.460753Z","caller":"traceutil/trace.go:171","msg":"trace[1187363088] transaction","detail":"{read_only:false; response_revision:3438; number_of_response:1; }","duration":"116.074719ms","start":"2026-04-27T04:06:36.344655Z","end":"2026-04-27T04:06:36.46073Z","steps":["trace[1187363088] 'process raft request' (duration: 115.447595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:49.203343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.904189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:06:49.20341Z","caller":"traceutil/trace.go:171","msg":"trace[381361488] linearizableReadLoop","detail":"{readStateIndex:3666; appliedIndex:3665; }","duration":"244.374538ms","start":"2026-04-27T04:06:48.959026Z","end":"2026-04-27T04:06:49.2034Z","steps":["trace[381361488] 'read index received' (duration: 35.561µs)","trace[381361488] 'applied index is now lower than readState.Index' (duration: 244.337907ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:06:49.203504Z","caller":"traceutil/trace.go:171","msg":"trace[1441894628] transaction","detail":"{read_only:false; response_revision:3534; number_of_response:1; }","duration":"327.488759ms","start":"2026-04-27T04:06:48.876009Z","end":"2026-04-27T04:06:49.203498Z","steps":["trace[1441894628] 'process raft request' (duration: 39.372329ms)","trace[1441894628] 'compare' (duration: 287.699697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:06:49.20356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T04:06:48.875981Z","time spent":"327.542559ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T04:06:49.203992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.755596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T04:06:49.204015Z","caller":"traceutil/trace.go:171","msg":"trace[650915502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3534; }","duration":"125.800926ms","start":"2026-04-27T04:06:49.078208Z","end":"2026-04-27T04:06:49.204009Z","steps":["trace[650915502] 'agreement among raft nodes before linearized reading' (duration: 125.698995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:49.203999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.623189ms","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-04-27T04:06:49.204059Z","caller":"traceutil/trace.go:171","msg":"trace[519269738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3534; }","duration":"240.718699ms","start":"2026-04-27T04:06:48.963325Z","end":"2026-04-27T04:06:49.204043Z","steps":["trace[519269738] 'agreement among raft nodes before linearized reading' (duration: 240.556738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:49.204091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.075233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:06:49.204114Z","caller":"traceutil/trace.go:171","msg":"trace[1866959941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3534; }","duration":"245.124043ms","start":"2026-04-27T04:06:48.958981Z","end":"2026-04-27T04:06:49.204105Z","steps":["trace[1866959941] 'agreement among raft nodes before linearized reading' (duration: 245.081093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:06:49.204206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.019341ms","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-04-27T04:06:49.204537Z","caller":"traceutil/trace.go:171","msg":"trace[1918661544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3534; }","duration":"129.267202ms","start":"2026-04-27T04:06:49.075134Z","end":"2026-04-27T04:06:49.204401Z","steps":["trace[1918661544] 'agreement among raft nodes before linearized reading' (duration: 128.843709ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:07:34.455573Z","caller":"traceutil/trace.go:171","msg":"trace[1868542398] transaction","detail":"{read_only:false; response_revision:3883; number_of_response:1; }","duration":"114.394845ms","start":"2026-04-27T04:07:34.341132Z","end":"2026-04-27T04:07:34.455527Z","steps":["trace[1868542398] 'process raft request' (duration: 89.833611ms)","trace[1868542398] 'compare' (duration: 24.378922ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:08:02.048774Z","caller":"traceutil/trace.go:171","msg":"trace[1266661676] transaction","detail":"{read_only:false; response_revision:4037; number_of_response:1; }","duration":"102.33755ms","start":"2026-04-27T04:08:01.946404Z","end":"2026-04-27T04:08:02.048742Z","steps":["trace[1266661676] 'process raft request' (duration: 40.807543ms)","trace[1266661676] 'compare' (duration: 61.328095ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:10:32.681963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2847} {"level":"info","ts":"2026-04-27T04:10:32.883101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2847,"took":"199.851978ms","hash":2695686520,"current-db-size-bytes":38109184,"current-db-size":"38 MB","current-db-size-in-use-bytes":13979648,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-04-27T04:10:32.883168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2695686520,"revision":2847,"compact-revision":-1} {"level":"info","ts":"2026-04-27T04:12:31.660435Z","caller":"traceutil/trace.go:171","msg":"trace[1499687898] linearizableReadLoop","detail":"{readStateIndex:5909; appliedIndex:5908; }","duration":"185.127821ms","start":"2026-04-27T04:12:31.475283Z","end":"2026-04-27T04:12:31.660411Z","steps":["trace[1499687898] 'read index received' (duration: 184.920089ms)","trace[1499687898] 'applied index is now lower than readState.Index' (duration: 206.882µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:12:31.660479Z","caller":"traceutil/trace.go:171","msg":"trace[1015711122] transaction","detail":"{read_only:false; response_revision:5696; number_of_response:1; }","duration":"212.517361ms","start":"2026-04-27T04:12:31.447935Z","end":"2026-04-27T04:12:31.660452Z","steps":["trace[1015711122] 'process raft request' (duration: 212.31953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:12:31.66061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.322622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-27T04:12:31.660679Z","caller":"traceutil/trace.go:171","msg":"trace[1583033971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5696; }","duration":"185.448674ms","start":"2026-04-27T04:12:31.475222Z","end":"2026-04-27T04:12:31.66067Z","steps":["trace[1583033971] 'agreement among raft nodes before linearized reading' (duration: 185.282622ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:12:42.461763Z","caller":"traceutil/trace.go:171","msg":"trace[1069710358] transaction","detail":"{read_only:false; response_revision:5787; number_of_response:1; }","duration":"111.229556ms","start":"2026-04-27T04:12:42.350506Z","end":"2026-04-27T04:12:42.461736Z","steps":["trace[1069710358] 'process raft request' (duration: 111.083415ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:12:42.47666Z","caller":"traceutil/trace.go:171","msg":"trace[893372644] linearizableReadLoop","detail":"{readStateIndex:6003; appliedIndex:6001; }","duration":"117.579624ms","start":"2026-04-27T04:12:42.359058Z","end":"2026-04-27T04:12:42.476638Z","steps":["trace[893372644] 'read index received' (duration: 102.527763ms)","trace[893372644] 'applied index is now lower than readState.Index' (duration: 15.051121ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:12:42.476869Z","caller":"traceutil/trace.go:171","msg":"trace[555430880] transaction","detail":"{read_only:false; response_revision:5788; number_of_response:1; }","duration":"122.973093ms","start":"2026-04-27T04:12:42.353861Z","end":"2026-04-27T04:12:42.476835Z","steps":["trace[555430880] 'process raft request' (duration: 122.667551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:12:42.47704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.961586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-27T04:12:42.477687Z","caller":"traceutil/trace.go:171","msg":"trace[1758369523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5788; }","duration":"118.627091ms","start":"2026-04-27T04:12:42.359035Z","end":"2026-04-27T04:12:42.477662Z","steps":["trace[1758369523] 'agreement among raft nodes before linearized reading' (duration: 117.902636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:12:42.492268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.148467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-27T04:12:42.492319Z","caller":"traceutil/trace.go:171","msg":"trace[1024306900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5789; }","duration":"133.217028ms","start":"2026-04-27T04:12:42.359091Z","end":"2026-04-27T04:12:42.492308Z","steps":["trace[1024306900] 'agreement among raft nodes before linearized reading' (duration: 133.036426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:12:42.492333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.120198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-27T04:12:42.492505Z","caller":"traceutil/trace.go:171","msg":"trace[1468648995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5789; }","duration":"133.273789ms","start":"2026-04-27T04:12:42.359179Z","end":"2026-04-27T04:12:42.492453Z","steps":["trace[1468648995] 'agreement among raft nodes before linearized reading' (duration: 133.003267ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:18.923759Z","caller":"traceutil/trace.go:171","msg":"trace[925082609] transaction","detail":"{read_only:false; response_revision:6953; number_of_response:1; }","duration":"106.469051ms","start":"2026-04-27T04:14:18.817272Z","end":"2026-04-27T04:14:18.923741Z","steps":["trace[925082609] 'process raft request' (duration: 101.624355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:24.435077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.49553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:14:24.435154Z","caller":"traceutil/trace.go:171","msg":"trace[1291920194] linearizableReadLoop","detail":"{readStateIndex:7284; appliedIndex:7283; }","duration":"164.092774ms","start":"2026-04-27T04:14:24.271049Z","end":"2026-04-27T04:14:24.435141Z","steps":["trace[1291920194] 'read index received' (duration: 18.21µs)","trace[1291920194] 'applied index is now lower than readState.Index' (duration: 164.073824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:14:24.435437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.380016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60602"} {"level":"info","ts":"2026-04-27T04:14:24.435465Z","caller":"traceutil/trace.go:171","msg":"trace[1883848540] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:7022; }","duration":"164.439667ms","start":"2026-04-27T04:14:24.271019Z","end":"2026-04-27T04:14:24.435458Z","steps":["trace[1883848540] 'agreement among raft nodes before linearized reading' (duration: 164.154404ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:24.435546Z","caller":"traceutil/trace.go:171","msg":"trace[1380843353] transaction","detail":"{read_only:false; response_revision:7022; number_of_response:1; }","duration":"164.487147ms","start":"2026-04-27T04:14:24.271024Z","end":"2026-04-27T04:14:24.435511Z","steps":["trace[1380843353] 'process raft request' (duration: 15.499733ms)","trace[1380843353] 'compare' (duration: 148.263059ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:14:24.498887Z","caller":"traceutil/trace.go:171","msg":"trace[1004857629] transaction","detail":"{read_only:false; response_revision:7024; number_of_response:1; }","duration":"222.551364ms","start":"2026-04-27T04:14:24.276319Z","end":"2026-04-27T04:14:24.49887Z","steps":["trace[1004857629] 'process raft request' (duration: 222.507414ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:24.499127Z","caller":"traceutil/trace.go:171","msg":"trace[1870977908] transaction","detail":"{read_only:false; response_revision:7023; number_of_response:1; }","duration":"228.027574ms","start":"2026-04-27T04:14:24.271095Z","end":"2026-04-27T04:14:24.499123Z","steps":["trace[1870977908] 'process raft request' (duration: 227.647851ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:24.645501Z","caller":"traceutil/trace.go:171","msg":"trace[802554643] linearizableReadLoop","detail":"{readStateIndex:7287; appliedIndex:7286; }","duration":"138.584127ms","start":"2026-04-27T04:14:24.506893Z","end":"2026-04-27T04:14:24.645477Z","steps":["trace[802554643] 'read index received' (duration: 138.413186ms)","trace[802554643] 'applied index is now lower than readState.Index' (duration: 170.191µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:14:24.645898Z","caller":"traceutil/trace.go:171","msg":"trace[717394988] transaction","detail":"{read_only:false; response_revision:7025; number_of_response:1; }","duration":"190.958081ms","start":"2026-04-27T04:14:24.454928Z","end":"2026-04-27T04:14:24.645886Z","steps":["trace[717394988] 'process raft request' (duration: 190.395427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:24.646107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.196812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-hbjd7-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:14:24.646137Z","caller":"traceutil/trace.go:171","msg":"trace[1478688265] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-hbjd7-tls; range_end:; response_count:0; response_revision:7025; }","duration":"139.261322ms","start":"2026-04-27T04:14:24.506865Z","end":"2026-04-27T04:14:24.646126Z","steps":["trace[1478688265] 'agreement among raft nodes before linearized reading' (duration: 139.200142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:24.647936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.004007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-27T04:14:24.64797Z","caller":"traceutil/trace.go:171","msg":"trace[1852161326] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:7025; }","duration":"125.065068ms","start":"2026-04-27T04:14:24.522894Z","end":"2026-04-27T04:14:24.647959Z","steps":["trace[1852161326] 'agreement among raft nodes before linearized reading' (duration: 123.357265ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:24.864771Z","caller":"traceutil/trace.go:171","msg":"trace[480392564] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7027; }","duration":"147.884315ms","start":"2026-04-27T04:14:24.716834Z","end":"2026-04-27T04:14:24.864718Z","steps":["trace[480392564] 'process raft request' (duration: 147.770914ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:24.86554Z","caller":"traceutil/trace.go:171","msg":"trace[1972412454] linearizableReadLoop","detail":"{readStateIndex:7290; appliedIndex:7290; }","duration":"144.010387ms","start":"2026-04-27T04:14:24.721516Z","end":"2026-04-27T04:14:24.865526Z","steps":["trace[1972412454] 'read index received' (duration: 144.004677ms)","trace[1972412454] 'applied index is now lower than readState.Index' (duration: 4.74µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:14:24.865902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.471808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-27T04:14:24.866058Z","caller":"traceutil/trace.go:171","msg":"trace[731880337] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:7027; }","duration":"145.607909ms","start":"2026-04-27T04:14:24.720386Z","end":"2026-04-27T04:14:24.865994Z","steps":["trace[731880337] 'agreement among raft nodes before linearized reading' (duration: 145.349547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:24.87198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.020382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-hbjd7-tls\" ","response":"range_response_count:1 size:4436"} {"level":"info","ts":"2026-04-27T04:14:24.872135Z","caller":"traceutil/trace.go:171","msg":"trace[2056956324] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-hbjd7-tls; range_end:; response_count:1; response_revision:7029; }","duration":"146.162533ms","start":"2026-04-27T04:14:24.725927Z","end":"2026-04-27T04:14:24.87209Z","steps":["trace[2056956324] 'agreement among raft nodes before linearized reading' (duration: 145.966271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:24.87241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.769063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-27T04:14:24.872512Z","caller":"traceutil/trace.go:171","msg":"trace[1500654584] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7029; }","duration":"144.882924ms","start":"2026-04-27T04:14:24.727621Z","end":"2026-04-27T04:14:24.872504Z","steps":["trace[1500654584] 'agreement among raft nodes before linearized reading' (duration: 144.738653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:24.872678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.606546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-hbjd7-tls-t2srf\" ","response":"range_response_count:1 size:2629"} {"level":"info","ts":"2026-04-27T04:14:24.872714Z","caller":"traceutil/trace.go:171","msg":"trace[214111243] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-hbjd7-tls-t2srf; range_end:; response_count:1; response_revision:7029; }","duration":"146.654056ms","start":"2026-04-27T04:14:24.726051Z","end":"2026-04-27T04:14:24.872705Z","steps":["trace[214111243] 'agreement among raft nodes before linearized reading' (duration: 146.564395ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:24.872428Z","caller":"traceutil/trace.go:171","msg":"trace[1834647164] transaction","detail":"{read_only:false; response_revision:7028; number_of_response:1; }","duration":"146.613186ms","start":"2026-04-27T04:14:24.725805Z","end":"2026-04-27T04:14:24.872419Z","steps":["trace[1834647164] 'process raft request' (duration: 145.978161ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:29.097499Z","caller":"traceutil/trace.go:171","msg":"trace[1582953516] transaction","detail":"{read_only:false; response_revision:7079; number_of_response:1; }","duration":"141.287897ms","start":"2026-04-27T04:14:28.956194Z","end":"2026-04-27T04:14:29.097482Z","steps":["trace[1582953516] 'process raft request' (duration: 141.176116ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:29.187641Z","caller":"traceutil/trace.go:171","msg":"trace[1526413672] linearizableReadLoop","detail":"{readStateIndex:7345; appliedIndex:7343; }","duration":"228.523717ms","start":"2026-04-27T04:14:28.95909Z","end":"2026-04-27T04:14:29.187614Z","steps":["trace[1526413672] 'read index received' (duration: 138.326055ms)","trace[1526413672] 'applied index is now lower than readState.Index' (duration: 90.196322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T04:14:29.187991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.87729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:14:29.188066Z","caller":"traceutil/trace.go:171","msg":"trace[1272545156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7080; }","duration":"229.006801ms","start":"2026-04-27T04:14:28.959042Z","end":"2026-04-27T04:14:29.188049Z","steps":["trace[1272545156] 'agreement among raft nodes before linearized reading' (duration: 228.87294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:29.18814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.499834ms","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-04-27T04:14:29.188188Z","caller":"traceutil/trace.go:171","msg":"trace[1157061287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7080; }","duration":"154.578394ms","start":"2026-04-27T04:14:29.033598Z","end":"2026-04-27T04:14:29.188176Z","steps":["trace[1157061287] 'agreement among raft nodes before linearized reading' (duration: 154.390333ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:14:29.188298Z","caller":"traceutil/trace.go:171","msg":"trace[1607792013] transaction","detail":"{read_only:false; response_revision:7080; number_of_response:1; }","duration":"230.985005ms","start":"2026-04-27T04:14:28.957299Z","end":"2026-04-27T04:14:29.188284Z","steps":["trace[1607792013] 'process raft request' (duration: 230.183999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:29.188075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.442742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T04:14:29.191248Z","caller":"traceutil/trace.go:171","msg":"trace[977196214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7080; }","duration":"165.651155ms","start":"2026-04-27T04:14:29.025574Z","end":"2026-04-27T04:14:29.191225Z","steps":["trace[977196214] 'agreement among raft nodes before linearized reading' (duration: 162.330521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:29.377582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.670103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T04:14:29.377658Z","caller":"traceutil/trace.go:171","msg":"trace[1472685450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7080; }","duration":"185.777304ms","start":"2026-04-27T04:14:29.191864Z","end":"2026-04-27T04:14:29.377641Z","steps":["trace[1472685450] 'range keys from in-memory index tree' (duration: 185.603592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T04:14:29.37778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.030151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T04:14:29.377866Z","caller":"traceutil/trace.go:171","msg":"trace[1362669360] transaction","detail":"{read_only:false; response_revision:7081; number_of_response:1; }","duration":"182.64276ms","start":"2026-04-27T04:14:29.195209Z","end":"2026-04-27T04:14:29.377852Z","steps":["trace[1362669360] 'process raft request' (duration: 66.478248ms)","trace[1362669360] 'compare' (duration: 115.8084ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T04:14:29.397952Z","caller":"traceutil/trace.go:171","msg":"trace[1144924235] transaction","detail":"{read_only:false; response_revision:7082; number_of_response:1; }","duration":"202.562207ms","start":"2026-04-27T04:14:29.195372Z","end":"2026-04-27T04:14:29.397934Z","steps":["trace[1144924235] 'process raft request' (duration: 202.465096ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T04:15:32.687426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4848} {"level":"info","ts":"2026-04-27T04:15:32.7311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4848,"took":"42.4996ms","hash":2802023975,"current-db-size-bytes":38109184,"current-db-size":"38 MB","current-db-size-in-use-bytes":29163520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-27T04:15:32.731156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2802023975,"revision":4848,"compact-revision":2847}